2023-05-16T00:09:26.453Z,1684195766.453 [Supervisor](DEBUG): Initializing supervisor. 2023-05-16T00:09:26.458Z,1684195766.458 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2023-05-16T00:09:26.459Z,1684195766.459 [SyncHandler](INFO): Protected caller Thread ID is 3093 2023-05-16T00:09:26.459Z,1684195766.459 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2023-05-16T00:09:26.460Z,1684195766.460 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2023-05-16T00:09:26.461Z,1684195766.461 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 3094 2023-05-16T00:09:26.465Z,1684195766.465 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2023-05-16T00:09:26.482Z,1684195766.482 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2023-05-16T00:09:26.483Z,1684195766.483 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2023-05-16T00:09:26.483Z,1684195766.483 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 3095 2023-05-16T00:09:26.488Z,1684195766.488 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2023-05-16T00:09:26.489Z,1684195766.489 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2023-05-16T00:09:26.489Z,1684195766.489 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 3096 2023-05-16T00:09:26.491Z,1684195766.491 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2023-05-16T00:09:26.492Z,1684195766.492 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2023-05-16T00:09:26.492Z,1684195766.492 [logger ThreadHandler](INFO): Protected caller Thread ID is 3097 2023-05-16T00:09:26.496Z,1684195766.496 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2023-05-16T00:09:26.496Z,1684195766.496 [Supervisor](INFO): Looking for Config files in directory: Config/ 2023-05-16T00:09:26.498Z,1684195766.498 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2023-05-16T00:09:26.755Z,1684195766.755 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2023-05-16T00:09:26.755Z,1684195766.755 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2023-05-16T00:09:26.835Z,1684195766.835 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2023-05-16T00:09:27.287Z,1684195767.287 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2023-05-16T00:09:27.288Z,1684195767.288 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2023-05-16T00:09:27.671Z,1684195767.671 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2023-05-16T00:09:27.672Z,1684195767.672 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2023-05-16T00:09:27.768Z,1684195767.768 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2023-05-16T00:09:27.768Z,1684195767.768 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2023-05-16T00:09:28.140Z,1684195768.140 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2023-05-16T00:09:28.140Z,1684195768.140 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2023-05-16T00:09:28.341Z,1684195768.341 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2023-05-16T00:09:28.341Z,1684195768.341 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2023-05-16T00:09:28.828Z,1684195768.828 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2023-05-16T00:09:28.829Z,1684195768.829 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2023-05-16T00:09:28.961Z,1684195768.961 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2023-05-16T00:09:28.962Z,1684195768.962 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2023-05-16T00:09:29.040Z,1684195769.040 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2023-05-16T00:09:29.744Z,1684195769.744 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2023-05-16T00:09:29.745Z,1684195769.745 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2023-05-16T00:09:30.101Z,1684195770.101 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2023-05-16T00:09:30.102Z,1684195770.102 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2023-05-16T00:09:30.285Z,1684195770.285 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2023-05-16T00:09:30.285Z,1684195770.285 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2023-05-16T00:09:30.394Z,1684195770.394 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2023-05-16T00:09:30.395Z,1684195770.395 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2023-05-16T00:09:30.610Z,1684195770.610 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2023-05-16T00:09:30.611Z,1684195770.611 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2023-05-16T00:09:30.839Z,1684195770.839 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2023-05-16T00:09:30.841Z,1684195770.841 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2023-05-16T00:09:30.842Z,1684195770.842 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg 2023-05-16T00:09:30.932Z,1684195770.932 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2023-05-16T00:09:31.016Z,1684195771.016 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2023-05-16T00:09:31.115Z,1684195771.115 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg 2023-05-16T00:09:31.227Z,1684195771.227 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2023-05-16T00:09:31.533Z,1684195771.533 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2023-05-16T00:09:31.685Z,1684195771.685 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2023-05-16T00:09:31.942Z,1684195771.942 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg 2023-05-16T00:09:32.019Z,1684195772.019 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2023-05-16T00:09:32.163Z,1684195772.163 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2023-05-16T00:09:32.294Z,1684195772.294 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2023-05-16T00:09:32.400Z,1684195772.400 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg 2023-05-16T00:09:32.658Z,1684195772.658 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2023-05-16T00:09:32.659Z,1684195772.659 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/ 2023-05-16T00:09:32.660Z,1684195772.660 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2023-05-16T00:09:32.661Z,1684195772.661 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2023-05-16T00:09:32.711Z,1684195772.711 [VerticalControl](DEBUG): Construct VerticalControl. 2023-05-16T00:09:32.770Z,1684195772.770 [VerticalControl] Loaded 2023-05-16T00:09:32.770Z,1684195772.770 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2023-05-16T00:09:32.773Z,1684195772.773 [HorizontalControl](DEBUG): Construct HorizontalControl. 2023-05-16T00:09:32.813Z,1684195772.813 [HorizontalControl] Loaded 2023-05-16T00:09:32.814Z,1684195772.814 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2023-05-16T00:09:32.816Z,1684195772.816 [SpeedControl](DEBUG): Construct SpeedControl. 2023-05-16T00:09:32.819Z,1684195772.819 [SpeedControl] Loaded 2023-05-16T00:09:32.819Z,1684195772.819 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2023-05-16T00:09:32.822Z,1684195772.822 [LoopControl](DEBUG): Construct LoopControl. 2023-05-16T00:09:32.822Z,1684195772.822 [LoopControl] Loaded 2023-05-16T00:09:32.822Z,1684195772.822 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2023-05-16T00:09:32.823Z,1684195772.823 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2023-05-16T00:09:32.823Z,1684195772.823 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2023-05-16T00:09:32.833Z,1684195772.833 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2023-05-16T00:09:32.834Z,1684195772.834 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2023-05-16T00:09:32.979Z,1684195772.979 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2023-05-16T00:09:32.979Z,1684195772.979 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2023-05-16T00:09:33.106Z,1684195773.106 [BuoyancyServo] Loaded 2023-05-16T00:09:33.106Z,1684195773.106 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2023-05-16T00:09:33.126Z,1684195773.126 [ElevatorServo] Loaded 2023-05-16T00:09:33.127Z,1684195773.127 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2023-05-16T00:09:33.145Z,1684195773.145 [MassServo] Loaded 2023-05-16T00:09:33.146Z,1684195773.146 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2023-05-16T00:09:33.163Z,1684195773.163 [RudderServo] Loaded 2023-05-16T00:09:33.164Z,1684195773.164 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2023-05-16T00:09:33.180Z,1684195773.180 [ThrusterServo] Loaded 2023-05-16T00:09:33.181Z,1684195773.181 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2023-05-16T00:09:33.181Z,1684195773.181 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2023-05-16T00:09:33.182Z,1684195773.182 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2023-05-16T00:09:33.293Z,1684195773.293 [DeadReckonUsingMultipleVelocitySources] Loaded 2023-05-16T00:09:33.293Z,1684195773.293 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2023-05-16T00:09:33.307Z,1684195773.307 [NavChart] Loaded 2023-05-16T00:09:33.307Z,1684195773.307 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2023-05-16T00:09:33.312Z,1684195773.312 [UniversalFixResidualReporter] Loaded 2023-05-16T00:09:33.313Z,1684195773.313 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2023-05-16T00:09:33.313Z,1684195773.313 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2023-05-16T00:09:33.314Z,1684195773.314 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2023-05-16T00:09:33.371Z,1684195773.371 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2023-05-16T00:09:33.372Z,1684195773.372 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2023-05-16T00:09:33.797Z,1684195773.797 [AHRS_M2] Loaded 2023-05-16T00:09:33.797Z,1684195773.797 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2023-05-16T00:09:34.605Z,1684195774.605 [BPC1] Loaded 2023-05-16T00:09:34.605Z,1684195774.605 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2023-05-16T00:09:34.677Z,1684195774.677 [DataOverHttps] Loaded 2023-05-16T00:09:34.677Z,1684195774.677 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2023-05-16T00:09:34.678Z,1684195774.678 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409004E0 2023-05-16T00:09:34.678Z,1684195774.678 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 3183 2023-05-16T00:09:34.699Z,1684195774.699 [Depth_Keller] Loaded 2023-05-16T00:09:34.699Z,1684195774.699 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2023-05-16T00:09:34.704Z,1684195774.704 [DropWeight] Loaded 2023-05-16T00:09:34.705Z,1684195774.705 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2023-05-16T00:09:34.766Z,1684195774.766 [NAL9602] Loaded 2023-05-16T00:09:34.766Z,1684195774.766 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2023-05-16T00:09:34.795Z,1684195774.795 [Onboard] Loaded 2023-05-16T00:09:34.795Z,1684195774.795 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2023-05-16T00:09:34.796Z,1684195774.796 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409304E0 2023-05-16T00:09:34.797Z,1684195774.797 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 3184 2023-05-16T00:09:34.810Z,1684195774.810 [Power24vConverter] Loaded 2023-05-16T00:09:34.810Z,1684195774.810 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2023-05-16T00:09:34.823Z,1684195774.823 [Radio_Surface] Loaded 2023-05-16T00:09:34.823Z,1684195774.823 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2023-05-16T00:09:34.824Z,1684195774.824 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409604E0 2023-05-16T00:09:34.825Z,1684195774.825 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 3185 2023-05-16T00:09:34.836Z,1684195774.836 [Sonardyne_Nano](CRITICAL): No configuration setting for: Sonardyne_Nano.chargeMax 2023-05-16T00:09:34.838Z,1684195774.838 [Sonardyne_Nano](CRITICAL): No configuration setting for: Sonardyne_Nano.chargeMin 2023-05-16T00:09:34.839Z,1684195774.839 [Sonardyne_Nano] Loaded 2023-05-16T00:09:34.839Z,1684195774.839 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2023-05-16T00:09:34.860Z,1684195774.860 [AMEcho] Loaded 2023-05-16T00:09:34.860Z,1684195774.860 [ComponentRegistry](DEBUG): SyncComponent "AMEcho" handled in the control thread. 2023-05-16T00:09:34.951Z,1684195774.951 [DAT] Loaded 2023-05-16T00:09:34.952Z,1684195774.952 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2023-05-16T00:09:34.953Z,1684195774.953 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409904E0 2023-05-16T00:09:34.953Z,1684195774.953 [DAT ThreadHandler](INFO): Protected caller Thread ID is 3186 2023-05-16T00:09:34.954Z,1684195774.954 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2023-05-16T00:09:34.954Z,1684195774.954 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2023-05-16T00:09:35.003Z,1684195775.003 [DepthRateCalculator] Loaded 2023-05-16T00:09:35.004Z,1684195775.004 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2023-05-16T00:09:35.009Z,1684195775.009 [PitchRateCalculator] Loaded 2023-05-16T00:09:35.009Z,1684195775.009 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2023-05-16T00:09:35.018Z,1684195775.018 [SpeedCalculator] Loaded 2023-05-16T00:09:35.018Z,1684195775.018 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2023-05-16T00:09:35.022Z,1684195775.022 [YawRateCalculator] Loaded 2023-05-16T00:09:35.022Z,1684195775.022 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2023-05-16T00:09:35.041Z,1684195775.041 [ElevatorOffsetCalculator] Loaded 2023-05-16T00:09:35.041Z,1684195775.041 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2023-05-16T00:09:35.042Z,1684195775.042 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2023-05-16T00:09:35.042Z,1684195775.042 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2023-05-16T00:09:35.176Z,1684195775.176 [CANONSampler] Loaded 2023-05-16T00:09:35.176Z,1684195775.176 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread. 2023-05-16T00:09:35.236Z,1684195775.236 [CTD_Seabird] Loaded 2023-05-16T00:09:35.236Z,1684195775.236 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2023-05-16T00:09:35.237Z,1684195775.237 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A494E0 2023-05-16T00:09:35.238Z,1684195775.238 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 3187 2023-05-16T00:09:35.257Z,1684195775.257 [PAR_Licor] Loaded 2023-05-16T00:09:35.258Z,1684195775.258 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2023-05-16T00:09:35.288Z,1684195775.288 [WetLabsBB2FL] Loaded 2023-05-16T00:09:35.288Z,1684195775.288 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2023-05-16T00:09:35.289Z,1684195775.289 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A794E0 2023-05-16T00:09:35.289Z,1684195775.289 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 3188 2023-05-16T00:09:35.290Z,1684195775.290 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2023-05-16T00:09:35.291Z,1684195775.291 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2023-05-16T00:09:35.318Z,1684195775.318 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2023-05-16T00:09:35.318Z,1684195775.318 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2023-05-16T00:09:35.627Z,1684195775.627 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2023-05-16T00:09:35.627Z,1684195775.627 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2023-05-16T00:09:35.768Z,1684195775.768 [SBIT](DEBUG): Construct Startup Built In Test. 2023-05-16T00:09:35.779Z,1684195775.779 [SBIT] Loaded 2023-05-16T00:09:35.779Z,1684195775.779 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2023-05-16T00:09:35.782Z,1684195775.782 [IBIT](DEBUG): Construct Initiated Built In Test. 2023-05-16T00:09:35.794Z,1684195775.794 [IBIT] Loaded 2023-05-16T00:09:35.794Z,1684195775.794 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2023-05-16T00:09:35.800Z,1684195775.800 [CBIT](DEBUG): Construct Continuous Built In Test. 2023-05-16T00:09:35.903Z,1684195775.903 [CBIT] Loaded 2023-05-16T00:09:35.904Z,1684195775.904 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2023-05-16T00:09:35.904Z,1684195775.904 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2023-05-16T00:09:35.905Z,1684195775.905 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2023-05-16T00:09:36.002Z,1684195776.002 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2023-05-16T00:09:36.008Z,1684195776.008 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2023-05-16T00:09:36.011Z,1684195776.011 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2023-05-16T00:09:36.022Z,1684195776.022 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2023-05-16T00:09:36.023Z,1684195776.023 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BB14E0 2023-05-16T00:09:36.023Z,1684195776.023 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 3189 2023-05-16T00:09:36.028Z,1684195776.028 [Supervisor](INFO): Main Thread ID is 3092 2023-05-16T00:09:36.028Z,1684195776.028 [Supervisor](DEBUG): Running supervisor. 2023-05-16T00:09:36.029Z,1684195776.029 [CommandExec ThreadHandler](INFO): Handler Thread ID is 3190 2023-05-16T00:09:36.029Z,1684195776.029 [CommandExec](INFO): Initializing the command executive. 2023-05-16T00:09:36.031Z,1684195776.031 [CommandLine ThreadHandler](INFO): Handler Thread ID is 3191 2023-05-16T00:09:36.033Z,1684195776.033 [controlThread ThreadHandler](INFO): Handler Thread ID is 3192 2023-05-16T00:09:36.034Z,1684195776.034 [controlThread](DEBUG): Initializing ControlThread 2023-05-16T00:09:36.034Z,1684195776.034 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2023-05-16T00:09:36.036Z,1684195776.036 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2023-05-16T00:09:36.037Z,1684195776.037 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2023-05-16T00:09:36.037Z,1684195776.037 [LoopControl](DEBUG): Initialize LoopControlComponent. 2023-05-16T00:09:36.039Z,1684195776.039 [NavChart](DEBUG): Initialize NavChart Navigation. 2023-05-16T00:09:36.040Z,1684195776.040 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2023-05-16T00:09:36.044Z,1684195776.044 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2023-05-16T00:09:36.044Z,1684195776.044 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2023-05-16T00:09:36.045Z,1684195776.045 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2023-05-16T00:09:36.045Z,1684195776.045 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2023-05-16T00:09:36.045Z,1684195776.045 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2023-05-16T00:09:36.050Z,1684195776.050 [SBIT](INFO): Initialize SBIT Component. 2023-05-16T00:09:36.050Z,1684195776.050 [SBIT](IMPORTANT): git: 2023-03-30-8-g9f4e94ede 2023-05-16T00:09:36.050Z,1684195776.050 [SBIT](INFO): git hash: 9f4e94ede96dd26b46ac6117d56fbd84feccae72 2023-05-16T00:09:36.050Z,1684195776.050 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2023-05-16T00:09:36.052Z,1684195776.052 [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 2023-05-16T00:09:36.053Z,1684195776.053 [SBIT](INFO): Beginning SBIT in 41.000000 seconds. 2023-05-16T00:09:36.054Z,1684195776.054 [IBIT](INFO): Initialize IBIT Component. 2023-05-16T00:09:36.054Z,1684195776.054 [CBIT](DEBUG): Initialize CBIT Component. 2023-05-16T00:09:36.057Z,1684195776.057 [logger ThreadHandler](INFO): Handler Thread ID is 3193 2023-05-16T00:09:36.065Z,1684195776.065 [CBIT](DEBUG): Initialized mux pins. 2023-05-16T00:09:36.065Z,1684195776.065 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2023-05-16T00:09:36.066Z,1684195776.066 [CBIT](DEBUG): Initializing the watchdog timer. 2023-05-16T00:09:36.074Z,1684195776.074 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 3194 2023-05-16T00:09:36.075Z,1684195776.075 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2023-05-16T00:09:36.086Z,1684195776.086 [Onboard ThreadHandler](INFO): Handler Thread ID is 3195 2023-05-16T00:09:36.090Z,1684195776.090 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2023-05-16T00:09:36.090Z,1684195776.090 [CBIT](DEBUG): Initializing heartbeat. 2023-05-16T00:09:36.106Z,1684195776.106 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 3196 2023-05-16T00:09:36.118Z,1684195776.118 [DAT ThreadHandler](INFO): Handler Thread ID is 3197 2023-05-16T00:09:36.118Z,1684195776.118 [DAT](INFO): Powering up 2023-05-16T00:09:36.119Z,1684195776.119 [DAT](DEBUG): Initializing DAT. 2023-05-16T00:09:36.122Z,1684195776.122 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 3199 2023-05-16T00:09:36.123Z,1684195776.123 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2023-05-16T00:09:36.126Z,1684195776.126 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 3201 2023-05-16T00:09:36.130Z,1684195776.130 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 3203 2023-05-16T00:09:36.131Z,1684195776.131 [WetLabsBB2FL](INFO): Powering up 2023-05-16T00:09:36.139Z,1684195776.139 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2023-05-16T00:09:36.140Z,1684195776.140 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2023-05-16T00:09:36.140Z,1684195776.140 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2023-05-16T00:09:36.140Z,1684195776.140 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2023-05-16T00:09:36.140Z,1684195776.140 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2023-05-16T00:09:36.140Z,1684195776.140 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2023-05-16T00:09:36.141Z,1684195776.141 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2023-05-16T00:09:36.141Z,1684195776.141 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2023-05-16T00:09:36.161Z,1684195776.161 [CBIT](DEBUG): Deactivating GF circuits. 2023-05-16T00:09:36.161Z,1684195776.161 [CBIT](DEBUG): Deactivating emergency mode. 2023-05-16T00:09:36.201Z,1684195776.201 [CBIT](DEBUG): Backplane powered. 2023-05-16T00:09:36.202Z,1684195776.202 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml 2023-05-16T00:09:36.221Z,1684195776.221 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2023-05-16T00:09:36.296Z,1684195776.296 [MissionManager](DEBUG): 2023-05-16T00:09:36.337Z,1684195776.337 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml 2023-05-16T00:09:36.517Z,1684195776.517 [Radio_Surface](INFO): Powering up 2023-05-16T00:09:36.522Z,1684195776.522 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2023-05-16T00:09:36.523Z,1684195776.523 [Default:A.Wait](DEBUG): Construct Wait. 2023-05-16T00:09:36.570Z,1684195776.570 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2023-05-16T00:09:36.619Z,1684195776.619 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2023-05-16T00:09:36.626Z,1684195776.626 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2023-05-16T00:09:36.709Z,1684195776.709 [Default:E.Execute](DEBUG): Construct Execute. 2023-05-16T00:09:36.712Z,1684195776.712 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2023-05-16T00:09:36.717Z,1684195776.717 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,AMEcho,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2023-05-16T00:09:36.729Z,1684195776.729 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2023-05-16T00:09:37.294Z,1684195777.294 [Power24vConverter](INFO): Powering up. 2023-05-16T00:09:37.295Z,1684195777.295 [Sonardyne_Nano](INFO): Initializing. 2023-05-16T00:09:37.314Z,1684195777.314 [AMEcho](INFO): Powering up. 2023-05-16T00:09:37.333Z,1684195777.333 [CANONSampler](INFO): Powering down 2023-05-16T00:09:37.384Z,1684195777.384 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2023-05-16T00:09:37.390Z,1684195777.390 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2023-05-16T00:09:37.391Z,1684195777.391 [ElevatorServo](DEBUG): Initializing EZServoServo. 2023-05-16T00:09:37.398Z,1684195777.398 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2023-05-16T00:09:37.398Z,1684195777.398 [MassServo](DEBUG): Initializing EZServoServo. 2023-05-16T00:09:37.406Z,1684195777.406 [MassServo](DEBUG): Initializing MassServo. 2023-05-16T00:09:37.406Z,1684195777.406 [RudderServo](DEBUG): Initializing EZServoServo. 2023-05-16T00:09:37.414Z,1684195777.414 [RudderServo](DEBUG): Initializing RudderServo. 2023-05-16T00:09:37.414Z,1684195777.414 [ThrusterServo](DEBUG): Initializing EZServoServo. 2023-05-16T00:09:37.422Z,1684195777.422 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2023-05-16T00:09:37.424Z,1684195777.424 [CommandExec](FAULT): Scheduling is paused 2023-05-16T00:09:37.424Z,1684195777.424 [CBIT](INFO): Critical error at 20230516T000934 2023-05-16T00:09:37.424Z,1684195777.424 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2023-05-16T00:09:37.618Z,1684195777.618 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2023-05-16T00:09:37.618Z,1684195777.618 [DropWeight] Hardware Fault, FailCount= 1 2023-05-16T00:09:37.619Z,1684195777.619 [DropWeight](ERROR): Hardware Fault 2023-05-16T00:09:37.620Z,1684195777.620 [Sonardyne_Nano](INFO): Starting 2023-05-16T00:09:37.793Z,1684195777.793 [AMEcho](INFO): Powering down. 2023-05-16T00:09:37.835Z,1684195777.835 [CBIT](INFO): Critical error at 20230516T000937 2023-05-16T00:09:37.854Z,1684195777.854 [CBIT](ERROR): Hardware Fault in component: DropWeight 2023-05-16T00:09:37.854Z,1684195777.854 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2023-05-16T00:09:37.903Z,1684195777.903 [Sonardyne_Nano](INFO): Starting 2023-05-16T00:09:37.967Z,1684195777.967 [CBIT](INFO): Critical error at 20230516T000937 2023-05-16T00:09:38.267Z,1684195778.267 [Sonardyne_Nano](INFO): Starting 2023-05-16T00:09:38.268Z,1684195778.268 [AMEcho](IMPORTANT): Setting 'depthThreshold' to 20.000000 2023-05-16T00:09:38.675Z,1684195778.675 [Sonardyne_Nano](INFO): Starting 2023-05-16T00:09:38.741Z,1684195778.741 [WetLabsBB2FL](INFO): Powering down 2023-05-16T00:09:39.075Z,1684195779.075 [Sonardyne_Nano](INFO): Starting 2023-05-16T00:09:39.479Z,1684195779.479 [Sonardyne_Nano](INFO): Command indicated, sending FS 2023-05-16T00:09:39.885Z,1684195779.885 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:09:39.886Z,1684195779.886 [Sonardyne_Nano](INFO): Read UUID: 28070 2023-05-16T00:09:39.886Z,1684195779.886 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:09:40.360Z,1684195780.360 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:09:40.360Z,1684195780.360 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:09:40.361Z,1684195780.361 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:09:40.728Z,1684195780.728 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:09:40.728Z,1684195780.728 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:09:40.729Z,1684195780.729 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:09:41.132Z,1684195781.132 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:09:41.132Z,1684195781.132 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:09:41.132Z,1684195781.132 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:09:41.588Z,1684195781.588 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:09:41.588Z,1684195781.588 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:09:41.589Z,1684195781.589 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:09:41.940Z,1684195781.940 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:09:41.940Z,1684195781.940 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:09:41.941Z,1684195781.941 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:09:42.344Z,1684195782.344 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:09:42.344Z,1684195782.344 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:09:42.344Z,1684195782.344 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:09:42.748Z,1684195782.748 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:09:42.748Z,1684195782.748 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:09:42.749Z,1684195782.749 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:09:43.154Z,1684195783.154 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2023-05-16T00:09:43.160Z,1684195783.160 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:09:43.160Z,1684195783.160 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:09:43.161Z,1684195783.161 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:09:43.560Z,1684195783.560 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:09:43.560Z,1684195783.560 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:09:43.560Z,1684195783.560 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:09:43.960Z,1684195783.960 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:09:43.960Z,1684195783.960 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:09:43.960Z,1684195783.960 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:09:44.364Z,1684195784.364 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:09:44.364Z,1684195784.364 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:09:44.365Z,1684195784.365 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:09:44.772Z,1684195784.772 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:09:44.772Z,1684195784.772 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:09:44.772Z,1684195784.772 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:09:45.161Z,1684195785.161 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:09:45.161Z,1684195785.161 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:09:45.161Z,1684195785.161 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:09:45.568Z,1684195785.568 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:09:45.569Z,1684195785.569 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:09:45.569Z,1684195785.569 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:09:45.960Z,1684195785.960 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:09:45.961Z,1684195785.961 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:09:45.961Z,1684195785.961 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:09:46.352Z,1684195786.352 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:09:46.352Z,1684195786.352 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:09:46.352Z,1684195786.352 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:09:46.764Z,1684195786.764 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:09:46.764Z,1684195786.764 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:09:46.764Z,1684195786.764 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:09:47.164Z,1684195787.164 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:09:47.164Z,1684195787.164 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:09:47.165Z,1684195787.165 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:09:47.566Z,1684195787.566 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:09:47.566Z,1684195787.566 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:09:47.566Z,1684195787.566 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:09:47.972Z,1684195787.972 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:09:47.972Z,1684195787.972 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:09:47.973Z,1684195787.973 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:09:48.380Z,1684195788.380 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:09:48.380Z,1684195788.380 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:09:48.380Z,1684195788.380 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:09:48.780Z,1684195788.780 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:09:48.780Z,1684195788.780 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:09:48.780Z,1684195788.780 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:09:49.180Z,1684195789.180 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:09:49.180Z,1684195789.180 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:09:49.180Z,1684195789.180 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:09:49.596Z,1684195789.596 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:09:49.596Z,1684195789.596 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:09:49.596Z,1684195789.596 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:09:49.978Z,1684195789.978 [DAT](INFO): DAT read: 2023-05-16T00:09:49.979Z,1684195789.979 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2023-05-16T00:09:50.000Z,1684195790.000 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:09:50.000Z,1684195790.000 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:09:50.001Z,1684195790.001 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:09:50.392Z,1684195790.392 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:09:50.392Z,1684195790.392 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:09:50.392Z,1684195790.392 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:09:50.583Z,1684195790.583 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004243 2023-05-16T00:09:50.796Z,1684195790.796 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:09:50.796Z,1684195790.796 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:09:50.796Z,1684195790.796 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:09:51.200Z,1684195791.200 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:09:51.200Z,1684195791.200 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:09:51.201Z,1684195791.201 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:09:51.604Z,1684195791.604 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:09:51.604Z,1684195791.604 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:09:51.605Z,1684195791.605 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:09:51.742Z,1684195791.742 [DAT](INFO): DAT read: MF Frequency Band 2023-05-16T00:09:51.743Z,1684195791.743 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2023-05-16T00:09:51.744Z,1684195791.744 [DAT](INFO): DAT read: May 16 2023 00:09:45 2023-05-16T00:09:52.008Z,1684195792.008 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:09:52.008Z,1684195792.008 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:09:52.008Z,1684195792.008 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:09:52.412Z,1684195792.412 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:09:52.412Z,1684195792.412 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:09:52.412Z,1684195792.412 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:09:52.816Z,1684195792.816 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:09:52.816Z,1684195792.816 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:09:52.816Z,1684195792.816 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:09:53.002Z,1684195793.002 [DAT](INFO): DAT read: Features enabled [Bearing] 2023-05-16T00:09:53.003Z,1684195793.003 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC 2023-05-16T00:09:53.004Z,1684195793.004 [DAT](INFO): commRate: 1200 2023-05-16T00:09:53.220Z,1684195793.220 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:09:53.220Z,1684195793.220 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:09:53.220Z,1684195793.220 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:09:53.624Z,1684195793.624 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:09:53.624Z,1684195793.624 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:09:53.624Z,1684195793.624 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:09:54.028Z,1684195794.028 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:09:54.028Z,1684195794.028 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:09:54.029Z,1684195794.029 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:09:54.432Z,1684195794.432 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:09:54.432Z,1684195794.432 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:09:54.432Z,1684195794.432 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:09:54.836Z,1684195794.836 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:09:54.836Z,1684195794.836 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:09:54.836Z,1684195794.836 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:09:55.069Z,1684195795.069 [DAT](INFO): entering command mode 2023-05-16T00:09:55.240Z,1684195795.240 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:09:55.240Z,1684195795.240 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:09:55.240Z,1684195795.240 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:09:55.270Z,1684195795.270 [DAT](INFO): DAT read: 2023-05-16T00:09:55.271Z,1684195795.271 [DAT](INFO): DAT read: user:1> 2023-05-16T00:09:55.271Z,1684195795.271 [DAT](INFO): setting verbose to 3 2023-05-16T00:09:55.522Z,1684195795.522 [DAT](INFO): DAT read: user:1> 2023-05-16T00:09:55.523Z,1684195795.523 [DAT](INFO): DAT read: Verbose | 3 2023-05-16T00:09:55.523Z,1684195795.523 [DAT](INFO): set verbose to 3 2023-05-16T00:09:55.524Z,1684195795.524 [DAT](INFO): setting DatVerbose to 27440 2023-05-16T00:09:55.644Z,1684195795.644 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:09:55.644Z,1684195795.644 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:09:55.644Z,1684195795.644 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:09:55.774Z,1684195795.774 [DAT](INFO): DAT read: user:2> 2023-05-16T00:09:55.775Z,1684195795.775 [DAT](INFO): DAT read: DatVerbose | 27440 2023-05-16T00:09:55.775Z,1684195795.775 [DAT](INFO): set DatVerbose to 27440 2023-05-16T00:09:55.776Z,1684195795.776 [DAT](INFO): setting transmit power to 8 2023-05-16T00:09:56.026Z,1684195796.026 [DAT](INFO): DAT read: user:3> 2023-05-16T00:09:56.027Z,1684195796.027 [DAT](INFO): DAT read: TxPower | 8 (Max) 2023-05-16T00:09:56.027Z,1684195796.027 [DAT](INFO): set transmit power to 8 2023-05-16T00:09:56.027Z,1684195796.027 [DAT](INFO): setting local address to 8 2023-05-16T00:09:56.048Z,1684195796.048 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:09:56.048Z,1684195796.048 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:09:56.049Z,1684195796.049 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:09:56.278Z,1684195796.278 [DAT](INFO): DAT read: user:4> 2023-05-16T00:09:56.279Z,1684195796.279 [DAT](INFO): DAT read: LocalAddr | 8 2023-05-16T00:09:56.279Z,1684195796.279 [DAT](INFO): set local address to 8 2023-05-16T00:09:56.280Z,1684195796.280 [DAT](INFO): Setting time to: 0:9:56 And date to:5/16/2023 2023-05-16T00:09:56.464Z,1684195796.464 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:09:56.464Z,1684195796.464 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:09:56.465Z,1684195796.465 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:09:56.530Z,1684195796.530 [DAT](INFO): DAT read: user:5> 2023-05-16T00:09:56.531Z,1684195796.531 [DAT](INFO): DAT read: Tue May 16, 2023 00:09:56 2023-05-16T00:09:56.531Z,1684195796.531 [DAT](INFO): Local DAT time set to Tue May 16, 2023 00:09:56 2023-05-16T00:09:56.856Z,1684195796.856 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:09:56.856Z,1684195796.856 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:09:56.856Z,1684195796.856 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:09:57.260Z,1684195797.260 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:09:57.260Z,1684195797.260 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:09:57.261Z,1684195797.261 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:09:57.664Z,1684195797.664 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:09:57.664Z,1684195797.664 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:09:57.664Z,1684195797.664 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:09:58.080Z,1684195798.080 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:09:58.080Z,1684195798.080 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:09:58.080Z,1684195798.080 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:09:58.472Z,1684195798.472 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:09:58.472Z,1684195798.472 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:09:58.472Z,1684195798.472 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:09:58.876Z,1684195798.876 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:09:58.876Z,1684195798.876 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:09:58.877Z,1684195798.877 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:09:59.280Z,1684195799.280 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:09:59.280Z,1684195799.280 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:09:59.280Z,1684195799.280 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:09:59.684Z,1684195799.684 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:09:59.684Z,1684195799.684 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:09:59.685Z,1684195799.685 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:00.088Z,1684195800.088 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:00.088Z,1684195800.088 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:00.089Z,1684195800.089 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:00.496Z,1684195800.496 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:00.496Z,1684195800.496 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:00.496Z,1684195800.496 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:00.896Z,1684195800.896 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:00.896Z,1684195800.896 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:00.896Z,1684195800.896 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:01.312Z,1684195801.312 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:01.312Z,1684195801.312 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:01.313Z,1684195801.313 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:01.704Z,1684195801.704 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:01.704Z,1684195801.704 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:01.704Z,1684195801.704 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:02.108Z,1684195802.108 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:02.108Z,1684195802.108 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:02.109Z,1684195802.109 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:02.512Z,1684195802.512 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:02.512Z,1684195802.512 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:02.513Z,1684195802.513 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:02.936Z,1684195802.936 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:02.936Z,1684195802.936 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:02.936Z,1684195802.936 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:03.320Z,1684195803.320 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:03.320Z,1684195803.320 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:03.321Z,1684195803.321 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:03.725Z,1684195803.725 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:03.725Z,1684195803.725 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:03.726Z,1684195803.726 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:04.132Z,1684195804.132 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:04.132Z,1684195804.132 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:04.132Z,1684195804.132 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:04.533Z,1684195804.533 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:04.533Z,1684195804.533 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:04.533Z,1684195804.533 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:04.935Z,1684195804.935 [NAL9602](INFO): Powering up NAL9602 2023-05-16T00:10:04.938Z,1684195804.938 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:04.938Z,1684195804.938 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:04.938Z,1684195804.938 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:05.354Z,1684195805.354 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:05.354Z,1684195805.354 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:05.354Z,1684195805.354 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:05.748Z,1684195805.748 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:05.749Z,1684195805.749 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:05.749Z,1684195805.749 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:06.148Z,1684195806.148 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:06.148Z,1684195806.148 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:06.148Z,1684195806.148 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:06.560Z,1684195806.560 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:06.560Z,1684195806.560 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:06.561Z,1684195806.561 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:06.960Z,1684195806.960 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:06.960Z,1684195806.960 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:06.961Z,1684195806.961 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:07.360Z,1684195807.360 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:07.360Z,1684195807.360 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:07.360Z,1684195807.360 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:07.764Z,1684195807.764 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:07.764Z,1684195807.764 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:07.764Z,1684195807.764 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:08.168Z,1684195808.168 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:08.168Z,1684195808.168 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:08.168Z,1684195808.168 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:08.572Z,1684195808.572 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:08.572Z,1684195808.572 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:08.573Z,1684195808.573 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:08.976Z,1684195808.976 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:08.976Z,1684195808.976 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:08.976Z,1684195808.976 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:09.384Z,1684195809.384 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:09.384Z,1684195809.384 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:09.384Z,1684195809.384 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:09.784Z,1684195809.784 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:09.784Z,1684195809.784 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:09.784Z,1684195809.784 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:10.188Z,1684195810.188 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:10.188Z,1684195810.188 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:10.188Z,1684195810.188 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:10.592Z,1684195810.592 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:10.592Z,1684195810.592 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:10.592Z,1684195810.592 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:10.996Z,1684195810.996 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:10.996Z,1684195810.996 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:10.996Z,1684195810.996 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:11.400Z,1684195811.400 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:11.400Z,1684195811.400 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:11.400Z,1684195811.400 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:11.804Z,1684195811.804 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:11.804Z,1684195811.804 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:11.804Z,1684195811.804 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:12.208Z,1684195812.208 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:12.208Z,1684195812.208 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:12.208Z,1684195812.208 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:12.612Z,1684195812.612 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:12.612Z,1684195812.612 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:12.612Z,1684195812.612 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:13.028Z,1684195813.028 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:13.028Z,1684195813.028 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:13.029Z,1684195813.029 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:13.420Z,1684195813.420 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:13.420Z,1684195813.420 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:13.420Z,1684195813.420 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:13.824Z,1684195813.824 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:13.824Z,1684195813.824 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:13.824Z,1684195813.824 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:14.228Z,1684195814.228 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:14.228Z,1684195814.228 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:14.228Z,1684195814.228 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:14.636Z,1684195814.636 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:14.636Z,1684195814.636 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:14.636Z,1684195814.636 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:15.036Z,1684195815.036 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:15.036Z,1684195815.036 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:15.036Z,1684195815.036 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:15.451Z,1684195815.451 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:15.451Z,1684195815.451 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:15.452Z,1684195815.452 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:15.847Z,1684195815.847 [NAL9602](INFO): NAL9602 initialized 2023-05-16T00:10:15.848Z,1684195815.848 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:15.849Z,1684195815.849 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:15.849Z,1684195815.849 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:16.248Z,1684195816.248 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:16.249Z,1684195816.249 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:16.249Z,1684195816.249 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:16.667Z,1684195816.667 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:16.667Z,1684195816.667 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:16.668Z,1684195816.668 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:17.103Z,1684195817.103 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:17.103Z,1684195817.103 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:17.103Z,1684195817.103 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:17.499Z,1684195817.499 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:17.499Z,1684195817.499 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:17.499Z,1684195817.499 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:17.632Z,1684195817.632 [SBIT](IMPORTANT): Beginning Startup BIT 2023-05-16T00:10:17.649Z,1684195817.649 [CBIT](IMPORTANT): Beginning ground fault scan 2023-05-16T00:10:17.894Z,1684195817.894 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:17.894Z,1684195817.894 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:17.895Z,1684195817.895 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:18.305Z,1684195818.305 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:18.305Z,1684195818.305 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:18.305Z,1684195818.305 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:18.693Z,1684195818.693 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:18.694Z,1684195818.694 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:18.694Z,1684195818.694 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:19.119Z,1684195819.119 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:19.119Z,1684195819.119 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:19.120Z,1684195819.120 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:19.530Z,1684195819.530 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:19.530Z,1684195819.530 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:19.531Z,1684195819.531 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:19.935Z,1684195819.935 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:19.935Z,1684195819.935 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:19.936Z,1684195819.936 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:20.321Z,1684195820.321 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:20.321Z,1684195820.321 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:20.321Z,1684195820.321 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:20.728Z,1684195820.728 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:20.729Z,1684195820.729 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:20.729Z,1684195820.729 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:21.166Z,1684195821.166 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:21.166Z,1684195821.166 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:21.167Z,1684195821.167 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:21.612Z,1684195821.612 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:21.612Z,1684195821.612 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:21.612Z,1684195821.612 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:22.071Z,1684195822.071 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:22.072Z,1684195822.072 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:22.072Z,1684195822.072 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:22.430Z,1684195822.430 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:22.430Z,1684195822.430 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:22.430Z,1684195822.430 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:22.805Z,1684195822.805 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:22.805Z,1684195822.805 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:22.805Z,1684195822.805 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:23.208Z,1684195823.208 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:23.208Z,1684195823.208 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:23.209Z,1684195823.209 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:23.627Z,1684195823.627 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:23.627Z,1684195823.627 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:23.627Z,1684195823.627 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:24.043Z,1684195824.043 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:24.043Z,1684195824.043 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:24.043Z,1684195824.043 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:24.445Z,1684195824.445 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:24.445Z,1684195824.445 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:24.487Z,1684195824.487 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:24.840Z,1684195824.840 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:24.841Z,1684195824.841 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:24.841Z,1684195824.841 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:25.273Z,1684195825.273 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:25.274Z,1684195825.274 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:25.274Z,1684195825.274 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:25.691Z,1684195825.691 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:25.691Z,1684195825.691 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:25.691Z,1684195825.691 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:26.179Z,1684195826.179 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:26.179Z,1684195826.179 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:26.179Z,1684195826.179 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:26.530Z,1684195826.530 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:26.530Z,1684195826.530 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:26.531Z,1684195826.531 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:26.916Z,1684195826.916 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:26.917Z,1684195826.917 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:26.917Z,1684195826.917 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:27.337Z,1684195827.337 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:27.337Z,1684195827.337 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:27.338Z,1684195827.338 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:27.753Z,1684195827.753 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:27.753Z,1684195827.753 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:27.753Z,1684195827.753 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:28.140Z,1684195828.140 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:28.140Z,1684195828.140 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:28.141Z,1684195828.141 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:28.558Z,1684195828.558 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:28.558Z,1684195828.558 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:28.558Z,1684195828.558 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:28.683Z,1684195828.683 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan. 2023-05-16T00:10:28.969Z,1684195828.969 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:28.969Z,1684195828.969 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:28.969Z,1684195828.969 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:29.135Z,1684195829.135 [CBIT](IMPORTANT): Beginning ground fault scan 2023-05-16T00:10:29.385Z,1684195829.385 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:29.385Z,1684195829.385 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:29.386Z,1684195829.386 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:29.772Z,1684195829.772 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:29.772Z,1684195829.772 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:29.773Z,1684195829.773 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:30.192Z,1684195830.192 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:30.193Z,1684195830.193 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:30.193Z,1684195830.193 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:30.588Z,1684195830.588 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:30.588Z,1684195830.588 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:30.589Z,1684195830.589 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:30.996Z,1684195830.996 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:30.997Z,1684195830.997 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:30.997Z,1684195830.997 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:31.413Z,1684195831.413 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:31.413Z,1684195831.413 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:31.437Z,1684195831.437 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:31.817Z,1684195831.817 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:31.817Z,1684195831.817 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:31.817Z,1684195831.817 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:32.074Z,1684195832.074 [CommandExec](IMPORTANT): got command quit 2023-05-16T00:10:32.212Z,1684195832.212 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:32.213Z,1684195832.213 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:32.213Z,1684195832.213 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:32.641Z,1684195832.641 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:32.642Z,1684195832.642 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:32.642Z,1684195832.642 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:33.029Z,1684195833.029 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:33.029Z,1684195833.029 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:33.029Z,1684195833.029 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:33.082Z,1684195833.082 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-16T00:10:33.082Z,1684195833.082 [CommandExec](INFO): Uninitializing the command executive. 2023-05-16T00:10:33.082Z,1684195833.082 [CommandExec](INFO): Uninitializing the command scheduler. 2023-05-16T00:10:33.083Z,1684195833.083 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-05-16T00:10:33.189Z,1684195833.189 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2023-05-16T00:10:33.189Z,1684195833.189 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2023-05-16T00:10:33.190Z,1684195833.190 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-05-16T00:10:33.190Z,1684195833.190 [NavChartDb](INFO): Join timeout helper Thread ID is 3221 2023-05-16T00:10:33.445Z,1684195833.445 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:33.445Z,1684195833.445 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:33.453Z,1684195833.453 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:33.844Z,1684195833.844 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:33.844Z,1684195833.844 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:33.845Z,1684195833.845 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:34.263Z,1684195834.263 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:34.263Z,1684195834.263 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:34.263Z,1684195834.263 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:34.457Z,1684195834.457 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-16T00:10:34.462Z,1684195834.462 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-05-16T00:10:34.469Z,1684195834.469 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2023-05-16T00:10:34.470Z,1684195834.470 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-05-16T00:10:34.470Z,1684195834.470 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 3222 2023-05-16T00:10:34.642Z,1684195834.642 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-16T00:10:34.642Z,1684195834.642 [WetLabsBB2FL](INFO): Powering down 2023-05-16T00:10:34.642Z,1684195834.642 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-05-16T00:10:34.657Z,1684195834.657 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2023-05-16T00:10:34.657Z,1684195834.657 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-05-16T00:10:34.658Z,1684195834.658 [CTD_Seabird](INFO): Join timeout helper Thread ID is 3223 2023-05-16T00:10:34.675Z,1684195834.675 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:34.675Z,1684195834.675 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:34.676Z,1684195834.676 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:35.062Z,1684195835.062 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:35.062Z,1684195835.062 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:35.062Z,1684195835.062 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:35.406Z,1684195835.406 [CTD_Seabird](INFO): Powering down 2023-05-16T00:10:35.418Z,1684195835.418 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-16T00:10:35.418Z,1684195835.418 [CTD_Seabird](INFO): Powering down 2023-05-16T00:10:35.437Z,1684195835.437 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-05-16T00:10:35.457Z,1684195835.457 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2023-05-16T00:10:35.458Z,1684195835.458 [DAT ThreadHandler](INFO): Thread cancelled. 2023-05-16T00:10:35.458Z,1684195835.458 [DAT](INFO): Join timeout helper Thread ID is 3224 2023-05-16T00:10:35.508Z,1684195835.508 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:35.509Z,1684195835.509 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:35.509Z,1684195835.509 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:35.690Z,1684195835.690 [DAT](INFO): Powering down 2023-05-16T00:10:35.758Z,1684195835.758 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-16T00:10:35.758Z,1684195835.758 [DAT](INFO): Powering down 2023-05-16T00:10:35.759Z,1684195835.759 [DAT ThreadHandler](INFO): Thread cancelled. 2023-05-16T00:10:35.777Z,1684195835.777 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2023-05-16T00:10:35.778Z,1684195835.778 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-05-16T00:10:35.778Z,1684195835.778 [Radio_Surface](INFO): Join timeout helper Thread ID is 3225 2023-05-16T00:10:35.885Z,1684195835.885 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:35.885Z,1684195835.885 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:35.885Z,1684195835.885 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:36.054Z,1684195836.054 [Radio_Surface](INFO): Powering down 2023-05-16T00:10:36.054Z,1684195836.054 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-16T00:10:36.055Z,1684195836.055 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-05-16T00:10:36.066Z,1684195836.066 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2023-05-16T00:10:36.066Z,1684195836.066 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-05-16T00:10:36.067Z,1684195836.067 [Onboard](INFO): Join timeout helper Thread ID is 3226 2023-05-16T00:10:36.146Z,1684195836.146 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-16T00:10:36.146Z,1684195836.146 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-05-16T00:10:36.158Z,1684195836.158 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2023-05-16T00:10:36.158Z,1684195836.158 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-05-16T00:10:36.159Z,1684195836.159 [DataOverHttps](INFO): Join timeout helper Thread ID is 3227 2023-05-16T00:10:36.273Z,1684195836.273 [Sonardyne_Nano](INFO): Got valid term character 2023-05-16T00:10:36.274Z,1684195836.274 [Sonardyne_Nano](INFO): Received command set request 2023-05-16T00:10:36.274Z,1684195836.274 [Sonardyne_Nano](INFO): Command indicated, sending SC:U 6da6,CHG1 2023-05-16T00:10:36.530Z,1684195836.530 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-16T00:10:36.530Z,1684195836.530 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-05-16T00:10:36.546Z,1684195836.546 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2023-05-16T00:10:36.546Z,1684195836.546 [logger ThreadHandler](INFO): Thread cancelled. 2023-05-16T00:10:36.547Z,1684195836.547 [logger](INFO): Join timeout helper Thread ID is 3228 2023-05-16T00:10:36.550Z,1684195836.550 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-16T00:10:36.550Z,1684195836.550 [logger ThreadHandler](INFO): Thread cancelled. 2023-05-16T00:10:36.566Z,1684195836.566 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2023-05-16T00:10:36.566Z,1684195836.566 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-05-16T00:10:36.567Z,1684195836.567 [CommandLine](INFO): Join timeout helper Thread ID is 3229 2023-05-16T00:10:36.610Z,1684195836.610 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-16T00:10:36.610Z,1684195836.610 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-05-16T00:10:36.626Z,1684195836.626 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2023-05-16T00:10:36.626Z,1684195836.626 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-05-16T00:10:36.627Z,1684195836.627 [CommandExec](INFO): Join timeout helper Thread ID is 3230 2023-05-16T00:10:36.628Z,1684195836.628 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2023-05-16T00:10:36.628Z,1684195836.628 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-05-16T00:10:36.628Z,1684195836.628 [controlThread](INFO): Join timeout helper Thread ID is 3231 2023-05-16T00:10:36.658Z,1684195836.658 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-16T00:10:36.658Z,1684195836.658 [controlThread](DEBUG): Uninitializing ControlThread 2023-05-16T00:10:36.658Z,1684195836.658 [AHRS_M2](INFO): Powering down 2023-05-16T00:10:36.731Z,1684195836.731 [NAL9602](INFO): Powering down 2023-05-16T00:10:36.732Z,1684195836.732 [Sonardyne_Nano](INFO): Uninitializing. 2023-05-16T00:10:36.732Z,1684195836.732 [Sonardyne_Nano](INFO): Powering down 2023-05-16T00:10:36.850Z,1684195836.850 [AMEcho](INFO): Powering down. 2023-05-16T00:10:36.852Z,1684195836.852 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2023-05-16T00:10:36.852Z,1684195836.852 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2023-05-16T00:10:36.852Z,1684195836.852 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2023-05-16T00:10:36.853Z,1684195836.853 [MissionManager](INFO): Uninitializing Mission Default 2023-05-16T00:10:36.856Z,1684195836.856 [MissionManager](INFO): Uninitializing Mission Startup 2023-05-16T00:10:36.857Z,1684195836.857 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2023-05-16T00:10:36.858Z,1684195836.858 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2023-05-16T00:10:36.858Z,1684195836.858 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2023-05-16T00:10:36.858Z,1684195836.858 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2023-05-16T00:10:36.859Z,1684195836.859 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2023-05-16T00:10:36.859Z,1684195836.859 [BuoyancyServo](INFO): Powering down 2023-05-16T00:10:36.874Z,1684195836.874 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2023-05-16T00:10:36.874Z,1684195836.874 [ElevatorServo](INFO): Powering down 2023-05-16T00:10:36.875Z,1684195836.875 [MassServo](DEBUG): Uninitialize Mass Servo. 2023-05-16T00:10:36.875Z,1684195836.875 [MassServo](INFO): Powering down 2023-05-16T00:10:36.876Z,1684195836.876 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2023-05-16T00:10:36.876Z,1684195836.876 [RudderServo](INFO): Powering down 2023-05-16T00:10:36.877Z,1684195836.877 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2023-05-16T00:10:36.877Z,1684195836.877 [ThrusterServo](INFO): Powering down 2023-05-16T00:10:36.878Z,1684195836.878 [SBIT](DEBUG): Uninitialize SBIT Component. 2023-05-16T00:10:36.878Z,1684195836.878 [IBIT](DEBUG): Uninitialize IBIT Component. 2023-05-16T00:10:36.878Z,1684195836.878 [CBIT](DEBUG): Uninitialize CBIT Component. 2023-05-16T00:10:36.878Z,1684195836.878 [CBIT](DEBUG): Powering off loads. 2023-05-16T00:10:36.889Z,1684195836.889 [CBIT](DEBUG): Disabling WDT. 2023-05-16T00:10:36.901Z,1684195836.901 [CBIT](DEBUG): Opening all GF detection circuits. 2023-05-16T00:10:36.902Z,1684195836.902 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-05-16T00:10:37.300Z,1684195837.300 [DAT ThreadHandler](INFO): Thread cancelled. 2023-05-16T00:10:37.306Z,1684195837.306 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-05-16T00:10:37.308Z,1684195837.308 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-05-16T00:10:37.314Z,1684195837.314 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-05-16T00:10:37.423Z,1684195837.423 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-05-16T00:10:37.428Z,1684195837.428 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-05-16T00:10:37.497Z,1684195837.497 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-05-16T00:10:37.585Z,1684195837.585 [logger ThreadHandler](INFO): Thread cancelled.