2023-09-07T13:00:35.812Z,1694091635.812 [Supervisor](DEBUG): Initializing supervisor. 2023-09-07T13:00:35.816Z,1694091635.816 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2023-09-07T13:00:35.817Z,1694091635.817 [SyncHandler](INFO): Protected caller Thread ID is 837 2023-09-07T13:00:35.817Z,1694091635.817 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2023-09-07T13:00:35.818Z,1694091635.818 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2023-09-07T13:00:35.818Z,1694091635.818 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 838 2023-09-07T13:00:35.823Z,1694091635.823 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2023-09-07T13:00:35.840Z,1694091635.840 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2023-09-07T13:00:35.841Z,1694091635.841 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2023-09-07T13:00:35.841Z,1694091635.841 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 839 2023-09-07T13:00:35.846Z,1694091635.846 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2023-09-07T13:00:35.846Z,1694091635.846 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2023-09-07T13:00:35.847Z,1694091635.847 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 840 2023-09-07T13:00:35.849Z,1694091635.849 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2023-09-07T13:00:35.850Z,1694091635.850 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2023-09-07T13:00:35.850Z,1694091635.850 [logger ThreadHandler](INFO): Protected caller Thread ID is 841 2023-09-07T13:00:35.854Z,1694091635.854 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2023-09-07T13:00:35.854Z,1694091635.854 [Supervisor](INFO): Looking for Config files in directory: Config/ 2023-09-07T13:00:35.859Z,1694091635.859 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2023-09-07T13:00:36.341Z,1694091636.341 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2023-09-07T13:00:36.342Z,1694091636.342 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2023-09-07T13:00:36.563Z,1694091636.563 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2023-09-07T13:00:36.565Z,1694091636.565 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2023-09-07T13:00:36.646Z,1694091636.646 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2023-09-07T13:00:36.878Z,1694091636.878 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2023-09-07T13:00:36.879Z,1694091636.879 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2023-09-07T13:00:36.958Z,1694091636.958 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2023-09-07T13:00:37.057Z,1694091637.057 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2023-09-07T13:00:37.059Z,1694091637.059 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2023-09-07T13:00:37.437Z,1694091637.437 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2023-09-07T13:00:37.438Z,1694091637.438 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2023-09-07T13:00:37.576Z,1694091637.576 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2023-09-07T13:00:37.577Z,1694091637.577 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2023-09-07T13:00:38.120Z,1694091638.120 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2023-09-07T13:00:38.121Z,1694091638.121 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2023-09-07T13:00:38.327Z,1694091638.327 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2023-09-07T13:00:38.329Z,1694091638.329 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2023-09-07T13:00:38.774Z,1694091638.774 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2023-09-07T13:00:38.775Z,1694091638.775 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2023-09-07T13:00:39.087Z,1694091639.087 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2023-09-07T13:00:39.584Z,1694091639.584 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2023-09-07T13:00:40.184Z,1694091640.184 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2023-09-07T13:00:40.185Z,1694091640.185 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2023-09-07T13:00:41.040Z,1694091641.040 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2023-09-07T13:00:41.041Z,1694091641.041 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2023-09-07T13:00:41.394Z,1694091641.394 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2023-09-07T13:00:41.396Z,1694091641.396 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2023-09-07T13:00:41.635Z,1694091641.635 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2023-09-07T13:00:41.637Z,1694091641.637 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/ 2023-09-07T13:00:41.639Z,1694091641.639 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg 2023-09-07T13:00:41.766Z,1694091641.766 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg 2023-09-07T13:00:41.850Z,1694091641.850 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg 2023-09-07T13:00:41.991Z,1694091641.991 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg 2023-09-07T13:00:42.078Z,1694091642.078 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg 2023-09-07T13:00:42.353Z,1694091642.353 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2023-09-07T13:00:42.354Z,1694091642.354 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg 2023-09-07T13:00:42.461Z,1694091642.461 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg 2023-09-07T13:00:42.573Z,1694091642.573 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg 2023-09-07T13:00:42.697Z,1694091642.697 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg 2023-09-07T13:00:42.869Z,1694091642.869 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg 2023-09-07T13:00:42.970Z,1694091642.970 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg 2023-09-07T13:00:43.071Z,1694091643.071 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2023-09-07T13:00:43.090Z,1694091643.090 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2023-09-07T13:00:43.107Z,1694091643.107 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2023-09-07T13:00:43.109Z,1694091643.109 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2023-09-07T13:00:43.242Z,1694091643.242 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2023-09-07T13:00:43.243Z,1694091643.243 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2023-09-07T13:00:43.315Z,1694091643.315 [VerticalControl](DEBUG): Construct VerticalControl. 2023-09-07T13:00:43.378Z,1694091643.378 [VerticalControl] Loaded 2023-09-07T13:00:43.378Z,1694091643.378 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2023-09-07T13:00:43.381Z,1694091643.381 [HorizontalControl](DEBUG): Construct HorizontalControl. 2023-09-07T13:00:43.424Z,1694091643.424 [HorizontalControl] Loaded 2023-09-07T13:00:43.424Z,1694091643.424 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2023-09-07T13:00:43.427Z,1694091643.427 [SpeedControl](DEBUG): Construct SpeedControl. 2023-09-07T13:00:43.430Z,1694091643.430 [SpeedControl] Loaded 2023-09-07T13:00:43.430Z,1694091643.430 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2023-09-07T13:00:43.433Z,1694091643.433 [LoopControl](DEBUG): Construct LoopControl. 2023-09-07T13:00:43.433Z,1694091643.433 [LoopControl] Loaded 2023-09-07T13:00:43.434Z,1694091643.434 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2023-09-07T13:00:43.434Z,1694091643.434 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2023-09-07T13:00:43.436Z,1694091643.436 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2023-09-07T13:00:43.632Z,1694091643.632 [BuoyancyServo] Loaded 2023-09-07T13:00:43.632Z,1694091643.632 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2023-09-07T13:00:43.653Z,1694091643.653 [ElevatorServo] Loaded 2023-09-07T13:00:43.653Z,1694091643.653 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2023-09-07T13:00:43.673Z,1694091643.673 [MassServo] Loaded 2023-09-07T13:00:43.674Z,1694091643.674 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2023-09-07T13:00:43.692Z,1694091643.692 [RudderServo] Loaded 2023-09-07T13:00:43.693Z,1694091643.693 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2023-09-07T13:00:43.708Z,1694091643.708 [ThrusterHE] Loaded 2023-09-07T13:00:43.708Z,1694091643.708 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2023-09-07T13:00:43.709Z,1694091643.709 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2023-09-07T13:00:43.709Z,1694091643.709 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2023-09-07T13:00:43.782Z,1694091643.782 [DepthRateCalculator] Loaded 2023-09-07T13:00:43.782Z,1694091643.782 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2023-09-07T13:00:43.787Z,1694091643.787 [PitchRateCalculator] Loaded 2023-09-07T13:00:43.788Z,1694091643.788 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2023-09-07T13:00:43.798Z,1694091643.798 [SpeedCalculator] Loaded 2023-09-07T13:00:43.798Z,1694091643.798 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2023-09-07T13:00:43.802Z,1694091643.802 [YawRateCalculator] Loaded 2023-09-07T13:00:43.803Z,1694091643.803 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2023-09-07T13:00:43.822Z,1694091643.822 [ElevatorOffsetCalculator] Loaded 2023-09-07T13:00:43.822Z,1694091643.822 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2023-09-07T13:00:43.823Z,1694091643.823 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2023-09-07T13:00:43.823Z,1694091643.823 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2023-09-07T13:00:43.969Z,1694091643.969 [DeadReckonUsingMultipleVelocitySources] Loaded 2023-09-07T13:00:43.969Z,1694091643.969 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2023-09-07T13:00:43.984Z,1694091643.984 [NavChart] Loaded 2023-09-07T13:00:43.984Z,1694091643.984 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2023-09-07T13:00:43.990Z,1694091643.990 [UniversalFixResidualReporter] Loaded 2023-09-07T13:00:43.990Z,1694091643.990 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2023-09-07T13:00:43.990Z,1694091643.990 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2023-09-07T13:00:43.991Z,1694091643.991 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2023-09-07T13:00:44.943Z,1694091644.943 [AHRS_M2] Loaded 2023-09-07T13:00:44.943Z,1694091644.943 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2023-09-07T13:00:45.208Z,1694091645.208 [BackseatComponent] Loaded 2023-09-07T13:00:45.208Z,1694091645.208 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2023-09-07T13:00:45.209Z,1694091645.209 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408E84E0 2023-09-07T13:00:45.210Z,1694091645.210 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 923 2023-09-07T13:00:45.213Z,1694091645.213 [LcmUniversalReporter] Loaded 2023-09-07T13:00:45.213Z,1694091645.213 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2023-09-07T13:00:45.227Z,1694091645.227 [BioacousticsDataBridge] Loaded 2023-09-07T13:00:45.228Z,1694091645.228 [ComponentRegistry](DEBUG): SyncComponent "BioacousticsDataBridge" handled in the control thread. 2023-09-07T13:00:46.153Z,1694091646.153 [BPC1] Loaded 2023-09-07T13:00:46.153Z,1694091646.153 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2023-09-07T13:00:46.295Z,1694091646.295 [DataOverHttps] Loaded 2023-09-07T13:00:46.296Z,1694091646.296 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2023-09-07T13:00:46.297Z,1694091646.297 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409184E0 2023-09-07T13:00:46.297Z,1694091646.297 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 924 2023-09-07T13:00:46.319Z,1694091646.319 [Depth_Keller] Loaded 2023-09-07T13:00:46.319Z,1694091646.319 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2023-09-07T13:00:46.325Z,1694091646.325 [DropWeight] Loaded 2023-09-07T13:00:46.325Z,1694091646.325 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2023-09-07T13:00:46.391Z,1694091646.391 [NAL9602] Loaded 2023-09-07T13:00:46.391Z,1694091646.391 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2023-09-07T13:00:46.422Z,1694091646.422 [Onboard] Loaded 2023-09-07T13:00:46.423Z,1694091646.423 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2023-09-07T13:00:46.424Z,1694091646.424 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409484E0 2023-09-07T13:00:46.424Z,1694091646.424 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 925 2023-09-07T13:00:46.437Z,1694091646.437 [Power24vConverter] Loaded 2023-09-07T13:00:46.437Z,1694091646.437 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2023-09-07T13:00:46.451Z,1694091646.451 [Radio_Surface] Loaded 2023-09-07T13:00:46.452Z,1694091646.452 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2023-09-07T13:00:46.453Z,1694091646.453 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409784E0 2023-09-07T13:00:46.453Z,1694091646.453 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 926 2023-09-07T13:00:46.467Z,1694091646.467 [Sonardyne_Nano] Loaded 2023-09-07T13:00:46.468Z,1694091646.468 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2023-09-07T13:00:46.468Z,1694091646.468 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2023-09-07T13:00:46.469Z,1694091646.469 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2023-09-07T13:00:46.555Z,1694091646.555 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2023-09-07T13:00:46.556Z,1694091646.556 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2023-09-07T13:00:46.750Z,1694091646.750 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2023-09-07T13:00:46.751Z,1694091646.751 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2023-09-07T13:00:46.795Z,1694091646.795 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2023-09-07T13:00:46.795Z,1694091646.795 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2023-09-07T13:00:47.026Z,1694091647.026 [CTD_Seabird] Loaded 2023-09-07T13:00:47.027Z,1694091647.027 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2023-09-07T13:00:47.028Z,1694091647.028 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40AAF4E0 2023-09-07T13:00:47.028Z,1694091647.028 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 927 2023-09-07T13:00:47.049Z,1694091647.049 [PAR_Licor] Loaded 2023-09-07T13:00:47.050Z,1694091647.050 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2023-09-07T13:00:47.092Z,1694091647.092 [WetLabsBB2FL] Loaded 2023-09-07T13:00:47.093Z,1694091647.093 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2023-09-07T13:00:47.094Z,1694091647.094 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40ADF4E0 2023-09-07T13:00:47.094Z,1694091647.094 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 928 2023-09-07T13:00:47.095Z,1694091647.095 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2023-09-07T13:00:47.095Z,1694091647.095 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2023-09-07T13:00:47.475Z,1694091647.475 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2023-09-07T13:00:47.477Z,1694091647.477 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2023-09-07T13:00:47.654Z,1694091647.654 [SBIT](DEBUG): Construct Startup Built In Test. 2023-09-07T13:00:47.663Z,1694091647.663 [SBIT] Loaded 2023-09-07T13:00:47.664Z,1694091647.664 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2023-09-07T13:00:47.667Z,1694091647.667 [IBIT](DEBUG): Construct Initiated Built In Test. 2023-09-07T13:00:47.681Z,1694091647.681 [IBIT] Loaded 2023-09-07T13:00:47.681Z,1694091647.681 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2023-09-07T13:00:47.687Z,1694091647.687 [CBIT](DEBUG): Construct Continuous Built In Test. 2023-09-07T13:00:47.797Z,1694091647.797 [CBIT] Loaded 2023-09-07T13:00:47.797Z,1694091647.797 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2023-09-07T13:00:47.798Z,1694091647.798 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2023-09-07T13:00:47.804Z,1694091647.804 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2023-09-07T13:00:47.807Z,1694091647.807 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2023-09-07T13:00:47.818Z,1694091647.818 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2023-09-07T13:00:47.819Z,1694091647.819 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BCB4E0 2023-09-07T13:00:47.820Z,1694091647.820 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 929 2023-09-07T13:00:47.824Z,1694091647.824 [Supervisor](INFO): Main Thread ID is 830 2023-09-07T13:00:47.824Z,1694091647.824 [Supervisor](DEBUG): Running supervisor. 2023-09-07T13:00:47.825Z,1694091647.825 [CommandExec ThreadHandler](INFO): Handler Thread ID is 930 2023-09-07T13:00:47.825Z,1694091647.825 [CommandExec](INFO): Initializing the command executive. 2023-09-07T13:00:47.827Z,1694091647.827 [CommandLine ThreadHandler](INFO): Handler Thread ID is 931 2023-09-07T13:00:47.829Z,1694091647.829 [controlThread ThreadHandler](INFO): Handler Thread ID is 932 2023-09-07T13:00:47.829Z,1694091647.829 [controlThread](DEBUG): Initializing ControlThread 2023-09-07T13:00:47.830Z,1694091647.830 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2023-09-07T13:00:47.832Z,1694091647.832 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2023-09-07T13:00:47.833Z,1694091647.833 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2023-09-07T13:00:47.833Z,1694091647.833 [LoopControl](DEBUG): Initialize LoopControlComponent. 2023-09-07T13:00:47.835Z,1694091647.835 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2023-09-07T13:00:47.835Z,1694091647.835 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2023-09-07T13:00:47.836Z,1694091647.836 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2023-09-07T13:00:47.836Z,1694091647.836 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2023-09-07T13:00:47.836Z,1694091647.836 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2023-09-07T13:00:47.838Z,1694091647.838 [NavChart](DEBUG): Initialize NavChart Navigation. 2023-09-07T13:00:47.838Z,1694091647.838 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2023-09-07T13:00:47.844Z,1694091647.844 [SBIT](INFO): Initialize SBIT Component. 2023-09-07T13:00:47.845Z,1694091647.845 [SBIT](IMPORTANT): git: 2023-09-06 2023-09-07T13:00:47.845Z,1694091647.845 [SBIT](INFO): git hash: 959e702194aa839aa3f9ffe5383ff3d41c72b783 2023-09-07T13:00:47.845Z,1694091647.845 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2023-09-07T13:00:47.846Z,1694091647.846 [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-09-07T13:00:47.847Z,1694091647.847 [SBIT](INFO): Beginning SBIT in 122.000000 seconds. 2023-09-07T13:00:47.848Z,1694091647.848 [IBIT](INFO): Initialize IBIT Component. 2023-09-07T13:00:47.849Z,1694091647.849 [CBIT](DEBUG): Initialize CBIT Component. 2023-09-07T13:00:47.850Z,1694091647.850 [logger ThreadHandler](INFO): Handler Thread ID is 933 2023-09-07T13:00:47.860Z,1694091647.860 [CBIT](DEBUG): Initialized mux pins. 2023-09-07T13:00:47.860Z,1694091647.860 [CBIT](DEBUG): Initializing the watchdog timer. 2023-09-07T13:00:47.868Z,1694091647.868 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 934 2023-09-07T13:00:47.880Z,1694091647.880 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 935 2023-09-07T13:00:47.881Z,1694091647.881 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2023-09-07T13:00:47.884Z,1694091647.884 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2023-09-07T13:00:47.884Z,1694091647.884 [CBIT](DEBUG): Initializing heartbeat. 2023-09-07T13:00:47.892Z,1694091647.892 [Onboard ThreadHandler](INFO): Handler Thread ID is 936 2023-09-07T13:00:47.910Z,1694091647.910 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 937 2023-09-07T13:00:47.932Z,1694091647.932 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 938 2023-09-07T13:00:47.933Z,1694091647.933 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2023-09-07T13:00:47.937Z,1694091647.937 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 940 2023-09-07T13:00:47.940Z,1694091647.940 [WetLabsBB2FL](INFO): Powering up 2023-09-07T13:00:47.942Z,1694091647.942 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 942 2023-09-07T13:00:47.950Z,1694091647.950 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI01M.000 2023-09-07T13:00:47.950Z,1694091647.950 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI01M.000 2023-09-07T13:00:47.951Z,1694091647.951 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI80M.000 2023-09-07T13:00:47.951Z,1694091647.951 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI80M.000 2023-09-07T13:00:47.951Z,1694091647.951 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI57M.000 2023-09-07T13:00:47.951Z,1694091647.951 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI57M.000 2023-09-07T13:00:47.951Z,1694091647.951 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI56M.000 2023-09-07T13:00:47.951Z,1694091647.951 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI56M.000 2023-09-07T13:00:47.952Z,1694091647.952 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI90M.000 2023-09-07T13:00:47.952Z,1694091647.952 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI90M.000 2023-09-07T13:00:47.952Z,1694091647.952 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI89M.000 2023-09-07T13:00:47.952Z,1694091647.952 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI89M.000 2023-09-07T13:00:47.953Z,1694091647.953 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI03M.000 2023-09-07T13:00:47.953Z,1694091647.953 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI03M.000 2023-09-07T13:00:47.953Z,1694091647.953 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI06M.000 2023-09-07T13:00:47.953Z,1694091647.953 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI06M.000 2023-09-07T13:00:47.953Z,1694091647.953 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4OH01M.000 2023-09-07T13:00:47.953Z,1694091647.953 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4OH01M.000 2023-09-07T13:00:47.953Z,1694091647.953 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4OH04M.000 2023-09-07T13:00:47.954Z,1694091647.954 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4OH04M.000 2023-09-07T13:00:47.954Z,1694091647.954 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4OH02M.000 2023-09-07T13:00:47.954Z,1694091647.954 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4OH02M.000 2023-09-07T13:00:47.954Z,1694091647.954 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4PA20M.000 2023-09-07T13:00:47.954Z,1694091647.954 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4PA20M.000 2023-09-07T13:00:47.954Z,1694091647.954 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4PA21M.000 2023-09-07T13:00:47.955Z,1694091647.955 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4PA21M.000 2023-09-07T13:00:47.956Z,1694091647.956 [CBIT](DEBUG): Deactivating GF circuits. 2023-09-07T13:00:47.956Z,1694091647.956 [CBIT](DEBUG): Deactivating emergency mode. 2023-09-07T13:00:47.996Z,1694091647.996 [CBIT](DEBUG): Backplane powered. 2023-09-07T13:00:47.997Z,1694091647.997 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml 2023-09-07T13:00:48.032Z,1694091648.032 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2023-09-07T13:00:48.043Z,1694091648.043 [MissionManager](DEBUG): 2023-09-07T13:00:48.060Z,1694091648.060 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml 2023-09-07T13:00:48.121Z,1694091648.121 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2023-09-07T13:00:48.123Z,1694091648.123 [Default:A.Wait](DEBUG): Construct Wait. 2023-09-07T13:00:48.126Z,1694091648.126 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2023-09-07T13:00:48.159Z,1694091648.159 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2023-09-07T13:00:48.161Z,1694091648.161 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2023-09-07T13:00:48.183Z,1694091648.183 [Default:E.Execute](DEBUG): Construct Execute. 2023-09-07T13:00:48.186Z,1694091648.186 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2023-09-07T13:00:48.200Z,1694091648.200 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BioacousticsDataBridge,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-09-07T13:00:48.212Z,1694091648.212 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2023-09-07T13:00:48.316Z,1694091648.316 [Radio_Surface](INFO): Powering up 2023-09-07T13:00:48.385Z,1694091648.385 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1926.123779 decibar, 0.000000 m 2023-09-07T13:00:48.386Z,1694091648.386 [Power24vConverter](INFO): Powering up. 2023-09-07T13:00:48.387Z,1694091648.387 [Sonardyne_Nano](INFO): Initializing. 2023-09-07T13:00:48.416Z,1694091648.416 [DepthRateCalculator](ERROR): Depth measurement is not active 2023-09-07T13:00:48.450Z,1694091648.450 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2023-09-07T13:00:48.456Z,1694091648.456 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2023-09-07T13:00:48.457Z,1694091648.457 [ElevatorServo](DEBUG): Initializing EZServoServo. 2023-09-07T13:00:48.464Z,1694091648.464 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2023-09-07T13:00:48.465Z,1694091648.465 [MassServo](DEBUG): Initializing EZServoServo. 2023-09-07T13:00:48.472Z,1694091648.472 [MassServo](DEBUG): Initializing MassServo. 2023-09-07T13:00:48.473Z,1694091648.473 [RudderServo](DEBUG): Initializing EZServoServo. 2023-09-07T13:00:48.481Z,1694091648.481 [RudderServo](DEBUG): Initializing RudderServo. 2023-09-07T13:00:48.481Z,1694091648.481 [ThrusterHE](DEBUG): Initializing EZServoServo. 2023-09-07T13:00:48.488Z,1694091648.488 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2023-09-07T13:00:50.048Z,1694091650.048 [WetLabsBB2FL](INFO): Powering down 2023-09-07T13:00:50.635Z,1694091650.635 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2023-09-07T13:00:51.873Z,1694091651.873 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2201 2023-09-07T13:00:54.113Z,1694091654.113 [ThrusterHE](ERROR): Zero Speed Commanded. 2023-09-07T13:01:16.493Z,1694091676.493 [NAL9602](INFO): Powering up NAL9602 2023-09-07T13:01:27.402Z,1694091687.402 [NAL9602](INFO): NAL9602 initialized 2023-09-07T13:02:50.272Z,1694091770.272 [SBIT](IMPORTANT): Beginning Startup BIT 2023-09-07T13:02:50.276Z,1694091770.276 [CBIT](IMPORTANT): Beginning ground fault scan 2023-09-07T13:02:55.959Z,1694091775.959 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-09-07T13:03:01.154Z,1694091781.154 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.004504 CHAN A1 (24V): -0.007304 CHAN A2 (12V): -0.004511 CHAN A3 (5V): -0.003478 CHAN B0 (3.3V): -0.001176 CHAN B1 (3.15aV): -0.001334 CHAN B2 (3.15bV): -0.000975 CHAN B3 (GND): -0.000409 OPEN: 0.003438 Full Scale: +/- 1 mA 2023-09-07T13:03:22.660Z,1694091802.660 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-09-07T13:03:44.385Z,1694091824.385 [SBIT](IMPORTANT): SBIT PASSED 2023-09-07T13:03:44.386Z,1694091824.386 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2023-09-07T13:03:44.386Z,1694091824.386 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=6 count; 2023-09-07T13:03:44.387Z,1694091824.387 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=10 minute; 2023-09-07T13:03:44.387Z,1694091824.387 [SBIT](IMPORTANT): Express linearApproximation BackseatComponent.handled_message 1.000000 bool; 2023-09-07T13:03:44.387Z,1694091824.387 [SBIT](IMPORTANT): Express linearApproximation CTD_Seabird.bin_mean_sea_water_temperature 5.000000 celsius; 2023-09-07T13:03:44.387Z,1694091824.387 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_salinity; 2023-09-07T13:03:44.387Z,1694091824.387 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water; 2023-09-07T13:03:44.387Z,1694091824.387 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 50.000000 meter; 2023-09-07T13:03:44.387Z,1694091824.387 [SBIT](IMPORTANT): Express none platform_roll_angle; 2023-09-07T13:03:44.387Z,1694091824.388 [SBIT](IMPORTANT): NAL9602.fastGPSFix=1 bool; 2023-09-07T13:03:44.388Z,1694091824.388 [SBIT](IMPORTANT): NAL9602.iridiumMTQueueTimeout=5 minute; 2023-09-07T13:03:44.389Z,1694091824.389 [SBIT](IMPORTANT): Sonardyne_Nano.loadAtStartup=1 bool; 2023-09-07T13:03:44.389Z,1694091824.389 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=391.543020 cubic_centimeter; 2023-09-07T13:03:44.389Z,1694091824.389 [SBIT](IMPORTANT): VerticalControl.elevDeadband=0.1 angular_degree; 2023-09-07T13:03:44.389Z,1694091824.389 [SBIT](IMPORTANT): VerticalControl.massDefault=25.724938 millimeter; 2023-09-07T13:03:44.802Z,1694091824.802 [MissionManager](IMPORTANT): Started mission Startup 2023-09-07T13:03:44.803Z,1694091824.803 [Startup] Running Loop=1 2023-09-07T13:03:44.803Z,1694091824.803 [Startup](DEBUG): Aggregate::initialize Startup 2023-09-07T13:03:44.803Z,1694091824.803 [Startup:A.GoToSurface] Running Loop=1 2023-09-07T13:03:44.803Z,1694091824.803 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-09-07T13:03:44.804Z,1694091824.804 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-09-07T13:03:44.805Z,1694091824.805 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-09-07T13:03:44.805Z,1694091824.805 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-09-07T13:03:44.805Z,1694091824.805 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-09-07T13:03:44.806Z,1694091824.806 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-09-07T13:03:44.807Z,1694091824.807 [Startup:StartupSatComms] Running Loop=1 2023-09-07T13:03:44.807Z,1694091824.807 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2023-09-07T13:03:44.807Z,1694091824.807 [Startup:StartupSatComms:A] Running Loop=1 2023-09-07T13:03:45.199Z,1694091825.199 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2023-09-07T13:03:48.005Z,1694091828.005 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2023-09-07T13:03:48.005Z,1694091828.005 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-09-07T13:03:48.041Z,1694091828.041 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-09-07T13:03:48.422Z,1694091828.422 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-09-07T13:03:48.422Z,1694091828.422 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2023-09-07T13:04:25.014Z,1694091865.014 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004389 2023-09-07T13:04:44.974Z,1694091884.974 [Startup:StartupSatComms:A](INFO): Timed out from 2023-09-07T13:03:44.8Z 2023-09-07T13:04:44.974Z,1694091884.974 [Startup:StartupSatComms:A] Stopped 2023-09-07T13:04:44.974Z,1694091884.974 [Startup:StartupSatComms:B] Running Loop=1 2023-09-07T13:04:45.387Z,1694091885.387 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2023-09-07T13:04:52.135Z,1694091892.135 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-09-07T13:04:58.048Z,1694091898.048 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20230907T123834/Courier0010.lzma 2023-09-07T13:04:59.050Z,1694091899.050 [DataOverHttps](INFO): Moved sent file to Logs/20230907T123834/Courier0010.lzma.bak 2023-09-07T13:04:59.050Z,1694091899.050 [DataOverHttps](INFO): SBD MOMSN=18626553 2023-09-07T13:05:16.648Z,1694091916.648 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-09-07T13:05:18.107Z,1694091918.107 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20230907T130035/Courier0000.lzma 2023-09-07T13:05:20.388Z,1694091920.388 [DataOverHttps](INFO): Moved sent file to Logs/20230907T130035/Courier0000.lzma.bak 2023-09-07T13:05:20.389Z,1694091920.389 [DataOverHttps](INFO): SBD MOMSN=18626555 2023-09-07T13:05:39.744Z,1694091939.744 [DataOverHttps](INFO): Sending 245 bytes from file Logs/20230907T123834/Express0011.lzma 2023-09-07T13:05:40.746Z,1694091940.746 [DataOverHttps](INFO): Moved sent file to Logs/20230907T123834/Express0011.lzma.bak 2023-09-07T13:05:40.746Z,1694091940.746 [DataOverHttps](INFO): SBD MOMSN=18626558 2023-09-07T13:05:45.269Z,1694091945.269 [Startup:StartupSatComms:B](INFO): Timed out from 2023-09-07T13:04:44.0Z 2023-09-07T13:05:45.269Z,1694091945.269 [Startup:StartupSatComms:B] Stopped 2023-09-07T13:05:45.270Z,1694091945.270 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2023-09-07T13:05:45.270Z,1694091945.270 [Startup:StartupSatComms] Stopped 2023-09-07T13:05:45.270Z,1694091945.270 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2023-09-07T13:05:45.270Z,1694091945.270 [Startup](INFO): Completed Startup 2023-09-07T13:05:45.271Z,1694091945.271 [MissionManager](INFO): Startup is completed. 2023-09-07T13:05:45.271Z,1694091945.271 [MissionManager](INFO): Uninitializing Mission Startup 2023-09-07T13:05:45.271Z,1694091945.271 [Startup] Stopped 2023-09-07T13:05:45.271Z,1694091945.271 [Startup](DEBUG): Aggregate::uninitialize Startup 2023-09-07T13:05:45.271Z,1694091945.271 [Startup:A.GoToSurface] Stopped 2023-09-07T13:05:45.271Z,1694091945.271 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-09-07T13:05:45.678Z,1694091945.678 [MissionManager](IMPORTANT): Started mission Default 2023-09-07T13:05:45.678Z,1694091945.678 [Default] Running Loop=1 2023-09-07T13:05:45.679Z,1694091945.679 [Default](DEBUG): Aggregate::initialize Default 2023-09-07T13:05:45.679Z,1694091945.679 [Default:B.GoToSurface] Running Loop=1 2023-09-07T13:05:45.679Z,1694091945.679 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-09-07T13:05:45.679Z,1694091945.679 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-09-07T13:05:45.679Z,1694091945.679 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-09-07T13:05:45.680Z,1694091945.680 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-09-07T13:05:45.681Z,1694091945.681 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-09-07T13:05:45.681Z,1694091945.681 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-09-07T13:05:45.681Z,1694091945.681 [Default:A.Wait] Running Loop=1 2023-09-07T13:05:45.681Z,1694091945.681 [Default:A.Wait](DEBUG): Initialize Wait Component. 2023-09-07T13:05:58.767Z,1694091958.767 [Default:A.Wait](INFO): Done Waiting. 2023-09-07T13:05:58.767Z,1694091958.767 [Default:A.Wait] Stopped 2023-09-07T13:05:58.767Z,1694091958.767 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2023-09-07T13:05:59.055Z,1694091959.055 [Default:CheckIn] Running Loop=1 2023-09-07T13:05:59.056Z,1694091959.056 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-07T13:05:59.056Z,1694091959.056 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-07T13:05:59.435Z,1694091959.435 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2023-09-07T13:06:00.709Z,1694091960.709 [DataOverHttps](INFO): Sending 1039 bytes from file Logs/20230907T130035/Express0001.lzma 2023-09-07T13:06:01.718Z,1694091961.718 [DataOverHttps](INFO): Moved sent file to Logs/20230907T130035/Express0001.lzma.bak 2023-09-07T13:06:01.718Z,1694091961.718 [DataOverHttps](INFO): SBD MOMSN=18626563 2023-09-07T13:06:05.511Z,1694091965.511 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-09-07T13:06:30.909Z,1694091990.909 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-09-07T13:06:39.979Z,1694091999.979 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-09-07T13:06:49.117Z,1694092009.117 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2023-09-07T13:06:49.117Z,1694092009.117 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-09-07T13:06:49.152Z,1694092009.152 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-09-07T13:06:49.535Z,1694092009.535 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-09-07T13:06:49.535Z,1694092009.535 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2023-09-07T13:07:48.149Z,1694092068.149 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-09-07T13:08:00.270Z,1694092080.270 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-09-07T13:08:26.940Z,1694092106.940 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-09-07T13:08:43.445Z,1694092123.445 [BPC1](INFO): Calculating totals. Valid battery stick count: 50. Valid reserve battery stick count: 6. 2023-09-07T13:08:43.497Z,1694092123.497 [BPC1](INFO): Received data from all battery sticks. 2023-09-07T13:09:50.122Z,1694092190.122 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2023-09-07T13:09:50.122Z,1694092190.122 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-09-07T13:09:50.132Z,1694092190.132 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-09-07T13:09:50.547Z,1694092190.547 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-09-07T13:09:50.547Z,1694092190.547 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2023-09-07T13:10:49.112Z,1694092249.112 [Power24vConverter](INFO): Powering down. 2023-09-07T13:10:59.239Z,1694092259.239 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-09-07T13:05:59.1Z 2023-09-07T13:10:59.239Z,1694092259.239 [Default:CheckIn:Read_GPS] Stopped 2023-09-07T13:10:59.239Z,1694092259.239 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-07T13:10:59.646Z,1694092259.646 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2023-09-07T13:11:08.440Z,1694092268.440 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20230907T130035/Courier0004.lzma 2023-09-07T13:11:09.442Z,1694092269.442 [DataOverHttps](INFO): Moved sent file to Logs/20230907T130035/Courier0004.lzma.bak 2023-09-07T13:11:09.442Z,1694092269.442 [DataOverHttps](INFO): SBD MOMSN=18626600 2023-09-07T13:11:27.263Z,1694092287.263 [DataOverHttps](INFO): Sending 187 bytes from file Logs/20230907T130035/Express0005.lzma 2023-09-07T13:11:28.266Z,1694092288.266 [DataOverHttps](INFO): Moved sent file to Logs/20230907T130035/Express0005.lzma.bak 2023-09-07T13:11:28.266Z,1694092288.266 [DataOverHttps](INFO): SBD MOMSN=18626602 2023-09-07T13:11:28.361Z,1694092288.361 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-09-07T13:11:28.362Z,1694092288.362 [NAL9602] Data Fault, FailCount= 1 2023-09-07T13:11:28.362Z,1694092288.362 [NAL9602](ERROR): Data Fault 2023-09-07T13:11:28.406Z,1694092288.406 [CBIT](ERROR): Data Fault in component: NAL9602 2023-09-07T13:11:28.766Z,1694092288.766 [NAL9602](INFO): Powering down 2023-09-07T13:11:29.612Z,1694092289.612 [CBIT](INFO): Clearing failed state for component NAL9602 2023-09-07T13:11:29.612Z,1694092289.612 [NAL9602] No Fault, FailCount= 1 2023-09-07T13:11:31.638Z,1694092291.638 [Default:CheckIn:Read_Iridium] Stopped 2023-09-07T13:11:31.638Z,1694092291.638 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-07T13:11:31.638Z,1694092291.638 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-07T13:11:59.070Z,1694092319.070 [NAL9602](INFO): Powering up NAL9602 2023-09-07T13:12:09.982Z,1694092329.982 [NAL9602](INFO): NAL9602 initialized 2023-09-07T13:12:51.209Z,1694092371.209 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2023-09-07T13:12:51.209Z,1694092371.209 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-09-07T13:12:51.220Z,1694092371.220 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-09-07T13:12:51.630Z,1694092371.630 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-09-07T13:12:51.631Z,1694092371.631 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2023-09-07T13:15:52.272Z,1694092552.272 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2023-09-07T13:15:52.272Z,1694092552.272 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-09-07T13:15:52.321Z,1694092552.321 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-09-07T13:15:52.725Z,1694092552.725 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-09-07T13:15:52.725Z,1694092552.725 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2023-09-07T13:16:32.272Z,1694092592.272 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-07T13:16:32.272Z,1694092592.272 [Default:CheckIn:C.Wait] Stopped 2023-09-07T13:16:32.272Z,1694092592.272 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-07T13:16:32.272Z,1694092592.272 [Default:CheckIn:D] Running Loop=1 2023-09-07T13:16:32.661Z,1694092592.661 [Default:CheckIn:D] Stopped 2023-09-07T13:16:32.661Z,1694092592.661 [Default:CheckIn:E] Running Loop=1 2023-09-07T13:16:33.066Z,1694092593.066 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.783044 min 2023-09-07T13:16:33.066Z,1694092593.066 [Default:CheckIn:E] Stopped 2023-09-07T13:16:33.067Z,1694092593.067 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-07T13:16:33.067Z,1694092593.067 [Default:CheckIn] Stopped 2023-09-07T13:16:33.067Z,1694092593.067 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-07T13:16:33.067Z,1694092593.067 [Default:CheckIn](INFO): Running loop #2 2023-09-07T13:16:33.067Z,1694092593.067 [Default:CheckIn] Running Loop=2 2023-09-07T13:16:33.067Z,1694092593.067 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-07T13:16:33.067Z,1694092593.067 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-07T13:18:53.325Z,1694092733.325 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2023-09-07T13:18:53.325Z,1694092733.325 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-09-07T13:18:53.371Z,1694092733.371 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-09-07T13:18:53.736Z,1694092733.736 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-09-07T13:18:53.736Z,1694092733.736 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2023-09-07T13:21:33.323Z,1694092893.323 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-09-07T13:16:33.1Z 2023-09-07T13:21:33.323Z,1694092893.323 [Default:CheckIn:Read_GPS] Stopped 2023-09-07T13:21:33.323Z,1694092893.323 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-07T13:21:41.859Z,1694092901.859 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230907T130035/Courier0007.lzma 2023-09-07T13:21:42.866Z,1694092902.866 [DataOverHttps](INFO): Moved sent file to Logs/20230907T130035/Courier0007.lzma.bak 2023-09-07T13:21:42.866Z,1694092902.866 [DataOverHttps](INFO): SBD MOMSN=18626616 2023-09-07T13:21:54.337Z,1694092914.337 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7 2023-09-07T13:21:54.337Z,1694092914.337 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-09-07T13:21:54.348Z,1694092914.348 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-09-07T13:21:54.722Z,1694092914.722 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-09-07T13:21:54.723Z,1694092914.723 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7 2023-09-07T13:22:00.636Z,1694092920.636 [DataOverHttps](INFO): Sending 182 bytes from file Logs/20230907T130035/Express0008.lzma 2023-09-07T13:22:01.638Z,1694092921.638 [DataOverHttps](INFO): Moved sent file to Logs/20230907T130035/Express0008.lzma.bak 2023-09-07T13:22:01.638Z,1694092921.638 [DataOverHttps](INFO): SBD MOMSN=18626620 2023-09-07T13:22:05.222Z,1694092925.222 [Default:CheckIn:Read_Iridium] Stopped 2023-09-07T13:22:05.222Z,1694092925.222 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-07T13:22:05.222Z,1694092925.222 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-07T13:22:11.669Z,1694092931.669 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-09-07T13:22:11.670Z,1694092931.670 [NAL9602] Data Fault, FailCount= 2 2023-09-07T13:22:11.670Z,1694092931.670 [NAL9602](ERROR): Data Fault 2023-09-07T13:22:11.688Z,1694092931.688 [CBIT](ERROR): Data Fault in component: NAL9602 2023-09-07T13:22:12.074Z,1694092932.074 [NAL9602](INFO): Powering down 2023-09-07T13:22:12.909Z,1694092932.909 [CBIT](INFO): Clearing failed state for component NAL9602 2023-09-07T13:22:12.909Z,1694092932.909 [NAL9602] No Fault, FailCount= 2 2023-09-07T13:22:42.374Z,1694092962.374 [NAL9602](INFO): Powering up NAL9602 2023-09-07T13:22:53.293Z,1694092973.293 [NAL9602](INFO): NAL9602 initialized 2023-09-07T13:24:55.396Z,1694093095.396 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8 2023-09-07T13:24:55.396Z,1694093095.396 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-09-07T13:24:55.415Z,1694093095.415 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-09-07T13:24:55.818Z,1694093095.818 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-09-07T13:24:55.818Z,1694093095.818 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8 2023-09-07T13:27:05.998Z,1694093225.998 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-07T13:27:05.998Z,1694093225.998 [Default:CheckIn:C.Wait] Stopped 2023-09-07T13:27:05.998Z,1694093225.998 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-07T13:27:05.999Z,1694093225.999 [Default:CheckIn:D] Running Loop=1 2023-09-07T13:27:06.406Z,1694093226.406 [Default:CheckIn:D] Stopped 2023-09-07T13:27:06.406Z,1694093226.406 [Default:CheckIn:E] Running Loop=1 2023-09-07T13:27:06.855Z,1694093226.855 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.345457 min 2023-09-07T13:27:06.855Z,1694093226.855 [Default:CheckIn:E] Stopped 2023-09-07T13:27:06.855Z,1694093226.855 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-07T13:27:06.855Z,1694093226.855 [Default:CheckIn] Stopped 2023-09-07T13:27:06.855Z,1694093226.855 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-07T13:27:06.855Z,1694093226.855 [Default:CheckIn](INFO): Running loop #3 2023-09-07T13:27:06.855Z,1694093226.855 [Default:CheckIn] Running Loop=3 2023-09-07T13:27:06.892Z,1694093226.892 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-07T13:27:06.892Z,1694093226.892 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-07T13:27:56.520Z,1694093276.520 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9 2023-09-07T13:27:56.520Z,1694093276.520 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-09-07T13:27:56.531Z,1694093276.531 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-09-07T13:27:56.938Z,1694093276.938 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-09-07T13:27:56.938Z,1694093276.938 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9 2023-09-07T13:30:57.551Z,1694093457.551 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10 2023-09-07T13:30:57.551Z,1694093457.551 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-09-07T13:30:57.561Z,1694093457.561 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-09-07T13:30:57.969Z,1694093457.969 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-09-07T13:30:57.969Z,1694093457.969 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10 2023-09-07T13:32:07.048Z,1694093527.048 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-09-07T13:27:06.9Z 2023-09-07T13:32:07.048Z,1694093527.048 [Default:CheckIn:Read_GPS] Stopped 2023-09-07T13:32:07.048Z,1694093527.048 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-07T13:32:15.871Z,1694093535.871 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230907T130035/Courier0010.lzma 2023-09-07T13:32:16.874Z,1694093536.874 [DataOverHttps](INFO): Moved sent file to Logs/20230907T130035/Courier0010.lzma.bak 2023-09-07T13:32:16.874Z,1694093536.874 [DataOverHttps](INFO): SBD MOMSN=18626635 2023-09-07T13:32:34.659Z,1694093554.659 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20230907T130035/Express0011.lzma 2023-09-07T13:32:35.662Z,1694093555.662 [DataOverHttps](INFO): Moved sent file to Logs/20230907T130035/Express0011.lzma.bak 2023-09-07T13:32:35.662Z,1694093555.662 [DataOverHttps](INFO): SBD MOMSN=18626639 2023-09-07T13:32:39.361Z,1694093559.361 [Default:CheckIn:Read_Iridium] Stopped 2023-09-07T13:32:39.362Z,1694093559.362 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-07T13:32:39.362Z,1694093559.362 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-07T13:32:54.376Z,1694093574.376 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-09-07T13:32:54.376Z,1694093574.376 [NAL9602] Data Fault, FailCount= 3 2023-09-07T13:32:54.376Z,1694093574.376 [NAL9602](ERROR): Data Fault 2023-09-07T13:32:54.481Z,1694093574.481 [CBIT](ERROR): Data Fault in component: NAL9602 2023-09-07T13:32:54.761Z,1694093574.761 [NAL9602](INFO): Powering down 2023-09-07T13:32:55.649Z,1694093575.649 [CBIT](INFO): Clearing failed state for component NAL9602 2023-09-07T13:32:55.649Z,1694093575.649 [NAL9602] No Fault, FailCount= 3 2023-09-07T13:33:25.089Z,1694093605.089 [NAL9602](INFO): Powering up NAL9602 2023-09-07T13:33:36.001Z,1694093616.001 [NAL9602](INFO): NAL9602 initialized 2023-09-07T13:33:58.649Z,1694093638.649 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11 2023-09-07T13:33:58.649Z,1694093638.649 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-09-07T13:33:58.660Z,1694093638.660 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-09-07T13:33:59.070Z,1694093639.070 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-09-07T13:33:59.071Z,1694093639.071 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11 2023-09-07T13:36:59.693Z,1694093819.693 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12 2023-09-07T13:36:59.693Z,1694093819.693 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-09-07T13:36:59.708Z,1694093819.708 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-09-07T13:37:00.158Z,1694093820.158 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-09-07T13:37:00.158Z,1694093820.158 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12 2023-09-07T13:37:40.118Z,1694093860.118 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-07T13:37:40.118Z,1694093860.118 [Default:CheckIn:C.Wait] Stopped 2023-09-07T13:37:40.118Z,1694093860.118 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-07T13:37:40.118Z,1694093860.118 [Default:CheckIn:D] Running Loop=1 2023-09-07T13:37:40.518Z,1694093860.518 [Default:CheckIn:D] Stopped 2023-09-07T13:37:40.518Z,1694093860.518 [Default:CheckIn:E] Running Loop=1 2023-09-07T13:37:40.906Z,1694093860.906 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.913997 min 2023-09-07T13:37:40.906Z,1694093860.906 [Default:CheckIn:E] Stopped 2023-09-07T13:37:40.906Z,1694093860.906 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-07T13:37:40.906Z,1694093860.906 [Default:CheckIn] Stopped 2023-09-07T13:37:40.906Z,1694093860.906 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-07T13:37:40.906Z,1694093860.906 [Default:CheckIn](INFO): Running loop #4 2023-09-07T13:37:40.906Z,1694093860.906 [Default:CheckIn] Running Loop=4 2023-09-07T13:37:40.907Z,1694093860.907 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-07T13:37:40.907Z,1694093860.907 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-07T13:40:00.733Z,1694094000.733 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13 2023-09-07T13:40:00.733Z,1694094000.733 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-09-07T13:40:00.768Z,1694094000.768 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-09-07T13:40:01.183Z,1694094001.183 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-09-07T13:40:01.183Z,1694094001.183 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13 2023-09-07T13:42:41.139Z,1694094161.139 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-09-07T13:37:40.9Z 2023-09-07T13:42:41.140Z,1694094161.140 [Default:CheckIn:Read_GPS] Stopped 2023-09-07T13:42:41.140Z,1694094161.140 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-07T13:42:50.303Z,1694094170.303 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230907T130035/Courier0013.lzma 2023-09-07T13:42:51.306Z,1694094171.306 [DataOverHttps](INFO): Moved sent file to Logs/20230907T130035/Courier0013.lzma.bak 2023-09-07T13:42:51.306Z,1694094171.306 [DataOverHttps](INFO): SBD MOMSN=18626653 2023-09-07T13:43:01.861Z,1694094181.861 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14 2023-09-07T13:43:01.861Z,1694094181.861 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-09-07T13:43:01.913Z,1694094181.913 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-09-07T13:43:02.304Z,1694094182.304 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-09-07T13:43:02.304Z,1694094182.304 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14 2023-09-07T13:43:18.755Z,1694094198.755 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20230907T130035/Express0014.lzma 2023-09-07T13:43:19.758Z,1694094199.758 [DataOverHttps](INFO): Moved sent file to Logs/20230907T130035/Express0014.lzma.bak 2023-09-07T13:43:19.758Z,1694094199.758 [DataOverHttps](INFO): SBD MOMSN=18626663 2023-09-07T13:43:23.697Z,1694094203.697 [Default:CheckIn:Read_Iridium] Stopped 2023-09-07T13:43:23.697Z,1694094203.697 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-07T13:43:23.697Z,1694094203.697 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-07T13:43:38.606Z,1694094218.606 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-09-07T13:43:38.606Z,1694094218.606 [NAL9602] Data Fault, FailCount= 4 2023-09-07T13:43:38.606Z,1694094218.606 [NAL9602](ERROR): Data Fault 2023-09-07T13:43:38.650Z,1694094218.650 [CBIT](ERROR): Data Fault in component: NAL9602 2023-09-07T13:43:39.013Z,1694094219.013 [NAL9602](INFO): Powering down 2023-09-07T13:43:39.842Z,1694094219.842 [CBIT](INFO): Clearing failed state for component NAL9602 2023-09-07T13:43:39.842Z,1694094219.842 [NAL9602] No Fault, FailCount= 4 2023-09-07T13:44:09.309Z,1694094249.309 [NAL9602](INFO): Powering up NAL9602 2023-09-07T13:44:20.221Z,1694094260.221 [NAL9602](INFO): NAL9602 initialized 2023-09-07T13:46:02.918Z,1694094362.918 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15 2023-09-07T13:46:02.918Z,1694094362.918 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-09-07T13:46:02.948Z,1694094362.948 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-09-07T13:46:03.330Z,1694094363.330 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-09-07T13:46:03.330Z,1694094363.330 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15 2023-09-07T13:48:24.321Z,1694094504.321 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-07T13:48:24.321Z,1694094504.321 [Default:CheckIn:C.Wait] Stopped 2023-09-07T13:48:24.321Z,1694094504.321 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-07T13:48:24.321Z,1694094504.321 [Default:CheckIn:D] Running Loop=1 2023-09-07T13:48:24.742Z,1694094504.742 [Default:CheckIn:D] Stopped 2023-09-07T13:48:24.742Z,1694094504.742 [Default:CheckIn:E] Running Loop=1 2023-09-07T13:48:25.154Z,1694094505.154 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.651062 min 2023-09-07T13:48:25.154Z,1694094505.154 [Default:CheckIn:E] Stopped 2023-09-07T13:48:25.155Z,1694094505.155 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-07T13:48:25.155Z,1694094505.155 [Default:CheckIn] Stopped 2023-09-07T13:48:25.155Z,1694094505.155 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-07T13:48:25.155Z,1694094505.155 [Default:CheckIn](INFO): Running loop #5 2023-09-07T13:48:25.155Z,1694094505.155 [Default:CheckIn] Running Loop=5 2023-09-07T13:48:25.155Z,1694094505.155 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-07T13:48:25.155Z,1694094505.155 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-07T13:49:03.986Z,1694094543.986 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16 2023-09-07T13:49:03.986Z,1694094543.986 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-09-07T13:49:04.053Z,1694094544.053 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-09-07T13:49:04.439Z,1694094544.439 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-09-07T13:49:04.439Z,1694094544.439 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16 2023-09-07T13:52:04.993Z,1694094724.993 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17 2023-09-07T13:52:04.993Z,1694094724.993 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-09-07T13:52:05.003Z,1694094725.003 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-09-07T13:52:05.409Z,1694094725.409 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-09-07T13:52:05.409Z,1694094725.409 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17 2023-09-07T13:53:25.406Z,1694094805.406 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-09-07T13:48:25.2Z 2023-09-07T13:53:25.406Z,1694094805.406 [Default:CheckIn:Read_GPS] Stopped 2023-09-07T13:53:25.406Z,1694094805.406 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-07T13:53:33.459Z,1694094813.459 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230907T130035/Courier0016.lzma 2023-09-07T13:53:34.462Z,1694094814.462 [DataOverHttps](INFO): Moved sent file to Logs/20230907T130035/Courier0016.lzma.bak 2023-09-07T13:53:34.462Z,1694094814.462 [DataOverHttps](INFO): SBD MOMSN=18626677 2023-09-07T13:53:52.523Z,1694094832.523 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20230907T130035/Express0017.lzma 2023-09-07T13:53:53.526Z,1694094833.526 [DataOverHttps](INFO): Moved sent file to Logs/20230907T130035/Express0017.lzma.bak 2023-09-07T13:53:53.526Z,1694094833.526 [DataOverHttps](INFO): SBD MOMSN=18626683 2023-09-07T13:53:57.337Z,1694094837.337 [Default:CheckIn:Read_Iridium] Stopped 2023-09-07T13:53:57.337Z,1694094837.337 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-07T13:53:57.337Z,1694094837.337 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-07T13:54:21.957Z,1694094861.957 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-09-07T13:54:21.957Z,1694094861.957 [NAL9602] Data Fault, FailCount= 5 2023-09-07T13:54:21.957Z,1694094861.957 [NAL9602](ERROR): Data Fault 2023-09-07T13:54:22.000Z,1694094862.000 [CBIT](ERROR): Data Fault in component: NAL9602 2023-09-07T13:54:22.001Z,1694094862.001 [CBIT](CRITICAL): Data Fault in component: NAL9602 2023-09-07T13:54:22.361Z,1694094862.361 [NAL9602](INFO): Powering down 2023-09-07T13:54:22.377Z,1694094862.377 [CommandExec](FAULT): Scheduling is paused 2023-09-07T13:54:22.377Z,1694094862.377 [CBIT](INFO): Critical error at 20230907T135422 2023-09-07T13:54:22.377Z,1694094862.377 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2023-09-07T13:55:06.003Z,1694094906.003 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18 2023-09-07T13:55:06.003Z,1694094906.003 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-09-07T13:55:06.014Z,1694094906.014 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-09-07T13:55:06.032Z,1694094906.032 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.367275 2023-09-07T13:55:06.421Z,1694094906.421 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-09-07T13:55:06.421Z,1694094906.421 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18 2023-09-07T13:55:28.764Z,1694094928.764 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003075 2023-09-07T13:56:22.003Z,1694094982.003 [CBIT](INFO): Clearing failed state for component NAL9602 2023-09-07T13:56:22.003Z,1694094982.003 [NAL9602] No Fault, FailCount= 5 2023-09-07T13:56:22.385Z,1694094982.385 [NAL9602](INFO): Powering up NAL9602 2023-09-07T13:56:33.290Z,1694094993.290 [NAL9602](INFO): NAL9602 initialized 2023-09-07T13:58:07.115Z,1694095087.115 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19 2023-09-07T13:58:07.115Z,1694095087.115 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-09-07T13:58:07.126Z,1694095087.126 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-09-07T13:58:07.541Z,1694095087.541 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-09-07T13:58:07.541Z,1694095087.541 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19 2023-09-07T13:58:58.082Z,1694095138.082 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-07T13:58:58.082Z,1694095138.082 [Default:CheckIn:C.Wait] Stopped 2023-09-07T13:58:58.082Z,1694095138.082 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-07T13:58:58.082Z,1694095138.082 [Default:CheckIn:D] Running Loop=1 2023-09-07T13:58:58.494Z,1694095138.494 [Default:CheckIn:D] Stopped 2023-09-07T13:58:58.494Z,1694095138.494 [Default:CheckIn:E] Running Loop=1 2023-09-07T13:58:58.907Z,1694095138.907 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 53.213582 min 2023-09-07T13:58:58.907Z,1694095138.907 [Default:CheckIn:E] Stopped 2023-09-07T13:58:58.907Z,1694095138.907 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-07T13:58:58.907Z,1694095138.907 [Default:CheckIn] Stopped 2023-09-07T13:58:58.907Z,1694095138.907 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-07T13:58:58.907Z,1694095138.907 [Default:CheckIn](INFO): Running loop #6 2023-09-07T13:58:58.907Z,1694095138.907 [Default:CheckIn] Running Loop=6 2023-09-07T13:58:58.907Z,1694095138.907 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-07T13:58:58.907Z,1694095138.907 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-07T14:01:08.214Z,1694095268.214 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 20 2023-09-07T14:01:08.214Z,1694095268.214 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-09-07T14:01:08.225Z,1694095268.225 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-09-07T14:01:08.639Z,1694095268.639 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-09-07T14:01:08.639Z,1694095268.639 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 20 2023-09-07T14:03:59.115Z,1694095439.115 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-09-07T13:58:58.9Z 2023-09-07T14:03:59.115Z,1694095439.115 [Default:CheckIn:Read_GPS] Stopped 2023-09-07T14:03:59.115Z,1694095439.115 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-07T14:04:07.623Z,1694095447.623 [DataOverHttps](INFO): Sending 105 bytes from file Logs/20230907T130035/Courier0019.lzma 2023-09-07T14:04:08.626Z,1694095448.626 [DataOverHttps](INFO): Moved sent file to Logs/20230907T130035/Courier0019.lzma.bak 2023-09-07T14:04:08.626Z,1694095448.626 [DataOverHttps](INFO): SBD MOMSN=18626696 2023-09-07T14:04:09.233Z,1694095449.233 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 21 2023-09-07T14:04:09.233Z,1694095449.233 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-09-07T14:04:09.244Z,1694095449.244 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-09-07T14:04:09.663Z,1694095449.663 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-09-07T14:04:09.663Z,1694095449.663 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 21 2023-09-07T14:04:27.432Z,1694095467.432 [DataOverHttps](INFO): Sending 201 bytes from file Logs/20230907T130035/Express0020.lzma 2023-09-07T14:04:28.434Z,1694095468.434 [DataOverHttps](INFO): Moved sent file to Logs/20230907T130035/Express0020.lzma.bak 2023-09-07T14:04:28.434Z,1694095468.434 [DataOverHttps](INFO): SBD MOMSN=18626705 2023-09-07T14:04:32.255Z,1694095472.255 [Default:CheckIn:Read_Iridium] Stopped 2023-09-07T14:04:32.255Z,1694095472.255 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-07T14:04:32.255Z,1694095472.255 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-07T14:06:34.269Z,1694095594.269 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-09-07T14:06:34.269Z,1694095594.269 [NAL9602] Data Fault, FailCount= 1 2023-09-07T14:06:34.269Z,1694095594.269 [NAL9602](ERROR): Data Fault 2023-09-07T14:06:34.350Z,1694095594.350 [CBIT](ERROR): Data Fault in component: NAL9602 2023-09-07T14:06:34.673Z,1694095594.673 [NAL9602](INFO): Powering down 2023-09-07T14:06:35.519Z,1694095595.519 [CBIT](INFO): Clearing failed state for component NAL9602 2023-09-07T14:06:35.519Z,1694095595.519 [NAL9602] No Fault, FailCount= 1 2023-09-07T14:07:05.009Z,1694095625.009 [NAL9602](INFO): Powering up NAL9602 2023-09-07T14:07:10.366Z,1694095630.366 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 22 2023-09-07T14:07:10.366Z,1694095630.366 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-09-07T14:07:10.419Z,1694095630.419 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-09-07T14:07:10.805Z,1694095630.805 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-09-07T14:07:10.805Z,1694095630.805 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 22 2023-09-07T14:07:15.986Z,1694095635.986 [NAL9602](INFO): NAL9602 initialized 2023-09-07T14:09:33.034Z,1694095773.034 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-07T14:09:33.034Z,1694095773.034 [Default:CheckIn:C.Wait] Stopped 2023-09-07T14:09:33.034Z,1694095773.034 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-07T14:09:33.034Z,1694095773.034 [Default:CheckIn:D] Running Loop=1 2023-09-07T14:09:33.430Z,1694095773.430 [Default:CheckIn:D] Stopped 2023-09-07T14:09:33.430Z,1694095773.430 [Default:CheckIn:E] Running Loop=1 2023-09-07T14:09:33.814Z,1694095773.814 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 63.795862 min 2023-09-07T14:09:33.814Z,1694095773.814 [Default:CheckIn:E] Stopped 2023-09-07T14:09:33.814Z,1694095773.814 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-07T14:09:33.814Z,1694095773.814 [Default:CheckIn] Stopped 2023-09-07T14:09:33.814Z,1694095773.814 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-07T14:09:33.814Z,1694095773.814 [Default:CheckIn](INFO): Running loop #7 2023-09-07T14:09:33.814Z,1694095773.814 [Default:CheckIn] Running Loop=7 2023-09-07T14:09:33.814Z,1694095773.814 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-07T14:09:33.815Z,1694095773.815 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-07T14:10:11.537Z,1694095811.537 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 23 2023-09-07T14:10:11.537Z,1694095811.537 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-09-07T14:10:11.564Z,1694095811.564 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-09-07T14:10:11.873Z,1694095811.873 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-09-07T14:10:11.873Z,1694095811.873 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 23 2023-09-07T14:10:37.725Z,1694095837.725 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-09-07T14:11:26.618Z,1694095886.618 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-09-07T14:12:40.129Z,1694095960.129 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-09-07T14:12:51.843Z,1694095971.843 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-09-07T14:13:12.441Z,1694095992.441 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 24 2023-09-07T14:13:12.441Z,1694095992.441 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-09-07T14:13:12.472Z,1694095992.472 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-09-07T14:13:12.840Z,1694095992.840 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-09-07T14:13:12.840Z,1694095992.840 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 24 2023-09-07T14:14:12.298Z,1694096052.298 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-09-07T14:14:34.061Z,1694096074.061 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-09-07T14:09:33.8Z 2023-09-07T14:14:34.062Z,1694096074.062 [Default:CheckIn:Read_GPS] Stopped 2023-09-07T14:14:34.062Z,1694096074.062 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-07T14:14:42.239Z,1694096082.239 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230907T130035/Courier0022.lzma 2023-09-07T14:14:44.246Z,1694096084.246 [DataOverHttps](INFO): Moved sent file to Logs/20230907T130035/Courier0022.lzma.bak 2023-09-07T14:14:44.246Z,1694096084.246 [DataOverHttps](INFO): SBD MOMSN=18626722 2023-09-07T14:15:19.083Z,1694096119.083 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20230907T130035/Express0023.lzma 2023-09-07T14:15:21.086Z,1694096121.086 [DataOverHttps](INFO): Moved sent file to Logs/20230907T130035/Express0023.lzma.bak 2023-09-07T14:15:21.086Z,1694096121.086 [DataOverHttps](INFO): SBD MOMSN=18626724 2023-09-07T14:15:24.675Z,1694096124.675 [Default:CheckIn:Read_Iridium] Stopped 2023-09-07T14:15:24.675Z,1694096124.675 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-07T14:15:24.675Z,1694096124.675 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-07T14:15:53.390Z,1694096153.390 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-09-07T14:16:07.070Z,1694096167.070 [BPC1](INFO): Calculating totals. Valid battery stick count: 52. Valid reserve battery stick count: 6. 2023-09-07T14:16:07.073Z,1694096167.073 [BPC1](INFO): Received data from all battery sticks. 2023-09-07T14:16:13.565Z,1694096173.565 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 25 2023-09-07T14:16:13.565Z,1694096173.565 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-09-07T14:16:13.588Z,1694096173.588 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-09-07T14:16:14.001Z,1694096174.001 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-09-07T14:16:14.002Z,1694096174.002 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 25 2023-09-07T14:16:17.889Z,1694096177.889 [DataOverHttps](IMPORTANT): SBD MTMSN=20230907T141600 2023-09-07T14:16:25.096Z,1694096185.096 [DataOverHttps](INFO): Received command: restart sys 2023-09-07T14:16:25.177Z,1694096185.177 [CommandExec](IMPORTANT): got command restart system 2023-09-07T14:16:27.236Z,1694096187.236 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2023-09-07T14:16:27.236Z,1694096187.236 [CommandExec](INFO): Uninitializing the command executive. 2023-09-07T14:16:27.236Z,1694096187.236 [CommandExec](INFO): Uninitializing the command scheduler. 2023-09-07T14:16:27.236Z,1694096187.236 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-09-07T14:16:27.372Z,1694096187.372 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2023-09-07T14:16:27.372Z,1694096187.372 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2023-09-07T14:16:27.372Z,1694096187.372 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-09-07T14:16:27.373Z,