2024-02-29T15:58:49.467Z,1709222329.467 [Supervisor](DEBUG): Initializing supervisor. 2024-02-29T15:58:49.472Z,1709222329.472 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2024-02-29T15:58:49.472Z,1709222329.472 [SyncHandler](INFO): Protected caller Thread ID is 6917 2024-02-29T15:58:49.473Z,1709222329.473 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2024-02-29T15:58:49.474Z,1709222329.474 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2024-02-29T15:58:49.474Z,1709222329.474 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 6918 2024-02-29T15:58:49.478Z,1709222329.478 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2024-02-29T15:58:49.496Z,1709222329.496 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2024-02-29T15:58:49.497Z,1709222329.497 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2024-02-29T15:58:49.497Z,1709222329.497 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 6919 2024-02-29T15:58:49.502Z,1709222329.502 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2024-02-29T15:58:49.503Z,1709222329.503 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2024-02-29T15:58:49.503Z,1709222329.503 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 6920 2024-02-29T15:58:49.505Z,1709222329.505 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2024-02-29T15:58:49.506Z,1709222329.506 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2024-02-29T15:58:49.506Z,1709222329.506 [logger ThreadHandler](INFO): Protected caller Thread ID is 6921 2024-02-29T15:58:49.510Z,1709222329.510 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2024-02-29T15:58:49.511Z,1709222329.511 [Supervisor](INFO): Looking for Config files in directory: Config/ 2024-02-29T15:58:49.512Z,1709222329.512 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2024-02-29T15:58:49.851Z,1709222329.851 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2024-02-29T15:58:49.852Z,1709222329.852 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2024-02-29T15:58:49.934Z,1709222329.934 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2024-02-29T15:58:50.043Z,1709222330.043 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2024-02-29T15:58:50.043Z,1709222330.043 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2024-02-29T15:58:50.176Z,1709222330.176 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2024-02-29T15:58:50.176Z,1709222330.176 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2024-02-29T15:58:50.531Z,1709222330.531 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2024-02-29T15:58:50.532Z,1709222330.532 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2024-02-29T15:58:50.630Z,1709222330.630 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2024-02-29T15:58:50.631Z,1709222330.631 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2024-02-29T15:58:51.051Z,1709222331.051 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2024-02-29T15:58:51.051Z,1709222331.051 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2024-02-29T15:58:51.268Z,1709222331.268 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2024-02-29T15:58:51.268Z,1709222331.268 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2024-02-29T15:58:51.348Z,1709222331.348 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2024-02-29T15:58:51.591Z,1709222331.591 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2024-02-29T15:58:51.591Z,1709222331.591 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2024-02-29T15:58:51.817Z,1709222331.818 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2024-02-29T15:58:51.818Z,1709222331.818 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2024-02-29T15:58:52.351Z,1709222332.351 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2024-02-29T15:58:52.352Z,1709222332.352 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2024-02-29T15:58:52.553Z,1709222332.553 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2024-02-29T15:58:52.553Z,1709222332.553 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2024-02-29T15:58:52.849Z,1709222332.849 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2024-02-29T15:58:52.849Z,1709222332.849 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2024-02-29T15:58:53.679Z,1709222333.679 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2024-02-29T15:58:53.680Z,1709222333.680 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2024-02-29T15:58:54.226Z,1709222334.226 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2024-02-29T15:58:54.228Z,1709222334.228 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/ 2024-02-29T15:58:54.229Z,1709222334.229 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Control.cfg 2024-02-29T15:58:54.347Z,1709222334.347 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Science.cfg 2024-02-29T15:58:54.469Z,1709222334.469 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/secure.cfg 2024-02-29T15:58:54.554Z,1709222334.554 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Servo.cfg 2024-02-29T15:58:54.666Z,1709222334.666 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/BIT.cfg 2024-02-29T15:58:54.769Z,1709222334.769 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Battery.cfg 2024-02-29T15:58:55.033Z,1709222335.033 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2024-02-29T15:58:55.034Z,1709222335.034 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/vehicle.cfg 2024-02-29T15:58:55.150Z,1709222335.150 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/logger.cfg 2024-02-29T15:58:55.247Z,1709222335.247 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Navigation.cfg 2024-02-29T15:58:55.353Z,1709222335.353 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Sensor.cfg 2024-02-29T15:58:55.490Z,1709222335.490 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Simulator.cfg 2024-02-29T15:58:55.578Z,1709222335.578 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2024-02-29T15:58:55.586Z,1709222335.586 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2024-02-29T15:58:55.699Z,1709222335.699 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2024-02-29T15:58:55.699Z,1709222335.699 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2024-02-29T15:58:55.789Z,1709222335.789 [VerticalControl](DEBUG): Construct VerticalControl. 2024-02-29T15:58:55.883Z,1709222335.883 [VerticalControl] Loaded 2024-02-29T15:58:55.883Z,1709222335.883 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2024-02-29T15:58:55.886Z,1709222335.886 [HorizontalControl](DEBUG): Construct HorizontalControl. 2024-02-29T15:58:55.928Z,1709222335.928 [HorizontalControl] Loaded 2024-02-29T15:58:55.929Z,1709222335.929 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2024-02-29T15:58:55.931Z,1709222335.931 [SpeedControl](DEBUG): Construct SpeedControl. 2024-02-29T15:58:55.935Z,1709222335.935 [SpeedControl] Loaded 2024-02-29T15:58:55.936Z,1709222335.936 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2024-02-29T15:58:55.939Z,1709222335.939 [LoopControl](DEBUG): Construct LoopControl. 2024-02-29T15:58:55.939Z,1709222335.939 [LoopControl] Loaded 2024-02-29T15:58:55.939Z,1709222335.939 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2024-02-29T15:58:55.940Z,1709222335.940 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2024-02-29T15:58:55.941Z,1709222335.941 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2024-02-29T15:58:55.998Z,1709222335.998 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2024-02-29T15:58:55.998Z,1709222335.998 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2024-02-29T15:58:56.422Z,1709222336.422 [AHRS_M2] Loaded 2024-02-29T15:58:56.423Z,1709222336.423 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2024-02-29T15:58:56.459Z,1709222336.459 [BackseatComponent] Loaded 2024-02-29T15:58:56.459Z,1709222336.459 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2024-02-29T15:58:56.460Z,1709222336.460 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408894E0 2024-02-29T15:58:56.460Z,1709222336.460 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 7004 2024-02-29T15:58:56.463Z,1709222336.463 [LcmUniversalReporter] Loaded 2024-02-29T15:58:56.463Z,1709222336.463 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2024-02-29T15:58:57.281Z,1709222337.281 [BPC1] Loaded 2024-02-29T15:58:57.281Z,1709222337.281 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2024-02-29T15:58:57.353Z,1709222337.353 [DataOverHttps] Loaded 2024-02-29T15:58:57.353Z,1709222337.353 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2024-02-29T15:58:57.354Z,1709222337.354 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408B94E0 2024-02-29T15:58:57.354Z,1709222337.354 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 7005 2024-02-29T15:58:57.375Z,1709222337.375 [Depth_Keller] Loaded 2024-02-29T15:58:57.375Z,1709222337.375 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2024-02-29T15:58:57.380Z,1709222337.380 [DropWeight] Loaded 2024-02-29T15:58:57.381Z,1709222337.381 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2024-02-29T15:58:57.444Z,1709222337.444 [NAL9602] Loaded 2024-02-29T15:58:57.444Z,1709222337.444 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2024-02-29T15:58:57.474Z,1709222337.474 [Onboard] Loaded 2024-02-29T15:58:57.474Z,1709222337.474 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2024-02-29T15:58:57.475Z,1709222337.475 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408E94E0 2024-02-29T15:58:57.475Z,1709222337.475 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 7006 2024-02-29T15:58:57.488Z,1709222337.488 [Power24vConverter] Loaded 2024-02-29T15:58:57.488Z,1709222337.488 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2024-02-29T15:58:57.501Z,1709222337.501 [Radio_Surface] Loaded 2024-02-29T15:58:57.501Z,1709222337.501 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2024-02-29T15:58:57.502Z,1709222337.502 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409194E0 2024-02-29T15:58:57.503Z,1709222337.503 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 7007 2024-02-29T15:58:57.517Z,1709222337.517 [Sonardyne_Nano] Loaded 2024-02-29T15:58:57.517Z,1709222337.517 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2024-02-29T15:58:57.610Z,1709222337.610 [DAT] Loaded 2024-02-29T15:58:57.610Z,1709222337.610 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2024-02-29T15:58:57.611Z,1709222337.611 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409494E0 2024-02-29T15:58:57.611Z,1709222337.611 [DAT ThreadHandler](INFO): Protected caller Thread ID is 7008 2024-02-29T15:58:57.634Z,1709222337.634 [Waterlinked] Loaded 2024-02-29T15:58:57.634Z,1709222337.634 [ComponentRegistry](DEBUG): SyncComponent "Waterlinked" handled in the control thread. 2024-02-29T15:58:57.635Z,1709222337.635 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2024-02-29T15:58:57.635Z,1709222337.635 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2024-02-29T15:58:57.646Z,1709222337.646 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2024-02-29T15:58:57.646Z,1709222337.646 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2024-02-29T15:58:57.673Z,1709222337.673 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2024-02-29T15:58:57.673Z,1709222337.673 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2024-02-29T15:58:57.813Z,1709222337.813 [SBIT](DEBUG): Construct Startup Built In Test. 2024-02-29T15:58:57.822Z,1709222337.822 [SBIT] Loaded 2024-02-29T15:58:57.822Z,1709222337.822 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2024-02-29T15:58:57.825Z,1709222337.825 [IBIT](DEBUG): Construct Initiated Built In Test. 2024-02-29T15:58:57.838Z,1709222337.838 [IBIT] Loaded 2024-02-29T15:58:57.838Z,1709222337.838 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2024-02-29T15:58:57.844Z,1709222337.844 [CBIT](DEBUG): Construct Continuous Built In Test. 2024-02-29T15:58:57.949Z,1709222337.949 [CBIT] Loaded 2024-02-29T15:58:57.949Z,1709222337.949 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2024-02-29T15:58:57.949Z,1709222337.949 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2024-02-29T15:58:57.950Z,1709222337.950 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2024-02-29T15:58:58.282Z,1709222338.282 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2024-02-29T15:58:58.283Z,1709222338.283 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2024-02-29T15:58:58.332Z,1709222338.332 [DepthRateCalculator] Loaded 2024-02-29T15:58:58.333Z,1709222338.333 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2024-02-29T15:58:58.338Z,1709222338.338 [PitchRateCalculator] Loaded 2024-02-29T15:58:58.338Z,1709222338.338 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2024-02-29T15:58:58.349Z,1709222338.349 [SpeedCalculator] Loaded 2024-02-29T15:58:58.349Z,1709222338.349 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2024-02-29T15:58:58.353Z,1709222338.353 [YawRateCalculator] Loaded 2024-02-29T15:58:58.354Z,1709222338.354 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2024-02-29T15:58:58.372Z,1709222338.372 [ElevatorOffsetCalculator] Loaded 2024-02-29T15:58:58.373Z,1709222338.373 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2024-02-29T15:58:58.373Z,1709222338.373 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2024-02-29T15:58:58.374Z,1709222338.374 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2024-02-29T15:58:58.521Z,1709222338.521 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2024-02-29T15:58:58.522Z,1709222338.522 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2024-02-29T15:58:58.633Z,1709222338.633 [DeadReckonUsingMultipleVelocitySources] Loaded 2024-02-29T15:58:58.633Z,1709222338.633 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2024-02-29T15:58:58.677Z,1709222338.677 [DeadReckonUsingSpeedCalculator] Loaded 2024-02-29T15:58:58.677Z,1709222338.677 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2024-02-29T15:58:58.720Z,1709222338.720 [DeadReckonWithRespectToSeafloor] Loaded 2024-02-29T15:58:58.720Z,1709222338.720 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread. 2024-02-29T15:58:58.734Z,1709222338.734 [NavChart] Loaded 2024-02-29T15:58:58.734Z,1709222338.734 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2024-02-29T15:58:58.740Z,1709222338.740 [UniversalFixResidualReporter] Loaded 2024-02-29T15:58:58.740Z,1709222338.740 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2024-02-29T15:58:58.741Z,1709222338.741 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2024-02-29T15:58:58.741Z,1709222338.741 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2024-02-29T15:58:58.872Z,1709222338.872 [BuoyancyServo] Loaded 2024-02-29T15:58:58.873Z,1709222338.873 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2024-02-29T15:58:58.893Z,1709222338.893 [ElevatorServo] Loaded 2024-02-29T15:58:58.894Z,1709222338.894 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2024-02-29T15:58:58.914Z,1709222338.914 [MassServo] Loaded 2024-02-29T15:58:58.914Z,1709222338.914 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2024-02-29T15:58:58.933Z,1709222338.933 [RudderServo] Loaded 2024-02-29T15:58:58.933Z,1709222338.933 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2024-02-29T15:58:58.948Z,1709222338.948 [ThrusterHE] Loaded 2024-02-29T15:58:58.949Z,1709222338.949 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2024-02-29T15:58:58.949Z,1709222338.949 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2024-02-29T15:58:58.950Z,1709222338.950 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2024-02-29T15:58:59.125Z,1709222339.125 [CTD_Seabird] Loaded 2024-02-29T15:58:59.125Z,1709222339.125 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2024-02-29T15:58:59.126Z,1709222339.126 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40BB04E0 2024-02-29T15:58:59.126Z,1709222339.126 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 7009 2024-02-29T15:58:59.160Z,1709222339.160 [ESPComponent] Loaded 2024-02-29T15:58:59.160Z,1709222339.160 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2024-02-29T15:58:59.181Z,1709222339.181 [PAR_Licor] Loaded 2024-02-29T15:58:59.181Z,1709222339.181 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2024-02-29T15:58:59.222Z,1709222339.222 [WetLabsBB2FL] Loaded 2024-02-29T15:58:59.222Z,1709222339.222 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2024-02-29T15:58:59.223Z,1709222339.223 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BE04E0 2024-02-29T15:58:59.224Z,1709222339.224 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 7010 2024-02-29T15:58:59.225Z,1709222339.225 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2024-02-29T15:58:59.242Z,1709222339.242 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2024-02-29T15:58:59.245Z,1709222339.245 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2024-02-29T15:58:59.259Z,1709222339.259 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2024-02-29T15:58:59.260Z,1709222339.260 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C104E0 2024-02-29T15:58:59.260Z,1709222339.260 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 7011 2024-02-29T15:58:59.265Z,1709222339.265 [Supervisor](INFO): Main Thread ID is 6916 2024-02-29T15:58:59.265Z,1709222339.265 [Supervisor](DEBUG): Running supervisor. 2024-02-29T15:58:59.265Z,1709222339.265 [CommandExec ThreadHandler](INFO): Handler Thread ID is 7012 2024-02-29T15:58:59.266Z,1709222339.266 [CommandExec](INFO): Initializing the command executive. 2024-02-29T15:58:59.267Z,1709222339.267 [CommandLine ThreadHandler](INFO): Handler Thread ID is 7013 2024-02-29T15:58:59.270Z,1709222339.270 [controlThread ThreadHandler](INFO): Handler Thread ID is 7014 2024-02-29T15:58:59.270Z,1709222339.270 [controlThread](DEBUG): Initializing ControlThread 2024-02-29T15:58:59.271Z,1709222339.271 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2024-02-29T15:58:59.273Z,1709222339.273 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2024-02-29T15:58:59.273Z,1709222339.273 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2024-02-29T15:58:59.274Z,1709222339.274 [LoopControl](DEBUG): Initialize LoopControlComponent. 2024-02-29T15:58:59.279Z,1709222339.279 [SBIT](INFO): Initialize SBIT Component. 2024-02-29T15:58:59.279Z,1709222339.279 [SBIT](IMPORTANT): git: 2024-02-28 2024-02-29T15:58:59.279Z,1709222339.279 [SBIT](INFO): git hash: 6d890a75647ce81d8a618abe9965886d38e67e29 2024-02-29T15:58:59.279Z,1709222339.279 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2024-02-29T15:58:59.281Z,1709222339.281 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014 Kernel Reported: #1 PREEMPT Tue Nov 28 15:04:22 PST 2023 2024-02-29T15:58:59.282Z,1709222339.282 [SBIT](INFO): Beginning SBIT in 90.000000 seconds. 2024-02-29T15:58:59.282Z,1709222339.282 [IBIT](INFO): Initialize IBIT Component. 2024-02-29T15:58:59.283Z,1709222339.283 [CBIT](DEBUG): Initialize CBIT Component. 2024-02-29T15:58:59.284Z,1709222339.284 [logger ThreadHandler](INFO): Handler Thread ID is 7015 2024-02-29T15:58:59.296Z,1709222339.296 [CBIT](DEBUG): Initialized mux pins. 2024-02-29T15:58:59.296Z,1709222339.296 [CBIT](DEBUG): Initializing the watchdog timer. 2024-02-29T15:58:59.305Z,1709222339.305 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 7016 2024-02-29T15:58:59.317Z,1709222339.317 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 7017 2024-02-29T15:58:59.318Z,1709222339.318 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2024-02-29T15:58:59.320Z,1709222339.320 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2024-02-29T15:58:59.321Z,1709222339.321 [CBIT](DEBUG): Initializing heartbeat. 2024-02-29T15:58:59.329Z,1709222339.329 [Onboard ThreadHandler](INFO): Handler Thread ID is 7018 2024-02-29T15:58:59.358Z,1709222339.358 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 7019 2024-02-29T15:58:59.369Z,1709222339.369 [DAT ThreadHandler](INFO): Handler Thread ID is 7020 2024-02-29T15:58:59.370Z,1709222339.370 [DAT](INFO): Powering up 2024-02-29T15:58:59.370Z,1709222339.370 [DAT](DEBUG): Initializing DAT. 2024-02-29T15:58:59.374Z,1709222339.374 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 7022 2024-02-29T15:58:59.375Z,1709222339.375 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2024-02-29T15:58:59.378Z,1709222339.378 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 7024 2024-02-29T15:58:59.381Z,1709222339.381 [WetLabsBB2FL](INFO): Powering up 2024-02-29T15:58:59.382Z,1709222339.382 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 7026 2024-02-29T15:58:59.385Z,1709222339.385 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2HA05M.000 2024-02-29T15:58:59.385Z,1709222339.385 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2HA05M.000 2024-02-29T15:58:59.385Z,1709222339.385 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3HA20M.000 2024-02-29T15:58:59.386Z,1709222339.386 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3HA20M.000 2024-02-29T15:58:59.386Z,1709222339.386 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4HA51M.000 2024-02-29T15:58:59.386Z,1709222339.386 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4HA51M.000 2024-02-29T15:58:59.386Z,1709222339.386 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4HA51M.000 2024-02-29T15:58:59.386Z,1709222339.386 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4HA51M.000 2024-02-29T15:58:59.386Z,1709222339.386 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5HA53M.000 2024-02-29T15:58:59.387Z,1709222339.387 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5HA53M.000 2024-02-29T15:58:59.387Z,1709222339.387 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5HA55M.000 2024-02-29T15:58:59.387Z,1709222339.387 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5HA55M.000 2024-02-29T15:58:59.392Z,1709222339.392 [CBIT](DEBUG): Deactivating GF circuits. 2024-02-29T15:58:59.393Z,1709222339.393 [CBIT](DEBUG): Deactivating emergency mode. 2024-02-29T15:58:59.432Z,1709222339.432 [CBIT](DEBUG): Backplane powered. 2024-02-29T15:58:59.433Z,1709222339.433 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2024-02-29T15:58:59.433Z,1709222339.433 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2024-02-29T15:58:59.433Z,1709222339.433 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2024-02-29T15:58:59.434Z,1709222339.434 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2024-02-29T15:58:59.434Z,1709222339.434 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2024-02-29T15:58:59.435Z,1709222339.435 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2024-02-29T15:58:59.436Z,1709222339.436 [NavChart](DEBUG): Initialize NavChart Navigation. 2024-02-29T15:58:59.437Z,1709222339.437 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2024-02-29T15:58:59.441Z,1709222339.441 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2024-02-29T15:58:59.442Z,1709222339.442 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Startup.tx 2024-02-29T15:58:59.467Z,1709222339.467 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2024-02-29T15:58:59.512Z,1709222339.512 [MissionManager](DEBUG): mission Startup { behavior Guidance:GoToSurface { run in progression } aggregate StartupSatComms { run in sequence readDatum { timeout duration=P1M Universal:latitude_fix } readDatum { timeout duration=P1M Universal:platform_communications } } } 2024-02-29T15:58:59.513Z,1709222339.513 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2024-02-29T15:58:59.513Z,1709222339.513 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Default.tx 2024-02-29T15:58:59.618Z,1709222339.618 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2024-02-29T15:58:59.619Z,1709222339.619 [Default:A.Wait](DEBUG): Construct Wait. 2024-02-29T15:58:59.626Z,1709222339.626 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2024-02-29T15:58:59.647Z,1709222339.647 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2024-02-29T15:58:59.674Z,1709222339.674 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2024-02-29T15:58:59.680Z,1709222339.680 [Default:E.Execute](DEBUG): Construct Execute. 2024-02-29T15:58:59.699Z,1709222339.699 [MissionManager](DEBUG): mission Default { arguments { ElapsedSinceDefaultStarted = 0 minute } behavior Guidance:Wait { """ Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. """ run in sequence set duration = 13 second } behavior Guidance:GoToSurface { run in progression } aggregate CheckIn { run in sequence repeat=288 readDatum id="Read_GPS" { timeout duration=P5M Universal:time_fix } readDatum id="Read_Iridium" { timeout duration=P4H { behavior Guidance:Execute { run in sequence set command = "Burn on" } syslog critical "Dropped weight due to communications timeout." } Universal:platform_communications } behavior Guidance:Wait { run in sequence set duration = 5 minute } assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started ) syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute } syslog important "Restarting logs and Default mission." behavior Guidance:Execute { run in sequence set command = "restart logs" } } 2024-02-29T15:58:59.704Z,1709222339.704 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,Waterlinked,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2024-02-29T15:58:59.724Z,1709222339.724 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2024-02-29T15:58:59.760Z,1709222339.760 [Radio_Surface](INFO): Powering up 2024-02-29T15:58:59.794Z,1709222339.794 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.140412 decibar, -1.132002 m 2024-02-29T15:58:59.795Z,1709222339.795 [Power24vConverter](INFO): Powering up. 2024-02-29T15:58:59.796Z,1709222339.796 [Sonardyne_Nano](INFO): Initializing. 2024-02-29T15:58:59.830Z,1709222339.830 [DepthRateCalculator](ERROR): Depth measurement is not active 2024-02-29T15:58:59.858Z,1709222339.858 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2024-02-29T15:58:59.865Z,1709222339.865 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2024-02-29T15:58:59.866Z,1709222339.866 [ElevatorServo](DEBUG): Initializing EZServoServo. 2024-02-29T15:58:59.874Z,1709222339.874 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2024-02-29T15:58:59.875Z,1709222339.875 [MassServo](DEBUG): Initializing EZServoServo. 2024-02-29T15:58:59.881Z,1709222339.881 [MassServo](DEBUG): Initializing MassServo. 2024-02-29T15:58:59.882Z,1709222339.882 [RudderServo](DEBUG): Initializing EZServoServo. 2024-02-29T15:58:59.889Z,1709222339.889 [RudderServo](DEBUG): Initializing RudderServo. 2024-02-29T15:58:59.890Z,1709222339.890 [ThrusterHE](DEBUG): Initializing EZServoServo. 2024-02-29T15:58:59.897Z,1709222339.897 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2024-02-29T15:59:01.488Z,1709222341.488 [WetLabsBB2FL](INFO): Powering down 2024-02-29T15:59:01.735Z,1709222341.735 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2024-02-29T15:59:03.018Z,1709222343.018 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2311 2024-02-29T15:59:11.717Z,1709222351.717 [DAT](INFO): DAT read: 2024-02-29T15:59:11.718Z,1709222351.718 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2024-02-29T15:59:13.229Z,1709222353.229 [DAT](INFO): DAT read: MF Frequency Band 2024-02-29T15:59:13.230Z,1709222353.230 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2024-02-29T15:59:13.231Z,1709222353.231 [DAT](INFO): DAT read: Feb 28 2024 23:34:50 2024-02-29T15:59:13.238Z,1709222353.238 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004780 2024-02-29T15:59:14.237Z,1709222354.237 [DAT](INFO): DAT read: Features enabled [Bearing] 2024-02-29T15:59:14.239Z,1709222354.239 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2024-02-29T15:59:14.239Z,1709222354.239 [DAT](INFO): commRate: 800 2024-02-29T15:59:16.304Z,1709222356.304 [DAT](INFO): entering command mode 2024-02-29T15:59:16.505Z,1709222356.505 [DAT](INFO): DAT read: 2024-02-29T15:59:16.505Z,1709222356.505 [DAT](INFO): DAT read: user:1> 2024-02-29T15:59:16.506Z,1709222356.506 [DAT](INFO): setting verbose to 3 2024-02-29T15:59:16.757Z,1709222356.757 [DAT](INFO): DAT read: user:1> 2024-02-29T15:59:16.758Z,1709222356.758 [DAT](INFO): DAT read: Verbose | 3 2024-02-29T15:59:16.758Z,1709222356.758 [DAT](INFO): set verbose to 3 2024-02-29T15:59:16.759Z,1709222356.759 [DAT](INFO): setting DatVerbose to 27440 2024-02-29T15:59:17.009Z,1709222357.009 [DAT](INFO): DAT read: user:2> 2024-02-29T15:59:17.010Z,1709222357.010 [DAT](INFO): DAT read: DatVerbose | 27440 2024-02-29T15:59:17.010Z,1709222357.010 [DAT](INFO): set DatVerbose to 27440 2024-02-29T15:59:17.011Z,1709222357.011 [DAT](INFO): setting transmit power to 8 2024-02-29T15:59:17.261Z,1709222357.261 [DAT](INFO): DAT read: user:3> 2024-02-29T15:59:17.262Z,1709222357.262 [DAT](INFO): DAT read: TxPower | 8 (Max) 2024-02-29T15:59:17.262Z,1709222357.262 [DAT](INFO): set transmit power to 8 2024-02-29T15:59:17.263Z,1709222357.263 [DAT](INFO): setting local address to 1 2024-02-29T15:59:17.513Z,1709222357.513 [DAT](INFO): DAT read: user:4> 2024-02-29T15:59:17.514Z,1709222357.514 [DAT](INFO): DAT read: LocalAddr | 1 2024-02-29T15:59:17.514Z,1709222357.514 [DAT](INFO): set local address to 1 2024-02-29T15:59:17.515Z,1709222357.515 [DAT](INFO): Setting time to: 15:59:17 And date to:2/29/2024 2024-02-29T15:59:17.765Z,1709222357.765 [DAT](INFO): DAT read: user:5> 2024-02-29T15:59:17.766Z,1709222357.766 [DAT](INFO): DAT read: Thu Feb 29, 2024 15:59:17 2024-02-29T15:59:17.766Z,1709222357.766 [DAT](INFO): Local DAT time set to Thu Feb 29, 2024 15:59:17 2024-02-29T15:59:27.582Z,1709222367.582 [NAL9602](INFO): Powering up NAL9602 2024-02-29T15:59:38.502Z,1709222378.502 [NAL9602](INFO): NAL9602 initialized 2024-02-29T15:59:44.654Z,1709222384.654 [CommandExec](IMPORTANT): got command strobe off 2024-02-29T15:59:44.654Z,1709222384.654 [CommandExec](IMPORTANT): Deactivating strobe 2024-02-29T16:00:07.353Z,1709222407.353 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-29T16:00:29.718Z,1709222429.718 [SBIT](IMPORTANT): Beginning Startup BIT 2024-02-29T16:00:29.722Z,1709222429.722 [CBIT](IMPORTANT): Beginning ground fault scan 2024-02-29T16:00:41.088Z,1709222441.088 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.002313 CHAN A1 (24V): 0.000850 CHAN A2 (12V): 0.000751 CHAN A3 (5V): -0.001609 CHAN B0 (3.3V): 0.000500 CHAN B1 (3.15aV): -0.000475 CHAN B2 (3.15bV): -0.000037 CHAN B3 (GND): -0.001039 OPEN: 0.006759 Full Scale: +/- 1 mA 2024-02-29T16:00:54.880Z,1709222454.880 [Waterlinked](ERROR): only read 3 of 5 data items. Device response::BI, -5, +3, 0,A 2024-02-29T16:01:23.909Z,1709222483.909 [SBIT](IMPORTANT): SBIT PASSED 2024-02-29T16:01:23.909Z,1709222483.909 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2024-02-29T16:01:23.910Z,1709222483.910 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=10 count; 2024-02-29T16:01:23.910Z,1709222483.910 [SBIT](IMPORTANT): Express linearApproximation CTD_Seabird.bin_median_sea_water_salinity 0.050000 practical_salinity_unit; 2024-02-29T16:01:23.910Z,1709222483.910 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_LM_AvgRois 0.200000 count_per_second; 2024-02-29T16:01:23.911Z,1709222483.911 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 5.000000 meter; 2024-02-29T16:01:23.911Z,1709222483.911 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=187.373980 cubic_centimeter; 2024-02-29T16:01:23.911Z,1709222483.911 [SBIT](IMPORTANT): VerticalControl.massDefault=17.565199 millimeter; 2024-02-29T16:01:24.271Z,1709222484.271 [MissionManager](IMPORTANT): Started mission Startup 2024-02-29T16:01:24.271Z,1709222484.271 [Startup] Running Loop=1 2024-02-29T16:01:24.271Z,1709222484.271 [Startup](DEBUG): Aggregate::initialize Startup 2024-02-29T16:01:24.271Z,1709222484.271 [Startup:A.GoToSurface] Running Loop=1 2024-02-29T16:01:24.271Z,1709222484.271 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-02-29T16:01:24.272Z,1709222484.272 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-02-29T16:01:24.273Z,1709222484.273 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-02-29T16:01:24.274Z,1709222484.274 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-02-29T16:01:24.274Z,1709222484.274 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-02-29T16:01:24.274Z,1709222484.274 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-02-29T16:01:24.276Z,1709222484.276 [Startup:StartupSatComms] Running Loop=1 2024-02-29T16:01:24.276Z,1709222484.276 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2024-02-29T16:01:24.276Z,1709222484.276 [Startup:StartupSatComms:A] Running Loop=1 2024-02-29T16:01:24.632Z,1709222484.632 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2024-02-29T16:01:32.694Z,1709222492.694 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error. Device response::TS,22032019575, +0.0, 0.0,1475.0, 0 2024-02-29T16:01:49.159Z,1709222509.159 [Waterlinked](ERROR): only read 0 of 5 data items. Device response::BI +0, +1, +0,A 2024-02-29T16:01:50.373Z,1709222510.373 [Waterlinked](ERROR): only read 3 of 5 data items. Device response::BI, +2, -3, +0,A 2024-02-29T16:01:59.679Z,1709222519.679 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2024-02-29T16:01:59.679Z,1709222519.679 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-02-29T16:01:59.681Z,1709222519.681 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2024-02-29T16:01:59.681Z,1709222519.681 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2024-02-29T16:01:59.682Z,1709222519.682 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2024-02-29T16:01:59.682Z,1709222519.682 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-29T16:01:59.760Z,1709222519.760 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-02-29T16:01:59.761Z,1709222519.761 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2024-02-29T16:01:59.761Z,1709222519.761 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-29T16:02:00.152Z,1709222520.152 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-02-29T16:02:00.152Z,1709222520.152 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2024-02-29T16:02:00.153Z,1709222520.153 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2024-02-29T16:02:00.153Z,1709222520.153 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2024-02-29T16:02:00.153Z,1709222520.153 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-29T16:02:00.153Z,1709222520.153 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2024-02-29T16:02:00.453Z,1709222520.453 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2024-02-29T16:02:10.957Z,1709222530.957 [Waterlinked](INFO): Powering down 2024-02-29T16:02:24.661Z,1709222544.661 [Startup:StartupSatComms:A](INFO): Timed out from 2024-02-29T16:01:24.3Z 2024-02-29T16:02:24.661Z,1709222544.661 [Startup:StartupSatComms:A] Stopped 2024-02-29T16:02:24.661Z,1709222544.661 [Startup:StartupSatComms:B] Running Loop=1 2024-02-29T16:02:25.053Z,1709222545.053 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-02-29T16:02:32.008Z,1709222552.008 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240228T180930/Courier0043.lzma 2024-02-29T16:02:33.010Z,1709222553.010 [DataOverHttps](INFO): Moved sent file to Logs/20240228T180930/Courier0043.lzma.bak 2024-02-29T16:02:33.011Z,1709222553.011 [DataOverHttps](INFO): SBD MOMSN=19304922 2024-02-29T16:02:33.574Z,1709222553.574 [CommandLine](INFO): End of History 2024-02-29T16:02:33.605Z,1709222553.605 [CommandLine](INFO): End of History 2024-02-29T16:02:33.637Z,1709222553.637 [CommandLine](INFO): End of History 2024-02-29T16:02:33.667Z,1709222553.667 [CommandLine](INFO): End of History 2024-02-29T16:02:33.701Z,1709222553.701 [CommandLine](INFO): End of History 2024-02-29T16:02:33.729Z,1709222553.729 [CommandLine](INFO): End of History 2024-02-29T16:02:33.758Z,1709222553.758 [CommandLine](INFO): End of History 2024-02-29T16:02:33.789Z,1709222553.789 [CommandLine](INFO): End of History 2024-02-29T16:02:33.817Z,1709222553.817 [CommandLine](INFO): End of History 2024-02-29T16:02:33.849Z,1709222553.849 [CommandLine](INFO): End of History 2024-02-29T16:02:33.879Z,1709222553.879 [CommandLine](INFO): End of History 2024-02-29T16:02:33.929Z,1709222553.929 [CommandLine](INFO): End of History 2024-02-29T16:02:33.941Z,1709222553.941 [CommandLine](INFO): End of History 2024-02-29T16:02:33.979Z,1709222553.979 [CommandLine](INFO): End of History 2024-02-29T16:02:34.002Z,1709222554.002 [CommandLine](INFO): End of History 2024-02-29T16:02:34.038Z,1709222554.038 [CommandLine](INFO): End of History 2024-02-29T16:02:34.067Z,1709222554.067 [CommandLine](INFO): End of History 2024-02-29T16:02:34.093Z,1709222554.093 [CommandLine](INFO): End of History 2024-02-29T16:02:46.434Z,1709222566.434 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-29T16:02:48.653Z,1709222568.653 [CommandExec](IMPORTANT): got command failComponent 2024-02-29T16:02:48.653Z,1709222568.653 [CommandExec](IMPORTANT): Failed components: 2024-02-29T16:02:48.653Z,1709222568.653 [CommandExec](IMPORTANT): No failed Components. 2024-02-29T16:02:49.300Z,1709222569.300 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240229T155849/Courier0000.lzma 2024-02-29T16:02:50.302Z,1709222570.302 [DataOverHttps](INFO): Moved sent file to Logs/20240229T155849/Courier0000.lzma.bak 2024-02-29T16:02:50.302Z,1709222570.302 [DataOverHttps](INFO): SBD MOMSN=19304924 2024-02-29T16:02:59.766Z,1709222579.766 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-29T16:03:06.332Z,1709222586.332 [DataOverHttps](INFO): Sending 64 bytes from file Logs/20240228T180930/Express0044.lzma 2024-02-29T16:03:07.334Z,1709222587.334 [DataOverHttps](INFO): Moved sent file to Logs/20240228T180930/Express0044.lzma.bak 2024-02-29T16:03:07.334Z,1709222587.334 [DataOverHttps](INFO): SBD MOMSN=19304927 2024-02-29T16:03:13.098Z,1709222593.098 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-29T16:03:24.832Z,1709222604.832 [Startup:StartupSatComms:B](INFO): Timed out from 2024-02-29T16:02:24.7Z 2024-02-29T16:03:24.832Z,1709222604.832 [Startup:StartupSatComms:B] Stopped 2024-02-29T16:03:24.832Z,1709222604.832 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2024-02-29T16:03:24.832Z,1709222604.832 [Startup:StartupSatComms] Stopped 2024-02-29T16:03:24.833Z,1709222604.833 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2024-02-29T16:03:24.833Z,1709222604.833 [Startup](INFO): Completed Startup 2024-02-29T16:03:24.833Z,1709222604.833 [MissionManager](INFO): Startup is completed. 2024-02-29T16:03:24.834Z,1709222604.834 [MissionManager](INFO): Uninitializing Mission Startup 2024-02-29T16:03:24.834Z,1709222604.834 [Startup] Stopped 2024-02-29T16:03:24.834Z,1709222604.834 [Startup](DEBUG): Aggregate::uninitialize Startup 2024-02-29T16:03:24.834Z,1709222604.834 [Startup:A.GoToSurface] Stopped 2024-02-29T16:03:24.834Z,1709222604.834 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-02-29T16:03:25.236Z,1709222605.236 [MissionManager](IMPORTANT): Started mission Default 2024-02-29T16:03:25.236Z,1709222605.236 [Default] Running Loop=1 2024-02-29T16:03:25.236Z,1709222605.236 [Default](DEBUG): Aggregate::initialize Default 2024-02-29T16:03:25.236Z,1709222605.236 [Default:B.GoToSurface] Running Loop=1 2024-02-29T16:03:25.236Z,1709222605.236 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-02-29T16:03:25.237Z,1709222605.237 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-02-29T16:03:25.237Z,1709222605.237 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-02-29T16:03:25.237Z,1709222605.237 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-02-29T16:03:25.237Z,1709222605.237 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-02-29T16:03:25.238Z,1709222605.238 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-02-29T16:03:25.238Z,1709222605.238 [Default:A.Wait] Running Loop=1 2024-02-29T16:03:25.238Z,1709222605.238 [Default:A.Wait](DEBUG): Initialize Wait Component. 2024-02-29T16:03:26.834Z,1709222606.834 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-29T16:03:38.573Z,1709222618.573 [Default:A.Wait](INFO): Done Waiting. 2024-02-29T16:03:38.573Z,1709222618.573 [Default:A.Wait] Stopped 2024-02-29T16:03:38.573Z,1709222618.573 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2024-02-29T16:03:38.969Z,1709222618.969 [Default:CheckIn] Running Loop=1 2024-02-29T16:03:38.969Z,1709222618.969 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-29T16:03:38.969Z,1709222618.969 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-29T16:03:39.381Z,1709222619.381 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2024-02-29T16:03:40.570Z,1709222620.570 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-29T16:03:54.306Z,1709222634.306 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-29T16:04:08.450Z,1709222648.450 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-29T16:04:16.470Z,1709222656.470 [DataOverHttps](INFO): Sending 1012 bytes from file Logs/20240229T155849/Express0001.lzma 2024-02-29T16:04:17.470Z,1709222657.470 [DataOverHttps](INFO): Moved sent file to Logs/20240229T155849/Express0001.lzma.bak 2024-02-29T16:04:17.470Z,1709222657.470 [DataOverHttps](INFO): SBD MOMSN=19304930 2024-02-29T16:04:22.182Z,1709222662.182 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-29T16:04:36.321Z,1709222676.321 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-29T16:04:41.986Z,1709222681.986 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-02-29T16:04:50.465Z,1709222690.465 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-29T16:05:00.577Z,1709222700.577 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2024-02-29T16:05:00.577Z,1709222700.577 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-02-29T16:05:00.579Z,1709222700.579 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2 2024-02-29T16:05:00.579Z,1709222700.579 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2024-02-29T16:05:00.580Z,1709222700.580 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2024-02-29T16:05:00.608Z,1709222700.608 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-29T16:05:00.673Z,1709222700.673 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-02-29T16:05:00.674Z,1709222700.674 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2024-02-29T16:05:00.674Z,1709222700.674 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-29T16:05:01.040Z,1709222701.040 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-02-29T16:05:01.040Z,1709222701.040 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2024-02-29T16:05:01.041Z,1709222701.041 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2024-02-29T16:05:01.041Z,1709222701.041 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2 2024-02-29T16:05:01.041Z,1709222701.041 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-29T16:05:01.042Z,1709222701.042 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2024-02-29T16:05:01.386Z,1709222701.386 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2024-02-29T16:07:19.134Z,1709222839.134 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-29T16:07:26.002Z,1709222846.002 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-29T16:07:42.161Z,1709222862.161 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-29T16:07:58.321Z,1709222878.321 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-29T16:08:01.569Z,1709222881.569 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2024-02-29T16:08:01.569Z,1709222881.569 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-02-29T16:08:01.570Z,1709222881.570 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3 2024-02-29T16:08:01.570Z,1709222881.570 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2024-02-29T16:08:01.571Z,1709222881.571 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3 2024-02-29T16:08:01.571Z,1709222881.571 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-29T16:08:01.639Z,1709222881.639 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-02-29T16:08:01.639Z,1709222881.639 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2024-02-29T16:08:01.639Z,1709222881.639 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-29T16:08:02.068Z,1709222882.068 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-02-29T16:08:02.068Z,1709222882.068 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2024-02-29T16:08:02.069Z,1709222882.069 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2024-02-29T16:08:02.069Z,1709222882.069 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3 2024-02-29T16:08:02.069Z,1709222882.069 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-29T16:08:02.069Z,1709222882.069 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3 2024-02-29T16:08:02.404Z,1709222882.404 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2024-02-29T16:08:13.674Z,1709222893.674 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-29T16:08:28.626Z,1709222908.626 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-29T16:08:39.145Z,1709222919.145 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-02-29T16:03:38.0Z 2024-02-29T16:08:39.145Z,1709222919.145 [Default:CheckIn:Read_GPS] Stopped 2024-02-29T16:08:39.145Z,1709222919.145 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-29T16:08:39.568Z,1709222919.568 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-02-29T16:08:44.393Z,1709222924.393 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-29T16:08:46.188Z,1709222926.188 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240229T155849/Courier0004.lzma 2024-02-29T16:08:47.190Z,1709222927.190 [DataOverHttps](INFO): Moved sent file to Logs/20240229T155849/Courier0004.lzma.bak 2024-02-29T16:08:47.190Z,1709222927.190 [DataOverHttps](INFO): SBD MOMSN=19304952 2024-02-29T16:08:59.402Z,1709222939.402 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-29T16:09:05.832Z,1709222945.832 [DataOverHttps](INFO): Sending 238 bytes from file Logs/20240229T155849/Express0005.lzma 2024-02-29T16:09:06.834Z,1709222946.834 [DataOverHttps](INFO): Moved sent file to Logs/20240229T155849/Express0005.lzma.bak 2024-02-29T16:09:06.835Z,1709222946.835 [DataOverHttps](INFO): SBD MOMSN=19304954 2024-02-29T16:09:07.916Z,1709222947.916 [Default:CheckIn:Read_Iridium] Stopped 2024-02-29T16:09:07.916Z,1709222947.916 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-29T16:09:07.916Z,1709222947.916 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-29T16:09:14.349Z,1709222954.349 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-29T16:09:29.297Z,1709222969.297 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-29T16:09:33.752Z,1709222973.752 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160932.00,A,3648.16437,N,12147.28303,W,0.525,0.00,290224,,,A*7B 2024-02-29T16:09:33.755Z,1709222973.755 [NAL9602](INFO): GPS fix at 20240229T160932: (36.802740, -121.788050) 2024-02-29T16:10:06.473Z,1709223006.473 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-29T16:11:02.645Z,1709223062.645 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2024-02-29T16:11:02.645Z,1709223062.645 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-29T16:11:02.732Z,1709223062.732 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-29T16:11:03.110Z,1709223063.110 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-29T16:11:03.110Z,1709223063.110 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2024-02-29T16:12:16.560Z,1709223136.560 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-29T16:12:30.298Z,1709223150.298 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-29T16:12:44.032Z,1709223164.032 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-29T16:12:57.382Z,1709223177.382 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-29T16:13:11.119Z,1709223191.119 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-29T16:13:24.451Z,1709223204.451 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-29T16:13:38.592Z,1709223218.592 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-29T16:13:51.924Z,1709223231.924 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-29T16:14:03.655Z,1709223243.655 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2024-02-29T16:14:03.655Z,1709223243.655 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-29T16:14:03.720Z,1709223243.720 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-29T16:14:04.149Z,1709223244.149 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-29T16:14:04.149Z,1709223244.149 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2024-02-29T16:14:05.255Z,1709223245.255 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-29T16:14:08.507Z,1709223248.507 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-29T16:14:08.507Z,1709223248.507 [Default:CheckIn:C.Wait] Stopped 2024-02-29T16:14:08.507Z,1709223248.507 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-29T16:14:08.507Z,1709223248.507 [Default:CheckIn:D] Running Loop=1 2024-02-29T16:14:08.922Z,1709223248.922 [Default:CheckIn:D] Stopped 2024-02-29T16:14:08.922Z,1709223248.922 [Default:CheckIn:E] Running Loop=1 2024-02-29T16:14:09.315Z,1709223249.315 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.728105 min 2024-02-29T16:14:09.315Z,1709223249.315 [Default:CheckIn:E] Stopped 2024-02-29T16:14:09.315Z,1709223249.315 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-29T16:14:09.315Z,1709223249.315 [Default:CheckIn] Stopped 2024-02-29T16:14:09.315Z,1709223249.315 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-29T16:14:09.315Z,1709223249.315 [Default:CheckIn](INFO): Running loop #2 2024-02-29T16:14:09.315Z,1709223249.315 [Default:CheckIn] Running Loop=2 2024-02-29T16:14:09.315Z,1709223249.315 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-29T16:14:09.316Z,1709223249.316 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-29T16:14:11.319Z,1709223251.319 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161410.00,A,3648.16443,N,12147.27869,W,0.836,0.00,290224,,,A*73 2024-02-29T16:14:11.322Z,1709223251.322 [NAL9602](INFO): GPS fix at 20240229T161410: (36.802740, -121.787978) 2024-02-29T16:14:11.339Z,1709223251.339 [Default:CheckIn:Read_GPS] Stopped 2024-02-29T16:14:11.339Z,1709223251.339 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-29T16:14:12.139Z,1709223252.139 [Default:CheckIn:Read_Iridium] Stopped 2024-02-29T16:14:12.139Z,1709223252.139 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-29T16:14:12.139Z,1709223252.139 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-29T16:14:18.587Z,1709223258.587 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-29T16:14:42.026Z,1709223282.026 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2024-02-29T16:14:42.100Z,1709223282.100 [NAL9602](ERROR): received: +CSQ:0 OK 2024-02-29T16:14:54.553Z,1709223294.553 [NAL9602](INFO): SBD MO Status=0, MOMSN=10858, MT Status=0, MTMSN=0 2024-02-29T16:14:54.553Z,1709223294.553 [NAL9602](INFO): No messages in MT queue 2024-02-29T16:15:25.256Z,1709223325.256 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-29T16:16:21.942Z,1709223381.942 [DataOverHttps](IMPORTANT): SBD MTMSN=20240229T161620 2024-02-29T16:16:29.432Z,1709223389.432 [DataOverHttps](INFO): Received command: ibit 2024-02-29T16:16:29.457Z,1709223389.457 [CommandExec](IMPORTANT): got command ibit 2024-02-29T16:16:29.547Z,1709223389.547 [IBIT](IMPORTANT): Beginning Initiated BIT 2024-02-29T16:16:29.547Z,1709223389.547 [IBIT](IMPORTANT): Beginning control surface checks. 2024-02-29T16:16:29.550Z,1709223389.550 [CBIT](IMPORTANT): Beginning ground fault scan 2024-02-29T16:16:31.111Z,1709223391.111 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161630.00,A,3648.17057,N,12147.26853,W,2.605,122.73,290224,,,A*72 2024-02-29T16:16:31.114Z,1709223391.114 [NAL9602](INFO): GPS fix at 20240229T161630: (36.802843, -121.787809) 2024-02-29T16:16:40.536Z,1709223400.536 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.000046 CHAN A1 (24V): 0.000706 CHAN A2 (12V): -0.000293 CHAN A3 (5V): -0.001874 CHAN B0 (3.3V): 0.000361 CHAN B1 (3.15aV): 0.000413 CHAN B2 (3.15bV): -0.000281 CHAN B3 (GND): -0.000660 OPEN: 0.006794 Full Scale: +/- 1 mA 2024-02-29T16:16:44.842Z,1709223404.842 [NAL9602](INFO): SBD MO Status=0, MOMSN=10859, MT Status=0, MTMSN=0 2024-02-29T16:16:44.843Z,1709223404.843 [NAL9602](INFO): No messages in MT queue 2024-02-29T16:16:46.082Z,1709223406.082 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161645.00,A,3648.16537,N,12147.25650,W,2.527,115.07,290224,,,A*78 2024-02-29T16:16:46.089Z,1709223406.089 [NAL9602](INFO): GPS fix at 20240229T161645: (36.802756, -121.787608) 2024-02-29T16:16:48.897Z,1709223408.897 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161648.00,A,3648.16443,N,12147.25440,W,2.546,117.66,290224,,,A*76 2024-02-29T16:16:48.899Z,1709223408.899 [NAL9602](INFO): GPS fix at 20240229T161648: (36.802740, -121.787573) 2024-02-29T16:16:52.141Z,1709223412.141 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161651.00,A,3648.16301,N,12147.25208,W,3.052,132.04,290224,,,A*77 2024-02-29T16:16:52.144Z,1709223412.144 [NAL9602](INFO): GPS fix at 20240229T161651: (36.802717, -121.787535) 2024-02-29T16:16:52.516Z,1709223412.516 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-29T16:16:54.943Z,1709223414.943 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161654.00,A,3648.16094,N,12147.25134,W,2.605,173.25,290224,,,A*72 2024-02-29T16:16:54.946Z,1709223414.946 [NAL9602](INFO): GPS fix at 20240229T161654: (36.802682, -121.787522) 2024-02-29T16:16:58.179Z,1709223418.179 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161657.00,A,3648.15925,N,12147.25190,W,2.994,204.08,290224,,,A*74 2024-02-29T16:16:58.182Z,1709223418.182 [NAL9602](INFO): GPS fix at 20240229T161657: (36.802654, -121.787532) 2024-02-29T16:17:01.007Z,1709223421.007 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161700.00,A,3648.15710,N,12147.25316,W,3.305,207.42,290224,,,A*7D 2024-02-29T16:17:01.010Z,1709223421.010 [NAL9602](INFO): GPS fix at 20240229T161700: (36.802618, -121.787553) 2024-02-29T16:17:03.836Z,1709223423.836 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161703.00,A,3648.15467,N,12147.25434,W,3.324,205.05,290224,,,A*78 2024-02-29T16:17:03.838Z,1709223423.838 [NAL9602](INFO): GPS fix at 20240229T161703: (36.802578, -121.787572) 2024-02-29T16:17:04.650Z,1709223424.650 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2024-02-29T16:17:04.650Z,1709223424.650 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-29T16:17:04.709Z,1709223424.709 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-29T16:17:05.182Z,1709223425.182 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-29T16:17:05.182Z,1709223425.182 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2024-02-29T16:17:07.063Z,1709223427.063 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161706.00,A,3648.15214,N,12147.25490,W,3.052,193.30,290224,,,A*79 2024-02-29T16:17:07.075Z,1709223427.075 [NAL9602](INFO): GPS fix at 20240229T161706: (36.802536, -121.787582) 2024-02-29T16:17:07.868Z,1709223427.868 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-29T16:17:09.899Z,1709223429.899 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161709.00,A,3648.14981,N,12147.25462,W,2.857,178.87,290224,,,A*78 2024-02-29T16:17:09.901Z,1709223429.901 [NAL9602](INFO): GPS fix at 20240229T161709: (36.802497, -121.787577) 2024-02-29T16:17:13.124Z,1709223433.124 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161712.00,A,3648.14798,N,12147.25419,W,2.138,173.24,290224,,,A*7A 2024-02-29T16:17:13.127Z,1709223433.127 [NAL9602](INFO): GPS fix at 20240229T161712: (36.802466, -121.787570) 2024-02-29T16:17:15.598Z,1709223435.598 [IBIT](IMPORTANT): Communications Status: Fix Status: 1 Iridium Signal Strength: 5 Latitude: 36.802467 Longitude: -121.787567 2024-02-29T16:17:15.955Z,1709223435.955 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161715.00,A,3648.14637,N,12147.25317,W,2.177,158.31,290224,,,A*76 2024-02-29T16:17:15.958Z,1709223435.958 [NAL9602](INFO): GPS fix at 20240229T161715: (36.802439, -121.787553) 2024-02-29T16:17:16.002Z,1709223436.002 [IBIT](FAULT): Warning: Battery Data not active. Expected only when running primaries. Threshold checking not active. 2024-02-29T16:17:16.396Z,1709223436.396 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 % 2024-02-29T16:17:16.396Z,1709223436.396 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi 2024-02-29T16:17:16.396Z,1709223436.396 [IBIT](IMPORTANT): Pressure:9.951720 PSI 2024-02-29T16:17:16.397Z,1709223436.397 [IBIT](IMPORTANT): Humidity:18.514223 % 2024-02-29T16:17:16.795Z,1709223436.795 [IBIT](IMPORTANT): Vehicle Pitch:-2.755427 degrees 2024-02-29T16:17:16.795Z,1709223436.795 [IBIT](IMPORTANT): Vehicle Roll:2.361674 degrees 2024-02-29T16:17:16.796Z,1709223436.796 [IBIT](IMPORTANT): Vehicle Heading:147.814148 degrees 2024-02-29T16:17:17.181Z,1709223437.181 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m 2024-02-29T16:17:17.181Z,1709223437.181 [IBIT](IMPORTANT): buoyancyNeutral: 187.373978 cc 2024-02-29T16:17:17.181Z,1709223437.181 [IBIT](IMPORTANT): massDefault: 1.756520 cm 2024-02-29T16:17:17.182Z,1709223437.182 [IBIT](IMPORTANT): stopDepth: 112.000000 m 2024-02-29T16:17:17.182Z,1709223437.182 [IBIT](IMPORTANT): abortDepth: 130.000000 m 2024-02-29T16:17:17.182Z,1709223437.182 [IBIT](IMPORTANT): IBIT PASSED 2024-02-29T16:17:23.283Z,1709223443.283 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-29T16:17:38.167Z,1709223458.167 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-29T16:17:38.617Z,1709223458.617 [DAT](INFO): DAT read: user:6>Rx Time:16:17:37.5231 2024-02-29T16:17:38.618Z,1709223458.618 [DAT](INFO): Rx dataTimestamp_ set to:1709223458.617536 2024-02-29T16:17:38.618Z,1709223458.618 [DAT](INFO): DAT read: 2024-02-29T16:17:38.619Z,1709223458.619 [DAT](INFO): DAT read: $Packet for address 0 2024-02-29T16:17:38.619Z,1709223458.619 [DAT](INFO): received a packet notification 2024-02-29T16:17:48.674Z,1709223468.674 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-29T16:17:53.115Z,1709223473.115 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-29T16:18:08.069Z,1709223488.069 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-29T16:18:23.415Z,1709223503.415 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-29T16:18:39.171Z,1709223519.171 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-29T16:18:54.543Z,1709223534.543 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-29T16:19:09.086Z,1709223549.086 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7). 2024-02-29T16:19:12.739Z,1709223552.739 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-29T16:19:12.739Z,1709223552.739 [Default:CheckIn:C.Wait] Stopped 2024-02-29T16:19:12.739Z,1709223552.739 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-29T16:19:12.739Z,1709223552.739 [Default:CheckIn:D] Running Loop=1 2024-02-29T16:19:13.143Z,1709223553.143 [Default:CheckIn:D] Stopped 2024-02-29T16:19:13.143Z,1709223553.143 [Default:CheckIn:E] Running Loop=1 2024-02-29T16:19:13.560Z,1709223553.560 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 15.798447 min 2024-02-29T16:19:13.560Z,1709223553.560 [Default:CheckIn:E] Stopped 2024-02-29T16:19:13.561Z,1709223553.561 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-29T16:19:13.561Z,1709223553.561 [Default:CheckIn] Stopped 2024-02-29T16:19:13.561Z,1709223553.561 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-29T16:19:13.561Z,1709223553.561 [Default:CheckIn](INFO): Running loop #3 2024-02-29T16:19:13.561Z,1709223553.561 [Default:CheckIn] Running Loop=3 2024-02-29T16:19:13.561Z,1709223553.561 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-29T16:19:13.561Z,1709223553.561 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-29T16:19:15.561Z,1709223555.561 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161914.00,A,3648.14395,N,12147.22835,W,0.019,39.53,290224,,,D*44 2024-02-29T16:19:15.564Z,1709223555.564 [NAL9602](INFO): GPS fix at 20240229T161914: (36.802399, -121.787139) 2024-02-29T16:19:15.603Z,1709223555.603 [Default:CheckIn:Read_GPS] Stopped 2024-02-29T16:19:15.603Z,1709223555.603 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-29T16:19:22.744Z,1709223562.744 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20240229T155849/Courier0007.lzma 2024-02-29T16:19:23.746Z,1709223563.746 [DataOverHttps](INFO): Moved sent file to Logs/20240229T155849/Courier0007.lzma.bak 2024-02-29T16:19:23.747Z,1709223563.747 [DataOverHttps](INFO): SBD MOMSN=19304965 2024-02-29T16:19:29.699Z,1709223569.699 [NAL9602](INFO): SBD MO Status=0, MOMSN=10860, MT Status=0, MTMSN=0 2024-02-29T16:19:29.699Z,1709223569.699 [NAL9602](INFO): No messages in MT queue 2024-02-29T16:19:39.760Z,1709223579.760 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240229T155849/Courier0010.lzma 2024-02-29T16:19:40.762Z,1709223580.762 [DataOverHttps](INFO): Moved sent file to Logs/20240229T155849/Courier0010.lzma.bak 2024-02-29T16:19:40.763Z,1709223580.763 [DataOverHttps](INFO): SBD MOMSN=19304968 2024-02-29T16:19:56.705Z,1709223596.705 [DataOverHttps](INFO): Sending 281 bytes from file Logs/20240229T155849/Express0008.lzma 2024-02-29T16:19:57.706Z,1709223597.706 [DataOverHttps](INFO): Moved sent file to Logs/20240229T155849/Express0008.lzma.bak 2024-02-29T16:19:57.706Z,1709223597.706 [DataOverHttps](INFO): SBD MOMSN=19304972 2024-02-29T16:20:00.400Z,1709223600.400 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-29T16:20:05.672Z,1709223605.672 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2024-02-29T16:20:05.672Z,1709223605.672 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-29T16:20:05.683Z,1709223605.683 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-29T16:20:06.099Z,1709223606.099 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-29T16:20:06.099Z,1709223606.099 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2024-02-29T16:20:13.697Z,1709223613.697 [DataOverHttps](INFO): Sending 845 bytes from file Logs/20240229T155849/Express0011.lzma 2024-02-29T16:20:14.698Z,1709223614.698 [DataOverHttps](INFO): Moved sent file to Logs/20240229T155849/Express0011.lzma.bak 2024-02-29T16:20:14.698Z,1709223614.698 [DataOverHttps](INFO): SBD MOMSN=19304975 2024-02-29T16:20:15.766Z,1709223615.766 [Default:CheckIn:Read_Iridium] Stopped 2024-02-29T16:20:15.766Z,1709223615.766 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-29T16:20:15.767Z,1709223615.767 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-29T16:21:45.075Z,1709223705.075 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-29T16:21:59.216Z,1709223719.216 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-29T16:22:13.357Z,1709223733.357 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-29T16:22:27.095Z,1709223747.095 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-29T16:22:40.424Z,1709223760.424 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-29T16:22:54.183Z,1709223774.183 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-29T16:23:06.756Z,1709223786.756 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2024-02-29T16:23:06.756Z,1709223786.756 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-29T16:23:06.766Z,1709223786.766 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-29T16:23:07.175Z,1709223787.175 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-29T16:23:07.175Z,1709223787.175 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2024-02-29T16:23:08.347Z,1709223788.347 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-29T16:23:21.680Z,1709223801.680 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-29T16:23:35.414Z,1709223815.414 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-29T16:23:48.749Z,1709223828.749 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-29T16:25:16.503Z,1709223916.503 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-29T16:25:16.503Z,1709223916.503 [Default:CheckIn:C.Wait] Stopped 2024-02-29T16:25:16.504Z,1709223916.504 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-29T16:25:16.504Z,1709223916.504 [Default:CheckIn:D] Running Loop=1 2024-02-29T16:25:16.907Z,1709223916.907 [Default:CheckIn:D] Stopped 2024-02-29T16:25:16.907Z,1709223916.907 [Default:CheckIn:E] Running Loop=1 2024-02-29T16:25:17.307Z,1709223917.307 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.861178 min 2024-02-29T16:25:17.307Z,1709223917.307 [Default:CheckIn:E] Stopped 2024-02-29T16:25:17.307Z,1709223917.307 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-29T16:25:17.307Z,1709223917.307 [Default:CheckIn] Stopped 2024-02-29T16:25:17.308Z,1709223917.308 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-29T16:25:17.308Z,1709223917.308 [Default:CheckIn](INFO): Running loop #4 2024-02-29T16:25:17.308Z,1709223917.308 [Default:CheckIn] Running Loop=4 2024-02-29T16:25:17.308Z,1709223917.308 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-29T16:25:17.308Z,1709223917.308 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-29T16:25:19.319Z,1709223919.319 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162518.00,A,3648.14018,N,12147.22755,W,0.019,114.91,290224,,,D*78 2024-02-29T16:25:19.329Z,1709223919.329 [NAL9602](INFO): GPS fix at 20240229T162518: (36.802336, -121.787126) 2024-02-29T16:25:19.345Z,1709223919.345 [Default:CheckIn:Read_GPS] Stopped 2024-02-29T16:25:19.345Z,1709223919.345 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-29T16:25:26.676Z,1709223926.676 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240229T155849/Courier0015.lzma 2024-02-29T16:25:27.678Z,1709223927.678 [DataOverHttps](INFO): Moved sent file to Logs/20240229T155849/Courier0015.lzma.bak 2024-02-29T16:25:27.678Z,1709223927.678 [DataOverHttps](INFO): SBD MOMSN=19304999 2024-02-29T16:25:31.439Z,1709223931.439 [NAL9602](INFO): SBD MO Status=0, MOMSN=10861, MT Status=0, MTMSN=0 2024-02-29T16:25:31.440Z,1709223931.440 [NAL9602](INFO): No messages in MT queue 2024-02-29T16:25:43.600Z,1709223943.600 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240229T155849/Express0017.lzma 2024-02-29T16:25:44.602Z,1709223944.602 [DataOverHttps](INFO): Moved sent file to Logs/20240229T155849/Express0017.lzma.bak 2024-02-29T16:25:44.602Z,1709223944.602 [DataOverHttps](INFO): SBD MOMSN=19305002 2024-02-29T16:25:46.062Z,1709223946.062 [Default:CheckIn:Read_Iridium] Stopped 2024-02-29T16:25:46.062Z,1709223946.062 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-29T16:25:46.062Z,1709223946.062 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-29T16:26:02.137Z,1709223962.137 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-29T16:26:07.805Z,1709223967.805 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2024-02-29T16:26:07.805Z,1709223967.805 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-29T16:26:07.816Z,1709223967.816 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-29T16:26:08.225Z,1709223968.225 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-29T16:26:08.225Z,1709223968.225 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2024-02-29T16:26:23.143Z,1709223983.143 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-29T16:26:38.155Z,1709223998.155 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-29T16:26:53.084Z,1709224013.084 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-29T16:27:08.460Z,1709224028.460 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-29T16:27:23.811Z,1709224043.811 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-29T16:27:38.759Z,1709224058.759 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-29T16:27:53.707Z,1709224073.707 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-29T16:28:09.059Z,1709224089.059 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-29T16:28:24.884Z,1709224104.884 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-29T16:28:40.169Z,1709224120.169 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7). 2024-02-29T16:29:08.913Z,1709224148.913 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2024-02-29T16:29:08.913Z,1709224148.913 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-29T16:29:08.924Z,1709224148.924 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-29T16:29:09.349Z,1709224149.349 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-29T16:29:09.349Z,1709224149.349 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2024-02-29T16:29:57.226Z,1709224197.226 [DataOverHttps](IMPORTANT): SBD MTMSN=20240229T162956 2024-02-29T16:30:00.218Z,1709224200.218 [BPC1](INFO): Calculating totals. Valid battery stick count: 45. Valid reserve battery stick count: 5. 2024-02-29T16:30:00.221Z,1709224200.221 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle. 2024-02-29T16:30:04.708Z,1709224204.708 [DataOverHttps](INFO): Received command: ibit 2024-02-29T16:30:04.765Z,1709224204.765 [CommandExec](IMPORTANT): got command ibit 2024-02-29T16:30:05.079Z,1709224205.079 [IBIT](IMPORTANT): Beginning Initiated BIT 2024-02-29T16:30:05.079Z,1709224205.079 [IBIT](IMPORTANT): Beginning control surface checks. 2024-02-29T16:30:05.082Z,1709224205.082 [CBIT](IMPORTANT): Beginning ground fault scan 2024-02-29T16:30:06.670Z,1709224206.670 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163005.00,A,3648.14026,N,12147.22683,W,0.078,114.91,290224,,,A*75 2024-02-29T16:30:06.673Z,1709224206.673 [NAL9602](INFO): GPS fix at 20240229T163005: (36.802338, -121.787114) 2024-02-29T16:30:16.099Z,1709224216.099 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.000728 CHAN A1 (24V): 0.000318 CHAN A2 (12V): 0.000298 CHAN A3 (5V): -0.001283 CHAN B0 (3.3V): 0.000413 CHAN B1 (3.15aV): 0.000074 CHAN B2 (3.15bV): -0.000120 CHAN B3 (GND): -0.000297 OPEN: 0.006747 Full Scale: +/- 1 mA 2024-02-29T16:30:27.678Z,1709224227.678 [NAL9602](INFO): SBD MO Status=0, MOMSN=10862, MT Status=0, MTMSN=0 2024-02-29T16:30:27.678Z,1709224227.678 [NAL9602](INFO): No messages in MT queue 2024-02-29T16:30:28.890Z,1709224228.890 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163028.00,A,3648.14008,N,12147.22610,W,0.078,114.91,290224,,,A*7C 2024-02-29T16:30:28.892Z,1709224228.892 [NAL9602](INFO): GPS fix at 20240229T163028: (36.802335, -121.787102) 2024-02-29T16:30:32.123Z,1709224232.123 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163031.00,A,3648.14005,N,12147.22618,W,0.000,114.91,290224,,,A*7E 2024-02-29T16:30:32.126Z,1709224232.126 [NAL9602](INFO): GPS fix at 20240229T163031: (36.802334, -121.787103) 2024-02-29T16:30:34.959Z,1709224234.959 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163034.00,A,3648.14005,N,12147.22622,W,0.019,114.91,290224,,,A*7A 2024-02-29T16:30:34.962Z,1709224234.962 [NAL9602](INFO): GPS fix at 20240229T163034: (36.802334, -121.787104) 2024-02-29T16:30:38.191Z,1709224238.191 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163037.00,A,3648.14015,N,12147.22625,W,0.019,114.91,290224,,,A*7F 2024-02-29T16:30:38.194Z,1709224238.194 [NAL9602](INFO): GPS fix at 20240229T163037: (36.802336, -121.787104) 2024-02-29T16:30:41.010Z,1709224241.010 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163040.00,A,3648.14024,N,12147.22625,W,0.019,114.91,290224,,,A*7D 2024-02-29T16:30:41.015Z,1709224241.015 [NAL9602](INFO): GPS fix at 20240229T163040: (36.802337, -121.787104) 2024-02-29T16:30:43.842Z,1709224243.842 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163043.00,A,3648.14031,N,12147.22622,W,0.019,114.91,290224,,,A*7D 2024-02-29T16:30:43.844Z,1709224243.844 [NAL9602](INFO): GPS fix at 20240229T163043: (36.802338, -121.787104) 2024-02-29T16:30:47.075Z,1709224247.075 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163046.00,A,3648.14034,N,12147.22625,W,0.019,114.91,290224,,,A*7A 2024-02-29T16:30:47.078Z,1709224247.078 [NAL9602](INFO): GPS fix at 20240229T163046: (36.802339, -121.787104) 2024-02-29T16:30:49.919Z,1709224249.919 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163049.00,A,3648.14032,N,12147.22634,W,0.039,114.91,290224,,,A*71 2024-02-29T16:30:49.922Z,1709224249.922 [NAL9602](INFO): GPS fix at 20240229T163049: (36.802339, -121.787106) 2024-02-29T16:30:50.811Z,1709224250.811 [IBIT](IMPORTANT): Communications Status: Fix Status: 1 Iridium Signal Strength: 3 Latitude: 36.802338 Longitude: -121.787109 2024-02-29T16:30:51.218Z,1709224251.218 [IBIT](IMPORTANT): Battery Status: Battery Charge (AH): 0.000000 Voltage: 16.611000 2024-02-29T16:30:51.218Z,1709224251.218 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah 2024-02-29T16:30:51.219Z,1709224251.219 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V 2024-02-29T16:30:51.602Z,1709224251.602 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 % 2024-02-29T16:30:51.603Z,1709224251.603 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi 2024-02-29T16:30:51.603Z,1709224251.603 [IBIT](IMPORTANT): Pressure:9.912636 PSI 2024-02-29T16:30:51.603Z,1709224251.603 [IBIT](IMPORTANT): Humidity:18.563057 % 2024-02-29T16:30:52.030Z,1709224252.030 [IBIT](IMPORTANT): Vehicle Pitch:-1.080659 degrees 2024-02-29T16:30:52.030Z,1709224252.030 [IBIT](IMPORTANT): Vehicle Roll:5.202343 degrees 2024-02-29T16:30:52.030Z,1709224252.030 [IBIT](IMPORTANT): Vehicle Heading:208.643921 degrees 2024-02-29T16:30:52.426Z,1709224252.426 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m 2024-02-29T16:30:52.427Z,1709224252.427 [IBIT](IMPORTANT): buoyancyNeutral: 187.373978 cc 2024-02-29T16:30:52.427Z,1709224252.427 [IBIT](IMPORTANT): massDefault: 1.756520 cm 2024-02-29T16:30:52.427Z,1709224252.427 [IBIT](IMPORTANT): stopDepth: 112.000000 m 2024-02-29T16:30:52.427Z,1709224252.427 [IBIT](IMPORTANT): abortDepth: 130.000000 m 2024-02-29T16:30:52.428Z,1709224252.428 [IBIT](IMPORTANT): IBIT PASSED 2024-02-29T16:30:52.755Z,1709224252.755 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-29T16:30:52.756Z,1709224252.756 [Default:CheckIn:C.Wait] Stopped 2024-02-29T16:30:52.756Z,1709224252.756 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-29T16:30:52.756Z,1709224252.756 [Default:CheckIn:D] Running Loop=1 2024-02-29T16:30:53.176Z,1709224253.176 [Default:CheckIn:D] Stopped 2024-02-29T16:30:53.176Z,1709224253.176 [Default:CheckIn:E] Running Loop=1 2024-02-29T16:30:53.561Z,1709224253.561 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 27.465664 min 2024-02-29T16:30:53.561Z,1709224253.561 [Default:CheckIn:E] Stopped 2024-02-29T16:30:53.561Z,1709224253.561 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-29T16:30:53.561Z,1709224253.561 [Default:CheckIn] Stopped 2024-02-29T16:30:53.561Z,1709224253.561 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-29T16:30:53.561Z,1709224253.561 [Default:CheckIn](INFO): Running loop #5 2024-02-29T16:30:53.561Z,1709224253.561 [Default:CheckIn] Running Loop=5 2024-02-29T16:30:53.562Z,1709224253.562 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-29T16:30:53.562Z,1709224253.562 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-29T16:30:55.559Z,1709224255.559 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163054.00,A,3648.14020,N,12147.22640,W,0.058,114.91,290224,,,A*7A 2024-02-29T16:30:55.562Z,1709224255.562 [NAL9602](INFO): GPS fix at 20240229T163054: (36.802337, -121.787107) 2024-02-29T16:30:55.630Z,1709224255.630 [Default:CheckIn:Read_GPS] Stopped 2024-02-29T16:30:55.630Z,1709224255.630 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-29T16:31:03.168Z,1709224263.168 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20240229T155849/Courier0019.lzma 2024-02-29T16:31:04.170Z,1709224264.170 [DataOverHttps](INFO): Moved sent file to Logs/20240229T155849/Courier0019.lzma.bak 2024-02-29T16:31:04.170Z,1709224264.170 [DataOverHttps](INFO): SBD MOMSN=19305008 2024-02-29T16:31:20.258Z,1709224280.258 [DataOverHttps](INFO): Sending 775 bytes from file Logs/20240229T155849/Express0020.lzma 2024-02-29T16:31:21.258Z,1709224281.258 [DataOverHttps](INFO): Moved sent file to Logs/20240229T155849/Express0020.lzma.bak 2024-02-29T16:31:21.258Z,1709224281.258 [DataOverHttps](INFO): SBD MOMSN=19305012 2024-02-29T16:31:22.472Z,1709224282.472 [Default:CheckIn:Read_Iridium] Stopped 2024-02-29T16:31:22.472Z,1709224282.472 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-29T16:31:22.472Z,1709224282.472 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-29T16:31:27.301Z,1709224287.301 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-29T16:32:09.737Z,1709224329.737 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2024-02-29T16:32:09.737Z,1709224329.737 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-29T16:32:09.805Z,1709224329.805 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-29T16:32:10.201Z,1709224330.201 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-29T16:32:10.201Z,1709224330.201 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2024-02-29T16:35:10.726Z,1709224510.726 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2024-02-29T16:35:10.727Z,1709224510.727 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-29T16:35:10.801Z,1709224510.801 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-29T16:35:11.220Z,1709224511.220 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-29T16:35:11.220Z,1709224511.220 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2024-02-29T16:36:23.039Z,1709224583.039 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-29T16:36:23.039Z,1709224583.039 [Default:CheckIn:C.Wait] Stopped 2024-02-29T16:36:23.039Z,1709224583.039 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-29T16:36:23.039Z,1709224583.039 [Default:CheckIn:D] Running Loop=1 2024-02-29T16:36:23.442Z,1709224583.442 [Default:CheckIn:D] Stopped 2024-02-29T16:36:23.442Z,1709224583.442 [Default:CheckIn:E] Running Loop=1 2024-02-29T16:36:23.860Z,1709224583.860 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 32.970099 min 2024-02-29T16:36:23.860Z,1709224583.860 [Default:CheckIn:E] Stopped 2024-02-29T16:36:23.860Z,1709224583.860 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-29T16:36:23.860Z,1709224583.860 [Default:CheckIn] Stopped 2024-02-29T16:36:23.861Z,1709224583.861 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-29T16:36:23.861Z,1709224583.861 [Default:CheckIn](INFO): Running loop #6 2024-02-29T16:36:23.861Z,1709224583.861 [Default:CheckIn] Running Loop=6 2024-02-29T16:36:23.861Z,1709224583.861 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-29T16:36:23.861Z,1709224583.861 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-29T16:36:25.866Z,1709224585.866 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163625.00,A,3648.13964,N,12147.22780,W,0.019,114.91,290224,,,A*7C 2024-02-29T16:36:25.869Z,1709224585.869 [NAL9602](INFO): GPS fix at 20240229T163625: (36.802327, -121.787130) 2024-02-29T16:36:25.884Z,1709224585.884 [Default:CheckIn:Read_GPS] Stopped 2024-02-29T16:36:25.884Z,1709224585.884 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-29T16:36:26.680Z,1709224586.680 [Default:CheckIn:Read_Iridium] Stopped 2024-02-29T16:36:26.680Z,1709224586.680 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-29T16:36:26.680Z,1709224586.680 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-29T16:36:46.061Z,1709224606.061 [NAL9602](INFO): SBD MO Status=0, MOMSN=10863, MT Status=0, MTMSN=0 2024-02-29T16:36:46.061Z,1709224606.061 [NAL9602](INFO): No messages in MT queue 2024-02-29T16:37:16.757Z,1709224636.757 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-29T16:38:11.713Z,1709224691.713 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2024-02-29T16:38:11.713Z,1709224691.713 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-29T16:38:11.777Z,1709224691.777 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-29T16:38:12.196Z,1709224692.196 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-29T16:38:12.196Z,1709224692.196 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2024-02-29T16:41:12.713Z,1709224872.713 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2024-02-29T16:41:12.713Z,1709224872.713 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-29T16:41:12.792Z,1709224872.792 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-29T16:41:13.180Z,1709224873.180 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-29T16:41:13.180Z,1709224873.180 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2024-02-29T16:41:27.256Z,1709224887.256 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-29T16:41:27.256Z,1709224887.256 [Default:CheckIn:C.Wait] Stopped 2024-02-29T16:41:27.256Z,1709224887.256 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-29T16:41:27.256Z,1709224887.256 [Default:CheckIn:D] Running Loop=1 2024-02-29T16:41:27.664Z,1709224887.664 [Default:CheckIn:D] Stopped 2024-02-29T16:41:27.664Z,1709224887.664 [Default:CheckIn:E] Running Loop=1 2024-02-29T16:41:28.079Z,1709224888.079 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 38.040470 min 2024-02-29T16:41:28.079Z,1709224888.079 [Default:CheckIn:E] Stopped 2024-02-29T16:41:28.079Z,1709224888.079 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-29T16:41:28.079Z,1709224888.079 [Default:CheckIn] Stopped 2024-02-29T16:41:28.079Z,1709224888.079 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-29T16:41:28.079Z,1709224888.079 [Default:CheckIn](INFO): Running loop #7 2024-02-29T16:41:28.079Z,1709224888.079 [Default:CheckIn] Running Loop=7 2024-02-29T16:41:28.079Z,1709224888.079 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-29T16:41:28.079Z,1709224888.079 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-29T16:41:30.075Z,1709224890.075 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164129.00,A,3648.14185,N,12147.22706,W,0.000,114.91,290224,,,A*76 2024-02-29T16:41:30.078Z,1709224890.078 [NAL9602](INFO): GPS fix at 20240229T164129: (36.802364, -121.787118) 2024-02-29T16:41:30.121Z,1709224890.121 [Default:CheckIn:Read_GPS] Stopped 2024-02-29T16:41:30.121Z,1709224890.121 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-29T16:41:37.892Z,1709224897.892 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240229T155849/Courier0025.lzma 2024-02-29T16:41:38.894Z,1709224898.894 [DataOverHttps](INFO): Moved sent file to Logs/20240229T155849/Courier0025.lzma.bak 2024-02-29T16:41:38.894Z,1709224898.894 [DataOverHttps](INFO): SBD MOMSN=19305040 2024-02-29T16:41:42.595Z,1709224902.595 [NAL9602](INFO): SBD MO Status=0, MOMSN=10864, MT Status=0, MTMSN=0 2024-02-29T16:41:42.595Z,1709224902.595 [NAL9602](INFO): No messages in MT queue 2024-02-29T16:41:55.048Z,1709224915.048 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240229T155849/Courier0028.lzma 2024-02-29T16:41:56.052Z,1709224916.052 [DataOverHttps](INFO): Moved sent file to Logs/20240229T155849/Courier0028.lzma.bak 2024-02-29T16:41:56.052Z,1709224916.052 [DataOverHttps](INFO): SBD MOMSN=19305044 2024-02-29T16:42:12.224Z,1709224932.224 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20240229T155849/Express0026.lzma 2024-02-29T16:42:13.226Z,1709224933.226 [DataOverHttps](INFO): Moved sent file to Logs/20240229T155849/Express0026.lzma.bak 2024-02-29T16:42:13.226Z,1709224933.226 [DataOverHttps](INFO): SBD MOMSN=19305047 2024-02-29T16:42:13.297Z,1709224933.297 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-29T16:42:29.436Z,1709224949.436 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240229T155849/Express0029.lzma 2024-02-29T16:42:30.438Z,1709224950.438 [DataOverHttps](INFO): Moved sent file to Logs/20240229T155849/Express0029.lzma.bak 2024-02-29T16:42:30.438Z,1709224950.438 [DataOverHttps](INFO): SBD MOMSN=19305050 2024-02-29T16:42:31.501Z,1709224951.501 [Default:CheckIn:Read_Iridium] Stopped 2024-02-29T16:42:31.501Z,1709224951.501 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-29T16:42:31.501Z,1709224951.501 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-29T16:44:13.709Z,1709225053.709 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2024-02-29T16:44:13.709Z,1709225053.709 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-29T16:44:13.720Z,1709225053.720 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-29T16:44:14.120Z,1709225054.120 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-29T16:44:14.121Z,1709225054.121 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2024-02-29T16:47:14.723Z,1709225234.723 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2024-02-29T16:47:14.723Z,1709225234.723 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-29T16:47:14.734Z,1709225234.734 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-29T16:47:15.151Z,1709225235.151 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-29T16:47:15.151Z,1709225235.151 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2024-02-29T16:47:32.099Z,1709225252.099 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-29T16:47:32.099Z,1709225252.099 [Default:CheckIn:C.Wait] Stopped 2024-02-29T16:47:32.099Z,1709225252.099 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-29T16:47:32.099Z,1709225252.099 [Default:CheckIn:D] Running Loop=1 2024-02-29T16:47:32.506Z,1709225252.506 [Default:CheckIn:D] Stopped 2024-02-29T16:47:32.507Z,1709225252.507 [Default:CheckIn:E] Running Loop=1 2024-02-29T16:47:32.900Z,1709225252.900 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 44.121175 min 2024-02-29T16:47:32.900Z,1709225252.900 [Default:CheckIn:E] Stopped 2024-02-29T16:47:32.900Z,1709225252.900 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-29T16:47:32.900Z,1709225252.900 [Default:CheckIn] Stopped 2024-02-29T16:47:32.900Z,1709225252.900 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-29T16:47:32.900Z,1709225252.900 [Default:CheckIn](INFO): Running loop #8 2024-02-29T16:47:32.901Z,1709225252.901 [Default:CheckIn] Running Loop=8 2024-02-29T16:47:32.901Z,1709225252.901 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-29T16:47:32.901Z,1709225252.901 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-29T16:47:34.907Z,1709225254.907 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164734.00,A,3648.14869,N,12147.21913,W,0.019,327.55,290224,,,A*7C 2024-02-29T16:47:34.910Z,1709225254.910 [NAL9602](INFO): GPS fix at 20240229T164734: (36.802478, -121.786986) 2024-02-29T16:47:34.925Z,1709225254.925 [Default:CheckIn:Read_GPS] Stopped 2024-02-29T16:47:34.925Z,1709225254.925 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-29T16:47:42.304Z,1709225262.304 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240229T155849/Courier0031.lzma 2024-02-29T16:47:43.306Z,1709225263.306 [DataOverHttps](INFO): Moved sent file to Logs/20240229T155849/Courier0031.lzma.bak 2024-02-29T16:47:43.306Z,1709225263.306 [DataOverHttps](INFO): SBD MOMSN=19305053 2024-02-29T16:47:56.727Z,1709225276.727 [NAL9602](INFO): SBD MO Status=2, MOMSN=10865, MT Status=2, MTMSN=0 2024-02-29T16:47:56.727Z,1709225276.727 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-02-29T16:47:59.180Z,1709225279.180 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240229T155849/Express0032.lzma 2024-02-29T16:48:00.182Z,1709225280.182 [DataOverHttps](INFO): Moved sent file to Logs/20240229T155849/Express0032.lzma.bak 2024-02-29T16:48:00.182Z,1709225280.182 [DataOverHttps](INFO): SBD MOMSN=19305056 2024-02-29T16:48:01.225Z,1709225281.225 [Default:CheckIn:Read_Iridium] Stopped 2024-02-29T16:48:01.225Z,1709225281.225 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-29T16:48:01.225Z,1709225281.225 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-29T16:48:21.777Z,1709225301.777 [NAL9602](INFO): SBD MO Status=0, MOMSN=10865, MT Status=0, MTMSN=0 2024-02-29T16:48:21.777Z,1709225301.777 [NAL9602](INFO): No messages in MT queue 2024-02-29T16:48:52.469Z,1709225332.469 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-29T16:50:15.711Z,1709225415.711 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2024-02-29T16:50:15.711Z,1709225415.711 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-29T16:50:15.721Z,1709225415.721 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-29T16:50:16.133Z,1709225416.133 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-29T16:50:16.133Z,1709225416.133 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2024-02-29T16:53:01.757Z,1709225581.757 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-29T16:53:01.757Z,1709225581.757 [Default:CheckIn:C.Wait] Stopped 2024-02-29T16:53:01.757Z,1709225581.757 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-29T16:53:01.757Z,1709225581.757 [Default:CheckIn:D] Running Loop=1 2024-02-29T16:53:02.174Z,1709225582.174 [Default:CheckIn:D] Stopped 2024-02-29T16:53:02.174Z,1709225582.174 [Default:CheckIn:E] Running Loop=1 2024-02-29T16:53:02.580Z,1709225582.580 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 49.615629 min 2024-02-29T16:53:02.580Z,1709225582.580 [Default:CheckIn:E] Stopped 2024-02-29T16:53:02.580Z,1709225582.580 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-29T16:53:02.580Z,1709225582.580 [Default:CheckIn] Stopped 2024-02-29T16:53:02.580Z,1709225582.580 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-29T16:53:02.581Z,1709225582.581 [Default:CheckIn](INFO): Running loop #9 2024-02-29T16:53:02.581Z,1709225582.581 [Default:CheckIn] Running Loop=9 2024-02-29T16:53:02.581Z,1709225582.581 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-29T16:53:02.581Z,1709225582.581 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-29T16:53:04.580Z,1709225584.580 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165303.00,A,3648.35875,N,12147.12238,W,5.287,7.62,290224,,,D*71 2024-02-29T16:53:04.583Z,1709225584.583 [NAL9602](INFO): GPS fix at 20240229T165303: (36.805979, -121.785373) 2024-02-29T16:53:04.627Z,1709225584.627 [Default:CheckIn:Read_GPS] Stopped 2024-02-29T16:53:04.627Z,1709225584.627 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-29T16:53:11.864Z,1709225591.864 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240229T155849/Courier0034.lzma 2024-02-29T16:53:12.866Z,1709225592.866 [DataOverHttps](INFO): Moved sent file to Logs/20240229T155849/Courier0034.lzma.bak 2024-02-29T16:53:12.867Z,1709225592.867 [DataOverHttps](INFO): SBD MOMSN=19305060 2024-02-29T16:53:16.725Z,1709225596.725 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2024-02-29T16:53:16.725Z,1709225596.725 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-29T16:53:16.772Z,1709225596.772 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-29T16:53:17.193Z,1709225597.193 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-29T16:53:17.193Z,1709225597.193 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2024-02-29T16:53:22.359Z,1709225602.359 [NAL9602](INFO): SBD MO Status=0, MOMSN=10866, MT Status=0, MTMSN=0 2024-02-29T16:53:22.360Z,1709225602.360 [NAL9602](INFO): No messages in MT queue 2024-02-29T16:53:28.816Z,1709225608.816 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20240229T155849/Express0035.lzma 2024-02-29T16:53:29.818Z,1709225609.818 [DataOverHttps](INFO): Moved sent file to Logs/20240229T155849/Express0035.lzma.bak 2024-02-29T16:53:29.818Z,1709225609.818 [DataOverHttps](INFO): SBD MOMSN=19305063 2024-02-29T16:53:30.870Z,1709225610.870 [Default:CheckIn:Read_Iridium] Stopped 2024-02-29T16:53:30.870Z,1709225610.870 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-29T16:53:30.870Z,1709225610.870 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-29T16:53:53.057Z,1709225633.057 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-29T16:56:17.711Z,1709225777.711 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2024-02-29T16:56:17.711Z,1709225777.711 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-29T16:56:17.721Z,1709225777.721 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-29T16:56:18.139Z,1709225778.139 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-29T16:56:18.140Z,1709225778.140 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2024-02-29T16:58:31.496Z,1709225911.496 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-29T16:58:31.496Z,1709225911.496 [Default:CheckIn:C.Wait] Stopped 2024-02-29T16:58:31.496Z,1709225911.496 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-29T16:58:31.497Z,1709225911.497 [Default:CheckIn:D] Running Loop=1 2024-02-29T16:58:31.910Z,1709225911.910 [Default:CheckIn:D] Stopped 2024-02-29T16:58:31.910Z,1709225911.910 [Default:CheckIn:E] Running Loop=1 2024-02-29T16:58:32.279Z,1709225912.279 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 55.111235 min 2024-02-29T16:58:32.279Z,1709225912.279 [Default:CheckIn:E] Stopped 2024-02-29T16:58:32.279Z,1709225912.279 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-29T16:58:32.279Z,1709225912.279 [Default:CheckIn] Stopped 2024-02-29T16:58:32.279Z,1709225912.279 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-29T16:58:32.280Z,1709225912.280 [Default:CheckIn](INFO): Running loop #10 2024-02-29T16:58:32.280Z,1709225912.280 [Default:CheckIn] Running Loop=10 2024-02-29T16:58:32.280Z,1709225912.280 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-29T16:58:32.280Z,1709225912.280 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-29T16:58:34.301Z,1709225914.301 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165833.00,A,3648.18158,N,12148.09063,W,23.209,258.71,290224,,,D*41 2024-02-29T16:58:34.303Z,1709225914.303 [NAL9602](INFO): GPS fix at 20240229T165833: (36.803026, -121.801511) 2024-02-29T16:58:34.318Z,1709225914.318 [Default:CheckIn:Read_GPS] Stopped 2024-02-29T16:58:34.318Z,1709225914.318 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-29T16:58:41.304Z,1709225921.304 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240229T155849/Courier0037.lzma 2024-02-29T16:58:42.309Z,1709225922.309 [DataOverHttps](INFO): Moved sent file to Logs/20240229T155849/Courier0037.lzma.bak 2024-02-29T16:58:42.310Z,1709225922.310 [DataOverHttps](INFO): SBD MOMSN=19305068 2024-02-29T16:58:52.898Z,1709225932.898 [NAL9602](INFO): SBD MO Status=2, MOMSN=10867, MT Status=2, MTMSN=0 2024-02-29T16:58:52.898Z,1709225932.898 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-02-29T16:59:01.407Z,1709225941.407 [NAL9602](INFO): SBD MO Status=0, MOMSN=10867, MT Status=0, MTMSN=0 2024-02-29T16:59:01.408Z,1709225941.408 [NAL9602](INFO): No messages in MT queue 2024-02-29T16:59:01.480Z,1709225941.480 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20240229T155849/Express0038.lzma 2024-02-29T16:59:02.482Z,1709225942.482 [DataOverHttps](INFO): Moved sent file to Logs/20240229T155849/Express0038.lzma.bak 2024-02-29T16:59:02.482Z,1709225942.482 [DataOverHttps](INFO): SBD MOMSN=19305071 2024-02-29T16:59:03.840Z,1709225943.840 [Default:CheckIn:Read_Iridium] Stopped 2024-02-29T16:59:03.840Z,1709225943.840 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-29T16:59:03.840Z,1709225943.840 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-29T16:59:18.782Z,1709225958.782 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2024-02-29T16:59:18.782Z,1709225958.782 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-29T16:59:18.793Z,1709225958.793 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-29T16:59:19.204Z,1709225959.204 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-29T16:59:19.204Z,1709225959.204 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2024-02-29T16:59:32.102Z,1709225972.102 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-29T17:02:19.793Z,1709226139.793 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2024-02-29T17:02:19.793Z,1709226139.793 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-02-29T17:02:19.821Z,1709226139.821 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-02-29T17:02:20.207Z,1709226140.207 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-02-29T17:02:20.207Z,1709226140.207 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2024-02-29T17:04:04.425Z,1709226244.425 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-29T17:04:04.425Z,1709226244.425 [Default:CheckIn:C.Wait] Stopped 2024-02-29T17:04:04.425Z,1709226244.425 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-29T17:04:04.426Z,1709226244.426 [Default:CheckIn:D] Running Loop=1 2024-02-29T17:04:04.829Z,1709226244.829 [Default:CheckIn:D] Stopped 2024-02-29T17:04:04.829Z,1709226244.829 [Default:CheckIn:E] Running Loop=1 2024-02-29T17:04:05.236Z,1709226245.236 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 60.659888 min 2024-02-29T17:04:05.236Z,1709226245.236 [Default:CheckIn:E] Stopped 2024-02-29T17:04:05.236Z,1709226245.236 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-29T17:04:05.236Z,1709226245.236 [Default:CheckIn] Stopped 2024-02-29T17:04:05.236Z,1709226245.236 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-29T17:04:05.236Z,1709226245.236 [Default:CheckIn](INFO): Running loop #11 2024-02-29T17:04:05.236Z,1709226245.236 [Default:CheckIn] Running Loop=11 2024-02-29T17:04:05.237Z,1709226245.237 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-29T17:04:05.237Z,1709226245.237 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-29T17:04:07.247Z,1709226247.247 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170406.00,A,3648.17673,N,12148.34289,W,0.661,316.99,290224,,,D*70 2024-02-29T17:04:07.257Z,1709226247.257 [NAL9602](INFO): GPS fix at 20240229T170406: (36.802945, -121.805715) 2024-02-29T17:04:07.274Z,1709226247.274 [Default:CheckIn:Read_GPS] Stopped 2024-02-29T17:04:07.274Z,1709226247.274 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-29T17:04:14.768Z,1709226254.768 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240229T155849/Courier0040.lzma 2024-02-29T17:04:15.770Z,1709226255.770 [DataOverHttps](INFO): Moved sent file to Logs/20240229T155849/Courier0040.lzma.bak 2024-02-29T17:04:15.771Z,1709226255.771 [DataOverHttps](IMPORTANT): SBD MOMSN=19305103, MTMSN=20240229T170414 2024-02-29T17:04:23.152Z,1709226263.152 [DataOverHttps](INFO): Received command: restart logs