2021-04-30T18:37:14.782Z,1619807834.782 [Supervisor](DEBUG): Initializing supervisor.
2021-04-30T18:37:14.787Z,1619807834.787 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2021-04-30T18:37:14.788Z,1619807834.788 [SyncHandler](INFO): Protected caller Thread ID is 5779
2021-04-30T18:37:14.788Z,1619807834.788 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2021-04-30T18:37:14.789Z,1619807834.789 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2021-04-30T18:37:14.790Z,1619807834.790 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 5780
2021-04-30T18:37:14.794Z,1619807834.794 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2021-04-30T18:37:14.814Z,1619807834.814 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2021-04-30T18:37:14.815Z,1619807834.815 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2021-04-30T18:37:14.815Z,1619807834.815 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 5781
2021-04-30T18:37:14.817Z,1619807834.817 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2021-04-30T18:37:14.818Z,1619807834.818 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2021-04-30T18:37:14.819Z,1619807834.819 [logger ThreadHandler](INFO): Protected caller Thread ID is 5782
2021-04-30T18:37:14.822Z,1619807834.822 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2021-04-30T18:37:14.823Z,1619807834.823 [Supervisor](INFO): Looking for Config files in directory: Config/
2021-04-30T18:37:14.824Z,1619807834.824 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2021-04-30T18:37:15.226Z,1619807835.226 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2021-04-30T18:37:15.227Z,1619807835.227 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2021-04-30T18:37:15.436Z,1619807835.436 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2021-04-30T18:37:15.437Z,1619807835.437 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2021-04-30T18:37:15.637Z,1619807835.637 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2021-04-30T18:37:15.638Z,1619807835.638 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2021-04-30T18:37:15.736Z,1619807835.736 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2021-04-30T18:37:15.737Z,1619807835.737 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2021-04-30T18:37:16.090Z,1619807836.090 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2021-04-30T18:37:16.091Z,1619807836.091 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2021-04-30T18:37:16.437Z,1619807836.437 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2021-04-30T18:37:16.438Z,1619807836.438 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2021-04-30T18:37:16.533Z,1619807836.533 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2021-04-30T18:37:16.534Z,1619807836.534 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2021-04-30T18:37:16.682Z,1619807836.682 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2021-04-30T18:37:16.683Z,1619807836.683 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2021-04-30T18:37:17.213Z,1619807837.213 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2021-04-30T18:37:17.214Z,1619807837.214 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2021-04-30T18:37:17.572Z,1619807837.572 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2021-04-30T18:37:17.573Z,1619807837.573 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2021-04-30T18:37:17.652Z,1619807837.652 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2021-04-30T18:37:17.934Z,1619807837.934 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2021-04-30T18:37:17.934Z,1619807837.934 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2021-04-30T18:37:18.060Z,1619807838.060 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2021-04-30T18:37:18.060Z,1619807838.060 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2021-04-30T18:37:18.714Z,1619807838.714 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2021-04-30T18:37:18.715Z,1619807838.715 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2021-04-30T18:37:18.794Z,1619807838.794 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2021-04-30T18:37:19.043Z,1619807839.043 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2021-04-30T18:37:19.045Z,1619807839.045 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/
2021-04-30T18:37:19.045Z,1619807839.045 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg
2021-04-30T18:37:19.216Z,1619807839.216 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg
2021-04-30T18:37:19.311Z,1619807839.311 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg
2021-04-30T18:37:19.420Z,1619807839.420 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg
2021-04-30T18:37:19.520Z,1619807839.520 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg
2021-04-30T18:37:19.602Z,1619807839.602 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg
2021-04-30T18:37:19.687Z,1619807839.687 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg
2021-04-30T18:37:19.892Z,1619807839.892 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg
2021-04-30T18:37:19.991Z,1619807839.991 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg
2021-04-30T18:37:20.371Z,1619807840.371 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg
2021-04-30T18:37:20.850Z,1619807840.850 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2021-04-30T18:37:20.851Z,1619807840.851 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg
2021-04-30T18:37:20.945Z,1619807840.945 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/root/
2021-04-30T18:37:20.945Z,1619807840.945 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2021-04-30T18:37:20.956Z,1619807840.956 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2021-04-30T18:37:21.288Z,1619807841.288 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2021-04-30T18:37:21.289Z,1619807841.289 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2021-04-30T18:37:21.370Z,1619807841.370 [DeadReckonUsingMultipleVelocitySources] Loaded
2021-04-30T18:37:21.370Z,1619807841.370 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2021-04-30T18:37:21.404Z,1619807841.404 [DeadReckonUsingSpeedCalculator] Loaded
2021-04-30T18:37:21.404Z,1619807841.404 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2021-04-30T18:37:21.417Z,1619807841.417 [NavChart] Loaded
2021-04-30T18:37:21.417Z,1619807841.417 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2021-04-30T18:37:21.423Z,1619807841.423 [UniversalFixResidualReporter] Loaded
2021-04-30T18:37:21.423Z,1619807841.423 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2021-04-30T18:37:21.424Z,1619807841.424 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2021-04-30T18:37:21.424Z,1619807841.424 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2021-04-30T18:37:21.436Z,1619807841.436 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2021-04-30T18:37:21.436Z,1619807841.436 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2021-04-30T18:37:21.553Z,1619807841.553 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2021-04-30T18:37:21.554Z,1619807841.554 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2021-04-30T18:37:21.608Z,1619807841.608 [VerticalControl](DEBUG): Construct VerticalControl.
2021-04-30T18:37:21.668Z,1619807841.668 [VerticalControl] Loaded
2021-04-30T18:37:21.668Z,1619807841.668 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2021-04-30T18:37:21.671Z,1619807841.671 [HorizontalControl](DEBUG): Construct HorizontalControl.
2021-04-30T18:37:21.709Z,1619807841.709 [HorizontalControl] Loaded
2021-04-30T18:37:21.709Z,1619807841.709 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2021-04-30T18:37:21.712Z,1619807841.712 [SpeedControl](DEBUG): Construct SpeedControl.
2021-04-30T18:37:21.715Z,1619807841.715 [SpeedControl] Loaded
2021-04-30T18:37:21.715Z,1619807841.715 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2021-04-30T18:37:21.718Z,1619807841.718 [LoopControl](DEBUG): Construct LoopControl.
2021-04-30T18:37:21.718Z,1619807841.718 [LoopControl] Loaded
2021-04-30T18:37:21.718Z,1619807841.718 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2021-04-30T18:37:21.719Z,1619807841.719 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2021-04-30T18:37:21.720Z,1619807841.720 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2021-04-30T18:37:21.761Z,1619807841.761 [DepthRateCalculator] Loaded
2021-04-30T18:37:21.762Z,1619807841.762 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2021-04-30T18:37:21.766Z,1619807841.766 [PitchRateCalculator] Loaded
2021-04-30T18:37:21.767Z,1619807841.767 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2021-04-30T18:37:21.776Z,1619807841.776 [SpeedCalculator] Loaded
2021-04-30T18:37:21.777Z,1619807841.777 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2021-04-30T18:37:21.791Z,1619807841.791 [TempGradientCalculator] Loaded
2021-04-30T18:37:21.791Z,1619807841.791 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2021-04-30T18:37:21.795Z,1619807841.795 [YawRateCalculator] Loaded
2021-04-30T18:37:21.796Z,1619807841.796 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2021-04-30T18:37:21.813Z,1619807841.813 [ElevatorOffsetCalculator] Loaded
2021-04-30T18:37:21.813Z,1619807841.813 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2021-04-30T18:37:21.814Z,1619807841.814 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2021-04-30T18:37:21.814Z,1619807841.814 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2021-04-30T18:37:21.912Z,1619807841.912 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2021-04-30T18:37:21.913Z,1619807841.913 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2021-04-30T18:37:21.969Z,1619807841.969 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2021-04-30T18:37:21.970Z,1619807841.970 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2021-04-30T18:37:22.095Z,1619807842.095 [BuoyancyServo] Loaded
2021-04-30T18:37:22.095Z,1619807842.095 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2021-04-30T18:37:22.114Z,1619807842.114 [ElevatorServo] Loaded
2021-04-30T18:37:22.114Z,1619807842.114 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2021-04-30T18:37:22.138Z,1619807842.138 [DockingServo] Loaded
2021-04-30T18:37:22.138Z,1619807842.138 [ComponentRegistry](DEBUG): SyncComponent "DockingServo" handled in the control thread.
2021-04-30T18:37:22.157Z,1619807842.157 [RudderServo] Loaded
2021-04-30T18:37:22.157Z,1619807842.157 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2021-04-30T18:37:22.174Z,1619807842.174 [ThrusterServo] Loaded
2021-04-30T18:37:22.174Z,1619807842.174 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2021-04-30T18:37:22.175Z,1619807842.175 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2021-04-30T18:37:22.176Z,1619807842.176 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2021-04-30T18:37:22.282Z,1619807842.282 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2021-04-30T18:37:22.282Z,1619807842.282 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2021-04-30T18:37:22.634Z,1619807842.634 [AHRS_M2] Loaded
2021-04-30T18:37:22.635Z,1619807842.635 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2021-04-30T18:37:23.441Z,1619807843.441 [BPC1] Loaded
2021-04-30T18:37:23.442Z,1619807843.442 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2021-04-30T18:37:23.522Z,1619807843.522 [DataOverHttps] Loaded
2021-04-30T18:37:23.522Z,1619807843.522 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2021-04-30T18:37:23.524Z,1619807843.524 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409AA4E0
2021-04-30T18:37:23.524Z,1619807843.524 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 5865
2021-04-30T18:37:23.544Z,1619807843.544 [Depth_Keller] Loaded
2021-04-30T18:37:23.544Z,1619807843.544 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2021-04-30T18:37:23.604Z,1619807843.604 [NAL9602] Loaded
2021-04-30T18:37:23.604Z,1619807843.604 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2021-04-30T18:37:23.630Z,1619807843.630 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/voltage_now
2021-04-30T18:37:23.631Z,1619807843.631 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/current_now
2021-04-30T18:37:23.632Z,1619807843.632 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_now
2021-04-30T18:37:23.632Z,1619807843.632 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_full
2021-04-30T18:37:23.633Z,1619807843.633 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/voltage_now
2021-04-30T18:37:23.633Z,1619807843.633 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/current_now
2021-04-30T18:37:23.634Z,1619807843.634 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_now
2021-04-30T18:37:23.634Z,1619807843.634 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_full
2021-04-30T18:37:23.634Z,1619807843.634 [Onboard] Loaded
2021-04-30T18:37:23.635Z,1619807843.635 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2021-04-30T18:37:23.636Z,1619807843.636 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409DA4E0
2021-04-30T18:37:23.636Z,1619807843.636 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 5866
2021-04-30T18:37:23.649Z,1619807843.649 [Radio_Surface] Loaded
2021-04-30T18:37:23.649Z,1619807843.649 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2021-04-30T18:37:23.650Z,1619807843.650 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A0A4E0
2021-04-30T18:37:23.651Z,1619807843.651 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 5867
2021-04-30T18:37:23.732Z,1619807843.732 [DAT] Loaded
2021-04-30T18:37:23.732Z,1619807843.732 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2021-04-30T18:37:23.733Z,1619807843.733 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2021-04-30T18:37:23.733Z,1619807843.733 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2021-04-30T18:37:23.887Z,1619807843.887 [SBIT](DEBUG): Construct Startup Built In Test.
2021-04-30T18:37:23.896Z,1619807843.896 [SBIT] Loaded
2021-04-30T18:37:23.897Z,1619807843.897 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2021-04-30T18:37:23.899Z,1619807843.899 [IBIT](DEBUG): Construct Initiated Built In Test.
2021-04-30T18:37:23.913Z,1619807843.913 [IBIT] Loaded
2021-04-30T18:37:23.913Z,1619807843.913 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2021-04-30T18:37:23.918Z,1619807843.918 [CBIT](DEBUG): Construct Continuous Built In Test.
2021-04-30T18:37:24.019Z,1619807844.019 [CBIT] Loaded
2021-04-30T18:37:24.019Z,1619807844.019 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2021-04-30T18:37:24.020Z,1619807844.020 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2021-04-30T18:37:24.020Z,1619807844.020 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2021-04-30T18:37:24.048Z,1619807844.048 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2021-04-30T18:37:24.055Z,1619807844.055 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2021-04-30T18:37:24.057Z,1619807844.057 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2021-04-30T18:37:24.068Z,1619807844.068 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2021-04-30T18:37:24.069Z,1619807844.069 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A954E0
2021-04-30T18:37:24.070Z,1619807844.070 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 5868
2021-04-30T18:37:24.074Z,1619807844.074 [Supervisor](INFO): Main Thread ID is 5778
2021-04-30T18:37:24.075Z,1619807844.075 [Supervisor](DEBUG): Running supervisor.
2021-04-30T18:37:24.075Z,1619807844.075 [CommandLine ThreadHandler](INFO): Handler Thread ID is 5869
2021-04-30T18:37:24.078Z,1619807844.078 [controlThread ThreadHandler](INFO): Handler Thread ID is 5870
2021-04-30T18:37:24.078Z,1619807844.078 [controlThread](DEBUG): Initializing ControlThread
2021-04-30T18:37:24.080Z,1619807844.080 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2021-04-30T18:37:24.080Z,1619807844.080 [NavChart](DEBUG): Initialize NavChart Navigation.
2021-04-30T18:37:24.080Z,1619807844.080 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2021-04-30T18:37:24.081Z,1619807844.081 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2021-04-30T18:37:24.083Z,1619807844.083 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2021-04-30T18:37:24.083Z,1619807844.083 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2021-04-30T18:37:24.084Z,1619807844.084 [LoopControl](DEBUG): Initialize LoopControlComponent.
2021-04-30T18:37:24.084Z,1619807844.084 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2021-04-30T18:37:24.084Z,1619807844.084 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2021-04-30T18:37:24.085Z,1619807844.085 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2021-04-30T18:37:24.085Z,1619807844.085 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2021-04-30T18:37:24.086Z,1619807844.086 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2021-04-30T18:37:24.086Z,1619807844.086 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2021-04-30T18:37:24.092Z,1619807844.092 [SBIT](INFO): Initialize SBIT Component.
2021-04-30T18:37:24.092Z,1619807844.092 [SBIT](IMPORTANT): git: 2021-02-15-23-g3a852c62c
2021-04-30T18:37:24.093Z,1619807844.093 [SBIT](INFO): git hash: 3a852c62cd7a39cad2b788ae0015bb0a43866a82
2021-04-30T18:37:24.093Z,1619807844.093 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2021-04-30T18:37:24.094Z,1619807844.094 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
2021-04-30T18:37:24.095Z,1619807844.095 [SBIT](INFO): Beginning SBIT in 24.000000 seconds.
2021-04-30T18:37:24.096Z,1619807844.096 [IBIT](INFO): Initialize IBIT Component.
2021-04-30T18:37:24.097Z,1619807844.097 [CBIT](DEBUG): Initialize CBIT Component.
2021-04-30T18:37:24.098Z,1619807844.098 [logger ThreadHandler](INFO): Handler Thread ID is 5871
2021-04-30T18:37:24.111Z,1619807844.111 [CBIT](DEBUG): Initialized mux pins.
2021-04-30T18:37:24.111Z,1619807844.111 [CBIT](DEBUG): Initializing the watchdog timer.
2021-04-30T18:37:24.116Z,1619807844.116 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 5872
2021-04-30T18:37:24.117Z,1619807844.117 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2021-04-30T18:37:24.124Z,1619807844.124 [Onboard ThreadHandler](INFO): Handler Thread ID is 5873
2021-04-30T18:37:24.135Z,1619807844.135 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2021-04-30T18:37:24.135Z,1619807844.135 [CBIT](DEBUG): Initializing heartbeat.
2021-04-30T18:37:24.140Z,1619807844.140 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 5874
2021-04-30T18:37:24.148Z,1619807844.148 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 5875
2021-04-30T18:37:24.151Z,1619807844.151 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2021-04-30T18:37:24.151Z,1619807844.151 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2021-04-30T18:37:24.151Z,1619807844.151 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2021-04-30T18:37:24.152Z,1619807844.152 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2021-04-30T18:37:24.152Z,1619807844.152 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2021-04-30T18:37:24.152Z,1619807844.152 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2021-04-30T18:37:24.152Z,1619807844.152 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2021-04-30T18:37:24.152Z,1619807844.152 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2021-04-30T18:37:24.152Z,1619807844.152 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2021-04-30T18:37:24.153Z,1619807844.153 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2021-04-30T18:37:24.153Z,1619807844.153 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2021-04-30T18:37:24.153Z,1619807844.153 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2021-04-30T18:37:24.153Z,1619807844.153 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2021-04-30T18:37:24.153Z,1619807844.153 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2021-04-30T18:37:24.153Z,1619807844.153 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2021-04-30T18:37:24.154Z,1619807844.154 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2021-04-30T18:37:24.207Z,1619807844.207 [CBIT](DEBUG): Deactivating GF circuits.
2021-04-30T18:37:24.207Z,1619807844.207 [CBIT](DEBUG): Deactivating emergency mode.
2021-04-30T18:37:24.243Z,1619807844.243 [CBIT](DEBUG): Backplane powered.
2021-04-30T18:37:24.244Z,1619807844.244 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2021-04-30T18:37:24.256Z,1619807844.256 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2021-04-30T18:37:24.284Z,1619807844.284 [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>


2021-04-30T18:37:24.285Z,1619807844.285 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2021-04-30T18:37:24.357Z,1619807844.357 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2021-04-30T18:37:24.359Z,1619807844.359 [Default:A.Wait](DEBUG): Construct Wait.
2021-04-30T18:37:24.361Z,1619807844.361 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2021-04-30T18:37:24.394Z,1619807844.394 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2021-04-30T18:37:24.420Z,1619807844.420 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2021-04-30T18:37:24.428Z,1619807844.428 [Default:E.Execute](DEBUG): Construct Execute.
2021-04-30T18:37:24.462Z,1619807844.462 [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="P2H">
                <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>


2021-04-30T18:37:24.467Z,1619807844.467 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,NAL9602,DAT,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,DockingServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2021-04-30T18:37:24.487Z,1619807844.487 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2021-04-30T18:37:24.543Z,1619807844.543 [Radio_Surface](INFO): Powering up
2021-04-30T18:37:24.554Z,1619807844.554 [DAT](INFO): Powering up
2021-04-30T18:37:24.554Z,1619807844.554 [DAT](DEBUG): Initializing DAT.
2021-04-30T18:37:24.593Z,1619807844.593 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2021-04-30T18:37:24.600Z,1619807844.600 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2021-04-30T18:37:24.601Z,1619807844.601 [ElevatorServo](DEBUG): Initializing EZServoServo.
2021-04-30T18:37:24.612Z,1619807844.612 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2021-04-30T18:37:24.612Z,1619807844.612 [DockingServo](INFO): Start
2021-04-30T18:37:24.613Z,1619807844.613 [DockingServo](DEBUG): Initializing EZServoServo.
2021-04-30T18:37:24.620Z,1619807844.620 [DockingServo](DEBUG): Initializing DockingServo.
2021-04-30T18:37:24.620Z,1619807844.620 [RudderServo](DEBUG): Initializing EZServoServo.
2021-04-30T18:37:24.628Z,1619807844.628 [RudderServo](DEBUG): Initializing RudderServo.
2021-04-30T18:37:24.628Z,1619807844.628 [ThrusterServo](DEBUG): Initializing EZServoServo.
2021-04-30T18:37:24.639Z,1619807844.639 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2021-04-30T18:37:24.904Z,1619807844.904 [DockingServo](INFO): Starting
2021-04-30T18:37:25.291Z,1619807845.291 [DockingServo](INFO): Starting
2021-04-30T18:37:25.595Z,1619807845.595 [DockingServo](ERROR): Docking arm initialization uart error serial timeout
2021-04-30T18:37:25.595Z,1619807845.595 [DockingServo](FAULT): Failed to initialize
2021-04-30T18:37:25.595Z,1619807845.595 [DockingServo] Communications Fault, FailCount= 1
2021-04-30T18:37:25.596Z,1619807845.596 [DockingServo](ERROR): Communications Fault
2021-04-30T18:37:25.743Z,1619807845.743 [CBIT](ERROR): Communications Fault in component: DockingServo
2021-04-30T18:37:25.912Z,1619807845.912 [DockingServo](INFO): Stop
2021-04-30T18:37:25.912Z,1619807845.912 [DockingServo](DEBUG): Uninitialize Docking Servo.
2021-04-30T18:37:25.912Z,1619807845.912 [DockingServo](INFO): Powering down
2021-04-30T18:37:26.167Z,1619807846.167 [DockingServo](INFO): Stopping
2021-04-30T18:37:26.571Z,1619807846.571 [DockingServo](INFO): Stopped
2021-04-30T18:37:26.572Z,1619807846.572 [DockingServo](INFO): Is needed returning:1
2021-04-30T18:37:26.572Z,1619807846.572 [DockingServo](INFO): Start
2021-04-30T18:37:26.572Z,1619807846.572 [DockingServo](DEBUG): Initializing EZServoServo.
2021-04-30T18:37:26.692Z,1619807846.692 [DockingServo](DEBUG): Initializing DockingServo.
2021-04-30T18:37:26.696Z,1619807846.696 [CBIT](INFO): Clearing failed state for component DockingServo
2021-04-30T18:37:26.697Z,1619807846.697 [DockingServo] No Fault, FailCount= 1
2021-04-30T18:37:26.975Z,1619807846.975 [DockingServo](INFO): Starting
2021-04-30T18:37:27.432Z,1619807847.432 [DockingServo](INFO): Starting
2021-04-30T18:37:27.743Z,1619807847.743 [DockingServo](ERROR): Docking arm initialization uart error serial timeout
2021-04-30T18:37:27.743Z,1619807847.743 [DockingServo](FAULT): Failed to initialize
2021-04-30T18:37:27.743Z,1619807847.743 [DockingServo] Communications Fault, FailCount= 2
2021-04-30T18:37:27.743Z,1619807847.743 [DockingServo](ERROR): Communications Fault
2021-04-30T18:37:27.771Z,1619807847.771 [CBIT](ERROR): Communications Fault in component: DockingServo
2021-04-30T18:37:27.863Z,1619807847.863 [DockingServo](INFO): Stop
2021-04-30T18:37:27.863Z,1619807847.863 [DockingServo](DEBUG): Uninitialize Docking Servo.
2021-04-30T18:37:27.864Z,1619807847.864 [DockingServo](INFO): Powering down
2021-04-30T18:37:28.293Z,1619807848.293 [DockingServo](INFO): Stopping
2021-04-30T18:37:28.640Z,1619807848.640 [DockingServo](INFO): Stopped
2021-04-30T18:37:28.641Z,1619807848.641 [DockingServo](INFO): Is needed returning:1
2021-04-30T18:37:28.641Z,1619807848.641 [DockingServo](INFO): Start
2021-04-30T18:37:28.641Z,1619807848.641 [DockingServo](DEBUG): Initializing EZServoServo.
2021-04-30T18:37:28.760Z,1619807848.760 [DockingServo](DEBUG): Initializing DockingServo.
2021-04-30T18:37:29.003Z,1619807849.003 [CBIT](INFO): Clearing failed state for component DockingServo
2021-04-30T18:37:29.003Z,1619807849.003 [DockingServo] No Fault, FailCount= 2
2021-04-30T18:37:29.101Z,1619807849.101 [DockingServo](INFO): Starting
2021-04-30T18:37:29.452Z,1619807849.452 [DockingServo](INFO): Starting
2021-04-30T18:37:29.763Z,1619807849.763 [DockingServo](ERROR): Docking arm initialization uart error serial timeout
2021-04-30T18:37:29.763Z,1619807849.763 [DockingServo](FAULT): Failed to initialize
2021-04-30T18:37:29.763Z,1619807849.763 [DockingServo] Communications Fault, FailCount= 3
2021-04-30T18:37:29.763Z,1619807849.763 [DockingServo](ERROR): Communications Fault
2021-04-30T18:37:29.768Z,1619807849.768 [CBIT](ERROR): Communications Fault in component: DockingServo
2021-04-30T18:37:29.768Z,1619807849.768 [CBIT](CRITICAL): Communications Fault in component: DockingServo
2021-04-30T18:37:29.856Z,1619807849.856 [DockingServo](INFO): Stop
2021-04-30T18:37:29.856Z,1619807849.856 [DockingServo](DEBUG): Uninitialize Docking Servo.
2021-04-30T18:37:29.856Z,1619807849.856 [DockingServo](INFO): Powering down
2021-04-30T18:37:29.859Z,1619807849.859 [CommandLine](FAULT): Scheduling is paused
2021-04-30T18:37:29.875Z,1619807849.875 [CBIT](INFO): Critical error at 20210430T183729
2021-04-30T18:37:29.875Z,1619807849.875 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2021-04-30T18:37:30.392Z,1619807850.392 [DockingServo](INFO): Stopping
2021-04-30T18:37:30.664Z,1619807850.664 [DockingServo](INFO): Stopped
2021-04-30T18:37:30.664Z,1619807850.664 [DockingServo](INFO): Is needed returning:1
2021-04-30T18:37:30.664Z,1619807850.664 [DockingServo](INFO): Start
2021-04-30T18:37:30.665Z,1619807850.665 [DockingServo](DEBUG): Initializing EZServoServo.
2021-04-30T18:37:30.784Z,1619807850.784 [DockingServo](DEBUG): Initializing DockingServo.
2021-04-30T18:37:37.302Z,1619807857.302 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004713
2021-04-30T18:37:39.114Z,1619807859.114 [DAT](INFO): commRate: 800
2021-04-30T18:37:41.137Z,1619807861.137 [DAT](INFO): entering command mode
2021-04-30T18:37:41.537Z,1619807861.537 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-30T18:37:41.941Z,1619807861.941 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-30T18:37:42.349Z,1619807862.349 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-30T18:37:42.753Z,1619807862.753 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-30T18:37:43.153Z,1619807863.153 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-30T18:37:43.561Z,1619807863.561 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-30T18:37:43.961Z,1619807863.961 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-30T18:37:44.365Z,1619807864.365 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-30T18:37:44.769Z,1619807864.769 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-30T18:37:45.173Z,1619807865.173 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-30T18:37:45.577Z,1619807865.577 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-30T18:37:45.993Z,1619807865.993 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-30T18:37:46.385Z,1619807866.385 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-30T18:37:46.789Z,1619807866.789 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-30T18:37:47.193Z,1619807867.193 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-30T18:37:47.597Z,1619807867.597 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-30T18:37:48.008Z,1619807868.008 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-30T18:37:48.405Z,1619807868.405 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-30T18:37:48.809Z,1619807868.809 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-30T18:37:48.826Z,1619807868.826 [SBIT](IMPORTANT): Beginning Startup BIT
2021-04-30T18:37:48.830Z,1619807868.830 [CBIT](IMPORTANT): Beginning ground fault scan
2021-04-30T18:37:49.217Z,1619807869.217 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-30T18:37:49.617Z,1619807869.617 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-30T18:37:50.021Z,1619807870.021 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-30T18:37:50.425Z,1619807870.425 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-30T18:37:50.833Z,1619807870.833 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-30T18:37:51.233Z,1619807871.233 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-30T18:37:51.437Z,1619807871.437 [CommandLine](IMPORTANT): got command configSet list
2021-04-30T18:37:51.437Z,1619807871.437 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2021-04-30T18:37:51.438Z,1619807871.438 [CommandLine](IMPORTANT): CTD_NeilBrown.loadAtStartup=0 bool;

2021-04-30T18:37:51.438Z,1619807871.438 [CommandLine](IMPORTANT): CTD_Seabird.loadAtStartup=0 bool;

2021-04-30T18:37:51.438Z,1619807871.438 [CommandLine](IMPORTANT): DAT.loadAtStartup=1 bool;

2021-04-30T18:37:51.438Z,1619807871.438 [CommandLine](IMPORTANT): DockingServo.loadAtStartup=1 bool;

2021-04-30T18:37:51.439Z,1619807871.439 [CommandLine](IMPORTANT): DockingServo.simulateHardware=0 bool;

2021-04-30T18:37:51.439Z,1619807871.439 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2021-04-30T18:37:51.439Z,1619807871.439 [CommandLine](IMPORTANT): MassServo.loadAtStartup=0 bool;

2021-04-30T18:37:51.439Z,1619807871.439 [CommandLine](IMPORTANT): NAL9602.fastGPSFix=1 bool;

2021-04-30T18:37:51.439Z,1619807871.439 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=235 cubic_centimeter;

2021-04-30T18:37:51.439Z,1619807871.439 [CommandLine](IMPORTANT): VerticalControl.massDefault=-1 millimeter;

2021-04-30T18:37:51.440Z,1619807871.440 [CommandLine](IMPORTANT): WetLabsBB2FL.loadAtStartup=0 bool;

2021-04-30T18:37:51.637Z,1619807871.637 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-30T18:37:52.053Z,1619807872.053 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-30T18:37:52.445Z,1619807872.445 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-30T18:37:52.849Z,1619807872.849 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-30T18:37:53.253Z,1619807873.253 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-30T18:37:53.663Z,1619807873.663 [NAL9602](INFO): Powering up NAL9602
2021-04-30T18:37:53.664Z,1619807873.664 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-30T18:37:54.061Z,1619807874.061 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-30T18:37:54.465Z,1619807874.465 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-30T18:37:54.869Z,1619807874.869 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-30T18:37:55.280Z,1619807875.280 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-30T18:37:55.677Z,1619807875.677 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-30T18:37:56.081Z,1619807876.081 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-30T18:37:56.485Z,1619807876.485 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-30T18:37:56.485Z,1619807876.485 [DAT](FAULT): failed to enter command mode
2021-04-30T18:37:56.897Z,1619807876.897 [DAT](INFO): entering command mode
2021-04-30T18:37:57.294Z,1619807877.294 [DAT](INFO): setting verbose to 3
2021-04-30T18:37:57.698Z,1619807877.698 [DAT](INFO): set verbose to 3
2021-04-30T18:37:57.698Z,1619807877.698 [DAT](INFO): setting DatVerbose to 27440
2021-04-30T18:37:58.110Z,1619807878.110 [DAT](INFO): set DatVerbose to 27440
2021-04-30T18:37:58.110Z,1619807878.110 [DAT](INFO): setting transmit power to 8
2021-04-30T18:37:58.512Z,1619807878.512 [DAT](INFO): set transmit power to 8
2021-04-30T18:37:58.512Z,1619807878.512 [DAT](INFO): setting local address to 10
2021-04-30T18:37:58.910Z,1619807878.910 [DAT](INFO): set local address to 10
2021-04-30T18:37:59.749Z,1619807879.749 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.007482
CHAN A1 (24V): 0.177830
CHAN A2 (12V): -0.005800
CHAN A3 (5V): -0.003475
CHAN B0 (3.3V): -0.000996
CHAN B1 (3.15aV): -0.001140
CHAN B2 (3.15bV): -0.001435
CHAN B3 (GND): -0.000581
OPEN: 0.004421
Full Scale Calc: 4.765 mA, -1.589 mA
2021-04-30T18:38:02.154Z,1619807882.154 [SBIT](ERROR): Could not read massPosReader_.
2021-04-30T18:38:04.573Z,1619807884.573 [NAL9602](INFO): NAL9602 initialized
2021-04-30T18:38:23.753Z,1619807903.753 [CommandLine](IMPORTANT): got command configSet MassServo.loadAtStartup 1.000000 bool persist
2021-04-30T18:38:23.754Z,1619807903.754 [CommandLine](IMPORTANT): configSet MassServo.loadAtStartup requires a restart to take effect.
2021-04-30T18:38:27.873Z,1619807907.873 [CommandLine](IMPORTANT): got command quit
2021-04-30T18:38:28.842Z,1619807908.842 [SBIT](ERROR): Could not read massPosReader_.
2021-04-30T18:38:28.842Z,1619807908.842 [SBIT](FAULT): Control surface position failure.
2021-04-30T18:38:28.880Z,1619807908.880 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-30T18:38:28.880Z,1619807908.880 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-04-30T18:38:28.939Z,1619807908.939 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2021-04-30T18:38:28.939Z,1619807908.939 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-04-30T18:38:28.940Z,1619807908.940 [CommandLine](INFO): Join timeout helper Thread ID is 5889
2021-04-30T18:38:28.940Z,1619807908.940 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2021-04-30T18:38:28.941Z,1619807908.941 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-04-30T18:38:28.941Z,1619807908.941 [NavChartDb](INFO): Join timeout helper Thread ID is 5890
2021-04-30T18:38:29.068Z,1619807909.068 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-30T18:38:29.068Z,1619807909.068 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-04-30T18:38:29.083Z,1619807909.083 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2021-04-30T18:38:29.083Z,1619807909.083 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-04-30T18:38:29.084Z,1619807909.084 [Radio_Surface](INFO): Join timeout helper Thread ID is 5891
2021-04-30T18:38:29.184Z,1619807909.184 [Radio_Surface](INFO): Powering down
2021-04-30T18:38:29.185Z,1619807909.185 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-30T18:38:29.185Z,1619807909.185 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-04-30T18:38:29.186Z,1619807909.186 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2021-04-30T18:38:29.187Z,1619807909.187 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-04-30T18:38:29.190Z,1619807909.190 [Onboard](INFO): Join timeout helper Thread ID is 5892
2021-04-30T18:38:29.192Z,1619807909.192 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-30T18:38:29.192Z,1619807909.192 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-04-30T18:38:29.192Z,1619807909.192 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2021-04-30T18:38:29.193Z,1619807909.193 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-04-30T18:38:29.193Z,1619807909.193 [DataOverHttps](INFO): Join timeout helper Thread ID is 5893
2021-04-30T18:38:29.892Z,1619807909.892 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-30T18:38:29.892Z,1619807909.892 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-04-30T18:38:29.900Z,1619807909.900 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2021-04-30T18:38:29.900Z,1619807909.900 [logger ThreadHandler](INFO): Thread cancelled.
2021-04-30T18:38:29.901Z,1619807909.901 [logger](INFO): Join timeout helper Thread ID is 5894
2021-04-30T18:38:29.943Z,1619807909.943 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-30T18:38:29.944Z,1619807909.944 [logger ThreadHandler](INFO): Thread cancelled.
2021-04-30T18:38:29.960Z,1619807909.960 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2021-04-30T18:38:29.960Z,1619807909.960 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-04-30T18:38:29.960Z,1619807909.960 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2021-04-30T18:38:29.960Z,1619807909.960 [controlThread ThreadHandler](INFO): Thread cancelled.
2021-04-30T18:38:29.961Z,1619807909.961 [controlThread](INFO): Join timeout helper Thread ID is 5895
2021-04-30T18:38:30.007Z,1619807910.007 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-30T18:38:30.008Z,1619807910.008 [controlThread](DEBUG): Uninitializing ControlThread
2021-04-30T18:38:30.008Z,1619807910.008 [AHRS_M2](INFO): Powering down
2021-04-30T18:38:30.080Z,1619807910.080 [NAL9602](INFO): Powering down
2021-04-30T18:38:30.082Z,1619807910.082 [DAT](INFO): Powering down
2021-04-30T18:38:30.200Z,1619807910.200 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2021-04-30T18:38:30.201Z,1619807910.201 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2021-04-30T18:38:30.201Z,1619807910.201 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2021-04-30T18:38:30.202Z,1619807910.202 [MissionManager](INFO): Uninitializing Mission Default
2021-04-30T18:38:30.204Z,1619807910.204 [MissionManager](INFO): Uninitializing Mission Startup
2021-04-30T18:38:30.206Z,1619807910.206 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2021-04-30T18:38:30.206Z,1619807910.206 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2021-04-30T18:38:30.206Z,1619807910.206 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2021-04-30T18:38:30.207Z,1619807910.207 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2021-04-30T18:38:30.207Z,1619807910.207 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2021-04-30T18:38:30.207Z,1619807910.207 [BuoyancyServo](INFO): Powering down
2021-04-30T18:38:30.219Z,1619807910.219 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2021-04-30T18:38:30.219Z,1619807910.219 [ElevatorServo](INFO): Powering down
2021-04-30T18:38:30.220Z,1619807910.220 [DockingServo](DEBUG): Uninitialize Docking Servo.
2021-04-30T18:38:30.220Z,1619807910.220 [DockingServo](INFO): Powering down
2021-04-30T18:38:30.221Z,1619807910.221 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2021-04-30T18:38:30.221Z,1619807910.221 [RudderServo](INFO): Powering down
2021-04-30T18:38:30.222Z,1619807910.222 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2021-04-30T18:38:30.222Z,1619807910.222 [ThrusterServo](INFO): Powering down
2021-04-30T18:38:30.223Z,1619807910.223 [SBIT](DEBUG): Uninitialize SBIT Component.
2021-04-30T18:38:30.223Z,1619807910.223 [IBIT](DEBUG): Uninitialize IBIT Component.
2021-04-30T18:38:30.224Z,1619807910.224 [CBIT](DEBUG): Uninitialize CBIT Component.
2021-04-30T18:38:30.224Z,1619807910.224 [CBIT](DEBUG): Powering off loads.
2021-04-30T18:38:30.235Z,1619807910.235 [CBIT](DEBUG): Disabling WDT.
2021-04-30T18:38:30.247Z,1619807910.247 [CBIT](DEBUG): Opening all GF detection circuits.
2021-04-30T18:38:30.248Z,1619807910.248 [controlThread ThreadHandler](INFO): Thread cancelled.
2021-04-30T18:38:30.442Z,1619807910.442 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-04-30T18:38:30.444Z,1619807910.444 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-04-30T18:38:30.449Z,1619807910.449 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-04-30T18:38:30.528Z,1619807910.528 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-04-30T18:38:30.587Z,1619807910.587 [logger ThreadHandler](INFO): Thread cancelled.