2023-05-23T00:06:57.308Z,1684800417.308 [Supervisor](DEBUG): Initializing supervisor.
2023-05-23T00:06:57.313Z,1684800417.313 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-05-23T00:06:57.314Z,1684800417.314 [SyncHandler](INFO): Protected caller Thread ID is 1797
2023-05-23T00:06:57.314Z,1684800417.314 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-05-23T00:06:57.315Z,1684800417.315 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-05-23T00:06:57.315Z,1684800417.315 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1798
2023-05-23T00:06:57.319Z,1684800417.319 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-05-23T00:06:57.337Z,1684800417.337 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-05-23T00:06:57.338Z,1684800417.338 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-05-23T00:06:57.338Z,1684800417.338 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1799
2023-05-23T00:06:57.342Z,1684800417.342 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-05-23T00:06:57.343Z,1684800417.343 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-05-23T00:06:57.343Z,1684800417.343 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1800
2023-05-23T00:06:57.345Z,1684800417.345 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-05-23T00:06:57.346Z,1684800417.346 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-05-23T00:06:57.347Z,1684800417.347 [logger ThreadHandler](INFO): Protected caller Thread ID is 1801
2023-05-23T00:06:57.350Z,1684800417.350 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-05-23T00:06:57.351Z,1684800417.351 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-05-23T00:06:57.352Z,1684800417.352 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-05-23T00:06:57.447Z,1684800417.447 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-05-23T00:06:57.448Z,1684800417.448 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-05-23T00:06:57.659Z,1684800417.659 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-05-23T00:06:57.659Z,1684800417.659 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-05-23T00:06:57.906Z,1684800417.906 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-05-23T00:06:57.906Z,1684800417.906 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-05-23T00:06:58.013Z,1684800418.013 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-05-23T00:06:58.013Z,1684800418.013 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-05-23T00:06:58.729Z,1684800418.729 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-05-23T00:06:58.730Z,1684800418.730 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-05-23T00:06:59.194Z,1684800419.194 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-05-23T00:06:59.194Z,1684800419.194 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-05-23T00:06:59.664Z,1684800419.664 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-05-23T00:06:59.664Z,1684800419.664 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-05-23T00:07:00.069Z,1684800420.069 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-05-23T00:07:00.070Z,1684800420.070 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-05-23T00:07:00.396Z,1684800420.396 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-05-23T00:07:00.396Z,1684800420.396 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-05-23T00:07:00.753Z,1684800420.753 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-05-23T00:07:00.754Z,1684800420.754 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-05-23T00:07:00.968Z,1684800420.968 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-05-23T00:07:00.968Z,1684800420.968 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-05-23T00:07:01.108Z,1684800421.108 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-05-23T00:07:01.109Z,1684800421.109 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-05-23T00:07:01.190Z,1684800421.190 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-05-23T00:07:01.268Z,1684800421.268 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-05-23T00:07:01.447Z,1684800421.447 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-05-23T00:07:01.448Z,1684800421.448 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-05-23T00:07:01.662Z,1684800421.662 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-05-23T00:07:01.664Z,1684800421.664 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2023-05-23T00:07:01.665Z,1684800421.665 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2023-05-23T00:07:01.747Z,1684800421.747 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2023-05-23T00:07:02.005Z,1684800422.005 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-05-23T00:07:02.006Z,1684800422.006 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2023-05-23T00:07:02.103Z,1684800422.103 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2023-05-23T00:07:02.258Z,1684800422.258 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2023-05-23T00:07:02.431Z,1684800422.431 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2023-05-23T00:07:02.515Z,1684800422.515 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2023-05-23T00:07:02.623Z,1684800422.623 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2023-05-23T00:07:02.726Z,1684800422.726 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2023-05-23T00:07:02.878Z,1684800422.878 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2023-05-23T00:07:02.972Z,1684800422.972 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2023-05-23T00:07:03.070Z,1684800423.070 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-05-23T00:07:03.096Z,1684800423.096 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-05-23T00:07:03.242Z,1684800423.242 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-05-23T00:07:03.243Z,1684800423.243 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-05-23T00:07:03.398Z,1684800423.398 [BuoyancyServo] Loaded
2023-05-23T00:07:03.398Z,1684800423.398 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-05-23T00:07:03.418Z,1684800423.418 [ElevatorServo] Loaded
2023-05-23T00:07:03.419Z,1684800423.419 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-05-23T00:07:03.439Z,1684800423.439 [MassServo] Loaded
2023-05-23T00:07:03.439Z,1684800423.439 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-05-23T00:07:03.458Z,1684800423.458 [RudderServo] Loaded
2023-05-23T00:07:03.458Z,1684800423.458 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-05-23T00:07:03.474Z,1684800423.474 [ThrusterHE] Loaded
2023-05-23T00:07:03.474Z,1684800423.474 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2023-05-23T00:07:03.474Z,1684800423.474 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-05-23T00:07:03.475Z,1684800423.475 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-05-23T00:07:03.615Z,1684800423.615 [SBIT](DEBUG): Construct Startup Built In Test.
2023-05-23T00:07:03.629Z,1684800423.629 [SBIT] Loaded
2023-05-23T00:07:03.630Z,1684800423.630 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-05-23T00:07:03.632Z,1684800423.632 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-05-23T00:07:03.653Z,1684800423.653 [IBIT] Loaded
2023-05-23T00:07:03.653Z,1684800423.653 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-05-23T00:07:03.659Z,1684800423.659 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-05-23T00:07:03.872Z,1684800423.872 [CBIT] Loaded
2023-05-23T00:07:03.872Z,1684800423.872 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-05-23T00:07:03.873Z,1684800423.873 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-05-23T00:07:03.873Z,1684800423.873 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-05-23T00:07:04.147Z,1684800424.147 [CTD_Seabird] Loaded
2023-05-23T00:07:04.147Z,1684800424.147 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-05-23T00:07:04.149Z,1684800424.149 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406644E0
2023-05-23T00:07:04.149Z,1684800424.149 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1883
2023-05-23T00:07:04.267Z,1684800424.267 [ESPComponent] Loaded
2023-05-23T00:07:04.267Z,1684800424.267 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2023-05-23T00:07:04.350Z,1684800424.350 [PAR_Licor] Loaded
2023-05-23T00:07:04.350Z,1684800424.350 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-05-23T00:07:04.397Z,1684800424.397 [WetLabsBB2FL] Loaded
2023-05-23T00:07:04.397Z,1684800424.397 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-05-23T00:07:04.409Z,1684800424.409 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406944E0
2023-05-23T00:07:04.409Z,1684800424.409 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1884
2023-05-23T00:07:04.410Z,1684800424.410 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-05-23T00:07:04.410Z,1684800424.410 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-05-23T00:07:04.553Z,1684800424.553 [DepthRateCalculator] Loaded
2023-05-23T00:07:04.554Z,1684800424.554 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-05-23T00:07:04.559Z,1684800424.559 [PitchRateCalculator] Loaded
2023-05-23T00:07:04.559Z,1684800424.559 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-05-23T00:07:04.580Z,1684800424.580 [SpeedCalculator] Loaded
2023-05-23T00:07:04.580Z,1684800424.580 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-05-23T00:07:04.584Z,1684800424.584 [YawRateCalculator] Loaded
2023-05-23T00:07:04.585Z,1684800424.585 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-05-23T00:07:04.603Z,1684800424.603 [ElevatorOffsetCalculator] Loaded
2023-05-23T00:07:04.604Z,1684800424.604 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-05-23T00:07:04.604Z,1684800424.604 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-05-23T00:07:04.605Z,1684800424.605 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-05-23T00:07:04.741Z,1684800424.741 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-05-23T00:07:04.741Z,1684800424.741 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-05-23T00:07:04.815Z,1684800424.815 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-05-23T00:07:04.815Z,1684800424.815 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-05-23T00:07:04.924Z,1684800424.924 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-05-23T00:07:04.924Z,1684800424.924 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-05-23T00:07:04.938Z,1684800424.938 [NavChart] Loaded
2023-05-23T00:07:04.938Z,1684800424.938 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-05-23T00:07:04.943Z,1684800424.943 [UniversalFixResidualReporter] Loaded
2023-05-23T00:07:04.944Z,1684800424.944 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-05-23T00:07:04.944Z,1684800424.944 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-05-23T00:07:04.944Z,1684800424.944 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-05-23T00:07:05.382Z,1684800425.382 [AHRS_M2] Loaded
2023-05-23T00:07:05.382Z,1684800425.382 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-05-23T00:07:05.416Z,1684800425.416 [BackseatComponent] Loaded
2023-05-23T00:07:05.416Z,1684800425.416 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2023-05-23T00:07:05.417Z,1684800425.417 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 40A374E0
2023-05-23T00:07:05.418Z,1684800425.418 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1885
2023-05-23T00:07:05.420Z,1684800425.420 [LcmUniversalReporter] Loaded
2023-05-23T00:07:05.421Z,1684800425.421 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2023-05-23T00:07:06.148Z,1684800426.148 [BPC1] Loaded
2023-05-23T00:07:06.148Z,1684800426.148 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-05-23T00:07:06.237Z,1684800426.237 [DAT] Loaded
2023-05-23T00:07:06.237Z,1684800426.237 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2023-05-23T00:07:06.238Z,1684800426.238 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40A674E0
2023-05-23T00:07:06.238Z,1684800426.238 [DAT ThreadHandler](INFO): Protected caller Thread ID is 1886
2023-05-23T00:07:06.310Z,1684800426.310 [DataOverHttps] Loaded
2023-05-23T00:07:06.310Z,1684800426.310 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-05-23T00:07:06.311Z,1684800426.311 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40A974E0
2023-05-23T00:07:06.311Z,1684800426.311 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1887
2023-05-23T00:07:06.332Z,1684800426.332 [Depth_Keller] Loaded
2023-05-23T00:07:06.332Z,1684800426.332 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-05-23T00:07:06.337Z,1684800426.337 [DropWeight] Loaded
2023-05-23T00:07:06.337Z,1684800426.337 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2023-05-23T00:07:06.398Z,1684800426.398 [NAL9602] Loaded
2023-05-23T00:07:06.398Z,1684800426.398 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-05-23T00:07:06.423Z,1684800426.423 [Onboard] Loaded
2023-05-23T00:07:06.423Z,1684800426.423 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-05-23T00:07:06.424Z,1684800426.424 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40AC74E0
2023-05-23T00:07:06.424Z,1684800426.424 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1888
2023-05-23T00:07:06.436Z,1684800426.436 [Power24vConverter] Loaded
2023-05-23T00:07:06.437Z,1684800426.437 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-05-23T00:07:06.450Z,1684800426.450 [Radio_Surface] Loaded
2023-05-23T00:07:06.450Z,1684800426.450 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-05-23T00:07:06.451Z,1684800426.451 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40AF74E0
2023-05-23T00:07:06.451Z,1684800426.451 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1889
2023-05-23T00:07:06.453Z,1684800426.453 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-05-23T00:07:06.453Z,1684800426.453 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-05-23T00:07:06.523Z,1684800426.523 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-05-23T00:07:06.524Z,1684800426.524 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-05-23T00:07:06.574Z,1684800426.574 [VerticalControl](DEBUG): Construct VerticalControl.
2023-05-23T00:07:06.624Z,1684800426.624 [VerticalControl] Loaded
2023-05-23T00:07:06.624Z,1684800426.624 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-05-23T00:07:06.627Z,1684800426.627 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-05-23T00:07:06.664Z,1684800426.664 [HorizontalControl] Loaded
2023-05-23T00:07:06.664Z,1684800426.664 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-05-23T00:07:06.666Z,1684800426.666 [SpeedControl](DEBUG): Construct SpeedControl.
2023-05-23T00:07:06.668Z,1684800426.668 [SpeedControl] Loaded
2023-05-23T00:07:06.668Z,1684800426.668 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-05-23T00:07:06.671Z,1684800426.671 [LoopControl](DEBUG): Construct LoopControl.
2023-05-23T00:07:06.671Z,1684800426.671 [LoopControl] Loaded
2023-05-23T00:07:06.672Z,1684800426.672 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-05-23T00:07:06.672Z,1684800426.672 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-05-23T00:07:06.673Z,1684800426.673 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-05-23T00:07:06.683Z,1684800426.683 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-05-23T00:07:06.683Z,1684800426.683 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-05-23T00:07:06.986Z,1684800426.986 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-05-23T00:07:06.992Z,1684800426.992 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-05-23T00:07:06.995Z,1684800426.995 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-05-23T00:07:07.005Z,1684800427.005 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-05-23T00:07:07.006Z,1684800427.006 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BFD4E0
2023-05-23T00:07:07.007Z,1684800427.007 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1890
2023-05-23T00:07:07.011Z,1684800427.011 [Supervisor](INFO): Main Thread ID is 822
2023-05-23T00:07:07.011Z,1684800427.011 [Supervisor](DEBUG): Running supervisor.
2023-05-23T00:07:07.012Z,1684800427.012 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1891
2023-05-23T00:07:07.012Z,1684800427.012 [CommandExec](INFO): Initializing the command executive.
2023-05-23T00:07:07.014Z,1684800427.014 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1892
2023-05-23T00:07:07.016Z,1684800427.016 [controlThread ThreadHandler](INFO): Handler Thread ID is 1893
2023-05-23T00:07:07.016Z,1684800427.016 [controlThread](DEBUG): Initializing ControlThread
2023-05-23T00:07:07.018Z,1684800427.018 [SBIT](INFO): Initialize SBIT Component.
2023-05-23T00:07:07.019Z,1684800427.019 [SBIT](IMPORTANT): git: 2023-05-17
2023-05-23T00:07:07.019Z,1684800427.019 [SBIT](INFO): git hash: e0be937c7f1ec7c51b840ed3ea323bb1c80883b5
2023-05-23T00:07:07.019Z,1684800427.019 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-05-23T00:07:07.021Z,1684800427.021 [SBIT](FAULT): 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-23T00:07:07.022Z,1684800427.022 [SBIT](INFO): Beginning SBIT in 814.000000 seconds.
2023-05-23T00:07:07.022Z,1684800427.022 [IBIT](INFO): Initialize IBIT Component.
2023-05-23T00:07:07.023Z,1684800427.023 [CBIT](DEBUG): Initialize CBIT Component.
2023-05-23T00:07:07.024Z,1684800427.024 [logger ThreadHandler](INFO): Handler Thread ID is 1894
2023-05-23T00:07:07.037Z,1684800427.037 [CBIT](DEBUG): Initialized mux pins.
2023-05-23T00:07:07.037Z,1684800427.037 [CBIT](DEBUG): Initializing the watchdog timer.
2023-05-23T00:07:07.049Z,1684800427.049 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1895
2023-05-23T00:07:07.050Z,1684800427.050 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-05-23T00:07:07.054Z,1684800427.054 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1897
2023-05-23T00:07:07.057Z,1684800427.057 [WetLabsBB2FL](INFO): Powering up
2023-05-23T00:07:07.058Z,1684800427.058 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1899
2023-05-23T00:07:07.061Z,1684800427.061 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-05-23T00:07:07.061Z,1684800427.061 [CBIT](DEBUG): Initializing heartbeat.
2023-05-23T00:07:07.076Z,1684800427.076 [DAT ThreadHandler](INFO): Handler Thread ID is 1900
2023-05-23T00:07:07.076Z,1684800427.076 [DAT](INFO): Powering up
2023-05-23T00:07:07.076Z,1684800427.076 [DAT](DEBUG): Initializing DAT.
2023-05-23T00:07:07.085Z,1684800427.085 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1902
2023-05-23T00:07:07.086Z,1684800427.086 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-05-23T00:07:07.097Z,1684800427.097 [Onboard ThreadHandler](INFO): Handler Thread ID is 1903
2023-05-23T00:07:07.114Z,1684800427.114 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1904
2023-05-23T00:07:07.133Z,1684800427.133 [CBIT](DEBUG): Deactivating GF circuits.
2023-05-23T00:07:07.133Z,1684800427.133 [CBIT](DEBUG): Deactivating emergency mode.
2023-05-23T00:07:07.134Z,1684800427.134 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1905
2023-05-23T00:07:07.136Z,1684800427.136 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2023-05-23T00:07:07.136Z,1684800427.136 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2023-05-23T00:07:07.136Z,1684800427.136 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2023-05-23T00:07:07.137Z,1684800427.137 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2023-05-23T00:07:07.137Z,1684800427.137 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2023-05-23T00:07:07.137Z,1684800427.137 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2023-05-23T00:07:07.137Z,1684800427.137 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2023-05-23T00:07:07.137Z,1684800427.137 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2023-05-23T00:07:07.137Z,1684800427.137 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA69M.000
2023-05-23T00:07:07.138Z,1684800427.138 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA69M.000
2023-05-23T00:07:07.173Z,1684800427.173 [CBIT](DEBUG): Backplane powered.
2023-05-23T00:07:07.177Z,1684800427.177 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-05-23T00:07:07.178Z,1684800427.178 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-05-23T00:07:07.178Z,1684800427.178 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-05-23T00:07:07.178Z,1684800427.178 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-05-23T00:07:07.179Z,1684800427.179 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-05-23T00:07:07.180Z,1684800427.180 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-05-23T00:07:07.180Z,1684800427.180 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-05-23T00:07:07.187Z,1684800427.187 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-05-23T00:07:07.188Z,1684800427.188 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-05-23T00:07:07.193Z,1684800427.193 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-05-23T00:07:07.194Z,1684800427.194 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-05-23T00:07:07.195Z,1684800427.195 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-05-23T00:07:07.232Z,1684800427.232 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-05-23T00:07:07.267Z,1684800427.267 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2023-05-23T00:07:07.268Z,1684800427.268 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-05-23T00:07:07.362Z,1684800427.362 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-05-23T00:07:07.363Z,1684800427.363 [Default:A.Wait](DEBUG): Construct Wait.
2023-05-23T00:07:07.366Z,1684800427.366 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-05-23T00:07:07.421Z,1684800427.421 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-05-23T00:07:07.423Z,1684800427.423 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-05-23T00:07:07.445Z,1684800427.445 [Default:E.Execute](DEBUG): Construct Execute.
2023-05-23T00:07:07.449Z,1684800427.449 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2023-05-23T00:07:07.460Z,1684800427.460 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2023-05-23T00:07:07.484Z,1684800427.484 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-05-23T00:07:07.521Z,1684800427.521 [Radio_Surface](INFO): Powering up
2023-05-23T00:07:07.546Z,1684800427.546 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1895.444336 decibar, 0.000000 m
2023-05-23T00:07:07.547Z,1684800427.547 [Power24vConverter](INFO): Powering up.
2023-05-23T00:07:07.548Z,1684800427.548 [DepthRateCalculator](ERROR): Depth measurement is not active
2023-05-23T00:07:07.591Z,1684800427.591 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-05-23T00:07:07.597Z,1684800427.597 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-05-23T00:07:07.598Z,1684800427.598 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-05-23T00:07:07.605Z,1684800427.605 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-05-23T00:07:07.606Z,1684800427.606 [MassServo](DEBUG): Initializing EZServoServo.
2023-05-23T00:07:07.613Z,1684800427.613 [MassServo](DEBUG): Initializing MassServo.
2023-05-23T00:07:07.614Z,1684800427.614 [RudderServo](DEBUG): Initializing EZServoServo.
2023-05-23T00:07:07.621Z,1684800427.621 [RudderServo](DEBUG): Initializing RudderServo.
2023-05-23T00:07:07.622Z,1684800427.622 [ThrusterHE](DEBUG): Initializing EZServoServo.
2023-05-23T00:07:07.629Z,1684800427.629 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2023-05-23T00:07:09.165Z,1684800429.165 [WetLabsBB2FL](INFO): Powering down
2023-05-23T00:07:13.334Z,1684800433.334 [ThrusterHE](ERROR): Zero Speed Commanded.
2023-05-23T00:07:21.695Z,1684800441.695 [DAT](INFO): commRate: 1200
2023-05-23T00:07:23.777Z,1684800443.777 [DAT](INFO): entering command mode
2023-05-23T00:07:23.777Z,1684800443.777 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:23.965Z,1684800443.965 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:23.966Z,1684800443.966 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:24.217Z,1684800444.217 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:24.217Z,1684800444.217 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:24.469Z,1684800444.469 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:24.469Z,1684800444.469 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:24.721Z,1684800444.721 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:24.721Z,1684800444.721 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:24.973Z,1684800444.973 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:24.973Z,1684800444.973 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:25.225Z,1684800445.225 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:25.225Z,1684800445.225 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:25.477Z,1684800445.477 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:25.477Z,1684800445.477 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:25.729Z,1684800445.729 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:25.730Z,1684800445.730 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:25.981Z,1684800445.981 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:25.981Z,1684800445.981 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:26.233Z,1684800446.233 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:26.233Z,1684800446.233 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:26.485Z,1684800446.485 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:26.485Z,1684800446.485 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:26.737Z,1684800446.737 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:26.737Z,1684800446.737 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:26.989Z,1684800446.989 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:26.989Z,1684800446.989 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:27.241Z,1684800447.241 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:27.241Z,1684800447.241 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:27.493Z,1684800447.493 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:27.493Z,1684800447.493 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:27.745Z,1684800447.745 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:27.745Z,1684800447.745 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:27.999Z,1684800447.999 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:27.000Z,1684800448.000 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:28.249Z,1684800448.249 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:28.249Z,1684800448.249 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:28.501Z,1684800448.501 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:28.501Z,1684800448.501 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:28.753Z,1684800448.753 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:28.753Z,1684800448.753 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:29.007Z,1684800449.007 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:29.007Z,1684800449.007 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:29.257Z,1684800449.257 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:29.257Z,1684800449.257 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:29.509Z,1684800449.509 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:29.509Z,1684800449.509 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:29.761Z,1684800449.761 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:29.762Z,1684800449.762 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:30.013Z,1684800450.013 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:30.013Z,1684800450.013 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:30.265Z,1684800450.265 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:30.265Z,1684800450.265 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:30.517Z,1684800450.517 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:30.517Z,1684800450.517 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:30.769Z,1684800450.769 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:30.769Z,1684800450.769 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:31.021Z,1684800451.021 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:31.021Z,1684800451.021 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:31.273Z,1684800451.273 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:31.273Z,1684800451.273 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:31.525Z,1684800451.525 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:31.525Z,1684800451.525 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:31.777Z,1684800451.777 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:31.777Z,1684800451.777 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:32.029Z,1684800452.029 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:32.029Z,1684800452.029 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:32.282Z,1684800452.282 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:32.282Z,1684800452.282 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:32.533Z,1684800452.533 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:32.533Z,1684800452.533 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:32.785Z,1684800452.785 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:32.785Z,1684800452.785 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:33.037Z,1684800453.037 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:33.037Z,1684800453.037 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:33.289Z,1684800453.289 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:33.289Z,1684800453.289 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:33.541Z,1684800453.541 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:33.541Z,1684800453.541 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:33.793Z,1684800453.793 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:33.793Z,1684800453.793 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:34.045Z,1684800454.045 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:34.045Z,1684800454.045 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:34.297Z,1684800454.297 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:34.297Z,1684800454.297 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:34.549Z,1684800454.549 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:34.549Z,1684800454.549 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:34.801Z,1684800454.801 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:34.801Z,1684800454.801 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:35.053Z,1684800455.053 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:35.053Z,1684800455.053 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:35.305Z,1684800455.305 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:35.305Z,1684800455.305 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:35.557Z,1684800455.557 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:35.558Z,1684800455.558 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:35.809Z,1684800455.809 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:35.810Z,1684800455.810 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:36.061Z,1684800456.061 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:36.061Z,1684800456.061 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:36.313Z,1684800456.313 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:36.313Z,1684800456.313 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:36.563Z,1684800456.563 [NAL9602](INFO): Powering up NAL9602
2023-05-23T00:07:36.565Z,1684800456.565 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:36.565Z,1684800456.565 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:36.817Z,1684800456.817 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:36.817Z,1684800456.817 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:37.071Z,1684800457.071 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:37.071Z,1684800457.071 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:37.321Z,1684800457.321 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:37.321Z,1684800457.321 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:37.573Z,1684800457.573 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:37.573Z,1684800457.573 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:37.825Z,1684800457.825 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:37.825Z,1684800457.825 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:38.077Z,1684800458.077 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:38.077Z,1684800458.077 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:38.329Z,1684800458.329 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:38.329Z,1684800458.329 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:38.581Z,1684800458.581 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:38.581Z,1684800458.581 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:38.834Z,1684800458.834 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:38.834Z,1684800458.834 [DAT](FAULT): failed to enter command mode
2023-05-23T00:07:39.149Z,1684800459.149 [DAT](INFO): entering command mode
2023-05-23T00:07:39.149Z,1684800459.149 [DAT](DEBUG): checking for command mode acknowledgment
2023-05-23T00:07:39.338Z,1684800459.338 [DAT](INFO): setting verbose to 3
2023-05-23T00:07:39.590Z,1684800459.590 [DAT](INFO): set verbose to 3
2023-05-23T00:07:39.591Z,1684800459.591 [DAT](INFO): setting DatVerbose to 27440
2023-05-23T00:07:39.842Z,1684800459.842 [DAT](INFO): set DatVerbose to 27440
2023-05-23T00:07:39.843Z,1684800459.843 [DAT](INFO): setting transmit power to 8
2023-05-23T00:07:40.094Z,1684800460.094 [DAT](INFO): set transmit power to 8
2023-05-23T00:07:40.095Z,1684800460.095 [DAT](INFO): setting local address to 5
2023-05-23T00:07:40.346Z,1684800460.346 [DAT](INFO): set local address to 5
2023-05-23T00:07:40.347Z,1684800460.347 [DAT](INFO): Setting time to: 0:7:40 And date to:5/23/2023
2023-05-23T00:07:40.598Z,1684800460.598 [DAT](INFO): Local DAT time set to Tue May 23, 2023  00:07:40  

