2024-11-19T16:29:10.519Z,1732033750.519 [Supervisor](DEBUG): Initializing supervisor.
2024-11-19T16:29:10.523Z,1732033750.523 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2024-11-19T16:29:10.524Z,1732033750.524 [SyncHandler](INFO): Protected caller Thread ID is 1594
2024-11-19T16:29:10.524Z,1732033750.524 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2024-11-19T16:29:10.525Z,1732033750.525 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2024-11-19T16:29:10.526Z,1732033750.526 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1595
2024-11-19T16:29:10.529Z,1732033750.529 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2024-11-19T16:29:10.547Z,1732033750.547 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2024-11-19T16:29:10.548Z,1732033750.548 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2024-11-19T16:29:10.548Z,1732033750.548 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1596
2024-11-19T16:29:10.552Z,1732033750.552 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2024-11-19T16:29:10.553Z,1732033750.553 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2024-11-19T16:29:10.553Z,1732033750.553 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1597
2024-11-19T16:29:10.555Z,1732033750.555 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2024-11-19T16:29:10.556Z,1732033750.556 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2024-11-19T16:29:10.557Z,1732033750.557 [logger ThreadHandler](INFO): Protected caller Thread ID is 1598
2024-11-19T16:29:10.560Z,1732033750.560 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2024-11-19T16:29:10.561Z,1732033750.561 [Supervisor](INFO): Looking for Config files in directory: Config/
2024-11-19T16:29:10.562Z,1732033750.562 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2024-11-19T16:29:10.818Z,1732033750.818 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2024-11-19T16:29:10.818Z,1732033750.818 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2024-11-19T16:29:11.048Z,1732033751.048 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2024-11-19T16:29:11.049Z,1732033751.049 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2024-11-19T16:29:11.157Z,1732033751.157 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2024-11-19T16:29:11.157Z,1732033751.157 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2024-11-19T16:29:11.363Z,1732033751.363 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2024-11-19T16:29:11.363Z,1732033751.363 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2024-11-19T16:29:11.968Z,1732033751.968 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2024-11-19T16:29:11.969Z,1732033751.969 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2024-11-19T16:29:12.089Z,1732033752.089 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2024-11-19T16:29:12.090Z,1732033752.090 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2024-11-19T16:29:12.385Z,1732033752.385 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2024-11-19T16:29:12.386Z,1732033752.386 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2024-11-19T16:29:12.622Z,1732033752.622 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2024-11-19T16:29:12.622Z,1732033752.622 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2024-11-19T16:29:13.194Z,1732033753.194 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2024-11-19T16:29:13.194Z,1732033753.194 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2024-11-19T16:29:13.640Z,1732033753.640 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2024-11-19T16:29:13.641Z,1732033753.641 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2024-11-19T16:29:13.731Z,1732033753.731 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2024-11-19T16:29:14.124Z,1732033754.124 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2024-11-19T16:29:14.124Z,1732033754.124 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2024-11-19T16:29:14.278Z,1732033754.278 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2024-11-19T16:29:14.278Z,1732033754.278 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2024-11-19T16:29:15.176Z,1732033755.176 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2024-11-19T16:29:15.176Z,1732033755.176 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2024-11-19T16:29:15.559Z,1732033755.559 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2024-11-19T16:29:15.559Z,1732033755.559 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2024-11-19T16:29:15.692Z,1732033755.692 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-aku/
2024-11-19T16:29:15.693Z,1732033755.693 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/secure.cfg
2024-11-19T16:29:15.789Z,1732033755.789 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/logger.cfg
2024-11-19T16:29:15.909Z,1732033755.909 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/vehicle.cfg
2024-11-19T16:29:16.027Z,1732033756.027 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Navigation.cfg
2024-11-19T16:29:16.146Z,1732033756.146 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/BIT.cfg
2024-11-19T16:29:16.247Z,1732033756.247 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Simulator.cfg
2024-11-19T16:29:16.344Z,1732033756.344 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Servo.cfg
2024-11-19T16:29:16.461Z,1732033756.461 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Science.cfg
2024-11-19T16:29:16.644Z,1732033756.644 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Sensor.cfg
2024-11-19T16:29:16.828Z,1732033756.828 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Control.cfg
2024-11-19T16:29:16.940Z,1732033756.940 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Battery.cfg
2024-11-19T16:29:17.220Z,1732033757.220 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2024-11-19T16:29:17.221Z,1732033757.221 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-aku/root/
2024-11-19T16:29:17.222Z,1732033757.222 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2024-11-19T16:29:22.881Z,1732033762.881 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2024-11-19T16:29:23.219Z,1732033763.219 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2024-11-19T16:29:23.220Z,1732033763.220 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2024-11-19T16:29:23.655Z,1732033763.655 [AHRS_M2] Loaded
2024-11-19T16:29:23.656Z,1732033763.656 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2024-11-19T16:29:23.694Z,1732033763.694 [BackseatComponent] Loaded
2024-11-19T16:29:23.694Z,1732033763.694 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2024-11-19T16:29:23.695Z,1732033763.695 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408914E0
2024-11-19T16:29:23.696Z,1732033763.696 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1681
2024-11-19T16:29:23.698Z,1732033763.698 [LcmUniversalReporter] Loaded
2024-11-19T16:29:23.699Z,1732033763.699 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2024-11-19T16:29:23.705Z,1732033763.705 [BioacousticsDataBridge] Loaded
2024-11-19T16:29:23.705Z,1732033763.705 [ComponentRegistry](DEBUG): SyncComponent "BioacousticsDataBridge" handled in the control thread.
2024-11-19T16:29:24.684Z,1732033764.684 [BPC1] Loaded
2024-11-19T16:29:24.684Z,1732033764.684 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2024-11-19T16:29:24.774Z,1732033764.774 [DAT] Loaded
2024-11-19T16:29:24.774Z,1732033764.774 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2024-11-19T16:29:24.775Z,1732033764.775 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 408C14E0
2024-11-19T16:29:24.776Z,1732033764.776 [DAT ThreadHandler](INFO): Protected caller Thread ID is 1682
2024-11-19T16:29:24.861Z,1732033764.861 [DataOverHttps] Loaded
2024-11-19T16:29:24.861Z,1732033764.861 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2024-11-19T16:29:24.862Z,1732033764.862 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408F14E0
2024-11-19T16:29:24.862Z,1732033764.862 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1683
2024-11-19T16:29:24.885Z,1732033764.885 [Depth_Keller] Loaded
2024-11-19T16:29:24.885Z,1732033764.885 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2024-11-19T16:29:25.005Z,1732033765.005 [NAL9602] Loaded
2024-11-19T16:29:25.005Z,1732033765.005 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2024-11-19T16:29:25.034Z,1732033765.034 [Onboard] Loaded
2024-11-19T16:29:25.035Z,1732033765.035 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2024-11-19T16:29:25.036Z,1732033765.036 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409214E0
2024-11-19T16:29:25.036Z,1732033765.036 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1684
2024-11-19T16:29:25.050Z,1732033765.050 [Power24vConverter] Loaded
2024-11-19T16:29:25.051Z,1732033765.051 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2024-11-19T16:29:25.067Z,1732033765.067 [Radio_Surface] Loaded
2024-11-19T16:29:25.067Z,1732033765.067 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2024-11-19T16:29:25.068Z,1732033765.068 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409514E0
2024-11-19T16:29:25.068Z,1732033765.068 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1685
2024-11-19T16:29:25.083Z,1732033765.083 [Sonardyne_Nano] Loaded
2024-11-19T16:29:25.084Z,1732033765.084 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2024-11-19T16:29:25.084Z,1732033765.084 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2024-11-19T16:29:25.085Z,1732033765.085 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2024-11-19T16:29:25.194Z,1732033765.194 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2024-11-19T16:29:25.194Z,1732033765.194 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2024-11-19T16:29:25.247Z,1732033765.247 [VerticalControl](DEBUG): Construct VerticalControl.
2024-11-19T16:29:25.305Z,1732033765.305 [VerticalControl] Loaded
2024-11-19T16:29:25.306Z,1732033765.306 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2024-11-19T16:29:25.308Z,1732033765.308 [HorizontalControl](DEBUG): Construct HorizontalControl.
2024-11-19T16:29:25.355Z,1732033765.355 [HorizontalControl] Loaded
2024-11-19T16:29:25.356Z,1732033765.356 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2024-11-19T16:29:25.358Z,1732033765.358 [SpeedControl](DEBUG): Construct SpeedControl.
2024-11-19T16:29:25.361Z,1732033765.361 [SpeedControl] Loaded
2024-11-19T16:29:25.361Z,1732033765.361 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2024-11-19T16:29:25.364Z,1732033765.364 [LoopControl](DEBUG): Construct LoopControl.
2024-11-19T16:29:25.365Z,1732033765.365 [LoopControl] Loaded
2024-11-19T16:29:25.365Z,1732033765.365 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2024-11-19T16:29:25.365Z,1732033765.365 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2024-11-19T16:29:25.366Z,1732033765.366 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2024-11-19T16:29:25.376Z,1732033765.376 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2024-11-19T16:29:25.377Z,1732033765.377 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2024-11-19T16:29:25.403Z,1732033765.403 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2024-11-19T16:29:25.404Z,1732033765.404 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2024-11-19T16:29:25.516Z,1732033765.516 [DeadReckonUsingMultipleVelocitySources] Loaded
2024-11-19T16:29:25.516Z,1732033765.516 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2024-11-19T16:29:25.558Z,1732033765.558 [DeadReckonUsingSpeedCalculator] Loaded
2024-11-19T16:29:25.558Z,1732033765.558 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2024-11-19T16:29:25.600Z,1732033765.600 [DeadReckonWithRespectToSeafloor] Loaded
2024-11-19T16:29:25.601Z,1732033765.601 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2024-11-19T16:29:25.614Z,1732033765.614 [NavChart] Loaded
2024-11-19T16:29:25.614Z,1732033765.614 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2024-11-19T16:29:25.620Z,1732033765.620 [UniversalFixResidualReporter] Loaded
2024-11-19T16:29:25.620Z,1732033765.620 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2024-11-19T16:29:25.620Z,1732033765.620 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2024-11-19T16:29:25.621Z,1732033765.621 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2024-11-19T16:29:25.763Z,1732033765.763 [SBIT](DEBUG): Construct Startup Built In Test.
2024-11-19T16:29:25.772Z,1732033765.772 [SBIT] Loaded
2024-11-19T16:29:25.772Z,1732033765.772 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2024-11-19T16:29:25.775Z,1732033765.775 [IBIT](DEBUG): Construct Initiated Built In Test.
2024-11-19T16:29:25.818Z,1732033765.818 [IBIT] Loaded
2024-11-19T16:29:25.818Z,1732033765.818 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2024-11-19T16:29:25.844Z,1732033765.844 [CBIT](DEBUG): Construct Continuous Built In Test.
2024-11-19T16:29:26.044Z,1732033766.044 [CBIT] Loaded
2024-11-19T16:29:26.044Z,1732033766.044 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2024-11-19T16:29:26.045Z,1732033766.045 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2024-11-19T16:29:26.045Z,1732033766.045 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2024-11-19T16:29:26.220Z,1732033766.220 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2024-11-19T16:29:26.221Z,1732033766.221 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2024-11-19T16:29:26.354Z,1732033766.354 [BuoyancyServo] Loaded
2024-11-19T16:29:26.354Z,1732033766.354 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2024-11-19T16:29:26.377Z,1732033766.377 [ElevatorServo] Loaded
2024-11-19T16:29:26.377Z,1732033766.377 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2024-11-19T16:29:26.399Z,1732033766.399 [MassServo] Loaded
2024-11-19T16:29:26.399Z,1732033766.399 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2024-11-19T16:29:26.420Z,1732033766.420 [RudderServo] Loaded
2024-11-19T16:29:26.420Z,1732033766.420 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2024-11-19T16:29:26.437Z,1732033766.437 [ThrusterHE] Loaded
2024-11-19T16:29:26.437Z,1732033766.437 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2024-11-19T16:29:26.437Z,1732033766.437 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2024-11-19T16:29:26.438Z,1732033766.438 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2024-11-19T16:29:26.614Z,1732033766.614 [CTD_Seabird] Loaded
2024-11-19T16:29:26.614Z,1732033766.614 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2024-11-19T16:29:26.616Z,1732033766.616 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B7B4E0
2024-11-19T16:29:26.616Z,1732033766.616 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1686
2024-11-19T16:29:26.638Z,1732033766.638 [PAR_Licor] Loaded
2024-11-19T16:29:26.639Z,1732033766.639 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2024-11-19T16:29:26.684Z,1732033766.684 [WetLabsBB2FL] Loaded
2024-11-19T16:29:26.685Z,1732033766.685 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2024-11-19T16:29:26.686Z,1732033766.686 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BAB4E0
2024-11-19T16:29:26.686Z,1732033766.686 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1687
2024-11-19T16:29:26.687Z,1732033766.687 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2024-11-19T16:29:26.687Z,1732033766.687 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2024-11-19T16:29:26.738Z,1732033766.738 [DepthRateCalculator] Loaded
2024-11-19T16:29:26.738Z,1732033766.738 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2024-11-19T16:29:26.743Z,1732033766.743 [PitchRateCalculator] Loaded
2024-11-19T16:29:26.744Z,1732033766.744 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2024-11-19T16:29:26.753Z,1732033766.753 [SpeedCalculator] Loaded
2024-11-19T16:29:26.753Z,1732033766.753 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2024-11-19T16:29:26.757Z,1732033766.757 [YawRateCalculator] Loaded
2024-11-19T16:29:26.757Z,1732033766.757 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2024-11-19T16:29:26.777Z,1732033766.777 [ElevatorOffsetCalculator] Loaded
2024-11-19T16:29:26.777Z,1732033766.777 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2024-11-19T16:29:26.777Z,1732033766.777 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2024-11-19T16:29:26.778Z,1732033766.778 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2024-11-19T16:29:26.834Z,1732033766.834 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2024-11-19T16:29:26.840Z,1732033766.840 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2024-11-19T16:29:26.843Z,1732033766.843 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2024-11-19T16:29:26.854Z,1732033766.854 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2024-11-19T16:29:26.855Z,1732033766.855 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C374E0
2024-11-19T16:29:26.855Z,1732033766.855 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1688
2024-11-19T16:29:26.860Z,1732033766.860 [Supervisor](INFO): Main Thread ID is 1593
2024-11-19T16:29:26.860Z,1732033766.860 [Supervisor](DEBUG): Running supervisor.
2024-11-19T16:29:26.861Z,1732033766.861 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1689
2024-11-19T16:29:26.861Z,1732033766.861 [CommandExec](INFO): Initializing the command executive.
2024-11-19T16:29:26.863Z,1732033766.863 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1690
2024-11-19T16:29:26.865Z,1732033766.865 [controlThread ThreadHandler](INFO): Handler Thread ID is 1691
2024-11-19T16:29:26.865Z,1732033766.865 [controlThread](DEBUG): Initializing ControlThread
2024-11-19T16:29:26.869Z,1732033766.869 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2024-11-19T16:29:26.870Z,1732033766.870 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2024-11-19T16:29:26.871Z,1732033766.871 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2024-11-19T16:29:26.871Z,1732033766.871 [LoopControl](DEBUG): Initialize LoopControlComponent.
2024-11-19T16:29:26.872Z,1732033766.872 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-11-19T16:29:26.873Z,1732033766.873 [NavChart](DEBUG): Initialize NavChart Navigation.
2024-11-19T16:29:26.873Z,1732033766.873 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2024-11-19T16:29:26.873Z,1732033766.873 [SBIT](INFO): Initialize SBIT Component.
2024-11-19T16:29:26.874Z,1732033766.874 [SBIT](IMPORTANT): git: 2024-10-30
2024-11-19T16:29:26.874Z,1732033766.874 [SBIT](INFO): git hash: c4b9a7fa8cf6bf84d0dff31525ea982806c2df17
2024-11-19T16:29:26.874Z,1732033766.874 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2024-11-19T16:29:26.875Z,1732033766.875 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2024-11-19T16:29:26.877Z,1732033766.877 [SBIT](INFO): Beginning SBIT in 63.000000 seconds.
2024-11-19T16:29:26.877Z,1732033766.877 [IBIT](INFO): Initialize IBIT Component.
2024-11-19T16:29:26.878Z,1732033766.878 [CBIT](DEBUG): Initialize CBIT Component.
2024-11-19T16:29:26.879Z,1732033766.879 [logger ThreadHandler](INFO): Handler Thread ID is 1692
2024-11-19T16:29:26.892Z,1732033766.892 [CBIT](DEBUG): Initialized mux pins.
2024-11-19T16:29:26.892Z,1732033766.892 [CBIT](DEBUG): Initializing the watchdog timer.
2024-11-19T16:29:26.896Z,1732033766.896 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1693
2024-11-19T16:29:26.904Z,1732033766.904 [DAT ThreadHandler](INFO): Handler Thread ID is 1694
2024-11-19T16:29:26.905Z,1732033766.905 [DAT](INFO): Powering up
2024-11-19T16:29:26.905Z,1732033766.905 [DAT](DEBUG): Initializing DAT.
2024-11-19T16:29:26.909Z,1732033766.909 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1696
2024-11-19T16:29:26.911Z,1732033766.911 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2024-11-19T16:29:26.916Z,1732033766.916 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2024-11-19T16:29:26.917Z,1732033766.917 [CBIT](DEBUG): Initializing heartbeat.
2024-11-19T16:29:26.924Z,1732033766.924 [Onboard ThreadHandler](INFO): Handler Thread ID is 1697
2024-11-19T16:29:26.952Z,1732033766.952 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1698
2024-11-19T16:29:26.965Z,1732033766.965 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1699
2024-11-19T16:29:26.965Z,1732033766.965 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2024-11-19T16:29:26.969Z,1732033766.969 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1701
2024-11-19T16:29:26.972Z,1732033766.972 [WetLabsBB2FL](INFO): Powering up
2024-11-19T16:29:26.973Z,1732033766.973 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1703
2024-11-19T16:29:26.976Z,1732033766.976 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2HA05M.000
2024-11-19T16:29:26.976Z,1732033766.976 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2HA05M.000
2024-11-19T16:29:26.976Z,1732033766.976 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3HA20M.000
2024-11-19T16:29:26.977Z,1732033766.977 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3HA20M.000
2024-11-19T16:29:26.977Z,1732033766.977 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4HA51M.000
2024-11-19T16:29:26.977Z,1732033766.977 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4HA51M.000
2024-11-19T16:29:26.977Z,1732033766.977 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4HA51M.000
2024-11-19T16:29:26.977Z,1732033766.977 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4HA51M.000
2024-11-19T16:29:26.977Z,1732033766.977 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5HA53M.000
2024-11-19T16:29:26.977Z,1732033766.977 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5HA53M.000
2024-11-19T16:29:26.978Z,1732033766.978 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5HA55M.000
2024-11-19T16:29:26.978Z,1732033766.978 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5HA55M.000
2024-11-19T16:29:26.988Z,1732033766.988 [CBIT](DEBUG): Deactivating GF circuits.
2024-11-19T16:29:26.988Z,1732033766.988 [CBIT](DEBUG): Deactivating emergency mode.
2024-11-19T16:29:27.028Z,1732033767.028 [CBIT](DEBUG): Backplane powered.
2024-11-19T16:29:27.032Z,1732033767.032 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2024-11-19T16:29:27.033Z,1732033767.033 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2024-11-19T16:29:27.033Z,1732033767.033 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2024-11-19T16:29:27.033Z,1732033767.033 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2024-11-19T16:29:27.034Z,1732033767.034 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2024-11-19T16:29:27.035Z,1732033767.035 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2024-11-19T16:29:27.035Z,1732033767.035 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2024-11-19T16:29:27.036Z,1732033767.036 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2024-11-19T16:29:27.037Z,1732033767.037 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2024-11-19T16:29:27.094Z,1732033767.094 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2024-11-19T16:29:27.099Z,1732033767.099 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2024-11-19T16:29:27.124Z,1732033767.124 [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-11-19T16:29:27.125Z,1732033767.125 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2024-11-19T16:29:27.125Z,1732033767.125 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2024-11-19T16:29:27.126Z,1732033767.126 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2024-11-19T16:29:27.308Z,1732033767.308 [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-11-19T16:29:27.314Z,1732033767.314 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2024-11-19T16:29:27.315Z,1732033767.315 [Default:A.Wait](DEBUG): Construct Wait.
2024-11-19T16:29:27.331Z,1732033767.331 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-11-19T16:29:27.356Z,1732033767.356 [Radio_Surface](INFO): Powering up
2024-11-19T16:29:27.362Z,1732033767.362 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2024-11-19T16:29:27.377Z,1732033767.377 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2024-11-19T16:29:27.383Z,1732033767.383 [Default:E.Execute](DEBUG): Construct Execute.
2024-11-19T16:29:27.386Z,1732033767.386 [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-11-19T16:29:27.390Z,1732033767.390 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BioacousticsDataBridge,BPC1,Depth_Keller,NAL9602,Power24vConverter,Sonardyne_Nano,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-11-19T16:29:27.409Z,1732033767.409 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-11-19T16:29:27.458Z,1732033767.458 [Power24vConverter](INFO): Powering up.
2024-11-19T16:29:27.459Z,1732033767.459 [Sonardyne_Nano](INFO): Initializing.
2024-11-19T16:29:27.506Z,1732033767.506 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2024-11-19T16:29:27.512Z,1732033767.512 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2024-11-19T16:29:27.513Z,1732033767.513 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-11-19T16:29:27.520Z,1732033767.520 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-11-19T16:29:27.521Z,1732033767.521 [MassServo](DEBUG): Initializing EZServoServo.
2024-11-19T16:29:27.528Z,1732033767.528 [MassServo](DEBUG): Initializing MassServo.
2024-11-19T16:29:27.529Z,1732033767.529 [RudderServo](DEBUG): Initializing EZServoServo.
2024-11-19T16:29:27.536Z,1732033767.536 [RudderServo](DEBUG): Initializing RudderServo.
2024-11-19T16:29:27.537Z,1732033767.537 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-11-19T16:29:27.544Z,1732033767.544 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-11-19T16:29:29.080Z,1732033769.080 [WetLabsBB2FL](INFO): Powering down
2024-11-19T16:29:29.418Z,1732033769.418 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2024-11-19T16:29:30.704Z,1732033770.704 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2409
2024-11-19T16:29:33.276Z,1732033773.276 [ThrusterHE](ERROR): Zero Speed Commanded.
2024-11-19T16:29:36.830Z,1732033776.830 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004734
2024-11-19T16:29:38.753Z,1732033778.753 [DAT](INFO): DAT read:
2024-11-19T16:29:38.755Z,1732033778.755 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2024-11-19T16:29:40.265Z,1732033780.265 [DAT](INFO): DAT read: MF Frequency Band
2024-11-19T16:29:40.266Z,1732033780.266 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2024-11-19T16:29:40.267Z,1732033780.267 [DAT](INFO): DAT read: Nov 18 2024 18:20:42
2024-11-19T16:29:41.273Z,1732033781.273 [DAT](INFO): DAT read: Features enabled [Bearing]
2024-11-19T16:29:41.274Z,1732033781.274 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP
2024-11-19T16:29:41.275Z,1732033781.275 [DAT](INFO): commRate: 800
2024-11-19T16:29:43.340Z,1732033783.340 [DAT](INFO): entering command mode
2024-11-19T16:29:43.540Z,1732033783.540 [DAT](INFO): DAT read:
2024-11-19T16:29:43.541Z,1732033783.541 [DAT](INFO): DAT read: user:1>
2024-11-19T16:29:43.541Z,1732033783.541 [DAT](INFO): setting verbose to 3
2024-11-19T16:29:43.792Z,1732033783.792 [DAT](INFO): DAT read: user:1>
2024-11-19T16:29:43.794Z,1732033783.794 [DAT](INFO): DAT read: Verbose | 3
2024-11-19T16:29:43.794Z,1732033783.794 [DAT](INFO): set verbose to 3
2024-11-19T16:29:43.794Z,1732033783.794 [DAT](INFO): setting DatVerbose to 27440
2024-11-19T16:29:44.045Z,1732033784.045 [DAT](INFO): DAT read: user:2>
2024-11-19T16:29:44.046Z,1732033784.046 [DAT](INFO): DAT read: DatVerbose | 27440
2024-11-19T16:29:44.046Z,1732033784.046 [DAT](INFO): set DatVerbose to 27440
2024-11-19T16:29:44.046Z,1732033784.046 [DAT](INFO): setting transmit power to 8
2024-11-19T16:29:44.297Z,1732033784.297 [DAT](INFO): DAT read: user:3>
2024-11-19T16:29:44.297Z,1732033784.297 [DAT](INFO): DAT read: TxPower | 8 (Max)
2024-11-19T16:29:44.298Z,1732033784.298 [DAT](INFO): set transmit power to 8
2024-11-19T16:29:44.298Z,1732033784.298 [DAT](INFO): setting local address to 2
2024-11-19T16:29:44.549Z,1732033784.549 [DAT](INFO): DAT read: user:4>
2024-11-19T16:29:44.549Z,1732033784.549 [DAT](INFO): DAT read: LocalAddr | 2
2024-11-19T16:29:44.550Z,1732033784.550 [DAT](INFO): set local address to 2
2024-11-19T16:29:44.551Z,1732033784.551 [DAT](INFO): Setting time to: 16:29:44 And date to:11/19/2024
2024-11-19T16:29:44.801Z,1732033784.801 [DAT](INFO): DAT read: user:5>
2024-11-19T16:29:44.802Z,1732033784.802 [DAT](INFO): DAT read: Tue Nov 19, 2024 16:29:44
2024-11-19T16:29:44.802Z,1732033784.802 [DAT](INFO): Local DAT time set to Tue Nov 19, 2024 16:29:44
2024-11-19T16:29:55.278Z,1732033795.278 [NAL9602](INFO): Powering up NAL9602
2024-11-19T16:30:06.190Z,1732033806.190 [NAL9602](INFO): NAL9602 initialized
2024-11-19T16:30:30.464Z,1732033830.464 [SBIT](IMPORTANT): Beginning Startup BIT
2024-11-19T16:30:30.473Z,1732033830.473 [CBIT](IMPORTANT): Beginning ground fault scan
2024-11-19T16:30:33.658Z,1732033833.658 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-11-19T16:30:34.468Z,1732033834.468 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2024-11-19T16:30:36.689Z,1732033836.689 [CommandExec](IMPORTANT): got command strobe off
2024-11-19T16:30:36.689Z,1732033836.689 [CommandExec](IMPORTANT): Deactivating strobe
2024-11-19T16:30:41.422Z,1732033841.422 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.043607
CHAN A1 (24V): -0.000148
CHAN A2 (12V): 0.000336
CHAN A3 (5V): -0.002074
CHAN B0 (3.3V): -0.000845
CHAN B1 (3.15aV): -0.001078
CHAN B2 (3.15bV): -0.001355
CHAN B3 (GND): -0.001783
OPEN: 0.004658
Full Scale: +/- 1 mA
2024-11-19T16:30:46.177Z,1732033846.177 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-11-19T16:30:59.105Z,1732033859.105 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-11-19T16:31:11.629Z,1732033871.629 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-11-19T16:31:24.650Z,1732033884.650 [SBIT](IMPORTANT): SBIT PASSED
2024-11-19T16:31:24.650Z,1732033884.650 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-11-19T16:31:24.651Z,1732033884.651 [SBIT](IMPORTANT): BioacousticsDataBridge.loadAtStartup=1 bool;
2024-11-19T16:31:24.651Z,1732033884.651 [SBIT](IMPORTANT): CBIT.gf24Offset=162 microampere;
2024-11-19T16:31:24.651Z,1732033884.651 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool;
2024-11-19T16:31:24.651Z,1732033884.651 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 5.000000 meter;
2024-11-19T16:31:24.651Z,1732033884.651 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=267.110907 cubic_centimeter;
2024-11-19T16:31:24.653Z,1732033884.653 [SBIT](IMPORTANT): VerticalControl.kdPitchMass=1.25 second;
2024-11-19T16:31:24.653Z,1732033884.653 [SBIT](IMPORTANT): VerticalControl.kiPitchMass=1.25 reciprocal_second;
2024-11-19T16:31:24.653Z,1732033884.653 [SBIT](IMPORTANT): VerticalControl.kpPitchMass=1.25 none;
2024-11-19T16:31:24.653Z,1732033884.653 [SBIT](IMPORTANT): VerticalControl.massDeadband=0.05 centimeter;
2024-11-19T16:31:24.653Z,1732033884.653 [SBIT](IMPORTANT): VerticalControl.massDefault=10.867964 millimeter;
2024-11-19T16:31:24.654Z,1732033884.654 [SBIT](IMPORTANT): VerticalControl.massOnlyForceInt=0 bool;
2024-11-19T16:31:24.961Z,1732033884.961 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-11-19T16:31:25.008Z,1732033885.008 [MissionManager](IMPORTANT): Started mission Startup
2024-11-19T16:31:25.009Z,1732033885.009 [Startup] Running Loop=1
2024-11-19T16:31:25.009Z,1732033885.009 [Startup](DEBUG): Aggregate::initialize Startup
2024-11-19T16:31:25.009Z,1732033885.009 [Startup:A.GoToSurface] Running Loop=1
2024-11-19T16:31:25.009Z,1732033885.009 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-11-19T16:31:25.010Z,1732033885.010 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-11-19T16:31:25.010Z,1732033885.010 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-11-19T16:31:25.010Z,1732033885.010 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-11-19T16:31:25.011Z,1732033885.011 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-11-19T16:31:25.011Z,1732033885.011 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-11-19T16:31:25.011Z,1732033885.011 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-11-19T16:31:25.013Z,1732033885.013 [Startup:StartupSatComms] Running Loop=1
2024-11-19T16:31:25.013Z,1732033885.013 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2024-11-19T16:31:25.013Z,1732033885.013 [Startup:StartupSatComms:A] Running Loop=1
2024-11-19T16:31:25.396Z,1732033885.396 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2024-11-19T16:31:37.485Z,1732033897.485 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-11-19T16:31:50.414Z,1732033910.414 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-11-19T16:32:25.182Z,1732033945.182 [Startup:StartupSatComms:A](INFO): Timed out from 2024-11-19T16:31:25.0Z
2024-11-19T16:32:25.182Z,1732033945.182 [Startup:StartupSatComms:A] Stopped
2024-11-19T16:32:25.182Z,1732033945.182 [Startup:StartupSatComms:B] Running Loop=1
2024-11-19T16:32:25.591Z,1732033945.591 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-11-19T16:32:27.193Z,1732033947.193 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2024-11-19T16:32:27.193Z,1732033947.193 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-11-19T16:32:27.194Z,1732033947.194 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2024-11-19T16:32:27.194Z,1732033947.194 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-11-19T16:32:27.195Z,1732033947.195 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2024-11-19T16:32:27.195Z,1732033947.195 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-11-19T16:32:27.250Z,1732033947.250 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-11-19T16:32:27.250Z,1732033947.250 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-11-19T16:32:27.251Z,1732033947.251 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-11-19T16:32:27.609Z,1732033947.609 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-11-19T16:32:27.609Z,1732033947.609 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2024-11-19T16:32:27.610Z,1732033947.610 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-11-19T16:32:27.610Z,1732033947.610 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2024-11-19T16:32:27.610Z,1732033947.610 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-11-19T16:32:27.610Z,1732033947.610 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2024-11-19T16:32:28.021Z,1732033948.021 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-11-19T16:32:31.695Z,1732033951.695 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20241118T181229/Courier0004.lzma
2024-11-19T16:32:32.706Z,1732033952.706 [DataOverHttps](INFO): Moved sent file to Logs/20241118T181229/Courier0004.lzma.bak
2024-11-19T16:32:32.706Z,1732033952.706 [DataOverHttps](INFO): SBD MOMSN=23397002
2024-11-19T16:32:48.496Z,1732033968.496 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20241119T162910/Courier0000.lzma
2024-11-19T16:32:49.498Z,1732033969.498 [DataOverHttps](INFO): Moved sent file to Logs/20241119T162910/Courier0000.lzma.bak
2024-11-19T16:32:49.498Z,1732033969.498 [DataOverHttps](INFO): SBD MOMSN=23397004
2024-11-19T16:33:05.200Z,1732033985.200 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20241118T181229/Express0005.lzma
2024-11-19T16:33:06.202Z,1732033986.202 [DataOverHttps](INFO): Moved sent file to Logs/20241118T181229/Express0005.lzma.bak
2024-11-19T16:33:06.202Z,1732033986.202 [DataOverHttps](INFO): SBD MOMSN=23397007
2024-11-19T16:33:22.135Z,1732034002.135 [DataOverHttps](INFO): Sending 967 bytes from file Logs/20241119T162910/Express0001.lzma
2024-11-19T16:33:23.134Z,1732034003.134 [DataOverHttps](INFO): Moved sent file to Logs/20241119T162910/Express0001.lzma.bak
2024-11-19T16:33:23.134Z,1732034003.134 [DataOverHttps](INFO): SBD MOMSN=23397012
2024-11-19T16:33:24.598Z,1732034004.598 [Startup:StartupSatComms:B] Stopped
2024-11-19T16:33:24.598Z,1732034004.598 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2024-11-19T16:33:24.598Z,1732034004.598 [Startup:StartupSatComms] Stopped
2024-11-19T16:33:24.598Z,1732034004.598 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2024-11-19T16:33:24.599Z,1732034004.599 [Startup](INFO): Completed Startup
2024-11-19T16:33:24.600Z,1732034004.600 [MissionManager](INFO): Startup is completed.
2024-11-19T16:33:24.600Z,1732034004.600 [MissionManager](INFO): Uninitializing Mission Startup
2024-11-19T16:33:24.600Z,1732034004.600 [Startup] Stopped
2024-11-19T16:33:24.600Z,1732034004.600 [Startup](DEBUG): Aggregate::uninitialize Startup
2024-11-19T16:33:24.600Z,1732034004.600 [Startup:A.GoToSurface] Stopped
2024-11-19T16:33:24.600Z,1732034004.600 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-11-19T16:33:24.987Z,1732034004.987 [MissionManager](IMPORTANT): Started mission Default
2024-11-19T16:33:24.987Z,1732034004.987 [Default] Running Loop=1
2024-11-19T16:33:24.988Z,1732034004.988 [Default](DEBUG): Aggregate::initialize Default
2024-11-19T16:33:24.988Z,1732034004.988 [Default:B.GoToSurface] Running Loop=1
2024-11-19T16:33:24.988Z,1732034004.988 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-11-19T16:33:24.988Z,1732034004.988 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-11-19T16:33:24.988Z,1732034004.988 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-11-19T16:33:24.989Z,1732034004.989 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-11-19T16:33:24.989Z,1732034004.989 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-11-19T16:33:24.989Z,1732034004.989 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-11-19T16:33:24.990Z,1732034004.990 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-11-19T16:33:24.990Z,1732034004.990 [Default:A.Wait] Running Loop=1
2024-11-19T16:33:24.990Z,1732034004.990 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-11-19T16:33:38.314Z,1732034018.314 [Default:A.Wait](INFO): Done Waiting.
2024-11-19T16:33:38.315Z,1732034018.315 [Default:A.Wait] Stopped
2024-11-19T16:33:38.315Z,1732034018.315 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-11-19T16:33:38.730Z,1732034018.730 [Default:CheckIn] Running Loop=1
2024-11-19T16:33:38.730Z,1732034018.730 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-11-19T16:33:38.731Z,1732034018.731 [Default:CheckIn:Read_GPS] Running Loop=1
2024-11-19T16:33:39.133Z,1732034019.133 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2024-11-19T16:35:09.189Z,1732034109.189 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-11-19T16:35:28.192Z,1732034128.192 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2024-11-19T16:35:28.192Z,1732034128.192 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-11-19T16:35:28.193Z,1732034128.193 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2024-11-19T16:35:28.194Z,1732034128.194 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-11-19T16:35:28.195Z,1732034128.195 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2024-11-19T16:35:28.195Z,1732034128.195 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-11-19T16:35:28.258Z,1732034128.258 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-11-19T16:35:28.258Z,1732034128.258 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-11-19T16:35:28.258Z,1732034128.258 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-11-19T16:35:28.623Z,1732034128.623 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-11-19T16:35:28.623Z,1732034128.623 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2024-11-19T16:35:28.623Z,1732034128.623 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-11-19T16:35:28.655Z,1732034128.655 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2024-11-19T16:35:28.660Z,1732034128.660 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-11-19T16:35:28.660Z,1732034128.660 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2024-11-19T16:35:29.107Z,1732034129.107 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-11-19T16:37:00.281Z,1732034220.281 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2024-11-19T16:37:01.905Z,1732034221.905 [BPC1](ERROR): Battery stick #62 (s/n: 00CC) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2024-11-19T16:37:01.905Z,1732034221.905 [BPC1](INFO): Calculating totals. Valid battery stick count: 52. Valid reserve battery stick count: 5.
2024-11-19T16:37:01.908Z,1732034221.908 [BPC1](INFO): Received data from all battery sticks.
2024-11-19T16:38:29.175Z,1732034309.175 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2024-11-19T16:38:29.175Z,1732034309.175 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-11-19T16:38:29.177Z,1732034309.177 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2024-11-19T16:38:29.177Z,1732034309.177 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-11-19T16:38:29.178Z,1732034309.178 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2024-11-19T16:38:29.178Z,1732034309.178 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-11-19T16:38:29.225Z,1732034309.225 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-11-19T16:38:29.225Z,1732034309.225 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-11-19T16:38:29.225Z,1732034309.225 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-11-19T16:38:29.615Z,1732034309.615 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-11-19T16:38:29.615Z,1732034309.615 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2024-11-19T16:38:29.615Z,1732034309.615 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-11-19T16:38:29.615Z,1732034309.615 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2024-11-19T16:38:29.616Z,1732034309.616 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-11-19T16:38:29.616Z,1732034309.616 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3
2024-11-19T16:38:29.983Z,1732034309.983 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-11-19T16:38:38.874Z,1732034318.874 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-11-19T16:33:38.7Z
2024-11-19T16:38:38.874Z,1732034318.874 [Default:CheckIn:Read_GPS] Stopped
2024-11-19T16:38:38.875Z,1732034318.875 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-11-19T16:38:39.299Z,1732034319.299 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-11-19T16:38:46.104Z,1732034326.104 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20241119T162910/Courier0004.lzma
2024-11-19T16:38:47.106Z,1732034327.106 [DataOverHttps](INFO): Moved sent file to Logs/20241119T162910/Courier0004.lzma.bak
2024-11-19T16:38:47.106Z,1732034327.106 [DataOverHttps](INFO): SBD MOMSN=23397038
2024-11-19T16:39:01.652Z,1732034341.652 [DataOverHttps](INFO): Sending 187 bytes from file Logs/20241119T162910/Express0005.lzma
2024-11-19T16:39:02.654Z,1732034342.654 [DataOverHttps](INFO): Moved sent file to Logs/20241119T162910/Express0005.lzma.bak
2024-11-19T16:39:02.654Z,1732034342.654 [DataOverHttps](INFO): SBD MOMSN=23397040
2024-11-19T16:39:03.929Z,1732034343.929 [Default:CheckIn:Read_Iridium] Stopped
2024-11-19T16:39:03.929Z,1732034343.929 [Default:CheckIn:C.Wait] Running Loop=1
2024-11-19T16:39:03.929Z,1732034343.929 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-11-19T16:40:08.557Z,1732034408.557 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-11-19T16:40:08.557Z,1732034408.557 [NAL9602] Data Fault, FailCount= 1
2024-11-19T16:40:08.557Z,1732034408.557 [NAL9602](ERROR): Data Fault
2024-11-19T16:40:08.619Z,1732034408.619 [CBIT](ERROR): Data Fault in component: NAL9602
2024-11-19T16:40:08.973Z,1732034408.973 [NAL9602](INFO): Powering down
2024-11-19T16:40:09.811Z,1732034409.811 [CBIT](INFO): Clearing failed state for component NAL9602
2024-11-19T16:40:09.811Z,1732034409.811 [NAL9602] No Fault, FailCount= 1
2024-11-19T16:40:39.257Z,1732034439.257 [NAL9602](INFO): Powering up NAL9602
2024-11-19T16:40:50.170Z,1732034450.170 [NAL9602](INFO): NAL9602 initialized
2024-11-19T16:41:30.229Z,1732034490.229 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2024-11-19T16:41:30.229Z,1732034490.229 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-11-19T16:41:30.230Z,1732034490.230 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2024-11-19T16:41:30.230Z,1732034490.230 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-11-19T16:41:30.231Z,1732034490.231 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 4
2024-11-19T16:41:30.232Z,1732034490.232 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-11-19T16:41:30.242Z,1732034490.242 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-11-19T16:41:30.242Z,1732034490.242 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-11-19T16:41:30.243Z,1732034490.243 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-11-19T16:41:30.653Z,1732034490.653 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-11-19T16:41:30.653Z,1732034490.653 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2024-11-19T16:41:30.654Z,1732034490.654 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-11-19T16:41:30.654Z,1732034490.654 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2024-11-19T16:41:30.654Z,1732034490.654 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-11-19T16:41:30.654Z,1732034490.654 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 4
2024-11-19T16:41:31.027Z,1732034491.027 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-11-19T16:44:04.575Z,1732034644.575 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-11-19T16:44:04.575Z,1732034644.575 [Default:CheckIn:C.Wait] Stopped
2024-11-19T16:44:04.575Z,1732034644.575 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-11-19T16:44:04.575Z,1732034644.575 [Default:CheckIn:D] Running Loop=1
2024-11-19T16:44:04.983Z,1732034644.983 [Default:CheckIn:D] Stopped
2024-11-19T16:44:04.983Z,1732034644.983 [Default:CheckIn:E] Running Loop=1
2024-11-19T16:44:05.397Z,1732034645.397 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.666595 min
2024-11-19T16:44:05.397Z,1732034645.397 [Default:CheckIn:E] Stopped
2024-11-19T16:44:05.397Z,1732034645.397 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-11-19T16:44:05.397Z,1732034645.397 [Default:CheckIn] Stopped
2024-11-19T16:44:05.397Z,1732034645.397 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-11-19T16:44:05.397Z,1732034645.397 [Default:CheckIn](INFO): Running loop #2
2024-11-19T16:44:05.397Z,1732034645.397 [Default:CheckIn] Running Loop=2
2024-11-19T16:44:05.397Z,1732034645.397 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-11-19T16:44:05.398Z,1732034645.398 [Default:CheckIn:Read_GPS] Running Loop=1
2024-11-19T16:44:31.216Z,1732034671.216 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2024-11-19T16:44:31.216Z,1732034671.216 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-11-19T16:44:31.217Z,1732034671.217 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2024-11-19T16:44:31.217Z,1732034671.217 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-11-19T16:44:31.219Z,1732034671.219 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 5
2024-11-19T16:44:31.219Z,1732034671.219 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-11-19T16:44:31.229Z,1732034671.229 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-11-19T16:44:31.230Z,1732034671.230 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-11-19T16:44:31.230Z,1732034671.230 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-11-19T16:44:31.638Z,1732034671.638 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-11-19T16:44:31.638Z,1732034671.638 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2024-11-19T16:44:31.639Z,1732034671.639 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-11-19T16:44:31.639Z,1732034671.639 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2024-11-19T16:44:31.639Z,1732034671.639 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-11-19T16:44:31.639Z,1732034671.639 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 5
2024-11-19T16:44:32.033Z,1732034672.033 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-11-19T16:47:32.233Z,1732034852.233 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2024-11-19T16:47:32.233Z,1732034852.233 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-11-19T16:47:32.234Z,1732034852.234 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6
2024-11-19T16:47:32.234Z,1732034852.234 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-11-19T16:47:32.235Z,1732034852.235 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 6
2024-11-19T16:47:32.235Z,1732034852.235 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-11-19T16:47:32.246Z,1732034852.246 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-11-19T16:47:32.246Z,1732034852.246 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-11-19T16:47:32.246Z,1732034852.246 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-11-19T16:47:32.631Z,1732034852.631 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-11-19T16:47:32.631Z,1732034852.631 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2024-11-19T16:47:32.648Z,1732034852.648 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-11-19T16:47:32.648Z,1732034852.648 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6
2024-11-19T16:47:32.648Z,1732034852.648 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-11-19T16:47:32.648Z,1732034852.648 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 6
2024-11-19T16:47:33.019Z,1732034853.019 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-11-19T16:49:05.563Z,1732034945.563 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-11-19T16:44:05.4Z
2024-11-19T16:49:05.563Z,1732034945.563 [Default:CheckIn:Read_GPS] Stopped
2024-11-19T16:49:05.563Z,1732034945.563 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-11-19T16:49:12.536Z,1732034952.536 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20241119T162910/Courier0007.lzma
2024-11-19T16:49:13.534Z,1732034953.534 [DataOverHttps](INFO): Moved sent file to Logs/20241119T162910/Courier0007.lzma.bak
2024-11-19T16:49:13.534Z,1732034953.534 [DataOverHttps](INFO): SBD MOMSN=23397044
2024-11-19T16:49:28.231Z,1732034968.231 [DataOverHttps](INFO): Sending 182 bytes from file Logs/20241119T162910/Express0008.lzma
2024-11-19T16:49:29.218Z,1732034969.218 [DataOverHttps](INFO): Moved sent file to Logs/20241119T162910/Express0008.lzma.bak
2024-11-19T16:49:29.218Z,1732034969.218 [DataOverHttps](INFO): SBD MOMSN=23397046
2024-11-19T16:49:30.596Z,1732034970.596 [Default:CheckIn:Read_Iridium] Stopped
2024-11-19T16:49:30.596Z,1732034970.596 [Default:CheckIn:C.Wait] Running Loop=1
2024-11-19T16:49:30.596Z,1732034970.596 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-11-19T16:50:33.208Z,1732035033.208 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2024-11-19T16:50:33.208Z,1732035033.208 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-11-19T16:50:33.209Z,1732035033.209 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7
2024-11-19T16:50:33.209Z,1732035033.209 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-11-19T16:50:33.213Z,1732035033.213 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 7
2024-11-19T16:50:33.213Z,1732035033.213 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-11-19T16:50:33.226Z,1732035033.226 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-11-19T16:50:33.226Z,1732035033.226 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-11-19T16:50:33.226Z,1732035033.226 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-11-19T16:50:33.647Z,1732035033.647 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-11-19T16:50:33.647Z,1732035033.647 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2024-11-19T16:50:33.647Z,1732035033.647 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-11-19T16:50:33.668Z,1732035033.668 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7
2024-11-19T16:50:33.668Z,1732035033.668 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-11-19T16:50:33.668Z,1732035033.668 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 7
2024-11-19T16:50:34.045Z,1732035034.045 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-11-19T16:50:52.597Z,1732035052.597 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-11-19T16:50:52.597Z,1732035052.597 [NAL9602] Data Fault, FailCount= 2
2024-11-19T16:50:52.598Z,1732035052.598 [NAL9602](ERROR): Data Fault
2024-11-19T16:50:52.639Z,1732035052.639 [CBIT](ERROR): Data Fault in component: NAL9602
2024-11-19T16:50:52.994Z,1732035052.994 [NAL9602](INFO): Powering down
2024-11-19T16:50:53.829Z,1732035053.829 [CBIT](INFO): Clearing failed state for component NAL9602
2024-11-19T16:50:53.829Z,1732035053.829 [NAL9602] No Fault, FailCount= 2
2024-11-19T16:51:23.297Z,1732035083.297 [NAL9602](INFO): Powering up NAL9602
2024-11-19T16:51:34.201Z,1732035094.201 [NAL9602](INFO): NAL9602 initialized
2024-11-19T16:51:43.777Z,1732035103.777 [CommandExec](IMPORTANT): got command restart system
2024-11-19T16:51:45.832Z,1732035105.832 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2024-11-19T16:51:45.832Z,1732035105.832 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2024-11-19T16:51:45.833Z,1732035105.833 [CommandExec](INFO): Uninitializing the command executive.
2024-11-19T16:51:45.833Z,1732035105.833 [CommandExec](INFO): Uninitializing the command scheduler.
2024-11-19T16:51:45.833Z,1732035105.833 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-11-19T16:51:45.000Z,1732035106.000 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2024-11-19T16:51:45.000Z,1732035106.000 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2024-11-19T16:51:46.000Z,1732035106.000 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-11-19T16:51:46.001Z,1732035106.001 [NavChartDb](INFO): Join timeout helper Thread ID is 1751
2024-11-19T16:51:46.072Z,1732035106.072 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2024-11-19T16:51:46.072Z,1732035106.072 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-11-19T16:51:46.080Z,1732035106.080 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2024-11-19T16:51:46.080Z,1732035106.080 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-11-19T16:51:46.080Z,1732035106.080 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1752
2024-11-19T16:51:46.212Z,1732035106.212 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2024-11-19T16:51:46.212Z,1732035106.212 [WetLabsBB2FL](INFO): Powering down
2024-11-19T16:51:46.213Z,1732035106.213 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-11-19T16:51:46.228Z,1732035106.228 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2024-11-19T16:51:46.228Z,1732035106.228 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-11-19T16:51:46.228Z,1732035106.228 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1753
2024-11-19T16:51:46.556Z,1732035106.556 [CTD_Seabird](INFO): Powering down
2024-11-19T16:51:46.568Z,1732035106.568 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2024-11-19T16:51:46.568Z,1732035106.568 [CTD_Seabird](INFO): Powering down
2024-11-19T16:51:46.580Z,1732035106.580 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-11-19T16:51:46.596Z,1732035106.596 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2024-11-19T16:51:46.596Z,1732035106.596 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-11-19T16:51:46.596Z,1732035106.596 [Radio_Surface](INFO): Join timeout helper Thread ID is 1754
2024-11-19T16:51:46.688Z,1732035106.688 [Radio_Surface](INFO): Powering down
2024-11-19T16:51:46.689Z,1732035106.689 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2024-11-19T16:51:46.689Z,1732035106.689 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-11-19T16:51:46.698Z,1732035106.698 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2024-11-19T16:51:46.699Z,1732035106.699 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-11-19T16:51:46.699Z,1732035106.699 [Onboard](INFO): Join timeout helper Thread ID is 1755
2024-11-19T16:51:47.424Z,1732035107.424 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2024-11-19T16:51:48.068Z,1732035108.068 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2024-11-19T16:51:48.068Z,1732035108.068 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-11-19T16:51:48.073Z,1732035108.073 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2024-11-19T16:51:48.073Z,1732035108.073 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-11-19T16:51:48.073Z,1732035108.073 [DataOverHttps](INFO): Join timeout helper Thread ID is 1756
2024-11-19T16:51:48.428Z,1732035108.428 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2024-11-19T16:51:48.428Z,1732035108.428 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-11-19T16:51:48.444Z,1732035108.444 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2024-11-19T16:51:48.445Z,1732035108.445 [DAT ThreadHandler](INFO): Thread cancelled.
2024-11-19T16:51:48.445Z,1732035108.445 [DAT](INFO): Join timeout helper Thread ID is 1757
2024-11-19T16:51:48.492Z,1732035108.492 [DAT](INFO): Powering down
2024-11-19T16:51:48.565Z,1732035108.565 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2024-11-19T16:51:48.565Z,1732035108.565 [DAT](INFO): Powering down
2024-11-19T16:51:48.566Z,1732035108.566 [DAT ThreadHandler](INFO): Thread cancelled.
2024-11-19T16:51:48.584Z,1732035108.584 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2024-11-19T16:51:48.585Z,1732035108.585 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-11-19T16:51:48.585Z,1732035108.585 [BackseatComponent](INFO): Join timeout helper Thread ID is 1758
2024-11-19T16:51:48.768Z,1732035108.768 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2024-11-19T16:51:48.768Z,1732035108.768 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-11-19T16:51:48.770Z,1732035108.770 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2024-11-19T16:51:48.770Z,1732035108.770 [logger ThreadHandler](INFO): Thread cancelled.
2024-11-19T16:51:48.770Z,1732035108.770 [logger](INFO): Join timeout helper Thread ID is 1759
2024-11-19T16:51:48.776Z,1732035108.776 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2024-11-19T16:51:48.777Z,1732035108.777 [logger ThreadHandler](INFO): Thread cancelled.
2024-11-19T16:51:48.781Z,1732035108.781 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2024-11-19T16:51:48.781Z,1732035108.781 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-11-19T16:51:48.781Z,1732035108.781 [CommandLine](INFO): Join timeout helper Thread ID is 1760
2024-11-19T16:51:48.876Z,1732035108.876 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2024-11-19T16:51:48.876Z,1732035108.876 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-11-19T16:51:48.884Z,1732035108.884 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2024-11-19T16:51:48.885Z,1732035108.885 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-11-19T16:51:48.885Z,1732035108.885 [CommandExec](INFO): Join timeout helper Thread ID is 1761
2024-11-19T16:51:48.886Z,1732035108.886 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2024-11-19T16:51:48.886Z,1732035108.886 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-11-19T16:51:48.887Z,1732035108.887 [controlThread](INFO): Join timeout helper Thread ID is 1762
2024-11-19T16:51:49.136Z,1732035109.136 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2024-11-19T16:51:49.136Z,1732035109.136 [controlThread](DEBUG): Uninitializing ControlThread
2024-11-19T16:51:49.137Z,1732035109.137 [AHRS_M2](INFO): Powering down
2024-11-19T16:51:49.209Z,1732035109.209 [NAL9602](INFO): Powering down
2024-11-19T16:51:49.210Z,1732035109.210 [Sonardyne_Nano](INFO): Powering down
2024-11-19T16:51:49.329Z,1732035109.329 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2024-11-19T16:51:49.329Z,1732035109.329 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2024-11-19T16:51:49.330Z,1732035109.330 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2024-11-19T16:51:49.331Z,1732035109.331 [MissionManager](INFO): Uninitializing Mission Default
2024-11-19T16:51:49.331Z,1732035109.331 [Default] Stopped
2024-11-19T16:51:49.331Z,1732035109.331 [Default](DEBUG): Aggregate::uninitialize Default
2024-11-19T16:51:49.331Z,1732035109.331 [Default:B.GoToSurface] Stopped
2024-11-19T16:51:49.331Z,1732035109.331 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-11-19T16:51:49.331Z,1732035109.331 [Default:CheckIn] Stopped
2024-11-19T16:51:49.331Z,1732035109.331 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-11-19T16:51:49.331Z,1732035109.331 [Default:CheckIn:C.Wait] Stopped
2024-11-19T16:51:49.331Z,1732035109.331 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-11-19T16:51:49.334Z,1732035109.334 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2024-11-19T16:51:49.334Z,1732035109.334 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2024-11-19T16:51:49.335Z,1732035109.335 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2024-11-19T16:51:49.335Z,1732035109.335 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2024-11-19T16:51:49.335Z,1732035109.335 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2024-11-19T16:51:49.335Z,1732035109.335 [BuoyancyServo](INFO): Powering down
2024-11-19T16:51:49.348Z,1732035109.348 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2024-11-19T16:51:49.348Z,1732035109.348 [ElevatorServo](INFO): Powering down
2024-11-19T16:51:49.349Z,1732035109.349 [MassServo](DEBUG): Uninitialize Mass Servo.
2024-11-19T16:51:49.349Z,1732035109.349 [MassServo](INFO): Powering down
2024-11-19T16:51:49.350Z,1732035109.350 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2024-11-19T16:51:49.350Z,1732035109.350 [RudderServo](INFO): Powering down
2024-11-19T16:51:49.351Z,1732035109.351 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2024-11-19T16:51:49.351Z,1732035109.351 [ThrusterHE](INFO): Powering down
2024-11-19T16:51:49.352Z,1732035109.352 [SBIT](DEBUG): Uninitialize SBIT Component.
2024-11-19T16:51:49.352Z,1732035109.352 [IBIT](DEBUG): Uninitialize IBIT Component.
2024-11-19T16:51:49.353Z,1732035109.353 [CBIT](DEBUG): Uninitialize CBIT Component.
2024-11-19T16:51:49.353Z,1732035109.353 [CBIT](DEBUG): Powering off loads.
2024-11-19T16:51:49.364Z,1732035109.364 [CBIT](DEBUG): Disabling WDT.
2024-11-19T16:51:49.376Z,1732035109.376 [CBIT](DEBUG): Opening all GF detection circuits.
2024-11-19T16:51:49.377Z,1732035109.377 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-11-19T16:51:49.403Z,1732035109.403 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-11-19T16:51:49.406Z,1732035109.406 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-11-19T16:51:49.412Z,1732035109.412 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-11-19T16:51:49.419Z,1732035109.419 [DAT ThreadHandler](INFO): Thread cancelled.
2024-11-19T16:51:49.463Z,1732035109.463 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-11-19T16:51:49.572Z,1732035109.572 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-11-19T16:51:49.577Z,1732035109.577 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-11-19T16:51:49.601Z,1732035109.601 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-11-19T16:51:49.648Z,1732035109.648 [logger ThreadHandler](INFO): Thread cancelled.