2022-04-18T19:15:11.258Z,1650309311.258 [Supervisor](DEBUG): Initializing supervisor.
2022-04-18T19:15:11.262Z,1650309311.262 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2022-04-18T19:15:11.263Z,1650309311.263 [SyncHandler](INFO): Protected caller Thread ID is 1560
2022-04-18T19:15:11.263Z,1650309311.263 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2022-04-18T19:15:11.264Z,1650309311.264 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2022-04-18T19:15:11.264Z,1650309311.264 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1561
2022-04-18T19:15:11.268Z,1650309311.268 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2022-04-18T19:15:11.287Z,1650309311.287 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2022-04-18T19:15:11.288Z,1650309311.288 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2022-04-18T19:15:11.288Z,1650309311.288 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1562
2022-04-18T19:15:11.292Z,1650309311.292 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2022-04-18T19:15:11.293Z,1650309311.293 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2022-04-18T19:15:11.294Z,1650309311.294 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1563
2022-04-18T19:15:11.296Z,1650309311.296 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2022-04-18T19:15:11.297Z,1650309311.297 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2022-04-18T19:15:11.297Z,1650309311.297 [logger ThreadHandler](INFO): Protected caller Thread ID is 1564
2022-04-18T19:15:11.301Z,1650309311.301 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2022-04-18T19:15:11.301Z,1650309311.301 [Supervisor](INFO): Looking for Config files in directory: Config/
2022-04-18T19:15:11.303Z,1650309311.303 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2022-04-18T19:15:11.755Z,1650309311.755 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2022-04-18T19:15:11.755Z,1650309311.755 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2022-04-18T19:15:11.834Z,1650309311.834 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2022-04-18T19:15:12.188Z,1650309312.188 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2022-04-18T19:15:12.189Z,1650309312.189 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2022-04-18T19:15:12.372Z,1650309312.372 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2022-04-18T19:15:12.373Z,1650309312.373 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2022-04-18T19:15:12.583Z,1650309312.583 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2022-04-18T19:15:12.584Z,1650309312.584 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2022-04-18T19:15:13.183Z,1650309313.183 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2022-04-18T19:15:13.184Z,1650309313.184 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2022-04-18T19:15:13.469Z,1650309313.469 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2022-04-18T19:15:13.469Z,1650309313.469 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2022-04-18T19:15:13.698Z,1650309313.698 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2022-04-18T19:15:13.698Z,1650309313.698 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2022-04-18T19:15:13.898Z,1650309313.898 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2022-04-18T19:15:13.899Z,1650309313.899 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2022-04-18T19:15:13.997Z,1650309313.997 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2022-04-18T19:15:13.998Z,1650309313.998 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2022-04-18T19:15:14.231Z,1650309314.231 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2022-04-18T19:15:14.232Z,1650309314.232 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2022-04-18T19:15:14.648Z,1650309314.648 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2022-04-18T19:15:14.648Z,1650309314.648 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2022-04-18T19:15:14.730Z,1650309314.730 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2022-04-18T19:15:14.860Z,1650309314.860 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2022-04-18T19:15:14.861Z,1650309314.861 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2022-04-18T19:15:15.613Z,1650309315.613 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2022-04-18T19:15:15.613Z,1650309315.613 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2022-04-18T19:15:16.013Z,1650309316.013 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2022-04-18T19:15:16.015Z,1650309316.015 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-polaris/
2022-04-18T19:15:16.016Z,1650309316.016 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/vehicle.cfg
2022-04-18T19:15:16.233Z,1650309316.233 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/Control.cfg
2022-04-18T19:15:16.337Z,1650309316.337 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/Estimation.cfg
2022-04-18T19:15:16.440Z,1650309316.440 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/BIT.cfg
2022-04-18T19:15:16.543Z,1650309316.543 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/Battery.cfg
2022-04-18T19:15:16.820Z,1650309316.820 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2022-04-18T19:15:16.820Z,1650309316.820 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/Simulator.cfg
2022-04-18T19:15:16.934Z,1650309316.934 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/Navigation.cfg
2022-04-18T19:15:17.039Z,1650309317.039 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/Derivation.cfg
2022-04-18T19:15:17.169Z,1650309317.169 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/logger.cfg
2022-04-18T19:15:17.510Z,1650309317.510 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/secure.cfg
2022-04-18T19:15:17.693Z,1650309317.693 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/Dock.cfg
2022-04-18T19:15:17.785Z,1650309317.785 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/Servo.cfg
2022-04-18T19:15:18.023Z,1650309318.023 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/workSite.cfg
2022-04-18T19:15:18.130Z,1650309318.130 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/Sensor.cfg
2022-04-18T19:15:18.350Z,1650309318.350 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/Science.cfg
2022-04-18T19:15:18.520Z,1650309318.520 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2022-04-18T19:15:18.530Z,1650309318.530 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2022-04-18T19:15:18.960Z,1650309318.960 [AHRS_M2] Loaded
2022-04-18T19:15:18.961Z,1650309318.961 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2022-04-18T19:15:18.996Z,1650309318.996 [BackseatComponent] Loaded
2022-04-18T19:15:18.996Z,1650309318.996 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2022-04-18T19:15:18.997Z,1650309318.997 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 407E14E0
2022-04-18T19:15:18.998Z,1650309318.998 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1658
2022-04-18T19:15:19.000Z,1650309319.000 [LcmUniversalReporter] Loaded
2022-04-18T19:15:19.001Z,1650309319.001 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2022-04-18T19:15:19.813Z,1650309319.813 [BPC1] Loaded
2022-04-18T19:15:19.813Z,1650309319.813 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2022-04-18T19:15:19.885Z,1650309319.885 [DataOverHttps] Loaded
2022-04-18T19:15:19.886Z,1650309319.886 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2022-04-18T19:15:19.887Z,1650309319.887 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408114E0
2022-04-18T19:15:19.887Z,1650309319.887 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1659
2022-04-18T19:15:19.907Z,1650309319.907 [Depth_Keller] Loaded
2022-04-18T19:15:19.908Z,1650309319.908 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2022-04-18T19:15:19.912Z,1650309319.912 [DropWeight] Loaded
2022-04-18T19:15:19.913Z,1650309319.913 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2022-04-18T19:15:19.963Z,1650309319.963 [Micromodem] Loaded
2022-04-18T19:15:19.963Z,1650309319.963 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2022-04-18T19:15:20.023Z,1650309320.023 [NAL9602] Loaded
2022-04-18T19:15:20.023Z,1650309320.023 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2022-04-18T19:15:20.052Z,1650309320.052 [Onboard] Loaded
2022-04-18T19:15:20.053Z,1650309320.053 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2022-04-18T19:15:20.054Z,1650309320.054 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408414E0
2022-04-18T19:15:20.054Z,1650309320.054 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1660
2022-04-18T19:15:20.060Z,1650309320.060 [PowerOnly](INFO): Adding load control power supply at /dev/loadC1
2022-04-18T19:15:20.071Z,1650309320.071 [PowerOnly] Loaded
2022-04-18T19:15:20.072Z,1650309320.072 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2022-04-18T19:15:20.083Z,1650309320.083 [Power24vConverter] Loaded
2022-04-18T19:15:20.083Z,1650309320.083 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2022-04-18T19:15:20.098Z,1650309320.098 [Radio_Surface] Loaded
2022-04-18T19:15:20.098Z,1650309320.098 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2022-04-18T19:15:20.099Z,1650309320.099 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408714E0
2022-04-18T19:15:20.099Z,1650309320.099 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1661
2022-04-18T19:15:20.143Z,1650309320.143 [RDI_Pathfinder] Loaded
2022-04-18T19:15:20.143Z,1650309320.143 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2022-04-18T19:15:20.171Z,1650309320.171 [RDI_PathfinderUp] Loaded
2022-04-18T19:15:20.171Z,1650309320.171 [ComponentRegistry](DEBUG): SyncComponent "RDI_PathfinderUp" handled in the control thread.
2022-04-18T19:15:20.172Z,1650309320.172 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2022-04-18T19:15:20.172Z,1650309320.172 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2022-04-18T19:15:20.183Z,1650309320.183 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2022-04-18T19:15:20.183Z,1650309320.183 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2022-04-18T19:15:20.298Z,1650309320.298 [DeadReckonUsingMultipleVelocitySources] Loaded
2022-04-18T19:15:20.298Z,1650309320.298 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2022-04-18T19:15:20.323Z,1650309320.323 [LBLNavigation] Loaded
2022-04-18T19:15:20.323Z,1650309320.323 [ComponentRegistry](DEBUG): SyncComponent "LBLNavigation" handled in the control thread.
2022-04-18T19:15:20.336Z,1650309320.336 [NavChart] Loaded
2022-04-18T19:15:20.337Z,1650309320.337 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2022-04-18T19:15:20.343Z,1650309320.343 [UniversalFixResidualReporter] Loaded
2022-04-18T19:15:20.343Z,1650309320.343 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2022-04-18T19:15:20.354Z,1650309320.354 [WorkSite] Loaded
2022-04-18T19:15:20.354Z,1650309320.354 [ComponentRegistry](DEBUG): SyncComponent "WorkSite" handled in the control thread.
2022-04-18T19:15:20.355Z,1650309320.355 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2022-04-18T19:15:20.355Z,1650309320.355 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2022-04-18T19:15:20.456Z,1650309320.456 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2022-04-18T19:15:20.457Z,1650309320.457 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2022-04-18T19:15:20.588Z,1650309320.588 [BuoyancyServo] Loaded
2022-04-18T19:15:20.589Z,1650309320.589 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2022-04-18T19:15:20.611Z,1650309320.611 [ElevatorServo] Loaded
2022-04-18T19:15:20.611Z,1650309320.611 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2022-04-18T19:15:20.633Z,1650309320.633 [MassServo] Loaded
2022-04-18T19:15:20.633Z,1650309320.633 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2022-04-18T19:15:20.653Z,1650309320.653 [RudderServo] Loaded
2022-04-18T19:15:20.654Z,1650309320.654 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2022-04-18T19:15:20.671Z,1650309320.671 [ThrusterHE] Loaded
2022-04-18T19:15:20.671Z,1650309320.671 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2022-04-18T19:15:20.672Z,1650309320.672 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2022-04-18T19:15:20.672Z,1650309320.672 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2022-04-18T19:15:20.848Z,1650309320.848 [CTD_Seabird] Loaded
2022-04-18T19:15:20.848Z,1650309320.848 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2022-04-18T19:15:20.849Z,1650309320.849 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409AF4E0
2022-04-18T19:15:20.849Z,1650309320.849 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1662
2022-04-18T19:15:20.870Z,1650309320.870 [PAR_Licor] Loaded
2022-04-18T19:15:20.870Z,1650309320.870 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2022-04-18T19:15:20.903Z,1650309320.903 [WetLabsSeaOWL_UV_A] Loaded
2022-04-18T19:15:20.903Z,1650309320.903 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2022-04-18T19:15:20.905Z,1650309320.905 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 409DF4E0
2022-04-18T19:15:20.905Z,1650309320.905 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 1663
2022-04-18T19:15:20.906Z,1650309320.906 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2022-04-18T19:15:20.906Z,1650309320.906 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2022-04-18T19:15:21.233Z,1650309321.233 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2022-04-18T19:15:21.233Z,1650309321.233 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2022-04-18T19:15:21.287Z,1650309321.287 [DepthRateCalculator] Loaded
2022-04-18T19:15:21.287Z,1650309321.287 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2022-04-18T19:15:21.293Z,1650309321.293 [PitchRateCalculator] Loaded
2022-04-18T19:15:21.293Z,1650309321.293 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2022-04-18T19:15:21.303Z,1650309321.303 [SpeedCalculator] Loaded
2022-04-18T19:15:21.303Z,1650309321.303 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2022-04-18T19:15:21.317Z,1650309321.317 [TempGradientCalculator] Loaded
2022-04-18T19:15:21.317Z,1650309321.317 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2022-04-18T19:15:21.333Z,1650309321.333 [VerticalHomogeneityIndexCalculator] Loaded
2022-04-18T19:15:21.333Z,1650309321.333 [ComponentRegistry](DEBUG): SyncComponent "VerticalHomogeneityIndexCalculator" handled in the control thread.
2022-04-18T19:15:21.338Z,1650309321.338 [YawRateCalculator] Loaded
2022-04-18T19:15:21.338Z,1650309321.338 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2022-04-18T19:15:21.362Z,1650309321.362 [ElevatorOffsetCalculator] Loaded
2022-04-18T19:15:21.362Z,1650309321.362 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2022-04-18T19:15:21.363Z,1650309321.363 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2022-04-18T19:15:21.363Z,1650309321.363 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2022-04-18T19:15:21.509Z,1650309321.509 [SBIT](DEBUG): Construct Startup Built In Test.
2022-04-18T19:15:21.522Z,1650309321.522 [SBIT] Loaded
2022-04-18T19:15:21.522Z,1650309321.522 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2022-04-18T19:15:21.525Z,1650309321.525 [IBIT](DEBUG): Construct Initiated Built In Test.
2022-04-18T19:15:21.538Z,1650309321.538 [IBIT] Loaded
2022-04-18T19:15:21.539Z,1650309321.539 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2022-04-18T19:15:21.545Z,1650309321.545 [CBIT](DEBUG): Construct Continuous Built In Test.
2022-04-18T19:15:21.654Z,1650309321.654 [CBIT] Loaded
2022-04-18T19:15:21.654Z,1650309321.654 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2022-04-18T19:15:21.655Z,1650309321.655 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2022-04-18T19:15:21.655Z,1650309321.655 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2022-04-18T19:15:21.715Z,1650309321.715 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2022-04-18T19:15:21.715Z,1650309321.715 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2022-04-18T19:15:21.827Z,1650309321.827 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2022-04-18T19:15:21.828Z,1650309321.828 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2022-04-18T19:15:21.883Z,1650309321.883 [VerticalControl](DEBUG): Construct VerticalControl.
2022-04-18T19:15:21.937Z,1650309321.937 [VerticalControl] Loaded
2022-04-18T19:15:21.937Z,1650309321.937 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2022-04-18T19:15:21.940Z,1650309321.940 [HorizontalControl](DEBUG): Construct HorizontalControl.
2022-04-18T19:15:21.978Z,1650309321.978 [HorizontalControl] Loaded
2022-04-18T19:15:21.978Z,1650309321.978 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2022-04-18T19:15:21.981Z,1650309321.981 [SpeedControl](DEBUG): Construct SpeedControl.
2022-04-18T19:15:21.983Z,1650309321.983 [SpeedControl] Loaded
2022-04-18T19:15:21.983Z,1650309321.983 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2022-04-18T19:15:21.986Z,1650309321.986 [LoopControl](DEBUG): Construct LoopControl.
2022-04-18T19:15:21.986Z,1650309321.986 [LoopControl] Loaded
2022-04-18T19:15:21.987Z,1650309321.987 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2022-04-18T19:15:21.987Z,1650309321.987 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2022-04-18T19:15:21.987Z,1650309321.987 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2022-04-18T19:15:22.015Z,1650309322.015 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2022-04-18T19:15:22.021Z,1650309322.021 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2022-04-18T19:15:22.024Z,1650309322.024 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2022-04-18T19:15:22.035Z,1650309322.035 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2022-04-18T19:15:22.036Z,1650309322.036 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BA64E0
2022-04-18T19:15:22.036Z,1650309322.036 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1664
2022-04-18T19:15:22.041Z,1650309322.041 [Supervisor](INFO): Main Thread ID is 829
2022-04-18T19:15:22.041Z,1650309322.041 [Supervisor](DEBUG): Running supervisor.
2022-04-18T19:15:22.042Z,1650309322.042 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1665
2022-04-18T19:15:22.042Z,1650309322.042 [CommandExec](INFO): Initializing the command executive.
2022-04-18T19:15:22.043Z,1650309322.043 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1666
2022-04-18T19:15:22.045Z,1650309322.045 [controlThread ThreadHandler](INFO): Handler Thread ID is 1667
2022-04-18T19:15:22.046Z,1650309322.046 [controlThread](DEBUG): Initializing ControlThread
2022-04-18T19:15:22.052Z,1650309322.052 [NavChart](DEBUG): Initialize NavChart Navigation.
2022-04-18T19:15:22.052Z,1650309322.052 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2022-04-18T19:15:22.053Z,1650309322.053 [WorkSite](DEBUG): Initializing WorkSite component.
2022-04-18T19:15:22.057Z,1650309322.057 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2022-04-18T19:15:22.057Z,1650309322.057 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2022-04-18T19:15:22.058Z,1650309322.058 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2022-04-18T19:15:22.058Z,1650309322.058 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2022-04-18T19:15:22.058Z,1650309322.058 [VerticalHomogeneityIndexCalculator](DEBUG): (re)initializing
2022-04-18T19:15:22.059Z,1650309322.059 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2022-04-18T19:15:22.059Z,1650309322.059 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2022-04-18T19:15:22.060Z,1650309322.060 [SBIT](INFO): Initialize SBIT Component.
2022-04-18T19:15:22.060Z,1650309322.060 [SBIT](IMPORTANT): git: 2022-04-13
2022-04-18T19:15:22.061Z,1650309322.061 [SBIT](INFO): git hash: d1e60664077c229c1f2059d1d9396c3c0e4e8f0d
2022-04-18T19:15:22.061Z,1650309322.061 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2022-04-18T19:15:22.062Z,1650309322.062 [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-04-18T19:15:22.063Z,1650309322.063 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2022-04-18T19:15:22.064Z,1650309322.064 [IBIT](INFO): Initialize IBIT Component.
2022-04-18T19:15:22.065Z,1650309322.065 [CBIT](DEBUG): Initialize CBIT Component.
2022-04-18T19:15:22.066Z,1650309322.066 [logger ThreadHandler](INFO): Handler Thread ID is 1668
2022-04-18T19:15:22.077Z,1650309322.077 [CBIT](DEBUG): Initialized mux pins.
2022-04-18T19:15:22.077Z,1650309322.077 [CBIT](DEBUG): Initializing the watchdog timer.
2022-04-18T19:15:22.085Z,1650309322.085 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1669
2022-04-18T19:15:22.097Z,1650309322.097 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1670
2022-04-18T19:15:22.098Z,1650309322.098 [DataOverHttps](DEBUG): dashIP=128.128.181.226 starts with a digit so assuming it is a numeric IP
2022-04-18T19:15:22.101Z,1650309322.101 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2022-04-18T19:15:22.101Z,1650309322.101 [CBIT](DEBUG): Initializing heartbeat.
2022-04-18T19:15:22.109Z,1650309322.109 [Onboard ThreadHandler](INFO): Handler Thread ID is 1671
2022-04-18T19:15:22.127Z,1650309322.127 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1672
2022-04-18T19:15:22.149Z,1650309322.149 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1673
2022-04-18T19:15:22.150Z,1650309322.150 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-04-18T19:15:22.155Z,1650309322.155 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 1675
2022-04-18T19:15:22.161Z,1650309322.161 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1676
2022-04-18T19:15:22.164Z,1650309322.164 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2022-04-18T19:15:22.164Z,1650309322.164 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2022-04-18T19:15:22.165Z,1650309322.165 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2022-04-18T19:15:22.165Z,1650309322.165 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2022-04-18T19:15:22.165Z,1650309322.165 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2022-04-18T19:15:22.165Z,1650309322.165 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2022-04-18T19:15:22.166Z,1650309322.166 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2022-04-18T19:15:22.166Z,1650309322.166 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2022-04-18T19:15:22.166Z,1650309322.166 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2022-04-18T19:15:22.166Z,1650309322.166 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2022-04-18T19:15:22.166Z,1650309322.166 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2022-04-18T19:15:22.166Z,1650309322.166 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2022-04-18T19:15:22.167Z,1650309322.167 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2022-04-18T19:15:22.167Z,1650309322.167 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2022-04-18T19:15:22.167Z,1650309322.167 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2022-04-18T19:15:22.167Z,1650309322.167 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2022-04-18T19:15:22.173Z,1650309322.173 [CBIT](DEBUG): Deactivating GF circuits.
2022-04-18T19:15:22.173Z,1650309322.173 [CBIT](DEBUG): Deactivating emergency mode.
2022-04-18T19:15:22.209Z,1650309322.209 [CBIT](DEBUG): Backplane powered.
2022-04-18T19:15:22.209Z,1650309322.209 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2022-04-18T19:15:22.211Z,1650309322.211 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2022-04-18T19:15:22.211Z,1650309322.211 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2022-04-18T19:15:22.212Z,1650309322.212 [LoopControl](DEBUG): Initialize LoopControlComponent.
2022-04-18T19:15:22.213Z,1650309322.213 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2022-04-18T19:15:22.225Z,1650309322.225 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2022-04-18T19:15:22.248Z,1650309322.248 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2022-04-18T19:15:22.262Z,1650309322.262 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2022-04-18T19:15:22.324Z,1650309322.324 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2022-04-18T19:15:22.325Z,1650309322.325 [Default:A.Wait](DEBUG): Construct Wait.
2022-04-18T19:15:22.327Z,1650309322.327 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2022-04-18T19:15:22.375Z,1650309322.375 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2022-04-18T19:15:22.378Z,1650309322.378 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2022-04-18T19:15:22.408Z,1650309322.408 [Default:E.Execute](DEBUG): Construct Execute.
2022-04-18T19:15:22.411Z,1650309322.411 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2022-04-18T19:15:22.416Z,1650309322.416 [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-04-18T19:15:22.437Z,1650309322.437 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2022-04-18T19:15:22.533Z,1650309322.533 [Radio_Surface](INFO): Powering up
2022-04-18T19:15:22.580Z,1650309322.580 [Power24vConverter](INFO): Powering up.
2022-04-18T19:15:22.682Z,1650309322.682 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2022-04-18T19:15:22.689Z,1650309322.689 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2022-04-18T19:15:22.690Z,1650309322.690 [ElevatorServo](DEBUG): Initializing EZServoServo.
2022-04-18T19:15:22.765Z,1650309322.765 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2022-04-18T19:15:22.766Z,1650309322.766 [MassServo](DEBUG): Initializing EZServoServo.
2022-04-18T19:15:22.845Z,1650309322.845 [MassServo](DEBUG): Initializing MassServo.
2022-04-18T19:15:22.846Z,1650309322.846 [RudderServo](DEBUG): Initializing EZServoServo.
2022-04-18T19:15:22.905Z,1650309322.905 [RudderServo](DEBUG): Initializing RudderServo.
2022-04-18T19:15:22.906Z,1650309322.906 [ThrusterHE](DEBUG): Initializing EZServoServo.
2022-04-18T19:15:22.913Z,1650309322.913 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2022-04-18T19:15:23.002Z,1650309323.002 [Micromodem](INFO): Powering up
2022-04-18T19:15:23.002Z,1650309323.002 [Micromodem](DEBUG): Initializing Micromodem.
2022-04-18T19:15:27.817Z,1650309327.817 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2022-04-18T19:15:28.219Z,1650309328.219 [Micromodem](INFO): Nmea in: $CATMG,2022-04-18T19:15:27.746703Z,RTC,RTC*57

2022-04-18T19:15:28.220Z,1650309328.220 [Micromodem](ERROR): CFG response from modem unexpected: $CATMG,2022-04-18T19:15:27.746703Z,RTC,RTC*57

2022-04-18T19:15:28.627Z,1650309328.627 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31

2022-04-18T19:15:28.627Z,1650309328.627 [Micromodem](INFO): Nmea out: $CCCFG,POW,20*08

2022-04-18T19:15:29.027Z,1650309329.027 [Micromodem](INFO): Nmea in: $CACFG,POW,20*0A

2022-04-18T19:15:29.027Z,1650309329.027 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2022-04-18T19:15:29.431Z,1650309329.431 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33

2022-04-18T19:15:29.431Z,1650309329.431 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64

2022-04-18T19:15:29.835Z,1650309329.835 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66

2022-04-18T19:15:29.835Z,1650309329.835 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F

2022-04-18T19:15:30.243Z,1650309330.243 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D

2022-04-18T19:15:30.244Z,1650309330.244 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E

2022-04-18T19:15:30.631Z,1650309330.631 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C

2022-04-18T19:15:30.631Z,1650309330.631 [Micromodem](INFO): Nmea out: $CCCFG,FC0,14500*47

2022-04-18T19:15:31.039Z,1650309331.039 [Micromodem](INFO): Nmea in: $CACFG,FC0,14500*45

2022-04-18T19:15:31.039Z,1650309331.039 [Micromodem](INFO): Nmea out: $CCCFG,BW0,4000*63

2022-04-18T19:15:31.429Z,1650309331.429 [Micromodem](INFO): Nmea in: $CACFG,BW0,4000*61

2022-04-18T19:15:31.429Z,1650309331.429 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A

2022-04-18T19:15:31.827Z,1650309331.827 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38

2022-04-18T19:15:31.828Z,1650309331.828 [Micromodem](INFO): Nmea out: $CCCLK,2022,04,18,19,15,32*46

2022-04-18T19:15:32.238Z,1650309332.238 [Micromodem](INFO): Nmea in: $CACLK,2022,4,18,19,15,32*74

2022-04-18T19:15:32.240Z,1650309332.240 [Micromodem](INFO): Nmea in: $CATMS,0,2022-04-18T19:15:33Z*75

2022-04-18T19:15:32.635Z,1650309332.635 [Micromodem](INFO): Nmea in: $CATMG,2022-04-18T19:15:33.027186Z,USER_CMD,RTC*18

2022-04-18T19:15:42.748Z,1650309342.748 [Micromodem](INFO): Nmea in: $CAMSG,BAD_CRC, Data Header,2*00

2022-04-18T19:15:43.134Z,1650309343.134 [Micromodem](INFO): Nmea in: $CACST,6,1,20220418191541.593495,06,600,21,0091,0150,74,01,01,01,01,2,-01,-01,2,3,1,0,150,-20.7,2.32,06,0.68,-01,-0.17,41,14500,4000*41

2022-04-18T19:15:43.537Z,1650309343.537 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-04-18T19:15:43.946Z,1650309343.946 [Micromodem](INFO): Nmea in: $CACST,6,1,20220418191543.596135,06,568,21,0118,0150,194,01,01,01,01,-1,-01,-01,1,3,1,0,150,-26.9,0.16,-100,2.84,-01,-1.26,53,14500,4000*40

2022-04-18T19:15:50.404Z,1650309350.404 [NAL9602](INFO): Powering up NAL9602
2022-04-18T19:15:50.457Z,1650309350.457 [SBIT](IMPORTANT): Beginning Startup BIT
2022-04-18T19:15:50.469Z,1650309350.469 [CBIT](IMPORTANT): Beginning ground fault scan
2022-04-18T19:15:51.621Z,1650309351.621 [Micromodem](INFO): Nmea in: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error

2022-04-18T19:15:51.621Z,1650309351.621 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error
 expected 41
2022-04-18T19:15:51.623Z,1650309351.623 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-04-18T19:15:52.027Z,1650309352.027 [Micromodem](INFO): Nmea in: $CACST,6,1,20220418191551.581488,06,1147,24,0107,0150,208,02,02,01,03,-1,-01,-01,1,3,1,0,150,-100.0,0.46,-100,2.54,-01,-1.81,-2147483648,14500,4000*66

2022-04-18T19:15:53.639Z,1650309353.639 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-04-18T19:15:53.644Z,1650309353.644 [Micromodem](INFO): Nmea in: $CACST,6,1,20220418191553.575312,06,1376,25,0096,0150,208,01,01,01,02,-1,-01,-01,1,3,6,0,150,10.9,0.02,-100,2.98,-01,-2.20,59,14500,4000*5D

2022-04-18T19:15:59.712Z,1650309359.712 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-04-18T19:15:59.716Z,1650309359.716 [Micromodem](INFO): Nmea in: $CACST,6,1,20220418191559.586539,06,575,21,0130,0150,141,01,01,01,01,-1,-01,-01,1,3,6,0,150,22.4,0.04,-100,2.96,-01,-0.22,54,14500,4000*6D

2022-04-18T19:16:01.320Z,1650309361.320 [NAL9602](INFO): NAL9602 initialized
2022-04-18T19:16:01.410Z,1650309361.410 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.008568
CHAN A1 (24V): -0.027860
CHAN A2 (12V): -0.006883
CHAN A3 (5V): -0.002348
CHAN B0 (3.3V): 0.000231
CHAN B1 (3.15aV): 0.000320
CHAN B2 (3.15bV): 0.000555
CHAN B3 (GND): 0.002378
OPEN: 0.004455
Full Scale: +/- 1 mA
2022-04-18T19:16:05.772Z,1650309365.772 [Micromodem](INFO): Nmea in: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error

2022-04-18T19:16:05.772Z,1650309365.772 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error
 expected 41
2022-04-18T19:16:05.805Z,1650309365.805 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-04-18T19:16:06.167Z,1650309366.167 [Micromodem](INFO): Nmea in: $CACST,6,1,20220418191605.595442,06,791,22,0107,0150,194,02,02,01,02,-1,-01,-01,1,3,4,0,150,69.0,1.15,-100,1.85,-01,-2.57,-12,14500,4000*4A

2022-04-18T19:16:29.633Z,1650309389.633 [Micromodem](INFO): Nmea in: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error

2022-04-18T19:16:29.634Z,1650309389.634 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error
 expected 41
2022-04-18T19:16:29.635Z,1650309389.635 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-04-18T19:16:30.026Z,1650309390.026 [Micromodem](INFO): Nmea in: $CACST,6,1,20220418191629.574315,06,634,22,0095,0150,194,01,01,01,02,-1,-01,-01,1,3,5,0,150,69.3,1.40,-100,1.60,-01,-1.66,-12,14500,4000*42

2022-04-18T19:16:30.430Z,1650309390.430 [NAL9602](INFO): SBD MO Status=2, MOMSN=36041, MT Status=2, MTMSN=0
2022-04-18T19:16:30.430Z,1650309390.430 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-04-18T19:16:44.586Z,1650309404.586 [SBIT](IMPORTANT): SBIT PASSED
2022-04-18T19:16:44.586Z,1650309404.586 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2022-04-18T19:16:44.587Z,1650309404.587 [SBIT](IMPORTANT): CBIT.gfScanTimeout=2 hour;

2022-04-18T19:16:44.587Z,1650309404.587 [SBIT](IMPORTANT): CTD_Seabird.loadAtStartup=1 bool;

2022-04-18T19:16:44.587Z,1650309404.587 [SBIT](IMPORTANT): Express linearApproximation CTD_Seabird.bin_median_sea_water_salinity 0.050000 practical_salinity_unit;

2022-04-18T19:16:44.587Z,1650309404.587 [SBIT](IMPORTANT): Express linearApproximation WetLabsSeaOWL_UV_A.mass_concentration_of_petroleum_hydrocarbons_in_sea_water 0.000020 kilogram_per_cubic_meter;

2022-04-18T19:16:44.588Z,1650309404.588 [SBIT](IMPORTANT): Express none mass_concentration_of_chlorophyll_in_sea_water;

2022-04-18T19:16:44.588Z,1650309404.588 [SBIT](IMPORTANT): InternalEnvSim.timeAdjust=1.5 day;

2022-04-18T19:16:44.588Z,1650309404.588 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=200 cubic_centimeter;

2022-04-18T19:16:44.588Z,1650309404.588 [SBIT](IMPORTANT): VerticalControl.massDefault=2 millimeter;
2022-04-18T19:16:44.976Z,1650309404.976 [WorkSite](IMPORTANT): Reading workSite position from Data/workSite.cfg
2022-04-18T19:16:44.979Z,1650309404.979 [WorkSite](IMPORTANT): WorkSite fix at 20220418T191423: (41.529666, -70.767989)
2022-04-18T19:16:44.997Z,1650309404.997 [MissionManager](IMPORTANT): Started mission Startup
2022-04-18T19:16:44.997Z,1650309404.997 [Startup] Running Loop=1
2022-04-18T19:16:44.997Z,1650309404.997 [Startup](DEBUG): Aggregate::initialize Startup
2022-04-18T19:16:44.997Z,1650309404.997 [Startup:A.GoToSurface] Running Loop=1
2022-04-18T19:16:44.997Z,1650309404.997 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-04-18T19:16:44.998Z,1650309404.998 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-04-18T19:16:44.998Z,1650309404.998 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-04-18T19:16:44.998Z,1650309404.998 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-04-18T19:16:44.999Z,1650309404.999 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-04-18T19:16:44.999Z,1650309404.999 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-04-18T19:16:45.005Z,1650309405.005 [Startup:StartupSatComms] Running Loop=1
2022-04-18T19:16:45.005Z,1650309405.005 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2022-04-18T19:16:45.005Z,1650309405.005 [Startup:StartupSatComms:A] Running Loop=1
2022-04-18T19:16:45.382Z,1650309405.382 [LBLNavigation](INFO): Reset ping filters
2022-04-18T19:16:45.383Z,1650309405.383 [LBLNavigation](INFO): Reset fix filter
2022-04-18T19:16:45.384Z,1650309405.384 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2022-04-18T19:16:51.422Z,1650309411.422 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-04-18T19:16:51.834Z,1650309411.834 [Micromodem](INFO): Nmea in: $CACST,6,1,20220418191651.586582,06,669,22,0114,0150,208,00,00,01,00,-1,-01,-01,1,3,6,0,150,24.6,-0.01,-100,3.01,-01,0.54,59,14500,4000*60

2022-04-18T19:16:53.452Z,1650309413.452 [Micromodem](INFO): Nmea in: $CAMSG,Error accessing coproc,4*51

2022-04-18T19:16:53.458Z,1650309413.458 [Micromodem](INFO): Nmea in: $CACST,6,1,20220418191653.580184,06,560,21,0800,0150,179,03,03,01,03,-1,-01,-01,4,3,0,0,-999,-99.9,-9.99,-999,-9.99,-01,0.00,-999,14500,4000*5D

2022-04-18T19:16:55.464Z,1650309415.464 [Micromodem](INFO): Nmea in: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error

2022-04-18T19:16:55.464Z,1650309415.464 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error
 expected 41
2022-04-18T19:16:55.467Z,1650309415.467 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-04-18T19:16:55.927Z,1650309415.927 [Micromodem](INFO): Nmea in: $CACST,6,1,20220418191655.579185,06,1055,24,0112,0150,194,00,00,01,00,-1,-01,-01,1,3,1,0,150,-100.0,0.00,-100,3.00,-01,-0.03,-2147483648,14500,4000*64

2022-04-18T19:17:08.384Z,1650309428.384 [NAL9602](INFO): SBD MO Status=0, MOMSN=36041, MT Status=0, MTMSN=0
2022-04-18T19:17:08.385Z,1650309428.385 [NAL9602](INFO): No messages in MT queue
2022-04-18T19:17:09.601Z,1650309429.601 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191710.00,A,4131.75483,N,07046.86405,W,0.991,0.00,180422,,,A*7F
2022-04-18T19:17:09.604Z,1650309429.604 [NAL9602](INFO): GPS fix at 20220418T191710: (41.529247, -70.781068)
2022-04-18T19:17:09.616Z,1650309429.616 [Startup:StartupSatComms:A] Stopped
2022-04-18T19:17:09.616Z,1650309429.616 [Startup:StartupSatComms:B] Running Loop=1
2022-04-18T19:17:10.166Z,1650309430.166 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-04-18T19:17:27.524Z,1650309447.524 [NAL9602](INFO): SBD MO Status=1, MOMSN=36042, MT Status=0, MTMSN=0
2022-04-18T19:17:27.573Z,1650309447.573 [NAL9602](INFO): Sent 52 bytes from file Logs/20220418T185344/Courier0016.lzma
2022-04-18T19:17:27.573Z,1650309447.573 [NAL9602](INFO): Packets left to send: 0
2022-04-18T19:17:33.665Z,1650309453.665 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-04-18T19:17:34.080Z,1650309454.080 [Micromodem](INFO): Nmea in: $CACST,6,1,20220418191733.578896,06,1226,24,0141,0150,149,02,02,01,02,-1,-01,-01,1,3,1,0,150,22.2,0.09,-100,2.91,-01,-1.13,50,14500,4000*5E

2022-04-18T19:17:40.944Z,1650309460.944 [Micromodem](INFO): Nmea in: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error

2022-04-18T19:17:40.945Z,1650309460.945 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error
 expected 41
2022-04-18T19:17:41.335Z,1650309461.335 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-04-18T19:17:41.746Z,1650309461.746 [Micromodem](INFO): Nmea in: $CACST,6,1,20220418191739.592349,06,272,24,0103,0150,179,02,02,00,02,-1,-01,-01,1,3,8,0,150,76.1,0.22,-100,2.78,-01,-0.96,-12,14500,4000*4F

2022-04-18T19:17:43.768Z,1650309463.768 [NAL9602](INFO): SBD MO Status=1, MOMSN=36043, MT Status=0, MTMSN=0
2022-04-18T19:17:43.825Z,1650309463.825 [NAL9602](INFO): Sent 184 bytes from file Logs/20220418T191511/Courier0000.lzma
2022-04-18T19:17:43.825Z,1650309463.825 [NAL9602](INFO): Packets left to send: 1
2022-04-18T19:17:49.640Z,1650309469.640 [Micromodem](INFO): Nmea in: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error

2022-04-18T19:17:49.640Z,1650309469.640 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error
 expected 41
2022-04-18T19:17:49.991Z,1650309469.991 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-04-18T19:17:50.456Z,1650309470.456 [Micromodem](INFO): Nmea in: $CACST,6,1,20220418191749.600401,06,643,22,0233,0150,194,03,03,01,03,-1,-01,-01,1,3,5,0,150,80.2,1.16,-100,1.84,-01,0.50,-18,14500,4000*64

2022-04-18T19:17:59.236Z,1650309479.236 [NAL9602](INFO): SBD MO Status=2, MOMSN=36044, MT Status=2, MTMSN=0
2022-04-18T19:17:59.236Z,1650309479.236 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-04-18T19:17:59.630Z,1650309479.630 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-04-18T19:18:00.041Z,1650309480.041 [Micromodem](INFO): Nmea in: $CACST,6,1,20220418191759.586306,06,799,23,0401,0150,194,01,01,01,02,-1,-01,-01,1,3,1,0,150,14.4,0.87,-100,2.13,-01,-0.26,56,14500,4000*69

2022-04-18T19:18:06.656Z,1650309486.656 [Micromodem](INFO): Nmea in: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error

2022-04-18T19:18:06.656Z,1650309486.656 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error
 expected 41
2022-04-18T19:18:07.005Z,1650309487.005 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-04-18T19:18:07.443Z,1650309487.443 [Micromodem](INFO): Nmea in: $CACST,6,1,20220418191805.584632,06,658,22,0229,0150,141,01,01,01,01,-1,-01,-01,1,3,8,0,150,55.4,1.30,-100,1.70,-01,-1.98,-6,14500,4000*7E

2022-04-18T19:18:09.838Z,1650309489.838 [Startup:StartupSatComms:B](INFO): Timed out from 2022-04-18T19:17:09.6Z
2022-04-18T19:18:09.839Z,1650309489.839 [Startup:StartupSatComms:B] Stopped
2022-04-18T19:18:09.839Z,1650309489.839 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2022-04-18T19:18:09.839Z,1650309489.839 [Startup:StartupSatComms] Stopped
2022-04-18T19:18:09.839Z,1650309489.839 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2022-04-18T19:18:09.840Z,1650309489.840 [Startup](INFO): Completed Startup
2022-04-18T19:18:09.840Z,1650309489.840 [MissionManager](INFO): Startup is completed.
2022-04-18T19:18:09.840Z,1650309489.840 [MissionManager](INFO): Uninitializing Mission Startup
2022-04-18T19:18:09.840Z,1650309489.840 [Startup] Stopped
2022-04-18T19:18:09.840Z,1650309489.840 [Startup](DEBUG): Aggregate::uninitialize Startup
2022-04-18T19:18:09.840Z,1650309489.840 [Startup:A.GoToSurface] Stopped
2022-04-18T19:18:09.840Z,1650309489.840 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-04-18T19:18:10.260Z,1650309490.260 [MissionManager](IMPORTANT): Started mission Default
2022-04-18T19:18:10.261Z,1650309490.261 [Default] Running Loop=1
2022-04-18T19:18:10.261Z,1650309490.261 [Default](DEBUG): Aggregate::initialize Default
2022-04-18T19:18:10.261Z,1650309490.261 [Default:B.GoToSurface] Running Loop=1
2022-04-18T19:18:10.261Z,1650309490.261 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-04-18T19:18:10.261Z,1650309490.261 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-04-18T19:18:10.261Z,1650309490.261 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-04-18T19:18:10.262Z,1650309490.262 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-04-18T19:18:10.262Z,1650309490.262 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-04-18T19:18:10.262Z,1650309490.262 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-04-18T19:18:10.263Z,1650309490.263 [Default:A.Wait] Running Loop=1
2022-04-18T19:18:10.263Z,1650309490.263 [Default:A.Wait](DEBUG): Initialize Wait Component.
2022-04-18T19:18:13.881Z,1650309493.881 [Micromodem](INFO): Nmea in: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error

2022-04-18T19:18:13.881Z,1650309493.881 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error
 expected 41
2022-04-18T19:18:13.883Z,1650309493.883 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-04-18T19:18:14.278Z,1650309494.278 [Micromodem](INFO): Nmea in: $CACST,6,1,20220418191813.579209,06,1624,26,0188,0150,194,02,02,01,02,-1,-01,-01,1,3,2,0,150,68.8,0.39,-100,2.61,-01,1.37,-12,14500,4000*54

2022-04-18T19:18:23.568Z,1650309503.568 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-04-18T19:18:23.591Z,1650309503.591 [Micromodem](INFO): Nmea in: $CACST,6,1,20220418191823.579532,06,1034,24,0109,0150,134,04,04,01,04,-1,-01,-01,1,3,1,0,150,2.3,0.49,-100,2.51,-01,-0.99,54,14500,4000*6F

2022-04-18T19:18:23.604Z,1650309503.604 [Default:A.Wait](INFO): Done Waiting.
2022-04-18T19:18:23.604Z,1650309503.604 [Default:A.Wait] Stopped
2022-04-18T19:18:23.605Z,1650309503.605 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2022-04-18T19:18:24.005Z,1650309504.005 [Default:CheckIn] Running Loop=1
2022-04-18T19:18:24.005Z,1650309504.005 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-18T19:18:24.005Z,1650309504.005 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-18T19:18:24.421Z,1650309504.421 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2022-04-18T19:18:25.589Z,1650309505.589 [Micromodem](INFO): Nmea in: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error

2022-04-18T19:18:25.589Z,1650309505.589 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error
 expected 41
2022-04-18T19:18:25.591Z,1650309505.591 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-04-18T19:18:26.002Z,1650309506.002 [Micromodem](INFO): Nmea in: $CACST,6,1,20220418191825.594214,06,1047,24,0137,0150,194,02,02,01,02,-1,-01,-01,1,3,1,0,150,-100.0,0.59,-100,2.41,-01,-1.21,-2147483648,14500,4000*6A

2022-04-18T19:18:27.204Z,1650309507.204 [NAL9602](INFO): SBD MO Status=2, MOMSN=36044, MT Status=2, MTMSN=0
2022-04-18T19:18:27.204Z,1650309507.204 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-04-18T19:18:27.611Z,1650309507.611 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-04-18T19:18:27.616Z,1650309507.616 [Micromodem](INFO): Nmea in: $CACST,6,1,20220418191827.590664,06,613,21,0107,0150,149,04,04,01,04,-1,-01,-01,1,3,1,0,150,7.9,0.13,-100,2.87,-01,-0.56,50,14500,4000*53

2022-04-18T19:18:28.413Z,1650309508.413 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191828.00,A,4131.75002,N,07046.89812,W,1.400,266.76,180422,,,A*74
2022-04-18T19:18:28.415Z,1650309508.415 [NAL9602](INFO): GPS fix at 20220418T191828: (41.529167, -70.781635)
2022-04-18T19:18:28.427Z,1650309508.427 [Default:CheckIn:Read_GPS] Stopped
2022-04-18T19:18:28.427Z,1650309508.427 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-18T19:18:28.883Z,1650309508.883 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-04-18T19:18:36.988Z,1650309516.988 [NAL9602](INFO): SBD MO Status=1, MOMSN=36044, MT Status=0, MTMSN=0
2022-04-18T19:18:37.037Z,1650309517.037 [NAL9602](INFO): Sent 70 bytes from file Logs/20220418T191511/Courier0000.lzma
2022-04-18T19:18:37.037Z,1650309517.037 [NAL9602](INFO): Packets left to send: 0
2022-04-18T19:18:44.416Z,1650309524.416 [Micromodem](INFO): Nmea in: $CAMSG,BAD_CRC, Data Header,2*00

2022-04-18T19:18:44.784Z,1650309524.784 [Micromodem](INFO): Nmea in: $CACST,6,1,20220418191839.575831,06,979,23,0095,0150,194,01,01,01,01,3,-01,-01,2,3,2,0,150,9.5,-7.94,00,10.94,-01,-0.51,56,14500,4000*73

2022-04-18T19:18:48.041Z,1650309528.041 [NAL9602](INFO): SBD MO Status=1, MOMSN=36045, MT Status=0, MTMSN=0
2022-04-18T19:18:48.089Z,1650309528.089 [NAL9602](INFO): Sent 72 bytes from file Logs/20220418T191511/Courier0004.lzma
2022-04-18T19:18:48.089Z,1650309528.089 [NAL9602](INFO): Packets left to send: 0
2022-04-18T19:18:51.021Z,1650309531.021 [Micromodem](INFO): Nmea in: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error

2022-04-18T19:18:51.022Z,1650309531.022 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error
 expected 41
2022-04-18T19:18:51.392Z,1650309531.392 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-04-18T19:18:51.796Z,1650309531.796 [Micromodem](INFO): Nmea in: $CACST,6,1,20220418191849.593659,06,824,23,0101,0150,208,01,01,01,01,-1,-01,-01,1,3,4,0,150,65.1,1.36,-100,1.64,-01,-1.71,-6,14500,4000*70

2022-04-18T19:18:53.416Z,1650309533.416 [Micromodem](INFO): Nmea in: $CAMSG,Error accessing coproc,4*51

2022-04-18T19:18:53.819Z,1650309533.819 [Micromodem](INFO): Nmea in: $CACST,6,1,20220418191853.596122,06,573,21,0111,0150,149,01,01,01,01,-1,-01,-01,4,3,0,0,-999,-99.9,-9.99,-999,-9.99,-01,0.00,-999,14500,4000*52

2022-04-18T19:18:57.457Z,1650309537.457 [NAL9602](INFO): SBD MO Status=1, MOMSN=36046, MT Status=0, MTMSN=0
2022-04-18T19:18:57.517Z,1650309537.517 [NAL9602](INFO): Sent 132 bytes from file Logs/20220418T185344/Express0014.lzma
2022-04-18T19:18:57.517Z,1650309537.517 [NAL9602](INFO): Packets left to send: 0
2022-04-18T19:19:01.655Z,1650309541.655 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-04-18T19:19:02.062Z,1650309542.062 [Micromodem](INFO): Nmea in: $CACST,6,1,20220418191901.599713,06,532,21,0309,0150,186,01,01,01,01,-1,-01,-01,1,3,1,0,150,20.4,0.05,-100,2.95,-01,-3.39,56,14500,4000*64

2022-04-18T19:19:03.683Z,1650309543.683 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-04-18T19:19:04.098Z,1650309544.098 [Micromodem](INFO): Nmea in: $CACST,6,1,20220418191903.603078,06,1059,24,0095,0150,208,03,03,01,03,-1,-01,-01,1,3,1,0,150,3.8,1.01,-100,1.99,-01,-1.95,59,14500,4000*6D

2022-04-18T19:19:08.115Z,1650309548.115 [NAL9602](INFO): SBD MO Status=1, MOMSN=36047, MT Status=0, MTMSN=0
2022-04-18T19:19:08.173Z,1650309548.173 [NAL9602](INFO): Sent 184 bytes from file Logs/20220418T185344/Express0017.lzma
2022-04-18T19:19:08.173Z,1650309548.173 [NAL9602](INFO): Packets left to send: 0
2022-04-18T19:19:13.501Z,1650309553.501 [Micromodem](INFO): Nmea in: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error

2022-04-18T19:19:13.502Z,1650309553.502 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error
 expected 41
2022-04-18T19:19:13.884Z,1650309553.884 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-04-18T19:19:14.302Z,1650309554.302 [Micromodem](INFO): Nmea in: $CACST,6,1,20220418191913.591742,06,561,21,0112,0150,208,02,02,01,02,-1,-01,-01,1,3,7,0,150,71.1,2.99,-100,0.01,-01,1.63,-12,14500,4000*61

2022-04-18T19:19:19.543Z,1650309559.543 [Micromodem](INFO): Nmea in: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error

2022-04-18T19:19:19.544Z,1650309559.544 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error
 expected 41
2022-04-18T19:19:19.943Z,1650309559.943 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-04-18T19:19:20.346Z,1650309560.346 [Micromodem](INFO): Nmea in: $CACST,6,1,20220418191919.599844,06,128,21,0150,0150,179,02,02,00,02,-1,-01,-01,1,3,1,0,150,-100.0,0.38,-100,2.62,-01,1.13,-2147483648,14500,4000*73

2022-04-18T19:19:29.298Z,1650309569.298 [NAL9602](INFO): SBD MO Status=1, MOMSN=36048, MT Status=0, MTMSN=0
2022-04-18T19:19:29.353Z,1650309569.353 [NAL9602](INFO): Sent 184 bytes from file Logs/20220418T191511/Express0001.lzma
2022-04-18T19:19:29.353Z,1650309569.353 [NAL9602](INFO): Packets left to send: 5
2022-04-18T19:19:32.224Z,1650309572.224 [Micromodem](INFO): Nmea in: $CAMSG,BAD_CRC, Data Header,2*00

2022-04-18T19:19:32.606Z,1650309572.606 [Micromodem](INFO): Nmea in: $CACST,6,1,20220418191929.573372,06,934,23,0319,0150,208,02,02,01,02,4,-01,-01,2,3,1,0,150,6.3,0.00,-100,3.00,-01,-0.47,59,14500,4000*73

2022-04-18T19:19:37.860Z,1650309577.860 [Micromodem](INFO): Nmea in: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error

2022-04-18T19:19:37.860Z,1650309577.860 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error
 expected 41
2022-04-18T19:19:38.257Z,1650309578.257 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-04-18T19:19:38.673Z,1650309578.673 [Micromodem](INFO): Nmea in: $CACST,6,1,20220418191937.591100,06,522,21,0098,0150,134,01,01,01,01,-1,-01,-01,1,3,5,0,150,59.0,-0.51,-100,3.51,-01,-2.61,-6,14500,4000*51

2022-04-18T19:19:40.703Z,1650309580.703 [NAL9602](INFO): SBD MO Status=1, MOMSN=36049, MT Status=0, MTMSN=0
2022-04-18T19:19:40.756Z,1650309580.756 [NAL9602](INFO): Sent 184 bytes from file Logs/20220418T191511/Express0001.lzma
2022-04-18T19:19:40.757Z,1650309580.757 [NAL9602](INFO): Packets left to send: 4
2022-04-18T19:19:41.494Z,1650309581.494 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-04-18T19:19:43.654Z,1650309583.654 [Micromodem](INFO): Nmea in: $CACST,6,1,20220418191941.576014,06,1505,25,0129,0150,208,02,02,01,03,-1,-01,-01,1,3,1,0,150,1.6,0.40,-100,2.60,-01,-2.52,59,14500,4000*6E

2022-04-18T19:19:44.848Z,1650309584.848 [Micromodem](INFO): Nmea in: $CAMSG,Error accessing coproc,4*51

2022-04-18T19:19:45.264Z,1650309585.264 [Micromodem](INFO): Nmea in: $CACST,6,1,20220418191943.595101,06,641,22,0114,0150,208,03,03,01,03,-1,-01,-01,4,3,0,0,-999,-99.9,-9.99,-999,-9.99,-01,0.00,-999,14500,4000*50

2022-04-18T19:19:46.460Z,1650309586.460 [Micromodem](INFO): Nmea in: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error

2022-04-18T19:19:46.461Z,1650309586.461 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error
 expected 41
2022-04-18T19:19:46.859Z,1650309586.859 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-04-18T19:19:47.266Z,1650309587.266 [Micromodem](INFO): Nmea in: $CACST,6,1,20220418191945.576589,06,1337,25,0107,0150,194,03,03,01,03,-1,-01,-01,1,3,1,0,150,-100.0,0.54,-100,2.46,-01,-0.48,-2147483648,14500,4000*61

2022-04-18T19:19:48.484Z,1650309588.484 [Micromodem](INFO): Nmea in: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error

2022-04-18T19:19:48.484Z,1650309588.484 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error
 expected 41
2022-04-18T19:19:48.875Z,1650309588.875 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-04-18T19:19:49.287Z,1650309589.287 [Micromodem](INFO): Nmea in: $CACST,6,1,20220418191947.593066,06,168,22,0247,0150,194,03,03,00,03,-1,-01,-01,1,3,2,0,150,79.0,4.00,-100,-1.00,-01,-2.91,-18,14500,4000*66

2022-04-18T19:19:50.491Z,1650309590.491 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-04-18T19:19:50.918Z,1650309590.918 [Micromodem](INFO): Nmea in: $CACST,6,1,20220418191949.580891,06,1331,25,0151,0150,223,02,02,01,02,-1,-01,-01,1,3,1,0,150,8.2,0.18,-100,2.82,-01,-2.84,61,14500,4000*60

2022-04-18T19:19:53.013Z,1650309593.013 [NAL9602](INFO): SBD MO Status=1, MOMSN=36050, MT Status=0, MTMSN=0
2022-04-18T19:19:53.071Z,1650309593.071 [NAL9602](INFO): Sent 184 bytes from file Logs/20220418T191511/Express0001.lzma
2022-04-18T19:19:53.071Z,1650309593.071 [NAL9602](INFO): Packets left to send: 3
2022-04-18T19:19:57.474Z,1650309597.474 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-04-18T19:19:57.886Z,1650309597.886 [Micromodem](INFO): Nmea in: $CACST,6,1,20220418191957.591869,06,883,23,0112,0150,141,01,01,01,01,-1,-01,-01,1,3,7,0,150,28.4,0.01,-100,2.99,-01,-0.54,49,14500,4000*6B

2022-04-18T19:19:59.486Z,1650309599.486 [Micromodem](INFO): Nmea in: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error

2022-04-18T19:19:59.487Z,1650309599.487 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error
 expected 41
2022-04-18T19:19:59.890Z,1650309599.890 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-04-18T19:20:00.284Z,1650309600.284 [Micromodem](INFO): Nmea in: $CACST,6,1,20220418191959.573357,06,1293,25,0138,0150,216,02,02,01,02,-1,-01,-01,1,3,7,0,150,71.8,2.29,-100,0.71,-01,-2.21,-12,14500,4000*76

2022-04-18T19:20:02.308Z,1650309602.308 [NAL9602](INFO): SBD MO Status=1, MOMSN=36051, MT Status=0, MTMSN=0
2022-04-18T19:20:02.359Z,1650309602.359 [NAL9602](INFO): Sent 184 bytes from file Logs/20220418T191511/Express0001.lzma
2022-04-18T19:20:02.359Z,1650309602.359 [NAL9602](INFO): Packets left to send: 2
2022-04-18T19:20:05.302Z,1650309605.302 [Micromodem](INFO): Nmea in: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error

2022-04-18T19:20:05.303Z,1650309605.303 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error
 expected 41
2022-04-18T19:20:05.663Z,1650309605.663 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-04-18T19:20:06.074Z,1650309606.074 [Micromodem](INFO): Nmea in: $CACST,6,1,20220418192003.597945,06,556,21,0166,0150,208,03,03,01,03,-1,-01,-01,1,3,1,0,150,-100.0,0.42,-100,2.58,-01,-2.28,-2147483648,14500,4000*53

2022-04-18T19:20:11.728Z,1650309611.728 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-04-18T19:20:12.153Z,1650309612.153 [Micromodem](INFO): Nmea in: $CACST,6,1,20220418192011.594223,06,174,22,0130,0150,141,01,01,00,01,-1,-01,-01,1,3,8,0,150,25.9,0.04,-100,2.96,-01,-1.29,51,14500,4000*64

2022-04-18T19:20:13.740Z,1650309613.740 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-04-18T19:20:14.150Z,1650309614.150 [Micromodem](INFO): Nmea in: $CACST,6,1,20220418192013.591161,06,806,23,0091,0150,208,01,01,01,01,-1,-01,-01,1,3,6,0,150,18.2,-0.02,-100,3.02,-01,-0.65,59,14500,4000*43

2022-04-18T19:20:14.971Z,1650309614.971 [NAL9602](INFO): SBD MO Status=1, MOMSN=36052, MT Status=0, MTMSN=0
2022-04-18T19:20:15.021Z,1650309615.021 [NAL9602](INFO): Sent 184 bytes from file Logs/20220418T191511/Express0001.lzma
2022-04-18T19:20:15.021Z,1650309615.021 [NAL9602](INFO): Packets left to send: 1
2022-04-18T19:20:15.768Z,1650309615.768 [Micromodem](INFO): Nmea in: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error

2022-04-18T19:20:15.769Z,1650309615.769 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error
 expected 41
2022-04-18T19:20:17.950Z,1650309617.950 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-04-18T19:20:18.317Z,1650309618.317 [Micromodem](INFO): Nmea in: $CACST,6,1,20220418192015.582149,06,567,21,0439,0150,149,04,04,01,04,-1,-01,-01,1,3,3,0,150,74.4,0.88,-100,2.12,-01,1.09,-24,14500,4000*67

2022-04-18T19:20:19.523Z,1650309619.523 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-04-18T19:20:19.930Z,1650309619.930 [Micromodem](INFO): Nmea in: $CACST,6,1,20220418192017.599299,06,702,22,0264,0150,141,01,01,01,01,-1,-01,-01,1,3,6,0,150,26.3,-0.04,-100,3.04,-01,-2.88,49,14500,4000*4B

2022-04-18T19:20:24.840Z,1650309624.840 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2022-04-18T19:20:28.008Z,1650309628.008 [Micromodem](INFO): Nmea in: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error

2022-04-18T19:20:28.009Z,1650309628.009 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error
 expected 41
2022-04-18T19:20:28.011Z,1650309628.011 [NAL9602](INFO): SBD MO Status=1, MOMSN=36053, MT Status=0, MTMSN=0
2022-04-18T19:20:28.077Z,1650309628.077 [NAL9602](INFO): Sent 70 bytes from file Logs/20220418T191511/Express0001.lzma
2022-04-18T19:20:28.077Z,1650309628.077 [NAL9602](INFO): Packets left to send: 0
2022-04-18T19:20:28.411Z,1650309628.411 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-04-18T19:20:28.827Z,1650309628.827 [Micromodem](INFO): Nmea in: $CACST,6,1,20220418192027.576053,06,756,22,0098,0150,208,02,02,01,02,-1,-01,-01,1,3,3,0,150,71.3,0.40,-100,2.60,-01,1.34,-12,14500,4000*63

2022-04-18T19:20:34.180Z,1650309634.180 [Micromodem](INFO): Nmea in: $CAMSG,BAD_CRC, Data Header,2*00

2022-04-18T19:20:34.586Z,1650309634.586 [Micromodem](INFO): Nmea in: $CACST,6,1,20220418192033.598154,06,1118,24,0100,0150,216,02,02,01,02,5,-01,-01,2,3,1,0,150,-32.8,7.21,-100,-4.21,-01,-0.68,59,14500,4000*74

2022-04-18T19:20:39.029Z,1650309639.029 [NAL9602](INFO): SBD MO Status=1, MOMSN=36054, MT Status=0, MTMSN=0
2022-04-18T19:20:39.084Z,1650309639.084 [NAL9602](INFO): Sent 94 bytes from file Logs/20220418T191511/Express0005.lzma
2022-04-18T19:20:39.084Z,1650309639.084 [NAL9602](INFO): Packets left to send: 0
2022-04-18T19:20:45.493Z,1650309645.493 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-04-18T19:20:45.926Z,1650309645.926 [Micromodem](INFO): Nmea in: $CACST,6,1,20220418192045.582448,06,618,21,0105,0150,194,02,02,01,02,-1,-01,-01,1,3,1,0,150,14.0,0.88,-100,2.12,-01,0.30,57,14500,4000*43

2022-04-18T19:20:46.301Z,1650309646.301 [NAL9602](INFO): SBD MO Status=0, MOMSN=36055, MT Status=0, MTMSN=0
2022-04-18T19:20:46.383Z,1650309646.383 [Default:CheckIn:Read_Iridium] Stopped
2022-04-18T19:20:46.383Z,1650309646.383 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-18T19:20:46.383Z,1650309646.383 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-18T19:20:49.607Z,1650309649.607 [Micromodem](INFO): Nmea in: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error

2022-04-18T19:20:49.607Z,1650309649.607 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error
 expected 41
2022-04-18T19:20:49.609Z,1650309649.609 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-04-18T19:20:49.950Z,1650309649.950 [Micromodem](INFO): Nmea in: $CACST,6,1,20220418192049.592438,06,168,22,0094,0150,208,01,01,00,01,-1,-01,-01,1,3,1,0,150,-100.0,0.11,-100,2.89,-01,0.33,-2147483648,14500,4000*75

2022-04-18T19:20:53.976Z,1650309653.976 [Micromodem](INFO): Nmea in: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error

2022-04-18T19:20:53.987Z,1650309653.987 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error
 expected 41
2022-04-18T19:20:54.001Z,1650309654.001 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-04-18T19:20:54.386Z,1650309654.386 [Micromodem](INFO): Nmea in: $CACST,6,1,20220418192051.583451,06,1230,24,0125,0150,208,03,03,01,03,-1,-01,-01,1,3,5,0,150,80.5,0.34,-100,2.66,-01,0.54,-18,14500,4000*5C

2022-04-18T19:20:57.615Z,1650309657.615 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-04-18T19:20:57.620Z,1650309657.620 [Micromodem](INFO): Nmea in: $CACST,6,1,20220418192057.595137,06,1035,24,0098,0150,194,02,02,01,02,-1,-01,-01,1,3,1,0,150,13.1,0.56,-100,2.44,-01,-2.28,56,14500,4000*52

2022-04-18T19:21:07.717Z,1650309667.717 [Micromodem](INFO): Nmea in: $CAMSG,Error accessing coproc,4*51

2022-04-18T19:21:07.722Z,1650309667.722 [Micromodem](INFO): Nmea in: $CACST,6,1,20220418192107.595655,06,858,23,0111,0150,208,01,01,01,01,-1,-01,-01,4,3,0,0,-999,-99.9,-9.99,-999,-9.99,-01,0.00,-999,14500,4000*5D

2022-04-18T19:21:07.730Z,1650309667.730 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items
2022-04-18T19:21:17.023Z,1650309677.023 [NAL9602](INFO): Not Powering down - fast GPS
2022-04-18T19:21:17.816Z,1650309677.816 [Micromodem](INFO): Nmea in: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error

2022-04-18T19:21:17.830Z,1650309677.830 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error
 expected 41
2022-04-18T19:21:17.832Z,1650309677.832 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-04-18T19:21:18.218Z,1650309678.218 [Micromodem](INFO): Nmea in: $CACST,6,1,20220418192117.580382,06,619,21,0132,0150,119,01,01,01,02,-1,-01,-01,1,3,2,0,150,58.2,2.19,-100,0.81,-01,-1.27,-12,14500,4000*4B

2022-04-18T19:21:29.601Z,1650309689.601 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-04-18T19:21:29.606Z,1650309689.606 [Micromodem](INFO): Nmea in: $CACST,6,1,20220418192129.572662,06,832,23,0131,0150,208,02,02,01,03,-1,-01,-01,1,3,1,0,150,5.3,0.04,-100,2.96,-01,0.27,58,14500,4000*7B

2022-04-18T19:21:31.561Z,1650309691.561 [Micromodem](INFO): Nmea in: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error

2022-04-18T19:21:31.562Z,1650309691.562 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error
 expected 41
2022-04-18T19:21:31.564Z,1650309691.564 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-04-18T19:21:31.961Z,1650309691.961 [Micromodem](INFO): Nmea in: $CACST,6,1,20220418192131.576561,06,767,22,0121,0150,194,01,01,01,01,-1,-01,-01,1,3,7,0,150,63.2,2.28,-100,0.72,-01,-2.03,-6,14500,4000*71

2022-04-18T19:21:41.248Z,1650309701.248 [Micromodem](INFO): Nmea in: $CAMSG,BAD_CRC, Data Header,2*00

2022-04-18T19:21:41.252Z,1650309701.252 [Micromodem](INFO): Nmea in: $CACST,6,1,20220418192137.580039,06,1606,26,0114,0150,208,03,03,01,03,1,-01,-01,2,3,3,0,150,1.5,0.65,-100,2.35,-01,-5.58,58,14500,4000*4B

2022-04-18T19:21:43.689Z,1650309703.689 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-04-18T19:21:43.698Z,1650309703.698 [Micromodem](INFO): Nmea in: $CACST,6,1,20220418192143.592116,06,839,23,0096,0150,179,03,03,01,03,-1,-01,-01,1,3,3,0,150,7.8,-0.03,-100,3.03,-01,-2.11,55,14500,4000*74

2022-04-18T19:21:45.691Z,1650309705.691 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-04-18T19:21:45.696Z,1650309705.696 [Micromodem](INFO): Nmea in: $CACST,6,1,20220418192145.596653,06,606,21,0217,0150,141,01,01,01,02,-1,-01,-01,1,3,3,0,150,20.3,0.10,-100,2.90,-01,-1.36,49,14500,4000*60

2022-04-18T19:21:47.714Z,1650309707.714 [Micromodem](INFO): Nmea in: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error

2022-04-18T19:21:47.715Z,1650309707.715 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error
 expected 41
2022-04-18T19:21:47.716Z,1650309707.716 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-04-18T19:21:48.120Z,1650309708.120 [Micromodem](INFO): Nmea in: $CACST,6,1,20220418192147.591842,06,963,23,0111,0150,134,00,00,01,01,-1,-01,-01,1,3,1,0,150,-100.0,0.08,-100,2.92,-01,-1.87,-2147483648,14500,4000*5A

2022-04-18T19:21:49.734Z,1650309709.734 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-04-18T19:21:49.739Z,1650309709.739 [Micromodem](INFO): Nmea in: $CACST,6,1,20220418192149.593458,06,620,21,0197,0150,208,01,01,01,01,-1,-01,-01,1,3,6,0,150,16.3,0.01,-100,2.99,-01,-1.42,59,14500,4000*69

2022-04-18T19:21:55.387Z,1650309715.387 [Micromodem](INFO): Nmea in: $CAMSG,BAD_CRC, Data Header,2*00

2022-04-18T19:21:55.392Z,1650309715.392 [Micromodem](INFO): Nmea in: $CACST,6,1,20220418192151.589857,06,253,24,0122,0150,194,00,00,00,01,5,-01,-01,2,3,8,0,150,9.2,4.48,-100,-1.48,-01,-3.75,57,14500,4000*5C

2022-04-18T19:21:58.216Z,1650309718.216 [Micromodem](INFO): Nmea in: $CAMSG,BAD_CRC, Data Header,2*00

2022-04-18T19:21:58.235Z,1650309718.235 [Micromodem](INFO): Nmea in: $CACST,6,1,20220418192157.580772,06,1029,24,0125,0150,194,02,02,01,02,6,-01,-01,2,3,1,0,150,-0.2,3.35,09,-0.35,-01,-2.59,57,14500,4000*52

2022-04-18T19:22:29.728Z,1650309749.728 [Micromodem](INFO): Nmea in: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error

2022-04-18T19:22:29.729Z,1650309749.729 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error
 expected 41
2022-04-18T19:22:29.730Z,1650309749.730 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-04-18T19:22:30.131Z,1650309750.131 [Micromodem](INFO): Nmea in: $CACST,6,1,20220418192229.596343,06,820,23,0098,0150,194,01,01,01,02,-1,-01,-01,1,3,1,0,150,-100.0,0.54,-100,2.46,-01,0.01,-2147483648,14500,4000*71

2022-04-18T19:22:42.654Z,1650309762.654 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2022-04-18T19:22:42.657Z,1650309762.657 [BPC1](INFO): Received data from all battery sticks.
2022-04-18T19:23:01.238Z,1650309781.238 [Micromodem](INFO): Nmea in: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error

2022-04-18T19:23:01.239Z,1650309781.239 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error
 expected 41
2022-04-18T19:23:01.241Z,1650309781.241 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-04-18T19:23:01.651Z,1650309781.651 [Micromodem](INFO): Nmea in: $CACST,6,1,20220418192259.598954,06,1030,24,0093,0150,208,01,01,01,01,-1,-01,-01,1,3,8,0,150,74.8,-6.25,-100,9.25,-01,-3.52,-6,14500,4000*63

2022-04-18T19:25:46.924Z,1650309946.924 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-18T19:25:46.924Z,1650309946.924 [Default:CheckIn:C.Wait] Stopped
2022-04-18T19:25:46.924Z,1650309946.924 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-18T19:25:46.929Z,1650309946.929 [Default:CheckIn:D] Running Loop=1
2022-04-18T19:25:47.292Z,1650309947.292 [Default:CheckIn:D] Stopped
2022-04-18T19:25:47.292Z,1650309947.292 [Default:CheckIn:E] Running Loop=1
2022-04-18T19:25:47.712Z,1650309947.712 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 7.617189 min 
2022-04-18T19:25:47.712Z,1650309947.712 [Default:CheckIn:E] Stopped
2022-04-18T19:25:47.712Z,1650309947.712 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-18T19:25:47.712Z,1650309947.712 [Default:CheckIn] Stopped
2022-04-18T19:25:47.712Z,1650309947.712 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-18T19:25:47.713Z,1650309947.713 [Default:CheckIn](INFO): Running loop #2
2022-04-18T19:25:47.713Z,1650309947.713 [Default:CheckIn] Running Loop=2
2022-04-18T19:25:47.713Z,1650309947.713 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-18T19:25:47.713Z,1650309947.713 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-18T19:25:49.709Z,1650309949.709 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192550.00,A,4131.74552,N,07047.03807,W,0.719,326.42,180422,,,A*7B
2022-04-18T19:25:49.711Z,1650309949.711 [NAL9602](INFO): GPS fix at 20220418T192550: (41.529092, -70.783968)
2022-04-18T19:25:49.746Z,1650309949.746 [Default:CheckIn:Read_GPS] Stopped
2022-04-18T19:25:49.746Z,1650309949.746 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-18T19:26:10.411Z,1650309970.411 [NAL9602](INFO): SBD MO Status=1, MOMSN=36056, MT Status=0, MTMSN=0
2022-04-18T19:26:10.465Z,1650309970.465 [NAL9602](INFO): Sent 184 bytes from file Logs/20220418T191511/Courier0007.lzma
2022-04-18T19:26:10.465Z,1650309970.465 [NAL9602](INFO): Packets left to send: 1
2022-04-18T19:26:21.425Z,1650309981.425 [NAL9602](INFO): SBD MO Status=1, MOMSN=36057, MT Status=0, MTMSN=0
2022-04-18T19:26:21.481Z,1650309981.481 [NAL9602](INFO): Sent 19 bytes from file Logs/20220418T191511/Courier0007.lzma
2022-04-18T19:26:21.481Z,1650309981.481 [NAL9602](INFO): Packets left to send: 0
2022-04-18T19:26:32.410Z,1650309992.410 [NAL9602](INFO): SBD MO Status=1, MOMSN=36058, MT Status=0, MTMSN=0
2022-04-18T19:26:32.465Z,1650309992.465 [NAL9602](INFO): Sent 151 bytes from file Logs/20220418T191511/Express0008.lzma
2022-04-18T19:26:32.465Z,1650309992.465 [NAL9602](INFO): Packets left to send: 0
2022-04-18T19:26:42.941Z,1650310002.941 [NAL9602](INFO): SBD MO Status=0, MOMSN=36059, MT Status=0, MTMSN=0
2022-04-18T19:26:43.051Z,1650310003.051 [Default:CheckIn:Read_Iridium] Stopped
2022-04-18T19:26:43.051Z,1650310003.051 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-18T19:26:43.051Z,1650310003.051 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-18T19:27:13.618Z,1650310033.618 [NAL9602](INFO): Not Powering down - fast GPS
2022-04-18T19:28:32.832Z,1650310112.832 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2022-04-18T19:30:42.970Z,1650310242.970 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items
2022-04-18T19:31:43.489Z,1650310303.489 [Micromodem](INFO): Nmea in: $CAMSG,Error accessing coproc,4*51

2022-04-18T19:31:43.498Z,1650310303.498 [Micromodem](INFO): Nmea in: $CACST,6,1,20220418193143.574148,06,1928,26,0101,0150,208,01,01,01,01,-1,-01,-01,4,3,0,0,-999,-99.9,-9.99,-999,-9.99,-01,0.00,-999,14500,4000*6B

2022-04-18T19:31:43.564Z,1650310303.564 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-18T19:31:43.564Z,1650310303.564 [Default:CheckIn:C.Wait] Stopped
2022-04-18T19:31:43.564Z,1650310303.564 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-18T19:31:43.564Z,1650310303.564 [Default:CheckIn:D] Running Loop=1
2022-04-18T19:31:43.969Z,1650310303.969 [Default:CheckIn:D] Stopped
2022-04-18T19:31:43.969Z,1650310303.969 [Default:CheckIn:E] Running Loop=1
2022-04-18T19:31:44.319Z,1650310304.319 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 13.561805 min 
2022-04-18T19:31:44.319Z,1650310304.319 [Default:CheckIn:E] Stopped
2022-04-18T19:31:44.319Z,1650310304.319 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-18T19:31:44.319Z,1650310304.319 [Default:CheckIn] Stopped
2022-04-18T19:31:44.319Z,1650310304.319 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-18T19:31:44.320Z,1650310304.320 [Default:CheckIn](INFO): Running loop #3
2022-04-18T19:31:44.320Z,1650310304.320 [Default:CheckIn] Running Loop=3
2022-04-18T19:31:44.320Z,1650310304.320 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-18T19:31:44.320Z,1650310304.320 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-18T19:31:46.330Z,1650310306.330 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193147.00,A,4131.75342,N,07047.06841,W,0.233,237.27,180422,,,A*76
2022-04-18T19:31:46.332Z,1650310306.332 [NAL9602](INFO): GPS fix at 20220418T193147: (41.529224, -70.784474)
2022-04-18T19:31:46.345Z,1650310306.345 [Default:CheckIn:Read_GPS] Stopped
2022-04-18T19:31:46.346Z,1650310306.346 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-18T19:32:03.011Z,1650310323.011 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=36060, MT Status=1, MTMSN=1404
2022-04-18T19:32:03.069Z,1650310323.069 [NAL9602](INFO): Sent 81 bytes from file Logs/20220418T191511/Courier0010.lzma
2022-04-18T19:32:03.069Z,1650310323.069 [NAL9602](INFO): Packets left to send: 0