2023-10-02T22:16:31.107Z,1696284991.107 [Supervisor](DEBUG): Initializing supervisor. 2023-10-02T22:16:31.111Z,1696284991.111 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2023-10-02T22:16:31.111Z,1696284991.111 [SyncHandler](INFO): Protected caller Thread ID is 829 2023-10-02T22:16:31.112Z,1696284991.112 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2023-10-02T22:16:31.113Z,1696284991.113 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2023-10-02T22:16:31.113Z,1696284991.113 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 830 2023-10-02T22:16:31.117Z,1696284991.117 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2023-10-02T22:16:31.135Z,1696284991.135 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2023-10-02T22:16:31.136Z,1696284991.136 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2023-10-02T22:16:31.136Z,1696284991.136 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 831 2023-10-02T22:16:31.140Z,1696284991.140 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2023-10-02T22:16:31.141Z,1696284991.141 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2023-10-02T22:16:31.142Z,1696284991.142 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 832 2023-10-02T22:16:31.144Z,1696284991.144 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2023-10-02T22:16:31.145Z,1696284991.145 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2023-10-02T22:16:31.145Z,1696284991.145 [logger ThreadHandler](INFO): Protected caller Thread ID is 833 2023-10-02T22:16:31.149Z,1696284991.149 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2023-10-02T22:16:31.149Z,1696284991.149 [Supervisor](INFO): Looking for Config files in directory: Config/ 2023-10-02T22:16:31.153Z,1696284991.153 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2023-10-02T22:16:31.517Z,1696284991.517 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2023-10-02T22:16:31.518Z,1696284991.518 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2023-10-02T22:16:31.598Z,1696284991.598 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2023-10-02T22:16:31.704Z,1696284991.704 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2023-10-02T22:16:31.706Z,1696284991.706 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2023-10-02T22:16:31.832Z,1696284991.832 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2023-10-02T22:16:31.833Z,1696284991.833 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2023-10-02T22:16:32.166Z,1696284992.166 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2023-10-02T22:16:32.167Z,1696284992.167 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2023-10-02T22:16:32.262Z,1696284992.262 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2023-10-02T22:16:32.263Z,1696284992.263 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2023-10-02T22:16:32.648Z,1696284992.648 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2023-10-02T22:16:32.649Z,1696284992.649 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2023-10-02T22:16:32.855Z,1696284992.855 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2023-10-02T22:16:32.856Z,1696284992.856 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2023-10-02T22:16:32.935Z,1696284992.935 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2023-10-02T22:16:33.157Z,1696284993.157 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2023-10-02T22:16:33.158Z,1696284993.158 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2023-10-02T22:16:33.371Z,1696284993.371 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2023-10-02T22:16:33.372Z,1696284993.372 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2023-10-02T22:16:33.966Z,1696284993.966 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2023-10-02T22:16:33.967Z,1696284993.967 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2023-10-02T22:16:34.186Z,1696284994.186 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2023-10-02T22:16:34.186Z,1696284994.186 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2023-10-02T22:16:34.464Z,1696284994.464 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2023-10-02T22:16:34.464Z,1696284994.464 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2023-10-02T22:16:35.277Z,1696284995.277 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2023-10-02T22:16:35.277Z,1696284995.277 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2023-10-02T22:16:35.783Z,1696284995.783 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2023-10-02T22:16:35.785Z,1696284995.785 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/ 2023-10-02T22:16:35.788Z,1696284995.788 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Control.cfg 2023-10-02T22:16:35.884Z,1696284995.884 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Science.cfg 2023-10-02T22:16:36.001Z,1696284996.001 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/secure.cfg 2023-10-02T22:16:36.085Z,1696284996.085 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Servo.cfg 2023-10-02T22:16:36.193Z,1696284996.193 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/BIT.cfg 2023-10-02T22:16:36.292Z,1696284996.292 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Battery.cfg 2023-10-02T22:16:36.554Z,1696284996.554 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2023-10-02T22:16:36.554Z,1696284996.554 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/vehicle.cfg 2023-10-02T22:16:36.664Z,1696284996.664 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/logger.cfg 2023-10-02T22:16:36.759Z,1696284996.759 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Navigation.cfg 2023-10-02T22:16:36.861Z,1696284996.861 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Sensor.cfg 2023-10-02T22:16:36.992Z,1696284996.992 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Simulator.cfg 2023-10-02T22:16:37.078Z,1696284997.078 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/root/ 2023-10-02T22:16:37.078Z,1696284997.078 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2023-10-02T22:16:37.091Z,1696284997.091 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2023-10-02T22:16:37.216Z,1696284997.216 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2023-10-02T22:16:37.216Z,1696284997.216 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2023-10-02T22:16:37.283Z,1696284997.283 [VerticalControl](DEBUG): Construct VerticalControl. 2023-10-02T22:16:37.344Z,1696284997.344 [VerticalControl] Loaded 2023-10-02T22:16:37.344Z,1696284997.344 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2023-10-02T22:16:37.347Z,1696284997.347 [HorizontalControl](DEBUG): Construct HorizontalControl. 2023-10-02T22:16:37.389Z,1696284997.389 [HorizontalControl] Loaded 2023-10-02T22:16:37.389Z,1696284997.389 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2023-10-02T22:16:37.392Z,1696284997.392 [SpeedControl](DEBUG): Construct SpeedControl. 2023-10-02T22:16:37.395Z,1696284997.395 [SpeedControl] Loaded 2023-10-02T22:16:37.395Z,1696284997.395 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2023-10-02T22:16:37.398Z,1696284997.398 [LoopControl](DEBUG): Construct LoopControl. 2023-10-02T22:16:37.398Z,1696284997.398 [LoopControl] Loaded 2023-10-02T22:16:37.399Z,1696284997.399 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2023-10-02T22:16:37.399Z,1696284997.399 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2023-10-02T22:16:37.401Z,1696284997.401 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2023-10-02T22:16:37.481Z,1696284997.481 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2023-10-02T22:16:37.481Z,1696284997.481 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2023-10-02T22:16:38.442Z,1696284998.442 [AHRS_M2] Loaded 2023-10-02T22:16:38.442Z,1696284998.442 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2023-10-02T22:16:38.692Z,1696284998.692 [BackseatComponent] Loaded 2023-10-02T22:16:38.693Z,1696284998.693 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2023-10-02T22:16:38.694Z,1696284998.694 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408814E0 2023-10-02T22:16:38.694Z,1696284998.694 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 916 2023-10-02T22:16:38.697Z,1696284998.697 [LcmUniversalReporter] Loaded 2023-10-02T22:16:38.697Z,1696284998.697 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2023-10-02T22:16:39.518Z,1696284999.518 [BPC1] Loaded 2023-10-02T22:16:39.518Z,1696284999.518 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2023-10-02T22:16:39.844Z,1696284999.844 [DataOverHttps] Loaded 2023-10-02T22:16:39.844Z,1696284999.844 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2023-10-02T22:16:39.845Z,1696284999.845 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408B14E0 2023-10-02T22:16:39.845Z,1696284999.845 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 917 2023-10-02T22:16:39.866Z,1696284999.866 [Depth_Keller] Loaded 2023-10-02T22:16:39.866Z,1696284999.866 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2023-10-02T22:16:39.871Z,1696284999.871 [DropWeight] Loaded 2023-10-02T22:16:39.871Z,1696284999.871 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2023-10-02T22:16:39.934Z,1696284999.934 [NAL9602] Loaded 2023-10-02T22:16:39.934Z,1696284999.934 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2023-10-02T22:16:39.963Z,1696284999.963 [Onboard] Loaded 2023-10-02T22:16:39.964Z,1696284999.964 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2023-10-02T22:16:39.965Z,1696284999.965 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408E14E0 2023-10-02T22:16:39.965Z,1696284999.965 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 918 2023-10-02T22:16:39.977Z,1696284999.977 [Power24vConverter] Loaded 2023-10-02T22:16:39.977Z,1696284999.977 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2023-10-02T22:16:39.990Z,1696284999.990 [Radio_Surface] Loaded 2023-10-02T22:16:39.991Z,1696284999.991 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2023-10-02T22:16:39.992Z,1696284999.992 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409114E0 2023-10-02T22:16:39.992Z,1696284999.992 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 919 2023-10-02T22:16:40.083Z,1696285000.083 [DAT] Loaded 2023-10-02T22:16:40.083Z,1696285000.083 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2023-10-02T22:16:40.084Z,1696285000.084 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409414E0 2023-10-02T22:16:40.084Z,1696285000.084 [DAT ThreadHandler](INFO): Protected caller Thread ID is 920 2023-10-02T22:16:40.107Z,1696285000.107 [Waterlinked] Loaded 2023-10-02T22:16:40.108Z,1696285000.108 [ComponentRegistry](DEBUG): SyncComponent "Waterlinked" handled in the control thread. 2023-10-02T22:16:40.108Z,1696285000.108 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2023-10-02T22:16:40.109Z,1696285000.109 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2023-10-02T22:16:40.124Z,1696285000.124 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2023-10-02T22:16:40.125Z,1696285000.125 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2023-10-02T22:16:40.166Z,1696285000.166 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2023-10-02T22:16:40.168Z,1696285000.168 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2023-10-02T22:16:40.333Z,1696285000.333 [SBIT](DEBUG): Construct Startup Built In Test. 2023-10-02T22:16:40.342Z,1696285000.342 [SBIT] Loaded 2023-10-02T22:16:40.342Z,1696285000.342 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2023-10-02T22:16:40.345Z,1696285000.345 [IBIT](DEBUG): Construct Initiated Built In Test. 2023-10-02T22:16:40.358Z,1696285000.358 [IBIT] Loaded 2023-10-02T22:16:40.358Z,1696285000.358 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2023-10-02T22:16:40.364Z,1696285000.364 [CBIT](DEBUG): Construct Continuous Built In Test. 2023-10-02T22:16:40.469Z,1696285000.469 [CBIT] Loaded 2023-10-02T22:16:40.469Z,1696285000.469 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2023-10-02T22:16:40.469Z,1696285000.469 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2023-10-02T22:16:40.470Z,1696285000.470 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2023-10-02T22:16:40.823Z,1696285000.823 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2023-10-02T22:16:40.824Z,1696285000.824 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2023-10-02T22:16:40.891Z,1696285000.891 [DepthRateCalculator] Loaded 2023-10-02T22:16:40.892Z,1696285000.892 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2023-10-02T22:16:40.897Z,1696285000.897 [PitchRateCalculator] Loaded 2023-10-02T22:16:40.897Z,1696285000.897 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2023-10-02T22:16:40.907Z,1696285000.907 [SpeedCalculator] Loaded 2023-10-02T22:16:40.908Z,1696285000.908 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2023-10-02T22:16:40.912Z,1696285000.912 [YawRateCalculator] Loaded 2023-10-02T22:16:40.912Z,1696285000.912 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2023-10-02T22:16:40.931Z,1696285000.931 [ElevatorOffsetCalculator] Loaded 2023-10-02T22:16:40.931Z,1696285000.931 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2023-10-02T22:16:40.932Z,1696285000.932 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2023-10-02T22:16:40.932Z,1696285000.932 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2023-10-02T22:16:41.113Z,1696285001.113 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2023-10-02T22:16:41.114Z,1696285001.114 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2023-10-02T22:16:41.249Z,1696285001.249 [DeadReckonUsingMultipleVelocitySources] Loaded 2023-10-02T22:16:41.250Z,1696285001.250 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2023-10-02T22:16:41.293Z,1696285001.293 [DeadReckonUsingSpeedCalculator] Loaded 2023-10-02T22:16:41.294Z,1696285001.294 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2023-10-02T22:16:41.337Z,1696285001.337 [DeadReckonWithRespectToSeafloor] Loaded 2023-10-02T22:16:41.337Z,1696285001.337 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread. 2023-10-02T22:16:41.351Z,1696285001.351 [NavChart] Loaded 2023-10-02T22:16:41.351Z,1696285001.351 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2023-10-02T22:16:41.357Z,1696285001.357 [UniversalFixResidualReporter] Loaded 2023-10-02T22:16:41.357Z,1696285001.357 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2023-10-02T22:16:41.358Z,1696285001.358 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2023-10-02T22:16:41.358Z,1696285001.358 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2023-10-02T22:16:41.514Z,1696285001.514 [BuoyancyServo] Loaded 2023-10-02T22:16:41.515Z,1696285001.515 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2023-10-02T22:16:41.535Z,1696285001.535 [ElevatorServo] Loaded 2023-10-02T22:16:41.535Z,1696285001.535 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2023-10-02T22:16:41.555Z,1696285001.555 [MassServo] Loaded 2023-10-02T22:16:41.556Z,1696285001.556 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2023-10-02T22:16:41.575Z,1696285001.575 [RudderServo] Loaded 2023-10-02T22:16:41.575Z,1696285001.575 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2023-10-02T22:16:41.590Z,1696285001.590 [ThrusterHE] Loaded 2023-10-02T22:16:41.590Z,1696285001.590 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2023-10-02T22:16:41.590Z,1696285001.590 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2023-10-02T22:16:41.591Z,1696285001.591 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2023-10-02T22:16:41.810Z,1696285001.810 [CTD_Seabird] Loaded 2023-10-02T22:16:41.810Z,1696285001.810 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2023-10-02T22:16:41.811Z,1696285001.811 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B9B4E0 2023-10-02T22:16:41.812Z,1696285001.812 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 921 2023-10-02T22:16:41.845Z,1696285001.845 [ESPComponent] Loaded 2023-10-02T22:16:41.845Z,1696285001.845 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2023-10-02T22:16:41.866Z,1696285001.866 [PAR_Licor] Loaded 2023-10-02T22:16:41.866Z,1696285001.866 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2023-10-02T22:16:41.907Z,1696285001.907 [WetLabsBB2FL] Loaded 2023-10-02T22:16:41.907Z,1696285001.907 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2023-10-02T22:16:41.908Z,1696285001.908 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BCB4E0 2023-10-02T22:16:41.909Z,1696285001.909 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 922 2023-10-02T22:16:41.909Z,1696285001.909 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2023-10-02T22:16:41.916Z,1696285001.916 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2023-10-02T22:16:41.919Z,1696285001.919 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2023-10-02T22:16:41.930Z,1696285001.930 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2023-10-02T22:16:41.931Z,1696285001.931 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BFB4E0 2023-10-02T22:16:41.931Z,1696285001.931 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 923 2023-10-02T22:16:41.936Z,1696285001.936 [Supervisor](INFO): Main Thread ID is 822 2023-10-02T22:16:41.936Z,1696285001.936 [Supervisor](DEBUG): Running supervisor. 2023-10-02T22:16:41.936Z,1696285001.936 [CommandExec ThreadHandler](INFO): Handler Thread ID is 924 2023-10-02T22:16:41.937Z,1696285001.937 [CommandExec](INFO): Initializing the command executive. 2023-10-02T22:16:41.938Z,1696285001.938 [CommandLine ThreadHandler](INFO): Handler Thread ID is 925 2023-10-02T22:16:41.941Z,1696285001.941 [controlThread ThreadHandler](INFO): Handler Thread ID is 926 2023-10-02T22:16:41.941Z,1696285001.941 [controlThread](DEBUG): Initializing ControlThread 2023-10-02T22:16:41.942Z,1696285001.942 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2023-10-02T22:16:41.944Z,1696285001.944 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2023-10-02T22:16:41.945Z,1696285001.945 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2023-10-02T22:16:41.945Z,1696285001.945 [LoopControl](DEBUG): Initialize LoopControlComponent. 2023-10-02T22:16:41.949Z,1696285001.949 [SBIT](INFO): Initialize SBIT Component. 2023-10-02T22:16:41.949Z,1696285001.949 [SBIT](IMPORTANT): git: 2023-09-18-3-g7ee55e0de 2023-10-02T22:16:41.949Z,1696285001.949 [SBIT](INFO): git hash: 7ee55e0ded82548f5c252ba0d9a41930617440e4 2023-10-02T22:16:41.950Z,1696285001.950 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2023-10-02T22:16:41.951Z,1696285001.951 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014 Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021 2023-10-02T22:16:41.952Z,1696285001.952 [SBIT](INFO): Beginning SBIT in 60.000000 seconds. 2023-10-02T22:16:41.952Z,1696285001.952 [IBIT](INFO): Initialize IBIT Component. 2023-10-02T22:16:41.953Z,1696285001.953 [CBIT](DEBUG): Initialize CBIT Component. 2023-10-02T22:16:41.954Z,1696285001.954 [logger ThreadHandler](INFO): Handler Thread ID is 927 2023-10-02T22:16:41.967Z,1696285001.967 [CBIT](DEBUG): Initialized mux pins. 2023-10-02T22:16:41.967Z,1696285001.967 [CBIT](DEBUG): Initializing the watchdog timer. 2023-10-02T22:16:41.975Z,1696285001.975 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 928 2023-10-02T22:16:41.987Z,1696285001.987 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 929 2023-10-02T22:16:41.988Z,1696285001.988 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2023-10-02T22:16:41.991Z,1696285001.991 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2023-10-02T22:16:41.991Z,1696285001.991 [CBIT](DEBUG): Initializing heartbeat. 2023-10-02T22:16:42.041Z,1696285002.041 [Onboard ThreadHandler](INFO): Handler Thread ID is 930 2023-10-02T22:16:42.061Z,1696285002.061 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 931 2023-10-02T22:16:42.062Z,1696285002.062 [CBIT](DEBUG): Deactivating GF circuits. 2023-10-02T22:16:42.063Z,1696285002.063 [CBIT](DEBUG): Deactivating emergency mode. 2023-10-02T22:16:42.075Z,1696285002.075 [DAT ThreadHandler](INFO): Handler Thread ID is 932 2023-10-02T22:16:42.076Z,1696285002.076 [DAT](INFO): Powering up 2023-10-02T22:16:42.076Z,1696285002.076 [DAT](DEBUG): Initializing DAT. 2023-10-02T22:16:42.080Z,1696285002.080 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 934 2023-10-02T22:16:42.081Z,1696285002.081 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2023-10-02T22:16:42.084Z,1696285002.084 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 936 2023-10-02T22:16:42.087Z,1696285002.087 [WetLabsBB2FL](INFO): Powering up 2023-10-02T22:16:42.088Z,1696285002.088 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 938 2023-10-02T22:16:42.095Z,1696285002.095 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2HA05M.000 2023-10-02T22:16:42.095Z,1696285002.095 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2HA05M.000 2023-10-02T22:16:42.096Z,1696285002.096 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3HA20M.000 2023-10-02T22:16:42.096Z,1696285002.096 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3HA20M.000 2023-10-02T22:16:42.096Z,1696285002.096 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4HA51M.000 2023-10-02T22:16:42.096Z,1696285002.096 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4HA51M.000 2023-10-02T22:16:42.096Z,1696285002.096 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4HA51M.000 2023-10-02T22:16:42.096Z,1696285002.096 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4HA51M.000 2023-10-02T22:16:42.097Z,1696285002.097 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5HA53M.000 2023-10-02T22:16:42.097Z,1696285002.097 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5HA53M.000 2023-10-02T22:16:42.097Z,1696285002.097 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5HA55M.000 2023-10-02T22:16:42.097Z,1696285002.097 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5HA55M.000 2023-10-02T22:16:42.103Z,1696285002.103 [CBIT](DEBUG): Backplane powered. 2023-10-02T22:16:42.103Z,1696285002.103 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2023-10-02T22:16:42.103Z,1696285002.103 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2023-10-02T22:16:42.104Z,1696285002.104 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2023-10-02T22:16:42.104Z,1696285002.104 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2023-10-02T22:16:42.104Z,1696285002.104 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2023-10-02T22:16:42.106Z,1696285002.106 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-02T22:16:42.107Z,1696285002.107 [NavChart](DEBUG): Initialize NavChart Navigation. 2023-10-02T22:16:42.107Z,1696285002.107 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2023-10-02T22:16:42.111Z,1696285002.111 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml 2023-10-02T22:16:42.151Z,1696285002.151 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2023-10-02T22:16:42.174Z,1696285002.174 [MissionManager](DEBUG): 2023-10-02T22:16:42.175Z,1696285002.175 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml 2023-10-02T22:16:42.244Z,1696285002.244 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2023-10-02T22:16:42.245Z,1696285002.245 [Default:A.Wait](DEBUG): Construct Wait. 2023-10-02T22:16:42.259Z,1696285002.259 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2023-10-02T22:16:42.270Z,1696285002.270 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2023-10-02T22:16:42.285Z,1696285002.285 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2023-10-02T22:16:42.304Z,1696285002.304 [Default:E.Execute](DEBUG): Construct Execute. 2023-10-02T22:16:42.307Z,1696285002.307 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2023-10-02T22:16:42.324Z,1696285002.324 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,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, 2023-10-02T22:16:42.339Z,1696285002.339 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2023-10-02T22:16:42.467Z,1696285002.467 [Radio_Surface](INFO): Powering up 2023-10-02T22:16:42.485Z,1696285002.485 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.140412 decibar, -1.132002 m 2023-10-02T22:16:42.486Z,1696285002.486 [Power24vConverter](INFO): Powering up. 2023-10-02T22:16:42.513Z,1696285002.513 [DepthRateCalculator](ERROR): Depth measurement is not active 2023-10-02T22:16:42.538Z,1696285002.538 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2023-10-02T22:16:42.543Z,1696285002.543 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2023-10-02T22:16:42.544Z,1696285002.544 [ElevatorServo](DEBUG): Initializing EZServoServo. 2023-10-02T22:16:42.551Z,1696285002.551 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2023-10-02T22:16:42.552Z,1696285002.552 [MassServo](DEBUG): Initializing EZServoServo. 2023-10-02T22:16:42.561Z,1696285002.561 [MassServo](DEBUG): Initializing MassServo. 2023-10-02T22:16:42.562Z,1696285002.562 [RudderServo](DEBUG): Initializing EZServoServo. 2023-10-02T22:16:42.572Z,1696285002.572 [RudderServo](DEBUG): Initializing RudderServo. 2023-10-02T22:16:42.574Z,1696285002.574 [ThrusterHE](DEBUG): Initializing EZServoServo. 2023-10-02T22:16:42.588Z,1696285002.588 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2023-10-02T22:16:44.195Z,1696285004.195 [WetLabsBB2FL](INFO): Powering down 2023-10-02T22:16:48.235Z,1696285008.236 [ThrusterHE](ERROR): Zero Speed Commanded. 2023-10-02T22:16:52.159Z,1696285012.159 [DAT](INFO): DAT read: 2023-10-02T22:16:52.161Z,1696285012.161 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2023-10-02T22:16:53.420Z,1696285013.420 [DAT](INFO): DAT read: MF Frequency Band 2023-10-02T22:16:53.421Z,1696285013.421 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.10.9 2023-10-02T22:16:53.421Z,1696285013.421 [DAT](INFO): DAT read: Oct 2 2023 22:14:14 2023-10-02T22:16:54.181Z,1696285014.181 [DAT](INFO): DAT read: Features enabled [Bearing] 2023-10-02T22:16:54.182Z,1696285014.182 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2023-10-02T22:16:54.183Z,1696285014.183 [DAT](INFO): commRate: 800 2023-10-02T22:16:56.246Z,1696285016.246 [DAT](INFO): entering command mode 2023-10-02T22:16:56.447Z,1696285016.447 [DAT](INFO): DAT read: 2023-10-02T22:16:56.448Z,1696285016.448 [DAT](INFO): DAT read: user:1> 2023-10-02T22:16:56.448Z,1696285016.448 [DAT](INFO): setting verbose to 3 2023-10-02T22:16:56.699Z,1696285016.699 [DAT](INFO): DAT read: user:1> 2023-10-02T22:16:56.701Z,1696285016.701 [DAT](INFO): DAT read: Verbose | 3 2023-10-02T22:16:56.701Z,1696285016.701 [DAT](INFO): set verbose to 3 2023-10-02T22:16:56.701Z,1696285016.701 [DAT](INFO): setting DatVerbose to 27440 2023-10-02T22:16:56.951Z,1696285016.951 [DAT](INFO): DAT read: user:2> 2023-10-02T22:16:56.952Z,1696285016.952 [DAT](INFO): DAT read: DatVerbose | 27440 2023-10-02T22:16:56.953Z,1696285016.953 [DAT](INFO): set DatVerbose to 27440 2023-10-02T22:16:56.953Z,1696285016.953 [DAT](INFO): setting transmit power to 8 2023-10-02T22:16:57.204Z,1696285017.204 [DAT](INFO): DAT read: user:3> 2023-10-02T22:16:57.205Z,1696285017.205 [DAT](INFO): DAT read: TxPower | 8 (Max) 2023-10-02T22:16:57.205Z,1696285017.205 [DAT](INFO): set transmit power to 8 2023-10-02T22:16:57.205Z,1696285017.205 [DAT](INFO): setting local address to 1 2023-10-02T22:16:57.455Z,1696285017.455 [DAT](INFO): DAT read: user:4> 2023-10-02T22:16:57.456Z,1696285017.456 [DAT](INFO): DAT read: LocalAddr | 1 2023-10-02T22:16:57.457Z,1696285017.457 [DAT](INFO): set local address to 1 2023-10-02T22:16:57.458Z,1696285017.458 [DAT](INFO): Setting time to: 22:16:57 And date to:10/2/2023 2023-10-02T22:16:57.707Z,1696285017.707 [DAT](INFO): DAT read: user:5> 2023-10-02T22:16:57.708Z,1696285017.708 [DAT](INFO): DAT read: Mon Oct 2, 2023 22:16:57 2023-10-02T22:16:57.708Z,1696285017.708 [DAT](INFO): Local DAT time set to Mon Oct 2, 2023 22:16:57 2023-10-02T22:16:57.709Z,1696285017.709 [DAT](INFO): DAT read: Ok 2023-10-02T22:16:57.709Z,1696285017.709 [DAT](INFO): unknown deviceResponse_: Ok 2023-10-02T22:17:10.220Z,1696285030.220 [NAL9602](INFO): Powering up NAL9602 2023-10-02T22:17:20.421Z,1696285040.421 [CommandExec](IMPORTANT): got command strobe off 2023-10-02T22:17:20.421Z,1696285040.421 [CommandExec](IMPORTANT): Deactivating strobe 2023-10-02T22:17:21.148Z,1696285041.148 [NAL9602](INFO): NAL9602 initialized 2023-10-02T22:17:33.670Z,1696285053.670 [CommandExec](IMPORTANT): got command failComponent 2023-10-02T22:17:33.671Z,1696285053.671 [CommandExec](IMPORTANT): Failed components: 2023-10-02T22:17:33.672Z,1696285053.672 [CommandExec](IMPORTANT): No failed Components. 2023-10-02T22:17:42.662Z,1696285062.662 [SBIT](IMPORTANT): Beginning Startup BIT 2023-10-02T22:17:42.666Z,1696285062.666 [CBIT](IMPORTANT): Beginning ground fault scan 2023-10-02T22:17:50.128Z,1696285070.128 [Waterlinked](ERROR): only read 3 of 5 data items 2023-10-02T22:17:51.473Z,1696285071.473 [CommandExec](IMPORTANT): got command get Onboard.Pressure 2023-10-02T22:17:51.473Z,1696285071.473 [CommandExec](IMPORTANT): Onboard.Pressure 69831.765625 Pa 2023-10-02T22:17:54.141Z,1696285074.141 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.000222 CHAN A1 (24V): -0.000491 CHAN A2 (12V): -0.000037 CHAN A3 (5V): -0.001957 CHAN B0 (3.3V): 0.000314 CHAN B1 (3.15aV): -0.000079 CHAN B2 (3.15bV): 0.000451 CHAN B3 (GND): 0.000229 OPEN: 0.006227 Full Scale: +/- 1 mA 2023-10-02T22:18:08.962Z,1696285088.962 [CommandExec](IMPORTANT): got command get Onboard.Humidity 2023-10-02T22:18:08.963Z,1696285088.963 [CommandExec](IMPORTANT): Onboard.Humidity 16.866072 % 2023-10-02T22:18:20.147Z,1696285100.147 [Waterlinked](INFO): Powering down 2023-10-02T22:18:36.663Z,1696285116.663 [SBIT](IMPORTANT): SBIT PASSED 2023-10-02T22:18:36.663Z,1696285116.663 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2023-10-02T22:18:36.664Z,1696285116.664 [SBIT](IMPORTANT): BackseatComponent.loadAtStartup=1 bool; 2023-10-02T22:18:36.664Z,1696285116.664 [SBIT](IMPORTANT): BackseatComponent.needs24v=1 bool; 2023-10-02T22:18:36.664Z,1696285116.664 [SBIT](IMPORTANT): CBIT.gf24Offset=158.5 microampere; 2023-10-02T22:18:36.665Z,1696285116.665 [SBIT](IMPORTANT): PowerOnly.sampleTime1=1 second; 2023-10-02T22:18:36.665Z,1696285116.665 [SBIT](IMPORTANT): Rowe_600.loadAtStartup=0 bool; 2023-10-02T22:18:36.665Z,1696285116.665 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=159.679458 cubic_centimeter; 2023-10-02T22:18:36.665Z,1696285116.665 [SBIT](IMPORTANT): VerticalControl.massDefault=10.000082 millimeter; 2023-10-02T22:18:37.057Z,1696285117.057 [MissionManager](IMPORTANT): Started mission Startup 2023-10-02T22:18:37.058Z,1696285117.058 [Startup] Running Loop=1 2023-10-02T22:18:37.058Z,1696285117.058 [Startup](DEBUG): Aggregate::initialize Startup 2023-10-02T22:18:37.058Z,1696285117.058 [Startup:A.GoToSurface] Running Loop=1 2023-10-02T22:18:37.058Z,1696285117.058 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-10-02T22:18:37.059Z,1696285117.059 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-10-02T22:18:37.059Z,1696285117.059 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-10-02T22:18:37.060Z,1696285117.060 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-10-02T22:18:37.060Z,1696285117.060 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-10-02T22:18:37.060Z,1696285117.060 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-10-02T22:18:37.062Z,1696285117.062 [Startup:StartupSatComms] Running Loop=1 2023-10-02T22:18:37.062Z,1696285117.062 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2023-10-02T22:18:37.062Z,1696285117.062 [Startup:StartupSatComms:A] Running Loop=1 2023-10-02T22:18:37.508Z,1696285117.508 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2023-10-02T22:19:15.102Z,1696285155.102 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005617 2023-10-02T22:19:37.247Z,1696285177.247 [Startup:StartupSatComms:A](INFO): Timed out from 2023-10-02T22:18:37.1Z 2023-10-02T22:19:37.247Z,1696285177.247 [Startup:StartupSatComms:A] Stopped 2023-10-02T22:19:37.247Z,1696285177.247 [Startup:StartupSatComms:B] Running Loop=1 2023-10-02T22:19:37.656Z,1696285177.656 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2023-10-02T22:19:42.499Z,1696285182.499 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2023-10-02T22:19:42.499Z,1696285182.499 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-02T22:19:42.500Z,1696285182.500 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2023-10-02T22:19:42.501Z,1696285182.501 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-10-02T22:19:42.502Z,1696285182.502 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2023-10-02T22:19:42.502Z,1696285182.502 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-02T22:19:42.513Z,1696285182.513 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-02T22:19:42.513Z,1696285182.513 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-10-02T22:19:42.513Z,1696285182.513 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-02T22:19:42.919Z,1696285182.919 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-02T22:19:42.919Z,1696285182.919 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2023-10-02T22:19:42.919Z,1696285182.919 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-10-02T22:19:42.920Z,1696285182.920 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2023-10-02T22:19:42.920Z,1696285182.920 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-02T22:19:42.920Z,1696285182.920 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2023-10-02T22:19:43.316Z,1696285183.316 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-02T22:20:09.319Z,1696285209.319 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-02T22:20:37.447Z,1696285237.447 [Startup:StartupSatComms:B](INFO): Timed out from 2023-10-02T22:19:37.2Z 2023-10-02T22:20:37.447Z,1696285237.447 [Startup:StartupSatComms:B] Stopped 2023-10-02T22:20:37.447Z,1696285237.447 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2023-10-02T22:20:37.447Z,1696285237.447 [Startup:StartupSatComms] Stopped 2023-10-02T22:20:37.447Z,1696285237.447 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2023-10-02T22:20:37.448Z,1696285237.448 [Startup](INFO): Completed Startup 2023-10-02T22:20:37.448Z,1696285237.448 [MissionManager](INFO): Startup is completed. 2023-10-02T22:20:37.448Z,1696285237.448 [MissionManager](INFO): Uninitializing Mission Startup 2023-10-02T22:20:37.448Z,1696285237.448 [Startup] Stopped 2023-10-02T22:20:37.448Z,1696285237.448 [Startup](DEBUG): Aggregate::uninitialize Startup 2023-10-02T22:20:37.448Z,1696285237.448 [Startup:A.GoToSurface] Stopped 2023-10-02T22:20:37.449Z,1696285237.449 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-10-02T22:20:37.851Z,1696285237.851 [MissionManager](IMPORTANT): Started mission Default 2023-10-02T22:20:37.851Z,1696285237.851 [Default] Running Loop=1 2023-10-02T22:20:37.851Z,1696285237.851 [Default](DEBUG): Aggregate::initialize Default 2023-10-02T22:20:37.851Z,1696285237.851 [Default:B.GoToSurface] Running Loop=1 2023-10-02T22:20:37.851Z,1696285237.851 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-10-02T22:20:37.851Z,1696285237.851 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-10-02T22:20:37.852Z,1696285237.852 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-10-02T22:20:37.852Z,1696285237.852 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-10-02T22:20:37.852Z,1696285237.852 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-10-02T22:20:37.852Z,1696285237.852 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-10-02T22:20:37.853Z,1696285237.853 [Default:A.Wait] Running Loop=1 2023-10-02T22:20:37.853Z,1696285237.853 [Default:A.Wait](DEBUG): Initialize Wait Component. 2023-10-02T22:20:40.443Z,1696285240.443 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-02T22:20:51.183Z,1696285251.183 [Default:A.Wait](INFO): Done Waiting. 2023-10-02T22:20:51.183Z,1696285251.183 [Default:A.Wait] Stopped 2023-10-02T22:20:51.183Z,1696285251.183 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2023-10-02T22:20:51.586Z,1696285251.586 [Default:CheckIn] Running Loop=1 2023-10-02T22:20:51.586Z,1696285251.586 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-02T22:20:51.586Z,1696285251.586 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-02T22:20:51.997Z,1696285251.997 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2023-10-02T22:21:11.583Z,1696285271.583 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-02T22:21:42.708Z,1696285302.708 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-02T22:22:13.831Z,1696285333.831 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-02T22:22:15.839Z,1696285335.839 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.743073 2023-10-02T22:22:24.490Z,1696285344.490 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-10-02T22:22:43.527Z,1696285363.527 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2023-10-02T22:22:43.527Z,1696285363.527 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-02T22:22:43.528Z,1696285363.528 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2 2023-10-02T22:22:43.528Z,1696285363.528 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-10-02T22:22:43.530Z,1696285363.530 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2023-10-02T22:22:43.530Z,1696285363.530 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-02T22:22:43.540Z,1696285363.540 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-02T22:22:43.541Z,1696285363.541 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-10-02T22:22:43.541Z,1696285363.541 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-02T22:22:43.941Z,1696285363.941 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-02T22:22:43.941Z,1696285363.941 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2023-10-02T22:22:43.941Z,1696285363.941 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-10-02T22:22:43.941Z,1696285363.941 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2 2023-10-02T22:22:43.942Z,1696285363.942 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-02T22:22:43.942Z,1696285363.942 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2023-10-02T22:22:44.311Z,1696285364.311 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-02T22:23:15.075Z,1696285395.075 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-02T22:23:46.207Z,1696285426.207 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-02T22:24:17.347Z,1696285457.347 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-02T22:24:48.479Z,1696285488.479 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-02T22:25:19.619Z,1696285519.619 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-02T22:25:44.496Z,1696285544.496 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2023-10-02T22:25:44.496Z,1696285544.496 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-02T22:25:44.497Z,1696285544.497 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3 2023-10-02T22:25:44.497Z,1696285544.497 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-10-02T22:25:44.499Z,1696285544.499 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3 2023-10-02T22:25:44.499Z,1696285544.499 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-02T22:25:44.533Z,1696285544.533 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-02T22:25:44.533Z,1696285544.533 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-10-02T22:25:44.534Z,1696285544.534 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-02T22:25:44.902Z,1696285544.902 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-02T22:25:44.902Z,1696285544.902 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2023-10-02T22:25:44.903Z,1696285544.903 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-10-02T22:25:44.903Z,1696285544.903 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3 2023-10-02T22:25:44.903Z,1696285544.903 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-02T22:25:44.904Z,1696285544.904 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3 2023-10-02T22:25:45.301Z,1696285545.301 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-02T22:25:50.751Z,1696285550.751 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-02T22:25:51.783Z,1696285551.783 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-10-02T22:20:51.6Z 2023-10-02T22:25:51.783Z,1696285551.783 [Default:CheckIn:Read_GPS] Stopped 2023-10-02T22:25:51.783Z,1696285551.783 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-02T22:25:52.177Z,1696285552.177 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2023-10-02T22:26:21.883Z,1696285581.883 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-02T22:26:25.101Z,1696285585.101 [NAL9602](INFO): SBD MO Status=2, MOMSN=7630, MT Status=2, MTMSN=0 2023-10-02T22:26:25.102Z,1696285585.102 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-10-02T22:26:53.021Z,1696285613.021 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-02T22:27:24.155Z,1696285644.155 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-02T22:27:28.149Z,1696285648.149 [NAL9602](INFO): SBD MO Status=2, MOMSN=7630, MT Status=2, MTMSN=0 2023-10-02T22:27:28.150Z,1696285648.150 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-10-02T22:27:28.960Z,1696285648.960 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-10-02T22:27:28.960Z,1696285648.960 [NAL9602] Data Fault, FailCount= 1 2023-10-02T22:27:28.960Z,1696285648.960 [NAL9602](ERROR): Data Fault 2023-10-02T22:27:28.979Z,1696285648.979 [CBIT](ERROR): Data Fault in component: NAL9602 2023-10-02T22:27:29.364Z,1696285649.364 [NAL9602](INFO): Powering down 2023-10-02T22:27:30.202Z,1696285650.202 [CBIT](INFO): Clearing failed state for component NAL9602 2023-10-02T22:27:30.202Z,1696285650.202 [NAL9602] No Fault, FailCount= 1 2023-10-02T22:27:55.287Z,1696285675.287 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-02T22:27:59.658Z,1696285679.658 [NAL9602](INFO): Powering up NAL9602 2023-10-02T22:28:10.573Z,1696285690.573 [NAL9602](INFO): NAL9602 initialized 2023-10-02T22:28:26.428Z,1696285706.428 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-02T22:28:47.113Z,1696285727.113 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2023-10-02T22:28:47.113Z,1696285727.113 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-02T22:28:47.114Z,1696285727.114 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4 2023-10-02T22:28:47.114Z,1696285727.114 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-10-02T22:28:47.116Z,1696285727.116 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 4 2023-10-02T22:28:47.116Z,1696285727.116 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-02T22:28:47.126Z,1696285727.126 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-02T22:28:47.127Z,1696285727.127 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-10-02T22:28:47.127Z,1696285727.127 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-02T22:28:47.213Z,1696285727.213 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-02T22:28:47.213Z,1696285727.213 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2023-10-02T22:28:47.214Z,1696285727.214 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-10-02T22:28:47.214Z,1696285727.214 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4 2023-10-02T22:28:47.214Z,1696285727.214 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-02T22:28:47.215Z,1696285727.215 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 4 2023-10-02T22:28:47.566Z,1696285727.566 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-02T22:28:57.555Z,1696285737.555 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-02T22:29:12.598Z,1696285752.598 [NAL9602](INFO): SBD MO Status=2, MOMSN=7630, MT Status=2, MTMSN=0 2023-10-02T22:29:12.598Z,1696285752.598 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-10-02T22:29:28.691Z,1696285768.691 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-02T22:29:31.373Z,1696285771.373 [NAL9602](INFO): SBD MO Status=1, MOMSN=7630, MT Status=0, MTMSN=0 2023-10-02T22:29:31.423Z,1696285771.423 [NAL9602](INFO): Sent 39 bytes from file Logs/20231002T215730/Courier0007.lzma 2023-10-02T22:29:31.423Z,1696285771.423 [NAL9602](INFO): Packets left to send: 0 2023-10-02T22:29:43.639Z,1696285783.639 [NAL9602](INFO): SBD MO Status=2, MOMSN=7631, MT Status=2, MTMSN=0 2023-10-02T22:29:43.640Z,1696285783.640 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-10-02T22:29:59.815Z,1696285799.815 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-02T22:30:02.410Z,1696285802.410 [NAL9602](INFO): SBD MO Status=1, MOMSN=7631, MT Status=0, MTMSN=0 2023-10-02T22:30:02.467Z,1696285802.467 [NAL9602](INFO): Sent 151 bytes from file Logs/20231002T221631/Courier0000.lzma 2023-10-02T22:30:02.467Z,1696285802.467 [NAL9602](INFO): Packets left to send: 0 2023-10-02T22:30:16.730Z,1696285816.730 [NAL9602](INFO): SBD MO Status=1, MOMSN=7632, MT Status=0, MTMSN=0 2023-10-02T22:30:16.780Z,1696285816.780 [NAL9602](INFO): Sent 39 bytes from file Logs/20231002T221631/Courier0004.lzma 2023-10-02T22:30:16.780Z,1696285816.780 [NAL9602](INFO): Packets left to send: 0 2023-10-02T22:30:25.701Z,1696285825.701 [NAL9602](INFO): SBD MO Status=1, MOMSN=7633, MT Status=0, MTMSN=0 2023-10-02T22:30:25.751Z,1696285825.751 [NAL9602](INFO): Sent 167 bytes from file Logs/20231002T215730/Express0008.lzma 2023-10-02T22:30:25.751Z,1696285825.751 [NAL9602](INFO): Packets left to send: 0 2023-10-02T22:30:30.939Z,1696285830.939 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-02T22:30:57.674Z,1696285857.674 [NAL9602](INFO): SBD MO Status=2, MOMSN=7634, MT Status=0, MTMSN=0 2023-10-02T22:30:57.674Z,1696285857.674 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-10-02T22:31:02.063Z,1696285862.063 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-02T22:31:19.946Z,1696285879.946 [NAL9602](INFO): SBD MO Status=1, MOMSN=7634, MT Status=0, MTMSN=0 2023-10-02T22:31:19.996Z,1696285879.996 [NAL9602](INFO): Sent 332 bytes from file Logs/20231002T221631/Express0001.lzma 2023-10-02T22:31:19.996Z,1696285879.996 [NAL9602](INFO): Packets left to send: 2 2023-10-02T22:31:33.187Z,1696285893.187 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-02T22:31:36.573Z,1696285896.573 [NAL9602](INFO): SBD MO Status=2, MOMSN=7635, MT Status=2, MTMSN=0 2023-10-02T22:31:36.574Z,1696285896.574 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-10-02T22:31:47.908Z,1696285907.908 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2023-10-02T22:31:47.908Z,1696285907.908 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-02T22:31:47.909Z,1696285907.909 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5 2023-10-02T22:31:47.909Z,1696285907.909 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-10-02T22:31:47.911Z,1696285907.911 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 5 2023-10-02T22:31:47.911Z,1696285907.911 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-02T22:31:47.925Z,1696285907.925 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-02T22:31:47.926Z,1696285907.926 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-10-02T22:31:47.926Z,1696285907.926 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-02T22:31:48.326Z,1696285908.326 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-02T22:31:48.327Z,1696285908.327 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2023-10-02T22:31:48.328Z,1696285908.328 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-10-02T22:31:48.328Z,1696285908.328 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5 2023-10-02T22:31:48.328Z,1696285908.328 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-02T22:31:48.329Z,1696285908.329 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 5 2023-10-02T22:31:48.725Z,1696285908.725 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-02T22:31:59.697Z,1696285919.697 [NAL9602](INFO): SBD MO Status=2, MOMSN=7635, MT Status=2, MTMSN=0 2023-10-02T22:31:59.698Z,1696285919.698 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-10-02T22:32:04.319Z,1696285924.319 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-02T22:32:35.443Z,1696285955.443 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-02T22:33:06.567Z,1696285986.567 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-02T22:33:37.691Z,1696286017.691 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-02T22:34:08.815Z,1696286048.815 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-02T22:34:39.939Z,1696286079.939 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-02T22:34:48.920Z,1696286088.920 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2023-10-02T22:34:48.920Z,1696286088.920 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-02T22:34:48.921Z,1696286088.921 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6 2023-10-02T22:34:48.921Z,1696286088.921 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-10-02T22:34:48.923Z,1696286088.923 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 6 2023-10-02T22:34:48.923Z,1696286088.923 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-02T22:34:48.933Z,1696286088.933 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-02T22:34:48.934Z,1696286088.934 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-10-02T22:34:48.934Z,1696286088.934 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-02T22:34:49.344Z,1696286089.344 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-02T22:34:49.344Z,1696286089.344 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2023-10-02T22:34:49.345Z,1696286089.345 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-10-02T22:34:49.345Z,1696286089.345 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6 2023-10-02T22:34:49.345Z,1696286089.345 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-02T22:34:49.345Z,1696286089.345 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 6 2023-10-02T22:34:49.727Z,1696286089.727 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-02T22:35:11.071Z,1696286111.071 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-02T22:35:11.977Z,1696286111.977 [NAL9602](INFO): SBD MO Status=2, MOMSN=7635, MT Status=2, MTMSN=0 2023-10-02T22:35:11.977Z,1696286111.977 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-10-02T22:35:42.207Z,1696286142.207 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-02T22:36:13.347Z,1696286173.347 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-02T22:36:13.959Z,1696286173.959 [NAL9602](INFO): SBD MO Status=2, MOMSN=7635, MT Status=2, MTMSN=0 2023-10-02T22:36:13.959Z,1696286173.959 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-10-02T22:36:44.475Z,1696286204.475 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-02T22:37:12.799Z,1696286232.799 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003434 2023-10-02T22:37:17.747Z,1696286237.747 [NAL9602](INFO): SBD MO Status=2, MOMSN=7635, MT Status=2, MTMSN=0 2023-10-02T22:37:17.748Z,1696286237.748 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-10-02T22:37:21.072Z,1696286241.072 [DataOverHttps](INFO): Sending 915 bytes from file Logs/20231002T221631/Express0001.lzma 2023-10-02T22:37:22.073Z,1696286242.073 [DataOverHttps](INFO): Moved sent file to Logs/20231002T221631/Express0001.lzma.bak 2023-10-02T22:37:22.073Z,1696286242.073 [DataOverHttps](IMPORTANT): SBD MOMSN=18977137, MTMSN=20231002T223714 2023-10-02T22:37:30.427Z,1696286250.427 [DataOverHttps](INFO): Received command: run Maintenance/tank_ballast_and_trim.tl 2023-10-02T22:37:30.482Z,1696286250.482 [CommandExec](IMPORTANT): got command run ./Missions/Maintenance/tank_ballast_and_trim.tl 2023-10-02T22:37:30.483Z,1696286250.483 [MissionManager](INFO): Loading Mission from file: ./Missions/Maintenance/tank_ballast_and_trim.tl 2023-10-02T22:37:30.483Z,1696286250.483 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file ./Missions/Maintenance/tank_ballast_and_trim.tx 2023-10-02T22:37:30.552Z,1696286250.552 [MissionManager](INFO): DefineArg ballast_and_trim.MissionTimeout = 120.000000 min 2023-10-02T22:37:30.555Z,1696286250.555 [MissionManager](INFO): DefineArg ballast_and_trim.SkipComms = 0 bool 2023-10-02T22:37:30.557Z,1696286250.557 [MissionManager](INFO): DefineArg ballast_and_trim.Depth1 = 7.000000 m 2023-10-02T22:37:30.560Z,1696286250.560 [MissionManager](INFO): DefineArg ballast_and_trim.Depth2 = nan m 2023-10-02T22:37:30.563Z,1696286250.563 [MissionManager](INFO): DefineArg ballast_and_trim.DepthDeadband = 0.100000 m 2023-10-02T22:37:30.566Z,1696286250.566 [MissionManager](INFO): DefineArg ballast_and_trim.SurfaceThreshold = 2.500000 m 2023-10-02T22:37:30.569Z,1696286250.569 [MissionManager](INFO): DefineArg ballast_and_trim.MaxWaitOnSurface = 20.000000 min 2023-10-02T22:37:30.572Z,1696286250.572 [MissionManager](INFO): DefineArg ballast_and_trim.SettleTime = 10.000000 min 2023-10-02T22:37:30.574Z,1696286250.574 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationTimeout = 45.000000 min 2023-10-02T22:37:30.577Z,1696286250.577 [MissionManager](INFO): DefineArg ballast_and_trim.MinEstimationTime = 10.000000 min 2023-10-02T22:37:30.580Z,1696286250.580 [MissionManager](INFO): DefineArg ballast_and_trim.MassEstimationErrorBound = 0.250000 mm 2023-10-02T22:37:30.583Z,1696286250.583 [MissionManager](INFO): DefineArg ballast_and_trim.BuoyEstimationErrorBound = 15.000000 cc 2023-10-02T22:37:30.585Z,1696286250.585 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationConfidence = 99.900002 % 2023-10-02T22:37:30.588Z,1696286250.588 [MissionManager](INFO): DefineArg ballast_and_trim.WaitToFloat = 5.000000 min 2023-10-02T22:37:30.589Z,1696286250.589 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/Science.tl 2023-10-02T22:37:30.589Z,1696286250.589 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/Science.tx 2023-10-02T22:37:31.024Z,1696286251.024 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectChlActive = 0 bool 2023-10-02T22:37:31.027Z,1696286251.027 [MissionManager](INFO): DefineArg ballast_and_trim:Science.TimeWindowPeakReport = nan min 2023-10-02T22:37:31.030Z,1696286251.030 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestChlPeakReportActive = 0 bool 2023-10-02T22:37:31.032Z,1696286251.032 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestSaltPeakReportActive = 0 bool 2023-10-02T22:37:31.035Z,1696286251.035 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestOilPeakReportActive = 0 bool 2023-10-02T22:37:31.037Z,1696286251.037 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PatchTracking = 0 bool 2023-10-02T22:37:31.084Z,1696286251.084 [MissionManager](INFO): DefineArg ballast_and_trim:Science.DetectTrough = 0 bool 2023-10-02T22:37:31.096Z,1696286251.096 [MissionManager](INFO): DefineArg ballast_and_trim:Science.FilterWidthHorizontal = 3.000000 count 2023-10-02T22:37:31.112Z,1696286251.112 [MissionManager](INFO): DefineArg ballast_and_trim:Science.NumProfilesSlidingwindow = 100.000000 count 2023-10-02T22:37:31.117Z,1696286251.117 [MissionManager](INFO): DefineArg ballast_and_trim:Science.OffPeakFractionHorizontal = 80.000000 % 2023-10-02T22:37:31.129Z,1696286251.129 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectNO3Active = 0 bool 2023-10-02T22:37:31.132Z,1696286251.132 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectOilActive = 0 bool 2023-10-02T22:37:31.147Z,1696286251.147 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectFDOMActive = 0 bool 2023-10-02T22:37:31.150Z,1696286251.150 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectSalinityActive = 0 bool 2023-10-02T22:37:31.154Z,1696286251.154 [MissionManager](INFO): DefineArg ballast_and_trim:Science.UpwardDerivativeOfTemperatureActive = 0 bool 2023-10-02T22:37:31.166Z,1696286251.166 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledAanderaaO2 = 0 bool 2023-10-02T22:37:31.174Z,1696286251.174 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledNeilBrown = 0 bool 2023-10-02T22:37:31.181Z,1696286251.181 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledSeabird = 1 bool 2023-10-02T22:37:31.189Z,1696286251.189 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsBB2FL = 1 bool 2023-10-02T22:37:31.192Z,1696286251.192 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsSeaOWL_UV_A = 0 bool 2023-10-02T22:37:31.205Z,1696286251.205 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsUBAT = 0 bool 2023-10-02T22:37:31.208Z,1696286251.208 [MissionManager](INFO): DefineArg ballast_and_trim:Science.LowPassWindowLength = 20.000000 count 2023-10-02T22:37:31.219Z,1696286251.219 [MissionManager](INFO): DefineArg ballast_and_trim:Science.MedianFilterLen = 5.000000 count 2023-10-02T22:37:31.222Z,1696286251.222 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakShallowBound = nan m 2023-10-02T22:37:31.230Z,1696286251.230 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDeepBound = nan m 2023-10-02T22:37:31.237Z,1696286251.237 [MissionManager](INFO): DefineArg ballast_and_trim:Science.DepChangeThreshForAttitudeFlip = 2.000000 m 2023-10-02T22:37:31.244Z,1696286251.244 [MissionManager](INFO): DefineArg ballast_and_trim:Science.ChlThreshTriggerActive = 0 bool 2023-10-02T22:37:31.251Z,1696286251.251 [MissionManager](INFO): DefineArg ballast_and_trim:Science.ChlLowerThresh = nan ug/l 2023-10-02T22:37:31.254Z,1696286251.254 [MissionManager](INFO): DefineArg ballast_and_trim:Science.ChlUpperThresh = nan ug/l 2023-10-02T22:37:31.261Z,1696286251.261 [MissionManager](INFO): DefineArg ballast_and_trim:Science.NumProfilesThresh = 5.000000 count 2023-10-02T22:37:31.264Z,1696286251.264 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChl = nan ug/l 2023-10-02T22:37:31.280Z,1696286251.280 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlDepth = nan m 2023-10-02T22:37:31.287Z,1696286251.287 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlTemperature = nan degC 2023-10-02T22:37:31.290Z,1696286251.290 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLatitude = nan arcdeg 2023-10-02T22:37:31.301Z,1696286251.301 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLongitude = nan arcdeg 2023-10-02T22:37:31.304Z,1696286251.304 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3 = nan umol/l 2023-10-02T22:37:31.316Z,1696286251.316 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Depth = nan m 2023-10-02T22:37:31.323Z,1696286251.323 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Latitude = nan arcdeg 2023-10-02T22:37:31.326Z,1696286251.326 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Longitude = nan arcdeg 2023-10-02T22:37:31.333Z,1696286251.333 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOil = nan kg/m3 2023-10-02T22:37:31.340Z,1696286251.340 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilDepth = nan m 2023-10-02T22:37:31.345Z,1696286251.345 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLatitude = nan arcdeg 2023-10-02T22:37:31.360Z,1696286251.360 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLongitude = nan arcdeg 2023-10-02T22:37:31.367Z,1696286251.367 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOM = nan ppb 2023-10-02T22:37:31.370Z,1696286251.370 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMDepth = nan m 2023-10-02T22:37:31.377Z,1696286251.377 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMTemperature = nan degC 2023-10-02T22:37:31.381Z,1696286251.381 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMLatitude = nan arcdeg 2023-10-02T22:37:31.396Z,1696286251.396 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMLongitude = nan arcdeg 2023-10-02T22:37:31.408Z,1696286251.408 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchFDOMDistance = nan m 2023-10-02T22:37:31.418Z,1696286251.418 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSalt = nan psu 2023-10-02T22:37:31.453Z,1696286251.453 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltDepth = nan m 2023-10-02T22:37:31.500Z,1696286251.500 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltTemperature = nan degC 2023-10-02T22:37:31.508Z,1696286251.508 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltLatitude = nan arcdeg 2023-10-02T22:37:31.527Z,1696286251.527 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltLongitude = nan arcdeg 2023-10-02T22:37:31.530Z,1696286251.530 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChl = nan ug/l 2023-10-02T22:37:31.550Z,1696286251.550 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDepth = nan m 2023-10-02T22:37:31.563Z,1696286251.563 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLatitude = nan arcdeg 2023-10-02T22:37:31.566Z,1696286251.566 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLongitude = nan arcdeg 2023-10-02T22:37:31.578Z,1696286251.578 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDistance = nan m 2023-10-02T22:37:31.593Z,1696286251.593 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSalt = nan psu 2023-10-02T22:37:31.601Z,1696286251.601 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltDepth = nan m 2023-10-02T22:37:31.624Z,1696286251.624 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltLatitude = nan arcdeg 2023-10-02T22:37:31.645Z,1696286251.645 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltLongitude = nan arcdeg 2023-10-02T22:37:31.653Z,1696286251.653 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltDistance = nan m 2023-10-02T22:37:31.660Z,1696286251.660 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOil = nan psu 2023-10-02T22:37:31.680Z,1696286251.680 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilDepth = nan m 2023-10-02T22:37:31.691Z,1696286251.691 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilLatitude = nan arcdeg 2023-10-02T22:37:31.694Z,1696286251.694 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilLongitude = nan arcdeg 2023-10-02T22:37:31.705Z,1696286251.705 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilDistance = nan m 2023-10-02T22:37:31.712Z,1696286251.712 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.TriggeredOnChl = 0 bool 2023-10-02T22:37:31.732Z,1696286251.732 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.ChlTriggering = nan ug/l 2023-10-02T22:37:31.827Z,1696286251.827 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2023-10-02T22:37:31.951Z,1696286251.951 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal. 2023-10-02T22:37:31.973Z,1696286251.973 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2023-10-02T22:37:32.008Z,1696286252.008 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2023-10-02T22:37:32.039Z,1696286252.039 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal. 2023-10-02T22:37:32.064Z,1696286252.064 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2023-10-02T22:37:32.093Z,1696286252.093 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2023-10-02T22:37:32.109Z,1696286252.109 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal. 2023-10-02T22:37:32.138Z,1696286252.138 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect](DEBUG): Construct ValueDetect. 2023-10-02T22:37:32.165Z,1696286252.165 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl 2023-10-02T22:37:32.165Z,1696286252.165 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/BackseatDriver.tx 2023-10-02T22:37:32.194Z,1696286252.194 [MissionManager](INFO): DefineArg ballast_and_trim:BackseatDriver.EnableBackseat = 0 bool 2023-10-02T22:37:32.201Z,1696286252.201 [ballast_and_trim:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver. 2023-10-02T22:37:32.217Z,1696286252.217 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BallastAndTrim.tl 2023-10-02T22:37:32.217Z,1696286252.217 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/BallastAndTrim.tx 2023-10-02T22:37:32.467Z,1696286252.467 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepth = 25.000000 m 2023-10-02T22:37:32.470Z,1696286252.470 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachSpeed = 0.000000 m/s 2023-10-02T22:37:32.477Z,1696286252.477 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthRate = 0.400000 m/s 2023-10-02T22:37:32.491Z,1696286252.491 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachPitchLimit = 20.000000 arcdeg 2023-10-02T22:37:32.499Z,1696286252.499 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachSettleTimePreDive = 0.000000 min 2023-10-02T22:37:32.505Z,1696286252.505 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthTimeout = 20.000000 min 2023-10-02T22:37:32.509Z,1696286252.509 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertSettleTime = 10.000000 min 2023-10-02T22:37:32.512Z,1696286252.512 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstTimeout = 45.000000 min 2023-10-02T22:37:32.523Z,1696286252.523 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MinEstTime = 10.000000 min 2023-10-02T22:37:32.531Z,1696286252.531 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MassEstErrorBound = 0.250000 mm 2023-10-02T22:37:32.534Z,1696286252.534 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.BuoyEstErrorBound = 15.000000 cc 2023-10-02T22:37:32.541Z,1696286252.541 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstConfidence = 99.900002 % 2023-10-02T22:37:32.559Z,1696286252.559 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepthDeadband = 1.000000 m 2023-10-02T22:37:32.567Z,1696286252.567 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertMassDeadband = 0.250000 mm 2023-10-02T22:37:32.593Z,1696286252.593 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Construct. 2023-10-02T22:37:32.605Z,1696286252.605 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Construct Buoyancy. 2023-10-02T22:37:32.621Z,1696286252.621 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Construct. 2023-10-02T22:37:32.644Z,1696286252.644 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Construct Wait. 2023-10-02T22:37:32.657Z,1696286252.657 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Construct. 2023-10-02T22:37:32.660Z,1696286252.660 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](DEBUG): Construct. 2023-10-02T22:37:32.688Z,1696286252.688 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch:A_Timeout:B.Execute](DEBUG): Construct Execute. 2023-10-02T22:37:32.696Z,1696286252.696 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Construct. 2023-10-02T22:37:32.716Z,1696286252.716 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch](DEBUG): Construct. 2023-10-02T22:37:32.740Z,1696286252.740 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Construct Wait. 2023-10-02T22:37:32.742Z,1696286252.742 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:E.Execute](DEBUG): Construct Execute. 2023-10-02T22:37:32.764Z,1696286252.764 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](DEBUG): Construct BallastAndTrim. 2023-10-02T22:37:32.804Z,1696286252.804 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepthDeadband = reader:ballast_and_trim.DepthDeadband 2023-10-02T22:37:32.806Z,1696286252.806 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthTimeout = reader:ballast_and_trim.MaxWaitOnSurface 2023-10-02T22:37:32.812Z,1696286252.812 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertSettleTime = reader:ballast_and_trim.SettleTime 2023-10-02T22:37:32.814Z,1696286252.814 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstTimeout = reader:ballast_and_trim.EstimationTimeout 2023-10-02T22:37:32.820Z,1696286252.820 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MinEstTime = reader:ballast_and_trim.MinEstimationTime 2023-10-02T22:37:32.822Z,1696286252.822 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MassEstErrorBound = reader:ballast_and_trim.MassEstimationErrorBound 2023-10-02T22:37:32.827Z,1696286252.827 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.BuoyEstErrorBound = reader:ballast_and_trim.BuoyEstimationErrorBound 2023-10-02T22:37:32.829Z,1696286252.829 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstConfidence = reader:ballast_and_trim.EstimationConfidence 2023-10-02T22:37:32.850Z,1696286252.850 [ballast_and_trim:Float_Up:A.Buoyancy](DEBUG): Construct Buoyancy. 2023-10-02T22:37:32.863Z,1696286252.863 [ballast_and_trim:Float_Up:B.Wait](DEBUG): Construct Wait. 2023-10-02T22:37:32.913Z,1696286252.913 [MissionManager](DEBUG): mission ballast_and_trim { arguments { MissionTimeout = 120 minute """ Maximum duration of mission """ SkipComms = false """ Skip communications at start of mission. """ Depth1 = 7 meter Depth2 = NaN meter DepthDeadband = 0.1 meter """ How much vertical drift from the specified depth is allowed durnig the mission. """ SurfaceThreshold = 2.5 meter """ Surface threshold depth. """ MaxWaitOnSurface = 20 minute """ Maximum wait time for the vehicle to go under the surface (i.e., reach the surface threshold depth). """ SettleTime = 10 minute """ How long to wait after reaching target depth before starting ballast and trim. """ EstimationTimeout = 45 minute """ Timeout for ballast and trim estimation. """ MinEstimationTime = 10 minute """ Minimum time to run ballast and trim estimation before determining convergence (must be shorter than timeout). """ MassEstimationErrorBound = 0.25 millimeter """ Desired range of the estimated sample mean from the true mean statistic (smaller values take longer to converge). """ BuoyEstimationErrorBound = 15 cubic_centimeter """ Desired range of the estimated sample mean from the true mean statistic (smaller values take longer to converge). """ EstimationConfidence = 99.9 percent """ Confidence level that the estimator has reached the desired error range (larger values take longer to converge). """ WaitToFloat = 5 minute } timeout duration=MissionTimeout insert Insert/Science.tl insert Insert/BackseatDriver.tl aggregate RunBallastAndTrim { run in sequence insert Insert/BallastAndTrim.tl { redefineArg InsertDepthDeadband = DepthDeadband redefineArg InsertApproachDepthTimeout = MaxWaitOnSurface redefineArg InsertSettleTime = SettleTime redefineArg EstTimeout = EstimationTimeout redefineArg MinEstTime = MinEstimationTime redefineArg MassEstErrorBound = MassEstimationErrorBound redefineArg BuoyEstErrorBound = BuoyEstimationErrorBound redefineArg EstConfidence = EstimationConfidence } aggregate Depth1 { run in sequence break if ( isNaN ( Depth1 ) ) assign in sequence BallastAndTrim:InsertDepth = Depth1 call refId="BallastAndTrim" } aggregate Depth2 { run in sequence break if ( isNaN ( Depth2 ) ) assign in sequence BallastAndTrim:InsertDepth = Depth1 call refId="BallastAndTrim" } } aggregate Float_Up { """ Float to surface instead of driving. """ run in sequence break if ( Universal:depth < Control:VerticalControl.surfaceThreshold ) behavior Guidance:Buoyancy { run in parallel set position = Control:VerticalControl.buoyancyDefault } behavior Guidance:Wait { run in sequence set duration = WaitToFloat } } } 2023-10-02T22:37:32.917Z,1696286252.917 [CommandExec](IMPORTANT): Running ./Missions/Maintenance/tank_ballast_and_trim.tl 2023-10-02T22:37:33.125Z,1696286253.125 [Default] Stopped 2023-10-02T22:37:33.125Z,1696286253.125 [Default](DEBUG): Aggregate::uninitialize Default 2023-10-02T22:37:33.125Z,1696286253.125 [Default:B.GoToSurface] Stopped 2023-10-02T22:37:33.125Z,1696286253.125 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-10-02T22:37:33.125Z,1696286253.125 [Default:CheckIn] Stopped 2023-10-02T22:37:33.126Z,1696286253.126 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-02T22:37:33.126Z,1696286253.126 [Default:CheckIn:Read_Iridium] Stopped 2023-10-02T22:37:33.126Z,1696286253.126 [MissionManager](IMPORTANT): Started mission ballast_and_trim 2023-10-02T22:37:33.126Z,1696286253.126 [ballast_and_trim] Running Loop=1 2023-10-02T22:37:33.126Z,1696286253.126 [ballast_and_trim](DEBUG): Aggregate::initialize ballast_and_trim 2023-10-02T22:37:33.126Z,1696286253.126 [ballast_and_trim:Science] Running Loop=1 2023-10-02T22:37:33.131Z,1696286253.131 [ballast_and_trim:Science](DEBUG): Aggregate::initialize ballast_and_trim:Science 2023-10-02T22:37:33.131Z,1696286253.131 [ballast_and_trim:Science:A] Running Loop=1 2023-10-02T22:37:33.131Z,1696286253.131 [ballast_and_trim:Science:B] Running Loop=1 2023-10-02T22:37:33.131Z,1696286253.131 [ballast_and_trim:Science:C] Running Loop=1 2023-10-02T22:37:33.131Z,1696286253.131 [ballast_and_trim:Science:D] Running Loop=1 2023-10-02T22:37:33.131Z,1696286253.131 [ballast_and_trim:Science:E] Running Loop=1 2023-10-02T22:37:33.131Z,1696286253.131 [ballast_and_trim:Science:F] Running Loop=1 2023-10-02T22:37:33.131Z,1696286253.131 [ballast_and_trim:Science:Read_Oil] Running Loop=1 2023-10-02T22:37:33.131Z,1696286253.131 [ballast_and_trim:Science:PeakDetectChl] Running Loop=1 2023-10-02T22:37:33.131Z,1696286253.131 [ballast_and_trim:Science:PeakDetectChl](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectChl 2023-10-02T22:37:33.131Z,1696286253.131 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1 2023-10-02T22:37:33.131Z,1696286253.131 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize. 2023-10-02T22:37:33.133Z,1696286253.133 [ballast_and_trim:Science:HighestChlPeakReport] Running Loop=1 2023-10-02T22:37:33.134Z,1696286253.134 [ballast_and_trim:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestChlPeakReport 2023-10-02T22:37:33.134Z,1696286253.134 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1 2023-10-02T22:37:33.134Z,1696286253.134 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2023-10-02T22:37:33.134Z,1696286253.134 [ballast_and_trim:Science:PeakDetectNO3] Running Loop=1 2023-10-02T22:37:33.143Z,1696286253.143 [ballast_and_trim:Science:PeakDetectNO3](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectNO3 2023-10-02T22:37:33.143Z,1696286253.143 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1 2023-10-02T22:37:33.143Z,1696286253.143 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize. 2023-10-02T22:37:33.145Z,1696286253.145 [ballast_and_trim:Science:PeakDetectOil] Running Loop=1 2023-10-02T22:37:33.145Z,1696286253.145 [ballast_and_trim:Science:PeakDetectOil](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectOil 2023-10-02T22:37:33.145Z,1696286253.145 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1 2023-10-02T22:37:33.145Z,1696286253.145 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize. 2023-10-02T22:37:33.152Z,1696286253.152 [ballast_and_trim:Science:HighestOilPeakReport] Running Loop=1 2023-10-02T22:37:33.152Z,1696286253.152 [ballast_and_trim:Science:HighestOilPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestOilPeakReport 2023-10-02T22:37:33.152Z,1696286253.152 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Running Loop=1 2023-10-02T22:37:33.153Z,1696286253.153 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2023-10-02T22:37:33.153Z,1696286253.153 [ballast_and_trim:Science:PeakDetectFDOM] Running Loop=1 2023-10-02T22:37:33.153Z,1696286253.153 [ballast_and_trim:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectFDOM 2023-10-02T22:37:33.153Z,1696286253.153 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1 2023-10-02T22:37:33.153Z,1696286253.153 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize. 2023-10-02T22:37:33.156Z,1696286253.156 [ballast_and_trim:Science:PeakDetectSalinity] Running Loop=1 2023-10-02T22:37:33.156Z,1696286253.156 [ballast_and_trim:Science:PeakDetectSalinity](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectSalinity 2023-10-02T22:37:33.156Z,1696286253.156 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Running Loop=1 2023-10-02T22:37:33.156Z,1696286253.156 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Initialize. 2023-10-02T22:37:33.164Z,1696286253.164 [ballast_and_trim:Science:HighestSaltPeakReport] Running Loop=1 2023-10-02T22:37:33.165Z,1696286253.165 [ballast_and_trim:Science:HighestSaltPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestSaltPeakReport 2023-10-02T22:37:33.165Z,1696286253.165 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Running Loop=1 2023-10-02T22:37:33.165Z,1696286253.165 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2023-10-02T22:37:33.166Z,1696286253.166 [ballast_and_trim:Science:ChlThreshTrigger] Running Loop=1 2023-10-02T22:37:33.166Z,1696286253.166 [ballast_and_trim:Science:ChlThreshTrigger](DEBUG): Aggregate::initialize ballast_and_trim:Science:ChlThreshTrigger 2023-10-02T22:37:33.166Z,1696286253.166 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect] Running Loop=1 2023-10-02T22:37:33.166Z,1696286253.166 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect](INFO): Initialize. 2023-10-02T22:37:33.178Z,1696286253.178 [ballast_and_trim:Science:OceanCurrent] Running Loop=1 2023-10-02T22:37:33.179Z,1696286253.179 [ballast_and_trim:Science:OceanCurrent](DEBUG): Aggregate::initialize ballast_and_trim:Science:OceanCurrent 2023-10-02T22:37:33.179Z,1696286253.179 [ballast_and_trim:Science:OceanCurrent:A.] Running Loop=1 2023-10-02T22:37:33.179Z,1696286253.179 [ballast_and_trim:Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator. 2023-10-02T22:37:33.179Z,1696286253.179 [ballast_and_trim:BackseatDriver] Running Loop=1 2023-10-02T22:37:33.179Z,1696286253.179 [ballast_and_trim:BackseatDriver](DEBUG): Aggregate::initialize ballast_and_trim:BackseatDriver 2023-10-02T22:37:33.179Z,1696286253.179 [ballast_and_trim:BackseatDriver:A.BackseatDriver] Running Loop=1 2023-10-02T22:37:33.179Z,1696286253.179 [ballast_and_trim:RunBallastAndTrim] Running Loop=1 2023-10-02T22:37:33.179Z,1696286253.179 [ballast_and_trim:RunBallastAndTrim](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim 2023-10-02T22:37:33.180Z,1696286253.180 [ballast_and_trim:RunBallastAndTrim:Depth1] Running Loop=1 2023-10-02T22:37:33.180Z,1696286253.180 [ballast_and_trim:RunBallastAndTrim:Depth1](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth1 2023-10-02T22:37:33.180Z,1696286253.180 [ballast_and_trim:RunBallastAndTrim:Depth1:A] Running Loop=1 2023-10-02T22:37:33.180Z,1696286253.180 [ballast_and_trim:RunBallastAndTrim:Depth1:A] Stopped 2023-10-02T22:37:33.180Z,1696286253.180 [ballast_and_trim:RunBallastAndTrim:Depth1:B] Running Loop=1 2023-10-02T22:37:33.181Z,1696286253.181 [ballast_and_trim:RunBallastAndTrim:Depth1:B](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth1:B 2023-10-02T22:37:33.181Z,1696286253.181 [ballast_and_trim:BackseatDriver] Running Loop=1 2023-10-02T22:37:33.181Z,1696286253.181 [ballast_and_trim:Science] Running Loop=1 2023-10-02T22:37:33.187Z,1696286253.187 [ballast_and_trim:Science:ChlThreshTrigger] Stopped 2023-10-02T22:37:33.187Z,1696286253.187 [ballast_and_trim:Science:ChlThreshTrigger](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:ChlThreshTrigger 2023-10-02T22:37:33.187Z,1696286253.187 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect] Stopped 2023-10-02T22:37:33.187Z,1696286253.187 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect](DEBUG): Uninitialize. 2023-10-02T22:37:33.187Z,1696286253.187 [ballast_and_trim:Science:HighestSaltPeakReport] Stopped 2023-10-02T22:37:33.187Z,1696286253.187 [ballast_and_trim:Science:HighestSaltPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestSaltPeakReport 2023-10-02T22:37:33.187Z,1696286253.187 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Stopped 2023-10-02T22:37:33.187Z,1696286253.187 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2023-10-02T22:37:33.188Z,1696286253.188 [ballast_and_trim:Science:PeakDetectSalinity] Stopped 2023-10-02T22:37:33.188Z,1696286253.188 [ballast_and_trim:Science:PeakDetectSalinity](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectSalinity 2023-10-02T22:37:33.188Z,1696286253.188 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Stopped 2023-10-02T22:37:33.188Z,1696286253.188 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2023-10-02T22:37:33.188Z,1696286253.188 [ballast_and_trim:Science:PeakDetectFDOM] Stopped 2023-10-02T22:37:33.188Z,1696286253.188 [ballast_and_trim:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectFDOM 2023-10-02T22:37:33.188Z,1696286253.188 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped 2023-10-02T22:37:33.188Z,1696286253.188 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2023-10-02T22:37:33.188Z,1696286253.188 [ballast_and_trim:Science:HighestOilPeakReport] Stopped 2023-10-02T22:37:33.188Z,1696286253.188 [ballast_and_trim:Science:HighestOilPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestOilPeakReport 2023-10-02T22:37:33.188Z,1696286253.188 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Stopped 2023-10-02T22:37:33.188Z,1696286253.188 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2023-10-02T22:37:33.188Z,1696286253.188 [ballast_and_trim:Science:PeakDetectOil] Stopped 2023-10-02T22:37:33.189Z,1696286253.189 [ballast_and_trim:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectOil 2023-10-02T22:37:33.189Z,1696286253.189 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped 2023-10-02T22:37:33.189Z,1696286253.189 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2023-10-02T22:37:33.189Z,1696286253.189 [ballast_and_trim:Science:PeakDetectNO3] Stopped 2023-10-02T22:37:33.189Z,1696286253.189 [ballast_and_trim:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectNO3 2023-10-02T22:37:33.189Z,1696286253.189 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped 2023-10-02T22:37:33.189Z,1696286253.189 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2023-10-02T22:37:33.189Z,1696286253.189 [ballast_and_trim:Science:HighestChlPeakReport] Stopped 2023-10-02T22:37:33.189Z,1696286253.189 [ballast_and_trim:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestChlPeakReport 2023-10-02T22:37:33.189Z,1696286253.189 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped 2023-10-02T22:37:33.189Z,1696286253.189 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2023-10-02T22:37:33.189Z,1696286253.189 [ballast_and_trim:Science:PeakDetectChl] Stopped 2023-10-02T22:37:33.190Z,1696286253.190 [ballast_and_trim:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectChl 2023-10-02T22:37:33.190Z,1696286253.190 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped 2023-10-02T22:37:33.190Z,1696286253.190 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2023-10-02T22:37:33.190Z,1696286253.190 [ballast_and_trim:Science:OceanCurrent:A.] Running Loop=1 2023-10-02T22:37:33.190Z,1696286253.190 [ballast_and_trim:Science:Read_Oil] Stopped 2023-10-02T22:37:33.190Z,1696286253.190 [ballast_and_trim:Science:F] Stopped 2023-10-02T22:37:33.190Z,1696286253.190 [ballast_and_trim:Science:E] Running Loop=1 2023-10-02T22:37:33.199Z,1696286253.199 [ballast_and_trim:Science:E](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water 2023-10-02T22:37:33.199Z,1696286253.199 [ballast_and_trim:Science:D] Stopped 2023-10-02T22:37:33.199Z,1696286253.199 [ballast_and_trim:Science:C] Running Loop=1 2023-10-02T22:37:33.200Z,1696286253.200 [ballast_and_trim:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature 2023-10-02T22:37:33.201Z,1696286253.201 [ballast_and_trim:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity 2023-10-02T22:37:33.201Z,1696286253.201 [ballast_and_trim:Science:B] Running Loop=1 2023-10-02T22:37:33.201Z,1696286253.201 [ballast_and_trim:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature 2023-10-02T22:37:33.202Z,1696286253.202 [ballast_and_trim:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity 2023-10-02T22:37:33.202Z,1696286253.202 [ballast_and_trim:Science:A] Stopped 2023-10-02T22:37:33.396Z,1696286253.396 [WetLabsBB2FL](INFO): Powering up 2023-10-02T22:37:33.527Z,1696286253.527 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim] Running Loop=1 2023-10-02T22:37:33.527Z,1696286253.527 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim 2023-10-02T22:37:33.527Z,1696286253.527 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Running Loop=1 2023-10-02T22:37:33.527Z,1696286253.527 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Running Loop=1 2023-10-02T22:37:33.527Z,1696286253.527 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Running Loop=1 2023-10-02T22:37:33.527Z,1696286253.527 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Running Loop=1 2023-10-02T22:37:33.527Z,1696286253.527 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Running Loop=1 2023-10-02T22:37:33.528Z,1696286253.528 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F] Running Loop=1 2023-10-02T22:37:33.529Z,1696286253.529 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F](INFO): Going to target depth. Speed set to 0.000000 m/s 2023-10-02T22:37:33.530Z,1696286253.530 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F] Stopped 2023-10-02T22:37:33.530Z,1696286253.530 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth] Running Loop=1 2023-10-02T22:37:33.530Z,1696286253.530 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth 2023-10-02T22:37:33.530Z,1696286253.530 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Running Loop=1 2023-10-02T22:37:33.530Z,1696286253.530 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold] Running Loop=1 2023-10-02T22:37:33.530Z,1696286253.530 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold 2023-10-02T22:37:33.530Z,1696286253.530 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Running Loop=1 2023-10-02T22:37:33.530Z,1696286253.530 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Initialize. 2023-10-02T22:37:33.535Z,1696286253.535 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Running Loop=1 2023-10-02T22:37:33.535Z,1696286253.535 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Initialize Buoyancy Component. 2023-10-02T22:37:33.535Z,1696286253.535 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Running Loop=1 2023-10-02T22:37:33.535Z,1696286253.535 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Initialize. 2023-10-02T22:37:33.535Z,1696286253.535 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive] Running Loop=1 2023-10-02T22:37:33.536Z,1696286253.536 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive 2023-10-02T22:37:33.536Z,1696286253.536 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A] Running Loop=1 2023-10-02T22:37:33.536Z,1696286253.536 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Running Loop=1 2023-10-02T22:37:33.536Z,1696286253.536 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Running Loop=1 2023-10-02T22:37:33.536Z,1696286253.536 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Running Loop=1 2023-10-02T22:37:33.536Z,1696286253.536 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Running Loop=1 2023-10-02T22:37:33.537Z,1696286253.537 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Running Loop=1 2023-10-02T22:37:33.927Z,1696286253.927 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A](INFO): Waiting for 0.000000 min while pumping down to neutral. 2023-10-02T22:37:33.927Z,1696286253.927 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A] Stopped 2023-10-02T22:37:33.927Z,1696286253.927 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait] Running Loop=1 2023-10-02T22:37:33.927Z,1696286253.927 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Initialize Wait Component. 2023-10-02T22:37:33.928Z,1696286253.928 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold] Stopped 2023-10-02T22:37:33.928Z,1696286253.928 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold 2023-10-02T22:37:33.928Z,1696286253.928 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Stopped 2023-10-02T22:37:33.928Z,1696286253.928 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Stopped 2023-10-02T22:37:33.928Z,1696286253.928 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2023-10-02T22:37:33.928Z,1696286253.928 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Stopped 2023-10-02T22:37:33.928Z,1696286253.928 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Running Loop=1 2023-10-02T22:37:34.319Z,1696286254.319 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](INFO): Done Waiting. 2023-10-02T22:37:34.319Z,1696286254.319 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait] Stopped 2023-10-02T22:37:34.319Z,1696286254.319 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Uninitialize Wait Component. 2023-10-02T22:37:34.319Z,1696286254.319 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive 2023-10-02T22:37:34.319Z,1696286254.319 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive] Stopped 2023-10-02T22:37:34.319Z,1696286254.319 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive 2023-10-02T22:37:34.320Z,1696286254.320 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth] Running Loop=1 2023-10-02T22:37:34.320Z,1696286254.320 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth 2023-10-02T22:37:34.321Z,1696286254.321 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Running Loop=1 2023-10-02T22:37:34.321Z,1696286254.321 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Initialize. 2023-10-02T22:37:34.342Z,1696286254.342 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A] Running Loop=1 2023-10-02T22:37:34.733Z,1696286254.733 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Running Loop=1 2023-10-02T22:37:34.734Z,1696286254.734 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A](INFO): Moving to 7.000000 m 2023-10-02T22:37:34.734Z,1696286254.734 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A] Stopped 2023-10-02T22:37:34.734Z,1696286254.734 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch] Running Loop=1 2023-10-02T22:37:34.734Z,1696286254.734 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](DEBUG): Initialize. 2023-10-02T22:37:36.232Z,1696286256.232 [CTD_Seabird](ERROR): Failed to parse device response: 2023-10-02T22:37:39.003Z,1696286259.003 [DataOverHttps](INFO): Sending 195 bytes from file Logs/20231002T221631/Express0005.lzma 2023-10-02T22:37:40.005Z,1696286260.005 [DataOverHttps](INFO): Moved sent file to Logs/20231002T221631/Express0005.lzma.bak 2023-10-02T22:37:40.005Z,1696286260.005 [DataOverHttps](INFO): SBD MOMSN=18977165 2023-10-02T22:37:50.100Z,1696286270.100 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7 2023-10-02T22:37:50.100Z,1696286270.100 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-02T22:37:50.101Z,1696286270.101 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7 2023-10-02T22:37:50.101Z,1696286270.101 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-10-02T22:37:50.103Z,1696286270.103 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 7 2023-10-02T22:37:50.103Z,1696286270.103 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-02T22:37:50.203Z,1696286270.203 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-02T22:37:50.203Z,1696286270.203 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-10-02T22:37:50.203Z,1696286270.203 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-02T22:37:50.663Z,1696286270.663 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-02T22:37:50.663Z,1696286270.663 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7 2023-10-02T22:37:50.663Z,1696286270.663 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-10-02T22:37:50.663Z,1696286270.663 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7 2023-10-02T22:37:50.664Z,1696286270.664 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-02T22:37:50.664Z,1696286270.664 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 7 2023-10-02T22:37:50.903Z,1696286270.903 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-02T22:38:13.899Z,1696286293.899 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-10-02T22:38:13.899Z,1696286293.899 [NAL9602] Data Fault, FailCount= 2 2023-10-02T22:38:13.899Z,1696286293.899 [NAL9602](ERROR): Data Fault 2023-10-02T22:38:13.948Z,1696286293.948 [CBIT](ERROR): Data Fault in component: NAL9602 2023-10-02T22:38:14.309Z,1696286294.309 [NAL9602](INFO): Powering down 2023-10-02T22:38:15.186Z,1696286295.186 [CBIT](INFO): Clearing failed state for component NAL9602 2023-10-02T22:38:15.186Z,1696286295.186 [NAL9602] No Fault, FailCount= 2 2023-10-02T22:38:44.604Z,1696286324.604 [NAL9602](INFO): Powering up NAL9602 2023-10-02T22:38:55.517Z,1696286335.517 [NAL9602](INFO): NAL9602 initialized 2023-10-02T22:38:57.569Z,1696286337.569 [CTD_Seabird](ERROR): Salinity reading out of range: 17.193697 psu 2023-10-02T22:38:57.570Z,1696286337.570 [CTD_Seabird](INFO): some bad data, not updating bins 2023-10-02T22:38:58.573Z,1696286338.573 [CTD_Seabird](ERROR): Salinity reading out of range: 14.006231 psu 2023-10-02T22:38:58.574Z,1696286338.574 [CTD_Seabird](INFO): some bad data, not updating bins 2023-10-02T22:39:35.577Z,1696286375.577 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-02T22:40:06.727Z,1696286406.727 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-02T22:40:06.755Z,1696286406.755 [Radio_Surface](INFO): Powering down 2023-10-02T22:40:07.731Z,1696286407.731 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2023-10-02T22:40:20.357Z,1696286420.357 [BPC1](ERROR): BPC1A: No match for serial number FFFF in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file). 2023-10-02T22:40:28.479Z,1696286428.479 [Waterlinked](ERROR): only read 3 of 5 data items 2023-10-02T22:40:38.583Z,1696286438.583 [Waterlinked](ERROR): only read 0 of 1 data item for altitude 2023-10-02T22:40:43.453Z,1696286443.453 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch] Stopped 2023-10-02T22:40:43.454Z,1696286443.454 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth 2023-10-02T22:40:43.454Z,1696286443.454 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth] Stopped 2023-10-02T22:40:43.454Z,1696286443.454 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth 2023-10-02T22:40:43.454Z,1696286443.454 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Stopped 2023-10-02T22:40:43.455Z,1696286443.455 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Uninitialize. 2023-10-02T22:40:43.455Z,1696286443.455 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth 2023-10-02T22:40:43.455Z,1696286443.455 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth] Stopped 2023-10-02T22:40:43.455Z,1696286443.455 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth 2023-10-02T22:40:43.455Z,1696286443.455 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Stopped 2023-10-02T22:40:43.455Z,1696286443.455 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth] Running Loop=1 2023-10-02T22:40:43.455Z,1696286443.455 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth 2023-10-02T22:40:43.456Z,1696286443.456 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Running Loop=1 2023-10-02T22:40:43.456Z,1696286443.456 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Initialize. 2023-10-02T22:40:43.456Z,1696286443.456 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Running Loop=1 2023-10-02T22:40:43.456Z,1696286443.456 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch](DEBUG): Initialize. 2023-10-02T22:40:43.456Z,1696286443.456 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C] Running Loop=1 2023-10-02T22:40:43.847Z,1696286443.847 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C](INFO): Waiting for the vehicle to settle. Depth = 7.049896 m 2023-10-02T22:40:43.847Z,1696286443.847 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C] Stopped 2023-10-02T22:40:43.847Z,1696286443.847 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait] Running Loop=1 2023-10-02T22:40:43.847Z,1696286443.847 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Initialize Wait Component. 2023-10-02T22:40:43.847Z,1696286443.847 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Running Loop=1 2023-10-02T22:40:43.847Z,1696286443.847 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Running Loop=1 2023-10-02T22:40:51.120Z,1696286451.120 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8 2023-10-02T22:40:51.120Z,1696286451.120 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-02T22:40:51.122Z,1696286451.122 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 8 2023-10-02T22:40:51.122Z,1696286451.122 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-10-02T22:40:51.123Z,1696286451.123 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 8 2023-10-02T22:40:51.123Z,1696286451.123 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-02T22:40:51.168Z,1696286451.168 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-02T22:40:51.168Z,1696286451.168 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-10-02T22:40:51.168Z,1696286451.168 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-02T22:40:51.539Z,1696286451.539 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-02T22:40:51.539Z,1696286451.539 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8 2023-10-02T22:40:51.540Z,1696286451.540 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-10-02T22:40:51.540Z,1696286451.540 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 8 2023-10-02T22:40:51.541Z,1696286451.541 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-02T22:40:51.541Z,1696286451.541 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 8 2023-10-02T22:40:51.916Z,1696286451.916 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-02T22:41:11.005Z,1696286471.005 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.188675 2023-10-02T22:41:35.968Z,1696286495.968 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error 2023-10-02T22:42:02.755Z,1696286522.755 [Waterlinked](ERROR): only read 0 of 5 data items 2023-10-02T22:42:30.157Z,1696286550.157 [Waterlinked](ERROR): only read 3 of 5 data items 2023-10-02T22:42:30.585Z,1696286550.585 [Waterlinked](ERROR): only read 0 of 1 data item for altitude 2023-10-02T22:42:34.619Z,1696286554.619 [Waterlinked](ERROR): only read 0 of 1 data item for altitude 2023-10-02T22:42:40.607Z,1696286560.607 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error 2023-10-02T22:42:43.864Z,1696286563.864 [Waterlinked](ERROR): only read 3 of 5 data items 2023-10-02T22:42:48.737Z,1696286568.737 [Waterlinked](ERROR): only read 0 of 1 data item for altitude 2023-10-02T22:42:57.189Z,1696286577.189 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error 2023-10-02T22:43:15.438Z,1696286595.438 [Waterlinked](ERROR): only read 0 of 1 data item for altitude 2023-10-02T22:43:17.826Z,1696286597.826 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error 2023-10-02T22:43:23.920Z,1696286603.920 [Waterlinked](ERROR): only read 0 of 5 data items 2023-10-02T22:43:26.701Z,1696286606.701 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error 2023-10-02T22:43:44.507Z,1696286624.507 [Waterlinked](ERROR): only read 3 of 5 data items 2023-10-02T22:43:46.914Z,1696286626.914 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error 2023-10-02T22:43:50.908Z,1696286630.908 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error 2023-10-02T22:43:52.176Z,1696286632.176 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9 2023-10-02T22:43:52.176Z,1696286632.176 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-02T22:43:52.177Z,1696286632.177 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 9 2023-10-02T22:43:52.177Z,1696286632.177 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-10-02T22:43:52.179Z,1696286632.179 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 9 2023-10-02T22:43:52.179Z,1696286632.179 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-02T22:43:52.279Z,1696286632.279 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-02T22:43:52.280Z,1696286632.280 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-10-02T22:43:52.280Z,1696286632.280 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-02T22:43:52.686Z,1696286632.686 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-02T22:43:52.687Z,1696286632.687 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9 2023-10-02T22:43:52.689Z,1696286632.689 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-10-02T22:43:52.690Z,1696286632.690 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 9 2023-10-02T22:43:52.693Z,1696286632.693 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-02T22:43:52.693Z,1696286632.693 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 9 2023-10-02T22:43:53.110Z,1696286633.110 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-02T22:43:59.107Z,1696286639.107 [Waterlinked](ERROR): only read 0 of 1 data item for altitude 2023-10-02T22:44:09.930Z,1696286649.930 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error 2023-10-02T22:44:13.772Z,1696286653.772 [WetLabsBB2FL](ERROR): Error parsing data, unexpected wavelength found:50 2023-10-02T22:44:18.781Z,1696286658.781 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error 2023-10-02T22:44:25.267Z,1696286665.267 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error 2023-10-02T22:44:39.458Z,1696286679.458 [Waterlinked](ERROR): only read 3 of 5 data items 2023-10-02T22:44:42.640Z,1696286682.640 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error 2023-10-02T22:44:53.989Z,1696286693.989 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error 2023-10-02T22:45:11.349Z,1696286711.349 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error 2023-10-02T22:46:34.993Z,1696286794.993 [Waterlinked](ERROR): only read 2 of 5 data items 2023-10-02T22:46:53.178Z,1696286813.178 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10 2023-10-02T22:46:53.179Z,1696286813.179 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-02T22:46:53.180Z,1696286813.180 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 10 2023-10-02T22:46:53.180Z,1696286813.180 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-10-02T22:46:53.181Z,1696286813.181 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 10 2023-10-02T22:46:53.181Z,1696286813.181 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-02T22:46:53.197Z,1696286813.197 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-02T22:46:53.197Z,1696286813.197 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-10-02T22:46:53.197Z,1696286813.197 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-02T22:46:53.607Z,1696286813.607 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-02T22:46:53.609Z,1696286813.609 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10 2023-10-02T22:46:53.625Z,1696286813.625 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-10-02T22:46:53.625Z,1696286813.625 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 10 2023-10-02T22:46:53.625Z,1696286813.625 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-02T22:46:53.625Z,1696286813.625 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 10 2023-10-02T22:46:53.982Z,1696286813.982 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-02T22:47:23.885Z,1696286843.885 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error 2023-10-02T22:47:42.843Z,1696286862.843 [BPC1](INFO): Calculating totals. Valid battery stick count: 43. Valid reserve battery stick count: 4. 2023-10-02T22:47:42.850Z,1696286862.850 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle. 2023-10-02T22:48:51.934Z,1696286931.934 [Waterlinked](ERROR): only read 3 of 5 data items 2023-10-02T22:48:58.364Z,1696286938.364 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-10-02T22:48:58.364Z,1696286938.364 [NAL9602] Data Fault, FailCount= 3 2023-10-02T22:48:58.364Z,1696286938.364 [NAL9602](ERROR): Data Fault 2023-10-02T22:48:58.483Z,1696286938.483 [CBIT](ERROR): Data Fault in component: NAL9602 2023-10-02T22:48:58.768Z,1696286938.768 [NAL9602](INFO): Powering down 2023-10-02T22:48:59.677Z,1696286939.677 [CBIT](INFO): Clearing failed state for component NAL9602 2023-10-02T22:48:59.677Z,1696286939.677 [NAL9602] No Fault, FailCount= 3 2023-10-02T22:49:28.279Z,1696286968.279 [Waterlinked](ERROR): only read 0 of 1 data item for altitude 2023-10-02T22:49:29.081Z,1696286969.081 [NAL9602](INFO): Powering up NAL9602 2023-10-02T22:49:39.998Z,1696286979.998 [NAL9602](INFO): NAL9602 initialized 2023-10-02T22:49:42.849Z,1696286982.849 [Waterlinked](ERROR): only read 3 of 5 data items 2023-10-02T22:49:53.789Z,1696286993.789 [Waterlinked](ERROR): only read 2 of 5 data items 2023-10-02T22:49:54.166Z,1696286994.166 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11 2023-10-02T22:49:54.166Z,1696286994.166 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-02T22:49:54.167Z,1696286994.167 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 11 2023-10-02T22:49:54.167Z,1696286994.167 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-10-02T22:49:54.169Z,1696286994.169 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 11 2023-10-02T22:49:54.169Z,1696286994.169 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-02T22:49:54.267Z,1696286994.267 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-02T22:49:54.267Z,1696286994.267 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-10-02T22:49:54.268Z,1696286994.268 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-02T22:49:54.635Z,1696286994.635 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-02T22:49:54.635Z,1696286994.635 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11 2023-10-02T22:49:54.636Z,1696286994.636 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-10-02T22:49:54.636Z,1696286994.636 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 11 2023-10-02T22:49:54.636Z,1696286994.636 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-02T22:49:54.636Z,1696286994.636 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 11 2023-10-02T22:49:54.953Z,1696286994.953 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-02T22:50:04.265Z,1696287004.265 [Waterlinked](ERROR): only read 3 of 5 data items 2023-10-02T22:50:11.139Z,1696287011.139 [Waterlinked](ERROR): only read 1 of 5 data items 2023-10-02T22:50:44.284Z,1696287044.284 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](INFO): Done Waiting. 2023-10-02T22:50:44.284Z,1696287044.284 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait] Stopped 2023-10-02T22:50:44.284Z,1696287044.284 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Uninitialize Wait Component. 2023-10-02T22:50:44.285Z,1696287044.285 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:E.Execute] Running Loop=1 2023-10-02T22:50:44.685Z,1696287044.685 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:E.Execute](INFO): Executing command gfscan 2023-10-02T22:50:44.686Z,1696287044.686 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:E.Execute] Stopped 2023-10-02T22:50:44.686Z,1696287044.686 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F] Running Loop=1 2023-10-02T22:50:44.775Z,1696287044.775 [CommandExec](IMPORTANT): got command gfscan 2023-10-02T22:50:45.097Z,1696287045.097 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F](INFO): Running ballast and trim. Depth = 7.059304 m 2023-10-02T22:50:45.097Z,1696287045.097 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F] Stopped 2023-10-02T22:50:45.097Z,1696287045.097 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.] Running Loop=1 2023-10-02T22:50:45.097Z,1696287045.097 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](INFO): Initializing BallastAndTrim. 2023-10-02T22:50:45.111Z,1696287045.111 [CBIT](IMPORTANT): Beginning ground fault scan 2023-10-02T22:50:45.580Z,1696287045.580 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2023-10-02T22:50:45.582Z,1696287045.582 [CTD_Seabird](INFO): some bad data, not updating bins 2023-10-02T22:50:46.584Z,1696287046.584 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2023-10-02T22:50:46.585Z,1696287046.585 [CTD_Seabird](INFO): some bad data, not updating bins 2023-10-02T22:50:47.588Z,1696287047.588 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2023-10-02T22:50:47.589Z,1696287047.589 [CTD_Seabird](INFO): some bad data, not updating bins 2023-10-02T22:50:48.599Z,1696287048.599 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2023-10-02T22:50:48.606Z,1696287048.606 [CTD_Seabird](INFO): some bad data, not updating bins 2023-10-02T22:50:49.596Z,1696287049.596 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2023-10-02T22:50:49.597Z,1696287049.597 [CTD_Seabird](INFO): some bad data, not updating bins 2023-10-02T22:50:50.600Z,1696287050.600 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2023-10-02T22:50:50.601Z,1696287050.601 [CTD_Seabird](INFO): some bad data, not updating bins 2023-10-02T22:50:51.605Z,1696287051.605 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2023-10-02T22:50:51.605Z,1696287051.605 [CTD_Seabird](INFO): some bad data, not updating bins 2023-10-02T22:50:52.615Z,1696287052.615 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2023-10-02T22:50:52.620Z,1696287052.620 [CTD_Seabird](INFO): some bad data, not updating bins 2023-10-02T22:50:53.612Z,1696287053.612 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2023-10-02T22:50:53.613Z,1696287053.613 [CTD_Seabird](INFO): some bad data, not updating bins 2023-10-02T22:50:54.616Z,1696287054.616 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2023-10-02T22:50:54.617Z,1696287054.617 [CTD_Seabird](INFO): some bad data, not updating bins 2023-10-02T22:50:55.636Z,1696287055.636 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2023-10-02T22:50:55.636Z,1696287055.636 [CTD_Seabird](INFO): some bad data, not updating bins 2023-10-02T22:50:56.031Z,1696287056.031 [CBIT](IMPORTANT): Low side ground fault detected mA: CHAN A0 (Batt): 0.752390 CHAN A1 (24V): 0.793116 CHAN A2 (12V): 0.485422 CHAN A3 (5V): 0.115485 CHAN B0 (3.3V): 0.050526 CHAN B1 (3.15aV): 0.050522 CHAN B2 (3.15bV): 0.055590 CHAN B3 (GND): -0.106557 OPEN: 0.012545 Full Scale: +/- 1 mA 2023-10-02T22:51:06.869Z,1696287066.869 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error 2023-10-02T22:51:07.296Z,1696287067.296 [Waterlinked](ERROR): only read 0 of 1 data item for altitude 2023-10-02T22:51:41.613Z,1696287101.613 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error 2023-10-02T22:51:52.953Z,1696287112.953 [Waterlinked](ERROR): only read 1 of 5 data items 2023-10-02T22:51:56.586Z,1696287116.586 [Waterlinked](ERROR): only read 0 of 1 data item for altitude 2023-10-02T22:51:59.795Z,1696287119.795 [Waterlinked](ERROR): only read 3 of 5 data items 2023-10-02T22:52:10.736Z,1696287130.736 [Waterlinked](ERROR): only read 0 of 1 data item for altitude 2023-10-02T22:52:12.940Z,1696287132.940 [CTD_Seabird](ERROR): Failed to parse device response: 7.47.75 2023-10-02T22:52:21.220Z,1696287141.220 [Waterlinked](ERROR): only read 2 of 5 data items 2023-10-02T22:52:28.056Z,1696287148.056 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error 2023-10-02T22:52:31.731Z,1696287151.731 [Waterlinked](ERROR): only read 0 of 1 data item for altitude 2023-10-02T22:52:38.593Z,1696287158.593 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error 2023-10-02T22:52:45.872Z,1696287165.872 [Waterlinked](ERROR): only read 0 of 1 data item for altitude 2023-10-02T22:52:55.167Z,1696287175.167 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12 2023-10-02T22:52:55.167Z,1696287175.167 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-02T22:52:55.168Z,1696287175.168 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 12 2023-10-02T22:52:55.168Z,1696287175.168 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-10-02T22:52:55.170Z,1696287175.170 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 12 2023-10-02T22:52:55.170Z,1696287175.170 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-02T22:52:55.189Z,1696287175.189 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-02T22:52:55.190Z,1696287175.190 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-10-02T22:52:55.190Z,1696287175.190 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-02T22:52:55.596Z,1696287175.596 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-02T22:52:55.596Z,1696287175.596 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12 2023-10-02T22:52:55.597Z,1696287175.597 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-10-02T22:52:55.597Z,1696287175.597 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 12 2023-10-02T22:52:55.597Z,1696287175.597 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-02T22:52:55.597Z,1696287175.597 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 12 2023-10-02T22:52:55.993Z,1696287175.993 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-02T22:52:56.414Z,1696287176.414 [Waterlinked](ERROR): only read 0 of 1 data item for altitude 2023-10-02T22:53:11.315Z,1696287191.315 [Waterlinked](ERROR): only read 2 of 5 data items 2023-10-02T22:53:33.125Z,1696287213.125 [Waterlinked](ERROR): only read 3 of 5 data items 2023-10-02T22:53:52.928Z,1696287232.928 [Waterlinked](ERROR): only read 3 of 5 data items 2023-10-02T22:54:02.292Z,1696287242.292 [Waterlinked](ERROR): only read 0 of 1 data item for altitude 2023-10-02T22:54:05.493Z,1696287245.493 [Waterlinked](ERROR): only read 3 of 5 data items 2023-10-02T22:55:19.380Z,1696287319.380 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error 2023-10-02T22:55:56.164Z,1696287356.164 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13 2023-10-02T22:55:56.164Z,1696287356.164 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-02T22:55:56.165Z,1696287356.165 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 13 2023-10-02T22:55:56.166Z,1696287356.166 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-10-02T22:55:56.167Z,1696287356.167 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 13 2023-10-02T22:55:56.167Z,1696287356.167 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-02T22:55:56.260Z,1696287356.260 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-02T22:55:56.260Z,1696287356.260 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-10-02T22:55:56.260Z,1696287356.260 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-02T22:55:56.668Z,1696287356.668 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-02T22:55:56.668Z,1696287356.668 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13 2023-10-02T22:55:56.669Z,1696287356.669 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-10-02T22:55:56.669Z,1696287356.669 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 13 2023-10-02T22:55:56.669Z,1696287356.669 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-02T22:55:56.669Z,1696287356.669 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 13 2023-10-02T22:55:56.986Z,1696287356.986 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-02T22:58:31.314Z,1696287511.314 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error 2023-10-02T22:58:53.556Z,1696287533.556 [Waterlinked](ERROR): only read 3 of 5 data items 2023-10-02T22:58:57.196Z,1696287537.196 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14 2023-10-02T22:58:57.196Z,1696287537.196 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-02T22:58:57.197Z,1696287537.197 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 14 2023-10-02T22:58:57.197Z,1696287537.197 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-10-02T22:58:57.199Z,1696287537.199 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 14 2023-10-02T22:58:57.199Z,1696287537.199 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-02T22:58:57.276Z,1696287537.276 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-02T22:58:57.276Z,1696287537.276 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-10-02T22:58:57.276Z,1696287537.276 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-02T22:58:57.673Z,1696287537.673 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-02T22:58:57.673Z,1696287537.673 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14 2023-10-02T22:58:57.674Z,1696287537.674 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-10-02T22:58:57.674Z,1696287537.674 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 14 2023-10-02T22:58:57.674Z,1696287537.674 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-02T22:58:57.674Z,1696287537.674 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 14 2023-10-02T22:58:57.985Z,1696287537.985 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-02T22:58:59.573Z,1696287539.573 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error 2023-10-02T22:59:23.020Z,1696287563.020 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error 2023-10-02T22:59:26.668Z,1696287566.668 [Waterlinked](ERROR): only read 0 of 1 data item for altitude 2023-10-02T22:59:41.568Z,1696287581.568 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-10-02T22:59:41.568Z,1696287581.568 [NAL9602] Data Fault, FailCount= 4 2023-10-02T22:59:41.568Z,1696287581.568 [NAL9602](ERROR): Data Fault 2023-10-02T22:59:41.637Z,1696287581.637 [CBIT](ERROR): Data Fault in component: NAL9602 2023-10-02T22:59:41.978Z,1696287581.978 [NAL9602](INFO): Powering down 2023-10-02T22:59:42.887Z,1696287582.887 [CBIT](INFO): Clearing failed state for component NAL9602 2023-10-02T22:59:42.887Z,1696287582.887 [NAL9602] No Fault, FailCount= 4 2023-10-02T23:00:02.594Z,1696287602.594 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error 2023-10-02T23:00:03.019Z,1696287603.019 [Waterlinked](ERROR): only read 0 of 1 data item for altitude 2023-10-02T23:00:04.605Z,1696287604.605 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error 2023-10-02T23:00:12.269Z,1696287612.269 [NAL9602](INFO): Powering up NAL9602 2023-10-02T23:00:17.142Z,1696287617.142 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error 2023-10-02T23:00:23.180Z,1696287623.180 [NAL9602](INFO): NAL9602 initialized 2023-10-02T23:00:29.653Z,1696287629.653 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error 2023-10-02T23:00:31.278Z,1696287631.278 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error 2023-10-02T23:00:34.524Z,1696287634.524 [Waterlinked](ERROR): only read 0 of 1 data item for altitude 2023-10-02T23:01:01.219Z,1696287661.219 [Waterlinked](ERROR): only read 0 of 1 data item for altitude 2023-10-02T23:01:12.912Z,1696287672.912 [Waterlinked](ERROR): only read 0 of 1 data item for altitude 2023-10-02T23:01:20.175Z,1696287680.175 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error 2023-10-02T23:01:31.105Z,1696287691.105 [Waterlinked](ERROR): only read 2 of 5 data items 2023-10-02T23:01:31.510Z,1696287691.510 [Waterlinked](ERROR): only read 0 of 1 data item for altitude 2023-10-02T23:01:35.133Z,1696287695.133 [Waterlinked](ERROR): only read 3 of 5 data items 2023-10-02T23:01:56.951Z,1696287716.951 [Waterlinked](ERROR): only read 3 of 5 data items 2023-10-02T23:01:58.213Z,1696287718.213 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15 2023-10-02T23:01:58.213Z,1696287718.213 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-02T23:01:58.214Z,1696287718.214 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 15 2023-10-02T23:01:58.214Z,1696287718.214 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-10-02T23:01:58.216Z,1696287718.216 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 15 2023-10-02T23:01:58.216Z,1696287718.216 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-02T23:01:58.292Z,1696287718.292 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-02T23:01:58.292Z,1696287718.292 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-10-02T23:01:58.293Z,1696287718.293 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-02T23:01:58.695Z,1696287718.695 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-02T23:01:58.695Z,1696287718.695 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15 2023-10-02T23:01:58.696Z,1696287718.696 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-10-02T23:01:58.696Z,1696287718.696 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 15 2023-10-02T23:01:58.696Z,1696287718.696 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-02T23:01:58.696Z,1696287718.696 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 15 2023-10-02T23:01:58.977Z,1696287718.977 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-02T23:02:04.664Z,1696287724.664 [Waterlinked](ERROR): only read 0 of 1 data item for altitude 2023-10-02T23:02:33.313Z,1696287753.313 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error 2023-10-02T23:03:06.070Z,1696287786.070 [Waterlinked](ERROR): only read 3 of 5 data items 2023-10-02T23:03:39.587Z,1696287819.587 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error 2023-10-02T23:03:46.852Z,1696287826.852 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error 2023-10-02T23:03:54.140Z,1696287834.140 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error 2023-10-02T23:04:31.683Z,1696287871.683 [Waterlinked](ERROR): only read 0 of 1 data item for altitude 2023-10-02T23:04:59.180Z,1696287899.180 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16 2023-10-02T23:04:59.180Z,1696287899.180 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-02T23:04:59.181Z,1696287899.181 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 16 2023-10-02T23:04:59.181Z,1696287899.181 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-10-02T23:04:59.183Z,1696287899.183 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 16 2023-10-02T23:04:59.183Z,1696287899.183 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-02T23:04:59.267Z,1696287899.267 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-02T23:04:59.267Z,1696287899.267 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-10-02T23:04:59.267Z,1696287899.267 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-02T23:04:59.692Z,1696287899.692 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-02T23:04:59.692Z,1696287899.692 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16 2023-10-02T23:04:59.692Z,1696287899.692 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-10-02T23:04:59.692Z,1696287899.692 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 16 2023-10-02T23:04:59.693Z,1696287899.693 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-02T23:04:59.693Z,1696287899.693 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 16 2023-10-02T23:04:59.940Z,1696287899.940 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-02T23:06:34.902Z,1696287994.902 [Waterlinked](ERROR): only read 3 of 5 data items 2023-10-02T23:07:34.745Z,1696288054.745 [Waterlinked](ERROR): only read 3 of 5 data items 2023-10-02T23:07:53.722Z,1696288073.722 [Waterlinked](ERROR): only read 4 of 5 data items 2023-10-02T23:08:00.177Z,1696288080.177 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17 2023-10-02T23:08:00.177Z,1696288080.177 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-02T23:08:00.183Z,1696288080.183 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 17 2023-10-02T23:08:00.183Z,1696288080.183 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-10-02T23:08:00.184Z,1696288080.184 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 17 2023-10-02T23:08:00.184Z,1696288080.184 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-02T23:08:00.271Z,1696288080.271 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-02T23:08:00.272Z,1696288080.272 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-10-02T23:08:00.272Z,1696288080.272 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-02T23:08:00.659Z,1696288080.659 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-02T23:08:00.659Z,1696288080.659 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17 2023-10-02T23:08:00.660Z,1696288080.660 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-10-02T23:08:00.660Z,1696288080.660 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 17 2023-10-02T23:08:00.660Z,1696288080.660 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-02T23:08:00.660Z,1696288080.660 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 17 2023-10-02T23:08:01.015Z,1696288081.015 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-02T23:08:19.931Z,1696288099.931 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error 2023-10-02T23:08:38.567Z,1696288118.567 [Waterlinked](ERROR): only read 0 of 1 data item for altitude 2023-10-02T23:09:07.259Z,1696288147.259 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error 2023-10-02T23:09:26.615Z,1696288166.615 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error 2023-10-02T23:09:27.041Z,1696288167.041 [Waterlinked](ERROR): only read 0 of 1 data item for altitude 2023-10-02T23:09:30.253Z,1696288170.253 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error 2023-10-02T23:09:58.960Z,1696288198.960 [Waterlinked](ERROR): only read 3 of 5 data items 2023-10-02T23:10:09.835Z,1696288209.835 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error 2023-10-02T23:10:13.908Z,1696288213.908 [Waterlinked](ERROR): only read 3 of 5 data items 2023-10-02T23:10:24.772Z,1696288224.772 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-10-02T23:10:24.772Z,1696288224.772 [NAL9602] Data Fault, FailCount= 5 2023-10-02T23:10:24.772Z,1696288224.772 [NAL9602](ERROR): Data Fault 2023-10-02T23:10:24.907Z,1696288224.907 [CBIT](ERROR): Data Fault in component: NAL9602 2023-10-02T23:10:24.907Z,1696288224.907 [CBIT](CRITICAL): Data Fault in component: NAL9602 2023-10-02T23:10:25.177Z,1696288225.177 [NAL9602](INFO): Powering down 2023-10-02T23:10:25.264Z,1696288225.264 [CommandExec](FAULT): Scheduling is paused 2023-10-02T23:10:25.265Z,1696288225.265 [CBIT](INFO): Critical error at 20231002T231024 2023-10-02T23:10:25.265Z,1696288225.265 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2023-10-02T23:10:25.657Z,1696288225.657 [MissionManager](INFO): MissionManager is completed. 2023-10-02T23:10:25.657Z,1696288225.657 [MissionManager](INFO): Uninitializing Mission ballast_and_trim 2023-10-02T23:10:25.657Z,1696288225.657 [ballast_and_trim] Stopped 2023-10-02T23:10:25.657Z,1696288225.657 [ballast_and_trim](DEBUG): Aggregate::uninitialize ballast_and_trim 2023-10-02T23:10:25.657Z,1696288225.657 [ballast_and_trim:Science] Stopped 2023-10-02T23:10:25.657Z,1696288225.657 [ballast_and_trim:Science](DEBUG): Aggregate::uninitialize ballast_and_trim:Science 2023-10-02T23:10:25.657Z,1696288225.657 [ballast_and_trim:Science:B] Stopped 2023-10-02T23:10:25.657Z,1696288225.657 [ballast_and_trim:Science:C] Stopped 2023-10-02T23:10:25.657Z,1696288225.657 [ballast_and_trim:Science:E] Stopped 2023-10-02T23:10:25.657Z,1696288225.657 [ballast_and_trim:Science:OceanCurrent] Stopped 2023-10-02T23:10:25.658Z,1696288225.658 [ballast_and_trim:Science:OceanCurrent](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:OceanCurrent 2023-10-02T23:10:25.658Z,1696288225.658 [ballast_and_trim:Science:OceanCurrent:A.] Stopped 2023-10-02T23:10:25.658Z,1696288225.658 [ballast_and_trim:BackseatDriver] Stopped 2023-10-02T23:10:25.658Z,1696288225.658 [ballast_and_trim:BackseatDriver](DEBUG): Aggregate::uninitialize ballast_and_trim:BackseatDriver 2023-10-02T23:10:25.658Z,1696288225.658 [ballast_and_trim:BackseatDriver:A.BackseatDriver] Stopped 2023-10-02T23:10:25.658Z,1696288225.658 [ballast_and_trim:RunBallastAndTrim] Stopped 2023-10-02T23:10:25.658Z,1696288225.658 [ballast_and_trim:RunBallastAndTrim](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim 2023-10-02T23:10:25.658Z,1696288225.658 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim] Stopped 2023-10-02T23:10:25.658Z,1696288225.658 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim 2023-10-02T23:10:25.658Z,1696288225.658 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Stopped 2023-10-02T23:10:25.659Z,1696288225.659 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Stopped 2023-10-02T23:10:25.659Z,1696288225.659 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Stopped 2023-10-02T23:10:25.659Z,1696288225.659 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Stopped 2023-10-02T23:10:25.659Z,1696288225.659 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Stopped 2023-10-02T23:10:25.659Z,1696288225.659 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth] Stopped 2023-10-02T23:10:25.659Z,1696288225.659 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth 2023-10-02T23:10:25.659Z,1696288225.659 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Stopped 2023-10-02T23:10:25.659Z,1696288225.659 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Uninitialize. 2023-10-02T23:10:25.659Z,1696288225.659 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Stopped 2023-10-02T23:10:25.659Z,1696288225.659 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.] Stopped 2023-10-02T23:10:25.660Z,1696288225.660 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](FAULT): BallastAndTrim aborted prematurely - reporting best estimate. Estimation time: 19.67 minutes. 2023-10-02T23:10:25.661Z,1696288225.661 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](IMPORTANT): Estimated buoyancyNeutral: 332.166942 +/- 14.363304 cc (conf. level 99.90%, sigma: 23.892200 cc). 2023-10-02T23:10:25.662Z,1696288225.662 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](IMPORTANT): Estimated massDefault: 6.878256 +/- 0.258152 mm (conf. level 99.90%, sigma: 0.429416 mm). 2023-10-02T23:10:25.662Z,1696288225.662 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](INFO): Uninitializing BallastAndTrim. 2023-10-02T23:10:25.662Z,1696288225.662 [ballast_and_trim:RunBallastAndTrim:Depth1] Stopped 2023-10-02T23:10:25.662Z,1696288225.662 [ballast_and_trim:RunBallastAndTrim:Depth1](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth1 2023-10-02T23:10:25.662Z,1696288225.662 [ballast_and_trim:RunBallastAndTrim:Depth1:B] Stopped 2023-10-02T23:10:25.662Z,1696288225.662 [ballast_and_trim:RunBallastAndTrim:Depth1:B](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth1:B 2023-10-02T23:10:25.955Z,1696288225.955 [WetLabsBB2FL](INFO): Powering down 2023-10-02T23:10:26.058Z,1696288226.058 [MissionManager](IMPORTANT): Started mission Default 2023-10-02T23:10:26.058Z,1696288226.058 [Default] Running Loop=1 2023-10-02T23:10:26.058Z,1696288226.058 [Default](DEBUG): Aggregate::initialize Default 2023-10-02T23:10:26.059Z,1696288226.059 [Default:B.GoToSurface] Running Loop=1 2023-10-02T23:10:26.059Z,1696288226.059 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-10-02T23:10:26.059Z,1696288226.059 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-10-02T23:10:26.059Z,1696288226.059 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-10-02T23:10:26.059Z,1696288226.059 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-10-02T23:10:26.060Z,1696288226.060 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-10-02T23:10:26.060Z,1696288226.060 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-10-02T23:10:26.061Z,1696288226.061 [Default:A.Wait] Running Loop=1 2023-10-02T23:10:26.061Z,1696288226.061 [Default:A.Wait](DEBUG): Initialize Wait Component. 2023-10-02T23:10:26.442Z,1696288226.442 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -21.00 mm (1 active estimators). 2023-10-02T23:10:26.643Z,1696288226.643 [Radio_Surface](INFO): Powering up 2023-10-02T23:10:31.254Z,1696288231.254 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error 2023-10-02T23:10:32.313Z,1696288232.313 [DataOverHttps](INFO): Radio surface powered ON. 2023-10-02T23:10:39.369Z,1696288239.369 [Default:A.Wait](INFO): Done Waiting. 2023-10-02T23:10:39.369Z,1696288239.369 [Default:A.Wait] Stopped 2023-10-02T23:10:39.369Z,1696288239.369 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2023-10-02T23:10:42.970Z,1696288242.970 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error 2023-10-02T23:10:47.030Z,1696288247.030 [Waterlinked](ERROR): only read 0 of 1 data item for altitude 2023-10-02T23:10:55.166Z,1696288255.166 [Default:B.GoToSurface](ERROR): Altitude too low: 1.44 2023-10-02T23:10:55.504Z,1696288255.504 [Default:B.GoToSurface](ERROR): Altitude too low: 1.44 2023-10-02T23:10:55.979Z,1696288255.979 [Default:B.GoToSurface](ERROR): Altitude too low: 1.38 2023-10-02T23:10:56.382Z,1696288256.382 [Default:B.GoToSurface](ERROR): Altitude too low: 1.38 2023-10-02T23:10:57.587Z,1696288257.587 [Default:B.GoToSurface](ERROR): Altitude too low: 1.80 2023-10-02T23:11:01.207Z,1696288261.207 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18 2023-10-02T23:11:01.207Z,1696288261.207 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-02T23:11:01.209Z,1696288261.209 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 18 2023-10-02T23:11:01.209Z,1696288261.209 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-10-02T23:11:01.210Z,1696288261.210 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 18 2023-10-02T23:11:01.210Z,1696288261.210 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-02T23:11:01.410Z,1696288261.410 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-02T23:11:01.411Z,1696288261.411 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-10-02T23:11:01.411Z,1696288261.411 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-02T23:11:01.791Z,1696288261.791 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-02T23:11:01.791Z,1696288261.791 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18 2023-10-02T23:11:01.791Z,1696288261.791 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-10-02T23:11:01.791Z,1696288261.791 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 18 2023-10-02T23:11:01.792Z,1696288261.792 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-02T23:11:01.792Z,1696288261.792 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 18 2023-10-02T23:11:01.979Z,1696288261.979 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-02T23:11:02.431Z,1696288262.431 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-02T23:11:05.261Z,1696288265.261 [Default:B.GoToSurface](ERROR): Altitude too low: 1.55 2023-10-02T23:11:05.659Z,1696288265.659 [Default:B.GoToSurface](ERROR): Altitude too low: 1.47 2023-10-02T23:11:06.070Z,1696288266.070 [Default:B.GoToSurface](ERROR): Altitude too low: 1.43 2023-10-02T23:11:06.469Z,1696288266.469 [Default:B.GoToSurface](ERROR): Altitude too low: 1.40 2023-10-02T23:11:06.870Z,1696288266.870 [Default:B.GoToSurface](ERROR): Altitude too low: 1.25 2023-10-02T23:11:07.296Z,1696288267.296 [Default:B.GoToSurface](ERROR): Altitude too low: 1.19 2023-10-02T23:11:07.681Z,1696288267.681 [Default:B.GoToSurface](ERROR): Altitude too low: 1.15 2023-10-02T23:11:08.062Z,1696288268.062 [Default:B.GoToSurface](ERROR): Altitude too low: 1.27 2023-10-02T23:11:08.485Z,1696288268.485 [Default:B.GoToSurface](ERROR): Altitude too low: 1.21 2023-10-02T23:11:08.896Z,1696288268.896 [Default:B.GoToSurface](ERROR): Altitude too low: 1.20 2023-10-02T23:11:09.303Z,1696288269.303 [Default:B.GoToSurface](ERROR): Altitude too low: 1.16 2023-10-02T23:11:09.694Z,1696288269.694 [Default:B.GoToSurface](ERROR): Altitude too low: 1.14 2023-10-02T23:11:10.072Z,1696288270.072 [Default:B.GoToSurface](ERROR): Altitude too low: 1.13 2023-10-02T23:11:10.510Z,1696288270.510 [Default:B.GoToSurface](ERROR): Altitude too low: 1.12 2023-10-02T23:11:10.918Z,1696288270.918 [Default:B.GoToSurface](ERROR): Altitude too low: 1.11 2023-10-02T23:11:11.337Z,1696288271.337 [Default:B.GoToSurface](ERROR): Altitude too low: 1.09 2023-10-02T23:11:11.723Z,1696288271.723 [Default:B.GoToSurface](ERROR): Altitude too low: 1.05 2023-10-02T23:11:12.122Z,1696288272.122 [Default:B.GoToSurface](ERROR): Altitude too low: 1.07 2023-10-02T23:11:12.507Z,1696288272.507 [Default:B.GoToSurface](ERROR): Altitude too low: 1.06 2023-10-02T23:11:12.971Z,1696288272.971 [Default:B.GoToSurface](ERROR): Altitude too low: 1.06 2023-10-02T23:11:13.361Z,1696288273.361 [Default:B.GoToSurface](ERROR): Altitude too low: 1.04 2023-10-02T23:11:13.737Z,1696288273.737 [Default:B.GoToSurface](ERROR): Altitude too low: 1.03 2023-10-02T23:11:14.114Z,1696288274.114 [Default:B.GoToSurface](ERROR): Altitude too low: 1.03 2023-10-02T23:11:14.521Z,1696288274.521 [Default:B.GoToSurface](ERROR): Altitude too low: 1.03 2023-10-02T23:11:14.941Z,1696288274.941 [Default:B.GoToSurface](ERROR): Altitude too low: 1.02 2023-10-02T23:11:15.345Z,1696288275.345 [Default:B.GoToSurface](ERROR): Altitude too low: 1.03 2023-10-02T23:11:15.740Z,1696288275.740 [Waterlinked](ERROR): only read 3 of 5 data items 2023-10-02T23:11:15.760Z,1696288275.760 [Default:B.GoToSurface](ERROR): Altitude too low: 1.03 2023-10-02T23:11:16.169Z,1696288276.169 [Default:B.GoToSurface](ERROR): Altitude too low: 1.02 2023-10-02T23:11:16.568Z,1696288276.568 [Default:B.GoToSurface](ERROR): Altitude too low: 1.05 2023-10-02T23:11:16.978Z,1696288276.978 [Default:B.GoToSurface](ERROR): Altitude too low: 1.02 2023-10-02T23:11:17.381Z,1696288277.381 [Default:B.GoToSurface](ERROR): Altitude too low: 1.05 2023-10-02T23:11:17.788Z,1696288277.788 [Default:B.GoToSurface](ERROR): Altitude too low: 1.03 2023-10-02T23:11:18.184Z,1696288278.184 [Default:B.GoToSurface](ERROR): Altitude too low: 1.06 2023-10-02T23:11:18.589Z,1696288278.589 [Default:B.GoToSurface](ERROR): Altitude too low: 1.06 2023-10-02T23:11:18.997Z,1696288278.997 [Default:B.GoToSurface](ERROR): Altitude too low: 1.10 2023-10-02T23:11:19.428Z,1696288279.428 [Default:B.GoToSurface](ERROR): Altitude too low: 1.11 2023-10-02T23:11:19.802Z,1696288279.802 [Default:B.GoToSurface](ERROR): Altitude too low: 1.11 2023-10-02T23:11:20.200Z,1696288280.200 [Default:B.GoToSurface](ERROR): Altitude too low: 1.14 2023-10-02T23:11:20.605Z,1696288280.605 [Default:B.GoToSurface](ERROR): Altitude too low: 1.13 2023-10-02T23:11:21.005Z,1696288281.005 [Default:B.GoToSurface](ERROR): Altitude too low: 1.15 2023-10-02T23:11:21.438Z,1696288281.438 [Default:B.GoToSurface](ERROR): Altitude too low: 1.17 2023-10-02T23:11:21.857Z,1696288281.857 [Default:B.GoToSurface](ERROR): Altitude too low: 1.15 2023-10-02T23:11:22.180Z,1696288282.180 [Default:B.GoToSurface](ERROR): Altitude too low: 1.15 2023-10-02T23:11:22.642Z,1696288282.642 [Default:B.GoToSurface](ERROR): Altitude too low: 1.21 2023-10-02T23:11:23.027Z,1696288283.027 [Default:B.GoToSurface](ERROR): Altitude too low: 1.21 2023-10-02T23:11:23.446Z,1696288283.446 [Default:B.GoToSurface](ERROR): Altitude too low: 1.23 2023-10-02T23:11:23.861Z,1696288283.861 [Default:B.GoToSurface](ERROR): Altitude too low: 1.24 2023-10-02T23:11:24.255Z,1696288284.255 [Default:B.GoToSurface](ERROR): Altitude too low: 1.28 2023-10-02T23:11:24.600Z,1696288284.600 [Default:B.GoToSurface](ERROR): Altitude too low: 1.30 2023-10-02T23:11:25.041Z,1696288285.041 [Default:B.GoToSurface](ERROR): Altitude too low: 1.29 2023-10-02T23:11:25.469Z,1696288285.469 [Default:B.GoToSurface](ERROR): Altitude too low: 1.36 2023-10-02T23:11:25.866Z,1696288285.866 [Default:B.GoToSurface](ERROR): Altitude too low: 1.31 2023-10-02T23:11:26.277Z,1696288286.277 [Default:B.GoToSurface](ERROR): Altitude too low: 1.32 2023-10-02T23:11:26.679Z,1696288286.679 [Default:B.GoToSurface](ERROR): Altitude too low: 1.35 2023-10-02T23:11:27.067Z,1696288287.067 [Default:B.GoToSurface](ERROR): Altitude too low: 1.35 2023-10-02T23:11:27.487Z,1696288287.487 [Default:B.GoToSurface](ERROR): Altitude too low: 1.37 2023-10-02T23:11:27.876Z,1696288287.876 [Default:B.GoToSurface](ERROR): Altitude too low: 1.39 2023-10-02T23:11:28.278Z,1696288288.278 [Default:B.GoToSurface](ERROR): Altitude too low: 1.44 2023-10-02T23:11:28.690Z,1696288288.690 [Default:B.GoToSurface](ERROR): Altitude too low: 1.43 2023-10-02T23:11:29.044Z,1696288289.044 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error 2023-10-02T23:11:29.055Z,1696288289.055 [Default:B.GoToSurface](ERROR): Altitude too low: 1.43 2023-10-02T23:11:29.500Z,1696288289.500 [Default:B.GoToSurface](ERROR): Altitude too low: 1.43 2023-10-02T23:11:29.919Z,1696288289.919 [Default:B.GoToSurface](ERROR): Altitude too low: 1.47 2023-10-02T23:11:30.279Z,1696288290.279 [Default:B.GoToSurface](ERROR): Altitude too low: 1.45 2023-10-02T23:11:30.738Z,1696288290.738 [Default:B.GoToSurface](ERROR): Altitude too low: 1.47 2023-10-02T23:11:31.132Z,1696288291.132 [Default:B.GoToSurface](ERROR): Altitude too low: 1.45 2023-10-02T23:11:31.511Z,1696288291.511 [Default:B.GoToSurface](ERROR): Altitude too low: 1.48 2023-10-02T23:11:31.930Z,1696288291.930 [Default:B.GoToSurface](ERROR): Altitude too low: 1.44 2023-10-02T23:11:32.315Z,1696288292.315 [Default:B.GoToSurface](ERROR): Altitude too low: 1.17 2023-10-02T23:11:32.775Z,1696288292.775 [Default:B.GoToSurface](ERROR): Altitude too low: 1.22 2023-10-02T23:11:33.139Z,1696288293.139 [Default:B.GoToSurface](ERROR): Altitude too low: 1.45 2023-10-02T23:11:33.545Z,1696288293.545 [Default:B.GoToSurface](ERROR): Altitude too low: 1.42 2023-10-02T23:11:33.555Z,1696288293.555 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-02T23:11:33.886Z,1696288293.885 [Default:B.GoToSurface](ERROR): Altitude too low: 1.42 2023-10-02T23:11:34.352Z,1696288294.352 [Default:B.GoToSurface](ERROR): Altitude too low: 1.44 2023-10-02T23:11:34.693Z,1696288294.693 [Default:B.GoToSurface](ERROR): Altitude too low: 1.43 2023-10-02T23:11:35.160Z,1696288295.160 [Default:B.GoToSurface](ERROR): Altitude too low: 1.21 2023-10-02T23:11:35.550Z,1696288295.550 [Default:B.GoToSurface](ERROR): Altitude too low: 1.42 2023-10-02T23:11:35.902Z,1696288295.902 [Default:B.GoToSurface](ERROR): Altitude too low: 1.42 2023-10-02T23:11:36.370Z,1696288296.370 [Default:B.GoToSurface](ERROR): Altitude too low: 1.43 2023-10-02T23:11:36.780Z,1696288296.780 [Default:B.GoToSurface](ERROR): Altitude too low: 1.43 2023-10-02T23:11:37.175Z,1696288297.175 [Default:B.GoToSurface](ERROR): Altitude too low: 1.40 2023-10-02T23:11:37.580Z,1696288297.580 [Default:B.GoToSurface](ERROR): Altitude too low: 1.40 2023-10-02T23:11:37.932Z,1696288297.932 [Default:B.GoToSurface](ERROR): Altitude too low: 1.40 2023-10-02T23:11:38.424Z,1696288298.424 [Default:B.GoToSurface](ERROR): Altitude too low: 1.43 2023-10-02T23:11:38.809Z,1696288298.809 [Default:B.GoToSurface](ERROR): Altitude too low: 1.43 2023-10-02T23:11:39.195Z,1696288299.195 [Default:B.GoToSurface](ERROR): Altitude too low: 1.43 2023-10-02T23:11:39.568Z,1696288299.568 [Default:B.GoToSurface](ERROR): Altitude too low: 1.39 2023-10-02T23:11:40.816Z,1696288300.816 [Default:B.GoToSurface](ERROR): Altitude too low: 1.49 2023-10-02T23:11:41.192Z,1696288301.192 [Default:B.GoToSurface](ERROR): Altitude too low: 1.48 2023-10-02T23:11:41.608Z,1696288301.608 [Waterlinked](ERROR): only read 3 of 5 data items 2023-10-02T23:11:41.647Z,1696288301.647 [Default:B.GoToSurface](ERROR): Altitude too low: 1.48 2023-10-02T23:11:41.974Z,1696288301.974 [Default:B.GoToSurface](ERROR): Altitude too low: 1.48 2023-10-02T23:11:42.437Z,1696288302.437 [Default:B.GoToSurface](ERROR): Altitude too low: 1.55 2023-10-02T23:11:42.833Z,1696288302.833 [Default:B.GoToSurface](ERROR): Altitude too low: 1.56 2023-10-02T23:11:43.210Z,1696288303.210 [Default:B.GoToSurface](ERROR): Altitude too low: 1.56 2023-10-02T23:11:43.664Z,1696288303.664 [Default:B.GoToSurface](ERROR): Altitude too low: 1.59 2023-10-02T23:11:44.080Z,1696288304.080 [Default:B.GoToSurface](ERROR): Altitude too low: 1.60 2023-10-02T23:11:44.453Z,1696288304.453 [Default:B.GoToSurface](ERROR): Altitude too low: 1.61 2023-10-02T23:11:44.788Z,1696288304.788 [Default:B.GoToSurface](ERROR): Altitude too low: 1.57 2023-10-02T23:11:45.225Z,1696288305.225 [Waterlinked](ERROR): only read 2 of 5 data items 2023-10-02T23:11:45.236Z,1696288305.236 [Default:B.GoToSurface](ERROR): Altitude too low: 1.57 2023-10-02T23:11:45.657Z,1696288305.657 [Default:B.GoToSurface](ERROR): Altitude too low: 1.63 2023-10-02T23:11:46.042Z,1696288306.042 [Default:B.GoToSurface](ERROR): Altitude too low: 1.72 2023-10-02T23:11:47.284Z,1696288307.284 [Default:B.GoToSurface](ERROR): Altitude too low: 1.24 2023-10-02T23:11:47.682Z,1696288307.682 [Default:B.GoToSurface](ERROR): Altitude too low: 1.28 2023-10-02T23:11:48.092Z,1696288308.092 [Default:B.GoToSurface](ERROR): Altitude too low: 1.35 2023-10-02T23:11:48.464Z,1696288308.464 [Default:B.GoToSurface](ERROR): Altitude too low: 1.41 2023-10-02T23:11:50.436Z,1696288310.436 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error 2023-10-02T23:11:52.459Z,1696288312.459 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error 2023-10-02T23:11:53.307Z,1696288313.307 [Waterlinked](ERROR): only read 0 of 1 data item for altitude 2023-10-02T23:11:56.498Z,1696288316.498 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error 2023-10-02T23:12:04.224Z,1696288324.224 [Waterlinked](ERROR): only read 3 of 5 data items 2023-10-02T23:12:04.695Z,1696288324.695 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-02T23:12:08.268Z,1696288328.268 [Waterlinked](ERROR): only read 3 of 5 data items 2023-10-02T23:12:11.066Z,1696288331.066 [Default:CheckIn] Running Loop=1 2023-10-02T23:12:11.067Z,1696288331.067 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-02T23:12:11.067Z,1696288331.067 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-02T23:12:24.918Z,1696288344.918 [CBIT](INFO): Clearing failed state for component NAL9602 2023-10-02T23:12:24.918Z,1696288344.918 [NAL9602] No Fault, FailCount= 5 2023-10-02T23:12:25.171Z,1696288345.171 [NAL9602](INFO): Powering up NAL9602 2023-10-02T23:12:31.237Z,1696288351.237 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error 2023-10-02T23:12:35.819Z,1696288355.819 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-02T23:12:36.065Z,1696288356.065 [NAL9602](INFO): NAL9602 initialized 2023-10-02T23:12:44.591Z,1696288364.591 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error 2023-10-02T23:13:06.943Z,1696288386.943 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-02T23:13:29.844Z,1696288409.844 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error 2023-10-02T23:13:38.075Z,1696288418.075 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-02T23:14:02.192Z,1696288442.192 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19 2023-10-02T23:14:02.192Z,1696288442.192 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-02T23:14:02.193Z,1696288442.193 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 19 2023-10-02T23:14:02.193Z,1696288442.193 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-10-02T23:14:02.194Z,1696288442.194 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 19 2023-10-02T23:14:02.194Z,1696288442.194 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-02T23:14:02.259Z,1696288442.259 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-02T23:14:02.260Z,1696288442.260 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-10-02T23:14:02.260Z,1696288442.260 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-02T23:14:02.635Z,1696288442.635 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-02T23:14:02.635Z,1696288442.635 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19 2023-10-02T23:14:02.636Z,1696288442.636 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-10-02T23:14:02.636Z,1696288442.636 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 19 2023-10-02T23:14:02.636Z,1696288442.636 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-02T23:14:02.636Z,1696288442.636 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 19 2023-10-02T23:14:02.967Z,1696288442.967 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-02T23:14:09.207Z,1696288449.207 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-02T23:14:18.607Z,1696288458.607 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003054 2023-10-02T23:14:32.055Z,1696288472.055 [Waterlinked](ERROR): only read 3 of 5 data items 2023-10-02T23:15:19.532Z,1696288519.532 [DataOverHttps](IMPORTANT): SBD MTMSN=20231002T231512 2023-10-02T23:15:27.942Z,1696288527.942 [DataOverHttps](INFO): Received command: stop 2023-10-02T23:15:27.947Z,1696288527.947 [CommandExec](IMPORTANT): got command stop 2023-10-02T23:15:27.948Z,1696288527.948 [CommandExec](IMPORTANT): Scheduling is paused 2023-10-02T23:16:44.256Z,1696288604.256 [Waterlinked](ERROR): only read 3 of 5 data items 2023-10-02T23:17:03.184Z,1696288623.184 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 20 2023-10-02T23:17:03.184Z,1696288623.184 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-02T23:17:03.185Z,1696288623.185 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 20 2023-10-02T23:17:03.185Z,1696288623.185 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-10-02T23:17:03.186Z,1696288623.186 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 20 2023-10-02T23:17:03.186Z,1696288623.186 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-02T23:17:03.268Z,1696288623.268 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-02T23:17:03.269Z,1696288623.269 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-10-02T23:17:03.270Z,1696288623.270 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-02T23:17:03.668Z,1696288623.668 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-02T23:17:03.668Z,1696288623.668 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 20 2023-10-02T23:17:03.669Z,1696288623.669 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-10-02T23:17:03.669Z,1696288623.669 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 20 2023-10-02T23:17:03.669Z,1696288623.669 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-02T23:17:03.669Z,1696288623.669 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 20 2023-10-02T23:17:04.011Z,1696288624.011 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-02T23:17:11.345Z,1696288631.345 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-10-02T23:12:11.1Z 2023-10-02T23:17:11.345Z,1696288631.345 [Default:CheckIn:Read_GPS] Stopped 2023-10-02T23:17:11.351Z,1696288631.351 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-02T23:17:21.454Z,1696288641.454 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20231002T221631/Courier0007.lzma 2023-10-02T23:17:22.459Z,1696288642.459 [DataOverHttps](INFO): Moved sent file to Logs/20231002T221631/Courier0007.lzma.bak 2023-10-02T23:17:22.459Z,1696288642.459 [DataOverHttps](INFO): SBD MOMSN=18977203 2023-10-02T23:17:24.207Z,1696288644.207 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error 2023-10-02T23:17:40.346Z,1696288660.346 [DataOverHttps](INFO): Sending 221 bytes from file Logs/20231002T221631/Courier0010.lzma 2023-10-02T23:17:41.357Z,1696288661.357 [DataOverHttps](INFO): Moved sent file to Logs/20231002T221631/Courier0010.lzma.bak 2023-10-02T23:17:41.358Z,1696288661.358 [DataOverHttps](INFO): SBD MOMSN=18977205 2023-10-02T23:17:46.080Z,1696288666.080 [Waterlinked](ERROR): only read 3 of 5 data items 2023-10-02T23:17:48.463Z,1696288668.463 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error 2023-10-02T23:17:51.718Z,1696288671.718 [Waterlinked](ERROR): only read 0 of 1 data item for altitude 2023-10-02T23:17:57.583Z,1696288677.583 [CommandLine](INFO): End of History 2023-10-02T23:17:57.585Z,1696288677.585 [CommandLine](INFO): End of History 2023-10-02T23:17:57.586Z,1696288677.586 [CommandLine](INFO): End of History 2023-10-02T23:17:57.591Z,1696288677.591 [CommandLine](INFO): End of History 2023-10-02T23:17:57.593Z,1696288677.593 [CommandLine](INFO): End of History 2023-10-02T23:17:57.594Z,1696288677.594 [CommandLine](INFO): End of History 2023-10-02T23:17:57.616Z,1696288677.616 [CommandLine](INFO): End of History 2023-10-02T23:17:57.617Z,1696288677.617 [CommandLine](INFO): End of History 2023-10-02T23:17:57.623Z,1696288677.623 [CommandLine](INFO): End of History 2023-10-02T23:17:57.625Z,1696288677.625 [CommandLine](INFO): End of History 2023-10-02T23:17:57.626Z,1696288677.626 [CommandLine](INFO): End of History 2023-10-02T23:17:57.632Z,1696288677.632 [CommandLine](INFO): End of History 2023-10-02T23:17:57.633Z,1696288677.633 [CommandLine](INFO): End of History 2023-10-02T23:17:57.634Z,1696288677.634 [CommandLine](INFO): End of History 2023-10-02T23:17:57.640Z,1696288677.640 [CommandLine](INFO): End of History 2023-10-02T23:17:57.641Z,1696288677.641 [CommandLine](INFO): End of History 2023-10-02T23:17:57.642Z,1696288677.642 [CommandLine](INFO): End of History 2023-10-02T23:17:57.648Z,1696288677.648 [CommandLine](INFO): End of History 2023-10-02T23:17:57.649Z,1696288677.649 [CommandLine](INFO): End of History 2023-10-02T23:17:57.667Z,1696288677.667 [CommandLine](INFO): End of History 2023-10-02T23:17:57.668Z,1696288677.668 [CommandLine](INFO): End of History 2023-10-02T23:17:57.669Z,1696288677.669 [CommandLine](INFO): End of History 2023-10-02T23:17:57.679Z,1696288677.679 [CommandLine](INFO): End of History 2023-10-02T23:17:57.680Z,1696288677.680 [CommandLine](INFO): End of History 2023-10-02T23:17:57.681Z,1696288677.681 [CommandLine](INFO): End of History 2023-10-02T23:17:57.695Z,1696288677.695 [CommandLine](INFO): End of History 2023-10-02T23:18:05.150Z,1696288685.150 [DataOverHttps](INFO): Sending 317 bytes from file Logs/20231002T221631/Express0008.lzma 2023-10-02T23:18:06.149Z,1696288686.149 [DataOverHttps](INFO): Moved sent file to Logs/20231002T221631/Express0008.lzma.bak 2023-10-02T23:18:06.149Z,1696288686.149 [DataOverHttps](INFO): SBD MOMSN=18977208 2023-10-02T23:18:15.550Z,1696288695.550 [Waterlinked](ERROR): only read 4 of 5 data items 2023-10-02T23:18:24.490Z,1696288704.490 [DataOverHttps](INFO): Sending 1587 bytes from file Logs/20231002T221631/Express0011.lzma 2023-10-02T23:18:24.865Z,1696288704.865 [Waterlinked](ERROR): only read 0 of 1 data item for altitude 2023-10-02T23:18:25.488Z,1696288705.488 [DataOverHttps](INFO): Moved sent file to Logs/20231002T221631/Express0011.lzma.bak 2023-10-02T23:18:25.489Z,1696288705.489 [DataOverHttps](INFO): SBD MOMSN=18977215 2023-10-02T23:18:26.652Z,1696288706.652 [CommandExec](IMPORTANT): got command burn off 2023-10-02T23:18:26.652Z,1696288706.652 [CommandExec](IMPORTANT): Deactivating dropweight wire 2023-10-02T23:18:27.671Z,1696288707.671 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error 2023-10-02T23:18:48.487Z,1696288728.487 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20231002T221631/Express0014.lzma 2023-10-02T23:18:49.489Z,1696288729.489 [DataOverHttps](INFO): Moved sent file to Logs/20231002T221631/Express0014.lzma.bak 2023-10-02T23:18:49.489Z,1696288729.489 [DataOverHttps](INFO): SBD MOMSN=18977236 2023-10-02T23:18:52.952Z,1696288732.952 [Default:CheckIn:Read_Iridium] Stopped 2023-10-02T23:18:52.953Z,1696288732.953 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-02T23:18:52.953Z,1696288732.953 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-02T23:19:04.067Z,1696288744.067 [CommandExec](IMPORTANT): got command failComponent 2023-10-02T23:19:04.067Z,1696288744.067 [CommandExec](IMPORTANT): Failed components: 2023-10-02T23:19:04.068Z,1696288744.068 [CommandExec](IMPORTANT): No failed Components. 2023-10-02T23:19:26.381Z,1696288766.381 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,231919.00,A,3648.16450,N,12147.28041,W,0.175,0.00,021023,,,A*7D 2023-10-02T23:19:26.398Z,1696288766.398 [NAL9602](INFO): GPS fix at 20231002T231919: (36.802742, -121.788007) 2023-10-02T23:19:58.683Z,1696288798.683 [NAL9602](INFO): Not Powering down - fast GPS 2023-10-02T23:20:20.536Z,1696288820.536 [Waterlinked](ERROR): only read 3 of 5 data items 2023-10-02T23:20:20.549Z,1696288820.549 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2023-10-02T23:20:20.549Z,1696288820.549 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-02T23:20:20.647Z,1696288820.647 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-02T23:20:21.019Z,1696288821.019 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-02T23:20:21.019Z,1696288821.019 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2023-10-02T23:20:41.172Z,1696288841.172 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error 2023-10-02T23:20:45.289Z,1696288845.289 [Waterlinked](ERROR): only read 3 of 5 data items 2023-10-02T23:21:50.739Z,1696288910.739 [Waterlinked](INFO): Powering down 2023-10-02T23:23:21.667Z,1696289001.667 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2023-10-02T23:23:21.667Z,1696289001.667 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-02T23:23:21.739Z,1696289001.739 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-02T23:23:22.121Z,1696289002.121 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-02T23:23:22.121Z,1696289002.121 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2023-10-02T23:23:53.579Z,1696289033.579 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-02T23:23:53.579Z,1696289033.579 [Default:CheckIn:C.Wait] Stopped 2023-10-02T23:23:53.579Z,1696289033.579 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-02T23:23:53.580Z,1696289033.580 [Default:CheckIn:D] Running Loop=1 2023-10-02T23:23:53.974Z,1696289033.974 [Default:CheckIn:D] Stopped 2023-10-02T23:23:53.974Z,1696289033.974 [Default:CheckIn:E] Running Loop=1 2023-10-02T23:23:54.377Z,1696289034.377 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 13.465255 min 2023-10-02T23:23:54.377Z,1696289034.377 [Default:CheckIn:E] Stopped 2023-10-02T23:23:54.377Z,1696289034.377 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-02T23:23:54.377Z,1696289034.377 [Default:CheckIn] Stopped 2023-10-02T23:23:54.377Z,1696289034.377 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-02T23:23:54.378Z,1696289034.378 [Default:CheckIn](INFO): Running loop #2 2023-10-02T23:23:54.378Z,1696289034.378 [Default:CheckIn] Running Loop=2 2023-10-02T23:23:54.378Z,1696289034.378 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-02T23:23:54.378Z,1696289034.378 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-02T23:23:56.386Z,1696289036.386 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232349.00,A,3648.16359,N,12147.28106,W,0.156,0.00,021023,,,A*7C 2023-10-02T23:23:56.388Z,1696289036.388 [NAL9602](INFO): GPS fix at 20231002T232349: (36.802726, -121.788018) 2023-10-02T23:23:56.404Z,1696289036.404 [Default:CheckIn:Read_GPS] Stopped 2023-10-02T23:23:56.404Z,1696289036.404 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-02T23:24:04.026Z,1696289044.026 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20231002T221631/Courier0016.lzma 2023-10-02T23:24:05.028Z,1696289045.028 [DataOverHttps](INFO): Moved sent file to Logs/20231002T221631/Courier0016.lzma.bak 2023-10-02T23:24:05.028Z,1696289045.028 [DataOverHttps](INFO): SBD MOMSN=18977244 2023-10-02T23:24:22.255Z,1696289062.255 [DataOverHttps](INFO): Sending 392 bytes from file Logs/20231002T221631/Express0017.lzma 2023-10-02T23:24:23.257Z,1696289063.257 [DataOverHttps](INFO): Moved sent file to Logs/20231002T221631/Express0017.lzma.bak 2023-10-02T23:24:23.257Z,1696289063.257 [DataOverHttps](INFO): SBD MOMSN=18977247 2023-10-02T23:24:25.543Z,1696289065.543 [Default:CheckIn:Read_Iridium] Stopped 2023-10-02T23:24:25.543Z,1696289065.543 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-02T23:24:25.543Z,1696289065.543 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-02T23:26:22.673Z,1696289182.673 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2023-10-02T23:26:22.673Z,1696289182.673 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-02T23:26:22.739Z,1696289182.739 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-02T23:26:23.151Z,1696289183.151 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-02T23:26:23.151Z,1696289183.151 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2023-10-02T23:28:58.714Z,1696289338.714 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-10-02T23:29:23.783Z,1696289363.783 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2023-10-02T23:29:23.783Z,1696289363.783 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-02T23:29:23.863Z,1696289363.863 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-02T23:29:24.227Z,1696289364.227 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-02T23:29:24.227Z,1696289364.227 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2023-10-02T23:29:26.189Z,1696289366.189 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-02T23:29:26.189Z,1696289366.189 [Default:CheckIn:C.Wait] Stopped 2023-10-02T23:29:26.189Z,1696289366.189 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-02T23:29:26.189Z,1696289366.189 [Default:CheckIn:D] Running Loop=1 2023-10-02T23:29:26.602Z,1696289366.602 [Default:CheckIn:D] Stopped 2023-10-02T23:29:26.602Z,1696289366.602 [Default:CheckIn:E] Running Loop=1 2023-10-02T23:29:26.995Z,1696289366.995 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 19.009062 min 2023-10-02T23:29:26.995Z,1696289366.995 [Default:CheckIn:E] Stopped 2023-10-02T23:29:26.995Z,1696289366.995 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-02T23:29:26.996Z,1696289366.996 [Default:CheckIn] Stopped 2023-10-02T23:29:26.996Z,1696289366.996 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-02T23:29:26.996Z,1696289366.996 [Default:CheckIn](INFO): Running loop #3 2023-10-02T23:29:26.996Z,1696289366.996 [Default:CheckIn] Running Loop=3 2023-10-02T23:29:26.996Z,1696289366.996 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-02T23:29:26.996Z,1696289366.996 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-02T23:29:29.005Z,1696289369.005 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232921.00,A,3648.16522,N,12147.28421,W,0.156,0.00,021023,,,A*72 2023-10-02T23:29:29.008Z,1696289369.008 [NAL9602](INFO): GPS fix at 20231002T232921: (36.802754, -121.788070) 2023-10-02T23:29:29.023Z,1696289369.023 [Default:CheckIn:Read_GPS] Stopped 2023-10-02T23:29:29.024Z,1696289369.024 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-02T23:29:36.062Z,1696289376.062 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231002T221631/Courier0019.lzma 2023-10-02T23:29:37.064Z,1696289377.064 [DataOverHttps](INFO): Moved sent file to Logs/20231002T221631/Courier0019.lzma.bak 2023-10-02T23:29:37.064Z,1696289377.064 [DataOverHttps](INFO): SBD MOMSN=18977255 2023-10-02T23:29:53.111Z,1696289393.111 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20231002T221631/Express0020.lzma 2023-10-02T23:29:54.112Z,1696289394.112 [DataOverHttps](INFO): Moved sent file to Logs/20231002T221631/Express0020.lzma.bak 2023-10-02T23:29:54.112Z,1696289394.112 [DataOverHttps](INFO): SBD MOMSN=18977258 2023-10-02T23:29:56.210Z,1696289396.210 [Default:CheckIn:Read_Iridium] Stopped 2023-10-02T23:29:56.210Z,1696289396.210 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-02T23:29:56.210Z,1696289396.210 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-02T23:30:01.832Z,1696289401.832 [NAL9602](INFO): Not Powering down - fast GPS 2023-10-02T23:31:48.111Z,1696289508.111 [CommandExec](IMPORTANT): got command gfscan 2023-10-02T23:31:48.216Z,1696289508.216 [CBIT](IMPORTANT): Beginning ground fault scan 2023-10-02T23:31:59.119Z,1696289519.119 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.000552 CHAN A1 (24V): 0.003364 CHAN A2 (12V): 0.001988 CHAN A3 (5V): -0.001256 CHAN B0 (3.3V): 0.000502 CHAN B1 (3.15aV): -0.000252 CHAN B2 (3.15bV): 0.000220 CHAN B3 (GND): -0.000592 OPEN: 0.006948 Full Scale: +/- 1 mA 2023-10-02T23:32:24.921Z,1696289544.921 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2023-10-02T23:32:24.921Z,1696289544.921 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-02T23:32:24.987Z,1696289544.987 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-02T23:32:25.395Z,1696289545.395 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-02T23:32:25.395Z,1696289545.395 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2023-10-02T23:34:56.931Z,1696289696.931 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-02T23:34:56.931Z,1696289696.931 [Default:CheckIn:C.Wait] Stopped 2023-10-02T23:34:56.931Z,1696289696.931 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-02T23:34:56.932Z,1696289696.932 [Default:CheckIn:D] Running Loop=1 2023-10-02T23:34:57.340Z,1696289697.340 [Default:CheckIn:D] Stopped 2023-10-02T23:34:57.341Z,1696289697.341 [Default:CheckIn:E] Running Loop=1 2023-10-02T23:34:57.745Z,1696289697.745 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 24.521368 min 2023-10-02T23:34:57.745Z,1696289697.745 [Default:CheckIn:E] Stopped 2023-10-02T23:34:57.745Z,1696289697.745 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-02T23:34:57.745Z,1696289697.745 [Default:CheckIn] Stopped 2023-10-02T23:34:57.745Z,1696289697.745 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-02T23:34:57.746Z,1696289697.746 [Default:CheckIn](INFO): Running loop #4 2023-10-02T23:34:57.746Z,1696289697.746 [Default:CheckIn] Running Loop=4 2023-10-02T23:34:57.746Z,1696289697.746 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-02T23:34:57.746Z,1696289697.746 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-02T23:34:59.753Z,1696289699.753 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,233452.00,A,3648.16494,N,12147.28274,W,0.233,0.00,021023,,,A*70 2023-10-02T23:34:59.764Z,1696289699.764 [NAL9602](INFO): GPS fix at 20231002T233452: (36.802749, -121.788046) 2023-10-02T23:34:59.780Z,1696289699.780 [Default:CheckIn:Read_GPS] Stopped 2023-10-02T23:34:59.780Z,1696289699.780 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-02T23:35:07.460Z,1696289707.460 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20231002T221631/Courier0022.lzma 2023-10-02T23:35:08.460Z,1696289708.460 [DataOverHttps](INFO): Moved sent file to Logs/20231002T221631/Courier0022.lzma.bak 2023-10-02T23:35:08.461Z,1696289708.461 [DataOverHttps](INFO): SBD MOMSN=18977270 2023-10-02T23:35:25.664Z,1696289725.664 [DataOverHttps](INFO): Sending 341 bytes from file Logs/20231002T221631/Express0023.lzma 2023-10-02T23:35:26.021Z,1696289726.021 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2023-10-02T23:35:26.021Z,1696289726.021 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-02T23:35:26.099Z,1696289726.099 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-02T23:35:26.495Z,1696289726.495 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-02T23:35:26.495Z,1696289726.495 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2023-10-02T23:35:26.664Z,1696289726.664 [DataOverHttps](INFO): Moved sent file to Logs/20231002T221631/Express0023.lzma.bak 2023-10-02T23:35:26.665Z,1696289726.665 [DataOverHttps](INFO): SBD MOMSN=18977273 2023-10-02T23:35:28.888Z,1696289728.888 [Default:CheckIn:Read_Iridium] Stopped 2023-10-02T23:35:28.889Z,1696289728.889 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-02T23:35:28.889Z,1696289728.889 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-02T23:35:30.451Z,1696289730.451 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-10-02T23:35:30.967Z,1696289730.967 [NAL9602](ERROR): received: 2023-10-02T23:37:16.607Z,1696289836.607 [CommandExec](IMPORTANT): got command gfscan 2023-10-02T23:37:16.735Z,1696289836.735 [CBIT](IMPORTANT): Beginning ground fault scan 2023-10-02T23:37:27.591Z,1696289847.591 [CBIT](IMPORTANT): Low side ground fault detected mA: CHAN A0 (Batt): 0.830202 CHAN A1 (24V): 0.805371 CHAN A2 (12V): 0.542081 CHAN A3 (5V): 0.122892 CHAN B0 (3.3V): 0.055685 CHAN B1 (3.15aV): 0.054607 CHAN B2 (3.15bV): 0.054628 CHAN B3 (GND): -0.121118 OPEN: 0.012590 Full Scale: +/- 1 mA 2023-10-02T23:38:26.909Z,1696289906.909 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2023-10-02T23:38:26.909Z,1696289906.909 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-02T23:38:26.983Z,1696289906.983 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-02T23:38:27.391Z,1696289907.391 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-02T23:38:27.391Z,1696289907.391 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2023-10-02T23:40:01.576Z,1696290001.576 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-10-02T23:40:18.675Z,1696290018.675 [CommandExec](IMPORTANT): got command gfscan 2023-10-02T23:40:19.020Z,1696290019.020 [CBIT](IMPORTANT): Beginning ground fault scan 2023-10-02T23:40:29.468Z,1696290029.468 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-02T23:40:29.468Z,1696290029.468 [Default:CheckIn:C.Wait] Stopped 2023-10-02T23:40:29.468Z,1696290029.468 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-02T23:40:29.469Z,1696290029.469 [Default:CheckIn:D] Running Loop=1 2023-10-02T23:40:29.871Z,1696290029.871 [Default:CheckIn:D] Stopped 2023-10-02T23:40:29.871Z,1696290029.871 [Default:CheckIn:E] Running Loop=1 2023-10-02T23:40:29.940Z,1696290029.940 [CBIT](IMPORTANT): Low side ground fault detected mA: CHAN A0 (Batt): 0.826343 CHAN A1 (24V): 0.803816 CHAN A2 (12V): 0.539003 CHAN A3 (5V): 0.121963 CHAN B0 (3.3V): 0.050393 CHAN B1 (3.15aV): 0.049573 CHAN B2 (3.15bV): 0.048874 CHAN B3 (GND): -0.125253 OPEN: 0.012812 Full Scale: +/- 1 mA 2023-10-02T23:40:30.273Z,1696290030.273 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 30.063538 min 2023-10-02T23:40:30.273Z,1696290030.273 [Default:CheckIn:E] Stopped 2023-10-02T23:40:30.273Z,1696290030.273 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-02T23:40:30.273Z,1696290030.273 [Default:CheckIn] Stopped 2023-10-02T23:40:30.273Z,1696290030.273 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-02T23:40:30.273Z,1696290030.273 [Default:CheckIn](INFO): Running loop #5 2023-10-02T23:40:30.273Z,1696290030.273 [Default:CheckIn] Running Loop=5 2023-10-02T23:40:30.273Z,1696290030.273 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-02T23:40:30.273Z,1696290030.273 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-02T23:40:32.276Z,1696290032.276 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,234025.00,A,3648.16552,N,12147.28116,W,0.078,0.00,021023,,,A*72 2023-10-02T23:40:32.278Z,1696290032.278 [NAL9602](INFO): GPS fix at 20231002T234025: (36.802759, -121.788019) 2023-10-02T23:40:32.313Z,1696290032.313 [Default:CheckIn:Read_GPS] Stopped 2023-10-02T23:40:32.314Z,1696290032.314 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-02T23:40:41.218Z,1696290041.218 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231002T221631/Courier0025.lzma 2023-10-02T23:40:42.221Z,1696290042.221 [DataOverHttps](INFO): Moved sent file to Logs/20231002T221631/Courier0025.lzma.bak 2023-10-02T23:40:42.221Z,1696290042.221 [DataOverHttps](INFO): SBD MOMSN=18977279 2023-10-02T23:41:03.087Z,1696290063.087 [DataOverHttps](INFO): Sending 483 bytes from file Logs/20231002T221631/Express0026.lzma 2023-10-02T23:41:04.088Z,1696290064.088 [DataOverHttps](INFO): Moved sent file to Logs/20231002T221631/Express0026.lzma.bak 2023-10-02T23:41:04.089Z,1696290064.089 [DataOverHttps](INFO): SBD MOMSN=18977282 2023-10-02T23:41:04.625Z,1696290064.625 [NAL9602](INFO): Not Powering down - fast GPS 2023-10-02T23:41:06.265Z,1696290066.265 [Default:CheckIn:Read_Iridium] Stopped 2023-10-02T23:41:06.265Z,1696290066.265 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-02T23:41:06.265Z,1696290066.265 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-02T23:41:28.058Z,1696290088.058 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2023-10-02T23:41:28.058Z,1696290088.058 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-02T23:41:28.123Z,1696290088.123 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-02T23:41:28.531Z,1696290088.531 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-02T23:41:28.531Z,1696290088.531 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2023-10-02T23:43:46.475Z,1696290226.475 [CommandExec](IMPORTANT): got command gfscan 2023-10-02T23:43:46.700Z,1696290226.700 [CBIT](IMPORTANT): Beginning ground fault scan 2023-10-02T23:43:57.626Z,1696290237.626 [CBIT](IMPORTANT): Low side ground fault detected mA: CHAN A0 (Batt): 0.816745 CHAN A1 (24V): 0.802584 CHAN A2 (12V): 0.532965 CHAN A3 (5V): 0.118416 CHAN B0 (3.3V): 0.050658 CHAN B1 (3.15aV): 0.049981 CHAN B2 (3.15bV): 0.050249 CHAN B3 (GND): -0.121330 OPEN: 0.011951 Full Scale: +/- 1 mA 2023-10-02T23:44:29.050Z,1696290269.050 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2023-10-02T23:44:29.050Z,1696290269.050 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-02T23:44:29.113Z,1696290269.113 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-02T23:44:29.523Z,1696290269.523 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-02T23:44:29.523Z,1696290269.523 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2023-10-02T23:44:50.380Z,1696290290.380 [CommandExec](IMPORTANT): got command ! echo -i > /dev/loadC3 2023-10-02T23:44:53.220Z,1696290293.220 [CommandExec](IMPORTANT): got command gfscan 2023-10-02T23:44:53.420Z,1696290293.420 [CBIT](IMPORTANT): Beginning ground fault scan 2023-10-02T23:45:04.279Z,1696290304.279 [CBIT](IMPORTANT): Low side ground fault detected mA: CHAN A0 (Batt): 0.820580 CHAN A1 (24V): 0.802189 CHAN A2 (12V): 0.532012 CHAN A3 (5V): 0.116866 CHAN B0 (3.3V): 0.047001 CHAN B1 (3.15aV): 0.046565 CHAN B2 (3.15bV): 0.046729 CHAN B3 (GND): -0.125721 OPEN: 0.011842 Full Scale: +/- 1 mA 2023-10-02T23:45:23.952Z,1696290323.952 [CommandExec](IMPORTANT): got command ! echo -i > /dev/loadB2 2023-10-02T23:45:26.035Z,1696290326.035 [CommandExec](IMPORTANT): got command gfscan 2023-10-02T23:45:26.120Z,1696290326.120 [CBIT](IMPORTANT): Beginning ground fault scan 2023-10-02T23:45:37.003Z,1696290337.003 [CBIT](IMPORTANT): Low side ground fault detected mA: CHAN A0 (Batt): 0.821117 CHAN A1 (24V): 0.802242 CHAN A2 (12V): 0.531899 CHAN A3 (5V): 0.115029 CHAN B0 (3.3V): 0.046674 CHAN B1 (3.15aV): 0.045549 CHAN B2 (3.15bV): 0.045144 CHAN B3 (GND): -0.129123 OPEN: 0.011139 Full Scale: +/- 1 mA 2023-10-02T23:46:06.833Z,1696290366.833 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-02T23:46:06.833Z,1696290366.833 [Default:CheckIn:C.Wait] Stopped 2023-10-02T23:46:06.833Z,1696290366.833 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-02T23:46:06.834Z,1696290366.834 [Default:CheckIn:D] Running Loop=1 2023-10-02T23:46:07.254Z,1696290367.254 [Default:CheckIn:D] Stopped 2023-10-02T23:46:07.254Z,1696290367.254 [Default:CheckIn:E] Running Loop=1 2023-10-02T23:46:07.673Z,1696290367.673 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 35.686589 min 2023-10-02T23:46:07.673Z,1696290367.673 [Default:CheckIn:E] Stopped 2023-10-02T23:46:07.673Z,1696290367.673 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-02T23:46:07.673Z,1696290367.673 [Default:CheckIn] Stopped 2023-10-02T23:46:07.673Z,1696290367.673 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-02T23:46:07.673Z,1696290367.673 [Default:CheckIn](INFO): Running loop #6 2023-10-02T23:46:07.673Z,1696290367.673 [Default:CheckIn] Running Loop=6 2023-10-02T23:46:07.674Z,1696290367.674 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-02T23:46:07.674Z,1696290367.674 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-02T23:46:09.653Z,1696290369.653 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,234602.00,A,3648.16505,N,12147.28034,W,0.097,0.00,021023,,,A*73 2023-10-02T23:46:09.656Z,1696290369.656 [NAL9602](INFO): GPS fix at 20231002T234602: (36.802751, -121.788006) 2023-10-02T23:46:09.671Z,1696290369.671 [Default:CheckIn:Read_GPS] Stopped 2023-10-02T23:46:09.671Z,1696290369.671 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-02T23:46:17.630Z,1696290377.630 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231002T221631/Courier0028.lzma 2023-10-02T23:46:18.640Z,1696290378.640 [DataOverHttps](INFO): Moved sent file to Logs/20231002T221631/Courier0028.lzma.bak 2023-10-02T23:46:18.640Z,1696290378.640 [DataOverHttps](INFO): SBD MOMSN=18977298 2023-10-02T23:46:21.544Z,1696290381.544 [CommandExec](IMPORTANT): got command gfscan 2023-10-02T23:46:21.844Z,1696290381.844 [CBIT](IMPORTANT): Beginning ground fault scan 2023-10-02T23:46:32.795Z,1696290392.795 [CBIT](IMPORTANT): Low side ground fault detected mA: CHAN A0 (Batt): 0.820281 CHAN A1 (24V): 0.801935 CHAN A2 (12V): 0.530508 CHAN A3 (5V): 0.114809 CHAN B0 (3.3V): 0.044985 CHAN B1 (3.15aV): 0.044134 CHAN B2 (3.15bV): 0.045137 CHAN B3 (GND): -0.124352 OPEN: 0.011591 Full Scale: +/- 1 mA 2023-10-02T23:46:36.067Z,1696290396.067 [DataOverHttps](INFO): Sending 503 bytes from file Logs/20231002T221631/Express0029.lzma 2023-10-02T23:46:37.069Z,1696290397.069 [DataOverHttps](INFO): Moved sent file to Logs/20231002T221631/Express0029.lzma.bak 2023-10-02T23:46:37.069Z,1696290397.069 [DataOverHttps](INFO): SBD MOMSN=18977301 2023-10-02T23:46:39.622Z,1696290399.622 [Default:CheckIn:Read_Iridium] Stopped 2023-10-02T23:46:39.627Z,1696290399.627 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-02T23:46:39.627Z,1696290399.627 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-02T23:46:40.368Z,1696290400.368 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-10-02T23:46:40.911Z,1696290400.911 [NAL9602](ERROR): received: 2023-10-02T23:46:47.199Z,1696290407.199 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2023-10-02T23:46:47.200Z,1696290407.200 [DropWeight] Hardware Fault, FailCount= 1 2023-10-02T23:46:47.200Z,1696290407.200 [DropWeight](ERROR): Hardware Fault 2023-10-02T23:46:47.385Z,1696290407.385 [CommandExec](FAULT): Scheduling is paused 2023-10-02T23:46:47.385Z,1696290407.385 [CBIT](INFO): Critical error at 20231002T234647 2023-10-02T23:46:47.388Z,1696290407.388 [CBIT](ERROR): Hardware Fault in component: DropWeight 2023-10-02T23:46:47.388Z,1696290407.388 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2023-10-02T23:46:47.753Z,1696290407.753 [CBIT](INFO): Critical error at 20231002T234647 2023-10-02T23:46:56.775Z,1696290416.775 [CommandExec](IMPORTANT): got command gfscan 2023-10-02T23:46:57.008Z,1696290417.008 [CBIT](IMPORTANT): Beginning ground fault scan 2023-10-02T23:47:07.883Z,1696290427.883 [CBIT](IMPORTANT): Low side ground fault detected mA: CHAN A0 (Batt): 0.810426 CHAN A1 (24V): -0.145894 CHAN A2 (12V): 0.519935 CHAN A3 (5V): 0.114402 CHAN B0 (3.3V): 0.046750 CHAN B1 (3.15aV): 0.046321 CHAN B2 (3.15bV): 0.047276 CHAN B3 (GND): -0.119804 OPEN: 0.010883 Full Scale: +/- 1 mA 2023-10-02T23:47:12.245Z,1696290432.245 [NAL9602](INFO): SBD MO Status=2, MOMSN=7635, MT Status=2, MTMSN=0 2023-10-02T23:47:12.245Z,1696290432.245 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-10-02T23:47:30.026Z,1696290450.026 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2023-10-02T23:47:30.026Z,1696290450.026 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-02T23:47:30.091Z,1696290450.091 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-02T23:47:30.504Z,1696290450.504 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-02T23:47:30.504Z,1696290450.504 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2023-10-02T23:47:58.297Z,1696290478.297 [NAL9602](INFO): SBD MO Status=2, MOMSN=7635, MT Status=2, MTMSN=0 2023-10-02T23:47:58.297Z,1696290478.297 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-10-02T23:48:06.149Z,1696290486.149 [CommandExec](IMPORTANT): got command gfscan 2023-10-02T23:48:06.464Z,1696290486.464 [CBIT](IMPORTANT): Beginning ground fault scan 2023-10-02T23:48:17.363Z,1696290497.363 [CBIT](IMPORTANT): Low side ground fault detected mA: CHAN A0 (Batt): 0.807457 CHAN A1 (24V): -0.186550 CHAN A2 (12V): 0.519757 CHAN A3 (5V): 0.115221 CHAN B0 (3.3V): 0.047487 CHAN B1 (3.15aV): 0.046433 CHAN B2 (3.15bV): 0.046977 CHAN B3 (GND): -0.113324 OPEN: 0.011329 Full Scale: +/- 1 mA 2023-10-02T23:48:51.636Z,1696290531.636 [CommandExec](IMPORTANT): got command gfscan 2023-10-02T23:48:51.708Z,1696290531.708 [CBIT](IMPORTANT): Beginning ground fault scan 2023-10-02T23:49:02.647Z,1696290542.647 [CBIT](IMPORTANT): Low side ground fault detected mA: CHAN A0 (Batt): 0.802464 CHAN A1 (24V): -0.203846 CHAN A2 (12V): 0.515381 CHAN A3 (5V): 0.113829 CHAN B0 (3.3V): 0.048731 CHAN B1 (3.15aV): 0.045559 CHAN B2 (3.15bV): 0.046656 CHAN B3 (GND): -0.112541 OPEN: 0.011236 Full Scale: +/- 1 mA 2023-10-02T23:49:29.731Z,1696290569.731 [CommandExec](IMPORTANT): got command gfscan 2023-10-02T23:49:30.108Z,1696290570.108 [CBIT](IMPORTANT): Beginning ground fault scan 2023-10-02T23:49:40.998Z,1696290580.998 [CBIT](IMPORTANT): Low side ground fault detected mA: CHAN A0 (Batt): 0.811225 CHAN A1 (24V): -0.218203 CHAN A2 (12V): 0.519146 CHAN A3 (5V): 0.117337 CHAN B0 (3.3V): 0.049985 CHAN B1 (3.15aV): 0.047701 CHAN B2 (3.15bV): 0.048528 CHAN B3 (GND): -0.111443 OPEN: 0.011345 Full Scale: +/- 1 mA 2023-10-02T23:50:30.571Z,1696290630.571 [CommandExec](IMPORTANT): got command gfscan 2023-10-02T23:50:30.712Z,1696290630.712 [CBIT](IMPORTANT): Beginning ground fault scan 2023-10-02T23:50:31.019Z,1696290631.019 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2023-10-02T23:50:31.019Z,1696290631.019 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-02T23:50:31.084Z,1696290631.084 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-02T23:50:31.544Z,1696290631.544 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-02T23:50:31.544Z,1696290631.544 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2023-10-02T23:50:41.619Z,1696290641.619 [CBIT](IMPORTANT): Low side ground fault detected mA: CHAN A0 (Batt): 0.807463 CHAN A1 (24V): -0.239188 CHAN A2 (12V): 0.518346 CHAN A3 (5V): 0.112770 CHAN B0 (3.3V): 0.048254 CHAN B1 (3.15aV): 0.047233 CHAN B2 (3.15bV): 0.047054 CHAN B3 (GND): -0.110711 OPEN: 0.011174 Full Scale: +/- 1 mA 2023-10-02T23:51:09.860Z,1696290669.860 [CommandExec](IMPORTANT): got command gfscan 2023-10-02T23:51:09.876Z,1696290669.876 [CBIT](IMPORTANT): Beginning ground fault scan 2023-10-02T23:51:11.408Z,1696290671.408 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-10-02T23:51:20.688Z,1696290680.688 [AHRS_M2](FAULT): LCB fault: Current Limiter Activated. 2023-10-02T23:51:20.688Z,1696290680.688 [AHRS_M2] Hardware Fault, FailCount= 1 2023-10-02T23:51:20.688Z,1696290680.688 [AHRS_M2](ERROR): Hardware Fault 2023-10-02T23:51:20.800Z,1696290680.800 [CBIT](ERROR): Hardware Fault in component: AHRS_M2 2023-10-02T23:51:20.803Z,1696290680.803 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.803985 CHAN A1 (24V): -0.242716 CHAN A2 (12V): 0.513330 CHAN A3 (5V): 0.112164 CHAN B0 (3.3V): 0.045988 CHAN B1 (3.15aV): 0.045896 CHAN B2 (3.15bV): 0.000172 CHAN B3 (GND): -0.000794 OPEN: 0.011204 Full Scale: +/- 1 mA 2023-10-02T23:51:21.092Z,1696290681.092 [AHRS_M2](INFO): Powering down 2023-10-02T23:51:21.496Z,1696290681.496 [AHRS_M2](FAULT): LCB fault: Current Limiter Activated. 2023-10-02T23:51:21.999Z,1696290681.999 [CBIT](INFO): Clearing failed state for component AHRS_M2 2023-10-02T23:51:21.999Z,1696290681.999 [AHRS_M2] No Fault, FailCount= 1 2023-10-02T23:51:22.303Z,1696290682.303 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2023-10-02T23:51:24.643Z,1696290684.643 [CommandExec](IMPORTANT): got command gfscan 2023-10-02T23:51:24.812Z,1696290684.812 [CBIT](IMPORTANT): Beginning ground fault scan 2023-10-02T23:51:35.752Z,1696290695.752 [CBIT](IMPORTANT): Low side ground fault detected mA: CHAN A0 (Batt): 0.797653 CHAN A1 (24V): -0.250556 CHAN A2 (12V): 0.510567 CHAN A3 (5V): 0.110904 CHAN B0 (3.3V): 0.043881 CHAN B1 (3.15aV): 0.042077 CHAN B2 (3.15bV): 0.043024 CHAN B3 (GND): -0.120403 OPEN: 0.010238 Full Scale: +/- 1 mA 2023-10-02T23:51:40.112Z,1696290700.112 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-02T23:51:40.112Z,1696290700.112 [Default:CheckIn:C.Wait] Stopped 2023-10-02T23:51:40.112Z,1696290700.112 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-02T23:51:40.113Z,1696290700.113 [Default:CheckIn:D] Running Loop=1 2023-10-02T23:51:40.513Z,1696290700.513 [Default:CheckIn:D] Stopped 2023-10-02T23:51:40.514Z,1696290700.514 [Default:CheckIn:E] Running Loop=1 2023-10-02T23:51:40.927Z,1696290700.927 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 41.240918 min 2023-10-02T23:51:40.927Z,1696290700.927 [Default:CheckIn:E] Stopped 2023-10-02T23:51:40.927Z,1696290700.927 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-02T23:51:40.927Z,1696290700.927 [Default:CheckIn] Stopped 2023-10-02T23:51:40.927Z,1696290700.927 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-02T23:51:40.928Z,1696290700.928 [Default:CheckIn](INFO): Running loop #7 2023-10-02T23:51:40.928Z,1696290700.928 [Default:CheckIn] Running Loop=7 2023-10-02T23:51:40.928Z,1696290700.928 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-02T23:51:40.928Z,1696290700.928 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-02T23:51:42.925Z,1696290702.925 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235135.00,A,3648.16823,N,12147.28216,W,0.447,0.00,021023,,,A*73 2023-10-02T23:51:42.928Z,1696290702.928 [NAL9602](INFO): GPS fix at 20231002T235135: (36.802804, -121.788036) 2023-10-02T23:51:42.971Z,1696290702.971 [Default:CheckIn:Read_GPS] Stopped 2023-10-02T23:51:42.971Z,1696290702.971 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-02T23:51:49.939Z,1696290709.939 [CommandExec](IMPORTANT): got command gfscan 2023-10-02T23:51:50.267Z,1696290710.267 [DataOverHttps](INFO): Sending 170 bytes from file Logs/20231002T221631/Courier0031.lzma 2023-10-02T23:51:50.276Z,1696290710.276 [CBIT](IMPORTANT): Beginning ground fault scan 2023-10-02T23:51:51.269Z,1696290711.269 [DataOverHttps](INFO): Moved sent file to Logs/20231002T221631/Courier0031.lzma.bak 2023-10-02T23:51:51.269Z,1696290711.269 [DataOverHttps](INFO): SBD MOMSN=18977315 2023-10-02T23:52:01.195Z,1696290721.195 [CBIT](IMPORTANT): Low side ground fault detected mA: CHAN A0 (Batt): 0.798659 CHAN A1 (24V): -0.249184 CHAN A2 (12V): 0.512106 CHAN A3 (5V): 0.111548 CHAN B0 (3.3V): 0.044941 CHAN B1 (3.15aV): 0.043121 CHAN B2 (3.15bV): 0.042910 CHAN B3 (GND): -0.116242 OPEN: 0.010682 Full Scale: +/- 1 mA 2023-10-02T23:52:10.096Z,1696290730.096 [DataOverHttps](INFO): Sending 860 bytes from file Logs/20231002T221631/Express0032.lzma 2023-10-02T23:52:11.096Z,1696290731.096 [DataOverHttps](INFO): Moved sent file to Logs/20231002T221631/Express0032.lzma.bak 2023-10-02T23:52:11.097Z,1696290731.097 [DataOverHttps](INFO): SBD MOMSN=18977321 2023-10-02T23:52:13.260Z,1696290733.260 [Default:CheckIn:Read_Iridium] Stopped 2023-10-02T23:52:13.260Z,1696290733.260 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-02T23:52:13.260Z,1696290733.260 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-02T23:52:14.836Z,1696290734.836 [NAL9602](INFO): Not Powering down - fast GPS 2023-10-02T23:52:38.948Z,1696290758.948 [CommandExec](IMPORTANT): got command gfscan 2023-10-02T23:52:39.172Z,1696290759.172 [CBIT](IMPORTANT): Beginning ground fault scan 2023-10-02T23:52:50.059Z,1696290770.059 [CBIT](IMPORTANT): Low side ground fault detected mA: CHAN A0 (Batt): 0.803735 CHAN A1 (24V): -0.251197 CHAN A2 (12V): 0.513723 CHAN A3 (5V): 0.111969 CHAN B0 (3.3V): 0.045880 CHAN B1 (3.15aV): 0.045134 CHAN B2 (3.15bV): 0.045270 CHAN B3 (GND): -0.112635 OPEN: 0.011197 Full Scale: +/- 1 mA 2023-10-02T23:53:17.723Z,1696290797.723 [CommandExec](IMPORTANT): got command gfscan 2023-10-02T23:53:17.000Z,1696290798.000 [CBIT](IMPORTANT): Beginning ground fault scan 2023-10-02T23:53:27.305Z,1696290807.305 [CommandExec](IMPORTANT): got command burn off 2023-10-02T23:53:27.305Z,1696290807.305 [CommandExec](IMPORTANT): Deactivating dropweight wire 2023-10-02T23:53:28.882Z,1696290808.882 [CBIT](IMPORTANT): Low side ground fault detected mA: CHAN A0 (Batt): 0.802713 CHAN A1 (24V): -0.254302 CHAN A2 (12V): 0.513986 CHAN A3 (5V): 0.112228 CHAN B0 (3.3V): 0.046974 CHAN B1 (3.15aV): 0.046212 CHAN B2 (3.15bV): 0.045982 CHAN B3 (GND): -0.115159 OPEN: 0.011204 Full Scale: +/- 1 mA 2023-10-02T23:53:32.034Z,1696290812.034 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2023-10-02T23:53:32.034Z,1696290812.034 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-02T23:53:32.123Z,1696290812.123 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-02T23:53:32.496Z,1696290812.496 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-02T23:53:32.496Z,1696290812.496 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2023-10-02T23:54:16.716Z,1696290856.716 [CommandExec](IMPORTANT): got command gfscan 2023-10-02T23:54:16.940Z,1696290856.940 [CBIT](IMPORTANT): Beginning ground fault scan 2023-10-02T23:54:28.047Z,1696290868.047 [CBIT](IMPORTANT): Low side ground fault detected mA: CHAN A0 (Batt): 0.799753 CHAN A1 (24V): -0.256058 CHAN A2 (12V): 0.513895 CHAN A3 (5V): 0.112855 CHAN B0 (3.3V): 0.046919 CHAN B1 (3.15aV): 0.046691 CHAN B2 (3.15bV): 0.046850 CHAN B3 (GND): -0.111357 OPEN: 0.010755 Full Scale: +/- 1 mA 2023-10-02T23:54:40.483Z,1696290880.483 [CommandExec](IMPORTANT): got command gfscan 2023-10-02T23:54:40.788Z,1696290880.788 [CBIT](IMPORTANT): Beginning ground fault scan 2023-10-02T23:54:51.687Z,1696290891.687 [CBIT](IMPORTANT): Low side ground fault detected mA: CHAN A0 (Batt): 0.798674 CHAN A1 (24V): -0.258657 CHAN A2 (12V): 0.511992 CHAN A3 (5V): 0.111692 CHAN B0 (3.3V): 0.045194 CHAN B1 (3.15aV): 0.044408 CHAN B2 (3.15bV): 0.045899 CHAN B3 (GND): -0.115769 OPEN: 0.010539 Full Scale: +/- 1 mA 2023-10-02T23:55:15.775Z,1696290915.775 [BuoyancyServo](ERROR): getPosition uart error serial timeout 2023-10-02T23:55:15.775Z,1696290915.775 [BuoyancyServo](FAULT): Buoyancy getPosition uart error.serial timeout 2023-10-02T23:55:15.775Z,1696290915.775 [BuoyancyServo] Communications Fault, FailCount= 1 2023-10-02T23:55:15.775Z,1696290915.775 [BuoyancyServo](ERROR): Communications Fault 2023-10-02T23:55:15.780Z,1696290915.780 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2023-10-02T23:55:17.492Z,1696290917.492 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2023-10-02T23:55:17.492Z,1696290917.492 [BuoyancyServo](INFO): Powering down 2023-10-02T23:55:18.282Z,1696290918.282 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2023-10-02T23:55:18.282Z,1696290918.282 [BuoyancyServo] No Fault, FailCount= 1 2023-10-02T23:55:18.697Z,1696290918.697 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2023-10-02T23:55:18.703Z,1696290918.703 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2023-10-02T23:55:20.731Z,1696290920.731 [CommandExec](IMPORTANT): got command gfscan 2023-10-02T23:55:20.738Z,1696290920.738 [CBIT](IMPORTANT): Beginning ground fault scan 2023-10-02T23:55:31.662Z,1696290931.662 [CBIT](IMPORTANT): Low side ground fault detected mA: CHAN A0 (Batt): 0.797968 CHAN A1 (24V): -0.261485 CHAN A2 (12V): 0.509134 CHAN A3 (5V): 0.111605 CHAN B0 (3.3V): 0.044927 CHAN B1 (3.15aV): 0.044360 CHAN B2 (3.15bV): 0.044717 CHAN B3 (GND): -0.111187 OPEN: 0.010642 Full Scale: +/- 1 mA 2023-10-02T23:56:33.107Z,1696290993.107 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2023-10-02T23:56:33.107Z,1696290993.107 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-02T23:56:33.128Z,1696290993.128 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-02T23:56:33.484Z,1696290993.484 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-02T23:56:33.484Z,1696290993.484 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2023-10-02T23:56:38.175Z,1696290998.175 [CommandExec](IMPORTANT): got command gfscan 2023-10-02T23:56:38.283Z,1696290998.283 [CBIT](IMPORTANT): Beginning ground fault scan 2023-10-02T23:56:49.207Z,1696291009.207 [CBIT](IMPORTANT): Low side ground fault detected mA: CHAN A0 (Batt): 0.794527 CHAN A1 (24V): -0.266190 CHAN A2 (12V): 0.508069 CHAN A3 (5V): 0.111926 CHAN B0 (3.3V): 0.045882 CHAN B1 (3.15aV): 0.045767 CHAN B2 (3.15bV): 0.046077 CHAN B3 (GND): -0.109135 OPEN: 0.010597 Full Scale: +/- 1 mA 2023-10-02T23:56:57.484Z,1696291017.484 [CommandExec](IMPORTANT): got command gfscan 2023-10-02T23:56:57.673Z,1696291017.673 [CBIT](IMPORTANT): Beginning ground fault scan 2023-10-02T23:57:08.581Z,1696291028.581 [CBIT](IMPORTANT): Low side ground fault detected mA: CHAN A0 (Batt): 0.790769 CHAN A1 (24V): -0.268486 CHAN A2 (12V): 0.506045 CHAN A3 (5V): 0.109813 CHAN B0 (3.3V): 0.044728 CHAN B1 (3.15aV): 0.043374 CHAN B2 (3.15bV): 0.043157 CHAN B3 (GND): -0.114398 OPEN: 0.010505 Full Scale: +/- 1 mA 2023-10-02T23:57:13.823Z,1696291033.823 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-02T23:57:13.823Z,1696291033.823 [Default:CheckIn:C.Wait] Stopped 2023-10-02T23:57:13.823Z,1696291033.823 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-02T23:57:13.824Z,1696291033.824 [Default:CheckIn:D] Running Loop=1 2023-10-02T23:57:14.221Z,1696291034.221 [Default:CheckIn:D] Stopped 2023-10-02T23:57:14.221Z,1696291034.221 [Default:CheckIn:E] Running Loop=1 2023-10-02T23:57:14.619Z,1696291034.619 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 46.802702 min 2023-10-02T23:57:14.619Z,1696291034.619 [Default:CheckIn:E] Stopped 2023-10-02T23:57:14.620Z,1696291034.620 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-02T23:57:14.620Z,1696291034.620 [Default:CheckIn] Stopped 2023-10-02T23:57:14.620Z,1696291034.620 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-02T23:57:14.620Z,1696291034.620 [Default:CheckIn](INFO): Running loop #8 2023-10-02T23:57:14.620Z,1696291034.620 [Default:CheckIn] Running Loop=8 2023-10-02T23:57:14.620Z,1696291034.620 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-02T23:57:14.620Z,1696291034.620 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-02T23:57:47.031Z,1696291067.031 [NAL9602](ERROR): getFix uart error: serial timeout 2023-10-02T23:57:47.367Z,1696291067.367 [CommandExec](IMPORTANT): got command gfscan 2023-10-02T23:57:47.535Z,1696291067.535 [CBIT](IMPORTANT): B