2023-05-23T00:07:47.475Z,1684800467.475 [NAL9602](INFO): NAL9602 initialized
2023-05-23T00:08:09.693Z,1684800489.693 [NAL9602](INFO): SBD MO Status=2, MOMSN=5763, MT Status=2, MTMSN=0
2023-05-23T00:08:09.693Z,1684800489.693 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-05-23T00:08:34.747Z,1684800514.747 [NAL9602](INFO): SBD MO Status=0, MOMSN=5763, MT Status=0, MTMSN=0
2023-05-23T00:08:34.747Z,1684800514.747 [NAL9602](INFO): No messages in MT queue
2023-05-23T00:08:35.950Z,1684800515.950 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000832.00,A,3648.15473,N,12147.22017,W,0.019,0.00,230523,,,A*72
2023-05-23T00:08:35.954Z,1684800515.954 [NAL9602](INFO): GPS fix at 20230523T000832: (36.802579, -121.787003)
2023-05-23T00:09:08.302Z,1684800548.302 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-23T00:10:41.303Z,1684800641.303 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004514
2023-05-23T00:14:25.242Z,1684800865.242 [DataOverHttps](IMPORTANT): SBD MTMSN=20230523T001424
2023-05-23T00:14:35.409Z,1684800875.409 [DataOverHttps](INFO): Received command: restart app
2023-05-23T00:14:35.470Z,1684800875.470 [CommandExec](IMPORTANT): got command restart application
2023-05-23T00:14:36.473Z,1684800876.473 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2023-05-23T00:14:36.473Z,1684800876.473 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-23T00:14:36.473Z,1684800876.473 [CommandExec](INFO): Uninitializing the command executive.
2023-05-23T00:14:36.473Z,1684800876.473 [CommandExec](INFO): Uninitializing the command scheduler.
2023-05-23T00:14:36.474Z,1684800876.474 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-05-23T00:14:36.525Z,1684800876.525 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2023-05-23T00:14:36.525Z,1684800876.525 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-05-23T00:14:36.525Z,1684800876.525 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-05-23T00:14:36.526Z,1684800876.526 [NavChartDb](INFO): Join timeout helper Thread ID is 1948
2023-05-23T00:14:36.561Z,1684800876.561 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-23T00:14:36.561Z,1684800876.561 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-05-23T00:14:36.562Z,1684800876.562 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-05-23T00:14:36.562Z,1684800876.562 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-05-23T00:14:36.562Z,1684800876.562 [Radio_Surface](INFO): Join timeout helper Thread ID is 1949
2023-05-23T00:14:36.569Z,1684800876.569 [Radio_Surface](INFO): Powering down
2023-05-23T00:14:36.570Z,1684800876.570 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-23T00:14:36.570Z,1684800876.570 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-05-23T00:14:36.593Z,1684800876.593 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-05-23T00:14:36.593Z,1684800876.593 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-05-23T00:14:36.593Z,1684800876.593 [Onboard](INFO): Join timeout helper Thread ID is 1950
2023-05-23T00:14:37.413Z,1684800877.413 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2023-05-23T00:14:37.461Z,1684800877.461 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-23T00:14:37.461Z,1684800877.461 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-05-23T00:14:37.465Z,1684800877.465 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-05-23T00:14:37.465Z,1684800877.465 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-05-23T00:14:37.465Z,1684800877.465 [DataOverHttps](INFO): Join timeout helper Thread ID is 1951
2023-05-23T00:14:38.417Z,1684800878.417 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-23T00:14:38.417Z,1684800878.417 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-05-23T00:14:38.428Z,1684800878.428 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2023-05-23T00:14:38.428Z,1684800878.428 [DAT ThreadHandler](INFO): Thread cancelled.
2023-05-23T00:14:38.428Z,1684800878.428 [DAT](INFO): Join timeout helper Thread ID is 1952
2023-05-23T00:14:38.537Z,1684800878.537 [DAT](INFO): Powering down
2023-05-23T00:14:38.609Z,1684800878.609 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-23T00:14:38.609Z,1684800878.609 [DAT](INFO): Powering down
2023-05-23T00:14:38.610Z,1684800878.610 [DAT ThreadHandler](INFO): Thread cancelled.
2023-05-23T00:14:38.614Z,1684800878.614 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2023-05-23T00:14:38.614Z,1684800878.614 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-05-23T00:14:38.614Z,1684800878.614 [BackseatComponent](INFO): Join timeout helper Thread ID is 1953
2023-05-23T00:14:38.749Z,1684800878.749 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-23T00:14:38.749Z,1684800878.749 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-05-23T00:14:38.753Z,1684800878.753 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-05-23T00:14:38.754Z,1684800878.754 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-05-23T00:14:38.754Z,1684800878.754 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1954
2023-05-23T00:14:38.898Z,1684800878.898 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-23T00:14:38.899Z,1684800878.899 [WetLabsBB2FL](INFO): Powering down
2023-05-23T00:14:38.902Z,1684800878.902 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-05-23T00:14:38.914Z,1684800878.914 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-05-23T00:14:38.914Z,1684800878.914 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-05-23T00:14:38.914Z,1684800878.914 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1955
2023-05-23T00:14:39.061Z,1684800879.061 [CTD_Seabird](INFO): Powering down
2023-05-23T00:14:39.073Z,1684800879.073 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-23T00:14:39.073Z,1684800879.073 [CTD_Seabird](INFO): Powering down
2023-05-23T00:14:39.085Z,1684800879.085 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-05-23T00:14:39.100Z,1684800879.100 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-05-23T00:14:39.100Z,1684800879.100 [logger ThreadHandler](INFO): Thread cancelled.
2023-05-23T00:14:39.111Z,1684800879.111 [logger](INFO): Join timeout helper Thread ID is 1956
2023-05-23T00:14:39.130Z,1684800879.130 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-23T00:14:39.130Z,1684800879.130 [logger ThreadHandler](INFO): Thread cancelled.
2023-05-23T00:14:39.149Z,1684800879.149 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-05-23T00:14:39.150Z,1684800879.150 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-05-23T00:14:39.150Z,1684800879.150 [CommandLine](INFO): Join timeout helper Thread ID is 1957
2023-05-23T00:14:39.153Z,1684800879.153 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-23T00:14:39.153Z,1684800879.153 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-05-23T00:14:39.169Z,1684800879.169 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-05-23T00:14:39.170Z,1684800879.170 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-05-23T00:14:39.170Z,1684800879.170 [CommandExec](INFO): Join timeout helper Thread ID is 1958
2023-05-23T00:14:39.171Z,1684800879.171 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-05-23T00:14:39.171Z,1684800879.171 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-05-23T00:14:39.172Z,1684800879.172 [controlThread](INFO): Join timeout helper Thread ID is 1959
2023-05-23T00:14:39.293Z,1684800879.293 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-23T00:14:39.293Z,1684800879.293 [controlThread](DEBUG): Uninitializing ControlThread
2023-05-23T00:14:39.295Z,1684800879.295 [AHRS_M2](INFO): Powering down
2023-05-23T00:14:39.366Z,1684800879.366 [NAL9602](INFO): Powering down
2023-05-23T00:14:39.368Z,1684800879.368 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-05-23T00:14:39.369Z,1684800879.369 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-05-23T00:14:39.369Z,1684800879.369 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-05-23T00:14:39.370Z,1684800879.370 [MissionManager](INFO): Uninitializing Mission Default
2023-05-23T00:14:39.372Z,1684800879.372 [MissionManager](INFO): Uninitializing Mission Startup
2023-05-23T00:14:39.374Z,1684800879.374 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2023-05-23T00:14:39.374Z,1684800879.374 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2023-05-23T00:14:39.374Z,1684800879.374 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2023-05-23T00:14:39.375Z,1684800879.375 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2023-05-23T00:14:39.375Z,1684800879.375 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2023-05-23T00:14:39.375Z,1684800879.375 [BuoyancyServo](INFO): Powering down
2023-05-23T00:14:39.389Z,1684800879.389 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-05-23T00:14:39.389Z,1684800879.389 [ElevatorServo](INFO): Powering down
2023-05-23T00:14:39.390Z,1684800879.390 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-05-23T00:14:39.390Z,1684800879.390 [MassServo](INFO): Powering down
2023-05-23T00:14:39.391Z,1684800879.391 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-05-23T00:14:39.391Z,1684800879.391 [RudderServo](INFO): Powering down
2023-05-23T00:14:39.392Z,1684800879.392 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2023-05-23T00:14:39.392Z,1684800879.392 [ThrusterHE](INFO): Powering down
2023-05-23T00:14:39.393Z,1684800879.393 [SBIT](DEBUG): Uninitialize SBIT Component.
2023-05-23T00:14:39.393Z,1684800879.393 [IBIT](DEBUG): Uninitialize IBIT Component.
2023-05-23T00:14:39.393Z,1684800879.393 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-05-23T00:14:39.394Z,1684800879.394 [CBIT](DEBUG): Powering off loads.
2023-05-23T00:14:39.405Z,1684800879.405 [CBIT](DEBUG): Disabling WDT.
2023-05-23T00:14:39.417Z,1684800879.417 [CBIT](DEBUG): Opening all GF detection circuits.
2023-05-23T00:14:39.418Z,1684800879.418 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-05-23T00:14:39.577Z,1684800879.577 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-05-23T00:14:39.583Z,1684800879.583 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-05-23T00:14:39.611Z,1684800879.611 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-05-23T00:14:39.613Z,1684800879.613 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-05-23T00:14:39.619Z,1684800879.619 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-05-23T00:14:39.626Z,1684800879.626 [DAT ThreadHandler](INFO): Thread cancelled.
2023-05-23T00:14:39.666Z,1684800879.666 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-05-23T00:14:39.717Z,1684800879.717 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-05-23T00:14:39.785Z,1684800879.785 [logger ThreadHandler](INFO): Thread cancelled.