2020-10-06T15:29:43.994Z,1601998183.994 [Supervisor](DEBUG): Initializing supervisor.
2020-10-06T15:29:43.996Z,1601998183.996 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-10-06T15:29:43.997Z,1601998183.997 [SyncHandler](INFO): Protected caller Thread ID is 589
2020-10-06T15:29:43.997Z,1601998183.997 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-10-06T15:29:43.998Z,1601998183.998 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-10-06T15:29:43.999Z,1601998183.999 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 590
2020-10-06T15:29:44.001Z,1601998184.001 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-10-06T15:29:44.016Z,1601998184.016 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-10-06T15:29:44.017Z,1601998184.017 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-10-06T15:29:44.017Z,1601998184.017 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 591
2020-10-06T15:29:44.018Z,1601998184.018 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-10-06T15:29:44.019Z,1601998184.019 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-10-06T15:29:44.019Z,1601998184.019 [logger ThreadHandler](INFO): Protected caller Thread ID is 592
2020-10-06T15:29:44.021Z,1601998184.021 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-10-06T15:29:44.022Z,1601998184.022 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-10-06T15:29:44.023Z,1601998184.023 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-10-06T15:29:44.179Z,1601998184.179 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-10-06T15:29:44.180Z,1601998184.180 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-10-06T15:29:44.754Z,1601998184.754 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-10-06T15:29:44.755Z,1601998184.755 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2020-10-06T15:29:44.947Z,1601998184.947 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2020-10-06T15:29:44.948Z,1601998184.948 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-10-06T15:29:45.044Z,1601998185.044 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-10-06T15:29:45.045Z,1601998185.045 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-10-06T15:29:45.145Z,1601998185.145 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-10-06T15:29:45.146Z,1601998185.146 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-10-06T15:29:45.225Z,1601998185.225 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-10-06T15:29:45.363Z,1601998185.363 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-10-06T15:29:45.364Z,1601998185.364 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-10-06T15:29:45.646Z,1601998185.646 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-10-06T15:29:45.646Z,1601998185.646 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-10-06T15:29:46.090Z,1601998186.090 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-10-06T15:29:46.090Z,1601998186.090 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-10-06T15:29:46.230Z,1601998186.230 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-10-06T15:29:46.231Z,1601998186.231 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-10-06T15:29:46.486Z,1601998186.486 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-10-06T15:29:46.487Z,1601998186.487 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-10-06T15:29:46.972Z,1601998186.972 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-10-06T15:29:46.973Z,1601998186.973 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-10-06T15:29:47.184Z,1601998187.184 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-10-06T15:29:47.184Z,1601998187.184 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-10-06T15:29:47.379Z,1601998187.379 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-10-06T15:29:47.380Z,1601998187.380 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-10-06T15:29:47.777Z,1601998187.777 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-10-06T15:29:47.778Z,1601998187.778 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-10-06T15:29:48.093Z,1601998188.093 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-10-06T15:29:48.095Z,1601998188.095 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2020-10-06T15:29:48.096Z,1601998188.096 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2020-10-06T15:29:48.178Z,1601998188.178 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2020-10-06T15:29:48.328Z,1601998188.328 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2020-10-06T15:29:48.434Z,1601998188.434 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2020-10-06T15:29:48.517Z,1601998188.517 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2020-10-06T15:29:48.610Z,1601998188.610 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2020-10-06T15:29:48.798Z,1601998188.798 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2020-10-06T15:29:49.061Z,1601998189.061 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-10-06T15:29:49.061Z,1601998189.061 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2020-10-06T15:29:49.539Z,1601998189.539 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2020-10-06T15:29:49.992Z,1601998189.992 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2020-10-06T15:29:50.118Z,1601998190.118 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2020-10-06T15:29:50.216Z,1601998190.216 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/
2020-10-06T15:29:50.216Z,1601998190.216 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-10-06T15:29:50.233Z,1601998190.233 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2020-10-06T15:29:50.314Z,1601998190.314 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2020-10-06T15:29:50.314Z,1601998190.314 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-10-06T15:29:50.360Z,1601998190.360 [VerticalControl](DEBUG): Construct VerticalControl.
2020-10-06T15:29:50.471Z,1601998190.471 [VerticalControl] Loaded
2020-10-06T15:29:50.471Z,1601998190.471 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-10-06T15:29:50.471Z,1601998190.471 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-10-06T15:29:50.550Z,1601998190.550 [HorizontalControl] Loaded
2020-10-06T15:29:50.550Z,1601998190.550 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-10-06T15:29:50.551Z,1601998190.551 [SpeedControl](DEBUG): Construct SpeedControl.
2020-10-06T15:29:50.556Z,1601998190.556 [SpeedControl] Loaded
2020-10-06T15:29:50.557Z,1601998190.557 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-10-06T15:29:50.557Z,1601998190.557 [LoopControl](DEBUG): Construct LoopControl.
2020-10-06T15:29:50.558Z,1601998190.558 [LoopControl] Loaded
2020-10-06T15:29:50.558Z,1601998190.558 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-10-06T15:29:50.558Z,1601998190.558 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-10-06T15:29:50.559Z,1601998190.559 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-10-06T15:29:50.599Z,1601998190.599 [DepthRateCalculator] Loaded
2020-10-06T15:29:50.599Z,1601998190.599 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-10-06T15:29:50.604Z,1601998190.604 [PitchRateCalculator] Loaded
2020-10-06T15:29:50.605Z,1601998190.605 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-10-06T15:29:50.620Z,1601998190.620 [SpeedCalculator] Loaded
2020-10-06T15:29:50.620Z,1601998190.620 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-10-06T15:29:50.641Z,1601998190.641 [TempGradientCalculator] Loaded
2020-10-06T15:29:50.641Z,1601998190.641 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-10-06T15:29:50.646Z,1601998190.646 [YawRateCalculator] Loaded
2020-10-06T15:29:50.646Z,1601998190.646 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-10-06T15:29:50.675Z,1601998190.675 [ElevatorOffsetCalculator] Loaded
2020-10-06T15:29:50.675Z,1601998190.675 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-10-06T15:29:50.676Z,1601998190.676 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-10-06T15:29:50.676Z,1601998190.676 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-10-06T15:29:50.702Z,1601998190.702 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-10-06T15:29:50.703Z,1601998190.703 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-10-06T15:29:50.790Z,1601998190.790 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-10-06T15:29:50.790Z,1601998190.790 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-10-06T15:29:51.077Z,1601998191.077 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-10-06T15:29:51.077Z,1601998191.077 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-10-06T15:29:51.178Z,1601998191.178 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-10-06T15:29:51.179Z,1601998191.179 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-10-06T15:29:51.487Z,1601998191.487 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-10-06T15:29:51.492Z,1601998191.492 [AHRS_M2](INFO): created writer for : platform_orientation
2020-10-06T15:29:51.494Z,1601998191.494 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-10-06T15:29:51.499Z,1601998191.499 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-10-06T15:29:51.500Z,1601998191.500 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-10-06T15:29:51.504Z,1601998191.504 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-10-06T15:29:51.505Z,1601998191.505 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-10-06T15:29:51.510Z,1601998191.510 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-10-06T15:29:51.581Z,1601998191.581 [AHRS_M2] Loaded
2020-10-06T15:29:51.581Z,1601998191.581 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-10-06T15:29:51.658Z,1601998191.658 [DataOverHttps] Loaded
2020-10-06T15:29:51.658Z,1601998191.658 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-10-06T15:29:51.659Z,1601998191.659 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4090E4E0
2020-10-06T15:29:51.660Z,1601998191.660 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 675
2020-10-06T15:29:51.689Z,1601998191.689 [Depth_Keller] Loaded
2020-10-06T15:29:51.689Z,1601998191.689 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-10-06T15:29:51.801Z,1601998191.801 [NAL9602] Loaded
2020-10-06T15:29:51.801Z,1601998191.801 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-10-06T15:29:51.849Z,1601998191.849 [Onboard] Loaded
2020-10-06T15:29:51.849Z,1601998191.849 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2020-10-06T15:29:51.850Z,1601998191.850 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4093E4E0
2020-10-06T15:29:51.850Z,1601998191.850 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 676
2020-10-06T15:29:51.870Z,1601998191.870 [Radio_Surface] Loaded
2020-10-06T15:29:51.870Z,1601998191.870 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-10-06T15:29:51.871Z,1601998191.871 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4096E4E0
2020-10-06T15:29:51.871Z,1601998191.871 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 677
2020-10-06T15:29:52.021Z,1601998192.021 [DAT] Loaded
2020-10-06T15:29:52.021Z,1601998192.021 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2020-10-06T15:29:53.483Z,1601998193.483 [BPC1] Loaded
2020-10-06T15:29:53.483Z,1601998193.483 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-10-06T15:29:53.483Z,1601998193.483 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-10-06T15:29:53.484Z,1601998193.484 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-10-06T15:29:53.599Z,1601998193.599 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-10-06T15:29:53.599Z,1601998193.599 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-10-06T15:29:53.619Z,1601998193.619 [NavChart] Loaded
2020-10-06T15:29:53.619Z,1601998193.619 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-10-06T15:29:53.623Z,1601998193.623 [UniversalFixResidualReporter] Loaded
2020-10-06T15:29:53.623Z,1601998193.623 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-10-06T15:29:53.624Z,1601998193.624 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-10-06T15:29:53.624Z,1601998193.624 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-10-06T15:29:53.760Z,1601998193.760 [SBIT](DEBUG): Construct Startup Built In Test.
2020-10-06T15:29:53.771Z,1601998193.771 [SBIT] Loaded
2020-10-06T15:29:53.771Z,1601998193.771 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-10-06T15:29:53.771Z,1601998193.771 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-10-06T15:29:53.784Z,1601998193.784 [IBIT] Loaded
2020-10-06T15:29:53.784Z,1601998193.784 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-10-06T15:29:53.787Z,1601998193.787 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-10-06T15:29:53.925Z,1601998193.925 [CBIT] Loaded
2020-10-06T15:29:53.925Z,1601998193.925 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-10-06T15:29:53.926Z,1601998193.926 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-10-06T15:29:53.926Z,1601998193.926 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-10-06T15:29:54.047Z,1601998194.047 [BuoyancyServo] Loaded
2020-10-06T15:29:54.047Z,1601998194.047 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-10-06T15:29:54.075Z,1601998194.075 [ElevatorServo] Loaded
2020-10-06T15:29:54.076Z,1601998194.076 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-10-06T15:29:54.103Z,1601998194.103 [MassServo] Loaded
2020-10-06T15:29:54.104Z,1601998194.104 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-10-06T15:29:54.130Z,1601998194.130 [RudderServo] Loaded
2020-10-06T15:29:54.131Z,1601998194.131 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-10-06T15:29:54.158Z,1601998194.158 [ThrusterServo] Loaded
2020-10-06T15:29:54.158Z,1601998194.158 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-10-06T15:29:54.158Z,1601998194.158 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-10-06T15:29:54.159Z,1601998194.159 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-10-06T15:29:54.366Z,1601998194.366 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-10-06T15:29:54.366Z,1601998194.366 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-10-06T15:29:54.602Z,1601998194.602 [BackSeatDriver] Loaded
2020-10-06T15:29:54.602Z,1601998194.602 [ComponentRegistry](DEBUG): Component "BackSeatDriver" handled in its own thread.
2020-10-06T15:29:54.603Z,1601998194.603 [BackSeatDriver ThreadHandler](DEBUG): Created PCaller Thread at 40B054E0
2020-10-06T15:29:54.603Z,1601998194.603 [BackSeatDriver ThreadHandler](INFO): Protected caller Thread ID is 678
2020-10-06T15:29:54.631Z,1601998194.631 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2020-10-06T15:29:54.636Z,1601998194.636 [CTD_Seabird](INFO): created writer for : sea_water_density
2020-10-06T15:29:54.637Z,1601998194.637 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2020-10-06T15:29:54.642Z,1601998194.642 [CTD_Seabird](INFO): created writer for : depth
2020-10-06T15:29:54.642Z,1601998194.642 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2020-10-06T15:29:54.647Z,1601998194.647 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2020-10-06T15:29:54.648Z,1601998194.648 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2020-10-06T15:29:54.653Z,1601998194.653 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2020-10-06T15:29:54.653Z,1601998194.653 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2020-10-06T15:29:54.658Z,1601998194.658 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2020-10-06T15:29:54.659Z,1601998194.659 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2020-10-06T15:29:54.665Z,1601998194.665 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2020-10-06T15:29:54.666Z,1601998194.666 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2020-10-06T15:29:54.671Z,1601998194.671 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2020-10-06T15:29:54.698Z,1601998194.698 [CTD_Seabird] Loaded
2020-10-06T15:29:54.698Z,1601998194.698 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2020-10-06T15:29:54.699Z,1601998194.699 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B354E0
2020-10-06T15:29:54.700Z,1601998194.700 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 679
2020-10-06T15:29:54.749Z,1601998194.749 [ESPComponent] Loaded
2020-10-06T15:29:54.749Z,1601998194.749 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2020-10-06T15:29:54.770Z,1601998194.770 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470
2020-10-06T15:29:54.770Z,1601998194.770 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470
2020-10-06T15:29:54.774Z,1601998194.774 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650
2020-10-06T15:29:54.775Z,1601998194.775 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650
2020-10-06T15:29:54.779Z,1601998194.779 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl
2020-10-06T15:29:54.779Z,1601998194.779 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl
2020-10-06T15:29:54.783Z,1601998194.783 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm
2020-10-06T15:29:54.783Z,1601998194.783 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm
2020-10-06T15:29:54.787Z,1601998194.787 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm
2020-10-06T15:29:54.787Z,1601998194.787 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm
2020-10-06T15:29:54.791Z,1601998194.791 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm
2020-10-06T15:29:54.792Z,1601998194.792 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm
2020-10-06T15:29:54.796Z,1601998194.796 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm
2020-10-06T15:29:54.796Z,1601998194.796 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm
2020-10-06T15:29:54.800Z,1601998194.800 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water
2020-10-06T15:29:54.804Z,1601998194.804 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water
2020-10-06T15:29:54.805Z,1601998194.805 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-10-06T15:29:54.805Z,1601998194.805 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-10-06T15:29:54.809Z,1601998194.809 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-10-06T15:29:54.809Z,1601998194.809 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-10-06T15:29:54.813Z,1601998194.813 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-10-06T15:29:54.814Z,1601998194.814 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-10-06T15:29:54.818Z,1601998194.818 [WetLabsBB2FL] Loaded
2020-10-06T15:29:54.818Z,1601998194.818 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2020-10-06T15:29:54.819Z,1601998194.819 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B654E0
2020-10-06T15:29:54.820Z,1601998194.820 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 680
2020-10-06T15:29:54.820Z,1601998194.820 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-10-06T15:29:54.824Z,1601998194.824 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-10-06T15:29:54.825Z,1601998194.825 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-10-06T15:29:54.831Z,1601998194.831 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-10-06T15:29:54.832Z,1601998194.832 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B954E0
2020-10-06T15:29:54.833Z,1601998194.833 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 681
2020-10-06T15:29:54.837Z,1601998194.837 [Supervisor](INFO): Main Thread ID is 588
2020-10-06T15:29:54.838Z,1601998194.838 [Supervisor](DEBUG): Running supervisor.
2020-10-06T15:29:54.838Z,1601998194.838 [CommandLine ThreadHandler](INFO): Handler Thread ID is 682
2020-10-06T15:29:54.841Z,1601998194.841 [controlThread ThreadHandler](INFO): Handler Thread ID is 683
2020-10-06T15:29:54.841Z,1601998194.841 [controlThread](DEBUG): Initializing ControlThread
2020-10-06T15:29:54.842Z,1601998194.842 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-10-06T15:29:54.844Z,1601998194.844 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-10-06T15:29:54.844Z,1601998194.844 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-10-06T15:29:54.845Z,1601998194.845 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-10-06T15:29:54.845Z,1601998194.845 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-10-06T15:29:54.846Z,1601998194.846 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-10-06T15:29:54.846Z,1601998194.846 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-10-06T15:29:54.846Z,1601998194.846 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-10-06T15:29:54.847Z,1601998194.847 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-10-06T15:29:54.847Z,1601998194.847 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-10-06T15:29:54.854Z,1601998194.854 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-10-06T15:29:54.854Z,1601998194.854 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-10-06T15:29:54.854Z,1601998194.854 [SBIT](INFO): Initialize SBIT Component.
2020-10-06T15:29:54.855Z,1601998194.855 [SBIT](IMPORTANT): git: 2020-09-25-4-g3c79f45c2
2020-10-06T15:29:54.855Z,1601998194.855 [SBIT](INFO): git hash: 3c79f45c23e378871ed20937b122d1fb360f9687
2020-10-06T15:29:54.855Z,1601998194.855 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-10-06T15:29:54.856Z,1601998194.856 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
Kernel Reported: #1 PREEMPT Thu Aug 6 12:04:53 PDT 2020
2020-10-06T15:29:54.857Z,1601998194.857 [SBIT](INFO): Beginning SBIT in 99.000000 seconds.
2020-10-06T15:29:54.858Z,1601998194.858 [IBIT](INFO): Initialize IBIT Component.
2020-10-06T15:29:54.859Z,1601998194.859 [CBIT](DEBUG): Initialize CBIT Component.
2020-10-06T15:29:54.860Z,1601998194.860 [logger ThreadHandler](INFO): Handler Thread ID is 684
2020-10-06T15:29:54.872Z,1601998194.872 [CBIT](DEBUG): Initialized mux pins.
2020-10-06T15:29:54.872Z,1601998194.872 [CBIT](DEBUG): Initializing the watchdog timer.
2020-10-06T15:29:54.881Z,1601998194.881 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 685
2020-10-06T15:29:54.882Z,1601998194.882 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-10-06T15:29:54.893Z,1601998194.893 [Onboard ThreadHandler](INFO): Handler Thread ID is 686
2020-10-06T15:29:54.897Z,1601998194.897 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-10-06T15:29:54.897Z,1601998194.897 [CBIT](DEBUG): Initializing heartbeat.
2020-10-06T15:29:54.915Z,1601998194.915 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 687
2020-10-06T15:29:54.929Z,1601998194.929 [BackSeatDriver ThreadHandler](INFO): Handler Thread ID is 688
2020-10-06T15:29:54.933Z,1601998194.933 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 689
2020-10-06T15:29:54.933Z,1601998194.933 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-10-06T15:29:54.938Z,1601998194.938 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 691
2020-10-06T15:29:54.939Z,1601998194.939 [WetLabsBB2FL](INFO): Powering down
2020-10-06T15:29:54.965Z,1601998194.965 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 692
2020-10-06T15:29:54.968Z,1601998194.968 [CBIT](DEBUG): Deactivating GF circuits.
2020-10-06T15:29:54.968Z,1601998194.968 [CBIT](DEBUG): Deactivating emergency mode.
2020-10-06T15:29:54.971Z,1601998194.971 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-10-06T15:29:54.971Z,1601998194.971 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-10-06T15:29:54.971Z,1601998194.971 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-10-06T15:29:54.971Z,1601998194.971 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-10-06T15:29:54.972Z,1601998194.972 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-10-06T15:29:54.972Z,1601998194.972 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-10-06T15:29:54.972Z,1601998194.972 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-10-06T15:29:54.972Z,1601998194.972 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-10-06T15:29:54.972Z,1601998194.972 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-10-06T15:29:54.972Z,1601998194.972 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-10-06T15:29:54.973Z,1601998194.973 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-10-06T15:29:54.973Z,1601998194.973 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-10-06T15:29:54.973Z,1601998194.973 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-10-06T15:29:54.973Z,1601998194.973 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-10-06T15:29:54.973Z,1601998194.973 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-10-06T15:29:54.973Z,1601998194.973 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-10-06T15:29:55.004Z,1601998195.004 [CBIT](DEBUG): Backplane powered.
2020-10-06T15:29:55.006Z,1601998195.006 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-10-06T15:29:55.021Z,1601998195.021 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-10-06T15:29:55.032Z,1601998195.032 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="StartupSatComms">

        <Sequence/>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2020-10-06T15:29:55.032Z,1601998195.032 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-10-06T15:29:55.096Z,1601998195.096 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-10-06T15:29:55.117Z,1601998195.117 [Default:A.Wait](DEBUG): Construct Wait.
