2020-10-01T16:48:45.509Z,1601570925.509 [Supervisor](DEBUG): Initializing supervisor.
2020-10-01T16:48:45.512Z,1601570925.512 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-10-01T16:48:45.513Z,1601570925.513 [SyncHandler](INFO): Protected caller Thread ID is 9605
2020-10-01T16:48:45.513Z,1601570925.513 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-10-01T16:48:45.514Z,1601570925.514 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-10-01T16:48:45.515Z,1601570925.515 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 9606
2020-10-01T16:48:45.518Z,1601570925.518 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-10-01T16:48:45.532Z,1601570925.532 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-10-01T16:48:45.533Z,1601570925.533 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-10-01T16:48:45.534Z,1601570925.534 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 9607
2020-10-01T16:48:45.534Z,1601570925.534 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-10-01T16:48:45.535Z,1601570925.535 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-10-01T16:48:45.536Z,1601570925.536 [logger ThreadHandler](INFO): Protected caller Thread ID is 9608
2020-10-01T16:48:45.538Z,1601570925.538 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-10-01T16:48:45.538Z,1601570925.538 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-10-01T16:48:45.540Z,1601570925.540 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-10-01T16:48:45.632Z,1601570925.632 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-10-01T16:48:45.632Z,1601570925.632 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-10-01T16:48:46.183Z,1601570926.183 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-10-01T16:48:46.184Z,1601570926.184 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2020-10-01T16:48:46.369Z,1601570926.369 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2020-10-01T16:48:46.370Z,1601570926.370 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-10-01T16:48:46.465Z,1601570926.465 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-10-01T16:48:46.465Z,1601570926.465 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-10-01T16:48:46.563Z,1601570926.563 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-10-01T16:48:46.564Z,1601570926.564 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-10-01T16:48:46.643Z,1601570926.643 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-10-01T16:48:46.776Z,1601570926.776 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-10-01T16:48:46.777Z,1601570926.777 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-10-01T16:48:47.049Z,1601570927.049 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-10-01T16:48:47.050Z,1601570927.050 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-10-01T16:48:47.476Z,1601570927.476 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-10-01T16:48:47.477Z,1601570927.477 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-10-01T16:48:47.615Z,1601570927.615 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-10-01T16:48:47.615Z,1601570927.615 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-10-01T16:48:47.791Z,1601570927.791 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-10-01T16:48:47.791Z,1601570927.791 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-10-01T16:48:48.258Z,1601570928.258 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-10-01T16:48:48.258Z,1601570928.258 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-10-01T16:48:48.485Z,1601570928.485 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-10-01T16:48:48.486Z,1601570928.486 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-10-01T16:48:48.873Z,1601570928.873 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-10-01T16:48:48.874Z,1601570928.874 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-10-01T16:48:49.261Z,1601570929.261 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-10-01T16:48:49.261Z,1601570929.261 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-10-01T16:48:49.564Z,1601570929.564 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-10-01T16:48:49.566Z,1601570929.566 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2020-10-01T16:48:49.567Z,1601570929.567 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2020-10-01T16:48:49.647Z,1601570929.647 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2020-10-01T16:48:49.794Z,1601570929.794 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2020-10-01T16:48:49.897Z,1601570929.897 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2020-10-01T16:48:49.981Z,1601570929.981 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2020-10-01T16:48:50.072Z,1601570930.072 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2020-10-01T16:48:50.255Z,1601570930.255 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2020-10-01T16:48:50.475Z,1601570930.475 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-10-01T16:48:50.476Z,1601570930.476 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2020-10-01T16:48:50.566Z,1601570930.566 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2020-10-01T16:48:50.660Z,1601570930.660 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2020-10-01T16:48:50.784Z,1601570930.784 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2020-10-01T16:48:50.880Z,1601570930.880 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/
2020-10-01T16:48:50.880Z,1601570930.880 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-10-01T16:48:50.897Z,1601570930.897 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2020-10-01T16:48:50.972Z,1601570930.972 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2020-10-01T16:48:50.973Z,1601570930.973 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-10-01T16:48:51.018Z,1601570931.018 [VerticalControl](DEBUG): Construct VerticalControl.
2020-10-01T16:48:51.123Z,1601570931.123 [VerticalControl] Loaded
2020-10-01T16:48:51.123Z,1601570931.123 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-10-01T16:48:51.124Z,1601570931.124 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-10-01T16:48:51.199Z,1601570931.199 [HorizontalControl] Loaded
2020-10-01T16:48:51.200Z,1601570931.200 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-10-01T16:48:51.201Z,1601570931.201 [SpeedControl](DEBUG): Construct SpeedControl.
2020-10-01T16:48:51.206Z,1601570931.206 [SpeedControl] Loaded
2020-10-01T16:48:51.206Z,1601570931.206 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-10-01T16:48:51.207Z,1601570931.207 [LoopControl](DEBUG): Construct LoopControl.
2020-10-01T16:48:51.207Z,1601570931.207 [LoopControl] Loaded
2020-10-01T16:48:51.207Z,1601570931.207 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-10-01T16:48:51.208Z,1601570931.208 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-10-01T16:48:51.208Z,1601570931.208 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-10-01T16:48:51.247Z,1601570931.247 [DepthRateCalculator] Loaded
2020-10-01T16:48:51.248Z,1601570931.248 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-10-01T16:48:51.253Z,1601570931.253 [PitchRateCalculator] Loaded
2020-10-01T16:48:51.253Z,1601570931.253 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-10-01T16:48:51.267Z,1601570931.267 [SpeedCalculator] Loaded
2020-10-01T16:48:51.268Z,1601570931.267 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-10-01T16:48:51.287Z,1601570931.287 [TempGradientCalculator] Loaded
2020-10-01T16:48:51.287Z,1601570931.287 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-10-01T16:48:51.292Z,1601570931.292 [YawRateCalculator] Loaded
2020-10-01T16:48:51.292Z,1601570931.292 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-10-01T16:48:51.320Z,1601570931.320 [ElevatorOffsetCalculator] Loaded
2020-10-01T16:48:51.320Z,1601570931.320 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-10-01T16:48:51.321Z,1601570931.321 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-10-01T16:48:51.321Z,1601570931.321 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-10-01T16:48:51.347Z,1601570931.347 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-10-01T16:48:51.347Z,1601570931.347 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-10-01T16:48:51.429Z,1601570931.429 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-10-01T16:48:51.430Z,1601570931.430 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-10-01T16:48:51.705Z,1601570931.705 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-10-01T16:48:51.706Z,1601570931.706 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-10-01T16:48:51.801Z,1601570931.801 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-10-01T16:48:51.802Z,1601570931.802 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-10-01T16:48:52.122Z,1601570932.122 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-10-01T16:48:52.127Z,1601570932.127 [AHRS_M2](INFO): created writer for : platform_orientation
2020-10-01T16:48:52.129Z,1601570932.129 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-10-01T16:48:52.134Z,1601570932.134 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-10-01T16:48:52.134Z,1601570932.134 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-10-01T16:48:52.139Z,1601570932.139 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-10-01T16:48:52.139Z,1601570932.139 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-10-01T16:48:52.145Z,1601570932.145 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-10-01T16:48:52.213Z,1601570932.213 [AHRS_M2] Loaded
2020-10-01T16:48:52.213Z,1601570932.213 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-10-01T16:48:52.300Z,1601570932.300 [DataOverHttps] Loaded
2020-10-01T16:48:52.300Z,1601570932.300 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-10-01T16:48:52.301Z,1601570932.301 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4090E4E0
2020-10-01T16:48:52.302Z,1601570932.302 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 9690
2020-10-01T16:48:52.330Z,1601570932.330 [Depth_Keller] Loaded
2020-10-01T16:48:52.330Z,1601570932.330 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-10-01T16:48:52.438Z,1601570932.438 [NAL9602] Loaded
2020-10-01T16:48:52.438Z,1601570932.438 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-10-01T16:48:52.484Z,1601570932.484 [Onboard] Loaded
2020-10-01T16:48:52.484Z,1601570932.484 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2020-10-01T16:48:52.485Z,1601570932.485 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4093E4E0
2020-10-01T16:48:52.486Z,1601570932.486 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 9691
2020-10-01T16:48:52.504Z,1601570932.504 [Radio_Surface] Loaded
2020-10-01T16:48:52.504Z,1601570932.504 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-10-01T16:48:52.505Z,1601570932.505 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4096E4E0
2020-10-01T16:48:52.505Z,1601570932.505 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 9692
2020-10-01T16:48:52.649Z,1601570932.649 [DAT] Loaded
2020-10-01T16:48:52.649Z,1601570932.649 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2020-10-01T16:48:54.193Z,1601570934.193 [BPC1] Loaded
2020-10-01T16:48:54.193Z,1601570934.193 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-10-01T16:48:54.193Z,1601570934.193 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-10-01T16:48:54.194Z,1601570934.194 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-10-01T16:48:54.488Z,1601570934.488 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-10-01T16:48:54.489Z,1601570934.489 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-10-01T16:48:54.508Z,1601570934.508 [NavChart] Loaded
2020-10-01T16:48:54.508Z,1601570934.508 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-10-01T16:48:54.512Z,1601570934.512 [UniversalFixResidualReporter] Loaded
2020-10-01T16:48:54.512Z,1601570934.512 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-10-01T16:48:54.513Z,1601570934.513 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-10-01T16:48:54.513Z,1601570934.513 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-10-01T16:48:54.738Z,1601570934.738 [SBIT](DEBUG): Construct Startup Built In Test.
2020-10-01T16:48:54.748Z,1601570934.748 [SBIT] Loaded
2020-10-01T16:48:54.749Z,1601570934.749 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-10-01T16:48:54.749Z,1601570934.749 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-10-01T16:48:54.761Z,1601570934.761 [IBIT] Loaded
2020-10-01T16:48:54.762Z,1601570934.762 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-10-01T16:48:54.765Z,1601570934.765 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-10-01T16:48:54.898Z,1601570934.898 [CBIT] Loaded
2020-10-01T16:48:54.898Z,1601570934.898 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-10-01T16:48:54.898Z,1601570934.898 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-10-01T16:48:54.899Z,1601570934.899 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-10-01T16:48:55.017Z,1601570935.017 [BuoyancyServo] Loaded
2020-10-01T16:48:55.017Z,1601570935.017 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-10-01T16:48:55.044Z,1601570935.044 [ElevatorServo] Loaded
2020-10-01T16:48:55.045Z,1601570935.045 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-10-01T16:48:55.071Z,1601570935.071 [MassServo] Loaded
2020-10-01T16:48:55.071Z,1601570935.071 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-10-01T16:48:55.098Z,1601570935.098 [RudderServo] Loaded
2020-10-01T16:48:55.098Z,1601570935.098 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-10-01T16:48:55.124Z,1601570935.124 [ThrusterServo] Loaded
2020-10-01T16:48:55.124Z,1601570935.124 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-10-01T16:48:55.124Z,1601570935.124 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-10-01T16:48:55.125Z,1601570935.125 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-10-01T16:48:55.143Z,1601570935.143 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-10-01T16:48:55.143Z,1601570935.143 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-10-01T16:48:55.368Z,1601570935.368 [BackSeatDriver] Loaded
2020-10-01T16:48:55.369Z,1601570935.369 [ComponentRegistry](DEBUG): Component "BackSeatDriver" handled in its own thread.
2020-10-01T16:48:55.370Z,1601570935.370 [BackSeatDriver ThreadHandler](DEBUG): Created PCaller Thread at 40B054E0
2020-10-01T16:48:55.370Z,1601570935.370 [BackSeatDriver ThreadHandler](INFO): Protected caller Thread ID is 9693
2020-10-01T16:48:55.397Z,1601570935.397 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2020-10-01T16:48:55.402Z,1601570935.402 [CTD_Seabird](INFO): created writer for : sea_water_density
2020-10-01T16:48:55.403Z,1601570935.403 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2020-10-01T16:48:55.407Z,1601570935.407 [CTD_Seabird](INFO): created writer for : depth
2020-10-01T16:48:55.408Z,1601570935.408 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2020-10-01T16:48:55.412Z,1601570935.412 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2020-10-01T16:48:55.413Z,1601570935.413 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2020-10-01T16:48:55.418Z,1601570935.418 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2020-10-01T16:48:55.419Z,1601570935.419 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2020-10-01T16:48:55.423Z,1601570935.423 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2020-10-01T16:48:55.424Z,1601570935.424 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2020-10-01T16:48:55.430Z,1601570935.430 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2020-10-01T16:48:55.431Z,1601570935.431 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2020-10-01T16:48:55.436Z,1601570935.436 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2020-10-01T16:48:55.462Z,1601570935.462 [CTD_Seabird] Loaded
2020-10-01T16:48:55.462Z,1601570935.462 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2020-10-01T16:48:55.463Z,1601570935.463 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B354E0
2020-10-01T16:48:55.463Z,1601570935.463 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 9694
2020-10-01T16:48:55.511Z,1601570935.511 [ESPComponent] Loaded
2020-10-01T16:48:55.511Z,1601570935.511 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2020-10-01T16:48:55.532Z,1601570935.532 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470
2020-10-01T16:48:55.532Z,1601570935.532 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470
2020-10-01T16:48:55.536Z,1601570935.536 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650
2020-10-01T16:48:55.536Z,1601570935.536 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650
2020-10-01T16:48:55.540Z,1601570935.540 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl
2020-10-01T16:48:55.540Z,1601570935.540 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl
2020-10-01T16:48:55.544Z,1601570935.544 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm
2020-10-01T16:48:55.544Z,1601570935.544 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm
2020-10-01T16:48:55.548Z,1601570935.548 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm
2020-10-01T16:48:55.548Z,1601570935.548 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm
2020-10-01T16:48:55.552Z,1601570935.552 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm
2020-10-01T16:48:55.553Z,1601570935.553 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm
2020-10-01T16:48:55.556Z,1601570935.556 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm
2020-10-01T16:48:55.557Z,1601570935.557 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm
2020-10-01T16:48:55.560Z,1601570935.560 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water
2020-10-01T16:48:55.565Z,1601570935.565 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water
2020-10-01T16:48:55.565Z,1601570935.565 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-10-01T16:48:55.565Z,1601570935.565 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-10-01T16:48:55.569Z,1601570935.569 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-10-01T16:48:55.570Z,1601570935.570 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-10-01T16:48:55.574Z,1601570935.574 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-10-01T16:48:55.574Z,1601570935.574 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-10-01T16:48:55.578Z,1601570935.578 [WetLabsBB2FL] Loaded
2020-10-01T16:48:55.578Z,1601570935.578 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2020-10-01T16:48:55.579Z,1601570935.579 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B654E0
2020-10-01T16:48:55.580Z,1601570935.580 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 9695
2020-10-01T16:48:55.580Z,1601570935.580 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-10-01T16:48:55.584Z,1601570935.584 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-10-01T16:48:55.585Z,1601570935.585 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-10-01T16:48:55.591Z,1601570935.591 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-10-01T16:48:55.592Z,1601570935.592 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B954E0
2020-10-01T16:48:55.593Z,1601570935.593 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 9696
2020-10-01T16:48:55.597Z,1601570935.597 [Supervisor](INFO): Main Thread ID is 8126
2020-10-01T16:48:55.597Z,1601570935.597 [Supervisor](DEBUG): Running supervisor.
2020-10-01T16:48:55.598Z,1601570935.598 [CommandLine ThreadHandler](INFO): Handler Thread ID is 9697
2020-10-01T16:48:55.600Z,1601570935.600 [controlThread ThreadHandler](INFO): Handler Thread ID is 9698
2020-10-01T16:48:55.601Z,1601570935.601 [controlThread](DEBUG): Initializing ControlThread
2020-10-01T16:48:55.602Z,1601570935.602 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-10-01T16:48:55.603Z,1601570935.603 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-10-01T16:48:55.604Z,1601570935.604 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-10-01T16:48:55.605Z,1601570935.605 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-10-01T16:48:55.605Z,1601570935.605 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-10-01T16:48:55.605Z,1601570935.605 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-10-01T16:48:55.606Z,1601570935.606 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-10-01T16:48:55.606Z,1601570935.606 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-10-01T16:48:55.607Z,1601570935.607 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-10-01T16:48:55.607Z,1601570935.607 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-10-01T16:48:55.610Z,1601570935.610 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-10-01T16:48:55.610Z,1601570935.610 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-10-01T16:48:55.610Z,1601570935.610 [SBIT](INFO): Initialize SBIT Component.
2020-10-01T16:48:55.611Z,1601570935.611 [SBIT](IMPORTANT): git: 2020-09-25-4-g3c79f45c2
2020-10-01T16:48:55.611Z,1601570935.611 [SBIT](INFO): git hash: 3c79f45c23e378871ed20937b122d1fb360f9687
2020-10-01T16:48:55.611Z,1601570935.611 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-10-01T16:48:55.612Z,1601570935.612 [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-10-01T16:48:55.614Z,1601570935.614 [SBIT](INFO): Beginning SBIT in 99.000000 seconds.
2020-10-01T16:48:55.614Z,1601570935.614 [IBIT](INFO): Initialize IBIT Component.
2020-10-01T16:48:55.615Z,1601570935.615 [CBIT](DEBUG): Initialize CBIT Component.
2020-10-01T16:48:55.616Z,1601570935.616 [logger ThreadHandler](INFO): Handler Thread ID is 9699
2020-10-01T16:48:55.628Z,1601570935.628 [CBIT](DEBUG): Initialized mux pins.
2020-10-01T16:48:55.628Z,1601570935.628 [CBIT](DEBUG): Initializing the watchdog timer.
2020-10-01T16:48:55.637Z,1601570935.637 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 9700
2020-10-01T16:48:55.638Z,1601570935.638 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-10-01T16:48:55.649Z,1601570935.649 [Onboard ThreadHandler](INFO): Handler Thread ID is 9701
2020-10-01T16:48:55.653Z,1601570935.653 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-10-01T16:48:55.653Z,1601570935.653 [CBIT](DEBUG): Initializing heartbeat.
2020-10-01T16:48:55.667Z,1601570935.667 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 9702
2020-10-01T16:48:55.685Z,1601570935.685 [BackSeatDriver ThreadHandler](INFO): Handler Thread ID is 9703
2020-10-01T16:48:55.697Z,1601570935.697 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 9704
2020-10-01T16:48:55.697Z,1601570935.697 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-10-01T16:48:55.702Z,1601570935.702 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 9706
2020-10-01T16:48:55.703Z,1601570935.703 [WetLabsBB2FL](INFO): Powering down
2020-10-01T16:48:55.724Z,1601570935.724 [CBIT](DEBUG): Deactivating GF circuits.
2020-10-01T16:48:55.724Z,1601570935.724 [CBIT](DEBUG): Deactivating emergency mode.
2020-10-01T16:48:55.730Z,1601570935.730 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 9707
2020-10-01T16:48:55.733Z,1601570935.733 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-10-01T16:48:55.733Z,1601570935.733 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-10-01T16:48:55.733Z,1601570935.733 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-10-01T16:48:55.733Z,1601570935.733 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-10-01T16:48:55.734Z,1601570935.734 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-10-01T16:48:55.734Z,1601570935.734 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-10-01T16:48:55.734Z,1601570935.734 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-10-01T16:48:55.734Z,1601570935.734 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-10-01T16:48:55.734Z,1601570935.734 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-10-01T16:48:55.734Z,1601570935.734 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-10-01T16:48:55.734Z,1601570935.734 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-10-01T16:48:55.735Z,1601570935.735 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-10-01T16:48:55.735Z,1601570935.735 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-10-01T16:48:55.735Z,1601570935.735 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-10-01T16:48:55.735Z,1601570935.735 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-10-01T16:48:55.735Z,1601570935.735 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-10-01T16:48:55.760Z,1601570935.760 [CBIT](DEBUG): Backplane powered.
2020-10-01T16:48:55.762Z,1601570935.762 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-10-01T16:48:55.772Z,1601570935.772 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-10-01T16:48:55.783Z,1601570935.783 [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-10-01T16:48:55.783Z,1601570935.783 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-10-01T16:48:55.863Z,1601570935.863 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-10-01T16:48:55.864Z,1601570935.864 [Default:A.Wait](DEBUG): Construct Wait.
2020-10-01T16:48:55.866Z,1601570935.866 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-10-01T16:48:55.896Z,1601570935.896 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-10-01T16:48:55.918Z,1601570935.918 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-10-01T16:48:55.924Z,1601570935.924 [Default:E.Execute](DEBUG): Construct Execute.
2020-10-01T16:48:55.941Z,1601570935.941 [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-10-01T16:48:55.960Z,1601570935.960 [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-10-01T16:48:55.981Z,1601570935.981 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-10-01T16:48:56.037Z,1601570936.037 [Depth_Keller](ERROR): Pressure reading out of range: 1650.297119 decibar
2020-10-01T16:48:56.040Z,1601570936.040 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2020-10-01T16:48:56.040Z,1601570936.040 [DAT](INFO): Powering up
2020-10-01T16:48:56.040Z,1601570936.040 [DAT](DEBUG): Initializing DAT.
2020-10-01T16:48:56.085Z,1601570936.085 [Radio_Surface](INFO): Powering up
2020-10-01T16:48:56.093Z,1601570936.093 [DepthRateCalculator](ERROR): Depth measurement is not active
2020-10-01T16:48:56.097Z,1601570936.097 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2020-10-01T16:48:56.161Z,1601570936.161 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-10-01T16:48:56.177Z,1601570936.177 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-10-01T16:48:56.178Z,1601570936.178 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-10-01T16:48:56.189Z,1601570936.189 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-10-01T16:48:56.190Z,1601570936.190 [MassServo](DEBUG): Initializing EZServoServo.
2020-10-01T16:48:56.201Z,1601570936.201 [MassServo](DEBUG): Initializing MassServo.
2020-10-01T16:48:56.202Z,1601570936.202 [RudderServo](DEBUG): Initializing EZServoServo.
2020-10-01T16:48:56.216Z,1601570936.216 [RudderServo](DEBUG): Initializing RudderServo.
2020-10-01T16:48:56.217Z,1601570936.217 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-10-01T16:48:56.225Z,1601570936.225 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-10-01T16:48:57.096Z,1601570937.096 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2020-10-01T16:48:57.096Z,1601570937.096 [RudderServo](FAULT): Rudder failed to initialize
2020-10-01T16:48:57.097Z,1601570937.097 [RudderServo] Communications Fault, FailCount= 1
2020-10-01T16:48:57.097Z,1601570937.097 [RudderServo](ERROR): Communications Fault
2020-10-01T16:48:57.208Z,1601570937.208 [CBIT](ERROR): Communications Fault in component: RudderServo
2020-10-01T16:48:57.381Z,1601570937.381 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-10-01T16:48:57.381Z,1601570937.381 [RudderServo](INFO): Powering down
2020-10-01T16:48:58.036Z,1601570938.036 [RudderServo](DEBUG): Initializing EZServoServo.
2020-10-01T16:48:58.153Z,1601570938.153 [RudderServo](DEBUG): Initializing RudderServo.
2020-10-01T16:48:58.157Z,1601570938.157 [CBIT](INFO): Clearing failed state for component RudderServo
2020-10-01T16:48:58.157Z,1601570938.157 [RudderServo] No Fault, FailCount= 1
2020-10-01T16:48:59.762Z,1601570939.762 [BuoyancyServo](INFO): requested pos: 955.000000
2020-10-01T16:49:02.729Z,1601570942.729 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2020-10-01T16:49:05.813Z,1601570945.813 [CBIT](CRITICAL): Environmental Failure. Press:14.492758 PSI. Humidity:51%. Temp:23 C. ABORTING MISSION
2020-10-01T16:49:05.813Z,1601570945.813 [Supervisor](INFO): Stop Mission called by CBIT::monitorEnvironmentals
2020-10-01T16:49:06.182Z,1601570946.182 [CommandLine](FAULT): Scheduling is paused
2020-10-01T16:49:06.182Z,1601570946.182 [CBIT](INFO): Critical error at 20201001T164905
2020-10-01T16:49:10.151Z,1601570950.151 [DAT](INFO): commRate: 800
2020-10-01T16:49:12.170Z,1601570952.170 [DAT](INFO): entering command mode
2020-10-01T16:49:12.574Z,1601570952.574 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:49:12.978Z,1601570952.978 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:49:13.382Z,1601570953.382 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:49:13.786Z,1601570953.786 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:49:14.190Z,1601570954.190 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:49:14.594Z,1601570954.594 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:49:14.998Z,1601570954.998 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:49:15.402Z,1601570955.402 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:49:15.806Z,1601570955.806 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:49:16.210Z,1601570956.210 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:49:16.614Z,1601570956.614 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:49:17.018Z,1601570957.018 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:49:17.422Z,1601570957.422 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:49:17.822Z,1601570957.822 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:49:18.226Z,1601570958.226 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:49:18.630Z,1601570958.630 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:49:19.038Z,1601570959.038 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:49:19.443Z,1601570959.443 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:49:19.842Z,1601570959.842 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:49:20.246Z,1601570960.246 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:49:20.650Z,1601570960.650 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:49:21.066Z,1601570961.066 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:49:21.471Z,1601570961.471 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:49:21.862Z,1601570961.862 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:49:22.274Z,1601570962.274 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:49:22.674Z,1601570962.674 [NAL9602](INFO): Powering up NAL9602
2020-10-01T16:49:22.675Z,1601570962.675 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:49:23.074Z,1601570963.074 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:49:23.482Z,1601570963.482 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:49:23.882Z,1601570963.882 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:49:24.290Z,1601570964.290 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:49:24.690Z,1601570964.690 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:49:25.098Z,1601570965.098 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:49:25.498Z,1601570965.498 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:49:25.906Z,1601570965.906 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:49:26.315Z,1601570966.315 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:49:26.710Z,1601570966.710 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:49:26.720Z,1601570966.720 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:49:27.114Z,1601570967.114 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:49:27.522Z,1601570967.522 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:49:27.522Z,1601570967.522 [DAT](FAULT): failed to enter command mode
2020-10-01T16:49:27.922Z,1601570967.922 [DAT](INFO): entering command mode
2020-10-01T16:49:28.331Z,1601570968.331 [DAT](INFO): setting verbose to 3
2020-10-01T16:49:28.401Z,1601570968.401 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:49:28.743Z,1601570968.743 [DAT](INFO): set verbose to 3
2020-10-01T16:49:28.743Z,1601570968.743 [DAT](INFO): setting DatVerbose to 27440
2020-10-01T16:49:29.147Z,1601570969.147 [DAT](INFO): set DatVerbose to 27440
2020-10-01T16:49:29.147Z,1601570969.147 [DAT](INFO): setting transmit power to 8
2020-10-01T16:49:29.539Z,1601570969.539 [DAT](INFO): set transmit power to 8
2020-10-01T16:49:29.539Z,1601570969.539 [DAT](INFO): setting local address to 9
2020-10-01T16:49:29.965Z,1601570969.965 [DAT](INFO): set local address to 9
2020-10-01T16:49:33.594Z,1601570973.594 [NAL9602](INFO): NAL9602 initialized
2020-10-01T16:49:59.045Z,1601570999.045 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:50:00.704Z,1601571000.704 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:50:03.094Z,1601571003.094 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2020-10-01T16:50:31.384Z,1601571031.384 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:50:33.013Z,1601571033.013 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:50:33.097Z,1601571033.097 [CommandLine](IMPORTANT): got command report touch platform_buoyancy_position
2020-10-01T16:50:33.434Z,1601571033.434 [Reporter](INFO): platform_buoyancy_position 953.905640 cc
2020-10-01T16:50:35.020Z,1601571035.020 [SBIT](IMPORTANT): Beginning Startup BIT
2020-10-01T16:50:35.024Z,1601571035.024 [CBIT](IMPORTANT): Beginning ground fault scan
2020-10-01T16:50:45.952Z,1601571045.952 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.886095
CHAN A1 (24V): 0.838365
CHAN A2 (12V): 0.918288
CHAN A3 (5V): 0.458297
CHAN B0 (3.3V): 0.141696
CHAN B1 (3.15aV): 0.187802
CHAN B2 (3.15bV): 0.193717
CHAN B3 (GND): -0.026292
OPEN: -0.076118
Full Scale Calc: 4.765 mA, -1.589 mA
2020-10-01T16:51:03.701Z,1601571063.701 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:51:05.357Z,1601571065.357 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:51:05.396Z,1601571065.396 [Reporter](INFO): platform_buoyancy_position 953.905640 cc
2020-10-01T16:51:05.911Z,1601571065.911 [Reporter](INFO): platform_buoyancy_position 953.771362 cc
2020-10-01T16:51:18.757Z,1601571078.757 [CommandLine](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 930.000000 cubic_centimeter
2020-10-01T16:51:18.759Z,1601571078.759 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.buoyancyAction" handled in the control thread.
2020-10-01T16:51:19.165Z,1601571079.165 [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,Maintain_VerticalControl.buoyancyAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2020-10-01T16:51:19.481Z,1601571079.481 [BuoyancyServo](FAULT): is needed
2020-10-01T16:51:21.097Z,1601571081.097 [BuoyancyServo](INFO): requested pos: 930.000000
2020-10-01T16:51:21.163Z,1601571081.163 [Reporter](INFO): platform_buoyancy_position 953.771362 cc
2020-10-01T16:51:21.538Z,1601571081.538 [Reporter](INFO): platform_buoyancy_position 953.771362 cc
2020-10-01T16:51:21.954Z,1601571081.954 [Reporter](INFO): platform_buoyancy_position 953.905640 cc
2020-10-01T16:51:22.358Z,1601571082.358 [Reporter](INFO): platform_buoyancy_position 953.771362 cc
2020-10-01T16:51:22.886Z,1601571082.886 [Reporter](INFO): platform_buoyancy_position 951.622559 cc
2020-10-01T16:51:23.188Z,1601571083.188 [Reporter](INFO): platform_buoyancy_position 950.145264 cc
2020-10-01T16:51:23.547Z,1601571083.547 [Reporter](INFO): platform_buoyancy_position 949.608032 cc
2020-10-01T16:51:23.759Z,1601571083.759 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004734
2020-10-01T16:51:23.960Z,1601571083.960 [Reporter](INFO): platform_buoyancy_position 947.593628 cc
2020-10-01T16:51:24.354Z,1601571084.354 [Reporter](INFO): platform_buoyancy_position 947.324951 cc
2020-10-01T16:51:24.749Z,1601571084.749 [Reporter](INFO): platform_buoyancy_position 945.310425 cc
2020-10-01T16:51:25.135Z,1601571085.135 [Reporter](INFO): platform_buoyancy_position 944.504639 cc
2020-10-01T16:51:25.551Z,1601571085.551 [Reporter](INFO): platform_buoyancy_position 943.161743 cc
2020-10-01T16:51:25.943Z,1601571085.943 [Reporter](INFO): platform_buoyancy_position 941.012939 cc
2020-10-01T16:51:26.366Z,1601571086.366 [Reporter](INFO): platform_buoyancy_position 940.878540 cc
2020-10-01T16:51:26.771Z,1601571086.771 [Reporter](INFO): platform_buoyancy_position 938.729736 cc
2020-10-01T16:51:27.166Z,1601571087.166 [Reporter](INFO): platform_buoyancy_position 936.580933 cc
2020-10-01T16:51:27.583Z,1601571087.583 [Reporter](INFO): platform_buoyancy_position 936.580933 cc
2020-10-01T16:51:27.967Z,1601571087.967 [Reporter](INFO): platform_buoyancy_position 934.566528 cc
2020-10-01T16:51:28.384Z,1601571088.384 [Reporter](INFO): platform_buoyancy_position 934.297852 cc
2020-10-01T16:51:28.775Z,1601571088.775 [SBIT](IMPORTANT): SBIT PASSED
2020-10-01T16:51:28.775Z,1601571088.775 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-10-01T16:51:28.776Z,1601571088.776 [SBIT](IMPORTANT): AMEcho.loadAtStartup=0 bool;

2020-10-01T16:51:28.776Z,1601571088.776 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=6 count;

2020-10-01T16:51:28.776Z,1601571088.776 [SBIT](IMPORTANT): BuoyancyServo.loadAtStartup=1 bool;

2020-10-01T16:51:28.776Z,1601571088.776 [SBIT](IMPORTANT): CBIT.gf24Offset=145 microampere;

2020-10-01T16:51:28.776Z,1601571088.776 [SBIT](IMPORTANT): DAT.loadAtStartup=1 bool;

2020-10-01T16:51:28.777Z,1601571088.777 [SBIT](IMPORTANT): DAT.sbdAddress=-1 enum;

2020-10-01T16:51:28.777Z,1601571088.777 [SBIT](IMPORTANT): DAT.surfaceThreshold=100 meter;

2020-10-01T16:51:28.777Z,1601571088.777 [SBIT](IMPORTANT): DAT.verbosity=0 count;

2020-10-01T16:51:28.777Z,1601571088.777 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2020-10-01T16:51:28.777Z,1601571088.777 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=1 bool;

2020-10-01T16:51:28.777Z,1601571088.777 [SBIT](IMPORTANT): Express linearApproximation AMEcho.altitude 1.000000 meter;

2020-10-01T16:51:28.777Z,1601571088.777 [SBIT](IMPORTANT): Express none ThrusterServo.component_avgCurrent;

2020-10-01T16:51:28.777Z,1601571088.777 [SBIT](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 1.000000 liter_per_second;

2020-10-01T16:51:28.777Z,1601571088.777 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 5.000000 meter;

2020-10-01T16:51:28.778Z,1601571088.778 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=13 volt;

2020-10-01T16:51:28.778Z,1601571088.778 [SBIT](IMPORTANT): RDI_Pathfinder.loadAtStartup=0 bool;

2020-10-01T16:51:28.778Z,1601571088.778 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=170 cubic_centimeter;

2020-10-01T16:51:28.778Z,1601571088.778 [SBIT](IMPORTANT): VerticalControl.massDefault=20 millimeter;

2020-10-01T16:51:28.778Z,1601571088.778 [SBIT](IMPORTANT): WetLabsUBAT.loadAtStartup=0 bool;

2020-10-01T16:51:28.813Z,1601571088.813 [Reporter](INFO): platform_buoyancy_position 932.417725 cc
2020-10-01T16:51:29.142Z,1601571089.142 [MissionManager](IMPORTANT): Started mission Startup
2020-10-01T16:51:29.142Z,1601571089.142 [Startup] Running Loop=1
2020-10-01T16:51:29.142Z,1601571089.142 [Startup](DEBUG): Aggregate::initialize Startup
2020-10-01T16:51:29.142Z,1601571089.142 [Startup:A.GoToSurface] Running Loop=1
2020-10-01T16:51:29.143Z,1601571089.143 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-10-01T16:51:29.143Z,1601571089.143 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-10-01T16:51:29.144Z,1601571089.144 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-10-01T16:51:29.144Z,1601571089.144 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-10-01T16:51:29.161Z,1601571089.161 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-10-01T16:51:29.161Z,1601571089.161 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-10-01T16:51:29.163Z,1601571089.163 [Startup:StartupSatComms] Running Loop=1
2020-10-01T16:51:29.163Z,1601571089.163 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-10-01T16:51:29.163Z,1601571089.163 [Startup:StartupSatComms:A] Running Loop=1
2020-10-01T16:51:29.538Z,1601571089.538 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-10-01T16:51:36.139Z,1601571096.139 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:51:37.681Z,1601571097.681 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:51:37.699Z,1601571097.699 [Reporter](INFO): platform_buoyancy_position 932.417725 cc
2020-10-01T16:51:38.074Z,1601571098.074 [Reporter](INFO): platform_buoyancy_position 932.417725 cc
2020-10-01T16:51:55.793Z,1601571115.793 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2020-10-01T16:51:55.793Z,1601571115.793 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-10-01T16:51:55.867Z,1601571115.867 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-10-01T16:51:56.214Z,1601571116.214 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-10-01T16:51:56.214Z,1601571116.214 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2020-10-01T16:52:08.351Z,1601571128.351 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:52:09.989Z,1601571129.989 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:52:09.994Z,1601571129.994 [Reporter](INFO): platform_buoyancy_position 933.895020 cc
2020-10-01T16:52:10.378Z,1601571130.378 [Reporter](INFO): platform_buoyancy_position 932.686279 cc
2020-10-01T16:52:24.650Z,1601571144.650 [CommandLine](IMPORTANT): got command show variable buyoancyservo
2020-10-01T16:52:29.508Z,1601571149.508 [Startup:StartupSatComms:A](INFO): Timed out from 2020-10-01T16:51:29.2Z
2020-10-01T16:52:29.508Z,1601571149.508 [Startup:StartupSatComms:A] Stopped
2020-10-01T16:52:29.508Z,1601571149.508 [Startup:StartupSatComms:B] Running Loop=1
2020-10-01T16:52:29.826Z,1601571149.826 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-10-01T16:52:33.193Z,1601571153.193 [CommandLine](IMPORTANT): got command show variable buoyancy
2020-10-01T16:52:33.196Z,1601571153.196 [CommandLine](IMPORTANT): platform_buoyancy_position (cubic_centimeter)
2020-10-01T16:52:33.211Z,1601571153.211 [CommandLine](IMPORTANT): BuoyancyServo.loadAtStartup (bool)
2020-10-01T16:52:33.211Z,1601571153.211 [CommandLine](IMPORTANT): BuoyancyServo.simulateHardware (bool)
2020-10-01T16:52:33.212Z,1601571153.212 [CommandLine](IMPORTANT): BuoyancyServo.accel (none)
2020-10-01T16:52:33.216Z,1601571153.216 [CommandLine](IMPORTANT): BuoyancyServo.currLimit (percent)
2020-10-01T16:52:33.217Z,1601571153.217 [CommandLine](IMPORTANT): BuoyancyServo.limitHi (count)
2020-10-01T16:52:33.217Z,1601571153.217 [CommandLine](IMPORTANT): BuoyancyServo.limitLo (count)
2020-10-01T16:52:33.218Z,1601571153.218 [CommandLine](IMPORTANT): BuoyancyServo.overloadTimeout (millisecond)
2020-10-01T16:52:33.218Z,1601571153.218 [CommandLine](IMPORTANT): BuoyancyServo.pidW (count)
2020-10-01T16:52:33.218Z,1601571153.218 [CommandLine](IMPORTANT): BuoyancyServo.pidX (count)
2020-10-01T16:52:33.219Z,1601571153.219 [CommandLine](IMPORTANT): BuoyancyServo.pidY (count)
2020-10-01T16:52:33.219Z,1601571153.219 [CommandLine](IMPORTANT): BuoyancyServo.powerOffTimeout (second)
2020-10-01T16:52:33.219Z,1601571153.219 [CommandLine](IMPORTANT): BuoyancyServo.powerOnTimeout (second)
2020-10-01T16:52:33.220Z,1601571153.220 [CommandLine](IMPORTANT): BuoyancyServo.velocity (none)
2020-10-01T16:52:33.220Z,1601571153.220 [CommandLine](IMPORTANT): BuoyancyServo.checkingTimeout (minute)
2020-10-01T16:52:33.249Z,1601571153.249 [CommandLine](IMPORTANT): BuoyancyServo.countsPerCC (count_per_cubic_centimeter)
2020-10-01T16:52:33.249Z,1601571153.249 [CommandLine](IMPORTANT): BuoyancyServo.deviationVolume (cubic_centimeter)
2020-10-01T16:52:33.249Z,1601571153.249 [CommandLine](IMPORTANT): BuoyancyServo.fastPumpCoefficient (none)
2020-10-01T16:52:33.250Z,1601571153.250 [CommandLine](IMPORTANT): BuoyancyServo.fastPumpDepth (meter)
2020-10-01T16:52:33.250Z,1601571153.250 [CommandLine](IMPORTANT): BuoyancyServo.offsetVolume (cubic_centimeter)
2020-10-01T16:52:33.259Z,1601571153.259 [CommandLine](IMPORTANT): Config/Simulator.initBuoyancyPosition (cubic_centimeter)
2020-10-01T16:52:33.273Z,1601571153.273 [CommandLine](IMPORTANT): Config/Simulator.centerHystBuoyancy (cubic_centimeter)
2020-10-01T16:52:33.273Z,1601571153.273 [CommandLine](IMPORTANT): Config/Simulator.speedBuoyancy (cubic_centimeter_per_second)
2020-10-01T16:52:33.273Z,1601571153.273 [CommandLine](IMPORTANT): Config/Simulator.wideHystBuoyancy (cubic_centimeter)
2020-10-01T16:52:33.274Z,1601571153.274 [CommandLine](IMPORTANT): Config/Simulator.buoyancyNeutralOffset (cubic_centimeter)
2020-10-01T16:52:33.285Z,1601571153.285 [CommandLine](IMPORTANT): BuoyancyServo.loadControl (none)
2020-10-01T16:52:33.285Z,1601571153.285 [CommandLine](IMPORTANT): BuoyancyServo.uart (none)
2020-10-01T16:52:33.286Z,1601571153.286 [CommandLine](IMPORTANT): BuoyancyServo.baud (bit_per_second)
2020-10-01T16:52:33.336Z,1601571153.336 [CommandLine](IMPORTANT): VerticalControl.buoyancyDefault (cubic_centimeter)
2020-10-01T16:52:33.345Z,1601571153.345 [CommandLine](IMPORTANT): VerticalControl.buoyancyLimitHiCC (cubic_centimeter)
2020-10-01T16:52:33.345Z,1601571153.345 [CommandLine](IMPORTANT): VerticalControl.buoyancyLimitLoCC (cubic_centimeter)
2020-10-01T16:52:33.345Z,1601571153.345 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral (cubic_centimeter)
2020-10-01T16:52:33.346Z,1601571153.346 [CommandLine](IMPORTANT): VerticalControl.buoyancyPumpDepth (meter)
2020-10-01T16:52:33.379Z,1601571153.379 [CommandLine](IMPORTANT): VeritcalControl.buoyancyLimitHiCC (cubic_centimeter)
2020-10-01T16:52:33.380Z,1601571153.380 [CommandLine](IMPORTANT): VerticalControl.buoyancyAction (cubic_centimeter)
2020-10-01T16:52:33.491Z,1601571153.491 [CommandLine](IMPORTANT): BuoyancyServo.component_voltage (volt)
2020-10-01T16:52:33.491Z,1601571153.491 [CommandLine](IMPORTANT): BuoyancyServo.component_avgVoltage (volt)
2020-10-01T16:52:33.492Z,1601571153.492 [CommandLine](IMPORTANT): BuoyancyServo.component_current (milliampere)
2020-10-01T16:52:33.492Z,1601571153.492 [CommandLine](IMPORTANT): BuoyancyServo.component_avgCurrent (milliampere)
2020-10-01T16:52:33.501Z,1601571153.501 [CommandLine](IMPORTANT): BuoyancyServo.platform_buoyancy_position (cubic_centimeter)
2020-10-01T16:52:33.770Z,1601571153.770 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201001T160035/Courier0016.lzma
2020-10-01T16:52:34.774Z,1601571154.774 [DataOverHttps](INFO): Moved sent file to Logs/20201001T160035/Courier0016.lzma.bak
2020-10-01T16:52:34.774Z,1601571154.774 [DataOverHttps](INFO): SBD MOMSN=12705715
2020-10-01T16:52:40.454Z,1601571160.454 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:52:42.557Z,1601571162.557 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:52:42.562Z,1601571162.562 [Reporter](INFO): platform_buoyancy_position 932.417725 cc
2020-10-01T16:52:42.999Z,1601571162.999 [Reporter](INFO): platform_buoyancy_position 932.417725 cc
2020-10-01T16:52:51.769Z,1601571171.769 [DataOverHttps](INFO): Sending 251 bytes from file Logs/20201001T164845/Courier0000.lzma
2020-10-01T16:52:52.770Z,1601571172.770 [DataOverHttps](INFO): Moved sent file to Logs/20201001T164845/Courier0000.lzma.bak
2020-10-01T16:52:52.770Z,1601571172.770 [DataOverHttps](INFO): SBD MOMSN=12705717
2020-10-01T16:53:09.936Z,1601571189.936 [DataOverHttps](INFO): Sending 360 bytes from file Logs/20201001T160035/Express0017.lzma
2020-10-01T16:53:10.938Z,1601571190.938 [DataOverHttps](INFO): Moved sent file to Logs/20201001T160035/Express0017.lzma.bak
2020-10-01T16:53:10.938Z,1601571190.938 [DataOverHttps](INFO): SBD MOMSN=12705721
2020-10-01T16:53:13.153Z,1601571193.153 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:53:14.821Z,1601571194.821 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:53:14.826Z,1601571194.826 [Reporter](INFO): platform_buoyancy_position 932.417725 cc
2020-10-01T16:53:15.222Z,1601571195.222 [Reporter](INFO): platform_buoyancy_position 932.552002 cc
2020-10-01T16:53:17.690Z,1601571197.690 [CommandLine](IMPORTANT): got command report touch VerticalControl.buoyancyAction
2020-10-01T16:53:18.039Z,1601571198.039 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:18.457Z,1601571198.457 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:18.819Z,1601571198.819 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:19.237Z,1601571199.237 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:19.627Z,1601571199.627 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:20.039Z,1601571200.039 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:20.441Z,1601571200.441 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:20.850Z,1601571200.850 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:21.244Z,1601571201.244 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:21.667Z,1601571201.667 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:22.125Z,1601571202.125 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:22.504Z,1601571202.504 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:22.948Z,1601571202.948 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:23.291Z,1601571203.291 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:23.689Z,1601571203.689 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:24.116Z,1601571204.116 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:24.475Z,1601571204.475 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:24.906Z,1601571204.906 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:25.304Z,1601571205.304 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:25.687Z,1601571205.687 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:26.124Z,1601571206.124 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:26.502Z,1601571206.502 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:26.932Z,1601571206.932 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:27.380Z,1601571207.380 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:27.869Z,1601571207.869 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:28.176Z,1601571208.176 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:28.382Z,1601571208.382 [DataOverHttps](INFO): Sending 1314 bytes from file Logs/20201001T164845/Express0001.lzma
2020-10-01T16:53:28.572Z,1601571208.572 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:28.993Z,1601571208.993 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:29.382Z,1601571209.382 [DataOverHttps](INFO): Moved sent file to Logs/20201001T164845/Express0001.lzma.bak
2020-10-01T16:53:29.382Z,1601571209.382 [DataOverHttps](INFO): SBD MOMSN=12705748
2020-10-01T16:53:29.394Z,1601571209.394 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:29.730Z,1601571209.730 [Startup:StartupSatComms:B](INFO): Timed out from 2020-10-01T16:52:29.5Z
2020-10-01T16:53:29.730Z,1601571209.730 [Startup:StartupSatComms:B] Stopped
2020-10-01T16:53:29.730Z,1601571209.730 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2020-10-01T16:53:29.730Z,1601571209.730 [Startup:StartupSatComms] Stopped
2020-10-01T16:53:29.730Z,1601571209.730 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-10-01T16:53:29.731Z,1601571209.731 [Startup](INFO): Completed Startup
2020-10-01T16:53:29.731Z,1601571209.731 [MissionManager](INFO): Startup is completed.
2020-10-01T16:53:29.731Z,1601571209.731 [MissionManager](INFO): Uninitializing Mission Startup
2020-10-01T16:53:29.731Z,1601571209.731 [Startup] Stopped
2020-10-01T16:53:29.731Z,1601571209.731 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-10-01T16:53:29.731Z,1601571209.731 [Startup:A.GoToSurface] Stopped
2020-10-01T16:53:29.731Z,1601571209.731 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-10-01T16:53:29.742Z,1601571209.742 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:30.117Z,1601571210.117 [MissionManager](IMPORTANT): Started mission Default
2020-10-01T16:53:30.118Z,1601571210.118 [Default] Running Loop=1
2020-10-01T16:53:30.118Z,1601571210.118 [Default](DEBUG): Aggregate::initialize Default
2020-10-01T16:53:30.118Z,1601571210.118 [Default:B.GoToSurface] Running Loop=1
2020-10-01T16:53:30.118Z,1601571210.118 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-10-01T16:53:30.118Z,1601571210.118 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-10-01T16:53:30.118Z,1601571210.118 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-10-01T16:53:30.119Z,1601571210.119 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-10-01T16:53:30.119Z,1601571210.119 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-10-01T16:53:30.119Z,1601571210.119 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-10-01T16:53:30.119Z,1601571210.119 [Default:A.Wait] Running Loop=1
2020-10-01T16:53:30.120Z,1601571210.120 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-10-01T16:53:30.150Z,1601571210.150 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:30.539Z,1601571210.539 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:30.943Z,1601571210.943 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:31.349Z,1601571211.349 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:31.763Z,1601571211.763 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:32.185Z,1601571212.185 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:32.572Z,1601571212.572 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:33.011Z,1601571213.011 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:33.384Z,1601571213.384 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:33.796Z,1601571213.796 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:34.176Z,1601571214.176 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:34.599Z,1601571214.599 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:35.007Z,1601571215.007 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:35.419Z,1601571215.419 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:35.870Z,1601571215.870 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:36.289Z,1601571216.289 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:36.616Z,1601571216.616 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:37.040Z,1601571217.040 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:37.439Z,1601571217.439 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:37.827Z,1601571217.827 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:38.224Z,1601571218.224 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:38.635Z,1601571218.635 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:39.019Z,1601571219.019 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:39.430Z,1601571219.430 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:39.835Z,1601571219.835 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:40.231Z,1601571220.231 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:40.639Z,1601571220.639 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:41.044Z,1601571221.044 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:41.456Z,1601571221.456 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:41.876Z,1601571221.876 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:42.251Z,1601571222.251 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:42.667Z,1601571222.667 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:43.092Z,1601571223.092 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:43.483Z,1601571223.483 [Default:A.Wait](INFO): Done Waiting.
2020-10-01T16:53:43.483Z,1601571223.483 [Default:A.Wait] Stopped
2020-10-01T16:53:43.483Z,1601571223.483 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-10-01T16:53:43.493Z,1601571223.493 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:43.870Z,1601571223.870 [Default:CheckIn] Running Loop=1
2020-10-01T16:53:43.870Z,1601571223.870 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-10-01T16:53:43.870Z,1601571223.870 [Default:CheckIn:Read_GPS] Running Loop=1
2020-10-01T16:53:43.879Z,1601571223.879 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:44.271Z,1601571224.271 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-10-01T16:53:44.281Z,1601571224.281 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:44.700Z,1601571224.700 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:45.080Z,1601571225.080 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:45.482Z,1601571225.482 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:53:45.488Z,1601571225.488 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:45.918Z,1601571225.918 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:46.310Z,1601571226.310 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:46.699Z,1601571226.699 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:47.101Z,1601571227.101 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:47.545Z,1601571227.545 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:53:47.550Z,1601571227.550 [Reporter](INFO): platform_buoyancy_position 932.417725 cc
2020-10-01T16:53:47.551Z,1601571227.551 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:47.965Z,1601571227.965 [Reporter](INFO): platform_buoyancy_position 932.417725 cc
2020-10-01T16:53:47.966Z,1601571227.966 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:48.339Z,1601571228.339 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:48.754Z,1601571228.754 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:49.203Z,1601571229.203 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:49.635Z,1601571229.635 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:50.122Z,1601571230.122 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:50.372Z,1601571230.372 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:50.759Z,1601571230.759 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:51.155Z,1601571231.155 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:51.567Z,1601571231.567 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:51.955Z,1601571231.955 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:52.357Z,1601571232.357 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:52.774Z,1601571232.774 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:53.187Z,1601571233.187 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:53.575Z,1601571233.575 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:53.987Z,1601571233.987 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:54.387Z,1601571234.387 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:54.771Z,1601571234.771 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:55.183Z,1601571235.183 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:55.591Z,1601571235.591 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:56.005Z,1601571236.005 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:56.395Z,1601571236.395 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:56.799Z,1601571236.799 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:57.205Z,1601571237.205 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:57.621Z,1601571237.621 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:58.007Z,1601571238.007 [Reporter](INFO): VerticalControl.buoyancyAction 930.000000 cc
2020-10-01T16:53:58.081Z,1601571238.081 [CommandLine](IMPORTANT): got command report clear
2020-10-01T16:54:03.745Z,1601571243.745 [CommandLine](IMPORTANT): got command show variable deviat
2020-10-01T16:54:03.761Z,1601571243.761 [CommandLine](IMPORTANT): AHRS_3DMGX3.magDeviation (degree)
2020-10-01T16:54:03.761Z,1601571243.761 [CommandLine](IMPORTANT): AHRS_M2.magDeviation (degree)
2020-10-01T16:54:03.762Z,1601571243.762 [CommandLine](IMPORTANT): AHRS_sp3003D.magDeviation (degree)
2020-10-01T16:54:03.798Z,1601571243.798 [CommandLine](IMPORTANT): DVL_micro.magDeviation (degree)
2020-10-01T16:54:03.801Z,1601571243.801 [CommandLine](IMPORTANT): PNI_TCM.magDeviation (degree)
2020-10-01T16:54:03.821Z,1601571243.821 [CommandLine](IMPORTANT): BuoyancyServo.deviationVolume (cubic_centimeter)
2020-10-01T16:54:03.822Z,1601571243.822 [CommandLine](IMPORTANT): ElevatorServo.deviationAngle (angular_degree)
2020-10-01T16:54:03.823Z,1601571243.823 [CommandLine](IMPORTANT): MassServo.deviationDistance (millimeter)
2020-10-01T16:54:03.824Z,1601571243.824 [CommandLine](IMPORTANT): RudderServo.deviationAngle (angular_degree)
2020-10-01T16:54:03.825Z,1601571243.825 [CommandLine](IMPORTANT): ThrusterServo.deviation (count)
2020-10-01T16:54:03.895Z,1601571243.895 [CommandLine](IMPORTANT): CTD_Seabird.bin_standard_deviation_sea_water_temperature (celsius)
2020-10-01T16:54:03.896Z,1601571243.896 [CommandLine](IMPORTANT): CTD_Seabird.bin_standard_deviation_sea_water_salinity (practical_salinity_unit)
2020-10-01T16:54:12.934Z,1601571252.934 [CommandLine](IMPORTANT): got command get BuoyancyServo.deviationVolume
2020-10-01T16:54:12.935Z,1601571252.935 [CommandLine](IMPORTANT): BuoyancyServo.deviationVolume 10.000000 cc
2020-10-01T16:54:18.209Z,1601571258.209 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:54:19.869Z,1601571259.869 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:54:24.034Z,1601571264.034 [CommandLine](IMPORTANT): got command quit
2020-10-01T16:54:25.040Z,1601571265.040 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-10-01T16:54:25.041Z,1601571265.041 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-10-01T16:54:25.128Z,1601571265.128 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-10-01T16:54:25.129Z,1601571265.129 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-10-01T16:54:25.129Z,1601571265.129 [CommandLine](INFO): Join timeout helper Thread ID is 9736
2020-10-01T16:54:25.135Z,1601571265.135 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-10-01T16:54:25.135Z,1601571265.135 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-10-01T16:54:25.136Z,1601571265.136 [NavChartDb](INFO): Join timeout helper Thread ID is 9737
2020-10-01T16:54:25.229Z,1601571265.229 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-10-01T16:54:25.229Z,1601571265.229 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-10-01T16:54:25.236Z,1601571265.236 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2020-10-01T16:54:25.236Z,1601571265.236 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-10-01T16:54:25.237Z,1601571265.237 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 9738
2020-10-01T16:54:25.417Z,1601571265.417 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2020-10-01T16:54:25.417Z,1601571265.417 [WetLabsBB2FL](INFO): Powering down
2020-10-01T16:54:25.418Z,1601571265.418 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-10-01T16:54:25.436Z,1601571265.436 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2020-10-01T16:54:25.436Z,1601571265.436 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-10-01T16:54:25.437Z,1601571265.437 [CTD_Seabird](INFO): Join timeout helper Thread ID is 9739
2020-10-01T16:54:26.064Z,1601571266.064 [CTD_Seabird](INFO): Powering down
2020-10-01T16:54:26.076Z,1601571266.076 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2020-10-01T16:54:26.077Z,1601571266.077 [CTD_Seabird](INFO): Powering down
2020-10-01T16:54:26.088Z,1601571266.088 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-10-01T16:54:26.089Z,1601571266.089 [ComponentRegistry](INFO): Shutting down BackSeatDriver ThreadHandler
2020-10-01T16:54:26.089Z,1601571266.089 [BackSeatDriver ThreadHandler](INFO): Thread cancelled.
2020-10-01T16:54:26.089Z,1601571266.089 [BackSeatDriver](INFO): Join timeout helper Thread ID is 9740
2020-10-01T16:54:26.184Z,1601571266.184 [BackSeatDriver ThreadHandler](INFO): Uninitializing protected caller thread.
2020-10-01T16:54:26.185Z,1601571266.185 [BackSeatDriver ThreadHandler](INFO): Thread cancelled.
2020-10-01T16:54:26.193Z,1601571266.193 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-10-01T16:54:26.193Z,1601571266.193 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-10-01T16:54:26.194Z,1601571266.194 [Radio_Surface](INFO): Join timeout helper Thread ID is 9741
2020-10-01T16:54:26.197Z,1601571266.197 [Radio_Surface](INFO): Powering down
2020-10-01T16:54:26.197Z,1601571266.197 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-10-01T16:54:26.198Z,1601571266.198 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-10-01T16:54:26.217Z,1601571266.217 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2020-10-01T16:54:26.217Z,1601571266.217 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-10-01T16:54:26.218Z,1601571266.218 [Onboard](INFO): Join timeout helper Thread ID is 9742
2020-10-01T16:54:26.917Z,1601571266.917 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2020-10-01T16:54:30.917Z,1601571270.917 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2020-10-01T16:54:30.917Z,1601571270.917 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-10-01T16:54:30.933Z,1601571270.933 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-10-01T16:54:30.933Z,1601571270.933 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-10-01T16:54:30.934Z,1601571270.934 [DataOverHttps](INFO): Join timeout helper Thread ID is 9743
2020-10-01T16:54:30.934Z,1601571270.934 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-10-01T16:54:30.934Z,1601571270.934 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-10-01T16:54:30.936Z,1601571270.936 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-10-01T16:54:30.936Z,1601571270.936 [logger ThreadHandler](INFO): Thread cancelled.
2020-10-01T16:54:30.936Z,1601571270.936 [logger](INFO): Join timeout helper Thread ID is 9744
2020-10-01T16:54:30.953Z,1601571270.953 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-10-01T16:54:30.953Z,1601571270.953 [logger ThreadHandler](INFO): Thread cancelled.
2020-10-01T16:54:30.957Z,1601571270.957 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-10-01T16:54:30.957Z,1601571270.957 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-10-01T16:54:30.957Z,1601571270.957 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-10-01T16:54:30.957Z,1601571270.957 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-10-01T16:54:30.958Z,1601571270.958 [controlThread](INFO): Join timeout helper Thread ID is 9745
2020-10-01T16:54:31.104Z,1601571271.104 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-10-01T16:54:31.105Z,1601571271.105 [controlThread](DEBUG): Uninitializing ControlThread
2020-10-01T16:54:31.105Z,1601571271.105 [AHRS_M2](INFO): Powering down
2020-10-01T16:54:31.177Z,1601571271.177 [NAL9602](INFO): Powering down
2020-10-01T16:54:31.178Z,1601571271.178 [DAT](INFO): Powering down
2020-10-01T16:54:31.299Z,1601571271.299 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-10-01T16:54:31.300Z,1601571271.300 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-10-01T16:54:31.300Z,1601571271.300 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-10-01T16:54:31.301Z,1601571271.301 [MissionManager](INFO): Uninitializing Mission Default
2020-10-01T16:54:31.301Z,1601571271.301 [Default] Stopped
2020-10-01T16:54:31.301Z,1601571271.301 [Default](DEBUG): Aggregate::uninitialize Default
2020-10-01T16:54:31.301Z,1601571271.301 [Default:B.GoToSurface] Stopped
2020-10-01T16:54:31.301Z,1601571271.301 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-10-01T16:54:31.301Z,1601571271.301 [Default:CheckIn] Stopped
2020-10-01T16:54:31.301Z,1601571271.301 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-10-01T16:54:31.301Z,1601571271.301 [Default:CheckIn:Read_GPS] Stopped
2020-10-01T16:54:31.304Z,1601571271.304 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-10-01T16:54:31.304Z,1601571271.304 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-10-01T16:54:31.304Z,1601571271.304 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-10-01T16:54:31.305Z,1601571271.305 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-10-01T16:54:31.305Z,1601571271.305 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-10-01T16:54:31.305Z,1601571271.305 [BuoyancyServo](INFO): Powering down
2020-10-01T16:54:31.316Z,1601571271.316 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-10-01T16:54:31.316Z,1601571271.316 [ElevatorServo](INFO): Powering down
2020-10-01T16:54:31.317Z,1601571271.317 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-10-01T16:54:31.317Z,1601571271.317 [MassServo](INFO): Powering down
2020-10-01T16:54:31.318Z,1601571271.318 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-10-01T16:54:31.318Z,1601571271.318 [RudderServo](INFO): Powering down
2020-10-01T16:54:31.319Z,1601571271.319 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-10-01T16:54:31.319Z,1601571271.319 [ThrusterServo](INFO): Powering down
2020-10-01T16:54:31.320Z,1601571271.320 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-10-01T16:54:31.320Z,1601571271.320 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-10-01T16:54:31.320Z,1601571271.320 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-10-01T16:54:31.320Z,1601571271.320 [CBIT](DEBUG): Powering off loads.
2020-10-01T16:54:31.332Z,1601571271.332 [CBIT](DEBUG): Disabling WDT.
2020-10-01T16:54:31.344Z,1601571271.344 [CBIT](DEBUG): Opening all GF detection circuits.
2020-10-01T16:54:31.345Z,1601571271.345 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-10-01T16:54:31.442Z,1601571271.442 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-10-01T16:54:31.444Z,1601571271.444 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-10-01T16:54:31.449Z,1601571271.449 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-10-01T16:54:31.506Z,1601571271.506 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-10-01T16:54:31.511Z,1601571271.511 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-10-01T16:54:31.514Z,1601571271.514 [BackSeatDriver ThreadHandler](INFO): Thread cancelled.
2020-10-01T16:54:31.524Z,1601571271.524 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-10-01T16:54:31.585Z,1601571271.585 [logger ThreadHandler](INFO): Thread cancelled.