2023-09-08T11:30:54.099Z,1694172654.099 [Supervisor](DEBUG): Initializing supervisor. 2023-09-08T11:30:54.104Z,1694172654.104 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2023-09-08T11:30:54.104Z,1694172654.104 [SyncHandler](INFO): Protected caller Thread ID is 1044 2023-09-08T11:30:54.105Z,1694172654.105 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2023-09-08T11:30:54.106Z,1694172654.106 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2023-09-08T11:30:54.106Z,1694172654.106 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1045 2023-09-08T11:30:54.110Z,1694172654.110 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2023-09-08T11:30:54.127Z,1694172654.127 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2023-09-08T11:30:54.128Z,1694172654.128 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2023-09-08T11:30:54.128Z,1694172654.128 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1046 2023-09-08T11:30:54.133Z,1694172654.133 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2023-09-08T11:30:54.134Z,1694172654.134 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2023-09-08T11:30:54.134Z,1694172654.134 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1047 2023-09-08T11:30:54.136Z,1694172654.136 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2023-09-08T11:30:54.137Z,1694172654.137 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2023-09-08T11:30:54.137Z,1694172654.137 [logger ThreadHandler](INFO): Protected caller Thread ID is 1048 2023-09-08T11:30:54.141Z,1694172654.141 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2023-09-08T11:30:54.141Z,1694172654.141 [Supervisor](INFO): Looking for Config files in directory: Config/ 2023-09-08T11:30:54.143Z,1694172654.143 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2023-09-08T11:30:54.236Z,1694172654.236 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2023-09-08T11:30:54.237Z,1694172654.237 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2023-09-08T11:30:54.448Z,1694172654.448 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2023-09-08T11:30:54.449Z,1694172654.449 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2023-09-08T11:30:54.696Z,1694172654.696 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2023-09-08T11:30:54.697Z,1694172654.697 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2023-09-08T11:30:54.801Z,1694172654.801 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2023-09-08T11:30:54.802Z,1694172654.802 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2023-09-08T11:30:55.504Z,1694172655.504 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2023-09-08T11:30:55.505Z,1694172655.505 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2023-09-08T11:30:55.975Z,1694172655.975 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2023-09-08T11:30:55.976Z,1694172655.976 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2023-09-08T11:30:56.459Z,1694172656.459 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2023-09-08T11:30:56.460Z,1694172656.460 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2023-09-08T11:30:57.018Z,1694172657.018 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2023-09-08T11:30:57.019Z,1694172657.019 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2023-09-08T11:30:57.351Z,1694172657.351 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2023-09-08T11:30:57.351Z,1694172657.351 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2023-09-08T11:30:57.712Z,1694172657.712 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2023-09-08T11:30:57.712Z,1694172657.712 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2023-09-08T11:30:57.927Z,1694172657.927 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2023-09-08T11:30:57.928Z,1694172657.928 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2023-09-08T11:30:58.066Z,1694172658.066 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2023-09-08T11:30:58.067Z,1694172658.067 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2023-09-08T11:30:58.147Z,1694172658.147 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2023-09-08T11:30:58.226Z,1694172658.226 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2023-09-08T11:30:58.404Z,1694172658.404 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2023-09-08T11:30:58.405Z,1694172658.405 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2023-09-08T11:30:58.636Z,1694172658.636 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2023-09-08T11:30:58.638Z,1694172658.638 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/ 2023-09-08T11:30:58.639Z,1694172658.639 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg 2023-09-08T11:30:58.721Z,1694172658.721 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg 2023-09-08T11:30:58.984Z,1694172658.984 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2023-09-08T11:30:58.985Z,1694172658.985 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg 2023-09-08T11:30:59.089Z,1694172659.089 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg 2023-09-08T11:30:59.243Z,1694172659.243 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg 2023-09-08T11:30:59.344Z,1694172659.344 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg 2023-09-08T11:30:59.453Z,1694172659.453 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg 2023-09-08T11:30:59.561Z,1694172659.561 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg 2023-09-08T11:30:59.664Z,1694172659.664 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg 2023-09-08T11:30:59.820Z,1694172659.820 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Derivation.cfg 2023-09-08T11:30:59.914Z,1694172659.914 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg 2023-09-08T11:31:00.008Z,1694172660.008 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg 2023-09-08T11:31:00.107Z,1694172660.107 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2023-09-08T11:31:00.130Z,1694172660.130 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2023-09-08T11:31:00.281Z,1694172660.281 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2023-09-08T11:31:00.282Z,1694172660.282 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2023-09-08T11:31:00.441Z,1694172660.441 [BuoyancyServo] Loaded 2023-09-08T11:31:00.441Z,1694172660.441 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2023-09-08T11:31:00.462Z,1694172660.462 [ElevatorServo] Loaded 2023-09-08T11:31:00.462Z,1694172660.462 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2023-09-08T11:31:00.482Z,1694172660.482 [MassServo] Loaded 2023-09-08T11:31:00.482Z,1694172660.482 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2023-09-08T11:31:00.502Z,1694172660.502 [RudderServo] Loaded 2023-09-08T11:31:00.503Z,1694172660.503 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2023-09-08T11:31:00.518Z,1694172660.518 [ThrusterHE] Loaded 2023-09-08T11:31:00.519Z,1694172660.519 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2023-09-08T11:31:00.519Z,1694172660.519 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2023-09-08T11:31:00.519Z,1694172660.519 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2023-09-08T11:31:00.662Z,1694172660.662 [SBIT](DEBUG): Construct Startup Built In Test. 2023-09-08T11:31:00.677Z,1694172660.677 [SBIT] Loaded 2023-09-08T11:31:00.677Z,1694172660.677 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2023-09-08T11:31:00.680Z,1694172660.680 [IBIT](DEBUG): Construct Initiated Built In Test. 2023-09-08T11:31:00.701Z,1694172660.701 [IBIT] Loaded 2023-09-08T11:31:00.701Z,1694172660.701 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2023-09-08T11:31:00.706Z,1694172660.706 [CBIT](DEBUG): Construct Continuous Built In Test. 2023-09-08T11:31:00.924Z,1694172660.924 [CBIT] Loaded 2023-09-08T11:31:00.925Z,1694172660.925 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2023-09-08T11:31:00.925Z,1694172660.925 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2023-09-08T11:31:00.925Z,1694172660.925 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2023-09-08T11:31:01.094Z,1694172661.094 [CTD_Seabird] Loaded 2023-09-08T11:31:01.095Z,1694172661.095 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2023-09-08T11:31:01.096Z,1694172661.096 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406674E0 2023-09-08T11:31:01.096Z,1694172661.096 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1133 2023-09-08T11:31:01.128Z,1694172661.128 [ESPComponent] Loaded 2023-09-08T11:31:01.128Z,1694172661.128 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2023-09-08T11:31:01.147Z,1694172661.147 [PAR_Licor] Loaded 2023-09-08T11:31:01.148Z,1694172661.148 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2023-09-08T11:31:01.188Z,1694172661.188 [WetLabsBB2FL] Loaded 2023-09-08T11:31:01.188Z,1694172661.188 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2023-09-08T11:31:01.189Z,1694172661.189 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406974E0 2023-09-08T11:31:01.189Z,1694172661.189 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1134 2023-09-08T11:31:01.190Z,1694172661.190 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2023-09-08T11:31:01.191Z,1694172661.191 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2023-09-08T11:31:01.241Z,1694172661.241 [DepthRateCalculator] Loaded 2023-09-08T11:31:01.241Z,1694172661.241 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2023-09-08T11:31:01.246Z,1694172661.246 [PitchRateCalculator] Loaded 2023-09-08T11:31:01.246Z,1694172661.246 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2023-09-08T11:31:01.257Z,1694172661.257 [SpeedCalculator] Loaded 2023-09-08T11:31:01.257Z,1694172661.257 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2023-09-08T11:31:01.261Z,1694172661.261 [YawRateCalculator] Loaded 2023-09-08T11:31:01.262Z,1694172661.262 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2023-09-08T11:31:01.281Z,1694172661.281 [ElevatorOffsetCalculator] Loaded 2023-09-08T11:31:01.281Z,1694172661.281 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2023-09-08T11:31:01.298Z,1694172661.298 [ShortestDistanceToPolygonSidesCalculator] Loaded 2023-09-08T11:31:01.298Z,1694172661.298 [ComponentRegistry](DEBUG): SyncComponent "ShortestDistanceToPolygonSidesCalculator" handled in the control thread. 2023-09-08T11:31:01.299Z,1694172661.299 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2023-09-08T11:31:01.299Z,1694172661.299 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2023-09-08T11:31:01.401Z,1694172661.401 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2023-09-08T11:31:01.401Z,1694172661.401 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2023-09-08T11:31:01.428Z,1694172661.428 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2023-09-08T11:31:01.428Z,1694172661.428 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2023-09-08T11:31:01.535Z,1694172661.535 [DeadReckonUsingSpeedCalculator] Loaded 2023-09-08T11:31:01.535Z,1694172661.535 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2023-09-08T11:31:01.549Z,1694172661.549 [NavChart] Loaded 2023-09-08T11:31:01.549Z,1694172661.549 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2023-09-08T11:31:01.555Z,1694172661.555 [UniversalFixResidualReporter] Loaded 2023-09-08T11:31:01.555Z,1694172661.555 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2023-09-08T11:31:01.556Z,1694172661.556 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2023-09-08T11:31:01.556Z,1694172661.556 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2023-09-08T11:31:02.167Z,1694172662.167 [AHRS_M2] Loaded 2023-09-08T11:31:02.167Z,1694172662.167 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2023-09-08T11:31:02.202Z,1694172662.202 [BackseatComponent] Loaded 2023-09-08T11:31:02.202Z,1694172662.202 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2023-09-08T11:31:02.203Z,1694172662.203 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 40A2F4E0 2023-09-08T11:31:02.204Z,1694172662.204 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1135 2023-09-08T11:31:02.207Z,1694172662.207 [LcmUniversalReporter] Loaded 2023-09-08T11:31:02.207Z,1694172662.207 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2023-09-08T11:31:02.943Z,1694172662.943 [BPC1] Loaded 2023-09-08T11:31:02.943Z,1694172662.943 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2023-09-08T11:31:03.025Z,1694172663.025 [DataOverHttps] Loaded 2023-09-08T11:31:03.025Z,1694172663.025 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2023-09-08T11:31:03.026Z,1694172663.026 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40A5F4E0 2023-09-08T11:31:03.027Z,1694172663.027 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1136 2023-09-08T11:31:03.047Z,1694172663.047 [Depth_Keller] Loaded 2023-09-08T11:31:03.048Z,1694172663.048 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2023-09-08T11:31:03.053Z,1694172663.053 [DropWeight] Loaded 2023-09-08T11:31:03.053Z,1694172663.053 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2023-09-08T11:31:03.092Z,1694172663.092 [DVL_micro] Loaded 2023-09-08T11:31:03.093Z,1694172663.093 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread. 2023-09-08T11:31:03.154Z,1694172663.154 [NAL9602] Loaded 2023-09-08T11:31:03.154Z,1694172663.154 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2023-09-08T11:31:03.179Z,1694172663.179 [Onboard] Loaded 2023-09-08T11:31:03.180Z,1694172663.180 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2023-09-08T11:31:03.181Z,1694172663.181 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40A8F4E0 2023-09-08T11:31:03.181Z,1694172663.181 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1137 2023-09-08T11:31:03.193Z,1694172663.193 [Power24vConverter] Loaded 2023-09-08T11:31:03.193Z,1694172663.193 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2023-09-08T11:31:03.207Z,1694172663.207 [Radio_Surface] Loaded 2023-09-08T11:31:03.207Z,1694172663.207 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2023-09-08T11:31:03.208Z,1694172663.208 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40ABF4E0 2023-09-08T11:31:03.208Z,1694172663.208 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1138 2023-09-08T11:31:03.222Z,1694172663.222 [Sonardyne_Nano] Loaded 2023-09-08T11:31:03.223Z,1694172663.223 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2023-09-08T11:31:03.223Z,1694172663.223 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2023-09-08T11:31:03.224Z,1694172663.224 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2023-09-08T11:31:03.280Z,1694172663.280 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2023-09-08T11:31:03.280Z,1694172663.280 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2023-09-08T11:31:03.331Z,1694172663.331 [VerticalControl](DEBUG): Construct VerticalControl. 2023-09-08T11:31:03.381Z,1694172663.381 [VerticalControl] Loaded 2023-09-08T11:31:03.381Z,1694172663.381 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2023-09-08T11:31:03.384Z,1694172663.384 [HorizontalControl](DEBUG): Construct HorizontalControl. 2023-09-08T11:31:03.421Z,1694172663.421 [HorizontalControl] Loaded 2023-09-08T11:31:03.421Z,1694172663.421 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2023-09-08T11:31:03.424Z,1694172663.424 [SpeedControl](DEBUG): Construct SpeedControl. 2023-09-08T11:31:03.425Z,1694172663.425 [SpeedControl] Loaded 2023-09-08T11:31:03.426Z,1694172663.426 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2023-09-08T11:31:03.429Z,1694172663.429 [LoopControl](DEBUG): Construct LoopControl. 2023-09-08T11:31:03.429Z,1694172663.429 [LoopControl] Loaded 2023-09-08T11:31:03.429Z,1694172663.429 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2023-09-08T11:31:03.430Z,1694172663.430 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2023-09-08T11:31:03.430Z,1694172663.430 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2023-09-08T11:31:03.440Z,1694172663.440 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2023-09-08T11:31:03.441Z,1694172663.441 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2023-09-08T11:31:03.748Z,1694172663.748 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2023-09-08T11:31:03.754Z,1694172663.754 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2023-09-08T11:31:03.757Z,1694172663.757 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2023-09-08T11:31:03.768Z,1694172663.768 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2023-09-08T11:31:03.769Z,1694172663.769 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BCB4E0 2023-09-08T11:31:03.769Z,1694172663.769 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1139 2023-09-08T11:31:03.774Z,1694172663.774 [Supervisor](INFO): Main Thread ID is 822 2023-09-08T11:31:03.774Z,1694172663.774 [Supervisor](DEBUG): Running supervisor. 2023-09-08T11:31:03.775Z,1694172663.775 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1140 2023-09-08T11:31:03.775Z,1694172663.775 [CommandExec](INFO): Initializing the command executive. 2023-09-08T11:31:03.777Z,1694172663.777 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1141 2023-09-08T11:31:03.779Z,1694172663.779 [controlThread ThreadHandler](INFO): Handler Thread ID is 1142 2023-09-08T11:31:03.779Z,1694172663.779 [controlThread](DEBUG): Initializing ControlThread 2023-09-08T11:31:03.781Z,1694172663.781 [SBIT](INFO): Initialize SBIT Component. 2023-09-08T11:31:03.781Z,1694172663.781 [SBIT](IMPORTANT): git: 2023-09-07 2023-09-08T11:31:03.782Z,1694172663.782 [SBIT](INFO): git hash: de73b65bba9e058a7fe4bf2bc7b950ee8b764c06 2023-09-08T11:31:03.782Z,1694172663.782 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2023-09-08T11:31:03.783Z,1694172663.783 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018 Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021 2023-09-08T11:31:03.785Z,1694172663.785 [SBIT](INFO): Beginning SBIT in 55.000000 seconds. 2023-09-08T11:31:03.785Z,1694172663.785 [IBIT](INFO): Initialize IBIT Component. 2023-09-08T11:31:03.786Z,1694172663.786 [CBIT](DEBUG): Initialize CBIT Component. 2023-09-08T11:31:03.787Z,1694172663.787 [logger ThreadHandler](INFO): Handler Thread ID is 1143 2023-09-08T11:31:03.800Z,1694172663.800 [CBIT](DEBUG): Initialized mux pins. 2023-09-08T11:31:03.800Z,1694172663.800 [CBIT](DEBUG): Initializing the watchdog timer. 2023-09-08T11:31:03.812Z,1694172663.812 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1144 2023-09-08T11:31:03.813Z,1694172663.813 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2023-09-08T11:31:03.817Z,1694172663.817 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1146 2023-09-08T11:31:03.820Z,1694172663.820 [WetLabsBB2FL](INFO): Powering up 2023-09-08T11:31:03.821Z,1694172663.821 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1148 2023-09-08T11:31:03.824Z,1694172663.824 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2023-09-08T11:31:03.824Z,1694172663.824 [CBIT](DEBUG): Initializing heartbeat. 2023-09-08T11:31:03.839Z,1694172663.839 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1149 2023-09-08T11:31:03.840Z,1694172663.840 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2023-09-08T11:31:03.852Z,1694172663.852 [Onboard ThreadHandler](INFO): Handler Thread ID is 1150 2023-09-08T11:31:03.869Z,1694172663.869 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1151 2023-09-08T11:31:03.888Z,1694172663.888 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1152 2023-09-08T11:31:03.892Z,1694172663.892 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI01M.000 2023-09-08T11:31:03.892Z,1694172663.892 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI01M.000 2023-09-08T11:31:03.892Z,1694172663.892 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI80M.000 2023-09-08T11:31:03.892Z,1694172663.892 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI80M.000 2023-09-08T11:31:03.893Z,1694172663.893 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI57M.000 2023-09-08T11:31:03.893Z,1694172663.893 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI57M.000 2023-09-08T11:31:03.893Z,1694172663.893 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI56M.000 2023-09-08T11:31:03.893Z,1694172663.893 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI56M.000 2023-09-08T11:31:03.893Z,1694172663.893 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI90M.000 2023-09-08T11:31:03.893Z,1694172663.893 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI90M.000 2023-09-08T11:31:03.894Z,1694172663.894 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI89M.000 2023-09-08T11:31:03.894Z,1694172663.894 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI89M.000 2023-09-08T11:31:03.894Z,1694172663.894 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI03M.000 2023-09-08T11:31:03.894Z,1694172663.894 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI03M.000 2023-09-08T11:31:03.894Z,1694172663.894 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI06M.000 2023-09-08T11:31:03.894Z,1694172663.894 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI06M.000 2023-09-08T11:31:03.894Z,1694172663.894 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4OH01M.000 2023-09-08T11:31:03.895Z,1694172663.895 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4OH01M.000 2023-09-08T11:31:03.895Z,1694172663.895 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4OH04M.000 2023-09-08T11:31:03.895Z,1694172663.895 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4OH04M.000 2023-09-08T11:31:03.895Z,1694172663.895 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4OH02M.000 2023-09-08T11:31:03.895Z,1694172663.895 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4OH02M.000 2023-09-08T11:31:03.895Z,1694172663.895 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4PA20M.000 2023-09-08T11:31:03.896Z,1694172663.896 [CBIT](DEBUG): Deactivating GF circuits. 2023-09-08T11:31:03.896Z,1694172663.896 [CBIT](DEBUG): Deactivating emergency mode. 2023-09-08T11:31:03.896Z,1694172663.896 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4PA20M.000 2023-09-08T11:31:03.896Z,1694172663.896 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4PA21M.000 2023-09-08T11:31:03.896Z,1694172663.896 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4PA21M.000 2023-09-08T11:31:03.936Z,1694172663.936 [CBIT](DEBUG): Backplane powered. 2023-09-08T11:31:03.940Z,1694172663.940 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2023-09-08T11:31:03.941Z,1694172663.941 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2023-09-08T11:31:03.941Z,1694172663.941 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2023-09-08T11:31:03.941Z,1694172663.941 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2023-09-08T11:31:03.942Z,1694172663.942 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2023-09-08T11:31:03.942Z,1694172663.942 [ShortestDistanceToPolygonSidesCalculator](DEBUG): Initializing. 2023-09-08T11:31:03.943Z,1694172663.943 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-09-08T11:31:03.944Z,1694172663.944 [NavChart](DEBUG): Initialize NavChart Navigation. 2023-09-08T11:31:03.944Z,1694172663.944 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2023-09-08T11:31:03.954Z,1694172663.954 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2023-09-08T11:31:03.960Z,1694172663.960 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2023-09-08T11:31:03.961Z,1694172663.961 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2023-09-08T11:31:03.961Z,1694172663.961 [LoopControl](DEBUG): Initialize LoopControlComponent. 2023-09-08T11:31:03.962Z,1694172663.962 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml 2023-09-08T11:31:03.999Z,1694172663.999 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2023-09-08T11:31:04.035Z,1694172664.035 [MissionManager](DEBUG): 2023-09-08T11:31:04.036Z,1694172664.036 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml 2023-09-08T11:31:04.113Z,1694172664.113 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2023-09-08T11:31:04.114Z,1694172664.114 [Default:A.Wait](DEBUG): Construct Wait. 2023-09-08T11:31:04.140Z,1694172664.140 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2023-09-08T11:31:04.160Z,1694172664.160 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2023-09-08T11:31:04.162Z,1694172664.162 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2023-09-08T11:31:04.193Z,1694172664.193 [Default:E.Execute](DEBUG): Construct Execute. 2023-09-08T11:31:04.212Z,1694172664.212 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2023-09-08T11:31:04.217Z,1694172664.217 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,ShortestDistanceToPolygonSidesCalculator,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2023-09-08T11:31:04.253Z,1694172664.253 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2023-09-08T11:31:04.276Z,1694172664.276 [Radio_Surface](INFO): Powering up 2023-09-08T11:31:04.325Z,1694172664.325 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1895.894409 decibar, 0.000000 m 2023-09-08T11:31:04.329Z,1694172664.329 [Power24vConverter](INFO): Powering up. 2023-09-08T11:31:04.330Z,1694172664.330 [Sonardyne_Nano](INFO): Initializing. 2023-09-08T11:31:04.360Z,1694172664.360 [DepthRateCalculator](ERROR): Depth measurement is not active 2023-09-08T11:31:04.397Z,1694172664.397 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2023-09-08T11:31:04.404Z,1694172664.404 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2023-09-08T11:31:04.405Z,1694172664.405 [ElevatorServo](DEBUG): Initializing EZServoServo. 2023-09-08T11:31:04.412Z,1694172664.412 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2023-09-08T11:31:04.413Z,1694172664.413 [MassServo](DEBUG): Initializing EZServoServo. 2023-09-08T11:31:04.420Z,1694172664.420 [MassServo](DEBUG): Initializing MassServo. 2023-09-08T11:31:04.421Z,1694172664.421 [RudderServo](DEBUG): Initializing EZServoServo. 2023-09-08T11:31:04.428Z,1694172664.428 [RudderServo](DEBUG): Initializing RudderServo. 2023-09-08T11:31:04.429Z,1694172664.429 [ThrusterHE](DEBUG): Initializing EZServoServo. 2023-09-08T11:31:04.436Z,1694172664.436 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2023-09-08T11:31:04.776Z,1694172664.776 [DVL_micro](INFO): Powering down 2023-09-08T11:31:05.944Z,1694172665.944 [WetLabsBB2FL](INFO): Powering down 2023-09-08T11:31:06.655Z,1694172666.655 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2023-09-08T11:31:07.893Z,1694172667.893 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2213 2023-09-08T11:31:10.129Z,1694172670.129 [ThrusterHE](ERROR): Zero Speed Commanded. 2023-09-08T11:31:10.845Z,1694172670.845 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2023-09-08T11:31:33.322Z,1694172693.322 [NAL9602](INFO): Powering up NAL9602 2023-09-08T11:31:44.242Z,1694172704.242 [NAL9602](INFO): NAL9602 initialized 2023-09-08T11:31:59.210Z,1694172719.210 [SBIT](IMPORTANT): Beginning Startup BIT 2023-09-08T11:31:59.214Z,1694172719.214 [CBIT](IMPORTANT): Beginning ground fault scan 2023-09-08T11:32:10.165Z,1694172730.165 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.007000 CHAN A1 (24V): 0.001601 CHAN A2 (12V): -0.006096 CHAN A3 (5V): -0.003502 CHAN B0 (3.3V): -0.001817 CHAN B1 (3.15aV): -0.001197 CHAN B2 (3.15bV): -0.000916 CHAN B3 (GND): -0.000721 OPEN: 0.000135 Full Scale: +/- 1 mA 2023-09-08T11:32:17.372Z,1694172737.372 [NAL9602](INFO): SBD MO Status=0, MOMSN=8950, MT Status=0, MTMSN=0 2023-09-08T11:32:17.372Z,1694172737.372 [NAL9602](INFO): No messages in MT queue 2023-09-08T11:32:18.586Z,1694172738.586 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,113214.00,A,4145.53012,N,08116.65539,W,0.058,0.00,080923,,,A*76 2023-09-08T11:32:18.589Z,1694172738.589 [NAL9602](INFO): GPS fix at 20230908T113214: (41.758835, -81.277590) 2023-09-08T11:32:50.502Z,1694172770.502 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-08T11:32:53.347Z,1694172773.347 [SBIT](IMPORTANT): SBIT PASSED 2023-09-08T11:32:53.347Z,1694172773.347 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2023-09-08T11:32:53.364Z,1694172773.364 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool; 2023-09-08T11:32:53.364Z,1694172773.364 [SBIT](IMPORTANT): DVL_micro.loadAtStartup=1 bool; 2023-09-08T11:32:53.364Z,1694172773.364 [SBIT](IMPORTANT): DeadReckonUsingMultipleVelocitySources.loadAtStartup=0 bool; 2023-09-08T11:32:53.365Z,1694172773.365 [SBIT](IMPORTANT): DeadReckonUsingSpeedCalculator.loadAtStartup=1 bool; 2023-09-08T11:32:53.365Z,1694172773.365 [SBIT](IMPORTANT): Depth_Keller.offset=-0.35 decibar; 2023-09-08T11:32:53.365Z,1694172773.365 [SBIT](IMPORTANT): DropWeight.loadAtStartup=1 bool; 2023-09-08T11:32:53.365Z,1694172773.365 [SBIT](IMPORTANT): ESPComponent.filterCompleteTimeout=135 minute; 2023-09-08T11:32:53.365Z,1694172773.365 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=3 minute; 2023-09-08T11:32:53.365Z,1694172773.365 [SBIT](IMPORTANT): ESPComponent.simulateHardware=0 bool; 2023-09-08T11:32:53.365Z,1694172773.365 [SBIT](IMPORTANT): ElevatorServo.offsetAngle=1 degree; 2023-09-08T11:32:53.365Z,1694172773.365 [SBIT](IMPORTANT): Express linearApproximation CTD_Seabird.bin_mean_sea_water_temperature 5.000000 celsius; 2023-09-08T11:32:53.365Z,1694172773.365 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_salinity; 2023-09-08T11:32:53.366Z,1694172773.366 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water; 2023-09-08T11:32:53.366Z,1694172773.366 [SBIT](IMPORTANT): Express linearApproximation WetLabsSeaOWL_UV_A.mass_concentration_of_petroleum_hydrocarbons_in_sea_water 1.000000 kilogram_per_cubic_meter; 2023-09-08T11:32:53.366Z,1694172773.366 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 50.000000 meter; 2023-09-08T11:32:53.366Z,1694172773.366 [SBIT](IMPORTANT): InternalEnvSim.loadAtStartup=0 bool; 2023-09-08T11:32:53.366Z,1694172773.366 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.loadAtStartup=1 bool; 2023-09-08T11:32:53.366Z,1694172773.366 [SBIT](IMPORTANT): Sonardyne_Nano.loadAtStartup=1 bool; 2023-09-08T11:32:53.366Z,1694172773.366 [SBIT](IMPORTANT): StratificationFrontDetector.loadAtStartup=0 bool; 2023-09-08T11:32:53.366Z,1694172773.366 [SBIT](IMPORTANT): StratificationFrontDetector.threshold_VTHI=0.05 celsius; 2023-09-08T11:32:53.367Z,1694172773.367 [SBIT](IMPORTANT): StratificationFrontDetector.verbosity=1 count; 2023-09-08T11:32:53.367Z,1694172773.367 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=193.25 cubic_centimeter; 2023-09-08T11:32:53.367Z,1694172773.367 [SBIT](IMPORTANT): VerticalControl.massDefault=8.91 millimeter; 2023-09-08T11:32:53.367Z,1694172773.367 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.depth3=20 meter; 2023-09-08T11:32:53.367Z,1694172773.367 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.depth4=30 meter; 2023-09-08T11:32:53.367Z,1694172773.367 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.loadAtStartup=0 bool; 2023-09-08T11:32:53.367Z,1694172773.367 [SBIT](IMPORTANT): WetLabsSeaOWL_UV_A.loadAtStartup=0 bool; 2023-09-08T11:32:53.750Z,1694172773.750 [MissionManager](IMPORTANT): Started mission Startup 2023-09-08T11:32:53.751Z,1694172773.751 [Startup] Running Loop=1 2023-09-08T11:32:53.751Z,1694172773.751 [Startup](DEBUG): Aggregate::initialize Startup 2023-09-08T11:32:53.751Z,1694172773.751 [Startup:A.GoToSurface] Running Loop=1 2023-09-08T11:32:53.751Z,1694172773.751 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-09-08T11:32:53.752Z,1694172773.752 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-09-08T11:32:53.753Z,1694172773.753 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-09-08T11:32:53.753Z,1694172773.753 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-09-08T11:32:53.753Z,1694172773.753 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-09-08T11:32:53.754Z,1694172773.754 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-09-08T11:32:53.755Z,1694172773.755 [Startup:StartupSatComms] Running Loop=1 2023-09-08T11:32:53.755Z,1694172773.755 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2023-09-08T11:32:53.755Z,1694172773.755 [Startup:StartupSatComms:A] Running Loop=1 2023-09-08T11:32:54.159Z,1694172774.159 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2023-09-08T11:32:55.756Z,1694172775.756 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,113251.00,A,4145.52942,N,08116.65461,W,0.058,0.00,080923,,,A*76 2023-09-08T11:32:55.759Z,1694172775.759 [NAL9602](INFO): GPS fix at 20230908T113251: (41.758824, -81.277577) 2023-09-08T11:32:55.769Z,1694172775.769 [Startup:StartupSatComms:A] Stopped 2023-09-08T11:32:55.769Z,1694172775.769 [Startup:StartupSatComms:B] Running Loop=1 2023-09-08T11:32:56.190Z,1694172776.190 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2023-09-08T11:33:22.263Z,1694172802.263 [NAL9602](INFO): SBD MO Status=2, MOMSN=8951, MT Status=2, MTMSN=0 2023-09-08T11:33:22.264Z,1694172802.264 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-08T11:33:42.263Z,1694172822.263 [NAL9602](INFO): SBD MO Status=2, MOMSN=8951, MT Status=2, MTMSN=0 2023-09-08T11:33:42.263Z,1694172822.263 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-08T11:33:55.791Z,1694172835.791 [Startup:StartupSatComms:B](INFO): Timed out from 2023-09-08T11:32:55.8Z 2023-09-08T11:33:55.791Z,1694172835.791 [Startup:StartupSatComms:B] Stopped 2023-09-08T11:33:55.792Z,1694172835.792 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2023-09-08T11:33:55.792Z,1694172835.792 [Startup:StartupSatComms] Stopped 2023-09-08T11:33:55.792Z,1694172835.792 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2023-09-08T11:33:55.793Z,1694172835.793 [Startup](INFO): Completed Startup 2023-09-08T11:33:55.793Z,1694172835.793 [MissionManager](INFO): Startup is completed. 2023-09-08T11:33:55.793Z,1694172835.793 [MissionManager](INFO): Uninitializing Mission Startup 2023-09-08T11:33:55.793Z,1694172835.793 [Startup] Stopped 2023-09-08T11:33:55.793Z,1694172835.793 [Startup](DEBUG): Aggregate::uninitialize Startup 2023-09-08T11:33:55.793Z,1694172835.793 [Startup:A.GoToSurface] Stopped 2023-09-08T11:33:55.793Z,1694172835.793 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-09-08T11:33:56.206Z,1694172836.206 [MissionManager](IMPORTANT): Started mission Default 2023-09-08T11:33:56.206Z,1694172836.206 [Default] Running Loop=1 2023-09-08T11:33:56.206Z,1694172836.206 [Default](DEBUG): Aggregate::initialize Default 2023-09-08T11:33:56.206Z,1694172836.206 [Default:B.GoToSurface] Running Loop=1 2023-09-08T11:33:56.206Z,1694172836.206 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-09-08T11:33:56.207Z,1694172836.207 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-09-08T11:33:56.207Z,1694172836.207 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-09-08T11:33:56.208Z,1694172836.208 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-09-08T11:33:56.208Z,1694172836.208 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-09-08T11:33:56.209Z,1694172836.209 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-09-08T11:33:56.209Z,1694172836.209 [Default:A.Wait] Running Loop=1 2023-09-08T11:33:56.209Z,1694172836.209 [Default:A.Wait](DEBUG): Initialize Wait Component. 2023-09-08T11:33:56.688Z,1694172836.688 [DVL_micro](INFO): Powering down 2023-09-08T11:34:08.710Z,1694172848.710 [NAL9602](INFO): SBD MO Status=1, MOMSN=8951, MT Status=0, MTMSN=0 2023-09-08T11:34:08.768Z,1694172848.768 [NAL9602](INFO): Sent 40 bytes from file Logs/20230908T111539/Courier0016.lzma 2023-09-08T11:34:08.768Z,1694172848.768 [NAL9602](INFO): Packets left to send: 0 2023-09-08T11:34:09.525Z,1694172849.525 [Default:A.Wait](INFO): Done Waiting. 2023-09-08T11:34:09.525Z,1694172849.525 [Default:A.Wait] Stopped 2023-09-08T11:34:09.525Z,1694172849.525 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2023-09-08T11:34:09.932Z,1694172849.932 [Default:CheckIn] Running Loop=1 2023-09-08T11:34:09.933Z,1694172849.933 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-08T11:34:09.933Z,1694172849.933 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-08T11:34:10.330Z,1694172850.330 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2023-09-08T11:34:12.007Z,1694172852.007 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,113408.00,A,4145.53035,N,08116.65508,W,0.039,0.00,080923,,,A*7D 2023-09-08T11:34:12.009Z,1694172852.009 [NAL9602](INFO): GPS fix at 20230908T113408: (41.758839, -81.277585) 2023-09-08T11:34:12.019Z,1694172852.019 [Default:CheckIn:Read_GPS] Stopped 2023-09-08T11:34:12.019Z,1694172852.019 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-08T11:34:12.465Z,1694172852.465 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2023-09-08T11:34:15.579Z,1694172855.579 [DVL_micro](ERROR): Failed to parse: :RD,+9999.99,+9999.99,+9999.99,+9999.99 2023-09-08T11:34:39.162Z,1694172879.162 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004420 2023-09-08T11:34:45.363Z,1694172885.363 [NAL9602](INFO): SBD MO Status=2, MOMSN=8952, MT Status=2, MTMSN=0 2023-09-08T11:34:45.363Z,1694172885.363 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-08T11:34:47.744Z,1694172887.744 [DataOverHttps](INFO): Sending 271 bytes from file Logs/20230908T113054/Courier0000.lzma 2023-09-08T11:34:48.746Z,1694172888.746 [DataOverHttps](INFO): Moved sent file to Logs/20230908T113054/Courier0000.lzma.bak 2023-09-08T11:34:48.746Z,1694172888.746 [DataOverHttps](INFO): SBD MOMSN=18630426 2023-09-08T11:35:06.015Z,1694172906.015 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20230908T113054/Courier0004.lzma 2023-09-08T11:35:07.018Z,1694172907.018 [DataOverHttps](INFO): Moved sent file to Logs/20230908T113054/Courier0004.lzma.bak 2023-09-08T11:35:07.018Z,1694172907.018 [DataOverHttps](INFO): SBD MOMSN=18630430 2023-09-08T11:35:16.053Z,1694172916.053 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-08T11:35:25.156Z,1694172925.156 [DataOverHttps](INFO): Sending 237 bytes from file Logs/20230908T111539/Express0017.lzma 2023-09-08T11:35:26.158Z,1694172926.158 [DataOverHttps](INFO): Moved sent file to Logs/20230908T111539/Express0017.lzma.bak 2023-09-08T11:35:26.158Z,1694172926.158 [DataOverHttps](IMPORTANT): SBD MOMSN=18630434, MTMSN=20230908T113525 2023-09-08T11:35:34.920Z,1694172934.920 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file). 2023-09-08T11:35:38.925Z,1694172938.925 [DataOverHttps](INFO): Received command: get distance_from_shore 2023-09-08T11:35:40.303Z,1694172940.303 [CommandExec](IMPORTANT): got command get distance_from_shore 2023-09-08T11:35:40.308Z,1694172940.308 [CommandExec](FAULT): Element has no value 2023-09-08T11:35:49.345Z,1694172949.345 [DataOverHttps](INFO): Sending 1277 bytes from file Logs/20230908T113054/Express0001.lzma 2023-09-08T11:35:50.346Z,1694172950.346 [DataOverHttps](INFO): Moved sent file to Logs/20230908T113054/Express0001.lzma.bak 2023-09-08T11:35:50.346Z,1694172950.346 [DataOverHttps](INFO): SBD MOMSN=18630441 2023-09-08T11:36:07.103Z,1694172967.103 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20230908T113054/Courier0007.lzma 2023-09-08T11:36:08.106Z,1694172968.106 [DataOverHttps](INFO): Moved sent file to Logs/20230908T113054/Courier0007.lzma.bak 2023-09-08T11:36:08.106Z,1694172968.106 [DataOverHttps](INFO): SBD MOMSN=18630480 2023-09-08T11:36:27.697Z,1694172987.697 [DataOverHttps](INFO): Sending 226 bytes from file Logs/20230908T113054/Express0005.lzma 2023-09-08T11:36:28.706Z,1694172988.706 [DataOverHttps](INFO): Moved sent file to Logs/20230908T113054/Express0005.lzma.bak 2023-09-08T11:36:28.706Z,1694172988.706 [DataOverHttps](INFO): SBD MOMSN=18630484 2023-09-08T11:36:52.627Z,1694173012.627 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20230908T113054/Express0008.lzma 2023-09-08T11:36:53.631Z,1694173013.631 [DataOverHttps](INFO): Moved sent file to Logs/20230908T113054/Express0008.lzma.bak 2023-09-08T11:36:53.631Z,1694173013.631 [DataOverHttps](INFO): SBD MOMSN=18630487 2023-09-08T11:37:11.739Z,1694173031.739 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20230908T113054/Express0009.lzma 2023-09-08T11:37:12.742Z,1694173032.742 [DataOverHttps](INFO): Moved sent file to Logs/20230908T113054/Express0009.lzma.bak 2023-09-08T11:37:12.742Z,1694173032.742 [DataOverHttps](INFO): SBD MOMSN=18630493 2023-09-08T11:37:15.915Z,1694173035.915 [Default:CheckIn:Read_Iridium] Stopped 2023-09-08T11:37:15.915Z,1694173035.915 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-08T11:37:15.915Z,1694173035.915 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-08T11:38:41.969Z,1694173121.969 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2023-09-08T11:38:41.973Z,1694173121.973 [BPC1](INFO): Received data from all battery sticks. 2023-09-08T11:42:16.577Z,1694173336.577 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-08T11:42:16.577Z,1694173336.577 [Default:CheckIn:C.Wait] Stopped 2023-09-08T11:42:16.577Z,1694173336.577 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-08T11:42:16.577Z,1694173336.577 [Default:CheckIn:D] Running Loop=1 2023-09-08T11:42:16.974Z,1694173336.974 [Default:CheckIn:D] Stopped 2023-09-08T11:42:16.974Z,1694173336.974 [Default:CheckIn:E] Running Loop=1 2023-09-08T11:42:17.374Z,1694173337.374 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 8.346127 min 2023-09-08T11:42:17.374Z,1694173337.374 [Default:CheckIn:E] Stopped 2023-09-08T11:42:17.374Z,1694173337.374 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-08T11:42:17.374Z,1694173337.374 [Default:CheckIn] Stopped 2023-09-08T11:42:17.374Z,1694173337.374 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-08T11:42:17.375Z,1694173337.375 [Default:CheckIn](INFO): Running loop #2 2023-09-08T11:42:17.375Z,1694173337.375 [Default:CheckIn] Running Loop=2 2023-09-08T11:42:17.375Z,1694173337.375 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-08T11:42:17.375Z,1694173337.375 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-08T11:42:19.392Z,1694173339.392 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,114218.00,A,4145.52954,N,08116.65506,W,0.000,0.00,080923,,,D*73 2023-09-08T11:42:19.394Z,1694173339.394 [NAL9602](INFO): GPS fix at 20230908T114218: (41.758826, -81.277584) 2023-09-08T11:42:19.404Z,1694173339.404 [Default:CheckIn:Read_GPS] Stopped 2023-09-08T11:42:19.404Z,1694173339.404 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-08T11:42:28.041Z,1694173348.041 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20230908T113054/Courier0010.lzma 2023-09-08T11:42:29.042Z,1694173349.042 [DataOverHttps](INFO): Moved sent file to Logs/20230908T113054/Courier0010.lzma.bak 2023-09-08T11:42:29.042Z,1694173349.042 [DataOverHttps](INFO): SBD MOMSN=18630534 2023-09-08T11:42:36.826Z,1694173356.826 [NAL9602](INFO): SBD MO Status=1, MOMSN=8952, MT Status=0, MTMSN=0 2023-09-08T11:42:36.826Z,1694173356.826 [NAL9602](INFO): No messages in MT queue 2023-09-08T11:42:51.831Z,1694173371.831 [DataOverHttps](INFO): Sending 162 bytes from file Logs/20230908T113054/Express0011.lzma 2023-09-08T11:42:52.834Z,1694173372.834 [DataOverHttps](INFO): Moved sent file to Logs/20230908T113054/Express0011.lzma.bak 2023-09-08T11:42:52.834Z,1694173372.834 [DataOverHttps](INFO): SBD MOMSN=18630540 2023-09-08T11:42:57.451Z,1694173377.451 [Default:CheckIn:Read_Iridium] Stopped 2023-09-08T11:42:57.451Z,1694173377.451 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-08T11:42:57.451Z,1694173377.451 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-08T11:43:07.531Z,1694173387.531 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-08T11:43:47.229Z,1694173427.229 [DataOverHttps](IMPORTANT): SBD MTMSN=20230908T114346 2023-09-08T11:43:54.574Z,1694173434.574 [DataOverHttps](INFO): Received command: ! ls -lah /mnt/mmc/LRAUV/Resources/ElectronicNavigationCharts 2023-09-08T11:43:54.627Z,1694173434.627 [CommandExec](IMPORTANT): got command ! ls -lah /mnt/mmc/LRAUV/Resources/ElectronicNavigationCharts 2023-09-08T11:43:55.299Z,1694173435.299 [CommandExec](IMPORTANT): total 98M drwxr-xr-x 2 root root 12K Aug 1 23:05 . drwxr-xr-x 3 root root 4.0K Apr 11 15:46 .. -rwxr-xr-x 1 root root 1.4M Jul 19 2022 US1AK90M.000 -rwxr-xr-x 1 root root 1002K Apr 19 2021 US1WC07M.000 -rwxr-xr-x 1 root root 2.4K Apr 19 2021 US1WC07M.001 -rwxr-xr-x 1 root root 2.3M Apr 19 2021 US2AK20M.000 -rw-r--r-- 1 root root 871K Jul 19 2022 US2AK91M.000 -rw-r--r-- 1 root root 2.3K Jul 19 2022 US2AK91M.001 -rwxr-xr-x 1 root root 1.1M Jul 19 2022 US2AK92M.000 -rwxr-xr-x 1 root root 868K Apr 19 2021 US2HA05M.000 -rwxr-xr-x 1 root root 3.7K Apr 19 2021 US2HA05M.001 -rwxr-xr-x 1 root root 3.9K Apr 19 2021 US2HA05M.002 -rwxr-xr-x 1 root root 12K Apr 19 2021 US2HA05M.003 -rwxr-xr-x 1 root root 2.6K Apr 19 2021 US2HA05M.004 -rwxr-xr-x 1 root root 305K Apr 19 2021 US2MI01M.000 -rwxr-xr-x 1 root root 2.2K Apr 19 2021 US2MI01M.001 -rwxr-xr-x 1 root root 2.4K Apr 19 2021 US2MI01M.002 -rwxr-xr-x 1 root root 3.4K Apr 19 2021 US2MI01M.003 -rwxr-xr-x 1 root root 2.3K Apr 19 2021 US2MI01M.004 -rwxr-xr-x 1 root root 2.1K Apr 19 2021 US2MI01M.005 -rwxr-xr-x 1 root root 3.4K Apr 19 2021 US2MI01M.006 -rwxr-xr-x 1 root root 2.2K Apr 19 2021 US2MI01M.007 -rwxr-xr-x 1 root root 2.5K Apr 19 2021 US2MI01M.008 -rwxr-xr-x 1 root root 2.5K Apr 19 2021 US2MI01M.009 -rwxr-xr-x 1 root root 5.7K Apr 19 2021 US2MI01M.010 -rwxr-xr-x 1 root root 2.1K Apr 19 2021 US2MI01M.011 -rw-r--r-- 1 root root 1.5M Aug 2 2022 US2MI79M.000 -rw-r--r-- 1 root root 2.8K Aug 2 2022 US2MI79M.001 -rw-r--r-- 1 root root 12K Aug 2 2022 US2MI79M.002 -rw-r--r-- 1 root root 2.5K Aug 2 2022 US2MI79M.003 -rwxr-xr-x 1 root root 3.0M Apr 19 2021 US2MI80M.000 -rwxr-xr-x 1 root root 2.2K Apr 19 2021 US2MI80M.001 -rwxr-xr-x 1 root root 2.2K Apr 19 2021 US2MI80M.002 -rwxr-xr-x 1 root root 2.2K Apr 19 2021 US2MI80M.003 -rwxr-xr-x 1 root root 11K Apr 19 2021 US2MI80M.004 -rwxr-xr-x 1 root root 2.5K Apr 19 2021 US2MI80M.005 -rwxr-xr-x 1 root root 2.5K Apr 19 2021 US2MI80M.006 -rwxr-xr-x 1 root root 793K Apr 19 2021 US2WC11M.000 -rwxr-xr-x 1 root root 3.7K Apr 19 2021 US2WC11M.001 -rwxr-xr-x 1 root root 2.3M Apr 19 2021 US3AK12M.000 -rwxr-xr-x 1 root root 1.7M Apr 19 2021 US3AK1DM.000 -rwxr-xr-x 1 root root 3.5M Apr 19 2021 US3AK5KM.000 -rw-r--r-- 1 root root 1.6M Apr 19 2021 US3CA52M.000 -rw-r--r-- 1 root root 2.1K Apr 19 2021 US3CA52M.001 -rwxr-xr-x 1 root root 1.5M May 11 2022 US3CA69M.000 -rwxr-xr-x 1 root root 17K May 11 2022 US3CA69M.001 -rwxr-xr-x 1 root root 292K Jul 24 17:31 US3EC09M.000 -rwxr-xr-x 1 root root 99K Jul 24 17:31 US3EC09M.001 -rwxr-xr-x 1 root root 2.3K Jul 24 17:31 US3EC09M.002 -rwxr-xr-x 1 root root 13K Jul 24 17:31 US3EC09M.003 -rwxr-xr-x 1 root root 1.1M Apr 19 2021 US3HA20M.000 -rwxr-xr-x 1 root root 7.1K Apr 19 2021 US3HA20M.001 -rwxr-xr-x 1 root root 2.1M Jul 24 17:31 US3NY01M.000 -rwxr-xr-x 1 root root 1.4M Apr 19 2021 US4AK13M.000 -rwxr-xr-x 1 root root 784K Apr 19 2021 US4AK14M.000 -rwxr-xr-x 1 root root 1.9M Apr 19 2021 US4AK15M.000 -rwxr-xr-x 1 root root 2.8M Apr 19 2021 US4AK1AM.000 -rwxr-xr-x 1 root root 2.1M Apr 19 2021 US4AK1CM.000 -rwxr-xr-x 1 root root 294K Apr 19 2021 US4AK1EM.000 -rwxr-xr-x 1 root root 2.1M Apr 19 2021 US4AK5BM.000 -rwxr-xr-x 1 root root 1.2M Apr 19 2021 US4AK5JM.000 -rwxr-xr-x 1 root root 1.9M Apr 19 2021 US4AK5QM.000 -rwxr-xr-x 1 root root 702K Apr 19 2021 US4AK5VM.000 -rwxr-xr-x 1 root root 2.1M Apr 19 2021 US4CA60M.000 -rwxr-xr-x 1 root root 28K Apr 19 2021 US4CA60M.001 -rwxr-xr-x 1 root root 34K Apr 19 2021 US4CA60M.002 -rwxr-xr-x 1 root root 55K Apr 19 2021 US4CA60M.003 -rwxr-xr-x 1 root root 929K Jul 24 17:31 US4CN22M.000 -rwxr-xr-x 1 root root 5.6K Jul 24 17:31 US4CN22M.001 -rwxr-xr-x 1 root root 3.1K Jul 24 17:31 US4CN22M.002 -rwxr-xr-x 1 root root 2.7K Jul 24 17:31 US4CN22M.003 -rwxr-xr-x 1 root root 2.1K Jul 24 17:31 US4CN22M.004 -rwxr-xr-x 1 root root 6.0K Jul 24 17:31 US4CN22M.005 -rwxr-xr-x 1 root root 1.7M Apr 19 2021 US4HA51M.000 -rwxr-xr-x 1 root root 2.8K Apr 19 2021 US4HA51M.001 -rwxr-xr-x 1 root root 857K Apr 19 2021 US4IN01M.000 -rwxr-xr-x 1 root root 4.6K Apr 19 2021 US4IN01M.001 -rwxr-xr-x 1 root root 2.5K Apr 19 2021 US4IN01M.002 -rwxr-xr-x 1 root root 2.5K Apr 19 2021 US4IN01M.003 -rwxr-xr-x 1 root root 2.1K Apr 19 2021 US4IN01M.004 -rwxr-xr-x 1 root root 4.6K Apr 19 2021 US4IN01M.005 -rwxr-xr-x 1 root root 3.0K Apr 19 2021 US4IN01M.006 -rwxr-xr-x 1 root root 4.2K Apr 19 2021 US4IN01M.007 -rwxr-xr-x 1 root root 2.4K Apr 19 2021 US4IN01M.008 -rwxr-xr-x 1 root root 2.2K Apr 19 2021 US4IN01M.009 -rwxr-xr-x 1 root root 4.8K Apr 19 2021 US4IN01M.010 -rwxr-xr-x 1 root root 2.3K Apr 19 2021 US4IN01M.011 -rwxr-xr-x 1 root root 3.6M Apr 25 15:28 US4MA23M.000 -rwxr-xr-x 1 root root 2.2K Apr 25 15:28 US4MA23M.001 -rwxr-xr-x 1 root root 2.5K Apr 25 15:28 US4MA23M.002 -rwxr-xr-x 1 root root 2.5K Apr 25 15:28 US4MA23M.003 -rwxr-xr-x 1 root root 2.8K Apr 25 15:28 US4MA23M.004 -rwxr-xr-x 1 root root 3.3K Apr 25 15:28 US4MA23M.005 -rwxr-xr-x 1 root root 6.2K Apr 25 15:28 US4MA23M.006 -rwxr-xr-x 1 root root 8.8K Apr 25 15:28 US4MA23M.007 -rwxr-xr-x 1 root root 3.0K Apr 25 15:28 US4MA23M.008 -rwxr-xr-x 1 root root 2.6K Apr 25 15:28 US4MA23M.009 -rwxr-xr-x 1 root root 2.7K Apr 25 15:28 US4MA23M.010 -rwxr-xr-x 1 root root 2.5K Apr 25 15:28 US4MA23M.011 -rwxr-xr-x 1 root root 15K Apr 25 15:28 US4MA23M.012 -rwxr-xr-x 1 root root 3.5K Apr 25 15:28 US4MA23M.013 -rwxr-xr-x 1 root root 2.9K Apr 25 15:28 US4MA23M.014 -rwxr-xr-x 1 root root 2.5K Apr 25 15:28 US4MA23M.015 -rwxr-xr-x 1 root root 2.7K Apr 25 15:28 US4MA23M.016 -rwxr-xr-x 1 root root 2.4K Apr 25 15:28 US4MA23M.017 -rwxr-xr-x 1 root root 2.4K Apr 25 15:28 US4MA23M.018 -rwxr-xr-x 1 root root 2.5K Apr 25 15:28 US4MA23M.019 -rwxr-xr-x 1 root root 2.4M Jul 24 17:31 US4MA43M.000 -rwxr-xr-x 1 root root 48K Jul 24 17:31 US4MA43M.001 -rwxr-xr-x 1 root root 16K Jul 24 17:31 US4MA43M.002 -rwxr-xr-x 1 root root 1.5M Apr 19 2021 US4MI11M.000 -rwxr-xr-x 1 root root 37K Apr 19 2021 US4MI11M.001 -rwxr-xr-x 1 root root 147K Apr 19 2021 US4MI11M.002 -rwxr-xr-x 1 root root 2.4K Apr 19 2021 US4MI11M.003 -rwxr-xr-x 1 root root 51K Apr 19 2021 US4MI11M.004 -rwxr-xr-x 1 root root 508K Apr 19 2021 US4MI56M.000 -rwxr-xr-x 1 root root 2.3K Apr 19 2021 US4MI56M.001 -rwxr-xr-x 1 root root 5.0K Apr 19 2021 US4MI56M.002 -rwxr-xr-x 1 root root 2.1K Apr 19 2021 US4MI56M.003 -rwxr-xr-x 1 root root 2.5K Apr 19 2021 US4MI56M.004 -rwxr-xr-x 1 root root 2.2K Apr 19 2021 US4MI56M.005 -rwxr-xr-x 1 root root 2.5K Apr 19 2021 US4MI56M.006 -rwxr-xr-x 1 root root 2.2K Apr 19 2021 US4MI56M.007 -rwxr-xr-x 1 root root 11K Apr 19 2021 US4MI56M.008 -rwxr-xr-x 1 root root 6.0K Apr 19 2021 US4MI56M.009 -rwxr-xr-x 1 root root 11K Apr 19 2021 US4MI56M.010 -rwxr-xr-x 1 root root 1.7M Apr 19 2021 US4MI57M.000 -rwxr-xr-x 1 root root 2.5K Apr 19 2021 US4MI57M.001 -rwxr-xr-x 1 root root 2.1K Apr 19 2021 US4MI57M.002 -rwxr-xr-x 1 root root 2.1K Apr 19 2021 US4MI57M.003 -rwxr-xr-x 1 root root 2.1K Apr 19 2021 US4MI57M.004 -rwxr-xr-x 1 root root 2.7K Apr 19 2021 US4MI57M.005 -rwxr-xr-x 1 root root 32K Apr 19 2021 US4MI57M.006 -rwxr-xr-x 1 root root 23K Apr 19 2021 US4MI57M.007 -rwxr-xr-x 1 root root 2.2K Apr 19 2021 US4MI57M.008 -rwxr-xr-x 1 root root 2.9K Apr 19 2021 US4MI57M.009 -rwxr-xr-x 1 root root 1.1M Apr 19 2021 US4MI59M.000 -rwxr-xr-x 1 root root 375K Apr 19 2021 US4MI89M.000 -rwxr-xr-x 1 root root 2.7K Apr 19 2021 US4MI89M.001 -rwxr-xr-x 1 root root 1.1M Apr 19 2021 US4MI90M.000 -rwxr-xr-x 1 root root 2.1K Apr 19 2021 US4MI90M.001 -rw-r--r-- 1 root root 59K Aug 2 2022 US4MN1CC.000 -rw-r--r-- 1 root root 58K Aug 2 2022 US4MN1CD.000 -rw-r--r-- 1 root root 6.9K Aug 2 2022 US4MN1CD.001 -rw-r--r-- 1 root root 3.8K Aug 2 2022 US4MN1CD.002 -rw-r--r-- 1 root root 2.6K Aug 2 2022 US4MN1CD.003 -rw-r--r-- 1 root root 25K Aug 2 2022 US4MN1CD.004 -rw-r--r-- 1 root root 35K Aug 2 2022 US4MN1CE.000 -rw-r--r-- 1 root root 9.2K Aug 2 2022 US4MN1DD.000 -rw-r--r-- 1 root root 76K Aug 2 2022 US4MN1DE.000 -rw-r--r-- 1 root root 9.2K Aug 2 2022 US4MN1DE.001 -rw-r--r-- 1 root root 34K Aug 2 2022 US4MN1DF.000 -rw-r--r-- 1 root root 14K Aug 2 2022 US4MN1DG.000 -rw-r--r-- 1 root root 15K Aug 2 2022 US4MN1DH.000 -rw-r--r-- 1 root root 402K Jul 24 17:31 US4OH01M.000 -rw-r--r-- 1 root root 2.5K Jul 24 17:31 US4OH01M.001 -rw-r--r-- 1 root root 3.8K Jul 24 17:31 US4OH01M.002 -rw-r--r-- 1 root root 5.6K Jul 24 17:31 US4OH01M.003 -rw-r--r-- 1 root root 310K Jul 24 17:31 US4OH02M.000 -rw-r--r-- 1 root root 6.6K Jul 24 17:31 US4OH02M.001 -rw-r--r-- 1 root root 28K Jul 24 17:31 US4OH04M.000 -rw-r--r-- 1 root root 8.4K Jul 24 17:31 US4OH04M.001 -rw-r--r-- 1 root root 27K Jul 24 17:31 US4OH04M.002 -rw-r--r-- 1 root root 2.5K Jul 24 17:31 US4OH04M.003 -rw-r--r-- 1 root root 27K Jul 24 17:31 US4PA20M.000 -rw-r--r-- 1 root root 2.6K Jul 24 17:31 US4PA20M.001 -rw-r--r-- 1 root root 23K Jul 24 17:31 US4PA20M.002 -rw-r--r-- 1 root root 2.5K Jul 24 17:31 US4PA20M.003 -rw-r--r-- 1 root root 378K Jul 24 17:31 US4PA21M.000 -rw-r--r-- 1 root root 2.8K Jul 24 17:31 US4PA21M.001 -rw-r--r-- 1 root root 4.9K Jul 24 17:31 US4PA21M.002 -rw-r--r-- 1 root root 5.5K Jul 24 17:31 US4PA21M.003 -rwxr-xr-x 1 root root 1.2M Aug 1 23:05 US4WI03M.000 -rwxr-xr-x 1 root root 2.1K Aug 1 23:05 US4WI03M.001 -rw-r--r-- 1 root root 2.5K Aug 1 23:05 US4WI03M.002 -rw-r--r-- 1 root root 2.5K Aug 1 23:05 US4WI03M.003 -rw-r--r-- 1 root root 2.5K Aug 1 23:05 US4WI03M.004 -rw-r--r-- 1 root root 3.5K Aug 1 23:05 US4WI03M.005 -rw-r--r-- 1 root root 5.7K Aug 1 23:05 US4WI03M.006 -rw-r--r-- 1 root root 37K Aug 1 23:05 US4WI03M.007 -rw-r--r-- 1 root root 5.1K Aug 1 23:05 US4WI03M.008 -rwxr-xr-x 1 root root 1.4M Aug 1 23:05 US4WI06M.000 -rwxr-xr-x 1 root root 3.3K Aug 1 23:05 US4WI06M.001 -rwxr-xr-x 1 root root 2.5K Aug 1 23:05 US4WI06M.002 -rw-r--r-- 1 root root 2.8K Aug 1 23:05 US4WI06M.003 -rw-r--r-- 1 root root 4.2K Aug 1 23:05 US4WI06M.004 -rw-r--r-- 1 root root 3.1K Aug 1 23:05 US4WI06M.005 -rw-r--r-- 1 root root 4.8K Aug 1 23:05 US4WI06M.006 -rw-r--r-- 1 root root 88K Aug 2 2022 US4WI1PB.000 -rw-r--r-- 1 root root 34K Aug 2 2022 US4WI1PC.000 -rw-r--r-- 1 root root 32K Aug 2 2022 US4WI1PD.000 -rw-r--r-- 1 root root 81K Aug 2 2022 US4WI1PE.000 -rw-r--r-- 1 root root 261K Aug 2 2022 US4WI1PF.000 -rw-r--r-- 1 root root 2.5K Aug 2 2022 US4WI1PF.001 -rw-r--r-- 1 root root 50K Aug 2 2022 US4WI1PG.000 -rw-r--r-- 1 root root 139K Aug 2 2022 US4WI1QE.000 -rw-r--r-- 1 root root 71K Aug 2 2022 US4WI1QE.001 -rw-r--r-- 1 root root 377K Aug 2 2022 US4WI1QF.000 -rw-r--r-- 1 root root 157K Aug 2 2022 US4WI1QG.000 -rwxr-xr-x 1 root root 491K Apr 19 2021 US4WI33M.000 -rwxr-xr-x 1 root root 1.1M Apr 19 2021 US4WI34M.000 -rwxr-xr-x 1 root root 20K Apr 19 2021 US4WI34M.001 -rwxr-xr-x 1 root root 2.1K Apr 19 2021 US4WI34M.002 -rwxr-xr-x 1 root root 2.5K Apr 19 2021 US4WI34M.003 -rwxr-xr-x 1 root root 503K Apr 19 2021 US5AK9NM.000 -rwxr-xr-x 1 root root 562K Apr 19 2021 US5AK9OM.000 -rwxr-xr-x 1 root root 484K Apr 19 2021 US5AK9PM.000 -rwxr-xr-x 1 root root 383K Apr 19 2021 US5AK9QM.000 -rwxr-xr-x 1 root root 160K Apr 19 2021 US5AK9RM.000 -rwxr-xr-x 1 root root 585K Apr 19 2021 US5AK9SM.000 -rwxr-xr-x 1 root root 299K Apr 19 2021 US5AK9TM.000 -rwxr-xr-x 1 root root 402K Apr 19 2021 US5AK9UM.000 -rwxr-xr-x 1 root root 1.5M Apr 19 2021 US5CA50M.000 -rwxr-xr-x 1 root root 2.3K Apr 19 2021 US5CA50M.001 -rwxr-xr-x 1 root root 1.6M Apr 19 2021 US5CA61M.000 -rwxr-xr-x 1 root root 3.2K Apr 19 2021 US5CA61M.001 -rwxr-xr-x 1 root root 3.0K Apr 19 2021 US5CA61M.002 -rwxr-xr-x 1 root root 2.2K Apr 19 2021 US5CA61M.003 -rwxr-xr-x 1 root root 45K Apr 19 2021 US5CA61M.004 -rwxr-xr-x 1 root root 2.0M Apr 19 2021 US5CA62M.000 -rwxr-xr-x 1 root root 2.4K Apr 19 2021 US5CA62M.001 -rwxr-xr-x 1 root root 81K Apr 19 2021 US5CA62M.002 -rwxr-xr-x 1 root root 2.2K Apr 19 2021 US5CA62M.003 -rwxr-xr-x 1 root root 146K Apr 19 2021 US5CA62M.004 -rwxr-xr-x 1 root root 2.8M Apr 19 2021 US5CA83M.000 -rwxr-xr-x 1 root root 564K Apr 19 2021 US5HA53M.000 -rwxr-xr-x 1 root root 1.4M Apr 19 2021 US5HA55M.000 -rwxr-xr-x 1 root root 140K Apr 25 15:28 US5MA1EG.000 -rwxr-xr-x 1 root root 62K Apr 25 15:28 US5MA1EH.000 -rwxr-xr-x 1 root root 133K Apr 25 15:28 US5MA1EI.000 -rwxr-xr-x 1 root root 1.8M Apr 25 15:28 US5MA1EJ.000 -rwxr-xr-x 1 root root 2.8K Apr 25 15:28 US5MA1EJ.001 -rwxr-xr-x 1 root root 11K Apr 25 15:28 US5MA1EJ.002 -rwxr-xr-x 1 root root 4.2K Apr 25 15:28 US5MA1EJ.003 -rwxr-xr-x 1 root root 2.4K Apr 25 15:28 US5MA1EJ.004 -rwxr-xr-x 1 root root 392K Apr 25 15:28 US5MA1FG.000 -rwxr-xr-x 1 root root 497K Apr 25 15:28 US5MA1FH.000 -rwxr-xr-x 1 root root 67K Apr 25 15:28 US5MA1FI.000 -rwxr-xr-x 1 root root 4.2K Apr 25 15:28 US5MA1FI.001 -rwxr-xr-x 1 root root 3.1K Apr 25 15:28 US5MA1FI.002 -rwxr-xr-x 1 root root 112K Apr 25 15:28 US5MA1FJ.000 -rwxr-xr-x 1 root root 135K Apr 25 15:28 US5MA1FK.000 -rwxr-xr-x 1 root root 281K Apr 25 15:28 US5MA1GH.000 -rwxr-xr-x 1 root root 407K Apr 25 15:28 US5MA1GI.000 -rwxr-xr-x 1 root root 2.3K Apr 25 15:28 US5MA1GI.001 -rwxr-xr-x 1 root root 418K Apr 25 15:28 US5MA1GJ.000 -rwxr-xr-x 1 root root 15K Apr 25 15:28 US5MA1GJ.001 -rwxr-xr-x 1 root root 534K Apr 25 15:28 US5MA1GK.000 -rwxr-xr-x 1 root root 542K Apr 25 15:28 US5MA1HJ.000 -rwxr-xr-x 1 root root 5.3K Apr 25 15:28 US5MA1HJ.001 -rwxr-xr-x 1 root root 563K Apr 25 15:28 US5MA1HK.000 -rwxr-xr-x 1 root root 2.4K Apr 25 15:28 US5MA1HK.001 -rwxr-xr-x 1 root root 2.3K Apr 25 15:28 US5MA1HK.002 -rwxr-xr-x 1 root root 15K Apr 25 15:28 US5MA1HK.003 -rw-r--r-- 1 root root 4.0K Apr 19 2021 US5MA25A.TXT -rw-r--r-- 1 root root 718 Apr 19 2021 US5MA25B.TXT -rw-r--r-- 1 root root 614 Apr 19 2021 US5MA25C.TXT -rw-r--r-- 1 root root 799 Apr 19 2021 US5MA25D.TXT -rw-r--r-- 1 root root 426 Apr 19 2021 US5MA25E.TXT -rw-r--r-- 1 root root 1.2M Apr 19 2021 US5MA25M.000 -rw-r--r-- 1 root root 4.5K Apr 19 2021 US5MA25M.001 -rw-r--r-- 1 root root 2.2K Apr 19 2021 US5MA25M.002 -rw-r--r-- 1 root root 76K Apr 19 2021 US5MA25M.003 -rw-r--r-- 1 root root 3.7K Apr 19 2021 US5MA25M.004 -rw-r--r-- 1 root root 4.4K Apr 19 2021 US5MA26A.TXT -rw-r--r-- 1 root root 615 Apr 19 2021 US5MA26B.TXT -rw-r--r-- 1 root root 795 Apr 19 2021 US5MA26C.TXT -rw-r--r-- 1 root root 426 Apr 19 2021 US5MA26D.TXT -rw-r--r-- 1 root root 2.4M Apr 19 2021 US5MA26M.000 -rw-r--r-- 1 root root 82K Apr 19 2021 US5MA26M.001 -rw-r--r-- 1 root root 4.4K Apr 19 2021 US5MA27A.TXT -rw-r--r-- 1 root root 180 Apr 19 2021 US5MA27B.TXT -rw-r--r-- 1 root root 678 Apr 19 2021 US5MA27D.TXT -rw-r--r-- 1 root root 720 Apr 19 2021 US5MA27E.TXT -rw-r--r-- 1 root root 386 Apr 19 2021 US5MA27F.TXT -rw-r--r-- 1 root root 314 Apr 19 2021 US5MA27G.TXT -rw-r--r-- 1 root root 802 Apr 19 2021 US5MA27H.TXT -rw-r--r-- 1 root root 426 Apr 19 2021 US5MA27I.TXT -rw-r--r-- 1 root root 2.9M Apr 19 2021 US5MA27M.000 -rw-r--r-- 1 root root 2.2K Apr 19 2021 US5MA27M.001 -rw-r--r-- 1 root root 2.6K Apr 19 2021 US5MA27M.002 -rw-r--r-- 1 root root 18K Apr 19 2021 US5MA27M.003 -rwxr-xr-x 1 root root 1.7M Apr 19 2021 US5MI07M.000 -rwxr-xr-x 1 root root 1.8M Apr 19 2021 US5OH07M.000 -rwxr-xr-x 1 root root 459K Apr 19 2021 US5OH31M.000 -rwxr-xr-x 1 root root 7.3K Apr 19 2021 US5OH31M.001 -rwxr-xr-x 1 root root 58K Apr 19 2021 US5OH31M.002 -rw-r--r-- 1 root root 535K Aug 2 2022 US5WI22M.000 2023-09-08T11:46:50.985Z,1694173610.985 [DVL_micro](ERROR): only read 3 of 4 data items for bottom velocity. Device response is::BI,-00030,+00040,+00266, 2023-09-08T11:46:50.985Z,1694173610.985 [DVL_micro](ERROR): Failed to parse: :BI,-00030,+00040,+00266, 2023-09-08T11:47:58.086Z,1694173678.086 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-08T11:47:58.086Z,1694173678.086 [Default:CheckIn:C.Wait] Stopped 2023-09-08T11:47:58.086Z,1694173678.086 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-08T11:47:58.087Z,1694173678.087 [Default:CheckIn:D] Running Loop=1 2023-09-08T11:47:58.473Z,1694173678.473 [Default:CheckIn:D] Stopped 2023-09-08T11:47:58.473Z,1694173678.473 [Default:CheckIn:E] Running Loop=1 2023-09-08T11:47:58.867Z,1694173678.867 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 14.037774 min 2023-09-08T11:47:58.867Z,1694173678.867 [Default:CheckIn:E] Stopped 2023-09-08T11:47:58.867Z,1694173678.867 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-08T11:47:58.868Z,1694173678.868 [Default:CheckIn] Stopped 2023-09-08T11:47:58.868Z,1694173678.868 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-08T11:47:58.868Z,1694173678.868 [Default:CheckIn](INFO): Running loop #3 2023-09-08T11:47:58.868Z,1694173678.868 [Default:CheckIn] Running Loop=3 2023-09-08T11:47:58.868Z,1694173678.868 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-08T11:47:58.868Z,1694173678.868 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-08T11:48:00.890Z,1694173680.890 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,114800.00,A,4145.52922,N,08116.65466,W,0.019,0.00,080923,,,D*7E 2023-09-08T11:48:00.893Z,1694173680.893 [NAL9602](INFO): GPS fix at 20230908T114800: (41.758820, -81.277578) 2023-09-08T11:48:00.902Z,1694173680.902 [Default:CheckIn:Read_GPS] Stopped 2023-09-08T11:48:00.903Z,1694173680.903 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-08T11:48:09.679Z,1694173689.679 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20230908T113054/Courier0013.lzma 2023-09-08T11:48:10.682Z,1694173690.682 [DataOverHttps](INFO): Moved sent file to Logs/20230908T113054/Courier0013.lzma.bak 2023-09-08T11:48:10.682Z,1694173690.682 [DataOverHttps](INFO): SBD MOMSN=18630559 2023-09-08T11:48:23.206Z,1694173703.206 [NAL9602](INFO): SBD MO Status=1, MOMSN=8953, MT Status=0, MTMSN=0 2023-09-08T11:48:23.206Z,1694173703.206 [NAL9602](INFO): No messages in MT queue 2023-09-08T11:48:28.439Z,1694173708.439 [DataOverHttps](INFO): Sending 1743 bytes from file Logs/20230908T113054/Express0014.lzma 2023-09-08T11:48:29.434Z,1694173709.434 [DataOverHttps](INFO): Moved sent file to Logs/20230908T113054/Express0014.lzma.bak 2023-09-08T11:48:29.434Z,1694173709.434 [DataOverHttps](INFO): SBD MOMSN=18630571 2023-09-08T11:48:53.136Z,1694173733.136 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20230908T113054/Express0017.lzma 2023-09-08T11:48:53.974Z,1694173733.974 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-08T11:48:54.138Z,1694173734.138 [DataOverHttps](INFO): Moved sent file to Logs/20230908T113054/Express0017.lzma.bak 2023-09-08T11:48:54.138Z,1694173734.138 [DataOverHttps](INFO): SBD MOMSN=18630580 2023-09-08T11:48:57.232Z,1694173737.232 [Default:CheckIn:Read_Iridium] Stopped 2023-09-08T11:48:57.232Z,1694173737.232 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-08T11:48:57.232Z,1694173737.232 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-08T11:49:57.840Z,1694173797.840 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,.00,+00000000.00,+00000000.00,9999.99,000.00 2023-09-08T11:49:57.840Z,1694173797.840 [DVL_micro](ERROR): Failed to parse: :TS,.00,+00000000.00,+00000000.00,9999.99,000.00 2023-09-08T11:53:57.808Z,1694174037.808 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-08T11:53:57.808Z,1694174037.808 [Default:CheckIn:C.Wait] Stopped 2023-09-08T11:53:57.808Z,1694174037.808 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-08T11:53:57.808Z,1694174037.808 [Default:CheckIn:D] Running Loop=1 2023-09-08T11:53:58.214Z,1694174038.214 [Default:CheckIn:D] Stopped 2023-09-08T11:53:58.214Z,1694174038.214 [Default:CheckIn:E] Running Loop=1 2023-09-08T11:53:58.623Z,1694174038.623 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 20.033472 min 2023-09-08T11:53:58.623Z,1694174038.623 [Default:CheckIn:E] Stopped 2023-09-08T11:53:58.623Z,1694174038.623 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-08T11:53:58.623Z,1694174038.623 [Default:CheckIn] Stopped 2023-09-08T11:53:58.623Z,1694174038.623 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-08T11:53:58.623Z,1694174038.623 [Default:CheckIn](INFO): Running loop #4 2023-09-08T11:53:58.623Z,1694174038.623 [Default:CheckIn] Running Loop=4 2023-09-08T11:53:58.624Z,1694174038.624 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-08T11:53:58.624Z,1694174038.624 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-08T11:54:00.626Z,1694174040.626 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,115359.00,A,4145.52944,N,08116.65440,W,0.039,0.00,080923,,,D*7E 2023-09-08T11:54:00.628Z,1694174040.628 [NAL9602](INFO): GPS fix at 20230908T115359: (41.758824, -81.277573) 2023-09-08T11:54:00.658Z,1694174040.658 [Default:CheckIn:Read_GPS] Stopped 2023-09-08T11:54:00.658Z,1694174040.658 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-08T11:54:09.172Z,1694174049.172 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20230908T113054/Courier0019.lzma 2023-09-08T11:54:10.174Z,1694174050.174 [DataOverHttps](INFO): Moved sent file to Logs/20230908T113054/Courier0019.lzma.bak 2023-09-08T11:54:10.174Z,1694174050.174 [DataOverHttps](INFO): SBD MOMSN=18630595 2023-09-08T11:54:15.184Z,1694174055.184 [NAL9602](INFO): SBD MO Status=1, MOMSN=8954, MT Status=0, MTMSN=0 2023-09-08T11:54:15.184Z,1694174055.184 [NAL9602](INFO): No messages in MT queue 2023-09-08T11:54:27.688Z,1694174067.688 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230908T113054/Express0020.lzma 2023-09-08T11:54:28.690Z,1694174068.690 [DataOverHttps](INFO): Moved sent file to Logs/20230908T113054/Express0020.lzma.bak 2023-09-08T11:54:28.690Z,1694174068.690 [DataOverHttps](INFO): SBD MOMSN=18630605 2023-09-08T11:54:31.799Z,1694174071.799 [Default:CheckIn:Read_Iridium] Stopped 2023-09-08T11:54:31.799Z,1694174071.799 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-08T11:54:31.799Z,1694174071.799 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-08T11:54:45.983Z,1694174085.983 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-08T11:56:54.109Z,1694174214.109 [DataOverHttps](IMPORTANT): SBD MTMSN=20230908T115653 2023-09-08T11:57:01.472Z,1694174221.472 [DataOverHttps](INFO): Received command: ! cat /mnt/mmc/LRAUV/Config/lrauv-makai/Navigation.cfg 2023-09-08T11:57:01.531Z,1694174221.531 [CommandExec](IMPORTANT): got command ! cat /mnt/mmc/LRAUV/Config/lrauv-makai/Navigation.cfg 2023-09-08T11:57:01.614Z,1694174221.614 [CommandExec](IMPORTANT): //////////////////////////////////////////////////////////////// // // PURPOSE: This contains NavigationModule parameters for Makai. // // NOTES: All values (except "strings") must be followed by // a unit abbreviation (or one of the unit-like // abbreviations: n/a, bool, enum, count). // //////////////////////////////////////////////////////////////// DeadReckonUsingMultipleVelocitySources.loadAtStartup = 1 bool; DeadReckonUsingMultipleVelocitySources.allowableFailures = 100 count; DeadReckonUsingSpeedCalculator.loadAtStartup = 0 bool; DeadReckonUsingSpeedCalculator.allowableFailures = 100 count; DeadReckonUsingCompactModelForecast.loadAtStartup = 0 bool; DeadReckonUsingDVLWaterTrack.loadAtStartup = 0 bool; DeadReckonWithRespectToSeafloor.loadAtStartup = 0 bool; DeadReckonWithRespectToWater.loadAtStartup = 0 bool; // E Lake Michigan Charts // Lake Michigan, North end of Lake Michigan, Grand Traverse Bay to Little Traverse Bay, Platte Bay to Leland, Stony Lake to Point Betsie, South Haven to Stony Lake // W Lake Michigan // Lower Green Bay, Upper Green Bay // Central Lake Erie // Moss Point to Vermilion, Geneva to Lorain; Beaver Creek; Rocky River; Mentor Harbor; Chagrin River, Ashtabula to Chagrin River, Erie to Geneva, Sixteenmile Creek to Conneaut NavChartDb.charts = "US2MI01M,US2MI80M,US4MI57M,US4MI56M,US4MI90M,US4MI89M,US4WI03M,US4WI06M,US4OH01M,US4OH04M,US4OH02M,US4PA20M,US4PA21M"; //Pac ocean, San Diego to Cape Mendocino, Point Sur to San Francisco, Monterey Bay, Pt Dume to Purisma Pt. //NavChartDb.charts = "US1WC07M,US2WC11M,US3CA52M,US5CA50M,US3CA69M"; 2023-09-08T11:59:32.460Z,1694174372.460 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-08T11:59:32.460Z,1694174372.460 [Default:CheckIn:C.Wait] Stopped 2023-09-08T11:59:32.460Z,1694174372.460 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-08T11:59:32.460Z,1694174372.460 [Default:CheckIn:D] Running Loop=1 2023-09-08T11:59:32.851Z,1694174372.851 [Default:CheckIn:D] Stopped 2023-09-08T11:59:32.851Z,1694174372.851 [Default:CheckIn:E] Running Loop=1 2023-09-08T11:59:33.281Z,1694174373.281 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 25.610754 min 2023-09-08T11:59:33.281Z,1694174373.281 [Default:CheckIn:E] Stopped 2023-09-08T11:59:33.281Z,1694174373.281 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-08T11:59:33.281Z,1694174373.281 [Default:CheckIn] Stopped 2023-09-08T11:59:33.282Z,1694174373.282 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-08T11:59:33.282Z,1694174373.282 [Default:CheckIn](INFO): Running loop #5 2023-09-08T11:59:33.282Z,1694174373.282 [Default:CheckIn] Running Loop=5 2023-09-08T11:59:33.282Z,1694174373.282 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-08T11:59:33.282Z,1694174373.282 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-08T11:59:35.250Z,1694174375.250 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,115934.00,A,4145.52941,N,08116.65376,W,0.019,0.00,080923,,,D*7A 2023-09-08T11:59:35.253Z,1694174375.253 [NAL9602](INFO): GPS fix at 20230908T115934: (41.758823, -81.277563) 2023-09-08T11:59:35.287Z,1694174375.287 [Default:CheckIn:Read_GPS] Stopped 2023-09-08T11:59:35.287Z,1694174375.287 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-08T11:59:47.093Z,1694174387.093 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20230908T113054/Courier0022.lzma 2023-09-08T11:59:48.094Z,1694174388.094 [DataOverHttps](INFO): Moved sent file to Logs/20230908T113054/Courier0022.lzma.bak 2023-09-08T11:59:48.094Z,1694174388.094 [DataOverHttps](INFO): SBD MOMSN=18630623 2023-09-08T11:59:58.722Z,1694174398.722 [NAL9602](INFO): SBD MO Status=1, MOMSN=8955, MT Status=0, MTMSN=0 2023-09-08T11:59:58.722Z,1694174398.722 [NAL9602](INFO): No messages in MT queue 2023-09-08T12:00:07.493Z,1694174407.493 [DataOverHttps](INFO): Sending 959 bytes from file Logs/20230908T113054/Express0023.lzma 2023-09-08T12:00:08.494Z,1694174408.494 [DataOverHttps](INFO): Moved sent file to Logs/20230908T113054/Express0023.lzma.bak 2023-09-08T12:00:08.494Z,1694174408.494 [DataOverHttps](INFO): SBD MOMSN=18630626 2023-09-08T12:00:11.664Z,1694174411.664 [Default:CheckIn:Read_Iridium] Stopped 2023-09-08T12:00:11.664Z,1694174411.664 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-08T12:00:11.664Z,1694174411.664 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-08T12:00:29.448Z,1694174429.448 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-08T12:05:12.286Z,1694174712.286 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-08T12:05:12.286Z,1694174712.286 [Default:CheckIn:C.Wait] Stopped 2023-09-08T12:05:12.286Z,1694174712.286 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-08T12:05:12.286Z,1694174712.286 [Default:CheckIn:D] Running Loop=1 2023-09-08T12:05:12.687Z,1694174712.687 [Default:CheckIn:D] Stopped 2023-09-08T12:05:12.688Z,1694174712.688 [Default:CheckIn:E] Running Loop=1 2023-09-08T12:05:13.087Z,1694174713.087 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.274689 min 2023-09-08T12:05:13.087Z,1694174713.087 [Default:CheckIn:E] Stopped 2023-09-08T12:05:13.087Z,1694174713.087 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-08T12:05:13.087Z,1694174713.087 [Default:CheckIn] Stopped 2023-09-08T12:05:13.087Z,1694174713.087 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-08T12:05:13.087Z,1694174713.087 [Default:CheckIn](INFO): Running loop #6 2023-09-08T12:05:13.087Z,1694174713.087 [Default:CheckIn] Running Loop=6 2023-09-08T12:05:13.104Z,1694174713.104 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-08T12:05:13.104Z,1694174713.104 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-08T12:05:15.107Z,1694174715.107 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,120514.00,A,4145.52997,N,08116.65390,W,0.000,0.00,080923,,,D*79 2023-09-08T12:05:15.109Z,1694174715.109 [NAL9602](INFO): GPS fix at 20230908T120514: (41.758833, -81.277565) 2023-09-08T12:05:15.119Z,1694174715.119 [Default:CheckIn:Read_GPS] Stopped 2023-09-08T12:05:15.119Z,1694174715.119 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-08T12:05:22.457Z,1694174722.457 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20230908T113054/Courier0025.lzma 2023-09-08T12:05:23.458Z,1694174723.458 [DataOverHttps](INFO): Moved sent file to Logs/20230908T113054/Courier0025.lzma.bak 2023-09-08T12:05:23.458Z,1694174723.458 [DataOverHttps](INFO): SBD MOMSN=18630650 2023-09-08T12:05:31.267Z,1694174731.267 [NAL9602](INFO): SBD MO Status=1, MOMSN=8956, MT Status=0, MTMSN=0 2023-09-08T12:05:31.267Z,1694174731.267 [NAL9602](INFO): No messages in MT queue 2023-09-08T12:05:45.463Z,1694174745.463 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230908T113054/Express0027.lzma 2023-09-08T12:05:46.466Z,1694174746.466 [DataOverHttps](INFO): Moved sent file to Logs/20230908T113054/Express0027.lzma.bak 2023-09-08T12:05:46.466Z,1694174746.466 [DataOverHttps](INFO): SBD MOMSN=18630653 2023-09-08T12:05:49.464Z,1694174749.464 [Default:CheckIn:Read_Iridium] Stopped 2023-09-08T12:05:49.464Z,1694174749.464 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-08T12:05:49.464Z,1694174749.464 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-08T12:06:01.989Z,1694174761.989 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-08T12:10:50.238Z,1694175050.238 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-08T12:10:50.238Z,1694175050.238 [Default:CheckIn:C.Wait] Stopped 2023-09-08T12:10:50.238Z,1694175050.238 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-08T12:10:50.239Z,1694175050.239 [Default:CheckIn:D] Running Loop=1 2023-09-08T12:10:50.654Z,1694175050.654 [Default:CheckIn:D] Stopped 2023-09-08T12:10:50.654Z,1694175050.654 [Default:CheckIn:E] Running Loop=1 2023-09-08T12:10:51.066Z,1694175051.066 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 36.907467 min 2023-09-08T12:10:51.066Z,1694175051.066 [Default:CheckIn:E] Stopped 2023-09-08T12:10:51.066Z,1694175051.066 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-08T12:10:51.066Z,1694175051.066 [Default:CheckIn] Stopped 2023-09-08T12:10:51.066Z,1694175051.066 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-08T12:10:51.066Z,1694175051.066 [Default:CheckIn](INFO): Running loop #7 2023-09-08T12:10:51.066Z,1694175051.066 [Default:CheckIn] Running Loop=7 2023-09-08T12:10:51.066Z,1694175051.066 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-08T12:10:51.066Z,1694175051.066 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-08T12:10:53.062Z,1694175053.062 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,121052.00,A,4145.53000,N,08116.65424,W,0.019,0.00,080923,,,D*79 2023-09-08T12:10:53.065Z,1694175053.065 [NAL9602](INFO): GPS fix at 20230908T121052: (41.758833, -81.277571) 2023-09-08T12:10:53.075Z,1694175053.075 [Default:CheckIn:Read_GPS] Stopped 2023-09-08T12:10:53.075Z,1694175053.075 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-08T12:11:00.215Z,1694175060.215 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20230908T113054/Courier0031.lzma 2023-09-08T12:11:01.218Z,1694175061.218 [DataOverHttps](INFO): Moved sent file to Logs/20230908T113054/Courier0031.lzma.bak 2023-09-08T12:11:01.218Z,1694175061.218 [DataOverHttps](INFO): SBD MOMSN=18630665 2023-09-08T12:11:10.993Z,1694175070.993 [NAL9602](INFO): SBD MO Status=1, MOMSN=8957, MT Status=0, MTMSN=0 2023-09-08T12:11:10.993Z,1694175070.993 [NAL9602](INFO): No messages in MT queue 2023-09-08T12:11:19.439Z,1694175079.439 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230908T113054/Express0032.lzma 2023-09-08T12:11:20.442Z,1694175080.442 [DataOverHttps](INFO): Moved sent file to Logs/20230908T113054/Express0032.lzma.bak 2023-09-08T12:11:20.442Z,1694175080.442 [DataOverHttps](INFO): SBD MOMSN=18630670 2023-09-08T12:11:23.384Z,1694175083.384 [Default:CheckIn:Read_Iridium] Stopped 2023-09-08T12:11:23.384Z,1694175083.384 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-08T12:11:23.384Z,1694175083.384 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-08T12:11:41.593Z,1694175101.593 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-08T12:16:24.169Z,1694175384.169 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-08T12:16:24.170Z,1694175384.170 [Default:CheckIn:C.Wait] Stopped 2023-09-08T12:16:24.170Z,1694175384.170 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-08T12:16:24.170Z,1694175384.170 [Default:CheckIn:D] Running Loop=1 2023-09-08T12:16:24.579Z,1694175384.579 [Default:CheckIn:D] Stopped 2023-09-08T12:16:24.579Z,1694175384.579 [Default:CheckIn:E] Running Loop=1 2023-09-08T12:16:24.999Z,1694175384.999 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.472884 min 2023-09-08T12:16:24.999Z,1694175384.999 [Default:CheckIn:E] Stopped 2023-09-08T12:16:24.999Z,1694175384.999 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-08T12:16:24.999Z,1694175384.999 [Default:CheckIn] Stopped 2023-09-08T12:16:24.999Z,1694175384.999 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-08T12:16:24.999Z,1694175384.999 [Default:CheckIn](INFO): Running loop #8 2023-09-08T12:16:24.999Z,1694175384.999 [Default:CheckIn] Running Loop=8 2023-09-08T12:16:24.999Z,1694175384.999 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-08T12:16:24.999Z,1694175384.999 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-08T12:16:26.990Z,1694175386.990 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,121626.00,A,4145.53001,N,08116.65433,W,0.058,0.00,080923,,,D*7E 2023-09-08T12:16:26.992Z,1694175386.992 [NAL9602](INFO): GPS fix at 20230908T121626: (41.758834, -81.277572) 2023-09-08T12:16:27.002Z,1694175387.002 [Default:CheckIn:Read_GPS] Stopped 2023-09-08T12:16:27.002Z,1694175387.002 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-08T12:16:36.467Z,1694175396.467 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20230908T113054/Courier0034.lzma 2023-09-08T12:16:37.470Z,1694175397.470 [DataOverHttps](INFO): Moved sent file to Logs/20230908T113054/Courier0034.lzma.bak 2023-09-08T12:16:37.470Z,1694175397.470 [DataOverHttps](INFO): SBD MOMSN=18630687 2023-09-08T12:16:45.287Z,1694175405.287 [NAL9602](INFO): SBD MO Status=1, MOMSN=8958, MT Status=0, MTMSN=0 2023-09-08T12:16:45.287Z,1694175405.287 [NAL9602](INFO): No messages in MT queue 2023-09-08T12:16:59.043Z,1694175419.043 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230908T113054/Express0035.lzma 2023-09-08T12:17:01.389Z,1694175421.389 [DataOverHttps](INFO): Moved sent file to Logs/20230908T113054/Express0035.lzma.bak 2023-09-08T12:17:01.389Z,1694175421.389 [DataOverHttps](INFO): SBD MOMSN=18630691 2023-09-08T12:17:03.495Z,1694175423.495 [Default:CheckIn:Read_Iridium] Stopped 2023-09-08T12:17:03.495Z,1694175423.495 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-08T12:17:03.495Z,1694175423.495 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-08T12:17:15.981Z,1694175435.981 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-08T12:22:04.107Z,1694175724.107 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-08T12:22:04.108Z,1694175724.108 [Default:CheckIn:C.Wait] Stopped 2023-09-08T12:22:04.108Z,1694175724.108 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-08T12:22:04.108Z,1694175724.108 [Default:CheckIn:D] Running Loop=1 2023-09-08T12:22:04.526Z,1694175724.526 [Default:CheckIn:D] Stopped 2023-09-08T12:22:04.526Z,1694175724.526 [Default:CheckIn:E] Running Loop=1 2023-09-08T12:22:04.910Z,1694175724.910 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 48.138660 min 2023-09-08T12:22:04.910Z,1694175724.910 [Default:CheckIn:E] Stopped 2023-09-08T12:22:04.910Z,1694175724.910 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-08T12:22:04.910Z,1694175724.910 [Default:CheckIn] Stopped 2023-09-08T12:22:04.911Z,1694175724.911 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-08T12:22:04.911Z,1694175724.911 [Default:CheckIn](INFO): Running loop #9 2023-09-08T12:22:04.911Z,1694175724.911 [Default:CheckIn] Running Loop=9 2023-09-08T12:22:04.911Z,1694175724.911 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-08T12:22:04.911Z,1694175724.911 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-08T12:22:06.927Z,1694175726.927 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,122206.00,A,4145.52987,N,08116.65436,W,0.000,0.00,080923,,,D*75 2023-09-08T12:22:06.930Z,1694175726.930 [NAL9602](INFO): GPS fix at 20230908T122206: (41.758831, -81.277573) 2023-09-08T12:22:06.940Z,1694175726.940 [Default:CheckIn:Read_GPS] Stopped 2023-09-08T12:22:06.940Z,1694175726.940 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-08T12:22:16.332Z,1694175736.332 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20230908T113054/Courier0037.lzma 2023-09-08T12:22:17.338Z,1694175737.338 [DataOverHttps](INFO): Moved sent file to Logs/20230908T113054/Courier0037.lzma.bak 2023-09-08T12:22:17.338Z,1694175737.338 [DataOverHttps](INFO): SBD MOMSN=18630710 2023-09-08T12:22:19.869Z,1694175739.869 [NAL9602](INFO): SBD MO Status=1, MOMSN=8959, MT Status=0, MTMSN=0 2023-09-08T12:22:19.870Z,1694175739.870 [NAL9602](INFO): No messages in MT queue 2023-09-08T12:22:36.100Z,1694175756.100 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230908T113054/Express0038.lzma 2023-09-08T12:22:37.102Z,1694175757.102 [DataOverHttps](INFO): Moved sent file to Logs/20230908T113054/Express0038.lzma.bak 2023-09-08T12:22:37.102Z,1694175757.102 [DataOverHttps](INFO): SBD MOMSN=18630714 2023-09-08T12:22:41.401Z,1694175761.401 [Default:CheckIn:Read_Iridium] Stopped 2023-09-08T12:22:41.401Z,1694175761.401 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-08T12:22:41.401Z,1694175761.401 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-08T12:22:50.617Z,1694175770.617 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-08T12:27:42.038Z,1694176062.038 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-08T12:27:42.038Z,1694176062.038 [Default:CheckIn:C.Wait] Stopped 2023-09-08T12:27:42.038Z,1694176062.038 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-08T12:27:42.038Z,1694176062.038 [Default:CheckIn:D] Running Loop=1 2023-09-08T12:27:42.465Z,1694176062.465 [Default:CheckIn:D] Stopped 2023-09-08T12:27:42.465Z,1694176062.465 [Default:CheckIn:E] Running Loop=1 2023-09-08T12:27:42.871Z,1694176062.871 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 53.770984 min 2023-09-08T12:27:42.871Z,1694176062.871 [Default:CheckIn:E] Stopped 2023-09-08T12:27:42.872Z,1694176062.872 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-08T12:27:42.872Z,1694176062.872 [Default:CheckIn] Stopped 2023-09-08T12:27:42.872Z,1694176062.872 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-08T12:27:42.872Z,1694176062.872 [Default:CheckIn](INFO): Running loop #10 2023-09-08T12:27:42.872Z,1694176062.872 [Default:CheckIn] Running Loop=10 2023-09-08T12:27:42.872Z,1694176062.872 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-08T12:27:42.872Z,1694176062.872 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-08T12:27:44.852Z,1694176064.852 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,122744.00,A,4145.53052,N,08116.65464,W,0.019,0.00,080923,,,D*79 2023-09-08T12:27:44.854Z,1694176064.854 [NAL9602](INFO): GPS fix at 20230908T122744: (41.758842, -81.277577) 2023-09-08T12:27:44.865Z,1694176064.865 [Default:CheckIn:Read_GPS] Stopped 2023-09-08T12:27:44.865Z,1694176064.865 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-08T12:27:53.215Z,1694176073.215 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20230908T113054/Courier0040.lzma 2023-09-08T12:27:56.250Z,1694176076.250 [DataOverHttps](INFO): Moved sent file to Logs/20230908T113054/Courier0040.lzma.bak 2023-09-08T12:27:56.251Z,1694176076.251 [DataOverHttps](INFO): SBD MOMSN=18630726 2023-09-08T12:27:56.973Z,1694176076.973 [NAL9602](INFO): SBD MO Status=1, MOMSN=8960, MT Status=0, MTMSN=0 2023-09-08T12:27:56.973Z,1694176076.973 [NAL9602](INFO): No messages in MT queue 2023-09-08T12:28:13.047Z,1694176093.047 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20230908T113054/Express0041.lzma 2023-09-08T12:28:14.050Z,1694176094.050 [DataOverHttps](INFO): Moved sent file to Logs/20230908T113054/Express0041.lzma.bak 2023-09-08T12:28:14.050Z,1694176094.050 [DataOverHttps](INFO): SBD MOMSN=18630730 2023-09-08T12:28:17.191Z,1694176097.191 [Default:CheckIn:Read_Iridium] Stopped 2023-09-08T12:28:17.191Z,1694176097.191 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-08T12:28:17.191Z,1694176097.191 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-08T12:28:27.669Z,1694176107.669 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-08T12:33:17.938Z,1694176397.938 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-08T12:33:17.938Z,1694176397.938 [Default:CheckIn:C.Wait] Stopped 2023-09-08T12:33:17.938Z,1694176397.938 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-08T12:33:17.938Z,1694176397.938 [Default:CheckIn:D] Running Loop=1 2023-09-08T12:33:18.351Z,1694176398.351 [Default:CheckIn:D] Stopped 2023-09-08T12:33:18.351Z,1694176398.351 [Default:CheckIn:E] Running Loop=1 2023-09-08T12:33:18.738Z,1694176398.738 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 59.369084 min 2023-09-08T12:33:18.738Z,1694176398.738 [Default:CheckIn:E] Stopped 2023-09-08T12:33:18.738Z,1694176398.738 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-08T12:33:18.738Z,1694176398.738 [Default:CheckIn] Stopped 2023-09-08T12:33:18.738Z,1694176398.738 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-08T12:33:18.738Z,1694176398.738 [Default:CheckIn](INFO): Running loop #11 2023-09-08T12:33:18.738Z,1694176398.738 [Default:CheckIn] Running Loop=11 2023-09-08T12:33:18.738Z,1694176398.738 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-08T12:33:18.738Z,1694176398.738 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-08T12:33:20.781Z,1694176400.781 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,123320.00,A,4145.53058,N,08116.65416,W,0.039,0.00,080923,,,D*73 2023-09-08T12:33:20.783Z,1694176400.783 [NAL9602](INFO): GPS fix at 20230908T123320: (41.758843, -81.277569) 2023-09-08T12:33:20.872Z,1694176400.872 [Default:CheckIn:Read_GPS] Stopped 2023-09-08T12:33:20.873Z,1694176400.873 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-08T12:33:29.375Z,1694176409.375 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20230908T113054/Courier0043.lzma 2023-09-08T12:33:30.378Z,1694176410.378 [DataOverHttps](INFO): Moved sent file to Logs/20230908T113054/Courier0043.lzma.bak 2023-09-08T12:33:30.379Z,1694176410.379 [DataOverHttps](INFO): SBD MOMSN=18630748 2023-09-08T12:33:40.253Z,1694176420.253 [NAL9602](INFO): SBD MO Status=1, MOMSN=8961, MT Status=0, MTMSN=0 2023-09-08T12:33:40.253Z,1694176420.253 [NAL9602](INFO): No messages in MT queue 2023-09-08T12:33:51.495Z,1694176431.495 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20230908T113054/Express0044.lzma 2023-09-08T12:33:52.498Z,1694176432.498 [DataOverHttps](INFO): Moved sent file to Logs/20230908T113054/Express0044.lzma.bak 2023-09-08T12:33:52.498Z,1694176432.498 [DataOverHttps](INFO): SBD MOMSN=18630751 2023-09-08T12:33:55.619Z,1694176435.619 [Default:CheckIn:Read_Iridium] Stopped 2023-09-08T12:33:55.619Z,1694176435.619 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-08T12:33:55.619Z,1694176435.619 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-08T12:34:10.937Z,1694176450.937 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-08T12:36:35.357Z,1694176595.357 [CommandExec](IMPORTANT): got command restart application 2023-09-08T12:36:36.360Z,1694176596.360 [Supervisor](INFO): Stop Mission called by Supervisor::terminate 2023-09-08T12:36:36.360Z,1694176596.360 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2023-09-08T12:36:36.360Z,1694176596.360 [CommandExec](INFO): Uninitializing the command executive. 2023-09-08T12:36:36.361Z,1694176596.361 [CommandExec](INFO): Uninitializing the command scheduler. 2023-09-08T12:36:36.361Z,1694176596.361 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-09-08T12:36:36.444Z,1694176596.444 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2023-09-08T12:36:36.444Z,1694176596.444 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2023-09-08T12:36:36.444Z,1694176596.444 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-09-08T12:36:36.445Z,1694176596.445 [NavChartDb](INFO): Join timeout helper Thread ID is 1523 2023-09-08T12:36:36.648Z,1694176596.648 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2023-09-08T12:36:36.649Z,1694176596.649 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-09-08T12:36:36.664Z,1694176596.664 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2023-09-08T12:36:36.664Z,1694176596.664 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-09-08T12:36:36.664Z,1694176596.664 [Radio_Surface](INFO): Join timeout helper Thread ID is 1524 2023-09-08T12:36:36.696Z,1694176596.696 [Radio_Surface](INFO): Powering down 2023-09-08T12:36:36.697Z,1694176596.697 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2023-09-08T12:36:36.697Z,1694176596.697 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-09-08T12:36:36.704Z,1694176596.704 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2023-09-08T12:36:36.704Z,1694176596.704 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-09-08T12:36:36.704Z,1694176596.704 [Onboard](INFO): Join timeout helper Thread ID is 1525 2023-09-08T12:36:37.261Z,1694176597.261 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2023-09-08T12:36:37.261Z,1694176597.261 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-09-08T12:36:37.264Z,1694176597.264 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2023-09-08T12:36:37.264Z,1694176597.264 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-09-08T12:36:37.264Z,1694176597.264 [DataOverHttps](INFO): Join timeout helper Thread ID is 1526 2023-09-08T12:36:37.384Z,1694176597.384 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2023-09-08T12:36:37.385Z,1694176597.385 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-09-08T12:36:37.400Z,1694176597.400 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2023-09-08T12:36:37.400Z,1694176597.400 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-09-08T12:36:37.401Z,1694176597.401 [BackseatComponent](INFO): Join timeout helper Thread ID is 1527 2023-09-08T12:36:37.496Z,1694176597.496 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2023-09-08T12:36:37.496Z,1694176597.496 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-09-08T12:36:37.498Z,1694176597.498 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2023-09-08T12:36:37.498Z,1694176597.498 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-09-08T12:36:37.498Z,1694176597.498 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1528 2023-09-08T12:36:37.596Z,1694176597.596 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2023-09-08T12:36:37.596Z,1694176597.596 [WetLabsBB2FL](INFO): Powering down 2023-09-08T12:36:37.597Z,1694176597.597 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-09-08T12:36:37.609Z,1694176597.609 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2023-09-08T12:36:37.609Z,1694176597.609 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-09-08T12:36:37.609Z,1694176597.609 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1529 2023-09-08T12:36:37.800Z,1694176597.800 [CTD_Seabird](INFO): Powering down 2023-09-08T12:36:37.820Z,1694176597.820 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2023-09-08T12:36:37.820Z,1694176597.820 [CTD_Seabird](INFO): Powering down 2023-09-08T12:36:37.832Z,1694176597.832 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-09-08T12:36:37.845Z,1694176597.845 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2023-09-08T12:36:37.845Z,1694176597.845 [logger ThreadHandler](INFO): Thread cancelled. 2023-09-08T12:36:37.845Z,1694176597.845 [logger](INFO): Join timeout helper Thread ID is 1530 2023-09-08T12:36:37.877Z,1694176597.877 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2023-09-08T12:36:37.877Z,1694176597.877 [logger ThreadHandler](INFO): Thread cancelled. 2023-09-08T12:36:37.889Z,1694176597.889 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2023-09-08T12:36:37.889Z,1694176597.889 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-09-08T12:36:37.889Z,1694176597.889 [CommandLine](INFO): Join timeout helper Thread ID is 1531 2023-09-08T12:36:37.904Z,1694176597.904 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2023-09-08T12:36:37.905Z,1694176597.905 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-09-08T12:36:37.909Z,1694176597.909 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2023-09-08T12:36:37.909Z,1694176597.909 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-09-08T12:36:37.909Z,1694176597.909 [CommandExec](INFO): Join timeout helper Thread ID is 1532 2023-09-08T12:36:37.910Z,1694176597.910 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2023-09-08T12:36:37.910Z,1694176597.910 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-09-08T12:36:37.911Z,1694176597.911 [controlThread](INFO): Join timeout helper Thread ID is 1533 2023-09-08T12:36:38.056Z,1694176598.056 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2023-09-08T12:36:38.056Z,1694176598.056 [controlThread](DEBUG): Uninitializing ControlThread 2023-09-08T12:36:38.061Z,1694176598.061 [AHRS_M2](INFO): Powering down 2023-09-08T12:36:38.228Z,1694176598.228 [DVL_micro](INFO): Powering down 2023-09-08T12:36:38.229Z,1694176598.229 [NAL9602](INFO): Powering down 2023-09-08T12:36:38.230Z,1694176598.230 [Sonardyne_Nano](INFO): Powering down 2023-09-08T12:36:38.349Z,1694176598.349 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2023-09-08T12:36:38.350Z,1694176598.350 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2023-09-08T12:36:38.350Z,1694176598.350 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2023-09-08T12:36:38.351Z,1694176598.351 [MissionManager](INFO): Uninitializing Mission Default 2023-09-08T12:36:38.351Z,1694176598.351 [Default] Stopped 2023-09-08T12:36:38.351Z,1694176598.351 [Default](DEBUG): Aggregate::uninitialize Default 2023-09-08T12:36:38.351Z,1694176598.351 [Default:B.GoToSurface] Stopped 2023-09-08T12:36:38.351Z,1694176598.351 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-09-08T12:36:38.351Z,1694176598.351 [Default:CheckIn] Stopped 2023-09-08T12:36:38.351Z,1694176598.351 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-08T12:36:38.351Z,1694176598.351 [Default:CheckIn:C.Wait] Stopped 2023-09-08T12:36:38.351Z,1694176598.351 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-08T12:36:38.354Z,1694176598.354 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2023-09-08T12:36:38.354Z,1694176598.354 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2023-09-08T12:36:38.355Z,1694176598.355 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2023-09-08T12:36:38.355Z,1694176598.355 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2023-09-08T12:36:38.355Z,1694176598.355 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2023-09-08T12:36:38.355Z,1694176598.355 [BuoyancyServo](INFO): Powering down 2023-09-08T12:36:38.368Z,1694176598.368 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2023-09-08T12:36:38.368Z,1694176598.368 [ElevatorServo](INFO): Powering down 2023-09-08T12:36:38.369Z,1694176598.369 [MassServo](DEBUG): Uninitialize Mass Servo. 2023-09-08T12:36:38.369Z,1694176598.369 [MassServo](INFO): Powering down 2023-09-08T12:36:38.370Z,1694176598.370 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2023-09-08T12:36:38.370Z,1694176598.370 [RudderServo](INFO): Powering down 2023-09-08T12:36:38.371Z,1694176598.371 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2023-09-08T12:36:38.371Z,1694176598.371 [ThrusterHE](INFO): Powering down 2023-09-08T12:36:38.372Z,1694176598.372 [SBIT](DEBUG): Uninitialize SBIT Component. 2023-09-08T12:36:38.372Z,1694176598.372 [IBIT](DEBUG): Uninitialize IBIT Component. 2023-09-08T12:36:38.373Z,1694176598.373 [CBIT](DEBUG): Uninitialize CBIT Component. 2023-09-08T12:36:38.373Z,1694176598.373 [CBIT](DEBUG): Powering off loads. 2023-09-08T12:36:38.384Z,1694176598.384 [CBIT](DEBUG): Disabling WDT. 2023-09-08T12:36:38.396Z,1694176598.396 [CBIT](DEBUG): Opening all GF detection circuits. 2023-09-08T12:36:38.397Z,1694176598.397 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-09-08T12:36:38.452Z,1694176598.452 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-09-08T12:36:38.458Z,1694176598.458 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-09-08T12:36:38.506Z,1694176598.506 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-09-08T12:36:38.508Z,1694176598.508 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-09-08T12:36:38.515Z,1694176598.515 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-09-08T12:36:38.580Z,1694176598.580 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-09-08T12:36:38.656Z,1694176598.656 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-09-08T12:36:38.750Z,1694176598.750 [logger ThreadHandler](INFO): Thread cancelled.