2020-10-06T15:29:55.119Z,1601998195.119 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-10-06T15:29:55.145Z,1601998195.145 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-10-06T15:29:55.148Z,1601998195.148 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-10-06T15:29:55.169Z,1601998195.169 [Default:E.Execute](DEBUG): Construct Execute.
2020-10-06T15:29:55.172Z,1601998195.172 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

    <DefineArg Name="ElapsedSinceDefaultStarted"><Units:minute/><Value>0</Value></DefineArg>

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn on</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

    <Guidance:Execute>
        <Sequence/>
        <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
    </Guidance:Execute>

</Mission>


2020-10-06T15:29:55.188Z,1601998195.188 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,NAL9602,DAT,BPC1,ESPComponent,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2020-10-06T15:29:55.212Z,1601998195.212 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-10-06T15:29:55.333Z,1601998195.333 [Radio_Surface](INFO): Powering up
2020-10-06T15:29:55.387Z,1601998195.387 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2020-10-06T15:29:55.387Z,1601998195.387 [DAT](INFO): Powering up
2020-10-06T15:29:55.387Z,1601998195.387 [DAT](DEBUG): Initializing DAT.
2020-10-06T15:29:55.461Z,1601998195.461 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-10-06T15:29:55.462Z,1601998195.462 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2020-10-06T15:29:55.486Z,1601998195.486 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-10-06T15:29:55.493Z,1601998195.493 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-10-06T15:29:55.502Z,1601998195.502 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-10-06T15:29:55.504Z,1601998195.504 [MassServo](DEBUG): Initializing EZServoServo.
2020-10-06T15:29:55.513Z,1601998195.513 [MassServo](DEBUG): Initializing MassServo.
2020-10-06T15:29:55.514Z,1601998195.514 [RudderServo](DEBUG): Initializing EZServoServo.
2020-10-06T15:29:55.524Z,1601998195.524 [RudderServo](DEBUG): Initializing RudderServo.
2020-10-06T15:29:55.525Z,1601998195.525 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-10-06T15:29:55.533Z,1601998195.533 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-10-06T15:30:04.973Z,1601998204.973 [CBIT](CRITICAL): Environmental Failure. Press:14.465884 PSI. Humidity:51%. Temp:21 C. ABORTING MISSION
2020-10-06T15:30:04.974Z,1601998204.974 [Supervisor](INFO): Stop Mission called by CBIT::monitorEnvironmentals
2020-10-06T15:30:05.362Z,1601998205.362 [CommandLine](FAULT): Scheduling is paused
2020-10-06T15:30:05.363Z,1601998205.363 [CBIT](INFO): Critical error at 20201006T153004
2020-10-06T15:30:08.343Z,1601998208.343 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004809
2020-10-06T15:30:09.344Z,1601998209.344 [DAT](INFO): commRate: 800
2020-10-06T15:30:11.362Z,1601998211.362 [DAT](INFO): entering command mode
2020-10-06T15:30:11.766Z,1601998211.766 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-06T15:30:12.170Z,1601998212.170 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-06T15:30:12.580Z,1601998212.580 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-06T15:30:12.978Z,1601998212.978 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-06T15:30:13.382Z,1601998213.382 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-06T15:30:13.786Z,1601998213.786 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-06T15:30:14.190Z,1601998214.190 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-06T15:30:14.594Z,1601998214.594 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-06T15:30:14.998Z,1601998214.998 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-06T15:30:15.402Z,1601998215.402 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-06T15:30:15.806Z,1601998215.806 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-06T15:30:16.210Z,1601998216.210 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-06T15:30:16.614Z,1601998216.614 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-06T15:30:17.018Z,1601998217.018 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-06T15:30:17.422Z,1601998217.422 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-06T15:30:17.826Z,1601998217.826 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-06T15:30:18.230Z,1601998218.230 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-06T15:30:18.634Z,1601998218.634 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-06T15:30:19.038Z,1601998219.038 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-06T15:30:19.442Z,1601998219.442 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-06T15:30:19.846Z,1601998219.846 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-06T15:30:20.250Z,1601998220.250 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-06T15:30:20.650Z,1601998220.650 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-06T15:30:21.054Z,1601998221.054 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-06T15:30:21.466Z,1601998221.466 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-06T15:30:21.866Z,1601998221.866 [NAL9602](INFO): Powering up NAL9602
2020-10-06T15:30:21.867Z,1601998221.867 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-06T15:30:22.271Z,1601998222.271 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-06T15:30:22.671Z,1601998222.671 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-06T15:30:23.074Z,1601998223.074 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-06T15:30:23.486Z,1601998223.486 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-06T15:30:23.890Z,1601998223.890 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-06T15:30:24.290Z,1601998224.290 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-06T15:30:24.690Z,1601998224.690 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-06T15:30:25.098Z,1601998225.098 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-06T15:30:25.502Z,1601998225.502 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-06T15:30:25.902Z,1601998225.902 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-06T15:30:26.310Z,1601998226.310 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-06T15:30:26.343Z,1601998226.343 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:30:26.714Z,1601998226.714 [DAT](DEBUG): checking for command mode acknowledgment
2020-10-06T15:30:26.714Z,1601998226.714 [DAT](FAULT): failed to enter command mode
2020-10-06T15:30:27.118Z,1601998227.118 [DAT](INFO): entering command mode
2020-10-06T15:30:27.530Z,1601998227.530 [DAT](INFO): setting verbose to 3
2020-10-06T15:30:27.935Z,1601998227.935 [DAT](INFO): set verbose to 3
2020-10-06T15:30:27.935Z,1601998227.935 [DAT](INFO): setting DatVerbose to 27440
2020-10-06T15:30:28.009Z,1601998228.009 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:30:28.327Z,1601998228.327 [DAT](INFO): set DatVerbose to 27440
2020-10-06T15:30:28.327Z,1601998228.327 [DAT](INFO): setting transmit power to 8
2020-10-06T15:30:28.735Z,1601998228.735 [DAT](INFO): set transmit power to 8
2020-10-06T15:30:28.735Z,1601998228.735 [DAT](INFO): setting local address to 9
2020-10-06T15:30:29.151Z,1601998229.151 [DAT](INFO): set local address to 9
2020-10-06T15:30:32.842Z,1601998232.842 [NAL9602](INFO): NAL9602 initialized
2020-10-06T15:30:58.692Z,1601998258.692 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:31:00.699Z,1601998260.699 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:31:31.376Z,1601998291.376 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:31:33.053Z,1601998293.053 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:31:34.610Z,1601998294.610 [SBIT](IMPORTANT): Beginning Startup BIT
2020-10-06T15:31:34.614Z,1601998294.614 [CBIT](IMPORTANT): Beginning ground fault scan
2020-10-06T15:31:45.593Z,1601998305.593 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.011452
CHAN A1 (24V): 0.004693
CHAN A2 (12V): -0.006952
CHAN A3 (5V): -0.003476
CHAN B0 (3.3V): -0.000051
CHAN B1 (3.15aV): -0.000540
CHAN B2 (3.15bV): 0.000016
CHAN B3 (GND): 0.002051
OPEN: 0.005120
Full Scale Calc: 4.765 mA, -1.589 mA
2020-10-06T15:32:03.707Z,1601998323.707 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:32:05.361Z,1601998325.361 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:32:28.750Z,1601998348.750 [SBIT](IMPORTANT): SBIT PASSED
2020-10-06T15:32:28.750Z,1601998348.750 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-10-06T15:32:28.751Z,1601998348.751 [SBIT](IMPORTANT): AMEcho.loadAtStartup=0 bool;

