2019-10-03T14:32:58.282Z,1570113178.282 [Supervisor](DEBUG): Initializing supervisor.
2019-10-03T14:32:58.285Z,1570113178.285 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-10-03T14:32:58.286Z,1570113178.286 [SyncHandler](INFO): Protected caller Thread ID is 807
2019-10-03T14:32:58.286Z,1570113178.286 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-10-03T14:32:58.287Z,1570113178.287 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-10-03T14:32:58.288Z,1570113178.288 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 808
2019-10-03T14:32:58.290Z,1570113178.290 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-10-03T14:32:58.302Z,1570113178.302 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-10-03T14:32:58.302Z,1570113178.302 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-10-03T14:32:58.303Z,1570113178.303 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 809
2019-10-03T14:32:58.304Z,1570113178.304 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-10-03T14:32:58.305Z,1570113178.305 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-10-03T14:32:58.305Z,1570113178.305 [logger ThreadHandler](INFO): Protected caller Thread ID is 810
2019-10-03T14:32:58.307Z,1570113178.307 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-10-03T14:32:58.307Z,1570113178.307 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-10-03T14:32:58.312Z,1570113178.312 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-10-03T14:32:58.733Z,1570113178.733 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-10-03T14:32:58.735Z,1570113178.735 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-10-03T14:32:58.879Z,1570113178.879 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-10-03T14:32:58.881Z,1570113178.881 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-10-03T14:32:58.962Z,1570113178.962 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-10-03T14:32:59.062Z,1570113179.062 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-10-03T14:32:59.064Z,1570113179.064 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-10-03T14:32:59.159Z,1570113179.159 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-10-03T14:32:59.160Z,1570113179.160 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-10-03T14:32:59.540Z,1570113179.540 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-10-03T14:32:59.541Z,1570113179.541 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-10-03T14:32:59.644Z,1570113179.644 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-10-03T14:32:59.645Z,1570113179.645 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-10-03T14:33:00.105Z,1570113180.105 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-10-03T14:33:00.105Z,1570113180.105 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-10-03T14:33:00.307Z,1570113180.307 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-10-03T14:33:00.309Z,1570113180.309 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-10-03T14:33:00.612Z,1570113180.612 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-10-03T14:33:00.614Z,1570113180.614 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-10-03T14:33:00.827Z,1570113180.827 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-10-03T14:33:00.829Z,1570113180.829 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-10-03T14:33:00.971Z,1570113180.971 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-10-03T14:33:00.973Z,1570113180.973 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-10-03T14:33:01.614Z,1570113181.614 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-10-03T14:33:01.616Z,1570113181.616 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-10-03T14:33:02.454Z,1570113182.454 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-10-03T14:33:02.456Z,1570113182.456 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-10-03T14:33:02.664Z,1570113182.664 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-10-03T14:33:02.666Z,1570113182.666 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2019-10-03T14:33:02.669Z,1570113182.669 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2019-10-03T14:33:02.864Z,1570113182.864 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2019-10-03T14:33:02.947Z,1570113182.947 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2019-10-03T14:33:03.085Z,1570113183.085 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2019-10-03T14:33:03.171Z,1570113183.171 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2019-10-03T14:33:03.398Z,1570113183.398 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-10-03T14:33:03.400Z,1570113183.400 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2019-10-03T14:33:03.496Z,1570113183.496 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2019-10-03T14:33:03.604Z,1570113183.604 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2019-10-03T14:33:03.698Z,1570113183.698 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2019-10-03T14:33:03.853Z,1570113183.853 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2019-10-03T14:33:03.955Z,1570113183.955 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2019-10-03T14:33:04.056Z,1570113184.056 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2019-10-03T14:33:04.058Z,1570113184.058 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-10-03T14:33:04.083Z,1570113184.083 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-10-03T14:33:04.085Z,1570113184.085 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-10-03T14:33:04.174Z,1570113184.174 [VerticalControl](DEBUG): Construct VerticalControl.
2019-10-03T14:33:04.287Z,1570113184.287 [VerticalControl] Loaded
2019-10-03T14:33:04.287Z,1570113184.287 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-10-03T14:33:04.288Z,1570113184.288 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-10-03T14:33:04.357Z,1570113184.357 [HorizontalControl] Loaded
2019-10-03T14:33:04.357Z,1570113184.357 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-10-03T14:33:04.358Z,1570113184.358 [SpeedControl](DEBUG): Construct SpeedControl.
2019-10-03T14:33:04.364Z,1570113184.364 [SpeedControl] Loaded
2019-10-03T14:33:04.364Z,1570113184.364 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-10-03T14:33:04.365Z,1570113184.365 [LoopControl](DEBUG): Construct LoopControl.
2019-10-03T14:33:04.365Z,1570113184.365 [LoopControl] Loaded
2019-10-03T14:33:04.366Z,1570113184.366 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-10-03T14:33:04.366Z,1570113184.366 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-10-03T14:33:04.367Z,1570113184.367 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-10-03T14:33:04.517Z,1570113184.517 [BuoyancyServo] Loaded
2019-10-03T14:33:04.517Z,1570113184.517 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-10-03T14:33:04.528Z,1570113184.528 [ElevatorServo] Loaded
2019-10-03T14:33:04.528Z,1570113184.528 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-10-03T14:33:04.539Z,1570113184.539 [MassServo] Loaded
2019-10-03T14:33:04.540Z,1570113184.540 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-10-03T14:33:04.551Z,1570113184.551 [RudderServo] Loaded
2019-10-03T14:33:04.551Z,1570113184.551 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-10-03T14:33:04.562Z,1570113184.562 [ThrusterServo] Loaded
2019-10-03T14:33:04.562Z,1570113184.562 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-10-03T14:33:04.563Z,1570113184.563 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-10-03T14:33:04.563Z,1570113184.563 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-10-03T14:33:04.618Z,1570113184.618 [DepthRateCalculator] Loaded
2019-10-03T14:33:04.619Z,1570113184.619 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-10-03T14:33:04.624Z,1570113184.624 [PitchRateCalculator] Loaded
2019-10-03T14:33:04.624Z,1570113184.624 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-10-03T14:33:04.639Z,1570113184.639 [SpeedCalculator] Loaded
2019-10-03T14:33:04.640Z,1570113184.640 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-10-03T14:33:04.661Z,1570113184.661 [TempGradientCalculator] Loaded
2019-10-03T14:33:04.662Z,1570113184.662 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-10-03T14:33:04.667Z,1570113184.667 [YawRateCalculator] Loaded
2019-10-03T14:33:04.667Z,1570113184.667 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-10-03T14:33:04.697Z,1570113184.697 [ElevatorOffsetCalculator] Loaded
2019-10-03T14:33:04.697Z,1570113184.697 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-10-03T14:33:04.698Z,1570113184.698 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-10-03T14:33:04.700Z,1570113184.700 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-10-03T14:33:04.820Z,1570113184.820 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-10-03T14:33:04.820Z,1570113184.820 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-10-03T14:33:04.841Z,1570113184.841 [NavChart] Loaded
2019-10-03T14:33:04.842Z,1570113184.842 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-10-03T14:33:04.846Z,1570113184.846 [UniversalFixResidualReporter] Loaded
2019-10-03T14:33:04.846Z,1570113184.846 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-10-03T14:33:04.846Z,1570113184.846 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-10-03T14:33:04.847Z,1570113184.847 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-10-03T14:33:05.306Z,1570113185.306 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2019-10-03T14:33:05.311Z,1570113185.311 [AHRS_M2](INFO): created writer for : platform_orientation
2019-10-03T14:33:05.312Z,1570113185.312 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2019-10-03T14:33:05.318Z,1570113185.318 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2019-10-03T14:33:05.318Z,1570113185.318 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2019-10-03T14:33:05.323Z,1570113185.323 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2019-10-03T14:33:05.324Z,1570113185.324 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2019-10-03T14:33:05.329Z,1570113185.329 [AHRS_M2](INFO): created writer for : platform_roll_angle
2019-10-03T14:33:05.505Z,1570113185.505 [AHRS_M2] Loaded
2019-10-03T14:33:05.506Z,1570113185.506 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2019-10-03T14:33:05.648Z,1570113185.648 [DataOverHttps] Loaded
2019-10-03T14:33:05.649Z,1570113185.649 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-10-03T14:33:05.650Z,1570113185.650 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 407F84E0
2019-10-03T14:33:05.650Z,1570113185.650 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 889
2019-10-03T14:33:05.664Z,1570113185.664 [Depth_Keller] Loaded
2019-10-03T14:33:05.664Z,1570113185.664 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-10-03T14:33:05.669Z,1570113185.669 [DropWeight] Loaded
2019-10-03T14:33:05.670Z,1570113185.670 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-10-03T14:33:05.715Z,1570113185.715 [DVL_micro] Loaded
2019-10-03T14:33:05.715Z,1570113185.715 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2019-10-03T14:33:05.811Z,1570113185.811 [NAL9602] Loaded
2019-10-03T14:33:05.812Z,1570113185.812 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-10-03T14:33:05.828Z,1570113185.828 [Onboard] Loaded
2019-10-03T14:33:05.828Z,1570113185.828 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-10-03T14:33:05.831Z,1570113185.831 [Radio_Surface] Loaded
2019-10-03T14:33:05.832Z,1570113185.832 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-10-03T14:33:05.833Z,1570113185.833 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408284E0
2019-10-03T14:33:05.833Z,1570113185.833 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 890
2019-10-03T14:33:05.960Z,1570113185.960 [DAT] Loaded
2019-10-03T14:33:05.960Z,1570113185.960 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2019-10-03T14:33:07.625Z,1570113187.625 [BPC1] Loaded
2019-10-03T14:33:07.625Z,1570113187.625 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-10-03T14:33:07.626Z,1570113187.626 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-10-03T14:33:07.831Z,1570113187.831 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-10-03T14:33:07.969Z,1570113187.969 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-10-03T14:33:07.970Z,1570113187.970 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-10-03T14:33:08.227Z,1570113188.227 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-10-03T14:33:08.228Z,1570113188.228 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-10-03T14:33:08.276Z,1570113188.276 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-10-03T14:33:08.277Z,1570113188.277 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-10-03T14:33:08.524Z,1570113188.524 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-10-03T14:33:08.529Z,1570113188.529 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-10-03T14:33:08.530Z,1570113188.530 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-10-03T14:33:08.534Z,1570113188.534 [CTD_Seabird](INFO): created writer for : depth
2019-10-03T14:33:08.535Z,1570113188.535 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-10-03T14:33:08.540Z,1570113188.540 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-10-03T14:33:08.541Z,1570113188.541 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-10-03T14:33:08.546Z,1570113188.546 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-10-03T14:33:08.547Z,1570113188.547 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-10-03T14:33:08.552Z,1570113188.552 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-10-03T14:33:08.553Z,1570113188.553 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-10-03T14:33:08.558Z,1570113188.558 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-10-03T14:33:08.558Z,1570113188.558 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-10-03T14:33:08.564Z,1570113188.564 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-10-03T14:33:08.564Z,1570113188.564 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): mass_concentration_of_oxygen_in_sea_water
2019-10-03T14:33:08.570Z,1570113188.570 [CTD_Seabird](INFO): created writer for : mass_concentration_of_oxygen_in_sea_water
2019-10-03T14:33:08.570Z,1570113188.570 [CTD_Seabird](DEBUG): LcmSlateWriter::add(DAtaURI): CTD_Seabird.sea_water_dissolved_oxygen_freq
2019-10-03T14:33:08.571Z,1570113188.571 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): dataName: sea_water_dissolved_oxygen_freq
2019-10-03T14:33:08.604Z,1570113188.604 [CTD_Seabird] Loaded
2019-10-03T14:33:08.604Z,1570113188.604 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-10-03T14:33:08.605Z,1570113188.605 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409BF4E0
2019-10-03T14:33:08.605Z,1570113188.605 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 891
2019-10-03T14:33:08.636Z,1570113188.636 [ESPComponent] Loaded
2019-10-03T14:33:08.636Z,1570113188.636 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2019-10-03T14:33:08.650Z,1570113188.650 [PAR_Licor] Loaded
2019-10-03T14:33:08.651Z,1570113188.651 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-10-03T14:33:08.657Z,1570113188.657 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470
2019-10-03T14:33:08.657Z,1570113188.657 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470
2019-10-03T14:33:08.661Z,1570113188.661 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650
2019-10-03T14:33:08.661Z,1570113188.661 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650
2019-10-03T14:33:08.665Z,1570113188.665 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl
2019-10-03T14:33:08.666Z,1570113188.666 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl
2019-10-03T14:33:08.670Z,1570113188.670 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm
2019-10-03T14:33:08.670Z,1570113188.670 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm
2019-10-03T14:33:08.674Z,1570113188.674 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm
2019-10-03T14:33:08.674Z,1570113188.674 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm
2019-10-03T14:33:08.679Z,1570113188.679 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm
2019-10-03T14:33:08.679Z,1570113188.679 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm
2019-10-03T14:33:08.683Z,1570113188.683 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm
2019-10-03T14:33:08.684Z,1570113188.684 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm
2019-10-03T14:33:08.688Z,1570113188.688 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water
2019-10-03T14:33:08.692Z,1570113188.692 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water
2019-10-03T14:33:08.693Z,1570113188.693 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water
2019-10-03T14:33:08.693Z,1570113188.693 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water
2019-10-03T14:33:08.697Z,1570113188.697 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2019-10-03T14:33:08.697Z,1570113188.697 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2019-10-03T14:33:08.702Z,1570113188.702 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2019-10-03T14:33:08.702Z,1570113188.702 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2019-10-03T14:33:08.706Z,1570113188.706 [WetLabsBB2FL] Loaded
2019-10-03T14:33:08.706Z,1570113188.706 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-10-03T14:33:08.708Z,1570113188.708 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409EF4E0
2019-10-03T14:33:08.708Z,1570113188.708 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 892
2019-10-03T14:33:08.709Z,1570113188.709 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-10-03T14:33:08.709Z,1570113188.709 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-10-03T14:33:09.056Z,1570113189.056 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-10-03T14:33:09.057Z,1570113189.057 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-10-03T14:33:09.217Z,1570113189.217 [SBIT](DEBUG): Construct Startup Built In Test.
2019-10-03T14:33:09.228Z,1570113189.228 [SBIT] Loaded
2019-10-03T14:33:09.229Z,1570113189.229 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-10-03T14:33:09.229Z,1570113189.229 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-10-03T14:33:09.241Z,1570113189.241 [IBIT] Loaded
2019-10-03T14:33:09.242Z,1570113189.242 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-10-03T14:33:09.245Z,1570113189.245 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-10-03T14:33:09.386Z,1570113189.386 [CBIT] Loaded
2019-10-03T14:33:09.386Z,1570113189.386 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-10-03T14:33:09.387Z,1570113189.387 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-10-03T14:33:09.390Z,1570113189.390 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-10-03T14:33:09.391Z,1570113189.391 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-10-03T14:33:09.398Z,1570113189.398 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-10-03T14:33:09.399Z,1570113189.399 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ACE4E0
2019-10-03T14:33:09.400Z,1570113189.400 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 893
2019-10-03T14:33:09.404Z,1570113189.404 [Supervisor](INFO): Main Thread ID is 803
2019-10-03T14:33:09.405Z,1570113189.405 [Supervisor](DEBUG): Running supervisor.
2019-10-03T14:33:09.405Z,1570113189.405 [CommandLine ThreadHandler](INFO): Handler Thread ID is 894
2019-10-03T14:33:09.407Z,1570113189.407 [controlThread ThreadHandler](INFO): Handler Thread ID is 895
2019-10-03T14:33:09.408Z,1570113189.408 [controlThread](DEBUG): Initializing ControlThread
2019-10-03T14:33:09.409Z,1570113189.409 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-10-03T14:33:09.410Z,1570113189.410 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-10-03T14:33:09.411Z,1570113189.411 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-10-03T14:33:09.411Z,1570113189.411 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-10-03T14:33:09.413Z,1570113189.413 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-10-03T14:33:09.413Z,1570113189.413 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-10-03T14:33:09.413Z,1570113189.413 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-10-03T14:33:09.414Z,1570113189.414 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-10-03T14:33:09.414Z,1570113189.414 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-10-03T14:33:09.414Z,1570113189.414 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-10-03T14:33:09.415Z,1570113189.415 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-10-03T14:33:09.416Z,1570113189.416 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-10-03T14:33:09.424Z,1570113189.424 [SBIT](INFO): Initialize SBIT Component.
2019-10-03T14:33:09.424Z,1570113189.424 [SBIT](IMPORTANT): git: 2019-10-03_B
2019-10-03T14:33:09.424Z,1570113189.424 [SBIT](INFO): git hash: 395bdff68a97d6c59eee2553e61a5ed127931da4
2019-10-03T14:33:09.425Z,1570113189.425 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-10-03T14:33:09.426Z,1570113189.426 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
Kernel Reported: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
2019-10-03T14:33:09.427Z,1570113189.427 [SBIT](INFO): Beginning SBIT in 30.000000 seconds.
2019-10-03T14:33:09.428Z,1570113189.428 [IBIT](INFO): Initialize IBIT Component.
2019-10-03T14:33:09.428Z,1570113189.428 [CBIT](DEBUG): Initialize CBIT Component.
2019-10-03T14:33:09.429Z,1570113189.429 [logger ThreadHandler](INFO): Handler Thread ID is 896
2019-10-03T14:33:09.439Z,1570113189.439 [CBIT](DEBUG): Initialized mux pins.
2019-10-03T14:33:09.439Z,1570113189.439 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2019-10-03T14:33:09.439Z,1570113189.439 [CBIT](DEBUG): Initializing the watchdog timer.
2019-10-03T14:33:09.447Z,1570113189.447 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 897
2019-10-03T14:33:09.449Z,1570113189.449 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-10-03T14:33:09.459Z,1570113189.459 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 898
2019-10-03T14:33:09.463Z,1570113189.463 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-10-03T14:33:09.463Z,1570113189.463 [CBIT](DEBUG): Initializing heartbeat.
2019-10-03T14:33:09.471Z,1570113189.471 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 899
2019-10-03T14:33:09.472Z,1570113189.472 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-10-03T14:33:09.475Z,1570113189.475 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-10-03T14:33:09.477Z,1570113189.477 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 901
2019-10-03T14:33:09.478Z,1570113189.478 [WetLabsBB2FL](INFO): Powering down
2019-10-03T14:33:09.519Z,1570113189.519 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 902
2019-10-03T14:33:09.535Z,1570113189.535 [CBIT](DEBUG): Deactivating GF circuits.
2019-10-03T14:33:09.535Z,1570113189.535 [CBIT](DEBUG): Deactivating emergency mode.
2019-10-03T14:33:09.571Z,1570113189.571 [CBIT](DEBUG): Backplane powered.
2019-10-03T14:33:09.636Z,1570113189.636 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-10-03T14:33:09.714Z,1570113189.714 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-10-03T14:33:09.714Z,1570113189.714 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-10-03T14:33:09.714Z,1570113189.714 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-10-03T14:33:09.714Z,1570113189.714 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-10-03T14:33:09.715Z,1570113189.715 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-10-03T14:33:09.715Z,1570113189.715 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-10-03T14:33:09.715Z,1570113189.715 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-10-03T14:33:09.715Z,1570113189.715 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-10-03T14:33:09.715Z,1570113189.715 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-10-03T14:33:09.715Z,1570113189.715 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-10-03T14:33:09.716Z,1570113189.716 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-10-03T14:33:09.716Z,1570113189.716 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-10-03T14:33:09.716Z,1570113189.716 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-10-03T14:33:09.716Z,1570113189.716 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-10-03T14:33:09.716Z,1570113189.716 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-10-03T14:33:09.716Z,1570113189.716 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-10-03T14:33:09.773Z,1570113189.773 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-10-03T14:33:09.842Z,1570113189.842 [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>


2019-10-03T14:33:09.843Z,1570113189.843 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-10-03T14:33:09.863Z,1570113189.863 [Radio_Surface](INFO): Powering up
2019-10-03T14:33:09.936Z,1570113189.936 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-10-03T14:33:09.937Z,1570113189.937 [Default:A.Wait](DEBUG): Construct Wait.
2019-10-03T14:33:09.963Z,1570113189.963 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-10-03T14:33:09.990Z,1570113189.990 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-10-03T14:33:09.992Z,1570113189.992 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-10-03T14:33:10.010Z,1570113190.010 [Default:E.Execute](DEBUG): Construct Execute.
2019-10-03T14:33:10.013Z,1570113190.013 [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>


2019-10-03T14:33:10.029Z,1570113190.029 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,DVL_micro,NAL9602,Onboard,DAT,BPC1,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-10-03T14:33:10.035Z,1570113190.035 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-10-03T14:33:10.136Z,1570113190.136 [Depth_Keller](ERROR): Pressure reading out of range: 1925.893799 decibar
2019-10-03T14:33:10.161Z,1570113190.161 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2019-10-03T14:33:10.161Z,1570113190.161 [DAT](INFO): Powering up
2019-10-03T14:33:10.161Z,1570113190.161 [DAT](DEBUG): Initializing DAT.
2019-10-03T14:33:10.208Z,1570113190.208 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2019-10-03T14:33:10.263Z,1570113190.263 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-10-03T14:33:10.341Z,1570113190.341 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-10-03T14:33:10.347Z,1570113190.347 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-10-03T14:33:10.349Z,1570113190.349 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-10-03T14:33:10.359Z,1570113190.359 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-10-03T14:33:10.360Z,1570113190.360 [MassServo](DEBUG): Initializing EZServoServo.
2019-10-03T14:33:10.367Z,1570113190.367 [MassServo](DEBUG): Initializing MassServo.
2019-10-03T14:33:10.368Z,1570113190.368 [RudderServo](DEBUG): Initializing EZServoServo.
2019-10-03T14:33:10.375Z,1570113190.375 [RudderServo](DEBUG): Initializing RudderServo.
2019-10-03T14:33:10.376Z,1570113190.376 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-10-03T14:33:10.383Z,1570113190.383 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-10-03T14:33:11.223Z,1570113191.223 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-10-03T14:33:11.223Z,1570113191.223 [RudderServo](FAULT): Rudder failed to initialize
2019-10-03T14:33:11.223Z,1570113191.223 [RudderServo] Communications Fault, FailCount= 1
2019-10-03T14:33:11.223Z,1570113191.223 [RudderServo](ERROR): Communications Fault
2019-10-03T14:33:11.406Z,1570113191.406 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-10-03T14:33:11.584Z,1570113191.584 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-10-03T14:33:11.584Z,1570113191.584 [RudderServo](INFO): Powering down
2019-10-03T14:33:12.269Z,1570113192.269 [RudderServo](DEBUG): Initializing EZServoServo.
2019-10-03T14:33:12.388Z,1570113192.388 [RudderServo](DEBUG): Initializing RudderServo.
2019-10-03T14:33:12.392Z,1570113192.392 [CBIT](INFO): Clearing failed state for component RudderServo
2019-10-03T14:33:12.392Z,1570113192.392 [RudderServo] No Fault, FailCount= 1
2019-10-03T14:33:16.504Z,1570113196.504 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2019-10-03T14:33:23.556Z,1570113203.556 [DAT](INFO): setting local address to 5
2019-10-03T14:33:23.959Z,1570113203.959 [DAT](INFO): set local address to 5
2019-10-03T14:33:27.581Z,1570113207.581 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-10-03T14:33:27.581Z,1570113207.581 [DVL_micro] Communications Fault, FailCount= 1
2019-10-03T14:33:27.581Z,1570113207.581 [DVL_micro](ERROR): Communications Fault
2019-10-03T14:33:27.657Z,1570113207.657 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-10-03T14:33:28.059Z,1570113208.059 [DVL_micro](INFO): Powering down
2019-10-03T14:33:28.872Z,1570113208.872 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-10-03T14:33:28.872Z,1570113208.872 [DVL_micro] No Fault, FailCount= 1
2019-10-03T14:33:36.069Z,1570113216.069 [NAL9602](INFO): Powering up NAL9602
2019-10-03T14:33:40.154Z,1570113220.154 [SBIT](IMPORTANT): Beginning Startup BIT
2019-10-03T14:33:40.158Z,1570113220.158 [CBIT](IMPORTANT): Beginning ground fault scan
2019-10-03T14:33:46.664Z,1570113226.664 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-10-03T14:33:46.664Z,1570113226.664 [DVL_micro] Communications Fault, FailCount= 2
2019-10-03T14:33:46.664Z,1570113226.664 [DVL_micro](ERROR): Communications Fault
2019-10-03T14:33:46.777Z,1570113226.777 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-10-03T14:33:47.143Z,1570113227.143 [DVL_micro](INFO): Powering down
2019-10-03T14:33:47.144Z,1570113227.144 [NAL9602](INFO): NAL9602 initialized
2019-10-03T14:33:47.891Z,1570113227.891 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:33:47.976Z,1570113227.976 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-10-03T14:33:47.976Z,1570113227.976 [DVL_micro] No Fault, FailCount= 2
2019-10-03T14:33:51.189Z,1570113231.189 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.007561
CHAN A1 (24V): 0.179444
CHAN A2 (12V): -0.004828
CHAN A3 (5V): -0.002893
CHAN B0 (3.3V): 0.000019
CHAN B1 (3.15aV): -0.000864
CHAN B2 (3.15bV): -0.001279
CHAN B3 (GND): 0.002571
OPEN: -0.004471
Full Scale Calc: 4.765 mA, -1.589 mA
2019-10-03T14:34:05.833Z,1570113245.833 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-10-03T14:34:05.833Z,1570113245.833 [DVL_micro] Communications Fault, FailCount= 3
2019-10-03T14:34:05.833Z,1570113245.833 [DVL_micro](ERROR): Communications Fault
2019-10-03T14:34:05.944Z,1570113245.944 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-10-03T14:34:05.944Z,1570113245.944 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2019-10-03T14:34:06.307Z,1570113246.307 [DVL_micro](INFO): Powering down
2019-10-03T14:34:06.410Z,1570113246.410 [CommandLine](FAULT): Scheduling is paused
2019-10-03T14:34:06.410Z,1570113246.410 [CBIT](INFO): Critical error at 20191003T143405
2019-10-03T14:34:06.410Z,1570113246.410 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-10-03T14:34:33.454Z,1570113273.454 [SBIT](IMPORTANT): SBIT PASSED
2019-10-03T14:34:33.512Z,1570113273.512 [CommandLine](IMPORTANT): got command configSet list
2019-10-03T14:34:33.512Z,1570113273.512 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-10-03T14:34:33.515Z,1570113273.515 [CommandLine](IMPORTANT): CBIT.gf24Offset=155 microampere;

2019-10-03T14:34:33.515Z,1570113273.515 [CommandLine](IMPORTANT): DVL_micro.loadAtStartup=0 bool;

2019-10-03T14:34:33.516Z,1570113273.516 [CommandLine](IMPORTANT): ESPComponent.loadAtStartup=1 bool;

2019-10-03T14:34:33.516Z,1570113273.516 [CommandLine](IMPORTANT): Express linearApproximation DVL_micro.height_above_sea_floor 5.000000 meter;

2019-10-03T14:34:33.861Z,1570113273.861 [MissionManager](IMPORTANT): Started mission Startup
2019-10-03T14:34:33.862Z,1570113273.862 [Startup] Running Loop=1
2019-10-03T14:34:33.862Z,1570113273.862 [Startup](DEBUG): Aggregate::initialize Startup
2019-10-03T14:34:33.862Z,1570113273.862 [Startup:A.GoToSurface] Running Loop=1
2019-10-03T14:34:33.862Z,1570113273.862 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-10-03T14:34:33.863Z,1570113273.863 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-10-03T14:34:33.863Z,1570113273.863 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-10-03T14:34:33.864Z,1570113273.864 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-10-03T14:34:33.864Z,1570113273.864 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-10-03T14:34:33.864Z,1570113273.864 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-10-03T14:34:33.866Z,1570113273.866 [Startup:StartupSatComms] Running Loop=1
2019-10-03T14:34:33.866Z,1570113273.866 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-10-03T14:34:33.866Z,1570113273.866 [Startup:StartupSatComms:A] Running Loop=1
2019-10-03T14:34:34.251Z,1570113274.251 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-10-03T14:34:50.337Z,1570113290.337 [CommandLine](IMPORTANT): got command burn on
2019-10-03T14:34:50.337Z,1570113290.337 [CommandLine](IMPORTANT): Activating dropweight wire
2019-10-03T14:35:32.693Z,1570113332.693 [CommandLine](IMPORTANT): got command burn off
2019-10-03T14:35:32.693Z,1570113332.693 [CommandLine](IMPORTANT): Deactivating dropweight wire
2019-10-03T14:35:34.073Z,1570113334.073 [Startup:StartupSatComms:A](INFO): Timed out from 2019-10-03T14:34:33.9Z
2019-10-03T14:35:34.073Z,1570113334.073 [Startup:StartupSatComms:A] Stopped
2019-10-03T14:35:34.073Z,1570113334.073 [Startup:StartupSatComms:B] Running Loop=1
2019-10-03T14:35:34.561Z,1570113334.561 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-10-03T14:35:39.601Z,1570113339.601 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005036
2019-10-03T14:35:49.047Z,1570113349.047 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20191003T142020/Courier0007.lzma
2019-10-03T14:35:51.052Z,1570113351.052 [DataOverHttps](INFO): Moved sent file to Logs/20191003T142020/Courier0007.lzma.bak
2019-10-03T14:35:51.053Z,1570113351.053 [DataOverHttps](INFO): SBD MOMSN=11844705
2019-10-03T14:36:08.955Z,1570113368.955 [DataOverHttps](INFO): Sending 102 bytes from file Logs/20191003T143258/Courier0000.lzma
2019-10-03T14:36:09.779Z,1570113369.779 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-10-03T14:36:09.779Z,1570113369.779 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-03T14:36:09.789Z,1570113369.789 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-03T14:36:10.200Z,1570113370.200 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-03T14:36:10.200Z,1570113370.200 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-10-03T14:36:10.960Z,1570113370.960 [DataOverHttps](INFO): Moved sent file to Logs/20191003T143258/Courier0000.lzma.bak
2019-10-03T14:36:10.961Z,1570113370.961 [DataOverHttps](INFO): SBD MOMSN=11844707
2019-10-03T14:36:30.374Z,1570113390.374 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20191003T142020/Express0008.lzma
2019-10-03T14:36:32.381Z,1570113392.381 [DataOverHttps](INFO): Moved sent file to Logs/20191003T142020/Express0008.lzma.bak
2019-10-03T14:36:32.381Z,1570113392.381 [DataOverHttps](INFO): SBD MOMSN=11844711
2019-10-03T14:36:34.426Z,1570113394.426 [Startup:StartupSatComms:B](INFO): Timed out from 2019-10-03T14:35:34.1Z
2019-10-03T14:36:34.426Z,1570113394.426 [Startup:StartupSatComms:B] Stopped
2019-10-03T14:36:34.426Z,1570113394.426 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-10-03T14:36:34.426Z,1570113394.426 [Startup:StartupSatComms] Stopped
2019-10-03T14:36:34.426Z,1570113394.426 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-10-03T14:36:34.431Z,1570113394.431 [Startup](INFO): Completed Startup
2019-10-03T14:36:34.431Z,1570113394.431 [MissionManager](INFO): Startup is completed.
2019-10-03T14:36:34.431Z,1570113394.431 [MissionManager](INFO): Uninitializing Mission Startup
2019-10-03T14:36:34.432Z,1570113394.432 [Startup] Stopped
2019-10-03T14:36:34.432Z,1570113394.432 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-10-03T14:36:34.432Z,1570113394.432 [Startup:A.GoToSurface] Stopped
2019-10-03T14:36:34.432Z,1570113394.432 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-10-03T14:36:34.833Z,1570113394.833 [MissionManager](IMPORTANT): Started mission Default
2019-10-03T14:36:34.833Z,1570113394.833 [Default] Running Loop=1
2019-10-03T14:36:34.834Z,1570113394.834 [Default](DEBUG): Aggregate::initialize Default
2019-10-03T14:36:34.834Z,1570113394.834 [Default:B.GoToSurface] Running Loop=1
2019-10-03T14:36:34.834Z,1570113394.834 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-10-03T14:36:34.834Z,1570113394.834 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-10-03T14:36:34.834Z,1570113394.834 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-10-03T14:36:34.835Z,1570113394.835 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-10-03T14:36:34.839Z,1570113394.839 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-10-03T14:36:34.840Z,1570113394.840 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-10-03T14:36:34.840Z,1570113394.840 [Default:A.Wait] Running Loop=1
2019-10-03T14:36:34.840Z,1570113394.840 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-10-03T14:36:48.228Z,1570113408.228 [Default:A.Wait](INFO): Done Waiting.
2019-10-03T14:36:48.228Z,1570113408.228 [Default:A.Wait] Stopped
2019-10-03T14:36:48.228Z,1570113408.228 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-10-03T14:36:48.602Z,1570113408.602 [Default:CheckIn] Running Loop=1
2019-10-03T14:36:48.602Z,1570113408.602 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-10-03T14:36:48.602Z,1570113408.602 [Default:CheckIn:Read_GPS] Running Loop=1
2019-10-03T14:36:49.010Z,1570113409.010 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-10-03T14:37:03.918Z,1570113423.918 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2019-10-03T14:37:05.952Z,1570113425.952 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-10-03T14:37:05.952Z,1570113425.952 [DVL_micro] No Fault, FailCount= 3
2019-10-03T14:37:13.984Z,1570113433.984 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2019-10-03T14:37:13.984Z,1570113433.984 [DVL_micro] Data Fault, FailCount= 1
2019-10-03T14:37:13.984Z,1570113433.984 [DVL_micro](ERROR): Data Fault
2019-10-03T14:37:14.081Z,1570113434.081 [CBIT](ERROR): Data Fault in component: DVL_micro
2019-10-03T14:37:14.463Z,1570113434.463 [DVL_micro](INFO): Powering down
2019-10-03T14:37:15.277Z,1570113435.277 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-10-03T14:37:15.277Z,1570113435.277 [DVL_micro] No Fault, FailCount= 1
2019-10-03T14:37:17.236Z,1570113437.236 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2019-10-03T14:37:17.769Z,1570113437.769 [DataOverHttps](INFO): Sending 866 bytes from file Logs/20191003T143258/Express0001.lzma
2019-10-03T14:37:19.772Z,1570113439.772 [DataOverHttps](INFO): Moved sent file to Logs/20191003T143258/Express0001.lzma.bak
2019-10-03T14:37:19.773Z,1570113439.773 [DataOverHttps](INFO): SBD MOMSN=11844713
2019-10-03T14:37:30.190Z,1570113450.190 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2019-10-03T14:37:32.977Z,1570113452.977 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-10-03T14:37:32.977Z,1570113452.977 [DVL_micro] Communications Fault, FailCount= 2
2019-10-03T14:37:32.977Z,1570113452.977 [DVL_micro](ERROR): Communications Fault
2019-10-03T14:37:33.044Z,1570113453.044 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-10-03T14:37:33.443Z,1570113453.443 [DVL_micro](INFO): Powering down
2019-10-03T14:37:34.224Z,1570113454.224 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-10-03T14:37:34.224Z,1570113454.224 [DVL_micro] No Fault, FailCount= 2
2019-10-03T14:37:43.103Z,1570113463.103 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2019-10-03T14:37:51.972Z,1570113471.972 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-10-03T14:37:51.973Z,1570113471.973 [DVL_micro] Communications Fault, FailCount= 3
2019-10-03T14:37:51.973Z,1570113471.973 [DVL_micro](ERROR): Communications Fault
2019-10-03T14:37:52.181Z,1570113472.181 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-10-03T14:37:52.181Z,1570113472.181 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2019-10-03T14:37:52.439Z,1570113472.439 [DVL_micro](INFO): Powering down
2019-10-03T14:37:52.516Z,1570113472.516 [CBIT](INFO): Critical error at 20191003T143752
2019-10-03T14:37:56.026Z,1570113476.026 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2019-10-03T14:38:08.958Z,1570113488.958 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2019-10-03T14:38:21.880Z,1570113501.880 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2019-10-03T14:38:34.804Z,1570113514.804 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2019-10-03T14:38:47.732Z,1570113527.732 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2019-10-03T14:38:50.145Z,1570113530.145 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-10-03T14:38:50.959Z,1570113530.959 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:38:53.385Z,1570113533.385 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:38:56.215Z,1570113536.215 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:38:58.233Z,1570113538.233 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:39:00.660Z,1570113540.660 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2019-10-03T14:39:01.466Z,1570113541.466 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:39:04.291Z,1570113544.291 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:39:07.523Z,1570113547.523 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:39:10.351Z,1570113550.351 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:39:10.800Z,1570113550.800 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-10-03T14:39:10.800Z,1570113550.800 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-03T14:39:10.810Z,1570113550.810 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-03T14:39:11.210Z,1570113551.210 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-03T14:39:11.210Z,1570113551.210 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-10-03T14:39:13.583Z,1570113553.583 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:39:13.619Z,1570113553.619 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2019-10-03T14:39:16.407Z,1570113556.407 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:39:19.247Z,1570113559.247 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:39:22.475Z,1570113562.475 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:39:25.307Z,1570113565.307 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:39:26.527Z,1570113566.527 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2019-10-03T14:39:28.531Z,1570113568.531 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:39:31.359Z,1570113571.359 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:39:34.591Z,1570113574.591 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:39:37.419Z,1570113577.419 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:39:40.252Z,1570113580.252 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:39:43.487Z,1570113583.487 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:39:46.311Z,1570113586.311 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:39:49.543Z,1570113589.543 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:39:52.363Z,1570113592.363 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:39:55.599Z,1570113595.599 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:39:58.427Z,1570113598.427 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:40:01.258Z,1570113601.258 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:40:04.487Z,1570113604.487 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:40:07.319Z,1570113607.319 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:40:10.551Z,1570113610.551 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:40:13.375Z,1570113613.375 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:40:16.203Z,1570113616.203 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:40:19.435Z,1570113619.435 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:40:22.279Z,1570113622.279 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:40:25.503Z,1570113625.503 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:40:28.331Z,1570113628.331 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:40:31.563Z,1570113631.563 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:40:34.391Z,1570113634.391 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:40:37.223Z,1570113637.223 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:40:40.459Z,1570113640.459 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:40:43.279Z,1570113643.279 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:40:46.523Z,1570113646.523 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:40:49.335Z,1570113649.335 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:40:52.190Z,1570113652.190 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-10-03T14:40:52.190Z,1570113652.190 [DVL_micro] No Fault, FailCount= 3
2019-10-03T14:40:52.575Z,1570113652.575 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:40:55.407Z,1570113655.407 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:40:58.227Z,1570113658.227 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:41:00.236Z,1570113660.236 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2019-10-03T14:41:00.236Z,1570113660.236 [DVL_micro] Data Fault, FailCount= 1
2019-10-03T14:41:00.236Z,1570113660.236 [DVL_micro](ERROR): Data Fault
2019-10-03T14:41:00.308Z,1570113660.308 [CBIT](ERROR): Data Fault in component: DVL_micro
2019-10-03T14:41:00.711Z,1570113660.711 [DVL_micro](INFO): Powering down
2019-10-03T14:41:01.463Z,1570113661.463 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:41:01.510Z,1570113661.510 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-10-03T14:41:01.510Z,1570113661.510 [DVL_micro] No Fault, FailCount= 1
2019-10-03T14:41:04.289Z,1570113664.289 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:41:07.521Z,1570113667.521 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:41:10.351Z,1570113670.351 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:41:13.583Z,1570113673.583 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:41:16.407Z,1570113676.407 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:41:19.224Z,1570113679.224 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-10-03T14:41:19.225Z,1570113679.225 [DVL_micro] Communications Fault, FailCount= 2
2019-10-03T14:41:19.225Z,1570113679.225 [DVL_micro](ERROR): Communications Fault
2019-10-03T14:41:19.239Z,1570113679.239 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:41:19.304Z,1570113679.304 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-10-03T14:41:19.703Z,1570113679.703 [DVL_micro](INFO): Powering down
2019-10-03T14:41:20.480Z,1570113680.480 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-10-03T14:41:20.480Z,1570113680.480 [DVL_micro] No Fault, FailCount= 2
2019-10-03T14:41:22.479Z,1570113682.479 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:41:25.299Z,1570113685.299 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:41:28.531Z,1570113688.531 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:41:31.359Z,1570113691.359 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:41:34.603Z,1570113694.603 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:41:37.435Z,1570113697.435 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:41:38.218Z,1570113698.218 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-10-03T14:41:38.219Z,1570113698.219 [DVL_micro] Communications Fault, FailCount= 3
2019-10-03T14:41:38.219Z,1570113698.219 [DVL_micro](ERROR): Communications Fault
2019-10-03T14:41:38.297Z,1570113698.297 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-10-03T14:41:38.297Z,1570113698.297 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2019-10-03T14:41:38.684Z,1570113698.684 [DVL_micro](INFO): Powering down
2019-10-03T14:41:38.720Z,1570113698.720 [CBIT](INFO): Critical error at 20191003T144138
2019-10-03T14:41:40.239Z,1570113700.239 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:41:43.483Z,1570113703.483 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:41:46.307Z,1570113706.307 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:41:48.748Z,1570113708.748 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-10-03T14:36:48.6Z
2019-10-03T14:41:48.748Z,1570113708.748 [Default:CheckIn:Read_GPS] Stopped
2019-10-03T14:41:48.748Z,1570113708.748 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-10-03T14:41:49.146Z,1570113709.146 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-10-03T14:41:49.555Z,1570113709.555 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:41:52.359Z,1570113712.359 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:41:55.599Z,1570113715.599 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:41:56.294Z,1570113716.294 [DataOverHttps](INFO): Sending 87 bytes from file Logs/20191003T143258/Courier0004.lzma
2019-10-03T14:41:58.300Z,1570113718.300 [DataOverHttps](INFO): Moved sent file to Logs/20191003T143258/Courier0004.lzma.bak
2019-10-03T14:41:58.301Z,1570113718.301 [DataOverHttps](INFO): SBD MOMSN=11844745
2019-10-03T14:41:58.419Z,1570113718.419 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:42:01.251Z,1570113721.251 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:42:04.491Z,1570113724.491 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:42:07.307Z,1570113727.307 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:42:10.539Z,1570113730.539 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:42:11.762Z,1570113731.762 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-10-03T14:42:11.762Z,1570113731.762 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-03T14:42:11.784Z,1570113731.784 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-03T14:42:12.197Z,1570113732.197 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-03T14:42:12.197Z,1570113732.197 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-10-03T14:42:13.367Z,1570113733.367 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:42:16.599Z,1570113736.599 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:42:18.195Z,1570113738.195 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20191003T143258/Express0005.lzma
2019-10-03T14:42:19.439Z,1570113739.439 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:42:20.200Z,1570113740.200 [DataOverHttps](INFO): Moved sent file to Logs/20191003T143258/Express0005.lzma.bak
2019-10-03T14:42:20.201Z,1570113740.201 [DataOverHttps](INFO): SBD MOMSN=11844749
2019-10-03T14:42:22.259Z,1570113742.259 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:42:23.909Z,1570113743.909 [Default:CheckIn:Read_Iridium] Stopped
2019-10-03T14:42:23.909Z,1570113743.909 [Default:CheckIn:C.Wait] Running Loop=1
2019-10-03T14:42:23.910Z,1570113743.910 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-10-03T14:42:25.491Z,1570113745.491 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:42:28.319Z,1570113748.319 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:42:31.551Z,1570113751.551 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:42:34.383Z,1570113754.383 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:42:37.209Z,1570113757.209 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:42:40.443Z,1570113760.443 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:42:43.267Z,1570113763.267 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:42:46.507Z,1570113766.507 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:42:49.323Z,1570113769.323 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:42:52.563Z,1570113772.563 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:42:55.387Z,1570113775.387 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:42:58.211Z,1570113778.211 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:43:01.447Z,1570113781.447 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:43:04.275Z,1570113784.275 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:43:07.507Z,1570113787.507 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:43:10.337Z,1570113790.337 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:43:13.569Z,1570113793.569 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:43:16.399Z,1570113796.399 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:43:19.223Z,1570113799.223 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:43:22.451Z,1570113802.451 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:43:25.287Z,1570113805.287 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:43:28.515Z,1570113808.515 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:43:31.351Z,1570113811.351 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:43:34.579Z,1570113814.579 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:43:37.403Z,1570113817.403 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:43:40.227Z,1570113820.227 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:43:42.659Z,1570113822.659 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:43:45.483Z,1570113825.483 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:43:48.701Z,1570113828.701 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-10-03T14:43:48.701Z,1570113828.701 [NAL9602] Data Fault, FailCount= 1
2019-10-03T14:43:48.701Z,1570113828.701 [NAL9602](ERROR): Data Fault
2019-10-03T14:43:48.784Z,1570113828.784 [CBIT](ERROR): Data Fault in component: NAL9602
2019-10-03T14:43:49.104Z,1570113829.104 [NAL9602](INFO): Powering down
2019-10-03T14:43:49.989Z,1570113829.989 [CBIT](INFO): Clearing failed state for component NAL9602
2019-10-03T14:43:49.989Z,1570113829.989 [NAL9602] No Fault, FailCount= 1
2019-10-03T14:44:19.410Z,1570113859.410 [NAL9602](INFO): Powering up NAL9602
2019-10-03T14:44:30.318Z,1570113870.318 [NAL9602](INFO): NAL9602 initialized
2019-10-03T14:44:31.139Z,1570113871.139 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:44:33.555Z,1570113873.555 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:44:36.787Z,1570113876.787 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:44:38.453Z,1570113878.453 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-10-03T14:44:38.453Z,1570113878.453 [DVL_micro] No Fault, FailCount= 3
2019-10-03T14:44:39.623Z,1570113879.623 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:44:42.855Z,1570113882.855 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:44:45.686Z,1570113885.686 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:44:46.476Z,1570113886.476 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2019-10-03T14:44:46.476Z,1570113886.476 [DVL_micro] Data Fault, FailCount= 1
2019-10-03T14:44:46.476Z,1570113886.476 [DVL_micro](ERROR): Data Fault
2019-10-03T14:44:46.562Z,1570113886.562 [CBIT](ERROR): Data Fault in component: DVL_micro
2019-10-03T14:44:46.959Z,1570113886.959 [DVL_micro](INFO): Powering down
2019-10-03T14:44:47.744Z,1570113887.744 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-10-03T14:44:47.744Z,1570113887.744 [DVL_micro] No Fault, FailCount= 1
2019-10-03T14:44:48.515Z,1570113888.515 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:44:50.531Z,1570113890.531 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:44:52.547Z,1570113892.547 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:44:55.783Z,1570113895.783 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:44:58.622Z,1570113898.622 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:45:01.839Z,1570113901.839 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:45:04.671Z,1570113904.671 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:45:05.460Z,1570113905.460 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-10-03T14:45:05.460Z,1570113905.460 [DVL_micro] Communications Fault, FailCount= 2
2019-10-03T14:45:05.461Z,1570113905.461 [DVL_micro](ERROR): Communications Fault
2019-10-03T14:45:05.561Z,1570113905.561 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-10-03T14:45:05.931Z,1570113905.931 [DVL_micro](INFO): Powering down
2019-10-03T14:45:06.714Z,1570113906.714 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-10-03T14:45:06.714Z,1570113906.714 [DVL_micro] No Fault, FailCount= 2
2019-10-03T14:45:07.499Z,1570113907.499 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:45:09.515Z,1570113909.515 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:45:11.543Z,1570113911.543 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:45:12.759Z,1570113912.759 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2019-10-03T14:45:12.759Z,1570113912.759 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-03T14:45:12.793Z,1570113912.793 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-03T14:45:13.271Z,1570113913.271 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-03T14:45:13.271Z,1570113913.271 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2019-10-03T14:45:13.563Z,1570113913.563 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:45:16.795Z,1570113916.795 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:45:19.623Z,1570113919.623 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:45:22.851Z,1570113922.851 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:45:24.452Z,1570113924.452 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-10-03T14:45:24.452Z,1570113924.452 [DVL_micro] Communications Fault, FailCount= 3
2019-10-03T14:45:24.453Z,1570113924.453 [DVL_micro](ERROR): Communications Fault
2019-10-03T14:45:24.521Z,1570113924.521 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-10-03T14:45:24.522Z,1570113924.522 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2019-10-03T14:45:24.923Z,1570113924.923 [DVL_micro](INFO): Powering down
2019-10-03T14:45:24.976Z,1570113924.976 [CBIT](INFO): Critical error at 20191003T144524
2019-10-03T14:45:25.679Z,1570113925.679 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:45:28.519Z,1570113928.519 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:45:30.523Z,1570113930.523 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:45:32.543Z,1570113932.543 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:45:35.771Z,1570113935.771 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:45:38.606Z,1570113938.606 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:45:41.837Z,1570113941.837 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:45:44.667Z,1570113944.667 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:45:47.895Z,1570113947.895 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:45:50.719Z,1570113950.719 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:45:53.551Z,1570113953.551 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:45:56.783Z,1570113956.783 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:45:59.618Z,1570113959.618 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:46:02.846Z,1570113962.846 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:46:05.675Z,1570113965.675 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:46:08.499Z,1570113968.499 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:46:10.523Z,1570113970.523 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:46:12.551Z,1570113972.551 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:46:15.771Z,1570113975.771 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:46:18.599Z,1570113978.599 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:46:21.844Z,1570113981.844 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:46:24.673Z,1570113984.673 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:46:27.495Z,1570113987.495 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:46:29.519Z,1570113989.519 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:46:31.535Z,1570113991.535 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:46:33.551Z,1570113993.551 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:46:36.779Z,1570113996.779 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:46:38.007Z,1570113998.007 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2019-10-03T14:46:39.614Z,1570113999.614 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:46:42.843Z,1570114002.843 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:46:44.866Z,1570114004.866 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2019-10-03T14:46:45.678Z,1570114005.678 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:46:48.918Z,1570114008.918 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:46:51.734Z,1570114011.734 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:46:54.563Z,1570114014.563 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:46:57.799Z,1570114017.799 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:46:59.824Z,1570114019.824 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2019-10-03T14:47:00.615Z,1570114020.615 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:47:03.851Z,1570114023.851 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:47:06.679Z,1570114026.679 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:47:09.510Z,1570114029.510 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:47:11.527Z,1570114031.527 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:47:13.547Z,1570114033.547 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:47:15.164Z,1570114035.164 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2019-10-03T14:47:16.779Z,1570114036.779 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:47:19.623Z,1570114039.623 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:47:22.847Z,1570114042.847 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:47:24.478Z,1570114044.478 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-10-03T14:47:24.478Z,1570114044.478 [Default:CheckIn:C.Wait] Stopped
2019-10-03T14:47:24.478Z,1570114044.478 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-10-03T14:47:24.478Z,1570114044.478 [Default:CheckIn:D] Running Loop=1
2019-10-03T14:47:24.873Z,1570114044.873 [Default:CheckIn:D] Stopped
2019-10-03T14:47:24.873Z,1570114044.873 [Default:CheckIn:E] Running Loop=1
2019-10-03T14:47:25.286Z,1570114045.286 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.833995 min 
2019-10-03T14:47:25.286Z,1570114045.286 [Default:CheckIn:E] Stopped
2019-10-03T14:47:25.286Z,1570114045.286 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-10-03T14:47:25.286Z,1570114045.286 [Default:CheckIn] Stopped
2019-10-03T14:47:25.286Z,1570114045.286 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-10-03T14:47:25.286Z,1570114045.286 [Default:CheckIn](INFO): Running loop #2
2019-10-03T14:47:25.286Z,1570114045.286 [Default:CheckIn] Running Loop=2
2019-10-03T14:47:25.286Z,1570114045.286 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-10-03T14:47:25.286Z,1570114045.286 [Default:CheckIn:Read_GPS] Running Loop=1
2019-10-03T14:47:25.671Z,1570114045.671 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:47:28.503Z,1570114048.503 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:47:29.777Z,1570114049.777 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2019-10-03T14:47:30.519Z,1570114050.519 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:47:32.535Z,1570114052.535 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:47:35.767Z,1570114055.767 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:47:38.598Z,1570114058.598 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:47:41.829Z,1570114061.829 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:47:44.659Z,1570114064.659 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:47:45.078Z,1570114065.078 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2019-10-03T14:47:47.891Z,1570114067.891 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:47:50.719Z,1570114070.719 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:47:53.547Z,1570114073.547 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:47:56.791Z,1570114076.791 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:47:59.619Z,1570114079.619 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:48:00.816Z,1570114080.816 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2019-10-03T14:48:02.843Z,1570114082.843 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:48:05.663Z,1570114085.663 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:48:08.903Z,1570114088.903 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:48:11.723Z,1570114091.723 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:48:13.755Z,1570114093.755 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2019-10-03T14:48:13.755Z,1570114093.755 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-03T14:48:13.795Z,1570114093.795 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-03T14:48:14.196Z,1570114094.196 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-03T14:48:14.197Z,1570114094.197 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2019-10-03T14:48:14.551Z,1570114094.551 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:48:16.177Z,1570114096.177 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2019-10-03T14:48:17.787Z,1570114097.787 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:48:20.623Z,1570114100.623 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:48:23.843Z,1570114103.843 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:48:24.709Z,1570114104.709 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-10-03T14:48:24.709Z,1570114104.709 [DVL_micro] No Fault, FailCount= 3
2019-10-03T14:48:26.679Z,1570114106.679 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:48:29.523Z,1570114109.523 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:48:31.133Z,1570114111.133 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2019-10-03T14:48:31.527Z,1570114111.527 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:48:32.724Z,1570114112.724 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2019-10-03T14:48:32.724Z,1570114112.724 [DVL_micro] Data Fault, FailCount= 1
2019-10-03T14:48:32.724Z,1570114112.724 [DVL_micro](ERROR): Data Fault
2019-10-03T14:48:32.825Z,1570114112.825 [CBIT](ERROR): Data Fault in component: DVL_micro
2019-10-03T14:48:33.203Z,1570114113.203 [DVL_micro](INFO): Powering down
2019-10-03T14:48:33.547Z,1570114113.547 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:48:34.006Z,1570114114.006 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-10-03T14:48:34.006Z,1570114114.006 [DVL_micro] No Fault, FailCount= 1
2019-10-03T14:48:36.783Z,1570114116.783 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:48:39.611Z,1570114119.611 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:48:42.843Z,1570114122.843 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:48:45.671Z,1570114125.671 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:48:46.885Z,1570114126.885 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2019-10-03T14:48:48.499Z,1570114128.499 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:48:50.519Z,1570114130.519 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:48:51.716Z,1570114131.716 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-10-03T14:48:51.717Z,1570114131.717 [DVL_micro] Communications Fault, FailCount= 2
2019-10-03T14:48:51.717Z,1570114131.717 [DVL_micro](ERROR): Communications Fault
2019-10-03T14:48:51.793Z,1570114131.793 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-10-03T14:48:52.195Z,1570114132.195 [DVL_micro](INFO): Powering down
2019-10-03T14:48:52.539Z,1570114132.539 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:48:52.986Z,1570114132.986 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-10-03T14:48:52.986Z,1570114132.986 [DVL_micro] No Fault, FailCount= 2
2019-10-03T14:48:54.559Z,1570114134.559 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:48:57.787Z,1570114137.787 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:49:00.617Z,1570114140.617 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:49:02.240Z,1570114142.240 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2019-10-03T14:49:03.850Z,1570114143.850 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:49:06.679Z,1570114146.679 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:49:09.507Z,1570114149.507 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:49:10.704Z,1570114150.704 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-10-03T14:49:10.705Z,1570114150.705 [DVL_micro] Communications Fault, FailCount= 3
2019-10-03T14:49:10.705Z,1570114150.705 [DVL_micro](ERROR): Communications Fault
2019-10-03T14:49:10.774Z,1570114150.774 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-10-03T14:49:10.774Z,1570114150.774 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2019-10-03T14:49:11.191Z,1570114151.191 [DVL_micro](INFO): Powering down
2019-10-03T14:49:11.262Z,1570114151.262 [CBIT](INFO): Critical error at 20191003T144910
2019-10-03T14:49:11.527Z,1570114151.527 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:49:13.555Z,1570114153.555 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:49:16.795Z,1570114156.795 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:49:19.603Z,1570114159.603 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:49:22.835Z,1570114162.835 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:49:25.663Z,1570114165.663 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:49:28.899Z,1570114168.899 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:49:31.726Z,1570114171.726 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:49:34.555Z,1570114174.555 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:49:37.791Z,1570114177.791 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:49:40.611Z,1570114180.611 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:49:43.843Z,1570114183.843 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:49:46.671Z,1570114186.671 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:49:49.503Z,1570114189.503 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:49:51.521Z,1570114191.521 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:49:53.539Z,1570114193.539 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:49:56.771Z,1570114196.771 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:49:59.602Z,1570114199.602 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:50:02.834Z,1570114202.834 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:50:05.663Z,1570114205.663 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:50:08.899Z,1570114208.899 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:50:11.719Z,1570114211.719 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:50:14.551Z,1570114214.551 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:50:17.779Z,1570114217.779 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:50:20.607Z,1570114220.607 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:50:23.839Z,1570114223.839 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:50:26.679Z,1570114226.679 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:50:29.915Z,1570114229.915 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:50:32.735Z,1570114232.735 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:50:35.571Z,1570114235.571 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:50:38.801Z,1570114238.801 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:50:41.631Z,1570114241.631 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:50:44.863Z,1570114244.863 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:50:47.687Z,1570114247.687 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:50:50.515Z,1570114250.515 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:50:52.531Z,1570114252.531 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:50:55.763Z,1570114255.763 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:50:58.595Z,1570114258.595 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:51:01.827Z,1570114261.827 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:51:04.655Z,1570114264.655 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:51:07.883Z,1570114267.883 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:51:10.731Z,1570114270.731 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:51:13.543Z,1570114273.543 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:51:14.774Z,1570114274.774 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2019-10-03T14:51:14.774Z,1570114274.774 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-03T14:51:14.784Z,1570114274.784 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-03T14:51:15.188Z,1570114275.188 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-03T14:51:15.188Z,1570114275.188 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2019-10-03T14:51:16.779Z,1570114276.779 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:51:19.611Z,1570114279.611 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:51:22.839Z,1570114282.839 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:51:25.663Z,1570114285.663 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:51:28.487Z,1570114288.487 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:51:30.507Z,1570114290.507 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:51:32.531Z,1570114292.531 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:51:34.551Z,1570114294.551 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:51:37.786Z,1570114297.786 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:51:40.615Z,1570114300.615 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:51:43.847Z,1570114303.847 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:51:46.671Z,1570114306.671 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:51:49.499Z,1570114309.499 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:51:51.519Z,1570114311.519 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:51:53.543Z,1570114313.543 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:51:56.779Z,1570114316.779 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:51:59.603Z,1570114319.603 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:52:02.831Z,1570114322.831 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:52:05.659Z,1570114325.659 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:52:08.503Z,1570114328.503 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:52:10.515Z,1570114330.515 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:52:10.974Z,1570114330.974 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-10-03T14:52:10.974Z,1570114330.974 [DVL_micro] No Fault, FailCount= 3
2019-10-03T14:52:12.531Z,1570114332.531 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:52:14.551Z,1570114334.551 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:52:17.787Z,1570114337.787 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:52:18.980Z,1570114338.980 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2019-10-03T14:52:18.980Z,1570114338.980 [DVL_micro] Data Fault, FailCount= 1
2019-10-03T14:52:18.980Z,1570114338.980 [DVL_micro](ERROR): Data Fault
2019-10-03T14:52:19.040Z,1570114339.040 [CBIT](ERROR): Data Fault in component: DVL_micro
2019-10-03T14:52:19.459Z,1570114339.459 [DVL_micro](INFO): Powering down
2019-10-03T14:52:20.234Z,1570114340.234 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-10-03T14:52:20.235Z,1570114340.235 [DVL_micro] No Fault, FailCount= 1
2019-10-03T14:52:20.607Z,1570114340.607 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:52:23.843Z,1570114343.843 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:52:25.485Z,1570114345.485 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-10-03T14:47:25.3Z
2019-10-03T14:52:25.485Z,1570114345.485 [Default:CheckIn:Read_GPS] Stopped
2019-10-03T14:52:25.485Z,1570114345.485 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-10-03T14:52:26.751Z,1570114346.751 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:52:29.563Z,1570114349.563 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:52:32.787Z,1570114352.787 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:52:35.094Z,1570114355.094 [DataOverHttps](INFO): Sending 87 bytes from file Logs/20191003T143258/Courier0007.lzma
2019-10-03T14:52:35.627Z,1570114355.627 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:52:37.101Z,1570114357.101 [DataOverHttps](INFO): Moved sent file to Logs/20191003T143258/Courier0007.lzma.bak
2019-10-03T14:52:37.101Z,1570114357.101 [DataOverHttps](INFO): SBD MOMSN=11844771
2019-10-03T14:52:38.037Z,1570114358.037 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-10-03T14:52:38.037Z,1570114358.037 [DVL_micro] Communications Fault, FailCount= 2
2019-10-03T14:52:38.037Z,1570114358.037 [DVL_micro](ERROR): Communications Fault
2019-10-03T14:52:38.131Z,1570114358.131 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-10-03T14:52:38.503Z,1570114358.503 [DVL_micro](INFO): Powering down
2019-10-03T14:52:38.855Z,1570114358.855 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:52:39.332Z,1570114359.332 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-10-03T14:52:39.332Z,1570114359.332 [DVL_micro] No Fault, FailCount= 2
2019-10-03T14:52:41.679Z,1570114361.679 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:52:44.507Z,1570114364.507 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:52:46.527Z,1570114366.527 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:52:48.547Z,1570114368.547 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:52:51.779Z,1570114371.779 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:52:54.607Z,1570114374.607 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:52:56.259Z,1570114376.259 [DataOverHttps](INFO): Sending 205 bytes from file Logs/20191003T143258/Express0008.lzma
2019-10-03T14:52:57.020Z,1570114377.020 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-10-03T14:52:57.020Z,1570114377.020 [DVL_micro] Communications Fault, FailCount= 3
2019-10-03T14:52:57.020Z,1570114377.020 [DVL_micro](ERROR): Communications Fault
2019-10-03T14:52:57.090Z,1570114377.090 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-10-03T14:52:57.091Z,1570114377.091 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2019-10-03T14:52:57.503Z,1570114377.503 [DVL_micro](INFO): Powering down
2019-10-03T14:52:57.552Z,1570114377.552 [CBIT](INFO): Critical error at 20191003T145257
2019-10-03T14:52:57.839Z,1570114377.839 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:52:58.268Z,1570114378.268 [DataOverHttps](INFO): Moved sent file to Logs/20191003T143258/Express0008.lzma.bak
2019-10-03T14:52:58.269Z,1570114378.269 [DataOverHttps](INFO): SBD MOMSN=11844775
2019-10-03T14:53:00.669Z,1570114380.669 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:53:01.900Z,1570114381.900 [Default:CheckIn:Read_Iridium] Stopped
2019-10-03T14:53:01.900Z,1570114381.900 [Default:CheckIn:C.Wait] Running Loop=1
2019-10-03T14:53:01.900Z,1570114381.901 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-10-03T14:53:03.511Z,1570114383.511 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:53:05.517Z,1570114385.517 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:53:07.535Z,1570114387.535 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:53:10.770Z,1570114390.770 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:53:13.603Z,1570114393.603 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:53:16.831Z,1570114396.831 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:53:19.655Z,1570114399.655 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:53:22.483Z,1570114402.483 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:53:24.507Z,1570114404.507 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:53:26.527Z,1570114406.527 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:53:28.543Z,1570114408.543 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:53:31.779Z,1570114411.779 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:53:34.611Z,1570114414.611 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:53:37.835Z,1570114417.835 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:53:40.663Z,1570114420.663 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:53:43.494Z,1570114423.494 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:53:45.507Z,1570114425.507 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:53:47.531Z,1570114427.531 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:53:50.763Z,1570114430.763 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:53:53.591Z,1570114433.591 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:53:56.831Z,1570114436.831 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:53:59.659Z,1570114439.659 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:54:02.887Z,1570114442.887 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:54:05.711Z,1570114445.711 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:54:08.543Z,1570114448.543 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:54:11.774Z,1570114451.774 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:54:14.603Z,1570114454.603 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:54:15.828Z,1570114455.828 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2019-10-03T14:54:15.828Z,1570114455.828 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-03T14:54:15.863Z,1570114455.863 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-03T14:54:16.236Z,1570114456.236 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-03T14:54:16.237Z,1570114456.237 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2019-10-03T14:54:17.839Z,1570114457.839 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:54:20.659Z,1570114460.659 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:54:23.491Z,1570114463.491 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:54:25.519Z,1570114465.519 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:54:28.795Z,1570114468.795 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:54:31.609Z,1570114471.609 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-10-03T14:54:31.609Z,1570114471.609 [NAL9602] Data Fault, FailCount= 2
2019-10-03T14:54:31.609Z,1570114471.609 [NAL9602](ERROR): Data Fault
2019-10-03T14:54:31.718Z,1570114471.718 [CBIT](ERROR): Data Fault in component: NAL9602
2019-10-03T14:54:32.008Z,1570114472.008 [NAL9602](INFO): Powering down
2019-10-03T14:54:32.928Z,1570114472.928 [CBIT](INFO): Clearing failed state for component NAL9602
2019-10-03T14:54:32.928Z,1570114472.928 [NAL9602] No Fault, FailCount= 2
2019-10-03T14:55:02.308Z,1570114502.308 [NAL9602](INFO): Powering up NAL9602
2019-10-03T14:55:13.222Z,1570114513.222 [NAL9602](INFO): NAL9602 initialized
2019-10-03T14:55:14.031Z,1570114514.031 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:55:16.463Z,1570114516.463 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:55:19.691Z,1570114519.691 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:55:22.519Z,1570114522.519 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:55:25.751Z,1570114525.751 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:55:28.583Z,1570114528.583 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:55:31.414Z,1570114531.414 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:55:33.427Z,1570114533.427 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:55:35.443Z,1570114535.443 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:55:38.679Z,1570114538.679 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:55:41.503Z,1570114541.503 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:55:44.743Z,1570114544.743 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:55:47.575Z,1570114547.575 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:55:50.395Z,1570114550.395 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:55:52.415Z,1570114552.415 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:55:54.438Z,1570114554.438 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:55:56.455Z,1570114556.455 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:55:57.314Z,1570114557.314 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-10-03T14:55:57.314Z,1570114557.314 [DVL_micro] No Fault, FailCount= 3
2019-10-03T14:55:59.691Z,1570114559.691 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:56:02.519Z,1570114562.519 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:56:05.336Z,1570114565.336 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2019-10-03T14:56:05.336Z,1570114565.336 [DVL_micro] Data Fault, FailCount= 1
2019-10-03T14:56:05.336Z,1570114565.336 [DVL_micro](ERROR): Data Fault
2019-10-03T14:56:05.368Z,1570114565.368 [CBIT](ERROR): Data Fault in component: DVL_micro
2019-10-03T14:56:05.811Z,1570114565.811 [DVL_micro](INFO): Powering down
2019-10-03T14:56:05.823Z,1570114565.823 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:56:06.602Z,1570114566.602 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-10-03T14:56:06.602Z,1570114566.602 [DVL_micro] No Fault, FailCount= 1
2019-10-03T14:56:08.575Z,1570114568.575 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:56:11.418Z,1570114571.418 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:56:13.423Z,1570114573.423 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:56:14.249Z,1570114574.249 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2019-10-03T14:56:15.451Z,1570114575.451 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:56:18.679Z,1570114578.679 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:56:21.105Z,1570114581.105 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2019-10-03T14:56:21.503Z,1570114581.503 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:56:24.320Z,1570114584.320 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-10-03T14:56:24.320Z,1570114584.320 [DVL_micro] Communications Fault, FailCount= 2
2019-10-03T14:56:24.320Z,1570114584.320 [DVL_micro](ERROR): Communications Fault
2019-10-03T14:56:24.387Z,1570114584.387 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-10-03T14:56:24.791Z,1570114584.791 [DVL_micro](INFO): Powering down
2019-10-03T14:56:24.803Z,1570114584.803 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:56:25.622Z,1570114585.622 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-10-03T14:56:25.622Z,1570114585.622 [DVL_micro] No Fault, FailCount= 2
2019-10-03T14:56:27.583Z,1570114587.583 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:56:30.795Z,1570114590.795 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:56:33.623Z,1570114593.623 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:56:36.454Z,1570114596.454 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:56:36.885Z,1570114596.885 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2019-10-03T14:56:39.687Z,1570114599.687 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:56:42.515Z,1570114602.515 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:56:43.310Z,1570114603.310 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-10-03T14:56:43.310Z,1570114603.310 [DVL_micro] Communications Fault, FailCount= 3
2019-10-03T14:56:43.311Z,1570114603.311 [DVL_micro](ERROR): Communications Fault
2019-10-03T14:56:43.372Z,1570114603.372 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-10-03T14:56:43.372Z,1570114603.372 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2019-10-03T14:56:43.783Z,1570114603.783 [DVL_micro](INFO): Powering down
2019-10-03T14:56:43.819Z,1570114603.819 [CBIT](INFO): Critical error at 20191003T145643
2019-10-03T14:56:45.739Z,1570114605.739 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:56:48.575Z,1570114608.575 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:56:51.407Z,1570114611.407 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:56:52.225Z,1570114612.225 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2019-10-03T14:56:53.422Z,1570114613.422 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:56:55.435Z,1570114615.435 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:56:58.675Z,1570114618.675 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:57:01.501Z,1570114621.501 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:57:04.743Z,1570114624.743 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:57:07.559Z,1570114627.559 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:57:07.592Z,1570114627.592 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2019-10-03T14:57:10.383Z,1570114630.383 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:57:12.407Z,1570114632.407 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:57:14.435Z,1570114634.435 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:57:16.451Z,1570114636.451 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:57:16.888Z,1570114636.888 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2019-10-03T14:57:16.888Z,1570114636.888 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-03T14:57:16.898Z,1570114636.898 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-03T14:57:17.297Z,1570114637.297 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-03T14:57:17.297Z,1570114637.297 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2019-10-03T14:57:19.681Z,1570114639.681 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:57:22.550Z,1570114642.550 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:57:22.970Z,1570114642.970 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2019-10-03T14:57:25.759Z,1570114645.759 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:57:28.594Z,1570114648.594 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:57:31.423Z,1570114651.423 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:57:33.439Z,1570114653.439 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:57:35.475Z,1570114655.475 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:57:37.901Z,1570114657.901 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2019-10-03T14:57:38.695Z,1570114658.695 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:57:41.519Z,1570114661.519 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:57:44.755Z,1570114664.755 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:57:47.579Z,1570114667.579 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:57:50.407Z,1570114670.407 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:57:52.427Z,1570114672.427 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:57:52.850Z,1570114672.850 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2019-10-03T14:57:54.450Z,1570114674.450 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:57:57.683Z,1570114677.683 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:58:00.519Z,1570114680.519 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:58:02.545Z,1570114682.545 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-10-03T14:58:02.545Z,1570114682.545 [Default:CheckIn:C.Wait] Stopped
2019-10-03T14:58:02.545Z,1570114682.545 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-10-03T14:58:02.545Z,1570114682.545 [Default:CheckIn:D] Running Loop=1
2019-10-03T14:58:02.959Z,1570114682.959 [Default:CheckIn:D] Stopped
2019-10-03T14:58:02.959Z,1570114682.959 [Default:CheckIn:E] Running Loop=1
2019-10-03T14:58:03.346Z,1570114683.346 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.468752 min 
2019-10-03T14:58:03.346Z,1570114683.346 [Default:CheckIn:E] Stopped
2019-10-03T14:58:03.347Z,1570114683.347 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-10-03T14:58:03.347Z,1570114683.347 [Default:CheckIn] Stopped
2019-10-03T14:58:03.347Z,1570114683.347 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-10-03T14:58:03.347Z,1570114683.347 [Default:CheckIn](INFO): Running loop #3
2019-10-03T14:58:03.347Z,1570114683.347 [Default:CheckIn] Running Loop=3
2019-10-03T14:58:03.347Z,1570114683.347 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-10-03T14:58:03.347Z,1570114683.347 [Default:CheckIn:Read_GPS] Running Loop=1
2019-10-03T14:58:03.739Z,1570114683.739 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:58:06.567Z,1570114686.567 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:58:08.188Z,1570114688.188 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2019-10-03T14:58:09.391Z,1570114689.391 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:58:11.419Z,1570114691.419 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:58:13.435Z,1570114693.435 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:58:16.671Z,1570114696.671 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:58:19.499Z,1570114699.499 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:58:22.727Z,1570114702.727 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:58:23.555Z,1570114703.555 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2019-10-03T14:58:25.551Z,1570114705.551 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:58:28.416Z,1570114708.416 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:58:30.431Z,1570114710.431 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:58:32.447Z,1570114712.447 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:58:35.671Z,1570114715.671 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:58:38.499Z,1570114718.499 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:58:41.731Z,1570114721.731 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:58:44.561Z,1570114724.561 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:58:47.395Z,1570114727.395 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:58:49.407Z,1570114729.407 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:58:51.431Z,1570114731.431 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:58:54.659Z,1570114734.659 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:58:57.490Z,1570114737.490 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:59:00.722Z,1570114740.722 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:59:03.555Z,1570114743.555 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:59:06.775Z,1570114746.775 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:59:09.607Z,1570114749.607 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:59:12.431Z,1570114752.431 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:59:15.671Z,1570114755.671 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:59:18.491Z,1570114758.491 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:59:21.727Z,1570114761.727 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:59:24.559Z,1570114764.559 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:59:27.791Z,1570114767.791 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:59:30.619Z,1570114770.619 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:59:33.446Z,1570114773.446 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:59:36.677Z,1570114776.677 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:59:39.511Z,1570114779.511 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:59:42.735Z,1570114782.735 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:59:43.586Z,1570114783.586 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-10-03T14:59:43.586Z,1570114783.586 [DVL_micro] No Fault, FailCount= 3
2019-10-03T14:59:45.563Z,1570114785.563 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:59:48.795Z,1570114788.795 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:59:51.609Z,1570114791.609 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2019-10-03T14:59:51.609Z,1570114791.609 [DVL_micro] Data Fault, FailCount= 1
2019-10-03T14:59:51.609Z,1570114791.609 [DVL_micro](ERROR): Data Fault
2019-10-03T14:59:51.623Z,1570114791.623 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:59:51.678Z,1570114791.678 [CBIT](ERROR): Data Fault in component: DVL_micro
2019-10-03T14:59:52.079Z,1570114792.079 [DVL_micro](INFO): Powering down
2019-10-03T14:59:52.859Z,1570114792.859 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-10-03T14:59:52.859Z,1570114792.859 [DVL_micro] No Fault, FailCount= 1
2019-10-03T14:59:54.459Z,1570114794.459 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:59:57.687Z,1570114797.687 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:00:00.515Z,1570114800.515 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:00:03.747Z,1570114803.747 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:00:06.575Z,1570114806.575 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:00:09.403Z,1570114809.403 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:00:10.600Z,1570114810.600 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-10-03T15:00:10.601Z,1570114810.601 [DVL_micro] Communications Fault, FailCount= 2
2019-10-03T15:00:10.601Z,1570114810.601 [DVL_micro](ERROR): Communications Fault
2019-10-03T15:00:10.666Z,1570114810.666 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-10-03T15:00:11.075Z,1570114811.075 [DVL_micro](INFO): Powering down
2019-10-03T15:00:11.423Z,1570114811.423 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:00:11.866Z,1570114811.866 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-10-03T15:00:11.866Z,1570114811.866 [DVL_micro] No Fault, FailCount= 2
2019-10-03T15:00:13.443Z,1570114813.443 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:00:16.671Z,1570114816.671 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:00:17.913Z,1570114817.913 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2019-10-03T15:00:17.913Z,1570114817.913 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-03T15:00:17.939Z,1570114817.939 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-03T15:00:18.325Z,1570114818.325 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-03T15:00:18.325Z,1570114818.325 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2019-10-03T15:00:19.499Z,1570114819.499 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:00:22.735Z,1570114822.735 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:00:25.563Z,1570114825.563 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:00:28.797Z,1570114828.797 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:00:29.234Z,1570114829.234 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2019-10-03T15:00:29.593Z,1570114829.593 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-10-03T15:00:29.593Z,1570114829.593 [DVL_micro] Communications Fault, FailCount= 3
2019-10-03T15:00:29.593Z,1570114829.593 [DVL_micro](ERROR): Communications Fault
2019-10-03T15:00:29.802Z,1570114829.802 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-10-03T15:00:29.802Z,1570114829.802 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2019-10-03T15:00:30.071Z,1570114830.071 [DVL_micro](INFO): Powering down
2019-10-03T15:00:30.144Z,1570114830.144 [CBIT](INFO): Critical error at 20191003T150029
2019-10-03T15:00:31.627Z,1570114831.627 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:00:34.455Z,1570114834.455 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:00:37.683Z,1570114837.683 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:00:40.511Z,1570114840.511 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:00:43.743Z,1570114843.743 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:00:46.579Z,1570114846.579 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:00:49.395Z,1570114849.395 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:00:51.427Z,1570114851.427 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:00:53.442Z,1570114853.442 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:00:56.667Z,1570114856.667 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:00:59.499Z,1570114859.499 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:01:02.727Z,1570114862.727 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:01:05.562Z,1570114865.562 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:01:08.793Z,1570114868.793 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:01:11.627Z,1570114871.627 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:01:14.447Z,1570114874.447 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:01:17.675Z,1570114877.675 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:01:20.511Z,1570114880.511 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:01:23.759Z,1570114883.759 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:01:26.575Z,1570114886.575 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:01:29.807Z,1570114889.807 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:01:32.627Z,1570114892.627 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:01:35.455Z,1570114895.455 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:01:38.691Z,1570114898.691 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:01:41.517Z,1570114901.517 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:01:44.750Z,1570114904.750 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:01:47.579Z,1570114907.579 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:01:50.407Z,1570114910.407 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:01:52.427Z,1570114912.427 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:01:54.447Z,1570114914.447 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:01:57.679Z,1570114917.679 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:02:00.507Z,1570114920.507 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:02:03.731Z,1570114923.731 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:02:06.563Z,1570114926.563 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:02:09.791Z,1570114929.791 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:02:12.627Z,1570114932.627 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:02:15.455Z,1570114935.455 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:02:18.683Z,1570114938.683 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:02:21.507Z,1570114941.507 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:02:24.751Z,1570114944.751 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:02:27.569Z,1570114947.569 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:02:30.411Z,1570114950.411 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:02:32.419Z,1570114952.419 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:02:34.439Z,1570114954.439 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:02:37.675Z,1570114957.675 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:02:40.499Z,1570114960.499 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:02:43.727Z,1570114963.727 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:02:46.567Z,1570114966.567 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:02:49.387Z,1570114969.387 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:02:51.407Z,1570114971.407 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:02:53.435Z,1570114973.435 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:02:55.447Z,1570114975.447 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:02:58.682Z,1570114978.682 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:03:01.515Z,1570114981.515 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:03:03.548Z,1570114983.548 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-10-03T14:58:03.3Z
2019-10-03T15:03:03.548Z,1570114983.548 [Default:CheckIn:Read_GPS] Stopped
2019-10-03T15:03:03.548Z,1570114983.548 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-10-03T15:03:04.743Z,1570114984.743 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:03:07.567Z,1570114987.567 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:03:10.391Z,1570114990.391 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:03:12.334Z,1570114992.334 [DataOverHttps](INFO): Sending 92 bytes from file Logs/20191003T143258/Courier0010.lzma
2019-10-03T15:03:12.415Z,1570114992.415 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:03:14.340Z,1570114994.340 [DataOverHttps](INFO): Moved sent file to Logs/20191003T143258/Courier0010.lzma.bak
2019-10-03T15:03:14.341Z,1570114994.341 [DataOverHttps](INFO): SBD MOMSN=11844803
2019-10-03T15:03:14.443Z,1570114994.443 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:03:17.671Z,1570114997.671 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:03:18.894Z,1570114998.894 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2019-10-03T15:03:18.894Z,1570114998.894 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-03T15:03:18.904Z,1570114998.904 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-03T15:03:19.312Z,1570114999.312 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-03T15:03:19.312Z,1570114999.312 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2019-10-03T15:03:20.507Z,1570115000.507 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:03:23.731Z,1570115003.731 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:03:26.559Z,1570115006.559 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:03:29.791Z,1570115009.791 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:03:29.860Z,1570115009.860 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-10-03T15:03:29.860Z,1570115009.860 [DVL_micro] No Fault, FailCount= 3
2019-10-03T15:03:32.626Z,1570115012.626 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:03:34.767Z,1570115014.767 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20191003T143258/Express0011.lzma
2019-10-03T15:03:35.447Z,1570115015.447 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:03:36.772Z,1570115016.772 [DataOverHttps](INFO): Moved sent file to Logs/20191003T143258/Express0011.lzma.bak
2019-10-03T15:03:36.773Z,1570115016.773 [DataOverHttps](INFO): SBD MOMSN=11844808
2019-10-03T15:03:37.858Z,1570115017.858 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2019-10-03T15:03:37.858Z,1570115017.858 [DVL_micro] Data Fault, FailCount= 1
2019-10-03T15:03:37.858Z,1570115017.858 [DVL_micro](ERROR): Data Fault
2019-10-03T15:03:37.942Z,1570115017.942 [CBIT](ERROR): Data Fault in component: DVL_micro
2019-10-03T15:03:38.327Z,1570115018.327 [DVL_micro](INFO): Powering down
2019-10-03T15:03:38.679Z,1570115018.679 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:03:39.145Z,1570115019.145 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-10-03T15:03:39.145Z,1570115019.145 [DVL_micro] No Fault, FailCount= 1
2019-10-03T15:03:40.320Z,1570115020.320 [Default:CheckIn:Read_Iridium] Stopped
2019-10-03T15:03:40.320Z,1570115020.320 [Default:CheckIn:C.Wait] Running Loop=1
2019-10-03T15:03:40.321Z,1570115020.321 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-10-03T15:03:41.505Z,1570115021.505 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:03:44.747Z,1570115024.747 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:03:47.567Z,1570115027.567 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:03:50.407Z,1570115030.407 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:03:52.415Z,1570115032.415 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:03:54.447Z,1570115034.447 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:03:56.844Z,1570115036.844 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-10-03T15:03:56.844Z,1570115036.844 [DVL_micro] Communications Fault, FailCount= 2
2019-10-03T15:03:56.845Z,1570115036.845 [DVL_micro](ERROR): Communications Fault
2019-10-03T15:03:56.905Z,1570115036.905 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-10-03T15:03:57.319Z,1570115037.319 [DVL_micro](INFO): Powering down
2019-10-03T15:03:57.671Z,1570115037.671 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:03:58.106Z,1570115038.106 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-10-03T15:03:58.106Z,1570115038.106 [DVL_micro] No Fault, FailCount= 2
2019-10-03T15:04:00.495Z,1570115040.495 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:04:03.727Z,1570115043.727 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:04:06.555Z,1570115046.555 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:04:09.783Z,1570115049.783 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:04:10.615Z,1570115050.615 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 5.
2019-10-03T15:04:10.618Z,1570115050.618 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2019-10-03T15:04:12.622Z,1570115052.622 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:04:15.447Z,1570115055.447 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:04:15.846Z,1570115055.846 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-10-03T15:04:15.846Z,1570115055.846 [DVL_micro] Communications Fault, FailCount= 3
2019-10-03T15:04:15.846Z,1570115055.846 [DVL_micro](ERROR): Communications Fault
2019-10-03T15:04:15.890Z,1570115055.890 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-10-03T15:04:15.890Z,1570115055.890 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2019-10-03T15:04:16.303Z,1570115056.303 [DVL_micro](INFO): Powering down
2019-10-03T15:04:16.352Z,1570115056.352 [CBIT](INFO): Critical error at 20191003T150415
2019-10-03T15:04:18.683Z,1570115058.683 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:04:21.503Z,1570115061.503 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:04:24.731Z,1570115064.731 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:04:27.561Z,1570115067.561 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:04:30.807Z,1570115070.807 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:04:33.619Z,1570115073.619 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:04:36.447Z,1570115076.447 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:04:39.679Z,1570115079.679 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:04:42.510Z,1570115082.510 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:04:45.741Z,1570115085.741 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:04:48.575Z,1570115088.575 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:04:51.399Z,1570115091.399 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:04:53.415Z,1570115093.415 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:04:55.435Z,1570115095.435 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:04:58.670Z,1570115098.670 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:05:01.501Z,1570115101.501 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:05:04.730Z,1570115104.730 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:05:07.555Z,1570115107.555 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:05:10.383Z,1570115110.383 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:05:12.403Z,1570115112.403 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:05:14.418Z,1570115114.418 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-10-03T15:05:14.419Z,1570115114.419 [NAL9602] Data Fault, FailCount= 3
2019-10-03T15:05:14.419Z,1570115114.419 [NAL9602](ERROR): Data Fault
2019-10-03T15:05:14.458Z,1570115114.458 [CBIT](ERROR): Data Fault in component: NAL9602
2019-10-03T15:05:14.812Z,1570115114.812 [NAL9602](INFO): Powering down
2019-10-03T15:05:15.657Z,1570115115.657 [CBIT](INFO): Clearing failed state for component NAL9602
2019-10-03T15:05:15.657Z,1570115115.657 [NAL9602] No Fault, FailCount= 3
2019-10-03T15:05:45.114Z,1570115145.114 [NAL9602](INFO): Powering up NAL9602
2019-10-03T15:05:56.024Z,1570115156.024 [NAL9602](INFO): NAL9602 initialized
2019-10-03T15:05:56.851Z,1570115156.851 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:05:59.267Z,1570115159.267 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:06:02.501Z,1570115162.501 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:06:05.335Z,1570115165.335 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:06:08.559Z,1570115168.559 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:06:11.387Z,1570115171.387 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:06:14.219Z,1570115174.219 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:06:16.239Z,1570115176.239 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:06:18.267Z,1570115178.267 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:06:19.892Z,1570115179.892 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2019-10-03T15:06:19.892Z,1570115179.892 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-03T15:06:19.940Z,1570115179.940 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-03T15:06:20.345Z,1570115180.345 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-03T15:06:20.345Z,1570115180.345 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2019-10-03T15:06:21.483Z,1570115181.483 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:06:24.319Z,1570115184.319 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:06:27.543Z,1570115187.543 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:06:30.371Z,1570115190.371 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:06:33.203Z,1570115193.203 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:06:35.227Z,1570115195.227 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:06:37.239Z,1570115197.239 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:06:40.475Z,1570115200.475 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:06:43.299Z,1570115203.299 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:06:46.535Z,1570115206.535 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:06:49.365Z,1570115209.365 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:06:52.611Z,1570115212.611 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:06:55.419Z,1570115215.419 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:06:58.255Z,1570115218.255 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:07:01.479Z,1570115221.479 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:07:04.313Z,1570115224.313 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:07:07.539Z,1570115227.539 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:07:10.379Z,1570115230.379 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:07:13.211Z,1570115233.211 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:07:15.223Z,1570115235.223 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:07:16.070Z,1570115236.070 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-10-03T15:07:16.070Z,1570115236.070 [DVL_micro] No Fault, FailCount= 3
2019-10-03T15:07:17.239Z,1570115237.239 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:07:20.479Z,1570115240.479 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:07:23.299Z,1570115243.299 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:07:24.096Z,1570115244.096 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2019-10-03T15:07:24.096Z,1570115244.096 [DVL_micro] Data Fault, FailCount= 1
2019-10-03T15:07:24.097Z,1570115244.097 [DVL_micro](ERROR): Data Fault
2019-10-03T15:07:24.181Z,1570115244.181 [CBIT](ERROR): Data Fault in component: DVL_micro
2019-10-03T15:07:24.563Z,1570115244.563 [DVL_micro](INFO): Powering down
2019-10-03T15:07:25.350Z,1570115245.350 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-10-03T15:07:25.350Z,1570115245.350 [DVL_micro] No Fault, FailCount= 1
2019-10-03T15:07:26.535Z,1570115246.535 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:07:29.355Z,1570115249.355 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:07:32.599Z,1570115252.599 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:07:35.423Z,1570115255.423 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:07:38.251Z,1570115258.251 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:07:41.483Z,1570115261.483 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:07:43.081Z,1570115263.081 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-10-03T15:07:43.081Z,1570115263.081 [DVL_micro] Communications Fault, FailCount= 2
2019-10-03T15:07:43.081Z,1570115263.081 [DVL_micro](ERROR): Communications Fault
2019-10-03T15:07:43.146Z,1570115263.146 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-10-03T15:07:43.559Z,1570115263.559 [DVL_micro](INFO): Powering down
2019-10-03T15:07:44.307Z,1570115264.307 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:07:44.350Z,1570115264.350 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-10-03T15:07:44.350Z,1570115264.350 [DVL_micro] No Fault, FailCount= 2
2019-10-03T15:07:47.539Z,1570115267.539 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:07:50.369Z,1570115270.369 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:07:53.203Z,1570115273.203 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:07:56.435Z,1570115276.435 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:07:59.263Z,1570115279.263 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:08:02.068Z,1570115282.068 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-10-03T15:08:02.069Z,1570115282.069 [DVL_micro] Communications Fault, FailCount= 3
2019-10-03T15:08:02.069Z,1570115282.069 [DVL_micro](ERROR): Communications Fault
2019-10-03T15:08:02.159Z,1570115282.159 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-10-03T15:08:02.163Z,1570115282.163 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2019-10-03T15:08:02.551Z,1570115282.551 [DVL_micro](INFO): Powering down
2019-10-03T15:08:02.569Z,1570115282.569 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:08:02.631Z,1570115282.631 [CBIT](INFO): Critical error at 20191003T150802
2019-10-03T15:08:05.315Z,1570115285.315 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:08:08.543Z,1570115288.543 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:08:11.379Z,1570115291.379 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:08:14.203Z,1570115294.203 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:08:17.439Z,1570115297.439 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:08:20.265Z,1570115300.265 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:08:23.499Z,1570115303.499 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:08:26.327Z,1570115306.327 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:08:29.559Z,1570115309.559 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:08:32.383Z,1570115312.383 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:08:35.211Z,1570115315.211 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:08:38.439Z,1570115318.439 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:08:40.883Z,1570115320.883 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-10-03T15:08:40.883Z,1570115320.883 [Default:CheckIn:C.Wait] Stopped
2019-10-03T15:08:40.883Z,1570115320.883 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-10-03T15:08:40.883Z,1570115320.883 [Default:CheckIn:D] Running Loop=1
2019-10-03T15:08:41.267Z,1570115321.267 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:08:41.301Z,1570115321.301 [Default:CheckIn:D] Stopped
2019-10-03T15:08:41.301Z,1570115321.301 [Default:CheckIn:E] Running Loop=1
2019-10-03T15:08:41.713Z,1570115321.713 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 32.107792 min 
2019-10-03T15:08:41.713Z,1570115321.713 [Default:CheckIn:E] Stopped
2019-10-03T15:08:41.714Z,1570115321.714 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-10-03T15:08:41.714Z,1570115321.714 [Default:CheckIn] Stopped
2019-10-03T15:08:41.714Z,1570115321.714 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-10-03T15:08:41.714Z,1570115321.714 [Default:CheckIn](INFO): Running loop #4
2019-10-03T15:08:41.714Z,1570115321.714 [Default:CheckIn] Running Loop=4
2019-10-03T15:08:41.714Z,1570115321.714 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-10-03T15:08:41.714Z,1570115321.714 [Default:CheckIn:Read_GPS] Running Loop=1
2019-10-03T15:08:44.505Z,1570115324.505 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:08:47.335Z,1570115327.335 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:08:50.567Z,1570115330.567 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:08:53.387Z,1570115333.387 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:08:56.219Z,1570115336.219 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:08:59.451Z,1570115339.451 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:09:02.283Z,1570115342.283 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:09:05.513Z,1570115345.513 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:09:08.359Z,1570115348.359 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:09:11.171Z,1570115351.171 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:09:13.195Z,1570115353.195 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:09:15.211Z,1570115355.211 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:09:18.443Z,1570115358.443 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:09:20.884Z,1570115360.884 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2019-10-03T15:09:20.884Z,1570115360.884 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-03T15:09:20.894Z,1570115360.894 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-03T15:09:21.263Z,1570115361.263 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:09:21.311Z,1570115361.311 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-03T15:09:21.311Z,1570115361.311 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2019-10-03T15:09:24.503Z,1570115364.503 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:09:27.323Z,1570115367.323 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:09:30.559Z,1570115370.559 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:09:33.395Z,1570115373.395 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:09:36.623Z,1570115376.623 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:09:39.443Z,1570115379.443 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:09:42.675Z,1570115382.675 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:09:45.503Z,1570115385.503 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:09:48.335Z,1570115388.335 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:09:51.159Z,1570115391.159 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:09:53.187Z,1570115393.187 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:09:55.199Z,1570115395.199 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:09:58.435Z,1570115398.435 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:10:01.265Z,1570115401.265 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:10:04.497Z,1570115404.497 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:10:07.331Z,1570115407.331 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:10:10.563Z,1570115410.563 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:10:13.383Z,1570115413.383 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:10:16.215Z,1570115416.215 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:10:18.647Z,1570115418.647 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:10:21.467Z,1570115421.467 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:10:24.699Z,1570115424.699 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:10:27.529Z,1570115427.529 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:10:30.355Z,1570115430.355 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:10:33.187Z,1570115433.187 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:10:35.199Z,1570115435.199 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:10:38.431Z,1570115438.431 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:10:41.263Z,1570115441.263 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:10:44.495Z,1570115444.495 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:10:47.325Z,1570115447.325 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:10:50.557Z,1570115450.557 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:10:53.387Z,1570115453.387 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:10:56.219Z,1570115456.219 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:10:59.447Z,1570115459.447 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:11:02.271Z,1570115462.271 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:11:02.321Z,1570115462.321 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-10-03T15:11:02.321Z,1570115462.321 [DVL_micro] No Fault, FailCount= 3
2019-10-03T15:11:05.511Z,1570115465.511 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:11:08.335Z,1570115468.335 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:11:10.336Z,1570115470.336 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2019-10-03T15:11:10.337Z,1570115470.337 [DVL_micro] Data Fault, FailCount= 1
2019-10-03T15:11:10.337Z,1570115470.337 [DVL_micro](ERROR): Data Fault
2019-10-03T15:11:10.397Z,1570115470.397 [CBIT](ERROR): Data Fault in component: DVL_micro
2019-10-03T15:11:10.819Z,1570115470.819 [DVL_micro](INFO): Powering down
2019-10-03T15:11:11.565Z,1570115471.565 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:11:11.601Z,1570115471.601 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-10-03T15:11:11.601Z,1570115471.601 [DVL_micro] No Fault, FailCount= 1
2019-10-03T15:11:14.403Z,1570115474.403 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:11:17.223Z,1570115477.223 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:11:20.455Z,1570115480.455 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:11:23.283Z,1570115483.283 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:11:26.524Z,1570115486.524 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:11:29.328Z,1570115489.328 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-10-03T15:11:29.329Z,1570115489.329 [DVL_micro] Communications Fault, FailCount= 2
2019-10-03T15:11:29.329Z,1570115489.329 [DVL_micro](ERROR): Communications Fault
2019-10-03T15:11:29.343Z,1570115489.343 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:11:29.391Z,1570115489.391 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-10-03T15:11:29.803Z,1570115489.803 [DVL_micro](INFO): Powering down
2019-10-03T15:11:30.593Z,1570115490.593 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-10-03T15:11:30.593Z,1570115490.593 [DVL_micro] No Fault, FailCount= 2
2019-10-03T15:11:32.579Z,1570115492.579 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:11:35.399Z,1570115495.399 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:11:38.229Z,1570115498.229 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:11:41.461Z,1570115501.461 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:11:44.291Z,1570115504.291 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:11:47.523Z,1570115507.523 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:11:48.316Z,1570115508.316 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-10-03T15:11:48.317Z,1570115508.317 [DVL_micro] Communications Fault, FailCount= 3
2019-10-03T15:11:48.317Z,1570115508.317 [DVL_micro](ERROR): Communications Fault
2019-10-03T15:11:48.399Z,1570115508.399 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-10-03T15:11:48.399Z,1570115508.399 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2019-10-03T15:11:48.787Z,1570115508.787 [DVL_micro](INFO): Powering down
2019-10-03T15:11:48.835Z,1570115508.835 [CBIT](INFO): Critical error at 20191003T151148
2019-10-03T15:11:50.347Z,1570115510.347 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:11:53.175Z,1570115513.175 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:11:55.195Z,1570115515.195 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:11:58.427Z,1570115518.427 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:12:01.255Z,1570115521.255 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:12:04.487Z,1570115524.487 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:12:07.311Z,1570115527.311 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:12:10.547Z,1570115530.547 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:12:13.407Z,1570115533.407 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:12:16.211Z,1570115536.211 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:12:18.643Z,1570115538.643 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:12:21.463Z,1570115541.463 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:12:21.896Z,1570115541.896 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2019-10-03T15:12:21.896Z,1570115541.896 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-03T15:12:21.905Z,1570115541.905 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-03T15:12:22.313Z,1570115542.313 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-03T15:12:22.313Z,1570115542.313 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2019-10-03T15:12:24.699Z,1570115544.699 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:12:27.519Z,1570115547.519 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:12:30.353Z,1570115550.353 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:12:33.583Z,1570115553.583 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:12:36.419Z,1570115556.419 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:12:39.243Z,1570115559.243 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:12:42.467Z,1570115562.467 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:12:45.299Z,1570115565.299 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:12:48.527Z,1570115568.527 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:12:51.359Z,1570115571.359 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:12:54.587Z,1570115574.587 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:12:57.427Z,1570115577.427 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:13:00.655Z,1570115580.655 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:13:03.483Z,1570115583.483 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:13:06.711Z,1570115586.711 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:13:09.539Z,1570115589.539 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:13:12.371Z,1570115592.371 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:13:15.195Z,1570115595.195 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:13:18.423Z,1570115598.423 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:13:21.251Z,1570115601.251 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:13:24.487Z,1570115604.487 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:13:27.315Z,1570115607.315 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:13:30.551Z,1570115610.551 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:13:33.375Z,1570115613.375 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:13:36.603Z,1570115616.603 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:13:39.431Z,1570115619.431 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:13:41.876Z,1570115621.876 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-10-03T15:08:41.7Z
2019-10-03T15:13:41.876Z,1570115621.876 [Default:CheckIn:Read_GPS] Stopped
2019-10-03T15:13:41.876Z,1570115621.876 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-10-03T15:13:42.663Z,1570115622.663 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:13:45.491Z,1570115625.491 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:13:48.327Z,1570115628.327 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:13:50.059Z,1570115630.059 [DataOverHttps](INFO): Sending 245 bytes from file Logs/20191003T143258/Courier0013.lzma
2019-10-03T15:13:51.151Z,1570115631.151 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:13:52.064Z,1570115632.064 [DataOverHttps](INFO): Moved sent file to Logs/20191003T143258/Courier0013.lzma.bak
2019-10-03T15:13:52.065Z,1570115632.065 [DataOverHttps](INFO): SBD MOMSN=11844831
2019-10-03T15:13:54.383Z,1570115634.383 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:13:57.217Z,1570115637.217 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:14:00.443Z,1570115640.443 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:14:03.270Z,1570115643.270 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:14:06.523Z,1570115646.523 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:14:09.339Z,1570115649.339 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:14:12.575Z,1570115652.575 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:14:15.399Z,1570115655.399 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:14:16.223Z,1570115656.223 [DataOverHttps](INFO): Sending 175 bytes from file Logs/20191003T143258/Express0014.lzma
2019-10-03T15:14:18.229Z,1570115658.229 [DataOverHttps](INFO): Moved sent file to Logs/20191003T143258/Express0014.lzma.bak
2019-10-03T15:14:18.229Z,1570115658.229 [DataOverHttps](INFO): SBD MOMSN=11844836
2019-10-03T15:14:18.639Z,1570115658.639 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:14:21.463Z,1570115661.463 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:14:21.919Z,1570115661.919 [Default:CheckIn:Read_Iridium] Stopped
2019-10-03T15:14:21.919Z,1570115661.919 [Default:CheckIn:C.Wait] Running Loop=1
2019-10-03T15:14:21.919Z,1570115661.919 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-10-03T15:14:24.699Z,1570115664.699 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:14:27.519Z,1570115667.519 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:14:30.755Z,1570115670.755 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:14:33.585Z,1570115673.585 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:14:36.415Z,1570115676.415 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:14:39.239Z,1570115679.239 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:14:42.467Z,1570115682.467 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:14:45.303Z,1570115685.303 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:14:48.533Z,1570115688.533 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:14:48.568Z,1570115688.568 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-10-03T15:14:48.568Z,1570115688.568 [DVL_micro] No Fault, FailCount= 3
2019-10-03T15:14:51.367Z,1570115691.367 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:14:54.599Z,1570115694.599 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:14:56.604Z,1570115696.604 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2019-10-03T15:14:56.604Z,1570115696.604 [DVL_micro] Data Fault, FailCount= 1
2019-10-03T15:14:56.605Z,1570115696.605 [DVL_micro](ERROR): Data Fault
2019-10-03T15:14:56.670Z,1570115696.670 [CBIT](ERROR): Data Fault in component: DVL_micro
2019-10-03T15:14:57.075Z,1570115697.075 [DVL_micro](INFO): Powering down
2019-10-03T15:14:57.419Z,1570115697.419 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:14:57.873Z,1570115697.873 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-10-03T15:14:57.873Z,1570115697.873 [DVL_micro] No Fault, FailCount= 1
2019-10-03T15:15:00.651Z,1570115700.651 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:15:03.483Z,1570115703.483 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:15:06.713Z,1570115706.713 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:15:09.547Z,1570115709.547 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:15:12.771Z,1570115712.771 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:15:15.588Z,1570115715.588 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-10-03T15:15:15.589Z,1570115715.589 [DVL_micro] Communications Fault, FailCount= 2
2019-10-03T15:15:15.589Z,1570115715.589 [DVL_micro](ERROR): Communications Fault
2019-10-03T15:15:15.603Z,1570115715.603 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:15:15.664Z,1570115715.664 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-10-03T15:15:16.067Z,1570115716.067 [DVL_micro](INFO): Powering down
2019-10-03T15:15:16.846Z,1570115716.846 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-10-03T15:15:16.846Z,1570115716.846 [DVL_micro] No Fault, FailCount= 2
2019-10-03T15:15:18.433Z,1570115718.433 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:15:21.263Z,1570115721.263 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:15:22.899Z,1570115722.899 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2019-10-03T15:15:22.899Z,1570115722.899 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-03T15:15:22.909Z,1570115722.909 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-03T15:15:23.301Z,1570115723.301 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-03T15:15:23.301Z,1570115723.301 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2019-10-03T15:15:24.495Z,1570115724.495 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:15:27.315Z,1570115727.315 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:15:30.547Z,1570115730.547 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:15:33.377Z,1570115733.377 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:15:34.577Z,1570115734.577 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-10-03T15:15:34.577Z,1570115734.577 [DVL_micro] Communications Fault, FailCount= 3
2019-10-03T15:15:34.578Z,1570115734.578 [DVL_micro](ERROR): Communications Fault
2019-10-03T15:15:34.614Z,1570115734.614 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-10-03T15:15:34.614Z,1570115734.614 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2019-10-03T15:15:35.047Z,1570115735.047 [DVL_micro](INFO): Powering down
2019-10-03T15:15:35.080Z,1570115735.080 [CBIT](INFO): Critical error at 20191003T151534
2019-10-03T15:15:36.609Z,1570115736.609 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:15:39.443Z,1570115739.443 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:15:42.667Z,1570115742.667 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:15:45.499Z,1570115745.499 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:15:48.727Z,1570115748.727 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:15:51.567Z,1570115751.567 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:15:54.383Z,1570115754.383 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:15:57.201Z,1570115757.201 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-10-03T15:15:57.201Z,1570115757.201 [NAL9602] Data Fault, FailCount= 4
2019-10-03T15:15:57.201Z,1570115757.201 [NAL9602](ERROR): Data Fault
2019-10-03T15:15:57.231Z,1570115757.231 [CBIT](ERROR): Data Fault in component: NAL9602
2019-10-03T15:15:57.605Z,1570115757.605 [NAL9602](INFO): Powering down
2019-10-03T15:15:58.455Z,1570115758.455 [CBIT](INFO): Clearing failed state for component NAL9602
2019-10-03T15:15:58.455Z,1570115758.455 [NAL9602] No Fault, FailCount= 4
2019-10-03T15:16:27.920Z,1570115787.920 [NAL9602](INFO): Powering up NAL9602
2019-10-03T15:16:38.828Z,1570115798.828 [NAL9602](INFO): NAL9602 initialized
2019-10-03T15:16:39.647Z,1570115799.647 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:16:42.475Z,1570115802.475 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:16:45.303Z,1570115805.303 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:16:48.539Z,1570115808.539 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:16:51.367Z,1570115811.367 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:16:54.599Z,1570115814.599 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:16:57.423Z,1570115817.423 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:17:00.655Z,1570115820.655 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:17:03.490Z,1570115823.490 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:17:06.717Z,1570115826.717 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:17:09.555Z,1570115829.555 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:17:12.379Z,1570115832.379 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:17:15.209Z,1570115835.209 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:17:18.442Z,1570115838.442 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:17:21.275Z,1570115841.275 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:17:24.507Z,1570115844.507 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:17:27.327Z,1570115847.327 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:17:30.559Z,1570115850.559 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:17:33.383Z,1570115853.383 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:17:36.623Z,1570115856.623 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:17:39.449Z,1570115859.449 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:17:42.695Z,1570115862.695 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:17:45.519Z,1570115865.519 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:17:48.743Z,1570115868.743 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:17:51.567Z,1570115871.567 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:17:54.399Z,1570115874.399 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:17:57.226Z,1570115877.226 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:18:00.459Z,1570115880.459 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:18:03.291Z,1570115883.291 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:18:06.523Z,1570115886.523 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:18:09.367Z,1570115889.367 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:18:12.607Z,1570115892.607 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:18:15.435Z,1570115895.435 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:18:18.667Z,1570115898.667 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:18:21.492Z,1570115901.492 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:18:23.940Z,1570115903.940 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2019-10-03T15:18:23.941Z,1570115903.941 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-03T15:18:23.951Z,1570115903.951 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-03T15:18:24.345Z,1570115904.345 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-03T15:18:24.345Z,1570115904.345 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2019-10-03T15:18:24.731Z,1570115904.731 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:18:27.558Z,1570115907.558 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:18:30.379Z,1570115910.379 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:18:33.623Z,1570115913.623 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:18:34.873Z,1570115914.873 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-10-03T15:18:34.873Z,1570115914.873 [DVL_micro] No Fault, FailCount= 3
2019-10-03T15:18:36.459Z,1570115916.459 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:18:39.275Z,1570115919.275 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:18:42.519Z,1570115922.519 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:18:42.917Z,1570115922.917 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2019-10-03T15:18:42.917Z,1570115922.917 [DVL_micro] Data Fault, FailCount= 1
2019-10-03T15:18:42.917Z,1570115922.917 [DVL_micro](ERROR): Data Fault
2019-10-03T15:18:43.026Z,1570115923.026 [CBIT](ERROR): Data Fault in component: DVL_micro
2019-10-03T15:18:43.383Z,1570115923.383 [DVL_micro](INFO): Powering down
2019-10-03T15:18:44.232Z,1570115924.232 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-10-03T15:18:44.232Z,1570115924.232 [DVL_micro] No Fault, FailCount= 1
2019-10-03T15:18:45.347Z,1570115925.347 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:18:48.579Z,1570115928.579 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:18:51.403Z,1570115931.403 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:18:54.641Z,1570115934.641 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:18:55.030Z,1570115935.030 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,151854.00,A,3648.14415,N,12147.22534,W,0.408,32.14,031019,,,A*4D
2019-10-03T15:18:55.033Z,1570115935.033 [NAL9602](INFO): GPS fix at 20191003T151854: (36.802402, -121.787089)
2019-10-03T15:19:01.900Z,1570115941.900 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-10-03T15:19:01.900Z,1570115941.900 [DVL_micro] Communications Fault, FailCount= 2
2019-10-03T15:19:01.900Z,1570115941.900 [DVL_micro](ERROR): Communications Fault
2019-10-03T15:19:02.024Z,1570115942.024 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-10-03T15:19:02.383Z,1570115942.383 [DVL_micro](INFO): Powering down
2019-10-03T15:19:03.200Z,1570115943.200 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-10-03T15:19:03.200Z,1570115943.200 [DVL_micro] No Fault, FailCount= 2
2019-10-03T15:19:20.884Z,1570115960.884 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-10-03T15:19:20.884Z,1570115960.884 [DVL_micro] Communications Fault, FailCount= 3
2019-10-03T15:19:20.884Z,1570115960.884 [DVL_micro](ERROR): Communications Fault
2019-10-03T15:19:20.993Z,1570115960.993 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-10-03T15:19:20.993Z,1570115960.993 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2019-10-03T15:19:21.359Z,1570115961.359 [DVL_micro](INFO): Powering down
2019-10-03T15:19:21.456Z,1570115961.456 [CBIT](INFO): Critical error at 20191003T151920
2019-10-03T15:19:22.533Z,1570115962.533 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-10-03T15:19:22.533Z,1570115962.533 [Default:CheckIn:C.Wait] Stopped
2019-10-03T15:19:22.533Z,1570115962.533 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-10-03T15:19:22.533Z,1570115962.533 [Default:CheckIn:D] Running Loop=1
2019-10-03T15:19:22.928Z,1570115962.928 [Default:CheckIn:D] Stopped
2019-10-03T15:19:22.928Z,1570115962.928 [Default:CheckIn:E] Running Loop=1
2019-10-03T15:19:23.331Z,1570115963.331 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.801579 min 
2019-10-03T15:19:23.331Z,1570115963.331 [Default:CheckIn:E] Stopped
2019-10-03T15:19:23.332Z,1570115963.332 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-10-03T15:19:23.332Z,1570115963.332 [Default:CheckIn] Stopped
2019-10-03T15:19:23.332Z,1570115963.332 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-10-03T15:19:23.332Z,1570115963.332 [Default:CheckIn](INFO): Running loop #5
2019-10-03T15:19:23.332Z,1570115963.332 [Default:CheckIn] Running Loop=5
2019-10-03T15:19:23.332Z,1570115963.332 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-10-03T15:19:23.332Z,1570115963.332 [Default:CheckIn:Read_GPS] Running Loop=1
2019-10-03T15:19:24.939Z,1570115964.939 [NAL9602](DEBUG): Fix Requested
2019-10-03T15:19:25.340Z,1570115965.340 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,151924.00,A,3648.14333,N,12147.22699,W,0.039,32.14,031019,,,A*4A
2019-10-03T15:19:25.342Z,1570115965.342 [NAL9602](INFO): GPS fix at 20191003T151924: (36.802389, -121.787116)
2019-10-03T15:19:25.366Z,1570115965.366 [Default:CheckIn:Read_GPS] Stopped
2019-10-03T15:19:25.366Z,1570115965.366 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-10-03T15:19:34.319Z,1570115974.319 [DataOverHttps](INFO): Sending 269 bytes from file Logs/20191003T143258/Courier0016.lzma
2019-10-03T15:19:36.325Z,1570115976.325 [DataOverHttps](INFO): Moved sent file to Logs/20191003T143258/Courier0016.lzma.bak
2019-10-03T15:19:36.325Z,1570115976.325 [DataOverHttps](INFO): SBD MOMSN=11844843
2019-10-03T15:19:55.965Z,1570115995.965 [DataOverHttps](INFO): Sending 321 bytes from file Logs/20191003T143258/Express0017.lzma
2019-10-03T15:19:57.308Z,1570115997.308 [NAL9602](INFO): Not Powering down - fast GPS
2019-10-03T15:19:57.969Z,1570115997.969 [DataOverHttps](INFO): Moved sent file to Logs/20191003T143258/Express0017.lzma.bak
2019-10-03T15:19:57.969Z,1570115997.969 [DataOverHttps](INFO): SBD MOMSN=11844848
2019-10-03T15:20:01.811Z,1570116001.811 [Default:CheckIn:Read_Iridium] Stopped
2019-10-03T15:20:01.811Z,1570116001.811 [Default:CheckIn:C.Wait] Running Loop=1
2019-10-03T15:20:01.811Z,1570116001.811 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-10-03T15:20:16.212Z,1570116016.212 [DataOverHttps](IMPORTANT): SBD MTMSN=20191003T152015
2019-10-03T15:20:23.719Z,1570116023.719 [DataOverHttps](INFO): Received command:configSet DVL_micro.loadAtStartup 0 bool persist;restart app
2019-10-03T15:20:23.910Z,1570116023.910 [CommandLine](IMPORTANT): got command configSet DVL_micro.loadAtStartup 0.000000 bool persist
2019-10-03T15:20:23.910Z,1570116023.910 [CommandLine](IMPORTANT): configSet DVL_micro.loadAtStartup requires a restart to take effect.
2019-10-03T15:20:23.912Z,1570116023.912 [CommandLine](IMPORTANT): got command restart application
2019-10-03T15:20:24.915Z,1570116024.915 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-03T15:20:24.915Z,1570116024.915 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-10-03T15:20:24.927Z,1570116024.927 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-10-03T15:20:24.927Z,1570116024.927 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-10-03T15:20:24.928Z,1570116024.928 [CommandLine](INFO): Join timeout helper Thread ID is 1171
2019-10-03T15:20:24.928Z,1570116024.928 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-10-03T15:20:24.928Z,1570116024.928 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-10-03T15:20:24.929Z,1570116024.929 [NavChartDb](INFO): Join timeout helper Thread ID is 1172
2019-10-03T15:20:25.063Z,1570116025.063 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-03T15:20:25.063Z,1570116025.063 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-10-03T15:20:25.067Z,1570116025.067 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-10-03T15:20:25.067Z,1570116025.067 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-10-03T15:20:25.067Z,1570116025.067 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1173
2019-10-03T15:20:25.103Z,1570116025.103 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-03T15:20:25.104Z,1570116025.104 [WetLabsBB2FL](INFO): Powering down
2019-10-03T15:20:25.104Z,1570116025.104 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-10-03T15:20:25.107Z,1570116025.107 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-10-03T15:20:25.107Z,1570116025.107 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-10-03T15:20:25.108Z,1570116025.108 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1174
2019-10-03T15:20:25.207Z,1570116025.207 [CTD_Seabird](INFO): Powering down
2019-10-03T15:20:25.219Z,1570116025.219 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-03T15:20:25.219Z,1570116025.219 [CTD_Seabird](INFO): Powering down
2019-10-03T15:20:25.231Z,1570116025.231 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-10-03T15:20:25.239Z,1570116025.239 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-10-03T15:20:25.239Z,1570116025.239 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-10-03T15:20:25.240Z,1570116025.240 [Radio_Surface](INFO): Join timeout helper Thread ID is 1175
2019-10-03T15:20:25.351Z,1570116025.351 [Radio_Surface](INFO): Powering down
2019-10-03T15:20:25.352Z,1570116025.352 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-03T15:20:25.352Z,1570116025.352 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-10-03T15:20:25.368Z,1570116025.368 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-10-03T15:20:25.368Z,1570116025.368 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-10-03T15:20:25.368Z,1570116025.368 [DataOverHttps](INFO): Join timeout helper Thread ID is 1176
2019-10-03T15:20:25.723Z,1570116025.723 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-03T15:20:25.727Z,1570116025.727 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-10-03T15:20:25.728Z,1570116025.728 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-10-03T15:20:25.728Z,1570116025.728 [logger ThreadHandler](INFO): Thread cancelled.
2019-10-03T15:20:25.729Z,1570116025.729 [logger](INFO): Join timeout helper Thread ID is 1177
2019-10-03T15:20:25.771Z,1570116025.771 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-03T15:20:25.771Z,1570116025.771 [logger ThreadHandler](INFO): Thread cancelled.
2019-10-03T15:20:25.788Z,1570116025.788 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-10-03T15:20:25.788Z,1570116025.788 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-10-03T15:20:25.788Z,1570116025.788 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-10-03T15:20:25.788Z,1570116025.788 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-10-03T15:20:25.788Z,1570116025.788 [controlThread](INFO): Join timeout helper Thread ID is 1178
2019-10-03T15:20:25.991Z,1570116025.991 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-03T15:20:25.991Z,1570116025.991 [controlThread](DEBUG): Uninitializing ControlThread
2019-10-03T15:20:25.992Z,1570116025.992 [AHRS_M2](INFO): Powering down
2019-10-03T15:20:26.064Z,1570116026.064 [DVL_micro](INFO): Powering down
2019-10-03T15:20:26.064Z,1570116026.064 [NAL9602](INFO): Powering down
2019-10-03T15:20:26.066Z,1570116026.066 [DAT](INFO): Powering down
2019-10-03T15:20:26.203Z,1570116026.203 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-10-03T15:20:26.204Z,1570116026.204 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-10-03T15:20:26.204Z,1570116026.204 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-10-03T15:20:26.205Z,1570116026.205 [MissionManager](INFO): Uninitializing Mission Default
2019-10-03T15:20:26.205Z,1570116026.205 [Default] Stopped
2019-10-03T15:20:26.205Z,1570116026.205 [Default](DEBUG): Aggregate::uninitialize Default
2019-10-03T15:20:26.205Z,1570116026.205 [Default:B.GoToSurface] Stopped
2019-10-03T15:20:26.205Z,1570116026.205 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-10-03T15:20:26.205Z,1570116026.205 [Default:CheckIn] Stopped
2019-10-03T15:20:26.205Z,1570116026.205 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-10-03T15:20:26.205Z,1570116026.205 [Default:CheckIn:C.Wait] Stopped
2019-10-03T15:20:26.205Z,1570116026.205 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-10-03T15:20:26.208Z,1570116026.208 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-10-03T15:20:26.208Z,1570116026.208 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-10-03T15:20:26.208Z,1570116026.208 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-10-03T15:20:26.209Z,1570116026.209 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-10-03T15:20:26.209Z,1570116026.209 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-10-03T15:20:26.209Z,1570116026.209 [BuoyancyServo](INFO): Powering down
2019-10-03T15:20:26.223Z,1570116026.223 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-10-03T15:20:26.223Z,1570116026.223 [ElevatorServo](INFO): Powering down
2019-10-03T15:20:26.224Z,1570116026.224 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-10-03T15:20:26.224Z,1570116026.224 [MassServo](INFO): Powering down
2019-10-03T15:20:26.225Z,1570116026.225 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-10-03T15:20:26.225Z,1570116026.225 [RudderServo](INFO): Powering down
2019-10-03T15:20:26.226Z,1570116026.226 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-10-03T15:20:26.226Z,1570116026.226 [ThrusterServo](INFO): Powering down
2019-10-03T15:20:26.226Z,1570116026.226 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-10-03T15:20:26.227Z,1570116026.227 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-10-03T15:20:26.227Z,1570116026.227 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-10-03T15:20:26.227Z,1570116026.227 [CBIT](DEBUG): Powering off loads.
2019-10-03T15:20:26.239Z,1570116026.239 [CBIT](DEBUG): Disabling WDT.
2019-10-03T15:20:26.251Z,1570116026.251 [CBIT](DEBUG): Opening all GF detection circuits.
2019-10-03T15:20:26.252Z,1570116026.252 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-10-03T15:20:26.346Z,1570116026.346 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-10-03T15:20:26.353Z,1570116026.353 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-10-03T15:20:26.396Z,1570116026.396 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-10-03T15:20:26.401Z,1570116026.401 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-10-03T15:20:26.440Z,1570116026.440 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-10-03T15:20:26.508Z,1570116026.508 [logger ThreadHandler](INFO): Thread cancelled.