2020-09-03T16:37:05.058Z,1599151025.058 [Supervisor](DEBUG): Initializing supervisor.
2020-09-03T16:37:05.062Z,1599151025.062 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-09-03T16:37:05.062Z,1599151025.062 [SyncHandler](INFO): Protected caller Thread ID is 811
2020-09-03T16:37:05.063Z,1599151025.063 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-09-03T16:37:05.064Z,1599151025.064 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-09-03T16:37:05.064Z,1599151025.064 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 812
2020-09-03T16:37:05.067Z,1599151025.067 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-09-03T16:37:05.081Z,1599151025.081 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-09-03T16:37:05.082Z,1599151025.082 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-09-03T16:37:05.082Z,1599151025.082 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 813
2020-09-03T16:37:05.084Z,1599151025.084 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-09-03T16:37:05.085Z,1599151025.085 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-09-03T16:37:05.085Z,1599151025.085 [logger ThreadHandler](INFO): Protected caller Thread ID is 814
2020-09-03T16:37:05.088Z,1599151025.088 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-09-03T16:37:05.088Z,1599151025.088 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-09-03T16:37:05.092Z,1599151025.092 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-09-03T16:37:05.594Z,1599151025.594 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-09-03T16:37:05.596Z,1599151025.596 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2020-09-03T16:37:05.795Z,1599151025.795 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2020-09-03T16:37:05.796Z,1599151025.796 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-09-03T16:37:05.996Z,1599151025.996 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-09-03T16:37:05.998Z,1599151025.998 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-09-03T16:37:06.138Z,1599151026.138 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-09-03T16:37:06.140Z,1599151026.140 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-09-03T16:37:06.441Z,1599151026.441 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-09-03T16:37:06.443Z,1599151026.443 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-09-03T16:37:06.775Z,1599151026.775 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-09-03T16:37:06.775Z,1599151026.775 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-09-03T16:37:06.872Z,1599151026.872 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-09-03T16:37:06.873Z,1599151026.873 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-09-03T16:37:07.022Z,1599151027.022 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-09-03T16:37:07.022Z,1599151027.022 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-09-03T16:37:07.498Z,1599151027.498 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-09-03T16:37:07.500Z,1599151027.500 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-09-03T16:37:07.920Z,1599151027.920 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-09-03T16:37:07.922Z,1599151027.922 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-09-03T16:37:08.007Z,1599151028.007 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-09-03T16:37:08.531Z,1599151028.531 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-09-03T16:37:08.532Z,1599151028.532 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-09-03T16:37:08.637Z,1599151028.637 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-09-03T16:37:08.638Z,1599151028.638 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-09-03T16:37:09.317Z,1599151029.317 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-09-03T16:37:09.318Z,1599151029.318 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-09-03T16:37:09.420Z,1599151029.420 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-09-03T16:37:09.421Z,1599151029.421 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-09-03T16:37:09.651Z,1599151029.651 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-09-03T16:37:09.653Z,1599151029.653 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/
2020-09-03T16:37:09.655Z,1599151029.655 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg
2020-09-03T16:37:09.832Z,1599151029.832 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg
2020-09-03T16:37:09.929Z,1599151029.929 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg
2020-09-03T16:37:10.039Z,1599151030.039 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg
2020-09-03T16:37:10.139Z,1599151030.139 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg
2020-09-03T16:37:10.222Z,1599151030.222 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg
2020-09-03T16:37:10.308Z,1599151030.308 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg
2020-09-03T16:37:10.424Z,1599151030.424 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg
2020-09-03T16:37:10.525Z,1599151030.525 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg
2020-09-03T16:37:10.677Z,1599151030.677 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg
2020-09-03T16:37:10.911Z,1599151030.911 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-09-03T16:37:10.911Z,1599151030.911 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg
2020-09-03T16:37:11.005Z,1599151031.005 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-09-03T16:37:11.016Z,1599151031.016 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-09-03T16:37:11.342Z,1599151031.342 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-09-03T16:37:11.342Z,1599151031.342 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-09-03T16:37:11.467Z,1599151031.467 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-09-03T16:37:11.468Z,1599151031.468 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-09-03T16:37:11.529Z,1599151031.529 [DeadReckonUsingSpeedCalculator] Loaded
2020-09-03T16:37:11.529Z,1599151031.529 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2020-09-03T16:37:11.552Z,1599151031.552 [NavChart] Loaded
2020-09-03T16:37:11.553Z,1599151031.553 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-09-03T16:37:11.560Z,1599151031.560 [UniversalFixResidualReporter] Loaded
2020-09-03T16:37:11.560Z,1599151031.560 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-09-03T16:37:11.561Z,1599151031.561 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-09-03T16:37:11.562Z,1599151031.562 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-09-03T16:37:11.585Z,1599151031.585 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-09-03T16:37:11.586Z,1599151031.586 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-09-03T16:37:11.827Z,1599151031.827 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-09-03T16:37:11.827Z,1599151031.827 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-09-03T16:37:11.894Z,1599151031.894 [VerticalControl](DEBUG): Construct VerticalControl.
2020-09-03T16:37:12.003Z,1599151032.003 [VerticalControl] Loaded
2020-09-03T16:37:12.003Z,1599151032.003 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-09-03T16:37:12.008Z,1599151032.008 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-09-03T16:37:12.074Z,1599151032.074 [HorizontalControl] Loaded
2020-09-03T16:37:12.074Z,1599151032.074 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-09-03T16:37:12.079Z,1599151032.079 [SpeedControl](DEBUG): Construct SpeedControl.
2020-09-03T16:37:12.084Z,1599151032.084 [SpeedControl] Loaded
2020-09-03T16:37:12.084Z,1599151032.084 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-09-03T16:37:12.089Z,1599151032.089 [LoopControl](DEBUG): Construct LoopControl.
2020-09-03T16:37:12.089Z,1599151032.089 [LoopControl] Loaded
2020-09-03T16:37:12.089Z,1599151032.089 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-09-03T16:37:12.090Z,1599151032.090 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-09-03T16:37:12.091Z,1599151032.091 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-09-03T16:37:12.150Z,1599151032.150 [DepthRateCalculator] Loaded
2020-09-03T16:37:12.151Z,1599151032.151 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-09-03T16:37:12.160Z,1599151032.160 [PitchRateCalculator] Loaded
2020-09-03T16:37:12.160Z,1599151032.160 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-09-03T16:37:12.176Z,1599151032.176 [SpeedCalculator] Loaded
2020-09-03T16:37:12.176Z,1599151032.176 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-09-03T16:37:12.201Z,1599151032.201 [TempGradientCalculator] Loaded
2020-09-03T16:37:12.201Z,1599151032.201 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-09-03T16:37:12.210Z,1599151032.210 [YawRateCalculator] Loaded
2020-09-03T16:37:12.210Z,1599151032.210 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-09-03T16:37:12.243Z,1599151032.243 [ElevatorOffsetCalculator] Loaded
2020-09-03T16:37:12.243Z,1599151032.243 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-09-03T16:37:12.243Z,1599151032.243 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-09-03T16:37:12.244Z,1599151032.244 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2020-09-03T16:37:12.248Z,1599151032.248 [Module Loader](ERROR): Cannot load library: Modules/Dock.so: wrong ELF class: ELFCLASS64
2020-09-03T16:37:12.248Z,1599151032.248 [Module Loader](ERROR): Could not load the module at Modules/Dock.so
2020-09-03T16:37:12.248Z,1599151032.248 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-09-03T16:37:12.358Z,1599151032.358 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-09-03T16:37:12.359Z,1599151032.359 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-09-03T16:37:12.511Z,1599151032.511 [BuoyancyServo] Loaded
2020-09-03T16:37:12.511Z,1599151032.511 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-09-03T16:37:12.526Z,1599151032.526 [ElevatorServo] Loaded
2020-09-03T16:37:12.527Z,1599151032.527 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-09-03T16:37:12.542Z,1599151032.542 [MassServo] Loaded
2020-09-03T16:37:12.542Z,1599151032.542 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-09-03T16:37:12.557Z,1599151032.557 [RudderServo] Loaded
2020-09-03T16:37:12.557Z,1599151032.557 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-09-03T16:37:12.571Z,1599151032.571 [ThrusterServo] Loaded
2020-09-03T16:37:12.571Z,1599151032.571 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-09-03T16:37:12.572Z,1599151032.572 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-09-03T16:37:12.572Z,1599151032.572 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-09-03T16:37:12.693Z,1599151032.693 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-09-03T16:37:12.693Z,1599151032.693 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-09-03T16:37:13.465Z,1599151033.465 [AHRS_M2] Loaded
2020-09-03T16:37:13.465Z,1599151033.465 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-09-03T16:37:13.805Z,1599151033.805 [DataOverHttps] Loaded
2020-09-03T16:37:13.805Z,1599151033.805 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-09-03T16:37:13.806Z,1599151033.806 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409BE4E0
2020-09-03T16:37:13.806Z,1599151033.806 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 896
2020-09-03T16:37:13.824Z,1599151033.824 [Depth_Keller] Loaded
2020-09-03T16:37:13.824Z,1599151033.824 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-09-03T16:37:13.833Z,1599151033.833 [DropWeight] Loaded
2020-09-03T16:37:13.833Z,1599151033.833 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2020-09-03T16:37:14.038Z,1599151034.038 [NAL9602] Loaded
2020-09-03T16:37:14.038Z,1599151034.038 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-09-03T16:37:14.097Z,1599151034.097 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/voltage_now
2020-09-03T16:37:14.097Z,1599151034.097 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/current_now
2020-09-03T16:37:14.098Z,1599151034.098 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_now
2020-09-03T16:37:14.098Z,1599151034.098 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_full
2020-09-03T16:37:14.099Z,1599151034.099 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/voltage_now
2020-09-03T16:37:14.099Z,1599151034.099 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/current_now
2020-09-03T16:37:14.100Z,1599151034.100 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_now
2020-09-03T16:37:14.100Z,1599151034.100 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_full
2020-09-03T16:37:14.101Z,1599151034.101 [Onboard] Loaded
2020-09-03T16:37:14.101Z,1599151034.101 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2020-09-03T16:37:14.102Z,1599151034.102 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409EE4E0
2020-09-03T16:37:14.102Z,1599151034.102 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 897
2020-09-03T16:37:14.110Z,1599151034.110 [Radio_Surface] Loaded
2020-09-03T16:37:14.110Z,1599151034.110 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-09-03T16:37:14.111Z,1599151034.111 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A1E4E0
2020-09-03T16:37:14.111Z,1599151034.111 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 898
2020-09-03T16:37:15.686Z,1599151035.686 [BPC1] Loaded
2020-09-03T16:37:15.686Z,1599151035.686 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-09-03T16:37:15.686Z,1599151035.686 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-09-03T16:37:15.688Z,1599151035.688 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-09-03T16:37:15.846Z,1599151035.846 [SBIT](DEBUG): Construct Startup Built In Test.
2020-09-03T16:37:15.857Z,1599151035.857 [SBIT] Loaded
2020-09-03T16:37:15.857Z,1599151035.857 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-09-03T16:37:15.862Z,1599151035.862 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-09-03T16:37:15.875Z,1599151035.875 [IBIT] Loaded
2020-09-03T16:37:15.875Z,1599151035.875 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-09-03T16:37:15.883Z,1599151035.883 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-09-03T16:37:16.019Z,1599151036.019 [CBIT] Loaded
2020-09-03T16:37:16.019Z,1599151036.019 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-09-03T16:37:16.019Z,1599151036.019 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-09-03T16:37:16.020Z,1599151036.020 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-09-03T16:37:16.067Z,1599151036.067 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-09-03T16:37:16.072Z,1599151036.072 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-09-03T16:37:16.075Z,1599151036.075 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-09-03T16:37:16.083Z,1599151036.083 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-09-03T16:37:16.084Z,1599151036.084 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AAA4E0
2020-09-03T16:37:16.084Z,1599151036.084 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 900
2020-09-03T16:37:16.089Z,1599151036.089 [Supervisor](INFO): Main Thread ID is 806
2020-09-03T16:37:16.089Z,1599151036.089 [Supervisor](DEBUG): Running supervisor.
2020-09-03T16:37:16.090Z,1599151036.090 [CommandLine ThreadHandler](INFO): Handler Thread ID is 901
2020-09-03T16:37:16.092Z,1599151036.092 [controlThread ThreadHandler](INFO): Handler Thread ID is 902
2020-09-03T16:37:16.092Z,1599151036.092 [controlThread](DEBUG): Initializing ControlThread
2020-09-03T16:37:16.094Z,1599151036.094 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2020-09-03T16:37:16.094Z,1599151036.094 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-09-03T16:37:16.095Z,1599151036.095 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-09-03T16:37:16.095Z,1599151036.095 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-09-03T16:37:16.097Z,1599151036.097 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-09-03T16:37:16.097Z,1599151036.097 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-09-03T16:37:16.098Z,1599151036.098 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-09-03T16:37:16.098Z,1599151036.098 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-09-03T16:37:16.099Z,1599151036.099 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-09-03T16:37:16.099Z,1599151036.099 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-09-03T16:37:16.099Z,1599151036.099 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-09-03T16:37:16.100Z,1599151036.100 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-09-03T16:37:16.100Z,1599151036.100 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-09-03T16:37:16.107Z,1599151036.107 [SBIT](INFO): Initialize SBIT Component.
2020-09-03T16:37:16.107Z,1599151036.107 [SBIT](IMPORTANT): git: 2020_06_10_lcm_load_test-6-ga170864
2020-09-03T16:37:16.107Z,1599151036.107 [SBIT](INFO): git hash: a1708641688566620cff26e57d44215247cf1b46
2020-09-03T16:37:16.108Z,1599151036.108 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-09-03T16:37:16.109Z,1599151036.109 [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
2020-09-03T16:37:16.110Z,1599151036.110 [SBIT](INFO): Beginning SBIT in 24.000000 seconds.
2020-09-03T16:37:16.110Z,1599151036.110 [IBIT](INFO): Initialize IBIT Component.
2020-09-03T16:37:16.111Z,1599151036.111 [CBIT](DEBUG): Initialize CBIT Component.
2020-09-03T16:37:16.112Z,1599151036.112 [logger ThreadHandler](INFO): Handler Thread ID is 903
2020-09-03T16:37:16.125Z,1599151036.125 [CBIT](DEBUG): Initialized mux pins.
2020-09-03T16:37:16.125Z,1599151036.125 [CBIT](DEBUG): Initializing the watchdog timer.
2020-09-03T16:37:16.129Z,1599151036.129 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 904
2020-09-03T16:37:16.130Z,1599151036.130 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-09-03T16:37:16.149Z,1599151036.149 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-09-03T16:37:16.149Z,1599151036.149 [CBIT](DEBUG): Initializing heartbeat.
2020-09-03T16:37:16.181Z,1599151036.181 [Onboard ThreadHandler](INFO): Handler Thread ID is 905
2020-09-03T16:37:16.197Z,1599151036.197 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 906
2020-09-03T16:37:16.209Z,1599151036.209 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 907
2020-09-03T16:37:16.218Z,1599151036.218 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-09-03T16:37:16.218Z,1599151036.218 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-09-03T16:37:16.218Z,1599151036.218 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-09-03T16:37:16.218Z,1599151036.218 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-09-03T16:37:16.219Z,1599151036.219 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-09-03T16:37:16.219Z,1599151036.219 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-09-03T16:37:16.219Z,1599151036.219 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-09-03T16:37:16.219Z,1599151036.219 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-09-03T16:37:16.219Z,1599151036.219 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-09-03T16:37:16.219Z,1599151036.219 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-09-03T16:37:16.220Z,1599151036.220 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-09-03T16:37:16.220Z,1599151036.220 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-09-03T16:37:16.220Z,1599151036.220 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-09-03T16:37:16.220Z,1599151036.220 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-09-03T16:37:16.221Z,1599151036.221 [CBIT](DEBUG): Deactivating GF circuits.
2020-09-03T16:37:16.221Z,1599151036.221 [CBIT](DEBUG): Deactivating emergency mode.
2020-09-03T16:37:16.221Z,1599151036.221 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-09-03T16:37:16.221Z,1599151036.221 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-09-03T16:37:16.257Z,1599151036.257 [CBIT](DEBUG): Backplane powered.
2020-09-03T16:37:16.258Z,1599151036.258 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-09-03T16:37:16.289Z,1599151036.289 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-09-03T16:37:16.320Z,1599151036.320 [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>


2020-09-03T16:37:16.321Z,1599151036.321 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-09-03T16:37:16.384Z,1599151036.384 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-09-03T16:37:16.405Z,1599151036.405 [Default:A.Wait](DEBUG): Construct Wait.
2020-09-03T16:37:16.407Z,1599151036.407 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-09-03T16:37:16.438Z,1599151036.438 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-09-03T16:37:16.440Z,1599151036.440 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-09-03T16:37:16.466Z,1599151036.466 [Default:E.Execute](DEBUG): Construct Execute.
2020-09-03T16:37:16.469Z,1599151036.469 [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>


2020-09-03T16:37:16.473Z,1599151036.473 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,NAL9602,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2020-09-03T16:37:16.501Z,1599151036.501 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-09-03T16:37:16.601Z,1599151036.601 [Radio_Surface](INFO): Powering up
2020-09-03T16:37:16.618Z,1599151036.618 [Depth_Keller](ERROR): Pressure reading out of range: 1628.471313 decibar
2020-09-03T16:37:16.713Z,1599151036.713 [DepthRateCalculator](ERROR): Depth measurement is not active
2020-09-03T16:37:16.978Z,1599151036.978 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-09-03T16:37:16.985Z,1599151036.985 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-09-03T16:37:16.991Z,1599151036.991 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-09-03T16:37:16.997Z,1599151036.997 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-09-03T16:37:17.011Z,1599151037.011 [MassServo](DEBUG): Initializing EZServoServo.
2020-09-03T16:37:17.017Z,1599151037.017 [MassServo](DEBUG): Initializing MassServo.
2020-09-03T16:37:17.038Z,1599151037.038 [RudderServo](DEBUG): Initializing EZServoServo.
2020-09-03T16:37:17.045Z,1599151037.045 [RudderServo](DEBUG): Initializing RudderServo.
2020-09-03T16:37:17.066Z,1599151037.066 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-09-03T16:37:17.073Z,1599151037.073 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-09-03T16:37:17.192Z,1599151037.192 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2020-09-03T16:37:17.192Z,1599151037.192 [DropWeight] Hardware Fault, FailCount= 1
2020-09-03T16:37:17.192Z,1599151037.192 [DropWeight](ERROR): Hardware Fault
2020-09-03T16:37:17.242Z,1599151037.242 [CommandLine](FAULT): Scheduling is paused
2020-09-03T16:37:17.242Z,1599151037.242 [CBIT](INFO): Critical error at 20200903T163717
2020-09-03T16:37:17.242Z,1599151037.242 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2020-09-03T16:37:17.245Z,1599151037.245 [CBIT](ERROR): Hardware Fault in component: DropWeight
2020-09-03T16:37:17.246Z,1599151037.246 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2020-09-03T16:37:17.861Z,1599151037.861 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2020-09-03T16:37:17.861Z,1599151037.861 [RudderServo](FAULT): Rudder failed to initialize
2020-09-03T16:37:17.861Z,1599151037.861 [RudderServo] Communications Fault, FailCount= 1
2020-09-03T16:37:17.861Z,1599151037.861 [RudderServo](ERROR): Communications Fault
2020-09-03T16:37:18.091Z,1599151038.091 [CBIT](INFO): Critical error at 20200903T163717
2020-09-03T16:37:18.093Z,1599151038.093 [CBIT](ERROR): Communications Fault in component: RudderServo
2020-09-03T16:37:18.259Z,1599151038.259 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-09-03T16:37:18.260Z,1599151038.260 [RudderServo](INFO): Powering down
2020-09-03T16:37:18.925Z,1599151038.925 [RudderServo](DEBUG): Initializing EZServoServo.
2020-09-03T16:37:19.046Z,1599151039.046 [RudderServo](DEBUG): Initializing RudderServo.
2020-09-03T16:37:19.050Z,1599151039.050 [CBIT](INFO): Clearing failed state for component RudderServo
2020-09-03T16:37:19.050Z,1599151039.050 [RudderServo] No Fault, FailCount= 1
2020-09-03T16:37:20.037Z,1599151040.037 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-09-03T16:37:20.573Z,1599151040.573 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-09-03T16:37:20.634Z,1599151040.634 [CommandLine](IMPORTANT): got command quit
2020-09-03T16:37:20.973Z,1599151040.973 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-09-03T16:37:21.453Z,1599151041.453 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-09-03T16:37:21.637Z,1599151041.637 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-03T16:37:21.637Z,1599151041.637 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-09-03T16:37:21.725Z,1599151041.725 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-09-03T16:37:21.725Z,1599151041.725 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-09-03T16:37:21.728Z,1599151041.728 [CommandLine](INFO): Join timeout helper Thread ID is 915
2020-09-03T16:37:21.733Z,1599151041.733 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-09-03T16:37:21.733Z,1599151041.733 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-09-03T16:37:21.733Z,1599151041.733 [NavChartDb](INFO): Join timeout helper Thread ID is 916
2020-09-03T16:37:22.009Z,1599151042.009 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-09-03T16:37:22.421Z,1599151042.421 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-09-03T16:37:22.845Z,1599151042.845 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-09-03T16:37:23.301Z,1599151043.301 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-09-03T16:37:23.537Z,1599151043.537 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.023348 sec
2020-09-03T16:37:23.573Z,1599151043.573 [MassServo](INFO): Mass Shifter getPosition after 0.059284 sec
2020-09-03T16:37:23.610Z,1599151043.610 [MassServo](INFO): Mass Shifter done after 0.095687 sec
2020-09-03T16:37:24.065Z,1599151044.065 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-09-03T16:37:24.109Z,1599151044.109 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.017924 sec
2020-09-03T16:37:24.145Z,1599151044.145 [MassServo](INFO): Mass Shifter getPosition after 0.053708 sec
2020-09-03T16:37:24.146Z,1599151044.146 [MassServo](INFO): Mass Shifter done after 0.054272 sec
2020-09-03T16:37:24.485Z,1599151044.485 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-09-03T16:37:24.520Z,1599151044.520 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000341 sec
2020-09-03T16:37:24.557Z,1599151044.557 [MassServo](INFO): Mass Shifter getPosition after 0.037914 sec
2020-09-03T16:37:24.558Z,1599151044.558 [MassServo](INFO): Mass Shifter done after 0.038480 sec
2020-09-03T16:37:24.893Z,1599151044.893 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-09-03T16:37:24.920Z,1599151044.920 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000229 sec
2020-09-03T16:37:24.957Z,1599151044.957 [MassServo](INFO): Mass Shifter getPosition after 0.037868 sec
2020-09-03T16:37:24.958Z,1599151044.958 [MassServo](INFO): Mass Shifter done after 0.038618 sec
2020-09-03T16:37:25.293Z,1599151045.293 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-09-03T16:37:25.349Z,1599151045.349 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.021347 sec
2020-09-03T16:37:25.389Z,1599151045.389 [MassServo](INFO): Mass Shifter getPosition after 0.061550 sec
2020-09-03T16:37:25.725Z,1599151045.725 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-09-03T16:37:29.310Z,1599151049.310 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-03T16:37:29.310Z,1599151049.310 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-09-03T16:37:29.325Z,1599151049.325 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-09-03T16:37:29.325Z,1599151049.325 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-09-03T16:37:29.325Z,1599151049.325 [Radio_Surface](INFO): Join timeout helper Thread ID is 917
2020-09-03T16:37:29.537Z,1599151049.537 [AHRS_M2](FAULT): Failed to initialize within timeout.
2020-09-03T16:37:29.537Z,1599151049.537 [AHRS_M2] Communications Fault, FailCount= 1
2020-09-03T16:37:29.537Z,1599151049.537 [AHRS_M2](ERROR): Communications Fault
2020-09-03T16:37:29.549Z,1599151049.549 [Radio_Surface](INFO): Powering down
2020-09-03T16:37:29.550Z,1599151049.550 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-03T16:37:29.550Z,1599151049.550 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-09-03T16:37:29.557Z,1599151049.557 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2020-09-03T16:37:29.557Z,1599151049.557 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-09-03T16:37:29.557Z,1599151049.557 [Onboard](INFO): Join timeout helper Thread ID is 918
2020-09-03T16:37:29.567Z,1599151049.567 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2020-09-03T16:37:29.917Z,1599151049.917 [AHRS_M2](INFO): Powering down
2020-09-03T16:37:30.747Z,1599151050.747 [CBIT](INFO): Clearing failed state for component AHRS_M2
2020-09-03T16:37:30.747Z,1599151050.747 [AHRS_M2] No Fault, FailCount= 1
2020-09-03T16:37:31.129Z,1599151051.129 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-09-03T16:37:31.193Z,1599151051.193 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-03T16:37:31.193Z,1599151051.193 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-09-03T16:37:31.201Z,1599151051.201 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-09-03T16:37:31.201Z,1599151051.201 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-09-03T16:37:31.201Z,1599151051.201 [DataOverHttps](INFO): Join timeout helper Thread ID is 920
2020-09-03T16:37:32.193Z,1599151052.193 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-03T16:37:32.193Z,1599151052.193 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-09-03T16:37:32.214Z,1599151052.214 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-09-03T16:37:32.214Z,1599151052.214 [logger ThreadHandler](INFO): Thread cancelled.
2020-09-03T16:37:32.214Z,1599151052.214 [logger](INFO): Join timeout helper Thread ID is 921
2020-09-03T16:37:32.257Z,1599151052.257 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-03T16:37:32.258Z,1599151052.258 [logger ThreadHandler](INFO): Thread cancelled.
2020-09-03T16:37:32.265Z,1599151052.265 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-09-03T16:37:32.265Z,1599151052.265 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-09-03T16:37:32.266Z,1599151052.266 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-09-03T16:37:32.266Z,1599151052.266 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-09-03T16:37:32.266Z,1599151052.266 [controlThread](INFO): Join timeout helper Thread ID is 922
2020-09-03T16:37:32.337Z,1599151052.337 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-03T16:37:32.337Z,1599151052.337 [controlThread](DEBUG): Uninitializing ControlThread
2020-09-03T16:37:32.337Z,1599151052.337 [AHRS_M2](INFO): Powering down
2020-09-03T16:37:32.409Z,1599151052.409 [NAL9602](INFO): Powering down
2020-09-03T16:37:32.429Z,1599151052.429 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-09-03T16:37:32.430Z,1599151052.430 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-09-03T16:37:32.430Z,1599151052.430 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-09-03T16:37:32.431Z,1599151052.431 [MissionManager](INFO): Uninitializing Mission Default
2020-09-03T16:37:32.433Z,1599151052.433 [MissionManager](INFO): Uninitializing Mission Startup
2020-09-03T16:37:32.435Z,1599151052.435 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-09-03T16:37:32.435Z,1599151052.435 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-09-03T16:37:32.435Z,1599151052.435 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-09-03T16:37:32.436Z,1599151052.436 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-09-03T16:37:32.436Z,1599151052.436 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-09-03T16:37:32.436Z,1599151052.436 [BuoyancyServo](INFO): Powering down
2020-09-03T16:37:32.449Z,1599151052.449 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-09-03T16:37:32.449Z,1599151052.449 [ElevatorServo](INFO): Powering down
2020-09-03T16:37:32.468Z,1599151052.468 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-09-03T16:37:32.468Z,1599151052.468 [MassServo](INFO): Powering down
2020-09-03T16:37:32.469Z,1599151052.469 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-09-03T16:37:32.469Z,1599151052.469 [RudderServo](INFO): Powering down
2020-09-03T16:37:32.470Z,1599151052.470 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-09-03T16:37:32.470Z,1599151052.470 [ThrusterServo](INFO): Powering down
2020-09-03T16:37:32.470Z,1599151052.470 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-09-03T16:37:32.471Z,1599151052.471 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-09-03T16:37:32.471Z,1599151052.471 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-09-03T16:37:32.471Z,1599151052.471 [CBIT](DEBUG): Powering off loads.
2020-09-03T16:37:32.485Z,1599151052.485 [CBIT](DEBUG): Disabling WDT.
2020-09-03T16:37:32.497Z,1599151052.497 [CBIT](DEBUG): Opening all GF detection circuits.
2020-09-03T16:37:32.497Z,1599151052.497 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-09-03T16:37:32.719Z,1599151052.719 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-09-03T16:37:32.721Z,1599151052.721 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-09-03T16:37:32.726Z,1599151052.726 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-09-03T16:37:32.765Z,1599151052.765 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-09-03T16:37:32.870Z,1599151052.870 [logger ThreadHandler](INFO): Thread cancelled.