2020-02-19T22:57:15.740Z,1582153035.740 [Supervisor](DEBUG): Initializing supervisor.
2020-02-19T22:57:15.743Z,1582153035.743 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-02-19T22:57:15.743Z,1582153035.743 [SyncHandler](INFO): Protected caller Thread ID is 5781
2020-02-19T22:57:15.744Z,1582153035.744 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-02-19T22:57:15.745Z,1582153035.745 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-02-19T22:57:15.745Z,1582153035.745 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 5782
2020-02-19T22:57:15.748Z,1582153035.748 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-02-19T22:57:15.760Z,1582153035.760 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-02-19T22:57:15.761Z,1582153035.761 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-02-19T22:57:15.761Z,1582153035.761 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 5783
2020-02-19T22:57:15.762Z,1582153035.762 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-02-19T22:57:15.763Z,1582153035.763 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-02-19T22:57:15.763Z,1582153035.763 [logger ThreadHandler](INFO): Protected caller Thread ID is 5784
2020-02-19T22:57:15.765Z,1582153035.765 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-02-19T22:57:15.765Z,1582153035.765 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-02-19T22:57:15.767Z,1582153035.767 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-02-19T22:57:15.859Z,1582153035.859 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-02-19T22:57:15.860Z,1582153035.860 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-02-19T22:57:16.555Z,1582153036.555 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-02-19T22:57:16.555Z,1582153036.555 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-02-19T22:57:16.649Z,1582153036.649 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-02-19T22:57:16.650Z,1582153036.650 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-02-19T22:57:16.748Z,1582153036.748 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-02-19T22:57:16.748Z,1582153036.748 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-02-19T22:57:16.826Z,1582153036.826 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-02-19T22:57:16.958Z,1582153036.958 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-02-19T22:57:16.959Z,1582153036.959 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-02-19T22:57:17.234Z,1582153037.234 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-02-19T22:57:17.235Z,1582153037.235 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-02-19T22:57:17.667Z,1582153037.667 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-02-19T22:57:17.668Z,1582153037.668 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-02-19T22:57:17.809Z,1582153037.809 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-02-19T22:57:17.810Z,1582153037.810 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-02-19T22:57:17.989Z,1582153037.989 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-02-19T22:57:17.990Z,1582153037.990 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-02-19T22:57:18.441Z,1582153038.441 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-02-19T22:57:18.442Z,1582153038.442 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-02-19T22:57:18.644Z,1582153038.644 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-02-19T22:57:18.644Z,1582153038.644 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-02-19T22:57:18.835Z,1582153038.835 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-02-19T22:57:18.836Z,1582153038.836 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-02-19T22:57:19.225Z,1582153039.225 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-02-19T22:57:19.226Z,1582153039.226 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-02-19T22:57:19.536Z,1582153039.536 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-02-19T22:57:19.538Z,1582153039.538 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2020-02-19T22:57:19.539Z,1582153039.539 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2020-02-19T22:57:19.619Z,1582153039.619 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2020-02-19T22:57:19.770Z,1582153039.770 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2020-02-19T22:57:19.873Z,1582153039.873 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2020-02-19T22:57:20.053Z,1582153040.053 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2020-02-19T22:57:20.145Z,1582153040.145 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2020-02-19T22:57:20.338Z,1582153040.338 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2020-02-19T22:57:20.560Z,1582153040.560 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-02-19T22:57:20.560Z,1582153040.560 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2020-02-19T22:57:20.650Z,1582153040.650 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2020-02-19T22:57:20.744Z,1582153040.744 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2020-02-19T22:57:20.876Z,1582153040.876 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2020-02-19T22:57:20.972Z,1582153040.972 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/
2020-02-19T22:57:20.972Z,1582153040.972 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-02-19T22:57:20.987Z,1582153040.987 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-02-19T22:57:21.034Z,1582153041.034 [VerticalControl](DEBUG): Construct VerticalControl.
2020-02-19T22:57:21.144Z,1582153041.144 [VerticalControl] Loaded
2020-02-19T22:57:21.144Z,1582153041.144 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-02-19T22:57:21.145Z,1582153041.145 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-02-19T22:57:21.224Z,1582153041.224 [HorizontalControl] Loaded
2020-02-19T22:57:21.224Z,1582153041.224 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-02-19T22:57:21.225Z,1582153041.225 [SpeedControl](DEBUG): Construct SpeedControl.
2020-02-19T22:57:21.230Z,1582153041.230 [SpeedControl] Loaded
2020-02-19T22:57:21.230Z,1582153041.230 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-02-19T22:57:21.231Z,1582153041.231 [LoopControl](DEBUG): Construct LoopControl.
2020-02-19T22:57:21.232Z,1582153041.232 [LoopControl] Loaded
2020-02-19T22:57:21.232Z,1582153041.232 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-02-19T22:57:21.232Z,1582153041.232 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-02-19T22:57:21.233Z,1582153041.233 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-02-19T22:57:21.429Z,1582153041.429 [DepthRateCalculator] Loaded
2020-02-19T22:57:21.429Z,1582153041.429 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-02-19T22:57:21.459Z,1582153041.459 [PitchRateCalculator] Loaded
2020-02-19T22:57:21.459Z,1582153041.459 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-02-19T22:57:21.474Z,1582153041.474 [SpeedCalculator] Loaded
2020-02-19T22:57:21.475Z,1582153041.475 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-02-19T22:57:21.495Z,1582153041.495 [TempGradientCalculator] Loaded
2020-02-19T22:57:21.495Z,1582153041.495 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-02-19T22:57:21.500Z,1582153041.500 [YawRateCalculator] Loaded
2020-02-19T22:57:21.500Z,1582153041.500 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-02-19T22:57:21.557Z,1582153041.557 [ElevatorOffsetCalculator] Loaded
2020-02-19T22:57:21.558Z,1582153041.558 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-02-19T22:57:21.558Z,1582153041.558 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-02-19T22:57:21.559Z,1582153041.559 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-02-19T22:57:21.591Z,1582153041.591 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-02-19T22:57:21.591Z,1582153041.591 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-02-19T22:57:21.703Z,1582153041.703 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-02-19T22:57:21.703Z,1582153041.703 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-02-19T22:57:22.358Z,1582153042.358 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-02-19T22:57:22.358Z,1582153042.358 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-02-19T22:57:22.454Z,1582153042.454 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-02-19T22:57:22.455Z,1582153042.455 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-02-19T22:57:22.760Z,1582153042.760 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-02-19T22:57:22.765Z,1582153042.765 [AHRS_M2](INFO): created writer for : platform_orientation
2020-02-19T22:57:22.767Z,1582153042.767 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-02-19T22:57:22.772Z,1582153042.772 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-02-19T22:57:22.773Z,1582153042.773 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-02-19T22:57:22.777Z,1582153042.777 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-02-19T22:57:22.778Z,1582153042.778 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-02-19T22:57:22.783Z,1582153042.783 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-02-19T22:57:22.853Z,1582153042.853 [AHRS_M2] Loaded
2020-02-19T22:57:22.854Z,1582153042.854 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-02-19T22:57:22.930Z,1582153042.930 [DataOverHttps] Loaded
2020-02-19T22:57:22.930Z,1582153042.930 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-02-19T22:57:22.931Z,1582153042.931 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408ED4E0
2020-02-19T22:57:22.931Z,1582153042.931 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 5863
2020-02-19T22:57:22.944Z,1582153042.944 [Depth_Keller] Loaded
2020-02-19T22:57:22.945Z,1582153042.945 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-02-19T22:57:22.949Z,1582153042.949 [DropWeight] Loaded
2020-02-19T22:57:22.949Z,1582153042.949 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2020-02-19T22:57:23.045Z,1582153043.045 [NAL9602] Loaded
2020-02-19T22:57:23.046Z,1582153043.046 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-02-19T22:57:23.082Z,1582153043.082 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/voltage_now
2020-02-19T22:57:23.083Z,1582153043.083 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/current_now
2020-02-19T22:57:23.083Z,1582153043.083 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_now
2020-02-19T22:57:23.084Z,1582153043.084 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_full
2020-02-19T22:57:23.084Z,1582153043.084 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/voltage_now
2020-02-19T22:57:23.085Z,1582153043.085 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/current_now
2020-02-19T22:57:23.085Z,1582153043.085 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_now
2020-02-19T22:57:23.086Z,1582153043.086 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_full
2020-02-19T22:57:23.086Z,1582153043.086 [Onboard] Loaded
2020-02-19T22:57:23.086Z,1582153043.086 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2020-02-19T22:57:23.090Z,1582153043.090 [Radio_Surface] Loaded
2020-02-19T22:57:23.090Z,1582153043.090 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-02-19T22:57:23.091Z,1582153043.091 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4091D4E0
2020-02-19T22:57:23.091Z,1582153043.091 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 5864
2020-02-19T22:57:23.135Z,1582153043.135 [RDI_Pathfinder] Loaded
2020-02-19T22:57:23.135Z,1582153043.135 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2020-02-19T22:57:23.265Z,1582153043.265 [DAT] Loaded
2020-02-19T22:57:23.266Z,1582153043.266 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2020-02-19T22:57:24.746Z,1582153044.746 [BPC1] Loaded
2020-02-19T22:57:24.747Z,1582153044.747 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-02-19T22:57:24.747Z,1582153044.747 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-02-19T22:57:24.748Z,1582153044.748 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-02-19T22:57:24.848Z,1582153044.848 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-02-19T22:57:24.848Z,1582153044.848 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-02-19T22:57:24.868Z,1582153044.868 [NavChart] Loaded
2020-02-19T22:57:24.868Z,1582153044.868 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-02-19T22:57:24.872Z,1582153044.872 [UniversalFixResidualReporter] Loaded
2020-02-19T22:57:24.872Z,1582153044.872 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-02-19T22:57:24.872Z,1582153044.872 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-02-19T22:57:24.873Z,1582153044.873 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-02-19T22:57:25.237Z,1582153045.237 [SBIT](DEBUG): Construct Startup Built In Test.
2020-02-19T22:57:25.251Z,1582153045.251 [SBIT] Loaded
2020-02-19T22:57:25.258Z,1582153045.258 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-02-19T22:57:25.259Z,1582153045.259 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-02-19T22:57:25.299Z,1582153045.299 [IBIT] Loaded
2020-02-19T22:57:25.300Z,1582153045.300 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-02-19T22:57:25.325Z,1582153045.325 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-02-19T22:57:25.728Z,1582153045.728 [CBIT] Loaded
2020-02-19T22:57:25.728Z,1582153045.728 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-02-19T22:57:25.728Z,1582153045.728 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-02-19T22:57:25.729Z,1582153045.729 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-02-19T22:57:25.872Z,1582153045.872 [BuoyancyServo] Loaded
2020-02-19T22:57:25.872Z,1582153045.872 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-02-19T22:57:25.884Z,1582153045.884 [ElevatorServo] Loaded
2020-02-19T22:57:25.885Z,1582153045.885 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-02-19T22:57:26.003Z,1582153046.003 [MassServo] Loaded
2020-02-19T22:57:26.003Z,1582153046.003 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-02-19T22:57:26.041Z,1582153046.041 [RudderServo] Loaded
2020-02-19T22:57:26.041Z,1582153046.041 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-02-19T22:57:26.089Z,1582153046.089 [ThrusterServo] Loaded
2020-02-19T22:57:26.089Z,1582153046.089 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-02-19T22:57:26.090Z,1582153046.090 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-02-19T22:57:26.090Z,1582153046.090 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-02-19T22:57:26.103Z,1582153046.103 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-02-19T22:57:26.104Z,1582153046.104 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-02-19T22:57:26.313Z,1582153046.313 [BackSeatDriver] Loaded
2020-02-19T22:57:26.313Z,1582153046.313 [ComponentRegistry](DEBUG): Component "BackSeatDriver" handled in its own thread.
2020-02-19T22:57:26.314Z,1582153046.314 [BackSeatDriver ThreadHandler](DEBUG): Created PCaller Thread at 40AA34E0
2020-02-19T22:57:26.315Z,1582153046.315 [BackSeatDriver ThreadHandler](INFO): Protected caller Thread ID is 5865
2020-02-19T22:57:26.324Z,1582153046.324 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2020-02-19T22:57:26.329Z,1582153046.329 [CTD_Seabird](INFO): created writer for : sea_water_density
2020-02-19T22:57:26.329Z,1582153046.329 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2020-02-19T22:57:26.334Z,1582153046.334 [CTD_Seabird](INFO): created writer for : depth
2020-02-19T22:57:26.335Z,1582153046.335 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2020-02-19T22:57:26.339Z,1582153046.339 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2020-02-19T22:57:26.340Z,1582153046.340 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2020-02-19T22:57:26.345Z,1582153046.345 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2020-02-19T22:57:26.346Z,1582153046.346 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2020-02-19T22:57:26.351Z,1582153046.351 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2020-02-19T22:57:26.352Z,1582153046.352 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2020-02-19T22:57:26.357Z,1582153046.357 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2020-02-19T22:57:26.357Z,1582153046.357 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2020-02-19T22:57:26.363Z,1582153046.363 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2020-02-19T22:57:26.389Z,1582153046.389 [CTD_Seabird] Loaded
2020-02-19T22:57:26.389Z,1582153046.389 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2020-02-19T22:57:26.390Z,1582153046.390 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40AD34E0
2020-02-19T22:57:26.391Z,1582153046.391 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 5866
2020-02-19T22:57:26.421Z,1582153046.421 [ESPComponent] Loaded
2020-02-19T22:57:26.421Z,1582153046.421 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2020-02-19T22:57:26.427Z,1582153046.427 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470
2020-02-19T22:57:26.428Z,1582153046.428 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470
2020-02-19T22:57:26.432Z,1582153046.432 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650
2020-02-19T22:57:26.432Z,1582153046.432 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650
2020-02-19T22:57:26.436Z,1582153046.436 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl
2020-02-19T22:57:26.436Z,1582153046.436 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl
2020-02-19T22:57:26.440Z,1582153046.440 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm
2020-02-19T22:57:26.441Z,1582153046.441 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm
2020-02-19T22:57:26.445Z,1582153046.445 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm
2020-02-19T22:57:26.445Z,1582153046.445 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm
2020-02-19T22:57:26.449Z,1582153046.449 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm
2020-02-19T22:57:26.450Z,1582153046.450 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm
2020-02-19T22:57:26.454Z,1582153046.454 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm
2020-02-19T22:57:26.454Z,1582153046.454 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm
2020-02-19T22:57:26.458Z,1582153046.458 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water
2020-02-19T22:57:26.462Z,1582153046.462 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water
2020-02-19T22:57:26.463Z,1582153046.463 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-02-19T22:57:26.463Z,1582153046.463 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-02-19T22:57:26.467Z,1582153046.467 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-02-19T22:57:26.467Z,1582153046.467 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-02-19T22:57:26.472Z,1582153046.472 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-02-19T22:57:26.472Z,1582153046.472 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-02-19T22:57:26.476Z,1582153046.476 [WetLabsBB2FL] Loaded
2020-02-19T22:57:26.476Z,1582153046.476 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2020-02-19T22:57:26.477Z,1582153046.477 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B034E0
2020-02-19T22:57:26.478Z,1582153046.478 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 5867
2020-02-19T22:57:26.491Z,1582153046.491 [WetLabsUBAT..SerialDriver](INFO): Created logger
2020-02-19T22:57:26.492Z,1582153046.492 [WetLabsUBAT..SerialDriver](INFO): publishing on LCM channel WetLabsUBAT
2020-02-19T22:57:26.492Z,1582153046.492 [WetLabsUBAT..StreamSerialDriver](INFO): Created logger
2020-02-19T22:57:26.497Z,1582153046.497 [WetLabsUBAT](INFO): readConfig(): serialNo_: UBAT0051, uartName_: /dev/ttyC1, optionalArgs_: -ldir /mnt/mmc/LRAUV/Logs/latest
2020-02-19T22:57:26.497Z,1582153046.497 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsUBAT.biolum_potential
2020-02-19T22:57:26.497Z,1582153046.497 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(): dataName: biolum_potential
2020-02-19T22:57:26.502Z,1582153046.502 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsUBAT.flow_rate
2020-02-19T22:57:26.502Z,1582153046.502 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(): dataName: flow_rate
2020-02-19T22:57:26.506Z,1582153046.506 [WetLabsUBAT] Loaded
2020-02-19T22:57:26.506Z,1582153046.506 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread.
2020-02-19T22:57:26.507Z,1582153046.507 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40B334E0
2020-02-19T22:57:26.508Z,1582153046.508 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 5868
2020-02-19T22:57:26.508Z,1582153046.508 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-02-19T22:57:26.512Z,1582153046.512 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-02-19T22:57:26.513Z,1582153046.513 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-02-19T22:57:26.519Z,1582153046.519 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-02-19T22:57:26.520Z,1582153046.520 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B634E0
2020-02-19T22:57:26.520Z,1582153046.520 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 5869
2020-02-19T22:57:26.525Z,1582153046.525 [Supervisor](INFO): Main Thread ID is 5636
2020-02-19T22:57:26.525Z,1582153046.525 [Supervisor](DEBUG): Running supervisor.
2020-02-19T22:57:26.526Z,1582153046.526 [CommandLine ThreadHandler](INFO): Handler Thread ID is 5870
2020-02-19T22:57:26.529Z,1582153046.529 [controlThread ThreadHandler](INFO): Handler Thread ID is 5871
2020-02-19T22:57:26.529Z,1582153046.529 [controlThread](DEBUG): Initializing ControlThread
2020-02-19T22:57:26.530Z,1582153046.530 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-02-19T22:57:26.531Z,1582153046.531 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-02-19T22:57:26.532Z,1582153046.532 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-02-19T22:57:26.533Z,1582153046.533 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-02-19T22:57:26.533Z,1582153046.533 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-02-19T22:57:26.533Z,1582153046.533 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-02-19T22:57:26.534Z,1582153046.534 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-02-19T22:57:26.534Z,1582153046.534 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-02-19T22:57:26.534Z,1582153046.534 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-02-19T22:57:26.535Z,1582153046.535 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-02-19T22:57:26.541Z,1582153046.541 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-02-19T22:57:26.542Z,1582153046.542 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-02-19T22:57:26.542Z,1582153046.542 [SBIT](INFO): Initialize SBIT Component.
2020-02-19T22:57:26.542Z,1582153046.542 [SBIT](IMPORTANT): git: 2019-12-09_A
2020-02-19T22:57:26.543Z,1582153046.543 [SBIT](INFO): git hash: 5f7f9b7f448f424256857c85d4e34999b9088e71
2020-02-19T22:57:26.543Z,1582153046.543 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-02-19T22:57:26.543Z,1582153046.543 [SBIT](IMPORTANT): Kernel Version:#1 PREEMPT Thu Feb 21 11:17:40 PST 2019
2020-02-19T22:57:26.544Z,1582153046.544 [SBIT](INFO): Beginning SBIT in 39.000000 seconds.
2020-02-19T22:57:26.545Z,1582153046.545 [IBIT](INFO): Initialize IBIT Component.
2020-02-19T22:57:26.545Z,1582153046.545 [CBIT](DEBUG): Initialize CBIT Component.
2020-02-19T22:57:26.547Z,1582153046.547 [logger ThreadHandler](INFO): Handler Thread ID is 5872
2020-02-19T22:57:26.558Z,1582153046.558 [CBIT](DEBUG): Initialized mux pins.
2020-02-19T22:57:26.558Z,1582153046.558 [CBIT](DEBUG): Initializing the watchdog timer.
2020-02-19T22:57:26.567Z,1582153046.567 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 5873
2020-02-19T22:57:26.568Z,1582153046.568 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-02-19T22:57:26.579Z,1582153046.579 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 5874
2020-02-19T22:57:26.582Z,1582153046.582 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-02-19T22:57:26.583Z,1582153046.583 [CBIT](DEBUG): Initializing heartbeat.
2020-02-19T22:57:26.591Z,1582153046.591 [BackSeatDriver ThreadHandler](INFO): Handler Thread ID is 5875
2020-02-19T22:57:26.607Z,1582153046.607 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 5876
2020-02-19T22:57:26.607Z,1582153046.607 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-02-19T22:57:26.611Z,1582153046.611 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2020-02-19T22:57:26.612Z,1582153046.612 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 5878
2020-02-19T22:57:26.613Z,1582153046.613 [WetLabsBB2FL](INFO): Powering down
2020-02-19T22:57:26.651Z,1582153046.651 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 5879
2020-02-19T22:57:26.654Z,1582153046.654 [CBIT](DEBUG): Deactivating GF circuits.
2020-02-19T22:57:26.654Z,1582153046.654 [CBIT](DEBUG): Deactivating emergency mode.
2020-02-19T22:57:26.655Z,1582153046.655 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 5880
2020-02-19T22:57:26.690Z,1582153046.690 [CBIT](DEBUG): Backplane powered.
2020-02-19T22:57:26.692Z,1582153046.692 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-02-19T22:57:26.702Z,1582153046.702 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-02-19T22:57:26.712Z,1582153046.712 [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-02-19T22:57:26.713Z,1582153046.713 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-02-19T22:57:26.754Z,1582153046.754 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-02-19T22:57:26.760Z,1582153046.760 [Default:A.Wait](DEBUG): Construct Wait.
2020-02-19T22:57:26.762Z,1582153046.762 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-02-19T22:57:26.762Z,1582153046.762 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-02-19T22:57:26.763Z,1582153046.763 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-02-19T22:57:26.763Z,1582153046.763 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-02-19T22:57:26.763Z,1582153046.763 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-02-19T22:57:26.763Z,1582153046.763 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-02-19T22:57:26.763Z,1582153046.763 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-02-19T22:57:26.763Z,1582153046.763 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-02-19T22:57:26.764Z,1582153046.764 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-02-19T22:57:26.764Z,1582153046.764 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-02-19T22:57:26.764Z,1582153046.764 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-02-19T22:57:26.764Z,1582153046.764 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-02-19T22:57:26.764Z,1582153046.764 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-02-19T22:57:26.764Z,1582153046.764 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-02-19T22:57:26.765Z,1582153046.765 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-02-19T22:57:26.765Z,1582153046.765 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-02-19T22:57:26.792Z,1582153046.792 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-02-19T22:57:26.823Z,1582153046.823 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-02-19T22:57:26.826Z,1582153046.826 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-02-19T22:57:26.845Z,1582153046.845 [Default:E.Execute](DEBUG): Construct Execute.
2020-02-19T22:57:26.900Z,1582153046.900 [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-02-19T22:57:26.905Z,1582153046.905 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,NAL9602,Onboard,RDI_Pathfinder,DAT,BPC1,ESPComponent,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2020-02-19T22:57:26.931Z,1582153046.931 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-02-19T22:57:26.972Z,1582153046.972 [Depth_Keller](ERROR): Pressure reading out of range: 1650.297119 decibar
2020-02-19T22:57:26.983Z,1582153046.983 [Radio_Surface](INFO): Powering up
2020-02-19T22:57:26.997Z,1582153046.997 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2020-02-19T22:57:26.998Z,1582153046.998 [DAT](INFO): Powering up
2020-02-19T22:57:26.998Z,1582153046.998 [DAT](DEBUG): Initializing DAT.
2020-02-19T22:57:27.028Z,1582153047.028 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2020-02-19T22:57:27.084Z,1582153047.084 [DepthRateCalculator](ERROR): Depth measurement is not active
2020-02-19T22:57:27.117Z,1582153047.117 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-02-19T22:57:27.127Z,1582153047.127 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-02-19T22:57:27.128Z,1582153047.128 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-02-19T22:57:27.139Z,1582153047.139 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-02-19T22:57:27.140Z,1582153047.140 [MassServo](DEBUG): Initializing EZServoServo.
2020-02-19T22:57:27.151Z,1582153047.151 [MassServo](DEBUG): Initializing MassServo.
2020-02-19T22:57:27.151Z,1582153047.151 [RudderServo](DEBUG): Initializing EZServoServo.
2020-02-19T22:57:27.163Z,1582153047.163 [RudderServo](DEBUG): Initializing RudderServo.
2020-02-19T22:57:27.164Z,1582153047.164 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-02-19T22:57:27.175Z,1582153047.175 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-02-19T22:57:27.330Z,1582153047.330 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2020-02-19T22:57:27.330Z,1582153047.330 [DropWeight] Hardware Fault, FailCount= 1
2020-02-19T22:57:27.330Z,1582153047.330 [DropWeight](ERROR): Hardware Fault
2020-02-19T22:57:27.347Z,1582153047.347 [DepthRateCalculator](ERROR): Depth measurement is not active
2020-02-19T22:57:27.354Z,1582153047.354 [CommandLine](FAULT): Scheduling is paused
2020-02-19T22:57:27.355Z,1582153047.355 [CBIT](INFO): Critical error at 20200219T225727
2020-02-19T22:57:27.355Z,1582153047.355 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2020-02-19T22:57:27.357Z,1582153047.357 [CBIT](ERROR): Hardware Fault in component: DropWeight
2020-02-19T22:57:27.358Z,1582153047.358 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2020-02-19T22:57:28.074Z,1582153048.074 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2020-02-19T22:57:28.075Z,1582153048.075 [RudderServo](FAULT): Rudder failed to initialize
2020-02-19T22:57:28.075Z,1582153048.075 [RudderServo] Communications Fault, FailCount= 1
2020-02-19T22:57:28.075Z,1582153048.075 [RudderServo](ERROR): Communications Fault
2020-02-19T22:57:28.184Z,1582153048.184 [CBIT](INFO): Critical error at 20200219T225727
2020-02-19T22:57:28.186Z,1582153048.186 [CBIT](ERROR): Communications Fault in component: RudderServo
2020-02-19T22:57:28.347Z,1582153048.347 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-02-19T22:57:28.347Z,1582153048.347 [RudderServo](INFO): Powering down
2020-02-19T22:57:29.034Z,1582153049.034 [RudderServo](DEBUG): Initializing EZServoServo.
2020-02-19T22:57:29.151Z,1582153049.151 [RudderServo](DEBUG): Initializing RudderServo.
2020-02-19T22:57:29.155Z,1582153049.155 [CBIT](INFO): Clearing failed state for component RudderServo
2020-02-19T22:57:29.155Z,1582153049.155 [RudderServo] No Fault, FailCount= 1
2020-02-19T22:57:37.534Z,1582153057.534 [DAT](DEBUG): 
2020-02-19T22:57:37.535Z,1582153057.535 [DAT](DEBUG): Teledyne Benthos DAT-900 Series
2020-02-19T22:57:38.720Z,1582153058.720 [DAT](DEBUG): MF Frequency Band
2020-02-19T22:57:38.721Z,1582153058.721 [DAT](DEBUG): Directional Acoustic Transponder version 8.12.21
2020-02-19T22:57:38.721Z,1582153058.721 [DAT](DEBUG): Feb 19 2020 22:58:14 
2020-02-19T22:57:39.528Z,1582153059.528 [DAT](DEBUG): Features enabled [Bearing]
2020-02-19T22:57:39.529Z,1582153059.529 [DAT](DEBUG): CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP
2020-02-19T22:57:39.529Z,1582153059.529 [DAT](INFO): commRate: 800
2020-02-19T22:57:39.931Z,1582153059.931 [DAT](INFO): entering command mode
2020-02-19T22:57:40.344Z,1582153060.344 [DAT](DEBUG): checking for command mode acknowledgment
2020-02-19T22:57:40.739Z,1582153060.739 [DAT](DEBUG): checking for command mode acknowledgment
2020-02-19T22:57:40.739Z,1582153060.739 [DAT](INFO): command mode acknowledged
2020-02-19T22:57:40.739Z,1582153060.739 [DAT](INFO): setting verbose to 3
2020-02-19T22:57:41.143Z,1582153061.143 [DAT](DEBUG): checking for verbose setting acknowledgment
2020-02-19T22:57:41.144Z,1582153061.144 [DAT](INFO): set verbose to 3
2020-02-19T22:57:41.556Z,1582153061.556 [DAT](INFO): setting transmit power to 8
2020-02-19T22:57:41.951Z,1582153061.951 [DAT](DEBUG): checking for transmit power setting acknowledgment
2020-02-19T22:57:42.354Z,1582153062.354 [DAT](DEBUG): checking for transmit power setting acknowledgment
2020-02-19T22:57:42.355Z,1582153062.355 [DAT](INFO): set transmit power to 8
2020-02-19T22:57:42.760Z,1582153062.760 [DAT](INFO): setting local address to 9
2020-02-19T22:57:43.162Z,1582153063.162 [DAT](DEBUG): checking for local address setting acknowledgment
2020-02-19T22:57:43.567Z,1582153063.567 [DAT](DEBUG): checking for local address setting acknowledgment
2020-02-19T22:57:43.568Z,1582153063.568 [DAT](INFO): set local address to 9
2020-02-19T22:57:49.226Z,1582153069.226 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-02-19T22:57:49.226Z,1582153069.226 [RDI_Pathfinder] Communications Fault, FailCount= 1
2020-02-19T22:57:49.226Z,1582153069.226 [RDI_Pathfinder](ERROR): Communications Fault
2020-02-19T22:57:49.249Z,1582153069.249 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-02-19T22:57:49.690Z,1582153069.690 [RDI_Pathfinder](INFO): Powering down
2020-02-19T22:57:50.450Z,1582153070.450 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-02-19T22:57:50.450Z,1582153070.450 [RDI_Pathfinder] No Fault, FailCount= 1
2020-02-19T22:57:53.252Z,1582153073.252 [NAL9602](INFO): Powering up NAL9602
2020-02-19T22:58:04.160Z,1582153084.160 [NAL9602](INFO): NAL9602 initialized
2020-02-19T22:58:06.216Z,1582153086.216 [SBIT](IMPORTANT): Beginning Startup BIT
2020-02-19T22:58:06.224Z,1582153086.224 [CBIT](IMPORTANT): Beginning ground fault scan
2020-02-19T22:58:13.172Z,1582153093.172 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-02-19T22:58:13.172Z,1582153093.172 [RDI_Pathfinder] Communications Fault, FailCount= 2
2020-02-19T22:58:13.172Z,1582153093.172 [RDI_Pathfinder](ERROR): Communications Fault
2020-02-19T22:58:13.227Z,1582153093.227 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-02-19T22:58:13.631Z,1582153093.631 [RDI_Pathfinder](INFO): Powering down
2020-02-19T22:58:14.451Z,1582153094.451 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-02-19T22:58:14.451Z,1582153094.451 [RDI_Pathfinder] No Fault, FailCount= 2
2020-02-19T22:58:17.287Z,1582153097.287 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.015492
CHAN A1 (24V): -0.000752
CHAN A2 (12V): -0.006500
CHAN A3 (5V): -0.002905
CHAN B0 (3.3V): -0.000395
CHAN B1 (3.15aV): 0.000095
CHAN B2 (3.15bV): -0.000032
CHAN B3 (GND): 0.002101
OPEN: 0.005144
Full Scale Calc: 4.765 mA, -1.589 mA
2020-02-19T22:58:37.146Z,1582153117.146 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-02-19T22:58:37.146Z,1582153117.146 [RDI_Pathfinder] Communications Fault, FailCount= 3
2020-02-19T22:58:37.146Z,1582153117.146 [RDI_Pathfinder](ERROR): Communications Fault
2020-02-19T22:58:37.214Z,1582153117.214 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-02-19T22:58:37.215Z,1582153117.215 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder
2020-02-19T22:58:37.619Z,1582153117.619 [RDI_Pathfinder](INFO): Powering down
2020-02-19T22:58:37.761Z,1582153117.761 [CBIT](INFO): Critical error at 20200219T225837
2020-02-19T22:58:59.487Z,1582153139.487 [SBIT](IMPORTANT): SBIT PASSED
2020-02-19T22:58:59.505Z,1582153139.505 [CommandLine](IMPORTANT): got command configSet list
2020-02-19T22:58:59.505Z,1582153139.505 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-02-19T22:58:59.506Z,1582153139.506 [CommandLine](IMPORTANT): BR_Ping1D.loadAtStartup=0 bool;

2020-02-19T22:58:59.506Z,1582153139.506 [CommandLine](IMPORTANT): CBIT.gf24Offset=143 microampere;

2020-02-19T22:58:59.506Z,1582153139.506 [CommandLine](IMPORTANT): DAT.sbdAddress=-1 enum;

2020-02-19T22:58:59.506Z,1582153139.506 [CommandLine](IMPORTANT): DAT.transponderAddress=4 enum;

2020-02-19T22:58:59.507Z,1582153139.507 [CommandLine](IMPORTANT): DAT.verbosity=3 count;

2020-02-19T22:58:59.507Z,1582153139.507 [CommandLine](IMPORTANT): ESPComponent.loadAtStartup=1 bool;

2020-02-19T22:58:59.507Z,1582153139.507 [CommandLine](IMPORTANT): ESPComponent.sampleTimeout=5 minute;

2020-02-19T22:58:59.507Z,1582153139.507 [CommandLine](IMPORTANT): ESPComponent.simulateHardware=1 bool;

2020-02-19T22:58:59.507Z,1582153139.507 [CommandLine](IMPORTANT): Express linearApproximation CTD_Seabird.bin_median_sea_water_temperature 0.025000 kelvin;

2020-02-19T22:58:59.507Z,1582153139.507 [CommandLine](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 50.000000 liter_per_second;

2020-02-19T22:58:59.507Z,1582153139.507 [CommandLine](IMPORTANT): Express linearApproximation acoustic_contact_range 15.000000 meter;

2020-02-19T22:58:59.507Z,1582153139.507 [CommandLine](IMPORTANT): Express linearApproximation height_above_sea_floor 5.000000 meter;

2020-02-19T22:58:59.507Z,1582153139.507 [CommandLine](IMPORTANT): IBIT.batteryCapacityThreshold=5 ampere_hour;

2020-02-19T22:58:59.508Z,1582153139.508 [CommandLine](IMPORTANT): IBIT.batteryVoltageThreshold=13 volt;

2020-02-19T22:58:59.508Z,1582153139.508 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=225 cubic_centimeter;

2020-02-19T22:58:59.508Z,1582153139.508 [CommandLine](IMPORTANT): VerticalControl.massDefault=5 millimeter;

2020-02-19T22:58:59.508Z,1582153139.508 [CommandLine](IMPORTANT): WetLabsUBAT.loadAtStartup=1 bool;

2020-02-19T22:58:59.891Z,1582153139.891 [MissionManager](IMPORTANT): Started mission Startup
2020-02-19T22:58:59.892Z,1582153139.892 [Startup] Running Loop=1
2020-02-19T22:58:59.892Z,1582153139.892 [Startup](DEBUG): Aggregate::initialize Startup
2020-02-19T22:58:59.892Z,1582153139.892 [Startup:A.GoToSurface] Running Loop=1
2020-02-19T22:58:59.892Z,1582153139.892 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-02-19T22:58:59.893Z,1582153139.893 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-02-19T22:58:59.893Z,1582153139.893 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-02-19T22:58:59.893Z,1582153139.893 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-02-19T22:58:59.894Z,1582153139.894 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-02-19T22:58:59.894Z,1582153139.894 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-02-19T22:58:59.896Z,1582153139.896 [Startup:StartupSatComms] Running Loop=1
2020-02-19T22:58:59.896Z,1582153139.896 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-02-19T22:58:59.896Z,1582153139.896 [Startup:StartupSatComms:A] Running Loop=1
2020-02-19T22:59:00.299Z,1582153140.299 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-02-19T22:59:29.852Z,1582153169.852 [CommandLine](IMPORTANT): got command failComponent
2020-02-19T22:59:29.852Z,1582153169.852 [CommandLine](IMPORTANT): Failed components:
2020-02-19T22:59:29.852Z,1582153169.852 [CommandLine](IMPORTANT): DropWeight: Hardware Fault
2020-02-19T22:59:29.853Z,1582153169.853 [CommandLine](IMPORTANT): RDI_Pathfinder: Communications Fault
2020-02-19T23:00:00.069Z,1582153200.069 [Startup:StartupSatComms:A](INFO): Timed out from 2020-02-19T22:58:59.9Z
2020-02-19T23:00:00.069Z,1582153200.069 [Startup:StartupSatComms:A] Stopped
2020-02-19T23:00:00.069Z,1582153200.069 [Startup:StartupSatComms:B] Running Loop=1
2020-02-19T23:00:00.567Z,1582153200.567 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-02-19T23:00:26.792Z,1582153226.792 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2020-02-19T23:00:26.792Z,1582153226.792 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-02-19T23:00:26.824Z,1582153226.824 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-02-19T23:00:27.238Z,1582153227.238 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-02-19T23:00:27.238Z,1582153227.238 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2020-02-19T23:00:50.638Z,1582153250.638 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.006216
2020-02-19T23:00:58.118Z,1582153258.118 [DataOverHttps](INFO): Sending 157 bytes from file Logs/20200219T225426/Courier0000.lzma
2020-02-19T23:01:00.124Z,1582153260.124 [DataOverHttps](INFO): Moved sent file to Logs/20200219T225426/Courier0000.lzma.bak
2020-02-19T23:01:00.124Z,1582153260.124 [DataOverHttps](INFO): SBD MOMSN=12250778
2020-02-19T23:01:00.264Z,1582153260.264 [Startup:StartupSatComms:B](INFO): Timed out from 2020-02-19T23:00:00.1Z
2020-02-19T23:01:00.264Z,1582153260.264 [Startup:StartupSatComms:B] Stopped
2020-02-19T23:01:00.264Z,1582153260.264 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2020-02-19T23:01:00.264Z,1582153260.264 [Startup:StartupSatComms] Stopped
2020-02-19T23:01:00.264Z,1582153260.264 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-02-19T23:01:00.265Z,1582153260.265 [Startup](INFO): Completed Startup
2020-02-19T23:01:00.265Z,1582153260.265 [MissionManager](INFO): Startup is completed.
2020-02-19T23:01:00.265Z,1582153260.265 [MissionManager](INFO): Uninitializing Mission Startup
2020-02-19T23:01:00.265Z,1582153260.265 [Startup] Stopped
2020-02-19T23:01:00.266Z,1582153260.266 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-02-19T23:01:00.266Z,1582153260.266 [Startup:A.GoToSurface] Stopped
2020-02-19T23:01:00.266Z,1582153260.266 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-02-19T23:01:00.667Z,1582153260.667 [MissionManager](IMPORTANT): Started mission Default
2020-02-19T23:01:00.667Z,1582153260.667 [Default] Running Loop=1
2020-02-19T23:01:00.667Z,1582153260.667 [Default](DEBUG): Aggregate::initialize Default
2020-02-19T23:01:00.667Z,1582153260.667 [Default:B.GoToSurface] Running Loop=1
2020-02-19T23:01:00.668Z,1582153260.668 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-02-19T23:01:00.668Z,1582153260.668 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-02-19T23:01:00.668Z,1582153260.668 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-02-19T23:01:00.668Z,1582153260.668 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-02-19T23:01:00.669Z,1582153260.669 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-02-19T23:01:00.669Z,1582153260.669 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-02-19T23:01:00.669Z,1582153260.669 [Default:A.Wait] Running Loop=1
2020-02-19T23:01:00.669Z,1582153260.669 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-02-19T23:01:14.001Z,1582153274.001 [Default:A.Wait](INFO): Done Waiting.
2020-02-19T23:01:14.001Z,1582153274.001 [Default:A.Wait] Stopped
2020-02-19T23:01:14.001Z,1582153274.001 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-02-19T23:01:14.399Z,1582153274.399 [Default:CheckIn] Running Loop=1
2020-02-19T23:01:14.399Z,1582153274.399 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-19T23:01:14.399Z,1582153274.399 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-19T23:01:14.815Z,1582153274.815 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-02-19T23:01:37.480Z,1582153297.480 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-02-19T23:01:37.480Z,1582153297.480 [RDI_Pathfinder] No Fault, FailCount= 3
2020-02-19T23:01:50.355Z,1582153310.355 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout.
2020-02-19T23:01:50.355Z,1582153310.355 [RDI_Pathfinder] Data Fault, FailCount= 1
2020-02-19T23:01:50.355Z,1582153310.355 [RDI_Pathfinder](ERROR): Data Fault
2020-02-19T23:01:50.433Z,1582153310.433 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder
2020-02-19T23:01:50.831Z,1582153310.831 [RDI_Pathfinder](INFO): Powering down
2020-02-19T23:01:51.599Z,1582153311.599 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-02-19T23:01:51.599Z,1582153311.599 [RDI_Pathfinder] No Fault, FailCount= 1
2020-02-19T23:01:59.710Z,1582153319.710 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-02-19T23:02:14.203Z,1582153334.203 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-02-19T23:02:14.203Z,1582153334.203 [RDI_Pathfinder] Communications Fault, FailCount= 2
2020-02-19T23:02:14.203Z,1582153334.203 [RDI_Pathfinder](ERROR): Communications Fault
2020-02-19T23:02:14.267Z,1582153334.267 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-02-19T23:02:14.674Z,1582153334.674 [RDI_Pathfinder](INFO): Powering down
2020-02-19T23:02:15.448Z,1582153335.448 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-02-19T23:02:15.448Z,1582153335.448 [RDI_Pathfinder] No Fault, FailCount= 2
2020-02-19T23:02:38.033Z,1582153358.033 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-02-19T23:02:38.033Z,1582153358.033 [RDI_Pathfinder] Communications Fault, FailCount= 3
2020-02-19T23:02:38.033Z,1582153358.033 [RDI_Pathfinder](ERROR): Communications Fault
2020-02-19T23:02:38.068Z,1582153358.068 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-02-19T23:02:38.068Z,1582153358.068 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder
2020-02-19T23:02:38.495Z,1582153358.495 [RDI_Pathfinder](INFO): Powering down
2020-02-19T23:02:38.513Z,1582153358.513 [CBIT](INFO): Critical error at 20200219T230238
2020-02-19T23:03:07.515Z,1582153387.515 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-02-19T23:03:27.785Z,1582153407.785 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2020-02-19T23:03:27.785Z,1582153407.785 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-02-19T23:03:27.811Z,1582153407.811 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-02-19T23:03:28.228Z,1582153408.228 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-02-19T23:03:28.228Z,1582153408.228 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2020-02-19T23:03:52.728Z,1582153432.728 [CommandLine](IMPORTANT): got command failComponent
2020-02-19T23:03:52.728Z,1582153432.728 [CommandLine](IMPORTANT): Failed components:
2020-02-19T23:03:52.728Z,1582153432.728 [CommandLine](IMPORTANT): DropWeight: Hardware Fault
2020-02-19T23:03:52.729Z,1582153432.729 [CommandLine](IMPORTANT): RDI_Pathfinder: Communications Fault
2020-02-19T23:04:06.777Z,1582153446.777 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2020-02-19T23:04:10.832Z,1582153450.832 [CommandLine](IMPORTANT): got command failComponent
2020-02-19T23:04:10.833Z,1582153450.833 [CommandLine](IMPORTANT): Failed components:
2020-02-19T23:04:10.833Z,1582153450.833 [CommandLine](IMPORTANT): DropWeight: Hardware Fault
2020-02-19T23:04:10.833Z,1582153450.833 [CommandLine](IMPORTANT): RDI_Pathfinder: Communications Fault
2020-02-19T23:04:20.568Z,1582153460.568 [CommandLine](IMPORTANT): got command failComponent none RDI_Pathfinder
2020-02-19T23:04:20.568Z,1582153460.568 [RDI_Pathfinder] No Fault, FailCount= 3
2020-02-19T23:04:20.569Z,1582153460.569 [CommandLine](IMPORTANT): RDI_Pathfinder failureMode is No Fault
2020-02-19T23:04:37.567Z,1582153477.567 [CommandLine](IMPORTANT): got command show variable rdi
2020-02-19T23:04:37.697Z,1582153477.697 [CommandLine](IMPORTANT): projection_x_coordinate (unknown)
2020-02-19T23:04:37.698Z,1582153477.698 [CommandLine](IMPORTANT): projection_y_coordinate (unknown)
2020-02-19T23:04:37.761Z,1582153477.761 [CommandLine](IMPORTANT): RDI_Pathfinder.loadAtStartup (bool)
2020-02-19T23:04:37.762Z,1582153477.762 [CommandLine](IMPORTANT): RDI_Pathfinder.simulateHardware (bool)
2020-02-19T23:04:37.762Z,1582153477.762 [CommandLine](IMPORTANT): RDI_PathfinderUp.loadAtStartup (bool)
2020-02-19T23:04:37.766Z,1582153477.766 [CommandLine](IMPORTANT): RDI_PathfinderUp.simulateHardware (bool)
2020-02-19T23:04:37.843Z,1582153477.843 [CommandLine](IMPORTANT): RDI_Pathfinder.loadControl (none)
2020-02-19T23:04:37.844Z,1582153477.844 [CommandLine](IMPORTANT): RDI_Pathfinder.uart (none)
2020-02-19T23:04:37.844Z,1582153477.844 [CommandLine](IMPORTANT): RDI_Pathfinder.baud (bit_per_second)
2020-02-19T23:04:37.960Z,1582153477.960 [CommandLine](IMPORTANT): RDI_Pathfinder.height_above_sea_floor (meter)
2020-02-19T23:04:37.960Z,1582153477.960 [CommandLine](IMPORTANT): RDI_Pathfinder.platform_velocity_wrt_ground (meter_per_second)
2020-02-19T23:04:37.961Z,1582153477.961 [CommandLine](IMPORTANT): RDI_Pathfinder.platform_x_velocity_wrt_ground (meter_per_second)
2020-02-19T23:04:37.961Z,1582153477.961 [CommandLine](IMPORTANT): RDI_Pathfinder.platform_y_velocity_wrt_ground (meter_per_second)
2020-02-19T23:04:37.961Z,1582153477.961 [CommandLine](IMPORTANT): RDI_Pathfinder.platform_z_velocity_wrt_ground (meter_per_second)
2020-02-19T23:04:37.962Z,1582153477.962 [CommandLine](IMPORTANT): RDI_Pathfinder.BottomVelocityFlag (count)
2020-02-19T23:04:37.962Z,1582153477.962 [CommandLine](IMPORTANT): RDI_Pathfinder.Beam1Range (meter)
2020-02-19T23:04:37.962Z,1582153477.962 [CommandLine](IMPORTANT): RDI_Pathfinder.Beam2Range (meter)
2020-02-19T23:04:37.963Z,1582153477.963 [CommandLine](IMPORTANT): RDI_Pathfinder.Beam3Range (meter)
2020-02-19T23:04:37.963Z,1582153477.963 [CommandLine](IMPORTANT): RDI_Pathfinder.Beam4Range (meter)
2020-02-19T23:04:38.093Z,1582153478.093 [CommandLine](IMPORTANT): RDI_Pathfinder.component_voltage (volt)
2020-02-19T23:04:38.093Z,1582153478.093 [CommandLine](IMPORTANT): RDI_Pathfinder.component_avgVoltage (volt)
2020-02-19T23:04:38.094Z,1582153478.094 [CommandLine](IMPORTANT): RDI_Pathfinder.component_current (milliampere)
2020-02-19T23:04:38.094Z,1582153478.094 [CommandLine](IMPORTANT): RDI_Pathfinder.component_avgCurrent (milliampere)
2020-02-19T23:04:39.269Z,1582153479.269 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-02-19T23:04:39.272Z,1582153479.272 [BPC1](INFO): Received data from all battery sticks.
2020-02-19T23:04:42.904Z,1582153482.904 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-02-19T23:04:42.904Z,1582153482.904 [RDI_Pathfinder] Communications Fault, FailCount= 4
2020-02-19T23:04:42.905Z,1582153482.905 [RDI_Pathfinder](ERROR): Communications Fault
2020-02-19T23:04:42.948Z,1582153482.948 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-02-19T23:04:42.948Z,1582153482.948 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder
2020-02-19T23:04:43.383Z,1582153483.383 [RDI_Pathfinder](INFO): Powering down
2020-02-19T23:04:43.420Z,1582153483.420 [CBIT](INFO): Critical error at 20200219T230442
2020-02-19T23:06:14.611Z,1582153574.611 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-02-19T23:01:14.4Z
2020-02-19T23:06:14.611Z,1582153574.611 [Default:CheckIn:Read_GPS] Stopped
2020-02-19T23:06:14.611Z,1582153574.611 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-19T23:06:15.024Z,1582153575.024 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-02-19T23:06:21.930Z,1582153581.930 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20200219T225715/Courier0000.lzma
2020-02-19T23:06:23.936Z,1582153583.936 [DataOverHttps](INFO): Moved sent file to Logs/20200219T225715/Courier0000.lzma.bak
2020-02-19T23:06:23.936Z,1582153583.936 [DataOverHttps](INFO): SBD MOMSN=12250793
2020-02-19T23:06:28.770Z,1582153588.770 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2020-02-19T23:06:28.770Z,1582153588.770 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-02-19T23:06:28.788Z,1582153588.788 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-02-19T23:06:29.188Z,1582153589.188 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-02-19T23:06:29.188Z,1582153589.188 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2020-02-19T23:06:41.670Z,1582153601.670 [DataOverHttps](INFO): Sending 245 bytes from file Logs/20200219T225715/Courier0004.lzma
2020-02-19T23:06:43.676Z,1582153603.676 [DataOverHttps](INFO): Moved sent file to Logs/20200219T225715/Courier0004.lzma.bak
2020-02-19T23:06:43.676Z,1582153603.676 [DataOverHttps](INFO): SBD MOMSN=12250799
2020-02-19T23:07:00.779Z,1582153620.779 [DataOverHttps](INFO): Sending 86 bytes from file Logs/20191218T231239/Express0034.lzma
2020-02-19T23:07:02.188Z,1582153622.188 [DataOverHttps](INFO): Moved sent file to Logs/20191218T231239/Express0034.lzma.bak
2020-02-19T23:07:02.188Z,1582153622.188 [DataOverHttps](INFO): SBD MOMSN=12250807
2020-02-19T23:07:18.596Z,1582153638.596 [DataOverHttps](INFO): Sending 1329 bytes from file Logs/20200219T225426/Express0001.lzma
2020-02-19T23:07:20.604Z,1582153640.604 [DataOverHttps](INFO): Moved sent file to Logs/20200219T225426/Express0001.lzma.bak
2020-02-19T23:07:20.604Z,1582153640.604 [DataOverHttps](INFO): SBD MOMSN=12250816
2020-02-19T23:07:38.554Z,1582153658.554 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200219T225426/Express0005.lzma
2020-02-19T23:07:40.561Z,1582153660.561 [DataOverHttps](INFO): Moved sent file to Logs/20200219T225426/Express0005.lzma.bak
2020-02-19T23:07:40.561Z,1582153660.561 [DataOverHttps](INFO): SBD MOMSN=12250874
2020-02-19T23:07:43.113Z,1582153663.113 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-02-19T23:07:43.113Z,1582153663.113 [RDI_Pathfinder] No Fault, FailCount= 4
2020-02-19T23:07:56.024Z,1582153676.024 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout.
2020-02-19T23:07:56.024Z,1582153676.024 [RDI_Pathfinder] Data Fault, FailCount= 1
2020-02-19T23:07:56.024Z,1582153676.024 [RDI_Pathfinder](ERROR): Data Fault
2020-02-19T23:07:56.162Z,1582153676.162 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder
2020-02-19T23:07:56.490Z,1582153676.490 [RDI_Pathfinder](INFO): Powering down
2020-02-19T23:07:57.260Z,1582153677.260 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-02-19T23:07:57.260Z,1582153677.260 [RDI_Pathfinder] No Fault, FailCount= 1
2020-02-19T23:07:57.491Z,1582153677.491 [CommandLine](IMPORTANT): got command restart application
2020-02-19T23:07:57.912Z,1582153677.912 [DataOverHttps](INFO): Sending 1170 bytes from file Logs/20200219T225715/Express0001.lzma
2020-02-19T23:07:58.495Z,1582153678.495 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-19T23:07:58.495Z,1582153678.495 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-02-19T23:07:58.686Z,1582153678.686 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-02-19T23:07:58.686Z,1582153678.686 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-02-19T23:07:58.687Z,1582153678.687 [CommandLine](INFO): Join timeout helper Thread ID is 5944
2020-02-19T23:07:58.688Z,1582153678.688 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-02-19T23:07:58.688Z,1582153678.688 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-02-19T23:07:58.688Z,1582153678.688 [NavChartDb](INFO): Join timeout helper Thread ID is 5945
2020-02-19T23:07:58.947Z,1582153678.947 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-19T23:07:58.947Z,1582153678.947 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-02-19T23:07:58.966Z,1582153678.966 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler
2020-02-19T23:07:58.966Z,1582153678.966 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2020-02-19T23:07:58.967Z,1582153678.967 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 5946
2020-02-19T23:07:58.987Z,1582153678.987 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-19T23:07:58.987Z,1582153678.987 [WetLabsUBAT](INFO): Powering down
2020-02-19T23:07:59.019Z,1582153679.019 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2020-02-19T23:07:59.026Z,1582153679.026 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2020-02-19T23:07:59.026Z,1582153679.026 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-02-19T23:07:59.027Z,1582153679.027 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 5947
2020-02-19T23:07:59.399Z,1582153679.399 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-19T23:07:59.399Z,1582153679.399 [WetLabsBB2FL](INFO): Powering down
2020-02-19T23:07:59.400Z,1582153679.400 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-02-19T23:07:59.415Z,1582153679.415 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2020-02-19T23:07:59.415Z,1582153679.415 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-02-19T23:07:59.415Z,1582153679.415 [CTD_Seabird](INFO): Join timeout helper Thread ID is 5948
2020-02-19T23:07:59.916Z,1582153679.916 [DataOverHttps](INFO): Moved sent file to Logs/20200219T225715/Express0001.lzma.bak
2020-02-19T23:07:59.916Z,1582153679.916 [DataOverHttps](INFO): SBD MOMSN=12250876
2020-02-19T23:08:00.262Z,1582153680.262 [CTD_Seabird](INFO): Powering down
2020-02-19T23:08:00.274Z,1582153680.274 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-19T23:08:00.274Z,1582153680.274 [CTD_Seabird](INFO): Powering down
2020-02-19T23:08:00.286Z,1582153680.286 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-02-19T23:08:00.304Z,1582153680.304 [ComponentRegistry](INFO): Shutting down BackSeatDriver ThreadHandler
2020-02-19T23:08:00.304Z,1582153680.304 [BackSeatDriver ThreadHandler](INFO): Thread cancelled.
2020-02-19T23:08:00.304Z,1582153680.304 [BackSeatDriver](INFO): Join timeout helper Thread ID is 5949
2020-02-19T23:08:00.626Z,1582153680.626 [BackSeatDriver ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-19T23:08:00.627Z,1582153680.627 [BackSeatDriver ThreadHandler](INFO): Thread cancelled.
2020-02-19T23:08:00.643Z,1582153680.643 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-02-19T23:08:00.643Z,1582153680.643 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-02-19T23:08:00.644Z,1582153680.644 [Radio_Surface](INFO): Join timeout helper Thread ID is 5950
2020-02-19T23:08:00.995Z,1582153680.995 [Radio_Surface](INFO): Powering down
2020-02-19T23:08:00.995Z,1582153680.995 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-19T23:08:00.996Z,1582153680.996 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-02-19T23:08:01.015Z,1582153681.015 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-02-19T23:08:01.015Z,1582153681.015 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-02-19T23:08:01.016Z,1582153681.016 [DataOverHttps](INFO): Join timeout helper Thread ID is 5951
2020-02-19T23:08:01.416Z,1582153681.416 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2020-02-19T23:08:01.417Z,1582153681.417 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-19T23:08:01.417Z,1582153681.417 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-02-19T23:08:01.431Z,1582153681.431 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-02-19T23:08:01.431Z,1582153681.431 [logger ThreadHandler](INFO): Thread cancelled.
2020-02-19T23:08:01.432Z,1582153681.432 [logger](INFO): Join timeout helper Thread ID is 5952
2020-02-19T23:08:01.438Z,1582153681.438 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-19T23:08:01.439Z,1582153681.439 [logger ThreadHandler](INFO): Thread cancelled.
2020-02-19T23:08:01.451Z,1582153681.451 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-02-19T23:08:01.451Z,1582153681.451 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-02-19T23:08:01.451Z,1582153681.451 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-02-19T23:08:01.451Z,1582153681.451 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-02-19T23:08:01.452Z,1582153681.452 [controlThread](INFO): Join timeout helper Thread ID is 5953
2020-02-19T23:08:01.642Z,1582153681.642 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-19T23:08:01.643Z,1582153681.643 [controlThread](DEBUG): Uninitializing ControlThread
2020-02-19T23:08:01.643Z,1582153681.643 [AHRS_M2](INFO): Powering down
2020-02-19T23:08:01.715Z,1582153681.715 [NAL9602](INFO): Powering down
2020-02-19T23:08:01.786Z,1582153681.786 [RDI_Pathfinder](INFO): Powering down
2020-02-19T23:08:01.787Z,1582153681.787 [DAT](INFO): Powering down
2020-02-19T23:08:01.907Z,1582153681.907 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-02-19T23:08:01.908Z,1582153681.908 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-02-19T23:08:01.908Z,1582153681.908 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-02-19T23:08:01.909Z,1582153681.909 [MissionManager](INFO): Uninitializing Mission Default
2020-02-19T23:08:01.909Z,1582153681.909 [Default] Stopped
2020-02-19T23:08:01.909Z,1582153681.909 [Default](DEBUG): Aggregate::uninitialize Default
2020-02-19T23:08:01.909Z,1582153681.909 [Default:B.GoToSurface] Stopped
2020-02-19T23:08:01.909Z,1582153681.909 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-02-19T23:08:01.909Z,1582153681.909 [Default:CheckIn] Stopped
2020-02-19T23:08:01.909Z,1582153681.909 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-19T23:08:01.909Z,1582153681.909 [Default:CheckIn:Read_Iridium] Stopped
2020-02-19T23:08:01.912Z,1582153681.912 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-02-19T23:08:01.912Z,1582153681.912 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-02-19T23:08:01.913Z,1582153681.913 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-02-19T23:08:01.913Z,1582153681.913 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-02-19T23:08:01.913Z,1582153681.913 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-02-19T23:08:01.913Z,1582153681.913 [BuoyancyServo](INFO): Powering down
2020-02-19T23:08:01.926Z,1582153681.926 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-02-19T23:08:01.926Z,1582153681.926 [ElevatorServo](INFO): Powering down
2020-02-19T23:08:01.927Z,1582153681.927 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-02-19T23:08:01.927Z,1582153681.927 [MassServo](INFO): Powering down
2020-02-19T23:08:01.928Z,1582153681.928 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-02-19T23:08:01.928Z,1582153681.928 [RudderServo](INFO): Powering down
2020-02-19T23:08:01.929Z,1582153681.929 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-02-19T23:08:01.929Z,1582153681.929 [ThrusterServo](INFO): Powering down
2020-02-19T23:08:01.930Z,1582153681.930 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-02-19T23:08:01.930Z,1582153681.930 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-02-19T23:08:01.930Z,1582153681.930 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-02-19T23:08:01.931Z,1582153681.931 [CBIT](DEBUG): Powering off loads.
2020-02-19T23:08:01.942Z,1582153681.942 [CBIT](DEBUG): Disabling WDT.
2020-02-19T23:08:01.954Z,1582153681.954 [CBIT](DEBUG): Opening all GF detection circuits.
2020-02-19T23:08:01.955Z,1582153681.955 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-02-19T23:08:02.052Z,1582153682.052 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-02-19T23:08:02.059Z,1582153682.059 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-02-19T23:08:02.119Z,1582153682.119 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2020-02-19T23:08:02.120Z,1582153682.120 [WetLabsUBAT](INFO): WetLabs destructor: _dataLog is not open
2020-02-19T23:08:02.121Z,1582153682.121 [WetLabsUBAT](INFO): WetLabs destructor: done
2020-02-19T23:08:02.123Z,1582153682.123 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-02-19T23:08:02.127Z,1582153682.127 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-02-19T23:08:02.130Z,1582153682.130 [BackSeatDriver ThreadHandler](INFO): Thread cancelled.
2020-02-19T23:08:02.139Z,1582153682.139 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-02-19T23:08:02.200Z,1582153682.200 [logger ThreadHandler](INFO): Thread cancelled.