2025-06-16T16:17:51.492Z,1750090671.492 [Supervisor](DEBUG): Initializing supervisor. 2025-06-16T16:17:51.496Z,1750090671.496 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2025-06-16T16:17:51.497Z,1750090671.497 [SyncHandler](INFO): Protected caller Thread ID is 1024 2025-06-16T16:17:51.497Z,1750090671.497 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2025-06-16T16:17:51.498Z,1750090671.498 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2025-06-16T16:17:51.499Z,1750090671.499 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1025 2025-06-16T16:17:51.503Z,1750090671.503 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2025-06-16T16:17:51.523Z,1750090671.523 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2025-06-16T16:17:51.524Z,1750090671.524 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2025-06-16T16:17:51.524Z,1750090671.524 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1026 2025-06-16T16:17:51.529Z,1750090671.529 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2025-06-16T16:17:51.530Z,1750090671.530 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2025-06-16T16:17:51.530Z,1750090671.530 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1027 2025-06-16T16:17:51.532Z,1750090671.532 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2025-06-16T16:17:51.533Z,1750090671.533 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2025-06-16T16:17:51.534Z,1750090671.534 [logger ThreadHandler](INFO): Protected caller Thread ID is 1028 2025-06-16T16:17:51.537Z,1750090671.537 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2025-06-16T16:17:51.538Z,1750090671.538 [Supervisor](INFO): Looking for Config files in directory: Config/ 2025-06-16T16:17:51.539Z,1750090671.539 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2025-06-16T16:17:51.644Z,1750090671.644 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2025-06-16T16:17:51.644Z,1750090671.644 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2025-06-16T16:17:52.384Z,1750090672.384 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2025-06-16T16:17:52.384Z,1750090672.384 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2025-06-16T16:17:52.627Z,1750090672.627 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2025-06-16T16:17:52.627Z,1750090672.627 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2025-06-16T16:17:52.716Z,1750090672.716 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2025-06-16T16:17:52.856Z,1750090672.856 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2025-06-16T16:17:52.857Z,1750090672.857 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2025-06-16T16:17:52.957Z,1750090672.957 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance 2025-06-16T16:17:52.957Z,1750090672.957 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2025-06-16T16:17:53.075Z,1750090673.075 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2025-06-16T16:17:53.075Z,1750090673.075 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2025-06-16T16:17:53.489Z,1750090673.489 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2025-06-16T16:17:53.490Z,1750090673.490 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2025-06-16T16:17:54.027Z,1750090674.027 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2025-06-16T16:17:54.027Z,1750090674.027 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2025-06-16T16:17:54.253Z,1750090674.253 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2025-06-16T16:17:54.253Z,1750090674.253 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2025-06-16T16:17:54.447Z,1750090674.447 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2025-06-16T16:17:54.448Z,1750090674.448 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2025-06-16T16:17:55.009Z,1750090675.009 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2025-06-16T16:17:55.010Z,1750090675.010 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2025-06-16T16:17:55.306Z,1750090675.306 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2025-06-16T16:17:55.306Z,1750090675.306 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2025-06-16T16:17:55.553Z,1750090675.553 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2025-06-16T16:17:55.554Z,1750090675.554 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2025-06-16T16:17:55.949Z,1750090675.949 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2025-06-16T16:17:55.950Z,1750090675.950 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2025-06-16T16:17:56.743Z,1750090676.743 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2025-06-16T16:17:56.745Z,1750090676.745 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/ 2025-06-16T16:17:56.746Z,1750090676.746 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg 2025-06-16T16:17:56.838Z,1750090676.838 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg 2025-06-16T16:17:57.012Z,1750090677.012 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Dock.cfg 2025-06-16T16:17:57.126Z,1750090677.126 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/workSite.cfg 2025-06-16T16:17:57.229Z,1750090677.229 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg 2025-06-16T16:17:57.346Z,1750090677.346 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg 2025-06-16T16:17:57.441Z,1750090677.441 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg 2025-06-16T16:17:57.545Z,1750090677.545 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg 2025-06-16T16:17:57.667Z,1750090677.667 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg 2025-06-16T16:17:57.945Z,1750090677.945 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2025-06-16T16:17:57.945Z,1750090677.945 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg 2025-06-16T16:17:58.037Z,1750090678.037 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg 2025-06-16T16:17:58.138Z,1750090678.138 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg 2025-06-16T16:17:58.286Z,1750090678.286 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg 2025-06-16T16:17:58.390Z,1750090678.390 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg 2025-06-16T16:17:58.405Z,1750090678.405 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2025-06-16T16:17:58.548Z,1750090678.548 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2025-06-16T16:17:58.548Z,1750090678.548 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2025-06-16T16:17:58.603Z,1750090678.603 [VerticalControl](DEBUG): Construct VerticalControl. 2025-06-16T16:17:58.666Z,1750090678.666 [VerticalControl] Loaded 2025-06-16T16:17:58.666Z,1750090678.666 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2025-06-16T16:17:58.669Z,1750090678.669 [HorizontalControl](DEBUG): Construct HorizontalControl. 2025-06-16T16:17:58.717Z,1750090678.717 [HorizontalControl] Loaded 2025-06-16T16:17:58.717Z,1750090678.717 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2025-06-16T16:17:58.720Z,1750090678.720 [SpeedControl](DEBUG): Construct SpeedControl. 2025-06-16T16:17:58.723Z,1750090678.723 [SpeedControl] Loaded 2025-06-16T16:17:58.723Z,1750090678.723 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2025-06-16T16:17:58.726Z,1750090678.726 [LoopControl](DEBUG): Construct LoopControl. 2025-06-16T16:17:58.726Z,1750090678.726 [LoopControl] Loaded 2025-06-16T16:17:58.727Z,1750090678.727 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2025-06-16T16:17:58.727Z,1750090678.727 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2025-06-16T16:17:58.728Z,1750090678.728 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2025-06-16T16:17:58.780Z,1750090678.780 [DepthRateCalculator] Loaded 2025-06-16T16:17:58.780Z,1750090678.780 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2025-06-16T16:17:58.785Z,1750090678.785 [PitchRateCalculator] Loaded 2025-06-16T16:17:58.785Z,1750090678.785 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2025-06-16T16:17:58.795Z,1750090678.795 [SpeedCalculator] Loaded 2025-06-16T16:17:58.796Z,1750090678.796 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2025-06-16T16:17:58.800Z,1750090678.800 [YawRateCalculator] Loaded 2025-06-16T16:17:58.800Z,1750090678.800 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2025-06-16T16:17:58.819Z,1750090678.819 [ElevatorOffsetCalculator] Loaded 2025-06-16T16:17:58.819Z,1750090678.819 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2025-06-16T16:17:58.820Z,1750090678.820 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2025-06-16T16:17:58.820Z,1750090678.820 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2025-06-16T16:17:58.851Z,1750090678.851 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2025-06-16T16:17:58.851Z,1750090678.851 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2025-06-16T16:17:58.914Z,1750090678.914 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2025-06-16T16:17:58.914Z,1750090678.914 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2025-06-16T16:17:59.272Z,1750090679.272 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2025-06-16T16:17:59.272Z,1750090679.272 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2025-06-16T16:17:59.453Z,1750090679.453 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2025-06-16T16:17:59.453Z,1750090679.453 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2025-06-16T16:17:59.973Z,1750090679.973 [AHRS_M2] Loaded 2025-06-16T16:17:59.973Z,1750090679.973 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2025-06-16T16:18:00.012Z,1750090680.012 [BackseatComponent] Loaded 2025-06-16T16:18:00.013Z,1750090680.013 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2025-06-16T16:18:00.014Z,1750090680.014 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409E94E0 2025-06-16T16:18:00.014Z,1750090680.014 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1116 2025-06-16T16:18:00.017Z,1750090680.017 [LcmUniversalReporter] Loaded 2025-06-16T16:18:00.017Z,1750090680.017 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2025-06-16T16:18:00.861Z,1750090680.861 [BPC1] Loaded 2025-06-16T16:18:00.861Z,1750090680.861 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2025-06-16T16:18:00.961Z,1750090680.961 [DAT] Loaded 2025-06-16T16:18:00.961Z,1750090680.961 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2025-06-16T16:18:00.962Z,1750090680.962 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40A194E0 2025-06-16T16:18:00.963Z,1750090680.963 [DAT ThreadHandler](INFO): Protected caller Thread ID is 1117 2025-06-16T16:18:01.042Z,1750090681.042 [DataOverHttps] Loaded 2025-06-16T16:18:01.042Z,1750090681.042 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2025-06-16T16:18:01.043Z,1750090681.043 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40A494E0 2025-06-16T16:18:01.043Z,1750090681.043 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1118 2025-06-16T16:18:01.067Z,1750090681.067 [Depth_Keller] Loaded 2025-06-16T16:18:01.067Z,1750090681.067 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2025-06-16T16:18:01.133Z,1750090681.133 [NAL9602] Loaded 2025-06-16T16:18:01.133Z,1750090681.133 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2025-06-16T16:18:01.163Z,1750090681.163 [Onboard] Loaded 2025-06-16T16:18:01.163Z,1750090681.163 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2025-06-16T16:18:01.164Z,1750090681.164 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40A794E0 2025-06-16T16:18:01.165Z,1750090681.165 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1119 2025-06-16T16:18:01.179Z,1750090681.179 [Power24vConverter] Loaded 2025-06-16T16:18:01.179Z,1750090681.179 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2025-06-16T16:18:01.196Z,1750090681.196 [Radio_Surface] Loaded 2025-06-16T16:18:01.196Z,1750090681.196 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2025-06-16T16:18:01.197Z,1750090681.197 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40AA94E0 2025-06-16T16:18:01.197Z,1750090681.197 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1120 2025-06-16T16:18:01.245Z,1750090681.245 [RDI_Pathfinder] Loaded 2025-06-16T16:18:01.245Z,1750090681.245 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2025-06-16T16:18:01.261Z,1750090681.261 [Sonardyne_Nano] Loaded 2025-06-16T16:18:01.261Z,1750090681.261 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2025-06-16T16:18:01.262Z,1750090681.262 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2025-06-16T16:18:01.262Z,1750090681.262 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2025-06-16T16:18:01.647Z,1750090681.647 [DeadReckonUsingMultipleVelocitySources] Loaded 2025-06-16T16:18:01.647Z,1750090681.647 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2025-06-16T16:18:01.673Z,1750090681.673 [NavChart] Loaded 2025-06-16T16:18:01.673Z,1750090681.673 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2025-06-16T16:18:01.679Z,1750090681.679 [UniversalFixResidualReporter] Loaded 2025-06-16T16:18:01.679Z,1750090681.679 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2025-06-16T16:18:01.680Z,1750090681.680 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2025-06-16T16:18:01.680Z,1750090681.680 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2025-06-16T16:18:01.944Z,1750090681.944 [SBIT](DEBUG): Construct Startup Built In Test. 2025-06-16T16:18:01.953Z,1750090681.953 [SBIT] Loaded 2025-06-16T16:18:01.953Z,1750090681.953 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2025-06-16T16:18:01.956Z,1750090681.956 [IBIT](DEBUG): Construct Initiated Built In Test. 2025-06-16T16:18:01.969Z,1750090681.969 [IBIT] Loaded 2025-06-16T16:18:01.969Z,1750090681.969 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2025-06-16T16:18:01.975Z,1750090681.975 [CBIT](DEBUG): Construct Continuous Built In Test. 2025-06-16T16:18:02.058Z,1750090682.058 [CBIT] Loaded 2025-06-16T16:18:02.058Z,1750090682.058 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2025-06-16T16:18:02.091Z,1750090682.091 [GFScanner] Loaded 2025-06-16T16:18:02.092Z,1750090682.092 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread. 2025-06-16T16:18:02.092Z,1750090682.092 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2025-06-16T16:18:02.093Z,1750090682.093 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2025-06-16T16:18:02.234Z,1750090682.234 [BuoyancyServo] Loaded 2025-06-16T16:18:02.234Z,1750090682.234 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2025-06-16T16:18:02.258Z,1750090682.258 [ElevatorServo] Loaded 2025-06-16T16:18:02.258Z,1750090682.258 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2025-06-16T16:18:02.288Z,1750090682.288 [DockingStepper] Loaded 2025-06-16T16:18:02.288Z,1750090682.288 [ComponentRegistry](DEBUG): SyncComponent "DockingStepper" handled in the control thread. 2025-06-16T16:18:02.310Z,1750090682.310 [MassServo] Loaded 2025-06-16T16:18:02.310Z,1750090682.310 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2025-06-16T16:18:02.331Z,1750090682.331 [RudderServo] Loaded 2025-06-16T16:18:02.332Z,1750090682.332 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2025-06-16T16:18:02.352Z,1750090682.352 [ThrusterServo] Loaded 2025-06-16T16:18:02.352Z,1750090682.352 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2025-06-16T16:18:02.353Z,1750090682.353 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2025-06-16T16:18:02.353Z,1750090682.353 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2025-06-16T16:18:02.363Z,1750090682.363 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2025-06-16T16:18:02.364Z,1750090682.364 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2025-06-16T16:18:02.541Z,1750090682.541 [CTD_Seabird] Loaded 2025-06-16T16:18:02.541Z,1750090682.541 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2025-06-16T16:18:02.542Z,1750090682.542 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40C0C4E0 2025-06-16T16:18:02.543Z,1750090682.543 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1121 2025-06-16T16:18:02.566Z,1750090682.566 [PAR_Licor] Loaded 2025-06-16T16:18:02.566Z,1750090682.566 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2025-06-16T16:18:02.612Z,1750090682.612 [WetLabsBB2FL] Loaded 2025-06-16T16:18:02.612Z,1750090682.612 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2025-06-16T16:18:02.613Z,1750090682.613 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40C3C4E0 2025-06-16T16:18:02.614Z,1750090682.614 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1122 2025-06-16T16:18:02.635Z,1750090682.635 [WetLabsUBAT] Loaded 2025-06-16T16:18:02.635Z,1750090682.635 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread. 2025-06-16T16:18:02.636Z,1750090682.636 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40C6C4E0 2025-06-16T16:18:02.637Z,1750090682.637 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 1123 2025-06-16T16:18:02.637Z,1750090682.637 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2025-06-16T16:18:02.644Z,1750090682.644 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2025-06-16T16:18:02.647Z,1750090682.647 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2025-06-16T16:18:02.659Z,1750090682.659 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2025-06-16T16:18:02.660Z,1750090682.660 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C9C4E0 2025-06-16T16:18:02.661Z,1750090682.661 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1124 2025-06-16T16:18:02.665Z,1750090682.665 [Supervisor](INFO): Main Thread ID is 829 2025-06-16T16:18:02.665Z,1750090682.665 [Supervisor](DEBUG): Running supervisor. 2025-06-16T16:18:02.666Z,1750090682.666 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1125 2025-06-16T16:18:02.666Z,1750090682.666 [CommandExec](INFO): Initializing the command executive. 2025-06-16T16:18:02.668Z,1750090682.668 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1126 2025-06-16T16:18:02.670Z,1750090682.670 [controlThread ThreadHandler](INFO): Handler Thread ID is 1127 2025-06-16T16:18:02.670Z,1750090682.670 [controlThread](DEBUG): Initializing ControlThread 2025-06-16T16:18:02.671Z,1750090682.671 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2025-06-16T16:18:02.673Z,1750090682.673 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2025-06-16T16:18:02.673Z,1750090682.673 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2025-06-16T16:18:02.674Z,1750090682.674 [LoopControl](DEBUG): Initialize LoopControlComponent. 2025-06-16T16:18:02.674Z,1750090682.674 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2025-06-16T16:18:02.675Z,1750090682.675 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2025-06-16T16:18:02.675Z,1750090682.675 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2025-06-16T16:18:02.675Z,1750090682.675 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2025-06-16T16:18:02.676Z,1750090682.676 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2025-06-16T16:18:02.679Z,1750090682.679 [NavChart](DEBUG): Initialize NavChart Navigation. 2025-06-16T16:18:02.679Z,1750090682.679 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2025-06-16T16:18:02.680Z,1750090682.680 [SBIT](INFO): Initialize SBIT Component. 2025-06-16T16:18:02.680Z,1750090682.680 [SBIT](IMPORTANT): git: 2025-06-05-2-g07de84f03 2025-06-16T16:18:02.680Z,1750090682.680 [SBIT](INFO): git hash: 07de84f036b9b391a47d8c2a08834ee5eaa918cc 2025-06-16T16:18:02.681Z,1750090682.681 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2025-06-16T16:18:02.682Z,1750090682.682 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019 Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021 2025-06-16T16:18:02.683Z,1750090682.683 [SBIT](INFO): Beginning SBIT in 91.000000 seconds. 2025-06-16T16:18:02.683Z,1750090682.683 [IBIT](INFO): Initialize IBIT Component. 2025-06-16T16:18:02.684Z,1750090682.684 [CBIT](DEBUG): Initialize CBIT Component. 2025-06-16T16:18:02.685Z,1750090682.685 [logger ThreadHandler](INFO): Handler Thread ID is 1128 2025-06-16T16:18:02.696Z,1750090682.696 [CBIT](DEBUG): Initialized mux pins. 2025-06-16T16:18:02.696Z,1750090682.696 [CBIT](DEBUG): Initializing the watchdog timer. 2025-06-16T16:18:02.704Z,1750090682.704 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1129 2025-06-16T16:18:02.720Z,1750090682.720 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2025-06-16T16:18:02.720Z,1750090682.720 [CBIT](DEBUG): Initializing heartbeat. 2025-06-16T16:18:02.721Z,1750090682.721 [DAT ThreadHandler](INFO): Handler Thread ID is 1130 2025-06-16T16:18:02.722Z,1750090682.722 [DAT](INFO): Powering up 2025-06-16T16:18:02.722Z,1750090682.722 [DAT](DEBUG): Initializing DAT. 2025-06-16T16:18:02.725Z,1750090682.725 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1132 2025-06-16T16:18:02.727Z,1750090682.727 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2025-06-16T16:18:02.748Z,1750090682.748 [Onboard ThreadHandler](INFO): Handler Thread ID is 1133 2025-06-16T16:18:02.765Z,1750090682.765 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1134 2025-06-16T16:18:02.784Z,1750090682.784 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1135 2025-06-16T16:18:02.785Z,1750090682.785 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2025-06-16T16:18:02.789Z,1750090682.789 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1137 2025-06-16T16:18:02.792Z,1750090682.792 [CBIT](DEBUG): Deactivating emergency mode. 2025-06-16T16:18:02.793Z,1750090682.793 [WetLabsBB2FL](INFO): Powering up 2025-06-16T16:18:02.794Z,1750090682.794 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 1139 2025-06-16T16:18:02.801Z,1750090682.801 [WetLabsUBAT](INFO): Powering up 2025-06-16T16:18:02.802Z,1750090682.802 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1141 2025-06-16T16:18:02.805Z,1750090682.805 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2025-06-16T16:18:02.805Z,1750090682.805 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2025-06-16T16:18:02.805Z,1750090682.805 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2025-06-16T16:18:02.805Z,1750090682.805 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2025-06-16T16:18:02.806Z,1750090682.806 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2025-06-16T16:18:02.806Z,1750090682.806 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2025-06-16T16:18:02.806Z,1750090682.806 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2025-06-16T16:18:02.806Z,1750090682.806 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2025-06-16T16:18:02.832Z,1750090682.832 [CBIT](DEBUG): Backplane powered. 2025-06-16T16:18:02.832Z,1750090682.832 [GFScanner](DEBUG): Initializing GFScanner 2025-06-16T16:18:02.832Z,1750090682.832 [GFScanner](DEBUG): Deactivating GF circuits. 2025-06-16T16:18:02.840Z,1750090682.840 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2025-06-16T16:18:02.841Z,1750090682.841 [MissionManager](INFO): Loading Mission from file: Missions/underIce/StartupUnder.tl 2025-06-16T16:18:02.841Z,1750090682.841 [MissionManager](DEBUG): TethyslAPI: loading: Missions/underIce/StartupUnder.tl 2025-06-16T16:18:02.843Z,1750090682.843 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/underIce/StartupUnder.tl 2025-06-16T16:18:02.856Z,1750090682.856 [MissionManager](DEBUG): TethyslAPI: Missions/underIce/StartupUnder.tl translated into: 2025-06-16T16:18:02.856Z,1750090682.856 [MissionManager](ERROR): Top node has no child nodes: Mission 2025-06-16T16:18:02.857Z,1750090682.857 [MissionManager](ERROR): Could not load startup mission. 2025-06-16T16:18:02.857Z,1750090682.857 [MissionManager](INFO): Loading Mission from file: Missions/underIce/DefaultWithUndock.tl 2025-06-16T16:18:02.857Z,1750090682.857 [MissionManager](DEBUG): TethyslAPI: loading: Missions/underIce/DefaultWithUndock.tl 2025-06-16T16:18:02.858Z,1750090682.858 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/underIce/DefaultWithUndock.tl 2025-06-16T16:18:03.172Z,1750090683.172 [Radio_Surface](INFO): Powering up 2025-06-16T16:18:03.444Z,1750090683.444 [MissionManager](DEBUG): TethyslAPI: Missions/underIce/DefaultWithUndock.tl translated into: 0 6 10 1 3 30 Timed out while trying to detach from dock at current depth of . Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2025-06-16T16:18:03.451Z,1750090683.451 [MissionManager](INFO): DefineArg DefaultWithUndock.ElapsedSinceDefaultStarted = 0.000000 min 2025-06-16T16:18:03.471Z,1750090683.471 [MissionManager](INFO): DefineArg DefaultWithUndock.DetachTimeout = 6.000000 h 2025-06-16T16:18:03.480Z,1750090683.480 [MissionManager](INFO): DefineArg DefaultWithUndock.TransponderCode = 50 count 2025-06-16T16:18:03.504Z,1750090683.504 [MissionManager](INFO): DefineArg DefaultWithUndock.TrackingUpdatePeriod = 10.000000 s 2025-06-16T16:18:03.508Z,1750090683.508 [MissionManager](INFO): DefineArg DefaultWithUndock.NumberOfPings = 1.000000 count 2025-06-16T16:18:03.528Z,1750090683.528 [MissionManager](INFO): DefineArg DefaultWithUndock.DriftTimeout = 3.000000 min 2025-06-16T16:18:03.529Z,1750090683.529 [DefaultWithUndock:A.Wait](DEBUG): Construct Wait. 2025-06-16T16:18:03.699Z,1750090683.699 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Construct. 2025-06-16T16:18:03.699Z,1750090683.699 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values. 2025-06-16T16:18:03.749Z,1750090683.749 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Construct GoToSurface. 2025-06-16T16:18:03.791Z,1750090683.791 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2025-06-16T16:18:03.793Z,1750090683.793 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Construct Wait. 2025-06-16T16:18:03.819Z,1750090683.819 [DefaultWithUndock:E.Execute](DEBUG): Construct Execute. 2025-06-16T16:18:03.840Z,1750090683.840 [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 DefaultWithUndock { arguments { ElapsedSinceDefaultStarted = 0 minute DetachTimeout = 6 hour """ Maximum time duration for the vehicle to detach form the dock. """ TransponderCode = Dock:Dock.transponderCode """ Dock transponder address. """ TrackingUpdatePeriod = 10 second """ Time duration between acoustic range queries while the mission is detaching from the dock. """ NumberOfPings = 1 count """ Number of pings requested each time. """ DriftTimeout = 3 minute """ How long to allow vehicle to drift away from dock before retrying reverse """ } 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 = 30 second } aggregate LeaveDock { run in sequence timeout duration=DetachTimeout { syslog important "Timed out while trying to detach from dock at current depth of " + Universal:depth~meter + "." } # Acoustic tracking directives behavior Estimation:TrackAcousticContact { run while ( Universal:depth > Control:VerticalControl.surfaceThreshold ) set contactLabelSetting = TransponderCode set numberOfSamplesSetting = NumberOfPings set updatePeriodSetting = TrackingUpdatePeriod } # Leave the dock behavior Dock:Undock { run in sequence set driftTimeout = DriftTimeout } } aggregate SurfaceDefault { run in sequence # This mission assumes open water so from here on it's the same old default that's always used 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=P2H { 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" } } 2025-06-16T16:18:03.845Z,1750090683.845 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,NAL9602,Power24vConverter,RDI_Pathfinder,Sonardyne_Nano,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,DockingStepper,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,GFScanner,LcmUniversalReporter,Reporter,LogSplitter, 2025-06-16T16:18:03.869Z,1750090683.869 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2025-06-16T16:18:03.932Z,1750090683.932 [Depth_Keller](INFO): Initializing. 2025-06-16T16:18:03.933Z,1750090683.933 [Power24vConverter](INFO): Powering up. 2025-06-16T16:18:03.934Z,1750090683.934 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format 2025-06-16T16:18:03.941Z,1750090683.941 [Sonardyne_Nano](INFO): Initializing. 2025-06-16T16:18:03.984Z,1750090683.984 [DepthRateCalculator](ERROR): Depth measurement is not active 2025-06-16T16:18:04.011Z,1750090684.011 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2025-06-16T16:18:04.017Z,1750090684.017 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2025-06-16T16:18:04.018Z,1750090684.018 [ElevatorServo](DEBUG): Initializing EZServoServo. 2025-06-16T16:18:04.024Z,1750090684.024 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2025-06-16T16:18:04.025Z,1750090684.025 [DockingStepper](INFO): Start 2025-06-16T16:18:04.025Z,1750090684.025 [DockingStepper](DEBUG): Initializing EZServoServo. 2025-06-16T16:18:04.032Z,1750090684.032 [DockingStepper](DEBUG): Initializing DockingStepper. 2025-06-16T16:18:04.033Z,1750090684.033 [MassServo](DEBUG): Initializing EZServoServo. 2025-06-16T16:18:04.040Z,1750090684.040 [MassServo](DEBUG): Initializing MassServo. 2025-06-16T16:18:04.041Z,1750090684.041 [RudderServo](DEBUG): Initializing EZServoServo. 2025-06-16T16:18:04.048Z,1750090684.048 [RudderServo](DEBUG): Initializing RudderServo. 2025-06-16T16:18:04.049Z,1750090684.049 [ThrusterServo](DEBUG): Initializing EZServoServo. 2025-06-16T16:18:04.056Z,1750090684.056 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2025-06-16T16:18:04.346Z,1750090684.346 [Depth_Keller](ERROR): Pressure or depth reading out of range: 824.648376 decibar, 0.000000 m 2025-06-16T16:18:04.350Z,1750090684.350 [DepthRateCalculator](ERROR): Depth measurement is not active 2025-06-16T16:18:04.369Z,1750090684.369 [DockingStepper](INFO): Starting 2025-06-16T16:18:04.679Z,1750090684.679 [DockingStepper](INFO): Starting 2025-06-16T16:18:05.200Z,1750090685.200 [DockingStepper](INFO): Starting 2025-06-16T16:18:05.249Z,1750090685.249 [DockingStepper](INFO): Init string: /1z16368aE13561N3m50 2025-06-16T16:18:05.405Z,1750090685.405 [WetLabsBB2FL](INFO): Powering down 2025-06-16T16:18:05.502Z,1750090685.502 [DockingStepper](INFO): Starting 2025-06-16T16:18:05.874Z,1750090685.874 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2025-06-16T16:18:05.907Z,1750090685.907 [DockingStepper](INFO): Starting 2025-06-16T16:18:06.295Z,1750090686.295 [DockingStepper](INFO): Starting 2025-06-16T16:18:06.773Z,1750090686.773 [DockingStepper](INFO): Starting 2025-06-16T16:18:06.797Z,1750090686.797 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2025-06-16T16:18:07.224Z,1750090687.224 [DockingStepper](INFO): Starting 2025-06-16T16:18:07.532Z,1750090687.532 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2103 2025-06-16T16:18:07.965Z,1750090687.965 [DockingStepper](INFO): Pause 2025-06-16T16:18:08.424Z,1750090688.424 [Radio_Surface](INFO): Checking local IP, expecting subnet 10.89.10 2025-06-16T16:18:08.425Z,1750090688.425 [Radio_Surface](INFO): Found local IP address 10.89.10.2 for eth0 2025-06-16T16:18:12.068Z,1750090692.068 [WetLabsUBAT](INFO): Powering down 2025-06-16T16:18:14.818Z,1750090694.818 [DAT](INFO): DAT read: 2025-06-16T16:18:14.819Z,1750090694.819 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2025-06-16T16:18:20.361Z,1750090700.361 [DAT](INFO): DAT read: MF Frequency Band 2025-06-16T16:18:20.362Z,1750090700.362 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.17.2 2025-06-16T16:18:20.363Z,1750090700.363 [DAT](INFO): DAT read: Jun 16 2025 16:18:11 2025-06-16T16:18:21.621Z,1750090701.621 [DAT](INFO): DAT read: Features enabled [Bearing] 2025-06-16T16:18:21.622Z,1750090701.622 [DAT](INFO): DAT read: CONNECT 00600 bits/sec 1 of 4, Rate 1/2 CC 25.00ms MGP 2025-06-16T16:18:21.623Z,1750090701.623 [DAT](INFO): commRate: 600 2025-06-16T16:18:23.689Z,1750090703.689 [DAT](INFO): entering command mode 2025-06-16T16:18:23.889Z,1750090703.889 [DAT](INFO): DAT read: 2025-06-16T16:18:23.889Z,1750090703.889 [DAT](INFO): DAT read: user:1> 2025-06-16T16:18:23.889Z,1750090703.889 [DAT](INFO): setting verbose to 3 2025-06-16T16:18:24.141Z,1750090704.141 [DAT](INFO): DAT read: user:1> 2025-06-16T16:18:24.142Z,1750090704.142 [DAT](INFO): DAT read: Verbose | 3 2025-06-16T16:18:24.142Z,1750090704.142 [DAT](INFO): set verbose to 3 2025-06-16T16:18:24.142Z,1750090704.142 [DAT](INFO): setting DatVerbose to 27440 2025-06-16T16:18:24.392Z,1750090704.392 [DAT](INFO): DAT read: user:2> 2025-06-16T16:18:24.393Z,1750090704.393 [DAT](INFO): DAT read: DatVerbose | 27440 2025-06-16T16:18:24.394Z,1750090704.394 [DAT](INFO): set DatVerbose to 27440 2025-06-16T16:18:24.394Z,1750090704.394 [DAT](INFO): setting transmit power to 8 2025-06-16T16:18:24.645Z,1750090704.645 [DAT](INFO): DAT read: user:3> 2025-06-16T16:18:24.646Z,1750090704.646 [DAT](INFO): DAT read: TxPower | 8 (Max) 2025-06-16T16:18:24.646Z,1750090704.646 [DAT](INFO): set transmit power to 8 2025-06-16T16:18:24.646Z,1750090704.646 [DAT](INFO): setting local address to 10 2025-06-16T16:18:24.897Z,1750090704.897 [DAT](INFO): DAT read: user:4> 2025-06-16T16:18:24.898Z,1750090704.898 [DAT](INFO): DAT read: LocalAddr | 10 2025-06-16T16:18:24.898Z,1750090704.898 [DAT](INFO): set local address to 10 2025-06-16T16:18:24.899Z,1750090704.899 [DAT](INFO): Setting time to: 16:18:24 And date to:6/16/2025 2025-06-16T16:18:25.149Z,1750090705.149 [DAT](INFO): DAT read: user:5> 2025-06-16T16:18:25.150Z,1750090705.150 [DAT](INFO): DAT read: Mon Jun 16, 2025 16:18:24 2025-06-16T16:18:25.150Z,1750090705.150 [DAT](INFO): Local DAT time set to Mon Jun 16, 2025 16:18:24 2025-06-16T16:18:31.353Z,1750090711.353 [NAL9602](INFO): Powering up NAL9602 2025-06-16T16:18:33.848Z,1750090713.848 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-16T16:18:42.253Z,1750090722.253 [NAL9602](INFO): NAL9602 initialized 2025-06-16T16:18:56.399Z,1750090736.399 [NAL9602](INFO): SBD MO Status=0, MOMSN=48518, MT Status=0, MTMSN=0 2025-06-16T16:18:56.399Z,1750090736.399 [NAL9602](INFO): No messages in MT queue 2025-06-16T16:19:04.972Z,1750090744.972 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-16T16:19:34.396Z,1750090774.396 [SBIT](IMPORTANT): Beginning Startup BIT 2025-06-16T16:19:34.400Z,1750090774.400 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-06-16T16:19:36.104Z,1750090776.104 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-16T16:19:38.936Z,1750090778.936 [GFScanner](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.016745 CHAN A1 (24V): 0.013736 CHAN A2 (12V): 0.010671 CHAN A3 (5V): 0.001796 CHAN B0 (3.3V): 0.002126 CHAN B1 (3.15aV): 0.001894 CHAN B2 (3.15bV): 0.003866 CHAN B3 (GND): -0.000182 OPEN: 0.006109 Full Scale: +/- 1 mA 2025-06-16T16:19:42.059Z,1750090782.059 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161948.00,A,3652.48703,N,12159.34647,W,0.836,133.41,160625,,,A*76 2025-06-16T16:19:42.062Z,1750090782.062 [NAL9602](INFO): GPS fix at 20250616T161948: (36.874784, -121.989108) 2025-06-16T16:20:07.230Z,1750090807.230 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-16T16:20:14.103Z,1750090814.103 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-16T16:20:27.871Z,1750090827.871 [SBIT](IMPORTANT): SBIT PASSED 2025-06-16T16:20:27.871Z,1750090827.871 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2025-06-16T16:20:27.872Z,1750090827.872 [SBIT](IMPORTANT): CBIT.gf24Offset=173 microampere; 2025-06-16T16:20:27.872Z,1750090827.872 [SBIT](IMPORTANT): Docked.verbose=0 bool; 2025-06-16T16:20:27.872Z,1750090827.872 [SBIT](IMPORTANT): DockingStepper.closedValue=16450 count; 2025-06-16T16:20:27.873Z,1750090827.873 [SBIT](IMPORTANT): DockingStepper.slideValue=10700 count; 2025-06-16T16:20:27.874Z,1750090827.874 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool; 2025-06-16T16:20:27.875Z,1750090827.875 [SBIT](IMPORTANT): Express mostRecent WetLabsUBAT.average_bioluminescence; 2025-06-16T16:20:27.877Z,1750090827.877 [SBIT](IMPORTANT): LineCapture.rolloutSpeed=0 meter_per_second; 2025-06-16T16:20:27.878Z,1750090827.878 [SBIT](IMPORTANT): Normal linearApproximation _.dinoflagellates 10000000000.000000 none; 2025-06-16T16:20:27.878Z,1750090827.878 [SBIT](IMPORTANT): RudderServo.offsetAngle=1 degree; 2025-06-16T16:20:27.885Z,1750090827.885 [SBIT](IMPORTANT): SpeedControl.rollOptimum=1.025000 m/s; 2025-06-16T16:20:27.886Z,1750090827.886 [SBIT](IMPORTANT): ThrusterServo.currLimit=60 percent; 2025-06-16T16:20:27.888Z,1750090827.888 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=436.673744 cubic_centimeter; 2025-06-16T16:20:27.889Z,1750090827.889 [SBIT](IMPORTANT): VerticalControl.massDefault=17.954016 millimeter; 2025-06-16T16:20:28.264Z,1750090828.264 [MissionManager](IMPORTANT): Started mission DefaultWithUndock 2025-06-16T16:20:28.265Z,1750090828.265 [DefaultWithUndock] Running Loop=1 2025-06-16T16:20:28.265Z,1750090828.265 [DefaultWithUndock](DEBUG): Aggregate::initialize DefaultWithUndock 2025-06-16T16:20:28.265Z,1750090828.265 [DefaultWithUndock:A.Wait] Running Loop=1 2025-06-16T16:20:28.265Z,1750090828.265 [DefaultWithUndock:A.Wait](DEBUG): Initialize Wait Component. 2025-06-16T16:20:38.352Z,1750090838.352 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-16T16:20:58.562Z,1750090858.562 [DefaultWithUndock:A.Wait](INFO): Done Waiting. 2025-06-16T16:20:58.562Z,1750090858.562 [DefaultWithUndock:A.Wait] Stopped 2025-06-16T16:20:58.562Z,1750090858.562 [DefaultWithUndock:A.Wait](DEBUG): Uninitialize Wait Component. 2025-06-16T16:20:58.562Z,1750090858.562 [DefaultWithUndock:LeaveDock] Running Loop=1 2025-06-16T16:20:58.563Z,1750090858.563 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::initialize DefaultWithUndock:LeaveDock 2025-06-16T16:20:58.563Z,1750090858.563 [DefaultWithUndock:LeaveDock:A.] Running Loop=1 2025-06-16T16:20:58.563Z,1750090858.563 [DefaultWithUndock:LeaveDock:A.](INFO): Initializing TrackAcousticContact. 2025-06-16T16:20:58.563Z,1750090858.563 [DefaultWithUndock:LeaveDock:B.Undock] Running Loop=1 2025-06-16T16:20:58.563Z,1750090858.563 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize. 2025-06-16T16:20:58.563Z,1750090858.563 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values. 2025-06-16T16:20:58.969Z,1750090858.969 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize. 2025-06-16T16:20:58.969Z,1750090858.969 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values. 2025-06-16T16:20:58.970Z,1750090858.970 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached from dock at depth -0.213375 m. 2025-06-16T16:20:58.971Z,1750090858.971 [DefaultWithUndock:LeaveDock:A.] Stopped 2025-06-16T16:20:58.971Z,1750090858.971 [DefaultWithUndock:LeaveDock:A.](DEBUG): Uninitializing TrackAcousticContact. 2025-06-16T16:20:58.976Z,1750090858.976 [DockingStepper](INFO): Resume 2025-06-16T16:20:59.382Z,1750090859.382 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detected possible detachment. Starting timer. 2025-06-16T16:20:59.392Z,1750090859.392 [DockingStepper](INFO): Resuming 2025-06-16T16:20:59.808Z,1750090859.808 [DockingStepper](INFO): Start 2025-06-16T16:20:59.808Z,1750090859.808 [DockingStepper](DEBUG): Initializing EZServoServo. 2025-06-16T16:20:59.925Z,1750090859.925 [DockingStepper](DEBUG): Initializing DockingStepper. 2025-06-16T16:21:00.179Z,1750090860.179 [DockingStepper](INFO): Starting 2025-06-16T16:21:00.593Z,1750090860.593 [DockingStepper](INFO): Starting 2025-06-16T16:21:01.125Z,1750090861.125 [DockingStepper](INFO): Starting 2025-06-16T16:21:01.176Z,1750090861.176 [DockingStepper](INFO): Init string: /1z16368aE13561N3m50 2025-06-16T16:21:01.501Z,1750090861.501 [DockingStepper](INFO): Starting 2025-06-16T16:21:01.853Z,1750090861.853 [DockingStepper](INFO): Starting 2025-06-16T16:21:02.249Z,1750090862.249 [DockingStepper](INFO): Starting 2025-06-16T16:21:02.633Z,1750090862.633 [DockingStepper](INFO): Starting 2025-06-16T16:21:02.657Z,1750090862.657 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2025-06-16T16:21:03.117Z,1750090863.117 [DockingStepper](INFO): Starting 2025-06-16T16:21:09.476Z,1750090869.476 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-16T16:21:19.590Z,1750090879.590 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached at range: nan m. Transitioning docking module to standby. 2025-06-16T16:21:19.969Z,1750090879.969 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Docking module at standby. 2025-06-16T16:21:20.374Z,1750090880.374 [DefaultWithUndock:LeaveDock:B.Undock](IMPORTANT): Undocking sequence complete. 2025-06-16T16:21:20.374Z,1750090880.374 [DefaultWithUndock:LeaveDock:B.Undock] Stopped 2025-06-16T16:21:20.374Z,1750090880.374 [DefaultWithUndock:LeaveDock](INFO): Completed DefaultWithUndock:LeaveDock 2025-06-16T16:21:20.374Z,1750090880.374 [DefaultWithUndock:LeaveDock] Stopped 2025-06-16T16:21:20.375Z,1750090880.375 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::uninitialize DefaultWithUndock:LeaveDock 2025-06-16T16:21:20.375Z,1750090880.375 [DefaultWithUndock:SurfaceDefault] Running Loop=1 2025-06-16T16:21:20.375Z,1750090880.375 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault 2025-06-16T16:21:20.375Z,1750090880.375 [DefaultWithUndock:SurfaceDefault:A.GoToSurface] Running Loop=1 2025-06-16T16:21:20.375Z,1750090880.375 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-06-16T16:21:20.375Z,1750090880.375 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-06-16T16:21:20.376Z,1750090880.376 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-06-16T16:21:20.376Z,1750090880.376 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-06-16T16:21:20.377Z,1750090880.377 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-06-16T16:21:20.377Z,1750090880.377 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-06-16T16:21:20.377Z,1750090880.377 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-06-16T16:21:20.779Z,1750090880.779 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=1 2025-06-16T16:21:20.779Z,1750090880.779 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-06-16T16:21:20.779Z,1750090880.779 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2025-06-16T16:21:20.913Z,1750090880.913 [DockingStepper](INFO): Pause 2025-06-16T16:21:21.195Z,1750090881.195 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-06-16T16:21:22.795Z,1750090882.795 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162130.00,A,3652.48272,N,12159.33689,W,0.914,72.58,160625,,,D*4C 2025-06-16T16:21:22.798Z,1750090882.798 [NAL9602](INFO): GPS fix at 20250616T162130: (36.874712, -121.988948) 2025-06-16T16:21:22.817Z,1750090882.817 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2025-06-16T16:21:22.817Z,1750090882.817 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2025-06-16T16:21:23.229Z,1750090883.229 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-06-16T16:21:40.600Z,1750090900.600 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-16T16:21:44.199Z,1750090904.199 [NAL9602](INFO): SBD MO Status=2, MOMSN=48519, MT Status=2, MTMSN=0 2025-06-16T16:21:44.199Z,1750090904.199 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-16T16:22:10.294Z,1750090930.294 [NAL9602](INFO): SBD MO Status=2, MOMSN=48519, MT Status=2, MTMSN=0 2025-06-16T16:22:10.294Z,1750090930.294 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-16T16:22:11.733Z,1750090931.733 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-16T16:22:19.393Z,1750090939.393 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004363 2025-06-16T16:22:27.295Z,1750090947.295 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250616T160444/Courier0010.lzma 2025-06-16T16:22:28.298Z,1750090948.298 [DataOverHttps](INFO): Moved sent file to Logs/20250616T160444/Courier0010.lzma.bak 2025-06-16T16:22:28.298Z,1750090948.298 [DataOverHttps](INFO): SBD MOMSN=25218558 2025-06-16T16:22:36.011Z,1750090956.011 [NAL9602](INFO): SBD MO Status=2, MOMSN=48519, MT Status=2, MTMSN=0 2025-06-16T16:22:36.011Z,1750090956.011 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-16T16:22:50.904Z,1750090970.904 [DataOverHttps](INFO): Sending 267 bytes from file Logs/20250616T161751/Courier0000.lzma 2025-06-16T16:22:51.906Z,1750090971.906 [DataOverHttps](INFO): Moved sent file to Logs/20250616T161751/Courier0000.lzma.bak 2025-06-16T16:22:51.906Z,1750090971.906 [DataOverHttps](INFO): SBD MOMSN=25218560 2025-06-16T16:23:06.661Z,1750090986.661 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-16T16:23:10.736Z,1750090990.736 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20250616T160444/Express0011.lzma 2025-06-16T16:23:11.755Z,1750090991.755 [DataOverHttps](INFO): Moved sent file to Logs/20250616T160444/Express0011.lzma.bak 2025-06-16T16:23:11.755Z,1750090991.755 [DataOverHttps](INFO): SBD MOMSN=25218564 2025-06-16T16:23:28.187Z,1750091008.187 [DataOverHttps](INFO): Sending 1326 bytes from file Logs/20250616T161751/Express0001.lzma 2025-06-16T16:23:29.186Z,1750091009.186 [DataOverHttps](INFO): Moved sent file to Logs/20250616T161751/Express0001.lzma.bak 2025-06-16T16:23:29.186Z,1750091009.186 [DataOverHttps](INFO): SBD MOMSN=25218571 2025-06-16T16:23:30.924Z,1750091010.924 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2025-06-16T16:23:30.924Z,1750091010.924 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1 2025-06-16T16:23:30.925Z,1750091010.925 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-16T16:23:39.012Z,1750091019.012 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-16T16:23:47.756Z,1750091027.756 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-16T16:23:57.516Z,1750091037.516 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-16T16:24:09.484Z,1750091049.484 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-16T16:24:18.224Z,1750091058.224 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-16T16:24:27.124Z,1750091067.124 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-16T16:24:35.820Z,1750091075.820 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-16T16:24:45.660Z,1750091085.660 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-16T16:24:54.304Z,1750091094.304 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-16T16:25:03.104Z,1750091103.104 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-16T16:25:11.996Z,1750091111.996 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-16T16:25:20.616Z,1750091120.616 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-16T16:25:29.364Z,1750091129.364 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-16T16:25:38.208Z,1750091138.208 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-16T16:25:46.964Z,1750091146.964 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-16T16:25:55.628Z,1750091155.628 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-16T16:26:07.760Z,1750091167.760 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-16T16:26:17.008Z,1750091177.008 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-16T16:26:26.740Z,1750091186.740 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-16T16:26:35.480Z,1750091195.480 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-16T16:26:44.200Z,1750091204.200 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-16T16:27:10.952Z,1750091230.952 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-16T16:27:22.708Z,1750091242.708 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-16T16:27:31.480Z,1750091251.480 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-16T16:27:40.220Z,1750091260.220 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-16T16:27:48.936Z,1750091268.936 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-16T16:27:57.633Z,1750091277.633 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-16T16:28:09.606Z,1750091289.606 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-06-16T16:28:18.254Z,1750091298.254 [DataOverHttps](IMPORTANT): SBD MTMSN=20250616T162825 2025-06-16T16:28:26.048Z,1750091306.048 [DataOverHttps](INFO): Received command: restart logs