2024-07-18T14:21:33.751Z,1721312493.751 [Supervisor](DEBUG): Initializing supervisor.
2024-07-18T14:21:33.755Z,1721312493.755 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2024-07-18T14:21:33.755Z,1721312493.755 [SyncHandler](INFO): Protected caller Thread ID is 994
2024-07-18T14:21:33.756Z,1721312493.756 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2024-07-18T14:21:33.757Z,1721312493.757 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2024-07-18T14:21:33.757Z,1721312493.757 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 995
2024-07-18T14:21:33.761Z,1721312493.761 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2024-07-18T14:21:33.779Z,1721312493.779 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2024-07-18T14:21:33.780Z,1721312493.780 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2024-07-18T14:21:33.780Z,1721312493.780 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 996
2024-07-18T14:21:33.784Z,1721312493.784 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2024-07-18T14:21:33.785Z,1721312493.785 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2024-07-18T14:21:33.786Z,1721312493.786 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 997
2024-07-18T14:21:33.788Z,1721312493.788 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2024-07-18T14:21:33.789Z,1721312493.789 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2024-07-18T14:21:33.789Z,1721312493.789 [logger ThreadHandler](INFO): Protected caller Thread ID is 998
2024-07-18T14:21:33.793Z,1721312493.793 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2024-07-18T14:21:33.793Z,1721312493.793 [Supervisor](INFO): Looking for Config files in directory: Config/
2024-07-18T14:21:33.795Z,1721312493.795 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2024-07-18T14:21:34.298Z,1721312494.298 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2024-07-18T14:21:34.299Z,1721312494.299 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2024-07-18T14:21:34.535Z,1721312494.535 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2024-07-18T14:21:34.535Z,1721312494.535 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2024-07-18T14:21:34.629Z,1721312494.629 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2024-07-18T14:21:34.884Z,1721312494.884 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2024-07-18T14:21:34.885Z,1721312494.885 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2024-07-18T14:21:34.976Z,1721312494.976 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2024-07-18T14:21:35.085Z,1721312495.085 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2024-07-18T14:21:35.085Z,1721312495.085 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2024-07-18T14:21:35.470Z,1721312495.470 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2024-07-18T14:21:35.470Z,1721312495.470 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2024-07-18T14:21:35.862Z,1721312495.862 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2024-07-18T14:21:35.863Z,1721312495.863 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2024-07-18T14:21:36.448Z,1721312496.448 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2024-07-18T14:21:36.449Z,1721312496.449 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2024-07-18T14:21:36.658Z,1721312496.658 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2024-07-18T14:21:36.658Z,1721312496.658 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2024-07-18T14:21:37.099Z,1721312497.099 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2024-07-18T14:21:37.099Z,1721312497.099 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2024-07-18T14:21:37.413Z,1721312497.413 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2024-07-18T14:21:37.414Z,1721312497.414 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2024-07-18T14:21:37.537Z,1721312497.537 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2024-07-18T14:21:37.537Z,1721312497.537 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2024-07-18T14:21:38.425Z,1721312498.425 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2024-07-18T14:21:38.425Z,1721312498.425 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2024-07-18T14:21:38.808Z,1721312498.808 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2024-07-18T14:21:38.809Z,1721312498.809 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2024-07-18T14:21:39.060Z,1721312499.060 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2024-07-18T14:21:39.062Z,1721312499.062 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2024-07-18T14:21:39.063Z,1721312499.063 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2024-07-18T14:21:39.196Z,1721312499.196 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2024-07-18T14:21:39.291Z,1721312499.291 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2024-07-18T14:21:39.443Z,1721312499.443 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2024-07-18T14:21:39.540Z,1721312499.540 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2024-07-18T14:21:39.819Z,1721312499.819 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2024-07-18T14:21:39.820Z,1721312499.820 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2024-07-18T14:21:39.937Z,1721312499.937 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2024-07-18T14:21:40.058Z,1721312500.058 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2024-07-18T14:21:40.178Z,1721312500.178 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2024-07-18T14:21:40.344Z,1721312500.344 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2024-07-18T14:21:40.454Z,1721312500.454 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2024-07-18T14:21:40.576Z,1721312500.576 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/root/
2024-07-18T14:21:40.576Z,1721312500.576 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2024-07-18T14:21:40.606Z,1721312500.606 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2024-07-18T14:21:40.617Z,1721312500.617 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2024-07-18T14:21:40.618Z,1721312500.618 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2024-07-18T14:21:41.083Z,1721312501.083 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2024-07-18T14:21:41.083Z,1721312501.083 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2024-07-18T14:21:41.340Z,1721312501.340 [VerticalControl](DEBUG): Construct VerticalControl.
2024-07-18T14:21:41.402Z,1721312501.402 [VerticalControl] Loaded
2024-07-18T14:21:41.402Z,1721312501.402 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2024-07-18T14:21:41.405Z,1721312501.405 [HorizontalControl](DEBUG): Construct HorizontalControl.
2024-07-18T14:21:41.585Z,1721312501.585 [HorizontalControl] Loaded
2024-07-18T14:21:41.586Z,1721312501.586 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2024-07-18T14:21:41.608Z,1721312501.608 [SpeedControl](DEBUG): Construct SpeedControl.
2024-07-18T14:21:41.611Z,1721312501.611 [SpeedControl] Loaded
2024-07-18T14:21:41.611Z,1721312501.611 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2024-07-18T14:21:41.614Z,1721312501.614 [LoopControl](DEBUG): Construct LoopControl.
2024-07-18T14:21:41.614Z,1721312501.614 [LoopControl] Loaded
2024-07-18T14:21:41.615Z,1721312501.615 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2024-07-18T14:21:41.615Z,1721312501.615 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2024-07-18T14:21:41.624Z,1721312501.624 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2024-07-18T14:21:41.808Z,1721312501.807 [BuoyancyServo] Loaded
2024-07-18T14:21:41.808Z,1721312501.808 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2024-07-18T14:21:41.830Z,1721312501.830 [ElevatorServo] Loaded
2024-07-18T14:21:41.830Z,1721312501.830 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2024-07-18T14:21:41.851Z,1721312501.851 [MassServo] Loaded
2024-07-18T14:21:41.852Z,1721312501.852 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2024-07-18T14:21:41.872Z,1721312501.872 [RudderServo] Loaded
2024-07-18T14:21:41.873Z,1721312501.873 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2024-07-18T14:21:41.889Z,1721312501.889 [ThrusterHE] Loaded
2024-07-18T14:21:41.889Z,1721312501.889 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2024-07-18T14:21:41.890Z,1721312501.890 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2024-07-18T14:21:41.890Z,1721312501.890 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2024-07-18T14:21:41.942Z,1721312501.942 [DepthRateCalculator] Loaded
2024-07-18T14:21:41.943Z,1721312501.943 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2024-07-18T14:21:41.948Z,1721312501.948 [PitchRateCalculator] Loaded
2024-07-18T14:21:41.948Z,1721312501.948 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2024-07-18T14:21:41.958Z,1721312501.958 [SpeedCalculator] Loaded
2024-07-18T14:21:41.958Z,1721312501.958 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2024-07-18T14:21:41.962Z,1721312501.962 [YawRateCalculator] Loaded
2024-07-18T14:21:41.963Z,1721312501.963 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2024-07-18T14:21:41.982Z,1721312501.982 [ElevatorOffsetCalculator] Loaded
2024-07-18T14:21:41.982Z,1721312501.982 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2024-07-18T14:21:41.983Z,1721312501.983 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2024-07-18T14:21:41.983Z,1721312501.983 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2024-07-18T14:21:42.096Z,1721312502.096 [DeadReckonUsingMultipleVelocitySources] Loaded
2024-07-18T14:21:42.097Z,1721312502.097 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2024-07-18T14:21:42.110Z,1721312502.110 [NavChart] Loaded
2024-07-18T14:21:42.111Z,1721312502.111 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2024-07-18T14:21:42.116Z,1721312502.116 [UniversalFixResidualReporter] Loaded
2024-07-18T14:21:42.117Z,1721312502.117 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2024-07-18T14:21:42.117Z,1721312502.117 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2024-07-18T14:21:42.118Z,1721312502.118 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2024-07-18T14:21:42.561Z,1721312502.561 [AHRS_M2] Loaded
2024-07-18T14:21:42.561Z,1721312502.561 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2024-07-18T14:21:42.601Z,1721312502.601 [BackseatComponent] Loaded
2024-07-18T14:21:42.601Z,1721312502.601 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2024-07-18T14:21:42.602Z,1721312502.602 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4090B4E0
2024-07-18T14:21:42.603Z,1721312502.603 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1080
2024-07-18T14:21:42.605Z,1721312502.605 [LcmUniversalReporter] Loaded
2024-07-18T14:21:42.606Z,1721312502.606 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2024-07-18T14:21:42.612Z,1721312502.612 [BioacousticsDataBridge] Loaded
2024-07-18T14:21:42.612Z,1721312502.612 [ComponentRegistry](DEBUG): SyncComponent "BioacousticsDataBridge" handled in the control thread.
2024-07-18T14:21:43.455Z,1721312503.455 [BPC1] Loaded
2024-07-18T14:21:43.455Z,1721312503.455 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2024-07-18T14:21:43.527Z,1721312503.527 [DataOverHttps] Loaded
2024-07-18T14:21:43.527Z,1721312503.527 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2024-07-18T14:21:43.528Z,1721312503.528 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4093B4E0
2024-07-18T14:21:43.528Z,1721312503.528 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1081
2024-07-18T14:21:43.552Z,1721312503.552 [Depth_Keller] Loaded
2024-07-18T14:21:43.553Z,1721312503.553 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2024-07-18T14:21:43.558Z,1721312503.558 [DropWeight] Loaded
2024-07-18T14:21:43.558Z,1721312503.558 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2024-07-18T14:21:43.623Z,1721312503.623 [NAL9602] Loaded
2024-07-18T14:21:43.623Z,1721312503.623 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2024-07-18T14:21:43.653Z,1721312503.653 [Onboard] Loaded
2024-07-18T14:21:43.653Z,1721312503.653 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2024-07-18T14:21:43.654Z,1721312503.654 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4096B4E0
2024-07-18T14:21:43.655Z,1721312503.655 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1082
2024-07-18T14:21:43.669Z,1721312503.669 [Power24vConverter] Loaded
2024-07-18T14:21:43.669Z,1721312503.669 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2024-07-18T14:21:43.685Z,1721312503.685 [Radio_Surface] Loaded
2024-07-18T14:21:43.685Z,1721312503.685 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2024-07-18T14:21:43.686Z,1721312503.686 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4099B4E0
2024-07-18T14:21:43.687Z,1721312503.687 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1083
2024-07-18T14:21:43.702Z,1721312503.702 [Sonardyne_Nano] Loaded
2024-07-18T14:21:43.703Z,1721312503.703 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2024-07-18T14:21:43.703Z,1721312503.703 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2024-07-18T14:21:43.704Z,1721312503.704 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2024-07-18T14:21:43.760Z,1721312503.760 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2024-07-18T14:21:43.761Z,1721312503.761 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2024-07-18T14:21:43.938Z,1721312503.938 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2024-07-18T14:21:43.939Z,1721312503.939 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2024-07-18T14:21:43.966Z,1721312503.966 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2024-07-18T14:21:43.966Z,1721312503.966 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2024-07-18T14:21:44.145Z,1721312504.145 [CTD_Seabird] Loaded
2024-07-18T14:21:44.145Z,1721312504.145 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2024-07-18T14:21:44.146Z,1721312504.146 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40ADB4E0
2024-07-18T14:21:44.147Z,1721312504.147 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1084
2024-07-18T14:21:44.169Z,1721312504.169 [PAR_Licor] Loaded
2024-07-18T14:21:44.169Z,1721312504.169 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2024-07-18T14:21:44.215Z,1721312504.215 [WetLabsBB2FL] Loaded
2024-07-18T14:21:44.216Z,1721312504.216 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2024-07-18T14:21:44.217Z,1721312504.217 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B0B4E0
2024-07-18T14:21:44.217Z,1721312504.217 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1085
2024-07-18T14:21:44.218Z,1721312504.218 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2024-07-18T14:21:44.219Z,1721312504.219 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2024-07-18T14:21:44.571Z,1721312504.571 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2024-07-18T14:21:44.571Z,1721312504.571 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2024-07-18T14:21:44.719Z,1721312504.719 [SBIT](DEBUG): Construct Startup Built In Test.
2024-07-18T14:21:44.728Z,1721312504.728 [SBIT] Loaded
2024-07-18T14:21:44.729Z,1721312504.729 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2024-07-18T14:21:44.731Z,1721312504.731 [IBIT](DEBUG): Construct Initiated Built In Test.
2024-07-18T14:21:44.744Z,1721312504.744 [IBIT] Loaded
2024-07-18T14:21:44.745Z,1721312504.745 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2024-07-18T14:21:44.750Z,1721312504.750 [CBIT](DEBUG): Construct Continuous Built In Test.
2024-07-18T14:21:44.857Z,1721312504.857 [CBIT] Loaded
2024-07-18T14:21:44.857Z,1721312504.857 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2024-07-18T14:21:44.858Z,1721312504.858 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2024-07-18T14:21:44.864Z,1721312504.864 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2024-07-18T14:21:44.867Z,1721312504.867 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2024-07-18T14:21:44.878Z,1721312504.878 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2024-07-18T14:21:44.880Z,1721312504.880 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C074E0
2024-07-18T14:21:44.880Z,1721312504.880 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1086
2024-07-18T14:21:44.884Z,1721312504.884 [Supervisor](INFO): Main Thread ID is 830
2024-07-18T14:21:44.884Z,1721312504.884 [Supervisor](DEBUG): Running supervisor.
2024-07-18T14:21:44.885Z,1721312504.885 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1087
2024-07-18T14:21:44.886Z,1721312504.886 [CommandExec](INFO): Initializing the command executive.
2024-07-18T14:21:44.887Z,1721312504.887 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1088
2024-07-18T14:21:44.890Z,1721312504.890 [controlThread ThreadHandler](INFO): Handler Thread ID is 1089
2024-07-18T14:21:44.891Z,1721312504.891 [controlThread](DEBUG): Initializing ControlThread
2024-07-18T14:21:44.891Z,1721312504.891 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2024-07-18T14:21:44.893Z,1721312504.893 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2024-07-18T14:21:44.894Z,1721312504.894 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2024-07-18T14:21:44.894Z,1721312504.894 [LoopControl](DEBUG): Initialize LoopControlComponent.
2024-07-18T14:21:44.896Z,1721312504.896 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2024-07-18T14:21:44.896Z,1721312504.896 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2024-07-18T14:21:44.896Z,1721312504.896 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2024-07-18T14:21:44.896Z,1721312504.896 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2024-07-18T14:21:44.897Z,1721312504.897 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2024-07-18T14:21:44.898Z,1721312504.898 [NavChart](DEBUG): Initialize NavChart Navigation.
2024-07-18T14:21:44.898Z,1721312504.898 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2024-07-18T14:21:44.904Z,1721312504.904 [SBIT](INFO): Initialize SBIT Component.
2024-07-18T14:21:44.905Z,1721312504.905 [SBIT](IMPORTANT): git: 2024-07-18
2024-07-18T14:21:44.905Z,1721312504.905 [SBIT](INFO): git hash: e777c895b2d7d743d45b75a88845e2c8d3f60819
2024-07-18T14:21:44.905Z,1721312504.905 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2024-07-18T14:21:44.906Z,1721312504.906 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2024-07-18T14:21:44.907Z,1721312504.907 [SBIT](INFO): Beginning SBIT in 110.000000 seconds.
2024-07-18T14:21:44.908Z,1721312504.908 [IBIT](INFO): Initialize IBIT Component.
2024-07-18T14:21:44.909Z,1721312504.909 [CBIT](DEBUG): Initialize CBIT Component.
2024-07-18T14:21:44.910Z,1721312504.910 [logger ThreadHandler](INFO): Handler Thread ID is 1090
2024-07-18T14:21:44.920Z,1721312504.920 [CBIT](DEBUG): Initialized mux pins.
2024-07-18T14:21:44.920Z,1721312504.920 [CBIT](DEBUG): Initializing the watchdog timer.
2024-07-18T14:21:44.928Z,1721312504.928 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1091
2024-07-18T14:21:44.940Z,1721312504.940 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1092
2024-07-18T14:21:44.942Z,1721312504.942 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2024-07-18T14:21:44.944Z,1721312504.944 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2024-07-18T14:21:44.944Z,1721312504.944 [CBIT](DEBUG): Initializing heartbeat.
2024-07-18T14:21:44.952Z,1721312504.952 [Onboard ThreadHandler](INFO): Handler Thread ID is 1093
2024-07-18T14:21:44.970Z,1721312504.970 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1094
2024-07-18T14:21:44.989Z,1721312504.989 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1095
2024-07-18T14:21:44.989Z,1721312504.989 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2024-07-18T14:21:44.993Z,1721312504.993 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1097
2024-07-18T14:21:44.996Z,1721312504.996 [WetLabsBB2FL](INFO): Powering up
2024-07-18T14:21:44.997Z,1721312504.997 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1099
2024-07-18T14:21:44.999Z,1721312504.999 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI60M.000
2024-07-18T14:21:44.000Z,1721312505.000 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI60M.000
2024-07-18T14:21:44.000Z,1721312505.000 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI1ND.000
2024-07-18T14:21:45.000Z,1721312505.000 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI1ND.000
2024-07-18T14:21:45.016Z,1721312505.016 [CBIT](DEBUG): Deactivating GF circuits.
2024-07-18T14:21:45.016Z,1721312505.016 [CBIT](DEBUG): Deactivating emergency mode.
2024-07-18T14:21:45.056Z,1721312505.056 [CBIT](DEBUG): Backplane powered.
2024-07-18T14:21:45.056Z,1721312505.056 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2024-07-18T14:21:45.057Z,1721312505.057 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2024-07-18T14:21:45.057Z,1721312505.057 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2024-07-18T14:21:45.059Z,1721312505.059 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2024-07-18T14:21:45.111Z,1721312505.111 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2024-07-18T14:21:45.115Z,1721312505.115 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2024-07-18T14:21:45.159Z,1721312505.159 [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-07-18T14:21:45.160Z,1721312505.160 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2024-07-18T14:21:45.160Z,1721312505.160 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2024-07-18T14:21:45.161Z,1721312505.161 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2024-07-18T14:21:45.376Z,1721312505.376 [Radio_Surface](INFO): Powering up
2024-07-18T14:21:45.467Z,1721312505.467 [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-07-18T14:21:45.497Z,1721312505.497 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2024-07-18T14:21:45.498Z,1721312505.498 [Default:A.Wait](DEBUG): Construct Wait.
2024-07-18T14:21:45.500Z,1721312505.500 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-07-18T14:21:45.553Z,1721312505.553 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2024-07-18T14:21:45.555Z,1721312505.555 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2024-07-18T14:21:45.562Z,1721312505.562 [Default:E.Execute](DEBUG): Construct Execute.
2024-07-18T14:21:45.584Z,1721312505.584 [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-07-18T14:21:45.601Z,1721312505.601 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BioacousticsDataBridge,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2024-07-18T14:21:45.620Z,1721312505.620 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-07-18T14:21:45.690Z,1721312505.690 [Power24vConverter](INFO): Powering up.
2024-07-18T14:21:45.691Z,1721312505.691 [Sonardyne_Nano](INFO): Initializing.
2024-07-18T14:21:45.742Z,1721312505.742 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2024-07-18T14:21:45.748Z,1721312505.748 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2024-07-18T14:21:45.749Z,1721312505.749 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-07-18T14:21:45.760Z,1721312505.760 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-07-18T14:21:45.761Z,1721312505.761 [MassServo](DEBUG): Initializing EZServoServo.
2024-07-18T14:21:45.768Z,1721312505.768 [MassServo](DEBUG): Initializing MassServo.
2024-07-18T14:21:45.769Z,1721312505.769 [RudderServo](DEBUG): Initializing EZServoServo.
2024-07-18T14:21:45.776Z,1721312505.776 [RudderServo](DEBUG): Initializing RudderServo.
2024-07-18T14:21:45.777Z,1721312505.777 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-07-18T14:21:45.784Z,1721312505.784 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-07-18T14:21:45.786Z,1721312505.786 [CommandExec](FAULT): Scheduling is paused
2024-07-18T14:21:45.786Z,1721312505.786 [CBIT](INFO): Critical error at 20240718T142144
2024-07-18T14:21:45.787Z,1721312505.787 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2024-07-18T14:21:47.104Z,1721312507.104 [WetLabsBB2FL](INFO): Powering down
2024-07-18T14:21:47.630Z,1721312507.630 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2024-07-18T14:21:48.916Z,1721312508.916 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2107
2024-07-18T14:21:51.525Z,1721312511.525 [ThrusterHE](ERROR): Zero Speed Commanded.
2024-07-18T14:21:52.021Z,1721312512.021 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2024-07-18T14:22:13.897Z,1721312533.897 [NAL9602](INFO): Powering up NAL9602
2024-07-18T14:22:24.806Z,1721312544.806 [NAL9602](INFO): NAL9602 initialized
2024-07-18T14:22:41.775Z,1721312561.775 [NAL9602](INFO): SBD MO Status=0, MOMSN=5207, MT Status=0, MTMSN=0
2024-07-18T14:22:41.775Z,1721312561.775 [NAL9602](INFO): No messages in MT queue
2024-07-18T14:23:00.764Z,1721312580.764 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,142259.00,A,4538.82534,N,08428.34744,W,1.438,0.00,180724,,,A*77
2024-07-18T14:23:00.767Z,1721312580.767 [NAL9602](INFO): GPS fix at 20240718T142259: (45.647089, -84.472457)
2024-07-18T14:23:07.627Z,1721312587.627 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-18T14:23:22.575Z,1721312602.575 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-18T14:23:32.674Z,1721312612.674 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-07-18T14:23:33.097Z,1721312613.097 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-18T14:23:35.536Z,1721312615.536 [SBIT](IMPORTANT): Beginning Startup BIT
2024-07-18T14:23:35.544Z,1721312615.544 [CBIT](IMPORTANT): Beginning ground fault scan
2024-07-18T14:23:37.927Z,1721312617.927 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-18T14:23:46.437Z,1721312626.437 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.003874
CHAN A1 (24V): -0.007465
CHAN A2 (12V): -0.004431
CHAN A3 (5V): -0.002569
CHAN B0 (3.3V): -0.001070
CHAN B1 (3.15aV): -0.001914
CHAN B2 (3.15bV): -0.001009
CHAN B3 (GND): -0.000481
OPEN: 0.003467
Full Scale: +/- 1 mA
2024-07-18T14:23:48.026Z,1721312628.026 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-07-18T14:23:53.278Z,1721312633.278 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-18T14:24:04.186Z,1721312644.186 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-07-18T14:24:08.630Z,1721312648.630 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-18T14:24:19.539Z,1721312659.539 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-07-18T14:24:23.579Z,1721312663.579 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-18T14:24:29.663Z,1721312669.663 [SBIT](IMPORTANT): SBIT PASSED
2024-07-18T14:24:29.664Z,1721312669.664 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-07-18T14:24:29.665Z,1721312669.665 [SBIT](IMPORTANT): AHRS_M2.loadAtStartup=1 bool;
2024-07-18T14:24:29.665Z,1721312669.665 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=6 count;
2024-07-18T14:24:29.665Z,1721312669.665 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;
2024-07-18T14:24:29.665Z,1721312669.665 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=250 cubic_centimeter;
2024-07-18T14:24:29.665Z,1721312669.665 [SBIT](IMPORTANT): VerticalControl.massDefault=2.25 centimeter;
2024-07-18T14:24:30.070Z,1721312670.070 [MissionManager](IMPORTANT): Started mission Startup
2024-07-18T14:24:30.071Z,1721312670.071 [Startup] Running Loop=1
2024-07-18T14:24:30.071Z,1721312670.071 [Startup](DEBUG): Aggregate::initialize Startup
2024-07-18T14:24:30.071Z,1721312670.071 [Startup:A.GoToSurface] Running Loop=1
2024-07-18T14:24:30.071Z,1721312670.071 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-07-18T14:24:30.071Z,1721312670.071 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-07-18T14:24:30.072Z,1721312670.072 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-07-18T14:24:30.072Z,1721312670.072 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-07-18T14:24:30.073Z,1721312670.073 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-07-18T14:24:30.073Z,1721312670.073 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-07-18T14:24:30.073Z,1721312670.073 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-07-18T14:24:30.075Z,1721312670.075 [Startup:StartupSatComms] Running Loop=1
2024-07-18T14:24:30.075Z,1721312670.075 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2024-07-18T14:24:30.075Z,1721312670.075 [Startup:StartupSatComms:A] Running Loop=1
2024-07-18T14:24:30.463Z,1721312670.463 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2024-07-18T14:24:32.071Z,1721312672.071 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,142430.00,A,4538.83177,N,08428.35250,W,0.117,180.20,180724,,,A*7F
2024-07-18T14:24:32.073Z,1721312672.073 [NAL9602](INFO): GPS fix at 20240718T142430: (45.647196, -84.472542)
2024-07-18T14:24:32.108Z,1721312672.108 [Startup:StartupSatComms:A] Stopped
2024-07-18T14:24:32.108Z,1721312672.108 [Startup:StartupSatComms:B] Running Loop=1
2024-07-18T14:24:32.497Z,1721312672.497 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-07-18T14:24:35.294Z,1721312675.294 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-07-18T14:24:38.527Z,1721312678.527 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-18T14:24:50.559Z,1721312690.559 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-07-18T14:24:53.795Z,1721312693.795 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-18T14:25:03.099Z,1721312703.099 [NAL9602](INFO): SBD MO Status=2, MOMSN=5208, MT Status=2, MTMSN=0
2024-07-18T14:25:03.099Z,1721312703.099 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-07-18T14:25:05.911Z,1721312705.911 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-07-18T14:25:15.641Z,1721312715.641 [CommandExec](IMPORTANT): got command failComponent
2024-07-18T14:25:15.641Z,1721312715.641 [CommandExec](IMPORTANT): Failed components:
2024-07-18T14:25:15.641Z,1721312715.641 [CommandExec](IMPORTANT): No failed Components.
2024-07-18T14:25:18.082Z,1721312718.082 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-18T14:25:21.261Z,1721312721.261 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-07-18T14:25:23.137Z,1721312723.137 [CommandExec](IMPORTANT): got command burn on
2024-07-18T14:25:23.137Z,1721312723.137 [CommandExec](IMPORTANT): Activating dropweight wire
2024-07-18T14:25:29.581Z,1721312729.581 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003045
2024-07-18T14:25:31.867Z,1721312731.867 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-07-18T14:25:32.275Z,1721312732.275 [Startup:StartupSatComms:B](INFO): Timed out from 2024-07-18T14:24:32.1Z
2024-07-18T14:25:32.275Z,1721312732.275 [Startup:StartupSatComms:B] Stopped
2024-07-18T14:25:32.275Z,1721312732.275 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2024-07-18T14:25:32.275Z,1721312732.275 [Startup:StartupSatComms] Stopped
2024-07-18T14:25:32.275Z,1721312732.275 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2024-07-18T14:25:32.284Z,1721312732.284 [Startup](INFO): Completed Startup
2024-07-18T14:25:32.284Z,1721312732.284 [MissionManager](INFO): Startup is completed.
2024-07-18T14:25:32.285Z,1721312732.285 [MissionManager](INFO): Uninitializing Mission Startup
2024-07-18T14:25:32.285Z,1721312732.285 [Startup] Stopped
2024-07-18T14:25:32.285Z,1721312732.285 [Startup](DEBUG): Aggregate::uninitialize Startup
2024-07-18T14:25:32.285Z,1721312732.285 [Startup:A.GoToSurface] Stopped
2024-07-18T14:25:32.285Z,1721312732.285 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-07-18T14:25:32.625Z,1721312732.625 [MissionManager](IMPORTANT): Started mission Default
2024-07-18T14:25:32.625Z,1721312732.625 [Default] Running Loop=1
2024-07-18T14:25:32.625Z,1721312732.625 [Default](DEBUG): Aggregate::initialize Default
2024-07-18T14:25:32.626Z,1721312732.626 [Default:B.GoToSurface] Running Loop=1
2024-07-18T14:25:32.626Z,1721312732.626 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-07-18T14:25:32.626Z,1721312732.626 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-07-18T14:25:32.626Z,1721312732.626 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-07-18T14:25:32.627Z,1721312732.627 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-07-18T14:25:32.627Z,1721312732.627 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-07-18T14:25:32.627Z,1721312732.627 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-07-18T14:25:32.627Z,1721312732.627 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-07-18T14:25:32.628Z,1721312732.628 [Default:A.Wait] Running Loop=1
2024-07-18T14:25:32.628Z,1721312732.628 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-07-18T14:25:32.982Z,1721312732.982 [NAL9602](INFO): SBD MO Status=2, MOMSN=5208, MT Status=2, MTMSN=0
2024-07-18T14:25:32.983Z,1721312732.983 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-07-18T14:25:37.419Z,1721312737.419 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-07-18T14:25:41.575Z,1721312741.575 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240718T141724/Courier0007.lzma
2024-07-18T14:25:42.578Z,1721312742.578 [DataOverHttps](INFO): Moved sent file to Logs/20240718T141724/Courier0007.lzma.bak
2024-07-18T14:25:42.578Z,1721312742.578 [DataOverHttps](INFO): SBD MOMSN=21363625
2024-07-18T14:25:45.925Z,1721312745.925 [Default:A.Wait](INFO): Done Waiting.
2024-07-18T14:25:45.925Z,1721312745.925 [Default:A.Wait] Stopped
2024-07-18T14:25:45.925Z,1721312745.925 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-07-18T14:25:45.992Z,1721312745.992 [CommandExec](IMPORTANT): got command strobe off
2024-07-18T14:25:45.992Z,1721312745.992 [CommandExec](IMPORTANT): Deactivating strobe
2024-07-18T14:25:46.321Z,1721312746.321 [Default:CheckIn] Running Loop=1
2024-07-18T14:25:46.321Z,1721312746.321 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-18T14:25:46.321Z,1721312746.321 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-18T14:25:46.730Z,1721312746.730 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2024-07-18T14:25:48.335Z,1721312748.335 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,142546.00,A,4538.82741,N,08428.35373,W,0.019,180.20,180724,,,A*72
2024-07-18T14:25:48.345Z,1721312748.345 [NAL9602](INFO): GPS fix at 20240718T142546: (45.647123, -84.472562)
2024-07-18T14:25:48.355Z,1721312748.355 [Default:CheckIn:Read_GPS] Stopped
2024-07-18T14:25:48.356Z,1721312748.356 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-18T14:25:48.773Z,1721312748.773 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-07-18T14:25:52.771Z,1721312752.771 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2024-07-18T14:25:59.250Z,1721312759.250 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2024-07-18T14:26:07.644Z,1721312767.644 [DataOverHttps](INFO): Sending 331 bytes from file Logs/20240718T142133/Courier0000.lzma
2024-07-18T14:26:08.405Z,1721312768.405 [CommandExec](IMPORTANT): got command failComponent
2024-07-18T14:26:08.405Z,1721312768.405 [CommandExec](IMPORTANT): Failed components:
2024-07-18T14:26:08.405Z,1721312768.405 [CommandExec](IMPORTANT): No failed Components.
2024-07-18T14:26:08.646Z,1721312768.646 [DataOverHttps](INFO): Moved sent file to Logs/20240718T142133/Courier0000.lzma.bak
2024-07-18T14:26:08.646Z,1721312768.646 [DataOverHttps](INFO): SBD MOMSN=21363627
2024-07-18T14:26:14.199Z,1721312774.199 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2024-07-18T14:26:25.118Z,1721312785.118 [NAL9602](INFO): SBD MO Status=2, MOMSN=5208, MT Status=2, MTMSN=0
2024-07-18T14:26:25.119Z,1721312785.119 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-07-18T14:26:27.043Z,1721312787.043 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240718T142133/Courier0004.lzma
2024-07-18T14:26:27.964Z,1721312787.964 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-07-18T14:26:28.046Z,1721312788.046 [DataOverHttps](INFO): Moved sent file to Logs/20240718T142133/Courier0004.lzma.bak
2024-07-18T14:26:28.046Z,1721312788.046 [DataOverHttps](INFO): SBD MOMSN=21363632
2024-07-18T14:26:42.497Z,1721312802.497 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2024-07-18T14:26:53.063Z,1721312813.063 [NAL9602](INFO): SBD MO Status=1, MOMSN=5208, MT Status=0, MTMSN=0
2024-07-18T14:26:53.063Z,1721312813.063 [NAL9602](INFO): No messages in MT queue
2024-07-18T14:26:54.632Z,1721312814.632 [DataOverHttps](INFO): Sending 215 bytes from file Logs/20240718T141724/Express0005.lzma
2024-07-18T14:26:55.634Z,1721312815.634 [DataOverHttps](INFO): Moved sent file to Logs/20240718T141724/Express0005.lzma.bak
2024-07-18T14:26:55.634Z,1721312815.634 [DataOverHttps](INFO): SBD MOMSN=21363635
2024-07-18T14:26:57.086Z,1721312817.086 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2024-07-18T14:27:07.725Z,1721312827.725 [CommandExec](IMPORTANT): got command burn off
2024-07-18T14:27:07.725Z,1721312827.725 [CommandExec](IMPORTANT): Deactivating dropweight wire
2024-07-18T14:27:11.226Z,1721312831.226 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2024-07-18T14:27:17.200Z,1721312837.200 [DataOverHttps](INFO): Sending 267 bytes from file Logs/20240718T141724/Express0008.lzma
2024-07-18T14:27:18.202Z,1721312838.202 [DataOverHttps](INFO): Moved sent file to Logs/20240718T141724/Express0008.lzma.bak
2024-07-18T14:27:18.202Z,1721312838.202 [DataOverHttps](INFO): SBD MOMSN=21363640
2024-07-18T14:27:23.486Z,1721312843.486 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-18T14:27:25.366Z,1721312845.366 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2024-07-18T14:27:35.253Z,1721312855.253 [CommandExec](IMPORTANT): got command restart system
2024-07-18T14:27:37.364Z,1721312857.364 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-18T14:27:37.364Z,1721312857.364 [CommandExec](INFO): Uninitializing the command executive.
2024-07-18T14:27:37.364Z,1721312857.364 [CommandExec](INFO): Uninitializing the command scheduler.
2024-07-18T14:27:37.365Z,1721312857.365 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-07-18T14:27:37.368Z,1721312857.368 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2024-07-18T14:27:37.368Z,1721312857.368 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2024-07-18T14:27:37.368Z,1721312857.368 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-07-18T14:27:37.369Z,1721312857.369 [NavChartDb](INFO): Join timeout helper Thread ID is 1151
2024-07-18T14:27:37.568Z,1721312857.568 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-18T14:27:37.568Z,1721312857.568 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-07-18T14:27:37.584Z,1721312857.584 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2024-07-18T14:27:37.584Z,1721312857.584 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-07-18T14:27:37.584Z,1721312857.584 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1152
2024-07-18T14:27:37.896Z,1721312857.896 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-18T14:27:37.896Z,1721312857.896 [WetLabsBB2FL](INFO): Powering down
2024-07-18T14:27:37.897Z,1721312857.897 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-07-18T14:27:37.904Z,1721312857.904 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2024-07-18T14:27:37.904Z,1721312857.904 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-07-18T14:27:37.904Z,1721312857.904 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1153
2024-07-18T14:27:38.528Z,1721312858.528 [CTD_Seabird](INFO): Powering down
2024-07-18T14:27:38.540Z,1721312858.540 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-18T14:27:38.540Z,1721312858.540 [CTD_Seabird](INFO): Powering down
2024-07-18T14:27:38.552Z,1721312858.552 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-07-18T14:27:38.568Z,1721312858.568 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2024-07-18T14:27:38.568Z,1721312858.568 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-07-18T14:27:38.568Z,1721312858.568 [Radio_Surface](INFO): Join timeout helper Thread ID is 1154
2024-07-18T14:27:38.924Z,1721312858.924 [Radio_Surface](INFO): Powering down
2024-07-18T14:27:38.925Z,1721312858.925 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-18T14:27:38.925Z,1721312858.925 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-07-18T14:27:38.932Z,1721312858.932 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2024-07-18T14:27:38.932Z,1721312858.932 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-07-18T14:27:38.932Z,1721312858.932 [Onboard](INFO): Join timeout helper Thread ID is 1155
2024-07-18T14:27:40.304Z,1721312860.304 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-18T14:27:40.304Z,1721312860.304 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-07-18T14:27:40.316Z,1721312860.316 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2024-07-18T14:27:40.337Z,1721312860.337 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2024-07-18T14:27:40.337Z,1721312860.337 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-07-18T14:27:40.337Z,1721312860.337 [DataOverHttps](INFO): Join timeout helper Thread ID is 1156
2024-07-18T14:27:43.345Z,1721312863.345 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-18T14:27:43.353Z,1721312863.353 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-07-18T14:27:43.368Z,1721312863.368 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2024-07-18T14:27:43.369Z,1721312863.369 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-07-18T14:27:43.369Z,1721312863.369 [BackseatComponent](INFO): Join timeout helper Thread ID is 1157
2024-07-18T14:27:43.516Z,1721312863.516 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-18T14:27:43.516Z,1721312863.516 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-07-18T14:27:43.533Z,1721312863.533 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2024-07-18T14:27:43.533Z,1721312863.533 [logger ThreadHandler](INFO): Thread cancelled.
2024-07-18T14:27:43.533Z,1721312863.533 [logger](INFO): Join timeout helper Thread ID is 1158
2024-07-18T14:27:43.556Z,1721312863.556 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-18T14:27:43.556Z,1721312863.556 [logger ThreadHandler](INFO): Thread cancelled.
2024-07-18T14:27:43.561Z,1721312863.561 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2024-07-18T14:27:43.561Z,1721312863.561 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-07-18T14:27:43.561Z,1721312863.561 [CommandLine](INFO): Join timeout helper Thread ID is 1159
2024-07-18T14:27:43.588Z,1721312863.588 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-18T14:27:43.588Z,1721312863.588 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-07-18T14:27:43.604Z,1721312863.604 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2024-07-18T14:27:43.605Z,1721312863.605 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-07-18T14:27:43.605Z,1721312863.605 [CommandExec](INFO): Join timeout helper Thread ID is 1160
2024-07-18T14:27:43.606Z,1721312863.606 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2024-07-18T14:27:43.606Z,1721312863.606 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-07-18T14:27:43.607Z,1721312863.607 [controlThread](INFO): Join timeout helper Thread ID is 1161
2024-07-18T14:27:43.936Z,1721312863.936 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-18T14:27:43.936Z,1721312863.936 [controlThread](DEBUG): Uninitializing ControlThread
2024-07-18T14:27:43.937Z,1721312863.937 [AHRS_M2](INFO): Powering down
2024-07-18T14:27:44.009Z,1721312864.009 [NAL9602](INFO): Powering down
2024-07-18T14:27:44.011Z,1721312864.011 [Sonardyne_Nano](INFO): Powering down
2024-07-18T14:27:44.129Z,1721312864.129 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2024-07-18T14:27:44.129Z,1721312864.129 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2024-07-18T14:27:44.130Z,1721312864.130 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2024-07-18T14:27:44.130Z,1721312864.130 [MissionManager](INFO): Uninitializing Mission Default
2024-07-18T14:27:44.130Z,1721312864.130 [Default] Stopped
2024-07-18T14:27:44.131Z,1721312864.131 [Default](DEBUG): Aggregate::uninitialize Default
2024-07-18T14:27:44.131Z,1721312864.131 [Default:B.GoToSurface] Stopped
2024-07-18T14:27:44.131Z,1721312864.131 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-07-18T14:27:44.131Z,1721312864.131 [Default:CheckIn] Stopped
2024-07-18T14:27:44.131Z,1721312864.131 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-18T14:27:44.131Z,1721312864.131 [Default:CheckIn:Read_Iridium] Stopped
2024-07-18T14:27:44.134Z,1721312864.134 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2024-07-18T14:27:44.134Z,1721312864.134 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2024-07-18T14:27:44.134Z,1721312864.134 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2024-07-18T14:27:44.135Z,1721312864.135 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2024-07-18T14:27:44.135Z,1721312864.135 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2024-07-18T14:27:44.135Z,1721312864.135 [BuoyancyServo](INFO): Powering down
2024-07-18T14:27:44.148Z,1721312864.148 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2024-07-18T14:27:44.148Z,1721312864.148 [ElevatorServo](INFO): Powering down
2024-07-18T14:27:44.149Z,1721312864.149 [MassServo](DEBUG): Uninitialize Mass Servo.
2024-07-18T14:27:44.149Z,1721312864.149 [MassServo](INFO): Powering down
2024-07-18T14:27:44.150Z,1721312864.150 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2024-07-18T14:27:44.150Z,1721312864.150 [RudderServo](INFO): Powering down
2024-07-18T14:27:44.151Z,1721312864.151 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2024-07-18T14:27:44.151Z,1721312864.151 [ThrusterHE](INFO): Powering down
2024-07-18T14:27:44.152Z,1721312864.152 [SBIT](DEBUG): Uninitialize SBIT Component.
2024-07-18T14:27:44.152Z,1721312864.152 [IBIT](DEBUG): Uninitialize IBIT Component.
2024-07-18T14:27:44.153Z,1721312864.153 [CBIT](DEBUG): Uninitialize CBIT Component.
2024-07-18T14:27:44.153Z,1721312864.153 [CBIT](DEBUG): Powering off loads.
2024-07-18T14:27:44.164Z,1721312864.164 [CBIT](DEBUG): Disabling WDT.
2024-07-18T14:27:44.176Z,1721312864.176 [CBIT](DEBUG): Opening all GF detection circuits.
2024-07-18T14:27:44.177Z,1721312864.177 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-07-18T14:27:44.228Z,1721312864.228 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-07-18T14:27:44.231Z,1721312864.231 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-07-18T14:27:44.237Z,1721312864.237 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-07-18T14:27:44.283Z,1721312864.283 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-07-18T14:27:44.325Z,1721312864.325 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-07-18T14:27:44.330Z,1721312864.330 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-07-18T14:27:44.365Z,1721312864.365 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-07-18T14:27:44.414Z,1721312864.414 [logger ThreadHandler](INFO): Thread cancelled.