2024-07-19T00:19:45.863Z,1721348385.863 [Supervisor](DEBUG): Initializing supervisor.
2024-07-19T00:19:45.867Z,1721348385.867 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2024-07-19T00:19:45.868Z,1721348385.868 [SyncHandler](INFO): Protected caller Thread ID is 4112
2024-07-19T00:19:45.868Z,1721348385.868 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2024-07-19T00:19:45.869Z,1721348385.869 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2024-07-19T00:19:45.869Z,1721348385.869 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4113
2024-07-19T00:19:45.873Z,1721348385.873 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2024-07-19T00:19:45.891Z,1721348385.891 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2024-07-19T00:19:45.892Z,1721348385.892 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2024-07-19T00:19:45.892Z,1721348385.892 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 4114
2024-07-19T00:19:45.898Z,1721348385.898 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2024-07-19T00:19:45.900Z,1721348385.900 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2024-07-19T00:19:45.921Z,1721348385.921 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4115
2024-07-19T00:19:45.923Z,1721348385.923 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2024-07-19T00:19:45.924Z,1721348385.924 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2024-07-19T00:19:45.924Z,1721348385.924 [logger ThreadHandler](INFO): Protected caller Thread ID is 4116
2024-07-19T00:19:45.928Z,1721348385.928 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2024-07-19T00:19:45.928Z,1721348385.928 [Supervisor](INFO): Looking for Config files in directory: Config/
2024-07-19T00:19:45.939Z,1721348385.939 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2024-07-19T00:19:46.507Z,1721348386.507 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2024-07-19T00:19:46.508Z,1721348386.508 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2024-07-19T00:19:46.743Z,1721348386.743 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2024-07-19T00:19:46.744Z,1721348386.744 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2024-07-19T00:19:46.838Z,1721348386.838 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2024-07-19T00:19:47.094Z,1721348387.094 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2024-07-19T00:19:47.095Z,1721348387.095 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2024-07-19T00:19:47.186Z,1721348387.186 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2024-07-19T00:19:47.296Z,1721348387.296 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2024-07-19T00:19:47.296Z,1721348387.296 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2024-07-19T00:19:47.669Z,1721348387.669 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2024-07-19T00:19:47.670Z,1721348387.670 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2024-07-19T00:19:47.818Z,1721348387.818 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2024-07-19T00:19:47.819Z,1721348387.819 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2024-07-19T00:19:48.392Z,1721348388.392 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2024-07-19T00:19:48.393Z,1721348388.393 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2024-07-19T00:19:48.603Z,1721348388.603 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2024-07-19T00:19:48.603Z,1721348388.603 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2024-07-19T00:19:49.047Z,1721348389.047 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2024-07-19T00:19:49.048Z,1721348389.048 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2024-07-19T00:19:50.046Z,1721348390.046 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2024-07-19T00:19:50.046Z,1721348390.046 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2024-07-19T00:19:50.170Z,1721348390.170 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2024-07-19T00:19:50.171Z,1721348390.171 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2024-07-19T00:19:51.060Z,1721348391.060 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2024-07-19T00:19:51.061Z,1721348391.061 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2024-07-19T00:19:51.447Z,1721348391.447 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2024-07-19T00:19:51.448Z,1721348391.448 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2024-07-19T00:19:51.699Z,1721348391.699 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2024-07-19T00:19:51.701Z,1721348391.701 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2024-07-19T00:19:51.702Z,1721348391.702 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2024-07-19T00:19:51.836Z,1721348391.836 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2024-07-19T00:19:51.932Z,1721348391.932 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2024-07-19T00:19:52.085Z,1721348392.085 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2024-07-19T00:19:52.184Z,1721348392.184 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2024-07-19T00:19:52.462Z,1721348392.462 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2024-07-19T00:19:52.462Z,1721348392.462 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2024-07-19T00:19:52.582Z,1721348392.582 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2024-07-19T00:19:52.703Z,1721348392.703 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2024-07-19T00:19:52.824Z,1721348392.824 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2024-07-19T00:19:52.991Z,1721348392.991 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2024-07-19T00:19:53.102Z,1721348393.102 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2024-07-19T00:19:53.201Z,1721348393.201 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2024-07-19T00:19:53.209Z,1721348393.209 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2024-07-19T00:19:53.220Z,1721348393.220 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2024-07-19T00:19:53.220Z,1721348393.220 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2024-07-19T00:19:53.329Z,1721348393.329 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2024-07-19T00:19:53.330Z,1721348393.330 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2024-07-19T00:19:53.381Z,1721348393.381 [VerticalControl](DEBUG): Construct VerticalControl.
2024-07-19T00:19:53.442Z,1721348393.442 [VerticalControl] Loaded
2024-07-19T00:19:53.443Z,1721348393.443 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2024-07-19T00:19:53.446Z,1721348393.446 [HorizontalControl](DEBUG): Construct HorizontalControl.
2024-07-19T00:19:53.493Z,1721348393.493 [HorizontalControl] Loaded
2024-07-19T00:19:53.494Z,1721348393.494 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2024-07-19T00:19:53.496Z,1721348393.496 [SpeedControl](DEBUG): Construct SpeedControl.
2024-07-19T00:19:53.499Z,1721348393.499 [SpeedControl] Loaded
2024-07-19T00:19:53.499Z,1721348393.499 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2024-07-19T00:19:53.502Z,1721348393.502 [LoopControl](DEBUG): Construct LoopControl.
2024-07-19T00:19:53.502Z,1721348393.502 [LoopControl] Loaded
2024-07-19T00:19:53.503Z,1721348393.503 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2024-07-19T00:19:53.503Z,1721348393.503 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2024-07-19T00:19:53.504Z,1721348393.504 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2024-07-19T00:19:53.657Z,1721348393.657 [BuoyancyServo] Loaded
2024-07-19T00:19:53.657Z,1721348393.657 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2024-07-19T00:19:53.679Z,1721348393.679 [ElevatorServo] Loaded
2024-07-19T00:19:53.679Z,1721348393.679 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2024-07-19T00:19:53.700Z,1721348393.700 [MassServo] Loaded
2024-07-19T00:19:53.700Z,1721348393.700 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2024-07-19T00:19:53.721Z,1721348393.721 [RudderServo] Loaded
2024-07-19T00:19:53.721Z,1721348393.721 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2024-07-19T00:19:53.738Z,1721348393.738 [ThrusterHE] Loaded
2024-07-19T00:19:53.738Z,1721348393.738 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2024-07-19T00:19:53.738Z,1721348393.738 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2024-07-19T00:19:53.739Z,1721348393.739 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2024-07-19T00:19:53.788Z,1721348393.788 [DepthRateCalculator] Loaded
2024-07-19T00:19:53.789Z,1721348393.789 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2024-07-19T00:19:53.794Z,1721348393.794 [PitchRateCalculator] Loaded
2024-07-19T00:19:53.794Z,1721348393.794 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2024-07-19T00:19:53.803Z,1721348393.803 [SpeedCalculator] Loaded
2024-07-19T00:19:53.804Z,1721348393.804 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2024-07-19T00:19:53.808Z,1721348393.808 [YawRateCalculator] Loaded
2024-07-19T00:19:53.808Z,1721348393.808 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2024-07-19T00:19:53.827Z,1721348393.827 [ElevatorOffsetCalculator] Loaded
2024-07-19T00:19:53.827Z,1721348393.827 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2024-07-19T00:19:53.827Z,1721348393.827 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2024-07-19T00:19:53.828Z,1721348393.828 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2024-07-19T00:19:53.938Z,1721348393.938 [DeadReckonUsingMultipleVelocitySources] Loaded
2024-07-19T00:19:53.938Z,1721348393.938 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2024-07-19T00:19:53.952Z,1721348393.952 [NavChart] Loaded
2024-07-19T00:19:53.952Z,1721348393.952 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2024-07-19T00:19:53.958Z,1721348393.958 [UniversalFixResidualReporter] Loaded
2024-07-19T00:19:53.958Z,1721348393.958 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2024-07-19T00:19:53.958Z,1721348393.958 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2024-07-19T00:19:53.959Z,1721348393.959 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2024-07-19T00:19:54.723Z,1721348394.723 [AHRS_M2] Loaded
2024-07-19T00:19:54.723Z,1721348394.723 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2024-07-19T00:19:54.777Z,1721348394.777 [BackseatComponent] Loaded
2024-07-19T00:19:54.777Z,1721348394.777 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2024-07-19T00:19:54.778Z,1721348394.778 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4090B4E0
2024-07-19T00:19:54.778Z,1721348394.778 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 4198
2024-07-19T00:19:54.781Z,1721348394.781 [LcmUniversalReporter] Loaded
2024-07-19T00:19:54.781Z,1721348394.781 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2024-07-19T00:19:54.788Z,1721348394.788 [BioacousticsDataBridge] Loaded
2024-07-19T00:19:54.788Z,1721348394.788 [ComponentRegistry](DEBUG): SyncComponent "BioacousticsDataBridge" handled in the control thread.
2024-07-19T00:19:55.622Z,1721348395.622 [BPC1] Loaded
2024-07-19T00:19:55.623Z,1721348395.623 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2024-07-19T00:19:55.694Z,1721348395.694 [DataOverHttps] Loaded
2024-07-19T00:19:55.694Z,1721348395.694 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2024-07-19T00:19:55.695Z,1721348395.695 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4093B4E0
2024-07-19T00:19:55.695Z,1721348395.695 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 4199
2024-07-19T00:19:55.719Z,1721348395.719 [Depth_Keller] Loaded
2024-07-19T00:19:55.719Z,1721348395.719 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2024-07-19T00:19:55.724Z,1721348395.724 [DropWeight] Loaded
2024-07-19T00:19:55.724Z,1721348395.724 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2024-07-19T00:19:55.789Z,1721348395.789 [NAL9602] Loaded
2024-07-19T00:19:55.789Z,1721348395.789 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2024-07-19T00:19:55.819Z,1721348395.819 [Onboard] Loaded
2024-07-19T00:19:55.820Z,1721348395.820 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2024-07-19T00:19:55.821Z,1721348395.821 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4096B4E0
2024-07-19T00:19:55.821Z,1721348395.821 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 4200
2024-07-19T00:19:55.835Z,1721348395.835 [Power24vConverter] Loaded
2024-07-19T00:19:55.836Z,1721348395.836 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2024-07-19T00:19:55.851Z,1721348395.851 [Radio_Surface] Loaded
2024-07-19T00:19:55.852Z,1721348395.852 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2024-07-19T00:19:55.853Z,1721348395.853 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4099B4E0
2024-07-19T00:19:55.853Z,1721348395.853 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4201
2024-07-19T00:19:55.868Z,1721348395.868 [Sonardyne_Nano] Loaded
2024-07-19T00:19:55.869Z,1721348395.869 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2024-07-19T00:19:55.869Z,1721348395.869 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2024-07-19T00:19:55.870Z,1721348395.870 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2024-07-19T00:19:55.928Z,1721348395.928 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2024-07-19T00:19:55.928Z,1721348395.928 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2024-07-19T00:19:56.098Z,1721348396.098 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2024-07-19T00:19:56.098Z,1721348396.098 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2024-07-19T00:19:56.124Z,1721348396.124 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2024-07-19T00:19:56.125Z,1721348396.125 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2024-07-19T00:19:56.297Z,1721348396.297 [CTD_Seabird] Loaded
2024-07-19T00:19:56.297Z,1721348396.297 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2024-07-19T00:19:56.298Z,1721348396.298 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40ADB4E0
2024-07-19T00:19:56.299Z,1721348396.299 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 4202
2024-07-19T00:19:56.321Z,1721348396.321 [PAR_Licor] Loaded
2024-07-19T00:19:56.322Z,1721348396.322 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2024-07-19T00:19:56.368Z,1721348396.368 [WetLabsBB2FL] Loaded
2024-07-19T00:19:56.368Z,1721348396.368 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2024-07-19T00:19:56.369Z,1721348396.369 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B0B4E0
2024-07-19T00:19:56.369Z,1721348396.369 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 4203
2024-07-19T00:19:56.370Z,1721348396.370 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2024-07-19T00:19:56.371Z,1721348396.371 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2024-07-19T00:19:56.703Z,1721348396.703 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2024-07-19T00:19:56.704Z,1721348396.704 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2024-07-19T00:19:56.844Z,1721348396.844 [SBIT](DEBUG): Construct Startup Built In Test.
2024-07-19T00:19:56.853Z,1721348396.853 [SBIT] Loaded
2024-07-19T00:19:56.853Z,1721348396.853 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2024-07-19T00:19:56.856Z,1721348396.856 [IBIT](DEBUG): Construct Initiated Built In Test.
2024-07-19T00:19:56.869Z,1721348396.869 [IBIT] Loaded
2024-07-19T00:19:56.869Z,1721348396.869 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2024-07-19T00:19:56.875Z,1721348396.875 [CBIT](DEBUG): Construct Continuous Built In Test.
2024-07-19T00:19:56.980Z,1721348396.980 [CBIT] Loaded
2024-07-19T00:19:56.980Z,1721348396.980 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2024-07-19T00:19:56.981Z,1721348396.981 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2024-07-19T00:19:56.987Z,1721348396.987 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2024-07-19T00:19:56.990Z,1721348396.990 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2024-07-19T00:19:57.001Z,1721348397.001 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2024-07-19T00:19:57.002Z,1721348397.002 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C074E0
2024-07-19T00:19:57.003Z,1721348397.003 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4204
2024-07-19T00:19:57.007Z,1721348397.007 [Supervisor](INFO): Main Thread ID is 829
2024-07-19T00:19:57.007Z,1721348397.007 [Supervisor](DEBUG): Running supervisor.
2024-07-19T00:19:57.008Z,1721348397.008 [CommandExec ThreadHandler](INFO): Handler Thread ID is 4205
2024-07-19T00:19:57.009Z,1721348397.009 [CommandExec](INFO): Initializing the command executive.
2024-07-19T00:19:57.010Z,1721348397.010 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4206
2024-07-19T00:19:57.012Z,1721348397.012 [controlThread ThreadHandler](INFO): Handler Thread ID is 4207
2024-07-19T00:19:57.012Z,1721348397.012 [controlThread](DEBUG): Initializing ControlThread
2024-07-19T00:19:57.013Z,1721348397.013 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2024-07-19T00:19:57.015Z,1721348397.015 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2024-07-19T00:19:57.016Z,1721348397.016 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2024-07-19T00:19:57.016Z,1721348397.016 [LoopControl](DEBUG): Initialize LoopControlComponent.
2024-07-19T00:19:57.017Z,1721348397.017 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2024-07-19T00:19:57.018Z,1721348397.018 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2024-07-19T00:19:57.018Z,1721348397.018 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2024-07-19T00:19:57.018Z,1721348397.018 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2024-07-19T00:19:57.019Z,1721348397.019 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2024-07-19T00:19:57.020Z,1721348397.020 [NavChart](DEBUG): Initialize NavChart Navigation.
2024-07-19T00:19:57.020Z,1721348397.020 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2024-07-19T00:19:57.029Z,1721348397.029 [SBIT](INFO): Initialize SBIT Component.
2024-07-19T00:19:57.029Z,1721348397.029 [SBIT](IMPORTANT): git: 2024-07-18
2024-07-19T00:19:57.030Z,1721348397.030 [SBIT](INFO): git hash: e777c895b2d7d743d45b75a88845e2c8d3f60819
2024-07-19T00:19:57.030Z,1721348397.030 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2024-07-19T00:19:57.031Z,1721348397.031 [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-19T00:19:57.032Z,1721348397.032 [SBIT](INFO): Beginning SBIT in 110.000000 seconds.
2024-07-19T00:19:57.032Z,1721348397.032 [IBIT](INFO): Initialize IBIT Component.
2024-07-19T00:19:57.033Z,1721348397.033 [CBIT](DEBUG): Initialize CBIT Component.
2024-07-19T00:19:57.034Z,1721348397.034 [logger ThreadHandler](INFO): Handler Thread ID is 4208
2024-07-19T00:19:57.045Z,1721348397.045 [CBIT](DEBUG): Initialized mux pins.
2024-07-19T00:19:57.045Z,1721348397.045 [CBIT](DEBUG): Initializing the watchdog timer.
2024-07-19T00:19:57.053Z,1721348397.053 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 4209
2024-07-19T00:19:57.065Z,1721348397.065 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 4210
2024-07-19T00:19:57.067Z,1721348397.067 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2024-07-19T00:19:57.069Z,1721348397.069 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2024-07-19T00:19:57.069Z,1721348397.069 [CBIT](DEBUG): Initializing heartbeat.
2024-07-19T00:19:57.077Z,1721348397.077 [Onboard ThreadHandler](INFO): Handler Thread ID is 4211
2024-07-19T00:19:57.095Z,1721348397.095 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4212
2024-07-19T00:19:57.114Z,1721348397.114 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 4213
2024-07-19T00:19:57.114Z,1721348397.114 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2024-07-19T00:19:57.118Z,1721348397.118 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 4215
2024-07-19T00:19:57.121Z,1721348397.121 [WetLabsBB2FL](INFO): Powering up
2024-07-19T00:19:57.122Z,1721348397.122 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4217
2024-07-19T00:19:57.124Z,1721348397.124 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI60M.000
2024-07-19T00:19:57.125Z,1721348397.125 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US2MI60M.000
2024-07-19T00:19:57.125Z,1721348397.125 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI1ND.000
2024-07-19T00:19:57.127Z,1721348397.127 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4MI1ND.000
2024-07-19T00:19:57.127Z,1721348397.127 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI1OD.000
2024-07-19T00:19:57.128Z,1721348397.128 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4MI1OD.000
2024-07-19T00:19:57.129Z,1721348397.129 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI51M.000
2024-07-19T00:19:57.131Z,1721348397.131 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4MI51M.000
2024-07-19T00:19:57.131Z,1721348397.131 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI1OF.000
2024-07-19T00:19:57.132Z,1721348397.132 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4MI1OF.000
2024-07-19T00:19:57.132Z,1721348397.132 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI1NF.000
2024-07-19T00:19:57.134Z,1721348397.134 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4MI1NF.000
2024-07-19T00:19:57.137Z,1721348397.137 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US4MI1NF.000
2024-07-19T00:19:57.141Z,1721348397.141 [CBIT](DEBUG): Deactivating GF circuits.
2024-07-19T00:19:57.141Z,1721348397.141 [CBIT](DEBUG): Deactivating emergency mode.
2024-07-19T00:19:57.181Z,1721348397.181 [CBIT](DEBUG): Backplane powered.
2024-07-19T00:19:57.181Z,1721348397.181 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2024-07-19T00:19:57.182Z,1721348397.182 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2024-07-19T00:19:57.182Z,1721348397.182 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2024-07-19T00:19:57.185Z,1721348397.185 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2024-07-19T00:19:57.216Z,1721348397.216 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2024-07-19T00:19:57.233Z,1721348397.233 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2024-07-19T00:19:57.281Z,1721348397.281 [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-19T00:19:57.282Z,1721348397.282 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2024-07-19T00:19:57.282Z,1721348397.282 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2024-07-19T00:19:57.284Z,1721348397.284 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2024-07-19T00:19:57.450Z,1721348397.450 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US4MI1NF.000
2024-07-19T00:19:57.501Z,1721348397.501 [Radio_Surface](INFO): Powering up
2024-07-19T00:19:57.565Z,1721348397.565 [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-19T00:19:57.570Z,1721348397.570 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2024-07-19T00:19:57.571Z,1721348397.571 [Default:A.Wait](DEBUG): Construct Wait.
2024-07-19T00:19:57.590Z,1721348397.590 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-07-19T00:19:57.634Z,1721348397.634 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2024-07-19T00:19:57.636Z,1721348397.636 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2024-07-19T00:19:57.658Z,1721348397.658 [Default:E.Execute](DEBUG): Construct Execute.
2024-07-19T00:19:57.661Z,1721348397.661 [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-19T00:19:57.669Z,1721348397.669 [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-19T00:19:57.689Z,1721348397.689 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-07-19T00:19:57.759Z,1721348397.759 [Power24vConverter](INFO): Powering up.
2024-07-19T00:19:57.760Z,1721348397.760 [Sonardyne_Nano](INFO): Initializing.
2024-07-19T00:19:57.811Z,1721348397.811 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2024-07-19T00:19:57.817Z,1721348397.817 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2024-07-19T00:19:57.818Z,1721348397.818 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-07-19T00:19:57.825Z,1721348397.825 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-07-19T00:19:57.826Z,1721348397.826 [MassServo](DEBUG): Initializing EZServoServo.
2024-07-19T00:19:57.833Z,1721348397.833 [MassServo](DEBUG): Initializing MassServo.
2024-07-19T00:19:57.834Z,1721348397.834 [RudderServo](DEBUG): Initializing EZServoServo.
2024-07-19T00:19:57.842Z,1721348397.842 [RudderServo](DEBUG): Initializing RudderServo.
2024-07-19T00:19:57.842Z,1721348397.842 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-07-19T00:19:57.849Z,1721348397.849 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-07-19T00:19:59.229Z,1721348399.229 [WetLabsBB2FL](INFO): Powering down
2024-07-19T00:19:59.699Z,1721348399.699 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2024-07-19T00:20:00.986Z,1721348400.986 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2107
2024-07-19T00:20:09.184Z,1721348409.184 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US4MI1NF.000
2024-07-19T00:20:14.184Z,1721348414.184 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US4MI1NF.000
2024-07-19T00:20:16.547Z,1721348416.547 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US4MI1NF.000
2024-07-19T00:20:16.564Z,1721348416.564 [NavChartDb](INFO): # of records loaded: 4667
2024-07-19T00:20:16.566Z,1721348416.566 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US4MI1NF.000
2024-07-19T00:20:16.641Z,1721348416.641 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US4MI1OF.000
2024-07-19T00:20:18.620Z,1721348418.620 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US4MI1OF.000
2024-07-19T00:20:25.962Z,1721348425.962 [NAL9602](INFO): Powering up NAL9602
2024-07-19T00:20:27.703Z,1721348427.703 [NavChartDb](INFO): # of records loaded: 5000
2024-07-19T00:20:34.877Z,1721348434.877 [NavChartDb](INFO): # of records loaded: 10000
2024-07-19T00:20:36.874Z,1721348436.874 [NAL9602](INFO): NAL9602 initialized
2024-07-19T00:20:42.286Z,1721348442.286 [NavChartDb](INFO): # of records loaded: 15000
2024-07-19T00:20:51.925Z,1721348451.925 [NavChartDb](INFO): # of records loaded: 20000
2024-07-19T00:20:59.311Z,1721348459.311 [NavChartDb](INFO): # of records loaded: 25000
2024-07-19T00:21:02.727Z,1721348462.727 [NAL9602](INFO): SBD MO Status=2, MOMSN=5272, MT Status=2, MTMSN=0
2024-07-19T00:21:02.728Z,1721348462.728 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-07-19T00:21:06.874Z,1721348466.874 [NavChartDb](INFO): # of records loaded: 30000
2024-07-19T00:21:11.714Z,1721348471.714 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-19T00:21:15.085Z,1721348475.085 [NavChartDb](INFO): # of records loaded: 35000
2024-07-19T00:21:15.494Z,1721348475.494 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US4MI1OF.000
2024-07-19T00:21:24.308Z,1721348484.308 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US4MI1OF.000
2024-07-19T00:21:25.439Z,1721348485.439 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-07-19T00:21:25.558Z,1721348485.558 [NavChartDb](INFO): # of records loaded: 40000
2024-07-19T00:21:28.176Z,1721348488.176 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-19T00:21:33.311Z,1721348493.311 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US4MI1OF.000
2024-07-19T00:21:33.330Z,1721348493.330 [NavChartDb](INFO): # of records loaded: 42018
2024-07-19T00:21:33.335Z,1721348493.335 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US4MI1OF.000
2024-07-19T00:21:33.413Z,1721348493.413 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US4MI51M.000
2024-07-19T00:21:35.224Z,1721348495.224 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US4MI51M.000
2024-07-19T00:21:42.832Z,1721348502.832 [NavChartDb](INFO): # of records loaded: 5000
2024-07-19T00:21:44.393Z,1721348504.393 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-07-19T00:21:47.603Z,1721348507.603 [SBIT](IMPORTANT): Beginning Startup BIT
2024-07-19T00:21:47.632Z,1721348507.632 [CBIT](IMPORTANT): Beginning ground fault scan
2024-07-19T00:21:51.639Z,1721348511.639 [NavChartDb](INFO): # of records loaded: 10000
2024-07-19T00:21:52.921Z,1721348512.921 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-07-19T00:21:58.585Z,1721348518.585 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.001393
CHAN A1 (24V): -0.008101
CHAN A2 (12V): -0.005026
CHAN A3 (5V): -0.003025
CHAN B0 (3.3V): -0.001023
CHAN B1 (3.15aV): -0.001081
CHAN B2 (3.15bV): -0.001272
CHAN B3 (GND): -0.000665
OPEN: 0.003884
Full Scale: +/- 1 mA
2024-07-19T00:22:00.012Z,1721348520.012 [NavChartDb](INFO): # of records loaded: 15000
2024-07-19T00:22:00.090Z,1721348520.090 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-19T00:22:05.850Z,1721348525.850 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-07-19T00:22:08.125Z,1721348528.125 [NavChartDb](INFO): # of records loaded: 20000
2024-07-19T00:22:16.052Z,1721348536.052 [NavChartDb](INFO): # of records loaded: 25000
2024-07-19T00:22:16.654Z,1721348536.654 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-19T00:22:24.007Z,1721348544.007 [NavChartDb](INFO): # of records loaded: 30000
2024-07-19T00:22:28.631Z,1721348548.631 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US4MI51M.000
2024-07-19T00:22:32.640Z,1721348552.640 [NavChartDb](INFO): # of records loaded: 35000
2024-07-19T00:22:33.016Z,1721348553.016 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-07-19T00:22:40.253Z,1721348560.253 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US4MI51M.000
2024-07-19T00:22:41.348Z,1721348561.348 [SBIT](IMPORTANT): SBIT PASSED
2024-07-19T00:22:41.348Z,1721348561.348 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-07-19T00:22:41.353Z,1721348561.353 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=8 count;
2024-07-19T00:22:41.353Z,1721348561.353 [SBIT](IMPORTANT): CTD_Seabird.minSalinityBound=0 practical_salinity_unit;
2024-07-19T00:22:41.353Z,1721348561.353 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;
2024-07-19T00:22:41.354Z,1721348561.354 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=250 cubic_centimeter;
2024-07-19T00:22:41.354Z,1721348561.354 [SBIT](IMPORTANT): VerticalControl.massDefault=2.25 centimeter;
2024-07-19T00:22:41.735Z,1721348561.735 [MissionManager](IMPORTANT): Started mission Startup
2024-07-19T00:22:41.735Z,1721348561.735 [Startup] Running Loop=1
2024-07-19T00:22:41.735Z,1721348561.735 [Startup](DEBUG): Aggregate::initialize Startup
2024-07-19T00:22:41.735Z,1721348561.735 [Startup:A.GoToSurface] Running Loop=1
2024-07-19T00:22:41.735Z,1721348561.735 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-07-19T00:22:41.736Z,1721348561.736 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-07-19T00:22:41.736Z,1721348561.736 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-07-19T00:22:41.741Z,1721348561.741 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-07-19T00:22:41.741Z,1721348561.741 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-07-19T00:22:41.742Z,1721348561.742 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-07-19T00:22:41.742Z,1721348561.742 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-07-19T00:22:41.744Z,1721348561.744 [Startup:StartupSatComms] Running Loop=1
2024-07-19T00:22:41.744Z,1721348561.744 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2024-07-19T00:22:41.744Z,1721348561.744 [Startup:StartupSatComms:A] Running Loop=1
2024-07-19T00:22:42.143Z,1721348562.143 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2024-07-19T00:22:46.182Z,1721348566.182 [NavChartDb](INFO): # of records loaded: 40000
2024-07-19T00:22:47.762Z,1721348567.762 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-07-19T00:22:48.451Z,1721348568.451 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US4MI51M.000
2024-07-19T00:22:48.514Z,1721348568.514 [NavChartDb](INFO): # of records loaded: 40792
2024-07-19T00:22:48.519Z,1721348568.519 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US4MI51M.000
2024-07-19T00:22:48.553Z,1721348568.553 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US4MI1OD.000
2024-07-19T00:22:48.913Z,1721348568.913 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US4MI1OD.000
2024-07-19T00:22:49.781Z,1721348569.781 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-19T00:22:54.238Z,1721348574.238 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-07-19T00:22:56.882Z,1721348576.882 [NavChartDb](INFO): # of records loaded: 5000
2024-07-19T00:22:57.090Z,1721348577.090 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2024-07-19T00:22:57.090Z,1721348577.090 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-07-19T00:22:57.113Z,1721348577.113 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-07-19T00:22:57.523Z,1721348577.523 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-07-19T00:22:57.523Z,1721348577.523 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2024-07-19T00:23:02.508Z,1721348582.508 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US4MI1OD.000
2024-07-19T00:23:03.586Z,1721348583.586 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US4MI1OD.000
2024-07-19T00:23:04.903Z,1721348584.903 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US4MI1OD.000
2024-07-19T00:23:04.940Z,1721348584.940 [NavChartDb](INFO): # of records loaded: 9682
2024-07-19T00:23:04.943Z,1721348584.943 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US4MI1OD.000
2024-07-19T00:23:04.985Z,1721348584.985 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US4MI1ND.000
2024-07-19T00:23:05.922Z,1721348585.922 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US4MI1ND.000
2024-07-19T00:23:05.941Z,1721348585.941 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-19T00:23:08.770Z,1721348588.770 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-07-19T00:23:14.623Z,1721348594.623 [NavChartDb](INFO): # of records loaded: 5000
2024-07-19T00:23:22.910Z,1721348602.910 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-19T00:23:24.527Z,1721348604.527 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-07-19T00:23:24.687Z,1721348604.687 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004789
2024-07-19T00:23:25.536Z,1721348605.536 [NavChartDb](INFO): # of records loaded: 10000
2024-07-19T00:23:37.381Z,1721348617.381 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US4MI1ND.000
2024-07-19T00:23:37.401Z,1721348617.401 [NavChartDb](INFO): # of records loaded: 15000
2024-07-19T00:23:39.898Z,1721348619.898 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-07-19T00:23:41.951Z,1721348621.951 [Startup:StartupSatComms:A](INFO): Timed out from 2024-07-19T00:22:41.7Z
2024-07-19T00:23:41.951Z,1721348621.951 [Startup:StartupSatComms:A] Stopped
2024-07-19T00:23:41.952Z,1721348621.952 [Startup:StartupSatComms:B] Running Loop=1
2024-07-19T00:23:42.350Z,1721348622.350 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-07-19T00:23:44.751Z,1721348624.751 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US4MI1ND.000
2024-07-19T00:23:49.256Z,1721348629.256 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US4MI1ND.000
2024-07-19T00:23:49.375Z,1721348629.375 [NavChartDb](INFO): # of records loaded: 19081
2024-07-19T00:23:49.378Z,1721348629.378 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US4MI1ND.000
2024-07-19T00:23:49.379Z,1721348629.379 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US2MI60M.000
2024-07-19T00:23:53.662Z,1721348633.662 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US2MI60M.000
2024-07-19T00:23:54.938Z,1721348634.938 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-07-19T00:23:55.577Z,1721348635.577 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240718T143859/Courier0331.lzma
2024-07-19T00:23:56.579Z,1721348636.579 [DataOverHttps](INFO): Moved sent file to Logs/20240718T143859/Courier0331.lzma.bak
2024-07-19T00:23:56.579Z,1721348636.579 [DataOverHttps](INFO): SBD MOMSN=21367949
2024-07-19T00:24:06.494Z,1721348646.494 [NavChartDb](INFO): # of records loaded: 5000
2024-07-19T00:24:10.694Z,1721348650.694 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-07-19T00:24:23.180Z,1721348663.180 [NavChartDb](INFO): # of records loaded: 10000
2024-07-19T00:24:24.032Z,1721348664.032 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240719T001945/Courier0000.lzma
2024-07-19T00:24:25.035Z,1721348665.035 [DataOverHttps](INFO): Moved sent file to Logs/20240719T001945/Courier0000.lzma.bak
2024-07-19T00:24:25.035Z,1721348665.035 [DataOverHttps](INFO): SBD MOMSN=21367951
2024-07-19T00:24:26.881Z,1721348666.881 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-07-19T00:24:36.860Z,1721348676.860 [NavChartDb](INFO): # of records loaded: 15000
2024-07-19T00:24:42.511Z,1721348682.511 [Startup:StartupSatComms:B](INFO): Timed out from 2024-07-19T00:23:41.0Z
2024-07-19T00:24:42.511Z,1721348682.511 [Startup:StartupSatComms:B] Stopped
2024-07-19T00:24:42.511Z,1721348682.511 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2024-07-19T00:24:42.511Z,1721348682.511 [Startup:StartupSatComms] Stopped
2024-07-19T00:24:42.511Z,1721348682.511 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2024-07-19T00:24:42.512Z,1721348682.512 [Startup](INFO): Completed Startup
2024-07-19T00:24:42.512Z,1721348682.512 [MissionManager](INFO): Startup is completed.
2024-07-19T00:24:42.512Z,1721348682.512 [MissionManager](INFO): Uninitializing Mission Startup
2024-07-19T00:24:42.512Z,1721348682.512 [Startup] Stopped
2024-07-19T00:24:42.512Z,1721348682.512 [Startup](DEBUG): Aggregate::uninitialize Startup
2024-07-19T00:24:42.513Z,1721348682.513 [Startup:A.GoToSurface] Stopped
2024-07-19T00:24:42.513Z,1721348682.513 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-07-19T00:24:42.650Z,1721348682.650 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-07-19T00:24:42.732Z,1721348682.732 [MissionManager](IMPORTANT): Started mission Default
2024-07-19T00:24:42.732Z,1721348682.732 [Default] Running Loop=1
2024-07-19T00:24:42.753Z,1721348682.753 [Default](DEBUG): Aggregate::initialize Default
2024-07-19T00:24:42.753Z,1721348682.753 [Default:B.GoToSurface] Running Loop=1
2024-07-19T00:24:42.753Z,1721348682.753 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-07-19T00:24:42.753Z,1721348682.753 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-07-19T00:24:42.754Z,1721348682.754 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-07-19T00:24:42.754Z,1721348682.754 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-07-19T00:24:42.754Z,1721348682.754 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-07-19T00:24:42.754Z,1721348682.754 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-07-19T00:24:42.755Z,1721348682.755 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-07-19T00:24:42.755Z,1721348682.755 [Default:A.Wait] Running Loop=1
2024-07-19T00:24:42.755Z,1721348682.755 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-07-19T00:24:56.027Z,1721348696.027 [Default:A.Wait](INFO): Done Waiting.
2024-07-19T00:24:56.027Z,1721348696.027 [Default:A.Wait] Stopped
2024-07-19T00:24:56.027Z,1721348696.027 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-07-19T00:24:56.407Z,1721348696.407 [Default:CheckIn] Running Loop=1
2024-07-19T00:24:56.407Z,1721348696.407 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-19T00:24:56.407Z,1721348696.407 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-19T00:24:56.814Z,1721348696.814 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2024-07-19T00:24:58.002Z,1721348698.002 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-07-19T00:24:58.231Z,1721348698.231 [NavChartDb](INFO): # of records loaded: 20000
2024-07-19T00:25:06.893Z,1721348706.893 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20240718T143859/Express0329.lzma
2024-07-19T00:25:07.895Z,1721348707.895 [DataOverHttps](INFO): Moved sent file to Logs/20240718T143859/Express0329.lzma.bak
2024-07-19T00:25:07.895Z,1721348707.895 [DataOverHttps](INFO): SBD MOMSN=21367954
2024-07-19T00:25:10.944Z,1721348710.944 [NavChartDb](INFO): # of records loaded: 25000
2024-07-19T00:25:13.782Z,1721348713.782 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2024-07-19T00:25:18.483Z,1721348718.483 [NavChartDb](INFO): # of records loaded: 30000
2024-07-19T00:25:28.327Z,1721348728.327 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2024-07-19T00:25:29.036Z,1721348729.036 [NavChartDb](INFO): # of records loaded: 35000
2024-07-19T00:25:39.642Z,1721348739.642 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-07-19T00:25:39.845Z,1721348739.845 [NavChartDb](INFO): # of records loaded: 40000
2024-07-19T00:25:42.065Z,1721348742.065 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2024-07-19T00:25:45.305Z,1721348745.305 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,002544.00,A,4538.84230,N,08428.34029,W,1.380,0.00,190724,,,A*75
2024-07-19T00:25:45.308Z,1721348745.308 [NAL9602](INFO): GPS fix at 20240719T002544: (45.647372, -84.472338)
2024-07-19T00:25:45.327Z,1721348745.327 [Default:CheckIn:Read_GPS] Stopped
2024-07-19T00:25:45.327Z,1721348745.327 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-19T00:25:45.740Z,1721348745.740 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-07-19T00:25:48.995Z,1721348748.995 [NavChartDb](INFO): # of records loaded: 45000
2024-07-19T00:25:52.173Z,1721348752.173 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US2MI60M.000
2024-07-19T00:25:56.745Z,1721348756.745 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2024-07-19T00:26:03.697Z,1721348763.697 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20240719T001945/Courier0004.lzma
2024-07-19T00:26:04.183Z,1721348764.183 [NavChartDb](INFO): # of records loaded: 50000
2024-07-19T00:26:04.699Z,1721348764.699 [DataOverHttps](INFO): Moved sent file to Logs/20240719T001945/Courier0004.lzma.bak
2024-07-19T00:26:04.699Z,1721348764.699 [DataOverHttps](INFO): SBD MOMSN=21367958
2024-07-19T00:26:11.692Z,1721348771.693 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2024-07-19T00:26:17.787Z,1721348777.787 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-19T00:26:18.614Z,1721348778.614 [NavChartDb](INFO): # of records loaded: 55000
2024-07-19T00:26:26.664Z,1721348786.664 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2024-07-19T00:26:40.220Z,1721348800.220 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-07-19T00:26:44.625Z,1721348804.625 [NavChartDb](INFO): # of records loaded: 60000
2024-07-19T00:26:46.648Z,1721348806.648 [DataOverHttps](INFO): Sending 154 bytes from file Logs/20240718T143859/Express0332.lzma
2024-07-19T00:26:47.647Z,1721348807.647 [DataOverHttps](INFO): Moved sent file to Logs/20240718T143859/Express0332.lzma.bak
2024-07-19T00:26:47.647Z,1721348807.647 [DataOverHttps](INFO): SBD MOMSN=21367961
2024-07-19T00:26:48.746Z,1721348808.746 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US2MI60M.000
2024-07-19T00:26:54.631Z,1721348814.631 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2024-07-19T00:26:56.817Z,1721348816.817 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-07-19T00:27:06.342Z,1721348826.342 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US2MI60M.000
2024-07-19T00:27:07.508Z,1721348827.508 [NavChartDb](INFO): # of records loaded: 64544
2024-07-19T00:27:07.521Z,1721348827.521 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US2MI60M.000
2024-07-19T00:27:08.730Z,1721348828.730 [NavChartDb](INFO): Creating index for soundings
2024-07-19T00:27:08.787Z,1721348828.787 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2024-07-19T00:27:23.177Z,1721348843.177 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20240718T143859/Express0335.lzma
2024-07-19T00:27:23.332Z,1721348843.332 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2024-07-19T00:27:24.179Z,1721348844.179 [DataOverHttps](INFO): Moved sent file to Logs/20240718T143859/Express0335.lzma.bak
2024-07-19T00:27:24.179Z,1721348844.179 [DataOverHttps](INFO): SBD MOMSN=21367965
2024-07-19T00:27:28.906Z,1721348848.906 [NavChartDb](INFO): Creating index for 0p0
2024-07-19T00:27:39.064Z,1721348859.064 [NavChartDb](INFO): Creating index for 1p8
2024-07-19T00:27:44.440Z,1721348864.440 [NavChartDb](INFO): Creating index for 3p6
2024-07-19T00:27:47.095Z,1721348867.095 [NavChartDb](INFO): Creating index for 5p4
2024-07-19T00:27:56.667Z,1721348876.667 [NavChartDb](INFO): Creating index for 7p3
2024-07-19T00:27:57.270Z,1721348877.270 [DataOverHttps](INFO): Sending 944 bytes from file Logs/20240719T001945/Express0001.lzma
2024-07-19T00:27:58.270Z,1721348878.270 [DataOverHttps](INFO): Moved sent file to Logs/20240719T001945/Express0001.lzma.bak
2024-07-19T00:27:58.271Z,1721348878.271 [DataOverHttps](INFO): SBD MOMSN=21367968
2024-07-19T00:28:09.217Z,1721348889.217 [NavChartDb](INFO): Creating index for 9p1
2024-07-19T00:28:19.788Z,1721348899.788 [BPC1](INFO): Calculating totals. Valid battery stick count: 51. Valid reserve battery stick count: 5.
2024-07-19T00:28:19.836Z,1721348899.836 [BPC1](INFO): Received data from all battery sticks.
2024-07-19T00:28:33.625Z,1721348913.625 [DataOverHttps](INFO): Sending 362 bytes from file Logs/20240719T001945/Express0005.lzma
2024-07-19T00:28:34.626Z,1721348914.626 [DataOverHttps](INFO): Moved sent file to Logs/20240719T001945/Express0005.lzma.bak
2024-07-19T00:28:34.627Z,1721348914.627 [DataOverHttps](INFO): SBD MOMSN=21367996
2024-07-19T00:28:41.148Z,1721348921.148 [Default:CheckIn:Read_Iridium] Stopped
2024-07-19T00:28:41.148Z,1721348921.148 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-19T00:28:41.148Z,1721348921.148 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-19T00:29:58.722Z,1721348998.722 [Power24vConverter](INFO): Powering down.
2024-07-19T00:31:55.634Z,1721349115.634 [CommandExec](IMPORTANT): got command get latitude
2024-07-19T00:31:55.634Z,1721349115.634 [CommandExec](IMPORTANT): latitude 45.647372 arcdeg
2024-07-19T00:32:00.747Z,1721349120.747 [CommandExec](IMPORTANT): got command show variable distance
2024-07-19T00:32:00.750Z,1721349120.750 [CommandExec](IMPORTANT): distance_from_shore (meter)
2024-07-19T00:32:00.751Z,1721349120.751 [CommandExec](IMPORTANT): fix_distance_made_good (unknown)
2024-07-19T00:32:00.751Z,1721349120.751 [CommandExec](IMPORTANT): fix_residual_distance (unknown)
2024-07-19T00:32:00.751Z,1721349120.751 [CommandExec](IMPORTANT): fix_residual_percent_distance_traveled (unknown)
2024-07-19T00:32:00.753Z,1721349120.753 [CommandExec](IMPORTANT): platform_distance_wrt_ground (unknown)
2024-07-19T00:32:00.753Z,1721349120.753 [CommandExec](IMPORTANT): platform_distance_wrt_sea_water (meter)
2024-07-19T00:32:00.780Z,1721349120.780 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.loadAtStartup (bool)
2024-07-19T00:32:00.789Z,1721349120.789 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.verbosity (count)
2024-07-19T00:32:00.789Z,1721349120.789 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex1 (degree)
2024-07-19T00:32:00.789Z,1721349120.789 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex1 (degree)
2024-07-19T00:32:00.790Z,1721349120.790 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex2 (degree)
2024-07-19T00:32:00.790Z,1721349120.790 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex2 (degree)
2024-07-19T00:32:00.790Z,1721349120.790 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex3 (degree)
2024-07-19T00:32:00.791Z,1721349120.791 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex3 (degree)
2024-07-19T00:32:00.791Z,1721349120.791 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex4 (degree)
2024-07-19T00:32:00.791Z,1721349120.791 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex4 (degree)
2024-07-19T00:32:00.792Z,1721349120.792 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex5 (degree)
2024-07-19T00:32:00.792Z,1721349120.792 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex5 (degree)
2024-07-19T00:32:00.792Z,1721349120.792 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex6 (degree)
2024-07-19T00:32:00.811Z,1721349120.811 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex6 (degree)
2024-07-19T00:32:00.811Z,1721349120.811 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex7 (degree)
2024-07-19T00:32:00.812Z,1721349120.812 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex7 (degree)
2024-07-19T00:32:00.812Z,1721349120.812 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex8 (degree)
2024-07-19T00:32:00.813Z,1721349120.813 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex8 (degree)
2024-07-19T00:32:00.813Z,1721349120.813 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex9 (degree)
2024-07-19T00:32:00.813Z,1721349120.813 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex9 (degree)
2024-07-19T00:32:00.814Z,1721349120.814 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex10 (degree)
2024-07-19T00:32:00.814Z,1721349120.814 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex10 (degree)
2024-07-19T00:32:00.816Z,1721349120.816 [CommandExec](IMPORTANT): LineCapture.rolloutDistance (meter)
2024-07-19T00:32:00.898Z,1721349120.898 [CommandExec](IMPORTANT): MassServo.deviationDistance (millimeter)
2024-07-19T00:32:00.976Z,1721349120.976 [CommandExec](IMPORTANT): GobyModem.maxDistance (kilometer)
2024-07-19T00:32:00.986Z,1721349120.986 [CommandExec](IMPORTANT): Rowe_600.acousticBlankingDistance (meter)
2024-07-19T00:32:01.041Z,1721349121.041 [CommandExec](IMPORTANT): SpeedCalculator.platform_distance_wrt_sea_water (meter)
2024-07-19T00:32:01.042Z,1721349121.042 [CommandExec](IMPORTANT): DeadReckonUsingMultipleVelocitySources.fix_distance_made_good (meter)
2024-07-19T00:32:01.043Z,1721349121.043 [CommandExec](IMPORTANT): DeadReckonUsingMultipleVelocitySources.fix_residual_distance (meter)
2024-07-19T00:32:01.043Z,1721349121.043 [CommandExec](IMPORTANT): DeadReckonUsingMultipleVelocitySources.fix_residual_percent_distance_traveled (percent)
2024-07-19T00:32:01.044Z,1721349121.044 [CommandExec](IMPORTANT): NavChart.distance_from_shore (meter)
2024-07-19T00:32:01.190Z,1721349121.190 [CommandExec](IMPORTANT): NavChartDb.closestDistance (meter)
2024-07-19T00:32:01.190Z,1721349121.190 [CommandExec](IMPORTANT): NavChartDb.nextDistance (meter)
2024-07-19T00:32:13.040Z,1721349133.040 [CommandExec](IMPORTANT): got command get NavChart.distance_from_shore
2024-07-19T00:32:13.041Z,1721349133.041 [CommandExec](IMPORTANT): NavChart.distance_from_shore 22.086843 m
2024-07-19T00:33:26.227Z,1721349206.227 [CommandExec](IMPORTANT): got command
2024-07-19T00:33:26.228Z,1721349206.228 [CommandExec](FAULT): Incomplete syntax. Try: help
2024-07-19T00:33:28.003Z,1721349208.003 [CommandExec](IMPORTANT): got command failComponent
2024-07-19T00:33:28.003Z,1721349208.003 [CommandExec](IMPORTANT): Failed components:
2024-07-19T00:33:28.003Z,1721349208.003 [CommandExec](IMPORTANT): No failed Components.
2024-07-19T00:33:41.745Z,1721349221.745 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-19T00:33:41.745Z,1721349221.745 [Default:CheckIn:C.Wait] Stopped
2024-07-19T00:33:41.745Z,1721349221.745 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-19T00:33:41.746Z,1721349221.746 [Default:CheckIn:D] Running Loop=1
2024-07-19T00:33:42.145Z,1721349222.145 [Default:CheckIn:D] Stopped
2024-07-19T00:33:42.145Z,1721349222.145 [Default:CheckIn:E] Running Loop=1
2024-07-19T00:33:42.583Z,1721349222.583 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 8.990214 min
2024-07-19T00:33:42.583Z,1721349222.583 [Default:CheckIn:E] Stopped
2024-07-19T00:33:42.583Z,1721349222.583 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-19T00:33:42.583Z,1721349222.583 [Default:CheckIn] Stopped
2024-07-19T00:33:42.583Z,1721349222.583 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-19T00:33:42.583Z,1721349222.583 [Default:CheckIn](INFO): Running loop #2
2024-07-19T00:33:42.583Z,1721349222.583 [Default:CheckIn] Running Loop=2
2024-07-19T00:33:42.584Z,1721349222.584 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-19T00:33:42.584Z,1721349222.584 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-19T00:33:44.559Z,1721349224.559 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,003344.00,A,4538.83791,N,08428.36118,W,0.078,352.00,190724,,,A*7B
2024-07-19T00:33:44.562Z,1721349224.562 [NAL9602](INFO): GPS fix at 20240719T003344: (45.647298, -84.472686)
2024-07-19T00:33:44.591Z,1721349224.591 [Default:CheckIn:Read_GPS] Stopped
2024-07-19T00:33:44.591Z,1721349224.591 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-19T00:33:55.460Z,1721349235.460 [DataOverHttps](INFO): Sending 201 bytes from file Logs/20240719T001945/Courier0007.lzma
2024-07-19T00:33:56.463Z,1721349236.463 [DataOverHttps](INFO): Moved sent file to Logs/20240719T001945/Courier0007.lzma.bak
2024-07-19T00:33:56.463Z,1721349236.463 [DataOverHttps](INFO): SBD MOMSN=21368014
2024-07-19T00:34:15.308Z,1721349255.308 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-07-19T00:34:15.381Z,1721349255.381 [NAL9602](ERROR): received:
+CSQ:0
OK72, 2, 0, 0, 0
OK
2024-07-19T00:34:23.018Z,1721349263.018 [DataOverHttps](INFO): Sending 895 bytes from file Logs/20240719T001945/Express0008.lzma
2024-07-19T00:34:24.019Z,1721349264.019 [DataOverHttps](INFO): Moved sent file to Logs/20240719T001945/Express0008.lzma.bak
2024-07-19T00:34:24.019Z,1721349264.019 [DataOverHttps](INFO): SBD MOMSN=21368017
2024-07-19T00:34:33.067Z,1721349273.067 [Default:CheckIn:Read_Iridium] Stopped
2024-07-19T00:34:33.067Z,1721349273.067 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-19T00:34:33.067Z,1721349273.067 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-19T00:37:23.951Z,1721349443.951 [NAL9602](INFO): SBD MO Status=2, MOMSN=5272, MT Status=2, MTMSN=0
2024-07-19T00:37:23.951Z,1721349443.951 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-07-19T00:37:43.751Z,1721349463.751 [NAL9602](INFO): SBD MO Status=2, MOMSN=5272, MT Status=2, MTMSN=0
2024-07-19T00:37:43.751Z,1721349463.751 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-07-19T00:37:59.749Z,1721349479.749 [CommandExec](IMPORTANT): got command failComponent
2024-07-19T00:37:59.749Z,1721349479.749 [CommandExec](IMPORTANT): Failed components:
2024-07-19T00:37:59.750Z,1721349479.750 [CommandExec](IMPORTANT): No failed Components.
2024-07-19T00:38:17.186Z,1721349497.186 [CommandExec](IMPORTANT): got command show variable distance
2024-07-19T00:38:17.188Z,1721349497.188 [CommandExec](IMPORTANT): distance_from_shore (meter)
2024-07-19T00:38:17.189Z,1721349497.189 [CommandExec](IMPORTANT): fix_distance_made_good (unknown)
2024-07-19T00:38:17.189Z,1721349497.189 [CommandExec](IMPORTANT): fix_residual_distance (unknown)
2024-07-19T00:38:17.190Z,1721349497.190 [CommandExec](IMPORTANT): fix_residual_percent_distance_traveled (unknown)
2024-07-19T00:38:17.191Z,1721349497.191 [CommandExec](IMPORTANT): platform_distance_wrt_ground (unknown)
2024-07-19T00:38:17.191Z,1721349497.191 [CommandExec](IMPORTANT): platform_distance_wrt_sea_water (meter)
2024-07-19T00:38:17.207Z,1721349497.207 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.loadAtStartup (bool)
2024-07-19T00:38:17.207Z,1721349497.207 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.verbosity (count)
2024-07-19T00:38:17.207Z,1721349497.207 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex1 (degree)
2024-07-19T00:38:17.208Z,1721349497.208 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex1 (degree)
2024-07-19T00:38:17.208Z,1721349497.208 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex2 (degree)
2024-07-19T00:38:17.208Z,1721349497.208 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex2 (degree)
2024-07-19T00:38:17.209Z,1721349497.209 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex3 (degree)
2024-07-19T00:38:17.209Z,1721349497.209 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex3 (degree)
2024-07-19T00:38:17.209Z,1721349497.209 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex4 (degree)
2024-07-19T00:38:17.210Z,1721349497.210 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex4 (degree)
2024-07-19T00:38:17.210Z,1721349497.210 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex5 (degree)
2024-07-19T00:38:17.210Z,1721349497.210 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex5 (degree)
2024-07-19T00:38:17.211Z,1721349497.211 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex6 (degree)
2024-07-19T00:38:17.211Z,1721349497.211 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex6 (degree)
2024-07-19T00:38:17.211Z,1721349497.211 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex7 (degree)
2024-07-19T00:38:17.212Z,1721349497.212 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex7 (degree)
2024-07-19T00:38:17.212Z,1721349497.212 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex8 (degree)
2024-07-19T00:38:17.266Z,1721349497.266 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex8 (degree)
2024-07-19T00:38:17.267Z,1721349497.267 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex9 (degree)
2024-07-19T00:38:17.267Z,1721349497.267 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex9 (degree)
2024-07-19T00:38:17.267Z,1721349497.267 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex10 (degree)
2024-07-19T00:38:17.268Z,1721349497.268 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex10 (degree)
2024-07-19T00:38:17.270Z,1721349497.270 [CommandExec](IMPORTANT): LineCapture.rolloutDistance (meter)
2024-07-19T00:38:17.445Z,1721349497.445 [CommandExec](IMPORTANT): MassServo.deviationDistance (millimeter)
2024-07-19T00:38:17.552Z,1721349497.552 [CommandExec](IMPORTANT): GobyModem.maxDistance (kilometer)
2024-07-19T00:38:17.562Z,1721349497.562 [CommandExec](IMPORTANT): Rowe_600.acousticBlankingDistance (meter)
2024-07-19T00:38:17.636Z,1721349497.636 [CommandExec](IMPORTANT): SpeedCalculator.platform_distance_wrt_sea_water (meter)
2024-07-19T00:38:17.659Z,1721349497.659 [CommandExec](IMPORTANT): DeadReckonUsingMultipleVelocitySources.fix_distance_made_good (meter)
2024-07-19T00:38:17.660Z,1721349497.660 [CommandExec](IMPORTANT): DeadReckonUsingMultipleVelocitySources.fix_residual_distance (meter)
2024-07-19T00:38:17.660Z,1721349497.660 [CommandExec](IMPORTANT): DeadReckonUsingMultipleVelocitySources.fix_residual_percent_distance_traveled (percent)
2024-07-19T00:38:17.661Z,1721349497.661 [CommandExec](IMPORTANT): NavChart.distance_from_shore (meter)
2024-07-19T00:38:17.839Z,1721349497.839 [CommandExec](IMPORTANT): NavChartDb.closestDistance (meter)
2024-07-19T00:38:17.839Z,1721349497.839 [CommandExec](IMPORTANT): NavChartDb.nextDistance (meter)
2024-07-19T00:38:34.165Z,1721349514.165 [CommandExec](IMPORTANT): got command get NavChart.distance_from_shore
2024-07-19T00:38:34.166Z,1721349514.166 [CommandExec](IMPORTANT): NavChart.distance_from_shore 46.567749 m
2024-07-19T00:38:46.787Z,1721349526.787 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-07-19T00:39:17.490Z,1721349557.490 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-19T00:39:33.659Z,1721349573.659 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-19T00:39:33.660Z,1721349573.660 [Default:CheckIn:C.Wait] Stopped
2024-07-19T00:39:33.660Z,1721349573.660 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-19T00:39:33.660Z,1721349573.660 [Default:CheckIn:D] Running Loop=1
2024-07-19T00:39:34.080Z,1721349574.080 [Default:CheckIn:D] Stopped
2024-07-19T00:39:34.080Z,1721349574.080 [Default:CheckIn:E] Running Loop=1
2024-07-19T00:39:34.483Z,1721349574.483 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 14.855787 min
2024-07-19T00:39:34.484Z,1721349574.484 [Default:CheckIn:E] Stopped
2024-07-19T00:39:34.484Z,1721349574.484 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-19T00:39:34.484Z,1721349574.484 [Default:CheckIn] Stopped
2024-07-19T00:39:34.484Z,1721349574.484 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-19T00:39:34.484Z,1721349574.484 [Default:CheckIn](INFO): Running loop #3
2024-07-19T00:39:34.484Z,1721349574.484 [Default:CheckIn] Running Loop=3
2024-07-19T00:39:34.484Z,1721349574.484 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-19T00:39:34.484Z,1721349574.484 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-19T00:39:36.480Z,1721349576.480 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,003936.00,A,4538.82925,N,08428.35116,W,0.778,352.00,190724,,,A*7E
2024-07-19T00:39:36.482Z,1721349576.482 [NAL9602](INFO): GPS fix at 20240719T003936: (45.647154, -84.472519)
2024-07-19T00:39:36.519Z,1721349576.519 [Default:CheckIn:Read_GPS] Stopped
2024-07-19T00:39:36.519Z,1721349576.519 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-19T00:39:40.086Z,1721349580.086 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240719T001945/Courier0010.lzma
2024-07-19T00:39:41.091Z,1721349581.091 [DataOverHttps](INFO): Moved sent file to Logs/20240719T001945/Courier0010.lzma.bak
2024-07-19T00:39:41.091Z,1721349581.091 [DataOverHttps](INFO): SBD MOMSN=21368073
2024-07-19T00:40:02.402Z,1721349602.402 [DataOverHttps](INFO): Sending 795 bytes from file Logs/20240719T001945/Express0011.lzma
2024-07-19T00:40:03.403Z,1721349603.403 [DataOverHttps](INFO): Moved sent file to Logs/20240719T001945/Express0011.lzma.bak
2024-07-19T00:40:03.403Z,1721349603.403 [DataOverHttps](INFO): SBD MOMSN=21368076
2024-07-19T00:40:06.919Z,1721349606.919 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-07-19T00:40:06.993Z,1721349606.993 [NAL9602](ERROR): received:
+CSQ:1
OK72, 2, 0, 0, 0
OK
2024-07-19T00:40:09.769Z,1721349609.769 [Default:CheckIn:Read_Iridium] Stopped
2024-07-19T00:40:09.769Z,1721349609.769 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-19T00:40:09.769Z,1721349609.769 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-19T00:42:22.478Z,1721349742.478 [DataOverHttps](IMPORTANT): SBD MTMSN=20240719T004222
2024-07-19T00:42:29.905Z,1721349749.905 [DataOverHttps](INFO): Received command: restart app
2024-07-19T00:42:29.938Z,1721349749.938 [CommandExec](IMPORTANT): got command restart application
2024-07-19T00:42:30.941Z,1721349750.941 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2024-07-19T00:42:30.941Z,1721349750.941 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-19T00:42:30.941Z,1721349750.941 [CommandExec](INFO): Uninitializing the command executive.
2024-07-19T00:42:30.941Z,1721349750.941 [CommandExec](INFO): Uninitializing the command scheduler.
2024-07-19T00:42:30.942Z,1721349750.942 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-07-19T00:42:31.105Z,1721349751.105 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2024-07-19T00:42:31.105Z,1721349751.105 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2024-07-19T00:42:31.105Z,1721349751.105 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-07-19T00:42:31.106Z,1721349751.106 [NavChartDb](INFO): Join timeout helper Thread ID is 4349
2024-07-19T00:42:31.265Z,1721349751.265 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-19T00:42:31.265Z,1721349751.265 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-07-19T00:42:31.269Z,1721349751.269 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2024-07-19T00:42:31.269Z,1721349751.269 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-07-19T00:42:31.269Z,1721349751.269 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 4350
2024-07-19T00:42:31.529Z,1721349751.529 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-19T00:42:31.529Z,1721349751.529 [WetLabsBB2FL](INFO): Powering down
2024-07-19T00:42:31.530Z,1721349751.530 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-07-19T00:42:31.556Z,1721349751.556 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2024-07-19T00:42:31.556Z,1721349751.556 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-07-19T00:42:31.557Z,1721349751.557 [CTD_Seabird](INFO): Join timeout helper Thread ID is 4351
2024-07-19T00:42:31.881Z,1721349751.881 [CTD_Seabird](INFO): Powering down
2024-07-19T00:42:31.893Z,1721349751.893 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-19T00:42:31.893Z,1721349751.893 [CTD_Seabird](INFO): Powering down
2024-07-19T00:42:31.909Z,1721349751.909 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-07-19T00:42:31.913Z,1721349751.913 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2024-07-19T00:42:31.913Z,1721349751.913 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-07-19T00:42:31.913Z,1721349751.913 [Radio_Surface](INFO): Join timeout helper Thread ID is 4353
2024-07-19T00:42:31.965Z,1721349751.965 [Radio_Surface](INFO): Powering down
2024-07-19T00:42:31.966Z,1721349751.966 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-19T00:42:31.966Z,1721349751.966 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-07-19T00:42:31.969Z,1721349751.969 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2024-07-19T00:42:31.969Z,1721349751.969 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-07-19T00:42:31.969Z,1721349751.969 [Onboard](INFO): Join timeout helper Thread ID is 4354
2024-07-19T00:42:32.913Z,1721349752.913 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2024-07-19T00:42:33.213Z,1721349753.213 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-19T00:42:33.213Z,1721349753.213 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-07-19T00:42:33.222Z,1721349753.222 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2024-07-19T00:42:33.222Z,1721349753.222 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-07-19T00:42:33.222Z,1721349753.222 [DataOverHttps](INFO): Join timeout helper Thread ID is 4355
2024-07-19T00:42:33.917Z,1721349753.917 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-19T00:42:33.918Z,1721349753.918 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-07-19T00:42:33.921Z,1721349753.921 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2024-07-19T00:42:33.922Z,1721349753.922 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-07-19T00:42:33.922Z,1721349753.922 [BackseatComponent](INFO): Join timeout helper Thread ID is 4356
2024-07-19T00:42:34.066Z,1721349754.066 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-19T00:42:34.067Z,1721349754.067 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-07-19T00:42:34.070Z,1721349754.070 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2024-07-19T00:42:34.070Z,1721349754.070 [logger ThreadHandler](INFO): Thread cancelled.
2024-07-19T00:42:34.071Z,1721349754.071 [logger](INFO): Join timeout helper Thread ID is 4357
2024-07-19T00:42:34.073Z,1721349754.073 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-19T00:42:34.073Z,1721349754.073 [logger ThreadHandler](INFO): Thread cancelled.
2024-07-19T00:42:34.089Z,1721349754.089 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2024-07-19T00:42:34.089Z,1721349754.089 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-07-19T00:42:34.090Z,1721349754.090 [CommandLine](INFO): Join timeout helper Thread ID is 4358
2024-07-19T00:42:34.121Z,1721349754.121 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-19T00:42:34.121Z,1721349754.121 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-07-19T00:42:34.129Z,1721349754.129 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2024-07-19T00:42:34.129Z,1721349754.129 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-07-19T00:42:34.130Z,1721349754.130 [CommandExec](INFO): Join timeout helper Thread ID is 4359
2024-07-19T00:42:34.131Z,1721349754.131 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2024-07-19T00:42:34.131Z,1721349754.131 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-07-19T00:42:34.131Z,1721349754.131 [controlThread](INFO): Join timeout helper Thread ID is 4360
2024-07-19T00:42:34.381Z,1721349754.381 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-19T00:42:34.381Z,1721349754.381 [controlThread](DEBUG): Uninitializing ControlThread
2024-07-19T00:42:34.382Z,1721349754.382 [AHRS_M2](INFO): Powering down
2024-07-19T00:42:34.454Z,1721349754.454 [NAL9602](INFO): Powering down
2024-07-19T00:42:34.455Z,1721349754.455 [Sonardyne_Nano](INFO): Powering down
2024-07-19T00:42:34.574Z,1721349754.574 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2024-07-19T00:42:34.574Z,1721349754.574 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2024-07-19T00:42:34.575Z,1721349754.575 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2024-07-19T00:42:34.575Z,1721349754.575 [MissionManager](INFO): Uninitializing Mission Default
2024-07-19T00:42:34.575Z,1721349754.575 [Default] Stopped
2024-07-19T00:42:34.575Z,1721349754.575 [Default](DEBUG): Aggregate::uninitialize Default
2024-07-19T00:42:34.575Z,1721349754.575 [Default:B.GoToSurface] Stopped
2024-07-19T00:42:34.576Z,1721349754.576 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-07-19T00:42:34.576Z,1721349754.576 [Default:CheckIn] Stopped
2024-07-19T00:42:34.576Z,1721349754.576 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-19T00:42:34.576Z,1721349754.576 [Default:CheckIn:C.Wait] Stopped
2024-07-19T00:42:34.576Z,1721349754.576 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-19T00:42:34.579Z,1721349754.579 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2024-07-19T00:42:34.579Z,1721349754.579 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2024-07-19T00:42:34.579Z,1721349754.579 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2024-07-19T00:42:34.579Z,1721349754.579 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2024-07-19T00:42:34.580Z,1721349754.580 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2024-07-19T00:42:34.580Z,1721349754.580 [BuoyancyServo](INFO): Powering down
2024-07-19T00:42:34.593Z,1721349754.593 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2024-07-19T00:42:34.593Z,1721349754.593 [ElevatorServo](INFO): Powering down
2024-07-19T00:42:34.594Z,1721349754.594 [MassServo](DEBUG): Uninitialize Mass Servo.
2024-07-19T00:42:34.594Z,1721349754.594 [MassServo](INFO): Powering down
2024-07-19T00:42:34.595Z,1721349754.595 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2024-07-19T00:42:34.595Z,1721349754.595 [RudderServo](INFO): Powering down
2024-07-19T00:42:34.596Z,1721349754.596 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2024-07-19T00:42:34.596Z,1721349754.596 [ThrusterHE](INFO): Powering down
2024-07-19T00:42:34.597Z,1721349754.597 [SBIT](DEBUG): Uninitialize SBIT Component.
2024-07-19T00:42:34.597Z,1721349754.597 [IBIT](DEBUG): Uninitialize IBIT Component.
2024-07-19T00:42:34.597Z,1721349754.597 [CBIT](DEBUG): Uninitialize CBIT Component.
2024-07-19T00:42:34.597Z,1721349754.597 [CBIT](DEBUG): Powering off loads.
2024-07-19T00:42:34.609Z,1721349754.609 [CBIT](DEBUG): Disabling WDT.
2024-07-19T00:42:34.621Z,1721349754.621 [CBIT](DEBUG): Opening all GF detection circuits.
2024-07-19T00:42:34.622Z,1721349754.622 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-07-19T00:42:34.670Z,1721349754.670 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-07-19T00:42:34.673Z,1721349754.673 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-07-19T00:42:34.679Z,1721349754.679 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-07-19T00:42:34.723Z,1721349754.723 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-07-19T00:42:34.762Z,1721349754.762 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-07-19T00:42:34.766Z,1721349754.766 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-07-19T00:42:34.799Z,1721349754.799 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-07-19T00:42:34.848Z,1721349754.848 [logger ThreadHandler](INFO): Thread cancelled.