2020-10-06T15:32:28.751Z,1601998348.751 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=6 count;

2020-10-06T15:32:28.753Z,1601998348.753 [SBIT](IMPORTANT): BuoyancyServo.loadAtStartup=1 bool;

2020-10-06T15:32:28.753Z,1601998348.753 [SBIT](IMPORTANT): CBIT.gf24Offset=145 microampere;

2020-10-06T15:32:28.754Z,1601998348.754 [SBIT](IMPORTANT): DAT.loadAtStartup=1 bool;

2020-10-06T15:32:28.754Z,1601998348.754 [SBIT](IMPORTANT): DAT.sbdAddress=-1 enum;

2020-10-06T15:32:28.754Z,1601998348.754 [SBIT](IMPORTANT): DAT.surfaceThreshold=100 meter;

2020-10-06T15:32:28.754Z,1601998348.754 [SBIT](IMPORTANT): DAT.verbosity=0 count;

2020-10-06T15:32:28.754Z,1601998348.754 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2020-10-06T15:32:28.754Z,1601998348.754 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=1 bool;

2020-10-06T15:32:28.754Z,1601998348.754 [SBIT](IMPORTANT): Express linearApproximation AMEcho.altitude 1.000000 meter;

2020-10-06T15:32:28.754Z,1601998348.754 [SBIT](IMPORTANT): Express none ThrusterServo.component_avgCurrent;

