2020-09-30T17:32:12.034Z,1601487132.034 [Supervisor](DEBUG): Initializing supervisor.
2020-09-30T17:32:12.037Z,1601487132.037 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-09-30T17:32:12.038Z,1601487132.038 [SyncHandler](INFO): Protected caller Thread ID is 6530
2020-09-30T17:32:12.039Z,1601487132.039 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-09-30T17:32:12.040Z,1601487132.040 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-09-30T17:32:12.040Z,1601487132.040 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 6531
2020-09-30T17:32:12.043Z,1601487132.043 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-09-30T17:32:12.058Z,1601487132.058 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-09-30T17:32:12.059Z,1601487132.059 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-09-30T17:32:12.059Z,1601487132.059 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 6532
2020-09-30T17:32:12.060Z,1601487132.060 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-09-30T17:32:12.061Z,1601487132.061 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-09-30T17:32:12.061Z,1601487132.061 [logger ThreadHandler](INFO): Protected caller Thread ID is 6533
2020-09-30T17:32:12.063Z,1601487132.063 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-09-30T17:32:12.063Z,1601487132.063 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-09-30T17:32:12.065Z,1601487132.065 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-09-30T17:32:12.157Z,1601487132.157 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-09-30T17:32:12.157Z,1601487132.157 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-09-30T17:32:12.713Z,1601487132.713 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-09-30T17:32:12.714Z,1601487132.714 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2020-09-30T17:32:12.901Z,1601487132.901 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2020-09-30T17:32:12.902Z,1601487132.902 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-09-30T17:32:12.997Z,1601487132.997 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-09-30T17:32:12.998Z,1601487132.998 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-09-30T17:32:13.096Z,1601487133.096 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-09-30T17:32:13.097Z,1601487133.097 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-09-30T17:32:13.176Z,1601487133.176 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-09-30T17:32:13.310Z,1601487133.310 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-09-30T17:32:13.310Z,1601487133.310 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-09-30T17:32:13.583Z,1601487133.583 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-09-30T17:32:13.584Z,1601487133.584 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-09-30T17:32:14.014Z,1601487134.014 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-09-30T17:32:14.015Z,1601487134.015 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-09-30T17:32:14.153Z,1601487134.153 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-09-30T17:32:14.154Z,1601487134.154 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-09-30T17:32:14.330Z,1601487134.330 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-09-30T17:32:14.330Z,1601487134.330 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-09-30T17:32:14.798Z,1601487134.798 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-09-30T17:32:14.799Z,1601487134.799 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-09-30T17:32:15.005Z,1601487135.005 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-09-30T17:32:15.005Z,1601487135.005 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-09-30T17:32:15.197Z,1601487135.197 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-09-30T17:32:15.197Z,1601487135.197 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-09-30T17:32:15.586Z,1601487135.586 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-09-30T17:32:15.587Z,1601487135.587 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-09-30T17:32:15.891Z,1601487135.891 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-09-30T17:32:15.893Z,1601487135.893 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2020-09-30T17:32:15.894Z,1601487135.894 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2020-09-30T17:32:15.975Z,1601487135.975 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2020-09-30T17:32:16.220Z,1601487136.220 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2020-09-30T17:32:16.427Z,1601487136.427 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2020-09-30T17:32:16.513Z,1601487136.513 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2020-09-30T17:32:16.739Z,1601487136.739 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2020-09-30T17:32:16.928Z,1601487136.928 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2020-09-30T17:32:17.152Z,1601487137.152 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-09-30T17:32:17.152Z,1601487137.152 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2020-09-30T17:32:17.243Z,1601487137.243 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2020-09-30T17:32:17.337Z,1601487137.337 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2020-09-30T17:32:17.461Z,1601487137.461 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2020-09-30T17:32:17.557Z,1601487137.557 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/
2020-09-30T17:32:17.558Z,1601487137.558 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-09-30T17:32:17.574Z,1601487137.574 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2020-09-30T17:32:17.652Z,1601487137.652 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2020-09-30T17:32:17.652Z,1601487137.652 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-09-30T17:32:17.699Z,1601487137.699 [VerticalControl](DEBUG): Construct VerticalControl.
2020-09-30T17:32:17.808Z,1601487137.808 [VerticalControl] Loaded
2020-09-30T17:32:17.808Z,1601487137.808 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-09-30T17:32:17.809Z,1601487137.809 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-09-30T17:32:17.886Z,1601487137.886 [HorizontalControl] Loaded
2020-09-30T17:32:17.886Z,1601487137.886 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-09-30T17:32:17.887Z,1601487137.887 [SpeedControl](DEBUG): Construct SpeedControl.
2020-09-30T17:32:17.892Z,1601487137.892 [SpeedControl] Loaded
2020-09-30T17:32:17.893Z,1601487137.893 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-09-30T17:32:17.893Z,1601487137.893 [LoopControl](DEBUG): Construct LoopControl.
2020-09-30T17:32:17.894Z,1601487137.894 [LoopControl] Loaded
2020-09-30T17:32:17.894Z,1601487137.894 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-09-30T17:32:17.894Z,1601487137.894 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-09-30T17:32:17.895Z,1601487137.895 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-09-30T17:32:17.936Z,1601487137.936 [DepthRateCalculator] Loaded
2020-09-30T17:32:17.937Z,1601487137.937 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-09-30T17:32:17.941Z,1601487137.941 [PitchRateCalculator] Loaded
2020-09-30T17:32:17.942Z,1601487137.942 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-09-30T17:32:17.957Z,1601487137.957 [SpeedCalculator] Loaded
2020-09-30T17:32:17.957Z,1601487137.957 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-09-30T17:32:17.977Z,1601487137.977 [TempGradientCalculator] Loaded
2020-09-30T17:32:17.977Z,1601487137.977 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-09-30T17:32:17.982Z,1601487137.982 [YawRateCalculator] Loaded
2020-09-30T17:32:17.982Z,1601487137.982 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-09-30T17:32:18.011Z,1601487138.011 [ElevatorOffsetCalculator] Loaded
2020-09-30T17:32:18.011Z,1601487138.011 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-09-30T17:32:18.011Z,1601487138.011 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-09-30T17:32:18.012Z,1601487138.012 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-09-30T17:32:18.039Z,1601487138.039 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-09-30T17:32:18.039Z,1601487138.039 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-09-30T17:32:18.064Z,1601487138.064 [Module Loader](ERROR): Cannot load library: Modules/Estimation.so: undefined symbol: _ZN9Component10logVoltageEff
2020-09-30T17:32:18.064Z,1601487138.064 [Module Loader](ERROR): Could not load the module at Modules/Estimation.so
2020-09-30T17:32:18.064Z,1601487138.064 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-09-30T17:32:18.351Z,1601487138.351 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-09-30T17:32:18.352Z,1601487138.352 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-09-30T17:32:18.448Z,1601487138.448 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-09-30T17:32:18.449Z,1601487138.449 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-09-30T17:32:18.777Z,1601487138.777 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-09-30T17:32:18.782Z,1601487138.782 [AHRS_M2](INFO): created writer for : platform_orientation
2020-09-30T17:32:18.783Z,1601487138.783 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-09-30T17:32:18.789Z,1601487138.789 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-09-30T17:32:18.789Z,1601487138.789 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-09-30T17:32:18.794Z,1601487138.794 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-09-30T17:32:18.794Z,1601487138.794 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-09-30T17:32:18.799Z,1601487138.799 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-09-30T17:32:18.870Z,1601487138.870 [AHRS_M2] Loaded
2020-09-30T17:32:18.870Z,1601487138.870 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-09-30T17:32:18.946Z,1601487138.946 [DataOverHttps] Loaded
2020-09-30T17:32:18.946Z,1601487138.946 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-09-30T17:32:18.947Z,1601487138.947 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408B04E0
2020-09-30T17:32:18.948Z,1601487138.948 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 6615
2020-09-30T17:32:18.977Z,1601487138.977 [Depth_Keller] Loaded
2020-09-30T17:32:18.977Z,1601487138.977 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-09-30T17:32:19.089Z,1601487139.089 [NAL9602] Loaded
2020-09-30T17:32:19.089Z,1601487139.089 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-09-30T17:32:19.137Z,1601487139.137 [Onboard] Loaded
2020-09-30T17:32:19.137Z,1601487139.137 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2020-09-30T17:32:19.138Z,1601487139.138 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408E04E0
2020-09-30T17:32:19.138Z,1601487139.138 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 6616
2020-09-30T17:32:19.158Z,1601487139.158 [Radio_Surface] Loaded
2020-09-30T17:32:19.158Z,1601487139.158 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-09-30T17:32:19.159Z,1601487139.159 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409104E0
2020-09-30T17:32:19.159Z,1601487139.159 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 6617
2020-09-30T17:32:19.306Z,1601487139.306 [DAT] Loaded
2020-09-30T17:32:19.307Z,1601487139.307 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2020-09-30T17:32:20.794Z,1601487140.794 [BPC1] Loaded
2020-09-30T17:32:20.794Z,1601487140.794 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-09-30T17:32:20.794Z,1601487140.794 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-09-30T17:32:20.795Z,1601487140.795 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-09-30T17:32:20.909Z,1601487140.909 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-09-30T17:32:20.909Z,1601487140.909 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-09-30T17:32:20.930Z,1601487140.930 [NavChart] Loaded
2020-09-30T17:32:20.930Z,1601487140.930 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-09-30T17:32:20.934Z,1601487140.934 [UniversalFixResidualReporter] Loaded
2020-09-30T17:32:20.934Z,1601487140.934 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-09-30T17:32:20.934Z,1601487140.934 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-09-30T17:32:20.935Z,1601487140.935 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-09-30T17:32:21.075Z,1601487141.075 [SBIT](DEBUG): Construct Startup Built In Test.
2020-09-30T17:32:21.085Z,1601487141.085 [SBIT] Loaded
2020-09-30T17:32:21.086Z,1601487141.086 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-09-30T17:32:21.086Z,1601487141.086 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-09-30T17:32:21.098Z,1601487141.098 [IBIT] Loaded
2020-09-30T17:32:21.099Z,1601487141.099 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-09-30T17:32:21.102Z,1601487141.102 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-09-30T17:32:21.285Z,1601487141.285 [CBIT] Loaded
2020-09-30T17:32:21.285Z,1601487141.285 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-09-30T17:32:21.286Z,1601487141.286 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-09-30T17:32:21.295Z,1601487141.295 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-09-30T17:32:21.631Z,1601487141.631 [BuoyancyServo] Loaded
2020-09-30T17:32:21.631Z,1601487141.631 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-09-30T17:32:21.659Z,1601487141.659 [ElevatorServo] Loaded
2020-09-30T17:32:21.659Z,1601487141.659 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-09-30T17:32:21.686Z,1601487141.686 [MassServo] Loaded
2020-09-30T17:32:21.686Z,1601487141.686 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-09-30T17:32:21.713Z,1601487141.713 [RudderServo] Loaded
2020-09-30T17:32:21.713Z,1601487141.713 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-09-30T17:32:21.740Z,1601487141.740 [ThrusterServo] Loaded
2020-09-30T17:32:21.740Z,1601487141.740 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-09-30T17:32:21.740Z,1601487141.740 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-09-30T17:32:21.741Z,1601487141.741 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-09-30T17:32:21.759Z,1601487141.759 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-09-30T17:32:21.760Z,1601487141.760 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-09-30T17:32:21.995Z,1601487141.995 [BackSeatDriver] Loaded
2020-09-30T17:32:21.996Z,1601487141.996 [ComponentRegistry](DEBUG): Component "BackSeatDriver" handled in its own thread.
2020-09-30T17:32:21.997Z,1601487141.997 [BackSeatDriver ThreadHandler](DEBUG): Created PCaller Thread at 40AA74E0
2020-09-30T17:32:21.997Z,1601487141.997 [BackSeatDriver ThreadHandler](INFO): Protected caller Thread ID is 6618
2020-09-30T17:32:22.026Z,1601487142.026 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2020-09-30T17:32:22.031Z,1601487142.031 [CTD_Seabird](INFO): created writer for : sea_water_density
2020-09-30T17:32:22.031Z,1601487142.031 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2020-09-30T17:32:22.036Z,1601487142.036 [CTD_Seabird](INFO): created writer for : depth
2020-09-30T17:32:22.036Z,1601487142.036 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2020-09-30T17:32:22.041Z,1601487142.041 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2020-09-30T17:32:22.041Z,1601487142.041 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2020-09-30T17:32:22.047Z,1601487142.046 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2020-09-30T17:32:22.047Z,1601487142.047 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2020-09-30T17:32:22.052Z,1601487142.052 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2020-09-30T17:32:22.053Z,1601487142.053 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2020-09-30T17:32:22.058Z,1601487142.058 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2020-09-30T17:32:22.058Z,1601487142.058 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2020-09-30T17:32:22.064Z,1601487142.064 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2020-09-30T17:32:22.091Z,1601487142.091 [CTD_Seabird] Loaded
2020-09-30T17:32:22.091Z,1601487142.091 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2020-09-30T17:32:22.092Z,1601487142.092 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40AD74E0
2020-09-30T17:32:22.093Z,1601487142.093 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 6619
2020-09-30T17:32:22.142Z,1601487142.142 [ESPComponent] Loaded
2020-09-30T17:32:22.142Z,1601487142.142 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2020-09-30T17:32:22.164Z,1601487142.164 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470
2020-09-30T17:32:22.164Z,1601487142.164 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470
2020-09-30T17:32:22.168Z,1601487142.168 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650
2020-09-30T17:32:22.168Z,1601487142.168 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650
2020-09-30T17:32:22.172Z,1601487142.172 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl
2020-09-30T17:32:22.173Z,1601487142.173 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl
2020-09-30T17:32:22.177Z,1601487142.177 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm
2020-09-30T17:32:22.177Z,1601487142.177 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm
2020-09-30T17:32:22.181Z,1601487142.181 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm
2020-09-30T17:32:22.181Z,1601487142.181 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm
2020-09-30T17:32:22.185Z,1601487142.185 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm
2020-09-30T17:32:22.185Z,1601487142.185 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm
2020-09-30T17:32:22.189Z,1601487142.189 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm
2020-09-30T17:32:22.190Z,1601487142.190 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm
2020-09-30T17:32:22.194Z,1601487142.194 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water
2020-09-30T17:32:22.198Z,1601487142.198 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water
2020-09-30T17:32:22.199Z,1601487142.199 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-09-30T17:32:22.199Z,1601487142.199 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-09-30T17:32:22.203Z,1601487142.203 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-09-30T17:32:22.203Z,1601487142.203 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-09-30T17:32:22.207Z,1601487142.207 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-09-30T17:32:22.208Z,1601487142.208 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-09-30T17:32:22.212Z,1601487142.212 [WetLabsBB2FL] Loaded
2020-09-30T17:32:22.212Z,1601487142.212 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2020-09-30T17:32:22.213Z,1601487142.213 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B074E0
2020-09-30T17:32:22.213Z,1601487142.213 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 6620
2020-09-30T17:32:22.214Z,1601487142.214 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-09-30T17:32:22.218Z,1601487142.218 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-09-30T17:32:22.218Z,1601487142.218 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-09-30T17:32:22.225Z,1601487142.225 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-09-30T17:32:22.226Z,1601487142.226 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B374E0
2020-09-30T17:32:22.226Z,1601487142.226 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 6621
2020-09-30T17:32:22.231Z,1601487142.231 [Supervisor](INFO): Main Thread ID is 5459
2020-09-30T17:32:22.231Z,1601487142.231 [Supervisor](DEBUG): Running supervisor.
2020-09-30T17:32:22.232Z,1601487142.232 [CommandLine ThreadHandler](INFO): Handler Thread ID is 6622
2020-09-30T17:32:22.234Z,1601487142.234 [controlThread ThreadHandler](INFO): Handler Thread ID is 6623
2020-09-30T17:32:22.235Z,1601487142.235 [controlThread](DEBUG): Initializing ControlThread
2020-09-30T17:32:22.236Z,1601487142.236 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-09-30T17:32:22.237Z,1601487142.237 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-09-30T17:32:22.238Z,1601487142.238 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-09-30T17:32:22.238Z,1601487142.238 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-09-30T17:32:22.239Z,1601487142.239 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-09-30T17:32:22.239Z,1601487142.239 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-09-30T17:32:22.240Z,1601487142.240 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-09-30T17:32:22.240Z,1601487142.240 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-09-30T17:32:22.241Z,1601487142.241 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-09-30T17:32:22.241Z,1601487142.241 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-09-30T17:32:22.244Z,1601487142.244 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-09-30T17:32:22.245Z,1601487142.245 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-09-30T17:32:22.245Z,1601487142.245 [SBIT](INFO): Initialize SBIT Component.
2020-09-30T17:32:22.246Z,1601487142.246 [SBIT](IMPORTANT): git: 2020-09-25
2020-09-30T17:32:22.246Z,1601487142.246 [SBIT](INFO): git hash: 37975fc996eaab47e1849088435330a6c93ea7b4
2020-09-30T17:32:22.246Z,1601487142.246 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-09-30T17:32:22.247Z,1601487142.247 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
Kernel Reported: #1 PREEMPT Thu Aug 6 12:04:53 PDT 2020
2020-09-30T17:32:22.249Z,1601487142.249 [SBIT](INFO): Beginning SBIT in 99.000000 seconds.
2020-09-30T17:32:22.249Z,1601487142.249 [IBIT](INFO): Initialize IBIT Component.
2020-09-30T17:32:22.250Z,1601487142.250 [CBIT](DEBUG): Initialize CBIT Component.
2020-09-30T17:32:22.251Z,1601487142.251 [logger ThreadHandler](INFO): Handler Thread ID is 6624
2020-09-30T17:32:22.263Z,1601487142.263 [CBIT](DEBUG): Initialized mux pins.
2020-09-30T17:32:22.263Z,1601487142.263 [CBIT](DEBUG): Initializing the watchdog timer.
2020-09-30T17:32:22.271Z,1601487142.271 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 6625
2020-09-30T17:32:22.272Z,1601487142.272 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-09-30T17:32:22.283Z,1601487142.283 [Onboard ThreadHandler](INFO): Handler Thread ID is 6626
2020-09-30T17:32:22.288Z,1601487142.288 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2020-09-30T17:32:22.288Z,1601487142.288 [CBIT](DEBUG): Initializing heartbeat.
2020-09-30T17:32:22.301Z,1601487142.301 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 6627
2020-09-30T17:32:22.323Z,1601487142.323 [BackSeatDriver ThreadHandler](INFO): Handler Thread ID is 6628
2020-09-30T17:32:22.343Z,1601487142.343 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 6629
2020-09-30T17:32:22.344Z,1601487142.344 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-09-30T17:32:22.348Z,1601487142.348 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 6631
2020-09-30T17:32:22.349Z,1601487142.349 [WetLabsBB2FL](INFO): Powering down
2020-09-30T17:32:22.359Z,1601487142.359 [CBIT](DEBUG): Deactivating GF circuits.
2020-09-30T17:32:22.359Z,1601487142.359 [CBIT](DEBUG): Deactivating emergency mode.
2020-09-30T17:32:22.383Z,1601487142.383 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 6632
2020-09-30T17:32:22.386Z,1601487142.386 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-09-30T17:32:22.386Z,1601487142.386 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-09-30T17:32:22.387Z,1601487142.387 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-09-30T17:32:22.387Z,1601487142.387 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-09-30T17:32:22.387Z,1601487142.387 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-09-30T17:32:22.387Z,1601487142.387 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-09-30T17:32:22.388Z,1601487142.388 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-09-30T17:32:22.388Z,1601487142.388 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-09-30T17:32:22.388Z,1601487142.388 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-09-30T17:32:22.388Z,1601487142.388 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-09-30T17:32:22.388Z,1601487142.388 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-09-30T17:32:22.388Z,1601487142.388 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-09-30T17:32:22.389Z,1601487142.389 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-09-30T17:32:22.389Z,1601487142.389 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-09-30T17:32:22.389Z,1601487142.389 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-09-30T17:32:22.389Z,1601487142.389 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-09-30T17:32:22.395Z,1601487142.395 [CBIT](DEBUG): Backplane powered.
2020-09-30T17:32:22.397Z,1601487142.397 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-09-30T17:32:22.411Z,1601487142.411 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-09-30T17:32:22.430Z,1601487142.430 [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-30T17:32:22.431Z,1601487142.431 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-09-30T17:32:22.506Z,1601487142.506 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-09-30T17:32:22.526Z,1601487142.526 [Default:A.Wait](DEBUG): Construct Wait.
2020-09-30T17:32:22.528Z,1601487142.528 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-09-30T17:32:22.558Z,1601487142.558 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-09-30T17:32:22.584Z,1601487142.584 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-09-30T17:32:22.590Z,1601487142.590 [Default:E.Execute](DEBUG): Construct Execute.
2020-09-30T17:32:22.613Z,1601487142.613 [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-30T17:32:22.617Z,1601487142.617 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,NAL9602,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-09-30T17:32:22.627Z,1601487142.627 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-09-30T17:32:22.707Z,1601487142.707 [Radio_Surface](INFO): Powering up
2020-09-30T17:32:22.763Z,1601487142.763 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2020-09-30T17:32:22.763Z,1601487142.763 [DAT](INFO): Powering up
2020-09-30T17:32:22.763Z,1601487142.763 [DAT](DEBUG): Initializing DAT.
2020-09-30T17:32:22.831Z,1601487142.831 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2020-09-30T17:32:22.870Z,1601487142.870 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-09-30T17:32:22.883Z,1601487142.883 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-09-30T17:32:22.885Z,1601487142.885 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-09-30T17:32:22.895Z,1601487142.895 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-09-30T17:32:22.896Z,1601487142.896 [MassServo](DEBUG): Initializing EZServoServo.
2020-09-30T17:32:22.907Z,1601487142.907 [MassServo](DEBUG): Initializing MassServo.
2020-09-30T17:32:22.908Z,1601487142.908 [RudderServo](DEBUG): Initializing EZServoServo.
2020-09-30T17:32:22.919Z,1601487142.919 [RudderServo](DEBUG): Initializing RudderServo.
2020-09-30T17:32:22.920Z,1601487142.920 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-09-30T17:32:22.931Z,1601487142.931 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-09-30T17:32:22.934Z,1601487142.934 [CommandLine](FAULT): Scheduling is paused
2020-09-30T17:32:22.934Z,1601487142.934 [CBIT](INFO): Critical error at 20200930T173222
2020-09-30T17:32:22.934Z,1601487142.934 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2020-09-30T17:32:23.743Z,1601487143.743 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2020-09-30T17:32:23.743Z,1601487143.743 [RudderServo](FAULT): Rudder failed to initialize
2020-09-30T17:32:23.743Z,1601487143.743 [RudderServo] Communications Fault, FailCount= 1
2020-09-30T17:32:23.743Z,1601487143.743 [RudderServo](ERROR): Communications Fault
2020-09-30T17:32:23.866Z,1601487143.866 [CBIT](ERROR): Communications Fault in component: RudderServo
2020-09-30T17:32:24.028Z,1601487144.028 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-09-30T17:32:24.028Z,1601487144.028 [RudderServo](INFO): Powering down
2020-09-30T17:32:24.694Z,1601487144.694 [RudderServo](DEBUG): Initializing EZServoServo.
2020-09-30T17:32:24.812Z,1601487144.812 [RudderServo](DEBUG): Initializing RudderServo.
2020-09-30T17:32:24.816Z,1601487144.816 [CBIT](INFO): Clearing failed state for component RudderServo
2020-09-30T17:32:24.816Z,1601487144.816 [RudderServo] No Fault, FailCount= 1
2020-09-30T17:32:26.754Z,1601487146.754 [BuoyancyServo](INFO): Pause
2020-09-30T17:32:32.415Z,1601487152.415 [CBIT](CRITICAL): Environmental Failure. Press:14.554963 PSI. Humidity:57%. Temp:21 C. ABORTING MISSION
2020-09-30T17:32:32.817Z,1601487152.817 [CBIT](INFO): Critical error at 20200930T173232
2020-09-30T17:32:36.810Z,1601487156.810 [DAT](INFO): commRate: 800
2020-09-30T17:32:38.829Z,1601487158.829 [DAT](INFO): entering command mode
2020-09-30T17:32:39.233Z,1601487159.233 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T17:32:39.637Z,1601487159.637 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T17:32:40.040Z,1601487160.040 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T17:32:40.444Z,1601487160.444 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T17:32:40.848Z,1601487160.848 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T17:32:41.252Z,1601487161.252 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T17:32:41.656Z,1601487161.656 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T17:32:42.060Z,1601487162.060 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T17:32:42.464Z,1601487162.464 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T17:32:42.877Z,1601487162.877 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T17:32:43.272Z,1601487163.272 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T17:32:43.676Z,1601487163.676 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T17:32:44.080Z,1601487164.080 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T17:32:44.484Z,1601487164.484 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T17:32:44.884Z,1601487164.884 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T17:32:45.295Z,1601487165.295 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T17:32:45.693Z,1601487165.693 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T17:32:46.096Z,1601487166.096 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T17:32:46.500Z,1601487166.500 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T17:32:46.904Z,1601487166.904 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T17:32:47.310Z,1601487167.310 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T17:32:47.724Z,1601487167.724 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T17:32:48.117Z,1601487168.117 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T17:32:48.530Z,1601487168.530 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T17:32:48.932Z,1601487168.932 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T17:32:49.329Z,1601487169.329 [NAL9602](INFO): Powering up NAL9602
2020-09-30T17:32:49.330Z,1601487169.330 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T17:32:49.732Z,1601487169.732 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T17:32:50.148Z,1601487170.148 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T17:32:50.541Z,1601487170.541 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T17:32:50.944Z,1601487170.944 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T17:32:51.348Z,1601487171.348 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T17:32:51.756Z,1601487171.756 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T17:32:52.156Z,1601487172.156 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T17:32:52.564Z,1601487172.564 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T17:32:52.976Z,1601487172.976 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T17:32:53.051Z,1601487173.051 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:32:53.368Z,1601487173.368 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T17:32:53.378Z,1601487173.378 [BuoyancyServo](INFO): Data requested
2020-09-30T17:32:53.423Z,1601487173.423 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:32:53.779Z,1601487173.779 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T17:32:53.847Z,1601487173.847 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:32:54.194Z,1601487174.194 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-30T17:32:54.194Z,1601487174.194 [DAT](FAULT): failed to enter command mode
2020-09-30T17:32:54.263Z,1601487174.263 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:32:54.580Z,1601487174.580 [DAT](INFO): entering command mode
2020-09-30T17:32:54.662Z,1601487174.662 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:32:54.986Z,1601487174.986 [DAT](INFO): setting verbose to 3
2020-09-30T17:32:55.043Z,1601487175.043 [BuoyancyServo](INFO): Data requested
2020-09-30T17:32:55.076Z,1601487175.076 [CBIT](INFO): got buoyancy position: 958.068970
2020-09-30T17:32:55.406Z,1601487175.406 [DAT](INFO): set verbose to 3
2020-09-30T17:32:55.406Z,1601487175.406 [DAT](INFO): setting DatVerbose to 27440
2020-09-30T17:32:55.805Z,1601487175.805 [DAT](INFO): set DatVerbose to 27440
2020-09-30T17:32:55.806Z,1601487175.806 [DAT](INFO): setting transmit power to 8
2020-09-30T17:32:55.857Z,1601487175.857 [BuoyancyServo](INFO): Pause
2020-09-30T17:32:56.197Z,1601487176.197 [DAT](INFO): set transmit power to 8
2020-09-30T17:32:56.198Z,1601487176.198 [DAT](INFO): setting local address to 9
2020-09-30T17:32:56.605Z,1601487176.605 [DAT](INFO): set local address to 9
2020-09-30T17:33:00.240Z,1601487180.240 [NAL9602](INFO): NAL9602 initialized
2020-09-30T17:33:25.318Z,1601487205.318 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:33:25.703Z,1601487205.703 [BuoyancyServo](INFO): Data requested
2020-09-30T17:33:25.707Z,1601487205.707 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:33:26.116Z,1601487206.116 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:33:26.521Z,1601487206.521 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:33:26.935Z,1601487206.935 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:33:27.351Z,1601487207.351 [BuoyancyServo](INFO): Data requested
2020-09-30T17:33:27.374Z,1601487207.374 [CBIT](INFO): got buoyancy position: 958.068970
2020-09-30T17:33:28.134Z,1601487208.134 [BuoyancyServo](INFO): Pause
2020-09-30T17:33:57.634Z,1601487237.634 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:33:58.045Z,1601487238.045 [BuoyancyServo](INFO): Data requested
2020-09-30T17:33:58.050Z,1601487238.050 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:33:58.456Z,1601487238.456 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:33:58.839Z,1601487238.839 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:33:59.246Z,1601487239.246 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:33:59.675Z,1601487239.675 [BuoyancyServo](INFO): Data requested
2020-09-30T17:33:59.680Z,1601487239.680 [CBIT](INFO): got buoyancy position: 957.934692
2020-09-30T17:34:00.486Z,1601487240.486 [BuoyancyServo](INFO): Pause
2020-09-30T17:34:01.687Z,1601487241.687 [SBIT](IMPORTANT): Beginning Startup BIT
2020-09-30T17:34:01.691Z,1601487241.691 [CBIT](IMPORTANT): Beginning ground fault scan
2020-09-30T17:34:12.633Z,1601487252.633 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.011745
CHAN A1 (24V): -0.000346
CHAN A2 (12V): -0.007253
CHAN A3 (5V): -0.002331
CHAN B0 (3.3V): 0.000534
CHAN B1 (3.15aV): -0.000722
CHAN B2 (3.15bV): 0.000071
CHAN B3 (GND): 0.002051
OPEN: 0.005282
Full Scale Calc: 4.765 mA, -1.589 mA
2020-09-30T17:34:29.979Z,1601487269.979 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:34:30.385Z,1601487270.385 [BuoyancyServo](INFO): Data requested
2020-09-30T17:34:30.428Z,1601487270.428 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:34:30.807Z,1601487270.807 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:34:31.195Z,1601487271.195 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:34:31.591Z,1601487271.591 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:34:32.024Z,1601487272.024 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:34:32.447Z,1601487272.447 [BuoyancyServo](INFO): Data requested
2020-09-30T17:34:32.491Z,1601487272.491 [CBIT](INFO): got buoyancy position: 958.203247
2020-09-30T17:34:33.195Z,1601487273.195 [BuoyancyServo](INFO): Pause
2020-09-30T17:34:50.338Z,1601487290.338 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005323
2020-09-30T17:34:55.804Z,1601487295.804 [SBIT](IMPORTANT): SBIT PASSED
2020-09-30T17:34:55.805Z,1601487295.805 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-09-30T17:34:55.805Z,1601487295.805 [SBIT](IMPORTANT): AMEcho.loadAtStartup=0 bool;

2020-09-30T17:34:55.806Z,1601487295.806 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=6 count;

2020-09-30T17:34:55.806Z,1601487295.806 [SBIT](IMPORTANT): CBIT.gf24Offset=145 microampere;

2020-09-30T17:34:55.806Z,1601487295.806 [SBIT](IMPORTANT): DAT.loadAtStartup=1 bool;

2020-09-30T17:34:55.806Z,1601487295.806 [SBIT](IMPORTANT): DAT.sbdAddress=-1 enum;

2020-09-30T17:34:55.806Z,1601487295.806 [SBIT](IMPORTANT): DAT.surfaceThreshold=100 meter;

2020-09-30T17:34:55.806Z,1601487295.806 [SBIT](IMPORTANT): DAT.verbosity=0 count;

2020-09-30T17:34:55.806Z,1601487295.806 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2020-09-30T17:34:55.806Z,1601487295.806 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=1 bool;

2020-09-30T17:34:55.806Z,1601487295.806 [SBIT](IMPORTANT): Express linearApproximation AMEcho.altitude 1.000000 meter;

2020-09-30T17:34:55.807Z,1601487295.807 [SBIT](IMPORTANT): Express none ThrusterServo.component_avgCurrent;

2020-09-30T17:34:55.823Z,1601487295.823 [SBIT](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 1.000000 liter_per_second;

2020-09-30T17:34:55.823Z,1601487295.823 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 5.000000 meter;

2020-09-30T17:34:55.823Z,1601487295.823 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=13 volt;

2020-09-30T17:34:55.823Z,1601487295.823 [SBIT](IMPORTANT): RDI_Pathfinder.loadAtStartup=0 bool;

2020-09-30T17:34:55.823Z,1601487295.823 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=170 cubic_centimeter;

2020-09-30T17:34:55.823Z,1601487295.823 [SBIT](IMPORTANT): VerticalControl.massDefault=20 millimeter;

2020-09-30T17:34:55.824Z,1601487295.824 [SBIT](IMPORTANT): WetLabsUBAT.loadAtStartup=0 bool;

2020-09-30T17:34:56.224Z,1601487296.224 [MissionManager](IMPORTANT): Started mission Startup
2020-09-30T17:34:56.224Z,1601487296.224 [Startup] Running Loop=1
2020-09-30T17:34:56.224Z,1601487296.224 [Startup](DEBUG): Aggregate::initialize Startup
2020-09-30T17:34:56.224Z,1601487296.224 [Startup:A.GoToSurface] Running Loop=1
2020-09-30T17:34:56.224Z,1601487296.224 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-09-30T17:34:56.226Z,1601487296.226 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-09-30T17:34:56.226Z,1601487296.226 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-09-30T17:34:56.226Z,1601487296.226 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-09-30T17:34:56.227Z,1601487296.227 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-09-30T17:34:56.227Z,1601487296.227 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-09-30T17:34:56.229Z,1601487296.229 [Startup:StartupSatComms] Running Loop=1
2020-09-30T17:34:56.229Z,1601487296.229 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-09-30T17:34:56.229Z,1601487296.229 [Startup:StartupSatComms:A] Running Loop=1
2020-09-30T17:34:56.689Z,1601487296.689 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-09-30T17:35:02.669Z,1601487302.669 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:35:03.076Z,1601487303.076 [BuoyancyServo](INFO): Data requested
2020-09-30T17:35:03.080Z,1601487303.080 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:35:03.501Z,1601487303.501 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:35:03.907Z,1601487303.907 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:35:04.326Z,1601487304.326 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:35:04.737Z,1601487304.737 [BuoyancyServo](INFO): Data requested
2020-09-30T17:35:04.742Z,1601487304.742 [CBIT](INFO): got buoyancy position: 958.203247
2020-09-30T17:35:05.497Z,1601487305.497 [BuoyancyServo](INFO): Pause
2020-09-30T17:35:22.576Z,1601487322.576 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2020-09-30T17:35:22.576Z,1601487322.576 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-09-30T17:35:22.596Z,1601487322.596 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-09-30T17:35:22.918Z,1601487322.918 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-09-30T17:35:22.918Z,1601487322.918 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2020-09-30T17:35:35.018Z,1601487335.018 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:35:35.408Z,1601487335.408 [BuoyancyServo](INFO): Data requested
2020-09-30T17:35:35.440Z,1601487335.440 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:35:35.821Z,1601487335.821 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:35:36.279Z,1601487336.279 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:35:36.636Z,1601487336.636 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:35:37.112Z,1601487337.112 [BuoyancyServo](INFO): Data requested
2020-09-30T17:35:37.117Z,1601487337.117 [CBIT](INFO): got buoyancy position: 957.531738
2020-09-30T17:35:37.836Z,1601487337.836 [BuoyancyServo](INFO): Pause
2020-09-30T17:35:56.412Z,1601487356.412 [Startup:StartupSatComms:A](INFO): Timed out from 2020-09-30T17:34:56.2Z
2020-09-30T17:35:56.412Z,1601487356.412 [Startup:StartupSatComms:A] Stopped
2020-09-30T17:35:56.412Z,1601487356.412 [Startup:StartupSatComms:B] Running Loop=1
2020-09-30T17:35:56.817Z,1601487356.817 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-09-30T17:36:00.027Z,1601487360.027 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200930T172128/Courier0007.lzma
2020-09-30T17:36:00.433Z,1601487360.433 [DataOverHttps](INFO): Moved sent file to Logs/20200930T172128/Courier0007.lzma.bak
2020-09-30T17:36:00.433Z,1601487360.433 [DataOverHttps](INFO): SBD MOMSN=12687281
2020-09-30T17:36:07.444Z,1601487367.444 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:36:07.828Z,1601487367.828 [BuoyancyServo](INFO): Data requested
2020-09-30T17:36:07.852Z,1601487367.852 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:36:08.226Z,1601487368.226 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:36:08.636Z,1601487368.636 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:36:09.049Z,1601487369.049 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:36:09.467Z,1601487369.467 [BuoyancyServo](INFO): Data requested
2020-09-30T17:36:09.471Z,1601487369.471 [CBIT](INFO): got buoyancy position: 958.068970
2020-09-30T17:36:10.248Z,1601487370.248 [BuoyancyServo](INFO): Pause
2020-09-30T17:36:15.883Z,1601487375.883 [DataOverHttps](INFO): Sending 290 bytes from file Logs/20200930T173212/Courier0000.lzma
2020-09-30T17:36:16.885Z,1601487376.885 [DataOverHttps](INFO): Moved sent file to Logs/20200930T173212/Courier0000.lzma.bak
2020-09-30T17:36:16.885Z,1601487376.885 [DataOverHttps](INFO): SBD MOMSN=12687283
2020-09-30T17:36:34.992Z,1601487394.992 [DataOverHttps](INFO): Sending 1145 bytes from file Logs/20200930T171645/Express0001.lzma
2020-09-30T17:36:35.993Z,1601487395.993 [DataOverHttps](INFO): Moved sent file to Logs/20200930T171645/Express0001.lzma.bak
2020-09-30T17:36:35.993Z,1601487395.993 [DataOverHttps](INFO): SBD MOMSN=12687288
2020-09-30T17:36:39.748Z,1601487399.748 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:36:40.156Z,1601487400.156 [BuoyancyServo](INFO): Data requested
2020-09-30T17:36:40.161Z,1601487400.161 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:36:40.583Z,1601487400.583 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:36:40.960Z,1601487400.960 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:36:41.385Z,1601487401.385 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:36:41.788Z,1601487401.788 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:36:42.220Z,1601487402.220 [BuoyancyServo](INFO): Data requested
2020-09-30T17:36:42.235Z,1601487402.235 [CBIT](INFO): got buoyancy position: 957.934692
2020-09-30T17:36:43.119Z,1601487403.119 [BuoyancyServo](INFO): Pause
2020-09-30T17:36:52.734Z,1601487412.734 [DataOverHttps](INFO): Sending 88 bytes from file Logs/20200930T171645/Express0005.lzma
2020-09-30T17:36:53.737Z,1601487413.737 [DataOverHttps](INFO): Moved sent file to Logs/20200930T171645/Express0005.lzma.bak
2020-09-30T17:36:53.737Z,1601487413.737 [DataOverHttps](INFO): SBD MOMSN=12687322
2020-09-30T17:36:56.767Z,1601487416.767 [Startup:StartupSatComms:B](INFO): Timed out from 2020-09-30T17:35:56.4Z
2020-09-30T17:36:56.767Z,1601487416.767 [Startup:StartupSatComms:B] Stopped
2020-09-30T17:36:56.767Z,1601487416.767 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2020-09-30T17:36:56.767Z,1601487416.767 [Startup:StartupSatComms] Stopped
2020-09-30T17:36:56.768Z,1601487416.768 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-09-30T17:36:56.768Z,1601487416.768 [Startup](INFO): Completed Startup
2020-09-30T17:36:56.768Z,1601487416.768 [MissionManager](INFO): Startup is completed.
2020-09-30T17:36:56.769Z,1601487416.769 [MissionManager](INFO): Uninitializing Mission Startup
2020-09-30T17:36:56.769Z,1601487416.769 [Startup] Stopped
2020-09-30T17:36:56.769Z,1601487416.769 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-09-30T17:36:56.769Z,1601487416.769 [Startup:A.GoToSurface] Stopped
2020-09-30T17:36:56.769Z,1601487416.769 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-09-30T17:36:57.115Z,1601487417.115 [MissionManager](IMPORTANT): Started mission Default
2020-09-30T17:36:57.115Z,1601487417.115 [Default] Running Loop=1
2020-09-30T17:36:57.115Z,1601487417.115 [Default](DEBUG): Aggregate::initialize Default
2020-09-30T17:36:57.115Z,1601487417.115 [Default:B.GoToSurface] Running Loop=1
2020-09-30T17:36:57.115Z,1601487417.115 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-09-30T17:36:57.116Z,1601487417.116 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-09-30T17:36:57.116Z,1601487417.116 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-09-30T17:36:57.116Z,1601487417.116 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-09-30T17:36:57.117Z,1601487417.117 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-09-30T17:36:57.117Z,1601487417.117 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-09-30T17:36:57.117Z,1601487417.117 [Default:A.Wait] Running Loop=1
2020-09-30T17:36:57.117Z,1601487417.117 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-09-30T17:37:10.186Z,1601487430.186 [DataOverHttps](INFO): Sending 2247 bytes from file Logs/20200930T172128/Express0001.lzma
2020-09-30T17:37:10.515Z,1601487430.515 [Default:A.Wait](INFO): Done Waiting.
2020-09-30T17:37:10.516Z,1601487430.516 [Default:A.Wait] Stopped
2020-09-30T17:37:10.516Z,1601487430.516 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-09-30T17:37:10.863Z,1601487430.863 [Default:CheckIn] Running Loop=1
2020-09-30T17:37:10.863Z,1601487430.863 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-09-30T17:37:10.863Z,1601487430.863 [Default:CheckIn:Read_GPS] Running Loop=1
2020-09-30T17:37:11.185Z,1601487431.185 [DataOverHttps](INFO): Moved sent file to Logs/20200930T172128/Express0001.lzma.bak
2020-09-30T17:37:11.185Z,1601487431.185 [DataOverHttps](INFO): SBD MOMSN=12687325
2020-09-30T17:37:11.332Z,1601487431.332 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-09-30T17:37:12.484Z,1601487432.484 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:37:12.864Z,1601487432.864 [BuoyancyServo](INFO): Data requested
2020-09-30T17:37:12.868Z,1601487432.868 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:37:13.277Z,1601487433.277 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:37:13.688Z,1601487433.688 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:37:14.096Z,1601487434.096 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:37:14.535Z,1601487434.535 [BuoyancyServo](INFO): Data requested
2020-09-30T17:37:14.540Z,1601487434.540 [CBIT](INFO): got buoyancy position: 957.934692
2020-09-30T17:37:15.312Z,1601487435.312 [BuoyancyServo](INFO): Pause
2020-09-30T17:37:44.848Z,1601487464.848 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:37:45.251Z,1601487465.251 [BuoyancyServo](INFO): Data requested
2020-09-30T17:37:45.256Z,1601487465.256 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:37:45.671Z,1601487465.671 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:37:46.085Z,1601487466.085 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:37:46.476Z,1601487466.476 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:37:46.911Z,1601487466.911 [BuoyancyServo](INFO): Data requested
2020-09-30T17:37:46.916Z,1601487466.916 [CBIT](INFO): got buoyancy position: 958.068970
2020-09-30T17:37:47.676Z,1601487467.676 [BuoyancyServo](INFO): Pause
2020-09-30T17:38:03.412Z,1601487483.412 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-09-30T17:38:17.168Z,1601487497.168 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:38:17.637Z,1601487497.637 [BuoyancyServo](INFO): Data requested
2020-09-30T17:38:17.645Z,1601487497.645 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:38:17.999Z,1601487497.999 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:38:18.669Z,1601487498.669 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:38:18.864Z,1601487498.864 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:38:19.278Z,1601487499.278 [BuoyancyServo](INFO): Data requested
2020-09-30T17:38:19.282Z,1601487499.282 [CBIT](INFO): got buoyancy position: 958.068970
2020-09-30T17:38:19.988Z,1601487499.988 [BuoyancyServo](INFO): Pause
2020-09-30T17:38:23.637Z,1601487503.637 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2020-09-30T17:38:23.637Z,1601487503.637 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-09-30T17:38:23.647Z,1601487503.647 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-09-30T17:38:24.056Z,1601487504.056 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-09-30T17:38:24.056Z,1601487504.056 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2020-09-30T17:38:49.488Z,1601487529.488 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:38:49.892Z,1601487529.892 [BuoyancyServo](INFO): Data requested
2020-09-30T17:38:49.896Z,1601487529.896 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:38:50.315Z,1601487530.315 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:38:50.728Z,1601487530.728 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:38:51.130Z,1601487531.130 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:38:51.547Z,1601487531.547 [BuoyancyServo](INFO): Data requested
2020-09-30T17:38:51.552Z,1601487531.552 [CBIT](INFO): got buoyancy position: 957.934692
2020-09-30T17:38:52.333Z,1601487532.333 [BuoyancyServo](INFO): Pause
2020-09-30T17:39:21.830Z,1601487561.830 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:39:22.241Z,1601487562.241 [BuoyancyServo](INFO): Data requested
2020-09-30T17:39:22.249Z,1601487562.249 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:39:22.673Z,1601487562.673 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:39:23.042Z,1601487563.042 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:39:23.440Z,1601487563.440 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:39:23.828Z,1601487563.828 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:39:24.271Z,1601487564.271 [BuoyancyServo](INFO): Data requested
2020-09-30T17:39:24.276Z,1601487564.276 [CBIT](INFO): got buoyancy position: 958.203247
2020-09-30T17:39:25.075Z,1601487565.075 [BuoyancyServo](INFO): Pause
2020-09-30T17:39:54.557Z,1601487594.557 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:39:54.935Z,1601487594.935 [BuoyancyServo](INFO): Data requested
2020-09-30T17:39:54.939Z,1601487594.939 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:39:55.369Z,1601487595.369 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:39:55.761Z,1601487595.761 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:39:56.177Z,1601487596.177 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:39:56.587Z,1601487596.587 [BuoyancyServo](INFO): Data requested
2020-09-30T17:39:56.592Z,1601487596.592 [CBIT](INFO): got buoyancy position: 957.934692
2020-09-30T17:39:57.373Z,1601487597.373 [BuoyancyServo](INFO): Pause
2020-09-30T17:40:26.870Z,1601487626.870 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:40:27.256Z,1601487627.256 [BuoyancyServo](INFO): Data requested
2020-09-30T17:40:27.260Z,1601487627.260 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:40:27.664Z,1601487627.664 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:40:28.072Z,1601487628.072 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:40:28.493Z,1601487628.493 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:40:28.936Z,1601487628.936 [BuoyancyServo](INFO): Data requested
2020-09-30T17:40:28.963Z,1601487628.963 [CBIT](INFO): got buoyancy position: 958.068970
2020-09-30T17:40:29.705Z,1601487629.705 [BuoyancyServo](INFO): Pause
2020-09-30T17:40:46.249Z,1601487646.249 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-09-30T17:40:46.252Z,1601487646.252 [BPC1](INFO): Received data from all battery sticks.
2020-09-30T17:40:59.180Z,1601487659.180 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:40:59.585Z,1601487659.585 [BuoyancyServo](INFO): Data requested
2020-09-30T17:40:59.590Z,1601487659.590 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:41:00.010Z,1601487660.010 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:41:00.392Z,1601487660.392 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:41:00.793Z,1601487660.793 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:41:01.196Z,1601487661.196 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:41:01.616Z,1601487661.616 [BuoyancyServo](INFO): Data requested
2020-09-30T17:41:01.620Z,1601487661.620 [CBIT](INFO): got buoyancy position: 958.068970
2020-09-30T17:41:02.421Z,1601487662.421 [BuoyancyServo](INFO): Pause
2020-09-30T17:41:24.642Z,1601487684.642 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2020-09-30T17:41:24.642Z,1601487684.642 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-09-30T17:41:24.652Z,1601487684.652 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-09-30T17:41:25.044Z,1601487685.044 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-09-30T17:41:25.044Z,1601487685.044 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2020-09-30T17:41:31.895Z,1601487691.895 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:41:32.308Z,1601487692.308 [BuoyancyServo](INFO): Data requested
2020-09-30T17:41:32.312Z,1601487692.312 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:41:32.724Z,1601487692.724 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:41:33.107Z,1601487693.107 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:41:33.524Z,1601487693.524 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:41:33.967Z,1601487693.967 [BuoyancyServo](INFO): Data requested
2020-09-30T17:41:33.971Z,1601487693.971 [CBIT](INFO): got buoyancy position: 958.203247
2020-09-30T17:41:34.724Z,1601487694.724 [BuoyancyServo](INFO): Pause
2020-09-30T17:42:04.242Z,1601487724.242 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:42:04.632Z,1601487724.632 [BuoyancyServo](INFO): Data requested
2020-09-30T17:42:04.636Z,1601487724.636 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:42:05.039Z,1601487725.039 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:42:05.447Z,1601487725.447 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:42:05.831Z,1601487725.831 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:42:06.233Z,1601487726.233 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:42:06.675Z,1601487726.675 [BuoyancyServo](INFO): Data requested
2020-09-30T17:42:06.702Z,1601487726.702 [CBIT](INFO): got buoyancy position: 957.934692
2020-09-30T17:42:07.473Z,1601487727.473 [BuoyancyServo](INFO): Pause
2020-09-30T17:42:11.092Z,1601487731.092 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-09-30T17:37:10.9Z
2020-09-30T17:42:11.092Z,1601487731.092 [Default:CheckIn:Read_GPS] Stopped
2020-09-30T17:42:11.092Z,1601487731.092 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-09-30T17:42:11.486Z,1601487731.486 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-09-30T17:42:18.047Z,1601487738.047 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20200930T173212/Courier0004.lzma
2020-09-30T17:42:19.049Z,1601487739.049 [DataOverHttps](INFO): Moved sent file to Logs/20200930T173212/Courier0004.lzma.bak
2020-09-30T17:42:19.049Z,1601487739.049 [DataOverHttps](INFO): SBD MOMSN=12687871
2020-09-30T17:42:35.450Z,1601487755.450 [DataOverHttps](INFO): Sending 2239 bytes from file Logs/20200930T172128/Express0005.lzma
2020-09-30T17:42:36.449Z,1601487756.449 [DataOverHttps](INFO): Moved sent file to Logs/20200930T172128/Express0005.lzma.bak
2020-09-30T17:42:36.449Z,1601487756.449 [DataOverHttps](INFO): SBD MOMSN=12687873
2020-09-30T17:42:36.959Z,1601487756.959 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:42:37.352Z,1601487757.352 [BuoyancyServo](INFO): Data requested
2020-09-30T17:42:37.372Z,1601487757.372 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:42:37.815Z,1601487757.815 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:42:38.184Z,1601487758.184 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:42:38.580Z,1601487758.580 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:42:38.979Z,1601487758.979 [BuoyancyServo](INFO): Data requested
2020-09-30T17:42:38.984Z,1601487758.984 [CBIT](INFO): got buoyancy position: 958.068970
2020-09-30T17:42:39.770Z,1601487759.770 [BuoyancyServo](INFO): Pause
2020-09-30T17:42:54.367Z,1601487774.367 [DataOverHttps](INFO): Sending 401 bytes from file Logs/20200930T172128/Express0008.lzma
2020-09-30T17:42:55.373Z,1601487775.373 [DataOverHttps](INFO): Moved sent file to Logs/20200930T172128/Express0008.lzma.bak
2020-09-30T17:42:55.373Z,1601487775.373 [DataOverHttps](INFO): SBD MOMSN=12688803
2020-09-30T17:43:01.167Z,1601487781.167 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-09-30T17:43:01.167Z,1601487781.167 [NAL9602] Data Fault, FailCount= 1
2020-09-30T17:43:01.167Z,1601487781.167 [NAL9602](ERROR): Data Fault
2020-09-30T17:43:01.196Z,1601487781.196 [CBIT](ERROR): Data Fault in component: NAL9602
2020-09-30T17:43:01.564Z,1601487781.564 [NAL9602](INFO): Powering down
2020-09-30T17:43:02.391Z,1601487782.391 [CBIT](INFO): Clearing failed state for component NAL9602
2020-09-30T17:43:02.392Z,1601487782.392 [NAL9602] No Fault, FailCount= 1
2020-09-30T17:43:09.276Z,1601487789.276 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:43:09.685Z,1601487789.685 [BuoyancyServo](INFO): Data requested
2020-09-30T17:43:09.689Z,1601487789.689 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:43:10.094Z,1601487790.094 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:43:10.468Z,1601487790.468 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:43:10.876Z,1601487790.876 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:43:11.291Z,1601487791.291 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:43:11.704Z,1601487791.704 [BuoyancyServo](INFO): Data requested
2020-09-30T17:43:11.708Z,1601487791.708 [CBIT](INFO): got buoyancy position: 958.203247
2020-09-30T17:43:11.837Z,1601487791.837 [DataOverHttps](INFO): Sending 1259 bytes from file Logs/20200930T173212/Express0001.lzma
2020-09-30T17:43:12.491Z,1601487792.491 [BuoyancyServo](INFO): Pause
2020-09-30T17:43:12.837Z,1601487792.837 [DataOverHttps](INFO): Moved sent file to Logs/20200930T173212/Express0001.lzma.bak
2020-09-30T17:43:12.838Z,1601487792.838 [DataOverHttps](INFO): SBD MOMSN=12688908
2020-09-30T17:43:30.991Z,1601487810.991 [DataOverHttps](INFO): Sending 421 bytes from file Logs/20200930T173212/Express0005.lzma
2020-09-30T17:43:31.874Z,1601487811.874 [NAL9602](INFO): Powering up NAL9602
2020-09-30T17:43:31.993Z,1601487811.993 [DataOverHttps](INFO): Moved sent file to Logs/20200930T173212/Express0005.lzma.bak
2020-09-30T17:43:31.993Z,1601487811.993 [DataOverHttps](INFO): SBD MOMSN=12688972
2020-09-30T17:43:34.296Z,1601487814.296 [Default:CheckIn:Read_Iridium] Stopped
2020-09-30T17:43:34.296Z,1601487814.296 [Default:CheckIn:C.Wait] Running Loop=1
2020-09-30T17:43:34.296Z,1601487814.296 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-09-30T17:43:41.991Z,1601487821.991 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:43:42.406Z,1601487822.406 [BuoyancyServo](INFO): Data requested
2020-09-30T17:43:42.411Z,1601487822.411 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:43:42.776Z,1601487822.776 [NAL9602](INFO): NAL9602 initialized
2020-09-30T17:43:42.792Z,1601487822.792 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:43:43.204Z,1601487823.204 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:43:43.614Z,1601487823.614 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:43:44.052Z,1601487824.052 [BuoyancyServo](INFO): Data requested
2020-09-30T17:43:44.067Z,1601487824.067 [CBIT](INFO): got buoyancy position: 958.203247
2020-09-30T17:43:44.836Z,1601487824.836 [BuoyancyServo](INFO): Pause
2020-09-30T17:44:14.304Z,1601487854.304 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:44:14.713Z,1601487854.713 [BuoyancyServo](INFO): Data requested
2020-09-30T17:44:14.717Z,1601487854.717 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:44:15.141Z,1601487855.141 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:44:15.540Z,1601487855.540 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:44:15.948Z,1601487855.948 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:44:16.359Z,1601487856.359 [BuoyancyServo](INFO): Data requested
2020-09-30T17:44:16.364Z,1601487856.364 [CBIT](INFO): got buoyancy position: 957.934692
2020-09-30T17:44:17.140Z,1601487857.140 [BuoyancyServo](INFO): Pause
2020-09-30T17:44:25.606Z,1601487865.606 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2020-09-30T17:44:25.606Z,1601487865.606 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-09-30T17:44:25.616Z,1601487865.616 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-09-30T17:44:26.024Z,1601487866.024 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-09-30T17:44:26.024Z,1601487866.024 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2020-09-30T17:44:46.623Z,1601487886.623 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:44:47.025Z,1601487887.025 [BuoyancyServo](INFO): Data requested
2020-09-30T17:44:47.029Z,1601487887.029 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:44:47.440Z,1601487887.440 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:44:47.853Z,1601487887.853 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:44:48.274Z,1601487888.274 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:44:48.676Z,1601487888.676 [BuoyancyServo](INFO): Data requested
2020-09-30T17:44:48.680Z,1601487888.680 [CBIT](INFO): got buoyancy position: 958.068970
2020-09-30T17:44:49.470Z,1601487889.470 [BuoyancyServo](INFO): Pause
2020-09-30T17:45:18.943Z,1601487918.943 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:45:19.346Z,1601487919.346 [BuoyancyServo](INFO): Data requested
2020-09-30T17:45:19.350Z,1601487919.350 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:45:19.769Z,1601487919.769 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:45:20.185Z,1601487920.185 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:45:20.572Z,1601487920.572 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:45:20.999Z,1601487920.999 [BuoyancyServo](INFO): Data requested
2020-09-30T17:45:21.022Z,1601487921.022 [CBIT](INFO): got buoyancy position: 958.068970
2020-09-30T17:45:21.799Z,1601487921.799 [BuoyancyServo](INFO): Pause
2020-09-30T17:45:51.285Z,1601487951.285 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:45:51.707Z,1601487951.707 [BuoyancyServo](INFO): Data requested
2020-09-30T17:45:51.747Z,1601487951.747 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:45:52.092Z,1601487952.092 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:45:52.505Z,1601487952.505 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:45:52.895Z,1601487952.895 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:45:53.307Z,1601487953.307 [BuoyancyServo](INFO): Data requested
2020-09-30T17:45:53.312Z,1601487953.312 [CBIT](INFO): got buoyancy position: 957.531738
2020-09-30T17:45:54.094Z,1601487954.094 [BuoyancyServo](INFO): Pause
2020-09-30T17:46:23.600Z,1601487983.600 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:46:23.989Z,1601487983.989 [BuoyancyServo](INFO): Data requested
2020-09-30T17:46:23.993Z,1601487983.993 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:46:24.410Z,1601487984.410 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:46:24.795Z,1601487984.795 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:46:25.200Z,1601487985.200 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:46:25.609Z,1601487985.609 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:46:26.055Z,1601487986.055 [BuoyancyServo](INFO): Data requested
2020-09-30T17:46:26.067Z,1601487986.067 [CBIT](INFO): got buoyancy position: 958.068970
2020-09-30T17:46:26.833Z,1601487986.833 [BuoyancyServo](INFO): Pause
2020-09-30T17:46:56.326Z,1601488016.326 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:46:56.708Z,1601488016.708 [BuoyancyServo](INFO): Data requested
2020-09-30T17:46:56.712Z,1601488016.712 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:46:57.133Z,1601488017.133 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:46:57.537Z,1601488017.537 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:46:57.944Z,1601488017.944 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:46:58.351Z,1601488018.351 [BuoyancyServo](INFO): Data requested
2020-09-30T17:46:58.356Z,1601488018.356 [CBIT](INFO): got buoyancy position: 958.068970
2020-09-30T17:46:59.142Z,1601488019.142 [BuoyancyServo](INFO): Pause
2020-09-30T17:47:26.602Z,1601488046.602 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2020-09-30T17:47:26.602Z,1601488046.602 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-09-30T17:47:26.612Z,1601488046.612 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-09-30T17:47:27.032Z,1601488047.032 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-09-30T17:47:27.033Z,1601488047.033 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2020-09-30T17:47:28.643Z,1601488048.643 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:47:29.028Z,1601488049.028 [BuoyancyServo](INFO): Data requested
2020-09-30T17:47:29.032Z,1601488049.032 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:47:29.436Z,1601488049.436 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:47:29.860Z,1601488049.860 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:47:30.261Z,1601488050.261 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:47:30.699Z,1601488050.699 [BuoyancyServo](INFO): Data requested
2020-09-30T17:47:30.704Z,1601488050.704 [CBIT](INFO): got buoyancy position: 957.934692
2020-09-30T17:47:31.464Z,1601488051.464 [BuoyancyServo](INFO): Pause
2020-09-30T17:48:00.962Z,1601488080.962 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:48:01.373Z,1601488081.373 [BuoyancyServo](INFO): Data requested
2020-09-30T17:48:01.377Z,1601488081.377 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:48:01.769Z,1601488081.769 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:48:02.166Z,1601488082.166 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:48:02.582Z,1601488082.582 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:48:02.991Z,1601488082.991 [BuoyancyServo](INFO): Data requested
2020-09-30T17:48:02.996Z,1601488082.996 [CBIT](INFO): got buoyancy position: 957.800415
2020-09-30T17:48:03.797Z,1601488083.797 [BuoyancyServo](INFO): Pause
2020-09-30T17:48:33.275Z,1601488113.275 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:48:33.679Z,1601488113.679 [BuoyancyServo](INFO): Data requested
2020-09-30T17:48:33.683Z,1601488113.683 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:48:34.105Z,1601488114.105 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:48:34.517Z,1601488114.517 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:48:34.896Z,1601488114.896 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-09-30T17:48:34.896Z,1601488114.896 [Default:CheckIn:C.Wait] Stopped
2020-09-30T17:48:34.896Z,1601488114.896 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-09-30T17:48:34.896Z,1601488114.896 [Default:CheckIn:D] Running Loop=1
2020-09-30T17:48:34.905Z,1601488114.905 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:48:35.276Z,1601488115.276 [Default:CheckIn:D] Stopped
2020-09-30T17:48:35.276Z,1601488115.276 [Default:CheckIn:E] Running Loop=1
2020-09-30T17:48:35.308Z,1601488115.308 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:48:35.684Z,1601488115.684 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.636010 min 
2020-09-30T17:48:35.684Z,1601488115.684 [Default:CheckIn:E] Stopped
2020-09-30T17:48:35.685Z,1601488115.685 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-09-30T17:48:35.685Z,1601488115.685 [Default:CheckIn] Stopped
2020-09-30T17:48:35.685Z,1601488115.685 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-09-30T17:48:35.685Z,1601488115.685 [Default:CheckIn](INFO): Running loop #2
2020-09-30T17:48:35.685Z,1601488115.685 [Default:CheckIn] Running Loop=2
2020-09-30T17:48:35.685Z,1601488115.685 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-09-30T17:48:35.685Z,1601488115.685 [Default:CheckIn:Read_GPS] Running Loop=1
2020-09-30T17:48:35.718Z,1601488115.718 [BuoyancyServo](INFO): Data requested
2020-09-30T17:48:35.722Z,1601488115.722 [CBIT](INFO): got buoyancy position: 958.068970
2020-09-30T17:48:36.500Z,1601488116.500 [BuoyancyServo](INFO): Pause
2020-09-30T17:49:05.995Z,1601488145.995 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:49:06.399Z,1601488146.399 [BuoyancyServo](INFO): Data requested
2020-09-30T17:49:06.403Z,1601488146.403 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:49:06.823Z,1601488146.823 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:49:07.204Z,1601488147.204 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:49:07.611Z,1601488147.611 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:49:08.033Z,1601488148.033 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:49:08.459Z,1601488148.459 [BuoyancyServo](INFO): Data requested
2020-09-30T17:49:08.481Z,1601488148.481 [CBIT](INFO): got buoyancy position: 958.068970
2020-09-30T17:49:09.219Z,1601488149.219 [BuoyancyServo](INFO): Pause
2020-09-30T17:49:38.728Z,1601488178.728 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:49:39.132Z,1601488179.132 [BuoyancyServo](INFO): Data requested
2020-09-30T17:49:39.141Z,1601488179.141 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:49:39.560Z,1601488179.560 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:49:39.957Z,1601488179.957 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:49:40.347Z,1601488180.347 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:49:40.752Z,1601488180.752 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:49:41.163Z,1601488181.163 [BuoyancyServo](INFO): Data requested
2020-09-30T17:49:41.168Z,1601488181.168 [CBIT](INFO): got buoyancy position: 956.725952
2020-09-30T17:49:41.964Z,1601488181.964 [BuoyancyServo](INFO): Pause
2020-09-30T17:50:11.453Z,1601488211.453 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:50:11.840Z,1601488211.840 [BuoyancyServo](INFO): Data requested
2020-09-30T17:50:11.844Z,1601488211.844 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:50:12.253Z,1601488212.253 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:50:12.656Z,1601488212.656 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:50:13.080Z,1601488213.080 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:50:13.511Z,1601488213.511 [BuoyancyServo](INFO): Data requested
2020-09-30T17:50:13.530Z,1601488213.530 [CBIT](INFO): got buoyancy position: 957.800415
2020-09-30T17:50:14.263Z,1601488214.263 [BuoyancyServo](INFO): Pause
2020-09-30T17:50:27.611Z,1601488227.611 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2020-09-30T17:50:27.611Z,1601488227.611 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-09-30T17:50:27.630Z,1601488227.630 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-09-30T17:50:28.033Z,1601488228.033 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-09-30T17:50:28.033Z,1601488228.033 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2020-09-30T17:50:43.838Z,1601488243.838 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:50:44.197Z,1601488244.197 [BuoyancyServo](INFO): Data requested
2020-09-30T17:50:44.203Z,1601488244.203 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:50:44.622Z,1601488244.622 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:50:45.009Z,1601488245.009 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:50:45.420Z,1601488245.420 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:50:45.847Z,1601488245.847 [BuoyancyServo](INFO): Data requested
2020-09-30T17:50:45.871Z,1601488245.871 [CBIT](INFO): got buoyancy position: 958.068970
2020-09-30T17:50:46.608Z,1601488246.608 [BuoyancyServo](INFO): Pause
2020-09-30T17:51:16.108Z,1601488276.108 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:51:16.512Z,1601488276.512 [BuoyancyServo](INFO): Data requested
2020-09-30T17:51:16.516Z,1601488276.516 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:51:16.934Z,1601488276.934 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:51:17.351Z,1601488277.351 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:51:17.730Z,1601488277.730 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:51:18.135Z,1601488278.135 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:51:18.551Z,1601488278.551 [BuoyancyServo](INFO): Data requested
2020-09-30T17:51:18.555Z,1601488278.555 [CBIT](INFO): got buoyancy position: 957.934692
2020-09-30T17:51:19.332Z,1601488279.332 [BuoyancyServo](INFO): Pause
2020-09-30T17:51:48.840Z,1601488308.840 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:51:49.230Z,1601488309.230 [BuoyancyServo](INFO): Data requested
2020-09-30T17:51:49.237Z,1601488309.237 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:51:49.643Z,1601488309.643 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:51:50.033Z,1601488310.033 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:51:50.436Z,1601488310.436 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:51:50.864Z,1601488310.864 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:51:51.299Z,1601488311.299 [BuoyancyServo](INFO): Data requested
2020-09-30T17:51:51.304Z,1601488311.304 [CBIT](INFO): got buoyancy position: 956.323120
2020-09-30T17:51:52.052Z,1601488312.052 [BuoyancyServo](INFO): Pause
2020-09-30T17:52:21.576Z,1601488341.576 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:52:21.948Z,1601488341.948 [BuoyancyServo](INFO): Data requested
2020-09-30T17:52:21.952Z,1601488341.952 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:52:22.369Z,1601488342.369 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:52:22.780Z,1601488342.780 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:52:23.191Z,1601488343.191 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:52:23.603Z,1601488343.603 [BuoyancyServo](INFO): Data requested
2020-09-30T17:52:23.608Z,1601488343.608 [CBIT](INFO): got buoyancy position: 958.068970
2020-09-30T17:52:24.384Z,1601488344.384 [BuoyancyServo](INFO): Pause
2020-09-30T17:52:53.892Z,1601488373.892 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:52:54.268Z,1601488374.268 [BuoyancyServo](INFO): Data requested
2020-09-30T17:52:54.272Z,1601488374.272 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:52:54.690Z,1601488374.690 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:52:55.098Z,1601488375.098 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:52:55.485Z,1601488375.485 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:52:55.924Z,1601488375.924 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:52:56.342Z,1601488376.342 [BuoyancyServo](INFO): Data requested
2020-09-30T17:52:56.346Z,1601488376.346 [CBIT](INFO): got buoyancy position: 957.128906
2020-09-30T17:52:57.096Z,1601488377.096 [BuoyancyServo](INFO): Pause
2020-09-30T17:53:26.618Z,1601488406.618 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:53:26.993Z,1601488406.993 [BuoyancyServo](INFO): Data requested
2020-09-30T17:53:26.997Z,1601488406.997 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:53:27.406Z,1601488407.406 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:53:27.821Z,1601488407.821 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:53:28.208Z,1601488408.208 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:53:28.607Z,1601488408.607 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2020-09-30T17:53:28.607Z,1601488408.607 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-09-30T17:53:28.643Z,1601488408.643 [BuoyancyServo](INFO): Data requested
2020-09-30T17:53:28.648Z,1601488408.648 [CBIT](INFO): got buoyancy position: 957.934692
2020-09-30T17:53:28.648Z,1601488408.648 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-09-30T17:53:29.064Z,1601488409.064 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-09-30T17:53:29.064Z,1601488409.064 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2020-09-30T17:53:29.439Z,1601488409.439 [BuoyancyServo](INFO): Pause
2020-09-30T17:53:35.896Z,1601488415.896 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-09-30T17:48:35.7Z
2020-09-30T17:53:35.896Z,1601488415.896 [Default:CheckIn:Read_GPS] Stopped
2020-09-30T17:53:35.896Z,1601488415.896 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-09-30T17:53:43.142Z,1601488423.142 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200930T173212/Courier0007.lzma
2020-09-30T17:53:44.145Z,1601488424.145 [DataOverHttps](INFO): Moved sent file to Logs/20200930T173212/Courier0007.lzma.bak
2020-09-30T17:53:44.145Z,1601488424.145 [DataOverHttps](INFO): SBD MOMSN=12689097
2020-09-30T17:53:44.756Z,1601488424.756 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-09-30T17:53:44.756Z,1601488424.756 [NAL9602] Data Fault, FailCount= 2
2020-09-30T17:53:44.756Z,1601488424.756 [NAL9602](ERROR): Data Fault
2020-09-30T17:53:44.776Z,1601488424.776 [CBIT](ERROR): Data Fault in component: NAL9602
2020-09-30T17:53:45.192Z,1601488425.192 [NAL9602](INFO): Powering down
2020-09-30T17:53:46.025Z,1601488426.025 [CBIT](INFO): Clearing failed state for component NAL9602
2020-09-30T17:53:46.025Z,1601488426.025 [NAL9602] No Fault, FailCount= 2
2020-09-30T17:53:58.952Z,1601488438.952 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:53:59.360Z,1601488439.360 [BuoyancyServo](INFO): Data requested
2020-09-30T17:53:59.365Z,1601488439.365 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:53:59.741Z,1601488439.741 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:54:00.152Z,1601488440.152 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:54:00.557Z,1601488440.557 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:54:00.615Z,1601488440.615 [DataOverHttps](INFO): Sending 539 bytes from file Logs/20200930T173212/Express0008.lzma
2020-09-30T17:54:00.987Z,1601488440.987 [BuoyancyServo](INFO): Data requested
2020-09-30T17:54:00.992Z,1601488440.992 [CBIT](INFO): got buoyancy position: 957.800415
2020-09-30T17:54:01.617Z,1601488441.617 [DataOverHttps](INFO): Moved sent file to Logs/20200930T173212/Express0008.lzma.bak
2020-09-30T17:54:01.617Z,1601488441.617 [DataOverHttps](INFO): SBD MOMSN=12689099
2020-09-30T17:54:01.763Z,1601488441.763 [BuoyancyServo](INFO): Pause
2020-09-30T17:54:04.193Z,1601488444.193 [Default:CheckIn:Read_Iridium] Stopped
2020-09-30T17:54:04.193Z,1601488444.193 [Default:CheckIn:C.Wait] Running Loop=1
2020-09-30T17:54:04.193Z,1601488444.193 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-09-30T17:54:15.480Z,1601488455.480 [NAL9602](INFO): Powering up NAL9602
2020-09-30T17:54:21.561Z,1601488461.561 [CommandLine](IMPORTANT): got command show variable limit
2020-09-30T17:54:21.573Z,1601488461.573 [CommandLine](IMPORTANT): DDM.currentLimit (count)
2020-09-30T17:54:21.573Z,1601488461.573 [CommandLine](IMPORTANT): DDM.PWMLimit (count)
2020-09-30T17:54:21.628Z,1601488461.628 [CommandLine](IMPORTANT): BuoyancyServo.currLimit (percent)
2020-09-30T17:54:21.629Z,1601488461.629 [CommandLine](IMPORTANT): BuoyancyServo.limitHi (count)
2020-09-30T17:54:21.629Z,1601488461.629 [CommandLine](IMPORTANT): BuoyancyServo.limitLo (count)
2020-09-30T17:54:21.630Z,1601488461.630 [CommandLine](IMPORTANT): ElevatorServo.currLimit (percent)
2020-09-30T17:54:21.631Z,1601488461.631 [CommandLine](IMPORTANT): ElevatorServo.limitHi (count)
2020-09-30T17:54:21.639Z,1601488461.639 [CommandLine](IMPORTANT): ElevatorServo.limitLo (count)
2020-09-30T17:54:21.640Z,1601488461.640 [CommandLine](IMPORTANT): MassServo.currLimit (percent)
2020-09-30T17:54:21.640Z,1601488461.640 [CommandLine](IMPORTANT): MassServo.limitHi (count)
2020-09-30T17:54:21.641Z,1601488461.641 [CommandLine](IMPORTANT): MassServo.limitLo (count)
2020-09-30T17:54:21.641Z,1601488461.641 [CommandLine](IMPORTANT): RudderServo.currLimit (percent)
2020-09-30T17:54:21.642Z,1601488461.642 [CommandLine](IMPORTANT): RudderServo.limitHi (count)
2020-09-30T17:54:21.642Z,1601488461.642 [CommandLine](IMPORTANT): RudderServo.limitLo (count)
2020-09-30T17:54:21.643Z,1601488461.643 [CommandLine](IMPORTANT): ThrusterServo.currLimit (percent)
2020-09-30T17:54:21.745Z,1601488461.745 [CommandLine](IMPORTANT): HorizontalControl.rudLimit (angular_degree)
2020-09-30T17:54:21.756Z,1601488461.756 [CommandLine](IMPORTANT): VerticalControl.buoyancyLimitHiCC (cubic_centimeter)
2020-09-30T17:54:21.756Z,1601488461.756 [CommandLine](IMPORTANT): VerticalControl.buoyancyLimitLoCC (cubic_centimeter)
2020-09-30T17:54:21.757Z,1601488461.757 [CommandLine](IMPORTANT): VerticalControl.elevLimit (angular_degree)
2020-09-30T17:54:21.772Z,1601488461.772 [CommandLine](IMPORTANT): VerticalControl.limitDepthTrajectory (bool)
2020-09-30T17:54:21.772Z,1601488461.772 [CommandLine](IMPORTANT): VerticalControl.massFilterLimit (degree)
2020-09-30T17:54:21.773Z,1601488461.773 [CommandLine](IMPORTANT): VerticalControl.massPositionLimitAft (millimeter)
2020-09-30T17:54:21.773Z,1601488461.773 [CommandLine](IMPORTANT): VerticalControl.massPositionLimitFwd (millimeter)
2020-09-30T17:54:21.784Z,1601488461.784 [CommandLine](IMPORTANT): VerticalControl.pitchLimit (angular_degree)
2020-09-30T17:54:21.786Z,1601488461.786 [CommandLine](IMPORTANT): VeritcalControl.buoyancyLimitHiCC (cubic_centimeter)
2020-09-30T17:54:26.392Z,1601488466.392 [NAL9602](INFO): NAL9602 initialized
2020-09-30T17:54:30.265Z,1601488470.265 [CommandLine](IMPORTANT): got command get VerticalControl.buoyancyLimitLoCC
2020-09-30T17:54:30.265Z,1601488470.265 [CommandLine](IMPORTANT): VerticalControl.buoyancyLimitLoCC 70.000000 cc
2020-09-30T17:54:31.256Z,1601488471.256 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:54:31.668Z,1601488471.668 [BuoyancyServo](INFO): Data requested
2020-09-30T17:54:31.673Z,1601488471.673 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:54:32.084Z,1601488472.084 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:54:32.496Z,1601488472.496 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:54:32.909Z,1601488472.909 [CBIT](FAULT): requesting buoyancy position
2020-09-30T17:54:33.326Z,1601488473.326 [BuoyancyServo](INFO): Data requested
2020-09-30T17:54:33.331Z,1601488473.331 [CBIT](INFO): got buoyancy position: 958.068970
2020-09-30T17:54:34.080Z,1601488474.080 [BuoyancyServo](INFO): Pause
2020-09-30T17:54:49.720Z,1601488489.720 [CommandLine](IMPORTANT): got command restart application
2020-09-30T17:54:50.723Z,1601488490.723 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-30T17:54:50.724Z,1601488490.724 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-09-30T17:54:50.831Z,1601488490.831 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-09-30T17:54:50.831Z,1601488490.831 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-09-30T17:54:50.832Z,1601488490.832 [CommandLine](INFO): Join timeout helper Thread ID is 6717
2020-09-30T17:54:50.847Z,1601488490.847 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-09-30T17:54:50.847Z,1601488490.847 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-09-30T17:54:50.848Z,1601488490.848 [NavChartDb](INFO): Join timeout helper Thread ID is 6718
2020-09-30T17:54:51.227Z,1601488491.227 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-30T17:54:51.227Z,1601488491.227 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-09-30T17:54:51.243Z,1601488491.243 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2020-09-30T17:54:51.243Z,1601488491.243 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-09-30T17:54:51.243Z,1601488491.243 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 6719
2020-09-30T17:54:51.531Z,1601488491.531 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-30T17:54:51.532Z,1601488491.532 [WetLabsBB2FL](INFO): Powering down
2020-09-30T17:54:51.532Z,1601488491.532 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-09-30T17:54:51.547Z,1601488491.547 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2020-09-30T17:54:51.547Z,1601488491.547 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-09-30T17:54:51.547Z,1601488491.547 [CTD_Seabird](INFO): Join timeout helper Thread ID is 6720
2020-09-30T17:54:51.907Z,1601488491.907 [CTD_Seabird](INFO): Powering down
2020-09-30T17:54:51.919Z,1601488491.919 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-30T17:54:51.919Z,1601488491.919 [CTD_Seabird](INFO): Powering down
2020-09-30T17:54:51.934Z,1601488491.934 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-09-30T17:54:51.935Z,1601488491.935 [ComponentRegistry](INFO): Shutting down BackSeatDriver ThreadHandler
2020-09-30T17:54:51.935Z,1601488491.935 [BackSeatDriver ThreadHandler](INFO): Thread cancelled.
2020-09-30T17:54:51.936Z,1601488491.936 [BackSeatDriver](INFO): Join timeout helper Thread ID is 6721
2020-09-30T17:54:51.936Z,1601488491.936 [BackSeatDriver ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-30T17:54:51.937Z,1601488491.937 [BackSeatDriver ThreadHandler](INFO): Thread cancelled.
2020-09-30T17:54:51.940Z,1601488491.940 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-09-30T17:54:51.940Z,1601488491.940 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-09-30T17:54:51.941Z,1601488491.941 [Radio_Surface](INFO): Join timeout helper Thread ID is 6722
2020-09-30T17:54:52.335Z,1601488492.335 [Radio_Surface](INFO): Powering down
2020-09-30T17:54:52.336Z,1601488492.336 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-30T17:54:52.336Z,1601488492.336 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-09-30T17:54:52.356Z,1601488492.356 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2020-09-30T17:54:52.356Z,1601488492.356 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-09-30T17:54:52.356Z,1601488492.356 [Onboard](INFO): Join timeout helper Thread ID is 6723
2020-09-30T17:54:53.431Z,1601488493.431 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-30T17:54:53.431Z,1601488493.431 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-09-30T17:54:53.436Z,1601488493.436 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-09-30T17:54:53.436Z,1601488493.436 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-09-30T17:54:53.436Z,1601488493.436 [DataOverHttps](INFO): Join timeout helper Thread ID is 6724
2020-09-30T17:54:53.695Z,1601488493.695 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-30T17:54:53.699Z,1601488493.699 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-09-30T17:54:53.704Z,1601488493.704 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-09-30T17:54:53.704Z,1601488493.704 [logger ThreadHandler](INFO): Thread cancelled.
2020-09-30T17:54:53.704Z,1601488493.704 [logger](INFO): Join timeout helper Thread ID is 6725
2020-09-30T17:54:53.705Z,1601488493.705 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-30T17:54:53.705Z,1601488493.705 [logger ThreadHandler](INFO): Thread cancelled.
2020-09-30T17:54:53.706Z,1601488493.706 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-09-30T17:54:53.706Z,1601488493.706 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-09-30T17:54:53.707Z,1601488493.707 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-09-30T17:54:53.707Z,1601488493.707 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-09-30T17:54:53.707Z,1601488493.707 [controlThread](INFO): Join timeout helper Thread ID is 6726
2020-09-30T17:54:53.851Z,1601488493.851 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-30T17:54:53.851Z,1601488493.851 [controlThread](DEBUG): Uninitializing ControlThread
2020-09-30T17:54:53.852Z,1601488493.852 [AHRS_M2](INFO): Powering down
2020-09-30T17:54:53.923Z,1601488493.923 [NAL9602](INFO): Powering down
2020-09-30T17:54:53.925Z,1601488493.925 [DAT](INFO): Powering down
2020-09-30T17:54:54.045Z,1601488494.045 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-09-30T17:54:54.046Z,1601488494.046 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-09-30T17:54:54.046Z,1601488494.046 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-09-30T17:54:54.047Z,1601488494.047 [MissionManager](INFO): Uninitializing Mission Default
2020-09-30T17:54:54.047Z,1601488494.047 [Default] Stopped
2020-09-30T17:54:54.047Z,1601488494.047 [Default](DEBUG): Aggregate::uninitialize Default
2020-09-30T17:54:54.047Z,1601488494.047 [Default:B.GoToSurface] Stopped
2020-09-30T17:54:54.047Z,1601488494.047 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-09-30T17:54:54.047Z,1601488494.047 [Default:CheckIn] Stopped
2020-09-30T17:54:54.048Z,1601488494.048 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-09-30T17:54:54.048Z,1601488494.048 [Default:CheckIn:C.Wait] Stopped
2020-09-30T17:54:54.048Z,1601488494.048 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-09-30T17:54:54.050Z,1601488494.050 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-09-30T17:54:54.050Z,1601488494.050 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-09-30T17:54:54.051Z,1601488494.051 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-09-30T17:54:54.051Z,1601488494.051 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-09-30T17:54:54.051Z,1601488494.051 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-09-30T17:54:54.051Z,1601488494.051 [BuoyancyServo](INFO): Powering down
2020-09-30T17:54:54.063Z,1601488494.063 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-09-30T17:54:54.063Z,1601488494.063 [ElevatorServo](INFO): Powering down
2020-09-30T17:54:54.064Z,1601488494.064 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-09-30T17:54:54.064Z,1601488494.064 [MassServo](INFO): Powering down
2020-09-30T17:54:54.065Z,1601488494.065 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-09-30T17:54:54.065Z,1601488494.065 [RudderServo](INFO): Powering down
2020-09-30T17:54:54.066Z,1601488494.066 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-09-30T17:54:54.066Z,1601488494.066 [ThrusterServo](INFO): Powering down
2020-09-30T17:54:54.067Z,1601488494.067 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-09-30T17:54:54.067Z,1601488494.067 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-09-30T17:54:54.067Z,1601488494.067 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-09-30T17:54:54.068Z,1601488494.068 [CBIT](DEBUG): Powering off loads.
2020-09-30T17:54:54.079Z,1601488494.079 [CBIT](DEBUG): Disabling WDT.
2020-09-30T17:54:54.091Z,1601488494.091 [CBIT](DEBUG): Opening all GF detection circuits.
2020-09-30T17:54:54.092Z,1601488494.092 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-09-30T17:54:54.184Z,1601488494.184 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-09-30T17:54:54.186Z,1601488494.186 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-09-30T17:54:54.191Z,1601488494.191 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-09-30T17:54:54.256Z,1601488494.256 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-09-30T17:54:54.261Z,1601488494.261 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-09-30T17:54:54.264Z,1601488494.264 [BackSeatDriver ThreadHandler](INFO): Thread cancelled.
2020-09-30T17:54:54.273Z,1601488494.273 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-09-30T17:54:54.333Z,1601488494.333 [logger ThreadHandler](INFO): Thread cancelled.