2024-04-15T20:30:23.705Z,1713213023.705 [Supervisor](DEBUG): Initializing supervisor.
2024-04-15T20:30:23.709Z,1713213023.709 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2024-04-15T20:30:23.710Z,1713213023.710 [SyncHandler](INFO): Protected caller Thread ID is 836
2024-04-15T20:30:23.710Z,1713213023.710 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2024-04-15T20:30:23.711Z,1713213023.711 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2024-04-15T20:30:23.712Z,1713213023.712 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 837
2024-04-15T20:30:23.715Z,1713213023.715 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2024-04-15T20:30:23.733Z,1713213023.733 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2024-04-15T20:30:23.734Z,1713213023.734 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2024-04-15T20:30:23.734Z,1713213023.734 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 838
2024-04-15T20:30:23.739Z,1713213023.739 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2024-04-15T20:30:23.740Z,1713213023.740 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2024-04-15T20:30:23.740Z,1713213023.740 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 839
2024-04-15T20:30:23.742Z,1713213023.742 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2024-04-15T20:30:23.743Z,1713213023.743 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2024-04-15T20:30:23.743Z,1713213023.743 [logger ThreadHandler](INFO): Protected caller Thread ID is 840
2024-04-15T20:30:23.747Z,1713213023.747 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2024-04-15T20:30:23.747Z,1713213023.747 [Supervisor](INFO): Looking for Config files in directory: Config/
2024-04-15T20:30:23.751Z,1713213023.751 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2024-04-15T20:30:24.108Z,1713213024.108 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2024-04-15T20:30:24.110Z,1713213024.110 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2024-04-15T20:30:24.202Z,1713213024.202 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2024-04-15T20:30:24.320Z,1713213024.320 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2024-04-15T20:30:24.322Z,1713213024.322 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2024-04-15T20:30:24.462Z,1713213024.462 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2024-04-15T20:30:24.463Z,1713213024.463 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2024-04-15T20:30:24.823Z,1713213024.823 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2024-04-15T20:30:24.823Z,1713213024.823 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2024-04-15T20:30:24.930Z,1713213024.930 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2024-04-15T20:30:24.931Z,1713213024.931 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2024-04-15T20:30:25.346Z,1713213025.346 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2024-04-15T20:30:25.347Z,1713213025.347 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2024-04-15T20:30:25.574Z,1713213025.574 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2024-04-15T20:30:25.576Z,1713213025.576 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2024-04-15T20:30:25.665Z,1713213025.665 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2024-04-15T20:30:25.915Z,1713213025.915 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2024-04-15T20:30:25.916Z,1713213025.916 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2024-04-15T20:30:26.149Z,1713213026.149 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2024-04-15T20:30:26.149Z,1713213026.149 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2024-04-15T20:30:26.706Z,1713213026.706 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2024-04-15T20:30:26.707Z,1713213026.707 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2024-04-15T20:30:26.915Z,1713213026.915 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2024-04-15T20:30:26.974Z,1713213026.974 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2024-04-15T20:30:27.290Z,1713213027.290 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2024-04-15T20:30:27.292Z,1713213027.292 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2024-04-15T20:30:28.171Z,1713213028.171 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2024-04-15T20:30:28.172Z,1713213028.172 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2024-04-15T20:30:28.756Z,1713213028.756 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2024-04-15T20:30:28.758Z,1713213028.758 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/
2024-04-15T20:30:28.761Z,1713213028.761 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Control.cfg
2024-04-15T20:30:28.870Z,1713213028.870 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Science.cfg
2024-04-15T20:30:28.998Z,1713213028.998 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/secure.cfg
2024-04-15T20:30:29.092Z,1713213029.092 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Servo.cfg
2024-04-15T20:30:29.210Z,1713213029.210 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/BIT.cfg
2024-04-15T20:30:29.307Z,1713213029.307 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Battery.cfg
2024-04-15T20:30:29.575Z,1713213029.575 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2024-04-15T20:30:29.577Z,1713213029.577 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/vehicle.cfg
2024-04-15T20:30:29.696Z,1713213029.696 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/logger.cfg
2024-04-15T20:30:29.802Z,1713213029.802 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Navigation.cfg
2024-04-15T20:30:29.917Z,1713213029.917 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Sensor.cfg
2024-04-15T20:30:30.068Z,1713213030.068 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Simulator.cfg
2024-04-15T20:30:30.164Z,1713213030.164 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2024-04-15T20:30:30.178Z,1713213030.178 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2024-04-15T20:30:30.325Z,1713213030.325 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2024-04-15T20:30:30.325Z,1713213030.325 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2024-04-15T20:30:30.396Z,1713213030.396 [VerticalControl](DEBUG): Construct VerticalControl.
2024-04-15T20:30:30.457Z,1713213030.457 [VerticalControl] Loaded
2024-04-15T20:30:30.457Z,1713213030.457 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2024-04-15T20:30:30.460Z,1713213030.460 [HorizontalControl](DEBUG): Construct HorizontalControl.
2024-04-15T20:30:30.502Z,1713213030.502 [HorizontalControl] Loaded
2024-04-15T20:30:30.503Z,1713213030.503 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2024-04-15T20:30:30.505Z,1713213030.505 [SpeedControl](DEBUG): Construct SpeedControl.
2024-04-15T20:30:30.508Z,1713213030.508 [SpeedControl] Loaded
2024-04-15T20:30:30.508Z,1713213030.508 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2024-04-15T20:30:30.511Z,1713213030.511 [LoopControl](DEBUG): Construct LoopControl.
2024-04-15T20:30:30.512Z,1713213030.512 [LoopControl] Loaded
2024-04-15T20:30:30.512Z,1713213030.512 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2024-04-15T20:30:30.512Z,1713213030.512 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2024-04-15T20:30:30.513Z,1713213030.513 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2024-04-15T20:30:30.595Z,1713213030.595 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2024-04-15T20:30:30.595Z,1713213030.595 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2024-04-15T20:30:31.378Z,1713213031.378 [AHRS_M2] Loaded
2024-04-15T20:30:31.379Z,1713213031.379 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2024-04-15T20:30:31.629Z,1713213031.629 [BackseatComponent] Loaded
2024-04-15T20:30:31.629Z,1713213031.629 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2024-04-15T20:30:31.630Z,1713213031.630 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4089C4E0
2024-04-15T20:30:31.630Z,1713213031.630 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 922
2024-04-15T20:30:31.633Z,1713213031.633 [LcmUniversalReporter] Loaded
2024-04-15T20:30:31.634Z,1713213031.634 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2024-04-15T20:30:32.430Z,1713213032.430 [BPC1] Loaded
2024-04-15T20:30:32.431Z,1713213032.431 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2024-04-15T20:30:32.570Z,1713213032.570 [DataOverHttps] Loaded
2024-04-15T20:30:32.570Z,1713213032.570 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2024-04-15T20:30:32.571Z,1713213032.571 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408CC4E0
2024-04-15T20:30:32.572Z,1713213032.572 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 923
2024-04-15T20:30:32.592Z,1713213032.592 [Depth_Keller] Loaded
2024-04-15T20:30:32.592Z,1713213032.592 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2024-04-15T20:30:32.597Z,1713213032.597 [DropWeight] Loaded
2024-04-15T20:30:32.597Z,1713213032.597 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2024-04-15T20:30:32.658Z,1713213032.658 [NAL9602] Loaded
2024-04-15T20:30:32.659Z,1713213032.659 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2024-04-15T20:30:32.688Z,1713213032.688 [Onboard] Loaded
2024-04-15T20:30:32.689Z,1713213032.689 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2024-04-15T20:30:32.690Z,1713213032.690 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408FC4E0
2024-04-15T20:30:32.690Z,1713213032.690 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 924
2024-04-15T20:30:32.696Z,1713213032.696 [PowerOnly](INFO): Adding load control power supply at /dev/loadB1
2024-04-15T20:30:32.707Z,1713213032.707 [PowerOnly] Loaded
2024-04-15T20:30:32.722Z,1713213032.722 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2024-04-15T20:30:32.734Z,1713213032.734 [Power24vConverter] Loaded
2024-04-15T20:30:32.734Z,1713213032.734 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2024-04-15T20:30:32.747Z,1713213032.747 [Radio_Surface] Loaded
2024-04-15T20:30:32.748Z,1713213032.748 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2024-04-15T20:30:32.749Z,1713213032.749 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4092C4E0
2024-04-15T20:30:32.749Z,1713213032.749 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 925
2024-04-15T20:30:32.763Z,1713213032.763 [Sonardyne_Nano] Loaded
2024-04-15T20:30:32.763Z,1713213032.763 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2024-04-15T20:30:32.853Z,1713213032.853 [DAT] Loaded
2024-04-15T20:30:32.853Z,1713213032.853 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2024-04-15T20:30:32.854Z,1713213032.854 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 4095C4E0
2024-04-15T20:30:32.854Z,1713213032.854 [DAT ThreadHandler](INFO): Protected caller Thread ID is 926
2024-04-15T20:30:32.877Z,1713213032.877 [Waterlinked] Loaded
2024-04-15T20:30:32.877Z,1713213032.877 [ComponentRegistry](DEBUG): SyncComponent "Waterlinked" handled in the control thread.
2024-04-15T20:30:32.877Z,1713213032.877 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2024-04-15T20:30:32.878Z,1713213032.878 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2024-04-15T20:30:32.897Z,1713213032.897 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2024-04-15T20:30:32.898Z,1713213032.898 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2024-04-15T20:30:32.958Z,1713213032.958 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2024-04-15T20:30:32.959Z,1713213032.959 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2024-04-15T20:30:33.131Z,1713213033.131 [SBIT](DEBUG): Construct Startup Built In Test.
2024-04-15T20:30:33.140Z,1713213033.140 [SBIT] Loaded
2024-04-15T20:30:33.140Z,1713213033.140 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2024-04-15T20:30:33.143Z,1713213033.143 [IBIT](DEBUG): Construct Initiated Built In Test.
2024-04-15T20:30:33.156Z,1713213033.156 [IBIT] Loaded
2024-04-15T20:30:33.156Z,1713213033.156 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2024-04-15T20:30:33.162Z,1713213033.162 [CBIT](DEBUG): Construct Continuous Built In Test.
2024-04-15T20:30:33.265Z,1713213033.265 [CBIT] Loaded
2024-04-15T20:30:33.266Z,1713213033.266 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2024-04-15T20:30:33.266Z,1713213033.266 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2024-04-15T20:30:33.267Z,1713213033.267 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2024-04-15T20:30:33.670Z,1713213033.670 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2024-04-15T20:30:33.671Z,1713213033.671 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2024-04-15T20:30:33.740Z,1713213033.740 [DepthRateCalculator] Loaded
2024-04-15T20:30:33.740Z,1713213033.740 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2024-04-15T20:30:33.745Z,1713213033.745 [PitchRateCalculator] Loaded
2024-04-15T20:30:33.745Z,1713213033.745 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2024-04-15T20:30:33.755Z,1713213033.755 [SpeedCalculator] Loaded
2024-04-15T20:30:33.756Z,1713213033.756 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2024-04-15T20:30:33.760Z,1713213033.760 [YawRateCalculator] Loaded
2024-04-15T20:30:33.760Z,1713213033.760 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2024-04-15T20:30:33.780Z,1713213033.780 [ElevatorOffsetCalculator] Loaded
2024-04-15T20:30:33.781Z,1713213033.781 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2024-04-15T20:30:33.781Z,1713213033.781 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2024-04-15T20:30:33.782Z,1713213033.782 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2024-04-15T20:30:33.995Z,1713213033.995 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2024-04-15T20:30:33.996Z,1713213033.996 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2024-04-15T20:30:34.131Z,1713213034.131 [DeadReckonUsingMultipleVelocitySources] Loaded
2024-04-15T20:30:34.132Z,1713213034.132 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2024-04-15T20:30:34.173Z,1713213034.173 [DeadReckonUsingSpeedCalculator] Loaded
2024-04-15T20:30:34.174Z,1713213034.174 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2024-04-15T20:30:34.215Z,1713213034.215 [DeadReckonWithRespectToSeafloor] Loaded
2024-04-15T20:30:34.216Z,1713213034.216 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2024-04-15T20:30:34.230Z,1713213034.230 [NavChart] Loaded
2024-04-15T20:30:34.230Z,1713213034.230 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2024-04-15T20:30:34.235Z,1713213034.235 [UniversalFixResidualReporter] Loaded
2024-04-15T20:30:34.236Z,1713213034.236 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2024-04-15T20:30:34.236Z,1713213034.236 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2024-04-15T20:30:34.237Z,1713213034.237 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2024-04-15T20:30:34.401Z,1713213034.401 [BuoyancyServo] Loaded
2024-04-15T20:30:34.401Z,1713213034.401 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2024-04-15T20:30:34.422Z,1713213034.422 [ElevatorServo] Loaded
2024-04-15T20:30:34.422Z,1713213034.422 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2024-04-15T20:30:34.442Z,1713213034.442 [MassServo] Loaded
2024-04-15T20:30:34.442Z,1713213034.442 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2024-04-15T20:30:34.461Z,1713213034.461 [RudderServo] Loaded
2024-04-15T20:30:34.461Z,1713213034.461 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2024-04-15T20:30:34.476Z,1713213034.476 [ThrusterHE] Loaded
2024-04-15T20:30:34.476Z,1713213034.476 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2024-04-15T20:30:34.476Z,1713213034.476 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2024-04-15T20:30:34.477Z,1713213034.477 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2024-04-15T20:30:34.699Z,1713213034.699 [CTD_Seabird] Loaded
2024-04-15T20:30:34.699Z,1713213034.699 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2024-04-15T20:30:34.700Z,1713213034.700 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40BD64E0
2024-04-15T20:30:34.701Z,1713213034.701 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 927
2024-04-15T20:30:34.734Z,1713213034.734 [ESPComponent] Loaded
2024-04-15T20:30:34.734Z,1713213034.734 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2024-04-15T20:30:34.754Z,1713213034.754 [PAR_Licor] Loaded
2024-04-15T20:30:34.755Z,1713213034.755 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2024-04-15T20:30:34.798Z,1713213034.798 [WetLabsBB2FL] Loaded
2024-04-15T20:30:34.798Z,1713213034.798 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2024-04-15T20:30:34.799Z,1713213034.799 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40C064E0
2024-04-15T20:30:34.800Z,1713213034.800 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 928
2024-04-15T20:30:34.801Z,1713213034.801 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2024-04-15T20:30:34.807Z,1713213034.807 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2024-04-15T20:30:34.810Z,1713213034.810 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2024-04-15T20:30:34.821Z,1713213034.821 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2024-04-15T20:30:34.822Z,1713213034.822 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C364E0
2024-04-15T20:30:34.822Z,1713213034.822 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 929
2024-04-15T20:30:34.827Z,1713213034.827 [Supervisor](INFO): Main Thread ID is 827
2024-04-15T20:30:34.827Z,1713213034.827 [Supervisor](DEBUG): Running supervisor.
2024-04-15T20:30:34.827Z,1713213034.827 [CommandExec ThreadHandler](INFO): Handler Thread ID is 930
2024-04-15T20:30:34.828Z,1713213034.828 [CommandExec](INFO): Initializing the command executive.
2024-04-15T20:30:34.830Z,1713213034.830 [CommandLine ThreadHandler](INFO): Handler Thread ID is 931
2024-04-15T20:30:34.832Z,1713213034.832 [controlThread ThreadHandler](INFO): Handler Thread ID is 932
2024-04-15T20:30:34.832Z,1713213034.832 [controlThread](DEBUG): Initializing ControlThread
2024-04-15T20:30:34.833Z,1713213034.833 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2024-04-15T20:30:34.834Z,1713213034.834 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2024-04-15T20:30:34.835Z,1713213034.835 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2024-04-15T20:30:34.836Z,1713213034.836 [LoopControl](DEBUG): Initialize LoopControlComponent.
2024-04-15T20:30:34.842Z,1713213034.842 [SBIT](INFO): Initialize SBIT Component.
2024-04-15T20:30:34.842Z,1713213034.842 [SBIT](IMPORTANT): git: 2024-04-15_A
2024-04-15T20:30:34.843Z,1713213034.843 [SBIT](INFO): git hash: 2bacc01b88145f0e093f8ff9b52cfcf5607eedc3
2024-04-15T20:30:34.843Z,1713213034.843 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2024-04-15T20:30:34.844Z,1713213034.844 [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-04-15T20:30:34.845Z,1713213034.845 [SBIT](INFO): Beginning SBIT in 83.000000 seconds.
2024-04-15T20:30:34.846Z,1713213034.846 [IBIT](INFO): Initialize IBIT Component.
2024-04-15T20:30:34.846Z,1713213034.846 [CBIT](DEBUG): Initialize CBIT Component.
2024-04-15T20:30:34.847Z,1713213034.847 [logger ThreadHandler](INFO): Handler Thread ID is 933
2024-04-15T20:30:34.860Z,1713213034.860 [CBIT](DEBUG): Initialized mux pins.
2024-04-15T20:30:34.860Z,1713213034.860 [CBIT](DEBUG): Initializing the watchdog timer.
2024-04-15T20:30:34.868Z,1713213034.868 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 934
2024-04-15T20:30:34.880Z,1713213034.880 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 935
2024-04-15T20:30:34.882Z,1713213034.882 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2024-04-15T20:30:34.884Z,1713213034.884 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2024-04-15T20:30:34.884Z,1713213034.884 [CBIT](DEBUG): Initializing heartbeat.
2024-04-15T20:30:34.892Z,1713213034.892 [Onboard ThreadHandler](INFO): Handler Thread ID is 936
2024-04-15T20:30:34.909Z,1713213034.909 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 937
2024-04-15T20:30:34.929Z,1713213034.929 [DAT ThreadHandler](INFO): Handler Thread ID is 938
2024-04-15T20:30:34.930Z,1713213034.930 [DAT](INFO): Powering up
2024-04-15T20:30:34.930Z,1713213034.930 [DAT](DEBUG): Initializing DAT.
2024-04-15T20:30:34.934Z,1713213034.934 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 940
2024-04-15T20:30:34.934Z,1713213034.934 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2024-04-15T20:30:34.941Z,1713213034.941 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 942
2024-04-15T20:30:34.945Z,1713213034.945 [WetLabsBB2FL](INFO): Powering up
2024-04-15T20:30:34.946Z,1713213034.946 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 944
2024-04-15T20:30:34.952Z,1713213034.952 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2HA05M.000
2024-04-15T20:30:34.953Z,1713213034.953 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2HA05M.000
2024-04-15T20:30:34.953Z,1713213034.953 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3HA20M.000
2024-04-15T20:30:34.953Z,1713213034.953 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3HA20M.000
2024-04-15T20:30:34.953Z,1713213034.953 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4HA51M.000
2024-04-15T20:30:34.953Z,1713213034.953 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4HA51M.000
2024-04-15T20:30:34.953Z,1713213034.953 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4HA51M.000
2024-04-15T20:30:34.954Z,1713213034.954 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4HA51M.000
2024-04-15T20:30:34.954Z,1713213034.954 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5HA53M.000
2024-04-15T20:30:34.954Z,1713213034.954 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5HA53M.000
2024-04-15T20:30:34.954Z,1713213034.954 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5HA55M.000
2024-04-15T20:30:34.954Z,1713213034.954 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5HA55M.000
2024-04-15T20:30:34.956Z,1713213034.956 [CBIT](DEBUG): Deactivating GF circuits.
2024-04-15T20:30:34.956Z,1713213034.956 [CBIT](DEBUG): Deactivating emergency mode.
2024-04-15T20:30:34.996Z,1713213034.996 [CBIT](DEBUG): Backplane powered.
2024-04-15T20:30:34.996Z,1713213034.996 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2024-04-15T20:30:34.997Z,1713213034.997 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2024-04-15T20:30:34.997Z,1713213034.997 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2024-04-15T20:30:34.997Z,1713213034.997 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2024-04-15T20:30:34.998Z,1713213034.998 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2024-04-15T20:30:34.999Z,1713213034.999 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-04-15T20:30:34.000Z,1713213035.000 [NavChart](DEBUG): Initialize NavChart Navigation.
2024-04-15T20:30:35.000Z,1713213035.000 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2024-04-15T20:30:35.004Z,1713213035.004 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2024-04-15T20:30:35.005Z,1713213035.005 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2024-04-15T20:30:35.005Z,1713213035.005 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2024-04-15T20:30:35.014Z,1713213035.014 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2024-04-15T20:30:35.088Z,1713213035.088 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2024-04-15T20:30:35.093Z,1713213035.093 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2024-04-15T20:30:35.136Z,1713213035.136 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
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-04-15T20:30:35.137Z,1713213035.137 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2024-04-15T20:30:35.137Z,1713213035.137 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2024-04-15T20:30:35.141Z,1713213035.141 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2024-04-15T20:30:35.332Z,1713213035.332 [Radio_Surface](INFO): Powering up
2024-04-15T20:30:35.446Z,1713213035.446 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
0
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2024-04-15T20:30:35.468Z,1713213035.468 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2024-04-15T20:30:35.469Z,1713213035.469 [Default:A.Wait](DEBUG): Construct Wait.
2024-04-15T20:30:35.471Z,1713213035.471 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-04-15T20:30:35.508Z,1713213035.508 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2024-04-15T20:30:35.511Z,1713213035.511 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2024-04-15T20:30:35.537Z,1713213035.537 [Default:E.Execute](DEBUG): Construct Execute.
2024-04-15T20:30:35.540Z,1713213035.540 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
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-04-15T20:30:35.558Z,1713213035.558 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,PowerOnly,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-04-15T20:30:35.568Z,1713213035.568 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-04-15T20:30:35.741Z,1713213035.741 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.140412 decibar, -1.132002 m
2024-04-15T20:30:35.743Z,1713213035.743 [PowerOnly](INFO): Powering up loadControl
2024-04-15T20:30:35.760Z,1713213035.760 [Power24vConverter](INFO): Powering up.
2024-04-15T20:30:35.761Z,1713213035.761 [Sonardyne_Nano](INFO): Initializing.
2024-04-15T20:30:35.820Z,1713213035.820 [DepthRateCalculator](ERROR): Depth measurement is not active
2024-04-15T20:30:35.860Z,1713213035.860 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2024-04-15T20:30:35.869Z,1713213035.869 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2024-04-15T20:30:35.870Z,1713213035.870 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-04-15T20:30:35.876Z,1713213035.876 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-04-15T20:30:35.877Z,1713213035.877 [MassServo](DEBUG): Initializing EZServoServo.
2024-04-15T20:30:35.885Z,1713213035.885 [MassServo](DEBUG): Initializing MassServo.
2024-04-15T20:30:35.886Z,1713213035.886 [RudderServo](DEBUG): Initializing EZServoServo.
2024-04-15T20:30:35.892Z,1713213035.892 [RudderServo](DEBUG): Initializing RudderServo.
2024-04-15T20:30:35.893Z,1713213035.893 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-04-15T20:30:35.900Z,1713213035.900 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-04-15T20:30:37.048Z,1713213037.048 [WetLabsBB2FL](INFO): Powering down
2024-04-15T20:30:37.991Z,1713213037.991 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2024-04-15T20:30:39.233Z,1713213039.233 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2311
2024-04-15T20:30:41.473Z,1713213041.473 [ThrusterHE](ERROR): Zero Speed Commanded.
2024-04-15T20:30:47.796Z,1713213047.796 [DAT](INFO): DAT read:
2024-04-15T20:30:47.798Z,1713213047.798 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2024-04-15T20:30:49.309Z,1713213049.309 [DAT](INFO): DAT read: MF Frequency Band
2024-04-15T20:30:49.310Z,1713213049.310 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2024-04-15T20:30:49.310Z,1713213049.310 [DAT](INFO): DAT read: Apr 15 2024 20:30:44
2024-04-15T20:30:50.569Z,1713213050.569 [DAT](INFO): DAT read: Features enabled [Bearing]
2024-04-15T20:30:50.570Z,1713213050.570 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP
2024-04-15T20:30:50.571Z,1713213050.571 [DAT](INFO): commRate: 800
2024-04-15T20:30:52.640Z,1713213052.640 [DAT](INFO): entering command mode
2024-04-15T20:30:52.836Z,1713213052.836 [DAT](INFO): DAT read:
2024-04-15T20:30:52.837Z,1713213052.837 [DAT](INFO): DAT read: user:1>
2024-04-15T20:30:52.837Z,1713213052.837 [DAT](INFO): setting verbose to 3
2024-04-15T20:30:53.089Z,1713213053.089 [DAT](INFO): DAT read: user:1>
2024-04-15T20:30:53.090Z,1713213053.090 [DAT](INFO): DAT read: Verbose | 3
2024-04-15T20:30:53.090Z,1713213053.090 [DAT](INFO): set verbose to 3
2024-04-15T20:30:53.090Z,1713213053.090 [DAT](INFO): setting DatVerbose to 27440
2024-04-15T20:30:53.340Z,1713213053.340 [DAT](INFO): DAT read: user:2>
2024-04-15T20:30:53.341Z,1713213053.341 [DAT](INFO): DAT read: DatVerbose | 27440
2024-04-15T20:30:53.342Z,1713213053.342 [DAT](INFO): set DatVerbose to 27440
2024-04-15T20:30:53.342Z,1713213053.342 [DAT](INFO): setting transmit power to 8
2024-04-15T20:30:53.592Z,1713213053.592 [DAT](INFO): DAT read: user:3>
2024-04-15T20:30:53.593Z,1713213053.593 [DAT](INFO): DAT read: TxPower | 8 (Max)
2024-04-15T20:30:53.594Z,1713213053.594 [DAT](INFO): set transmit power to 8
2024-04-15T20:30:53.594Z,1713213053.594 [DAT](INFO): setting local address to 1
2024-04-15T20:30:53.845Z,1713213053.845 [DAT](INFO): DAT read: user:4>
2024-04-15T20:30:53.845Z,1713213053.845 [DAT](INFO): DAT read: LocalAddr | 1
2024-04-15T20:30:53.846Z,1713213053.846 [DAT](INFO): set local address to 1
2024-04-15T20:30:53.847Z,1713213053.847 [DAT](INFO): Setting time to: 20:30:53 And date to:4/15/2024
2024-04-15T20:30:54.097Z,1713213054.097 [DAT](INFO): DAT read: user:5>
2024-04-15T20:30:54.097Z,1713213054.097 [DAT](INFO): DAT read: Mon Apr 15, 2024 20:30:53
2024-04-15T20:30:54.098Z,1713213054.098 [DAT](INFO): Local DAT time set to Mon Apr 15, 2024 20:30:53
2024-04-15T20:31:03.041Z,1713213063.041 [NAL9602](INFO): Powering up NAL9602
2024-04-15T20:31:09.988Z,1713213069.988 [Waterlinked](INFO): Powering down
2024-04-15T20:31:13.954Z,1713213073.954 [NAL9602](INFO): NAL9602 initialized
2024-04-15T20:31:28.094Z,1713213088.094 [NAL9602](INFO): SBD MO Status=0, MOMSN=11875, MT Status=0, MTMSN=0
2024-04-15T20:31:28.094Z,1713213088.094 [NAL9602](INFO): No messages in MT queue
2024-04-15T20:31:32.134Z,1713213092.134 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203131.00,A,3648.39058,N,12147.44524,W,11.060,237.89,150424,,,A*45
2024-04-15T20:31:32.137Z,1713213092.137 [NAL9602](INFO): GPS fix at 20240415T203131: (36.806510, -121.790754)
2024-04-15T20:31:42.232Z,1713213102.232 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-15T20:31:58.421Z,1713213118.421 [SBIT](IMPORTANT): Beginning Startup BIT
2024-04-15T20:31:58.425Z,1713213118.425 [CBIT](IMPORTANT): Beginning ground fault scan
2024-04-15T20:32:04.454Z,1713213124.454 [NAL9602](INFO): Not Powering down - fast GPS
2024-04-15T20:32:09.540Z,1713213129.540 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.000510
CHAN A1 (24V): -0.000226
CHAN A2 (12V): 0.000180
CHAN A3 (5V): -0.001406
CHAN B0 (3.3V): 0.000884
CHAN B1 (3.15aV): -0.000272
CHAN B2 (3.15bV): -0.000149
CHAN B3 (GND): -0.000203
OPEN: 0.006871
Full Scale: +/- 1 mA
2024-04-15T20:32:52.147Z,1713213172.147 [SBIT](IMPORTANT): SBIT PASSED
2024-04-15T20:32:52.148Z,1713213172.148 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-04-15T20:32:52.149Z,1713213172.149 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=15 count;
2024-04-15T20:32:52.149Z,1713213172.149 [SBIT](IMPORTANT): Express linearApproximation DAT.acoustic_contact_range 10.000000 meter;
2024-04-15T20:32:52.149Z,1713213172.149 [SBIT](IMPORTANT): Express linearApproximation PowerOnly.loadControl 50.000000 milliampere;
2024-04-15T20:32:52.149Z,1713213172.149 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_HM_AvgRois 0.600000 count_per_second;
2024-04-15T20:32:52.149Z,1713213172.149 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_LM_AvgRois 0.600000 count_per_second;
2024-04-15T20:32:52.149Z,1713213172.149 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 5.000000 meter;
2024-04-15T20:32:52.149Z,1713213172.149 [SBIT](IMPORTANT): Vehicle.useTlLoader=1 bool;
2024-04-15T20:32:52.149Z,1713213172.149 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=205.380030 cubic_centimeter;
2024-04-15T20:32:52.150Z,1713213172.150 [SBIT](IMPORTANT): VerticalControl.massDefault=15.858152 millimeter;
2024-04-15T20:32:52.556Z,1713213172.556 [MissionManager](IMPORTANT): Started mission Startup
2024-04-15T20:32:52.556Z,1713213172.556 [Startup] Running Loop=1
2024-04-15T20:32:52.556Z,1713213172.556 [Startup](DEBUG): Aggregate::initialize Startup
2024-04-15T20:32:52.557Z,1713213172.557 [Startup:A.GoToSurface] Running Loop=1
2024-04-15T20:32:52.557Z,1713213172.557 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-04-15T20:32:52.557Z,1713213172.557 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-04-15T20:32:52.558Z,1713213172.558 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-04-15T20:32:52.558Z,1713213172.558 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-04-15T20:32:52.558Z,1713213172.558 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-04-15T20:32:52.559Z,1713213172.559 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-04-15T20:32:52.560Z,1713213172.560 [Startup:StartupSatComms] Running Loop=1
2024-04-15T20:32:52.561Z,1713213172.561 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2024-04-15T20:32:52.561Z,1713213172.561 [Startup:StartupSatComms:A] Running Loop=1
2024-04-15T20:32:52.972Z,1713213172.972 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2024-04-15T20:32:54.559Z,1713213174.559 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203253.00,A,3648.32507,N,12147.74268,W,11.683,271.40,150424,,,A*46
2024-04-15T20:32:54.561Z,1713213174.561 [NAL9602](INFO): GPS fix at 20240415T203253: (36.805418, -121.795711)
2024-04-15T20:32:54.596Z,1713213174.596 [Startup:StartupSatComms:A] Stopped
2024-04-15T20:32:54.596Z,1713213174.596 [Startup:StartupSatComms:B] Running Loop=1
2024-04-15T20:32:55.001Z,1713213175.001 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-04-15T20:33:13.182Z,1713213193.182 [NAL9602](INFO): SBD MO Status=1, MOMSN=11876, MT Status=0, MTMSN=0
2024-04-15T20:33:13.312Z,1713213193.312 [NAL9602](INFO): Sent 18 bytes from file Logs/20240415T202120/Courier0007.lzma
2024-04-15T20:33:13.312Z,1713213193.312 [NAL9602](INFO): Packets left to send: 0
2024-04-15T20:33:21.731Z,1713213201.731 [NAL9602](INFO): SBD MO Status=1, MOMSN=11877, MT Status=0, MTMSN=0
2024-04-15T20:33:21.788Z,1713213201.788 [NAL9602](INFO): Sent 269 bytes from file Logs/20240415T203023/Courier0000.lzma
2024-04-15T20:33:21.788Z,1713213201.788 [NAL9602](INFO): Packets left to send: 0
2024-04-15T20:33:30.290Z,1713213210.290 [NAL9602](INFO): SBD MO Status=1, MOMSN=11878, MT Status=0, MTMSN=0
2024-04-15T20:33:30.344Z,1713213210.344 [NAL9602](INFO): Sent 332 bytes from file Logs/20240415T202120/Express0001.lzma
2024-04-15T20:33:30.344Z,1713213210.344 [NAL9602](INFO): Packets left to send: 2
2024-04-15T20:33:35.214Z,1713213215.214 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2024-04-15T20:33:35.214Z,1713213215.214 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-04-15T20:33:35.225Z,1713213215.225 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-04-15T20:33:35.645Z,1713213215.645 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-04-15T20:33:35.645Z,1713213215.645 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2024-04-15T20:33:41.667Z,1713213221.667 [NAL9602](INFO): SBD MO Status=1, MOMSN=11879, MT Status=0, MTMSN=0
2024-04-15T20:33:41.718Z,1713213221.718 [NAL9602](INFO): Sent 332 bytes from file Logs/20240415T202120/Express0001.lzma
2024-04-15T20:33:41.718Z,1713213221.718 [NAL9602](INFO): Packets left to send: 1
2024-04-15T20:33:53.019Z,1713213233.019 [NAL9602](INFO): SBD MO Status=1, MOMSN=11880, MT Status=0, MTMSN=0
2024-04-15T20:33:53.072Z,1713213233.072 [NAL9602](INFO): Sent 153 bytes from file Logs/20240415T202120/Express0001.lzma
2024-04-15T20:33:53.072Z,1713213233.072 [NAL9602](INFO): Packets left to send: 0
2024-04-15T20:33:56.275Z,1713213236.275 [Startup:StartupSatComms:B](INFO): Timed out from 2024-04-15T20:32:54.6Z
2024-04-15T20:33:56.275Z,1713213236.275 [Startup:StartupSatComms:B] Stopped
2024-04-15T20:33:56.276Z,1713213236.276 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2024-04-15T20:33:56.276Z,1713213236.276 [Startup:StartupSatComms] Stopped
2024-04-15T20:33:56.276Z,1713213236.276 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2024-04-15T20:33:56.277Z,1713213236.277 [Startup](INFO): Completed Startup
2024-04-15T20:33:56.277Z,1713213236.277 [MissionManager](INFO): Startup is completed.
2024-04-15T20:33:56.277Z,1713213236.277 [MissionManager](INFO): Uninitializing Mission Startup
2024-04-15T20:33:56.277Z,1713213236.277 [Startup] Stopped
2024-04-15T20:33:56.277Z,1713213236.277 [Startup](DEBUG): Aggregate::uninitialize Startup
2024-04-15T20:33:56.278Z,1713213236.278 [Startup:A.GoToSurface] Stopped
2024-04-15T20:33:56.278Z,1713213236.278 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-04-15T20:33:56.391Z,1713213236.391 [MissionManager](IMPORTANT): Started mission Default
2024-04-15T20:33:56.391Z,1713213236.391 [Default] Running Loop=1
2024-04-15T20:33:56.391Z,1713213236.391 [Default](DEBUG): Aggregate::initialize Default
2024-04-15T20:33:56.396Z,1713213236.396 [Default:B.GoToSurface] Running Loop=1
2024-04-15T20:33:56.396Z,1713213236.396 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-04-15T20:33:56.396Z,1713213236.396 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-04-15T20:33:56.396Z,1713213236.396 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-04-15T20:33:56.397Z,1713213236.397 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-04-15T20:33:56.397Z,1713213236.397 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-04-15T20:33:56.397Z,1713213236.397 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-04-15T20:33:56.398Z,1713213236.398 [Default:A.Wait] Running Loop=1
2024-04-15T20:33:56.398Z,1713213236.398 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-04-15T20:34:04.011Z,1713213244.011 [NAL9602](INFO): SBD MO Status=1, MOMSN=11881, MT Status=0, MTMSN=0
2024-04-15T20:34:04.072Z,1713213244.072 [NAL9602](INFO): Sent 122 bytes from file Logs/20240415T202120/Express0005.lzma
2024-04-15T20:34:04.072Z,1713213244.072 [NAL9602](INFO): Packets left to send: 0
2024-04-15T20:34:09.691Z,1713213249.691 [Default:A.Wait](INFO): Done Waiting.
2024-04-15T20:34:09.691Z,1713213249.691 [Default:A.Wait] Stopped
2024-04-15T20:34:09.691Z,1713213249.691 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-04-15T20:34:10.114Z,1713213250.114 [Default:CheckIn] Running Loop=1
2024-04-15T20:34:10.114Z,1713213250.114 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-15T20:34:10.115Z,1713213250.115 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-15T20:34:10.489Z,1713213250.489 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2024-04-15T20:34:12.099Z,1713213252.099 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203411.00,A,3648.31627,N,12148.06822,W,12.130,265.50,150424,,,A*42
2024-04-15T20:34:12.101Z,1713213252.101 [NAL9602](INFO): GPS fix at 20240415T203411: (36.805271, -121.801137)
2024-04-15T20:34:12.117Z,1713213252.117 [Default:CheckIn:Read_GPS] Stopped
2024-04-15T20:34:12.117Z,1713213252.117 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-15T20:34:12.525Z,1713213252.525 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-04-15T20:34:19.940Z,1713213259.940 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-15T20:34:23.450Z,1713213263.450 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004389
2024-04-15T20:34:28.254Z,1713213268.254 [NAL9602](INFO): SBD MO Status=1, MOMSN=11882, MT Status=0, MTMSN=0
2024-04-15T20:34:28.308Z,1713213268.308 [NAL9602](INFO): Sent 73 bytes from file Logs/20240415T203023/Courier0004.lzma
2024-04-15T20:34:28.308Z,1713213268.308 [NAL9602](INFO): Packets left to send: 0
2024-04-15T20:34:28.382Z,1713213268.382 [Default:CheckIn:Read_Iridium] Stopped
2024-04-15T20:34:28.382Z,1713213268.382 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-15T20:34:28.383Z,1713213268.383 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-15T20:34:32.926Z,1713213272.926 [DataOverHttps](IMPORTANT): Could not stat file Logs/20240415T203023/Courier0004.lzma
2024-04-15T20:34:32.926Z,1713213272.926 [DataOverHttps](FAULT): Could not open file Logs/20240415T203023/Courier0004.lzma
2024-04-15T20:34:35.368Z,1713213275.368 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-15T20:34:41.466Z,1713213281.466 [DataOverHttps](IMPORTANT): Could not stat file Logs/20240415T203023/Courier0004.lzma
2024-04-15T20:34:41.466Z,1713213281.466 [DataOverHttps](FAULT): Could not open file Logs/20240415T203023/Courier0004.lzma
2024-04-15T20:34:50.323Z,1713213290.323 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-15T20:34:54.981Z,1713213294.981 [CommandExec](IMPORTANT): got command get PowerOnly.component_voltage_loadControl
2024-04-15T20:34:54.982Z,1713213294.982 [CommandExec](IMPORTANT): PowerOnly.component_voltage_loadControl 15.989040 V
2024-04-15T20:34:58.881Z,1713213298.881 [NAL9602](INFO): Not Powering down - fast GPS
2024-04-15T20:35:05.320Z,1713213305.320 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-15T20:35:06.137Z,1713213306.137 [CommandExec](IMPORTANT): got command get PowerOnly.component_avgCurrent_loadControl
2024-04-15T20:35:06.137Z,1713213306.137 [CommandExec](IMPORTANT): PowerOnly.component_avgCurrent_loadControl 341.309998 mA
2024-04-15T20:35:12.250Z,1713213312.250 [DataOverHttps](IMPORTANT): Could not stat file Logs/20240415T203023/Courier0004.lzma
2024-04-15T20:35:12.250Z,1713213312.250 [DataOverHttps](FAULT): Could not open file Logs/20240415T203023/Courier0004.lzma
2024-04-15T20:35:20.270Z,1713213320.270 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-15T20:35:34.408Z,1713213334.408 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-15T20:35:44.922Z,1713213344.922 [DataOverHttps](IMPORTANT): Could not stat file Logs/20240415T203023/Courier0004.lzma
2024-04-15T20:35:44.922Z,1713213344.922 [DataOverHttps](FAULT): Could not open file Logs/20240415T203023/Courier0004.lzma
2024-04-15T20:35:49.795Z,1713213349.795 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-15T20:36:05.545Z,1713213365.545 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-15T20:36:13.586Z,1713213373.586 [DataOverHttps](IMPORTANT): Could not stat file Logs/20240415T203023/Courier0004.lzma
2024-04-15T20:36:13.586Z,1713213373.586 [DataOverHttps](FAULT): Could not open file Logs/20240415T203023/Courier0004.lzma
2024-04-15T20:36:20.491Z,1713213380.491 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-15T20:36:35.440Z,1713213395.440 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7).
2024-04-15T20:36:36.290Z,1713213396.290 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2024-04-15T20:36:36.290Z,1713213396.290 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-04-15T20:36:36.317Z,1713213396.317 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-04-15T20:36:36.701Z,1713213396.701 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-04-15T20:36:36.701Z,1713213396.701 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2024-04-15T20:36:45.686Z,1713213405.686 [DataOverHttps](IMPORTANT): Could not stat file Logs/20240415T203023/Courier0004.lzma
2024-04-15T20:36:45.686Z,1713213405.686 [DataOverHttps](FAULT): Could not open file Logs/20240415T203023/Courier0004.lzma
2024-04-15T20:37:16.639Z,1713213436.639 [DataOverHttps](INFO): Sending 0 bytes from file Logs/20240415T203023/Courier0004.lzma
2024-04-15T20:37:17.642Z,1713213437.642 [DataOverHttps](INFO): Moved sent file to Logs/20240415T203023/Courier0004.lzma.bak
2024-04-15T20:37:17.642Z,1713213437.642 [DataOverHttps](INFO): SBD MOMSN=19409178
2024-04-15T20:38:58.569Z,1713213538.569 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-04-15T20:39:01.149Z,1713213541.149 [CommandExec](IMPORTANT): got command fileExec
2024-04-15T20:39:01.149Z,1713213541.149 [CommandExec](FAULT): Incomplete syntax. Try: help fileExec
2024-04-15T20:39:02.209Z,1713213542.209 [CommandExec](IMPORTANT): got command failComponent
2024-04-15T20:39:02.209Z,1713213542.209 [CommandExec](IMPORTANT): Failed components:
2024-04-15T20:39:02.209Z,1713213542.209 [CommandExec](IMPORTANT): No failed Components.
2024-04-15T20:39:03.778Z,1713213543.778 [BPC1](INFO): Calculating totals. Valid battery stick count: 45. Valid reserve battery stick count: 5.
2024-04-15T20:39:03.781Z,1713213543.781 [BPC1](INFO): Received data from all battery sticks.
2024-04-15T20:39:08.893Z,1713213548.893 [CommandExec](IMPORTANT): got command get latitude
2024-04-15T20:39:08.894Z,1713213548.894 [CommandExec](IMPORTANT): latitude 36.805271 arcdeg
2024-04-15T20:39:28.847Z,1713213568.847 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-15T20:39:28.847Z,1713213568.847 [Default:CheckIn:C.Wait] Stopped
2024-04-15T20:39:28.847Z,1713213568.847 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-15T20:39:28.847Z,1713213568.847 [Default:CheckIn:D] Running Loop=1
2024-04-15T20:39:29.256Z,1713213569.256 [Default:CheckIn:D] Stopped
2024-04-15T20:39:29.256Z,1713213569.256 [Default:CheckIn:E] Running Loop=1
2024-04-15T20:39:29.670Z,1713213569.670 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 5.547748 min
2024-04-15T20:39:29.671Z,1713213569.671 [Default:CheckIn:E] Stopped
2024-04-15T20:39:29.671Z,1713213569.671 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-15T20:39:29.671Z,1713213569.671 [Default:CheckIn] Stopped
2024-04-15T20:39:29.671Z,1713213569.671 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-15T20:39:29.671Z,1713213569.671 [Default:CheckIn](INFO): Running loop #2
2024-04-15T20:39:29.671Z,1713213569.671 [Default:CheckIn] Running Loop=2
2024-04-15T20:39:29.671Z,1713213569.671 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-15T20:39:29.671Z,1713213569.671 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-15T20:39:31.663Z,1713213571.663 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203930.00,A,3648.14122,N,12149.34975,W,12.091,260.48,150424,,,A*4C
2024-04-15T20:39:31.665Z,1713213571.665 [NAL9602](INFO): GPS fix at 20240415T203930: (36.802354, -121.822496)
2024-04-15T20:39:31.681Z,1713213571.681 [Default:CheckIn:Read_GPS] Stopped
2024-04-15T20:39:31.681Z,1713213571.681 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-15T20:39:37.357Z,1713213577.357 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2024-04-15T20:39:37.357Z,1713213577.357 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-04-15T20:39:37.376Z,1713213577.376 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-04-15T20:39:37.784Z,1713213577.784 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-04-15T20:39:37.784Z,1713213577.784 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2024-04-15T20:39:39.544Z,1713213579.544 [DataOverHttps](INFO): Sending 201 bytes from file Logs/20240415T203023/Courier0007.lzma
2024-04-15T20:39:40.545Z,1713213580.545 [DataOverHttps](INFO): Moved sent file to Logs/20240415T203023/Courier0007.lzma.bak
2024-04-15T20:39:40.546Z,1713213580.546 [DataOverHttps](INFO): SBD MOMSN=19409187
2024-04-15T20:39:40.954Z,1713213580.954 [NAL9602](INFO): SBD MO Status=0, MOMSN=11883, MT Status=0, MTMSN=0
2024-04-15T20:39:40.954Z,1713213580.954 [NAL9602](INFO): No messages in MT queue
2024-04-15T20:39:59.947Z,1713213599.947 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20240415T202120/Express0008.lzma
2024-04-15T20:40:00.950Z,1713213600.950 [DataOverHttps](INFO): Moved sent file to Logs/20240415T202120/Express0008.lzma.bak
2024-04-15T20:40:00.950Z,1713213600.950 [DataOverHttps](INFO): SBD MOMSN=19409196
2024-04-15T20:40:11.745Z,1713213611.745 [NAL9602](INFO): Not Powering down - fast GPS
2024-04-15T20:40:19.558Z,1713213619.558 [DataOverHttps](INFO): Sending 1078 bytes from file Logs/20240415T203023/Express0001.lzma
2024-04-15T20:40:20.558Z,1713213620.558 [DataOverHttps](INFO): Moved sent file to Logs/20240415T203023/Express0001.lzma.bak
2024-04-15T20:40:20.558Z,1713213620.558 [DataOverHttps](INFO): SBD MOMSN=19409198
2024-04-15T20:40:39.728Z,1713213639.728 [DataOverHttps](INFO): Sending 117 bytes from file Logs/20240415T203023/Express0005.lzma
2024-04-15T20:40:40.731Z,1713213640.731 [DataOverHttps](INFO): Moved sent file to Logs/20240415T203023/Express0005.lzma.bak
2024-04-15T20:40:40.731Z,1713213640.731 [DataOverHttps](INFO): SBD MOMSN=19409220
2024-04-15T20:41:06.509Z,1713213666.509 [CommandExec](IMPORTANT): got command get PowerOnly.component_avgCurrent_loadControl
2024-04-15T20:41:06.509Z,1713213666.509 [CommandExec](IMPORTANT): PowerOnly.component_avgCurrent_loadControl 344.980011 mA
2024-04-15T20:41:08.337Z,1713213668.337 [DataOverHttps](INFO): Sending 550 bytes from file Logs/20240415T203023/Express0008.lzma
2024-04-15T20:41:09.338Z,1713213669.338 [DataOverHttps](INFO): Moved sent file to Logs/20240415T203023/Express0008.lzma.bak
2024-04-15T20:41:09.338Z,1713213669.338 [DataOverHttps](INFO): SBD MOMSN=19409223
2024-04-15T20:41:10.487Z,1713213670.487 [Default:CheckIn:Read_Iridium] Stopped
2024-04-15T20:41:10.487Z,1713213670.487 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-15T20:41:10.487Z,1713213670.487 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-15T20:42:38.579Z,1713213758.579 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2024-04-15T20:42:38.579Z,1713213758.579 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-04-15T20:42:38.590Z,1713213758.590 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-04-15T20:42:39.002Z,1713213759.002 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-04-15T20:42:39.002Z,1713213759.002 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2024-04-15T20:45:39.713Z,1713213939.713 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2024-04-15T20:45:39.713Z,1713213939.713 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-04-15T20:45:39.724Z,1713213939.724 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-04-15T20:45:40.126Z,1713213940.126 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-04-15T20:45:40.126Z,1713213940.126 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3
2024-04-15T20:46:11.223Z,1713213971.223 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-15T20:46:11.223Z,1713213971.223 [Default:CheckIn:C.Wait] Stopped
2024-04-15T20:46:11.224Z,1713213971.224 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-15T20:46:11.224Z,1713213971.224 [Default:CheckIn:D] Running Loop=1
2024-04-15T20:46:11.658Z,1713213971.658 [Default:CheckIn:D] Stopped
2024-04-15T20:46:11.658Z,1713213971.658 [Default:CheckIn:E] Running Loop=1
2024-04-15T20:46:12.039Z,1713213972.039 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 12.254437 min
2024-04-15T20:46:12.039Z,1713213972.039 [Default:CheckIn:E] Stopped
2024-04-15T20:46:12.039Z,1713213972.039 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-15T20:46:12.039Z,1713213972.039 [Default:CheckIn] Stopped
2024-04-15T20:46:12.039Z,1713213972.039 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-15T20:46:12.039Z,1713213972.039 [Default:CheckIn](INFO): Running loop #3
2024-04-15T20:46:12.040Z,1713213972.040 [Default:CheckIn] Running Loop=3
2024-04-15T20:46:12.040Z,1713213972.040 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-15T20:46:12.040Z,1713213972.040 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-15T20:46:14.053Z,1713213974.053 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204614.00,A,3647.61266,N,12150.73543,W,3.149,190.21,150424,,,A*79
2024-04-15T20:46:14.055Z,1713213974.055 [NAL9602](INFO): GPS fix at 20240415T204614: (36.793544, -121.845591)
2024-04-15T20:46:14.071Z,1713213974.071 [Default:CheckIn:Read_GPS] Stopped
2024-04-15T20:46:14.071Z,1713213974.071 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-15T20:46:21.271Z,1713213981.271 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240415T203023/Courier0010.lzma
2024-04-15T20:46:22.274Z,1713213982.274 [DataOverHttps](INFO): Moved sent file to Logs/20240415T203023/Courier0010.lzma.bak
2024-04-15T20:46:22.274Z,1713213982.274 [DataOverHttps](INFO): SBD MOMSN=19409254
2024-04-15T20:46:26.978Z,1713213986.978 [NAL9602](INFO): SBD MO Status=0, MOMSN=11884, MT Status=0, MTMSN=0
2024-04-15T20:46:26.978Z,1713213986.978 [NAL9602](INFO): No messages in MT queue
2024-04-15T20:46:42.132Z,1713214002.132 [DataOverHttps](INFO): Sending 226 bytes from file Logs/20240415T203023/Express0011.lzma
2024-04-15T20:46:43.134Z,1713214003.134 [DataOverHttps](INFO): Moved sent file to Logs/20240415T203023/Express0011.lzma.bak
2024-04-15T20:46:43.134Z,1713214003.134 [DataOverHttps](INFO): SBD MOMSN=19409258
2024-04-15T20:46:44.371Z,1713214004.371 [Default:CheckIn:Read_Iridium] Stopped
2024-04-15T20:46:44.371Z,1713214004.371 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-15T20:46:44.371Z,1713214004.371 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-15T20:46:57.738Z,1713214017.738 [NAL9602](INFO): Not Powering down - fast GPS
2024-04-15T20:48:40.786Z,1713214120.786 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2024-04-15T20:48:40.786Z,1713214120.786 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-04-15T20:48:40.836Z,1713214120.836 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-04-15T20:48:41.210Z,1713214121.210 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-04-15T20:48:41.210Z,1713214121.210 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2024-04-15T20:50:18.548Z,1713214218.548 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-04-15T20:50:49.680Z,1713214249.680 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-04-15T20:51:41.874Z,1713214301.874 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2024-04-15T20:51:41.875Z,1713214301.875 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-04-15T20:51:41.885Z,1713214301.885 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-04-15T20:51:42.295Z,1713214302.295 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-04-15T20:51:42.295Z,1713214302.295 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2024-04-15T20:51:45.111Z,1713214305.111 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-15T20:51:45.111Z,1713214305.111 [Default:CheckIn:C.Wait] Stopped
2024-04-15T20:51:45.111Z,1713214305.111 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-15T20:51:45.111Z,1713214305.111 [Default:CheckIn:D] Running Loop=1
2024-04-15T20:51:45.512Z,1713214305.512 [Default:CheckIn:D] Stopped
2024-04-15T20:51:45.512Z,1713214305.512 [Default:CheckIn:E] Running Loop=1
2024-04-15T20:51:45.923Z,1713214305.923 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 17.818683 min
2024-04-15T20:51:45.923Z,1713214305.923 [Default:CheckIn:E] Stopped
2024-04-15T20:51:45.923Z,1713214305.923 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-15T20:51:45.924Z,1713214305.924 [Default:CheckIn] Stopped
2024-04-15T20:51:45.924Z,1713214305.924 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-15T20:51:45.924Z,1713214305.924 [Default:CheckIn](INFO): Running loop #4
2024-04-15T20:51:45.924Z,1713214305.924 [Default:CheckIn] Running Loop=4
2024-04-15T20:51:45.924Z,1713214305.924 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-15T20:51:45.924Z,1713214305.924 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-15T20:51:47.927Z,1713214307.927 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205148.00,A,3647.55941,N,12150.57947,W,1.341,116.04,150424,,,D*75
2024-04-15T20:51:47.929Z,1713214307.929 [NAL9602](INFO): GPS fix at 20240415T205148: (36.792657, -121.842991)
2024-04-15T20:51:47.970Z,1713214307.970 [Default:CheckIn:Read_GPS] Stopped
2024-04-15T20:51:47.970Z,1713214307.970 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-15T20:51:55.427Z,1713214315.427 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240415T203023/Courier0013.lzma
2024-04-15T20:51:56.430Z,1713214316.430 [DataOverHttps](INFO): Moved sent file to Logs/20240415T203023/Courier0013.lzma.bak
2024-04-15T20:51:56.430Z,1713214316.430 [DataOverHttps](INFO): SBD MOMSN=19409263
2024-04-15T20:52:03.683Z,1713214323.683 [NAL9602](INFO): SBD MO Status=0, MOMSN=11885, MT Status=0, MTMSN=0
2024-04-15T20:52:03.683Z,1713214323.683 [NAL9602](INFO): No messages in MT queue
2024-04-15T20:52:18.684Z,1713214338.684 [DataOverHttps](INFO): Sending 148 bytes from file Logs/20240415T203023/Express0014.lzma
2024-04-15T20:52:19.686Z,1713214339.686 [DataOverHttps](INFO): Moved sent file to Logs/20240415T203023/Express0014.lzma.bak
2024-04-15T20:52:19.686Z,1713214339.686 [DataOverHttps](INFO): SBD MOMSN=19409267
2024-04-15T20:52:21.098Z,1713214341.098 [Default:CheckIn:Read_Iridium] Stopped
2024-04-15T20:52:21.098Z,1713214341.098 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-15T20:52:21.098Z,1713214341.098 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-15T20:52:34.401Z,1713214354.401 [NAL9602](INFO): Not Powering down - fast GPS
2024-04-15T20:54:42.894Z,1713214482.894 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2024-04-15T20:54:42.894Z,1713214482.894 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-04-15T20:54:42.905Z,1713214482.905 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-04-15T20:54:43.323Z,1713214483.323 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-04-15T20:54:43.323Z,1713214483.323 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2024-04-15T20:55:00.393Z,1713214500.393 [DataOverHttps](IMPORTANT): SBD MTMSN=20240415T205500
2024-04-15T20:55:07.920Z,1713214507.920 [DataOverHttps](INFO): Received command: restart logs