2020-10-06T15:32:28.754Z,1601998348.754 [SBIT](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 1.000000 liter_per_second;

2020-10-06T15:32:28.754Z,1601998348.754 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 5.000000 meter;

2020-10-06T15:32:28.755Z,1601998348.755 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=13 volt;

2020-10-06T15:32:28.755Z,1601998348.755 [SBIT](IMPORTANT): RDI_Pathfinder.loadAtStartup=0 bool;

2020-10-06T15:32:28.755Z,1601998348.755 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=170 cubic_centimeter;

2020-10-06T15:32:28.755Z,1601998348.755 [SBIT](IMPORTANT): VerticalControl.massDefault=20 millimeter;

2020-10-06T15:32:28.755Z,1601998348.755 [SBIT](IMPORTANT): WetLabsUBAT.loadAtStartup=0 bool;

2020-10-06T15:32:29.160Z,1601998349.160 [MissionManager](IMPORTANT): Started mission Startup
2020-10-06T15:32:29.160Z,1601998349.160 [Startup] Running Loop=1
2020-10-06T15:32:29.160Z,1601998349.160 [Startup](DEBUG): Aggregate::initialize Startup
2020-10-06T15:32:29.160Z,1601998349.160 [Startup:A.GoToSurface] Running Loop=1
2020-10-06T15:32:29.161Z,1601998349.161 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-10-06T15:32:29.161Z,1601998349.161 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-10-06T15:32:29.162Z,1601998349.162 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-10-06T15:32:29.162Z,1601998349.162 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-10-06T15:32:29.162Z,1601998349.162 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-10-06T15:32:29.163Z,1601998349.163 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-10-06T15:32:29.166Z,1601998349.166 [Startup:StartupSatComms] Running Loop=1
2020-10-06T15:32:29.166Z,1601998349.166 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-10-06T15:32:29.166Z,1601998349.166 [Startup:StartupSatComms:A] Running Loop=1
2020-10-06T15:32:29.550Z,1601998349.550 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-10-06T15:32:36.047Z,1601998356.047 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:32:37.713Z,1601998357.713 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:32:54.000Z,1601998375.000 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2020-10-06T15:32:54.000Z,1601998375.000 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-10-06T15:32:55.009Z,1601998375.009 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-10-06T15:32:55.438Z,1601998375.438 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-10-06T15:32:55.439Z,1601998375.439 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2020-10-06T15:33:08.351Z,1601998388.351 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:33:10.393Z,1601998390.393 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:33:29.366Z,1601998409.366 [Startup:StartupSatComms:A](INFO): Timed out from 2020-10-06T15:32:29.2Z
2020-10-06T15:33:29.366Z,1601998409.366 [Startup:StartupSatComms:A] Stopped
2020-10-06T15:33:29.366Z,1601998409.366 [Startup:StartupSatComms:B] Running Loop=1
2020-10-06T15:33:29.771Z,1601998409.771 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-10-06T15:33:35.138Z,1601998415.138 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201005T195822/Courier0010.lzma
2020-10-06T15:33:36.138Z,1601998416.138 [DataOverHttps](INFO): Moved sent file to Logs/20201005T195822/Courier0010.lzma.bak
2020-10-06T15:33:36.138Z,1601998416.138 [DataOverHttps](INFO): SBD MOMSN=12795897
2020-10-06T15:33:41.118Z,1601998421.118 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:33:43.153Z,1601998423.153 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:33:51.481Z,1601998431.481 [DataOverHttps](INFO): Sending 250 bytes from file Logs/20201006T152943/Courier0000.lzma
2020-10-06T15:33:52.478Z,1601998432.478 [DataOverHttps](INFO): Moved sent file to Logs/20201006T152943/Courier0000.lzma.bak
2020-10-06T15:33:52.478Z,1601998432.478 [DataOverHttps](INFO): SBD MOMSN=12795899
2020-10-06T15:34:10.441Z,1601998450.441 [DataOverHttps](INFO): Sending 194 bytes from file Logs/20201005T195822/Express0011.lzma
2020-10-06T15:34:11.442Z,1601998451.442 [DataOverHttps](INFO): Moved sent file to Logs/20201005T195822/Express0011.lzma.bak
2020-10-06T15:34:11.442Z,1601998451.442 [DataOverHttps](INFO): SBD MOMSN=12795903
2020-10-06T15:34:13.846Z,1601998453.846 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:34:15.897Z,1601998455.897 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:34:27.555Z,1601998467.555 [DataOverHttps](INFO): Sending 1184 bytes from file Logs/20201006T152943/Express0001.lzma
2020-10-06T15:34:28.554Z,1601998468.554 [DataOverHttps](INFO): Moved sent file to Logs/20201006T152943/Express0001.lzma.bak
2020-10-06T15:34:28.555Z,1601998468.555 [DataOverHttps](INFO): SBD MOMSN=12795921
2020-10-06T15:34:29.609Z,1601998469.609 [Startup:StartupSatComms:B](INFO): Timed out from 2020-10-06T15:33:29.4Z
2020-10-06T15:34:29.609Z,1601998469.609 [Startup:StartupSatComms:B] Stopped
2020-10-06T15:34:29.609Z,1601998469.609 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2020-10-06T15:34:29.609Z,1601998469.609 [Startup:StartupSatComms] Stopped
2020-10-06T15:34:29.609Z,1601998469.609 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-10-06T15:34:29.610Z,1601998469.610 [Startup](INFO): Completed Startup
2020-10-06T15:34:29.610Z,1601998469.610 [MissionManager](INFO): Startup is completed.
2020-10-06T15:34:29.610Z,1601998469.610 [MissionManager](INFO): Uninitializing Mission Startup
2020-10-06T15:34:29.610Z,1601998469.610 [Startup] Stopped
2020-10-06T15:34:29.610Z,1601998469.610 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-10-06T15:34:29.611Z,1601998469.611 [Startup:A.GoToSurface] Stopped
2020-10-06T15:34:29.611Z,1601998469.611 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-10-06T15:34:29.993Z,1601998469.993 [MissionManager](IMPORTANT): Started mission Default
2020-10-06T15:34:29.993Z,1601998469.993 [Default] Running Loop=1
2020-10-06T15:34:29.993Z,1601998469.993 [Default](DEBUG): Aggregate::initialize Default
2020-10-06T15:34:29.993Z,1601998469.993 [Default:B.GoToSurface] Running Loop=1
2020-10-06T15:34:29.993Z,1601998469.993 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-10-06T15:34:29.994Z,1601998469.994 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-10-06T15:34:29.994Z,1601998469.994 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-10-06T15:34:29.994Z,1601998469.994 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-10-06T15:34:29.995Z,1601998469.995 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-10-06T15:34:29.995Z,1601998469.995 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-10-06T15:34:29.995Z,1601998469.995 [Default:A.Wait] Running Loop=1
2020-10-06T15:34:29.995Z,1601998469.995 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-10-06T15:34:43.332Z,1601998483.332 [Default:A.Wait](INFO): Done Waiting.
2020-10-06T15:34:43.332Z,1601998483.332 [Default:A.Wait] Stopped
2020-10-06T15:34:43.332Z,1601998483.332 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-10-06T15:34:43.723Z,1601998483.723 [Default:CheckIn] Running Loop=1
2020-10-06T15:34:43.723Z,1601998483.723 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-10-06T15:34:43.724Z,1601998483.724 [Default:CheckIn:Read_GPS] Running Loop=1
2020-10-06T15:34:44.122Z,1601998484.122 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-10-06T15:34:46.554Z,1601998486.554 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:34:48.237Z,1601998488.237 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:35:18.918Z,1601998518.918 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:35:20.925Z,1601998520.925 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:35:35.830Z,1601998535.830 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-10-06T15:35:51.618Z,1601998551.618 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:35:53.268Z,1601998553.268 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:35:56.052Z,1601998556.052 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2020-10-06T15:35:56.052Z,1601998556.052 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-10-06T15:35:56.063Z,1601998556.063 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-10-06T15:35:56.469Z,1601998556.469 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-10-06T15:35:56.469Z,1601998556.469 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2020-10-06T15:36:23.954Z,1601998583.954 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:36:25.645Z,1601998585.645 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:36:56.266Z,1601998616.266 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:36:58.309Z,1601998618.309 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:37:19.685Z,1601998639.685 [BPC1](INFO): Calculating totals. Valid battery stick count: 50. Valid reserve battery stick count: 6.
2020-10-06T15:37:19.688Z,1601998639.688 [BPC1](INFO): Received data from all battery sticks.
2020-10-06T15:37:28.978Z,1601998648.978 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:37:30.605Z,1601998650.605 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:38:01.278Z,1601998681.278 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:38:02.941Z,1601998682.941 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:38:33.603Z,1601998713.603 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:38:35.268Z,1601998715.268 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:38:57.023Z,1601998737.023 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2020-10-06T15:38:57.024Z,1601998737.024 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-10-06T15:38:57.065Z,1601998737.065 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-10-06T15:38:57.437Z,1601998737.437 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-10-06T15:38:57.437Z,1601998737.437 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2020-10-06T15:39:05.934Z,1601998745.934 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:39:07.981Z,1601998747.981 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:39:38.641Z,1601998778.641 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:39:40.313Z,1601998780.313 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:39:43.904Z,1601998783.904 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-10-06T15:34:43.7Z
2020-10-06T15:39:43.904Z,1601998783.904 [Default:CheckIn:Read_GPS] Stopped
2020-10-06T15:39:43.904Z,1601998783.904 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-10-06T15:39:44.299Z,1601998784.299 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-10-06T15:39:51.252Z,1601998791.252 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20201006T152943/Courier0004.lzma
2020-10-06T15:39:52.254Z,1601998792.254 [DataOverHttps](INFO): Moved sent file to Logs/20201006T152943/Courier0004.lzma.bak
2020-10-06T15:39:52.254Z,1601998792.254 [DataOverHttps](INFO): SBD MOMSN=12795980
2020-10-06T15:40:09.260Z,1601998809.260 [DataOverHttps](INFO): Sending 305 bytes from file Logs/20201006T152943/Express0005.lzma
2020-10-06T15:40:10.262Z,1601998810.262 [DataOverHttps](INFO): Moved sent file to Logs/20201006T152943/Express0005.lzma.bak
2020-10-06T15:40:10.262Z,1601998810.262 [DataOverHttps](INFO): SBD MOMSN=12795984
2020-10-06T15:40:10.986Z,1601998810.986 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:40:13.013Z,1601998813.013 [Default:CheckIn:Read_Iridium] Stopped
2020-10-06T15:40:13.013Z,1601998813.013 [Default:CheckIn:C.Wait] Running Loop=1
2020-10-06T15:40:13.013Z,1601998813.013 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-10-06T15:40:13.053Z,1601998813.053 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:40:34.786Z,1601998834.786 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-10-06T15:40:34.786Z,1601998834.786 [NAL9602] Data Fault, FailCount= 1
2020-10-06T15:40:34.786Z,1601998834.786 [NAL9602](ERROR): Data Fault
2020-10-06T15:40:34.801Z,1601998834.801 [CBIT](ERROR): Data Fault in component: NAL9602
2020-10-06T15:40:35.186Z,1601998835.186 [NAL9602](INFO): Powering down
2020-10-06T15:40:36.014Z,1601998836.014 [CBIT](INFO): Clearing failed state for component NAL9602
2020-10-06T15:40:36.014Z,1601998836.014 [NAL9602] No Fault, FailCount= 1
2020-10-06T15:40:43.685Z,1601998843.685 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:40:45.325Z,1601998845.325 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:41:05.493Z,1601998865.493 [NAL9602](INFO): Powering up NAL9602
2020-10-06T15:41:16.038Z,1601998876.038 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:41:16.399Z,1601998876.399 [NAL9602](INFO): NAL9602 initialized
2020-10-06T15:41:17.685Z,1601998877.685 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:41:48.325Z,1601998908.325 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:41:49.969Z,1601998909.969 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:41:58.016Z,1601998918.016 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2020-10-06T15:41:58.016Z,1601998918.016 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-10-06T15:41:58.048Z,1601998918.048 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-10-06T15:41:58.457Z,1601998918.457 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-10-06T15:41:58.457Z,1601998918.457 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2020-10-06T15:42:20.649Z,1601998940.649 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:42:22.709Z,1601998942.709 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:42:53.390Z,1601998973.390 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:42:55.429Z,1601998975.429 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:43:26.089Z,1601999006.089 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:43:27.737Z,1601999007.737 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:43:58.434Z,1601999038.434 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:44:00.474Z,1601999040.474 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:44:31.149Z,1601999071.149 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:44:32.801Z,1601999072.801 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:44:59.026Z,1601999099.026 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2020-10-06T15:44:59.026Z,1601999099.026 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-10-06T15:44:59.036Z,1601999099.036 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-10-06T15:44:59.437Z,1601999099.437 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-10-06T15:44:59.437Z,1601999099.437 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2020-10-06T15:45:03.458Z,1601999103.458 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:45:05.113Z,1601999105.113 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:45:13.563Z,1601999113.563 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-10-06T15:45:13.563Z,1601999113.563 [Default:CheckIn:C.Wait] Stopped
2020-10-06T15:45:13.563Z,1601999113.563 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-10-06T15:45:13.563Z,1601999113.563 [Default:CheckIn:D] Running Loop=1
2020-10-06T15:45:13.978Z,1601999113.978 [Default:CheckIn:D] Stopped
2020-10-06T15:45:13.978Z,1601999113.978 [Default:CheckIn:E] Running Loop=1
2020-10-06T15:45:14.377Z,1601999114.377 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.733082 min 
2020-10-06T15:45:14.377Z,1601999114.377 [Default:CheckIn:E] Stopped
2020-10-06T15:45:14.377Z,1601999114.377 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-10-06T15:45:14.377Z,1601999114.377 [Default:CheckIn] Stopped
2020-10-06T15:45:14.377Z,1601999114.377 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-10-06T15:45:14.377Z,1601999114.377 [Default:CheckIn](INFO): Running loop #2
2020-10-06T15:45:14.378Z,1601999114.378 [Default:CheckIn] Running Loop=2
2020-10-06T15:45:14.378Z,1601999114.378 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-10-06T15:45:14.378Z,1601999114.378 [Default:CheckIn:Read_GPS] Running Loop=1
2020-10-06T15:45:35.800Z,1601999135.800 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:45:37.821Z,1601999137.821 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:46:08.511Z,1601999168.511 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:46:10.549Z,1601999170.549 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:46:41.236Z,1601999201.236 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:46:42.881Z,1601999202.881 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:47:13.567Z,1601999233.567 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:47:15.185Z,1601999235.185 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:47:45.874Z,1601999265.874 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:47:47.528Z,1601999267.528 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:47:59.996Z,1601999279.996 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2020-10-06T15:47:59.996Z,1601999279.996 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-10-06T15:48:00.030Z,1601999280.030 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-10-06T15:48:00.444Z,1601999280.444 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-10-06T15:48:00.444Z,1601999280.444 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2020-10-06T15:48:18.219Z,1601999298.219 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:48:20.233Z,1601999300.233 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:48:50.922Z,1601999330.922 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:48:52.569Z,1601999332.569 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:49:23.280Z,1601999363.280 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:49:25.305Z,1601999365.305 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:49:55.991Z,1601999395.991 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:49:58.029Z,1601999398.029 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:50:14.566Z,1601999414.566 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-10-06T15:45:14.4Z
2020-10-06T15:50:14.566Z,1601999414.566 [Default:CheckIn:Read_GPS] Stopped
2020-10-06T15:50:14.566Z,1601999414.566 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-10-06T15:50:21.312Z,1601999421.312 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201006T152943/Courier0007.lzma
2020-10-06T15:50:22.314Z,1601999422.314 [DataOverHttps](INFO): Moved sent file to Logs/20201006T152943/Courier0007.lzma.bak
2020-10-06T15:50:22.315Z,1601999422.315 [DataOverHttps](INFO): SBD MOMSN=12796080
2020-10-06T15:50:28.719Z,1601999428.719 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:50:30.357Z,1601999430.357 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:50:45.072Z,1601999445.072 [DataOverHttps](INFO): Sending 342 bytes from file Logs/20201006T152943/Express0008.lzma
2020-10-06T15:50:46.074Z,1601999446.074 [DataOverHttps](INFO): Moved sent file to Logs/20201006T152943/Express0008.lzma.bak
2020-10-06T15:50:46.074Z,1601999446.074 [DataOverHttps](INFO): SBD MOMSN=12796089
2020-10-06T15:50:49.325Z,1601999449.325 [Default:CheckIn:Read_Iridium] Stopped
2020-10-06T15:50:49.325Z,1601999449.325 [Default:CheckIn:C.Wait] Running Loop=1
2020-10-06T15:50:49.325Z,1601999449.325 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-10-06T15:51:01.020Z,1601999461.020 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2020-10-06T15:51:01.020Z,1601999461.020 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-10-06T15:51:01.030Z,1601999461.030 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:51:01.063Z,1601999461.063 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-10-06T15:51:01.457Z,1601999461.457 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-10-06T15:51:01.457Z,1601999461.457 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2020-10-06T15:51:02.679Z,1601999462.679 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:51:18.386Z,1601999478.386 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-10-06T15:51:18.386Z,1601999478.386 [NAL9602] Data Fault, FailCount= 2
2020-10-06T15:51:18.386Z,1601999478.386 [NAL9602](ERROR): Data Fault
2020-10-06T15:51:18.402Z,1601999478.402 [CBIT](ERROR): Data Fault in component: NAL9602
2020-10-06T15:51:18.795Z,1601999478.795 [NAL9602](INFO): Powering down
2020-10-06T15:51:19.617Z,1601999479.617 [CBIT](INFO): Clearing failed state for component NAL9602
2020-10-06T15:51:19.617Z,1601999479.617 [NAL9602] No Fault, FailCount= 2
2020-10-06T15:51:33.353Z,1601999493.353 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:51:35.397Z,1601999495.397 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:51:49.090Z,1601999509.090 [NAL9602](INFO): Powering up NAL9602
2020-10-06T15:52:00.003Z,1601999520.003 [NAL9602](INFO): NAL9602 initialized
2020-10-06T15:52:06.086Z,1601999526.086 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:52:08.117Z,1601999528.117 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:52:38.818Z,1601999558.818 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:52:40.461Z,1601999560.461 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:53:11.145Z,1601999591.145 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:53:12.761Z,1601999592.761 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:53:43.445Z,1601999623.445 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:53:45.101Z,1601999625.101 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:54:02.032Z,1601999642.032 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2020-10-06T15:54:02.032Z,1601999642.032 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-10-06T15:54:02.071Z,1601999642.071 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-10-06T15:54:02.453Z,1601999642.453 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-10-06T15:54:02.453Z,1601999642.453 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2020-10-06T15:54:15.771Z,1601999655.771 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:54:17.409Z,1601999657.409 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:54:48.102Z,1601999688.102 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:54:49.733Z,1601999689.733 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:55:20.402Z,1601999720.402 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:55:22.065Z,1601999722.065 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:55:49.889Z,1601999749.889 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-10-06T15:55:49.889Z,1601999749.889 [Default:CheckIn:C.Wait] Stopped
2020-10-06T15:55:49.889Z,1601999749.889 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-10-06T15:55:49.889Z,1601999749.889 [Default:CheckIn:D] Running Loop=1
2020-10-06T15:55:50.305Z,1601999750.305 [Default:CheckIn:D] Stopped
2020-10-06T15:55:50.305Z,1601999750.305 [Default:CheckIn:E] Running Loop=1
2020-10-06T15:55:50.718Z,1601999750.718 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.338536 min 
2020-10-06T15:55:50.718Z,1601999750.718 [Default:CheckIn:E] Stopped
2020-10-06T15:55:50.718Z,1601999750.718 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-10-06T15:55:50.718Z,1601999750.718 [Default:CheckIn] Stopped
2020-10-06T15:55:50.718Z,1601999750.718 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-10-06T15:55:50.718Z,1601999750.718 [Default:CheckIn](INFO): Running loop #3
2020-10-06T15:55:50.718Z,1601999750.718 [Default:CheckIn] Running Loop=3
2020-10-06T15:55:50.718Z,1601999750.718 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-10-06T15:55:50.718Z,1601999750.718 [Default:CheckIn:Read_GPS] Running Loop=1
2020-10-06T15:55:52.729Z,1601999752.729 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:55:54.369Z,1601999754.369 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:56:25.050Z,1601999785.050 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:56:26.701Z,1601999786.701 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:56:57.365Z,1601999817.365 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:56:59.425Z,1601999819.425 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:57:03.038Z,1601999823.038 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2020-10-06T15:57:03.038Z,1601999823.038 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-10-06T15:57:03.049Z,1601999823.049 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-10-06T15:57:03.457Z,1601999823.457 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-10-06T15:57:03.457Z,1601999823.457 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2020-10-06T15:57:30.093Z,1601999850.093 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:57:32.153Z,1601999852.153 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:58:02.819Z,1601999882.819 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:58:04.465Z,1601999884.465 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:58:35.154Z,1601999915.154 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:58:36.777Z,1601999916.777 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:59:07.450Z,1601999947.450 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:59:09.121Z,1601999949.121 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:59:39.766Z,1601999979.766 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T15:59:41.453Z,1601999981.453 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:00:04.016Z,1602000004.016 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2020-10-06T16:00:04.016Z,1602000004.016 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-10-06T16:00:04.050Z,1602000004.050 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-10-06T16:00:04.433Z,1602000004.433 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-10-06T16:00:04.433Z,1602000004.433 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2020-10-06T16:00:12.102Z,1602000012.102 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:00:14.159Z,1602000014.159 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:00:44.830Z,1602000044.830 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:00:46.492Z,1602000046.492 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:00:50.869Z,1602000050.869 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-10-06T15:55:50.7Z
2020-10-06T16:00:50.869Z,1602000050.869 [Default:CheckIn:Read_GPS] Stopped
2020-10-06T16:00:50.869Z,1602000050.869 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-10-06T16:00:58.236Z,1602000058.236 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201006T152943/Courier0010.lzma
2020-10-06T16:00:59.238Z,1602000059.238 [DataOverHttps](INFO): Moved sent file to Logs/20201006T152943/Courier0010.lzma.bak
2020-10-06T16:00:59.239Z,1602000059.239 [DataOverHttps](INFO): SBD MOMSN=12796210
2020-10-06T16:01:17.174Z,1602000077.174 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:01:18.813Z,1602000078.813 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:01:25.832Z,1602000085.832 [DataOverHttps](INFO): Sending 320 bytes from file Logs/20201006T152943/Express0011.lzma
2020-10-06T16:01:26.838Z,1602000086.838 [DataOverHttps](INFO): Moved sent file to Logs/20201006T152943/Express0011.lzma.bak
2020-10-06T16:01:26.838Z,1602000086.838 [DataOverHttps](INFO): SBD MOMSN=12796220
2020-10-06T16:01:29.685Z,1602000089.685 [Default:CheckIn:Read_Iridium] Stopped
2020-10-06T16:01:29.685Z,1602000089.685 [Default:CheckIn:C.Wait] Running Loop=1
2020-10-06T16:01:29.686Z,1602000089.686 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-10-06T16:01:49.506Z,1602000109.506 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:01:51.565Z,1602000111.565 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:02:01.191Z,1602000121.191 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-10-06T16:02:01.191Z,1602000121.191 [NAL9602] Data Fault, FailCount= 3
2020-10-06T16:02:01.191Z,1602000121.191 [NAL9602](ERROR): Data Fault
2020-10-06T16:02:01.212Z,1602000121.212 [CBIT](ERROR): Data Fault in component: NAL9602
2020-10-06T16:02:01.589Z,1602000121.589 [NAL9602](INFO): Powering down
2020-10-06T16:02:02.421Z,1602000122.421 [CBIT](INFO): Clearing failed state for component NAL9602
2020-10-06T16:02:02.421Z,1602000122.421 [NAL9602] No Fault, FailCount= 3
2020-10-06T16:02:22.210Z,1602000142.210 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:02:24.265Z,1602000144.265 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:02:31.890Z,1602000151.890 [NAL9602](INFO): Powering up NAL9602
2020-10-06T16:02:42.794Z,1602000162.794 [NAL9602](INFO): NAL9602 initialized
2020-10-06T16:02:54.950Z,1602000174.950 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:02:56.977Z,1602000176.977 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:03:05.043Z,1602000185.043 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2020-10-06T16:03:05.043Z,1602000185.043 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-10-06T16:03:05.053Z,1602000185.053 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-10-06T16:03:05.459Z,1602000185.459 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-10-06T16:03:05.459Z,1602000185.459 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2020-10-06T16:03:27.678Z,1602000207.678 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:03:29.309Z,1602000209.309 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:03:59.986Z,1602000239.986 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:04:01.681Z,1602000241.681 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:04:32.298Z,1602000272.298 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:04:33.969Z,1602000273.969 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:05:04.613Z,1602000304.613 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:05:06.277Z,1602000306.277 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:05:36.958Z,1602000336.958 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:05:39.045Z,1602000339.045 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:06:06.028Z,1602000366.028 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2020-10-06T16:06:06.029Z,1602000366.029 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-10-06T16:06:06.039Z,1602000366.039 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-10-06T16:06:06.453Z,1602000366.453 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-10-06T16:06:06.453Z,1602000366.453 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2020-10-06T16:06:09.657Z,1602000369.657 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:06:11.309Z,1602000371.309 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:06:30.262Z,1602000390.262 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-10-06T16:06:30.263Z,1602000390.263 [Default:CheckIn:C.Wait] Stopped
2020-10-06T16:06:30.263Z,1602000390.263 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-10-06T16:06:30.263Z,1602000390.263 [Default:CheckIn:D] Running Loop=1
2020-10-06T16:06:30.658Z,1602000390.658 [Default:CheckIn:D] Stopped
2020-10-06T16:06:30.658Z,1602000390.658 [Default:CheckIn:E] Running Loop=1
2020-10-06T16:06:31.069Z,1602000391.069 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 32.011072 min 
2020-10-06T16:06:31.070Z,1602000391.070 [Default:CheckIn:E] Stopped
2020-10-06T16:06:31.070Z,1602000391.070 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-10-06T16:06:31.070Z,1602000391.070 [Default:CheckIn] Stopped
2020-10-06T16:06:31.070Z,1602000391.070 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-10-06T16:06:31.070Z,1602000391.070 [Default:CheckIn](INFO): Running loop #4
2020-10-06T16:06:31.070Z,1602000391.070 [Default:CheckIn] Running Loop=4
2020-10-06T16:06:31.070Z,1602000391.070 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-10-06T16:06:31.070Z,1602000391.070 [Default:CheckIn:Read_GPS] Running Loop=1
2020-10-06T16:06:41.999Z,1602000401.999 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:06:43.625Z,1602000403.625 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:07:14.323Z,1602000434.323 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:07:16.357Z,1602000436.357 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:07:47.027Z,1602000467.027 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:07:48.697Z,1602000468.697 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:08:19.341Z,1602000499.341 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:08:21.409Z,1602000501.409 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:08:52.094Z,1602000532.094 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:08:54.127Z,1602000534.127 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:09:07.025Z,1602000547.025 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2020-10-06T16:09:07.025Z,1602000547.025 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-10-06T16:09:07.035Z,1602000547.035 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-10-06T16:09:07.424Z,1602000547.424 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-10-06T16:09:07.424Z,1602000547.424 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2020-10-06T16:09:24.802Z,1602000564.802 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:09:26.461Z,1602000566.461 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:09:57.109Z,1602000597.109 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:09:58.757Z,1602000598.757 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:10:29.458Z,1602000629.458 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:10:31.103Z,1602000631.103 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:11:01.778Z,1602000661.778 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:11:03.394Z,1602000663.394 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:11:31.270Z,1602000691.270 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-10-06T16:06:31.1Z
2020-10-06T16:11:31.270Z,1602000691.270 [Default:CheckIn:Read_GPS] Stopped
2020-10-06T16:11:31.270Z,1602000691.270 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-10-06T16:11:34.097Z,1602000694.097 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:11:36.133Z,1602000696.133 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:11:38.892Z,1602000698.892 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20201006T152943/Courier0013.lzma
2020-10-06T16:11:39.894Z,1602000699.894 [DataOverHttps](INFO): Moved sent file to Logs/20201006T152943/Courier0013.lzma.bak
2020-10-06T16:11:39.894Z,1602000699.894 [DataOverHttps](INFO): SBD MOMSN=12796352
2020-10-06T16:11:56.772Z,1602000716.772 [DataOverHttps](INFO): Sending 313 bytes from file Logs/20201006T152943/Express0014.lzma
2020-10-06T16:11:57.774Z,1602000717.774 [DataOverHttps](INFO): Moved sent file to Logs/20201006T152943/Express0014.lzma.bak
2020-10-06T16:11:57.775Z,1602000717.775 [DataOverHttps](INFO): SBD MOMSN=12796362
2020-10-06T16:12:00.345Z,1602000720.345 [Default:CheckIn:Read_Iridium] Stopped
2020-10-06T16:12:00.345Z,1602000720.345 [Default:CheckIn:C.Wait] Running Loop=1
2020-10-06T16:12:00.346Z,1602000720.346 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-10-06T16:12:06.797Z,1602000726.797 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:12:08.017Z,1602000728.017 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2020-10-06T16:12:08.017Z,1602000728.017 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-10-06T16:12:08.044Z,1602000728.044 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-10-06T16:12:08.469Z,1602000728.469 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:12:08.473Z,1602000728.473 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-10-06T16:12:08.473Z,1602000728.473 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2020-10-06T16:12:39.144Z,1602000759.144 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:12:40.761Z,1602000760.761 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:12:45.166Z,1602000765.166 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-10-06T16:12:45.166Z,1602000765.166 [NAL9602] Data Fault, FailCount= 4
2020-10-06T16:12:45.166Z,1602000765.166 [NAL9602](ERROR): Data Fault
2020-10-06T16:12:45.207Z,1602000765.207 [CBIT](ERROR): Data Fault in component: NAL9602
2020-10-06T16:12:45.566Z,1602000765.566 [NAL9602](INFO): Powering down
2020-10-06T16:12:46.393Z,1602000766.393 [CBIT](INFO): Clearing failed state for component NAL9602
2020-10-06T16:12:46.393Z,1602000766.393 [NAL9602] No Fault, FailCount= 4
2020-10-06T16:13:11.439Z,1602000791.439 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:13:13.489Z,1602000793.489 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:13:15.870Z,1602000795.870 [NAL9602](INFO): Powering up NAL9602
2020-10-06T16:13:26.778Z,1602000806.778 [NAL9602](INFO): NAL9602 initialized
2020-10-06T16:13:44.158Z,1602000824.158 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:13:45.809Z,1602000825.809 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:14:16.496Z,1602000856.496 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:14:18.133Z,1602000858.133 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:14:48.822Z,1602000888.822 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:14:50.857Z,1602000890.857 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:15:08.992Z,1602000908.992 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2020-10-06T16:15:09.008Z,1602000909.008 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-10-06T16:15:09.027Z,1602000909.027 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-10-06T16:15:09.425Z,1602000909.425 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-10-06T16:15:09.425Z,1602000909.425 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2020-10-06T16:15:21.521Z,1602000921.521 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:15:23.185Z,1602000923.185 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:15:53.850Z,1602000953.850 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:15:55.533Z,1602000955.533 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:16:26.190Z,1602000986.190 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:16:28.229Z,1602000988.229 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:16:58.886Z,1602001018.886 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:17:00.559Z,1602001020.559 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:17:00.929Z,1602001020.929 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-10-06T16:17:00.929Z,1602001020.929 [Default:CheckIn:C.Wait] Stopped
2020-10-06T16:17:00.930Z,1602001020.930 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-10-06T16:17:00.930Z,1602001020.930 [Default:CheckIn:D] Running Loop=1
2020-10-06T16:17:01.317Z,1602001021.317 [Default:CheckIn:D] Stopped
2020-10-06T16:17:01.317Z,1602001021.317 [Default:CheckIn:E] Running Loop=1
2020-10-06T16:17:01.725Z,1602001021.725 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.522058 min 
2020-10-06T16:17:01.725Z,1602001021.725 [Default:CheckIn:E] Stopped
2020-10-06T16:17:01.725Z,1602001021.725 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-10-06T16:17:01.725Z,1602001021.725 [Default:CheckIn] Stopped
2020-10-06T16:17:01.725Z,1602001021.725 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-10-06T16:17:01.725Z,1602001021.725 [Default:CheckIn](INFO): Running loop #5
2020-10-06T16:17:01.726Z,1602001021.726 [Default:CheckIn] Running Loop=5
2020-10-06T16:17:01.726Z,1602001021.726 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-10-06T16:17:01.726Z,1602001021.726 [Default:CheckIn:Read_GPS] Running Loop=1
2020-10-06T16:17:31.226Z,1602001051.226 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:17:33.261Z,1602001053.261 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:18:03.950Z,1602001083.950 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:18:06.005Z,1602001086.005 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:18:09.988Z,1602001089.988 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2020-10-06T16:18:09.988Z,1602001089.988 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-10-06T16:18:09.998Z,1602001089.998 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-10-06T16:18:10.405Z,1602001090.405 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-10-06T16:18:10.405Z,1602001090.405 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2020-10-06T16:18:36.658Z,1602001116.658 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:18:38.317Z,1602001118.317 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:19:08.994Z,1602001148.994 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:19:11.045Z,1602001151.045 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:19:41.701Z,1602001181.701 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:19:43.345Z,1602001183.345 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:20:14.025Z,1602001214.025 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:20:16.099Z,1602001216.099 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:20:46.787Z,1602001246.787 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:20:48.422Z,1602001248.422 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:21:10.988Z,1602001270.988 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17
2020-10-06T16:21:10.988Z,1602001270.988 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-10-06T16:21:10.998Z,1602001270.998 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-10-06T16:21:11.403Z,1602001271.403 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-10-06T16:21:11.403Z,1602001271.403 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17
2020-10-06T16:21:19.069Z,1602001279.069 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:21:21.141Z,1602001281.141 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:21:51.822Z,1602001311.822 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:21:53.437Z,1602001313.437 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:22:01.893Z,1602001321.893 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-10-06T16:17:01.7Z
2020-10-06T16:22:01.894Z,1602001321.894 [Default:CheckIn:Read_GPS] Stopped
2020-10-06T16:22:01.894Z,1602001321.894 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-10-06T16:22:09.264Z,1602001329.264 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201006T152943/Courier0016.lzma
2020-10-06T16:22:10.266Z,1602001330.266 [DataOverHttps](INFO): Moved sent file to Logs/20201006T152943/Courier0016.lzma.bak
2020-10-06T16:22:10.266Z,1602001330.266 [DataOverHttps](INFO): SBD MOMSN=12796480
2020-10-06T16:22:24.130Z,1602001344.130 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:22:25.773Z,1602001345.773 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:22:27.096Z,1602001347.096 [DataOverHttps](INFO): Sending 320 bytes from file Logs/20201006T152943/Express0017.lzma
2020-10-06T16:22:28.098Z,1602001348.098 [DataOverHttps](INFO): Moved sent file to Logs/20201006T152943/Express0017.lzma.bak
2020-10-06T16:22:28.098Z,1602001348.098 [DataOverHttps](INFO): SBD MOMSN=12796488
2020-10-06T16:22:31.805Z,1602001351.805 [Default:CheckIn:Read_Iridium] Stopped
2020-10-06T16:22:31.806Z,1602001351.806 [Default:CheckIn:C.Wait] Running Loop=1
2020-10-06T16:22:31.806Z,1602001351.806 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-10-06T16:22:56.430Z,1602001376.430 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:22:58.505Z,1602001378.505 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:23:29.150Z,1602001409.150 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-10-06T16:23:29.150Z,1602001409.150 [NAL9602] Data Fault, FailCount= 5
2020-10-06T16:23:29.150Z,1602001409.150 [NAL9602](ERROR): Data Fault
2020-10-06T16:23:29.162Z,1602001409.162 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:23:29.166Z,1602001409.166 [CBIT](ERROR): Data Fault in component: NAL9602
2020-10-06T16:23:29.166Z,1602001409.166 [CBIT](CRITICAL): Data Fault in component: NAL9602
2020-10-06T16:23:29.550Z,1602001409.550 [NAL9602](INFO): Powering down
2020-10-06T16:23:29.565Z,1602001409.565 [CBIT](INFO): Critical error at 20201006T162329
2020-10-06T16:23:30.801Z,1602001410.801 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:24:01.476Z,1602001441.476 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:24:03.121Z,1602001443.121 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:24:11.975Z,1602001451.975 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18
2020-10-06T16:24:11.975Z,1602001451.975 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-10-06T16:24:11.985Z,1602001451.985 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-10-06T16:24:12.396Z,1602001452.396 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-10-06T16:24:12.396Z,1602001452.396 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18
2020-10-06T16:24:33.801Z,1602001473.801 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:24:35.853Z,1602001475.853 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:25:06.522Z,1602001506.522 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:25:08.573Z,1602001508.573 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:25:29.152Z,1602001529.152 [CBIT](INFO): Clearing failed state for component NAL9602
2020-10-06T16:25:29.152Z,1602001529.152 [NAL9602] No Fault, FailCount= 5
2020-10-06T16:25:29.542Z,1602001529.542 [NAL9602](INFO): Powering up NAL9602
2020-10-06T16:25:39.303Z,1602001539.303 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:25:40.486Z,1602001540.486 [NAL9602](INFO): NAL9602 initialized
2020-10-06T16:25:40.913Z,1602001540.913 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:26:11.614Z,1602001571.614 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:26:13.661Z,1602001573.661 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:26:44.331Z,1602001604.331 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:26:46.369Z,1602001606.369 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:27:13.020Z,1602001633.020 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19
2020-10-06T16:27:13.021Z,1602001633.021 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-10-06T16:27:13.031Z,1602001633.031 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-10-06T16:27:13.440Z,1602001633.440 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-10-06T16:27:13.440Z,1602001633.440 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19
2020-10-06T16:27:17.045Z,1602001637.045 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:27:19.117Z,1602001639.117 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:27:32.394Z,1602001652.394 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-10-06T16:27:32.394Z,1602001652.394 [Default:CheckIn:C.Wait] Stopped
2020-10-06T16:27:32.394Z,1602001652.394 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-10-06T16:27:32.394Z,1602001652.394 [Default:CheckIn:D] Running Loop=1
2020-10-06T16:27:32.823Z,1602001652.823 [Default:CheckIn:D] Stopped
2020-10-06T16:27:32.823Z,1602001652.823 [Default:CheckIn:E] Running Loop=1
2020-10-06T16:27:33.197Z,1602001653.197 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 53.047164 min 
2020-10-06T16:27:33.197Z,1602001653.197 [Default:CheckIn:E] Stopped
2020-10-06T16:27:33.197Z,1602001653.197 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-10-06T16:27:33.198Z,1602001653.198 [Default:CheckIn] Stopped
2020-10-06T16:27:33.198Z,1602001653.198 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-10-06T16:27:33.198Z,1602001653.198 [Default:CheckIn](INFO): Running loop #6
2020-10-06T16:27:33.198Z,1602001653.198 [Default:CheckIn] Running Loop=6
2020-10-06T16:27:33.198Z,1602001653.198 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-10-06T16:27:33.198Z,1602001653.198 [Default:CheckIn:Read_GPS] Running Loop=1
2020-10-06T16:27:49.787Z,1602001669.787 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:27:51.813Z,1602001671.813 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:28:22.522Z,1602001702.522 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:28:24.537Z,1602001704.537 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:28:47.386Z,1602001727.386 [CommandLine](IMPORTANT): got command configSet list
2020-10-06T16:28:47.388Z,1602001727.388 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-10-06T16:28:47.389Z,1602001727.389 [CommandLine](IMPORTANT): AMEcho.loadAtStartup=0 bool;

2020-10-06T16:28:47.389Z,1602001727.389 [CommandLine](IMPORTANT): BPC1.batteryMissingStickThreshold=6 count;

2020-10-06T16:28:47.389Z,1602001727.389 [CommandLine](IMPORTANT): BuoyancyServo.loadAtStartup=1 bool;

2020-10-06T16:28:47.389Z,1602001727.389 [CommandLine](IMPORTANT): CBIT.gf24Offset=145 microampere;

2020-10-06T16:28:47.390Z,1602001727.390 [CommandLine](IMPORTANT): DAT.loadAtStartup=1 bool;

2020-10-06T16:28:47.390Z,1602001727.390 [CommandLine](IMPORTANT): DAT.sbdAddress=-1 enum;

2020-10-06T16:28:47.390Z,1602001727.390 [CommandLine](IMPORTANT): DAT.surfaceThreshold=100 meter;

2020-10-06T16:28:47.390Z,1602001727.390 [CommandLine](IMPORTANT): DAT.verbosity=0 count;

2020-10-06T16:28:47.390Z,1602001727.390 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2020-10-06T16:28:47.390Z,1602001727.390 [CommandLine](IMPORTANT): ESPComponent.loadAtStartup=1 bool;

2020-10-06T16:28:47.390Z,1602001727.390 [CommandLine](IMPORTANT): Express linearApproximation AMEcho.altitude 1.000000 meter;

2020-10-06T16:28:47.390Z,1602001727.390 [CommandLine](IMPORTANT): Express none ThrusterServo.component_avgCurrent;

2020-10-06T16:28:47.390Z,1602001727.390 [CommandLine](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 1.000000 liter_per_second;

2020-10-06T16:28:47.391Z,1602001727.391 [CommandLine](IMPORTANT): Express linearApproximation height_above_sea_floor 5.000000 meter;

2020-10-06T16:28:47.391Z,1602001727.391 [CommandLine](IMPORTANT): IBIT.batteryVoltageThreshold=13 volt;

2020-10-06T16:28:47.391Z,1602001727.391 [CommandLine](IMPORTANT): RDI_Pathfinder.loadAtStartup=0 bool;

2020-10-06T16:28:47.391Z,1602001727.391 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=170 cubic_centimeter;

2020-10-06T16:28:47.391Z,1602001727.391 [CommandLine](IMPORTANT): VerticalControl.massDefault=20 millimeter;

2020-10-06T16:28:47.391Z,1602001727.391 [CommandLine](IMPORTANT): WetLabsUBAT.loadAtStartup=0 bool;

2020-10-06T16:28:55.210Z,1602001735.210 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:28:56.877Z,1602001736.877 [BuoyancyServo](FAULT): is needed (data)
2020-10-06T16:29:07.801Z,1602001747.801 [CommandLine](IMPORTANT): got command configSet RDI_Pathfinder.loadAtStartup 1.000000 bool persist
2020-10-06T16:29:07.802Z,1602001747.802 [CommandLine](IMPORTANT): configSet RDI_Pathfinder.loadAtStartup requires a restart to take effect.
2020-10-06T16:29:11.113Z,1602001751.113 [CommandLine](IMPORTANT): got command restart application
2020-10-06T16:29:12.116Z,1602001752.116 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-10-06T16:29:12.117Z,1602001752.117 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-10-06T16:29:12.128Z,1602001752.128 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-10-06T16:29:12.128Z,1602001752.128 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-10-06T16:29:12.129Z,1602001752.129 [CommandLine](INFO): Join timeout helper Thread ID is 889
2020-10-06T16:29:12.140Z,1602001752.140 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-10-06T16:29:12.140Z,1602001752.140 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-10-06T16:29:12.141Z,1602001752.141 [NavChartDb](INFO): Join timeout helper Thread ID is 890
2020-10-06T16:29:12.285Z,1602001752.285 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-10-06T16:29:12.285Z,1602001752.285 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-10-06T16:29:12.304Z,1602001752.304 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2020-10-06T16:29:12.304Z,1602001752.304 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-10-06T16:29:12.305Z,1602001752.305 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 891
2020-10-06T16:29:12.513Z,1602001752.513 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2020-10-06T16:29:12.513Z,1602001752.513 [WetLabsBB2FL](INFO): Powering down
2020-10-06T16:29:12.514Z,1602001752.514 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-10-06T16:29:12.517Z,1602001752.517 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2020-10-06T16:29:12.518Z,1602001752.518 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-10-06T16:29:12.519Z,1602001752.519 [CTD_Seabird](INFO): Join timeout helper Thread ID is 892
2020-10-06T16:29:12.837Z,1602001752.837 [CTD_Seabird](INFO): Powering down
2020-10-06T16:29:12.848Z,1602001752.848 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2020-10-06T16:29:12.849Z,1602001752.849 [CTD_Seabird](INFO): Powering down
2020-10-06T16:29:12.861Z,1602001752.861 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-10-06T16:29:12.881Z,1602001752.881 [ComponentRegistry](INFO): Shutting down BackSeatDriver ThreadHandler
2020-10-06T16:29:12.882Z,1602001752.882 [BackSeatDriver ThreadHandler](INFO): Thread cancelled.
2020-10-06T16:29:12.882Z,1602001752.882 [BackSeatDriver](INFO): Join timeout helper Thread ID is 893
2020-10-06T16:29:13.085Z,1602001753.085 [BackSeatDriver ThreadHandler](INFO): Uninitializing protected caller thread.
2020-10-06T16:29:13.085Z,1602001753.085 [BackSeatDriver ThreadHandler](INFO): Thread cancelled.
2020-10-06T16:29:13.105Z,1602001753.105 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-10-06T16:29:13.105Z,1602001753.105 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-10-06T16:29:13.106Z,1602001753.106 [Radio_Surface](INFO): Join timeout helper Thread ID is 894
2020-10-06T16:29:13.488Z,1602001753.488 [Radio_Surface](INFO): Powering down
2020-10-06T16:29:13.489Z,1602001753.489 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-10-06T16:29:13.490Z,1602001753.490 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-10-06T16:29:13.505Z,1602001753.505 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2020-10-06T16:29:13.505Z,1602001753.505 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-10-06T16:29:13.506Z,1602001753.506 [Onboard](INFO): Join timeout helper Thread ID is 895
2020-10-06T16:29:17.868Z,1602001757.868 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2020-10-06T16:29:17.869Z,1602001757.869 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-10-06T16:29:17.885Z,1602001757.885 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-10-06T16:29:17.885Z,1602001757.885 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-10-06T16:29:17.885Z,1602001757.885 [DataOverHttps](INFO): Join timeout helper Thread ID is 896
2020-10-06T16:29:18.088Z,1602001758.088 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-10-06T16:29:18.092Z,1602001758.092 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-10-06T16:29:18.105Z,1602001758.105 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-10-06T16:29:18.105Z,1602001758.105 [logger ThreadHandler](INFO): Thread cancelled.
2020-10-06T16:29:18.106Z,1602001758.106 [logger](INFO): Join timeout helper Thread ID is 897
2020-10-06T16:29:18.148Z,1602001758.148 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-10-06T16:29:18.149Z,1602001758.149 [logger ThreadHandler](INFO): Thread cancelled.
2020-10-06T16:29:18.165Z,1602001758.165 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-10-06T16:29:18.165Z,1602001758.165 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-10-06T16:29:18.165Z,1602001758.165 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-10-06T16:29:18.165Z,1602001758.165 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-10-06T16:29:18.166Z,1602001758.166 [controlThread](INFO): Join timeout helper Thread ID is 898
2020-10-06T16:29:18.216Z,1602001758.216 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-10-06T16:29:18.217Z,1602001758.217 [controlThread](DEBUG): Uninitializing ControlThread
2020-10-06T16:29:18.217Z,1602001758.217 [AHRS_M2](INFO): Powering down
2020-10-06T16:29:18.289Z,1602001758.289 [NAL9602](INFO): Powering down
2020-10-06T16:29:18.290Z,1602001758.290 [DAT](INFO): Powering down
2020-10-06T16:29:18.410Z,1602001758.410 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-10-06T16:29:18.411Z,1602001758.411 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-10-06T16:29:18.412Z,1602001758.412 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-10-06T16:29:18.412Z,1602001758.412 [MissionManager](INFO): Uninitializing Mission Default
2020-10-06T16:29:18.412Z,1602001758.412 [Default] Stopped
2020-10-06T16:29:18.412Z,1602001758.412 [Default](DEBUG): Aggregate::uninitialize Default
2020-10-06T16:29:18.412Z,1602001758.412 [Default:B.GoToSurface] Stopped
2020-10-06T16:29:18.413Z,1602001758.413 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-10-06T16:29:18.413Z,1602001758.413 [Default:CheckIn] Stopped
2020-10-06T16:29:18.413Z,1602001758.413 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-10-06T16:29:18.413Z,1602001758.413 [Default:CheckIn:Read_GPS] Stopped
2020-10-06T16:29:18.415Z,1602001758.415 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-10-06T16:29:18.415Z,1602001758.415 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-10-06T16:29:18.416Z,1602001758.416 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-10-06T16:29:18.416Z,1602001758.416 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-10-06T16:29:18.416Z,1602001758.416 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-10-06T16:29:18.417Z,1602001758.417 [BuoyancyServo](INFO): Powering down
2020-10-06T16:29:18.429Z,1602001758.429 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-10-06T16:29:18.429Z,1602001758.429 [ElevatorServo](INFO): Powering down
2020-10-06T16:29:18.429Z,1602001758.429 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-10-06T16:29:18.430Z,1602001758.430 [MassServo](INFO): Powering down
2020-10-06T16:29:18.430Z,1602001758.430 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-10-06T16:29:18.430Z,1602001758.430 [RudderServo](INFO): Powering down
2020-10-06T16:29:18.431Z,1602001758.431 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-10-06T16:29:18.431Z,1602001758.431 [ThrusterServo](INFO): Powering down
2020-10-06T16:29:18.432Z,1602001758.432 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-10-06T16:29:18.433Z,1602001758.433 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-10-06T16:29:18.433Z,1602001758.433 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-10-06T16:29:18.433Z,1602001758.433 [CBIT](DEBUG): Powering off loads.
2020-10-06T16:29:18.444Z,1602001758.444 [CBIT](DEBUG): Disabling WDT.
2020-10-06T16:29:18.456Z,1602001758.456 [CBIT](DEBUG): Opening all GF detection circuits.
2020-10-06T16:29:18.457Z,1602001758.457 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-10-06T16:29:18.547Z,1602001758.547 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-10-06T16:29:18.549Z,1602001758.549 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-10-06T16:29:18.554Z,1602001758.554 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-10-06T16:29:18.612Z,1602001758.612 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-10-06T16:29:18.617Z,1602001758.617 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-10-06T16:29:18.620Z,1602001758.620 [BackSeatDriver ThreadHandler](INFO): Thread cancelled.
2020-10-06T16:29:18.630Z,1602001758.630 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-10-06T16:29:18.691Z,1602001758.691 [logger ThreadHandler](INFO): Thread cancelled.