2024-05-07T19:02:28.784Z,1715108548.784 [Supervisor](DEBUG): Initializing supervisor.
2024-05-07T19:02:28.789Z,1715108548.789 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2024-05-07T19:02:28.789Z,1715108548.789 [SyncHandler](INFO): Protected caller Thread ID is 3566
2024-05-07T19:02:28.789Z,1715108548.789 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2024-05-07T19:02:28.790Z,1715108548.790 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2024-05-07T19:02:28.791Z,1715108548.791 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 3567
2024-05-07T19:02:28.795Z,1715108548.795 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2024-05-07T19:02:28.812Z,1715108548.812 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2024-05-07T19:02:28.813Z,1715108548.813 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2024-05-07T19:02:28.813Z,1715108548.813 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 3568
2024-05-07T19:02:28.818Z,1715108548.818 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2024-05-07T19:02:28.819Z,1715108548.819 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2024-05-07T19:02:28.819Z,1715108548.819 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 3569
2024-05-07T19:02:28.821Z,1715108548.821 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2024-05-07T19:02:28.822Z,1715108548.822 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2024-05-07T19:02:28.822Z,1715108548.822 [logger ThreadHandler](INFO): Protected caller Thread ID is 3570
2024-05-07T19:02:28.826Z,1715108548.826 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2024-05-07T19:02:28.826Z,1715108548.826 [Supervisor](INFO): Looking for Config files in directory: Config/
2024-05-07T19:02:28.828Z,1715108548.828 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2024-05-07T19:02:29.054Z,1715108549.054 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2024-05-07T19:02:29.054Z,1715108549.054 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2024-05-07T19:02:29.189Z,1715108549.189 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2024-05-07T19:02:29.189Z,1715108549.189 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2024-05-07T19:02:29.439Z,1715108549.439 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2024-05-07T19:02:29.439Z,1715108549.439 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2024-05-07T19:02:29.948Z,1715108549.948 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2024-05-07T19:02:29.948Z,1715108549.948 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2024-05-07T19:02:30.316Z,1715108550.316 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2024-05-07T19:02:30.317Z,1715108550.317 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2024-05-07T19:02:30.676Z,1715108550.676 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2024-05-07T19:02:30.677Z,1715108550.677 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2024-05-07T19:02:30.877Z,1715108550.877 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2024-05-07T19:02:30.878Z,1715108550.878 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2024-05-07T19:02:30.971Z,1715108550.971 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2024-05-07T19:02:31.065Z,1715108551.065 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2024-05-07T19:02:31.501Z,1715108551.501 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2024-05-07T19:02:31.502Z,1715108551.502 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2024-05-07T19:02:31.812Z,1715108551.812 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2024-05-07T19:02:31.812Z,1715108551.812 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2024-05-07T19:02:32.390Z,1715108552.390 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2024-05-07T19:02:32.390Z,1715108552.390 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2024-05-07T19:02:32.631Z,1715108552.631 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2024-05-07T19:02:32.631Z,1715108552.631 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2024-05-07T19:02:32.892Z,1715108552.892 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2024-05-07T19:02:32.892Z,1715108552.892 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2024-05-07T19:02:33.043Z,1715108553.043 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2024-05-07T19:02:33.044Z,1715108553.044 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2024-05-07T19:02:33.929Z,1715108553.929 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2024-05-07T19:02:33.931Z,1715108553.931 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/
2024-05-07T19:02:33.932Z,1715108553.932 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg
2024-05-07T19:02:34.029Z,1715108554.029 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg
2024-05-07T19:02:34.171Z,1715108554.171 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg
2024-05-07T19:02:34.487Z,1715108554.487 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2024-05-07T19:02:34.487Z,1715108554.487 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg
2024-05-07T19:02:34.655Z,1715108554.655 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg
2024-05-07T19:02:34.948Z,1715108554.948 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg
2024-05-07T19:02:35.109Z,1715108555.109 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg
2024-05-07T19:02:35.319Z,1715108555.319 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg
2024-05-07T19:02:35.430Z,1715108555.430 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg
2024-05-07T19:02:35.530Z,1715108555.530 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg
2024-05-07T19:02:35.632Z,1715108555.632 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg
2024-05-07T19:02:35.816Z,1715108555.816 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/root/
2024-05-07T19:02:35.817Z,1715108555.817 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2024-05-07T19:02:35.821Z,1715108555.821 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2024-05-07T19:02:35.872Z,1715108555.872 [DepthRateCalculator] Loaded
2024-05-07T19:02:35.873Z,1715108555.873 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2024-05-07T19:02:35.878Z,1715108555.878 [PitchRateCalculator] Loaded
2024-05-07T19:02:35.878Z,1715108555.878 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2024-05-07T19:02:35.888Z,1715108555.888 [SpeedCalculator] Loaded
2024-05-07T19:02:35.889Z,1715108555.889 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2024-05-07T19:02:35.893Z,1715108555.893 [YawRateCalculator] Loaded
2024-05-07T19:02:35.894Z,1715108555.894 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2024-05-07T19:02:35.915Z,1715108555.915 [ElevatorOffsetCalculator] Loaded
2024-05-07T19:02:35.915Z,1715108555.915 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2024-05-07T19:02:35.915Z,1715108555.915 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2024-05-07T19:02:35.916Z,1715108555.916 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2024-05-07T19:02:35.943Z,1715108555.943 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2024-05-07T19:02:35.943Z,1715108555.943 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2024-05-07T19:02:35.953Z,1715108555.953 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2024-05-07T19:02:35.954Z,1715108555.954 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2024-05-07T19:02:36.408Z,1715108556.408 [AHRS_M2] Loaded
2024-05-07T19:02:36.408Z,1715108556.408 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2024-05-07T19:02:36.443Z,1715108556.443 [BackseatComponent] Loaded
2024-05-07T19:02:36.443Z,1715108556.443 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2024-05-07T19:02:36.444Z,1715108556.444 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408584E0
2024-05-07T19:02:36.444Z,1715108556.444 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 3652
2024-05-07T19:02:36.447Z,1715108556.447 [LcmUniversalReporter] Loaded
2024-05-07T19:02:36.448Z,1715108556.448 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2024-05-07T19:02:37.232Z,1715108557.232 [BPC1] Loaded
2024-05-07T19:02:37.233Z,1715108557.233 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2024-05-07T19:02:37.304Z,1715108557.304 [DataOverHttps] Loaded
2024-05-07T19:02:37.304Z,1715108557.304 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2024-05-07T19:02:37.305Z,1715108557.305 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408884E0
2024-05-07T19:02:37.305Z,1715108557.305 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 3653
2024-05-07T19:02:37.325Z,1715108557.325 [Depth_Keller] Loaded
2024-05-07T19:02:37.325Z,1715108557.325 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2024-05-07T19:02:37.330Z,1715108557.330 [DropWeight] Loaded
2024-05-07T19:02:37.330Z,1715108557.330 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2024-05-07T19:02:37.392Z,1715108557.392 [NAL9602] Loaded
2024-05-07T19:02:37.392Z,1715108557.392 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2024-05-07T19:02:37.421Z,1715108557.421 [Onboard] Loaded
2024-05-07T19:02:37.421Z,1715108557.421 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2024-05-07T19:02:37.422Z,1715108557.422 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408B84E0
2024-05-07T19:02:37.423Z,1715108557.423 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 3654
2024-05-07T19:02:37.435Z,1715108557.435 [Power24vConverter] Loaded
2024-05-07T19:02:37.435Z,1715108557.435 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2024-05-07T19:02:37.449Z,1715108557.449 [Radio_Surface] Loaded
2024-05-07T19:02:37.449Z,1715108557.449 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2024-05-07T19:02:37.451Z,1715108557.451 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408E84E0
2024-05-07T19:02:37.451Z,1715108557.451 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 3655
2024-05-07T19:02:37.465Z,1715108557.465 [Sonardyne_Nano] Loaded
2024-05-07T19:02:37.465Z,1715108557.465 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2024-05-07T19:02:37.487Z,1715108557.487 [Waterlinked] Loaded
2024-05-07T19:02:37.487Z,1715108557.487 [ComponentRegistry](DEBUG): SyncComponent "Waterlinked" handled in the control thread.
2024-05-07T19:02:37.488Z,1715108557.488 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2024-05-07T19:02:37.488Z,1715108557.488 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2024-05-07T19:02:37.597Z,1715108557.597 [DeadReckonUsingMultipleVelocitySources] Loaded
2024-05-07T19:02:37.598Z,1715108557.598 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2024-05-07T19:02:37.639Z,1715108557.639 [DeadReckonUsingSpeedCalculator] Loaded
2024-05-07T19:02:37.639Z,1715108557.639 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2024-05-07T19:02:37.652Z,1715108557.652 [NavChart] Loaded
2024-05-07T19:02:37.652Z,1715108557.652 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2024-05-07T19:02:37.658Z,1715108557.658 [UniversalFixResidualReporter] Loaded
2024-05-07T19:02:37.658Z,1715108557.658 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2024-05-07T19:02:37.659Z,1715108557.659 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2024-05-07T19:02:37.659Z,1715108557.659 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2024-05-07T19:02:37.798Z,1715108557.798 [SBIT](DEBUG): Construct Startup Built In Test.
2024-05-07T19:02:37.813Z,1715108557.813 [SBIT] Loaded
2024-05-07T19:02:37.813Z,1715108557.813 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2024-05-07T19:02:37.816Z,1715108557.816 [IBIT](DEBUG): Construct Initiated Built In Test.
2024-05-07T19:02:37.828Z,1715108557.828 [IBIT] Loaded
2024-05-07T19:02:37.829Z,1715108557.829 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2024-05-07T19:02:37.834Z,1715108557.834 [CBIT](DEBUG): Construct Continuous Built In Test.
2024-05-07T19:02:37.936Z,1715108557.936 [CBIT] Loaded
2024-05-07T19:02:37.936Z,1715108557.936 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2024-05-07T19:02:37.936Z,1715108557.936 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2024-05-07T19:02:37.937Z,1715108557.937 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2024-05-07T19:02:38.106Z,1715108558.106 [CTD_Seabird] Loaded
2024-05-07T19:02:38.106Z,1715108558.106 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2024-05-07T19:02:38.107Z,1715108558.107 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409F04E0
2024-05-07T19:02:38.107Z,1715108558.107 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 3656
2024-05-07T19:02:38.127Z,1715108558.127 [PAR_Licor] Loaded
2024-05-07T19:02:38.127Z,1715108558.127 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2024-05-07T19:02:38.170Z,1715108558.170 [WetLabsBB2FL] Loaded
2024-05-07T19:02:38.171Z,1715108558.171 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2024-05-07T19:02:38.172Z,1715108558.172 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A204E0
2024-05-07T19:02:38.172Z,1715108558.172 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 3657
2024-05-07T19:02:38.173Z,1715108558.173 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2024-05-07T19:02:38.173Z,1715108558.173 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2024-05-07T19:02:38.305Z,1715108558.305 [BuoyancyServo] Loaded
2024-05-07T19:02:38.305Z,1715108558.305 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2024-05-07T19:02:38.327Z,1715108558.327 [ElevatorServo] Loaded
2024-05-07T19:02:38.327Z,1715108558.327 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2024-05-07T19:02:38.348Z,1715108558.348 [MassServo] Loaded
2024-05-07T19:02:38.348Z,1715108558.348 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2024-05-07T19:02:38.368Z,1715108558.368 [RudderServo] Loaded
2024-05-07T19:02:38.369Z,1715108558.369 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2024-05-07T19:02:38.384Z,1715108558.384 [ThrusterHE] Loaded
2024-05-07T19:02:38.384Z,1715108558.384 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2024-05-07T19:02:38.385Z,1715108558.385 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2024-05-07T19:02:38.385Z,1715108558.385 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2024-05-07T19:02:38.436Z,1715108558.436 [VerticalControl](DEBUG): Construct VerticalControl.
2024-05-07T19:02:38.488Z,1715108558.488 [VerticalControl] Loaded
2024-05-07T19:02:38.488Z,1715108558.488 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2024-05-07T19:02:38.491Z,1715108558.491 [HorizontalControl](DEBUG): Construct HorizontalControl.
2024-05-07T19:02:38.535Z,1715108558.535 [HorizontalControl] Loaded
2024-05-07T19:02:38.535Z,1715108558.535 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2024-05-07T19:02:38.538Z,1715108558.538 [SpeedControl](DEBUG): Construct SpeedControl.
2024-05-07T19:02:38.540Z,1715108558.540 [SpeedControl] Loaded
2024-05-07T19:02:38.540Z,1715108558.540 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2024-05-07T19:02:38.543Z,1715108558.543 [LoopControl](DEBUG): Construct LoopControl.
2024-05-07T19:02:38.543Z,1715108558.543 [LoopControl] Loaded
2024-05-07T19:02:38.543Z,1715108558.543 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2024-05-07T19:02:38.544Z,1715108558.544 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2024-05-07T19:02:38.544Z,1715108558.544 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2024-05-07T19:02:38.656Z,1715108558.656 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2024-05-07T19:02:38.657Z,1715108558.657 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2024-05-07T19:02:38.828Z,1715108558.828 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2024-05-07T19:02:38.828Z,1715108558.828 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2024-05-07T19:02:39.160Z,1715108559.160 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2024-05-07T19:02:39.161Z,1715108559.161 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2024-05-07T19:02:39.216Z,1715108559.216 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2024-05-07T19:02:39.222Z,1715108559.222 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2024-05-07T19:02:39.225Z,1715108559.225 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2024-05-07T19:02:39.236Z,1715108559.236 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2024-05-07T19:02:39.237Z,1715108559.237 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C074E0
2024-05-07T19:02:39.238Z,1715108559.238 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 3658
2024-05-07T19:02:39.242Z,1715108559.242 [Supervisor](INFO): Main Thread ID is 2701
2024-05-07T19:02:39.242Z,1715108559.242 [Supervisor](DEBUG): Running supervisor.
2024-05-07T19:02:39.243Z,1715108559.243 [CommandExec ThreadHandler](INFO): Handler Thread ID is 3659
2024-05-07T19:02:39.244Z,1715108559.244 [CommandExec](INFO): Initializing the command executive.
2024-05-07T19:02:39.245Z,1715108559.245 [CommandLine ThreadHandler](INFO): Handler Thread ID is 3660
2024-05-07T19:02:39.248Z,1715108559.248 [controlThread ThreadHandler](INFO): Handler Thread ID is 3661
2024-05-07T19:02:39.249Z,1715108559.249 [controlThread](DEBUG): Initializing ControlThread
2024-05-07T19:02:39.250Z,1715108559.250 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2024-05-07T19:02:39.250Z,1715108559.250 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2024-05-07T19:02:39.250Z,1715108559.250 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2024-05-07T19:02:39.251Z,1715108559.251 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2024-05-07T19:02:39.251Z,1715108559.251 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2024-05-07T19:02:39.257Z,1715108559.257 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-05-07T19:02:39.257Z,1715108559.257 [NavChart](DEBUG): Initialize NavChart Navigation.
2024-05-07T19:02:39.257Z,1715108559.257 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2024-05-07T19:02:39.258Z,1715108559.258 [SBIT](INFO): Initialize SBIT Component.
2024-05-07T19:02:39.258Z,1715108559.258 [SBIT](IMPORTANT): git: 2024-05-07
2024-05-07T19:02:39.258Z,1715108559.258 [SBIT](INFO): git hash: caae5b805170f9c680da20e21004b1da522ccce4
2024-05-07T19:02:39.259Z,1715108559.259 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2024-05-07T19:02:39.260Z,1715108559.260 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2024-05-07T19:02:39.261Z,1715108559.261 [SBIT](INFO): Beginning SBIT in 77.000000 seconds.
2024-05-07T19:02:39.261Z,1715108559.261 [IBIT](INFO): Initialize IBIT Component.
2024-05-07T19:02:39.263Z,1715108559.263 [CBIT](DEBUG): Initialize CBIT Component.
2024-05-07T19:02:39.263Z,1715108559.263 [logger ThreadHandler](INFO): Handler Thread ID is 3662
2024-05-07T19:02:39.274Z,1715108559.274 [CBIT](DEBUG): Initialized mux pins.
2024-05-07T19:02:39.274Z,1715108559.274 [CBIT](DEBUG): Initializing the watchdog timer.
2024-05-07T19:02:39.282Z,1715108559.282 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 3663
2024-05-07T19:02:39.294Z,1715108559.294 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 3664
2024-05-07T19:02:39.296Z,1715108559.296 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2024-05-07T19:02:39.298Z,1715108559.298 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2024-05-07T19:02:39.298Z,1715108559.298 [CBIT](DEBUG): Initializing heartbeat.
2024-05-07T19:02:39.306Z,1715108559.306 [Onboard ThreadHandler](INFO): Handler Thread ID is 3665
2024-05-07T19:02:39.324Z,1715108559.324 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 3666
2024-05-07T19:02:39.347Z,1715108559.347 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 3667
2024-05-07T19:02:39.347Z,1715108559.347 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2024-05-07T19:02:39.351Z,1715108559.351 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 3669
2024-05-07T19:02:39.354Z,1715108559.354 [WetLabsBB2FL](INFO): Powering up
2024-05-07T19:02:39.356Z,1715108559.356 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 3671
2024-05-07T19:02:39.358Z,1715108559.358 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2024-05-07T19:02:39.358Z,1715108559.358 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2024-05-07T19:02:39.358Z,1715108559.358 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2024-05-07T19:02:39.358Z,1715108559.358 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2024-05-07T19:02:39.359Z,1715108559.359 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2024-05-07T19:02:39.359Z,1715108559.359 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2024-05-07T19:02:39.359Z,1715108559.359 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2024-05-07T19:02:39.359Z,1715108559.359 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2024-05-07T19:02:39.370Z,1715108559.370 [CBIT](DEBUG): Deactivating GF circuits.
2024-05-07T19:02:39.370Z,1715108559.370 [CBIT](DEBUG): Deactivating emergency mode.
2024-05-07T19:02:39.410Z,1715108559.410 [CBIT](DEBUG): Backplane powered.
2024-05-07T19:02:39.415Z,1715108559.415 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2024-05-07T19:02:39.417Z,1715108559.417 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2024-05-07T19:02:39.418Z,1715108559.418 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2024-05-07T19:02:39.418Z,1715108559.418 [LoopControl](DEBUG): Initialize LoopControlComponent.
2024-05-07T19:02:39.419Z,1715108559.419 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2024-05-07T19:02:39.420Z,1715108559.420 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2024-05-07T19:02:39.420Z,1715108559.420 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2024-05-07T19:02:39.421Z,1715108559.421 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2024-05-07T19:02:39.463Z,1715108559.463 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2024-05-07T19:02:39.468Z,1715108559.468 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2024-05-07T19:02:39.507Z,1715108559.507 [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-05-07T19:02:39.508Z,1715108559.508 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2024-05-07T19:02:39.508Z,1715108559.508 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2024-05-07T19:02:39.519Z,1715108559.519 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2024-05-07T19:02:39.730Z,1715108559.730 [Radio_Surface](INFO): Powering up
2024-05-07T19:02:39.852Z,1715108559.852 [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-05-07T19:02:39.869Z,1715108559.869 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2024-05-07T19:02:39.870Z,1715108559.870 [Default:A.Wait](DEBUG): Construct Wait.
2024-05-07T19:02:39.872Z,1715108559.872 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-05-07T19:02:39.905Z,1715108559.905 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2024-05-07T19:02:39.916Z,1715108559.916 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2024-05-07T19:02:39.922Z,1715108559.922 [Default:E.Execute](DEBUG): Construct Execute.
2024-05-07T19:02:39.925Z,1715108559.925 [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-05-07T19:02:39.942Z,1715108559.942 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,Waterlinked,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2024-05-07T19:02:39.950Z,1715108559.950 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-05-07T19:02:40.004Z,1715108560.004 [Power24vConverter](INFO): Powering up.
2024-05-07T19:02:40.005Z,1715108560.005 [Sonardyne_Nano](INFO): Initializing.
2024-05-07T19:02:40.073Z,1715108560.073 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2024-05-07T19:02:40.078Z,1715108560.078 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2024-05-07T19:02:40.079Z,1715108560.079 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-05-07T19:02:40.086Z,1715108560.086 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-05-07T19:02:40.087Z,1715108560.087 [MassServo](DEBUG): Initializing EZServoServo.
2024-05-07T19:02:40.094Z,1715108560.094 [MassServo](DEBUG): Initializing MassServo.
2024-05-07T19:02:40.095Z,1715108560.095 [RudderServo](DEBUG): Initializing EZServoServo.
2024-05-07T19:02:40.102Z,1715108560.102 [RudderServo](DEBUG): Initializing RudderServo.
2024-05-07T19:02:40.103Z,1715108560.103 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-05-07T19:02:40.110Z,1715108560.110 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-05-07T19:02:41.968Z,1715108561.968 [WetLabsBB2FL](INFO): Powering down
2024-05-07T19:02:41.976Z,1715108561.976 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2024-05-07T19:02:43.259Z,1715108563.259 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2105
2024-05-07T19:02:46.378Z,1715108566.378 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2024-05-07T19:03:07.431Z,1715108587.431 [NAL9602](INFO): Powering up NAL9602
2024-05-07T19:03:18.340Z,1715108598.340 [NAL9602](INFO): NAL9602 initialized
2024-05-07T19:03:56.734Z,1715108636.734 [SBIT](IMPORTANT): Beginning Startup BIT
2024-05-07T19:03:56.738Z,1715108636.738 [CBIT](IMPORTANT): Beginning ground fault scan
2024-05-07T19:04:07.835Z,1715108647.835 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006811
CHAN A1 (24V): 0.002103
CHAN A2 (12V): -0.004999
CHAN A3 (5V): -0.003117
CHAN B0 (3.3V): -0.001388
CHAN B1 (3.15aV): -0.001120
CHAN B2 (3.15bV): -0.001187
CHAN B3 (GND): -0.000612
OPEN: 0.006688
Full Scale: +/- 1 mA
2024-05-07T19:04:50.912Z,1715108690.912 [SBIT](IMPORTANT): SBIT PASSED
2024-05-07T19:04:50.913Z,1715108690.913 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-05-07T19:04:50.913Z,1715108690.913 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;
2024-05-07T19:04:50.914Z,1715108690.914 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=352.020527 cubic_centimeter;
2024-05-07T19:04:50.914Z,1715108690.914 [SBIT](IMPORTANT): VerticalControl.massDefault=14.385980 millimeter;
2024-05-07T19:04:51.316Z,1715108691.316 [MissionManager](IMPORTANT): Started mission Startup
2024-05-07T19:04:51.317Z,1715108691.317 [Startup] Running Loop=1
2024-05-07T19:04:51.317Z,1715108691.317 [Startup](DEBUG): Aggregate::initialize Startup
2024-05-07T19:04:51.317Z,1715108691.317 [Startup:A.GoToSurface] Running Loop=1
2024-05-07T19:04:51.317Z,1715108691.317 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-05-07T19:04:51.318Z,1715108691.318 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-05-07T19:04:51.318Z,1715108691.318 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-05-07T19:04:51.319Z,1715108691.319 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-05-07T19:04:51.319Z,1715108691.319 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-05-07T19:04:51.319Z,1715108691.319 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-05-07T19:04:51.321Z,1715108691.321 [Startup:StartupSatComms] Running Loop=1
2024-05-07T19:04:51.321Z,1715108691.321 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2024-05-07T19:04:51.321Z,1715108691.321 [Startup:StartupSatComms:A] Running Loop=1
2024-05-07T19:04:51.721Z,1715108691.721 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2024-05-07T19:05:00.965Z,1715108700.965 [NAL9602](INFO): SBD MO Status=2, MOMSN=17048, MT Status=2, MTMSN=0
2024-05-07T19:05:00.965Z,1715108700.965 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-05-07T19:05:18.341Z,1715108718.341 [NAL9602](INFO): SBD MO Status=2, MOMSN=17048, MT Status=2, MTMSN=0
2024-05-07T19:05:18.341Z,1715108718.341 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-05-07T19:05:39.425Z,1715108739.425 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2024-05-07T19:05:39.425Z,1715108739.425 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-05-07T19:05:39.427Z,1715108739.427 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2024-05-07T19:05:39.427Z,1715108739.427 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-05-07T19:05:39.438Z,1715108739.438 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-05-07T19:05:39.438Z,1715108739.438 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-05-07T19:05:39.836Z,1715108739.836 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-05-07T19:05:39.836Z,1715108739.836 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2024-05-07T19:05:39.837Z,1715108739.837 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-05-07T19:05:39.837Z,1715108739.837 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2024-05-07T19:05:40.220Z,1715108740.220 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-05-07T19:05:51.544Z,1715108751.544 [Startup:StartupSatComms:A](INFO): Timed out from 2024-05-07T19:04:51.3Z
2024-05-07T19:05:51.544Z,1715108751.544 [Startup:StartupSatComms:A] Stopped
2024-05-07T19:05:51.544Z,1715108751.544 [Startup:StartupSatComms:B] Running Loop=1
2024-05-07T19:05:51.944Z,1715108751.944 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-05-07T19:06:10.178Z,1715108770.178 [NAL9602](INFO): SBD MO Status=2, MOMSN=17048, MT Status=2, MTMSN=0
2024-05-07T19:06:10.178Z,1715108770.178 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-05-07T19:06:19.605Z,1715108779.605 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003750
2024-05-07T19:06:21.313Z,1715108781.313 [CommandExec](IMPORTANT): got command get platform_mass_position centimeter
2024-05-07T19:06:21.326Z,1715108781.326 [CommandExec](IMPORTANT): platform_mass_position 1.438908 cm
2024-05-07T19:06:25.949Z,1715108785.949 [CommandExec](IMPORTANT): got command show variable limitFwd
2024-05-07T19:06:26.048Z,1715108786.048 [CommandExec](IMPORTANT): VerticalControl.massPositionLimitFwd (millimeter)
2024-05-07T19:06:28.169Z,1715108788.169 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240507T175837/Courier0037.lzma
2024-05-07T19:06:29.172Z,1715108789.172 [DataOverHttps](INFO): Moved sent file to Logs/20240507T175837/Courier0037.lzma.bak
2024-05-07T19:06:29.172Z,1715108789.172 [DataOverHttps](INFO): SBD MOMSN=20824225
2024-05-07T19:06:37.552Z,1715108797.552 [CommandExec](IMPORTANT): got command get VerticalControl.massPositionLimitFwd
2024-05-07T19:06:37.553Z,1715108797.553 [CommandExec](IMPORTANT): VerticalControl.massPositionLimitFwd 38.000000 mm
2024-05-07T19:06:49.613Z,1715108809.613 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240507T190228/Courier0000.lzma
2024-05-07T19:06:50.623Z,1715108810.623 [DataOverHttps](INFO): Moved sent file to Logs/20240507T190228/Courier0000.lzma.bak
2024-05-07T19:06:50.624Z,1715108810.624 [DataOverHttps](INFO): SBD MOMSN=20824235
2024-05-07T19:06:51.641Z,1715108811.641 [Startup:StartupSatComms:B](INFO): Timed out from 2024-05-07T19:05:51.5Z
2024-05-07T19:06:51.641Z,1715108811.641 [Startup:StartupSatComms:B] Stopped
2024-05-07T19:06:51.641Z,1715108811.641 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2024-05-07T19:06:51.641Z,1715108811.641 [Startup:StartupSatComms] Stopped
2024-05-07T19:06:51.641Z,1715108811.641 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2024-05-07T19:06:51.642Z,1715108811.642 [Startup](INFO): Completed Startup
2024-05-07T19:06:51.643Z,1715108811.643 [MissionManager](INFO): Startup is completed.
2024-05-07T19:06:51.643Z,1715108811.643 [MissionManager](INFO): Uninitializing Mission Startup
2024-05-07T19:06:51.643Z,1715108811.643 [Startup] Stopped
2024-05-07T19:06:51.643Z,1715108811.643 [Startup](DEBUG): Aggregate::uninitialize Startup
2024-05-07T19:06:51.643Z,1715108811.643 [Startup:A.GoToSurface] Stopped
2024-05-07T19:06:51.643Z,1715108811.643 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-05-07T19:06:52.017Z,1715108812.017 [MissionManager](IMPORTANT): Started mission Default
2024-05-07T19:06:52.017Z,1715108812.017 [Default] Running Loop=1
2024-05-07T19:06:52.017Z,1715108812.017 [Default](DEBUG): Aggregate::initialize Default
2024-05-07T19:06:52.018Z,1715108812.018 [Default:B.GoToSurface] Running Loop=1
2024-05-07T19:06:52.018Z,1715108812.018 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-05-07T19:06:52.018Z,1715108812.018 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-05-07T19:06:52.018Z,1715108812.018 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-05-07T19:06:52.019Z,1715108812.019 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-05-07T19:06:52.019Z,1715108812.019 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-05-07T19:06:52.019Z,1715108812.019 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-05-07T19:06:52.020Z,1715108812.020 [Default:A.Wait] Running Loop=1
2024-05-07T19:06:52.020Z,1715108812.020 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-05-07T19:06:57.253Z,1715108817.253 [NAL9602](INFO): SBD MO Status=2, MOMSN=17048, MT Status=2, MTMSN=0
2024-05-07T19:06:57.253Z,1715108817.253 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-05-07T19:07:05.373Z,1715108825.373 [Default:A.Wait](INFO): Done Waiting.
2024-05-07T19:07:05.373Z,1715108825.373 [Default:A.Wait] Stopped
2024-05-07T19:07:05.374Z,1715108825.374 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-05-07T19:07:05.820Z,1715108825.820 [Default:CheckIn] Running Loop=1
2024-05-07T19:07:05.820Z,1715108825.820 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-05-07T19:07:05.820Z,1715108825.820 [Default:CheckIn:Read_GPS] Running Loop=1
2024-05-07T19:07:06.212Z,1715108826.212 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2024-05-07T19:07:28.569Z,1715108848.569 [CommandExec](IMPORTANT): got command configSet VerticalControl.massDefault 25 millimeter persist
2024-05-07T19:07:36.259Z,1715108856.259 [CommandExec](IMPORTANT): got command get platform_mass_position millimeter
2024-05-07T19:07:36.266Z,1715108856.266 [CommandExec](IMPORTANT): platform_mass_position 18.654974 mm
2024-05-07T19:07:37.178Z,1715108857.178 [DataOverHttps](INFO): Sending 186 bytes from file Logs/20240507T175837/Express0038.lzma
2024-05-07T19:07:38.180Z,1715108858.180 [DataOverHttps](INFO): Moved sent file to Logs/20240507T175837/Express0038.lzma.bak
2024-05-07T19:07:38.180Z,1715108858.180 [DataOverHttps](INFO): SBD MOMSN=20824239
2024-05-07T19:07:42.565Z,1715108862.565 [CommandExec](IMPORTANT): got command get platform_mass_position millimeter
2024-05-07T19:07:42.566Z,1715108862.566 [CommandExec](IMPORTANT): platform_mass_position 23.669188 mm
2024-05-07T19:08:02.136Z,1715108882.136 [Waterlinked](ERROR): only read 3 of 5 data items. Device response::BI, +11, -13, +22,E, +0, +0, +0,V
2024-05-07T19:08:02.513Z,1715108882.513 [Waterlinked](ERROR): only read 0 of 1 data item for altitude. Device response::BD, +0.00, , +0.00, 2.56, 0.00
2024-05-07T19:08:13.013Z,1715108893.013 [NAL9602](INFO): SBD MO Status=2, MOMSN=17048, MT Status=2, MTMSN=0
2024-05-07T19:08:13.013Z,1715108893.013 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-05-07T19:08:27.610Z,1715108907.610 [NAL9602](INFO): SBD MO Status=2, MOMSN=17048, MT Status=2, MTMSN=0
2024-05-07T19:08:27.611Z,1715108907.611 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-05-07T19:08:27.964Z,1715108907.964 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-05-07T19:08:29.174Z,1715108909.174 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190827.00,A,3648.13892,N,12147.20502,W,0.719,75.49,070524,,,A*41
2024-05-07T19:08:29.177Z,1715108909.177 [NAL9602](INFO): GPS fix at 20240507T190827: (36.802315, -121.786750)
2024-05-07T19:08:29.311Z,1715108909.311 [Default:CheckIn:Read_GPS] Stopped
2024-05-07T19:08:29.312Z,1715108909.312 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-05-07T19:08:29.727Z,1715108909.727 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-05-07T19:08:40.422Z,1715108920.422 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20240507T190228/Courier0004.lzma
2024-05-07T19:08:41.433Z,1715108921.433 [DataOverHttps](INFO): Moved sent file to Logs/20240507T190228/Courier0004.lzma.bak
2024-05-07T19:08:41.433Z,1715108921.433 [DataOverHttps](INFO): SBD MOMSN=20824245
2024-05-07T19:09:01.636Z,1715108941.636 [NAL9602](INFO): Not Powering down - fast GPS
2024-05-07T19:09:03.767Z,1715108943.767 [DataOverHttps](INFO): Sending 769 bytes from file Logs/20240507T190228/Express0001.lzma
2024-05-07T19:09:04.768Z,1715108944.768 [DataOverHttps](INFO): Moved sent file to Logs/20240507T190228/Express0001.lzma.bak
2024-05-07T19:09:04.768Z,1715108944.768 [DataOverHttps](INFO): SBD MOMSN=20824254
2024-05-07T19:09:25.551Z,1715108965.551 [DataOverHttps](INFO): Sending 544 bytes from file Logs/20240507T190228/Express0005.lzma
2024-05-07T19:09:26.552Z,1715108966.552 [DataOverHttps](INFO): Moved sent file to Logs/20240507T190228/Express0005.lzma.bak
2024-05-07T19:09:26.552Z,1715108966.552 [DataOverHttps](INFO): SBD MOMSN=20824270
2024-05-07T19:09:27.934Z,1715108967.934 [Default:CheckIn:Read_Iridium] Stopped
2024-05-07T19:09:27.934Z,1715108967.934 [Default:CheckIn:C.Wait] Running Loop=1
2024-05-07T19:09:27.934Z,1715108967.934 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-05-07T19:09:30.001Z,1715108970.001 [Waterlinked](ERROR): only read 0 of 1 data item for altitude. Device response::BD, +0.0 +0.00, 0.00, 0.00
2024-05-07T19:09:36.357Z,1715108976.357 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error. Device response::TS,23061815024432,, +0, +0, +0,V
2024-05-07T19:09:54.938Z,1715108994.938 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error. Device response::TS,23061815030292, 0.0, +0.0, 0.0,14
2024-05-07T19:10:08.274Z,1715109008.274 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2024-05-07T19:10:08.277Z,1715109008.277 [BPC1](INFO): Received data from all battery sticks.
2024-05-07T19:13:38.881Z,1715109218.881 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error. Device response::TS,23061815064690, 0, 0.0,1475.0, 0
2024-05-07T19:14:02.967Z,1715109242.967 [CommandExec](IMPORTANT): got command get platform_orientation degree
2024-05-07T19:14:02.968Z,1715109242.968 [CommandExec](IMPORTANT): platform_orientation 194.206717 arcdeg
2024-05-07T19:14:28.571Z,1715109268.571 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-05-07T19:14:28.571Z,1715109268.571 [Default:CheckIn:C.Wait] Stopped
2024-05-07T19:14:28.571Z,1715109268.571 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-05-07T19:14:28.571Z,1715109268.571 [Default:CheckIn:D] Running Loop=1
2024-05-07T19:14:28.964Z,1715109268.964 [Default:CheckIn:D] Stopped
2024-05-07T19:14:28.964Z,1715109268.964 [Default:CheckIn:E] Running Loop=1
2024-05-07T19:14:29.379Z,1715109269.379 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 7.615777 min
2024-05-07T19:14:29.379Z,1715109269.379 [Default:CheckIn:E] Stopped
2024-05-07T19:14:29.379Z,1715109269.379 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-05-07T19:14:29.379Z,1715109269.379 [Default:CheckIn] Stopped
2024-05-07T19:14:29.379Z,1715109269.379 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-05-07T19:14:29.379Z,1715109269.379 [Default:CheckIn](INFO): Running loop #2
2024-05-07T19:14:29.379Z,1715109269.379 [Default:CheckIn] Running Loop=2
2024-05-07T19:14:29.380Z,1715109269.380 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-05-07T19:14:29.380Z,1715109269.380 [Default:CheckIn:Read_GPS] Running Loop=1
2024-05-07T19:14:31.351Z,1715109271.351 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191430.00,A,3648.13841,N,12147.20645,W,0.117,313.00,070524,,,D*77
2024-05-07T19:14:31.353Z,1715109271.353 [NAL9602](INFO): GPS fix at 20240507T191430: (36.802307, -121.786774)
2024-05-07T19:14:31.449Z,1715109271.449 [Default:CheckIn:Read_GPS] Stopped
2024-05-07T19:14:31.449Z,1715109271.449 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-05-07T19:14:39.694Z,1715109279.694 [DataOverHttps](INFO): Sending 203 bytes from file Logs/20240507T190228/Courier0007.lzma
2024-05-07T19:14:40.708Z,1715109280.708 [DataOverHttps](INFO): Moved sent file to Logs/20240507T190228/Courier0007.lzma.bak
2024-05-07T19:14:40.708Z,1715109280.708 [DataOverHttps](INFO): SBD MOMSN=20824338
2024-05-07T19:14:49.154Z,1715109289.154 [NAL9602](INFO): SBD MO Status=0, MOMSN=17048, MT Status=0, MTMSN=0
2024-05-07T19:14:49.154Z,1715109289.154 [NAL9602](INFO): No messages in MT queue
2024-05-07T19:14:53.221Z,1715109293.221 [Waterlinked](ERROR): only read 3 of 5 data items. Device response::BI, -5, -12, +0,A
2024-05-07T19:14:58.762Z,1715109298.762 [DataOverHttps](INFO): Sending 224 bytes from file Logs/20240507T190228/Express0008.lzma
2024-05-07T19:14:59.770Z,1715109299.770 [DataOverHttps](INFO): Moved sent file to Logs/20240507T190228/Express0008.lzma.bak
2024-05-07T19:14:59.771Z,1715109299.771 [DataOverHttps](INFO): SBD MOMSN=20824348
2024-05-07T19:15:00.864Z,1715109300.864 [Default:CheckIn:Read_Iridium] Stopped
2024-05-07T19:15:00.864Z,1715109300.864 [Default:CheckIn:C.Wait] Running Loop=1
2024-05-07T19:15:00.864Z,1715109300.864 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-05-07T19:15:19.835Z,1715109319.835 [NAL9602](INFO): Not Powering down - fast GPS
2024-05-07T19:15:20.246Z,1715109320.246 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error. Device response::TS,082824, 0.0, 0.0,1475.0, 0
2024-05-07T19:16:15.242Z,1715109375.242 [Waterlinked](ERROR): DVL uart error: serial timeout
2024-05-07T19:16:15.242Z,1715109375.242 [Waterlinked] Communications Fault, FailCount= 1
2024-05-07T19:16:15.242Z,1715109375.242 [Waterlinked](ERROR): Communications Fault
2024-05-07T19:16:15.312Z,1715109375.312 [CBIT](ERROR): Communications Fault in component: Waterlinked
2024-05-07T19:16:15.698Z,1715109375.698 [Waterlinked](INFO): Powering down
2024-05-07T19:16:16.436Z,1715109376.436 [CBIT](INFO): Clearing failed state for component Waterlinked
2024-05-07T19:16:16.436Z,1715109376.436 [Waterlinked] No Fault, FailCount= 1
2024-05-07T19:17:18.341Z,1715109438.341 [CommandExec](IMPORTANT): got command get platform_orientation degree
2024-05-07T19:17:18.341Z,1715109438.341 [CommandExec](IMPORTANT): platform_orientation 208.478260 arcdeg
2024-05-07T19:17:23.203Z,1715109443.203 [CommandExec](IMPORTANT): got command get platform_orientation degree
2024-05-07T19:17:23.203Z,1715109443.203 [CommandExec](IMPORTANT): platform_orientation 207.368928 arcdeg
2024-05-07T19:20:01.526Z,1715109601.526 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-05-07T19:20:01.527Z,1715109601.527 [Default:CheckIn:C.Wait] Stopped
2024-05-07T19:20:01.527Z,1715109601.527 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-05-07T19:20:01.527Z,1715109601.527 [Default:CheckIn:D] Running Loop=1
2024-05-07T19:20:01.920Z,1715109601.920 [Default:CheckIn:D] Stopped
2024-05-07T19:20:01.920Z,1715109601.920 [Default:CheckIn:E] Running Loop=1
2024-05-07T19:20:02.305Z,1715109602.305 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 13.165045 min
2024-05-07T19:20:02.305Z,1715109602.305 [Default:CheckIn:E] Stopped
2024-05-07T19:20:02.306Z,1715109602.306 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-05-07T19:20:02.306Z,1715109602.306 [Default:CheckIn] Stopped
2024-05-07T19:20:02.306Z,1715109602.306 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-05-07T19:20:02.306Z,1715109602.306 [Default:CheckIn](INFO): Running loop #3
2024-05-07T19:20:02.306Z,1715109602.306 [Default:CheckIn] Running Loop=3
2024-05-07T19:20:02.306Z,1715109602.306 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-05-07T19:20:02.306Z,1715109602.306 [Default:CheckIn:Read_GPS] Running Loop=1
2024-05-07T19:20:04.285Z,1715109604.285 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192003.00,A,3648.13818,N,12147.20629,W,0.603,313.00,070524,,,D*74
2024-05-07T19:20:04.287Z,1715109604.287 [NAL9602](INFO): GPS fix at 20240507T192003: (36.802303, -121.786772)
2024-05-07T19:20:04.300Z,1715109604.300 [Default:CheckIn:Read_GPS] Stopped
2024-05-07T19:20:04.300Z,1715109604.300 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-05-07T19:20:12.753Z,1715109612.753 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240507T190228/Courier0010.lzma
2024-05-07T19:20:13.756Z,1715109613.756 [DataOverHttps](INFO): Moved sent file to Logs/20240507T190228/Courier0010.lzma.bak
2024-05-07T19:20:13.756Z,1715109613.756 [DataOverHttps](INFO): SBD MOMSN=20824412
2024-05-07T19:20:31.810Z,1715109631.810 [DataOverHttps](INFO): Sending 212 bytes from file Logs/20240507T190228/Express0011.lzma
2024-05-07T19:20:32.812Z,1715109632.812 [DataOverHttps](INFO): Moved sent file to Logs/20240507T190228/Express0011.lzma.bak
2024-05-07T19:20:32.812Z,1715109632.812 [DataOverHttps](INFO): SBD MOMSN=20824415
2024-05-07T19:20:33.848Z,1715109633.848 [Default:CheckIn:Read_Iridium] Stopped
2024-05-07T19:20:33.848Z,1715109633.848 [Default:CheckIn:C.Wait] Running Loop=1
2024-05-07T19:20:33.848Z,1715109633.848 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-05-07T19:20:46.720Z,1715109646.720 [NAL9602](INFO): SBD MO Status=0, MOMSN=17049, MT Status=0, MTMSN=0
2024-05-07T19:20:46.720Z,1715109646.720 [NAL9602](INFO): No messages in MT queue
2024-05-07T19:21:17.419Z,1715109677.419 [NAL9602](INFO): Not Powering down - fast GPS
2024-05-07T19:22:45.893Z,1715109765.893 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error. Device response::TS,230618150.0, +0.0, 0.0,1475.0, 0
2024-05-07T19:23:05.686Z,1715109785.686 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error. Device response::TS,230618150236 +0.0, 0.0,1475.0, 0
2024-05-07T19:24:18.372Z,1715109858.372 [CommandExec](IMPORTANT): got command get platform_orientation degree
2024-05-07T19:24:18.372Z,1715109858.372 [CommandExec](IMPORTANT): platform_orientation 352.819487 arcdeg
2024-05-07T19:25:00.514Z,1715109900.514 [Waterlinked](ERROR): only read 3 of 5 data items. Device response::BI, +22, +7, +0,A
2024-05-07T19:25:34.453Z,1715109934.453 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-05-07T19:25:34.453Z,1715109934.453 [Default:CheckIn:C.Wait] Stopped
2024-05-07T19:25:34.453Z,1715109934.453 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-05-07T19:25:34.453Z,1715109934.453 [Default:CheckIn:D] Running Loop=1
2024-05-07T19:25:34.879Z,1715109934.879 [Default:CheckIn:D] Stopped
2024-05-07T19:25:34.880Z,1715109934.880 [Default:CheckIn:E] Running Loop=1
2024-05-07T19:25:35.301Z,1715109935.301 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 18.714351 min
2024-05-07T19:25:35.301Z,1715109935.301 [Default:CheckIn:E] Stopped
2024-05-07T19:25:35.301Z,1715109935.301 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-05-07T19:25:35.301Z,1715109935.301 [Default:CheckIn] Stopped
2024-05-07T19:25:35.301Z,1715109935.301 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-05-07T19:25:35.301Z,1715109935.301 [Default:CheckIn](INFO): Running loop #4
2024-05-07T19:25:35.301Z,1715109935.301 [Default:CheckIn] Running Loop=4
2024-05-07T19:25:35.302Z,1715109935.302 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-05-07T19:25:35.306Z,1715109935.306 [Default:CheckIn:Read_GPS] Running Loop=1
2024-05-07T19:25:37.257Z,1715109937.257 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192536.00,A,3648.13775,N,12147.20648,W,0.039,134.64,070524,,,D*7E
2024-05-07T19:25:37.260Z,1715109937.260 [NAL9602](INFO): GPS fix at 20240507T192536: (36.802296, -121.786775)
2024-05-07T19:25:37.350Z,1715109937.350 [Default:CheckIn:Read_GPS] Stopped
2024-05-07T19:25:37.350Z,1715109937.350 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-05-07T19:25:46.109Z,1715109946.109 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240507T190228/Courier0013.lzma
2024-05-07T19:25:47.112Z,1715109947.112 [DataOverHttps](INFO): Moved sent file to Logs/20240507T190228/Courier0013.lzma.bak
2024-05-07T19:25:47.112Z,1715109947.112 [DataOverHttps](INFO): SBD MOMSN=20824447
2024-05-07T19:26:00.208Z,1715109960.208 [CommandExec](IMPORTANT): got command configSet DAT.loadAtStartup 1 bool persist
2024-05-07T19:26:00.208Z,1715109960.208 [CommandExec](IMPORTANT): configSet DAT.loadAtStartup requires a restart to take effect.
2024-05-07T19:26:00.211Z,1715109960.211 [CommandExec](IMPORTANT): got command restart application
2024-05-07T19:26:01.214Z,1715109961.214 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2024-05-07T19:26:01.214Z,1715109961.214 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2024-05-07T19:26:01.214Z,1715109961.214 [CommandExec](INFO): Uninitializing the command executive.
2024-05-07T19:26:01.214Z,1715109961.214 [CommandExec](INFO): Uninitializing the command scheduler.
2024-05-07T19:26:01.215Z,1715109961.215 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-05-07T19:26:01.354Z,1715109961.354 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2024-05-07T19:26:01.354Z,1715109961.354 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2024-05-07T19:26:01.354Z,1715109961.354 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-05-07T19:26:01.355Z,1715109961.355 [NavChartDb](INFO): Join timeout helper Thread ID is 3729
2024-05-07T19:26:01.726Z,1715109961.726 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2024-05-07T19:26:01.727Z,1715109961.727 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-05-07T19:26:01.742Z,1715109961.742 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2024-05-07T19:26:01.742Z,1715109961.742 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-05-07T19:26:01.743Z,1715109961.743 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 3730
2024-05-07T19:26:01.840Z,1715109961.840 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2024-05-07T19:26:01.841Z,1715109961.841 [WetLabsBB2FL](INFO): Powering down
2024-05-07T19:26:01.844Z,1715109961.844 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-05-07T19:26:01.864Z,1715109961.864 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2024-05-07T19:26:01.864Z,1715109961.864 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-05-07T19:26:01.864Z,1715109961.864 [CTD_Seabird](INFO): Join timeout helper Thread ID is 3731
2024-05-07T19:26:02.310Z,1715109962.310 [CTD_Seabird](INFO): Powering down
2024-05-07T19:26:02.322Z,1715109962.322 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2024-05-07T19:26:02.322Z,1715109962.322 [CTD_Seabird](INFO): Powering down
2024-05-07T19:26:02.334Z,1715109962.334 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-05-07T19:26:02.335Z,1715109962.335 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2024-05-07T19:26:02.335Z,1715109962.335 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-05-07T19:26:02.335Z,1715109962.335 [Radio_Surface](INFO): Join timeout helper Thread ID is 3732
2024-05-07T19:26:02.458Z,1715109962.458 [Radio_Surface](INFO): Powering down
2024-05-07T19:26:02.459Z,1715109962.459 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2024-05-07T19:26:02.459Z,1715109962.459 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-05-07T19:26:02.466Z,1715109962.466 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2024-05-07T19:26:02.466Z,1715109962.466 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-05-07T19:26:02.467Z,1715109962.467 [Onboard](INFO): Join timeout helper Thread ID is 3733
2024-05-07T19:26:05.574Z,1715109965.574 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2024-05-07T19:26:05.574Z,1715109965.574 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-05-07T19:26:05.579Z,1715109965.579 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2024-05-07T19:26:05.579Z,1715109965.579 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-05-07T19:26:05.579Z,1715109965.579 [DataOverHttps](INFO): Join timeout helper Thread ID is 3734
2024-05-07T19:26:06.317Z,1715109966.317 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2024-05-07T19:26:06.320Z,1715109966.320 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-05-07T19:26:06.359Z,1715109966.359 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2024-05-07T19:26:06.359Z,1715109966.359 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-05-07T19:26:06.360Z,1715109966.360 [BackseatComponent](INFO): Join timeout helper Thread ID is 3735
2024-05-07T19:26:06.382Z,1715109966.382 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2024-05-07T19:26:06.382Z,1715109966.382 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-05-07T19:26:06.387Z,1715109966.387 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2024-05-07T19:26:06.387Z,1715109966.387 [logger ThreadHandler](INFO): Thread cancelled.
2024-05-07T19:26:06.387Z,1715109966.387 [logger](INFO): Join timeout helper Thread ID is 3736
2024-05-07T19:26:06.390Z,1715109966.390 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2024-05-07T19:26:06.390Z,1715109966.390 [logger ThreadHandler](INFO): Thread cancelled.
2024-05-07T19:26:06.392Z,1715109966.392 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2024-05-07T19:26:06.392Z,1715109966.392 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-05-07T19:26:06.392Z,1715109966.392 [CommandLine](INFO): Join timeout helper Thread ID is 3737
2024-05-07T19:26:06.430Z,1715109966.430 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2024-05-07T19:26:06.430Z,1715109966.430 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-05-07T19:26:06.435Z,1715109966.435 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2024-05-07T19:26:06.435Z,1715109966.435 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-05-07T19:26:06.435Z,1715109966.435 [CommandExec](INFO): Join timeout helper Thread ID is 3738
2024-05-07T19:26:06.436Z,1715109966.436 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2024-05-07T19:26:06.436Z,1715109966.436 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-05-07T19:26:06.437Z,1715109966.437 [controlThread](INFO): Join timeout helper Thread ID is 3739
2024-05-07T19:26:06.734Z,1715109966.734 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2024-05-07T19:26:06.734Z,1715109966.734 [controlThread](DEBUG): Uninitializing ControlThread
2024-05-07T19:26:06.735Z,1715109966.735 [AHRS_M2](INFO): Powering down
2024-05-07T19:26:06.807Z,1715109966.807 [NAL9602](INFO): Powering down
2024-05-07T19:26:06.808Z,1715109966.808 [Sonardyne_Nano](INFO): Powering down
2024-05-07T19:26:06.998Z,1715109966.998 [Waterlinked](INFO): Powering down
2024-05-07T19:26:06.999Z,1715109966.999 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2024-05-07T19:26:07.000Z,1715109967.000 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2024-05-07T19:26:07.001Z,1715109967.001 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2024-05-07T19:26:07.001Z,1715109967.001 [MissionManager](INFO): Uninitializing Mission Default
2024-05-07T19:26:07.001Z,1715109967.001 [Default] Stopped
2024-05-07T19:26:07.001Z,1715109967.001 [Default](DEBUG): Aggregate::uninitialize Default
2024-05-07T19:26:07.002Z,1715109967.002 [Default:B.GoToSurface] Stopped
2024-05-07T19:26:07.002Z,1715109967.002 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-05-07T19:26:07.002Z,1715109967.002 [Default:CheckIn] Stopped
2024-05-07T19:26:07.002Z,1715109967.002 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-05-07T19:26:07.002Z,1715109967.002 [Default:CheckIn:Read_Iridium] Stopped
2024-05-07T19:26:07.005Z,1715109967.005 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2024-05-07T19:26:07.005Z,1715109967.005 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2024-05-07T19:26:07.005Z,1715109967.005 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2024-05-07T19:26:07.006Z,1715109967.006 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2024-05-07T19:26:07.006Z,1715109967.006 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2024-05-07T19:26:07.006Z,1715109967.006 [BuoyancyServo](INFO): Powering down
2024-05-07T19:26:07.018Z,1715109967.018 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2024-05-07T19:26:07.018Z,1715109967.018 [ElevatorServo](INFO): Powering down
2024-05-07T19:26:07.019Z,1715109967.019 [MassServo](DEBUG): Uninitialize Mass Servo.
2024-05-07T19:26:07.019Z,1715109967.019 [MassServo](INFO): Powering down
2024-05-07T19:26:07.020Z,1715109967.020 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2024-05-07T19:26:07.020Z,1715109967.020 [RudderServo](INFO): Powering down
2024-05-07T19:26:07.021Z,1715109967.021 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2024-05-07T19:26:07.021Z,1715109967.021 [ThrusterHE](INFO): Powering down
2024-05-07T19:26:07.022Z,1715109967.022 [SBIT](DEBUG): Uninitialize SBIT Component.
2024-05-07T19:26:07.022Z,1715109967.022 [IBIT](DEBUG): Uninitialize IBIT Component.
2024-05-07T19:26:07.022Z,1715109967.022 [CBIT](DEBUG): Uninitialize CBIT Component.
2024-05-07T19:26:07.023Z,1715109967.023 [CBIT](DEBUG): Powering off loads.
2024-05-07T19:26:07.034Z,1715109967.034 [CBIT](DEBUG): Disabling WDT.
2024-05-07T19:26:07.046Z,1715109967.046 [CBIT](DEBUG): Opening all GF detection circuits.
2024-05-07T19:26:07.047Z,1715109967.047 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-05-07T19:26:07.069Z,1715109967.069 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-05-07T19:26:07.072Z,1715109967.072 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-05-07T19:26:07.078Z,1715109967.078 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-05-07T19:26:07.121Z,1715109967.121 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-05-07T19:26:07.169Z,1715109967.169 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-05-07T19:26:07.173Z,1715109967.173 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-05-07T19:26:07.225Z,1715109967.225 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-05-07T19:26:07.273Z,1715109967.273 [logger ThreadHandler](INFO): Thread cancelled.