2020-10-01T16:00:35.550Z,1601568035.550 [Supervisor](DEBUG): Initializing supervisor.
2020-10-01T16:00:35.553Z,1601568035.553 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-10-01T16:00:35.553Z,1601568035.553 [SyncHandler](INFO): Protected caller Thread ID is 9343
2020-10-01T16:00:35.554Z,1601568035.554 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-10-01T16:00:35.555Z,1601568035.555 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-10-01T16:00:35.555Z,1601568035.555 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 9344
2020-10-01T16:00:35.558Z,1601568035.558 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-10-01T16:00:35.573Z,1601568035.573 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-10-01T16:00:35.574Z,1601568035.574 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-10-01T16:00:35.575Z,1601568035.575 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 9345
2020-10-01T16:00:35.575Z,1601568035.575 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-10-01T16:00:35.576Z,1601568035.576 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-10-01T16:00:35.577Z,1601568035.577 [logger ThreadHandler](INFO): Protected caller Thread ID is 9346
2020-10-01T16:00:35.578Z,1601568035.578 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-10-01T16:00:35.579Z,1601568035.579 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-10-01T16:00:35.580Z,1601568035.580 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-10-01T16:00:35.673Z,1601568035.673 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-10-01T16:00:35.673Z,1601568035.673 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-10-01T16:00:36.225Z,1601568036.225 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-10-01T16:00:36.226Z,1601568036.226 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2020-10-01T16:00:36.412Z,1601568036.412 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2020-10-01T16:00:36.413Z,1601568036.413 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-10-01T16:00:36.508Z,1601568036.508 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-10-01T16:00:36.509Z,1601568036.509 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-10-01T16:00:36.607Z,1601568036.607 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-10-01T16:00:36.607Z,1601568036.607 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-10-01T16:00:36.686Z,1601568036.686 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-10-01T16:00:36.819Z,1601568036.819 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-10-01T16:00:36.819Z,1601568036.819 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-10-01T16:00:37.092Z,1601568037.092 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-10-01T16:00:37.093Z,1601568037.093 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-10-01T16:00:37.521Z,1601568037.521 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-10-01T16:00:37.522Z,1601568037.522 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-10-01T16:00:37.659Z,1601568037.659 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-10-01T16:00:37.660Z,1601568037.660 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-10-01T16:00:37.835Z,1601568037.835 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-10-01T16:00:37.836Z,1601568037.836 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-10-01T16:00:38.305Z,1601568038.305 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-10-01T16:00:38.306Z,1601568038.306 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-10-01T16:00:38.511Z,1601568038.511 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-10-01T16:00:38.511Z,1601568038.511 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-10-01T16:00:38.701Z,1601568038.701 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-10-01T16:00:38.702Z,1601568038.702 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-10-01T16:00:39.089Z,1601568039.089 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-10-01T16:00:39.090Z,1601568039.090 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-10-01T16:00:39.393Z,1601568039.393 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-10-01T16:00:39.395Z,1601568039.395 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2020-10-01T16:00:39.396Z,1601568039.396 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2020-10-01T16:00:39.477Z,1601568039.477 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2020-10-01T16:00:39.629Z,1601568039.629 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2020-10-01T16:00:39.766Z,1601568039.766 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2020-10-01T16:00:39.958Z,1601568039.958 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2020-10-01T16:00:40.123Z,1601568040.123 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2020-10-01T16:00:40.476Z,1601568040.476 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2020-10-01T16:00:40.700Z,1601568040.700 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-10-01T16:00:40.701Z,1601568040.701 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2020-10-01T16:00:40.791Z,1601568040.791 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2020-10-01T16:00:40.886Z,1601568040.886 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2020-10-01T16:00:41.009Z,1601568041.009 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2020-10-01T16:00:41.105Z,1601568041.105 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/
2020-10-01T16:00:41.106Z,1601568041.106 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-10-01T16:00:41.122Z,1601568041.122 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2020-10-01T16:00:41.199Z,1601568041.199 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2020-10-01T16:00:41.199Z,1601568041.199 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-10-01T16:00:41.245Z,1601568041.245 [VerticalControl](DEBUG): Construct VerticalControl.
2020-10-01T16:00:41.352Z,1601568041.352 [VerticalControl] Loaded
2020-10-01T16:00:41.352Z,1601568041.352 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-10-01T16:00:41.353Z,1601568041.353 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-10-01T16:00:41.429Z,1601568041.429 [HorizontalControl] Loaded
2020-10-01T16:00:41.429Z,1601568041.429 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-10-01T16:00:41.430Z,1601568041.430 [SpeedControl](DEBUG): Construct SpeedControl.
2020-10-01T16:00:41.435Z,1601568041.435 [SpeedControl] Loaded
2020-10-01T16:00:41.435Z,1601568041.435 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-10-01T16:00:41.436Z,1601568041.436 [LoopControl](DEBUG): Construct LoopControl.
2020-10-01T16:00:41.436Z,1601568041.436 [LoopControl] Loaded
2020-10-01T16:00:41.437Z,1601568041.437 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-10-01T16:00:41.437Z,1601568041.437 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-10-01T16:00:41.438Z,1601568041.438 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-10-01T16:00:41.477Z,1601568041.477 [DepthRateCalculator] Loaded
2020-10-01T16:00:41.478Z,1601568041.478 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-10-01T16:00:41.483Z,1601568041.483 [PitchRateCalculator] Loaded
2020-10-01T16:00:41.483Z,1601568041.483 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-10-01T16:00:41.497Z,1601568041.497 [SpeedCalculator] Loaded
2020-10-01T16:00:41.498Z,1601568041.498 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-10-01T16:00:41.518Z,1601568041.518 [TempGradientCalculator] Loaded
2020-10-01T16:00:41.518Z,1601568041.518 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-10-01T16:00:41.523Z,1601568041.523 [YawRateCalculator] Loaded
2020-10-01T16:00:41.523Z,1601568041.523 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-10-01T16:00:41.551Z,1601568041.551 [ElevatorOffsetCalculator] Loaded
2020-10-01T16:00:41.551Z,1601568041.551 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-10-01T16:00:41.551Z,1601568041.551 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-10-01T16:00:41.552Z,1601568041.552 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-10-01T16:00:41.578Z,1601568041.578 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-10-01T16:00:41.578Z,1601568041.578 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-10-01T16:00:41.662Z,1601568041.662 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-10-01T16:00:41.662Z,1601568041.662 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-10-01T16:00:41.942Z,1601568041.942 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-10-01T16:00:41.942Z,1601568041.942 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-10-01T16:00:42.039Z,1601568042.039 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-10-01T16:00:42.039Z,1601568042.039 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-10-01T16:00:42.362Z,1601568042.362 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-10-01T16:00:42.367Z,1601568042.367 [AHRS_M2](INFO): created writer for : platform_orientation
2020-10-01T16:00:42.369Z,1601568042.369 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-10-01T16:00:42.374Z,1601568042.374 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-10-01T16:00:42.374Z,1601568042.374 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-10-01T16:00:42.379Z,1601568042.379 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-10-01T16:00:42.380Z,1601568042.380 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-10-01T16:00:42.385Z,1601568042.385 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-10-01T16:00:42.454Z,1601568042.454 [AHRS_M2] Loaded
2020-10-01T16:00:42.454Z,1601568042.454 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-10-01T16:00:42.529Z,1601568042.529 [DataOverHttps] Loaded
2020-10-01T16:00:42.529Z,1601568042.529 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-10-01T16:00:42.531Z,1601568042.531 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4090E4E0
2020-10-01T16:00:42.531Z,1601568042.531 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 9428
2020-10-01T16:00:42.560Z,1601568042.560 [Depth_Keller] Loaded
2020-10-01T16:00:42.560Z,1601568042.560 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-10-01T16:00:42.669Z,1601568042.669 [NAL9602] Loaded
2020-10-01T16:00:42.669Z,1601568042.669 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-10-01T16:00:42.715Z,1601568042.715 [Onboard] Loaded
2020-10-01T16:00:42.716Z,1601568042.716 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2020-10-01T16:00:42.717Z,1601568042.717 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4093E4E0
2020-10-01T16:00:42.717Z,1601568042.717 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 9429
2020-10-01T16:00:42.736Z,1601568042.736 [Radio_Surface] Loaded
2020-10-01T16:00:42.736Z,1601568042.736 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-10-01T16:00:42.737Z,1601568042.737 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4096E4E0
2020-10-01T16:00:42.737Z,1601568042.737 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 9430
2020-10-01T16:00:42.882Z,1601568042.882 [DAT] Loaded
2020-10-01T16:00:42.883Z,1601568042.883 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2020-10-01T16:00:44.332Z,1601568044.332 [BPC1] Loaded
2020-10-01T16:00:44.332Z,1601568044.332 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-10-01T16:00:44.333Z,1601568044.333 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-10-01T16:00:44.333Z,1601568044.333 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-10-01T16:00:44.446Z,1601568044.446 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-10-01T16:00:44.446Z,1601568044.446 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-10-01T16:00:44.466Z,1601568044.466 [NavChart] Loaded
2020-10-01T16:00:44.466Z,1601568044.466 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-10-01T16:00:44.470Z,1601568044.470 [UniversalFixResidualReporter] Loaded
2020-10-01T16:00:44.470Z,1601568044.470 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-10-01T16:00:44.470Z,1601568044.470 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-10-01T16:00:44.471Z,1601568044.471 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-10-01T16:00:44.605Z,1601568044.605 [SBIT](DEBUG): Construct Startup Built In Test.
2020-10-01T16:00:44.616Z,1601568044.616 [SBIT] Loaded
2020-10-01T16:00:44.616Z,1601568044.616 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-10-01T16:00:44.617Z,1601568044.617 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-10-01T16:00:44.629Z,1601568044.629 [IBIT] Loaded
2020-10-01T16:00:44.629Z,1601568044.629 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-10-01T16:00:44.632Z,1601568044.632 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-10-01T16:00:44.766Z,1601568044.766 [CBIT] Loaded
2020-10-01T16:00:44.766Z,1601568044.766 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-10-01T16:00:44.766Z,1601568044.766 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-10-01T16:00:44.767Z,1601568044.767 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-10-01T16:00:45.077Z,1601568045.077 [BuoyancyServo] Loaded
2020-10-01T16:00:45.077Z,1601568045.077 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-10-01T16:00:45.140Z,1601568045.140 [ElevatorServo] Loaded
2020-10-01T16:00:45.140Z,1601568045.140 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-10-01T16:00:45.278Z,1601568045.278 [MassServo] Loaded
2020-10-01T16:00:45.279Z,1601568045.279 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-10-01T16:00:45.361Z,1601568045.361 [RudderServo] Loaded
2020-10-01T16:00:45.361Z,1601568045.361 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-10-01T16:00:45.393Z,1601568045.393 [ThrusterServo] Loaded
2020-10-01T16:00:45.394Z,1601568045.394 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-10-01T16:00:45.394Z,1601568045.394 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-10-01T16:00:45.395Z,1601568045.395 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-10-01T16:00:45.413Z,1601568045.413 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-10-01T16:00:45.413Z,1601568045.413 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-10-01T16:00:45.640Z,1601568045.640 [BackSeatDriver] Loaded
2020-10-01T16:00:45.641Z,1601568045.641 [ComponentRegistry](DEBUG): Component "BackSeatDriver" handled in its own thread.
2020-10-01T16:00:45.642Z,1601568045.642 [BackSeatDriver ThreadHandler](DEBUG): Created PCaller Thread at 40B054E0
2020-10-01T16:00:45.642Z,1601568045.642 [BackSeatDriver ThreadHandler](INFO): Protected caller Thread ID is 9431
2020-10-01T16:00:45.669Z,1601568045.669 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2020-10-01T16:00:45.674Z,1601568045.674 [CTD_Seabird](INFO): created writer for : sea_water_density
2020-10-01T16:00:45.675Z,1601568045.675 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2020-10-01T16:00:45.679Z,1601568045.679 [CTD_Seabird](INFO): created writer for : depth
2020-10-01T16:00:45.680Z,1601568045.680 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2020-10-01T16:00:45.685Z,1601568045.685 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2020-10-01T16:00:45.685Z,1601568045.685 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2020-10-01T16:00:45.690Z,1601568045.690 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2020-10-01T16:00:45.691Z,1601568045.691 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2020-10-01T16:00:45.696Z,1601568045.696 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2020-10-01T16:00:45.697Z,1601568045.697 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2020-10-01T16:00:45.703Z,1601568045.703 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2020-10-01T16:00:45.703Z,1601568045.703 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2020-10-01T16:00:45.708Z,1601568045.708 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2020-10-01T16:00:45.735Z,1601568045.735 [CTD_Seabird] Loaded
2020-10-01T16:00:45.735Z,1601568045.735 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2020-10-01T16:00:45.736Z,1601568045.736 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B354E0
2020-10-01T16:00:45.736Z,1601568045.736 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 9432
2020-10-01T16:00:45.784Z,1601568045.784 [ESPComponent] Loaded
2020-10-01T16:00:45.784Z,1601568045.784 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2020-10-01T16:00:45.805Z,1601568045.805 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470
2020-10-01T16:00:45.806Z,1601568045.806 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470
2020-10-01T16:00:45.810Z,1601568045.810 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650
2020-10-01T16:00:45.810Z,1601568045.810 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650
2020-10-01T16:00:45.814Z,1601568045.814 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl
2020-10-01T16:00:45.814Z,1601568045.814 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl
2020-10-01T16:00:45.818Z,1601568045.818 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm
2020-10-01T16:00:45.818Z,1601568045.818 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm
2020-10-01T16:00:45.822Z,1601568045.822 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm
2020-10-01T16:00:45.822Z,1601568045.822 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm
2020-10-01T16:00:45.826Z,1601568045.826 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm
2020-10-01T16:00:45.827Z,1601568045.827 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm
2020-10-01T16:00:45.831Z,1601568045.830 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm
2020-10-01T16:00:45.831Z,1601568045.831 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm
2020-10-01T16:00:45.835Z,1601568045.835 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water
2020-10-01T16:00:45.839Z,1601568045.839 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water
2020-10-01T16:00:45.839Z,1601568045.839 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-10-01T16:00:45.839Z,1601568045.839 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-10-01T16:00:45.844Z,1601568045.844 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-10-01T16:00:45.844Z,1601568045.844 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-10-01T16:00:45.848Z,1601568045.848 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-10-01T16:00:45.848Z,1601568045.848 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-10-01T16:00:45.852Z,1601568045.852 [WetLabsBB2FL] Loaded
2020-10-01T16:00:45.853Z,1601568045.853 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2020-10-01T16:00:45.854Z,1601568045.854 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B654E0
2020-10-01T16:00:45.854Z,1601568045.854 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 9433
2020-10-01T16:00:45.855Z,1601568045.855 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-10-01T16:00:45.858Z,1601568045.858 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-10-01T16:00:45.859Z,1601568045.859 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-10-01T16:00:45.866Z,1601568045.866 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-10-01T16:00:45.867Z,1601568045.867 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B954E0
2020-10-01T16:00:45.867Z,1601568045.867 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 9434
2020-10-01T16:00:45.872Z,1601568045.872 [Supervisor](INFO): Main Thread ID is 8126
2020-10-01T16:00:45.872Z,1601568045.872 [Supervisor](DEBUG): Running supervisor.
2020-10-01T16:00:45.872Z,1601568045.872 [CommandLine ThreadHandler](INFO): Handler Thread ID is 9435
2020-10-01T16:00:45.875Z,1601568045.875 [controlThread ThreadHandler](INFO): Handler Thread ID is 9436
2020-10-01T16:00:45.875Z,1601568045.875 [controlThread](DEBUG): Initializing ControlThread
2020-10-01T16:00:45.876Z,1601568045.876 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-10-01T16:00:45.878Z,1601568045.878 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-10-01T16:00:45.878Z,1601568045.878 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-10-01T16:00:45.879Z,1601568045.879 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-10-01T16:00:45.879Z,1601568045.879 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-10-01T16:00:45.880Z,1601568045.880 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-10-01T16:00:45.880Z,1601568045.880 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-10-01T16:00:45.880Z,1601568045.880 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-10-01T16:00:45.881Z,1601568045.881 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-10-01T16:00:45.881Z,1601568045.881 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-10-01T16:00:45.886Z,1601568045.886 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-10-01T16:00:45.886Z,1601568045.886 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-10-01T16:00:45.886Z,1601568045.886 [SBIT](INFO): Initialize SBIT Component.
2020-10-01T16:00:45.887Z,1601568045.887 [SBIT](IMPORTANT): git: 2020-09-25-4-g3c79f45c2
2020-10-01T16:00:45.887Z,1601568045.887 [SBIT](INFO): git hash: 3c79f45c23e378871ed20937b122d1fb360f9687
2020-10-01T16:00:45.887Z,1601568045.887 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-10-01T16:00:45.888Z,1601568045.888 [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:00:45.889Z,1601568045.889 [SBIT](INFO): Beginning SBIT in 99.000000 seconds.
2020-10-01T16:00:45.890Z,1601568045.890 [IBIT](INFO): Initialize IBIT Component.
2020-10-01T16:00:45.891Z,1601568045.891 [CBIT](DEBUG): Initialize CBIT Component.
2020-10-01T16:00:45.892Z,1601568045.892 [logger ThreadHandler](INFO): Handler Thread ID is 9437
2020-10-01T16:00:45.904Z,1601568045.904 [CBIT](DEBUG): Initialized mux pins.
2020-10-01T16:00:45.905Z,1601568045.905 [CBIT](DEBUG): Initializing the watchdog timer.
2020-10-01T16:00:45.913Z,1601568045.913 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 9438
2020-10-01T16:00:45.914Z,1601568045.914 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-10-01T16:00:45.925Z,1601568045.925 [Onboard ThreadHandler](INFO): Handler Thread ID is 9439
2020-10-01T16:00:45.929Z,1601568045.929 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-10-01T16:00:45.929Z,1601568045.929 [CBIT](DEBUG): Initializing heartbeat.
2020-10-01T16:00:45.942Z,1601568045.942 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 9440
2020-10-01T16:00:45.965Z,1601568045.965 [BackSeatDriver ThreadHandler](INFO): Handler Thread ID is 9441
2020-10-01T16:00:45.985Z,1601568045.985 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 9442
2020-10-01T16:00:45.985Z,1601568045.985 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-10-01T16:00:45.990Z,1601568045.990 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 9444
2020-10-01T16:00:45.991Z,1601568045.991 [WetLabsBB2FL](INFO): Powering down
2020-10-01T16:00:46.001Z,1601568046.001 [CBIT](DEBUG): Deactivating GF circuits.
2020-10-01T16:00:46.001Z,1601568046.001 [CBIT](DEBUG): Deactivating emergency mode.
2020-10-01T16:00:46.025Z,1601568046.025 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 9445
2020-10-01T16:00:46.028Z,1601568046.028 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-10-01T16:00:46.028Z,1601568046.028 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-10-01T16:00:46.028Z,1601568046.028 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-10-01T16:00:46.028Z,1601568046.028 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-10-01T16:00:46.029Z,1601568046.029 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-10-01T16:00:46.029Z,1601568046.029 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-10-01T16:00:46.029Z,1601568046.029 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-10-01T16:00:46.029Z,1601568046.029 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-10-01T16:00:46.029Z,1601568046.029 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-10-01T16:00:46.029Z,1601568046.029 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-10-01T16:00:46.029Z,1601568046.029 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-10-01T16:00:46.030Z,1601568046.030 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-10-01T16:00:46.030Z,1601568046.030 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-10-01T16:00:46.030Z,1601568046.030 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-10-01T16:00:46.030Z,1601568046.030 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-10-01T16:00:46.030Z,1601568046.030 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-10-01T16:00:46.036Z,1601568046.036 [CBIT](DEBUG): Backplane powered.
2020-10-01T16:00:46.038Z,1601568046.038 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-10-01T16:00:46.053Z,1601568046.053 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-10-01T16:00:46.072Z,1601568046.072 [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:00:46.093Z,1601568046.093 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-10-01T16:00:46.156Z,1601568046.156 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-10-01T16:00:46.174Z,1601568046.174 [Default:A.Wait](DEBUG): Construct Wait.
2020-10-01T16:00:46.176Z,1601568046.176 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-10-01T16:00:46.221Z,1601568046.221 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-10-01T16:00:46.223Z,1601568046.223 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-10-01T16:00:46.244Z,1601568046.244 [Default:E.Execute](DEBUG): Construct Execute.
2020-10-01T16:00:46.248Z,1601568046.248 [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:00:46.256Z,1601568046.256 [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:00:46.277Z,1601568046.277 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-10-01T16:00:46.333Z,1601568046.333 [Depth_Keller](ERROR): Pressure reading out of range: 1650.297119 decibar
2020-10-01T16:00:46.336Z,1601568046.336 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2020-10-01T16:00:46.336Z,1601568046.336 [DAT](INFO): Powering up
2020-10-01T16:00:46.336Z,1601568046.336 [DAT](DEBUG): Initializing DAT.
2020-10-01T16:00:46.348Z,1601568046.348 [Radio_Surface](INFO): Powering up
2020-10-01T16:00:46.383Z,1601568046.383 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2020-10-01T16:00:46.405Z,1601568046.405 [DepthRateCalculator](ERROR): Depth measurement is not active
2020-10-01T16:00:46.427Z,1601568046.427 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-10-01T16:00:46.441Z,1601568046.441 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-10-01T16:00:46.443Z,1601568046.443 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-10-01T16:00:46.453Z,1601568046.453 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-10-01T16:00:46.454Z,1601568046.454 [MassServo](DEBUG): Initializing EZServoServo.
2020-10-01T16:00:46.465Z,1601568046.465 [MassServo](DEBUG): Initializing MassServo.
2020-10-01T16:00:46.466Z,1601568046.466 [RudderServo](DEBUG): Initializing EZServoServo.
2020-10-01T16:00:46.477Z,1601568046.477 [RudderServo](DEBUG): Initializing RudderServo.
2020-10-01T16:00:46.478Z,1601568046.478 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-10-01T16:00:46.489Z,1601568046.489 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-10-01T16:00:47.396Z,1601568047.396 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2020-10-01T16:00:47.396Z,1601568047.396 [RudderServo](FAULT): Rudder failed to initialize
2020-10-01T16:00:47.397Z,1601568047.397 [RudderServo] Communications Fault, FailCount= 1
2020-10-01T16:00:47.397Z,1601568047.397 [RudderServo](ERROR): Communications Fault
2020-10-01T16:00:47.508Z,1601568047.508 [CBIT](ERROR): Communications Fault in component: RudderServo
2020-10-01T16:00:47.669Z,1601568047.669 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-10-01T16:00:47.669Z,1601568047.669 [RudderServo](INFO): Powering down
2020-10-01T16:00:48.344Z,1601568048.344 [RudderServo](DEBUG): Initializing EZServoServo.
2020-10-01T16:00:48.465Z,1601568048.465 [RudderServo](DEBUG): Initializing RudderServo.
2020-10-01T16:00:48.470Z,1601568048.470 [CBIT](INFO): Clearing failed state for component RudderServo
2020-10-01T16:00:48.470Z,1601568048.470 [RudderServo] No Fault, FailCount= 1
2020-10-01T16:00:56.046Z,1601568056.046 [CBIT](CRITICAL): Environmental Failure. Press:14.400376 PSI. Humidity:47%. Temp:22 C. ABORTING MISSION
2020-10-01T16:00:56.046Z,1601568056.046 [Supervisor](INFO): Stop Mission called by CBIT::monitorEnvironmentals
2020-10-01T16:00:56.452Z,1601568056.452 [CommandLine](FAULT): Scheduling is paused
2020-10-01T16:00:56.452Z,1601568056.452 [CBIT](INFO): Critical error at 20201001T160056
2020-10-01T16:01:00.452Z,1601568060.452 [DAT](INFO): commRate: 800
2020-10-01T16:01:02.470Z,1601568062.470 [DAT](INFO): entering command mode
2020-10-01T16:01:02.874Z,1601568062.874 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:01:03.278Z,1601568063.278 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:01:03.682Z,1601568063.682 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:01:04.086Z,1601568064.086 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:01:04.490Z,1601568064.490 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:01:04.894Z,1601568064.894 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:01:05.298Z,1601568065.298 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:01:05.702Z,1601568065.702 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:01:06.106Z,1601568066.106 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:01:06.518Z,1601568066.518 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:01:06.914Z,1601568066.914 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:01:07.318Z,1601568067.318 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:01:07.722Z,1601568067.722 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:01:08.126Z,1601568068.126 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:01:08.526Z,1601568068.526 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:01:08.934Z,1601568068.934 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:01:09.334Z,1601568069.334 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:01:09.742Z,1601568069.742 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:01:10.150Z,1601568070.150 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:01:10.546Z,1601568070.546 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:01:10.950Z,1601568070.950 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:01:11.358Z,1601568071.358 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:01:11.758Z,1601568071.758 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:01:12.162Z,1601568072.162 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:01:12.574Z,1601568072.574 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:01:12.974Z,1601568072.974 [NAL9602](INFO): Powering up NAL9602
2020-10-01T16:01:12.975Z,1601568072.975 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:01:13.379Z,1601568073.379 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:01:13.786Z,1601568073.786 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:01:14.182Z,1601568074.182 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:01:14.586Z,1601568074.586 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:01:14.996Z,1601568074.996 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:01:15.398Z,1601568075.398 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:01:15.798Z,1601568075.798 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:01:16.206Z,1601568076.206 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:01:16.610Z,1601568076.610 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:01:17.010Z,1601568077.010 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:01:17.038Z,1601568077.038 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:01:17.414Z,1601568077.414 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:01:17.830Z,1601568077.830 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-01T16:01:17.830Z,1601568077.830 [DAT](FAULT): failed to enter command mode
2020-10-01T16:01:18.226Z,1601568078.226 [DAT](INFO): entering command mode
2020-10-01T16:01:18.638Z,1601568078.638 [DAT](INFO): setting verbose to 3
2020-10-01T16:01:19.035Z,1601568079.035 [DAT](INFO): set verbose to 3
2020-10-01T16:01:19.035Z,1601568079.035 [DAT](INFO): setting DatVerbose to 27440
2020-10-01T16:01:19.121Z,1601568079.121 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:01:19.435Z,1601568079.435 [DAT](INFO): set DatVerbose to 27440
2020-10-01T16:01:19.435Z,1601568079.435 [DAT](INFO): setting transmit power to 8
2020-10-01T16:01:19.847Z,1601568079.847 [DAT](INFO): set transmit power to 8
2020-10-01T16:01:19.847Z,1601568079.847 [DAT](INFO): setting local address to 9
2020-10-01T16:01:20.261Z,1601568080.261 [DAT](INFO): set local address to 9
2020-10-01T16:01:23.878Z,1601568083.878 [NAL9602](INFO): NAL9602 initialized
2020-10-01T16:01:32.325Z,1601568092.325 [CommandLine](IMPORTANT): got command report touch platform_buoyancy_position
2020-10-01T16:01:49.753Z,1601568109.753 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:01:51.805Z,1601568111.805 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:01:51.829Z,1601568111.829 [Reporter](INFO): platform_buoyancy_position 962.366577 cc
2020-10-01T16:01:52.214Z,1601568112.214 [Reporter](INFO): platform_buoyancy_position 962.366577 cc
2020-10-01T16:02:22.485Z,1601568142.485 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:02:24.137Z,1601568144.137 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:02:24.161Z,1601568144.161 [Reporter](INFO): platform_buoyancy_position 962.366577 cc
2020-10-01T16:02:24.554Z,1601568144.554 [Reporter](INFO): platform_buoyancy_position 962.366577 cc
2020-10-01T16:02:25.322Z,1601568145.322 [SBIT](IMPORTANT): Beginning Startup BIT
2020-10-01T16:02:25.326Z,1601568145.326 [CBIT](IMPORTANT): Beginning ground fault scan
2020-10-01T16:02:36.248Z,1601568156.248 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.010891
CHAN A1 (24V): -0.000920
CHAN A2 (12V): -0.007742
CHAN A3 (5V): -0.002099
CHAN B0 (3.3V): 0.000458
CHAN B1 (3.15aV): -0.000638
CHAN B2 (3.15bV): -0.000401
CHAN B3 (GND): 0.002284
OPEN: 0.004219
Full Scale Calc: 4.765 mA, -1.589 mA
2020-10-01T16:02:54.793Z,1601568174.793 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:02:56.457Z,1601568176.457 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:02:56.582Z,1601568176.582 [Reporter](INFO): platform_buoyancy_position 962.366577 cc
2020-10-01T16:02:56.883Z,1601568176.883 [Reporter](INFO): platform_buoyancy_position 962.366577 cc
2020-10-01T16:03:19.044Z,1601568199.044 [SBIT](IMPORTANT): SBIT PASSED
2020-10-01T16:03:19.044Z,1601568199.044 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-10-01T16:03:19.045Z,1601568199.045 [SBIT](IMPORTANT): AMEcho.loadAtStartup=0 bool;

2020-10-01T16:03:19.045Z,1601568199.045 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=6 count;

2020-10-01T16:03:19.045Z,1601568199.045 [SBIT](IMPORTANT): BuoyancyServo.loadAtStartup=1 bool;

2020-10-01T16:03:19.045Z,1601568199.045 [SBIT](IMPORTANT): CBIT.gf24Offset=145 microampere;

2020-10-01T16:03:19.046Z,1601568199.046 [SBIT](IMPORTANT): DAT.loadAtStartup=1 bool;

2020-10-01T16:03:19.046Z,1601568199.046 [SBIT](IMPORTANT): DAT.sbdAddress=-1 enum;

2020-10-01T16:03:19.046Z,1601568199.046 [SBIT](IMPORTANT): DAT.surfaceThreshold=100 meter;

2020-10-01T16:03:19.046Z,1601568199.046 [SBIT](IMPORTANT): DAT.verbosity=0 count;

2020-10-01T16:03:19.046Z,1601568199.046 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2020-10-01T16:03:19.046Z,1601568199.046 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=1 bool;

2020-10-01T16:03:19.046Z,1601568199.046 [SBIT](IMPORTANT): Express linearApproximation AMEcho.altitude 1.000000 meter;

2020-10-01T16:03:19.046Z,1601568199.046 [SBIT](IMPORTANT): Express none ThrusterServo.component_avgCurrent;

2020-10-01T16:03:19.046Z,1601568199.046 [SBIT](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 1.000000 liter_per_second;

2020-10-01T16:03:19.047Z,1601568199.047 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 5.000000 meter;

2020-10-01T16:03:19.047Z,1601568199.047 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=13 volt;

2020-10-01T16:03:19.047Z,1601568199.047 [SBIT](IMPORTANT): RDI_Pathfinder.loadAtStartup=0 bool;

2020-10-01T16:03:19.047Z,1601568199.047 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=170 cubic_centimeter;

2020-10-01T16:03:19.047Z,1601568199.047 [SBIT](IMPORTANT): VerticalControl.massDefault=20 millimeter;

2020-10-01T16:03:19.047Z,1601568199.047 [SBIT](IMPORTANT): WetLabsUBAT.loadAtStartup=0 bool;

2020-10-01T16:03:19.438Z,1601568199.438 [MissionManager](IMPORTANT): Started mission Startup
2020-10-01T16:03:19.438Z,1601568199.438 [Startup] Running Loop=1
2020-10-01T16:03:19.439Z,1601568199.439 [Startup](DEBUG): Aggregate::initialize Startup
2020-10-01T16:03:19.439Z,1601568199.439 [Startup:A.GoToSurface] Running Loop=1
2020-10-01T16:03:19.439Z,1601568199.439 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-10-01T16:03:19.439Z,1601568199.439 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-10-01T16:03:19.440Z,1601568199.440 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-10-01T16:03:19.457Z,1601568199.457 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-10-01T16:03:19.457Z,1601568199.457 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-10-01T16:03:19.458Z,1601568199.458 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-10-01T16:03:19.459Z,1601568199.459 [Startup:StartupSatComms] Running Loop=1
2020-10-01T16:03:19.459Z,1601568199.459 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-10-01T16:03:19.459Z,1601568199.459 [Startup:StartupSatComms:A] Running Loop=1
2020-10-01T16:03:19.842Z,1601568199.842 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-10-01T16:03:27.169Z,1601568207.169 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:03:29.181Z,1601568209.181 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:03:29.186Z,1601568209.186 [Reporter](INFO): platform_buoyancy_position 962.366577 cc
2020-10-01T16:03:29.574Z,1601568209.574 [Reporter](INFO): platform_buoyancy_position 962.366577 cc
2020-10-01T16:03:46.113Z,1601568226.113 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2020-10-01T16:03:46.113Z,1601568226.113 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-10-01T16:03:46.145Z,1601568226.145 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-10-01T16:03:46.510Z,1601568226.510 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-10-01T16:03:46.510Z,1601568226.510 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2020-10-01T16:03:59.866Z,1601568239.866 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:04:01.489Z,1601568241.489 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:04:01.494Z,1601568241.494 [Reporter](INFO): platform_buoyancy_position 962.366577 cc
2020-10-01T16:04:01.910Z,1601568241.910 [Reporter](INFO): platform_buoyancy_position 962.500854 cc
2020-10-01T16:04:19.654Z,1601568259.654 [Startup:StartupSatComms:A](INFO): Timed out from 2020-10-01T16:03:19.5Z
2020-10-01T16:04:19.655Z,1601568259.655 [Startup:StartupSatComms:A] Stopped
2020-10-01T16:04:19.655Z,1601568259.655 [Startup:StartupSatComms:B] Running Loop=1
2020-10-01T16:04:20.070Z,1601568260.070 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-10-01T16:04:32.167Z,1601568272.167 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:04:34.237Z,1601568274.237 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:04:34.243Z,1601568274.243 [Reporter](INFO): platform_buoyancy_position 960.486328 cc
2020-10-01T16:04:34.686Z,1601568274.686 [Reporter](INFO): platform_buoyancy_position 960.755005 cc
2020-10-01T16:05:04.898Z,1601568304.898 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:05:06.957Z,1601568306.957 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:05:06.982Z,1601568306.982 [Reporter](INFO): platform_buoyancy_position 960.620728 cc
2020-10-01T16:05:07.370Z,1601568307.370 [Reporter](INFO): platform_buoyancy_position 960.352051 cc
2020-10-01T16:05:19.837Z,1601568319.837 [Startup:StartupSatComms:B](INFO): Timed out from 2020-10-01T16:04:19.7Z
2020-10-01T16:05:19.837Z,1601568319.837 [Startup:StartupSatComms:B] Stopped
2020-10-01T16:05:19.837Z,1601568319.837 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2020-10-01T16:05:19.838Z,1601568319.838 [Startup:StartupSatComms] Stopped
2020-10-01T16:05:19.838Z,1601568319.838 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-10-01T16:05:19.838Z,1601568319.838 [Startup](INFO): Completed Startup
2020-10-01T16:05:19.838Z,1601568319.838 [MissionManager](INFO): Startup is completed.
2020-10-01T16:05:19.839Z,1601568319.839 [MissionManager](INFO): Uninitializing Mission Startup
2020-10-01T16:05:19.839Z,1601568319.839 [Startup] Stopped
2020-10-01T16:05:19.839Z,1601568319.839 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-10-01T16:05:19.839Z,1601568319.839 [Startup:A.GoToSurface] Stopped
2020-10-01T16:05:19.839Z,1601568319.839 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-10-01T16:05:20.244Z,1601568320.244 [MissionManager](IMPORTANT): Started mission Default
2020-10-01T16:05:20.244Z,1601568320.244 [Default] Running Loop=1
2020-10-01T16:05:20.245Z,1601568320.245 [Default](DEBUG): Aggregate::initialize Default
2020-10-01T16:05:20.245Z,1601568320.245 [Default:B.GoToSurface] Running Loop=1
2020-10-01T16:05:20.245Z,1601568320.245 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-10-01T16:05:20.245Z,1601568320.245 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-10-01T16:05:20.245Z,1601568320.245 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-10-01T16:05:20.245Z,1601568320.245 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-10-01T16:05:20.246Z,1601568320.246 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-10-01T16:05:20.246Z,1601568320.246 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-10-01T16:05:20.246Z,1601568320.246 [Default:A.Wait] Running Loop=1
2020-10-01T16:05:20.246Z,1601568320.246 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-10-01T16:05:33.595Z,1601568333.595 [Default:A.Wait](INFO): Done Waiting.
2020-10-01T16:05:33.595Z,1601568333.595 [Default:A.Wait] Stopped
2020-10-01T16:05:33.595Z,1601568333.595 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-10-01T16:05:33.986Z,1601568333.986 [Default:CheckIn] Running Loop=1
2020-10-01T16:05:33.986Z,1601568333.986 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-10-01T16:05:33.986Z,1601568333.986 [Default:CheckIn:Read_GPS] Running Loop=1
2020-10-01T16:05:34.394Z,1601568334.394 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-10-01T16:05:37.613Z,1601568337.613 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:05:39.333Z,1601568339.333 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:05:39.338Z,1601568339.338 [Reporter](INFO): platform_buoyancy_position 961.963745 cc
2020-10-01T16:05:39.698Z,1601568339.698 [Reporter](INFO): platform_buoyancy_position 960.620728 cc
2020-10-01T16:06:09.967Z,1601568369.967 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:06:11.574Z,1601568371.574 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:06:11.589Z,1601568371.589 [Reporter](INFO): platform_buoyancy_position 962.366577 cc
2020-10-01T16:06:12.006Z,1601568372.006 [Reporter](INFO): platform_buoyancy_position 962.366577 cc
2020-10-01T16:06:26.886Z,1601568386.886 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-10-01T16:06:42.266Z,1601568402.266 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:06:44.323Z,1601568404.323 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:06:44.329Z,1601568404.329 [Reporter](INFO): platform_buoyancy_position 961.157837 cc
2020-10-01T16:06:44.744Z,1601568404.744 [Reporter](INFO): platform_buoyancy_position 960.217773 cc
2020-10-01T16:06:47.186Z,1601568407.186 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2020-10-01T16:06:47.186Z,1601568407.186 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-10-01T16:06:47.196Z,1601568407.196 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-10-01T16:06:47.574Z,1601568407.574 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-10-01T16:06:47.574Z,1601568407.574 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2020-10-01T16:07:14.999Z,1601568434.999 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:07:17.045Z,1601568437.045 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:07:17.071Z,1601568437.071 [Reporter](INFO): platform_buoyancy_position 962.232300 cc
2020-10-01T16:07:17.462Z,1601568437.462 [Reporter](INFO): platform_buoyancy_position 960.620728 cc
2020-10-01T16:07:19.740Z,1601568439.740 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.009618
2020-10-01T16:07:47.705Z,1601568467.705 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:07:49.346Z,1601568469.346 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:07:49.360Z,1601568469.360 [Reporter](INFO): platform_buoyancy_position 962.232300 cc
2020-10-01T16:07:49.762Z,1601568469.762 [Reporter](INFO): platform_buoyancy_position 962.366577 cc
2020-10-01T16:08:14.382Z,1601568494.382 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-10-01T16:08:14.385Z,1601568494.385 [BPC1](INFO): Received data from all battery sticks.
2020-10-01T16:08:20.018Z,1601568500.018 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:08:21.669Z,1601568501.669 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:08:21.674Z,1601568501.674 [Reporter](INFO): platform_buoyancy_position 962.232300 cc
2020-10-01T16:08:22.082Z,1601568502.082 [Reporter](INFO): platform_buoyancy_position 961.157837 cc
2020-10-01T16:08:52.366Z,1601568532.366 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:08:53.997Z,1601568533.997 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:08:54.002Z,1601568534.002 [Reporter](INFO): platform_buoyancy_position 961.963745 cc
2020-10-01T16:08:54.398Z,1601568534.398 [Reporter](INFO): platform_buoyancy_position 960.755005 cc
2020-10-01T16:09:24.661Z,1601568564.661 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:09:26.329Z,1601568566.329 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:09:26.334Z,1601568566.334 [Reporter](INFO): platform_buoyancy_position 962.366577 cc
2020-10-01T16:09:26.734Z,1601568566.734 [Reporter](INFO): platform_buoyancy_position 962.366577 cc
2020-10-01T16:09:48.084Z,1601568588.084 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2020-10-01T16:09:48.084Z,1601568588.084 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-10-01T16:09:48.094Z,1601568588.094 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-10-01T16:09:48.502Z,1601568588.502 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-10-01T16:09:48.502Z,1601568588.502 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2020-10-01T16:09:56.990Z,1601568596.990 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:09:59.037Z,1601568599.037 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:09:59.042Z,1601568599.042 [Reporter](INFO): platform_buoyancy_position 961.023560 cc
2020-10-01T16:09:59.458Z,1601568599.458 [Reporter](INFO): platform_buoyancy_position 960.486328 cc
2020-10-01T16:10:29.717Z,1601568629.717 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:10:31.365Z,1601568631.365 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:10:31.377Z,1601568631.377 [Reporter](INFO): platform_buoyancy_position 962.366577 cc
2020-10-01T16:10:31.769Z,1601568631.769 [Reporter](INFO): platform_buoyancy_position 962.366577 cc
2020-10-01T16:10:34.162Z,1601568634.162 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-10-01T16:05:33.0Z
2020-10-01T16:10:34.162Z,1601568634.162 [Default:CheckIn:Read_GPS] Stopped
2020-10-01T16:10:34.162Z,1601568634.162 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-10-01T16:10:34.546Z,1601568634.546 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-10-01T16:10:47.708Z,1601568647.708 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201001T145713/Courier0016.lzma
2020-10-01T16:10:48.710Z,1601568648.710 [DataOverHttps](INFO): Moved sent file to Logs/20201001T145713/Courier0016.lzma.bak
2020-10-01T16:10:48.710Z,1601568648.710 [DataOverHttps](INFO): SBD MOMSN=12705094
2020-10-01T16:11:02.114Z,1601568662.114 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:11:04.068Z,1601568664.068 [DataOverHttps](INFO): Sending 250 bytes from file Logs/20201001T160035/Courier0000.lzma
2020-10-01T16:11:04.149Z,1601568664.149 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:11:04.154Z,1601568664.154 [Reporter](INFO): platform_buoyancy_position 962.366577 cc
2020-10-01T16:11:04.563Z,1601568664.563 [Reporter](INFO): platform_buoyancy_position 962.232300 cc
2020-10-01T16:11:05.070Z,1601568665.070 [DataOverHttps](INFO): Moved sent file to Logs/20201001T160035/Courier0000.lzma.bak
2020-10-01T16:11:05.071Z,1601568665.071 [DataOverHttps](INFO): SBD MOMSN=12705096
2020-10-01T16:11:20.384Z,1601568680.384 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20201001T160035/Courier0004.lzma
2020-10-01T16:11:21.386Z,1601568681.386 [DataOverHttps](INFO): Moved sent file to Logs/20201001T160035/Courier0004.lzma.bak
2020-10-01T16:11:21.386Z,1601568681.386 [DataOverHttps](INFO): SBD MOMSN=12705100
2020-10-01T16:11:25.922Z,1601568685.922 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-10-01T16:11:25.922Z,1601568685.922 [NAL9602] Data Fault, FailCount= 1
2020-10-01T16:11:25.922Z,1601568685.922 [NAL9602](ERROR): Data Fault
2020-10-01T16:11:25.982Z,1601568685.982 [CBIT](ERROR): Data Fault in component: NAL9602
2020-10-01T16:11:26.322Z,1601568686.322 [NAL9602](INFO): Powering down
2020-10-01T16:11:27.174Z,1601568687.174 [CBIT](INFO): Clearing failed state for component NAL9602
2020-10-01T16:11:27.174Z,1601568687.174 [NAL9602] No Fault, FailCount= 1
2020-10-01T16:11:34.825Z,1601568694.825 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:11:36.470Z,1601568696.470 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:11:36.486Z,1601568696.486 [Reporter](INFO): platform_buoyancy_position 962.366577 cc
2020-10-01T16:11:36.898Z,1601568696.898 [Reporter](INFO): platform_buoyancy_position 962.232300 cc
2020-10-01T16:11:39.820Z,1601568699.820 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20201001T145713/Express0017.lzma
2020-10-01T16:11:40.822Z,1601568700.822 [DataOverHttps](INFO): Moved sent file to Logs/20201001T145713/Express0017.lzma.bak
2020-10-01T16:11:40.822Z,1601568700.822 [DataOverHttps](INFO): SBD MOMSN=12705102
2020-10-01T16:11:56.622Z,1601568716.622 [NAL9602](INFO): Powering up NAL9602
2020-10-01T16:11:57.298Z,1601568717.298 [DataOverHttps](INFO): Sending 1251 bytes from file Logs/20201001T160035/Express0001.lzma
2020-10-01T16:11:58.298Z,1601568718.298 [DataOverHttps](INFO): Moved sent file to Logs/20201001T160035/Express0001.lzma.bak
2020-10-01T16:11:58.298Z,1601568718.298 [DataOverHttps](INFO): SBD MOMSN=12705115
2020-10-01T16:12:07.174Z,1601568727.174 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:12:07.534Z,1601568727.534 [NAL9602](INFO): NAL9602 initialized
2020-10-01T16:12:08.844Z,1601568728.844 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:12:08.850Z,1601568728.850 [Reporter](INFO): platform_buoyancy_position 962.500854 cc
2020-10-01T16:12:09.295Z,1601568729.295 [Reporter](INFO): platform_buoyancy_position 962.366577 cc
2020-10-01T16:12:14.945Z,1601568734.945 [DataOverHttps](INFO): Sending 304 bytes from file Logs/20201001T160035/Express0005.lzma
2020-10-01T16:12:15.946Z,1601568735.946 [DataOverHttps](INFO): Moved sent file to Logs/20201001T160035/Express0005.lzma.bak
2020-10-01T16:12:15.946Z,1601568735.946 [DataOverHttps](INFO): SBD MOMSN=12705162
2020-10-01T16:12:18.453Z,1601568738.453 [Default:CheckIn:Read_Iridium] Stopped
2020-10-01T16:12:18.453Z,1601568738.453 [Default:CheckIn:C.Wait] Running Loop=1
2020-10-01T16:12:18.453Z,1601568738.453 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-10-01T16:12:39.489Z,1601568759.489 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:12:41.111Z,1601568761.111 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:12:41.117Z,1601568761.117 [Reporter](INFO): platform_buoyancy_position 962.098022 cc
2020-10-01T16:12:41.550Z,1601568761.550 [Reporter](INFO): platform_buoyancy_position 960.755005 cc
2020-10-01T16:12:49.193Z,1601568769.193 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2020-10-01T16:12:49.193Z,1601568769.193 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-10-01T16:12:49.274Z,1601568769.274 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-10-01T16:12:49.579Z,1601568769.579 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-10-01T16:12:49.579Z,1601568769.579 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2020-10-01T16:13:11.793Z,1601568791.793 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:13:13.437Z,1601568793.437 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:13:13.442Z,1601568793.442 [Reporter](INFO): platform_buoyancy_position 962.366577 cc
2020-10-01T16:13:13.858Z,1601568793.858 [Reporter](INFO): platform_buoyancy_position 962.500854 cc
2020-10-01T16:13:44.119Z,1601568824.119 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:13:46.161Z,1601568826.161 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:13:46.166Z,1601568826.166 [Reporter](INFO): platform_buoyancy_position 962.366577 cc
2020-10-01T16:13:46.594Z,1601568826.594 [Reporter](INFO): platform_buoyancy_position 961.963745 cc
2020-10-01T16:14:16.834Z,1601568856.834 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:14:18.885Z,1601568858.885 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:14:18.899Z,1601568858.899 [Reporter](INFO): platform_buoyancy_position 960.486328 cc
2020-10-01T16:14:19.286Z,1601568859.286 [Reporter](INFO): platform_buoyancy_position 960.486328 cc
2020-10-01T16:14:49.567Z,1601568889.567 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:14:51.225Z,1601568891.225 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:14:51.230Z,1601568891.230 [Reporter](INFO): platform_buoyancy_position 962.232300 cc
2020-10-01T16:14:51.662Z,1601568891.662 [Reporter](INFO): platform_buoyancy_position 961.292236 cc
2020-10-01T16:15:21.890Z,1601568921.890 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:15:23.949Z,1601568923.949 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:15:23.954Z,1601568923.954 [Reporter](INFO): platform_buoyancy_position 961.023560 cc
2020-10-01T16:15:24.338Z,1601568924.338 [Reporter](INFO): platform_buoyancy_position 960.889282 cc
2020-10-01T16:15:50.182Z,1601568950.182 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2020-10-01T16:15:50.182Z,1601568950.182 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-10-01T16:15:50.192Z,1601568950.192 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-10-01T16:15:50.610Z,1601568950.610 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-10-01T16:15:50.611Z,1601568950.611 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2020-10-01T16:15:54.601Z,1601568954.601 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:15:56.269Z,1601568956.269 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:15:56.274Z,1601568956.274 [Reporter](INFO): platform_buoyancy_position 962.500854 cc
2020-10-01T16:15:56.681Z,1601568956.681 [Reporter](INFO): platform_buoyancy_position 962.366577 cc
2020-10-01T16:16:26.950Z,1601568986.950 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:16:28.969Z,1601568988.969 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:16:28.974Z,1601568988.974 [Reporter](INFO): platform_buoyancy_position 960.352051 cc
2020-10-01T16:16:29.382Z,1601568989.382 [Reporter](INFO): platform_buoyancy_position 960.486328 cc
2020-10-01T16:16:59.666Z,1601569019.666 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:17:01.693Z,1601569021.693 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:17:01.699Z,1601569021.699 [Reporter](INFO): platform_buoyancy_position 962.366577 cc
2020-10-01T16:17:02.102Z,1601569022.102 [Reporter](INFO): platform_buoyancy_position 962.366577 cc
2020-10-01T16:17:19.062Z,1601569039.062 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-10-01T16:17:19.062Z,1601569039.062 [Default:CheckIn:C.Wait] Stopped
2020-10-01T16:17:19.062Z,1601569039.062 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-10-01T16:17:19.062Z,1601569039.062 [Default:CheckIn:D] Running Loop=1
2020-10-01T16:17:19.461Z,1601569039.461 [Default:CheckIn:D] Stopped
2020-10-01T16:17:19.461Z,1601569039.461 [Default:CheckIn:E] Running Loop=1
2020-10-01T16:17:19.850Z,1601569039.850 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.986938 min 
2020-10-01T16:17:19.850Z,1601569039.850 [Default:CheckIn:E] Stopped
2020-10-01T16:17:19.850Z,1601569039.850 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-10-01T16:17:19.850Z,1601569039.850 [Default:CheckIn] Stopped
2020-10-01T16:17:19.850Z,1601569039.850 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-10-01T16:17:19.850Z,1601569039.850 [Default:CheckIn](INFO): Running loop #2
2020-10-01T16:17:19.850Z,1601569039.850 [Default:CheckIn] Running Loop=2
2020-10-01T16:17:19.850Z,1601569039.850 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-10-01T16:17:19.851Z,1601569039.851 [Default:CheckIn:Read_GPS] Running Loop=1
2020-10-01T16:17:32.377Z,1601569052.377 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:17:34.037Z,1601569054.037 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:17:34.042Z,1601569054.042 [Reporter](INFO): platform_buoyancy_position 962.366577 cc
2020-10-01T16:17:34.461Z,1601569054.461 [Reporter](INFO): platform_buoyancy_position 962.366577 cc
2020-10-01T16:18:04.698Z,1601569084.698 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:18:06.349Z,1601569086.349 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:18:06.361Z,1601569086.361 [Reporter](INFO): platform_buoyancy_position 962.366577 cc
2020-10-01T16:18:06.750Z,1601569086.750 [Reporter](INFO): platform_buoyancy_position 962.232300 cc
2020-10-01T16:18:37.016Z,1601569117.016 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:18:38.676Z,1601569118.676 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:18:38.682Z,1601569118.682 [Reporter](INFO): platform_buoyancy_position 962.098022 cc
2020-10-01T16:18:39.074Z,1601569119.074 [Reporter](INFO): platform_buoyancy_position 960.486328 cc
2020-10-01T16:18:51.177Z,1601569131.177 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2020-10-01T16:18:51.177Z,1601569131.177 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-10-01T16:18:51.187Z,1601569131.187 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-10-01T16:18:51.577Z,1601569131.577 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-10-01T16:18:51.577Z,1601569131.577 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2020-10-01T16:19:09.346Z,1601569149.346 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:19:11.385Z,1601569151.385 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:19:11.411Z,1601569151.411 [Reporter](INFO): platform_buoyancy_position 962.500854 cc
2020-10-01T16:19:11.819Z,1601569151.819 [Reporter](INFO): platform_buoyancy_position 962.366577 cc
2020-10-01T16:19:42.072Z,1601569182.072 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:19:43.713Z,1601569183.713 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:19:43.738Z,1601569183.738 [Reporter](INFO): platform_buoyancy_position 962.500854 cc
2020-10-01T16:19:44.110Z,1601569184.110 [Reporter](INFO): platform_buoyancy_position 962.366577 cc
2020-10-01T16:20:14.401Z,1601569214.401 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:20:16.045Z,1601569216.045 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:20:16.050Z,1601569216.050 [Reporter](INFO): platform_buoyancy_position 961.560791 cc
2020-10-01T16:20:16.438Z,1601569216.438 [Reporter](INFO): platform_buoyancy_position 960.889282 cc
2020-10-01T16:20:46.703Z,1601569246.703 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:20:48.349Z,1601569248.349 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:20:48.354Z,1601569248.354 [Reporter](INFO): platform_buoyancy_position 962.366577 cc
2020-10-01T16:20:48.762Z,1601569248.762 [Reporter](INFO): platform_buoyancy_position 962.500854 cc
2020-10-01T16:21:19.042Z,1601569279.042 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:21:20.689Z,1601569280.689 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:21:20.694Z,1601569280.694 [Reporter](INFO): platform_buoyancy_position 962.366577 cc
2020-10-01T16:21:21.115Z,1601569281.115 [Reporter](INFO): platform_buoyancy_position 962.500854 cc
2020-10-01T16:21:51.355Z,1601569311.355 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:21:52.181Z,1601569312.181 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2020-10-01T16:21:52.181Z,1601569312.181 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-10-01T16:21:52.191Z,1601569312.191 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-10-01T16:21:52.573Z,1601569312.573 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-10-01T16:21:52.573Z,1601569312.573 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2020-10-01T16:21:53.397Z,1601569313.397 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:21:53.402Z,1601569313.402 [Reporter](INFO): platform_buoyancy_position 960.486328 cc
2020-10-01T16:21:53.818Z,1601569313.818 [Reporter](INFO): platform_buoyancy_position 960.486328 cc
2020-10-01T16:22:09.514Z,1601569329.514 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-10-01T16:22:09.514Z,1601569329.514 [NAL9602] Data Fault, FailCount= 2
2020-10-01T16:22:09.514Z,1601569329.514 [NAL9602](ERROR): Data Fault
2020-10-01T16:22:09.533Z,1601569329.533 [CBIT](ERROR): Data Fault in component: NAL9602
2020-10-01T16:22:09.914Z,1601569329.914 [NAL9602](INFO): Powering down
2020-10-01T16:22:10.740Z,1601569330.740 [CBIT](INFO): Clearing failed state for component NAL9602
2020-10-01T16:22:10.741Z,1601569330.741 [NAL9602] No Fault, FailCount= 2
2020-10-01T16:22:20.024Z,1601569340.024 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-10-01T16:17:19.9Z
2020-10-01T16:22:20.024Z,1601569340.024 [Default:CheckIn:Read_GPS] Stopped
2020-10-01T16:22:20.024Z,1601569340.024 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-10-01T16:22:24.069Z,1601569344.069 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:22:26.125Z,1601569346.125 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:22:26.130Z,1601569346.130 [Reporter](INFO): platform_buoyancy_position 962.366577 cc
2020-10-01T16:22:26.526Z,1601569346.526 [Reporter](INFO): platform_buoyancy_position 962.366577 cc
2020-10-01T16:22:27.180Z,1601569347.180 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201001T160035/Courier0007.lzma
2020-10-01T16:22:28.183Z,1601569348.183 [DataOverHttps](INFO): Moved sent file to Logs/20201001T160035/Courier0007.lzma.bak
2020-10-01T16:22:28.183Z,1601569348.183 [DataOverHttps](INFO): SBD MOMSN=12705276
2020-10-01T16:22:40.219Z,1601569360.219 [NAL9602](INFO): Powering up NAL9602
2020-10-01T16:22:44.730Z,1601569364.730 [DataOverHttps](INFO): Sending 369 bytes from file Logs/20201001T160035/Express0008.lzma
2020-10-01T16:22:45.730Z,1601569365.730 [DataOverHttps](INFO): Moved sent file to Logs/20201001T160035/Express0008.lzma.bak
2020-10-01T16:22:45.730Z,1601569365.730 [DataOverHttps](INFO): SBD MOMSN=12705278
2020-10-01T16:22:48.317Z,1601569368.317 [Default:CheckIn:Read_Iridium] Stopped
2020-10-01T16:22:48.317Z,1601569368.317 [Default:CheckIn:C.Wait] Running Loop=1
2020-10-01T16:22:48.317Z,1601569368.317 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-10-01T16:22:51.124Z,1601569371.124 [NAL9602](INFO): NAL9602 initialized
2020-10-01T16:22:56.786Z,1601569376.786 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:22:58.837Z,1601569378.837 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:22:58.849Z,1601569378.849 [Reporter](INFO): platform_buoyancy_position 962.366577 cc
2020-10-01T16:22:59.261Z,1601569379.261 [Reporter](INFO): platform_buoyancy_position 961.695068 cc
2020-10-01T16:23:29.531Z,1601569409.531 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:23:31.573Z,1601569411.573 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:23:31.594Z,1601569411.594 [Reporter](INFO): platform_buoyancy_position 962.232300 cc
2020-10-01T16:23:31.997Z,1601569411.997 [Reporter](INFO): platform_buoyancy_position 962.366577 cc
2020-10-01T16:23:40.590Z,1601569420.590 [CommandLine](IMPORTANT): got command show variable low
2020-10-01T16:23:40.597Z,1601569420.597 [CommandLine](IMPORTANT): sea_floor_depth_below_geoid (unknown)
2020-10-01T16:23:40.615Z,1601569420.615 [CommandLine](IMPORTANT): ThrusterServo.allowableBadVelocity (count)
2020-10-01T16:23:40.616Z,1601569420.616 [CommandLine](IMPORTANT): Config/Simulator.lowerRudX (meter)
2020-10-01T16:23:40.617Z,1601569420.617 [CommandLine](IMPORTANT): Config/Simulator.lowerRudY (meter)
2020-10-01T16:23:40.617Z,1601569420.617 [CommandLine](IMPORTANT): Config/Simulator.lowerRudZ (meter)
2020-10-01T16:23:40.624Z,1601569420.624 [CommandLine](IMPORTANT): TempGradientCalculator.depShallowBndForAvg (meter)
2020-10-01T16:23:40.662Z,1601569420.662 [CommandLine](IMPORTANT): DeadReckonUsingMultipleVelocitySources.allowableFailures (count)
2020-10-01T16:23:40.663Z,1601569420.663 [CommandLine](IMPORTANT): DeadReckonUsingSpeedCalculator.allowableFailures (count)
2020-10-01T16:23:40.669Z,1601569420.669 [CommandLine](IMPORTANT): DeadReckonWithRespectToWater.allowableFailures (count)
2020-10-01T16:23:40.670Z,1601569420.670 [CommandLine](IMPORTANT): DeadReckonWithRespectToSeafloor.allowableFailures (count)
2020-10-01T16:23:40.670Z,1601569420.670 [CommandLine](IMPORTANT): DeadReckonUsingDVLWaterTrack.allowableFailures (count)
2020-10-01T16:23:40.671Z,1601569420.671 [CommandLine](IMPORTANT): DeadReckonUsingCompactModelForecast.allowableFailures (count)
2020-10-01T16:23:40.689Z,1601569420.689 [CommandLine](IMPORTANT): ESPComponent.pppFlow (none)
2020-10-01T16:23:40.698Z,1601569420.698 [CommandLine](IMPORTANT): WetLabsUBAT.flowrateCalibCoeff (none_float)
2020-10-01T16:23:40.698Z,1601569420.698 [CommandLine](IMPORTANT): WetLabsUBAT.minFlowrate (liter_per_second)
2020-10-01T16:23:40.747Z,1601569420.747 [CommandLine](IMPORTANT): NavChart.sea_floor_depth_below_geoid (meter)
2020-10-01T16:23:58.094Z,1601569438.094 [CommandLine](IMPORTANT): got command show variable limitlo
2020-10-01T16:23:58.121Z,1601569438.121 [CommandLine](IMPORTANT): BuoyancyServo.limitLo (count)
2020-10-01T16:23:58.123Z,1601569438.123 [CommandLine](IMPORTANT): ElevatorServo.limitLo (count)
2020-10-01T16:23:58.124Z,1601569438.124 [CommandLine](IMPORTANT): MassServo.limitLo (count)
2020-10-01T16:23:58.125Z,1601569438.125 [CommandLine](IMPORTANT): RudderServo.limitLo (count)
2020-10-01T16:23:58.263Z,1601569438.263 [CommandLine](IMPORTANT): VerticalControl.buoyancyLimitLoCC (cubic_centimeter)
2020-10-01T16:24:02.263Z,1601569442.263 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:24:04.293Z,1601569444.293 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:24:04.298Z,1601569444.298 [Reporter](INFO): platform_buoyancy_position 961.292236 cc
2020-10-01T16:24:04.706Z,1601569444.706 [Reporter](INFO): platform_buoyancy_position 960.486328 cc
2020-10-01T16:24:13.262Z,1601569453.262 [CommandLine](IMPORTANT): got command configSet VerticalControl.buoyancyLimitLoCC 800.000000 cubic_centimeter
2020-10-01T16:24:18.222Z,1601569458.222 [CommandLine](INFO): End of History
2020-10-01T16:24:19.390Z,1601569459.390 [CommandLine](INFO): End of History
2020-10-01T16:24:19.966Z,1601569459.966 [CommandLine](INFO): End of History
2020-10-01T16:24:34.978Z,1601569474.978 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:24:36.991Z,1601569476.991 [CommandLine](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 800.000000 cubic_centimeter
2020-10-01T16:24:37.000Z,1601569477.000 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.buoyancyAction" handled in the control thread.
2020-10-01T16:24:37.041Z,1601569477.041 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:24:37.054Z,1601569477.054 [Reporter](INFO): platform_buoyancy_position 962.366577 cc
2020-10-01T16:24:37.059Z,1601569477.059 [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:24:41.076Z,1601569481.076 [BuoyancyServo](INFO): requested pos: 800.000000
2020-10-01T16:24:41.127Z,1601569481.127 [Reporter](INFO): platform_buoyancy_position 960.352051 cc
2020-10-01T16:24:41.474Z,1601569481.474 [Reporter](INFO): platform_buoyancy_position 960.352051 cc
2020-10-01T16:24:41.870Z,1601569481.870 [Reporter](INFO): platform_buoyancy_position 959.949219 cc
2020-10-01T16:24:42.286Z,1601569482.286 [Reporter](INFO): platform_buoyancy_position 947.324951 cc
2020-10-01T16:24:42.698Z,1601569482.698 [Reporter](INFO): platform_buoyancy_position 945.176147 cc
2020-10-01T16:24:43.111Z,1601569483.111 [Reporter](INFO): platform_buoyancy_position 943.027344 cc
2020-10-01T16:24:43.482Z,1601569483.482 [Reporter](INFO): platform_buoyancy_position 938.864136 cc
2020-10-01T16:24:43.894Z,1601569483.894 [Reporter](INFO): platform_buoyancy_position 934.432129 cc
2020-10-01T16:24:44.290Z,1601569484.290 [Reporter](INFO): platform_buoyancy_position 928.120117 cc
2020-10-01T16:24:44.723Z,1601569484.723 [Reporter](INFO): platform_buoyancy_position 923.688232 cc
2020-10-01T16:24:45.106Z,1601569485.106 [Reporter](INFO): platform_buoyancy_position 917.376099 cc
2020-10-01T16:24:45.514Z,1601569485.514 [Reporter](INFO): platform_buoyancy_position 913.078491 cc
2020-10-01T16:24:45.926Z,1601569485.926 [Reporter](INFO): platform_buoyancy_position 906.766357 cc
2020-10-01T16:24:46.338Z,1601569486.338 [Reporter](INFO): platform_buoyancy_position 902.200195 cc
2020-10-01T16:24:46.750Z,1601569486.750 [Reporter](INFO): platform_buoyancy_position 895.753784 cc
2020-10-01T16:24:47.161Z,1601569487.161 [Reporter](INFO): platform_buoyancy_position 891.456177 cc
2020-10-01T16:24:47.544Z,1601569487.544 [Reporter](INFO): platform_buoyancy_position 887.024292 cc
2020-10-01T16:24:47.953Z,1601569487.953 [Reporter](INFO): platform_buoyancy_position 880.846436 cc
2020-10-01T16:24:48.326Z,1601569488.326 [Reporter](INFO): platform_buoyancy_position 876.280273 cc
2020-10-01T16:24:48.734Z,1601569488.734 [Reporter](INFO): platform_buoyancy_position 870.505371 cc
2020-10-01T16:24:49.163Z,1601569489.163 [Reporter](INFO): platform_buoyancy_position 865.670532 cc
2020-10-01T16:24:49.554Z,1601569489.554 [Reporter](INFO): platform_buoyancy_position 859.224121 cc
2020-10-01T16:24:49.982Z,1601569489.982 [Reporter](INFO): platform_buoyancy_position 854.926636 cc
2020-10-01T16:24:50.354Z,1601569490.354 [Reporter](INFO): platform_buoyancy_position 850.360352 cc
2020-10-01T16:24:50.774Z,1601569490.774 [Reporter](INFO): platform_buoyancy_position 844.316895 cc
2020-10-01T16:24:51.183Z,1601569491.183 [Reporter](INFO): platform_buoyancy_position 837.870483 cc
2020-10-01T16:24:51.599Z,1601569491.599 [Reporter](INFO): platform_buoyancy_position 833.572876 cc
2020-10-01T16:24:51.982Z,1601569491.982 [Reporter](INFO): platform_buoyancy_position 828.872437 cc
2020-10-01T16:24:52.378Z,1601569492.378 [Reporter](INFO): platform_buoyancy_position 822.694580 cc
2020-10-01T16:24:52.782Z,1601569492.782 [Reporter](INFO): platform_buoyancy_position 818.396973 cc
2020-10-01T16:24:53.139Z,1601569493.139 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2020-10-01T16:24:53.139Z,1601569493.139 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-10-01T16:24:53.173Z,1601569493.173 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-10-01T16:24:53.175Z,1601569493.175 [Reporter](INFO): platform_buoyancy_position 812.084900 cc
2020-10-01T16:24:53.589Z,1601569493.589 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-10-01T16:24:53.589Z,1601569493.589 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2020-10-01T16:24:53.597Z,1601569493.597 [Reporter](INFO): platform_buoyancy_position 807.652954 cc
2020-10-01T16:25:07.704Z,1601569507.704 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:25:09.341Z,1601569509.341 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:25:09.346Z,1601569509.346 [Reporter](INFO): platform_buoyancy_position 807.787292 cc
2020-10-01T16:25:09.742Z,1601569509.742 [Reporter](INFO): platform_buoyancy_position 807.787292 cc
2020-10-01T16:25:25.107Z,1601569525.107 [CommandLine](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 783.000000 cubic_centimeter
2020-10-01T16:25:25.483Z,1601569525.483 [BuoyancyServo](FAULT): is needed
2020-10-01T16:25:31.161Z,1601569531.161 [BuoyancyServo](INFO): requested pos: 783.000000
2020-10-01T16:25:31.202Z,1601569531.202 [Reporter](INFO): platform_buoyancy_position 807.652954 cc
2020-10-01T16:25:31.577Z,1601569531.577 [Reporter](INFO): platform_buoyancy_position 807.652954 cc
2020-10-01T16:25:31.989Z,1601569531.989 [Reporter](INFO): platform_buoyancy_position 807.787292 cc
2020-10-01T16:25:32.386Z,1601569532.386 [Reporter](INFO): platform_buoyancy_position 801.340881 cc
2020-10-01T16:25:32.770Z,1601569532.770 [Reporter](INFO): platform_buoyancy_position 801.340881 cc
2020-10-01T16:25:33.186Z,1601569533.186 [Reporter](INFO): platform_buoyancy_position 801.206543 cc
2020-10-01T16:25:33.586Z,1601569533.586 [Reporter](INFO): platform_buoyancy_position 801.206543 cc
2020-10-01T16:25:33.986Z,1601569533.986 [Reporter](INFO): platform_buoyancy_position 801.206543 cc
2020-10-01T16:25:34.406Z,1601569534.406 [Reporter](INFO): platform_buoyancy_position 801.072266 cc
2020-10-01T16:25:34.818Z,1601569534.818 [Reporter](INFO): platform_buoyancy_position 799.057800 cc
2020-10-01T16:25:35.223Z,1601569535.223 [Reporter](INFO): platform_buoyancy_position 797.043274 cc
2020-10-01T16:25:35.602Z,1601569535.602 [Reporter](INFO): platform_buoyancy_position 796.908997 cc
2020-10-01T16:25:36.002Z,1601569536.002 [Reporter](INFO): platform_buoyancy_position 794.894470 cc
2020-10-01T16:25:36.406Z,1601569536.406 [Reporter](INFO): platform_buoyancy_position 794.760193 cc
2020-10-01T16:25:36.818Z,1601569536.818 [Reporter](INFO): platform_buoyancy_position 792.745667 cc
2020-10-01T16:25:37.238Z,1601569537.238 [Reporter](INFO): platform_buoyancy_position 791.134094 cc
2020-10-01T16:25:37.661Z,1601569537.661 [Reporter](INFO): platform_buoyancy_position 790.462585 cc
2020-10-01T16:25:38.039Z,1601569538.039 [Reporter](INFO): platform_buoyancy_position 788.448059 cc
2020-10-01T16:25:38.434Z,1601569538.434 [Reporter](INFO): platform_buoyancy_position 788.313782 cc
2020-10-01T16:25:38.834Z,1601569538.834 [Reporter](INFO): platform_buoyancy_position 786.299255 cc
2020-10-01T16:25:41.236Z,1601569541.236 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:25:43.309Z,1601569543.309 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:25:43.315Z,1601569543.315 [Reporter](INFO): platform_buoyancy_position 790.462585 cc
2020-10-01T16:25:43.722Z,1601569543.722 [Reporter](INFO): platform_buoyancy_position 790.596863 cc
2020-10-01T16:26:03.503Z,1601569563.503 [CommandLine](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 773.000000 cubic_centimeter
2020-10-01T16:26:03.853Z,1601569563.853 [BuoyancyServo](FAULT): is needed
2020-10-01T16:26:05.501Z,1601569565.501 [BuoyancyServo](INFO): requested pos: 773.000000
2020-10-01T16:26:05.558Z,1601569565.558 [Reporter](INFO): platform_buoyancy_position 790.865479 cc
2020-10-01T16:26:05.922Z,1601569565.922 [Reporter](INFO): platform_buoyancy_position 790.596863 cc
2020-10-01T16:26:06.322Z,1601569566.322 [Reporter](INFO): platform_buoyancy_position 790.462585 cc
2020-10-01T16:26:06.726Z,1601569566.726 [Reporter](INFO): platform_buoyancy_position 784.150452 cc
2020-10-01T16:26:07.134Z,1601569567.134 [Reporter](INFO): platform_buoyancy_position 784.419067 cc
2020-10-01T16:26:07.518Z,1601569567.518 [Reporter](INFO): platform_buoyancy_position 784.016174 cc
2020-10-01T16:26:07.926Z,1601569567.926 [Reporter](INFO): platform_buoyancy_position 784.150452 cc
2020-10-01T16:26:08.338Z,1601569568.338 [Reporter](INFO): platform_buoyancy_position 784.150452 cc
2020-10-01T16:26:08.755Z,1601569568.755 [Reporter](INFO): platform_buoyancy_position 784.016174 cc
2020-10-01T16:26:09.150Z,1601569569.150 [Reporter](INFO): platform_buoyancy_position 781.867371 cc
2020-10-01T16:26:09.534Z,1601569569.534 [Reporter](INFO): platform_buoyancy_position 781.733093 cc
2020-10-01T16:26:09.938Z,1601569569.938 [Reporter](INFO): platform_buoyancy_position 779.718567 cc
2020-10-01T16:26:10.354Z,1601569570.354 [Reporter](INFO): platform_buoyancy_position 777.704102 cc
2020-10-01T16:26:10.754Z,1601569570.754 [Reporter](INFO): platform_buoyancy_position 777.569763 cc
2020-10-01T16:26:11.170Z,1601569571.170 [Reporter](INFO): platform_buoyancy_position 775.555298 cc
2020-10-01T16:26:13.945Z,1601569573.945 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:26:15.585Z,1601569575.585 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:26:15.590Z,1601569575.590 [Reporter](INFO): platform_buoyancy_position 779.718567 cc
2020-10-01T16:26:15.993Z,1601569575.993 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:26:16.002Z,1601569576.002 [Reporter](INFO): platform_buoyancy_position 779.852844 cc
2020-10-01T16:26:16.401Z,1601569576.401 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:26:16.405Z,1601569576.405 [CBIT](FAULT): Buoyancy position below lower volume limit. VB valve could be leaking!
2020-10-01T16:26:16.405Z,1601569576.405 [CBIT](INFO): Current buoyancy position: 779.718567
2020-10-01T16:26:16.407Z,1601569576.407 [Reporter](INFO): platform_buoyancy_position 779.718567 cc
2020-10-01T16:26:16.833Z,1601569576.833 [Reporter](INFO): platform_buoyancy_position 779.718567 cc
2020-10-01T16:26:47.082Z,1601569607.082 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:26:49.141Z,1601569609.141 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:26:49.146Z,1601569609.146 [Reporter](INFO): platform_buoyancy_position 782.001648 cc
2020-10-01T16:26:49.566Z,1601569609.566 [Reporter](INFO): platform_buoyancy_position 781.867371 cc
2020-10-01T16:27:19.794Z,1601569639.794 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:27:21.449Z,1601569641.449 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:27:21.455Z,1601569641.455 [Reporter](INFO): platform_buoyancy_position 781.733093 cc
2020-10-01T16:27:21.874Z,1601569641.874 [Reporter](INFO): platform_buoyancy_position 780.524353 cc
2020-10-01T16:27:48.877Z,1601569668.877 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-10-01T16:27:48.877Z,1601569668.877 [Default:CheckIn:C.Wait] Stopped
2020-10-01T16:27:48.877Z,1601569668.877 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-10-01T16:27:48.877Z,1601569668.877 [Default:CheckIn:D] Running Loop=1
2020-10-01T16:27:49.301Z,1601569669.301 [Default:CheckIn:D] Stopped
2020-10-01T16:27:49.302Z,1601569669.302 [Default:CheckIn:E] Running Loop=1
2020-10-01T16:27:49.710Z,1601569669.710 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 22.484281 min 
2020-10-01T16:27:49.710Z,1601569669.710 [Default:CheckIn:E] Stopped
2020-10-01T16:27:49.710Z,1601569669.710 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-10-01T16:27:49.710Z,1601569669.710 [Default:CheckIn] Stopped
2020-10-01T16:27:49.710Z,1601569669.710 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-10-01T16:27:49.711Z,1601569669.711 [Default:CheckIn](INFO): Running loop #3
2020-10-01T16:27:49.711Z,1601569669.711 [Default:CheckIn] Running Loop=3
2020-10-01T16:27:49.711Z,1601569669.711 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-10-01T16:27:49.711Z,1601569669.711 [Default:CheckIn:Read_GPS] Running Loop=1
2020-10-01T16:27:52.137Z,1601569672.137 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:27:54.132Z,1601569674.132 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2020-10-01T16:27:54.132Z,1601569674.132 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-10-01T16:27:54.165Z,1601569674.165 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:27:54.170Z,1601569674.170 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-10-01T16:27:54.171Z,1601569674.171 [Reporter](INFO): platform_buoyancy_position 781.867371 cc
2020-10-01T16:27:54.577Z,1601569674.577 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-10-01T16:27:54.577Z,1601569674.577 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2020-10-01T16:27:54.579Z,1601569674.579 [Reporter](INFO): platform_buoyancy_position 781.330200 cc
2020-10-01T16:28:24.841Z,1601569704.841 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:28:26.901Z,1601569706.901 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:28:26.907Z,1601569706.907 [Reporter](INFO): platform_buoyancy_position 780.792969 cc
2020-10-01T16:28:27.316Z,1601569707.316 [Reporter](INFO): platform_buoyancy_position 780.255798 cc
2020-10-01T16:28:57.569Z,1601569737.569 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:28:59.633Z,1601569739.633 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:28:59.654Z,1601569739.654 [Reporter](INFO): platform_buoyancy_position 781.733093 cc
2020-10-01T16:29:00.046Z,1601569740.046 [Reporter](INFO): platform_buoyancy_position 781.867371 cc
2020-10-01T16:29:30.302Z,1601569770.302 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:29:31.949Z,1601569771.949 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:29:31.955Z,1601569771.955 [Reporter](INFO): platform_buoyancy_position 781.733093 cc
2020-10-01T16:29:32.356Z,1601569772.356 [Reporter](INFO): platform_buoyancy_position 781.330200 cc
2020-10-01T16:30:02.610Z,1601569802.610 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:30:04.680Z,1601569804.680 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:30:04.685Z,1601569804.685 [Reporter](INFO): platform_buoyancy_position 781.867371 cc
2020-10-01T16:30:05.069Z,1601569805.069 [Reporter](INFO): platform_buoyancy_position 781.867371 cc
2020-10-01T16:30:35.350Z,1601569835.350 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:30:37.401Z,1601569837.401 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:30:37.438Z,1601569837.438 [Reporter](INFO): platform_buoyancy_position 781.867371 cc
2020-10-01T16:30:37.838Z,1601569837.838 [Reporter](INFO): platform_buoyancy_position 781.867371 cc
2020-10-01T16:30:55.125Z,1601569855.125 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2020-10-01T16:30:55.125Z,1601569855.125 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-10-01T16:30:55.141Z,1601569855.141 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-10-01T16:30:55.551Z,1601569855.551 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-10-01T16:30:55.551Z,1601569855.551 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2020-10-01T16:31:08.070Z,1601569868.070 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:31:10.101Z,1601569870.101 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:31:10.106Z,1601569870.106 [Reporter](INFO): platform_buoyancy_position 781.061584 cc
2020-10-01T16:31:10.515Z,1601569870.515 [Reporter](INFO): platform_buoyancy_position 779.987183 cc
2020-10-01T16:31:40.782Z,1601569900.782 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:31:42.421Z,1601569902.421 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:31:42.426Z,1601569902.426 [Reporter](INFO): platform_buoyancy_position 782.001648 cc
2020-10-01T16:31:42.834Z,1601569902.834 [Reporter](INFO): platform_buoyancy_position 781.867371 cc
2020-10-01T16:32:13.110Z,1601569933.110 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:32:15.169Z,1601569935.169 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:32:15.174Z,1601569935.174 [Reporter](INFO): platform_buoyancy_position 781.867371 cc
2020-10-01T16:32:15.582Z,1601569935.582 [Reporter](INFO): platform_buoyancy_position 781.867371 cc
2020-10-01T16:32:45.825Z,1601569965.825 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:32:47.469Z,1601569967.469 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:32:47.482Z,1601569967.482 [Reporter](INFO): platform_buoyancy_position 781.867371 cc
2020-10-01T16:32:47.896Z,1601569967.896 [Reporter](INFO): platform_buoyancy_position 781.867371 cc
2020-10-01T16:32:49.878Z,1601569969.878 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-10-01T16:27:49.7Z
2020-10-01T16:32:49.878Z,1601569969.878 [Default:CheckIn:Read_GPS] Stopped
2020-10-01T16:32:49.878Z,1601569969.878 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-10-01T16:32:52.278Z,1601569972.278 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-10-01T16:32:52.278Z,1601569972.278 [NAL9602] Data Fault, FailCount= 3
2020-10-01T16:32:52.278Z,1601569972.278 [NAL9602](ERROR): Data Fault
2020-10-01T16:32:52.294Z,1601569972.294 [CBIT](ERROR): Data Fault in component: NAL9602
2020-10-01T16:32:52.677Z,1601569972.677 [NAL9602](INFO): Powering down
2020-10-01T16:32:53.507Z,1601569973.507 [CBIT](INFO): Clearing failed state for component NAL9602
2020-10-01T16:32:53.507Z,1601569973.507 [NAL9602] No Fault, FailCount= 3
2020-10-01T16:32:56.824Z,1601569976.824 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201001T160035/Courier0010.lzma
2020-10-01T16:32:57.826Z,1601569977.826 [DataOverHttps](INFO): Moved sent file to Logs/20201001T160035/Courier0010.lzma.bak
2020-10-01T16:32:57.826Z,1601569977.826 [DataOverHttps](INFO): SBD MOMSN=12705421
2020-10-01T16:33:14.457Z,1601569994.457 [DataOverHttps](INFO): Sending 924 bytes from file Logs/20201001T160035/Express0011.lzma
2020-10-01T16:33:15.458Z,1601569995.458 [DataOverHttps](INFO): Moved sent file to Logs/20201001T160035/Express0011.lzma.bak
2020-10-01T16:33:15.458Z,1601569995.458 [DataOverHttps](INFO): SBD MOMSN=12705423
2020-10-01T16:33:18.157Z,1601569998.157 [Default:CheckIn:Read_Iridium] Stopped
2020-10-01T16:33:18.157Z,1601569998.157 [Default:CheckIn:C.Wait] Running Loop=1
2020-10-01T16:33:18.157Z,1601569998.157 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-10-01T16:33:18.162Z,1601569998.162 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:33:20.205Z,1601570000.205 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:33:20.210Z,1601570000.210 [Reporter](INFO): platform_buoyancy_position 781.598755 cc
2020-10-01T16:33:20.626Z,1601570000.626 [Reporter](INFO): platform_buoyancy_position 779.987183 cc
2020-10-01T16:33:22.983Z,1601570002.983 [NAL9602](INFO): Powering up NAL9602
2020-10-01T16:33:33.890Z,1601570013.890 [NAL9602](INFO): NAL9602 initialized
2020-10-01T16:33:50.886Z,1601570030.886 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:33:52.941Z,1601570032.941 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:33:52.946Z,1601570032.946 [Reporter](INFO): platform_buoyancy_position 781.867371 cc
2020-10-01T16:33:53.357Z,1601570033.357 [Reporter](INFO): platform_buoyancy_position 781.733093 cc
2020-10-01T16:33:56.140Z,1601570036.140 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2020-10-01T16:33:56.140Z,1601570036.140 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-10-01T16:33:56.151Z,1601570036.151 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-10-01T16:33:56.553Z,1601570036.553 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-10-01T16:33:56.553Z,1601570036.553 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2020-10-01T16:34:23.599Z,1601570063.599 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:34:25.241Z,1601570065.241 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:34:25.246Z,1601570065.246 [Reporter](INFO): platform_buoyancy_position 781.598755 cc
2020-10-01T16:34:25.654Z,1601570065.654 [Reporter](INFO): platform_buoyancy_position 781.598755 cc
2020-10-01T16:34:55.938Z,1601570095.938 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:34:57.993Z,1601570097.993 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:34:57.998Z,1601570097.998 [Reporter](INFO): platform_buoyancy_position 780.658691 cc
2020-10-01T16:34:58.402Z,1601570098.402 [Reporter](INFO): platform_buoyancy_position 779.852844 cc
2020-10-01T16:35:28.658Z,1601570128.658 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:35:30.693Z,1601570130.693 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:35:30.717Z,1601570130.717 [Reporter](INFO): platform_buoyancy_position 781.598755 cc
2020-10-01T16:35:31.127Z,1601570131.127 [Reporter](INFO): platform_buoyancy_position 779.852844 cc
2020-10-01T16:36:01.378Z,1601570161.378 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:36:03.033Z,1601570163.033 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:36:03.038Z,1601570163.038 [Reporter](INFO): platform_buoyancy_position 781.330200 cc
2020-10-01T16:36:03.422Z,1601570163.422 [Reporter](INFO): platform_buoyancy_position 781.598755 cc
2020-10-01T16:36:33.700Z,1601570193.700 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:36:35.741Z,1601570195.741 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:36:35.746Z,1601570195.746 [Reporter](INFO): platform_buoyancy_position 781.330200 cc
2020-10-01T16:36:36.162Z,1601570196.162 [Reporter](INFO): platform_buoyancy_position 779.987183 cc
2020-10-01T16:36:57.128Z,1601570217.128 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2020-10-01T16:36:57.128Z,1601570217.128 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-10-01T16:36:57.139Z,1601570217.139 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-10-01T16:36:57.546Z,1601570217.546 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-10-01T16:36:57.546Z,1601570217.546 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2020-10-01T16:37:06.424Z,1601570226.424 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:37:08.473Z,1601570228.473 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:37:08.478Z,1601570228.478 [Reporter](INFO): platform_buoyancy_position 782.001648 cc
2020-10-01T16:37:08.896Z,1601570228.896 [Reporter](INFO): platform_buoyancy_position 781.867371 cc
2020-10-01T16:37:39.130Z,1601570259.130 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:37:40.789Z,1601570260.789 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:37:40.795Z,1601570260.795 [Reporter](INFO): platform_buoyancy_position 781.867371 cc
2020-10-01T16:37:41.186Z,1601570261.186 [Reporter](INFO): platform_buoyancy_position 781.195862 cc
2020-10-01T16:38:11.450Z,1601570291.450 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:38:13.109Z,1601570293.109 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:38:13.114Z,1601570293.114 [Reporter](INFO): platform_buoyancy_position 781.867371 cc
2020-10-01T16:38:13.521Z,1601570293.521 [Reporter](INFO): platform_buoyancy_position 780.927246 cc
2020-10-01T16:38:18.722Z,1601570298.722 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-10-01T16:38:18.722Z,1601570298.722 [Default:CheckIn:C.Wait] Stopped
2020-10-01T16:38:18.722Z,1601570298.722 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-10-01T16:38:18.722Z,1601570298.722 [Default:CheckIn:D] Running Loop=1
2020-10-01T16:38:19.142Z,1601570299.142 [Default:CheckIn:D] Stopped
2020-10-01T16:38:19.142Z,1601570299.142 [Default:CheckIn:E] Running Loop=1
2020-10-01T16:38:19.558Z,1601570299.558 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 32.981632 min 
2020-10-01T16:38:19.558Z,1601570299.558 [Default:CheckIn:E] Stopped
2020-10-01T16:38:19.558Z,1601570299.558 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-10-01T16:38:19.558Z,1601570299.558 [Default:CheckIn] Stopped
2020-10-01T16:38:19.558Z,1601570299.558 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-10-01T16:38:19.558Z,1601570299.558 [Default:CheckIn](INFO): Running loop #4
2020-10-01T16:38:19.558Z,1601570299.558 [Default:CheckIn] Running Loop=4
2020-10-01T16:38:19.558Z,1601570299.558 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-10-01T16:38:19.559Z,1601570299.559 [Default:CheckIn:Read_GPS] Running Loop=1
2020-10-01T16:38:43.779Z,1601570323.779 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:38:45.413Z,1601570325.413 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:38:45.418Z,1601570325.418 [Reporter](INFO): platform_buoyancy_position 781.867371 cc
2020-10-01T16:38:45.866Z,1601570325.866 [Reporter](INFO): platform_buoyancy_position 782.001648 cc
2020-10-01T16:39:16.093Z,1601570356.093 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:39:17.732Z,1601570357.732 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:39:17.742Z,1601570357.742 [Reporter](INFO): platform_buoyancy_position 781.733093 cc
2020-10-01T16:39:18.166Z,1601570358.166 [Reporter](INFO): platform_buoyancy_position 781.867371 cc
2020-10-01T16:39:48.434Z,1601570388.434 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:39:50.473Z,1601570390.473 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:39:50.478Z,1601570390.478 [Reporter](INFO): platform_buoyancy_position 780.658691 cc
2020-10-01T16:39:50.907Z,1601570390.907 [Reporter](INFO): platform_buoyancy_position 780.255798 cc
2020-10-01T16:39:58.112Z,1601570398.112 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2020-10-01T16:39:58.112Z,1601570398.112 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-10-01T16:39:58.123Z,1601570398.123 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-10-01T16:39:58.530Z,1601570398.530 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-10-01T16:39:58.530Z,1601570398.530 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2020-10-01T16:40:21.134Z,1601570421.134 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:40:22.803Z,1601570422.803 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:40:22.808Z,1601570422.808 [Reporter](INFO): platform_buoyancy_position 781.464478 cc
2020-10-01T16:40:23.207Z,1601570423.207 [Reporter](INFO): platform_buoyancy_position 781.330200 cc
2020-10-01T16:40:53.467Z,1601570453.467 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:40:55.109Z,1601570455.109 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:40:55.114Z,1601570455.114 [Reporter](INFO): platform_buoyancy_position 781.867371 cc
2020-10-01T16:40:55.510Z,1601570455.510 [Reporter](INFO): platform_buoyancy_position 781.867371 cc
2020-10-01T16:41:25.778Z,1601570485.778 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:41:27.429Z,1601570487.429 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:41:27.434Z,1601570487.434 [Reporter](INFO): platform_buoyancy_position 781.867371 cc
2020-10-01T16:41:27.853Z,1601570487.853 [Reporter](INFO): platform_buoyancy_position 781.733093 cc
2020-10-01T16:41:58.098Z,1601570518.098 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:42:00.161Z,1601570520.161 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:42:00.185Z,1601570520.185 [Reporter](INFO): platform_buoyancy_position 781.867371 cc
2020-10-01T16:42:00.574Z,1601570520.574 [Reporter](INFO): platform_buoyancy_position 781.733093 cc
2020-10-01T16:42:30.845Z,1601570550.845 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:42:32.461Z,1601570552.461 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:42:32.466Z,1601570552.466 [Reporter](INFO): platform_buoyancy_position 781.598755 cc
2020-10-01T16:42:32.890Z,1601570552.890 [Reporter](INFO): platform_buoyancy_position 781.867371 cc
2020-10-01T16:42:59.096Z,1601570579.096 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2020-10-01T16:42:59.096Z,1601570579.096 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-10-01T16:42:59.106Z,1601570579.106 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-10-01T16:42:59.511Z,1601570579.511 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-10-01T16:42:59.511Z,1601570579.511 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2020-10-01T16:43:03.154Z,1601570583.154 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:43:04.805Z,1601570584.805 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:43:04.826Z,1601570584.826 [Reporter](INFO): platform_buoyancy_position 782.001648 cc
2020-10-01T16:43:05.202Z,1601570585.202 [Reporter](INFO): platform_buoyancy_position 781.733093 cc
2020-10-01T16:43:19.715Z,1601570599.715 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-10-01T16:38:19.6Z
2020-10-01T16:43:19.715Z,1601570599.715 [Default:CheckIn:Read_GPS] Stopped
2020-10-01T16:43:19.715Z,1601570599.715 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-10-01T16:43:27.120Z,1601570607.120 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201001T160035/Courier0013.lzma
2020-10-01T16:43:33.142Z,1601570613.142 [DataOverHttps](INFO): Moved sent file to Logs/20201001T160035/Courier0013.lzma.bak
2020-10-01T16:43:33.142Z,1601570613.142 [DataOverHttps](INFO): SBD MOMSN=12705585
2020-10-01T16:43:35.042Z,1601570615.042 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-10-01T16:43:35.042Z,1601570615.042 [NAL9602] Data Fault, FailCount= 4
2020-10-01T16:43:35.042Z,1601570615.042 [NAL9602](ERROR): Data Fault
2020-10-01T16:43:35.058Z,1601570615.058 [CBIT](ERROR): Data Fault in component: NAL9602
2020-10-01T16:43:35.445Z,1601570615.445 [NAL9602](INFO): Powering down
2020-10-01T16:43:35.490Z,1601570615.490 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:43:36.269Z,1601570616.269 [CBIT](INFO): Clearing failed state for component NAL9602
2020-10-01T16:43:36.269Z,1601570616.269 [NAL9602] No Fault, FailCount= 4
2020-10-01T16:43:37.497Z,1601570617.497 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:43:37.502Z,1601570617.502 [Reporter](INFO): platform_buoyancy_position 781.867371 cc
2020-10-01T16:43:37.951Z,1601570617.951 [Reporter](INFO): platform_buoyancy_position 781.464478 cc
2020-10-01T16:44:05.746Z,1601570645.746 [NAL9602](INFO): Powering up NAL9602
2020-10-01T16:44:08.194Z,1601570648.194 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:44:09.841Z,1601570649.841 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:44:09.855Z,1601570649.855 [Reporter](INFO): platform_buoyancy_position 781.867371 cc
2020-10-01T16:44:10.234Z,1601570650.234 [Reporter](INFO): platform_buoyancy_position 781.867371 cc
2020-10-01T16:44:16.658Z,1601570656.658 [NAL9602](INFO): NAL9602 initialized
2020-10-01T16:44:24.276Z,1601570664.276 [DataOverHttps](INFO): Sending 306 bytes from file Logs/20201001T160035/Express0014.lzma
2020-10-01T16:44:25.278Z,1601570665.278 [DataOverHttps](INFO): Moved sent file to Logs/20201001T160035/Express0014.lzma.bak
2020-10-01T16:44:25.278Z,1601570665.278 [DataOverHttps](INFO): SBD MOMSN=12705587
2020-10-01T16:44:27.985Z,1601570667.985 [Default:CheckIn:Read_Iridium] Stopped
2020-10-01T16:44:27.985Z,1601570667.985 [Default:CheckIn:C.Wait] Running Loop=1
2020-10-01T16:44:27.986Z,1601570667.986 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-10-01T16:44:40.526Z,1601570680.526 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:44:42.577Z,1601570682.577 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:44:42.582Z,1601570682.582 [Reporter](INFO): platform_buoyancy_position 781.598755 cc
2020-10-01T16:44:42.966Z,1601570682.966 [Reporter](INFO): platform_buoyancy_position 781.195862 cc
2020-10-01T16:45:13.238Z,1601570713.238 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:45:15.289Z,1601570715.289 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:45:15.294Z,1601570715.294 [Reporter](INFO): platform_buoyancy_position 775.420959 cc
2020-10-01T16:45:15.709Z,1601570715.709 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:45:15.734Z,1601570715.734 [Reporter](INFO): platform_buoyancy_position 775.555298 cc
2020-10-01T16:45:16.097Z,1601570716.097 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:45:16.101Z,1601570716.101 [CBIT](FAULT): Buoyancy position below lower volume limit. VB valve could be leaking!
2020-10-01T16:45:16.101Z,1601570716.101 [CBIT](INFO): Current buoyancy position: 775.555298
2020-10-01T16:45:16.112Z,1601570716.112 [Reporter](INFO): platform_buoyancy_position 775.555298 cc
2020-10-01T16:45:16.516Z,1601570716.516 [Reporter](INFO): platform_buoyancy_position 775.420959 cc
2020-10-01T16:45:46.782Z,1601570746.782 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:45:48.905Z,1601570748.905 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:45:48.927Z,1601570748.927 [Reporter](INFO): platform_buoyancy_position 775.420959 cc
2020-10-01T16:45:49.237Z,1601570749.237 [Reporter](INFO): platform_buoyancy_position 775.555298 cc
2020-10-01T16:46:00.146Z,1601570760.146 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2020-10-01T16:46:00.146Z,1601570760.146 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-10-01T16:46:00.156Z,1601570760.156 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-10-01T16:46:00.542Z,1601570760.542 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-10-01T16:46:00.542Z,1601570760.542 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2020-10-01T16:46:19.522Z,1601570779.522 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:46:21.153Z,1601570781.153 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:46:21.158Z,1601570781.158 [Reporter](INFO): platform_buoyancy_position 775.420959 cc
2020-10-01T16:46:21.582Z,1601570781.582 [Reporter](INFO): platform_buoyancy_position 775.286682 cc
2020-10-01T16:46:51.863Z,1601570811.863 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:46:53.501Z,1601570813.501 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:46:53.506Z,1601570813.506 [Reporter](INFO): platform_buoyancy_position 775.420959 cc
2020-10-01T16:46:53.926Z,1601570813.926 [Reporter](INFO): platform_buoyancy_position 775.420959 cc
2020-10-01T16:47:10.543Z,1601570830.543 [CommandLine](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 900.000000 cubic_centimeter
2020-10-01T16:47:10.842Z,1601570830.842 [BuoyancyServo](FAULT): is needed
2020-10-01T16:47:16.117Z,1601570836.117 [BuoyancyServo](INFO): requested pos: 900.000000
2020-10-01T16:47:16.163Z,1601570836.163 [Reporter](INFO): platform_buoyancy_position 775.420959 cc
2020-10-01T16:47:16.546Z,1601570836.546 [Reporter](INFO): platform_buoyancy_position 775.555298 cc
2020-10-01T16:47:16.942Z,1601570836.942 [Reporter](INFO): platform_buoyancy_position 777.569763 cc
2020-10-01T16:47:17.342Z,1601570837.342 [Reporter](INFO): platform_buoyancy_position 781.867371 cc
2020-10-01T16:47:17.758Z,1601570837.758 [Reporter](INFO): platform_buoyancy_position 786.164978 cc
2020-10-01T16:47:18.172Z,1601570838.172 [Reporter](INFO): platform_buoyancy_position 790.596863 cc
2020-10-01T16:47:18.582Z,1601570838.582 [Reporter](INFO): platform_buoyancy_position 797.043274 cc
2020-10-01T16:47:18.954Z,1601570838.954 [Reporter](INFO): platform_buoyancy_position 801.340881 cc
2020-10-01T16:47:19.369Z,1601570839.369 [Reporter](INFO): platform_buoyancy_position 807.787292 cc
2020-10-01T16:47:19.782Z,1601570839.782 [Reporter](INFO): platform_buoyancy_position 812.084900 cc
2020-10-01T16:47:20.178Z,1601570840.178 [Reporter](INFO): platform_buoyancy_position 818.396973 cc
2020-10-01T16:47:20.571Z,1601570840.571 [Reporter](INFO): platform_buoyancy_position 822.694580 cc
2020-10-01T16:47:20.987Z,1601570840.987 [Reporter](INFO): platform_buoyancy_position 829.140991 cc
2020-10-01T16:47:21.402Z,1601570841.402 [Reporter](INFO): platform_buoyancy_position 833.707153 cc
2020-10-01T16:47:21.810Z,1601570841.810 [Reporter](INFO): platform_buoyancy_position 839.885010 cc
2020-10-01T16:47:22.190Z,1601570842.190 [Reporter](INFO): platform_buoyancy_position 844.316895 cc
2020-10-01T16:47:22.598Z,1601570842.598 [Reporter](INFO): platform_buoyancy_position 848.883057 cc
2020-10-01T16:47:23.019Z,1601570843.019 [Reporter](INFO): platform_buoyancy_position 855.060913 cc
2020-10-01T16:47:23.406Z,1601570843.406 [Reporter](INFO): platform_buoyancy_position 859.224121 cc
2020-10-01T16:47:23.826Z,1601570843.826 [Reporter](INFO): platform_buoyancy_position 865.670532 cc
2020-10-01T16:47:24.226Z,1601570844.226 [Reporter](INFO): platform_buoyancy_position 869.968140 cc
2020-10-01T16:47:24.630Z,1601570844.630 [Reporter](INFO): platform_buoyancy_position 876.414551 cc
2020-10-01T16:47:25.022Z,1601570845.022 [Reporter](INFO): platform_buoyancy_position 880.846436 cc
2020-10-01T16:47:25.422Z,1601570845.422 [Reporter](INFO): platform_buoyancy_position 887.158569 cc
2020-10-01T16:47:25.829Z,1601570845.829 [Reporter](INFO): platform_buoyancy_position 891.456177 cc
2020-10-01T16:47:54.486Z,1601570874.486 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:47:56.517Z,1601570876.517 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:47:56.522Z,1601570876.522 [Reporter](INFO): platform_buoyancy_position 900.185669 cc
2020-10-01T16:47:56.934Z,1601570876.934 [Reporter](INFO): platform_buoyancy_position 900.185669 cc
2020-10-01T16:48:27.193Z,1601570907.193 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:48:28.841Z,1601570908.841 [BuoyancyServo](FAULT): is needed (data)
2020-10-01T16:48:28.847Z,1601570908.847 [Reporter](INFO): platform_buoyancy_position 900.185669 cc
2020-10-01T16:48:29.254Z,1601570909.254 [Reporter](INFO): platform_buoyancy_position 900.185669 cc
2020-10-01T16:48:39.186Z,1601570919.186 [CommandLine](IMPORTANT): got command restart application
2020-10-01T16:48:40.193Z,1601570920.193 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-10-01T16:48:40.193Z,1601570920.193 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-10-01T16:48:40.384Z,1601570920.384 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-10-01T16:48:40.384Z,1601570920.384 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-10-01T16:48:40.385Z,1601570920.385 [CommandLine](INFO): Join timeout helper Thread ID is 9595
2020-10-01T16:48:40.386Z,1601570920.386 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-10-01T16:48:40.386Z,1601570920.386 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-10-01T16:48:40.386Z,1601570920.386 [NavChartDb](INFO): Join timeout helper Thread ID is 9596
2020-10-01T16:48:40.389Z,1601570920.389 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-10-01T16:48:40.389Z,1601570920.389 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-10-01T16:48:40.404Z,1601570920.404 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2020-10-01T16:48:40.405Z,1601570920.405 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-10-01T16:48:40.405Z,1601570920.405 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 9597
2020-10-01T16:48:40.557Z,1601570920.557 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2020-10-01T16:48:40.557Z,1601570920.557 [WetLabsBB2FL](INFO): Powering down
2020-10-01T16:48:40.558Z,1601570920.558 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-10-01T16:48:40.576Z,1601570920.576 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2020-10-01T16:48:40.577Z,1601570920.577 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-10-01T16:48:40.577Z,1601570920.577 [CTD_Seabird](INFO): Join timeout helper Thread ID is 9598
2020-10-01T16:48:40.896Z,1601570920.896 [CTD_Seabird](INFO): Powering down
2020-10-01T16:48:40.918Z,1601570920.918 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2020-10-01T16:48:40.924Z,1601570920.924 [CTD_Seabird](INFO): Powering down
2020-10-01T16:48:40.936Z,1601570920.936 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-10-01T16:48:40.944Z,1601570920.944 [ComponentRegistry](INFO): Shutting down BackSeatDriver ThreadHandler
2020-10-01T16:48:40.944Z,1601570920.944 [BackSeatDriver ThreadHandler](INFO): Thread cancelled.
2020-10-01T16:48:40.945Z,1601570920.945 [BackSeatDriver](INFO): Join timeout helper Thread ID is 9599
2020-10-01T16:48:41.012Z,1601570921.012 [BackSeatDriver ThreadHandler](INFO): Uninitializing protected caller thread.
2020-10-01T16:48:41.013Z,1601570921.013 [BackSeatDriver ThreadHandler](INFO): Thread cancelled.
2020-10-01T16:48:41.025Z,1601570921.025 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-10-01T16:48:41.025Z,1601570921.025 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-10-01T16:48:41.026Z,1601570921.026 [Radio_Surface](INFO): Join timeout helper Thread ID is 9600
2020-10-01T16:48:41.416Z,1601570921.416 [Radio_Surface](INFO): Powering down
2020-10-01T16:48:41.417Z,1601570921.417 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-10-01T16:48:41.418Z,1601570921.418 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-10-01T16:48:41.429Z,1601570921.429 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2020-10-01T16:48:41.429Z,1601570921.429 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-10-01T16:48:41.430Z,1601570921.430 [Onboard](INFO): Join timeout helper Thread ID is 9601
2020-10-01T16:48:41.909Z,1601570921.909 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2020-10-01T16:48:43.272Z,1601570923.272 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2020-10-01T16:48:43.273Z,1601570923.273 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-10-01T16:48:43.277Z,1601570923.277 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-10-01T16:48:43.277Z,1601570923.277 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-10-01T16:48:43.278Z,1601570923.278 [DataOverHttps](INFO): Join timeout helper Thread ID is 9602
2020-10-01T16:48:43.917Z,1601570923.917 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-10-01T16:48:43.917Z,1601570923.917 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-10-01T16:48:43.921Z,1601570923.921 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-10-01T16:48:43.921Z,1601570923.921 [logger ThreadHandler](INFO): Thread cancelled.
2020-10-01T16:48:43.922Z,1601570923.922 [logger](INFO): Join timeout helper Thread ID is 9603
2020-10-01T16:48:43.933Z,1601570923.933 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-10-01T16:48:43.933Z,1601570923.933 [logger ThreadHandler](INFO): Thread cancelled.
2020-10-01T16:48:43.941Z,1601570923.941 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-10-01T16:48:43.941Z,1601570923.941 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-10-01T16:48:43.941Z,1601570923.941 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-10-01T16:48:43.941Z,1601570923.941 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-10-01T16:48:43.942Z,1601570923.942 [controlThread](INFO): Join timeout helper Thread ID is 9604
2020-10-01T16:48:44.136Z,1601570924.136 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-10-01T16:48:44.137Z,1601570924.137 [controlThread](DEBUG): Uninitializing ControlThread
2020-10-01T16:48:44.137Z,1601570924.137 [AHRS_M2](INFO): Powering down
2020-10-01T16:48:44.209Z,1601570924.209 [NAL9602](INFO): Powering down
2020-10-01T16:48:44.210Z,1601570924.210 [DAT](INFO): Powering down
2020-10-01T16:48:44.330Z,1601570924.330 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-10-01T16:48:44.331Z,1601570924.331 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-10-01T16:48:44.332Z,1601570924.332 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-10-01T16:48:44.332Z,1601570924.332 [MissionManager](INFO): Uninitializing Mission Default
2020-10-01T16:48:44.332Z,1601570924.332 [Default] Stopped
2020-10-01T16:48:44.332Z,1601570924.332 [Default](DEBUG): Aggregate::uninitialize Default
2020-10-01T16:48:44.332Z,1601570924.332 [Default:B.GoToSurface] Stopped
2020-10-01T16:48:44.333Z,1601570924.333 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-10-01T16:48:44.333Z,1601570924.333 [Default:CheckIn] Stopped
2020-10-01T16:48:44.333Z,1601570924.333 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-10-01T16:48:44.333Z,1601570924.333 [Default:CheckIn:C.Wait] Stopped
2020-10-01T16:48:44.333Z,1601570924.333 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-10-01T16:48:44.335Z,1601570924.335 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-10-01T16:48:44.336Z,1601570924.336 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-10-01T16:48:44.336Z,1601570924.336 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-10-01T16:48:44.336Z,1601570924.336 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-10-01T16:48:44.337Z,1601570924.337 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-10-01T16:48:44.337Z,1601570924.337 [BuoyancyServo](INFO): Powering down
2020-10-01T16:48:44.348Z,1601570924.348 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-10-01T16:48:44.349Z,1601570924.349 [ElevatorServo](INFO): Powering down
2020-10-01T16:48:44.349Z,1601570924.349 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-10-01T16:48:44.349Z,1601570924.349 [MassServo](INFO): Powering down
2020-10-01T16:48:44.350Z,1601570924.350 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-10-01T16:48:44.350Z,1601570924.350 [RudderServo](INFO): Powering down
2020-10-01T16:48:44.351Z,1601570924.351 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-10-01T16:48:44.351Z,1601570924.351 [ThrusterServo](INFO): Powering down
2020-10-01T16:48:44.352Z,1601570924.352 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-10-01T16:48:44.352Z,1601570924.352 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-10-01T16:48:44.353Z,1601570924.353 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-10-01T16:48:44.353Z,1601570924.353 [CBIT](DEBUG): Powering off loads.
2020-10-01T16:48:44.364Z,1601570924.364 [CBIT](DEBUG): Disabling WDT.
2020-10-01T16:48:44.376Z,1601570924.376 [CBIT](DEBUG): Opening all GF detection circuits.
2020-10-01T16:48:44.377Z,1601570924.377 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-10-01T16:48:44.462Z,1601570924.462 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-10-01T16:48:44.465Z,1601570924.465 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-10-01T16:48:44.470Z,1601570924.470 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-10-01T16:48:44.527Z,1601570924.527 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-10-01T16:48:44.532Z,1601570924.532 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-10-01T16:48:44.535Z,1601570924.535 [BackSeatDriver ThreadHandler](INFO): Thread cancelled.
2020-10-01T16:48:44.545Z,1601570924.545 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-10-01T16:48:44.606Z,1601570924.606 [logger ThreadHandler](INFO): Thread cancelled.