2023-09-08T11:08:10.642Z,1694171290.642 [Supervisor](DEBUG): Initializing supervisor.
2023-09-08T11:08:10.646Z,1694171290.646 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-09-08T11:08:10.647Z,1694171290.647 [SyncHandler](INFO): Protected caller Thread ID is 4129
2023-09-08T11:08:10.647Z,1694171290.647 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-09-08T11:08:10.648Z,1694171290.648 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-09-08T11:08:10.648Z,1694171290.648 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4130
2023-09-08T11:08:10.652Z,1694171290.652 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-09-08T11:08:10.670Z,1694171290.670 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-09-08T11:08:10.671Z,1694171290.671 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-09-08T11:08:10.671Z,1694171290.671 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 4131
2023-09-08T11:08:10.675Z,1694171290.675 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-09-08T11:08:10.676Z,1694171290.676 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-09-08T11:08:10.677Z,1694171290.677 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4132
2023-09-08T11:08:10.679Z,1694171290.679 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-09-08T11:08:10.679Z,1694171290.679 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-09-08T11:08:10.680Z,1694171290.680 [logger ThreadHandler](INFO): Protected caller Thread ID is 4133
2023-09-08T11:08:10.683Z,1694171290.683 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-09-08T11:08:10.684Z,1694171290.684 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-09-08T11:08:10.686Z,1694171290.686 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-09-08T11:08:10.799Z,1694171290.799 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-09-08T11:08:10.799Z,1694171290.799 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-09-08T11:08:11.010Z,1694171291.010 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-09-08T11:08:11.010Z,1694171291.010 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-09-08T11:08:11.259Z,1694171291.259 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-09-08T11:08:11.260Z,1694171291.260 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-09-08T11:08:11.364Z,1694171291.364 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-09-08T11:08:11.365Z,1694171291.365 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-09-08T11:08:12.073Z,1694171292.073 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-09-08T11:08:12.074Z,1694171292.074 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-09-08T11:08:12.542Z,1694171292.542 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-09-08T11:08:12.543Z,1694171292.543 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-09-08T11:08:13.735Z,1694171293.735 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-09-08T11:08:13.735Z,1694171293.735 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-09-08T11:08:15.272Z,1694171295.272 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-09-08T11:08:15.272Z,1694171295.272 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-09-08T11:08:15.679Z,1694171295.679 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-09-08T11:08:15.680Z,1694171295.680 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-09-08T11:08:16.040Z,1694171296.040 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-09-08T11:08:16.040Z,1694171296.040 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-09-08T11:08:16.256Z,1694171296.256 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-09-08T11:08:16.257Z,1694171296.257 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-09-08T11:08:16.394Z,1694171296.394 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-09-08T11:08:16.395Z,1694171296.395 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-09-08T11:08:16.475Z,1694171296.475 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-09-08T11:08:16.554Z,1694171296.554 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-09-08T11:08:16.732Z,1694171296.732 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-09-08T11:08:16.733Z,1694171296.733 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-09-08T11:08:16.960Z,1694171296.960 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-09-08T11:08:16.962Z,1694171296.962 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2023-09-08T11:08:16.963Z,1694171296.963 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2023-09-08T11:08:17.046Z,1694171297.046 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2023-09-08T11:08:17.307Z,1694171297.307 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-09-08T11:08:17.308Z,1694171297.308 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2023-09-08T11:08:17.412Z,1694171297.412 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2023-09-08T11:08:17.567Z,1694171297.567 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2023-09-08T11:08:17.668Z,1694171297.668 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2023-09-08T11:08:17.779Z,1694171297.779 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2023-09-08T11:08:17.888Z,1694171297.888 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2023-09-08T11:08:17.990Z,1694171297.990 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2023-09-08T11:08:18.146Z,1694171298.146 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Derivation.cfg
2023-09-08T11:08:18.241Z,1694171298.241 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2023-09-08T11:08:18.336Z,1694171298.336 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2023-09-08T11:08:18.435Z,1694171298.435 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-09-08T11:08:18.458Z,1694171298.458 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-09-08T11:08:18.606Z,1694171298.606 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-09-08T11:08:18.607Z,1694171298.607 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-09-08T11:08:18.762Z,1694171298.762 [BuoyancyServo] Loaded
2023-09-08T11:08:18.763Z,1694171298.763 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-09-08T11:08:18.783Z,1694171298.783 [ElevatorServo] Loaded
2023-09-08T11:08:18.783Z,1694171298.783 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-09-08T11:08:18.803Z,1694171298.803 [MassServo] Loaded
2023-09-08T11:08:18.804Z,1694171298.804 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-09-08T11:08:18.823Z,1694171298.823 [RudderServo] Loaded
2023-09-08T11:08:18.824Z,1694171298.824 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-09-08T11:08:18.839Z,1694171298.839 [ThrusterHE] Loaded
2023-09-08T11:08:18.839Z,1694171298.839 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2023-09-08T11:08:18.840Z,1694171298.840 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-09-08T11:08:18.840Z,1694171298.840 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-09-08T11:08:19.185Z,1694171299.185 [SBIT](DEBUG): Construct Startup Built In Test.
2023-09-08T11:08:19.200Z,1694171299.200 [SBIT] Loaded
2023-09-08T11:08:19.200Z,1694171299.200 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-09-08T11:08:19.203Z,1694171299.203 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-09-08T11:08:19.224Z,1694171299.224 [IBIT] Loaded
2023-09-08T11:08:19.224Z,1694171299.224 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-09-08T11:08:19.230Z,1694171299.230 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-09-08T11:08:19.530Z,1694171299.530 [CBIT] Loaded
2023-09-08T11:08:19.530Z,1694171299.530 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-09-08T11:08:19.530Z,1694171299.530 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-09-08T11:08:19.531Z,1694171299.531 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-09-08T11:08:19.698Z,1694171299.698 [CTD_Seabird] Loaded
2023-09-08T11:08:19.698Z,1694171299.698 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-09-08T11:08:19.700Z,1694171299.700 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406674E0
2023-09-08T11:08:19.700Z,1694171299.700 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 4218
2023-09-08T11:08:19.732Z,1694171299.732 [ESPComponent] Loaded
2023-09-08T11:08:19.732Z,1694171299.732 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2023-09-08T11:08:19.752Z,1694171299.752 [PAR_Licor] Loaded
2023-09-08T11:08:19.752Z,1694171299.752 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-09-08T11:08:19.791Z,1694171299.791 [WetLabsBB2FL] Loaded
2023-09-08T11:08:19.792Z,1694171299.792 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-09-08T11:08:19.793Z,1694171299.793 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406974E0
2023-09-08T11:08:19.793Z,1694171299.793 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 4219
2023-09-08T11:08:19.794Z,1694171299.794 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-09-08T11:08:19.794Z,1694171299.794 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-09-08T11:08:19.844Z,1694171299.844 [DepthRateCalculator] Loaded
2023-09-08T11:08:19.844Z,1694171299.844 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-09-08T11:08:19.849Z,1694171299.849 [PitchRateCalculator] Loaded
2023-09-08T11:08:19.849Z,1694171299.849 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-09-08T11:08:19.859Z,1694171299.859 [SpeedCalculator] Loaded
2023-09-08T11:08:19.860Z,1694171299.860 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-09-08T11:08:19.864Z,1694171299.864 [YawRateCalculator] Loaded
2023-09-08T11:08:19.864Z,1694171299.864 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-09-08T11:08:19.884Z,1694171299.884 [ElevatorOffsetCalculator] Loaded
2023-09-08T11:08:19.884Z,1694171299.884 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-09-08T11:08:19.901Z,1694171299.901 [ShortestDistanceToPolygonSidesCalculator] Loaded
2023-09-08T11:08:19.901Z,1694171299.901 [ComponentRegistry](DEBUG): SyncComponent "ShortestDistanceToPolygonSidesCalculator" handled in the control thread.
2023-09-08T11:08:19.902Z,1694171299.902 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-09-08T11:08:19.902Z,1694171299.902 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-09-08T11:08:20.002Z,1694171300.002 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-09-08T11:08:20.002Z,1694171300.002 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-09-08T11:08:20.028Z,1694171300.028 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-09-08T11:08:20.029Z,1694171300.029 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-09-08T11:08:20.135Z,1694171300.135 [DeadReckonUsingSpeedCalculator] Loaded
2023-09-08T11:08:20.135Z,1694171300.135 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2023-09-08T11:08:20.149Z,1694171300.149 [NavChart] Loaded
2023-09-08T11:08:20.149Z,1694171300.149 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-09-08T11:08:20.155Z,1694171300.155 [UniversalFixResidualReporter] Loaded
2023-09-08T11:08:20.155Z,1694171300.155 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-09-08T11:08:20.155Z,1694171300.155 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-09-08T11:08:20.156Z,1694171300.156 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-09-08T11:08:20.574Z,1694171300.574 [AHRS_M2] Loaded
2023-09-08T11:08:20.575Z,1694171300.575 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-09-08T11:08:20.609Z,1694171300.609 [BackseatComponent] Loaded
2023-09-08T11:08:20.609Z,1694171300.609 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2023-09-08T11:08:20.611Z,1694171300.611 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 40A2F4E0
2023-09-08T11:08:20.611Z,1694171300.611 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 4220
2023-09-08T11:08:20.614Z,1694171300.614 [LcmUniversalReporter] Loaded
2023-09-08T11:08:20.614Z,1694171300.614 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2023-09-08T11:08:21.347Z,1694171301.347 [BPC1] Loaded
2023-09-08T11:08:21.347Z,1694171301.347 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-09-08T11:08:21.421Z,1694171301.421 [DataOverHttps] Loaded
2023-09-08T11:08:21.421Z,1694171301.421 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-09-08T11:08:21.422Z,1694171301.422 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40A5F4E0
2023-09-08T11:08:21.423Z,1694171301.423 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 4221
2023-09-08T11:08:21.443Z,1694171301.443 [Depth_Keller] Loaded
2023-09-08T11:08:21.444Z,1694171301.444 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-09-08T11:08:21.448Z,1694171301.448 [DropWeight] Loaded
2023-09-08T11:08:21.449Z,1694171301.449 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2023-09-08T11:08:21.487Z,1694171301.487 [DVL_micro] Loaded
2023-09-08T11:08:21.488Z,1694171301.488 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2023-09-08T11:08:21.548Z,1694171301.548 [NAL9602] Loaded
2023-09-08T11:08:21.549Z,1694171301.549 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-09-08T11:08:21.575Z,1694171301.575 [Onboard] Loaded
2023-09-08T11:08:21.575Z,1694171301.575 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-09-08T11:08:21.576Z,1694171301.576 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40A8F4E0
2023-09-08T11:08:21.576Z,1694171301.576 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 4222
2023-09-08T11:08:21.588Z,1694171301.588 [Power24vConverter] Loaded
2023-09-08T11:08:21.589Z,1694171301.589 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-09-08T11:08:21.602Z,1694171301.602 [Radio_Surface] Loaded
2023-09-08T11:08:21.602Z,1694171301.602 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-09-08T11:08:21.603Z,1694171301.603 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40ABF4E0
2023-09-08T11:08:21.604Z,1694171301.604 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4223
2023-09-08T11:08:21.618Z,1694171301.618 [Sonardyne_Nano] Loaded
2023-09-08T11:08:21.618Z,1694171301.618 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2023-09-08T11:08:21.618Z,1694171301.618 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-09-08T11:08:21.619Z,1694171301.619 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-09-08T11:08:21.674Z,1694171301.674 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-09-08T11:08:21.674Z,1694171301.674 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-09-08T11:08:21.724Z,1694171301.724 [VerticalControl](DEBUG): Construct VerticalControl.
2023-09-08T11:08:21.774Z,1694171301.774 [VerticalControl] Loaded
2023-09-08T11:08:21.775Z,1694171301.775 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-09-08T11:08:21.778Z,1694171301.778 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-09-08T11:08:21.815Z,1694171301.815 [HorizontalControl] Loaded
2023-09-08T11:08:21.815Z,1694171301.815 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-09-08T11:08:21.818Z,1694171301.818 [SpeedControl](DEBUG): Construct SpeedControl.
2023-09-08T11:08:21.819Z,1694171301.819 [SpeedControl] Loaded
2023-09-08T11:08:21.819Z,1694171301.819 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-09-08T11:08:21.822Z,1694171301.822 [LoopControl](DEBUG): Construct LoopControl.
2023-09-08T11:08:21.823Z,1694171301.823 [LoopControl] Loaded
2023-09-08T11:08:21.823Z,1694171301.823 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-09-08T11:08:21.824Z,1694171301.824 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-09-08T11:08:21.824Z,1694171301.824 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-09-08T11:08:21.834Z,1694171301.834 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-09-08T11:08:21.835Z,1694171301.835 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-09-08T11:08:22.135Z,1694171302.135 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-09-08T11:08:22.142Z,1694171302.142 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-09-08T11:08:22.144Z,1694171302.144 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-09-08T11:08:22.155Z,1694171302.155 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-09-08T11:08:22.156Z,1694171302.156 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BCB4E0
2023-09-08T11:08:22.157Z,1694171302.157 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4224
2023-09-08T11:08:22.161Z,1694171302.161 [Supervisor](INFO): Main Thread ID is 825
2023-09-08T11:08:22.161Z,1694171302.161 [Supervisor](DEBUG): Running supervisor.
2023-09-08T11:08:22.162Z,1694171302.162 [CommandExec ThreadHandler](INFO): Handler Thread ID is 4225
2023-09-08T11:08:22.162Z,1694171302.162 [CommandExec](INFO): Initializing the command executive.
2023-09-08T11:08:22.164Z,1694171302.164 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4226
2023-09-08T11:08:22.166Z,1694171302.166 [controlThread ThreadHandler](INFO): Handler Thread ID is 4227
2023-09-08T11:08:22.166Z,1694171302.166 [controlThread](DEBUG): Initializing ControlThread
2023-09-08T11:08:22.168Z,1694171302.168 [SBIT](INFO): Initialize SBIT Component.
2023-09-08T11:08:22.169Z,1694171302.169 [SBIT](IMPORTANT): git: 2023-09-07
2023-09-08T11:08:22.169Z,1694171302.169 [SBIT](INFO): git hash: de73b65bba9e058a7fe4bf2bc7b950ee8b764c06
2023-09-08T11:08:22.169Z,1694171302.169 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-09-08T11:08:22.171Z,1694171302.171 [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-09-08T11:08:22.172Z,1694171302.172 [SBIT](INFO): Beginning SBIT in 34.000000 seconds.
2023-09-08T11:08:22.172Z,1694171302.172 [IBIT](INFO): Initialize IBIT Component.
2023-09-08T11:08:22.173Z,1694171302.173 [CBIT](DEBUG): Initialize CBIT Component.
2023-09-08T11:08:22.174Z,1694171302.174 [logger ThreadHandler](INFO): Handler Thread ID is 4228
2023-09-08T11:08:22.185Z,1694171302.185 [CBIT](DEBUG): Initialized mux pins.
2023-09-08T11:08:22.185Z,1694171302.185 [CBIT](DEBUG): Initializing the watchdog timer.
2023-09-08T11:08:22.193Z,1694171302.193 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 4229
2023-09-08T11:08:22.194Z,1694171302.194 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-09-08T11:08:22.199Z,1694171302.199 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 4231
2023-09-08T11:08:22.201Z,1694171302.201 [WetLabsBB2FL](INFO): Powering up
2023-09-08T11:08:22.203Z,1694171302.203 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 4233
2023-09-08T11:08:22.209Z,1694171302.209 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-09-08T11:08:22.209Z,1694171302.209 [CBIT](DEBUG): Initializing heartbeat.
2023-09-08T11:08:22.210Z,1694171302.210 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 4234
2023-09-08T11:08:22.211Z,1694171302.211 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-09-08T11:08:22.221Z,1694171302.221 [Onboard ThreadHandler](INFO): Handler Thread ID is 4235
2023-09-08T11:08:22.251Z,1694171302.251 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4236
2023-09-08T11:08:22.261Z,1694171302.261 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4237
2023-09-08T11:08:22.268Z,1694171302.268 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI01M.000
2023-09-08T11:08:22.269Z,1694171302.269 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI01M.000
2023-09-08T11:08:22.269Z,1694171302.269 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI80M.000
2023-09-08T11:08:22.269Z,1694171302.269 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI80M.000
2023-09-08T11:08:22.269Z,1694171302.269 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI57M.000
2023-09-08T11:08:22.269Z,1694171302.269 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI57M.000
2023-09-08T11:08:22.270Z,1694171302.270 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI56M.000
2023-09-08T11:08:22.270Z,1694171302.270 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI56M.000
2023-09-08T11:08:22.270Z,1694171302.270 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI90M.000
2023-09-08T11:08:22.270Z,1694171302.270 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI90M.000
2023-09-08T11:08:22.270Z,1694171302.270 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI89M.000
2023-09-08T11:08:22.270Z,1694171302.270 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI89M.000
2023-09-08T11:08:22.271Z,1694171302.271 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI03M.000
2023-09-08T11:08:22.271Z,1694171302.271 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI03M.000
2023-09-08T11:08:22.271Z,1694171302.271 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI06M.000
2023-09-08T11:08:22.271Z,1694171302.271 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI06M.000
2023-09-08T11:08:22.271Z,1694171302.271 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4OH01M.000
2023-09-08T11:08:22.271Z,1694171302.271 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4OH01M.000
2023-09-08T11:08:22.272Z,1694171302.272 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4OH04M.000
2023-09-08T11:08:22.272Z,1694171302.272 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4OH04M.000
2023-09-08T11:08:22.272Z,1694171302.272 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4OH02M.000
2023-09-08T11:08:22.272Z,1694171302.272 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4OH02M.000
2023-09-08T11:08:22.272Z,1694171302.272 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4PA20M.000
2023-09-08T11:08:22.272Z,1694171302.272 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4PA20M.000
2023-09-08T11:08:22.272Z,1694171302.272 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4PA21M.000
2023-09-08T11:08:22.273Z,1694171302.273 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4PA21M.000
2023-09-08T11:08:22.281Z,1694171302.281 [CBIT](DEBUG): Deactivating GF circuits.
2023-09-08T11:08:22.281Z,1694171302.281 [CBIT](DEBUG): Deactivating emergency mode.
2023-09-08T11:08:22.321Z,1694171302.321 [CBIT](DEBUG): Backplane powered.
2023-09-08T11:08:22.325Z,1694171302.325 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-09-08T11:08:22.326Z,1694171302.326 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-09-08T11:08:22.326Z,1694171302.326 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-09-08T11:08:22.326Z,1694171302.326 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-09-08T11:08:22.327Z,1694171302.327 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-09-08T11:08:22.327Z,1694171302.327 [ShortestDistanceToPolygonSidesCalculator](DEBUG): Initializing.
2023-09-08T11:08:22.328Z,1694171302.328 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-09-08T11:08:22.329Z,1694171302.329 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-09-08T11:08:22.329Z,1694171302.329 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-09-08T11:08:22.335Z,1694171302.335 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-09-08T11:08:22.341Z,1694171302.341 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-09-08T11:08:22.342Z,1694171302.342 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-09-08T11:08:22.343Z,1694171302.343 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-09-08T11:08:22.344Z,1694171302.344 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-09-08T11:08:22.374Z,1694171302.374 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-09-08T11:08:22.410Z,1694171302.410 [MissionManager](DEBUG):
2023-09-08T11:08:22.410Z,1694171302.410 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-09-08T11:08:22.512Z,1694171302.512 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-09-08T11:08:22.514Z,1694171302.514 [Default:A.Wait](DEBUG): Construct Wait.
2023-09-08T11:08:22.516Z,1694171302.516 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-09-08T11:08:22.563Z,1694171302.563 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-09-08T11:08:22.574Z,1694171302.574 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-09-08T11:08:22.588Z,1694171302.588 [Default:E.Execute](DEBUG): Construct Execute.
2023-09-08T11:08:22.591Z,1694171302.591 [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-09-08T11:08:22.598Z,1694171302.598 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,ShortestDistanceToPolygonSidesCalculator,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2023-09-08T11:08:22.635Z,1694171302.635 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-09-08T11:08:22.653Z,1694171302.653 [Radio_Surface](INFO): Powering up
2023-09-08T11:08:22.702Z,1694171302.702 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1895.894409 decibar, 0.000000 m
2023-09-08T11:08:22.714Z,1694171302.714 [Power24vConverter](INFO): Powering up.
2023-09-08T11:08:22.715Z,1694171302.715 [Sonardyne_Nano](INFO): Initializing.
2023-09-08T11:08:22.741Z,1694171302.741 [DepthRateCalculator](ERROR): Depth measurement is not active
2023-09-08T11:08:22.771Z,1694171302.771 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-09-08T11:08:22.777Z,1694171302.777 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-09-08T11:08:22.778Z,1694171302.778 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-09-08T11:08:22.786Z,1694171302.786 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-09-08T11:08:22.787Z,1694171302.787 [MassServo](DEBUG): Initializing EZServoServo.
2023-09-08T11:08:22.793Z,1694171302.793 [MassServo](DEBUG): Initializing MassServo.
2023-09-08T11:08:22.794Z,1694171302.794 [RudderServo](DEBUG): Initializing EZServoServo.
2023-09-08T11:08:22.801Z,1694171302.801 [RudderServo](DEBUG): Initializing RudderServo.
2023-09-08T11:08:22.802Z,1694171302.802 [ThrusterHE](DEBUG): Initializing EZServoServo.
2023-09-08T11:08:22.809Z,1694171302.809 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2023-09-08T11:08:23.169Z,1694171303.169 [DVL_micro](INFO): Powering down
2023-09-08T11:08:24.325Z,1694171304.325 [WetLabsBB2FL](INFO): Powering down
2023-09-08T11:08:25.035Z,1694171305.035 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2023-09-08T11:08:26.286Z,1694171306.286 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2213
2023-09-08T11:08:28.370Z,1694171308.370 [ThrusterHE](ERROR): Zero Speed Commanded.
2023-09-08T11:08:51.703Z,1694171331.703 [NAL9602](INFO): Powering up NAL9602
2023-09-08T11:08:56.567Z,1694171336.567 [SBIT](IMPORTANT): Beginning Startup BIT
2023-09-08T11:08:56.571Z,1694171336.571 [CBIT](IMPORTANT): Beginning ground fault scan
2023-09-08T11:09:02.612Z,1694171342.612 [NAL9602](INFO): NAL9602 initialized
2023-09-08T11:09:07.918Z,1694171347.918 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006972
CHAN A1 (24V): 0.000866
CHAN A2 (12V): -0.006021
CHAN A3 (5V): -0.003405
CHAN B0 (3.3V): -0.001867
CHAN B1 (3.15aV): -0.001164
CHAN B2 (3.15bV): -0.001512
CHAN B3 (GND): 0.000047
OPEN: 0.000548
Full Scale: +/- 1 mA
2023-09-08T11:09:19.187Z,1694171359.187 [NAL9602](INFO): SBD MO Status=0, MOMSN=8934, MT Status=0, MTMSN=0
2023-09-08T11:09:19.187Z,1694171359.187 [NAL9602](INFO): No messages in MT queue
2023-09-08T11:09:32.513Z,1694171372.513 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,110929.00,A,4145.53087,N,08116.65689,W,0.039,0.00,080923,,,A*73
2023-09-08T11:09:32.516Z,1694171372.516 [NAL9602](INFO): GPS fix at 20230908T110929: (41.758848, -81.277615)
2023-09-08T11:09:50.310Z,1694171390.310 [SBIT](IMPORTANT): SBIT PASSED
2023-09-08T11:09:50.310Z,1694171390.310 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-09-08T11:09:50.311Z,1694171390.311 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;
2023-09-08T11:09:50.311Z,1694171390.311 [SBIT](IMPORTANT): DVL_micro.loadAtStartup=1 bool;
2023-09-08T11:09:50.311Z,1694171390.311 [SBIT](IMPORTANT): DeadReckonUsingMultipleVelocitySources.loadAtStartup=0 bool;
2023-09-08T11:09:50.312Z,1694171390.312 [SBIT](IMPORTANT): DeadReckonUsingSpeedCalculator.loadAtStartup=1 bool;
2023-09-08T11:09:50.312Z,1694171390.312 [SBIT](IMPORTANT): Depth_Keller.offset=-0.35 decibar;
2023-09-08T11:09:50.312Z,1694171390.312 [SBIT](IMPORTANT): DropWeight.loadAtStartup=1 bool;
2023-09-08T11:09:50.312Z,1694171390.312 [SBIT](IMPORTANT): ESPComponent.filterCompleteTimeout=135 minute;
2023-09-08T11:09:50.312Z,1694171390.312 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=3 minute;
2023-09-08T11:09:50.312Z,1694171390.312 [SBIT](IMPORTANT): ESPComponent.simulateHardware=0 bool;
2023-09-08T11:09:50.312Z,1694171390.312 [SBIT](IMPORTANT): ElevatorServo.offsetAngle=1 degree;
2023-09-08T11:09:50.312Z,1694171390.312 [SBIT](IMPORTANT): Express linearApproximation CTD_Seabird.bin_mean_sea_water_temperature 5.000000 celsius;
2023-09-08T11:09:50.313Z,1694171390.313 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_salinity;
2023-09-08T11:09:50.313Z,1694171390.313 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water;
2023-09-08T11:09:50.313Z,1694171390.313 [SBIT](IMPORTANT): Express linearApproximation WetLabsSeaOWL_UV_A.mass_concentration_of_petroleum_hydrocarbons_in_sea_water 1.000000 kilogram_per_cubic_meter;
2023-09-08T11:09:50.313Z,1694171390.313 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 50.000000 meter;
2023-09-08T11:09:50.313Z,1694171390.313 [SBIT](IMPORTANT): InternalEnvSim.loadAtStartup=0 bool;
2023-09-08T11:09:50.313Z,1694171390.313 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.loadAtStartup=1 bool;
2023-09-08T11:09:50.314Z,1694171390.314 [SBIT](IMPORTANT): Sonardyne_Nano.loadAtStartup=1 bool;
2023-09-08T11:09:50.314Z,1694171390.314 [SBIT](IMPORTANT): StratificationFrontDetector.loadAtStartup=0 bool;
2023-09-08T11:09:50.314Z,1694171390.314 [SBIT](IMPORTANT): StratificationFrontDetector.threshold_VTHI=0.05 celsius;
2023-09-08T11:09:50.314Z,1694171390.314 [SBIT](IMPORTANT): StratificationFrontDetector.verbosity=1 count;
2023-09-08T11:09:50.314Z,1694171390.314 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=90 cubic_centimeter;
2023-09-08T11:09:50.314Z,1694171390.314 [SBIT](IMPORTANT): VerticalControl.massDefault=3.505190 millimeter;
2023-09-08T11:09:50.314Z,1694171390.314 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.depth3=20 meter;
2023-09-08T11:09:50.314Z,1694171390.314 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.depth4=30 meter;
2023-09-08T11:09:50.314Z,1694171390.314 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.loadAtStartup=0 bool;
2023-09-08T11:09:50.315Z,1694171390.315 [SBIT](IMPORTANT): WetLabsSeaOWL_UV_A.loadAtStartup=0 bool;
2023-09-08T11:09:50.699Z,1694171390.699 [MissionManager](IMPORTANT): Started mission Startup
2023-09-08T11:09:50.700Z,1694171390.700 [Startup] Running Loop=1
2023-09-08T11:09:50.700Z,1694171390.700 [Startup](DEBUG): Aggregate::initialize Startup
2023-09-08T11:09:50.700Z,1694171390.700 [Startup:A.GoToSurface] Running Loop=1
2023-09-08T11:09:50.700Z,1694171390.700 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-09-08T11:09:50.701Z,1694171390.701 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-09-08T11:09:50.701Z,1694171390.701 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-09-08T11:09:50.701Z,1694171390.701 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-09-08T11:09:50.702Z,1694171390.702 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-09-08T11:09:50.702Z,1694171390.702 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-09-08T11:09:50.703Z,1694171390.703 [Startup:StartupSatComms] Running Loop=1
2023-09-08T11:09:50.703Z,1694171390.703 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-09-08T11:09:50.704Z,1694171390.704 [Startup:StartupSatComms:A] Running Loop=1
2023-09-08T11:09:51.105Z,1694171391.105 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-09-08T11:09:52.717Z,1694171392.717 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,110949.00,A,4145.53071,N,08116.65580,W,0.019,0.00,080923,,,A*74
2023-09-08T11:09:52.719Z,1694171392.719 [NAL9602](INFO): GPS fix at 20230908T110949: (41.758845, -81.277597)
2023-09-08T11:09:52.729Z,1694171392.729 [Startup:StartupSatComms:A] Stopped
2023-09-08T11:09:52.729Z,1694171392.729 [Startup:StartupSatComms:B] Running Loop=1
2023-09-08T11:09:53.154Z,1694171393.154 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-09-08T11:10:06.701Z,1694171406.701 [NAL9602](INFO): SBD MO Status=1, MOMSN=8935, MT Status=0, MTMSN=0
2023-09-08T11:10:06.765Z,1694171406.765 [NAL9602](INFO): Sent 40 bytes from file Logs/20230908T004718/Courier0289.lzma
2023-09-08T11:10:06.766Z,1694171406.766 [NAL9602](INFO): Packets left to send: 0
2023-09-08T11:10:30.328Z,1694171430.328 [NAL9602](INFO): SBD MO Status=2, MOMSN=8936, MT Status=2, MTMSN=0
2023-09-08T11:10:30.328Z,1694171430.328 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-08T11:10:50.239Z,1694171450.239 [NAL9602](INFO): SBD MO Status=1, MOMSN=8936, MT Status=0, MTMSN=0
2023-09-08T11:10:50.289Z,1694171450.289 [NAL9602](INFO): Sent 271 bytes from file Logs/20230908T110810/Courier0000.lzma
2023-09-08T11:10:50.289Z,1694171450.289 [NAL9602](INFO): Packets left to send: 0
2023-09-08T11:10:54.828Z,1694171454.828 [Startup:StartupSatComms:B](INFO): Timed out from 2023-09-08T11:09:52.7Z
2023-09-08T11:10:54.828Z,1694171454.828 [Startup:StartupSatComms:B] Stopped
2023-09-08T11:10:54.828Z,1694171454.828 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-09-08T11:10:54.828Z,1694171454.828 [Startup:StartupSatComms] Stopped
2023-09-08T11:10:54.829Z,1694171454.829 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-09-08T11:10:54.829Z,1694171454.829 [Startup](INFO): Completed Startup
2023-09-08T11:10:54.829Z,1694171454.829 [MissionManager](INFO): Startup is completed.
2023-09-08T11:10:54.830Z,1694171454.830 [MissionManager](INFO): Uninitializing Mission Startup
2023-09-08T11:10:54.830Z,1694171454.830 [Startup] Stopped
2023-09-08T11:10:54.830Z,1694171454.830 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-09-08T11:10:54.830Z,1694171454.830 [Startup:A.GoToSurface] Stopped
2023-09-08T11:10:54.830Z,1694171454.830 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-09-08T11:10:54.969Z,1694171454.969 [MissionManager](IMPORTANT): Started mission Default
2023-09-08T11:10:54.970Z,1694171454.970 [Default] Running Loop=1
2023-09-08T11:10:54.970Z,1694171454.970 [Default](DEBUG): Aggregate::initialize Default
2023-09-08T11:10:54.970Z,1694171454.970 [Default:B.GoToSurface] Running Loop=1
2023-09-08T11:10:54.970Z,1694171454.970 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-09-08T11:10:54.970Z,1694171454.970 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-09-08T11:10:54.970Z,1694171454.970 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-09-08T11:10:54.971Z,1694171454.971 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-09-08T11:10:54.971Z,1694171454.971 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-09-08T11:10:54.971Z,1694171454.971 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-09-08T11:10:54.972Z,1694171454.972 [Default:A.Wait] Running Loop=1
2023-09-08T11:10:54.972Z,1694171454.972 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-09-08T11:10:55.361Z,1694171455.361 [DVL_micro](INFO): Powering down
2023-09-08T11:11:00.119Z,1694171460.119 [NAL9602](INFO): SBD MO Status=1, MOMSN=8937, MT Status=0, MTMSN=0
2023-09-08T11:11:00.177Z,1694171460.177 [NAL9602](INFO): Sent 254 bytes from file Logs/20230908T004718/Express0290.lzma
2023-09-08T11:11:00.177Z,1694171460.177 [NAL9602](INFO): Packets left to send: 0
2023-09-08T11:11:08.261Z,1694171468.261 [Default:A.Wait](INFO): Done Waiting.
2023-09-08T11:11:08.261Z,1694171468.261 [Default:A.Wait] Stopped
2023-09-08T11:11:08.265Z,1694171468.265 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T11:11:08.644Z,1694171468.644 [Default:CheckIn] Running Loop=1
2023-09-08T11:11:08.644Z,1694171468.644 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-08T11:11:08.644Z,1694171468.644 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-08T11:11:09.042Z,1694171469.042 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-09-08T11:11:10.619Z,1694171470.619 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,111110.00,A,4145.53033,N,08116.65501,W,0.019,0.00,080923,,,A*7E
2023-09-08T11:11:10.621Z,1694171470.621 [NAL9602](INFO): GPS fix at 20230908T111110: (41.758839, -81.277584)
2023-09-08T11:11:10.631Z,1694171470.631 [Default:CheckIn:Read_GPS] Stopped
2023-09-08T11:11:10.631Z,1694171470.631 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-08T11:11:11.069Z,1694171471.069 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-09-08T11:11:14.259Z,1694171474.259 [DVL_micro](ERROR): Failed to parse:
:WI,-00106,+01030,+00330,+00000,A
2023-09-08T11:11:34.756Z,1694171494.756 [NAL9602](INFO): SBD MO Status=1, MOMSN=8938, MT Status=0, MTMSN=0
2023-09-08T11:11:34.810Z,1694171494.810 [NAL9602](INFO): Sent 76 bytes from file Logs/20230908T110810/Courier0004.lzma
2023-09-08T11:11:34.810Z,1694171494.810 [NAL9602](INFO): Packets left to send: 0
2023-09-08T11:11:46.140Z,1694171506.140 [NAL9602](INFO): SBD MO Status=2, MOMSN=8939, MT Status=2, MTMSN=0
2023-09-08T11:11:46.140Z,1694171506.140 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-08T11:12:24.261Z,1694171544.261 [NAL9602](INFO): SBD MO Status=2, MOMSN=8939, MT Status=2, MTMSN=0
2023-09-08T11:12:24.265Z,1694171544.265 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-09-08T11:12:28.579Z,1694171548.579 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004475
2023-09-08T11:12:42.141Z,1694171562.141 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20230908T004718/Express0293.lzma
2023-09-08T11:12:44.849Z,1694171564.849 [DataOverHttps](INFO): Moved sent file to Logs/20230908T004718/Express0293.lzma.bak
2023-09-08T11:12:44.849Z,1694171564.849 [DataOverHttps](INFO): SBD MOMSN=18630168
2023-09-08T11:13:02.205Z,1694171582.205 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-08T11:13:02.727Z,1694171582.727 [DataOverHttps](INFO): Sending 1280 bytes from file Logs/20230908T110810/Express0001.lzma
2023-09-08T11:13:03.723Z,1694171583.723 [DataOverHttps](INFO): Moved sent file to Logs/20230908T110810/Express0001.lzma.bak
2023-09-08T11:13:03.723Z,1694171583.723 [DataOverHttps](INFO): SBD MOMSN=18630170
2023-09-08T11:13:21.481Z,1694171601.481 [DataOverHttps](INFO): Sending 217 bytes from file Logs/20230908T110810/Express0005.lzma
2023-09-08T11:13:22.483Z,1694171602.483 [DataOverHttps](INFO): Moved sent file to Logs/20230908T110810/Express0005.lzma.bak
2023-09-08T11:13:22.483Z,1694171602.483 [DataOverHttps](INFO): SBD MOMSN=18630209
2023-09-08T11:13:26.912Z,1694171606.912 [Default:CheckIn:Read_Iridium] Stopped
2023-09-08T11:13:26.912Z,1694171606.912 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-08T11:13:26.912Z,1694171606.912 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-08T11:14:15.523Z,1694171655.523 [DataOverHttps](IMPORTANT): SBD MTMSN=20230908T111415
2023-09-08T11:14:23.021Z,1694171663.021 [DataOverHttps](INFO): Received command: restart sys
2023-09-08T11:14:23.102Z,1694171663.102 [CommandExec](IMPORTANT): got command restart system
2023-09-08T11:14:25.159Z,1694171665.159 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2023-09-08T11:14:25.159Z,1694171665.159 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-08T11:14:25.159Z,1694171665.159 [CommandExec](INFO): Uninitializing the command executive.
2023-09-08T11:14:25.160Z,1694171665.160 [CommandExec](INFO): Uninitializing the command scheduler.
2023-09-08T11:14:25.160Z,1694171665.160 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-09-08T11:14:25.337Z,1694171665.337 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2023-09-08T11:14:25.337Z,1694171665.337 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-09-08T11:14:25.337Z,1694171665.337 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-09-08T11:14:25.338Z,1694171665.338 [NavChartDb](INFO): Join timeout helper Thread ID is 4269
2023-09-08T11:14:25.717Z,1694171665.717 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-08T11:14:25.717Z,1694171665.717 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-09-08T11:14:25.725Z,1694171665.725 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-09-08T11:14:25.725Z,1694171665.725 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-09-08T11:14:25.726Z,1694171665.726 [Radio_Surface](INFO): Join timeout helper Thread ID is 4270
2023-09-08T11:14:26.025Z,1694171666.025 [Radio_Surface](INFO): Powering down
2023-09-08T11:14:26.026Z,1694171666.026 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-08T11:14:26.026Z,1694171666.026 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-09-08T11:14:26.029Z,1694171666.029 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2023-09-08T11:14:26.045Z,1694171666.045 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-09-08T11:14:26.045Z,1694171666.045 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-09-08T11:14:26.046Z,1694171666.046 [Onboard](INFO): Join timeout helper Thread ID is 4271
2023-09-08T11:14:27.521Z,1694171667.521 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-08T11:14:27.522Z,1694171667.522 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-09-08T11:14:27.529Z,1694171667.529 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-09-08T11:14:27.529Z,1694171667.529 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-09-08T11:14:27.530Z,1694171667.530 [DataOverHttps](INFO): Join timeout helper Thread ID is 4272
2023-09-08T11:14:28.037Z,1694171668.037 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-08T11:14:28.038Z,1694171668.038 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-09-08T11:14:28.041Z,1694171668.041 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2023-09-08T11:14:28.041Z,1694171668.041 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-09-08T11:14:28.041Z,1694171668.041 [BackseatComponent](INFO): Join timeout helper Thread ID is 4273
2023-09-08T11:14:28.077Z,1694171668.077 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-08T11:14:28.078Z,1694171668.078 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-09-08T11:14:28.082Z,1694171668.082 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-09-08T11:14:28.083Z,1694171668.083 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-09-08T11:14:28.083Z,1694171668.083 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 4274
2023-09-08T11:14:28.317Z,1694171668.317 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-08T11:14:28.318Z,1694171668.318 [WetLabsBB2FL](INFO): Powering down
2023-09-08T11:14:28.320Z,1694171668.320 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-09-08T11:14:28.326Z,1694171668.326 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-09-08T11:14:28.326Z,1694171668.326 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-09-08T11:14:28.326Z,1694171668.326 [CTD_Seabird](INFO): Join timeout helper Thread ID is 4275
2023-09-08T11:14:28.745Z,1694171668.745 [CTD_Seabird](INFO): Powering down
2023-09-08T11:14:28.757Z,1694171668.757 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-08T11:14:28.757Z,1694171668.757 [CTD_Seabird](INFO): Powering down
2023-09-08T11:14:28.773Z,1694171668.773 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-09-08T11:14:28.782Z,1694171668.782 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-09-08T11:14:28.782Z,1694171668.782 [logger ThreadHandler](INFO): Thread cancelled.
2023-09-08T11:14:28.782Z,1694171668.782 [logger](INFO): Join timeout helper Thread ID is 4276
2023-09-08T11:14:28.801Z,1694171668.801 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-08T11:14:28.802Z,1694171668.802 [logger ThreadHandler](INFO): Thread cancelled.
2023-09-08T11:14:28.822Z,1694171668.822 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-09-08T11:14:28.822Z,1694171668.822 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-09-08T11:14:28.822Z,1694171668.822 [CommandLine](INFO): Join timeout helper Thread ID is 4277
2023-09-08T11:14:28.881Z,1694171668.881 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-08T11:14:28.882Z,1694171668.882 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-09-08T11:14:28.902Z,1694171668.902 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-09-08T11:14:28.902Z,1694171668.902 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-09-08T11:14:28.902Z,1694171668.902 [CommandExec](INFO): Join timeout helper Thread ID is 4278
2023-09-08T11:14:28.903Z,1694171668.903 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-09-08T11:14:28.903Z,1694171668.903 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-09-08T11:14:28.904Z,1694171668.904 [controlThread](INFO): Join timeout helper Thread ID is 4279
2023-09-08T11:14:29.069Z,1694171669.069 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-08T11:14:29.069Z,1694171669.069 [controlThread](DEBUG): Uninitializing ControlThread
2023-09-08T11:14:29.071Z,1694171669.071 [AHRS_M2](INFO): Powering down
2023-09-08T11:14:29.237Z,1694171669.237 [DVL_micro](INFO): Powering down
2023-09-08T11:14:29.238Z,1694171669.238 [NAL9602](INFO): Powering down
2023-09-08T11:14:29.239Z,1694171669.239 [Sonardyne_Nano](INFO): Powering down
2023-09-08T11:14:29.358Z,1694171669.358 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-09-08T11:14:29.358Z,1694171669.358 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-09-08T11:14:29.359Z,1694171669.359 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-09-08T11:14:29.360Z,1694171669.360 [MissionManager](INFO): Uninitializing Mission Default
2023-09-08T11:14:29.360Z,1694171669.360 [Default] Stopped
2023-09-08T11:14:29.360Z,1694171669.360 [Default](DEBUG): Aggregate::uninitialize Default
2023-09-08T11:14:29.360Z,1694171669.360 [Default:B.GoToSurface] Stopped
2023-09-08T11:14:29.360Z,1694171669.360 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-09-08T11:14:29.360Z,1694171669.360 [Default:CheckIn] Stopped
2023-09-08T11:14:29.360Z,1694171669.360 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-08T11:14:29.360Z,1694171669.360 [Default:CheckIn:C.Wait] Stopped
2023-09-08T11:14:29.360Z,1694171669.360 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-08T11:14:29.363Z,1694171669.363 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2023-09-08T11:14:29.363Z,1694171669.363 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2023-09-08T11:14:29.364Z,1694171669.364 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2023-09-08T11:14:29.364Z,1694171669.364 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2023-09-08T11:14:29.364Z,1694171669.364 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2023-09-08T11:14:29.365Z,1694171669.365 [BuoyancyServo](INFO): Powering down
2023-09-08T11:14:29.377Z,1694171669.377 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-09-08T11:14:29.377Z,1694171669.377 [ElevatorServo](INFO): Powering down
2023-09-08T11:14:29.378Z,1694171669.378 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-09-08T11:14:29.378Z,1694171669.378 [MassServo](INFO): Powering down
2023-09-08T11:14:29.379Z,1694171669.379 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-09-08T11:14:29.380Z,1694171669.380 [RudderServo](INFO): Powering down
2023-09-08T11:14:29.380Z,1694171669.380 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2023-09-08T11:14:29.381Z,1694171669.381 [ThrusterHE](INFO): Powering down
2023-09-08T11:14:29.382Z,1694171669.382 [SBIT](DEBUG): Uninitialize SBIT Component.
2023-09-08T11:14:29.382Z,1694171669.382 [IBIT](DEBUG): Uninitialize IBIT Component.
2023-09-08T11:14:29.382Z,1694171669.382 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-09-08T11:14:29.383Z,1694171669.383 [CBIT](DEBUG): Powering off loads.
2023-09-08T11:14:29.397Z,1694171669.397 [CBIT](DEBUG): Disabling WDT.
2023-09-08T11:14:29.409Z,1694171669.409 [CBIT](DEBUG): Opening all GF detection circuits.
2023-09-08T11:14:29.410Z,1694171669.410 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-09-08T11:14:29.455Z,1694171669.455 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-09-08T11:14:29.461Z,1694171669.461 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-09-08T11:14:29.493Z,1694171669.493 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-09-08T11:14:29.495Z,1694171669.495 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-09-08T11:14:29.503Z,1694171669.503 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-09-08T11:14:29.550Z,1694171669.550 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-09-08T11:14:29.603Z,1694171669.603 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-09-08T11:14:29.666Z,1694171669.666 [logger ThreadHandler](INFO): Thread cancelled.