2023-06-22T19:34:05.886Z,1687462445.886 [Supervisor](DEBUG): Initializing supervisor. 2023-06-22T19:34:05.891Z,1687462445.891 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2023-06-22T19:34:05.891Z,1687462445.891 [SyncHandler](INFO): Protected caller Thread ID is 837 2023-06-22T19:34:05.892Z,1687462445.892 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2023-06-22T19:34:05.893Z,1687462445.893 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2023-06-22T19:34:05.893Z,1687462445.893 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 838 2023-06-22T19:34:05.897Z,1687462445.897 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2023-06-22T19:34:05.914Z,1687462445.914 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2023-06-22T19:34:05.915Z,1687462445.915 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2023-06-22T19:34:05.915Z,1687462445.915 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 839 2023-06-22T19:34:05.920Z,1687462445.920 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2023-06-22T19:34:05.921Z,1687462445.921 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2023-06-22T19:34:05.921Z,1687462445.921 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 840 2023-06-22T19:34:05.923Z,1687462445.923 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2023-06-22T19:34:05.924Z,1687462445.924 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2023-06-22T19:34:05.924Z,1687462445.924 [logger ThreadHandler](INFO): Protected caller Thread ID is 841 2023-06-22T19:34:05.928Z,1687462445.928 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2023-06-22T19:34:05.928Z,1687462445.928 [Supervisor](INFO): Looking for Config files in directory: Config/ 2023-06-22T19:34:05.933Z,1687462445.933 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2023-06-22T19:34:06.397Z,1687462446.397 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2023-06-22T19:34:06.399Z,1687462446.399 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2023-06-22T19:34:06.603Z,1687462446.603 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2023-06-22T19:34:06.605Z,1687462446.605 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2023-06-22T19:34:06.685Z,1687462446.685 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2023-06-22T19:34:06.899Z,1687462446.899 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2023-06-22T19:34:06.901Z,1687462446.901 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2023-06-22T19:34:06.982Z,1687462446.982 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2023-06-22T19:34:07.078Z,1687462447.078 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2023-06-22T19:34:07.080Z,1687462447.080 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2023-06-22T19:34:07.424Z,1687462447.424 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2023-06-22T19:34:07.426Z,1687462447.426 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2023-06-22T19:34:07.558Z,1687462447.558 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2023-06-22T19:34:07.559Z,1687462447.559 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2023-06-22T19:34:08.036Z,1687462448.036 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2023-06-22T19:34:08.037Z,1687462448.037 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2023-06-22T19:34:08.217Z,1687462448.217 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2023-06-22T19:34:08.219Z,1687462448.219 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2023-06-22T19:34:08.607Z,1687462448.607 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2023-06-22T19:34:08.609Z,1687462448.609 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2023-06-22T19:34:08.896Z,1687462448.896 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2023-06-22T19:34:08.898Z,1687462448.898 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2023-06-22T19:34:09.006Z,1687462449.006 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2023-06-22T19:34:09.006Z,1687462449.006 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2023-06-22T19:34:10.608Z,1687462450.608 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2023-06-22T19:34:10.610Z,1687462450.610 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2023-06-22T19:34:10.934Z,1687462450.934 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2023-06-22T19:34:10.935Z,1687462450.935 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2023-06-22T19:34:11.169Z,1687462451.169 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2023-06-22T19:34:11.170Z,1687462451.170 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/ 2023-06-22T19:34:11.173Z,1687462451.173 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg 2023-06-22T19:34:11.293Z,1687462451.293 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg 2023-06-22T19:34:11.376Z,1687462451.376 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg 2023-06-22T19:34:11.513Z,1687462451.513 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg 2023-06-22T19:34:11.599Z,1687462451.599 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg 2023-06-22T19:34:11.865Z,1687462451.865 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2023-06-22T19:34:11.865Z,1687462451.865 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg 2023-06-22T19:34:11.967Z,1687462451.967 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg 2023-06-22T19:34:12.072Z,1687462452.072 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg 2023-06-22T19:34:12.098Z,1687462452.098 [Config/Navigation](ERROR): Unterminated quote: "US2MI01M,US2MI80M,US4MI57M,US4MI56M,US4MI90M,US4MI89M, 2023-06-22T19:34:12.182Z,1687462452.182 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg 2023-06-22T19:34:12.325Z,1687462452.325 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg 2023-06-22T19:34:12.423Z,1687462452.423 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg 2023-06-22T19:34:12.520Z,1687462452.520 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2023-06-22T19:34:12.525Z,1687462452.525 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2023-06-22T19:34:12.541Z,1687462452.541 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2023-06-22T19:34:12.543Z,1687462452.543 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2023-06-22T19:34:12.668Z,1687462452.668 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2023-06-22T19:34:12.670Z,1687462452.670 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2023-06-22T19:34:12.736Z,1687462452.736 [VerticalControl](DEBUG): Construct VerticalControl. 2023-06-22T19:34:12.797Z,1687462452.797 [VerticalControl] Loaded 2023-06-22T19:34:12.797Z,1687462452.797 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2023-06-22T19:34:12.800Z,1687462452.800 [HorizontalControl](DEBUG): Construct HorizontalControl. 2023-06-22T19:34:12.841Z,1687462452.841 [HorizontalControl] Loaded 2023-06-22T19:34:12.841Z,1687462452.841 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2023-06-22T19:34:12.844Z,1687462452.844 [SpeedControl](DEBUG): Construct SpeedControl. 2023-06-22T19:34:12.847Z,1687462452.847 [SpeedControl] Loaded 2023-06-22T19:34:12.847Z,1687462452.847 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2023-06-22T19:34:12.850Z,1687462452.850 [LoopControl](DEBUG): Construct LoopControl. 2023-06-22T19:34:12.850Z,1687462452.850 [LoopControl] Loaded 2023-06-22T19:34:12.850Z,1687462452.850 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2023-06-22T19:34:12.851Z,1687462452.851 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2023-06-22T19:34:12.853Z,1687462452.853 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2023-06-22T19:34:13.035Z,1687462453.035 [BuoyancyServo] Loaded 2023-06-22T19:34:13.036Z,1687462453.036 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2023-06-22T19:34:13.055Z,1687462453.055 [ElevatorServo] Loaded 2023-06-22T19:34:13.056Z,1687462453.056 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2023-06-22T19:34:13.074Z,1687462453.074 [MassServo] Loaded 2023-06-22T19:34:13.075Z,1687462453.075 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2023-06-22T19:34:13.093Z,1687462453.093 [RudderServo] Loaded 2023-06-22T19:34:13.093Z,1687462453.093 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2023-06-22T19:34:13.108Z,1687462453.108 [ThrusterHE] Loaded 2023-06-22T19:34:13.108Z,1687462453.108 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2023-06-22T19:34:13.108Z,1687462453.108 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2023-06-22T19:34:13.109Z,1687462453.109 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2023-06-22T19:34:13.222Z,1687462453.222 [DepthRateCalculator] Loaded 2023-06-22T19:34:13.223Z,1687462453.223 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2023-06-22T19:34:13.227Z,1687462453.227 [PitchRateCalculator] Loaded 2023-06-22T19:34:13.228Z,1687462453.228 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2023-06-22T19:34:13.237Z,1687462453.237 [SpeedCalculator] Loaded 2023-06-22T19:34:13.237Z,1687462453.237 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2023-06-22T19:34:13.242Z,1687462453.242 [YawRateCalculator] Loaded 2023-06-22T19:34:13.242Z,1687462453.242 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2023-06-22T19:34:13.260Z,1687462453.260 [ElevatorOffsetCalculator] Loaded 2023-06-22T19:34:13.260Z,1687462453.260 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2023-06-22T19:34:13.261Z,1687462453.261 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2023-06-22T19:34:13.263Z,1687462453.263 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2023-06-22T19:34:13.401Z,1687462453.401 [DeadReckonUsingMultipleVelocitySources] Loaded 2023-06-22T19:34:13.401Z,1687462453.401 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2023-06-22T19:34:13.415Z,1687462453.415 [NavChart] Loaded 2023-06-22T19:34:13.415Z,1687462453.415 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2023-06-22T19:34:13.421Z,1687462453.421 [UniversalFixResidualReporter] Loaded 2023-06-22T19:34:13.421Z,1687462453.421 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2023-06-22T19:34:13.422Z,1687462453.422 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2023-06-22T19:34:13.423Z,1687462453.423 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2023-06-22T19:34:14.166Z,1687462454.166 [AHRS_M2] Loaded 2023-06-22T19:34:14.167Z,1687462454.167 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2023-06-22T19:34:14.417Z,1687462454.417 [BackseatComponent] Loaded 2023-06-22T19:34:14.417Z,1687462454.417 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2023-06-22T19:34:14.418Z,1687462454.418 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408E54E0 2023-06-22T19:34:14.418Z,1687462454.418 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 924 2023-06-22T19:34:14.421Z,1687462454.421 [LcmUniversalReporter] Loaded 2023-06-22T19:34:14.421Z,1687462454.421 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2023-06-22T19:34:15.244Z,1687462455.244 [BPC1] Loaded 2023-06-22T19:34:15.245Z,1687462455.245 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2023-06-22T19:34:15.605Z,1687462455.605 [DataOverHttps] Loaded 2023-06-22T19:34:15.605Z,1687462455.605 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2023-06-22T19:34:15.618Z,1687462455.618 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409154E0 2023-06-22T19:34:15.619Z,1687462455.619 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 925 2023-06-22T19:34:15.642Z,1687462455.642 [Depth_Keller] Loaded 2023-06-22T19:34:15.643Z,1687462455.643 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2023-06-22T19:34:15.648Z,1687462455.648 [DropWeight] Loaded 2023-06-22T19:34:15.648Z,1687462455.648 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2023-06-22T19:34:15.710Z,1687462455.710 [NAL9602] Loaded 2023-06-22T19:34:15.710Z,1687462455.710 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2023-06-22T19:34:15.745Z,1687462455.745 [Onboard] Loaded 2023-06-22T19:34:15.745Z,1687462455.745 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2023-06-22T19:34:15.746Z,1687462455.746 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409454E0 2023-06-22T19:34:15.747Z,1687462455.747 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 926 2023-06-22T19:34:15.759Z,1687462455.759 [Power24vConverter] Loaded 2023-06-22T19:34:15.759Z,1687462455.759 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2023-06-22T19:34:15.772Z,1687462455.772 [Radio_Surface] Loaded 2023-06-22T19:34:15.772Z,1687462455.772 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2023-06-22T19:34:15.773Z,1687462455.773 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409754E0 2023-06-22T19:34:15.774Z,1687462455.774 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 927 2023-06-22T19:34:15.788Z,1687462455.788 [Sonardyne_Nano] Loaded 2023-06-22T19:34:15.788Z,1687462455.788 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2023-06-22T19:34:15.789Z,1687462455.789 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2023-06-22T19:34:15.790Z,1687462455.790 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2023-06-22T19:34:15.880Z,1687462455.880 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2023-06-22T19:34:15.881Z,1687462455.881 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2023-06-22T19:34:16.060Z,1687462456.060 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2023-06-22T19:34:16.061Z,1687462456.061 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2023-06-22T19:34:16.104Z,1687462456.104 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2023-06-22T19:34:16.105Z,1687462456.105 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2023-06-22T19:34:16.321Z,1687462456.321 [CTD_Seabird] Loaded 2023-06-22T19:34:16.321Z,1687462456.321 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2023-06-22T19:34:16.322Z,1687462456.322 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40AA94E0 2023-06-22T19:34:16.322Z,1687462456.322 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 928 2023-06-22T19:34:16.342Z,1687462456.342 [PAR_Licor] Loaded 2023-06-22T19:34:16.342Z,1687462456.342 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2023-06-22T19:34:16.373Z,1687462456.373 [WetLabsBB2FL] Loaded 2023-06-22T19:34:16.373Z,1687462456.373 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2023-06-22T19:34:16.374Z,1687462456.374 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AD94E0 2023-06-22T19:34:16.375Z,1687462456.375 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 929 2023-06-22T19:34:16.375Z,1687462456.375 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2023-06-22T19:34:16.376Z,1687462456.376 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2023-06-22T19:34:16.733Z,1687462456.733 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2023-06-22T19:34:16.734Z,1687462456.734 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2023-06-22T19:34:16.903Z,1687462456.903 [SBIT](DEBUG): Construct Startup Built In Test. 2023-06-22T19:34:16.912Z,1687462456.912 [SBIT] Loaded 2023-06-22T19:34:16.912Z,1687462456.912 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2023-06-22T19:34:16.915Z,1687462456.915 [IBIT](DEBUG): Construct Initiated Built In Test. 2023-06-22T19:34:16.927Z,1687462456.927 [IBIT] Loaded 2023-06-22T19:34:16.928Z,1687462456.928 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2023-06-22T19:34:16.934Z,1687462456.934 [CBIT](DEBUG): Construct Continuous Built In Test. 2023-06-22T19:34:17.038Z,1687462457.038 [CBIT] Loaded 2023-06-22T19:34:17.038Z,1687462457.038 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2023-06-22T19:34:17.039Z,1687462457.039 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2023-06-22T19:34:17.045Z,1687462457.045 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2023-06-22T19:34:17.048Z,1687462457.048 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2023-06-22T19:34:17.059Z,1687462457.059 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2023-06-22T19:34:17.060Z,1687462457.060 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BC44E0 2023-06-22T19:34:17.061Z,1687462457.061 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 930 2023-06-22T19:34:17.065Z,1687462457.065 [Supervisor](INFO): Main Thread ID is 830 2023-06-22T19:34:17.065Z,1687462457.065 [Supervisor](DEBUG): Running supervisor. 2023-06-22T19:34:17.066Z,1687462457.066 [CommandExec ThreadHandler](INFO): Handler Thread ID is 931 2023-06-22T19:34:17.066Z,1687462457.066 [CommandExec](INFO): Initializing the command executive. 2023-06-22T19:34:17.068Z,1687462457.068 [CommandLine ThreadHandler](INFO): Handler Thread ID is 932 2023-06-22T19:34:17.070Z,1687462457.070 [controlThread ThreadHandler](INFO): Handler Thread ID is 933 2023-06-22T19:34:17.070Z,1687462457.070 [controlThread](DEBUG): Initializing ControlThread 2023-06-22T19:34:17.071Z,1687462457.071 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2023-06-22T19:34:17.073Z,1687462457.073 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2023-06-22T19:34:17.074Z,1687462457.074 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2023-06-22T19:34:17.074Z,1687462457.074 [LoopControl](DEBUG): Initialize LoopControlComponent. 2023-06-22T19:34:17.076Z,1687462457.076 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2023-06-22T19:34:17.076Z,1687462457.076 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2023-06-22T19:34:17.076Z,1687462457.076 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2023-06-22T19:34:17.077Z,1687462457.077 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2023-06-22T19:34:17.077Z,1687462457.077 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2023-06-22T19:34:17.078Z,1687462457.078 [NavChart](DEBUG): Initialize NavChart Navigation. 2023-06-22T19:34:17.079Z,1687462457.079 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2023-06-22T19:34:17.088Z,1687462457.088 [SBIT](INFO): Initialize SBIT Component. 2023-06-22T19:34:17.088Z,1687462457.088 [SBIT](IMPORTANT): git: 2023-06-22_A 2023-06-22T19:34:17.088Z,1687462457.088 [SBIT](INFO): git hash: f6e8fb21c2ca692927bf9f630bd97d92d0fd0251 2023-06-22T19:34:17.089Z,1687462457.089 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2023-06-22T19:34:17.090Z,1687462457.090 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018 Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021 2023-06-22T19:34:17.091Z,1687462457.091 [SBIT](INFO): Beginning SBIT in 20.000000 seconds. 2023-06-22T19:34:17.092Z,1687462457.092 [IBIT](INFO): Initialize IBIT Component. 2023-06-22T19:34:17.093Z,1687462457.093 [CBIT](DEBUG): Initialize CBIT Component. 2023-06-22T19:34:17.093Z,1687462457.093 [logger ThreadHandler](INFO): Handler Thread ID is 934 2023-06-22T19:34:17.103Z,1687462457.103 [CBIT](DEBUG): Initialized mux pins. 2023-06-22T19:34:17.104Z,1687462457.104 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2023-06-22T19:34:17.104Z,1687462457.104 [CBIT](DEBUG): Initializing the watchdog timer. 2023-06-22T19:34:17.112Z,1687462457.112 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 935 2023-06-22T19:34:17.124Z,1687462457.124 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 936 2023-06-22T19:34:17.125Z,1687462457.125 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2023-06-22T19:34:17.128Z,1687462457.128 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET. 2023-06-22T19:34:17.128Z,1687462457.128 [CBIT](DEBUG): Initializing heartbeat. 2023-06-22T19:34:17.136Z,1687462457.136 [Onboard ThreadHandler](INFO): Handler Thread ID is 937 2023-06-22T19:34:17.166Z,1687462457.166 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 938 2023-06-22T19:34:17.176Z,1687462457.176 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 939 2023-06-22T19:34:17.177Z,1687462457.177 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2023-06-22T19:34:17.181Z,1687462457.181 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 941 2023-06-22T19:34:17.184Z,1687462457.184 [WetLabsBB2FL](INFO): Powering up 2023-06-22T19:34:17.185Z,1687462457.185 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 943 2023-06-22T19:34:17.193Z,1687462457.193 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2023-06-22T19:34:17.193Z,1687462457.193 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2023-06-22T19:34:17.193Z,1687462457.193 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2023-06-22T19:34:17.193Z,1687462457.193 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2023-06-22T19:34:17.193Z,1687462457.193 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2023-06-22T19:34:17.194Z,1687462457.194 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2023-06-22T19:34:17.194Z,1687462457.194 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2023-06-22T19:34:17.194Z,1687462457.194 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2023-06-22T19:34:17.200Z,1687462457.200 [CBIT](DEBUG): Deactivating GF circuits. 2023-06-22T19:34:17.200Z,1687462457.200 [CBIT](DEBUG): Deactivating emergency mode. 2023-06-22T19:34:17.240Z,1687462457.240 [CBIT](DEBUG): Backplane powered. 2023-06-22T19:34:17.241Z,1687462457.241 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml 2023-06-22T19:34:17.281Z,1687462457.281 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2023-06-22T19:34:17.292Z,1687462457.292 [MissionManager](DEBUG): 2023-06-22T19:34:17.293Z,1687462457.293 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml 2023-06-22T19:34:17.365Z,1687462457.365 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2023-06-22T19:34:17.367Z,1687462457.367 [Default:A.Wait](DEBUG): Construct Wait. 2023-06-22T19:34:17.389Z,1687462457.389 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2023-06-22T19:34:17.437Z,1687462457.437 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2023-06-22T19:34:17.439Z,1687462457.439 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2023-06-22T19:34:17.461Z,1687462457.461 [Default:E.Execute](DEBUG): Construct Execute. 2023-06-22T19:34:17.465Z,1687462457.465 [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-06-22T19:34:17.470Z,1687462457.470 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2023-06-22T19:34:17.496Z,1687462457.496 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2023-06-22T19:34:17.568Z,1687462457.568 [Radio_Surface](INFO): Powering up 2023-06-22T19:34:17.643Z,1687462457.643 [Power24vConverter](INFO): Powering up. 2023-06-22T19:34:17.644Z,1687462457.644 [Sonardyne_Nano](INFO): Initializing. 2023-06-22T19:34:17.726Z,1687462457.726 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2023-06-22T19:34:17.732Z,1687462457.732 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2023-06-22T19:34:17.733Z,1687462457.733 [ElevatorServo](DEBUG): Initializing EZServoServo. 2023-06-22T19:34:17.740Z,1687462457.740 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2023-06-22T19:34:17.741Z,1687462457.741 [MassServo](DEBUG): Initializing EZServoServo. 2023-06-22T19:34:17.748Z,1687462457.748 [MassServo](DEBUG): Initializing MassServo. 2023-06-22T19:34:17.749Z,1687462457.749 [RudderServo](DEBUG): Initializing EZServoServo. 2023-06-22T19:34:17.756Z,1687462457.756 [RudderServo](DEBUG): Initializing RudderServo. 2023-06-22T19:34:17.757Z,1687462457.757 [ThrusterHE](DEBUG): Initializing EZServoServo. 2023-06-22T19:34:17.764Z,1687462457.764 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2023-06-22T19:34:17.766Z,1687462457.766 [CommandExec](FAULT): Scheduling is paused 2023-06-22T19:34:17.766Z,1687462457.766 [CBIT](INFO): Critical error at 20230622T193417 2023-06-22T19:34:17.766Z,1687462457.766 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2023-06-22T19:34:19.292Z,1687462459.292 [WetLabsBB2FL](INFO): Powering down 2023-06-22T19:34:19.902Z,1687462459.902 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2023-06-22T19:34:23.348Z,1687462463.348 [ThrusterHE](ERROR): Zero Speed Commanded. 2023-06-22T19:34:24.208Z,1687462464.208 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2023-06-22T19:34:37.773Z,1687462477.773 [SBIT](IMPORTANT): Beginning Startup BIT 2023-06-22T19:34:37.777Z,1687462477.777 [CBIT](IMPORTANT): Beginning ground fault scan 2023-06-22T19:34:45.766Z,1687462485.766 [NAL9602](INFO): Powering up NAL9602 2023-06-22T19:34:48.649Z,1687462488.649 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.787927 CHAN A1 (24V): 0.731447 CHAN A2 (12V): 0.592502 CHAN A3 (5V): 0.190532 CHAN B0 (3.3V): 0.119726 CHAN B1 (3.15aV): 0.129066 CHAN B2 (3.15bV): 0.128512 CHAN B3 (GND): -0.024132 OPEN: 0.017015 Full Scale: +/- 1 mA 2023-06-22T19:34:56.673Z,1687462496.673 [NAL9602](INFO): NAL9602 initialized 2023-06-22T19:35:18.116Z,1687462518.116 [NAL9602](INFO): SBD MO Status=2, MOMSN=12372, MT Status=2, MTMSN=0 2023-06-22T19:35:18.116Z,1687462518.116 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-06-22T19:35:31.834Z,1687462531.834 [SBIT](IMPORTANT): SBIT PASSED 2023-06-22T19:35:31.834Z,1687462531.834 [SBIT](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2023-06-22T19:35:32.240Z,1687462532.240 [MissionManager](IMPORTANT): Started mission Startup 2023-06-22T19:35:32.241Z,1687462532.241 [Startup] Running Loop=1 2023-06-22T19:35:32.241Z,1687462532.241 [Startup](DEBUG): Aggregate::initialize Startup 2023-06-22T19:35:32.241Z,1687462532.241 [Startup:A.GoToSurface] Running Loop=1 2023-06-22T19:35:32.241Z,1687462532.241 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-06-22T19:35:32.241Z,1687462532.241 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-06-22T19:35:32.242Z,1687462532.242 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-06-22T19:35:32.242Z,1687462532.242 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-06-22T19:35:32.243Z,1687462532.243 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-06-22T19:35:32.243Z,1687462532.243 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-06-22T19:35:32.244Z,1687462532.244 [Startup:StartupSatComms] Running Loop=1 2023-06-22T19:35:32.244Z,1687462532.244 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2023-06-22T19:35:32.245Z,1687462532.245 [Startup:StartupSatComms:A] Running Loop=1 2023-06-22T19:35:32.647Z,1687462532.647 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2023-06-22T19:36:32.442Z,1687462592.442 [Startup:StartupSatComms:A](INFO): Timed out from 2023-06-22T19:35:32.2Z 2023-06-22T19:36:32.442Z,1687462592.442 [Startup:StartupSatComms:A] Stopped 2023-06-22T19:36:32.442Z,1687462592.442 [Startup:StartupSatComms:B] Running Loop=1 2023-06-22T19:36:32.833Z,1687462592.833 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2023-06-22T19:37:17.272Z,1687462637.272 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2023-06-22T19:37:17.272Z,1687462637.272 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-06-22T19:37:17.292Z,1687462637.292 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-06-22T19:37:17.690Z,1687462637.690 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-06-22T19:37:17.690Z,1687462637.690 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2023-06-22T19:37:32.650Z,1687462652.650 [Startup:StartupSatComms:B](INFO): Timed out from 2023-06-22T19:36:32.4Z 2023-06-22T19:37:32.650Z,1687462652.650 [Startup:StartupSatComms:B] Stopped 2023-06-22T19:37:32.650Z,1687462652.650 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2023-06-22T19:37:32.650Z,1687462652.650 [Startup:StartupSatComms] Stopped 2023-06-22T19:37:32.650Z,1687462652.650 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2023-06-22T19:37:32.651Z,1687462652.651 [Startup](INFO): Completed Startup 2023-06-22T19:37:32.651Z,1687462652.651 [MissionManager](INFO): Startup is completed. 2023-06-22T19:37:32.652Z,1687462652.652 [MissionManager](INFO): Uninitializing Mission Startup 2023-06-22T19:37:32.652Z,1687462652.652 [Startup] Stopped 2023-06-22T19:37:32.652Z,1687462652.652 [Startup](DEBUG): Aggregate::uninitialize Startup 2023-06-22T19:37:32.652Z,1687462652.652 [Startup:A.GoToSurface] Stopped 2023-06-22T19:37:32.652Z,1687462652.652 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-06-22T19:37:33.053Z,1687462653.053 [MissionManager](IMPORTANT): Started mission Default 2023-06-22T19:37:33.053Z,1687462653.053 [Default] Running Loop=1 2023-06-22T19:37:33.053Z,1687462653.053 [Default](DEBUG): Aggregate::initialize Default 2023-06-22T19:37:33.053Z,1687462653.053 [Default:B.GoToSurface] Running Loop=1 2023-06-22T19:37:33.053Z,1687462653.053 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-06-22T19:37:33.054Z,1687462653.054 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-06-22T19:37:33.054Z,1687462653.054 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-06-22T19:37:33.054Z,1687462653.054 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-06-22T19:37:33.055Z,1687462653.055 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-06-22T19:37:33.055Z,1687462653.055 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-06-22T19:37:33.055Z,1687462653.055 [Default:A.Wait] Running Loop=1 2023-06-22T19:37:33.055Z,1687462653.055 [Default:A.Wait](DEBUG): Initialize Wait Component. 2023-06-22T19:37:46.372Z,1687462666.372 [Default:A.Wait](INFO): Done Waiting. 2023-06-22T19:37:46.372Z,1687462666.372 [Default:A.Wait] Stopped 2023-06-22T19:37:46.372Z,1687462666.372 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2023-06-22T19:37:46.762Z,1687462666.762 [Default:CheckIn] Running Loop=1 2023-06-22T19:37:46.762Z,1687462666.762 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-06-22T19:37:46.763Z,1687462666.763 [Default:CheckIn:Read_GPS] Running Loop=1 2023-06-22T19:37:47.174Z,1687462667.174 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2023-06-22T19:37:48.170Z,1687462668.170 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004408 2023-06-22T19:37:59.272Z,1687462679.272 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-22T19:38:14.625Z,1687462694.625 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-22T19:38:29.976Z,1687462709.976 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-22T19:38:45.739Z,1687462725.739 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-22T19:39:01.093Z,1687462741.093 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-22T19:39:15.633Z,1687462755.633 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-22T19:39:30.580Z,1687462770.580 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-22T19:39:45.528Z,1687462785.528 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-22T19:40:00.119Z,1687462800.119 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-06-22T19:40:00.476Z,1687462800.476 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-22T19:40:15.430Z,1687462815.430 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-22T19:40:18.269Z,1687462818.269 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2023-06-22T19:40:18.269Z,1687462818.269 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-06-22T19:40:18.280Z,1687462818.280 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-06-22T19:40:18.705Z,1687462818.705 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-06-22T19:40:18.705Z,1687462818.705 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2023-06-22T19:42:47.003Z,1687462967.003 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-06-22T19:37:46.8Z 2023-06-22T19:42:47.003Z,1687462967.003 [Default:CheckIn:Read_GPS] Stopped 2023-06-22T19:42:47.003Z,1687462967.003 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-06-22T19:42:47.458Z,1687462967.458 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2023-06-22T19:42:54.603Z,1687462974.603 [DataOverHttps](INFO): Sending 213 bytes from file Logs/20230622T193405/Courier0000.lzma 2023-06-22T19:42:55.608Z,1687462975.608 [DataOverHttps](INFO): Moved sent file to Logs/20230622T193405/Courier0000.lzma.bak 2023-06-22T19:42:55.608Z,1687462975.608 [DataOverHttps](INFO): SBD MOMSN=18418635 2023-06-22T19:43:14.295Z,1687462994.295 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230622T193405/Courier0004.lzma 2023-06-22T19:43:15.297Z,1687462995.297 [DataOverHttps](INFO): Moved sent file to Logs/20230622T193405/Courier0004.lzma.bak 2023-06-22T19:43:15.298Z,1687462995.298 [DataOverHttps](INFO): SBD MOMSN=18418639 2023-06-22T19:43:19.409Z,1687462999.409 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2023-06-22T19:43:19.409Z,1687462999.409 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-06-22T19:43:19.419Z,1687462999.419 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-06-22T19:43:19.845Z,1687462999.845 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-06-22T19:43:19.845Z,1687462999.845 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2023-06-22T19:43:30.863Z,1687463010.863 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20230622T143854/Express0110.lzma 2023-06-22T19:43:31.869Z,1687463011.869 [DataOverHttps](INFO): Moved sent file to Logs/20230622T143854/Express0110.lzma.bak 2023-06-22T19:43:31.869Z,1687463011.869 [DataOverHttps](INFO): SBD MOMSN=18418641 2023-06-22T19:43:47.544Z,1687463027.544 [DataOverHttps](INFO): Sending 648 bytes from file Logs/20230622T193405/Express0001.lzma 2023-06-22T19:43:48.545Z,1687463028.545 [DataOverHttps](INFO): Moved sent file to Logs/20230622T193405/Express0001.lzma.bak 2023-06-22T19:43:48.545Z,1687463028.545 [DataOverHttps](INFO): SBD MOMSN=18418643 2023-06-22T19:44:04.167Z,1687463044.167 [DataOverHttps](INFO): Sending 189 bytes from file Logs/20230622T193405/Express0005.lzma 2023-06-22T19:44:05.169Z,1687463045.169 [DataOverHttps](INFO): Moved sent file to Logs/20230622T193405/Express0005.lzma.bak 2023-06-22T19:44:05.170Z,1687463045.170 [DataOverHttps](INFO): SBD MOMSN=18418656 2023-06-22T19:44:06.686Z,1687463046.686 [Default:CheckIn:Read_Iridium] Stopped 2023-06-22T19:44:06.686Z,1687463046.686 [Default:CheckIn:C.Wait] Running Loop=1 2023-06-22T19:44:06.686Z,1687463046.686 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-06-22T19:44:18.390Z,1687463058.390 [Power24vConverter](INFO): Powering down. 2023-06-22T19:44:21.628Z,1687463061.628 [Sonardyne_Nano](INFO): Battery at maximum, disabling charging 2023-06-22T19:44:57.649Z,1687463097.649 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-06-22T19:44:57.649Z,1687463097.649 [NAL9602] Data Fault, FailCount= 1 2023-06-22T19:44:57.649Z,1687463097.649 [NAL9602](ERROR): Data Fault 2023-06-22T19:44:57.691Z,1687463097.691 [CBIT](ERROR): Data Fault in component: NAL9602 2023-06-22T19:44:58.041Z,1687463098.041 [NAL9602](INFO): Powering down 2023-06-22T19:44:58.877Z,1687463098.877 [CBIT](INFO): Clearing failed state for component NAL9602 2023-06-22T19:44:58.878Z,1687463098.878 [NAL9602] No Fault, FailCount= 1 2023-06-22T19:45:28.396Z,1687463128.396 [NAL9602](INFO): Powering up NAL9602 2023-06-22T19:45:39.253Z,1687463139.253 [NAL9602](INFO): NAL9602 initialized 2023-06-22T19:46:20.466Z,1687463180.466 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2023-06-22T19:46:20.466Z,1687463180.466 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-06-22T19:46:20.477Z,1687463180.477 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-06-22T19:46:20.884Z,1687463180.884 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-06-22T19:46:20.884Z,1687463180.884 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2023-06-22T19:47:38.833Z,1687463258.833 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-22T19:47:45.302Z,1687463265.302 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-22T19:48:00.244Z,1687463280.244 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-22T19:48:15.596Z,1687463295.596 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-22T19:48:30.948Z,1687463310.948 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-22T19:48:46.300Z,1687463326.300 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-22T19:49:01.653Z,1687463341.653 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-22T19:49:07.349Z,1687463347.349 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-06-22T19:49:07.349Z,1687463347.349 [Default:CheckIn:C.Wait] Stopped 2023-06-22T19:49:07.349Z,1687463347.349 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-06-22T19:49:07.349Z,1687463347.349 [Default:CheckIn:D] Running Loop=1 2023-06-22T19:49:07.730Z,1687463347.730 [Default:CheckIn:D] Stopped 2023-06-22T19:49:07.730Z,1687463347.730 [Default:CheckIn:E] Running Loop=1 2023-06-22T19:49:08.128Z,1687463348.128 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.577940 min 2023-06-22T19:49:08.128Z,1687463348.128 [Default:CheckIn:E] Stopped 2023-06-22T19:49:08.128Z,1687463348.128 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-06-22T19:49:08.128Z,1687463348.128 [Default:CheckIn] Stopped 2023-06-22T19:49:08.128Z,1687463348.128 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-06-22T19:49:08.129Z,1687463348.129 [Default:CheckIn](INFO): Running loop #2 2023-06-22T19:49:08.129Z,1687463348.129 [Default:CheckIn] Running Loop=2 2023-06-22T19:49:08.129Z,1687463348.129 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-06-22T19:49:08.129Z,1687463348.129 [Default:CheckIn:Read_GPS] Running Loop=1 2023-06-22T19:49:16.601Z,1687463356.601 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-22T19:49:21.477Z,1687463361.477 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2023-06-22T19:49:21.477Z,1687463361.477 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-06-22T19:49:21.511Z,1687463361.511 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-06-22T19:49:21.902Z,1687463361.902 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-06-22T19:49:21.902Z,1687463361.902 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2023-06-22T19:49:31.553Z,1687463371.553 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-22T19:49:46.900Z,1687463386.900 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-22T19:50:01.444Z,1687463401.444 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2023-06-22T19:52:22.528Z,1687463542.528 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2023-06-22T19:52:22.528Z,1687463542.528 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-06-22T19:52:22.539Z,1687463542.539 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-06-22T19:52:22.932Z,1687463542.932 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-06-22T19:52:22.933Z,1687463542.933 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2023-06-22T19:54:08.447Z,1687463648.447 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-06-22T19:49:08.1Z 2023-06-22T19:54:08.447Z,1687463648.447 [Default:CheckIn:Read_GPS] Stopped 2023-06-22T19:54:08.447Z,1687463648.447 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-06-22T19:54:14.615Z,1687463654.615 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230622T193405/Courier0007.lzma 2023-06-22T19:54:15.617Z,1687463655.617 [DataOverHttps](INFO): Moved sent file to Logs/20230622T193405/Courier0007.lzma.bak 2023-06-22T19:54:15.617Z,1687463655.617 [DataOverHttps](INFO): SBD MOMSN=18418673 2023-06-22T19:54:31.135Z,1687463671.135 [DataOverHttps](INFO): Sending 182 bytes from file Logs/20230622T193405/Express0008.lzma 2023-06-22T19:54:32.137Z,1687463672.137 [DataOverHttps](INFO): Moved sent file to Logs/20230622T193405/Express0008.lzma.bak 2023-06-22T19:54:32.137Z,1687463672.137 [DataOverHttps](INFO): SBD MOMSN=18418675 2023-06-22T19:54:33.458Z,1687463673.458 [Default:CheckIn:Read_Iridium] Stopped 2023-06-22T19:54:33.458Z,1687463673.458 [Default:CheckIn:C.Wait] Running Loop=1 2023-06-22T19:54:33.458Z,1687463673.458 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-06-22T19:55:23.654Z,1687463723.654 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7 2023-06-22T19:55:23.654Z,1687463723.654 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-06-22T19:55:23.665Z,1687463723.665 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-06-22T19:55:24.038Z,1687463724.038 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-06-22T19:55:24.038Z,1687463724.038 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7 2023-06-22T19:55:41.353Z,1687463741.353 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-06-22T19:55:41.353Z,1687463741.353 [NAL9602] Data Fault, FailCount= 2 2023-06-22T19:55:41.353Z,1687463741.353 [NAL9602](ERROR): Data Fault 2023-06-22T19:55:41.371Z,1687463741.371 [CBIT](ERROR): Data Fault in component: NAL9602 2023-06-22T19:55:41.757Z,1687463741.757 [NAL9602](INFO): Powering down 2023-06-22T19:55:42.598Z,1687463742.598 [CBIT](INFO): Clearing failed state for component NAL9602 2023-06-22T19:55:42.598Z,1687463742.598 [NAL9602] No Fault, FailCount= 2 2023-06-22T19:56:12.057Z,1687463772.057 [NAL9602](INFO): Powering up NAL9602 2023-06-22T19:56:23.052Z,1687463783.052 [NAL9602](INFO): NAL9602 initialized 2023-06-22T19:57:24.365Z,1687463844.365 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-22T19:57:27.628Z,1687463847.628 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.391033 2023-06-22T19:57:39.721Z,1687463859.721 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-22T19:57:54.664Z,1687463874.664 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-22T19:58:00.088Z,1687463880.088 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003114 2023-06-22T19:58:09.209Z,1687463889.209 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-22T19:58:24.560Z,1687463904.560 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-22T19:58:24.608Z,1687463904.608 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8 2023-06-22T19:58:24.608Z,1687463904.608 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-06-22T19:58:24.618Z,1687463904.618 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-06-22T19:58:25.022Z,1687463905.022 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-06-22T19:58:25.022Z,1687463905.022 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8 2023-06-22T19:58:40.720Z,1687463920.720 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-22T19:58:55.693Z,1687463935.693 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-22T19:59:10.641Z,1687463950.641 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-22T19:59:25.588Z,1687463965.588 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-22T19:59:34.113Z,1687463974.113 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-06-22T19:59:34.113Z,1687463974.113 [Default:CheckIn:C.Wait] Stopped 2023-06-22T19:59:34.113Z,1687463974.113 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-06-22T19:59:34.113Z,1687463974.113 [Default:CheckIn:D] Running Loop=1 2023-06-22T19:59:34.521Z,1687463974.521 [Default:CheckIn:D] Stopped 2023-06-22T19:59:34.521Z,1687463974.521 [Default:CheckIn:E] Running Loop=1 2023-06-22T19:59:34.922Z,1687463974.922 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 22.024465 min 2023-06-22T19:59:34.922Z,1687463974.922 [Default:CheckIn:E] Stopped 2023-06-22T19:59:34.922Z,1687463974.922 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-06-22T19:59:34.922Z,1687463974.922 [Default:CheckIn] Stopped 2023-06-22T19:59:34.922Z,1687463974.922 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-06-22T19:59:34.922Z,1687463974.922 [Default:CheckIn](INFO): Running loop #3 2023-06-22T19:59:34.922Z,1687463974.922 [Default:CheckIn] Running Loop=3 2023-06-22T19:59:34.922Z,1687463974.922 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-06-22T19:59:34.922Z,1687463974.922 [Default:CheckIn:Read_GPS] Running Loop=1 2023-06-22T19:59:40.942Z,1687463980.942 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-22T20:01:25.625Z,1687464085.625 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9 2023-06-22T20:01:25.625Z,1687464085.625 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-06-22T20:01:25.654Z,1687464085.654 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-06-22T20:01:26.035Z,1687464086.035 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-06-22T20:01:26.035Z,1687464086.035 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9 2023-06-22T20:01:39.102Z,1687464099.102 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=61.133053 2023-06-22T20:01:47.273Z,1687464107.273 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003125 2023-06-22T20:04:26.649Z,1687464266.649 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10 2023-06-22T20:04:26.649Z,1687464266.649 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-06-22T20:04:26.670Z,1687464266.670 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-06-22T20:04:27.055Z,1687464267.055 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-06-22T20:04:27.055Z,1687464267.055 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10 2023-06-22T20:04:35.132Z,1687464275.132 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-06-22T19:59:34.9Z 2023-06-22T20:04:35.132Z,1687464275.132 [Default:CheckIn:Read_GPS] Stopped 2023-06-22T20:04:35.132Z,1687464275.132 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-06-22T20:04:42.491Z,1687464282.491 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230622T193405/Courier0010.lzma 2023-06-22T20:04:43.493Z,1687464283.493 [DataOverHttps](INFO): Moved sent file to Logs/20230622T193405/Courier0010.lzma.bak 2023-06-22T20:04:43.493Z,1687464283.493 [DataOverHttps](INFO): SBD MOMSN=18418694 2023-06-22T20:05:01.595Z,1687464301.595 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20230622T193405/Express0011.lzma 2023-06-22T20:05:02.597Z,1687464302.597 [DataOverHttps](INFO): Moved sent file to Logs/20230622T193405/Express0011.lzma.bak 2023-06-22T20:05:02.597Z,1687464302.597 [DataOverHttps](INFO): SBD MOMSN=18418696 2023-06-22T20:05:03.843Z,1687464303.843 [Default:CheckIn:Read_Iridium] Stopped 2023-06-22T20:05:03.843Z,1687464303.843 [Default:CheckIn:C.Wait] Running Loop=1 2023-06-22T20:05:03.843Z,1687464303.843 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-06-22T20:05:17.945Z,1687464317.945 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6. 2023-06-22T20:05:17.948Z,1687464317.948 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle. 2023-06-22T20:06:23.805Z,1687464383.805 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-06-22T20:06:23.805Z,1687464383.805 [NAL9602] Data Fault, FailCount= 3 2023-06-22T20:06:23.805Z,1687464383.805 [NAL9602](ERROR): Data Fault 2023-06-22T20:06:23.874Z,1687464383.874 [CBIT](ERROR): Data Fault in component: NAL9602 2023-06-22T20:06:24.213Z,1687464384.213 [NAL9602](INFO): Powering down 2023-06-22T20:06:25.048Z,1687464385.048 [CBIT](INFO): Clearing failed state for component NAL9602 2023-06-22T20:06:25.048Z,1687464385.048 [NAL9602] No Fault, FailCount= 3 2023-06-22T20:06:54.589Z,1687464414.589 [NAL9602](INFO): Powering up NAL9602 2023-06-22T20:07:05.470Z,1687464425.470 [NAL9602](INFO): NAL9602 initialized 2023-06-22T20:07:27.739Z,1687464447.739 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11 2023-06-22T20:07:27.739Z,1687464447.739 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-06-22T20:07:27.774Z,1687464447.774 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-06-22T20:07:28.142Z,1687464448.142 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-06-22T20:07:28.142Z,1687464448.142 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11 2023-06-22T20:10:04.504Z,1687464604.504 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-06-22T20:10:04.504Z,1687464604.504 [Default:CheckIn:C.Wait] Stopped 2023-06-22T20:10:04.505Z,1687464604.505 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-06-22T20:10:04.505Z,1687464604.505 [Default:CheckIn:D] Running Loop=1 2023-06-22T20:10:04.902Z,1687464604.902 [Default:CheckIn:D] Stopped 2023-06-22T20:10:04.902Z,1687464604.902 [Default:CheckIn:E] Running Loop=1 2023-06-22T20:10:05.299Z,1687464605.299 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 32.530811 min 2023-06-22T20:10:05.299Z,1687464605.299 [Default:CheckIn:E] Stopped 2023-06-22T20:10:05.300Z,1687464605.300 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-06-22T20:10:05.300Z,1687464605.300 [Default:CheckIn] Stopped 2023-06-22T20:10:05.300Z,1687464605.300 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-06-22T20:10:05.300Z,1687464605.300 [Default:CheckIn](INFO): Running loop #4 2023-06-22T20:10:05.300Z,1687464605.300 [Default:CheckIn] Running Loop=4 2023-06-22T20:10:05.300Z,1687464605.300 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-06-22T20:10:05.300Z,1687464605.300 [Default:CheckIn:Read_GPS] Running Loop=1 2023-06-22T20:10:28.737Z,1687464628.737 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12 2023-06-22T20:10:28.737Z,1687464628.737 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-06-22T20:10:28.770Z,1687464628.770 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-06-22T20:10:29.154Z,1687464629.154 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-06-22T20:10:29.154Z,1687464629.154 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12 2023-06-22T20:13:29.776Z,1687464809.776 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13 2023-06-22T20:13:29.776Z,1687464809.776 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-06-22T20:13:29.792Z,1687464809.792 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-06-22T20:13:30.200Z,1687464810.200 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-06-22T20:13:30.200Z,1687464810.200 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13 2023-06-22T20:15:05.519Z,1687464905.519 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-06-22T20:10:05.3Z 2023-06-22T20:15:05.519Z,1687464905.519 [Default:CheckIn:Read_GPS] Stopped 2023-06-22T20:15:05.519Z,1687464905.519 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-06-22T20:15:13.559Z,1687464913.559 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20230622T193405/Courier0013.lzma 2023-06-22T20:15:14.561Z,1687464914.561 [DataOverHttps](INFO): Moved sent file to Logs/20230622T193405/Courier0013.lzma.bak 2023-06-22T20:15:14.561Z,1687464914.561 [DataOverHttps](INFO): SBD MOMSN=18418714 2023-06-22T20:15:29.903Z,1687464929.903 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20230622T193405/Express0014.lzma 2023-06-22T20:15:31.334Z,1687464931.334 [DataOverHttps](INFO): Moved sent file to Logs/20230622T193405/Express0014.lzma.bak 2023-06-22T20:15:31.335Z,1687464931.335 [DataOverHttps](INFO): SBD MOMSN=18418716 2023-06-22T20:15:32.229Z,1687464932.229 [Default:CheckIn:Read_Iridium] Stopped 2023-06-22T20:15:32.229Z,1687464932.229 [Default:CheckIn:C.Wait] Running Loop=1 2023-06-22T20:15:32.229Z,1687464932.229 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-06-22T20:16:30.821Z,1687464990.821 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14 2023-06-22T20:16:30.821Z,1687464990.821 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-06-22T20:16:30.832Z,1687464990.832 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-06-22T20:16:31.245Z,1687464991.245 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-06-22T20:16:31.245Z,1687464991.245 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14 2023-06-22T20:17:06.397Z,1687465026.397 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-06-22T20:17:06.397Z,1687465026.397 [NAL9602] Data Fault, FailCount= 4 2023-06-22T20:17:06.397Z,1687465026.397 [NAL9602](ERROR): Data Fault 2023-06-22T20:17:06.415Z,1687465026.415 [CBIT](ERROR): Data Fault in component: NAL9602 2023-06-22T20:17:06.806Z,1687465026.806 [NAL9602](INFO): Powering down 2023-06-22T20:17:07.657Z,1687465027.657 [CBIT](INFO): Clearing failed state for component NAL9602 2023-06-22T20:17:07.657Z,1687465027.657 [NAL9602] No Fault, FailCount= 4 2023-06-22T20:17:37.101Z,1687465057.101 [NAL9602](INFO): Powering up NAL9602 2023-06-22T20:17:48.008Z,1687465068.008 [NAL9602](INFO): NAL9602 initialized 2023-06-22T20:19:31.857Z,1687465171.857 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15 2023-06-22T20:19:31.857Z,1687465171.857 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-06-22T20:19:31.868Z,1687465171.868 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-06-22T20:19:32.271Z,1687465172.271 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-06-22T20:19:32.271Z,1687465172.271 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15 2023-06-22T20:20:32.869Z,1687465232.869 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-06-22T20:20:32.869Z,1687465232.869 [Default:CheckIn:C.Wait] Stopped 2023-06-22T20:20:32.869Z,1687465232.869 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-06-22T20:20:32.869Z,1687465232.869 [Default:CheckIn:D] Running Loop=1 2023-06-22T20:20:33.245Z,1687465233.245 [Depth_Keller](ERROR): Pressure or depth reading out of range: -0.507664 decibar, -0.503920 m 2023-06-22T20:20:33.271Z,1687465233.271 [Default:CheckIn:D] Stopped 2023-06-22T20:20:33.271Z,1687465233.271 [Default:CheckIn:E] Running Loop=1 2023-06-22T20:20:33.689Z,1687465233.689 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 43.003625 min 2023-06-22T20:20:33.690Z,1687465233.690 [Default:CheckIn:E] Stopped 2023-06-22T20:20:33.690Z,1687465233.690 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-06-22T20:20:33.690Z,1687465233.690 [Default:CheckIn] Stopped 2023-06-22T20:20:33.690Z,1687465233.690 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-06-22T20:20:33.690Z,1687465233.690 [Default:CheckIn](INFO): Running loop #5 2023-06-22T20:20:33.690Z,1687465233.690 [Default:CheckIn] Running Loop=5 2023-06-22T20:20:33.690Z,1687465233.690 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-06-22T20:20:33.690Z,1687465233.690 [Default:CheckIn:Read_GPS] Running Loop=1 2023-06-22T20:21:21.765Z,1687465281.765 [Depth_Keller](ERROR): Pressure or depth reading out of range: -0.503990 decibar, -0.500273 m 2023-06-22T20:22:32.880Z,1687465352.880 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16 2023-06-22T20:22:32.880Z,1687465352.880 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-06-22T20:22:32.891Z,1687465352.891 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-06-22T20:22:33.303Z,1687465353.303 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-06-22T20:22:33.303Z,1687465353.303 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16 2023-06-22T20:25:33.917Z,1687465533.917 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17 2023-06-22T20:25:33.917Z,1687465533.917 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-06-22T20:25:33.919Z,1687465533.919 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-06-22T20:20:33.7Z 2023-06-22T20:25:33.919Z,1687465533.919 [Default:CheckIn:Read_GPS] Stopped 2023-06-22T20:25:33.919Z,1687465533.919 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-06-22T20:25:33.929Z,1687465533.929 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-06-22T20:25:34.334Z,1687465534.334 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-06-22T20:25:34.334Z,1687465534.334 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17 2023-06-22T20:25:39.891Z,1687465539.891 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230622T193405/Courier0016.lzma 2023-06-22T20:25:41.444Z,1687465541.444 [DataOverHttps](INFO): Moved sent file to Logs/20230622T193405/Courier0016.lzma.bak 2023-06-22T20:25:41.444Z,1687465541.444 [DataOverHttps](INFO): SBD MOMSN=18418733 2023-06-22T20:25:56.267Z,1687465556.267 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20230622T193405/Express0017.lzma 2023-06-22T20:25:57.269Z,1687465557.269 [DataOverHttps](INFO): Moved sent file to Logs/20230622T193405/Express0017.lzma.bak 2023-06-22T20:25:57.270Z,1687465557.270 [DataOverHttps](INFO): SBD MOMSN=18418735 2023-06-22T20:25:58.582Z,1687465558.582 [Default:CheckIn:Read_Iridium] Stopped 2023-06-22T20:25:58.582Z,1687465558.582 [Default:CheckIn:C.Wait] Running Loop=1 2023-06-22T20:25:58.582Z,1687465558.582 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-06-22T20:27:48.829Z,1687465668.829 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-06-22T20:27:48.829Z,1687465668.829 [NAL9602] Data Fault, FailCount= 5 2023-06-22T20:27:48.829Z,1687465668.829 [NAL9602](ERROR): Data Fault 2023-06-22T20:27:48.894Z,1687465668.894 [CBIT](ERROR): Data Fault in component: NAL9602 2023-06-22T20:27:48.894Z,1687465668.894 [CBIT](CRITICAL): Data Fault in component: NAL9602 2023-06-22T20:27:49.237Z,1687465669.237 [NAL9602](INFO): Powering down 2023-06-22T20:27:49.272Z,1687465669.272 [CBIT](INFO): Critical error at 20230622T202748 2023-06-22T20:28:34.895Z,1687465714.895 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18 2023-06-22T20:28:34.895Z,1687465714.895 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-06-22T20:28:34.924Z,1687465714.924 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-06-22T20:28:35.309Z,1687465715.309 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-06-22T20:28:35.310Z,1687465715.310 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18 2023-06-22T20:29:48.865Z,1687465788.865 [CBIT](INFO): Clearing failed state for component NAL9602 2023-06-22T20:29:48.865Z,1687465788.865 [NAL9602] No Fault, FailCount= 5 2023-06-22T20:29:49.241Z,1687465789.241 [NAL9602](INFO): Powering up NAL9602 2023-06-22T20:30:00.161Z,1687465800.161 [NAL9602](INFO): NAL9602 initialized 2023-06-22T20:30:59.209Z,1687465859.209 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-06-22T20:30:59.209Z,1687465859.209 [Default:CheckIn:C.Wait] Stopped 2023-06-22T20:30:59.209Z,1687465859.209 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-06-22T20:30:59.209Z,1687465859.209 [Default:CheckIn:D] Running Loop=1 2023-06-22T20:30:59.607Z,1687465859.607 [Default:CheckIn:D] Stopped 2023-06-22T20:30:59.607Z,1687465859.607 [Default:CheckIn:E] Running Loop=1 2023-06-22T20:31:00.007Z,1687465860.007 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 53.442558 min 2023-06-22T20:31:00.007Z,1687465860.007 [Default:CheckIn:E] Stopped 2023-06-22T20:31:00.008Z,1687465860.008 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-06-22T20:31:00.008Z,1687465860.008 [Default:CheckIn] Stopped 2023-06-22T20:31:00.008Z,1687465860.008 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-06-22T20:31:00.008Z,1687465860.008 [Default:CheckIn](INFO): Running loop #6 2023-06-22T20:31:00.008Z,1687465860.008 [Default:CheckIn] Running Loop=6 2023-06-22T20:31:00.008Z,1687465860.008 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-06-22T20:31:00.008Z,1687465860.008 [Default:CheckIn:Read_GPS] Running Loop=1 2023-06-22T20:31:35.956Z,1687465895.956 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19 2023-06-22T20:31:35.956Z,1687465895.956 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-06-22T20:31:35.989Z,1687465895.989 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-06-22T20:31:36.370Z,1687465896.370 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-06-22T20:31:36.370Z,1687465896.370 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19 2023-06-22T20:34:36.951Z,1687466076.951 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 20 2023-06-22T20:34:36.951Z,1687466076.951 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-06-22T20:34:36.963Z,1687466076.963 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-06-22T20:34:37.367Z,1687466077.367 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-06-22T20:34:37.367Z,1687466077.367 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 20 2023-06-22T20:36:00.253Z,1687466160.253 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-06-22T20:31:00.0Z 2023-06-22T20:36:00.253Z,1687466160.253 [Default:CheckIn:Read_GPS] Stopped 2023-06-22T20:36:00.253Z,1687466160.253 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-06-22T20:36:05.788Z,1687466165.788 [DataOverHttps](INFO): Sending 81 bytes from file Logs/20230622T193405/Courier0019.lzma 2023-06-22T20:36:06.789Z,1687466166.789 [DataOverHttps](INFO): Moved sent file to Logs/20230622T193405/Courier0019.lzma.bak 2023-06-22T20:36:06.789Z,1687466166.789 [DataOverHttps](INFO): SBD MOMSN=18418745 2023-06-22T20:36:22.183Z,1687466182.183 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20230622T193405/Express0020.lzma 2023-06-22T20:36:23.185Z,1687466183.185 [DataOverHttps](INFO): Moved sent file to Logs/20230622T193405/Express0020.lzma.bak 2023-06-22T20:36:23.185Z,1687466183.185 [DataOverHttps](INFO): SBD MOMSN=18418752 2023-06-22T20:36:24.486Z,1687466184.486 [Default:CheckIn:Read_Iridium] Stopped 2023-06-22T20:36:24.486Z,1687466184.486 [Default:CheckIn:C.Wait] Running Loop=1 2023-06-22T20:36:24.486Z,1687466184.486 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-06-22T20:37:38.020Z,1687466258.020 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 21 2023-06-22T20:37:38.020Z,1687466258.020 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-06-22T20:37:38.055Z,1687466258.055 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-06-22T20:37:38.438Z,1687466258.438 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-06-22T20:37:38.438Z,1687466258.438 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 21 2023-06-22T20:38:17.253Z,1687466297.253 [DataOverHttps](IMPORTANT): SBD MTMSN=20230622T203805 2023-06-22T20:38:24.443Z,1687466304.443 [DataOverHttps](INFO): Received command: restart sys 2023-06-22T20:38:24.465Z,1687466304.465 [CommandExec](IMPORTANT): got command restart system 2023-06-22T20:38:26.528Z,1687466306.528 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2023-06-22T20:38:26.528Z,1687466306.528 [CommandExec](INFO): Uninitializing the command executive. 2023-06-22T20:38:26.528Z,1687466306.528 [CommandExec](INFO): Uninitializing the command scheduler. 2023-06-22T20:38:26.528Z,1687466306.528 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-06-22T20:38:26.679Z,1687466306.679 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2023-06-22T20:38:26.680Z,1687466306.680 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2023-06-22T20:38:26.680Z,1687466306.680 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-06-22T20:38:26.680Z,1687466306.680 [NavChartDb](INFO): Join timeout helper Thread ID is 1237 2023-06-22T20:38:27.040Z,1687466307.040 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2023-06-22T20:38:27.041Z,1687466307.041 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-06-22T20:38:27.051Z,1687466307.051 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2023-06-22T20:38:27.052Z,1687466307.052 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-06-22T20:38:27.052Z,1687466307.052 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1238 2023-06-22T20:38:27.316Z,1687466307.316 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2023-06-22T20:38:27.316Z,1687466307.316 [WetLabsBB2FL](INFO): Powering down 2023-06-22T20:38:27.317Z,1687466307.317 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-06-22T20:38:27.331Z,1687466307.331 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2023-06-22T20:38:27.332Z,1687466307.332 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-06-22T20:38:27.332Z,1687466307.332 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1239 2023-06-22T20:38:27.500Z,1687466307.500 [CTD_Seabird](INFO): Powering down 2023-06-22T20:38:27.512Z,1687466307.512 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2023-06-22T20:38:27.512Z,1687466307.512 [CTD_Seabird](INFO): Powering down 2023-06-22T20:38:27.523Z,1687466307.523 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-06-22T20:38:27.531Z,1687466307.531 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2023-06-22T20:38:27.532Z,1687466307.532 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-06-22T20:38:27.532Z,1687466307.532 [Radio_Surface](INFO): Join timeout helper Thread ID is 1240 2023-06-22T20:38:27.848Z,1687466307.848 [Radio_Surface](INFO): Powering down 2023-06-22T20:38:27.848Z,1687466307.848 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2023-06-22T20:38:27.848Z,1687466307.848 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-06-22T20:38:27.860Z,1687466307.860 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2023-06-22T20:38:27.860Z,1687466307.860 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-06-22T20:38:27.860Z,1687466307.860 [Onboard](INFO): Join timeout helper Thread ID is 1241 2023-06-22T20:38:28.456Z,1687466308.456 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2023-06-22T20:38:30.428Z,1687466310.428 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2023-06-22T20:38:30.428Z,1687466310.428 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-06-22T20:38:30.444Z,1687466310.444 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2023-06-22T20:38:30.444Z,1687466310.444 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-06-22T20:38:30.445Z,1687466310.445 [DataOverHttps](INFO): Join timeout helper Thread ID is 1242 2023-06-22T20:38:30.464Z,1687466310.464 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2023-06-22T20:38:30.464Z,1687466310.464 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-06-22T20:38:30.472Z,1687466310.472 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2023-06-22T20:38:30.473Z,1687466310.473 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-06-22T20:38:30.473Z,1687466310.473 [BackseatComponent](INFO): Join timeout helper Thread ID is 1243 2023-06-22T20:38:30.620Z,1687466310.620 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2023-06-22T20:38:30.620Z,1687466310.620 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-06-22T20:38:30.624Z,1687466310.624 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2023-06-22T20:38:30.624Z,1687466310.624 [logger ThreadHandler](INFO): Thread cancelled. 2023-06-22T20:38:30.625Z,1687466310.625 [logger](INFO): Join timeout helper Thread ID is 1244 2023-06-22T20:38:30.664Z,1687466310.664 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2023-06-22T20:38:30.664Z,1687466310.664 [logger ThreadHandler](INFO): Thread cancelled. 2023-06-22T20:38:30.686Z,1687466310.686 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2023-06-22T20:38:30.686Z,1687466310.686 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-06-22T20:38:30.687Z,1687466310.687 [CommandLine](INFO): Join timeout helper Thread ID is 1245 2023-06-22T20:38:30.724Z,1687466310.724 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2023-06-22T20:38:30.724Z,1687466310.724 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-06-22T20:38:30.728Z,1687466310.728 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2023-06-22T20:38:30.728Z,1687466310.728 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-06-22T20:38:30.729Z,1687466310.729 [CommandExec](INFO): Join timeout helper Thread ID is 1246 2023-06-22T20:38:30.730Z,1687466310.730 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2023-06-22T20:38:30.730Z,1687466310.730 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-06-22T20:38:30.730Z,1687466310.730 [controlThread](INFO): Join timeout helper Thread ID is 1247 2023-06-22T20:38:30.892Z,1687466310.892 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2023-06-22T20:38:30.892Z,1687466310.892 [controlThread](DEBUG): Uninitializing ControlThread 2023-06-22T20:38:30.892Z,1687466310.892 [AHRS_M2](INFO): Powering down 2023-06-22T20:38:30.964Z,1687466310.964 [NAL9602](INFO): Powering down 2023-06-22T20:38:30.965Z,1687466310.965 [Sonardyne_Nano](INFO): Powering down 2023-06-22T20:38:31.084Z,1687466311.084 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2023-06-22T20:38:31.085Z,1687466311.085 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2023-06-22T20:38:31.086Z,1687466311.086 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2023-06-22T20:38:31.086Z,1687466311.086 [MissionManager](INFO): Uninitializing Mission Default 2023-06-22T20:38:31.086Z,1687466311.086 [Default] Stopped 2023-06-22T20:38:31.086Z,1687466311.086 [Default](DEBUG): Aggregate::uninitialize Default 2023-06-22T20:38:31.086Z,1687466311.086 [Default:B.GoToSurface] Stopped 2023-06-22T20:38:31.086Z,1687466311.086 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-06-22T20:38:31.087Z,1687466311.087 [Default:CheckIn] Stopped 2023-06-22T20:38:31.087Z,1687466311.087 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-06-22T20:38:31.087Z,1687466311.087 [Default:CheckIn:C.Wait] Stopped 2023-06-22T20:38:31.087Z,1687466311.087 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-06-22T20:38:31.090Z,1687466311.090 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2023-06-22T20:38:31.090Z,1687466311.090 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2023-06-22T20:38:31.091Z,1687466311.091 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2023-06-22T20:38:31.091Z,1687466311.091 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2023-06-22T20:38:31.091Z,1687466311.091 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2023-06-22T20:38:31.092Z,1687466311.092 [BuoyancyServo](INFO): Powering down 2023-06-22T20:38:31.104Z,1687466311.104 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2023-06-22T20:38:31.104Z,1687466311.104 [ElevatorServo](INFO): Powering down 2023-06-22T20:38:31.105Z,1687466311.105 [MassServo](DEBUG): Uninitialize Mass Servo. 2023-06-22T20:38:31.105Z,1687466311.105 [MassServo](INFO): Powering down 2023-06-22T20:38:31.106Z,1687466311.106 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2023-06-22T20:38:31.106Z,1687466311.106 [RudderServo](INFO): Powering down 2023-06-22T20:38: