2024-05-10T19:35:48.680Z,1715369748.680 [Supervisor](DEBUG): Initializing supervisor. 2024-05-10T19:35:48.684Z,1715369748.684 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2024-05-10T19:35:48.685Z,1715369748.685 [SyncHandler](INFO): Protected caller Thread ID is 1159 2024-05-10T19:35:48.685Z,1715369748.685 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2024-05-10T19:35:48.686Z,1715369748.686 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2024-05-10T19:35:48.686Z,1715369748.686 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1160 2024-05-10T19:35:48.690Z,1715369748.690 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2024-05-10T19:35:48.709Z,1715369748.709 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2024-05-10T19:35:48.710Z,1715369748.710 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2024-05-10T19:35:48.710Z,1715369748.710 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1161 2024-05-10T19:35:48.715Z,1715369748.715 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2024-05-10T19:35:48.716Z,1715369748.716 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2024-05-10T19:35:48.716Z,1715369748.716 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1162 2024-05-10T19:35:48.718Z,1715369748.718 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2024-05-10T19:35:48.719Z,1715369748.719 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2024-05-10T19:35:48.720Z,1715369748.720 [logger ThreadHandler](INFO): Protected caller Thread ID is 1163 2024-05-10T19:35:48.723Z,1715369748.723 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2024-05-10T19:35:48.724Z,1715369748.724 [Supervisor](INFO): Looking for Config files in directory: Config/ 2024-05-10T19:35:48.725Z,1715369748.725 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2024-05-10T19:35:48.834Z,1715369748.834 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2024-05-10T19:35:48.835Z,1715369748.835 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2024-05-10T19:35:49.092Z,1715369749.092 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2024-05-10T19:35:49.093Z,1715369749.093 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2024-05-10T19:35:49.403Z,1715369749.403 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2024-05-10T19:35:49.403Z,1715369749.403 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2024-05-10T19:35:49.602Z,1715369749.602 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2024-05-10T19:35:49.603Z,1715369749.603 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2024-05-10T19:35:50.422Z,1715369750.422 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2024-05-10T19:35:50.422Z,1715369750.422 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2024-05-10T19:35:50.974Z,1715369750.974 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2024-05-10T19:35:50.975Z,1715369750.975 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2024-05-10T19:35:51.564Z,1715369751.564 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2024-05-10T19:35:51.564Z,1715369751.564 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2024-05-10T19:35:52.020Z,1715369752.020 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2024-05-10T19:35:52.020Z,1715369752.020 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2024-05-10T19:35:52.398Z,1715369752.398 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2024-05-10T19:35:52.399Z,1715369752.399 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2024-05-10T19:35:52.973Z,1715369752.973 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2024-05-10T19:35:52.974Z,1715369752.974 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2024-05-10T19:35:53.235Z,1715369753.235 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2024-05-10T19:35:53.235Z,1715369753.235 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2024-05-10T19:35:53.393Z,1715369753.393 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2024-05-10T19:35:53.394Z,1715369753.394 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2024-05-10T19:35:53.488Z,1715369753.488 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2024-05-10T19:35:53.579Z,1715369753.579 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2024-05-10T19:35:53.789Z,1715369753.789 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2024-05-10T19:35:53.789Z,1715369753.789 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2024-05-10T19:35:54.046Z,1715369754.046 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2024-05-10T19:35:54.047Z,1715369754.047 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/ 2024-05-10T19:35:54.048Z,1715369754.048 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg 2024-05-10T19:35:54.144Z,1715369754.144 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg 2024-05-10T19:35:54.481Z,1715369754.481 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2024-05-10T19:35:54.481Z,1715369754.481 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg 2024-05-10T19:35:54.719Z,1715369754.719 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg 2024-05-10T19:35:54.938Z,1715369754.938 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg 2024-05-10T19:35:55.103Z,1715369755.103 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg 2024-05-10T19:35:55.331Z,1715369755.331 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg 2024-05-10T19:35:55.480Z,1715369755.480 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg 2024-05-10T19:35:55.601Z,1715369755.601 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg 2024-05-10T19:35:55.782Z,1715369755.782 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Derivation.cfg 2024-05-10T19:35:55.893Z,1715369755.893 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg 2024-05-10T19:35:56.005Z,1715369756.005 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg 2024-05-10T19:35:56.108Z,1715369756.108 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/ 2024-05-10T19:35:56.108Z,1715369756.108 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg 2024-05-10T19:35:56.114Z,1715369756.114 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2024-05-10T19:35:56.295Z,1715369756.295 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2024-05-10T19:35:56.296Z,1715369756.296 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2024-05-10T19:35:56.457Z,1715369756.457 [BuoyancyServo] Loaded 2024-05-10T19:35:56.458Z,1715369756.458 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2024-05-10T19:35:56.479Z,1715369756.479 [ElevatorServo] Loaded 2024-05-10T19:35:56.479Z,1715369756.479 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2024-05-10T19:35:56.500Z,1715369756.500 [MassServo] Loaded 2024-05-10T19:35:56.500Z,1715369756.500 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2024-05-10T19:35:56.521Z,1715369756.521 [RudderServo] Loaded 2024-05-10T19:35:56.521Z,1715369756.521 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2024-05-10T19:35:56.537Z,1715369756.537 [ThrusterHE] Loaded 2024-05-10T19:35:56.538Z,1715369756.538 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2024-05-10T19:35:56.538Z,1715369756.538 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2024-05-10T19:35:56.539Z,1715369756.539 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2024-05-10T19:35:56.684Z,1715369756.684 [SBIT](DEBUG): Construct Startup Built In Test. 2024-05-10T19:35:56.699Z,1715369756.699 [SBIT] Loaded 2024-05-10T19:35:56.699Z,1715369756.699 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2024-05-10T19:35:56.702Z,1715369756.702 [IBIT](DEBUG): Construct Initiated Built In Test. 2024-05-10T19:35:56.723Z,1715369756.723 [IBIT] Loaded 2024-05-10T19:35:56.723Z,1715369756.723 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2024-05-10T19:35:56.729Z,1715369756.729 [CBIT](DEBUG): Construct Continuous Built In Test. 2024-05-10T19:35:56.952Z,1715369756.952 [CBIT] Loaded 2024-05-10T19:35:56.952Z,1715369756.952 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2024-05-10T19:35:56.953Z,1715369756.953 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2024-05-10T19:35:56.953Z,1715369756.953 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2024-05-10T19:35:57.127Z,1715369757.127 [CTD_Seabird] Loaded 2024-05-10T19:35:57.128Z,1715369757.128 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2024-05-10T19:35:57.129Z,1715369757.129 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406784E0 2024-05-10T19:35:57.129Z,1715369757.129 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1248 2024-05-10T19:35:57.162Z,1715369757.162 [ESPComponent] Loaded 2024-05-10T19:35:57.162Z,1715369757.162 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2024-05-10T19:35:57.182Z,1715369757.182 [PAR_Licor] Loaded 2024-05-10T19:35:57.182Z,1715369757.182 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2024-05-10T19:35:57.226Z,1715369757.226 [WetLabsBB2FL] Loaded 2024-05-10T19:35:57.226Z,1715369757.226 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2024-05-10T19:35:57.227Z,1715369757.227 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406A84E0 2024-05-10T19:35:57.227Z,1715369757.227 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1249 2024-05-10T19:35:57.228Z,1715369757.228 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2024-05-10T19:35:57.229Z,1715369757.229 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2024-05-10T19:35:57.280Z,1715369757.280 [DepthRateCalculator] Loaded 2024-05-10T19:35:57.280Z,1715369757.280 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2024-05-10T19:35:57.286Z,1715369757.286 [PitchRateCalculator] Loaded 2024-05-10T19:35:57.286Z,1715369757.286 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2024-05-10T19:35:57.297Z,1715369757.297 [SpeedCalculator] Loaded 2024-05-10T19:35:57.297Z,1715369757.297 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2024-05-10T19:35:57.302Z,1715369757.302 [YawRateCalculator] Loaded 2024-05-10T19:35:57.302Z,1715369757.302 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2024-05-10T19:35:57.322Z,1715369757.322 [ElevatorOffsetCalculator] Loaded 2024-05-10T19:35:57.322Z,1715369757.322 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2024-05-10T19:35:57.323Z,1715369757.323 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2024-05-10T19:35:57.323Z,1715369757.323 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2024-05-10T19:35:57.439Z,1715369757.439 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2024-05-10T19:35:57.440Z,1715369757.440 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2024-05-10T19:35:57.466Z,1715369757.466 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2024-05-10T19:35:57.467Z,1715369757.467 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2024-05-10T19:35:57.786Z,1715369757.786 [DeadReckonUsingMultipleVelocitySources] Loaded 2024-05-10T19:35:57.787Z,1715369757.787 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2024-05-10T19:35:57.801Z,1715369757.801 [NavChart] Loaded 2024-05-10T19:35:57.801Z,1715369757.801 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2024-05-10T19:35:57.807Z,1715369757.807 [UniversalFixResidualReporter] Loaded 2024-05-10T19:35:57.807Z,1715369757.807 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2024-05-10T19:35:57.808Z,1715369757.808 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2024-05-10T19:35:57.808Z,1715369757.808 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2024-05-10T19:35:58.265Z,1715369758.265 [AHRS_M2] Loaded 2024-05-10T19:35:58.265Z,1715369758.265 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2024-05-10T19:35:58.301Z,1715369758.301 [BackseatComponent] Loaded 2024-05-10T19:35:58.302Z,1715369758.302 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2024-05-10T19:35:58.303Z,1715369758.303 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 40A534E0 2024-05-10T19:35:58.303Z,1715369758.303 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1250 2024-05-10T19:35:58.306Z,1715369758.306 [LcmUniversalReporter] Loaded 2024-05-10T19:35:58.306Z,1715369758.306 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2024-05-10T19:35:59.057Z,1715369759.057 [BPC1] Loaded 2024-05-10T19:35:59.057Z,1715369759.057 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2024-05-10T19:35:59.131Z,1715369759.131 [DataOverHttps] Loaded 2024-05-10T19:35:59.131Z,1715369759.131 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2024-05-10T19:35:59.132Z,1715369759.132 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40A834E0 2024-05-10T19:35:59.132Z,1715369759.132 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1251 2024-05-10T19:35:59.153Z,1715369759.153 [Depth_Keller] Loaded 2024-05-10T19:35:59.154Z,1715369759.154 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2024-05-10T19:35:59.159Z,1715369759.159 [DropWeight] Loaded 2024-05-10T19:35:59.159Z,1715369759.159 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2024-05-10T19:35:59.199Z,1715369759.199 [DVL_micro] Loaded 2024-05-10T19:35:59.199Z,1715369759.199 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread. 2024-05-10T19:35:59.261Z,1715369759.261 [NAL9602] Loaded 2024-05-10T19:35:59.261Z,1715369759.261 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2024-05-10T19:35:59.287Z,1715369759.287 [Onboard] Loaded 2024-05-10T19:35:59.288Z,1715369759.288 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2024-05-10T19:35:59.289Z,1715369759.289 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40AB34E0 2024-05-10T19:35:59.289Z,1715369759.289 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1252 2024-05-10T19:35:59.301Z,1715369759.301 [Power24vConverter] Loaded 2024-05-10T19:35:59.302Z,1715369759.302 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2024-05-10T19:35:59.315Z,1715369759.315 [Radio_Surface] Loaded 2024-05-10T19:35:59.316Z,1715369759.316 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2024-05-10T19:35:59.317Z,1715369759.317 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40AE34E0 2024-05-10T19:35:59.317Z,1715369759.317 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1253 2024-05-10T19:35:59.331Z,1715369759.331 [Sonardyne_Nano] Loaded 2024-05-10T19:35:59.331Z,1715369759.331 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2024-05-10T19:35:59.427Z,1715369759.427 [DAT] Loaded 2024-05-10T19:35:59.427Z,1715369759.427 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2024-05-10T19:35:59.428Z,1715369759.428 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40B134E0 2024-05-10T19:35:59.429Z,1715369759.429 [DAT ThreadHandler](INFO): Protected caller Thread ID is 1254 2024-05-10T19:35:59.429Z,1715369759.429 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2024-05-10T19:35:59.430Z,1715369759.430 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2024-05-10T19:35:59.487Z,1715369759.487 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2024-05-10T19:35:59.488Z,1715369759.488 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2024-05-10T19:35:59.655Z,1715369759.655 [VerticalControl](DEBUG): Construct VerticalControl. 2024-05-10T19:35:59.787Z,1715369759.787 [VerticalControl] Loaded 2024-05-10T19:35:59.787Z,1715369759.787 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2024-05-10T19:35:59.790Z,1715369759.790 [HorizontalControl](DEBUG): Construct HorizontalControl. 2024-05-10T19:35:59.835Z,1715369759.835 [HorizontalControl] Loaded 2024-05-10T19:35:59.836Z,1715369759.836 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2024-05-10T19:35:59.838Z,1715369759.838 [SpeedControl](DEBUG): Construct SpeedControl. 2024-05-10T19:35:59.840Z,1715369759.840 [SpeedControl] Loaded 2024-05-10T19:35:59.840Z,1715369759.840 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2024-05-10T19:35:59.843Z,1715369759.843 [LoopControl](DEBUG): Construct LoopControl. 2024-05-10T19:35:59.844Z,1715369759.844 [LoopControl] Loaded 2024-05-10T19:35:59.844Z,1715369759.844 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2024-05-10T19:35:59.844Z,1715369759.844 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2024-05-10T19:35:59.845Z,1715369759.845 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2024-05-10T19:35:59.908Z,1715369759.908 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2024-05-10T19:35:59.909Z,1715369759.909 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2024-05-10T19:36:00.259Z,1715369760.259 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2024-05-10T19:36:00.265Z,1715369760.265 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2024-05-10T19:36:00.268Z,1715369760.268 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2024-05-10T19:36:00.280Z,1715369760.280 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2024-05-10T19:36:00.281Z,1715369760.281 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C374E0 2024-05-10T19:36:00.281Z,1715369760.281 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1255 2024-05-10T19:36:00.286Z,1715369760.286 [Supervisor](INFO): Main Thread ID is 826 2024-05-10T19:36:00.286Z,1715369760.286 [Supervisor](DEBUG): Running supervisor. 2024-05-10T19:36:00.287Z,1715369760.287 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1256 2024-05-10T19:36:00.287Z,1715369760.287 [CommandExec](INFO): Initializing the command executive. 2024-05-10T19:36:00.289Z,1715369760.289 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1257 2024-05-10T19:36:00.292Z,1715369760.292 [controlThread ThreadHandler](INFO): Handler Thread ID is 1258 2024-05-10T19:36:00.292Z,1715369760.292 [controlThread](DEBUG): Initializing ControlThread 2024-05-10T19:36:00.294Z,1715369760.294 [SBIT](INFO): Initialize SBIT Component. 2024-05-10T19:36:00.294Z,1715369760.294 [SBIT](IMPORTANT): git: 2024-05-10 2024-05-10T19:36:00.295Z,1715369760.295 [SBIT](INFO): git hash: a134c88d686ec779525c21b16fbeda2a3b596732 2024-05-10T19:36:00.295Z,1715369760.295 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2024-05-10T19:36:00.296Z,1715369760.296 [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 2024-05-10T19:36:00.297Z,1715369760.297 [SBIT](INFO): Beginning SBIT in 39.000000 seconds. 2024-05-10T19:36:00.298Z,1715369760.298 [IBIT](INFO): Initialize IBIT Component. 2024-05-10T19:36:00.299Z,1715369760.299 [CBIT](DEBUG): Initialize CBIT Component. 2024-05-10T19:36:00.299Z,1715369760.299 [logger ThreadHandler](INFO): Handler Thread ID is 1259 2024-05-10T19:36:00.312Z,1715369760.312 [CBIT](DEBUG): Initialized mux pins. 2024-05-10T19:36:00.312Z,1715369760.312 [CBIT](DEBUG): Initializing the watchdog timer. 2024-05-10T19:36:00.320Z,1715369760.320 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1260 2024-05-10T19:36:00.321Z,1715369760.321 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2024-05-10T19:36:00.326Z,1715369760.326 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1262 2024-05-10T19:36:00.328Z,1715369760.328 [WetLabsBB2FL](INFO): Powering up 2024-05-10T19:36:00.329Z,1715369760.329 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1264 2024-05-10T19:36:00.336Z,1715369760.336 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2024-05-10T19:36:00.336Z,1715369760.336 [CBIT](DEBUG): Initializing heartbeat. 2024-05-10T19:36:00.337Z,1715369760.337 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1265 2024-05-10T19:36:00.339Z,1715369760.339 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2024-05-10T19:36:00.360Z,1715369760.360 [Onboard ThreadHandler](INFO): Handler Thread ID is 1266 2024-05-10T19:36:00.377Z,1715369760.377 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1267 2024-05-10T19:36:00.400Z,1715369760.400 [DAT ThreadHandler](INFO): Handler Thread ID is 1268 2024-05-10T19:36:00.401Z,1715369760.401 [DAT](INFO): Powering up 2024-05-10T19:36:00.401Z,1715369760.401 [DAT](DEBUG): Initializing DAT. 2024-05-10T19:36:00.405Z,1715369760.405 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1270 2024-05-10T19:36:00.407Z,1715369760.407 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/DK1NORSO.000 2024-05-10T19:36:00.407Z,1715369760.407 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from DK1NORSO.000 2024-05-10T19:36:00.408Z,1715369760.408 [CBIT](DEBUG): Deactivating GF circuits. 2024-05-10T19:36:00.408Z,1715369760.408 [CBIT](DEBUG): Deactivating emergency mode. 2024-05-10T19:36:00.408Z,1715369760.408 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/DK2NORS.000 2024-05-10T19:36:00.408Z,1715369760.408 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from DK2NORS.000 2024-05-10T19:36:00.409Z,1715369760.409 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/DK3HORRO.000 2024-05-10T19:36:00.409Z,1715369760.409 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from DK3HORRO.000 2024-05-10T19:36:00.448Z,1715369760.448 [CBIT](DEBUG): Backplane powered. 2024-05-10T19:36:00.452Z,1715369760.452 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2024-05-10T19:36:00.453Z,1715369760.453 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2024-05-10T19:36:00.453Z,1715369760.453 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2024-05-10T19:36:00.453Z,1715369760.453 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2024-05-10T19:36:00.454Z,1715369760.454 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2024-05-10T19:36:00.455Z,1715369760.455 [NavChart](DEBUG): Initialize NavChart Navigation. 2024-05-10T19:36:00.455Z,1715369760.455 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2024-05-10T19:36:00.462Z,1715369760.462 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2024-05-10T19:36:00.476Z,1715369760.476 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2024-05-10T19:36:00.477Z,1715369760.477 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2024-05-10T19:36:00.477Z,1715369760.477 [LoopControl](DEBUG): Initialize LoopControlComponent. 2024-05-10T19:36:00.478Z,1715369760.478 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2024-05-10T19:36:00.479Z,1715369760.479 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2024-05-10T19:36:00.479Z,1715369760.479 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2024-05-10T19:36:00.488Z,1715369760.488 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2024-05-10T19:36:00.589Z,1715369760.589 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2024-05-10T19:36:00.594Z,1715369760.594 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2024-05-10T19:36:00.634Z,1715369760.634 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission Startup { behavior Guidance:GoToSurface { run in progression } aggregate StartupSatComms { run in sequence readDatum { timeout duration=P1M Universal:latitude_fix } readDatum { timeout duration=P1M Universal:platform_communications } } } 2024-05-10T19:36:00.635Z,1715369760.635 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2024-05-10T19:36:00.635Z,1715369760.635 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl 2024-05-10T19:36:00.636Z,1715369760.636 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl 2024-05-10T19:36:00.784Z,1715369760.784 [Radio_Surface](INFO): Powering up 2024-05-10T19:36:00.970Z,1715369760.970 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into: 0 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2024-05-10T19:36:00.975Z,1715369760.975 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2024-05-10T19:36:00.993Z,1715369760.993 [Default:A.Wait](DEBUG): Construct Wait. 2024-05-10T19:36:00.995Z,1715369760.995 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2024-05-10T19:36:01.031Z,1715369761.031 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2024-05-10T19:36:01.050Z,1715369761.050 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2024-05-10T19:36:01.055Z,1715369761.055 [Default:E.Execute](DEBUG): Construct Execute. 2024-05-10T19:36:01.074Z,1715369761.074 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission Default { arguments { ElapsedSinceDefaultStarted = 0 minute } behavior Guidance:Wait { """ Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. """ run in sequence set duration = 13 second } behavior Guidance:GoToSurface { run in progression } aggregate CheckIn { run in sequence repeat=288 readDatum id="Read_GPS" { timeout duration=P5M Universal:time_fix } readDatum id="Read_Iridium" { timeout duration=P4H { behavior Guidance:Execute { run in sequence set command = "Burn on" } syslog critical "Dropped weight due to communications timeout." } Universal:platform_communications } behavior Guidance:Wait { run in sequence set duration = 5 minute } assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started ) syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute } syslog important "Restarting logs and Default mission." behavior Guidance:Execute { run in sequence set command = "restart logs" } } 2024-05-10T19:36:01.079Z,1715369761.079 [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,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2024-05-10T19:36:01.094Z,1715369761.094 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2024-05-10T19:36:01.162Z,1715369761.162 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1895.444336 decibar, 0.000000 m 2024-05-10T19:36:01.164Z,1715369761.164 [Power24vConverter](INFO): Powering up. 2024-05-10T19:36:01.165Z,1715369761.165 [Sonardyne_Nano](INFO): Initializing. 2024-05-10T19:36:01.212Z,1715369761.212 [DepthRateCalculator](ERROR): Depth measurement is not active 2024-05-10T19:36:01.238Z,1715369761.238 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2024-05-10T19:36:01.244Z,1715369761.244 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2024-05-10T19:36:01.245Z,1715369761.245 [ElevatorServo](DEBUG): Initializing EZServoServo. 2024-05-10T19:36:01.252Z,1715369761.252 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2024-05-10T19:36:01.253Z,1715369761.253 [MassServo](DEBUG): Initializing EZServoServo. 2024-05-10T19:36:01.260Z,1715369761.260 [MassServo](DEBUG): Initializing MassServo. 2024-05-10T19:36:01.261Z,1715369761.261 [RudderServo](DEBUG): Initializing EZServoServo. 2024-05-10T19:36:01.268Z,1715369761.268 [RudderServo](DEBUG): Initializing RudderServo. 2024-05-10T19:36:01.269Z,1715369761.269 [ThrusterHE](DEBUG): Initializing EZServoServo. 2024-05-10T19:36:01.276Z,1715369761.276 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2024-05-10T19:36:02.437Z,1715369762.437 [WetLabsBB2FL](INFO): Powering down 2024-05-10T19:36:03.114Z,1715369763.114 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2024-05-10T19:36:04.757Z,1715369764.757 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2407 2024-05-10T19:36:07.352Z,1715369767.352 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2024-05-10T19:36:12.497Z,1715369772.497 [DAT](INFO): DAT read: 2024-05-10T19:36:12.498Z,1715369772.498 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2024-05-10T19:36:14.009Z,1715369774.009 [DAT](INFO): DAT read: MF Frequency Band 2024-05-10T19:36:14.010Z,1715369774.010 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2024-05-10T19:36:14.011Z,1715369774.011 [DAT](INFO): DAT read: May 10 2024 19:36:08 2024-05-10T19:36:15.269Z,1715369775.269 [DAT](INFO): DAT read: Features enabled [Bearing] 2024-05-10T19:36:15.270Z,1715369775.270 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC 2024-05-10T19:36:15.270Z,1715369775.270 [DAT](INFO): commRate: 1200 2024-05-10T19:36:17.336Z,1715369777.336 [DAT](INFO): entering command mode 2024-05-10T19:36:17.536Z,1715369777.536 [DAT](INFO): DAT read: 2024-05-10T19:36:17.537Z,1715369777.537 [DAT](INFO): DAT read: user:1> 2024-05-10T19:36:17.537Z,1715369777.537 [DAT](INFO): setting verbose to 3 2024-05-10T19:36:17.788Z,1715369777.788 [DAT](INFO): DAT read: user:1> 2024-05-10T19:36:17.790Z,1715369777.790 [DAT](INFO): DAT read: Verbose | 3 2024-05-10T19:36:17.790Z,1715369777.790 [DAT](INFO): set verbose to 3 2024-05-10T19:36:17.790Z,1715369777.790 [DAT](INFO): setting DatVerbose to 27440 2024-05-10T19:36:18.041Z,1715369778.041 [DAT](INFO): DAT read: user:2> 2024-05-10T19:36:18.042Z,1715369778.042 [DAT](INFO): DAT read: DatVerbose | 27440 2024-05-10T19:36:18.042Z,1715369778.042 [DAT](INFO): set DatVerbose to 27440 2024-05-10T19:36:18.042Z,1715369778.042 [DAT](INFO): setting transmit power to 8 2024-05-10T19:36:18.292Z,1715369778.292 [DAT](INFO): DAT read: user:3> 2024-05-10T19:36:18.293Z,1715369778.293 [DAT](INFO): DAT read: TxPower | 8 (Max) 2024-05-10T19:36:18.294Z,1715369778.294 [DAT](INFO): set transmit power to 8 2024-05-10T19:36:18.294Z,1715369778.294 [DAT](INFO): setting local address to 5 2024-05-10T19:36:18.544Z,1715369778.544 [DAT](INFO): DAT read: user:4> 2024-05-10T19:36:18.545Z,1715369778.545 [DAT](INFO): DAT read: LocalAddr | 5 2024-05-10T19:36:18.546Z,1715369778.546 [DAT](INFO): set local address to 5 2024-05-10T19:36:18.547Z,1715369778.547 [DAT](INFO): Setting time to: 19:36:18 And date to:5/10/2024 2024-05-10T19:36:18.797Z,1715369778.797 [DAT](INFO): DAT read: user:5> 2024-05-10T19:36:18.797Z,1715369778.797 [DAT](INFO): DAT read: Fri May 10, 2024 19:36:18 2024-05-10T19:36:18.798Z,1715369778.798 [DAT](INFO): Local DAT time set to Fri May 10, 2024 19:36:18 2024-05-10T19:36:29.383Z,1715369789.383 [NAL9602](INFO): Powering up NAL9602 2024-05-10T19:36:39.941Z,1715369799.941 [SBIT](IMPORTANT): Beginning Startup BIT 2024-05-10T19:36:39.947Z,1715369799.947 [CBIT](IMPORTANT): Beginning ground fault scan 2024-05-10T19:36:40.303Z,1715369800.303 [NAL9602](INFO): NAL9602 initialized 2024-05-10T19:36:50.872Z,1715369810.872 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.026590 CHAN A1 (24V): 0.060072 CHAN A2 (12V): -0.016822 CHAN A3 (5V): -0.030166 CHAN B0 (3.3V): -0.016140 CHAN B1 (3.15aV): -0.010714 CHAN B2 (3.15bV): -0.006781 CHAN B3 (GND): -0.016418 OPEN: 0.007689 Full Scale: +/- 1 mA 2024-05-10T19:36:55.653Z,1715369815.653 [NAL9602](INFO): SBD MO Status=0, MOMSN=11760, MT Status=0, MTMSN=0 2024-05-10T19:36:55.653Z,1715369815.653 [NAL9602](INFO): No messages in MT queue 2024-05-10T19:37:33.658Z,1715369853.658 [SBIT](IMPORTANT): SBIT PASSED 2024-05-10T19:37:33.658Z,1715369853.658 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2024-05-10T19:37:33.659Z,1715369853.659 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=3 minute; 2024-05-10T19:37:33.659Z,1715369853.659 [SBIT](IMPORTANT): ElevatorServo.offsetAngle=0.7 degree; 2024-05-10T19:37:33.659Z,1715369853.659 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=150 cubic_centimeter; 2024-05-10T19:37:33.659Z,1715369853.659 [SBIT](IMPORTANT): VerticalControl.massDefault=0.5 centimeter; 2024-05-10T19:37:34.052Z,1715369854.052 [MissionManager](IMPORTANT): Started mission Startup 2024-05-10T19:37:34.053Z,1715369854.053 [Startup] Running Loop=1 2024-05-10T19:37:34.053Z,1715369854.053 [Startup](DEBUG): Aggregate::initialize Startup 2024-05-10T19:37:34.053Z,1715369854.053 [Startup:A.GoToSurface] Running Loop=1 2024-05-10T19:37:34.053Z,1715369854.053 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-05-10T19:37:34.054Z,1715369854.054 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-05-10T19:37:34.054Z,1715369854.054 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-05-10T19:37:34.054Z,1715369854.054 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-05-10T19:37:34.055Z,1715369854.055 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-05-10T19:37:34.055Z,1715369854.055 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2024-05-10T19:37:34.055Z,1715369854.055 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-05-10T19:37:34.057Z,1715369854.057 [Startup:StartupSatComms] Running Loop=1 2024-05-10T19:37:34.057Z,1715369854.057 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2024-05-10T19:37:34.058Z,1715369854.058 [Startup:StartupSatComms:A] Running Loop=1 2024-05-10T19:37:34.457Z,1715369854.457 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2024-05-10T19:38:03.164Z,1715369883.164 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193800.00,A,3648.16670,N,12147.25890,W,0.019,0.00,100524,,,A*7D 2024-05-10T19:38:03.167Z,1715369883.167 [NAL9602](INFO): GPS fix at 20240510T193800: (36.802778, -121.787648) 2024-05-10T19:38:03.178Z,1715369883.178 [Startup:StartupSatComms:A] Stopped 2024-05-10T19:38:03.178Z,1715369883.178 [Startup:StartupSatComms:B] Running Loop=1 2024-05-10T19:38:03.575Z,1715369883.575 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-05-10T19:38:43.211Z,1715369923.211 [NAL9602](INFO): SBD MO Status=1, MOMSN=11761, MT Status=0, MTMSN=0 2024-05-10T19:38:43.268Z,1715369923.268 [NAL9602](INFO): Sent 207 bytes from file Logs/20240510T192059/Courier0007.lzma 2024-05-10T19:38:43.268Z,1715369923.268 [NAL9602](INFO): Packets left to send: 0 2024-05-10T19:38:52.563Z,1715369932.563 [NAL9602](INFO): SBD MO Status=1, MOMSN=11762, MT Status=0, MTMSN=0 2024-05-10T19:38:52.624Z,1715369932.624 [NAL9602](INFO): Sent 40 bytes from file Logs/20240510T192059/Courier0010.lzma 2024-05-10T19:38:52.624Z,1715369932.624 [NAL9602](INFO): Packets left to send: 0 2024-05-10T19:39:01.515Z,1715369941.515 [NAL9602](INFO): SBD MO Status=1, MOMSN=11763, MT Status=0, MTMSN=0 2024-05-10T19:39:01.569Z,1715369941.569 [NAL9602](INFO): Sent 270 bytes from file Logs/20240510T193548/Courier0000.lzma 2024-05-10T19:39:01.569Z,1715369941.569 [NAL9602](INFO): Packets left to send: 0 2024-05-10T19:39:04.371Z,1715369944.371 [Startup:StartupSatComms:B](INFO): Timed out from 2024-05-10T19:38:03.2Z 2024-05-10T19:39:04.371Z,1715369944.371 [Startup:StartupSatComms:B] Stopped 2024-05-10T19:39:04.371Z,1715369944.371 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2024-05-10T19:39:04.371Z,1715369944.371 [Startup:StartupSatComms] Stopped 2024-05-10T19:39:04.371Z,1715369944.371 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2024-05-10T19:39:04.372Z,1715369944.372 [Startup](INFO): Completed Startup 2024-05-10T19:39:04.372Z,1715369944.372 [MissionManager](INFO): Startup is completed. 2024-05-10T19:39:04.372Z,1715369944.372 [MissionManager](INFO): Uninitializing Mission Startup 2024-05-10T19:39:04.372Z,1715369944.372 [Startup] Stopped 2024-05-10T19:39:04.373Z,1715369944.373 [Startup](DEBUG): Aggregate::uninitialize Startup 2024-05-10T19:39:04.373Z,1715369944.373 [Startup:A.GoToSurface] Stopped 2024-05-10T19:39:04.373Z,1715369944.373 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-05-10T19:39:04.498Z,1715369944.498 [MissionManager](IMPORTANT): Started mission Default 2024-05-10T19:39:04.498Z,1715369944.498 [Default] Running Loop=1 2024-05-10T19:39:04.498Z,1715369944.498 [Default](DEBUG): Aggregate::initialize Default 2024-05-10T19:39:04.498Z,1715369944.498 [Default:B.GoToSurface] Running Loop=1 2024-05-10T19:39:04.498Z,1715369944.498 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-05-10T19:39:04.499Z,1715369944.499 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-05-10T19:39:04.499Z,1715369944.499 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-05-10T19:39:04.499Z,1715369944.499 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-05-10T19:39:04.499Z,1715369944.499 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-05-10T19:39:04.500Z,1715369944.500 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2024-05-10T19:39:04.500Z,1715369944.500 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-05-10T19:39:04.501Z,1715369944.501 [Default:A.Wait] Running Loop=1 2024-05-10T19:39:04.501Z,1715369944.501 [Default:A.Wait](DEBUG): Initialize Wait Component. 2024-05-10T19:39:12.570Z,1715369952.570 [NAL9602](INFO): SBD MO Status=1, MOMSN=11764, MT Status=0, MTMSN=0 2024-05-10T19:39:12.628Z,1715369952.628 [NAL9602](INFO): Sent 164 bytes from file Logs/20240510T192059/Express0008.lzma 2024-05-10T19:39:12.629Z,1715369952.629 [NAL9602](INFO): Packets left to send: 0 2024-05-10T19:39:17.745Z,1715369957.745 [Default:A.Wait](INFO): Done Waiting. 2024-05-10T19:39:17.746Z,1715369957.746 [Default:A.Wait] Stopped 2024-05-10T19:39:17.746Z,1715369957.746 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2024-05-10T19:39:18.165Z,1715369958.165 [Default:CheckIn] Running Loop=1 2024-05-10T19:39:18.165Z,1715369958.165 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-10T19:39:18.165Z,1715369958.165 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-10T19:39:18.571Z,1715369958.571 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2024-05-10T19:39:20.177Z,1715369960.177 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193917.00,A,3648.16480,N,12147.25720,W,0.039,0.00,100524,,,A*71 2024-05-10T19:39:20.179Z,1715369960.179 [NAL9602](INFO): GPS fix at 20240510T193917: (36.802747, -121.787620) 2024-05-10T19:39:20.189Z,1715369960.189 [Default:CheckIn:Read_GPS] Stopped 2024-05-10T19:39:20.189Z,1715369960.189 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-10T19:39:20.659Z,1715369960.659 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-05-10T19:39:35.162Z,1715369975.162 [NAL9602](INFO): SBD MO Status=1, MOMSN=11765, MT Status=0, MTMSN=0 2024-05-10T19:39:35.224Z,1715369975.224 [NAL9602](INFO): Sent 76 bytes from file Logs/20240510T193548/Courier0004.lzma 2024-05-10T19:39:35.225Z,1715369975.225 [NAL9602](INFO): Packets left to send: 0 2024-05-10T19:39:47.739Z,1715369987.739 [NAL9602](INFO): SBD MO Status=1, MOMSN=11766, MT Status=0, MTMSN=0 2024-05-10T19:39:47.800Z,1715369987.800 [NAL9602](INFO): Sent 138 bytes from file Logs/20240510T192059/Express0011.lzma 2024-05-10T19:39:47.800Z,1715369987.800 [NAL9602](INFO): Packets left to send: 0 2024-05-10T19:39:55.859Z,1715369995.859 [NAL9602](INFO): SBD MO Status=1, MOMSN=11767, MT Status=0, MTMSN=0 2024-05-10T19:39:55.920Z,1715369995.920 [NAL9602](INFO): Sent 332 bytes from file Logs/20240510T193548/Express0001.lzma 2024-05-10T19:39:55.920Z,1715369995.920 [NAL9602](INFO): Packets left to send: 2 2024-05-10T19:40:04.396Z,1715370004.396 [NAL9602](INFO): SBD MO Status=1, MOMSN=11768, MT Status=0, MTMSN=0 2024-05-10T19:40:04.448Z,1715370004.448 [NAL9602](INFO): Sent 332 bytes from file Logs/20240510T193548/Express0001.lzma 2024-05-10T19:40:04.448Z,1715370004.448 [NAL9602](INFO): Packets left to send: 1 2024-05-10T19:40:12.135Z,1715370012.135 [NAL9602](INFO): SBD MO Status=1, MOMSN=11769, MT Status=0, MTMSN=0 2024-05-10T19:40:12.196Z,1715370012.196 [NAL9602](INFO): Sent 180 bytes from file Logs/20240510T193548/Express0001.lzma 2024-05-10T19:40:12.196Z,1715370012.196 [NAL9602](INFO): Packets left to send: 0 2024-05-10T19:40:20.331Z,1715370020.331 [NAL9602](INFO): SBD MO Status=1, MOMSN=11770, MT Status=0, MTMSN=0 2024-05-10T19:40:20.388Z,1715370020.388 [NAL9602](INFO): Sent 108 bytes from file Logs/20240510T193548/Express0005.lzma 2024-05-10T19:40:20.388Z,1715370020.388 [NAL9602](INFO): Packets left to send: 0 2024-05-10T19:40:27.141Z,1715370027.141 [NAL9602](INFO): SBD MO Status=0, MOMSN=11771, MT Status=0, MTMSN=0 2024-05-10T19:40:27.219Z,1715370027.219 [Default:CheckIn:Read_Iridium] Stopped 2024-05-10T19:40:27.219Z,1715370027.219 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-10T19:40:27.219Z,1715370027.219 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-10T19:40:57.842Z,1715370057.842 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-10T19:41:58.845Z,1715370118.845 [DVL_micro](ERROR): DVL BIT error. See manual. Result code: -42 Device respsone is::TS,00000000000000,35.0,+19.6,0000.0,14,-00042,+01246,-00945,+00000,I 2024-05-10T19:41:58.847Z,1715370118.847 [DVL_micro](ERROR): Failed to parse: :RD,+9999.99,+9999.99,+9999.99,+9999.99 2024-05-10T19:43:35.002Z,1715370215.002 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2024-05-10T19:43:35.005Z,1715370215.005 [BPC1](INFO): Received data from all battery sticks. 2024-05-10T19:45:27.718Z,1715370327.718 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-10T19:45:27.718Z,1715370327.718 [Default:CheckIn:C.Wait] Stopped 2024-05-10T19:45:27.718Z,1715370327.718 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-10T19:45:27.718Z,1715370327.718 [Default:CheckIn:D] Running Loop=1 2024-05-10T19:45:28.126Z,1715370328.126 [Default:CheckIn:D] Stopped 2024-05-10T19:45:28.127Z,1715370328.127 [Default:CheckIn:E] Running Loop=1 2024-05-10T19:45:28.543Z,1715370328.543 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 6.393806 min 2024-05-10T19:45:28.543Z,1715370328.543 [Default:CheckIn:E] Stopped 2024-05-10T19:45:28.544Z,1715370328.544 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-10T19:45:28.544Z,1715370328.544 [Default:CheckIn] Stopped 2024-05-10T19:45:28.544Z,1715370328.544 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-10T19:45:28.544Z,1715370328.544 [Default:CheckIn](INFO): Running loop #2 2024-05-10T19:45:28.544Z,1715370328.544 [Default:CheckIn] Running Loop=2 2024-05-10T19:45:28.544Z,1715370328.544 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-10T19:45:28.544Z,1715370328.544 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-10T19:45:30.539Z,1715370330.539 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194530.00,A,3648.16704,N,12147.25551,W,0.019,0.00,100524,,,D*73 2024-05-10T19:45:30.541Z,1715370330.541 [NAL9602](INFO): GPS fix at 20240510T194530: (36.802784, -121.787592) 2024-05-10T19:45:30.550Z,1715370330.550 [Default:CheckIn:Read_GPS] Stopped 2024-05-10T19:45:30.550Z,1715370330.550 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-10T19:46:42.106Z,1715370402.106 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=11772, MT Status=1, MTMSN=3419 2024-05-10T19:46:42.164Z,1715370402.164 [NAL9602](INFO): Sent 207 bytes from file Logs/20240510T193548/Courier0007.lzma 2024-05-10T19:46:42.164Z,1715370402.164 [NAL9602](INFO): Packets left to send: 0 2024-05-10T19:46:42.609Z,1715370402.609 [NAL9602](INFO): Received command: ibit 2024-05-10T19:46:42.653Z,1715370402.653 [CommandExec](IMPORTANT): got command ibit 2024-05-10T19:46:42.942Z,1715370402.942 [IBIT](IMPORTANT): Beginning Initiated BIT 2024-05-10T19:46:42.943Z,1715370402.943 [IBIT](IMPORTANT): Beginning control surface checks. 2024-05-10T19:46:42.947Z,1715370402.947 [CBIT](IMPORTANT): Beginning ground fault scan 2024-05-10T19:46:44.131Z,1715370404.131 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194644.00,A,3648.16652,N,12147.25504,W,0.039,0.00,100524,,,D*73 2024-05-10T19:46:44.134Z,1715370404.134 [NAL9602](INFO): GPS fix at 20240510T194644: (36.802775, -121.787584) 2024-05-10T19:46:54.966Z,1715370414.966 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.033813 CHAN A1 (24V): 0.060035 CHAN A2 (12V): -0.013763 CHAN A3 (5V): -0.014444 CHAN B0 (3.3V): -0.012953 CHAN B1 (3.15aV): -0.008285 CHAN B2 (3.15bV): -0.005793 CHAN B3 (GND): -0.016436 OPEN: 0.007869 Full Scale: +/- 1 mA 2024-05-10T19:47:01.409Z,1715370421.409 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=11773, MT Status=1, MTMSN=3420 2024-05-10T19:47:01.476Z,1715370421.476 [NAL9602](INFO): Sent 162 bytes from file Logs/20240510T193548/Express0008.lzma 2024-05-10T19:47:01.476Z,1715370421.476 [NAL9602](INFO): Packets left to send: 0 2024-05-10T19:47:01.880Z,1715370421.880 [NAL9602](INFO): Received command: ! ifconfig -a 2024-05-10T19:47:01.925Z,1715370421.925 [CommandExec](IMPORTANT): got command ! ifconfig -a 2024-05-10T19:47:02.047Z,1715370422.047 [CommandExec](IMPORTANT): eth0 Link encap:Ethernet HWaddr 50:2D:F4:07:45:52 inet addr:10.89.5.2 Bcast:10.89.5.255 Mask:255.255.255.0 UP BROADCAST RUNNING MULTICAST MTU:1500 Metric:1 RX packets:496 errors:0 dropped:0 overruns:0 frame:0 TX packets:589 errors:0 dropped:0 overruns:0 carrier:0 collisions:0 txqueuelen:1000 RX bytes:49088 (47.9 Kb) TX bytes:86629 (84.5 Kb) Interrupt:29 lo Link encap:Local Loopback inet addr:127.0.0.1 Mask:255.0.0.0 UP LOOPBACK RUNNING MULTICAST MTU:16436 Metric:1 RX packets:10 errors:0 dropped:0 overruns:0 frame:0 TX packets:10 errors:0 dropped:0 overruns:0 carrier:0 collisions:0 txqueuelen:0 RX bytes:2180 (2.1 Kb) TX bytes:2180 (2.1 Kb) 2024-05-10T19:47:03.017Z,1715370423.017 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194703.00,A,3648.16662,N,12147.25541,W,0.039,0.00,100524,,,D*73 2024-05-10T19:47:03.020Z,1715370423.020 [NAL9602](INFO): GPS fix at 20240510T194703: (36.802777, -121.787590) 2024-05-10T19:47:11.494Z,1715370431.494 [NAL9602](INFO): SBD MO Status=0, MOMSN=11774, MT Status=0, MTMSN=0 2024-05-10T19:47:12.713Z,1715370432.713 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194712.00,A,3648.16646,N,12147.25542,W,0.117,0.00,100524,,,D*7B 2024-05-10T19:47:12.715Z,1715370432.715 [NAL9602](INFO): GPS fix at 20240510T194712: (36.802774, -121.787590) 2024-05-10T19:47:22.154Z,1715370442.154 [NAL9602](INFO): SBD MO Status=1, MOMSN=11775, MT Status=0, MTMSN=0 2024-05-10T19:47:22.206Z,1715370442.206 [NAL9602](INFO): Sent 76 bytes from file Logs/20240510T193548/Courier0010.lzma 2024-05-10T19:47:22.206Z,1715370442.206 [NAL9602](INFO): Packets left to send: 0 2024-05-10T19:47:29.082Z,1715370449.082 [IBIT](IMPORTANT): Communications Status: Fix Status: 1 Iridium Signal Strength: 2 Latitude: 36.802773 Longitude: -121.787590 2024-05-10T19:47:29.494Z,1715370449.494 [IBIT](IMPORTANT): Battery Status: Battery Charge (AH): 0.000000 Voltage: 14.431000 2024-05-10T19:47:29.494Z,1715370449.494 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah 2024-05-10T19:47:29.495Z,1715370449.495 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V 2024-05-10T19:47:29.882Z,1715370449.882 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 % 2024-05-10T19:47:29.883Z,1715370449.883 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi 2024-05-10T19:47:29.883Z,1715370449.883 [IBIT](IMPORTANT): Pressure:9.534312 PSI 2024-05-10T19:47:29.883Z,1715370449.883 [IBIT](IMPORTANT): Humidity:6.372848 % 2024-05-10T19:47:30.305Z,1715370450.305 [IBIT](IMPORTANT): Vehicle Pitch:-0.128371 degrees 2024-05-10T19:47:30.305Z,1715370450.305 [IBIT](IMPORTANT): Vehicle Roll:4.232616 degrees 2024-05-10T19:47:30.305Z,1715370450.305 [IBIT](IMPORTANT): Vehicle Heading:283.596802 degrees 2024-05-10T19:47:30.706Z,1715370450.706 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m 2024-05-10T19:47:30.707Z,1715370450.707 [IBIT](IMPORTANT): buoyancyNeutral: 150.000000 cc 2024-05-10T19:47:30.707Z,1715370450.707 [IBIT](IMPORTANT): massDefault: 0.500000 cm 2024-05-10T19:47:30.707Z,1715370450.707 [IBIT](IMPORTANT): stopDepth: 275.000000 m 2024-05-10T19:47:30.708Z,1715370450.708 [IBIT](IMPORTANT): abortDepth: 300.000000 m 2024-05-10T19:47:30.708Z,1715370450.708 [IBIT](IMPORTANT): IBIT PASSED 2024-05-10T19:47:34.317Z,1715370454.317 [NAL9602](INFO): SBD MO Status=1, MOMSN=11776, MT Status=0, MTMSN=0 2024-05-10T19:47:34.372Z,1715370454.372 [NAL9602](INFO): Sent 332 bytes from file Logs/20240510T193548/Express0011.lzma 2024-05-10T19:47:34.372Z,1715370454.372 [NAL9602](INFO): Packets left to send: 2 2024-05-10T19:47:42.929Z,1715370462.929 [NAL9602](INFO): SBD MO Status=1, MOMSN=11777, MT Status=0, MTMSN=0 2024-05-10T19:47:42.996Z,1715370462.996 [NAL9602](INFO): Sent 332 bytes from file Logs/20240510T193548/Express0011.lzma 2024-05-10T19:47:42.996Z,1715370462.996 [NAL9602](INFO): Packets left to send: 1 2024-05-10T19:47:54.693Z,1715370474.693 [NAL9602](INFO): SBD MO Status=2, MOMSN=11778, MT Status=2, MTMSN=0 2024-05-10T19:47:54.693Z,1715370474.693 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-10T19:48:05.265Z,1715370485.265 [NAL9602](INFO): SBD MO Status=1, MOMSN=11778, MT Status=0, MTMSN=0 2024-05-10T19:48:05.320Z,1715370485.320 [NAL9602](INFO): Sent 86 bytes from file Logs/20240510T193548/Express0011.lzma 2024-05-10T19:48:05.320Z,1715370485.320 [NAL9602](INFO): Packets left to send: 0 2024-05-10T19:48:19.009Z,1715370499.009 [NAL9602](INFO): SBD MO Status=0, MOMSN=11779, MT Status=0, MTMSN=0 2024-05-10T19:48:19.087Z,1715370499.087 [Default:CheckIn:Read_Iridium] Stopped 2024-05-10T19:48:19.087Z,1715370499.087 [Default:CheckIn:C.Wait] Running Loop=1 2024-05-10T19:48:19.087Z,1715370499.087 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-05-10T19:48:49.703Z,1715370529.703 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-10T19:52:36.848Z,1715370756.848 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2024-05-10T19:52:53.303Z,1715370773.303 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.009314 2024-05-10T19:53:19.585Z,1715370799.585 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-05-10T19:53:19.585Z,1715370799.585 [Default:CheckIn:C.Wait] Stopped 2024-05-10T19:53:19.585Z,1715370799.585 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-05-10T19:53:19.585Z,1715370799.585 [Default:CheckIn:D] Running Loop=1 2024-05-10T19:53:19.989Z,1715370799.989 [Default:CheckIn:D] Stopped 2024-05-10T19:53:19.989Z,1715370799.989 [Default:CheckIn:E] Running Loop=1 2024-05-10T19:53:20.411Z,1715370800.411 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 14.258174 min 2024-05-10T19:53:20.411Z,1715370800.411 [Default:CheckIn:E] Stopped 2024-05-10T19:53:20.411Z,1715370800.411 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-05-10T19:53:20.411Z,1715370800.411 [Default:CheckIn] Stopped 2024-05-10T19:53:20.411Z,1715370800.411 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-10T19:53:20.411Z,1715370800.411 [Default:CheckIn](INFO): Running loop #3 2024-05-10T19:53:20.411Z,1715370800.411 [Default:CheckIn] Running Loop=3 2024-05-10T19:53:20.412Z,1715370800.412 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-10T19:53:20.412Z,1715370800.412 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-10T19:53:22.413Z,1715370802.413 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195322.00,A,3648.16781,N,12147.25289,W,0.058,0.00,100524,,,D*7D 2024-05-10T19:53:22.415Z,1715370802.415 [NAL9602](INFO): GPS fix at 20240510T195322: (36.802797, -121.787548) 2024-05-10T19:53:22.424Z,1715370802.424 [Default:CheckIn:Read_GPS] Stopped 2024-05-10T19:53:22.424Z,1715370802.424 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-10T19:53:49.494Z,1715370829.494 [NAL9602](INFO): SBD MO Status=2, MOMSN=11780, MT Status=2, MTMSN=0 2024-05-10T19:53:49.494Z,1715370829.494 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-05-10T19:55:44.615Z,1715370944.615 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=11780, MT Status=1, MTMSN=3421 2024-05-10T19:55:44.615Z,1715370944.615 [NAL9602](INFO): Data available in MT queue 2024-05-10T19:55:45.152Z,1715370945.152 [NAL9602](INFO): Received command: restart sys 2024-05-10T19:55:45.179Z,1715370945.179 [CommandExec](IMPORTANT): got command restart system 2024-05-10T19:55:47.296Z,1715370947.296 [Supervisor](INFO): Stop Mission called by Supervisor::terminate 2024-05-10T19:55:47.296Z,1715370947.296 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-10T19:55:47.297Z,1715370947.297 [CommandExec](INFO): Uninitializing the command executive. 2024-05-10T19:55:47.297Z,1715370947.297 [CommandExec](INFO): Uninitializing the command scheduler. 2024-05-10T19:55:47.297Z,1715370947.297 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-05-10T19:55:47.376Z,1715370947.376 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2024-05-10T19:55:47.376Z,1715370947.376 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2024-05-10T19:55:47.376Z,1715370947.376 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-05-10T19:55:47.377Z,1715370947.377 [NavChartDb](INFO): Join timeout helper Thread ID is 1307 2024-05-10T19:55:47.552Z,1715370947.552 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-10T19:55:47.553Z,1715370947.553 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-05-10T19:55:47.556Z,1715370947.556 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2024-05-10T19:55:47.556Z,1715370947.556 [DAT ThreadHandler](INFO): Thread cancelled. 2024-05-10T19:55:47.557Z,1715370947.557 [DAT](INFO): Join timeout helper Thread ID is 1308 2024-05-10T19:55:47.788Z,1715370947.788 [DAT](INFO): Powering down 2024-05-10T19:55:47.864Z,1715370947.864 [DAT ThreadHandler](INFO): Uninitializing protected caller threa