2023-09-08T13:49:02.211Z,1694180942.211 [Supervisor](DEBUG): Initializing supervisor.
2023-09-08T13:49:02.216Z,1694180942.216 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-09-08T13:49:02.216Z,1694180942.216 [SyncHandler](INFO): Protected caller Thread ID is 1613
2023-09-08T13:49:02.217Z,1694180942.217 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-09-08T13:49:02.218Z,1694180942.218 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-09-08T13:49:02.218Z,1694180942.218 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1614
2023-09-08T13:49:02.222Z,1694180942.222 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-09-08T13:49:02.239Z,1694180942.239 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-09-08T13:49:02.240Z,1694180942.240 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-09-08T13:49:02.240Z,1694180942.240 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1615
2023-09-08T13:49:02.245Z,1694180942.245 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-09-08T13:49:02.246Z,1694180942.246 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-09-08T13:49:02.246Z,1694180942.246 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1616
2023-09-08T13:49:02.248Z,1694180942.248 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-09-08T13:49:02.249Z,1694180942.249 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-09-08T13:49:02.249Z,1694180942.249 [logger ThreadHandler](INFO): Protected caller Thread ID is 1617
2023-09-08T13:49:02.253Z,1694180942.253 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-09-08T13:49:02.253Z,1694180942.253 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-09-08T13:49:02.255Z,1694180942.255 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-09-08T13:49:02.703Z,1694180942.703 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-09-08T13:49:02.704Z,1694180942.704 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-09-08T13:49:02.907Z,1694180942.907 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-09-08T13:49:02.907Z,1694180942.907 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-09-08T13:49:02.988Z,1694180942.988 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-09-08T13:49:03.274Z,1694180943.274 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-09-08T13:49:03.274Z,1694180943.274 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-09-08T13:49:03.403Z,1694180943.403 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-09-08T13:49:03.633Z,1694180943.633 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-09-08T13:49:03.634Z,1694180943.634 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-09-08T13:49:03.981Z,1694180943.981 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-09-08T13:49:03.981Z,1694180943.981 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-09-08T13:49:04.112Z,1694180944.112 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-09-08T13:49:04.113Z,1694180944.113 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-09-08T13:49:04.588Z,1694180944.588 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-09-08T13:49:04.588Z,1694180944.588 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-09-08T13:49:04.778Z,1694180944.778 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-09-08T13:49:04.778Z,1694180944.778 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-09-08T13:49:05.187Z,1694180945.187 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-09-08T13:49:05.187Z,1694180945.187 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-09-08T13:49:05.474Z,1694180945.474 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-09-08T13:49:05.475Z,1694180945.475 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-09-08T13:49:05.585Z,1694180945.585 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-09-08T13:49:05.585Z,1694180945.585 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-09-08T13:49:06.346Z,1694180946.346 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-09-08T13:49:06.347Z,1694180946.347 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-09-08T13:49:06.669Z,1694180946.669 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-09-08T13:49:06.669Z,1694180946.669 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-09-08T13:49:06.897Z,1694180946.897 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-09-08T13:49:06.899Z,1694180946.899 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2023-09-08T13:49:06.900Z,1694180946.900 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2023-09-08T13:49:07.019Z,1694180947.019 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2023-09-08T13:49:07.102Z,1694180947.102 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2023-09-08T13:49:07.236Z,1694180947.236 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2023-09-08T13:49:07.322Z,1694180947.322 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2023-09-08T13:49:07.586Z,1694180947.586 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-09-08T13:49:07.586Z,1694180947.586 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2023-09-08T13:49:07.689Z,1694180947.689 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2023-09-08T13:49:07.794Z,1694180947.794 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2023-09-08T13:49:07.911Z,1694180947.911 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2023-09-08T13:49:08.056Z,1694180948.056 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2023-09-08T13:49:08.155Z,1694180948.155 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2023-09-08T13:49:08.254Z,1694180948.254 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/root/
2023-09-08T13:49:08.254Z,1694180948.254 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-09-08T13:49:08.313Z,1694180948.313 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-09-08T13:49:08.325Z,1694180948.325 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-09-08T13:49:08.326Z,1694180948.326 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-09-08T13:49:08.582Z,1694180948.582 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-09-08T13:49:08.583Z,1694180948.583 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-09-08T13:49:08.644Z,1694180948.644 [VerticalControl](DEBUG): Construct VerticalControl.
2023-09-08T13:49:08.703Z,1694180948.703 [VerticalControl] Loaded
2023-09-08T13:49:08.703Z,1694180948.703 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-09-08T13:49:08.706Z,1694180948.706 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-09-08T13:49:08.749Z,1694180948.749 [HorizontalControl] Loaded
2023-09-08T13:49:08.749Z,1694180948.749 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-09-08T13:49:08.754Z,1694180948.754 [SpeedControl](DEBUG): Construct SpeedControl.
2023-09-08T13:49:08.758Z,1694180948.758 [SpeedControl] Loaded
2023-09-08T13:49:08.758Z,1694180948.758 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-09-08T13:49:08.761Z,1694180948.761 [LoopControl](DEBUG): Construct LoopControl.
2023-09-08T13:49:08.761Z,1694180948.761 [LoopControl] Loaded
2023-09-08T13:49:08.761Z,1694180948.761 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-09-08T13:49:08.762Z,1694180948.762 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-09-08T13:49:08.762Z,1694180948.762 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-09-08T13:49:08.916Z,1694180948.916 [BuoyancyServo] Loaded
2023-09-08T13:49:08.917Z,1694180948.917 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-09-08T13:49:08.936Z,1694180948.936 [ElevatorServo] Loaded
2023-09-08T13:49:08.937Z,1694180948.937 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-09-08T13:49:08.956Z,1694180948.956 [MassServo] Loaded
2023-09-08T13:49:08.956Z,1694180948.956 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-09-08T13:49:08.974Z,1694180948.974 [RudderServo] Loaded
2023-09-08T13:49:08.974Z,1694180948.974 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-09-08T13:49:08.989Z,1694180948.989 [ThrusterHE] Loaded
2023-09-08T13:49:08.989Z,1694180948.989 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2023-09-08T13:49:08.989Z,1694180948.989 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-09-08T13:49:08.990Z,1694180948.990 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-09-08T13:49:09.039Z,1694180949.039 [DepthRateCalculator] Loaded
2023-09-08T13:49:09.039Z,1694180949.039 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-09-08T13:49:09.044Z,1694180949.044 [PitchRateCalculator] Loaded
2023-09-08T13:49:09.044Z,1694180949.044 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-09-08T13:49:09.054Z,1694180949.054 [SpeedCalculator] Loaded
2023-09-08T13:49:09.054Z,1694180949.054 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-09-08T13:49:09.058Z,1694180949.058 [YawRateCalculator] Loaded
2023-09-08T13:49:09.058Z,1694180949.058 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-09-08T13:49:09.077Z,1694180949.077 [ElevatorOffsetCalculator] Loaded
2023-09-08T13:49:09.077Z,1694180949.077 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-09-08T13:49:09.078Z,1694180949.078 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-09-08T13:49:09.078Z,1694180949.078 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-09-08T13:49:09.190Z,1694180949.190 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-09-08T13:49:09.190Z,1694180949.190 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-09-08T13:49:09.203Z,1694180949.203 [NavChart] Loaded
2023-09-08T13:49:09.204Z,1694180949.204 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-09-08T13:49:09.209Z,1694180949.209 [UniversalFixResidualReporter] Loaded
2023-09-08T13:49:09.210Z,1694180949.210 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-09-08T13:49:09.210Z,1694180949.210 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-09-08T13:49:09.211Z,1694180949.211 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-09-08T13:49:09.629Z,1694180949.629 [AHRS_M2] Loaded
2023-09-08T13:49:09.629Z,1694180949.629 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-09-08T13:49:09.665Z,1694180949.665 [BackseatComponent] Loaded
2023-09-08T13:49:09.665Z,1694180949.665 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2023-09-08T13:49:09.666Z,1694180949.666 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408E84E0
2023-09-08T13:49:09.667Z,1694180949.667 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1699
2023-09-08T13:49:09.669Z,1694180949.669 [LcmUniversalReporter] Loaded
2023-09-08T13:49:09.670Z,1694180949.670 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2023-09-08T13:49:09.676Z,1694180949.676 [BioacousticsDataBridge] Loaded
2023-09-08T13:49:09.676Z,1694180949.676 [ComponentRegistry](DEBUG): SyncComponent "BioacousticsDataBridge" handled in the control thread.
2023-09-08T13:49:10.504Z,1694180950.504 [BPC1] Loaded
2023-09-08T13:49:10.505Z,1694180950.505 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-09-08T13:49:10.580Z,1694180950.580 [DataOverHttps] Loaded
2023-09-08T13:49:10.580Z,1694180950.580 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-09-08T13:49:10.581Z,1694180950.581 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409184E0
2023-09-08T13:49:10.582Z,1694180950.582 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1700
2023-09-08T13:49:10.602Z,1694180950.602 [Depth_Keller] Loaded
2023-09-08T13:49:10.603Z,1694180950.603 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-09-08T13:49:10.608Z,1694180950.608 [DropWeight] Loaded
2023-09-08T13:49:10.608Z,1694180950.608 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2023-09-08T13:49:10.670Z,1694180950.670 [NAL9602] Loaded
2023-09-08T13:49:10.670Z,1694180950.670 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-09-08T13:49:10.700Z,1694180950.700 [Onboard] Loaded
2023-09-08T13:49:10.700Z,1694180950.700 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-09-08T13:49:10.701Z,1694180950.701 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409484E0
2023-09-08T13:49:10.701Z,1694180950.701 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1701
2023-09-08T13:49:10.713Z,1694180950.713 [Power24vConverter] Loaded
2023-09-08T13:49:10.714Z,1694180950.714 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-09-08T13:49:10.727Z,1694180950.727 [Radio_Surface] Loaded
2023-09-08T13:49:10.727Z,1694180950.727 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-09-08T13:49:10.728Z,1694180950.728 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409784E0
2023-09-08T13:49:10.729Z,1694180950.729 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1702
2023-09-08T13:49:10.742Z,1694180950.742 [Sonardyne_Nano] Loaded
2023-09-08T13:49:10.742Z,1694180950.742 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2023-09-08T13:49:10.743Z,1694180950.743 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-09-08T13:49:10.743Z,1694180950.743 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-09-08T13:49:10.799Z,1694180950.799 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-09-08T13:49:10.799Z,1694180950.799 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-09-08T13:49:10.945Z,1694180950.945 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-09-08T13:49:10.946Z,1694180950.946 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-09-08T13:49:10.972Z,1694180950.972 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-09-08T13:49:10.972Z,1694180950.972 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-09-08T13:49:11.142Z,1694180951.142 [CTD_Seabird] Loaded
2023-09-08T13:49:11.142Z,1694180951.142 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-09-08T13:49:11.143Z,1694180951.143 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40AAF4E0
2023-09-08T13:49:11.144Z,1694180951.144 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1703
2023-09-08T13:49:11.163Z,1694180951.163 [PAR_Licor] Loaded
2023-09-08T13:49:11.164Z,1694180951.164 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-09-08T13:49:11.204Z,1694180951.204 [WetLabsBB2FL] Loaded
2023-09-08T13:49:11.204Z,1694180951.204 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-09-08T13:49:11.205Z,1694180951.205 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40ADF4E0
2023-09-08T13:49:11.206Z,1694180951.206 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1704
2023-09-08T13:49:11.206Z,1694180951.206 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-09-08T13:49:11.207Z,1694180951.207 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-09-08T13:49:11.511Z,1694180951.511 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-09-08T13:49:11.512Z,1694180951.512 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-09-08T13:49:11.649Z,1694180951.649 [SBIT](DEBUG): Construct Startup Built In Test.
2023-09-08T13:49:11.658Z,1694180951.658 [SBIT] Loaded
2023-09-08T13:49:11.659Z,1694180951.659 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-09-08T13:49:11.661Z,1694180951.661 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-09-08T13:49:11.675Z,1694180951.675 [IBIT] Loaded
2023-09-08T13:49:11.675Z,1694180951.675 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-09-08T13:49:11.681Z,1694180951.681 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-09-08T13:49:11.785Z,1694180951.785 [CBIT] Loaded
2023-09-08T13:49:11.785Z,1694180951.785 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-09-08T13:49:11.785Z,1694180951.785 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-09-08T13:49:11.792Z,1694180951.792 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-09-08T13:49:11.795Z,1694180951.795 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-09-08T13:49:11.806Z,1694180951.806 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-09-08T13:49:11.807Z,1694180951.807 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BCB4E0
2023-09-08T13:49:11.807Z,1694180951.807 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1705
2023-09-08T13:49:11.811Z,1694180951.811 [Supervisor](INFO): Main Thread ID is 836
2023-09-08T13:49:11.812Z,1694180951.812 [Supervisor](DEBUG): Running supervisor.
2023-09-08T13:49:11.812Z,1694180951.812 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1706
2023-09-08T13:49:11.813Z,1694180951.813 [CommandExec](INFO): Initializing the command executive.
2023-09-08T13:49:11.814Z,1694180951.814 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1707
2023-09-08T13:49:11.818Z,1694180951.818 [controlThread ThreadHandler](INFO): Handler Thread ID is 1708
2023-09-08T13:49:11.818Z,1694180951.818 [controlThread](DEBUG): Initializing ControlThread
2023-09-08T13:49:11.819Z,1694180951.819 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-09-08T13:49:11.821Z,1694180951.821 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-09-08T13:49:11.822Z,1694180951.822 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-09-08T13:49:11.822Z,1694180951.822 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-09-08T13:49:11.823Z,1694180951.823 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-09-08T13:49:11.824Z,1694180951.824 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-09-08T13:49:11.824Z,1694180951.824 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-09-08T13:49:11.825Z,1694180951.825 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-09-08T13:49:11.825Z,1694180951.825 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-09-08T13:49:11.826Z,1694180951.826 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-09-08T13:49:11.827Z,1694180951.827 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-09-08T13:49:11.833Z,1694180951.833 [SBIT](INFO): Initialize SBIT Component.
2023-09-08T13:49:11.833Z,1694180951.833 [SBIT](IMPORTANT): git: 2023-09-07
2023-09-08T13:49:11.834Z,1694180951.834 [SBIT](INFO): git hash: de73b65bba9e058a7fe4bf2bc7b950ee8b764c06
2023-09-08T13:49:11.834Z,1694180951.834 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-09-08T13:49:11.835Z,1694180951.835 [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
2023-09-08T13:49:11.836Z,1694180951.836 [SBIT](INFO): Beginning SBIT in 122.000000 seconds.
2023-09-08T13:49:11.837Z,1694180951.837 [IBIT](INFO): Initialize IBIT Component.
2023-09-08T13:49:11.838Z,1694180951.838 [CBIT](DEBUG): Initialize CBIT Component.
2023-09-08T13:49:11.839Z,1694180951.839 [logger ThreadHandler](INFO): Handler Thread ID is 1709
2023-09-08T13:49:11.849Z,1694180951.849 [CBIT](DEBUG): Initialized mux pins.
2023-09-08T13:49:11.849Z,1694180951.849 [CBIT](DEBUG): Initializing the watchdog timer.
2023-09-08T13:49:11.857Z,1694180951.857 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1710
2023-09-08T13:49:11.869Z,1694180951.869 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1711
2023-09-08T13:49:11.870Z,1694180951.870 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-09-08T13:49:11.873Z,1694180951.873 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-09-08T13:49:11.873Z,1694180951.873 [CBIT](DEBUG): Initializing heartbeat.
2023-09-08T13:49:11.881Z,1694180951.881 [Onboard ThreadHandler](INFO): Handler Thread ID is 1712
2023-09-08T13:49:11.899Z,1694180951.899 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1713
2023-09-08T13:49:11.921Z,1694180951.921 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1714
2023-09-08T13:49:11.922Z,1694180951.922 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-09-08T13:49:11.926Z,1694180951.926 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1716
2023-09-08T13:49:11.929Z,1694180951.929 [WetLabsBB2FL](INFO): Powering up
2023-09-08T13:49:11.930Z,1694180951.930 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1718
2023-09-08T13:49:11.932Z,1694180951.932 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI01M.000
2023-09-08T13:49:11.934Z,1694180951.934 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US2MI01M.000
2023-09-08T13:49:11.934Z,1694180951.934 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI80M.000
2023-09-08T13:49:11.935Z,1694180951.935 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US2MI80M.000
2023-09-08T13:49:11.935Z,1694180951.935 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI57M.000
2023-09-08T13:49:11.937Z,1694180951.937 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4MI57M.000
2023-09-08T13:49:11.938Z,1694180951.938 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI56M.000
2023-09-08T13:49:11.939Z,1694180951.939 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4MI56M.000
2023-09-08T13:49:11.939Z,1694180951.939 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI90M.000
2023-09-08T13:49:11.941Z,1694180951.941 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4MI90M.000
2023-09-08T13:49:11.941Z,1694180951.941 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI89M.000
2023-09-08T13:49:11.942Z,1694180951.942 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4MI89M.000
2023-09-08T13:49:11.943Z,1694180951.943 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI03M.000
2023-09-08T13:49:11.944Z,1694180951.944 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4WI03M.000
2023-09-08T13:49:11.944Z,1694180951.944 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI06M.000
2023-09-08T13:49:11.945Z,1694180951.945 [CBIT](DEBUG): Deactivating GF circuits.
2023-09-08T13:49:11.945Z,1694180951.945 [CBIT](DEBUG): Deactivating emergency mode.
2023-09-08T13:49:11.950Z,1694180951.950 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4WI06M.000
2023-09-08T13:49:11.950Z,1694180951.950 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4OH01M.000
2023-09-08T13:49:11.952Z,1694180951.952 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4OH01M.000
2023-09-08T13:49:11.952Z,1694180951.952 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4OH04M.000
2023-09-08T13:49:11.958Z,1694180951.958 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4OH04M.000
2023-09-08T13:49:11.958Z,1694180951.958 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4OH02M.000
2023-09-08T13:49:11.960Z,1694180951.960 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4OH02M.000
2023-09-08T13:49:11.960Z,1694180951.960 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4PA20M.000
2023-09-08T13:49:11.962Z,1694180951.962 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4PA20M.000
2023-09-08T13:49:11.962Z,1694180951.962 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4PA21M.000
2023-09-08T13:49:11.964Z,1694180951.964 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4PA21M.000
2023-09-08T13:49:11.977Z,1694180951.977 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US4PA21M.000
2023-09-08T13:49:11.985Z,1694180951.985 [CBIT](DEBUG): Backplane powered.
2023-09-08T13:49:11.986Z,1694180951.986 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-09-08T13:49:11.998Z,1694180951.998 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-09-08T13:49:12.028Z,1694180952.028 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="StartupSatComms">

        <Sequence/>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2023-09-08T13:49:12.028Z,1694180952.028 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-09-08T13:49:12.078Z,1694180952.078 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-09-08T13:49:12.079Z,1694180952.079 [Default:A.Wait](DEBUG): Construct Wait.
2023-09-08T13:49:12.126Z,1694180952.126 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-09-08T13:49:12.157Z,1694180952.157 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-09-08T13:49:12.160Z,1694180952.160 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-09-08T13:49:12.174Z,1694180952.174 [Default:E.Execute](DEBUG): Construct Execute.
2023-09-08T13:49:12.177Z,1694180952.177 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

    <DefineArg Name="ElapsedSinceDefaultStarted"><Units:minute/><Value>0</Value></DefineArg>

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P4H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn on</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

    <Guidance:Execute>
        <Sequence/>
        <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
    </Guidance:Execute>

</Mission>


2023-09-08T13:49:12.213Z,1694180952.213 [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,
2023-09-08T13:49:12.227Z,1694180952.227 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-09-08T13:49:12.300Z,1694180952.300 [Power24vConverter](INFO): Powering up.
2023-09-08T13:49:12.305Z,1694180952.305 [Radio_Surface](INFO): Powering up
2023-09-08T13:49:12.305Z,1694180952.305 [Sonardyne_Nano](INFO): Initializing.
2023-09-08T13:49:12.379Z,1694180952.379 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-09-08T13:49:12.385Z,1694180952.385 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-09-08T13:49:12.386Z,1694180952.386 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-09-08T13:49:12.409Z,1694180952.409 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-09-08T13:49:12.410Z,1694180952.410 [MassServo](DEBUG): Initializing EZServoServo.
2023-09-08T13:49:12.417Z,1694180952.417 [MassServo](DEBUG): Initializing MassServo.
2023-09-08T13:49:12.418Z,1694180952.418 [RudderServo](DEBUG): Initializing EZServoServo.
2023-09-08T13:49:12.425Z,1694180952.425 [RudderServo](DEBUG): Initializing RudderServo.
2023-09-08T13:49:12.426Z,1694180952.426 [ThrusterHE](DEBUG): Initializing EZServoServo.
2023-09-08T13:49:12.433Z,1694180952.433 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2023-09-08T13:49:13.459Z,1694180953.459 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US4PA21M.000
2023-09-08T13:49:14.033Z,1694180954.033 [WetLabsBB2FL](INFO): Powering down
2023-09-08T13:49:14.243Z,1694180954.243 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2023-09-08T13:49:15.530Z,1694180955.530 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2201
2023-09-08T13:49:18.122Z,1694180958.122 [ThrusterHE](ERROR): Zero Speed Commanded.
2023-09-08T13:49:18.953Z,1694180958.953 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2023-09-08T13:49:29.083Z,1694180969.083 [NavChartDb](INFO): # of records loaded: 5000
2023-09-08T13:49:37.899Z,1694180977.899 [NavChartDb](INFO): # of records loaded: 10000
2023-09-08T13:49:40.674Z,1694180980.674 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US4PA21M.000
2023-09-08T13:49:40.914Z,1694180980.914 [NAL9602](INFO): Powering up NAL9602
2023-09-08T13:49:45.721Z,1694180985.721 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US4PA21M.000
2023-09-08T13:49:47.021Z,1694180987.021 [NavChartDb](INFO): # of records loaded: 15000
2023-09-08T13:49:50.047Z,1694180990.047 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US4PA21M.000
2023-09-08T13:49:50.541Z,1694180990.541 [NavChartDb](INFO): # of records loaded: 16189
2023-09-08T13:49:50.543Z,1694180990.543 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US4PA21M.000
2023-09-08T13:49:50.544Z,1694180990.544 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US4PA20M.000
2023-09-08T13:49:50.586Z,1694180990.586 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US4PA20M.000
2023-09-08T13:49:50.780Z,1694180990.780 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US4PA20M.000
2023-09-08T13:49:50.782Z,1694180990.782 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US4PA20M.000
2023-09-08T13:49:50.820Z,1694180990.820 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US4PA20M.000
2023-09-08T13:49:51.192Z,1694180991.192 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US4PA20M.000
2023-09-08T13:49:51.606Z,1694180991.606 [NavChartDb](INFO): # of records loaded: 262
2023-09-08T13:49:51.608Z,1694180991.608 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US4PA20M.000
2023-09-08T13:49:51.609Z,1694180991.609 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US4OH02M.000
2023-09-08T13:49:51.831Z,1694180991.831 [NAL9602](INFO): NAL9602 initialized
2023-09-08T13:49:52.574Z,1694180992.574 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US4OH02M.000
2023-09-08T13:50:02.665Z,1694181002.665 [NavChartDb](INFO): # of records loaded: 5000
2023-09-08T13:50:07.196Z,1694181007.196 [NAL9602](INFO): SBD MO Status=0, MOMSN=3497, MT Status=0, MTMSN=0
2023-09-08T13:50:07.196Z,1694181007.196 [NAL9602](INFO): No messages in MT queue
2023-09-08T13:50:08.409Z,1694181008.409 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,135006.00,A,4145.52973,N,08116.65076,W,0.564,203.32,080923,,,A*78
2023-09-08T13:50:08.413Z,1694181008.413 [NAL9602](INFO): GPS fix at 20230908T135006: (41.758829, -81.277513)
2023-09-08T13:50:09.067Z,1694181009.067 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US4OH02M.000
2023-09-08T13:50:09.473Z,1694181009.473 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US4OH02M.000
2023-09-08T13:50:11.970Z,1694181011.970 [NavChartDb](INFO): # of records loaded: 10000
2023-09-08T13:50:13.497Z,1694181013.497 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US4OH02M.000
2023-09-08T13:50:15.206Z,1694181015.206 [NavChartDb](INFO): # of records loaded: 10534
2023-09-08T13:50:15.209Z,1694181015.209 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US4OH02M.000
2023-09-08T13:50:15.210Z,1694181015.210 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US4OH04M.000
2023-09-08T13:50:15.251Z,1694181015.251 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US4OH04M.000
2023-09-08T13:50:15.544Z,1694181015.544 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US4OH04M.000
2023-09-08T13:50:15.548Z,1694181015.548 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US4OH04M.000
2023-09-08T13:50:15.549Z,1694181015.549 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US4OH04M.000
2023-09-08T13:50:15.565Z,1694181015.565 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US4OH04M.000
2023-09-08T13:50:16.273Z,1694181016.273 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US4OH04M.000
2023-09-08T13:50:17.666Z,1694181017.666 [NavChartDb](INFO): # of records loaded: 473
2023-09-08T13:50:17.668Z,1694181017.668 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US4OH04M.000
2023-09-08T13:50:17.669Z,1694181017.669 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US4OH01M.000
2023-09-08T13:50:19.073Z,1694181019.073 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US4OH01M.000
2023-09-08T13:50:28.158Z,1694181028.158 [NavChartDb](INFO): # of records loaded: 5000
2023-09-08T13:50:31.689Z,1694181031.689 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US4OH01M.000
2023-09-08T13:50:34.880Z,1694181034.880 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US4OH01M.000
2023-09-08T13:50:37.855Z,1694181037.855 [NavChartDb](INFO): # of records loaded: 10000
2023-09-08T13:50:38.459Z,1694181038.459 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US4OH01M.000
2023-09-08T13:50:40.146Z,1694181040.146 [NavChartDb](INFO): # of records loaded: 10183
2023-09-08T13:50:40.149Z,1694181040.149 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US4OH01M.000
2023-09-08T13:50:40.150Z,1694181040.150 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US4WI06M.000
2023-09-08T13:50:40.322Z,1694181040.322 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T13:50:44.030Z,1694181044.030 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US4WI06M.000
2023-09-08T13:50:52.409Z,1694181052.409 [NavChartDb](INFO): # of records loaded: 5000
2023-09-08T13:51:00.816Z,1694181060.816 [NavChartDb](INFO): # of records loaded: 10000
2023-09-08T13:51:11.246Z,1694181071.246 [NavChartDb](INFO): # of records loaded: 15000
2023-09-08T13:51:14.338Z,1694181074.338 [SBIT](IMPORTANT): Beginning Startup BIT
2023-09-08T13:51:14.343Z,1694181074.343 [CBIT](IMPORTANT): Beginning ground fault scan
2023-09-08T13:51:20.339Z,1694181080.339 [NavChartDb](INFO): # of records loaded: 20000
2023-09-08T13:51:25.239Z,1694181085.239 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006830
CHAN A1 (24V): -0.007651
CHAN A2 (12V): -0.004728
CHAN A3 (5V): -0.002748
CHAN B0 (3.3V): -0.000692
CHAN B1 (3.15aV): -0.001362
CHAN B2 (3.15bV): -0.001272
CHAN B3 (GND): -0.000736
OPEN: 0.003544
Full Scale: +/- 1 mA
2023-09-08T13:51:27.859Z,1694181087.859 [NavChartDb](INFO): # of records loaded: 25000
2023-09-08T13:51:37.121Z,1694181097.121 [NavChartDb](INFO): # of records loaded: 30000
2023-09-08T13:51:45.203Z,1694181105.203 [NavChartDb](INFO): # of records loaded: 35000
2023-09-08T13:51:53.551Z,1694181113.551 [NavChartDb](INFO): # of records loaded: 40000
2023-09-08T13:51:56.865Z,1694181116.865 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US4WI06M.000
2023-09-08T13:52:02.170Z,1694181122.170 [NavChartDb](INFO): # of records loaded: 45000
2023-09-08T13:52:08.562Z,1694181128.562 [SBIT](IMPORTANT): SBIT PASSED
2023-09-08T13:52:08.562Z,1694181128.562 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-09-08T13:52:08.563Z,1694181128.563 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=6 count;

2023-09-08T13:52:08.563Z,1694181128.563 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=10 minute;

2023-09-08T13:52:08.563Z,1694181128.563 [SBIT](IMPORTANT): Express linearApproximation BackseatComponent.handled_message 1.000000 bool;

2023-09-08T13:52:08.563Z,1694181128.563 [SBIT](IMPORTANT): Express linearApproximation CTD_Seabird.bin_mean_sea_water_temperature 5.000000 celsius;

2023-09-08T13:52:08.563Z,1694181128.563 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_salinity;

2023-09-08T13:52:08.564Z,1694181128.564 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water;

2023-09-08T13:52:08.564Z,1694181128.564 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 50.000000 meter;

2023-09-08T13:52:08.564Z,1694181128.564 [SBIT](IMPORTANT): Express none platform_roll_angle;

2023-09-08T13:52:08.564Z,1694181128.564 [SBIT](IMPORTANT): NAL9602.fastGPSFix=1 bool;

2023-09-08T13:52:08.564Z,1694181128.564 [SBIT](IMPORTANT): NAL9602.iridiumMTQueueTimeout=5 minute;

2023-09-08T13:52:08.564Z,1694181128.564 [SBIT](IMPORTANT): Sonardyne_Nano.loadAtStartup=1 bool;

2023-09-08T13:52:08.564Z,1694181128.564 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=391.543020 cubic_centimeter;

2023-09-08T13:52:08.614Z,1694181128.614 [SBIT](IMPORTANT): VerticalControl.elevDeadband=0.1 angular_degree;

2023-09-08T13:52:08.614Z,1694181128.614 [SBIT](IMPORTANT): VerticalControl.massDefault=25.724938 millimeter;

2023-09-08T13:52:08.878Z,1694181128.878 [MissionManager](IMPORTANT): Started mission Startup
2023-09-08T13:52:08.878Z,1694181128.878 [Startup] Running Loop=1
2023-09-08T13:52:08.878Z,1694181128.878 [Startup](DEBUG): Aggregate::initialize Startup
2023-09-08T13:52:08.878Z,1694181128.878 [Startup:A.GoToSurface] Running Loop=1
2023-09-08T13:52:08.878Z,1694181128.878 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-09-08T13:52:08.879Z,1694181128.879 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-09-08T13:52:08.879Z,1694181128.879 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-09-08T13:52:08.880Z,1694181128.880 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-09-08T13:52:08.880Z,1694181128.880 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-09-08T13:52:08.880Z,1694181128.880 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-09-08T13:52:08.894Z,1694181128.894 [Startup:StartupSatComms] Running Loop=1
2023-09-08T13:52:08.894Z,1694181128.894 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-09-08T13:52:08.894Z,1694181128.894 [Startup:StartupSatComms:A] Running Loop=1
2023-09-08T13:52:09.258Z,1694181129.258 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-09-08T13:52:10.820Z,1694181130.820 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,135209.00,A,4145.53014,N,08116.65079,W,0.039,189.33,080923,,,D*7B
2023-09-08T13:52:10.822Z,1694181130.822 [NAL9602](INFO): GPS fix at 20230908T135209: (41.758836, -81.277513)
2023-09-08T13:52:10.848Z,1694181130.848 [Startup:StartupSatComms:A] Stopped
2023-09-08T13:52:10.848Z,1694181130.848 [Startup:StartupSatComms:B] Running Loop=1
2023-09-08T13:52:11.293Z,1694181131.293 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-09-08T13:52:11.915Z,1694181131.915 [NavChartDb](INFO): # of records loaded: 50000
2023-09-08T13:52:23.362Z,1694181143.362 [NavChartDb](INFO): # of records loaded: 55000
2023-09-08T13:52:37.890Z,1694181157.890 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US4WI06M.000
2023-09-08T13:52:47.539Z,1694181167.539 [NavChartDb](INFO): # of records loaded: 60000
2023-09-08T13:52:55.150Z,1694181175.150 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002981
2023-09-08T13:53:11.147Z,1694181191.147 [Startup:StartupSatComms:B](INFO): Timed out from 2023-09-08T13:52:10.8Z
2023-09-08T13:53:11.147Z,1694181191.147 [Startup:StartupSatComms:B] Stopped
2023-09-08T13:53:11.147Z,1694181191.147 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-09-08T13:53:11.148Z,1694181191.148 [Startup:StartupSatComms] Stopped
2023-09-08T13:53:11.148Z,1694181191.148 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-09-08T13:53:11.148Z,1694181191.148 [Startup](INFO): Completed Startup
2023-09-08T13:53:11.205Z,1694181191.205 [MissionManager](INFO): Startup is completed.
2023-09-08T13:53:11.205Z,1694181191.205 [MissionManager](INFO): Uninitializing Mission Startup
2023-09-08T13:53:11.205Z,1694181191.205 [Startup] Stopped
2023-09-08T13:53:11.205Z,1694181191.205 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-09-08T13:53:11.205Z,1694181191.205 [Startup:A.GoToSurface] Stopped
2023-09-08T13:53:11.205Z,1694181191.205 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-09-08T13:53:11.595Z,1694181191.595 [MissionManager](IMPORTANT): Started mission Default
2023-09-08T13:53:11.595Z,1694181191.595 [Default] Running Loop=1
2023-09-08T13:53:11.596Z,1694181191.596 [Default](DEBUG): Aggregate::initialize Default
2023-09-08T13:53:11.596Z,1694181191.596 [Default:B.GoToSurface] Running Loop=1
2023-09-08T13:53:11.596Z,1694181191.596 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-09-08T13:53:11.596Z,1694181191.596 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-09-08T13:53:11.596Z,1694181191.596 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-09-08T13:53:11.601Z,1694181191.601 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-09-08T13:53:11.601Z,1694181191.601 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-09-08T13:53:11.602Z,1694181191.602 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-09-08T13:53:11.602Z,1694181191.602 [Default:A.Wait] Running Loop=1
2023-09-08T13:53:11.602Z,1694181191.602 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-09-08T13:53:13.976Z,1694181193.976 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230908T111755/Courier0097.lzma
2023-09-08T13:53:14.980Z,1694181194.980 [DataOverHttps](INFO): Moved sent file to Logs/20230908T111755/Courier0097.lzma.bak
2023-09-08T13:53:14.980Z,1694181194.980 [DataOverHttps](INFO): SBD MOMSN=18749968
2023-09-08T13:53:15.587Z,1694181195.587 [NAL9602](INFO): SBD MO Status=1, MOMSN=3498, MT Status=0, MTMSN=0
2023-09-08T13:53:15.647Z,1694181195.647 [NAL9602](INFO): Sent 39 bytes from file Logs/20230908T111755/Courier0097.lzma
2023-09-08T13:53:15.648Z,1694181195.648 [NAL9602](INFO): Packets left to send: 0
2023-09-08T13:53:15.950Z,1694181195.950 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US4WI06M.000
2023-09-08T13:53:19.455Z,1694181199.455 [NavChartDb](INFO): # of records loaded: 64244
2023-09-08T13:53:19.516Z,1694181199.516 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US4WI06M.000
2023-09-08T13:53:19.528Z,1694181199.528 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US4WI03M.000
2023-09-08T13:53:24.236Z,1694181204.236 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US4WI03M.000
2023-09-08T13:53:24.903Z,1694181204.903 [Default:A.Wait](INFO): Done Waiting.
2023-09-08T13:53:24.903Z,1694181204.903 [Default:A.Wait] Stopped
2023-09-08T13:53:24.903Z,1694181204.903 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T13:53:25.318Z,1694181205.318 [Default:CheckIn] Running Loop=1
2023-09-08T13:53:25.318Z,1694181205.318 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T13:53:25.318Z,1694181205.318 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T13:53:25.734Z,1694181205.734 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-09-08T13:53:27.300Z,1694181207.300 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,135325.00,A,4145.52931,N,08116.65094,W,0.058,189.33,080923,,,D*7F
2023-09-08T13:53:27.302Z,1694181207.302 [NAL9602](INFO): GPS fix at 20230908T135325: (41.758822, -81.277516)
2023-09-08T13:53:27.357Z,1694181207.357 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T13:53:27.358Z,1694181207.358 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T13:53:27.739Z,1694181207.739 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-09-08T13:53:39.599Z,1694181219.599 [NavChartDb](INFO): # of records loaded: 5000
2023-09-08T13:53:40.765Z,1694181220.765 [DataOverHttps](INFO): Sending 269 bytes from file Logs/20230908T134902/Courier0000.lzma
2023-09-08T13:53:41.767Z,1694181221.767 [DataOverHttps](INFO): Moved sent file to Logs/20230908T134902/Courier0000.lzma.bak
2023-09-08T13:53:41.767Z,1694181221.767 [DataOverHttps](INFO): SBD MOMSN=18749970
2023-09-08T13:53:54.064Z,1694181234.064 [NavChartDb](INFO): # of records loaded: 10000
2023-09-08T13:53:59.217Z,1694181239.217 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T13:54:11.692Z,1694181251.692 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230908T134902/Courier0004.lzma
2023-09-08T13:54:12.694Z,1694181252.694 [DataOverHttps](INFO): Moved sent file to Logs/20230908T134902/Courier0004.lzma.bak
2023-09-08T13:54:12.695Z,1694181252.695 [DataOverHttps](INFO): SBD MOMSN=18749974
2023-09-08T13:54:13.666Z,1694181253.666 [NavChartDb](INFO): # of records loaded: 15000
2023-09-08T13:54:28.189Z,1694181268.189 [NavChartDb](INFO): # of records loaded: 20000
2023-09-08T13:54:47.997Z,1694181287.997 [NavChartDb](INFO): # of records loaded: 25000
2023-09-08T13:54:48.744Z,1694181288.744 [DataOverHttps](INFO): Sending 111 bytes from file Logs/20230908T111755/Express0098.lzma
2023-09-08T13:54:49.747Z,1694181289.747 [DataOverHttps](INFO): Moved sent file to Logs/20230908T111755/Express0098.lzma.bak
2023-09-08T13:54:49.747Z,1694181289.747 [DataOverHttps](INFO): SBD MOMSN=18749977
2023-09-08T13:54:58.414Z,1694181298.414 [NavChartDb](INFO): # of records loaded: 30000
2023-09-08T13:55:18.707Z,1694181318.707 [NavChartDb](INFO): # of records loaded: 35000
2023-09-08T13:55:25.806Z,1694181325.806 [DataOverHttps](INFO): Sending 1296 bytes from file Logs/20230908T134902/Express0001.lzma
2023-09-08T13:55:26.807Z,1694181326.807 [DataOverHttps](INFO): Moved sent file to Logs/20230908T134902/Express0001.lzma.bak
2023-09-08T13:55:26.807Z,1694181326.807 [DataOverHttps](INFO): SBD MOMSN=18749980
2023-09-08T13:55:32.828Z,1694181332.828 [NavChartDb](INFO): # of records loaded: 40000
2023-09-08T13:55:41.242Z,1694181341.242 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US4WI03M.000
2023-09-08T13:55:44.849Z,1694181344.849 [NavChartDb](INFO): # of records loaded: 45000
2023-09-08T13:55:55.684Z,1694181355.684 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20230908T134902/Express0005.lzma
2023-09-08T13:55:56.687Z,1694181356.687 [DataOverHttps](INFO): Moved sent file to Logs/20230908T134902/Express0005.lzma.bak
2023-09-08T13:55:56.687Z,1694181356.687 [DataOverHttps](INFO): SBD MOMSN=18750026
2023-09-08T13:56:00.656Z,1694181360.656 [NavChartDb](INFO): # of records loaded: 50000
2023-09-08T13:56:02.993Z,1694181362.993 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T13:56:02.993Z,1694181362.993 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T13:56:02.993Z,1694181362.993 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T13:56:13.435Z,1694181373.435 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US4WI03M.000
2023-09-08T13:56:17.350Z,1694181377.350 [NavChartDb](INFO): # of records loaded: 55000
2023-09-08T13:56:35.271Z,1694181395.271 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US4WI03M.000
2023-09-08T13:56:37.310Z,1694181397.310 [NavChartDb](INFO): # of records loaded: 58175
2023-09-08T13:56:37.316Z,1694181397.316 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US4WI03M.000
2023-09-08T13:56:37.317Z,1694181397.317 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US4MI89M.000
2023-09-08T13:56:39.143Z,1694181399.143 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US4MI89M.000
2023-09-08T13:56:39.398Z,1694181399.398 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2023-09-08T13:56:39.404Z,1694181399.404 [BPC1](INFO): Received data from all battery sticks.
2023-09-08T13:56:49.003Z,1694181409.003 [NavChartDb](INFO): # of records loaded: 5000
2023-09-08T13:57:01.002Z,1694181421.002 [NavChartDb](INFO): # of records loaded: 10000
2023-09-08T13:57:08.294Z,1694181428.294 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US4MI89M.000
2023-09-08T13:57:08.809Z,1694181428.809 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US4MI89M.000
2023-09-08T13:57:16.011Z,1694181436.011 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US4MI89M.000
2023-09-08T13:57:17.356Z,1694181437.356 [NavChartDb](INFO): # of records loaded: 14969
2023-09-08T13:57:17.382Z,1694181437.382 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US4MI89M.000
2023-09-08T13:57:17.383Z,1694181437.383 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US4MI90M.000
2023-09-08T13:57:21.354Z,1694181441.354 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US4MI90M.000
2023-09-08T13:57:34.945Z,1694181454.945 [NavChartDb](INFO): # of records loaded: 5000
2023-09-08T13:57:44.772Z,1694181464.772 [NavChartDb](INFO): # of records loaded: 10000
2023-09-08T13:57:54.186Z,1694181474.186 [NavChartDb](INFO): # of records loaded: 15000
2023-09-08T13:58:03.594Z,1694181483.594 [NavChartDb](INFO): # of records loaded: 20000
2023-09-08T13:58:09.287Z,1694181489.287 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US4MI90M.000
2023-09-08T13:58:13.718Z,1694181493.718 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US4MI90M.000
2023-09-08T13:58:16.390Z,1694181496.390 [NavChartDb](INFO): # of records loaded: 25000
2023-09-08T13:58:26.969Z,1694181506.969 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US4MI90M.000
2023-09-08T13:58:31.289Z,1694181511.289 [NavChartDb](INFO): # of records loaded: 27984
2023-09-08T13:58:31.294Z,1694181511.294 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US4MI90M.000
2023-09-08T13:58:31.295Z,1694181511.295 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US4MI56M.000
2023-09-08T13:58:33.311Z,1694181513.311 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US4MI56M.000
2023-09-08T13:58:49.613Z,1694181529.613 [NavChartDb](INFO): # of records loaded: 5000
2023-09-08T13:58:59.303Z,1694181539.303 [NavChartDb](INFO): # of records loaded: 10000
2023-09-08T13:59:12.266Z,1694181552.266 [NavChartDb](INFO): # of records loaded: 15000
2023-09-08T13:59:13.187Z,1694181553.187 [Power24vConverter](INFO): Powering down.
2023-09-08T13:59:26.637Z,1694181566.637 [NavChartDb](INFO): # of records loaded: 20000
2023-09-08T13:59:31.255Z,1694181571.255 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US4MI56M.000
2023-09-08T13:59:31.687Z,1694181571.687 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US4MI56M.000
2023-09-08T13:59:34.868Z,1694181574.868 [NavChartDb](INFO): # of records loaded: 25000
2023-09-08T13:59:39.414Z,1694181579.414 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US4MI56M.000
2023-09-08T13:59:41.575Z,1694181581.575 [NavChartDb](INFO): # of records loaded: 26626
2023-09-08T13:59:41.579Z,1694181581.579 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US4MI56M.000
2023-09-08T13:59:41.580Z,1694181581.580 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US4MI57M.000
2023-09-08T13:59:46.693Z,1694181586.693 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US4MI57M.000
2023-09-08T14:00:02.675Z,1694181602.675 [NavChartDb](INFO): # of records loaded: 5000
2023-09-08T14:00:13.227Z,1694181613.227 [NavChartDb](INFO): # of records loaded: 10000
2023-09-08T14:00:22.920Z,1694181622.920 [NavChartDb](INFO): # of records loaded: 15000
2023-09-08T14:00:36.656Z,1694181636.656 [NavChartDb](INFO): # of records loaded: 20000
2023-09-08T14:00:50.130Z,1694181650.130 [NavChartDb](INFO): # of records loaded: 25000
2023-09-08T14:00:59.318Z,1694181659.318 [NavChartDb](INFO): # of records loaded: 30000
2023-09-08T14:01:03.721Z,1694181663.721 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T14:01:03.721Z,1694181663.721 [Default:CheckIn:C.Wait] Stopped
2023-09-08T14:01:03.721Z,1694181663.721 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T14:01:03.721Z,1694181663.721 [Default:CheckIn:D] Running Loop=1
2023-09-08T14:01:04.087Z,1694181664.087 [Default:CheckIn:D] Stopped
2023-09-08T14:01:04.087Z,1694181664.087 [Default:CheckIn:E] Running Loop=1
2023-09-08T14:01:04.520Z,1694181664.520 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 7.874862 min 
2023-09-08T14:01:04.520Z,1694181664.520 [Default:CheckIn:E] Stopped
2023-09-08T14:01:04.537Z,1694181664.537 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T14:01:04.537Z,1694181664.537 [Default:CheckIn] Stopped
2023-09-08T14:01:04.537Z,1694181664.537 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T14:01:04.537Z,1694181664.537 [Default:CheckIn](INFO): Running loop #2
2023-09-08T14:01:04.537Z,1694181664.537 [Default:CheckIn] Running Loop=2
2023-09-08T14:01:04.537Z,1694181664.537 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T14:01:04.537Z,1694181664.537 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T14:01:06.504Z,1694181666.504 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,140105.00,A,4145.52966,N,08116.65043,W,0.019,189.33,080923,,,D*70
2023-09-08T14:01:06.506Z,1694181666.506 [NAL9602](INFO): GPS fix at 20230908T140105: (41.758828, -81.277507)
2023-09-08T14:01:06.597Z,1694181666.597 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T14:01:06.597Z,1694181666.597 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T14:01:18.937Z,1694181678.937 [NavChartDb](INFO): # of records loaded: 35000
2023-09-08T14:01:20.159Z,1694181680.159 [DataOverHttps](INFO): Sending 203 bytes from file Logs/20230908T134902/Courier0007.lzma
2023-09-08T14:01:21.167Z,1694181681.167 [DataOverHttps](INFO): Moved sent file to Logs/20230908T134902/Courier0007.lzma.bak
2023-09-08T14:01:21.167Z,1694181681.167 [DataOverHttps](INFO): SBD MOMSN=18750066
2023-09-08T14:01:31.829Z,1694181691.829 [NavChartDb](INFO): # of records loaded: 40000
2023-09-08T14:01:44.594Z,1694181704.594 [NavChartDb](INFO): # of records loaded: 45000
2023-09-08T14:01:48.990Z,1694181708.990 [DataOverHttps](INFO): Sending 240 bytes from file Logs/20230908T134902/Express0008.lzma
2023-09-08T14:01:49.986Z,1694181709.986 [DataOverHttps](INFO): Moved sent file to Logs/20230908T134902/Express0008.lzma.bak
2023-09-08T14:01:49.987Z,1694181709.987 [DataOverHttps](INFO): SBD MOMSN=18750069
2023-09-08T14:01:56.221Z,1694181716.221 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T14:01:56.221Z,1694181716.221 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T14:01:56.221Z,1694181716.221 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T14:02:00.068Z,1694181720.068 [NavChartDb](INFO): # of records loaded: 50000
2023-09-08T14:02:11.413Z,1694181731.413 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US4MI57M.000
2023-09-08T14:02:13.273Z,1694181733.273 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US4MI57M.000
2023-09-08T14:02:16.548Z,1694181736.548 [NavChartDb](INFO): # of records loaded: 55000
2023-09-08T14:02:20.023Z,1694181740.023 [NAL9602](INFO): SBD MO Status=2, MOMSN=3499, MT Status=2, MTMSN=0
2023-09-08T14:02:20.024Z,1694181740.024 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-08T14:02:33.775Z,1694181753.775 [NAL9602](INFO): SBD MO Status=0, MOMSN=3499, MT Status=0, MTMSN=0
2023-09-08T14:02:33.776Z,1694181753.776 [NAL9602](INFO): No messages in MT queue
2023-09-08T14:02:41.447Z,1694181761.447 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US4MI57M.000
2023-09-08T14:02:44.894Z,1694181764.894 [NavChartDb](INFO): # of records loaded: 59244
2023-09-08T14:02:44.900Z,1694181764.900 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US4MI57M.000
2023-09-08T14:02:44.901Z,1694181764.901 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US2MI80M.000
2023-09-08T14:02:58.233Z,1694181778.233 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US2MI80M.000
2023-09-08T14:03:04.530Z,1694181784.530 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T14:03:19.383Z,1694181799.383 [NavChartDb](INFO): # of records loaded: 5000
2023-09-08T14:03:32.534Z,1694181812.534 [NavChartDb](INFO): # of records loaded: 10000
2023-09-08T14:03:43.901Z,1694181823.901 [NavChartDb](INFO): # of records loaded: 15000
2023-09-08T14:03:54.188Z,1694181834.188 [NavChartDb](INFO): # of records loaded: 20000
2023-09-08T14:04:08.235Z,1694181848.235 [NavChartDb](INFO): # of records loaded: 25000
2023-09-08T14:04:20.437Z,1694181860.437 [NavChartDb](INFO): # of records loaded: 30000
2023-09-08T14:04:32.437Z,1694181872.437 [NavChartDb](INFO): # of records loaded: 35000
2023-09-08T14:04:49.965Z,1694181889.965 [NavChartDb](INFO): # of records loaded: 40000
2023-09-08T14:05:00.813Z,1694181900.813 [NavChartDb](INFO): # of records loaded: 45000
2023-09-08T14:05:19.090Z,1694181919.090 [NavChartDb](INFO): # of records loaded: 50000
2023-09-08T14:05:31.215Z,1694181931.215 [NavChartDb](INFO): # of records loaded: 55000
2023-09-08T14:05:41.420Z,1694181941.420 [NavChartDb](INFO): # of records loaded: 60000
2023-09-08T14:05:54.600Z,1694181954.600 [NavChartDb](INFO): # of records loaded: 65000
2023-09-08T14:06:05.150Z,1694181965.150 [NavChartDb](INFO): # of records loaded: 70000
2023-09-08T14:06:13.273Z,1694181973.273 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US2MI80M.000
2023-09-08T14:06:16.900Z,1694181976.900 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US2MI80M.000
2023-09-08T14:06:32.782Z,1694181992.782 [NavChartDb](INFO): # of records loaded: 75000
2023-09-08T14:06:56.748Z,1694182016.748 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T14:06:56.748Z,1694182016.748 [Default:CheckIn:C.Wait] Stopped
2023-09-08T14:06:56.748Z,1694182016.748 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T14:06:56.748Z,1694182016.748 [Default:CheckIn:D] Running Loop=1
2023-09-08T14:06:57.145Z,1694182017.145 [Default:CheckIn:D] Stopped
2023-09-08T14:06:57.145Z,1694182017.145 [Default:CheckIn:E] Running Loop=1
2023-09-08T14:06:57.560Z,1694182017.560 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 13.759161 min 
2023-09-08T14:06:57.560Z,1694182017.560 [Default:CheckIn:E] Stopped
2023-09-08T14:06:57.561Z,1694182017.561 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T14:06:57.561Z,1694182017.561 [Default:CheckIn] Stopped
2023-09-08T14:06:57.561Z,1694182017.561 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T14:06:57.561Z,1694182017.561 [Default:CheckIn](INFO): Running loop #3
2023-09-08T14:06:57.561Z,1694182017.561 [Default:CheckIn] Running Loop=3
2023-09-08T14:06:57.561Z,1694182017.561 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T14:06:57.561Z,1694182017.561 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T14:06:59.556Z,1694182019.556 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,140658.00,A,4145.52937,N,08116.65174,W,0.019,189.33,080923,,,D*7E
2023-09-08T14:06:59.571Z,1694182019.571 [NAL9602](INFO): GPS fix at 20230908T140658: (41.758823, -81.277529)
2023-09-08T14:06:59.605Z,1694182019.605 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T14:06:59.605Z,1694182019.605 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T14:07:10.945Z,1694182030.945 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230908T134902/Courier0010.lzma
2023-09-08T14:07:11.950Z,1694182031.950 [DataOverHttps](INFO): Moved sent file to Logs/20230908T134902/Courier0010.lzma.bak
2023-09-08T14:07:11.951Z,1694182031.951 [DataOverHttps](INFO): SBD MOMSN=18750140
2023-09-08T14:07:16.052Z,1694182036.052 [NAL9602](INFO): SBD MO Status=0, MOMSN=3500, MT Status=0, MTMSN=0
2023-09-08T14:07:16.052Z,1694182036.052 [NAL9602](INFO): No messages in MT queue
2023-09-08T14:07:37.656Z,1694182057.656 [NavChartDb](INFO): # of records loaded: 80000
2023-09-08T14:07:39.973Z,1694182059.973 [DataOverHttps](INFO): Sending 184 bytes from file Logs/20230908T134902/Express0011.lzma
2023-09-08T14:07:40.975Z,1694182060.975 [DataOverHttps](INFO): Moved sent file to Logs/20230908T134902/Express0011.lzma.bak
2023-09-08T14:07:40.975Z,1694182060.975 [DataOverHttps](INFO): SBD MOMSN=18750145
2023-09-08T14:07:46.766Z,1694182066.766 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T14:07:46.837Z,1694182066.837 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T14:07:46.837Z,1694182066.837 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T14:07:46.837Z,1694182066.837 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T14:07:55.721Z,1694182075.721 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US2MI80M.000
2023-09-08T14:07:59.048Z,1694182079.048 [NavChartDb](INFO): # of records loaded: 83074
2023-09-08T14:07:59.067Z,1694182079.067 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US2MI80M.000
2023-09-08T14:07:59.068Z,1694182079.068 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US2MI01M.000
2023-09-08T14:08:00.027Z,1694182080.027 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US2MI01M.000
2023-09-08T14:08:14.303Z,1694182094.303 [NavChartDb](INFO): # of records loaded: 5000
2023-09-08T14:08:17.160Z,1694182097.160 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US2MI01M.000
2023-09-08T14:08:17.392Z,1694182097.392 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US2MI01M.000
2023-09-08T14:08:19.809Z,1694182099.809 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US2MI01M.000
2023-09-08T14:08:21.388Z,1694182101.388 [NavChartDb](INFO): # of records loaded: 7824
2023-09-08T14:08:21.391Z,1694182101.391 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US2MI01M.000
2023-09-08T14:08:25.752Z,1694182105.752 [NavChartDb](INFO): Creating index for soundings
2023-09-08T14:08:54.986Z,1694182134.986 [NavChartDb](INFO): Creating index for 0p0
2023-09-08T14:08:59.549Z,1694182139.549 [NavChartDb](INFO): Creating index for 0p6
2023-09-08T14:08:59.651Z,1694182139.651 [NavChartDb](INFO): Creating index for 0p9
2023-09-08T14:09:12.669Z,1694182152.669 [NavChartDb](INFO): Creating index for 1p8
2023-09-08T14:09:31.177Z,1694182171.177 [NavChartDb](INFO): Creating index for 3p6
2023-09-08T14:09:42.465Z,1694182182.465 [NavChartDb](INFO): Creating index for 5p4
2023-09-08T14:09:58.279Z,1694182198.279 [NavChartDb](INFO): Creating index for 7p3
2023-09-08T14:10:11.039Z,1694182211.039 [NavChartDb](INFO): Creating index for 9p1
2023-09-08T14:10:13.870Z,1694182213.870 [NavChartDb](INFO): Creating index for 20p0
2023-09-08T14:12:25.756Z,1694182345.756 [CommandExec](IMPORTANT): got command get distance_from_shore
2023-09-08T14:12:25.756Z,1694182345.756 [CommandExec](IMPORTANT): distance_from_shore 63.966236 m
2023-09-08T14:12:47.266Z,1694182367.266 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-08T14:12:47.266Z,1694182367.266 [Default:CheckIn:C.Wait] Stopped
2023-09-08T14:12:47.266Z,1694182367.266 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T14:12:47.267Z,1694182367.267 [Default:CheckIn:D] Running Loop=1
2023-09-08T14:12:47.664Z,1694182367.664 [Default:CheckIn:D] Stopped
2023-09-08T14:12:47.664Z,1694182367.664 [Default:CheckIn:E] Running Loop=1
2023-09-08T14:12:48.069Z,1694182368.069 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 19.601133 min 
2023-09-08T14:12:48.069Z,1694182368.069 [Default:CheckIn:E] Stopped
2023-09-08T14:12:48.069Z,1694182368.069 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-08T14:12:48.069Z,1694182368.069 [Default:CheckIn] Stopped
2023-09-08T14:12:48.069Z,1694182368.069 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T14:12:48.070Z,1694182368.070 [Default:CheckIn](INFO): Running loop #4
2023-09-08T14:12:48.070Z,1694182368.070 [Default:CheckIn] Running Loop=4
2023-09-08T14:12:48.070Z,1694182368.070 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T14:12:48.070Z,1694182368.070 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T14:12:50.072Z,1694182370.072 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,141249.00,A,4145.52961,N,08116.65076,W,0.078,189.33,080923,,,D*7C
2023-09-08T14:12:50.074Z,1694182370.074 [NAL9602](INFO): GPS fix at 20230908T141249: (41.758827, -81.277513)
2023-09-08T14:12:50.098Z,1694182370.098 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T14:12:50.098Z,1694182370.098 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T14:12:52.600Z,1694182372.600 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230908T134902/Courier0013.lzma
2023-09-08T14:12:53.606Z,1694182373.606 [DataOverHttps](INFO): Moved sent file to Logs/20230908T134902/Courier0013.lzma.bak
2023-09-08T14:12:53.606Z,1694182373.606 [DataOverHttps](INFO): SBD MOMSN=18750178
2023-09-08T14:13:00.638Z,1694182380.638 [NAL9602](INFO): SBD MO Status=0, MOMSN=3501, MT Status=0, MTMSN=0
2023-09-08T14:13:00.638Z,1694182380.638 [NAL9602](INFO): No messages in MT queue
2023-09-08T14:13:12.082Z,1694182392.082 [CommandExec](IMPORTANT): got command restart application
2023-09-08T14:13:12.377Z,1694182392.377 [DataOverHttps](INFO): Sending 272 bytes from file Logs/20230908T134902/Express0014.lzma
2023-09-08T14:13:13.085Z,1694182393.085 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2023-09-08T14:13:13.085Z,1694182393.085 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-08T14:13:13.085Z,1694182393.085 [CommandExec](INFO): Uninitializing the command executive.
2023-09-08T14:13:13.085Z,1694182393.085 [CommandExec](INFO): Uninitializing the command scheduler.
2023-09-08T14:13:13.086Z,1694182393.086 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-09-08T14:13:13.101Z,1694182393.101 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2023-09-08T14:13:13.101Z,1694182393.101 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-09-08T14:13:13.101Z,1694182393.101 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-09-08T14:13:13.102Z,1694182393.102 [NavChartDb](INFO): Join timeout helper Thread ID is 1838
2023-09-08T14:13:13.361Z,1694182393.361 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-08T14:13:13.361Z,1694182393.361 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-09-08T14:13:13.374Z,1694182393.374 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-09-08T14:13:13.375Z,1694182393.375 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-09-08T14:13:13.375Z,1694182393.375 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1839
2023-09-08T14:13:13.379Z,1694182393.379 [DataOverHttps](INFO): Moved sent file to Logs/20230908T134902/Express0014.lzma.bak
2023-09-08T14:13:13.379Z,1694182393.379 [DataOverHttps](INFO): SBD MOMSN=18750182
2023-09-08T14:13:13.761Z,1694182393.761 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-08T14:13:13.761Z,1694182393.761 [WetLabsBB2FL](INFO): Powering down
2023-09-08T14:13:13.762Z,1694182393.762 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-09-08T14:13:13.773Z,1694182393.773 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-09-08T14:13:13.773Z,1694182393.773 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-09-08T14:13:13.773Z,1694182393.773 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1840
2023-09-08T14:13:14.765Z,1694182394.765 [CTD_Seabird](INFO): Powering down
2023-09-08T14:13:14.777Z,1694182394.777 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-08T14:13:14.777Z,1694182394.777 [CTD_Seabird](INFO): Powering down
2023-09-08T14:13:14.789Z,1694182394.789 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-09-08T14:13:14.789Z,1694182394.789 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-09-08T14:13:14.789Z,1694182394.789 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-09-08T14:13:14.790Z,1694182394.790 [Radio_Surface](INFO): Join timeout helper Thread ID is 1841
2023-09-08T14:13:15.073Z,1694182395.073 [Radio_Surface](INFO): Powering down
2023-09-08T14:13:15.074Z,1694182395.074 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-08T14:13:15.074Z,1694182395.074 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-09-08T14:13:15.085Z,1694182395.085 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-09-08T14:13:15.085Z,1694182395.085 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-09-08T14:13:15.085Z,1694182395.085 [Onboard](INFO): Join timeout helper Thread ID is 1842
2023-09-08T14:13:17.206Z,1694182397.206 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2023-09-08T14:13:17.268Z,1694182397.268 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T14:13:17.268Z,1694182397.268 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T14:13:17.268Z,1694182397.268 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T14:13:18.261Z,1694182398.261 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-08T14:13:18.261Z,1694182398.261 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-09-08T14:13:18.282Z,1694182398.282 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-09-08T14:13:18.282Z,1694182398.282 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-09-08T14:13:18.283Z,1694182398.283 [DataOverHttps](INFO): Join timeout helper Thread ID is 1843
2023-09-08T14:13:19.850Z,1694182399.850 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-08T14:13:19.850Z,1694182399.850 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-09-08T14:13:19.853Z,1694182399.853 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2023-09-08T14:13:19.854Z,1694182399.854 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-09-08T14:13:19.854Z,1694182399.854 [BackseatComponent](INFO): Join timeout helper Thread ID is 1844
2023-09-08T14:13:19.949Z,1694182399.949 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-08T14:13:19.949Z,1694182399.949 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-09-08T14:13:19.965Z,1694182399.965 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-09-08T14:13:19.965Z,1694182399.965 [logger ThreadHandler](INFO): Thread cancelled.
2023-09-08T14:13:19.966Z,1694182399.966 [logger](INFO): Join timeout helper Thread ID is 1845
2023-09-08T14:13:19.989Z,1694182399.989 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-08T14:13:19.989Z,1694182399.989 [logger ThreadHandler](INFO): Thread cancelled.
2023-09-08T14:13:20.006Z,1694182400.006 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-09-08T14:13:20.007Z,1694182400.007 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-09-08T14:13:20.007Z,1694182400.007 [CommandLine](INFO): Join timeout helper Thread ID is 1846
2023-09-08T14:13:20.101Z,1694182400.101 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-08T14:13:20.101Z,1694182400.101 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-09-08T14:13:20.121Z,1694182400.121 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-09-08T14:13:20.121Z,1694182400.121 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-09-08T14:13:20.122Z,1694182400.122 [CommandExec](INFO): Join timeout helper Thread ID is 1847
2023-09-08T14:13:20.123Z,1694182400.123 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-09-08T14:13:20.123Z,1694182400.123 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-09-08T14:13:20.123Z,1694182400.123 [controlThread](INFO): Join timeout helper Thread ID is 1848
2023-09-08T14:13:20.405Z,1694182400.405 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-08T14:13:20.405Z,1694182400.405 [controlThread](DEBUG): Uninitializing ControlThread
2023-09-08T14:13:20.405Z,1694182400.405 [AHRS_M2](INFO): Powering down
2023-09-08T14:13:20.478Z,1694182400.478 [NAL9602](INFO): Powering down
2023-09-08T14:13:20.479Z,1694182400.479 [Sonardyne_Nano](INFO): Powering down
2023-09-08T14:13:20.597Z,1694182400.597 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-09-08T14:13:20.598Z,1694182400.598 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-09-08T14:13:20.599Z,1694182400.599 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-09-08T14:13:20.599Z,1694182400.599 [MissionManager](INFO): Uninitializing Mission Default
2023-09-08T14:13:20.599Z,1694182400.599 [Default] Stopped
2023-09-08T14:13:20.599Z,1694182400.599 [Default](DEBUG): Aggregate::uninitialize Default
2023-09-08T14:13:20.600Z,1694182400.600 [Default:B.GoToSurface] Stopped
2023-09-08T14:13:20.600Z,1694182400.600 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-09-08T14:13:20.600Z,1694182400.600 [Default:CheckIn] Stopped
2023-09-08T14:13:20.600Z,1694182400.600 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T14:13:20.600Z,1694182400.600 [Default:CheckIn:C.Wait] Stopped
2023-09-08T14:13:20.600Z,1694182400.600 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T14:13:20.603Z,1694182400.603 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2023-09-08T14:13:20.603Z,1694182400.603 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2023-09-08T14:13:20.603Z,1694182400.603 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2023-09-08T14:13:20.604Z,1694182400.604 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2023-09-08T14:13:20.604Z,1694182400.604 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2023-09-08T14:13:20.604Z,1694182400.604 [BuoyancyServo](INFO): Powering down
2023-09-08T14:13:20.617Z,1694182400.617 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-09-08T14:13:20.617Z,1694182400.617 [ElevatorServo](INFO): Powering down
2023-09-08T14:13:20.618Z,1694182400.618 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-09-08T14:13:20.618Z,1694182400.618 [MassServo](INFO): Powering down
2023-09-08T14:13:20.619Z,1694182400.619 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-09-08T14:13:20.619Z,1694182400.619 [RudderServo](INFO): Powering down
2023-09-08T14:13:20.620Z,1694182400.620 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2023-09-08T14:13:20.620Z,1694182400.620 [ThrusterHE](INFO): Powering down
2023-09-08T14:13:20.621Z,1694182400.621 [SBIT](DEBUG): Uninitialize SBIT Component.
2023-09-08T14:13:20.621Z,1694182400.621 [IBIT](DEBUG): Uninitialize IBIT Component.
2023-09-08T14:13:20.621Z,1694182400.621 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-09-08T14:13:20.621Z,1694182400.621 [CBIT](DEBUG): Powering off loads.
2023-09-08T14:13:20.633Z,1694182400.633 [CBIT](DEBUG): Disabling WDT.
2023-09-08T14:13:20.645Z,1694182400.645 [CBIT](DEBUG): Opening all GF detection circuits.
2023-09-08T14:13:20.646Z,1694182400.646 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-09-08T14:13:20.701Z,1694182400.701 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-09-08T14:13:20.704Z,1694182400.704 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-09-08T14:13:20.710Z,1694182400.710 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-09-08T14:13:20.754Z,1694182400.754 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-09-08T14:13:20.791Z,1694182400.791 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-09-08T14:13:20.795Z,1694182400.795 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-09-08T14:13:20.827Z,1694182400.827 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-09-08T14:13:20.877Z,1694182400.877 [logger ThreadHandler](INFO): Thread cancelled.