2024-02-14T18:14:31.854Z,1707934471.854 [Supervisor](DEBUG): Initializing supervisor. 2024-02-14T18:14:31.858Z,1707934471.858 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2024-02-14T18:14:31.859Z,1707934471.859 [SyncHandler](INFO): Protected caller Thread ID is 836 2024-02-14T18:14:31.859Z,1707934471.859 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2024-02-14T18:14:31.860Z,1707934471.860 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2024-02-14T18:14:31.860Z,1707934471.860 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 837 2024-02-14T18:14:31.864Z,1707934471.864 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2024-02-14T18:14:31.882Z,1707934471.882 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2024-02-14T18:14:31.883Z,1707934471.883 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2024-02-14T18:14:31.883Z,1707934471.883 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 838 2024-02-14T18:14:31.887Z,1707934471.887 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2024-02-14T18:14:31.888Z,1707934471.888 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2024-02-14T18:14:31.889Z,1707934471.889 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 839 2024-02-14T18:14:31.891Z,1707934471.891 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2024-02-14T18:14:31.892Z,1707934471.892 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2024-02-14T18:14:31.892Z,1707934471.892 [logger ThreadHandler](INFO): Protected caller Thread ID is 840 2024-02-14T18:14:31.896Z,1707934471.896 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2024-02-14T18:14:31.896Z,1707934471.896 [Supervisor](INFO): Looking for Config files in directory: Config/ 2024-02-14T18:14:31.900Z,1707934471.900 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2024-02-14T18:14:32.244Z,1707934472.244 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2024-02-14T18:14:32.245Z,1707934472.245 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2024-02-14T18:14:32.326Z,1707934472.326 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2024-02-14T18:14:32.433Z,1707934472.433 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2024-02-14T18:14:32.434Z,1707934472.434 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2024-02-14T18:14:32.564Z,1707934472.564 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2024-02-14T18:14:32.565Z,1707934472.565 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2024-02-14T18:14:32.914Z,1707934472.914 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2024-02-14T18:14:32.915Z,1707934472.915 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2024-02-14T18:14:33.012Z,1707934473.012 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2024-02-14T18:14:33.012Z,1707934473.012 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2024-02-14T18:14:33.412Z,1707934473.412 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2024-02-14T18:14:33.413Z,1707934473.413 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2024-02-14T18:14:33.625Z,1707934473.625 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2024-02-14T18:14:33.626Z,1707934473.626 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2024-02-14T18:14:33.706Z,1707934473.706 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2024-02-14T18:14:33.945Z,1707934473.945 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2024-02-14T18:14:33.946Z,1707934473.946 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2024-02-14T18:14:34.179Z,1707934474.179 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2024-02-14T18:14:34.181Z,1707934474.181 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2024-02-14T18:14:34.702Z,1707934474.702 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2024-02-14T18:14:34.702Z,1707934474.702 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2024-02-14T18:14:34.901Z,1707934474.901 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2024-02-14T18:14:34.901Z,1707934474.901 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2024-02-14T18:14:35.211Z,1707934475.211 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2024-02-14T18:14:35.212Z,1707934475.212 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2024-02-14T18:14:36.038Z,1707934476.038 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2024-02-14T18:14:36.039Z,1707934476.039 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2024-02-14T18:14:36.568Z,1707934476.568 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2024-02-14T18:14:36.570Z,1707934476.570 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/ 2024-02-14T18:14:36.572Z,1707934476.572 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Control.cfg 2024-02-14T18:14:36.673Z,1707934476.673 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Science.cfg 2024-02-14T18:14:36.791Z,1707934476.791 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/secure.cfg 2024-02-14T18:14:36.877Z,1707934476.877 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Servo.cfg 2024-02-14T18:14:36.985Z,1707934476.985 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/BIT.cfg 2024-02-14T18:14:37.085Z,1707934477.085 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Battery.cfg 2024-02-14T18:14:37.347Z,1707934477.347 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2024-02-14T18:14:37.347Z,1707934477.347 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/vehicle.cfg 2024-02-14T18:14:37.462Z,1707934477.462 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/logger.cfg 2024-02-14T18:14:37.557Z,1707934477.557 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Navigation.cfg 2024-02-14T18:14:37.661Z,1707934477.661 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Sensor.cfg 2024-02-14T18:14:37.795Z,1707934477.795 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Simulator.cfg 2024-02-14T18:14:37.882Z,1707934477.882 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2024-02-14T18:14:37.894Z,1707934477.894 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2024-02-14T18:14:38.035Z,1707934478.035 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2024-02-14T18:14:38.036Z,1707934478.036 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2024-02-14T18:14:38.104Z,1707934478.104 [VerticalControl](DEBUG): Construct VerticalControl. 2024-02-14T18:14:38.165Z,1707934478.165 [VerticalControl] Loaded 2024-02-14T18:14:38.166Z,1707934478.166 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2024-02-14T18:14:38.168Z,1707934478.168 [HorizontalControl](DEBUG): Construct HorizontalControl. 2024-02-14T18:14:38.211Z,1707934478.211 [HorizontalControl] Loaded 2024-02-14T18:14:38.211Z,1707934478.211 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2024-02-14T18:14:38.213Z,1707934478.213 [SpeedControl](DEBUG): Construct SpeedControl. 2024-02-14T18:14:38.217Z,1707934478.217 [SpeedControl] Loaded 2024-02-14T18:14:38.217Z,1707934478.217 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2024-02-14T18:14:38.219Z,1707934478.219 [LoopControl](DEBUG): Construct LoopControl. 2024-02-14T18:14:38.220Z,1707934478.220 [LoopControl] Loaded 2024-02-14T18:14:38.220Z,1707934478.220 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2024-02-14T18:14:38.221Z,1707934478.221 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2024-02-14T18:14:38.221Z,1707934478.221 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2024-02-14T18:14:38.303Z,1707934478.303 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2024-02-14T18:14:38.304Z,1707934478.304 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2024-02-14T18:14:39.071Z,1707934479.071 [AHRS_M2] Loaded 2024-02-14T18:14:39.071Z,1707934479.071 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2024-02-14T18:14:39.321Z,1707934479.321 [BackseatComponent] Loaded 2024-02-14T18:14:39.321Z,1707934479.321 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2024-02-14T18:14:39.322Z,1707934479.322 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4088A4E0 2024-02-14T18:14:39.323Z,1707934479.323 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 922 2024-02-14T18:14:39.325Z,1707934479.325 [LcmUniversalReporter] Loaded 2024-02-14T18:14:39.326Z,1707934479.326 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2024-02-14T18:14:40.141Z,1707934480.141 [BPC1] Loaded 2024-02-14T18:14:40.141Z,1707934480.141 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2024-02-14T18:14:40.280Z,1707934480.280 [DataOverHttps] Loaded 2024-02-14T18:14:40.280Z,1707934480.280 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2024-02-14T18:14:40.281Z,1707934480.281 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408BA4E0 2024-02-14T18:14:40.281Z,1707934480.281 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 923 2024-02-14T18:14:40.302Z,1707934480.302 [Depth_Keller] Loaded 2024-02-14T18:14:40.302Z,1707934480.302 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2024-02-14T18:14:40.307Z,1707934480.307 [DropWeight] Loaded 2024-02-14T18:14:40.307Z,1707934480.307 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2024-02-14T18:14:40.369Z,1707934480.369 [NAL9602] Loaded 2024-02-14T18:14:40.370Z,1707934480.370 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2024-02-14T18:14:40.400Z,1707934480.400 [Onboard] Loaded 2024-02-14T18:14:40.400Z,1707934480.400 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2024-02-14T18:14:40.401Z,1707934480.401 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408EA4E0 2024-02-14T18:14:40.401Z,1707934480.401 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 924 2024-02-14T18:14:40.413Z,1707934480.413 [Power24vConverter] Loaded 2024-02-14T18:14:40.414Z,1707934480.414 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2024-02-14T18:14:40.427Z,1707934480.427 [Radio_Surface] Loaded 2024-02-14T18:14:40.427Z,1707934480.427 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2024-02-14T18:14:40.428Z,1707934480.428 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4091A4E0 2024-02-14T18:14:40.428Z,1707934480.428 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 925 2024-02-14T18:14:40.442Z,1707934480.442 [Sonardyne_Nano] Loaded 2024-02-14T18:14:40.442Z,1707934480.442 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2024-02-14T18:14:40.534Z,1707934480.534 [DAT] Loaded 2024-02-14T18:14:40.534Z,1707934480.534 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2024-02-14T18:14:40.535Z,1707934480.535 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 4094A4E0 2024-02-14T18:14:40.536Z,1707934480.536 [DAT ThreadHandler](INFO): Protected caller Thread ID is 926 2024-02-14T18:14:40.558Z,1707934480.558 [Waterlinked] Loaded 2024-02-14T18:14:40.558Z,1707934480.558 [ComponentRegistry](DEBUG): SyncComponent "Waterlinked" handled in the control thread. 2024-02-14T18:14:40.559Z,1707934480.559 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2024-02-14T18:14:40.560Z,1707934480.560 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2024-02-14T18:14:40.652Z,1707934480.652 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2024-02-14T18:14:40.653Z,1707934480.653 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2024-02-14T18:14:40.695Z,1707934480.695 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2024-02-14T18:14:40.695Z,1707934480.695 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2024-02-14T18:14:40.864Z,1707934480.864 [SBIT](DEBUG): Construct Startup Built In Test. 2024-02-14T18:14:40.873Z,1707934480.873 [SBIT] Loaded 2024-02-14T18:14:40.873Z,1707934480.873 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2024-02-14T18:14:40.876Z,1707934480.876 [IBIT](DEBUG): Construct Initiated Built In Test. 2024-02-14T18:14:40.889Z,1707934480.889 [IBIT] Loaded 2024-02-14T18:14:40.889Z,1707934480.889 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2024-02-14T18:14:40.895Z,1707934480.895 [CBIT](DEBUG): Construct Continuous Built In Test. 2024-02-14T18:14:41.001Z,1707934481.001 [CBIT] Loaded 2024-02-14T18:14:41.001Z,1707934481.001 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2024-02-14T18:14:41.001Z,1707934481.001 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2024-02-14T18:14:41.002Z,1707934481.002 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2024-02-14T18:14:41.391Z,1707934481.391 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2024-02-14T18:14:41.392Z,1707934481.392 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2024-02-14T18:14:41.461Z,1707934481.461 [DepthRateCalculator] Loaded 2024-02-14T18:14:41.461Z,1707934481.461 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2024-02-14T18:14:41.466Z,1707934481.466 [PitchRateCalculator] Loaded 2024-02-14T18:14:41.467Z,1707934481.467 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2024-02-14T18:14:41.477Z,1707934481.477 [SpeedCalculator] Loaded 2024-02-14T18:14:41.477Z,1707934481.477 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2024-02-14T18:14:41.482Z,1707934481.482 [YawRateCalculator] Loaded 2024-02-14T18:14:41.482Z,1707934481.482 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2024-02-14T18:14:41.501Z,1707934481.501 [ElevatorOffsetCalculator] Loaded 2024-02-14T18:14:41.501Z,1707934481.501 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2024-02-14T18:14:41.502Z,1707934481.502 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2024-02-14T18:14:41.502Z,1707934481.502 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2024-02-14T18:14:41.685Z,1707934481.685 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2024-02-14T18:14:41.686Z,1707934481.686 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2024-02-14T18:14:41.823Z,1707934481.823 [DeadReckonUsingMultipleVelocitySources] Loaded 2024-02-14T18:14:41.824Z,1707934481.824 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2024-02-14T18:14:41.866Z,1707934481.866 [DeadReckonUsingSpeedCalculator] Loaded 2024-02-14T18:14:41.867Z,1707934481.867 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2024-02-14T18:14:41.910Z,1707934481.910 [DeadReckonWithRespectToSeafloor] Loaded 2024-02-14T18:14:41.910Z,1707934481.910 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread. 2024-02-14T18:14:41.924Z,1707934481.924 [NavChart] Loaded 2024-02-14T18:14:41.924Z,1707934481.924 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2024-02-14T18:14:41.930Z,1707934481.930 [UniversalFixResidualReporter] Loaded 2024-02-14T18:14:41.930Z,1707934481.930 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2024-02-14T18:14:41.930Z,1707934481.930 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2024-02-14T18:14:41.931Z,1707934481.931 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2024-02-14T18:14:42.104Z,1707934482.104 [BuoyancyServo] Loaded 2024-02-14T18:14:42.104Z,1707934482.104 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2024-02-14T18:14:42.125Z,1707934482.125 [ElevatorServo] Loaded 2024-02-14T18:14:42.125Z,1707934482.125 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2024-02-14T18:14:42.145Z,1707934482.145 [MassServo] Loaded 2024-02-14T18:14:42.145Z,1707934482.145 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2024-02-14T18:14:42.164Z,1707934482.164 [RudderServo] Loaded 2024-02-14T18:14:42.164Z,1707934482.164 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2024-02-14T18:14:42.179Z,1707934482.179 [ThrusterHE] Loaded 2024-02-14T18:14:42.179Z,1707934482.179 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2024-02-14T18:14:42.180Z,1707934482.180 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2024-02-14T18:14:42.181Z,1707934482.181 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2024-02-14T18:14:42.401Z,1707934482.401 [CTD_Seabird] Loaded 2024-02-14T18:14:42.401Z,1707934482.401 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2024-02-14T18:14:42.402Z,1707934482.402 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40BB14E0 2024-02-14T18:14:42.403Z,1707934482.403 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 927 2024-02-14T18:14:42.436Z,1707934482.436 [ESPComponent] Loaded 2024-02-14T18:14:42.437Z,1707934482.437 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2024-02-14T18:14:42.457Z,1707934482.457 [PAR_Licor] Loaded 2024-02-14T18:14:42.457Z,1707934482.457 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2024-02-14T18:14:42.498Z,1707934482.498 [WetLabsBB2FL] Loaded 2024-02-14T18:14:42.498Z,1707934482.498 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2024-02-14T18:14:42.499Z,1707934482.499 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BE14E0 2024-02-14T18:14:42.500Z,1707934482.500 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 928 2024-02-14T18:14:42.500Z,1707934482.500 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2024-02-14T18:14:42.507Z,1707934482.507 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2024-02-14T18:14:42.510Z,1707934482.510 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2024-02-14T18:14:42.521Z,1707934482.521 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2024-02-14T18:14:42.522Z,1707934482.522 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C114E0 2024-02-14T18:14:42.522Z,1707934482.522 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 929 2024-02-14T18:14:42.527Z,1707934482.527 [Supervisor](INFO): Main Thread ID is 827 2024-02-14T18:14:42.527Z,1707934482.527 [Supervisor](DEBUG): Running supervisor. 2024-02-14T18:14:42.527Z,1707934482.527 [CommandExec ThreadHandler](INFO): Handler Thread ID is 930 2024-02-14T18:14:42.528Z,1707934482.528 [CommandExec](INFO): Initializing the command executive. 2024-02-14T18:14:42.529Z,1707934482.529 [CommandLine ThreadHandler](INFO): Handler Thread ID is 931 2024-02-14T18:14:42.531Z,1707934482.531 [controlThread ThreadHandler](INFO): Handler Thread ID is 932 2024-02-14T18:14:42.532Z,1707934482.532 [controlThread](DEBUG): Initializing ControlThread 2024-02-14T18:14:42.533Z,1707934482.533 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2024-02-14T18:14:42.534Z,1707934482.534 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2024-02-14T18:14:42.535Z,1707934482.535 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2024-02-14T18:14:42.536Z,1707934482.536 [LoopControl](DEBUG): Initialize LoopControlComponent. 2024-02-14T18:14:42.542Z,1707934482.542 [SBIT](INFO): Initialize SBIT Component. 2024-02-14T18:14:42.543Z,1707934482.543 [SBIT](IMPORTANT): git: 2024-02-14 2024-02-14T18:14:42.543Z,1707934482.543 [SBIT](INFO): git hash: 8fc4e848ca047b5de9ae8a7c02f55958c6301ab2 2024-02-14T18:14:42.543Z,1707934482.543 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2024-02-14T18:14:42.545Z,1707934482.545 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014 Kernel Reported: #1 PREEMPT Tue Nov 28 15:04:22 PST 2023 2024-02-14T18:14:42.546Z,1707934482.546 [SBIT](INFO): Beginning SBIT in 88.000000 seconds. 2024-02-14T18:14:42.546Z,1707934482.546 [IBIT](INFO): Initialize IBIT Component. 2024-02-14T18:14:42.547Z,1707934482.547 [CBIT](DEBUG): Initialize CBIT Component. 2024-02-14T18:14:42.548Z,1707934482.548 [logger ThreadHandler](INFO): Handler Thread ID is 933 2024-02-14T18:14:42.560Z,1707934482.560 [CBIT](DEBUG): Initialized mux pins. 2024-02-14T18:14:42.560Z,1707934482.560 [CBIT](DEBUG): Initializing the watchdog timer. 2024-02-14T18:14:42.568Z,1707934482.568 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 934 2024-02-14T18:14:42.580Z,1707934482.580 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 935 2024-02-14T18:14:42.582Z,1707934482.582 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2024-02-14T18:14:42.584Z,1707934482.584 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2024-02-14T18:14:42.584Z,1707934482.584 [CBIT](DEBUG): Initializing heartbeat. 2024-02-14T18:14:42.592Z,1707934482.592 [Onboard ThreadHandler](INFO): Handler Thread ID is 936 2024-02-14T18:14:42.610Z,1707934482.610 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 937 2024-02-14T18:14:42.633Z,1707934482.633 [DAT ThreadHandler](INFO): Handler Thread ID is 938 2024-02-14T18:14:42.633Z,1707934482.633 [DAT](INFO): Powering up 2024-02-14T18:14:42.634Z,1707934482.634 [DAT](DEBUG): Initializing DAT. 2024-02-14T18:14:42.637Z,1707934482.637 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 940 2024-02-14T18:14:42.638Z,1707934482.638 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2024-02-14T18:14:42.641Z,1707934482.641 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 942 2024-02-14T18:14:42.644Z,1707934482.644 [WetLabsBB2FL](INFO): Powering up 2024-02-14T18:14:42.646Z,1707934482.646 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 944 2024-02-14T18:14:42.653Z,1707934482.653 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2HA05M.000 2024-02-14T18:14:42.653Z,1707934482.653 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2HA05M.000 2024-02-14T18:14:42.653Z,1707934482.653 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3HA20M.000 2024-02-14T18:14:42.653Z,1707934482.653 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3HA20M.000 2024-02-14T18:14:42.654Z,1707934482.654 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4HA51M.000 2024-02-14T18:14:42.654Z,1707934482.654 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4HA51M.000 2024-02-14T18:14:42.654Z,1707934482.654 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4HA51M.000 2024-02-14T18:14:42.654Z,1707934482.654 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4HA51M.000 2024-02-14T18:14:42.654Z,1707934482.654 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5HA53M.000 2024-02-14T18:14:42.654Z,1707934482.654 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5HA53M.000 2024-02-14T18:14:42.655Z,1707934482.655 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5HA55M.000 2024-02-14T18:14:42.655Z,1707934482.655 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5HA55M.000 2024-02-14T18:14:42.656Z,1707934482.656 [CBIT](DEBUG): Deactivating GF circuits. 2024-02-14T18:14:42.657Z,1707934482.657 [CBIT](DEBUG): Deactivating emergency mode. 2024-02-14T18:14:42.696Z,1707934482.696 [CBIT](DEBUG): Backplane powered. 2024-02-14T18:14:42.696Z,1707934482.696 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2024-02-14T18:14:42.697Z,1707934482.697 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2024-02-14T18:14:42.697Z,1707934482.697 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2024-02-14T18:14:42.697Z,1707934482.697 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2024-02-14T18:14:42.698Z,1707934482.698 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2024-02-14T18:14:42.699Z,1707934482.699 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2024-02-14T18:14:42.700Z,1707934482.700 [NavChart](DEBUG): Initialize NavChart Navigation. 2024-02-14T18:14:42.700Z,1707934482.700 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2024-02-14T18:14:42.705Z,1707934482.705 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2024-02-14T18:14:42.705Z,1707934482.705 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Startup.tx 2024-02-14T18:14:42.745Z,1707934482.745 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2024-02-14T18:14:42.791Z,1707934482.791 [MissionManager](DEBUG): mission Startup { behavior Guidance:GoToSurface { run in progression } aggregate StartupSatComms { run in sequence readDatum { timeout duration=P1M Universal:latitude_fix } readDatum { timeout duration=P1M Universal:platform_communications } } } 2024-02-14T18:14:42.797Z,1707934482.797 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2024-02-14T18:14:42.797Z,1707934482.797 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Default.tx 2024-02-14T18:14:42.861Z,1707934482.861 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2024-02-14T18:14:42.863Z,1707934482.863 [Default:A.Wait](DEBUG): Construct Wait. 2024-02-14T18:14:42.865Z,1707934482.865 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2024-02-14T18:14:42.920Z,1707934482.920 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2024-02-14T18:14:42.923Z,1707934482.923 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2024-02-14T18:14:42.946Z,1707934482.946 [Default:E.Execute](DEBUG): Construct Execute. 2024-02-14T18:14:42.951Z,1707934482.951 [MissionManager](DEBUG): mission Default { arguments { ElapsedSinceDefaultStarted = 0 minute } behavior Guidance:Wait { """ Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. """ run in sequence set duration = 13 second } behavior Guidance:GoToSurface { run in progression } aggregate CheckIn { run in sequence repeat=288 readDatum id="Read_GPS" { timeout duration=P5M Universal:time_fix } readDatum id="Read_Iridium" { timeout duration=P4H { behavior Guidance:Execute { run in sequence set command = "Burn on" } syslog critical "Dropped weight due to communications timeout." } Universal:platform_communications } behavior Guidance:Wait { run in sequence set duration = 5 minute } assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started ) syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute } syslog important "Restarting logs and Default mission." behavior Guidance:Execute { run in sequence set command = "restart logs" } } 2024-02-14T18:14:42.956Z,1707934482.956 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,Waterlinked,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2024-02-14T18:14:42.976Z,1707934482.976 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2024-02-14T18:14:43.017Z,1707934483.017 [Radio_Surface](INFO): Powering up 2024-02-14T18:14:43.142Z,1707934483.142 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.140412 decibar, -1.132002 m 2024-02-14T18:14:43.143Z,1707934483.143 [Power24vConverter](INFO): Powering up. 2024-02-14T18:14:43.143Z,1707934483.143 [Sonardyne_Nano](INFO): Initializing. 2024-02-14T18:14:43.178Z,1707934483.178 [DepthRateCalculator](ERROR): Depth measurement is not active 2024-02-14T18:14:43.207Z,1707934483.207 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2024-02-14T18:14:43.212Z,1707934483.212 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2024-02-14T18:14:43.213Z,1707934483.213 [ElevatorServo](DEBUG): Initializing EZServoServo. 2024-02-14T18:14:43.220Z,1707934483.220 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2024-02-14T18:14:43.222Z,1707934483.222 [MassServo](DEBUG): Initializing EZServoServo. 2024-02-14T18:14:43.228Z,1707934483.228 [MassServo](DEBUG): Initializing MassServo. 2024-02-14T18:14:43.229Z,1707934483.229 [RudderServo](DEBUG): Initializing EZServoServo. 2024-02-14T18:14:43.236Z,1707934483.236 [RudderServo](DEBUG): Initializing RudderServo. 2024-02-14T18:14:43.237Z,1707934483.237 [ThrusterHE](DEBUG): Initializing EZServoServo. 2024-02-14T18:14:43.244Z,1707934483.244 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2024-02-14T18:14:44.752Z,1707934484.752 [WetLabsBB2FL](INFO): Powering down 2024-02-14T18:14:45.390Z,1707934485.390 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2024-02-14T18:14:46.645Z,1707934486.645 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2311 2024-02-14T18:14:49.668Z,1707934489.668 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2024-02-14T18:14:54.980Z,1707934494.980 [DAT](INFO): DAT read: 2024-02-14T18:14:54.982Z,1707934494.982 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2024-02-14T18:14:56.493Z,1707934496.493 [DAT](INFO): DAT read: MF Frequency Band 2024-02-14T18:14:56.494Z,1707934496.494 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2024-02-14T18:14:56.495Z,1707934496.495 [DAT](INFO): DAT read: Feb 14 2024 18:14:52 2024-02-14T18:14:57.501Z,1707934497.501 [DAT](INFO): DAT read: Features enabled [Bearing] 2024-02-14T18:14:57.502Z,1707934497.502 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 2024-02-14T18:14:57.502Z,1707934497.502 [DAT](INFO): commRate: 800 2024-02-14T18:14:59.568Z,1707934499.568 [DAT](INFO): entering command mode 2024-02-14T18:14:59.769Z,1707934499.769 [DAT](INFO): DAT read: 2024-02-14T18:14:59.769Z,1707934499.769 [DAT](INFO): DAT read: user:1> 2024-02-14T18:14:59.769Z,1707934499.769 [DAT](INFO): setting verbose to 3 2024-02-14T18:15:00.021Z,1707934500.021 [DAT](INFO): DAT read: user:1> 2024-02-14T18:15:00.022Z,1707934500.022 [DAT](INFO): DAT read: Verbose | 3 2024-02-14T18:15:00.022Z,1707934500.022 [DAT](INFO): set verbose to 3 2024-02-14T18:15:00.022Z,1707934500.022 [DAT](INFO): setting DatVerbose to 27440 2024-02-14T18:15:00.273Z,1707934500.273 [DAT](INFO): DAT read: user:2> 2024-02-14T18:15:00.275Z,1707934500.275 [DAT](INFO): DAT read: DatVerbose | 27440 2024-02-14T18:15:00.277Z,1707934500.277 [DAT](INFO): set DatVerbose to 27440 2024-02-14T18:15:00.278Z,1707934500.278 [DAT](INFO): setting transmit power to 8 2024-02-14T18:15:00.525Z,1707934500.525 [DAT](INFO): DAT read: user:3> 2024-02-14T18:15:00.526Z,1707934500.526 [DAT](INFO): DAT read: TxPower | 8 (Max) 2024-02-14T18:15:00.526Z,1707934500.526 [DAT](INFO): set transmit power to 8 2024-02-14T18:15:00.527Z,1707934500.527 [DAT](INFO): setting local address to 1 2024-02-14T18:15:00.777Z,1707934500.777 [DAT](INFO): DAT read: user:4> 2024-02-14T18:15:00.778Z,1707934500.778 [DAT](INFO): DAT read: LocalAddr | 1 2024-02-14T18:15:00.778Z,1707934500.778 [DAT](INFO): set local address to 1 2024-02-14T18:15:00.779Z,1707934500.779 [DAT](INFO): Setting time to: 18:15:0 And date to:2/14/2024 2024-02-14T18:15:01.029Z,1707934501.029 [DAT](INFO): DAT read: user:5> 2024-02-14T18:15:01.029Z,1707934501.029 [DAT](INFO): DAT read: Wed Feb 14, 2024 18:15:00 2024-02-14T18:15:01.030Z,1707934501.030 [DAT](INFO): Local DAT time set to Wed Feb 14, 2024 18:15:00 2024-02-14T18:15:10.441Z,1707934510.441 [NAL9602](INFO): Powering up NAL9602 2024-02-14T18:15:17.738Z,1707934517.738 [Waterlinked](ERROR): only read 0 of 1 data item for altitude 2024-02-14T18:15:21.354Z,1707934521.354 [NAL9602](INFO): NAL9602 initialized 2024-02-14T18:15:26.680Z,1707934526.680 [Waterlinked](INFO): Powering down 2024-02-14T18:15:45.994Z,1707934545.994 [NAL9602](INFO): SBD MO Status=2, MOMSN=10358, MT Status=2, MTMSN=0 2024-02-14T18:15:45.994Z,1707934545.994 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-02-14T18:15:56.905Z,1707934556.905 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-14T18:16:11.085Z,1707934571.085 [SBIT](IMPORTANT): Beginning Startup BIT 2024-02-14T18:16:11.089Z,1707934571.089 [CBIT](IMPORTANT): Beginning ground fault scan 2024-02-14T18:16:22.208Z,1707934582.208 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.000134 CHAN A1 (24V): 0.000359 CHAN A2 (12V): 0.000283 CHAN A3 (5V): -0.001527 CHAN B0 (3.3V): 0.000787 CHAN B1 (3.15aV): 0.000620 CHAN B2 (3.15bV): 0.000110 CHAN B3 (GND): 0.000285 OPEN: 0.006645 Full Scale: +/- 1 mA 2024-02-14T18:16:40.943Z,1707934600.943 [NAL9602](INFO): SBD MO Status=2, MOMSN=10358, MT Status=2, MTMSN=0 2024-02-14T18:16:40.943Z,1707934600.943 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-02-14T18:17:04.816Z,1707934624.816 [SBIT](IMPORTANT): SBIT PASSED 2024-02-14T18:17:04.816Z,1707934624.816 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2024-02-14T18:17:04.817Z,1707934624.817 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=10 count; 2024-02-14T18:17:04.817Z,1707934624.817 [SBIT](IMPORTANT): Express linearApproximation CTD_Seabird.bin_median_sea_water_salinity 0.050000 practical_salinity_unit; 2024-02-14T18:17:04.817Z,1707934624.817 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_LM_AvgRois 50.000000 count_per_second; 2024-02-14T18:17:04.817Z,1707934624.817 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 5.000000 meter; 2024-02-14T18:17:04.817Z,1707934624.817 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=261.788552 cubic_centimeter; 2024-02-14T18:17:04.818Z,1707934624.818 [SBIT](IMPORTANT): VerticalControl.massDefault=17.131162 millimeter; 2024-02-14T18:17:05.192Z,1707934625.192 [MissionManager](IMPORTANT): Started mission Startup 2024-02-14T18:17:05.193Z,1707934625.193 [Startup] Running Loop=1 2024-02-14T18:17:05.193Z,1707934625.193 [Startup](DEBUG): Aggregate::initialize Startup 2024-02-14T18:17:05.193Z,1707934625.193 [Startup:A.GoToSurface] Running Loop=1 2024-02-14T18:17:05.193Z,1707934625.193 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-02-14T18:17:05.194Z,1707934625.194 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-02-14T18:17:05.194Z,1707934625.194 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-02-14T18:17:05.194Z,1707934625.194 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-02-14T18:17:05.195Z,1707934625.195 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-02-14T18:17:05.195Z,1707934625.195 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-02-14T18:17:05.197Z,1707934625.197 [Startup:StartupSatComms] Running Loop=1 2024-02-14T18:17:05.197Z,1707934625.197 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2024-02-14T18:17:05.197Z,1707934625.197 [Startup:StartupSatComms:A] Running Loop=1 2024-02-14T18:17:05.601Z,1707934625.601 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2024-02-14T18:17:15.691Z,1707934635.691 [NAL9602](INFO): SBD MO Status=2, MOMSN=10358, MT Status=2, MTMSN=0 2024-02-14T18:17:15.691Z,1707934635.691 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-02-14T18:17:42.778Z,1707934662.778 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2024-02-14T18:17:42.778Z,1707934662.778 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-02-14T18:17:42.779Z,1707934662.779 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2024-02-14T18:17:42.779Z,1707934662.779 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2024-02-14T18:17:42.780Z,1707934662.780 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2024-02-14T18:17:42.780Z,1707934662.780 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-14T18:17:42.807Z,1707934662.807 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-02-14T18:17:42.807Z,1707934662.807 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2024-02-14T18:17:42.807Z,1707934662.807 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-14T18:17:43.179Z,1707934663.179 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-02-14T18:17:43.179Z,1707934663.179 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2024-02-14T18:17:43.179Z,1707934663.179 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2024-02-14T18:17:43.179Z,1707934663.179 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2024-02-14T18:17:43.180Z,1707934663.180 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-14T18:17:43.180Z,1707934663.180 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2024-02-14T18:17:43.580Z,1707934663.580 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2024-02-14T18:18:01.045Z,1707934681.045 [DAT](INFO): DAT read: user:6>Lowpower 2024-02-14T18:18:01.045Z,1707934681.045 [DAT](DEBUG): Re-entering command mode due to deviceResponse_: user:6>Lowpower 2024-02-14T18:18:05.441Z,1707934685.441 [Startup:StartupSatComms:A](INFO): Timed out from 2024-02-14T18:17:05.2Z 2024-02-14T18:18:05.441Z,1707934685.441 [Startup:StartupSatComms:A] Stopped 2024-02-14T18:18:05.441Z,1707934685.441 [Startup:StartupSatComms:B] Running Loop=1 2024-02-14T18:18:05.809Z,1707934685.809 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-02-14T18:18:25.674Z,1707934705.674 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-02-14T18:18:27.854Z,1707934707.854 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004362 2024-02-14T18:18:32.437Z,1707934712.437 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-14T18:18:35.143Z,1707934715.143 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240214T175134/Courier0010.lzma 2024-02-14T18:18:36.146Z,1707934716.146 [DataOverHttps](INFO): Moved sent file to Logs/20240214T175134/Courier0010.lzma.bak 2024-02-14T18:18:36.147Z,1707934716.147 [DataOverHttps](INFO): SBD MOMSN=19278459 2024-02-14T18:18:47.385Z,1707934727.385 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-14T18:18:54.276Z,1707934734.276 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240214T181431/Courier0000.lzma 2024-02-14T18:18:55.278Z,1707934735.278 [DataOverHttps](INFO): Moved sent file to Logs/20240214T181431/Courier0000.lzma.bak 2024-02-14T18:18:55.278Z,1707934735.278 [DataOverHttps](INFO): SBD MOMSN=19278463 2024-02-14T18:19:02.377Z,1707934742.377 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-14T18:19:03.534Z,1707934743.534 [DataOverHttps](IMPORTANT): SBD MTMSN=20240214T181904 2024-02-14T18:19:05.634Z,1707934745.634 [Startup:StartupSatComms:B](INFO): Timed out from 2024-02-14T18:18:05.4Z 2024-02-14T18:19:05.634Z,1707934745.634 [Startup:StartupSatComms:B] Stopped 2024-02-14T18:19:05.634Z,1707934745.634 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2024-02-14T18:19:05.634Z,1707934745.634 [Startup:StartupSatComms] Stopped 2024-02-14T18:19:05.634Z,1707934745.634 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2024-02-14T18:19:05.635Z,1707934745.635 [Startup](INFO): Completed Startup 2024-02-14T18:19:05.635Z,1707934745.635 [MissionManager](INFO): Startup is completed. 2024-02-14T18:19:05.635Z,1707934745.635 [MissionManager](INFO): Uninitializing Mission Startup 2024-02-14T18:19:05.635Z,1707934745.635 [Startup] Stopped 2024-02-14T18:19:05.635Z,1707934745.635 [Startup](DEBUG): Aggregate::uninitialize Startup 2024-02-14T18:19:05.636Z,1707934745.636 [Startup:A.GoToSurface] Stopped 2024-02-14T18:19:05.636Z,1707934745.636 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-02-14T18:19:06.046Z,1707934746.046 [MissionManager](IMPORTANT): Started mission Default 2024-02-14T18:19:06.046Z,1707934746.046 [Default] Running Loop=1 2024-02-14T18:19:06.046Z,1707934746.046 [Default](DEBUG): Aggregate::initialize Default 2024-02-14T18:19:06.046Z,1707934746.046 [Default:B.GoToSurface] Running Loop=1 2024-02-14T18:19:06.046Z,1707934746.046 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-02-14T18:19:06.047Z,1707934746.047 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-02-14T18:19:06.047Z,1707934746.047 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-02-14T18:19:06.047Z,1707934746.047 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-02-14T18:19:06.047Z,1707934746.047 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-02-14T18:19:06.048Z,1707934746.048 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-02-14T18:19:06.048Z,1707934746.048 [Default:A.Wait] Running Loop=1 2024-02-14T18:19:06.048Z,1707934746.048 [Default:A.Wait](DEBUG): Initialize Wait Component. 2024-02-14T18:19:10.813Z,1707934750.813 [DataOverHttps](INFO): Sending 190 bytes from file Logs/20240214T175134/Express0011.lzma 2024-02-14T18:19:10.817Z,1707934750.817 [DataOverHttps](INFO): Received command: strobe off 2024-02-14T18:19:10.828Z,1707934750.828 [CommandExec](IMPORTANT): got command strobe off 2024-02-14T18:19:10.828Z,1707934750.828 [CommandExec](IMPORTANT): Deactivating strobe 2024-02-14T18:19:11.814Z,1707934751.814 [DataOverHttps](INFO): Moved sent file to Logs/20240214T175134/Express0011.lzma.bak 2024-02-14T18:19:11.814Z,1707934751.814 [DataOverHttps](INFO): SBD MOMSN=19278471 2024-02-14T18:19:17.325Z,1707934757.325 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-14T18:19:19.384Z,1707934759.384 [Default:A.Wait](INFO): Done Waiting. 2024-02-14T18:19:19.388Z,1707934759.388 [Default:A.Wait] Stopped 2024-02-14T18:19:19.388Z,1707934759.388 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2024-02-14T18:19:19.809Z,1707934759.809 [Default:CheckIn] Running Loop=1 2024-02-14T18:19:19.809Z,1707934759.809 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-14T18:19:19.809Z,1707934759.809 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-14T18:19:20.216Z,1707934760.216 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2024-02-14T18:19:32.688Z,1707934772.688 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-14T18:19:47.629Z,1707934787.629 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-14T18:20:02.977Z,1707934802.977 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-14T18:20:18.734Z,1707934818.734 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-14T18:20:22.788Z,1707934822.788 [NAL9602](INFO): SBD MO Status=2, MOMSN=10358, MT Status=2, MTMSN=0 2024-02-14T18:20:22.788Z,1707934822.788 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-02-14T18:20:24.798Z,1707934824.798 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-02-14T18:20:29.651Z,1707934829.651 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182029.00,A,3648.14623,N,12147.25451,W,2.177,154.41,140224,,,A*76 2024-02-14T18:20:29.662Z,1707934829.662 [NAL9602](INFO): GPS fix at 20240214T182029: (36.802437, -121.787575) 2024-02-14T18:20:29.680Z,1707934829.680 [Default:CheckIn:Read_GPS] Stopped 2024-02-14T18:20:29.680Z,1707934829.680 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-14T18:20:30.096Z,1707934830.096 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-02-14T18:20:33.687Z,1707934833.687 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-14T18:20:37.604Z,1707934837.604 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20240214T181431/Courier0004.lzma 2024-02-14T18:20:38.606Z,1707934838.606 [DataOverHttps](INFO): Moved sent file to Logs/20240214T181431/Courier0004.lzma.bak 2024-02-14T18:20:38.606Z,1707934838.606 [DataOverHttps](INFO): SBD MOMSN=19278524 2024-02-14T18:20:43.819Z,1707934843.819 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2024-02-14T18:20:43.819Z,1707934843.819 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-14T18:20:43.896Z,1707934843.896 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-14T18:20:44.334Z,1707934844.334 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-14T18:20:44.334Z,1707934844.334 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2024-02-14T18:20:49.470Z,1707934849.470 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7). 2024-02-14T18:20:57.893Z,1707934857.893 [DataOverHttps](INFO): Sending 956 bytes from file Logs/20240214T181431/Express0001.lzma 2024-02-14T18:20:58.894Z,1707934858.894 [DataOverHttps](INFO): Moved sent file to Logs/20240214T181431/Express0001.lzma.bak 2024-02-14T18:20:58.894Z,1707934858.894 [DataOverHttps](INFO): SBD MOMSN=19278527 2024-02-14T18:21:02.009Z,1707934862.009 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-14T18:21:14.873Z,1707934874.873 [DataOverHttps](INFO): Sending 412 bytes from file Logs/20240214T181431/Express0005.lzma 2024-02-14T18:21:15.874Z,1707934875.874 [DataOverHttps](INFO): Moved sent file to Logs/20240214T181431/Express0005.lzma.bak 2024-02-14T18:21:15.874Z,1707934875.874 [DataOverHttps](INFO): SBD MOMSN=19278549 2024-02-14T18:21:33.499Z,1707934893.499 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20240214T181431/Express0008.lzma 2024-02-14T18:21:34.503Z,1707934894.503 [DataOverHttps](INFO): Moved sent file to Logs/20240214T181431/Express0008.lzma.bak 2024-02-14T18:21:34.503Z,1707934894.503 [DataOverHttps](INFO): SBD MOMSN=19278556 2024-02-14T18:21:35.565Z,1707934895.565 [Default:CheckIn:Read_Iridium] Stopped 2024-02-14T18:21:35.565Z,1707934895.565 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-14T18:21:35.565Z,1707934895.565 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-14T18:22:00.204Z,1707934920.204 [BPC1](INFO): Calculating totals. Valid battery stick count: 52. Valid reserve battery stick count: 6. 2024-02-14T18:22:00.207Z,1707934920.207 [BPC1](INFO): Received data from all battery sticks. 2024-02-14T18:23:14.013Z,1707934994.013 [DataOverHttps](IMPORTANT): SBD MTMSN=20240214T182314 2024-02-14T18:23:21.416Z,1707935001.416 [DataOverHttps](INFO): Received command: ibit 2024-02-14T18:23:21.449Z,1707935001.449 [CommandExec](IMPORTANT): got command ibit 2024-02-14T18:23:21.825Z,1707935001.825 [IBIT](IMPORTANT): Beginning Initiated BIT 2024-02-14T18:23:21.825Z,1707935001.825 [IBIT](IMPORTANT): Beginning control surface checks. 2024-02-14T18:23:21.829Z,1707935001.829 [CBIT](IMPORTANT): Beginning ground fault scan 2024-02-14T18:23:23.423Z,1707935003.423 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182324.00,A,3648.14117,N,12147.22942,W,0.058,40.55,140224,,,D*4A 2024-02-14T18:23:23.426Z,1707935003.426 [NAL9602](INFO): GPS fix at 20240214T182324: (36.802353, -121.787157) 2024-02-14T18:23:32.856Z,1707935012.856 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.000231 CHAN A1 (24V): 0.000060 CHAN A2 (12V): -0.000528 CHAN A3 (5V): -0.001224 CHAN B0 (3.3V): 0.000464 CHAN B1 (3.15aV): 0.000246 CHAN B2 (3.15bV): 0.000095 CHAN B3 (GND): -0.000491 OPEN: 0.006224 Full Scale: +/- 1 mA 2024-02-14T18:23:44.843Z,1707935024.843 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2024-02-14T18:23:44.843Z,1707935024.843 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-14T18:23:44.928Z,1707935024.928 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-14T18:23:45.309Z,1707935025.309 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-14T18:23:45.309Z,1707935025.309 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2024-02-14T18:23:53.105Z,1707935033.105 [DataOverHttps](IMPORTANT): SBD MTMSN=20240214T182353 2024-02-14T18:23:54.134Z,1707935034.134 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2024-02-14T18:23:54.208Z,1707935034.208 [NAL9602](ERROR): received: +SBDI: 2, 10358, 2, 0, 0, 0 OK 2024-02-14T18:24:00.604Z,1707935040.604 [DataOverHttps](INFO): Received command: failc 2024-02-14T18:24:00.613Z,1707935040.613 [CommandExec](IMPORTANT): got command failComponent 2024-02-14T18:24:00.613Z,1707935040.613 [CommandExec](IMPORTANT): Failed components: 2024-02-14T18:24:00.613Z,1707935040.613 [CommandExec](IMPORTANT): No failed Components. 2024-02-14T18:24:07.879Z,1707935047.879 [IBIT](IMPORTANT): Communications Status: Fix Status: 1 Iridium Signal Strength: 5 Latitude: 36.802353 Longitude: -121.787155 2024-02-14T18:24:08.289Z,1707935048.289 [IBIT](IMPORTANT): Battery Status: Battery Charge (AH): 0.000000 Voltage: 16.690001 2024-02-14T18:24:08.289Z,1707935048.289 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah 2024-02-14T18:24:08.289Z,1707935048.289 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V 2024-02-14T18:24:08.699Z,1707935048.699 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 % 2024-02-14T18:24:08.700Z,1707935048.700 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi 2024-02-14T18:24:08.700Z,1707935048.700 [IBIT](IMPORTANT): Pressure:9.898260 PSI 2024-02-14T18:24:08.701Z,1707935048.701 [IBIT](IMPORTANT): Humidity:16.029789 % 2024-02-14T18:24:09.112Z,1707935049.112 [IBIT](IMPORTANT): Vehicle Pitch:-0.682823 degrees 2024-02-14T18:24:09.116Z,1707935049.116 [IBIT](IMPORTANT): Vehicle Roll:2.779549 degrees 2024-02-14T18:24:09.116Z,1707935049.116 [IBIT](IMPORTANT): Vehicle Heading:21.024551 degrees 2024-02-14T18:24:09.494Z,1707935049.494 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m 2024-02-14T18:24:09.494Z,1707935049.494 [IBIT](IMPORTANT): buoyancyNeutral: 261.788544 cc 2024-02-14T18:24:09.495Z,1707935049.495 [IBIT](IMPORTANT): massDefault: 1.713116 cm 2024-02-14T18:24:09.495Z,1707935049.495 [IBIT](IMPORTANT): stopDepth: 112.000000 m 2024-02-14T18:24:09.495Z,1707935049.495 [IBIT](IMPORTANT): abortDepth: 130.000000 m 2024-02-14T18:24:09.495Z,1707935049.495 [IBIT](IMPORTANT): IBIT PASSED 2024-02-14T18:24:10.694Z,1707935050.694 [NAL9602](INFO): SBD MO Status=0, MOMSN=10358, MT Status=0, MTMSN=0 2024-02-14T18:24:10.695Z,1707935050.695 [NAL9602](INFO): No messages in MT queue 2024-02-14T18:24:41.394Z,1707935081.394 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-14T18:26:36.141Z,1707935196.141 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-14T18:26:36.141Z,1707935196.141 [Default:CheckIn:C.Wait] Stopped 2024-02-14T18:26:36.141Z,1707935196.141 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-14T18:26:36.141Z,1707935196.141 [Default:CheckIn:D] Running Loop=1 2024-02-14T18:26:36.551Z,1707935196.551 [Default:CheckIn:D] Stopped 2024-02-14T18:26:36.551Z,1707935196.551 [Default:CheckIn:E] Running Loop=1 2024-02-14T18:26:36.951Z,1707935196.951 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 7.508409 min 2024-02-14T18:26:36.951Z,1707935196.951 [Default:CheckIn:E] Stopped 2024-02-14T18:26:36.952Z,1707935196.952 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-14T18:26:36.952Z,1707935196.952 [Default:CheckIn] Stopped 2024-02-14T18:26:36.952Z,1707935196.952 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-14T18:26:36.952Z,1707935196.952 [Default:CheckIn](INFO): Running loop #2 2024-02-14T18:26:36.952Z,1707935196.952 [Default:CheckIn] Running Loop=2 2024-02-14T18:26:36.952Z,1707935196.952 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-14T18:26:36.952Z,1707935196.952 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-14T18:26:38.959Z,1707935198.959 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182639.00,A,3648.14041,N,12147.22782,W,0.039,40.55,140224,,,D*44 2024-02-14T18:26:38.961Z,1707935198.961 [NAL9602](INFO): GPS fix at 20240214T182639: (36.802340, -121.787130) 2024-02-14T18:26:38.985Z,1707935198.985 [Default:CheckIn:Read_GPS] Stopped 2024-02-14T18:26:38.986Z,1707935198.986 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-14T18:26:45.894Z,1707935205.894 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2024-02-14T18:26:45.894Z,1707935205.894 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-14T18:26:45.981Z,1707935205.981 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-14T18:26:46.344Z,1707935206.344 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-14T18:26:46.345Z,1707935206.345 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2024-02-14T18:26:48.408Z,1707935208.408 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20240214T181431/Courier0010.lzma 2024-02-14T18:26:49.410Z,1707935209.410 [DataOverHttps](INFO): Moved sent file to Logs/20240214T181431/Courier0010.lzma.bak 2024-02-14T18:26:49.410Z,1707935209.410 [DataOverHttps](INFO): SBD MOMSN=19278606 2024-02-14T18:27:00.804Z,1707935220.804 [NAL9602](INFO): SBD MO Status=0, MOMSN=10359, MT Status=0, MTMSN=0 2024-02-14T18:27:00.805Z,1707935220.805 [NAL9602](INFO): No messages in MT queue 2024-02-14T18:27:06.854Z,1707935226.854 [DataOverHttps](INFO): Sending 878 bytes from file Logs/20240214T181431/Express0011.lzma 2024-02-14T18:27:07.854Z,1707935227.854 [DataOverHttps](INFO): Moved sent file to Logs/20240214T181431/Express0011.lzma.bak 2024-02-14T18:27:07.854Z,1707935227.854 [DataOverHttps](INFO): SBD MOMSN=19278609 2024-02-14T18:27:23.755Z,1707935243.755 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20240214T181431/Express0014.lzma 2024-02-14T18:27:24.758Z,1707935244.758 [DataOverHttps](INFO): Moved sent file to Logs/20240214T181431/Express0014.lzma.bak 2024-02-14T18:27:24.758Z,1707935244.758 [DataOverHttps](INFO): SBD MOMSN=19278639 2024-02-14T18:27:25.878Z,1707935245.878 [Default:CheckIn:Read_Iridium] Stopped 2024-02-14T18:27:25.878Z,1707935245.878 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-14T18:27:25.878Z,1707935245.878 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-14T18:27:31.506Z,1707935251.506 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-14T18:29:46.866Z,1707935386.866 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2024-02-14T18:29:46.866Z,1707935386.866 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-14T18:29:46.877Z,1707935386.877 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-14T18:29:47.290Z,1707935387.290 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-14T18:29:47.291Z,1707935387.291 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2024-02-14T18:32:26.444Z,1707935546.444 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-14T18:32:26.445Z,1707935546.445 [Default:CheckIn:C.Wait] Stopped 2024-02-14T18:32:26.445Z,1707935546.445 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-14T18:32:26.445Z,1707935546.445 [Default:CheckIn:D] Running Loop=1 2024-02-14T18:32:26.842Z,1707935546.842 [Default:CheckIn:D] Stopped 2024-02-14T18:32:26.842Z,1707935546.842 [Default:CheckIn:E] Running Loop=1 2024-02-14T18:32:27.242Z,1707935547.242 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 13.346605 min 2024-02-14T18:32:27.242Z,1707935547.242 [Default:CheckIn:E] Stopped 2024-02-14T18:32:27.242Z,1707935547.242 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-14T18:32:27.242Z,1707935547.242 [Default:CheckIn] Stopped 2024-02-14T18:32:27.242Z,1707935547.242 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-14T18:32:27.242Z,1707935547.242 [Default:CheckIn](INFO): Running loop #3 2024-02-14T18:32:27.242Z,1707935547.242 [Default:CheckIn] Running Loop=3 2024-02-14T18:32:27.242Z,1707935547.242 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-14T18:32:27.243Z,1707935547.243 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-14T18:32:29.252Z,1707935549.252 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183229.00,A,3648.14144,N,12147.22869,W,0.000,40.55,140224,,,D*44 2024-02-14T18:32:29.255Z,1707935549.255 [NAL9602](INFO): GPS fix at 20240214T183229: (36.802357, -121.787145) 2024-02-14T18:32:29.270Z,1707935549.270 [Default:CheckIn:Read_GPS] Stopped 2024-02-14T18:32:29.270Z,1707935549.270 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-14T18:32:36.383Z,1707935556.383 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240214T181431/Courier0016.lzma 2024-02-14T18:32:37.386Z,1707935557.386 [DataOverHttps](INFO): Moved sent file to Logs/20240214T181431/Courier0016.lzma.bak 2024-02-14T18:32:37.386Z,1707935557.386 [DataOverHttps](INFO): SBD MOMSN=19278667 2024-02-14T18:32:45.872Z,1707935565.872 [NAL9602](INFO): SBD MO Status=0, MOMSN=10360, MT Status=0, MTMSN=0 2024-02-14T18:32:45.872Z,1707935565.872 [NAL9602](INFO): No messages in MT queue 2024-02-14T18:32:47.906Z,1707935567.906 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2024-02-14T18:32:47.906Z,1707935567.906 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-14T18:32:47.929Z,1707935567.929 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-14T18:32:48.307Z,1707935568.307 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-14T18:32:48.308Z,1707935568.308 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2024-02-14T18:32:56.392Z,1707935576.392 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240214T181431/Express0017.lzma 2024-02-14T18:32:57.394Z,1707935577.394 [DataOverHttps](INFO): Moved sent file to Logs/20240214T181431/Express0017.lzma.bak 2024-02-14T18:32:57.394Z,1707935577.394 [DataOverHttps](INFO): SBD MOMSN=19278671 2024-02-14T18:32:58.798Z,1707935578.798 [Default:CheckIn:Read_Iridium] Stopped 2024-02-14T18:32:58.799Z,1707935578.799 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-14T18:32:58.799Z,1707935578.799 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-14T18:33:16.561Z,1707935596.561 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-14T18:35:48.880Z,1707935748.880 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2024-02-14T18:35:48.881Z,1707935748.881 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-14T18:35:48.891Z,1707935748.891 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-14T18:35:49.298Z,1707935749.298 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-14T18:35:49.298Z,1707935749.298 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2024-02-14T18:37:59.402Z,1707935879.402 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-14T18:37:59.402Z,1707935879.402 [Default:CheckIn:C.Wait] Stopped 2024-02-14T18:37:59.402Z,1707935879.402 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-14T18:37:59.402Z,1707935879.402 [Default:CheckIn:D] Running Loop=1 2024-02-14T18:37:59.785Z,1707935879.785 [Default:CheckIn:D] Stopped 2024-02-14T18:37:59.785Z,1707935879.785 [Default:CheckIn:E] Running Loop=1 2024-02-14T18:38:00.198Z,1707935880.198 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 18.895650 min 2024-02-14T18:38:00.198Z,1707935880.198 [Default:CheckIn:E] Stopped 2024-02-14T18:38:00.198Z,1707935880.198 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-14T18:38:00.198Z,1707935880.198 [Default:CheckIn] Stopped 2024-02-14T18:38:00.199Z,1707935880.199 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-14T18:38:00.199Z,1707935880.199 [Default:CheckIn](INFO): Running loop #4 2024-02-14T18:38:00.199Z,1707935880.199 [Default:CheckIn] Running Loop=4 2024-02-14T18:38:00.199Z,1707935880.199 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-14T18:38:00.199Z,1707935880.199 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-14T18:38:02.198Z,1707935882.198 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183802.00,A,3648.14107,N,12147.22849,W,0.058,40.55,140224,,,D*4F 2024-02-14T18:38:02.211Z,1707935882.211 [NAL9602](INFO): GPS fix at 20240214T183802: (36.802351, -121.787142) 2024-02-14T18:38:02.226Z,1707935882.226 [Default:CheckIn:Read_GPS] Stopped 2024-02-14T18:38:02.226Z,1707935882.226 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-14T18:38:09.851Z,1707935889.851 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240214T181431/Courier0019.lzma 2024-02-14T18:38:10.854Z,1707935890.854 [DataOverHttps](INFO): Moved sent file to Logs/20240214T181431/Courier0019.lzma.bak 2024-02-14T18:38:10.854Z,1707935890.854 [DataOverHttps](INFO): SBD MOMSN=19278700 2024-02-14T18:38:26.771Z,1707935906.771 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20240214T181431/Express0020.lzma 2024-02-14T18:38:27.774Z,1707935907.774 [DataOverHttps](INFO): Moved sent file to Logs/20240214T181431/Express0020.lzma.bak 2024-02-14T18:38:27.774Z,1707935907.774 [DataOverHttps](INFO): SBD MOMSN=19278703 2024-02-14T18:38:28.890Z,1707935908.890 [Default:CheckIn:Read_Iridium] Stopped 2024-02-14T18:38:28.890Z,1707935908.890 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-14T18:38:28.890Z,1707935908.890 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-14T18:38:31.699Z,1707935911.699 [NAL9602](INFO): SBD MO Status=0, MOMSN=10361, MT Status=0, MTMSN=0 2024-02-14T18:38:31.699Z,1707935911.699 [NAL9602](INFO): No messages in MT queue 2024-02-14T18:38:49.934Z,1707935929.934 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2024-02-14T18:38:49.934Z,1707935929.934 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-14T18:38:49.974Z,1707935929.974 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-14T18:38:50.305Z,1707935930.305 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-14T18:38:50.305Z,1707935930.305 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2024-02-14T18:39:02.393Z,1707935942.393 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-14T18:41:50.873Z,1707936110.873 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2024-02-14T18:41:50.873Z,1707936110.873 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-14T18:41:50.900Z,1707936110.900 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-14T18:41:51.290Z,1707936111.290 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-14T18:41:51.290Z,1707936111.290 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2024-02-14T18:43:29.450Z,1707936209.450 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-14T18:43:29.451Z,1707936209.451 [Default:CheckIn:C.Wait] Stopped 2024-02-14T18:43:29.451Z,1707936209.451 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-14T18:43:29.451Z,1707936209.451 [Default:CheckIn:D] Running Loop=1 2024-02-14T18:43:29.869Z,1707936209.869 [Default:CheckIn:D] Stopped 2024-02-14T18:43:29.869Z,1707936209.869 [Default:CheckIn:E] Running Loop=1 2024-02-14T18:43:30.258Z,1707936210.258 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 24.397044 min 2024-02-14T18:43:30.258Z,1707936210.258 [Default:CheckIn:E] Stopped 2024-02-14T18:43:30.258Z,1707936210.258 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-14T18:43:30.259Z,1707936210.259 [Default:CheckIn] Stopped 2024-02-14T18:43:30.259Z,1707936210.259 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-14T18:43:30.259Z,1707936210.259 [Default:CheckIn](INFO): Running loop #5 2024-02-14T18:43:30.259Z,1707936210.259 [Default:CheckIn] Running Loop=5 2024-02-14T18:43:30.259Z,1707936210.259 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-14T18:43:30.259Z,1707936210.259 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-14T18:43:32.267Z,1707936212.267 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184333.00,A,3648.15051,N,12147.22263,W,0.019,25.92,140224,,,D*4D 2024-02-14T18:43:32.270Z,1707936212.270 [NAL9602](INFO): GPS fix at 20240214T184333: (36.802509, -121.787044) 2024-02-14T18:43:32.285Z,1707936212.285 [Default:CheckIn:Read_GPS] Stopped 2024-02-14T18:43:32.285Z,1707936212.285 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-14T18:43:39.244Z,1707936219.244 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240214T181431/Courier0022.lzma 2024-02-14T18:43:40.246Z,1707936220.246 [DataOverHttps](INFO): Moved sent file to Logs/20240214T181431/Courier0022.lzma.bak 2024-02-14T18:43:40.246Z,1707936220.246 [DataOverHttps](INFO): SBD MOMSN=19278721 2024-02-14T18:43:56.120Z,1707936236.120 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20240214T181431/Express0023.lzma 2024-02-14T18:43:57.122Z,1707936237.122 [DataOverHttps](INFO): Moved sent file to Logs/20240214T181431/Express0023.lzma.bak 2024-02-14T18:43:57.122Z,1707936237.122 [DataOverHttps](INFO): SBD MOMSN=19278724 2024-02-14T18:43:58.166Z,1707936238.166 [Default:CheckIn:Read_Iridium] Stopped 2024-02-14T18:43:58.166Z,1707936238.166 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-14T18:43:58.166Z,1707936238.166 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-14T18:44:51.876Z,1707936291.876 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2024-02-14T18:44:51.877Z,1707936291.877 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-14T18:44:51.896Z,1707936291.896 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-14T18:44:52.311Z,1707936292.311 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-14T18:44:52.312Z,1707936292.312 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2024-02-14T18:45:35.890Z,1707936335.890 [NAL9602](INFO): SBD MO Status=0, MOMSN=10362, MT Status=0, MTMSN=0 2024-02-14T18:45:35.890Z,1707936335.890 [NAL9602](INFO): No messages in MT queue 2024-02-14T18:46:06.592Z,1707936366.592 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-14T18:47:52.863Z,1707936472.863 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2024-02-14T18:47:52.863Z,1707936472.863 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-14T18:47:52.873Z,1707936472.873 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-14T18:47:53.277Z,1707936473.277 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-14T18:47:53.277Z,1707936473.277 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2024-02-14T18:48:58.730Z,1707936538.730 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-14T18:48:58.730Z,1707936538.730 [Default:CheckIn:C.Wait] Stopped 2024-02-14T18:48:58.730Z,1707936538.730 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-14T18:48:58.730Z,1707936538.730 [Default:CheckIn:D] Running Loop=1 2024-02-14T18:48:59.112Z,1707936539.112 [Default:CheckIn:D] Stopped 2024-02-14T18:48:59.113Z,1707936539.113 [Default:CheckIn:E] Running Loop=1 2024-02-14T18:48:59.531Z,1707936539.531 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 29.884440 min 2024-02-14T18:48:59.531Z,1707936539.531 [Default:CheckIn:E] Stopped 2024-02-14T18:48:59.531Z,1707936539.531 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-14T18:48:59.532Z,1707936539.532 [Default:CheckIn] Stopped 2024-02-14T18:48:59.532Z,1707936539.532 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-14T18:48:59.532Z,1707936539.532 [Default:CheckIn](INFO): Running loop #6 2024-02-14T18:48:59.532Z,1707936539.532 [Default:CheckIn] Running Loop=6 2024-02-14T18:48:59.532Z,1707936539.532 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-14T18:48:59.532Z,1707936539.532 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-14T18:49:01.525Z,1707936541.525 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184902.00,A,3648.15012,N,12147.22130,W,0.097,25.92,140224,,,D*41 2024-02-14T18:49:01.528Z,1707936541.528 [NAL9602](INFO): GPS fix at 20240214T184902: (36.802502, -121.787022) 2024-02-14T18:49:01.573Z,1707936541.573 [Default:CheckIn:Read_GPS] Stopped 2024-02-14T18:49:01.573Z,1707936541.573 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-14T18:49:08.803Z,1707936548.803 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240214T181431/Courier0025.lzma 2024-02-14T18:49:09.806Z,1707936549.806 [DataOverHttps](INFO): Moved sent file to Logs/20240214T181431/Courier0025.lzma.bak 2024-02-14T18:49:09.806Z,1707936549.806 [DataOverHttps](INFO): SBD MOMSN=19278769 2024-02-14T18:49:12.869Z,1707936552.869 [NAL9602](INFO): SBD MO Status=0, MOMSN=10363, MT Status=0, MTMSN=0 2024-02-14T18:49:12.870Z,1707936552.870 [NAL9602](INFO): No messages in MT queue 2024-02-14T18:49:25.748Z,1707936565.748 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240214T181431/Express0026.lzma 2024-02-14T18:49:26.750Z,1707936566.750 [DataOverHttps](INFO): Moved sent file to Logs/20240214T181431/Express0026.lzma.bak 2024-02-14T18:49:26.750Z,1707936566.750 [DataOverHttps](INFO): SBD MOMSN=19278772 2024-02-14T18:49:27.819Z,1707936567.819 [Default:CheckIn:Read_Iridium] Stopped 2024-02-14T18:49:27.819Z,1707936567.819 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-14T18:49:27.819Z,1707936567.819 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-14T18:49:43.540Z,1707936583.540 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-14T18:50:53.857Z,1707936653.857 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2024-02-14T18:50:53.857Z,1707936653.857 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-14T18:50:53.867Z,1707936653.867 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-14T18:50:54.272Z,1707936654.272 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-14T18:50:54.272Z,1707936654.272 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2024-02-14T18:53:54.861Z,1707936834.861 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2024-02-14T18:53:54.861Z,1707936834.861 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-14T18:53:54.872Z,1707936834.872 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-14T18:53:55.281Z,1707936835.281 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-14T18:53:55.282Z,1707936835.282 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2024-02-14T18:54:28.381Z,1707936868.381 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-14T18:54:28.381Z,1707936868.381 [Default:CheckIn:C.Wait] Stopped 2024-02-14T18:54:28.381Z,1707936868.381 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-14T18:54:28.382Z,1707936868.382 [Default:CheckIn:D] Running Loop=1 2024-02-14T18:54:28.790Z,1707936868.790 [Default:CheckIn:D] Stopped 2024-02-14T18:54:28.790Z,1707936868.790 [Default:CheckIn:E] Running Loop=1 2024-02-14T18:54:29.200Z,1707936869.200 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 35.379069 min 2024-02-14T18:54:29.200Z,1707936869.200 [Default:CheckIn:E] Stopped 2024-02-14T18:54:29.200Z,1707936869.200 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-14T18:54:29.200Z,1707936869.200 [Default:CheckIn] Stopped 2024-02-14T18:54:29.200Z,1707936869.200 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-14T18:54:29.201Z,1707936869.201 [Default:CheckIn](INFO): Running loop #7 2024-02-14T18:54:29.201Z,1707936869.201 [Default:CheckIn] Running Loop=7 2024-02-14T18:54:29.201Z,1707936869.201 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-14T18:54:29.201Z,1707936869.201 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-14T18:54:31.203Z,1707936871.203 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185431.00,A,3648.15021,N,12147.22127,W,0.039,25.92,140224,,,D*4F 2024-02-14T18:54:31.205Z,1707936871.205 [NAL9602](INFO): GPS fix at 20240214T185431: (36.802504, -121.787021) 2024-02-14T18:54:31.240Z,1707936871.240 [Default:CheckIn:Read_GPS] Stopped 2024-02-14T18:54:31.240Z,1707936871.240 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-14T18:54:38.239Z,1707936878.239 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240214T181431/Courier0028.lzma 2024-02-14T18:54:39.242Z,1707936879.242 [DataOverHttps](INFO): Moved sent file to Logs/20240214T181431/Courier0028.lzma.bak 2024-02-14T18:54:39.243Z,1707936879.243 [DataOverHttps](INFO): SBD MOMSN=19278783 2024-02-14T18:54:47.843Z,1707936887.843 [NAL9602](INFO): SBD MO Status=0, MOMSN=10364, MT Status=0, MTMSN=0 2024-02-14T18:54:47.843Z,1707936887.843 [NAL9602](INFO): No messages in MT queue 2024-02-14T18:54:57.895Z,1707936897.895 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240214T181431/Express0029.lzma 2024-02-14T18:54:58.898Z,1707936898.898 [DataOverHttps](INFO): Moved sent file to Logs/20240214T181431/Express0029.lzma.bak 2024-02-14T18:54:58.898Z,1707936898.898 [DataOverHttps](INFO): SBD MOMSN=19278786 2024-02-14T18:54:59.962Z,1707936899.962 [Default:CheckIn:Read_Iridium] Stopped 2024-02-14T18:54:59.962Z,1707936899.962 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-14T18:54:59.962Z,1707936899.962 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-14T18:55:18.536Z,1707936918.536 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-14T18:56:55.917Z,1707937015.917 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2024-02-14T18:56:55.917Z,1707937015.917 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-14T18:56:55.927Z,1707937015.927 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-14T18:56:56.327Z,1707937016.327 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-14T18:56:56.327Z,1707937016.327 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2024-02-14T18:59:56.908Z,1707937196.908 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2024-02-14T18:59:56.908Z,1707937196.908 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-14T18:59:56.919Z,1707937196.919 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-14T18:59:57.323Z,1707937197.323 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-14T18:59:57.323Z,1707937197.323 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2024-02-14T19:00:00.542Z,1707937200.542 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-14T19:00:00.542Z,1707937200.542 [Default:CheckIn:C.Wait] Stopped 2024-02-14T19:00:00.543Z,1707937200.543 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-14T19:00:00.543Z,1707937200.543 [Default:CheckIn:D] Running Loop=1 2024-02-14T19:00:00.950Z,1707937200.950 [Default:CheckIn:D] Stopped 2024-02-14T19:00:00.951Z,1707937200.951 [Default:CheckIn:E] Running Loop=1 2024-02-14T19:00:01.367Z,1707937201.367 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 40.915072 min 2024-02-14T19:00:01.367Z,1707937201.367 [Default:CheckIn:E] Stopped 2024-02-14T19:00:01.367Z,1707937201.367 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-14T19:00:01.368Z,1707937201.368 [Default:CheckIn] Stopped 2024-02-14T19:00:01.368Z,1707937201.368 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-14T19:00:01.368Z,1707937201.368 [Default:CheckIn](INFO): Running loop #8 2024-02-14T19:00:01.368Z,1707937201.368 [Default:CheckIn] Running Loop=8 2024-02-14T19:00:01.368Z,1707937201.368 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-14T19:00:01.368Z,1707937201.368 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-14T19:00:03.360Z,1707937203.360 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190004.00,A,3648.15085,N,12147.21994,W,0.078,25.92,140224,,,D*41 2024-02-14T19:00:03.362Z,1707937203.362 [NAL9602](INFO): GPS fix at 20240214T190004: (36.802514, -121.786999) 2024-02-14T19:00:03.397Z,1707937203.397 [Default:CheckIn:Read_GPS] Stopped 2024-02-14T19:00:03.397Z,1707937203.397 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-14T19:00:10.319Z,1707937210.319 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240214T181431/Courier0031.lzma 2024-02-14T19:00:11.322Z,1707937211.322 [DataOverHttps](INFO): Moved sent file to Logs/20240214T181431/Courier0031.lzma.bak 2024-02-14T19:00:11.322Z,1707937211.322 [DataOverHttps](INFO): SBD MOMSN=19278810 2024-02-14T19:00:25.986Z,1707937225.986 [NAL9602](INFO): SBD MO Status=2, MOMSN=10365, MT Status=2, MTMSN=0 2024-02-14T19:00:25.987Z,1707937225.987 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-02-14T19:00:27.216Z,1707937227.216 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240214T181431/Express0032.lzma 2024-02-14T19:00:28.218Z,1707937228.218 [DataOverHttps](INFO): Moved sent file to Logs/20240214T181431/Express0032.lzma.bak 2024-02-14T19:00:28.218Z,1707937228.218 [DataOverHttps](INFO): SBD MOMSN=19278814 2024-02-14T19:00:29.261Z,1707937229.261 [Default:CheckIn:Read_Iridium] Stopped 2024-02-14T19:00:29.261Z,1707937229.261 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-14T19:00:29.262Z,1707937229.262 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-14T19:01:19.718Z,1707937279.718 [NAL9602](INFO): SBD MO Status=2, MOMSN=10365, MT Status=2, MTMSN=0 2024-02-14T19:01:19.719Z,1707937279.719 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-02-14T19:02:22.746Z,1707937342.746 [NAL9602](INFO): SBD MO Status=2, MOMSN=10365, MT Status=2, MTMSN=0 2024-02-14T19:02:22.747Z,1707937342.747 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-02-14T19:02:57.897Z,1707937377.897 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2024-02-14T19:02:57.897Z,1707937377.897 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-14T19:02:57.908Z,1707937377.908 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-14T19:02:58.327Z,1707937378.327 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-14T19:02:58.327Z,1707937378.327 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2024-02-14T19:03:34.651Z,1707937414.651 [NAL9602](INFO): SBD MO Status=2, MOMSN=10365, MT Status=2, MTMSN=0 2024-02-14T19:03:34.651Z,1707937414.651 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-02-14T19:04:20.710Z,1707937460.710 [NAL9602](INFO): SBD MO Status=2, MOMSN=10365, MT Status=2, MTMSN=0 2024-02-14T19:04:20.711Z,1707937460.711 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-02-14T19:04:56.663Z,1707937496.663 [NAL9602](INFO): SBD MO Status=0, MOMSN=10365, MT Status=0, MTMSN=0 2024-02-14T19:04:56.663Z,1707937496.663 [NAL9602](INFO): No messages in MT queue 2024-02-14T19:05:27.364Z,1707937527.364 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-14T19:05:29.816Z,1707937529.816 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-14T19:05:29.816Z,1707937529.816 [Default:CheckIn:C.Wait] Stopped 2024-02-14T19:05:29.816Z,1707937529.816 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-14T19:05:29.816Z,1707937529.816 [Default:CheckIn:D] Running Loop=1 2024-02-14T19:05:30.206Z,1707937530.206 [Default:CheckIn:D] Stopped 2024-02-14T19:05:30.206Z,1707937530.206 [Default:CheckIn:E] Running Loop=1 2024-02-14T19:05:30.614Z,1707937530.614 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 46.402669 min 2024-02-14T19:05:30.614Z,1707937530.614 [Default:CheckIn:E] Stopped 2024-02-14T19:05:30.614Z,1707937530.614 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-14T19:05:30.614Z,1707937530.614 [Default:CheckIn] Stopped 2024-02-14T19:05:30.615Z,1707937530.615 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-14T19:05:30.615Z,1707937530.615 [Default:CheckIn](INFO): Running loop #9 2024-02-14T19:05:30.615Z,1707937530.615 [Default:CheckIn] Running Loop=9 2024-02-14T19:05:30.615Z,1707937530.615 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-14T19:05:30.615Z,1707937530.615 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-14T19:05:32.623Z,1707937532.623 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190533.00,A,3648.14972,N,12147.22101,W,0.039,25.92,140224,,,D*42 2024-02-14T19:05:32.625Z,1707937532.625 [NAL9602](INFO): GPS fix at 20240214T190533: (36.802495, -121.787017) 2024-02-14T19:05:32.641Z,1707937532.641 [Default:CheckIn:Read_GPS] Stopped 2024-02-14T19:05:32.641Z,1707937532.641 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-14T19:05:39.915Z,1707937539.915 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240214T181431/Courier0034.lzma 2024-02-14T19:05:40.918Z,1707937540.918 [DataOverHttps](INFO): Moved sent file to Logs/20240214T181431/Courier0034.lzma.bak 2024-02-14T19:05:40.918Z,1707937540.918 [DataOverHttps](INFO): SBD MOMSN=19278833 2024-02-14T19:05:56.848Z,1707937556.848 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20240214T181431/Express0035.lzma 2024-02-14T19:05:57.850Z,1707937557.850 [DataOverHttps](INFO): Moved sent file to Logs/20240214T181431/Express0035.lzma.bak 2024-02-14T19:05:57.850Z,1707937557.850 [DataOverHttps](INFO): SBD MOMSN=19278836 2024-02-14T19:05:58.904Z,1707937558.904 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2024-02-14T19:05:58.904Z,1707937558.904 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-14T19:05:58.906Z,1707937558.906 [Default:CheckIn:Read_Iridium] Stopped 2024-02-14T19:05:58.906Z,1707937558.906 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-14T19:05:58.906Z,1707937558.906 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-14T19:05:58.915Z,1707937558.915 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-14T19:05:59.311Z,1707937559.311 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-14T19:05:59.311Z,1707937559.311 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2024-02-14T19:06:00.090Z,1707937560.090 [NAL9602](INFO): SBD MO Status=0, MOMSN=10366, MT Status=0, MTMSN=0 2024-02-14T19:06:00.090Z,1707937560.090 [NAL9602](INFO): No messages in MT queue 2024-02-14T19:06:30.797Z,1707937590.797 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-14T19:08:59.899Z,1707937739.899 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2024-02-14T19:08:59.899Z,1707937739.899 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-14T19:08:59.922Z,1707937739.922 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-14T19:09:00.349Z,1707937740.349 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-14T19:09:00.349Z,1707937740.349 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2024-02-14T19:10:59.479Z,1707937859.479 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-14T19:10:59.479Z,1707937859.479 [Default:CheckIn:C.Wait] Stopped 2024-02-14T19:10:59.479Z,1707937859.479 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-14T19:10:59.479Z,1707937859.479 [Default:CheckIn:D] Running Loop=1 2024-02-14T19:10:59.877Z,1707937859.877 [Default:CheckIn:D] Stopped 2024-02-14T19:10:59.877Z,1707937859.877 [Default:CheckIn:E] Running Loop=1 2024-02-14T19:11:00.290Z,1707937860.290 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 51.897188 min 2024-02-14T19:11:00.290Z,1707937860.290 [Default:CheckIn:E] Stopped 2024-02-14T19:11:00.290Z,1707937860.290 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-14T19:11:00.290Z,1707937860.290 [Default:CheckIn] Stopped 2024-02-14T19:11:00.290Z,1707937860.290 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-14T19:11:00.291Z,1707937860.291 [Default:CheckIn](INFO): Running loop #10 2024-02-14T19:11:00.291Z,1707937860.291 [Default:CheckIn] Running Loop=10 2024-02-14T19:11:00.291Z,1707937860.291 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-14T19:11:00.291Z,1707937860.291 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-14T19:11:02.300Z,1707937862.300 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191103.00,A,3648.32028,N,12147.12231,W,5.054,8.77,140224,,,D*7F 2024-02-14T19:11:02.302Z,1707937862.302 [NAL9602](INFO): GPS fix at 20240214T191103: (36.805338, -121.785372) 2024-02-14T19:11:02.318Z,1707937862.318 [Default:CheckIn:Read_GPS] Stopped 2024-02-14T19:11:02.318Z,1707937862.318 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-14T19:11:09.443Z,1707937869.443 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240214T181431/Courier0037.lzma 2024-02-14T19:11:10.446Z,1707937870.446 [DataOverHttps](INFO): Moved sent file to Logs/20240214T181431/Courier0037.lzma.bak 2024-02-14T19:11:10.446Z,1707937870.446 [DataOverHttps](INFO): SBD MOMSN=19278855 2024-02-14T19:11:22.086Z,1707937882.086 [NAL9602](INFO): SBD MO Status=0, MOMSN=10367, MT Status=0, MTMSN=0 2024-02-14T19:11:22.087Z,1707937882.087 [NAL9602](INFO): No messages in MT queue 2024-02-14T19:11:26.376Z,1707937886.376 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20240214T181431/Express0038.lzma 2024-02-14T19:11:27.378Z,1707937887.378 [DataOverHttps](INFO): Moved sent file to Logs/20240214T181431/Express0038.lzma.bak 2024-02-14T19:11:27.379Z,1707937887.379 [DataOverHttps](INFO): SBD MOMSN=19278858 2024-02-14T19:11:28.594Z,1707937888.594 [Default:CheckIn:Read_Iridium] Stopped 2024-02-14T19:11:28.594Z,1707937888.594 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-14T19:11:28.594Z,1707937888.594 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-14T19:11:52.789Z,1707937912.789 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-14T19:12:00.924Z,1707937920.924 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2024-02-14T19:12:00.924Z,1707937920.924 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-14T19:12:00.971Z,1707937920.971 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-14T19:12:01.301Z,1707937921.301 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-14T19:12:01.302Z,1707937921.302 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2024-02-14T19:15:01.881Z,1707938101.881 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2024-02-14T19:15:01.881Z,1707938101.881 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-14T19:15:01.921Z,1707938101.921 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-14T19:15:02.298Z,1707938102.298 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-14T19:15:02.298Z,1707938102.298 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2024-02-14T19:16:29.146Z,1707938189.146 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-14T19:16:29.146Z,1707938189.146 [Default:CheckIn:C.Wait] Stopped 2024-02-14T19:16:29.146Z,1707938189.146 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-14T19:16:29.146Z,1707938189.146 [Default:CheckIn:D] Running Loop=1 2024-02-14T19:16:29.551Z,1707938189.551 [Default:CheckIn:D] Stopped 2024-02-14T19:16:29.551Z,1707938189.551 [Default:CheckIn:E] Running Loop=1 2024-02-14T19:16:29.959Z,1707938189.959 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 57.391748 min 2024-02-14T19:16:29.959Z,1707938189.959 [Default:CheckIn:E] Stopped 2024-02-14T19:16:29.959Z,1707938189.959 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-14T19:16:29.959Z,1707938189.959 [Default:CheckIn] Stopped 2024-02-14T19:16:29.959Z,1707938189.959 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-14T19:16:29.959Z,1707938189.959 [Default:CheckIn](INFO): Running loop #11 2024-02-14T19:16:29.960Z,1707938189.960 [Default:CheckIn] Running Loop=11 2024-02-14T19:16:29.960Z,1707938189.960 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-14T19:16:29.960Z,1707938189.960 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-14T19:16:31.959Z,1707938191.959 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191632.00,A,3648.36388,N,12147.49644,W,10.147,237.73,140224,,,D*42 2024-02-14T19:16:31.961Z,1707938191.961 [NAL9602](INFO): GPS fix at 20240214T191632: (36.806065, -121.791607) 2024-02-14T19:16:31.997Z,1707938191.997 [Default:CheckIn:Read_GPS] Stopped 2024-02-14T19:16:31.997Z,1707938191.997 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-14T19:16:39.803Z,1707938199.803 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240214T181431/Courier0040.lzma 2024-02-14T19:16:40.806Z,1707938200.806 [DataOverHttps](INFO): Moved sent file to Logs/20240214T181431/Courier0040.lzma.bak 2024-02-14T19:16:40.806Z,1707938200.806 [DataOverHttps](INFO): SBD MOMSN=19278877 2024-02-14T19:16:49.815Z,1707938209.815 [NAL9602](INFO): SBD MO Status=0, MOMSN=10368, MT Status=0, MTMSN=0 2024-02-14T19:16:49.815Z,1707938209.815 [NAL9602](INFO): No messages in MT queue 2024-02-14T19:16:59.444Z,1707938219.444 [DataOverHttps](INFO): Sending 142 bytes from file Logs/20240214T181431/Express0041.lzma 2024-02-14T19:17:00.446Z,1707938220.446 [DataOverHttps](INFO): Moved sent file to Logs/20240214T181431/Express0041.lzma.bak 2024-02-14T19:17:00.446Z,1707938220.446 [DataOverHttps](INFO): SBD MOMSN=19278880 2024-02-14T19:17:01.533Z,1707938221.533 [Default:CheckIn:Read_Iridium] Stopped 2024-02-14T19:17:01.533Z,1707938221.533 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-14T19:17:01.533Z,1707938221.533 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-14T19:17:20.501Z,1707938240.501 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-14T19:18:02.939Z,1707938282.939 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2024-02-14T19:18:02.939Z,1707938282.939 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-14T19:18:02.970Z,1707938282.970 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-14T19:18:03.375Z,1707938283.375 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-14T19:18:03.375Z,1707938283.375 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2024-02-14T19:21:03.945Z,1707938463.945 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2024-02-14T19:21:03.945Z,1707938463.945 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-14T19:21:03.956Z,1707938463.956 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-14T19:21:04.371Z,1707938464.371 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-14T19:21:04.371Z,1707938464.371 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2024-02-14T19:22:02.122Z,1707938522.122 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-14T19:22:02.122Z,1707938522.122 [Default:CheckIn:C.Wait] Stopped 2024-02-14T19:22:02.122Z,1707938522.122 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-14T19:22:02.122Z,1707938522.122 [Default:CheckIn:D] Running Loop=1 2024-02-14T19:22:02.539Z,1707938522.539 [Default:CheckIn:D] Stopped 2024-02-14T19:22:02.539Z,1707938522.539 [Default:CheckIn:E] Running Loop=1 2024-02-14T19:22:02.930Z,1707938522.930 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 62.941553 min 2024-02-14T19:22:02.931Z,1707938522.931 [Default:CheckIn:E] Stopped 2024-02-14T19:22:02.931Z,1707938522.931 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-14T19:22:02.931Z,1707938522.931 [Default:CheckIn] Stopped 2024-02-14T19:22:02.931Z,1707938522.931 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-14T19:22:02.931Z,1707938522.931 [Default:CheckIn](INFO): Running loop #12 2024-02-14T19:22:02.931Z,1707938522.931 [Default:CheckIn] Running Loop=12 2024-02-14T19:22:02.931Z,1707938522.931 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-14T19:22:02.931Z,1707938522.931 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-14T19:22:04.943Z,1707938524.943 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192205.00,A,3648.07264,N,12149.72943,W,20.994,256.26,140224,,,D*4C 2024-02-14T19:22:04.945Z,1707938524.945 [NAL9602](INFO): GPS fix at 20240214T192205: (36.801211, -121.828824) 2024-02-14T19:22:04.961Z,1707938524.961 [Default:CheckIn:Read_GPS] Stopped 2024-02-14T19:22:04.961Z,1707938524.961 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-14T19:22:17.987Z,1707938537.987 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240214T181431/Courier0043.lzma 2024-02-14T19:22:18.990Z,1707938538.990 [DataOverHttps](INFO): Moved sent file to Logs/20240214T181431/Courier0043.lzma.bak 2024-02-14T19:22:18.990Z,1707938538.990 [DataOverHttps](INFO): SBD MOMSN=19278899 2024-02-14T19:22:19.481Z,1707938539.481 [NAL9602](INFO): SBD MO Status=0, MOMSN=10369, MT Status=0, MTMSN=0 2024-02-14T19:22:19.481Z,1707938539.481 [NAL9602](INFO): No messages in MT queue 2024-02-14T19:22:34.816Z,1707938554.816 [DataOverHttps](INFO): Sending 146 bytes from file Logs/20240214T181431/Express0044.lzma 2024-02-14T19:22:35.822Z,1707938555.822 [DataOverHttps](INFO): Moved sent file to Logs/20240214T181431/Express0044.lzma.bak 2024-02-14T19:22:35.822Z,1707938555.822 [DataOverHttps](INFO): SBD MOMSN=19278903 2024-02-14T19:22:36.874Z,1707938556.874 [Default:CheckIn:Read_Iridium] Stopped 2024-02-14T19:22:36.874Z,1707938556.874 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-14T19:22:36.874Z,1707938556.874 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-14T19:22:50.197Z,1707938570.197 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-14T19:24:04.970Z,1707938644.970 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2024-02-14T19:24:04.970Z,1707938644.970 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-14T19:24:04.980Z,1707938644.980 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-14T19:24:05.366Z,1707938645.366 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-14T19:24:05.366Z,1707938645.366 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2024-02-14T19:25:15.707Z,1707938715.707 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-14T19:25:31.454Z,1707938731.454 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-14T19:25:46.818Z,1707938746.818 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-14T19:26:01.762Z,1707938761.762 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-14T19:26:17.115Z,1707938777.115 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-14T19:26:32.466Z,1707938792.466 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-14T19:26:47.447Z,1707938807.447 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-14T19:27:02.414Z,1707938822.414 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-14T19:27:06.124Z,1707938826.124 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2024-02-14T19:27:06.124Z,1707938826.124 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-14T19:27:06.135Z,1707938826.135 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-14T19:27:06.504Z,1707938826.504 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-14T19:27:06.504Z,1707938826.504 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2024-02-14T19:27:17.767Z,1707938837.767 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-14T19:27:32.716Z,1707938852.716 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-14T19:27:37.579Z,1707938857.579 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-14T19:27:37.579Z,1707938857.579 [Default:CheckIn:C.Wait] Stopped 2024-02-14T19:27:37.579Z,1707938857.579 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-14T19:27:37.580Z,1707938857.580 [Default:CheckIn:D] Running Loop=1 2024-02-14T19:27:37.991Z,1707938857.991 [Default:CheckIn:D] Stopped 2024-02-14T19:27:37.991Z,1707938857.991 [Default:CheckIn:E] Running Loop=1 2024-02-14T19:27:38.407Z,1707938858.407 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 68.532422 min 2024-02-14T19:27:38.407Z,1707938858.407 [Default:CheckIn:E] Stopped 2024-02-14T19:27:38.407Z,1707938858.407 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-14T19:27:38.407Z,1707938858.407 [Default:CheckIn] Stopped 2024-02-14T19:27:38.407Z,1707938858.407 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-14T19:27:38.407Z,1707938858.407 [Default:CheckIn](INFO): Running loop #13 2024-02-14T19:27:38.407Z,1707938858.407 [Default:CheckIn] Running Loop=13 2024-02-14T19:27:38.407Z,1707938858.408 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-14T19:27:38.408Z,1707938858.408 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-14T19:27:40.395Z,1707938860.395 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192741.00,A,3647.87735,N,12150.74507,W,1.419,288.13,140224,,,D*73 2024-02-14T19:27:40.397Z,1707938860.397 [NAL9602](INFO): GPS fix at 20240214T192741: (36.797956, -121.845751) 2024-02-14T19:27:40.412Z,1707938860.412 [Default:CheckIn:Read_GPS] Stopped 2024-02-14T19:27:40.412Z,1707938860.412 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-14T19:27:47.723Z,1707938867.723 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240214T181431/Courier0046.lzma 2024-02-14T19:27:48.726Z,1707938868.726 [DataOverHttps](INFO): Moved sent file to Logs/20240214T181431/Courier0046.lzma.bak 2024-02-14T19:27:48.726Z,1707938868.726 [DataOverHttps](INFO): SBD MOMSN=19278921 2024-02-14T19:27:54.535Z,1707938874.535 [NAL9602](INFO): SBD MO Status=0, MOMSN=10370, MT Status=0, MTMSN=0 2024-02-14T19:27:54.535Z,1707938874.535 [NAL9602](INFO): No messages in MT queue 2024-02-14T19:28:06.716Z,1707938886.716 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20240214T181431/Express0047.lzma 2024-02-14T19:28:07.718Z,1707938887.718 [DataOverHttps](INFO): Moved sent file to Logs/20240214T181431/Express0047.lzma.bak 2024-02-14T19:28:07.718Z,1707938887.718 [DataOverHttps](INFO): SBD MOMSN=19278924 2024-02-14T19:28:09.153Z,1707938889.153 [Default:CheckIn:Read_Iridium] Stopped 2024-02-14T19:28:09.154Z,1707938889.154 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-14T19:28:09.154Z,1707938889.154 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-14T19:28:25.241Z,1707938905.241 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-14T19:30:07.113Z,1707939007.113 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2024-02-14T19:30:07.113Z,1707939007.113 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-14T19:30:07.124Z,1707939007.124 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-14T19:30:07.534Z,1707939007.534 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-14T19:30:07.534Z,1707939007.534 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2024-02-14T19:30:09.522Z,1707939009.522 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-14T19:30:23.662Z,1707939023.662 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-14T19:30:24.478Z,1707939024.478 [BPC1](INFO): Calculating totals. Valid battery stick count: 52. Valid reserve battery stick count: 6. 2024-02-14T19:30:24.497Z,1707939024.497 [BPC1](INFO): Received data from all battery sticks. 2024-02-14T19:31:18.785Z,1707939078.785 [DataOverHttps](IMPORTANT): SBD MTMSN=20240214T193119 2024-02-14T19:31:26.227Z,1707939086.227 [DataOverHttps](INFO): Received command: restart logs