2020-08-31T16:59:10.907Z,1598893150.907 [Supervisor](DEBUG): Initializing supervisor.
2020-08-31T16:59:10.910Z,1598893150.910 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-08-31T16:59:10.910Z,1598893150.910 [SyncHandler](INFO): Protected caller Thread ID is 826
2020-08-31T16:59:10.911Z,1598893150.911 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-08-31T16:59:10.912Z,1598893150.912 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-08-31T16:59:10.912Z,1598893150.912 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 827
2020-08-31T16:59:10.915Z,1598893150.915 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-08-31T16:59:10.928Z,1598893150.928 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-08-31T16:59:10.929Z,1598893150.929 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-08-31T16:59:10.929Z,1598893150.929 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 828
2020-08-31T16:59:10.930Z,1598893150.930 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-08-31T16:59:10.931Z,1598893150.931 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-08-31T16:59:10.931Z,1598893150.931 [logger ThreadHandler](INFO): Protected caller Thread ID is 829
2020-08-31T16:59:10.933Z,1598893150.933 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-08-31T16:59:10.934Z,1598893150.934 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-08-31T16:59:10.938Z,1598893150.938 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-08-31T16:59:11.031Z,1598893151.031 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-08-31T16:59:11.033Z,1598893151.033 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-08-31T16:59:11.619Z,1598893151.619 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-08-31T16:59:11.619Z,1598893151.619 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2020-08-31T16:59:11.808Z,1598893151.808 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2020-08-31T16:59:11.810Z,1598893151.810 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-08-31T16:59:11.908Z,1598893151.908 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-08-31T16:59:11.909Z,1598893151.909 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-08-31T16:59:12.009Z,1598893152.009 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-08-31T16:59:12.011Z,1598893152.011 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-08-31T16:59:12.092Z,1598893152.092 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-08-31T16:59:12.228Z,1598893152.228 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-08-31T16:59:12.230Z,1598893152.230 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-08-31T16:59:12.512Z,1598893152.512 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-08-31T16:59:12.514Z,1598893152.514 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-08-31T16:59:12.958Z,1598893152.958 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-08-31T16:59:12.959Z,1598893152.959 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-08-31T16:59:13.100Z,1598893153.100 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-08-31T16:59:13.101Z,1598893153.101 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-08-31T16:59:13.290Z,1598893153.290 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-08-31T16:59:13.292Z,1598893153.292 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-08-31T16:59:13.751Z,1598893153.751 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-08-31T16:59:13.753Z,1598893153.753 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-08-31T16:59:13.966Z,1598893153.966 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-08-31T16:59:13.968Z,1598893153.968 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-08-31T16:59:14.167Z,1598893154.167 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-08-31T16:59:14.169Z,1598893154.169 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-08-31T16:59:14.572Z,1598893154.572 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-08-31T16:59:14.572Z,1598893154.572 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-08-31T16:59:15.512Z,1598893155.512 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-08-31T16:59:15.514Z,1598893155.514 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2020-08-31T16:59:15.517Z,1598893155.517 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2020-08-31T16:59:15.600Z,1598893155.600 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2020-08-31T16:59:15.757Z,1598893155.757 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2020-08-31T16:59:15.863Z,1598893155.863 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2020-08-31T16:59:15.948Z,1598893155.948 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2020-08-31T16:59:16.042Z,1598893156.042 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2020-08-31T16:59:16.241Z,1598893156.241 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2020-08-31T16:59:16.469Z,1598893156.469 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-08-31T16:59:16.470Z,1598893156.470 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2020-08-31T16:59:16.563Z,1598893156.563 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2020-08-31T16:59:16.661Z,1598893156.661 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2020-08-31T16:59:16.790Z,1598893156.790 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2020-08-31T16:59:16.888Z,1598893156.888 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-08-31T16:59:16.902Z,1598893156.902 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2020-08-31T16:59:16.997Z,1598893156.997 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2020-08-31T16:59:16.998Z,1598893156.998 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-08-31T16:59:17.060Z,1598893157.060 [VerticalControl](DEBUG): Construct VerticalControl.
2020-08-31T16:59:17.168Z,1598893157.168 [VerticalControl] Loaded
2020-08-31T16:59:17.169Z,1598893157.169 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-08-31T16:59:17.169Z,1598893157.169 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-08-31T16:59:17.246Z,1598893157.246 [HorizontalControl] Loaded
2020-08-31T16:59:17.247Z,1598893157.247 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-08-31T16:59:17.247Z,1598893157.247 [SpeedControl](DEBUG): Construct SpeedControl.
2020-08-31T16:59:17.253Z,1598893157.253 [SpeedControl] Loaded
2020-08-31T16:59:17.253Z,1598893157.253 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-08-31T16:59:17.254Z,1598893157.254 [LoopControl](DEBUG): Construct LoopControl.
2020-08-31T16:59:17.254Z,1598893157.254 [LoopControl] Loaded
2020-08-31T16:59:17.254Z,1598893157.254 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-08-31T16:59:17.255Z,1598893157.255 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-08-31T16:59:17.255Z,1598893157.255 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-08-31T16:59:17.310Z,1598893157.310 [DepthRateCalculator] Loaded
2020-08-31T16:59:17.310Z,1598893157.310 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-08-31T16:59:17.315Z,1598893157.315 [PitchRateCalculator] Loaded
2020-08-31T16:59:17.315Z,1598893157.315 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-08-31T16:59:17.330Z,1598893157.330 [SpeedCalculator] Loaded
2020-08-31T16:59:17.330Z,1598893157.330 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-08-31T16:59:17.351Z,1598893157.351 [TempGradientCalculator] Loaded
2020-08-31T16:59:17.352Z,1598893157.352 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-08-31T16:59:17.356Z,1598893157.356 [YawRateCalculator] Loaded
2020-08-31T16:59:17.357Z,1598893157.357 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-08-31T16:59:17.385Z,1598893157.385 [ElevatorOffsetCalculator] Loaded
2020-08-31T16:59:17.385Z,1598893157.385 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-08-31T16:59:17.386Z,1598893157.386 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-08-31T16:59:17.386Z,1598893157.386 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-08-31T16:59:17.428Z,1598893157.428 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-08-31T16:59:17.428Z,1598893157.428 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-08-31T16:59:17.555Z,1598893157.555 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-08-31T16:59:17.555Z,1598893157.555 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-08-31T16:59:17.884Z,1598893157.884 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-08-31T16:59:17.884Z,1598893157.884 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-08-31T16:59:18.046Z,1598893158.046 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-08-31T16:59:18.049Z,1598893158.049 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-08-31T16:59:18.639Z,1598893158.639 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-08-31T16:59:18.644Z,1598893158.644 [AHRS_M2](INFO): created writer for : platform_orientation
2020-08-31T16:59:18.645Z,1598893158.645 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-08-31T16:59:18.651Z,1598893158.651 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-08-31T16:59:18.651Z,1598893158.651 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-08-31T16:59:18.656Z,1598893158.656 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-08-31T16:59:18.657Z,1598893158.657 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-08-31T16:59:18.662Z,1598893158.662 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-08-31T16:59:18.836Z,1598893158.836 [AHRS_M2] Loaded
2020-08-31T16:59:18.836Z,1598893158.836 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-08-31T16:59:18.980Z,1598893158.980 [DataOverHttps] Loaded
2020-08-31T16:59:18.980Z,1598893158.980 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-08-31T16:59:18.981Z,1598893158.981 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4090D4E0
2020-08-31T16:59:18.981Z,1598893158.981 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 912
2020-08-31T16:59:19.011Z,1598893159.011 [Depth_Keller] Loaded
2020-08-31T16:59:19.011Z,1598893159.011 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-08-31T16:59:19.015Z,1598893159.015 [DropWeight] Loaded
2020-08-31T16:59:19.016Z,1598893159.016 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2020-08-31T16:59:19.126Z,1598893159.126 [NAL9602] Loaded
2020-08-31T16:59:19.126Z,1598893159.126 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-08-31T16:59:19.173Z,1598893159.173 [Onboard] Loaded
2020-08-31T16:59:19.174Z,1598893159.174 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2020-08-31T16:59:19.175Z,1598893159.175 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4093D4E0
2020-08-31T16:59:19.175Z,1598893159.175 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 913
2020-08-31T16:59:19.194Z,1598893159.194 [Radio_Surface] Loaded
2020-08-31T16:59:19.194Z,1598893159.194 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-08-31T16:59:19.195Z,1598893159.195 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4096D4E0
2020-08-31T16:59:19.195Z,1598893159.195 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 914
2020-08-31T16:59:19.253Z,1598893159.253 [RDI_Pathfinder] Loaded
2020-08-31T16:59:19.253Z,1598893159.253 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2020-08-31T16:59:19.399Z,1598893159.399 [DAT] Loaded
2020-08-31T16:59:19.399Z,1598893159.399 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2020-08-31T16:59:21.265Z,1598893161.265 [BPC1] Loaded
2020-08-31T16:59:21.265Z,1598893161.265 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-08-31T16:59:21.296Z,1598893161.296 [AMEcho] Loaded
2020-08-31T16:59:21.296Z,1598893161.296 [ComponentRegistry](DEBUG): SyncComponent "AMEcho" handled in the control thread.
2020-08-31T16:59:21.297Z,1598893161.297 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-08-31T16:59:21.298Z,1598893161.298 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-08-31T16:59:21.429Z,1598893161.429 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-08-31T16:59:21.430Z,1598893161.430 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-08-31T16:59:21.449Z,1598893161.449 [NavChart] Loaded
2020-08-31T16:59:21.450Z,1598893161.450 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-08-31T16:59:21.454Z,1598893161.454 [UniversalFixResidualReporter] Loaded
2020-08-31T16:59:21.454Z,1598893161.454 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-08-31T16:59:21.454Z,1598893161.454 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-08-31T16:59:21.456Z,1598893161.456 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-08-31T16:59:21.612Z,1598893161.612 [SBIT](DEBUG): Construct Startup Built In Test.
2020-08-31T16:59:21.623Z,1598893161.623 [SBIT] Loaded
2020-08-31T16:59:21.623Z,1598893161.623 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-08-31T16:59:21.624Z,1598893161.624 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-08-31T16:59:21.636Z,1598893161.636 [IBIT] Loaded
2020-08-31T16:59:21.637Z,1598893161.637 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-08-31T16:59:21.640Z,1598893161.640 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-08-31T16:59:21.776Z,1598893161.776 [CBIT] Loaded
2020-08-31T16:59:21.776Z,1598893161.776 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-08-31T16:59:21.777Z,1598893161.777 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-08-31T16:59:21.777Z,1598893161.777 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-08-31T16:59:21.920Z,1598893161.920 [BuoyancyServo] Loaded
2020-08-31T16:59:21.920Z,1598893161.920 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-08-31T16:59:21.948Z,1598893161.948 [ElevatorServo] Loaded
2020-08-31T16:59:21.948Z,1598893161.948 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-08-31T16:59:21.976Z,1598893161.976 [MassServo] Loaded
2020-08-31T16:59:21.976Z,1598893161.976 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-08-31T16:59:22.003Z,1598893162.003 [RudderServo] Loaded
2020-08-31T16:59:22.003Z,1598893162.003 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-08-31T16:59:22.029Z,1598893162.029 [ThrusterServo] Loaded
2020-08-31T16:59:22.029Z,1598893162.029 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-08-31T16:59:22.029Z,1598893162.029 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-08-31T16:59:22.030Z,1598893162.030 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-08-31T16:59:22.060Z,1598893162.060 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-08-31T16:59:22.061Z,1598893162.061 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-08-31T16:59:22.374Z,1598893162.374 [BackSeatDriver] Loaded
2020-08-31T16:59:22.374Z,1598893162.374 [ComponentRegistry](DEBUG): Component "BackSeatDriver" handled in its own thread.
2020-08-31T16:59:22.375Z,1598893162.375 [BackSeatDriver ThreadHandler](DEBUG): Created PCaller Thread at 40B034E0
2020-08-31T16:59:22.375Z,1598893162.375 [BackSeatDriver ThreadHandler](INFO): Protected caller Thread ID is 915
2020-08-31T16:59:22.404Z,1598893162.404 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2020-08-31T16:59:22.409Z,1598893162.409 [CTD_Seabird](INFO): created writer for : sea_water_density
2020-08-31T16:59:22.410Z,1598893162.410 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2020-08-31T16:59:22.414Z,1598893162.414 [CTD_Seabird](INFO): created writer for : depth
2020-08-31T16:59:22.415Z,1598893162.415 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2020-08-31T16:59:22.420Z,1598893162.420 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2020-08-31T16:59:22.420Z,1598893162.420 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2020-08-31T16:59:22.426Z,1598893162.426 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2020-08-31T16:59:22.426Z,1598893162.426 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2020-08-31T16:59:22.431Z,1598893162.431 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2020-08-31T16:59:22.432Z,1598893162.432 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2020-08-31T16:59:22.437Z,1598893162.437 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2020-08-31T16:59:22.437Z,1598893162.437 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2020-08-31T16:59:22.442Z,1598893162.442 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2020-08-31T16:59:22.469Z,1598893162.469 [CTD_Seabird] Loaded
2020-08-31T16:59:22.469Z,1598893162.469 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2020-08-31T16:59:22.470Z,1598893162.470 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B334E0
2020-08-31T16:59:22.470Z,1598893162.470 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 916
2020-08-31T16:59:22.492Z,1598893162.492 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470
2020-08-31T16:59:22.492Z,1598893162.492 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470
2020-08-31T16:59:22.496Z,1598893162.496 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650
2020-08-31T16:59:22.497Z,1598893162.497 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650
2020-08-31T16:59:22.501Z,1598893162.501 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl
2020-08-31T16:59:22.501Z,1598893162.501 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl
2020-08-31T16:59:22.505Z,1598893162.505 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm
2020-08-31T16:59:22.505Z,1598893162.505 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm
2020-08-31T16:59:22.509Z,1598893162.509 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm
2020-08-31T16:59:22.510Z,1598893162.510 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm
2020-08-31T16:59:22.514Z,1598893162.514 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm
2020-08-31T16:59:22.514Z,1598893162.514 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm
2020-08-31T16:59:22.518Z,1598893162.518 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm
2020-08-31T16:59:22.518Z,1598893162.518 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm
2020-08-31T16:59:22.522Z,1598893162.522 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water
2020-08-31T16:59:22.527Z,1598893162.527 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water
2020-08-31T16:59:22.527Z,1598893162.527 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-08-31T16:59:22.527Z,1598893162.527 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-08-31T16:59:22.531Z,1598893162.531 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-08-31T16:59:22.531Z,1598893162.531 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-08-31T16:59:22.536Z,1598893162.536 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-08-31T16:59:22.536Z,1598893162.536 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-08-31T16:59:22.540Z,1598893162.540 [WetLabsBB2FL] Loaded
2020-08-31T16:59:22.540Z,1598893162.540 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2020-08-31T16:59:22.541Z,1598893162.541 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B634E0
2020-08-31T16:59:22.542Z,1598893162.542 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 917
2020-08-31T16:59:22.542Z,1598893162.542 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-08-31T16:59:22.546Z,1598893162.546 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-08-31T16:59:22.547Z,1598893162.547 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-08-31T16:59:22.554Z,1598893162.554 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-08-31T16:59:22.555Z,1598893162.555 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B934E0
2020-08-31T16:59:22.555Z,1598893162.555 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 918
2020-08-31T16:59:22.560Z,1598893162.560 [Supervisor](INFO): Main Thread ID is 822
2020-08-31T16:59:22.560Z,1598893162.560 [Supervisor](DEBUG): Running supervisor.
2020-08-31T16:59:22.560Z,1598893162.560 [CommandLine ThreadHandler](INFO): Handler Thread ID is 919
2020-08-31T16:59:22.563Z,1598893162.563 [controlThread ThreadHandler](INFO): Handler Thread ID is 920
2020-08-31T16:59:22.563Z,1598893162.563 [controlThread](DEBUG): Initializing ControlThread
2020-08-31T16:59:22.564Z,1598893162.564 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-08-31T16:59:22.566Z,1598893162.566 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-08-31T16:59:22.566Z,1598893162.566 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-08-31T16:59:22.567Z,1598893162.567 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-08-31T16:59:22.567Z,1598893162.567 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-08-31T16:59:22.568Z,1598893162.568 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-08-31T16:59:22.568Z,1598893162.568 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-08-31T16:59:22.568Z,1598893162.568 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-08-31T16:59:22.569Z,1598893162.569 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-08-31T16:59:22.569Z,1598893162.569 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-08-31T16:59:22.576Z,1598893162.576 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-08-31T16:59:22.576Z,1598893162.576 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-08-31T16:59:22.576Z,1598893162.576 [SBIT](INFO): Initialize SBIT Component.
2020-08-31T16:59:22.577Z,1598893162.577 [SBIT](IMPORTANT): git: 2020-08-31
2020-08-31T16:59:22.577Z,1598893162.577 [SBIT](INFO): git hash: b6c0ec5a69f4280de3f7606c4f2437bb78ebd873
2020-08-31T16:59:22.577Z,1598893162.577 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-08-31T16:59:22.579Z,1598893162.579 [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-08-31T16:59:22.580Z,1598893162.580 [SBIT](INFO): Beginning SBIT in 65.000000 seconds.
2020-08-31T16:59:22.580Z,1598893162.580 [IBIT](INFO): Initialize IBIT Component.
2020-08-31T16:59:22.581Z,1598893162.581 [CBIT](DEBUG): Initialize CBIT Component.
2020-08-31T16:59:22.582Z,1598893162.582 [logger ThreadHandler](INFO): Handler Thread ID is 921
2020-08-31T16:59:22.593Z,1598893162.593 [CBIT](DEBUG): Initialized mux pins.
2020-08-31T16:59:22.593Z,1598893162.593 [CBIT](DEBUG): Initializing the watchdog timer.
2020-08-31T16:59:22.601Z,1598893162.601 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 922
2020-08-31T16:59:22.603Z,1598893162.603 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-08-31T16:59:22.614Z,1598893162.614 [Onboard ThreadHandler](INFO): Handler Thread ID is 923
2020-08-31T16:59:22.618Z,1598893162.618 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-08-31T16:59:22.618Z,1598893162.618 [CBIT](DEBUG): Initializing heartbeat.
2020-08-31T16:59:22.638Z,1598893162.638 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 924
2020-08-31T16:59:22.653Z,1598893162.653 [BackSeatDriver ThreadHandler](INFO): Handler Thread ID is 925
2020-08-31T16:59:22.673Z,1598893162.673 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 926
2020-08-31T16:59:22.674Z,1598893162.674 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-08-31T16:59:22.678Z,1598893162.678 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 928
2020-08-31T16:59:22.679Z,1598893162.679 [WetLabsBB2FL](INFO): Powering down
2020-08-31T16:59:22.689Z,1598893162.689 [CBIT](DEBUG): Deactivating GF circuits.
2020-08-31T16:59:22.689Z,1598893162.689 [CBIT](DEBUG): Deactivating emergency mode.
2020-08-31T16:59:22.725Z,1598893162.725 [CBIT](DEBUG): Backplane powered.
2020-08-31T16:59:22.804Z,1598893162.804 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-08-31T16:59:22.815Z,1598893162.815 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 929
2020-08-31T16:59:22.935Z,1598893162.935 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-08-31T16:59:22.935Z,1598893162.935 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-08-31T16:59:22.936Z,1598893162.936 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-08-31T16:59:22.936Z,1598893162.936 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-08-31T16:59:22.936Z,1598893162.936 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-08-31T16:59:22.936Z,1598893162.936 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-08-31T16:59:22.936Z,1598893162.936 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-08-31T16:59:22.936Z,1598893162.936 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-08-31T16:59:22.937Z,1598893162.937 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-08-31T16:59:22.937Z,1598893162.937 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-08-31T16:59:22.937Z,1598893162.937 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-08-31T16:59:22.937Z,1598893162.937 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-08-31T16:59:22.938Z,1598893162.938 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-08-31T16:59:22.938Z,1598893162.938 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-08-31T16:59:22.938Z,1598893162.938 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-08-31T16:59:22.938Z,1598893162.938 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-08-31T16:59:22.974Z,1598893162.974 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-08-31T16:59:23.041Z,1598893163.041 [Radio_Surface](INFO): Powering up
2020-08-31T16:59:23.052Z,1598893163.052 [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-08-31T16:59:23.052Z,1598893163.052 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-08-31T16:59:23.174Z,1598893163.174 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-08-31T16:59:23.175Z,1598893163.175 [Default:A.Wait](DEBUG): Construct Wait.
2020-08-31T16:59:23.177Z,1598893163.177 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-08-31T16:59:23.224Z,1598893163.224 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-08-31T16:59:23.232Z,1598893163.232 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-08-31T16:59:23.253Z,1598893163.253 [Default:E.Execute](DEBUG): Construct Execute.
2020-08-31T16:59:23.256Z,1598893163.256 [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-08-31T16:59:23.273Z,1598893163.273 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,NAL9602,RDI_Pathfinder,DAT,BPC1,AMEcho,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-08-31T16:59:23.285Z,1598893163.285 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-08-31T16:59:23.556Z,1598893163.556 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2020-08-31T16:59:23.556Z,1598893163.556 [DAT](INFO): Powering up
2020-08-31T16:59:23.556Z,1598893163.556 [DAT](DEBUG): Initializing DAT.
2020-08-31T16:59:23.561Z,1598893163.561 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2020-08-31T16:59:23.630Z,1598893163.630 [AMEcho](INFO): Powering up
2020-08-31T16:59:23.670Z,1598893163.670 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-08-31T16:59:23.681Z,1598893163.681 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-08-31T16:59:23.683Z,1598893163.683 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-08-31T16:59:23.697Z,1598893163.697 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-08-31T16:59:23.698Z,1598893163.698 [MassServo](DEBUG): Initializing EZServoServo.
2020-08-31T16:59:23.705Z,1598893163.705 [MassServo](DEBUG): Initializing MassServo.
2020-08-31T16:59:23.706Z,1598893163.706 [RudderServo](DEBUG): Initializing EZServoServo.
2020-08-31T16:59:23.713Z,1598893163.713 [RudderServo](DEBUG): Initializing RudderServo.
2020-08-31T16:59:23.714Z,1598893163.714 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-08-31T16:59:23.721Z,1598893163.721 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-08-31T16:59:23.993Z,1598893163.993 [AMEcho](INFO): Powering down
2020-08-31T16:59:36.279Z,1598893176.279 [DAT](INFO): commRate: 800
2020-08-31T16:59:36.279Z,1598893176.279 [DAT](INFO): commRate: 800
2020-08-31T16:59:36.671Z,1598893176.671 [DAT](INFO): entering command mode
2020-08-31T16:59:37.075Z,1598893177.075 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-31T16:59:37.491Z,1598893177.491 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-31T16:59:37.883Z,1598893177.883 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-31T16:59:38.287Z,1598893178.287 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-31T16:59:38.703Z,1598893178.703 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-31T16:59:39.095Z,1598893179.095 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-31T16:59:39.499Z,1598893179.499 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-31T16:59:39.914Z,1598893179.914 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-31T16:59:40.307Z,1598893180.307 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-31T16:59:40.711Z,1598893180.711 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-31T16:59:41.126Z,1598893181.126 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-31T16:59:41.530Z,1598893181.530 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-31T16:59:41.919Z,1598893181.919 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-31T16:59:42.338Z,1598893182.338 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-31T16:59:42.739Z,1598893182.739 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-31T16:59:43.135Z,1598893183.135 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-31T16:59:43.550Z,1598893183.550 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-31T16:59:43.943Z,1598893183.943 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-31T16:59:44.391Z,1598893184.391 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-31T16:59:44.751Z,1598893184.751 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-31T16:59:45.171Z,1598893185.171 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-31T16:59:45.571Z,1598893185.571 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-31T16:59:45.963Z,1598893185.963 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-31T16:59:46.400Z,1598893186.400 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-31T16:59:46.772Z,1598893186.772 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-31T16:59:47.179Z,1598893187.179 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-31T16:59:47.600Z,1598893187.600 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-31T16:59:47.979Z,1598893187.979 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-31T16:59:48.387Z,1598893188.387 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-31T16:59:48.816Z,1598893188.816 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-31T16:59:49.194Z,1598893189.194 [NAL9602](INFO): Powering up NAL9602
2020-08-31T16:59:49.215Z,1598893189.215 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-31T16:59:49.603Z,1598893189.603 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-31T16:59:50.014Z,1598893190.014 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-31T16:59:50.419Z,1598893190.419 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-31T16:59:50.811Z,1598893190.811 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-31T16:59:51.256Z,1598893191.256 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-31T16:59:51.619Z,1598893191.619 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-31T16:59:52.027Z,1598893192.027 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-31T16:59:52.027Z,1598893192.027 [DAT](FAULT): failed to enter command mode
2020-08-31T16:59:52.463Z,1598893192.463 [DAT](INFO): entering command mode
2020-08-31T16:59:52.828Z,1598893192.828 [DAT](INFO): setting verbose to 3
2020-08-31T16:59:53.247Z,1598893193.247 [DAT](INFO): set verbose to 3
2020-08-31T16:59:53.247Z,1598893193.247 [DAT](INFO): setting DatVerbose to 27440
2020-08-31T16:59:53.640Z,1598893193.640 [DAT](INFO): set DatVerbose to 27440
2020-08-31T16:59:53.641Z,1598893193.641 [DAT](INFO): setting transmit power to 8
2020-08-31T16:59:54.044Z,1598893194.044 [DAT](INFO): set transmit power to 8
2020-08-31T16:59:54.045Z,1598893194.045 [DAT](INFO): setting local address to 9
2020-08-31T16:59:54.463Z,1598893194.463 [DAT](INFO): set local address to 9
2020-08-31T17:00:00.103Z,1598893200.103 [NAL9602](INFO): NAL9602 initialized
2020-08-31T17:00:27.999Z,1598893227.999 [SBIT](IMPORTANT): Beginning Startup BIT
2020-08-31T17:00:28.003Z,1598893228.003 [CBIT](IMPORTANT): Beginning ground fault scan
2020-08-31T17:00:39.069Z,1598893239.069 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.010973
CHAN A1 (24V): -0.001452
CHAN A2 (12V): -0.007450
CHAN A3 (5V): -0.001956
CHAN B0 (3.3V): 0.000514
CHAN B1 (3.15aV): -0.000319
CHAN B2 (3.15bV): -0.000758
CHAN B3 (GND): 0.002477
OPEN: -0.003581
Full Scale Calc: 4.765 mA, -1.589 mA
2020-08-31T17:00:51.904Z,1598893251.904 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-08-31T17:01:13.646Z,1598893273.646 [BPC1](ERROR): BPC1A: No match for serial number FFFF in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2020-08-31T17:01:21.747Z,1598893281.747 [SBIT](IMPORTANT): SBIT PASSED
2020-08-31T17:01:21.747Z,1598893281.747 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-08-31T17:01:21.748Z,1598893281.748 [SBIT](IMPORTANT): CBIT.gf24Offset=145 microampere;

2020-08-31T17:01:21.748Z,1598893281.748 [SBIT](IMPORTANT): DAT.sbdAddress=6 enum;

2020-08-31T17:01:21.748Z,1598893281.748 [SBIT](IMPORTANT): DAT.surfaceThreshold=-1 meter;

2020-08-31T17:01:21.749Z,1598893281.749 [SBIT](IMPORTANT): Express none ThrusterServo.component_avgCurrent;

2020-08-31T17:01:21.749Z,1598893281.749 [SBIT](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 1.000000 liter_per_second;

2020-08-31T17:01:21.749Z,1598893281.749 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 5.000000 meter;

2020-08-31T17:01:21.749Z,1598893281.749 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=344.685708 cubic_centimeter;

2020-08-31T17:01:21.749Z,1598893281.749 [SBIT](IMPORTANT): VerticalControl.massDefault=11.293600 millimeter;

2020-08-31T17:01:21.749Z,1598893281.749 [SBIT](IMPORTANT): WetLabsUBAT.loadAtStartup=0 bool;

2020-08-31T17:01:22.237Z,1598893282.237 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-08-31T17:01:22.244Z,1598893282.244 [MissionManager](IMPORTANT): Started mission Startup
2020-08-31T17:01:22.244Z,1598893282.244 [Startup] Running Loop=1
2020-08-31T17:01:22.244Z,1598893282.244 [Startup](DEBUG): Aggregate::initialize Startup
2020-08-31T17:01:22.244Z,1598893282.244 [Startup:A.GoToSurface] Running Loop=1
2020-08-31T17:01:22.244Z,1598893282.244 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-08-31T17:01:22.246Z,1598893282.246 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-08-31T17:01:22.246Z,1598893282.246 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-08-31T17:01:22.246Z,1598893282.246 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-08-31T17:01:22.247Z,1598893282.247 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-08-31T17:01:22.247Z,1598893282.247 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-08-31T17:01:22.248Z,1598893282.248 [Startup:StartupSatComms] Running Loop=1
2020-08-31T17:01:22.249Z,1598893282.249 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-08-31T17:01:22.249Z,1598893282.249 [Startup:StartupSatComms:A] Running Loop=1
2020-08-31T17:01:22.574Z,1598893282.574 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-08-31T17:01:33.895Z,1598893293.895 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-08-31T17:01:48.548Z,1598893308.548 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005618
2020-08-31T17:02:02.987Z,1598893322.987 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-08-31T17:02:22.339Z,1598893342.339 [Startup:StartupSatComms:A](INFO): Timed out from 2020-08-31T17:01:22.2Z
2020-08-31T17:02:22.339Z,1598893342.339 [Startup:StartupSatComms:A] Stopped
2020-08-31T17:02:22.339Z,1598893342.339 [Startup:StartupSatComms:B] Running Loop=1
2020-08-31T17:02:22.741Z,1598893342.741 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2020-08-31T17:02:22.741Z,1598893342.741 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-08-31T17:02:22.743Z,1598893342.743 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-08-31T17:02:22.752Z,1598893342.752 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-08-31T17:02:23.134Z,1598893343.134 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20200831T164333/Courier0007.lzma
2020-08-31T17:02:23.160Z,1598893343.160 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2020-08-31T17:02:23.186Z,1598893343.186 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-08-31T17:02:23.186Z,1598893343.186 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2020-08-31T17:02:23.551Z,1598893343.551 [DAT](INFO): setting remote address to 6
2020-08-31T17:02:23.949Z,1598893343.949 [DAT](INFO): set remote address to 6
2020-08-31T17:02:23.950Z,1598893343.950 [DAT](INFO): entering online mode
2020-08-31T17:02:24.123Z,1598893344.123 [DataOverHttps](INFO): Moved sent file to Logs/20200831T164333/Courier0007.lzma.bak
2020-08-31T17:02:24.123Z,1598893344.123 [DataOverHttps](INFO): SBD MOMSN=12625658
2020-08-31T17:02:24.382Z,1598893344.382 [DAT](INFO): commRate: 800
2020-08-31T17:02:24.382Z,1598893344.382 [DAT](INFO): online mode acknowledged
2020-08-31T17:02:24.383Z,1598893344.383 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2020-08-31T17:02:27.984Z,1598893347.984 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2020-08-31T17:02:29.094Z,1598893349.094 [CommandLine](IMPORTANT): got command strobe off
2020-08-31T17:02:29.094Z,1598893349.094 [CommandLine](IMPORTANT): Deactivating strobe
2020-08-31T17:02:35.359Z,1598893355.359 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-08-31T17:02:39.985Z,1598893359.985 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20200831T165910/Courier0000.lzma
2020-08-31T17:02:40.987Z,1598893360.987 [DataOverHttps](INFO): Moved sent file to Logs/20200831T165910/Courier0000.lzma.bak
2020-08-31T17:02:40.987Z,1598893360.987 [DataOverHttps](INFO): SBD MOMSN=12625660
2020-08-31T17:02:44.577Z,1598893364.577 [DAT](FAULT): Ack receipt timeout failure.
2020-08-31T17:02:44.577Z,1598893364.577 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER
2020-08-31T17:02:44.963Z,1598893364.963 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2020-08-31T17:02:45.001Z,1598893365.001 [Startup:StartupSatComms:B] Stopped
2020-08-31T17:02:45.001Z,1598893365.001 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2020-08-31T17:02:45.001Z,1598893365.001 [Startup:StartupSatComms] Stopped
2020-08-31T17:02:45.001Z,1598893365.001 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-08-31T17:02:45.002Z,1598893365.002 [Startup](INFO): Completed Startup
2020-08-31T17:02:45.002Z,1598893365.002 [MissionManager](INFO): Startup is completed.
2020-08-31T17:02:45.002Z,1598893365.002 [MissionManager](INFO): Uninitializing Mission Startup
2020-08-31T17:02:45.002Z,1598893365.002 [Startup] Stopped
2020-08-31T17:02:45.003Z,1598893365.003 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-08-31T17:02:45.003Z,1598893365.003 [Startup:A.GoToSurface] Stopped
2020-08-31T17:02:45.003Z,1598893365.003 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-08-31T17:02:45.381Z,1598893365.381 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2020-08-31T17:02:45.492Z,1598893365.492 [MissionManager](IMPORTANT): Started mission Default
2020-08-31T17:02:45.506Z,1598893365.506 [Default] Running Loop=1
2020-08-31T17:02:45.507Z,1598893365.507 [Default](DEBUG): Aggregate::initialize Default
2020-08-31T17:02:45.507Z,1598893365.507 [Default:B.GoToSurface] Running Loop=1
2020-08-31T17:02:45.507Z,1598893365.507 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-08-31T17:02:45.509Z,1598893365.509 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-08-31T17:02:45.520Z,1598893365.520 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-08-31T17:02:45.530Z,1598893365.530 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-08-31T17:02:45.532Z,1598893365.532 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-08-31T17:02:45.542Z,1598893365.542 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-08-31T17:02:45.543Z,1598893365.543 [Default:A.Wait] Running Loop=1
2020-08-31T17:02:45.543Z,1598893365.543 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-08-31T17:02:49.007Z,1598893369.007 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2020-08-31T17:02:57.055Z,1598893377.055 [DataOverHttps](INFO): Sending 563 bytes from file Logs/20200831T164333/Express0005.lzma
2020-08-31T17:02:58.055Z,1598893378.055 [DataOverHttps](INFO): Moved sent file to Logs/20200831T164333/Express0005.lzma.bak
2020-08-31T17:02:58.055Z,1598893378.055 [DataOverHttps](INFO): SBD MOMSN=12625663
2020-08-31T17:02:58.715Z,1598893378.715 [Default:A.Wait](INFO): Done Waiting.
2020-08-31T17:02:58.715Z,1598893378.715 [Default:A.Wait] Stopped
2020-08-31T17:02:58.715Z,1598893378.715 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-08-31T17:02:59.130Z,1598893379.130 [Default:CheckIn] Running Loop=1
2020-08-31T17:02:59.130Z,1598893379.130 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-08-31T17:02:59.130Z,1598893379.130 [Default:CheckIn:Read_GPS] Running Loop=1
2020-08-31T17:02:59.517Z,1598893379.517 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-08-31T17:03:05.566Z,1598893385.566 [DAT](FAULT): Ack receipt timeout failure.
2020-08-31T17:03:05.566Z,1598893385.566 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER
2020-08-31T17:03:05.964Z,1598893385.964 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2020-08-31T17:03:06.387Z,1598893386.387 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2020-08-31T17:03:07.994Z,1598893387.994 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-08-31T17:03:10.000Z,1598893390.000 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2020-08-31T17:03:26.579Z,1598893406.579 [DAT](FAULT): Ack receipt timeout failure.
2020-08-31T17:03:26.579Z,1598893406.579 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER
2020-08-31T17:03:26.972Z,1598893406.972 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2020-08-31T17:03:27.400Z,1598893407.400 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2020-08-31T17:03:31.008Z,1598893411.008 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2020-08-31T17:03:47.590Z,1598893427.590 [DAT](FAULT): Ack receipt timeout failure.
2020-08-31T17:03:47.590Z,1598893427.590 [DAT](DEBUG): In sendingAckWaiting, hit max timeouts so set commsState_ = SENDING_VERIFIED
2020-08-31T17:03:47.976Z,1598893427.976 [DAT](DEBUG): In sendingVerified, sbd done so set commsState_ = SENDING_FILL_BUFFER
2020-08-31T17:05:00.377Z,1598893500.377 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-08-31T17:05:03.119Z,1598893503.119 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-08-31T17:05:12.853Z,1598893512.853 [BPC1](ERROR): BPC1B failed to parse battery stick message.
2020-08-31T17:05:18.948Z,1598893518.948 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-08-31T17:05:18.962Z,1598893518.962 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-08-31T17:05:23.745Z,1598893523.745 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2020-08-31T17:05:23.745Z,1598893523.745 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-08-31T17:05:23.766Z,1598893523.766 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-08-31T17:05:24.154Z,1598893524.154 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-08-31T17:05:24.154Z,1598893524.154 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2020-08-31T17:05:33.026Z,1598893533.026 [BPC1](ERROR): BPC1A failed to parse battery stick message.
2020-08-31T17:05:46.438Z,1598893546.438 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-08-31T17:07:02.068Z,1598893622.068 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-08-31T17:07:52.076Z,1598893672.076 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-08-31T17:07:59.286Z,1598893679.286 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-08-31T17:02:59.1Z
2020-08-31T17:07:59.286Z,1598893679.286 [Default:CheckIn:Read_GPS] Stopped
2020-08-31T17:07:59.286Z,1598893679.286 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-08-31T17:07:59.684Z,1598893679.684 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-08-31T17:08:00.078Z,1598893680.078 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2020-08-31T17:08:00.492Z,1598893680.492 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2020-08-31T17:08:03.758Z,1598893683.758 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2020-08-31T17:08:06.952Z,1598893686.952 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-08-31T17:08:06.955Z,1598893686.955 [BPC1](INFO): Received data from all battery sticks.
2020-08-31T17:08:07.012Z,1598893687.012 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20200831T165910/Courier0004.lzma
2020-08-31T17:08:08.015Z,1598893688.015 [DataOverHttps](INFO): Moved sent file to Logs/20200831T165910/Courier0004.lzma.bak
2020-08-31T17:08:08.015Z,1598893688.015 [DataOverHttps](INFO): SBD MOMSN=12625711
2020-08-31T17:08:20.702Z,1598893700.702 [DAT](FAULT): Ack receipt timeout failure.
2020-08-31T17:08:20.702Z,1598893700.702 [DAT](DEBUG): In sendingAckWaiting, hit max timeouts so set commsState_ = SENDING_VERIFIED
2020-08-31T17:08:21.080Z,1598893701.080 [DAT](INFO): Sent 38 bytes from file Logs/20200831T165910/Courier0004.lzma.parts
2020-08-31T17:08:21.080Z,1598893701.080 [DAT](INFO): Packets left to send: 0
2020-08-31T17:08:21.084Z,1598893701.084 [DAT](DEBUG): In sendingVerified, sbd waiting so set commsState_ = SENDING_FILL_BUFFER
2020-08-31T17:08:21.515Z,1598893701.515 [Default:CheckIn:Read_Iridium] Stopped
2020-08-31T17:08:21.515Z,1598893701.515 [Default:CheckIn:C.Wait] Running Loop=1
2020-08-31T17:08:21.515Z,1598893701.515 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-08-31T17:08:23.805Z,1598893703.805 [DataOverHttps](INFO): Sending 343 bytes from file Logs/20200831T164333/Express0008.lzma
2020-08-31T17:08:24.725Z,1598893704.725 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2020-08-31T17:08:24.725Z,1598893704.725 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-08-31T17:08:24.763Z,1598893704.763 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-08-31T17:08:24.808Z,1598893704.808 [DataOverHttps](INFO): Moved sent file to Logs/20200831T164333/Express0008.lzma.bak
2020-08-31T17:08:24.808Z,1598893704.808 [DataOverHttps](INFO): SBD MOMSN=12625713
2020-08-31T17:08:25.175Z,1598893705.175 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-08-31T17:08:25.175Z,1598893705.175 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2020-08-31T17:10:01.271Z,1598893801.271 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-08-31T17:10:01.271Z,1598893801.271 [NAL9602] Data Fault, FailCount= 1
2020-08-31T17:10:01.271Z,1598893801.271 [NAL9602](ERROR): Data Fault
2020-08-31T17:10:01.295Z,1598893801.295 [CBIT](ERROR): Data Fault in component: NAL9602
2020-08-31T17:10:01.682Z,1598893801.682 [NAL9602](INFO): Powering down
2020-08-31T17:10:02.508Z,1598893802.508 [CBIT](INFO): Clearing failed state for component NAL9602
2020-08-31T17:10:02.508Z,1598893802.508 [NAL9602] No Fault, FailCount= 1
2020-08-31T17:10:31.982Z,1598893831.982 [NAL9602](INFO): Powering up NAL9602
2020-08-31T17:10:42.887Z,1598893842.887 [NAL9602](INFO): NAL9602 initialized
2020-08-31T17:11:25.725Z,1598893885.725 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2020-08-31T17:11:25.725Z,1598893885.725 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-08-31T17:11:25.774Z,1598893885.774 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-08-31T17:11:26.129Z,1598893886.129 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-08-31T17:11:26.129Z,1598893886.129 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2020-08-31T17:13:22.066Z,1598894002.066 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-08-31T17:13:22.066Z,1598894002.066 [Default:CheckIn:C.Wait] Stopped
2020-08-31T17:13:22.066Z,1598894002.066 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-08-31T17:13:22.066Z,1598894002.066 [Default:CheckIn:D] Running Loop=1
2020-08-31T17:13:22.489Z,1598894002.489 [Default:CheckIn:D] Stopped
2020-08-31T17:13:22.490Z,1598894002.490 [Default:CheckIn:E] Running Loop=1
2020-08-31T17:13:22.887Z,1598894002.887 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.616615 min 
2020-08-31T17:13:22.887Z,1598894002.887 [Default:CheckIn:E] Stopped
2020-08-31T17:13:22.887Z,1598894002.887 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-08-31T17:13:22.887Z,1598894002.887 [Default:CheckIn] Stopped
2020-08-31T17:13:22.887Z,1598894002.887 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-08-31T17:13:22.888Z,1598894002.888 [Default:CheckIn](INFO): Running loop #2
2020-08-31T17:13:22.888Z,1598894002.888 [Default:CheckIn] Running Loop=2
2020-08-31T17:13:22.888Z,1598894002.888 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-08-31T17:13:22.888Z,1598894002.888 [Default:CheckIn:Read_GPS] Running Loop=1
2020-08-31T17:14:26.709Z,1598894066.709 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2020-08-31T17:14:26.709Z,1598894066.709 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-08-31T17:14:26.719Z,1598894066.719 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-08-31T17:14:27.127Z,1598894067.127 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-08-31T17:14:27.127Z,1598894067.127 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2020-08-31T17:17:27.705Z,1598894247.705 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2020-08-31T17:17:27.705Z,1598894247.705 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-08-31T17:17:27.715Z,1598894247.715 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-08-31T17:17:28.120Z,1598894248.120 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-08-31T17:17:28.120Z,1598894248.120 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2020-08-31T17:18:23.047Z,1598894303.047 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-08-31T17:13:22.9Z
2020-08-31T17:18:23.047Z,1598894303.047 [Default:CheckIn:Read_GPS] Stopped
2020-08-31T17:18:23.047Z,1598894303.047 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-08-31T17:18:23.876Z,1598894303.876 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2020-08-31T17:18:24.274Z,1598894304.274 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2020-08-31T17:18:27.899Z,1598894307.899 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2020-08-31T17:18:30.053Z,1598894310.053 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20200831T165910/Courier0007.lzma
2020-08-31T17:18:31.055Z,1598894311.055 [DataOverHttps](INFO): Moved sent file to Logs/20200831T165910/Courier0007.lzma.bak
2020-08-31T17:18:31.055Z,1598894311.055 [DataOverHttps](INFO): SBD MOMSN=12625732
2020-08-31T17:18:44.471Z,1598894324.471 [DAT](FAULT): Ack receipt timeout failure.
2020-08-31T17:18:44.471Z,1598894324.471 [DAT](DEBUG): In sendingAckWaiting, hit max timeouts so set commsState_ = SENDING_VERIFIED
2020-08-31T17:18:44.866Z,1598894324.866 [DAT](INFO): Sent 168 bytes from file Logs/20200831T165910/Courier0007.lzma.parts
2020-08-31T17:18:44.866Z,1598894324.866 [DAT](INFO): Packets left to send: 0
2020-08-31T17:18:44.869Z,1598894324.869 [DAT](DEBUG): In sendingVerified, sbd waiting so set commsState_ = SENDING_FILL_BUFFER
2020-08-31T17:18:45.332Z,1598894325.332 [Default:CheckIn:Read_Iridium] Stopped
2020-08-31T17:18:45.332Z,1598894325.332 [Default:CheckIn:C.Wait] Running Loop=1
2020-08-31T17:18:45.332Z,1598894325.332 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-08-31T17:18:46.882Z,1598894326.882 [DataOverHttps](INFO): Sending 936 bytes from file Logs/20200831T165910/Express0001.lzma
2020-08-31T17:18:47.883Z,1598894327.883 [DataOverHttps](INFO): Moved sent file to Logs/20200831T165910/Express0001.lzma.bak
2020-08-31T17:18:47.883Z,1598894327.883 [DataOverHttps](INFO): SBD MOMSN=12625760
2020-08-31T17:20:28.700Z,1598894428.700 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2020-08-31T17:20:28.700Z,1598894428.700 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-08-31T17:20:28.711Z,1598894428.711 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-08-31T17:20:29.122Z,1598894429.122 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-08-31T17:20:29.122Z,1598894429.122 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2020-08-31T17:20:44.039Z,1598894444.039 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-08-31T17:20:44.039Z,1598894444.039 [NAL9602] Data Fault, FailCount= 2
2020-08-31T17:20:44.039Z,1598894444.039 [NAL9602](ERROR): Data Fault
2020-08-31T17:20:44.092Z,1598894444.092 [CBIT](ERROR): Data Fault in component: NAL9602
2020-08-31T17:20:44.442Z,1598894444.442 [NAL9602](INFO): Powering down
2020-08-31T17:20:45.281Z,1598894445.281 [CBIT](INFO): Clearing failed state for component NAL9602
2020-08-31T17:20:45.281Z,1598894445.281 [NAL9602] No Fault, FailCount= 2
2020-08-31T17:21:14.744Z,1598894474.744 [NAL9602](INFO): Powering up NAL9602
2020-08-31T17:21:25.647Z,1598894485.647 [NAL9602](INFO): NAL9602 initialized
2020-08-31T17:23:29.713Z,1598894609.713 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2020-08-31T17:23:29.713Z,1598894609.713 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-08-31T17:23:29.723Z,1598894609.723 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-08-31T17:23:30.136Z,1598894610.136 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-08-31T17:23:30.136Z,1598894610.136 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2020-08-31T17:23:45.856Z,1598894625.856 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-08-31T17:23:45.856Z,1598894625.856 [Default:CheckIn:C.Wait] Stopped
2020-08-31T17:23:45.857Z,1598894625.857 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-08-31T17:23:45.857Z,1598894625.857 [Default:CheckIn:D] Running Loop=1
2020-08-31T17:23:46.296Z,1598894626.296 [Default:CheckIn:D] Stopped
2020-08-31T17:23:46.296Z,1598894626.296 [Default:CheckIn:E] Running Loop=1
2020-08-31T17:23:46.682Z,1598894626.682 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.013393 min 
2020-08-31T17:23:46.683Z,1598894626.683 [Default:CheckIn:E] Stopped
2020-08-31T17:23:46.683Z,1598894626.683 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-08-31T17:23:46.683Z,1598894626.683 [Default:CheckIn] Stopped
2020-08-31T17:23:46.683Z,1598894626.683 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-08-31T17:23:46.683Z,1598894626.683 [Default:CheckIn](INFO): Running loop #3
2020-08-31T17:23:46.683Z,1598894626.683 [Default:CheckIn] Running Loop=3
2020-08-31T17:23:46.683Z,1598894626.683 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-08-31T17:23:46.683Z,1598894626.683 [Default:CheckIn:Read_GPS] Running Loop=1
2020-08-31T17:26:30.709Z,1598894790.709 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2020-08-31T17:26:30.709Z,1598894790.709 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-08-31T17:26:30.719Z,1598894790.719 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-08-31T17:26:31.120Z,1598894791.120 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-08-31T17:26:31.120Z,1598894791.120 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2020-08-31T17:28:46.871Z,1598894926.871 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-08-31T17:23:46.7Z
2020-08-31T17:28:46.871Z,1598894926.871 [Default:CheckIn:Read_GPS] Stopped
2020-08-31T17:28:46.871Z,1598894926.871 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-08-31T17:28:47.673Z,1598894927.673 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2020-08-31T17:28:48.060Z,1598894928.060 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2020-08-31T17:28:51.289Z,1598894931.289 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2020-08-31T17:28:54.048Z,1598894934.048 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20200831T165910/Courier0010.lzma
2020-08-31T17:28:55.051Z,1598894935.051 [DataOverHttps](INFO): Moved sent file to Logs/20200831T165910/Courier0010.lzma.bak
2020-08-31T17:28:55.051Z,1598894935.051 [DataOverHttps](INFO): SBD MOMSN=12625789
2020-08-31T17:29:08.274Z,1598894948.274 [DAT](FAULT): Ack receipt timeout failure.
2020-08-31T17:29:08.274Z,1598894948.274 [DAT](DEBUG): In sendingAckWaiting, hit max timeouts so set commsState_ = SENDING_VERIFIED
2020-08-31T17:29:08.677Z,1598894948.677 [DAT](INFO): Sent 38 bytes from file Logs/20200831T165910/Courier0010.lzma.parts
2020-08-31T17:29:08.678Z,1598894948.678 [DAT](INFO): Packets left to send: 0
2020-08-31T17:29:08.687Z,1598894948.687 [DAT](DEBUG): In sendingVerified, sbd waiting so set commsState_ = SENDING_FILL_BUFFER
2020-08-31T17:29:09.143Z,1598894949.143 [Default:CheckIn:Read_Iridium] Stopped
2020-08-31T17:29:09.143Z,1598894949.143 [Default:CheckIn:C.Wait] Running Loop=1
2020-08-31T17:29:09.143Z,1598894949.143 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-08-31T17:29:10.766Z,1598894950.766 [DataOverHttps](INFO): Sending 270 bytes from file Logs/20200831T165910/Express0005.lzma
2020-08-31T17:29:11.767Z,1598894951.767 [DataOverHttps](INFO): Moved sent file to Logs/20200831T165910/Express0005.lzma.bak
2020-08-31T17:29:11.767Z,1598894951.767 [DataOverHttps](INFO): SBD MOMSN=12625791
2020-08-31T17:29:31.694Z,1598894971.694 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2020-08-31T17:29:31.694Z,1598894971.694 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-08-31T17:29:31.704Z,1598894971.704 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-08-31T17:29:32.121Z,1598894972.121 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-08-31T17:29:32.122Z,1598894972.122 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2020-08-31T17:31:28.847Z,1598895088.847 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-08-31T17:31:28.847Z,1598895088.847 [NAL9602] Data Fault, FailCount= 3
2020-08-31T17:31:28.847Z,1598895088.847 [NAL9602](ERROR): Data Fault
2020-08-31T17:31:28.864Z,1598895088.864 [CBIT](ERROR): Data Fault in component: NAL9602
2020-08-31T17:31:29.255Z,1598895089.255 [NAL9602](INFO): Powering down
2020-08-31T17:31:30.095Z,1598895090.095 [CBIT](INFO): Clearing failed state for component NAL9602
2020-08-31T17:31:30.095Z,1598895090.095 [NAL9602] No Fault, FailCount= 3
2020-08-31T17:31:59.555Z,1598895119.555 [NAL9602](INFO): Powering up NAL9602
2020-08-31T17:32:10.467Z,1598895130.467 [NAL9602](INFO): NAL9602 initialized
2020-08-31T17:32:32.697Z,1598895152.697 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2020-08-31T17:32:32.697Z,1598895152.697 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-08-31T17:32:32.707Z,1598895152.707 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-08-31T17:32:33.110Z,1598895153.110 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-08-31T17:32:33.110Z,1598895153.110 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2020-08-31T17:34:09.663Z,1598895249.663 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-08-31T17:34:09.664Z,1598895249.664 [Default:CheckIn:C.Wait] Stopped
2020-08-31T17:34:09.664Z,1598895249.664 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-08-31T17:34:09.664Z,1598895249.664 [Default:CheckIn:D] Running Loop=1
2020-08-31T17:34:10.066Z,1598895250.066 [Default:CheckIn:D] Stopped
2020-08-31T17:34:10.066Z,1598895250.066 [Default:CheckIn:E] Running Loop=1
2020-08-31T17:34:10.480Z,1598895250.480 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.409558 min 
2020-08-31T17:34:10.480Z,1598895250.480 [Default:CheckIn:E] Stopped
2020-08-31T17:34:10.480Z,1598895250.480 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-08-31T17:34:10.480Z,1598895250.480 [Default:CheckIn] Stopped
2020-08-31T17:34:10.480Z,1598895250.480 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-08-31T17:34:10.480Z,1598895250.480 [Default:CheckIn](INFO): Running loop #4
2020-08-31T17:34:10.480Z,1598895250.480 [Default:CheckIn] Running Loop=4
2020-08-31T17:34:10.481Z,1598895250.481 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-08-31T17:34:10.481Z,1598895250.481 [Default:CheckIn:Read_GPS] Running Loop=1
2020-08-31T17:35:33.689Z,1598895333.689 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2020-08-31T17:35:33.689Z,1598895333.689 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-08-31T17:35:33.700Z,1598895333.700 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-08-31T17:35:34.094Z,1598895334.094 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-08-31T17:35:34.094Z,1598895334.094 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2020-08-31T17:38:34.725Z,1598895514.725 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2020-08-31T17:38:34.725Z,1598895514.725 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-08-31T17:38:34.735Z,1598895514.735 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-08-31T17:38:35.109Z,1598895515.109 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-08-31T17:38:35.109Z,1598895515.109 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2020-08-31T17:39:10.650Z,1598895550.650 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-08-31T17:34:10.5Z
2020-08-31T17:39:10.650Z,1598895550.650 [Default:CheckIn:Read_GPS] Stopped
2020-08-31T17:39:10.650Z,1598895550.650 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-08-31T17:39:11.459Z,1598895551.459 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2020-08-31T17:39:11.882Z,1598895551.882 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2020-08-31T17:39:15.502Z,1598895555.502 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2020-08-31T17:39:18.401Z,1598895558.401 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200831T165910/Courier0013.lzma
2020-08-31T17:39:19.402Z,1598895559.402 [DataOverHttps](INFO): Moved sent file to Logs/20200831T165910/Courier0013.lzma.bak
2020-08-31T17:39:19.402Z,1598895559.402 [DataOverHttps](INFO): SBD MOMSN=12625807
2020-08-31T17:39:32.035Z,1598895572.035 [DAT](FAULT): Ack receipt timeout failure.
2020-08-31T17:39:32.035Z,1598895572.035 [DAT](DEBUG): In sendingAckWaiting, hit max timeouts so set commsState_ = SENDING_VERIFIED
2020-08-31T17:39:32.463Z,1598895572.463 [DAT](INFO): Sent 39 bytes from file Logs/20200831T165910/Courier0013.lzma.parts
2020-08-31T17:39:32.463Z,1598895572.463 [DAT](INFO): Packets left to send: 0
2020-08-31T17:39:32.469Z,1598895572.469 [DAT](DEBUG): In sendingVerified, sbd waiting so set commsState_ = SENDING_FILL_BUFFER
2020-08-31T17:39:32.868Z,1598895572.868 [Default:CheckIn:Read_Iridium] Stopped
2020-08-31T17:39:32.868Z,1598895572.868 [Default:CheckIn:C.Wait] Running Loop=1
2020-08-31T17:39:32.869Z,1598895572.869 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-08-31T17:39:35.203Z,1598895575.203 [DataOverHttps](INFO): Sending 315 bytes from file Logs/20200831T165910/Express0008.lzma
2020-08-31T17:39:36.195Z,1598895576.195 [DataOverHttps](INFO): Moved sent file to Logs/20200831T165910/Express0008.lzma.bak
2020-08-31T17:39:36.195Z,1598895576.195 [DataOverHttps](INFO): SBD MOMSN=12625809
2020-08-31T17:41:35.701Z,1598895695.701 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2020-08-31T17:41:35.701Z,1598895695.701 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-08-31T17:41:35.733Z,1598895695.733 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-08-31T17:41:36.107Z,1598895696.107 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-08-31T17:41:36.107Z,1598895696.107 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2020-08-31T17:42:12.423Z,1598895732.423 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-08-31T17:42:12.423Z,1598895732.423 [NAL9602] Data Fault, FailCount= 4
2020-08-31T17:42:12.423Z,1598895732.423 [NAL9602](ERROR): Data Fault
2020-08-31T17:42:12.465Z,1598895732.465 [CBIT](ERROR): Data Fault in component: NAL9602
2020-08-31T17:42:12.834Z,1598895732.834 [NAL9602](INFO): Powering down
2020-08-31T17:42:13.659Z,1598895733.659 [CBIT](INFO): Clearing failed state for component NAL9602
2020-08-31T17:42:13.659Z,1598895733.659 [NAL9602] No Fault, FailCount= 4
2020-08-31T17:42:43.130Z,1598895763.130 [NAL9602](INFO): Powering up NAL9602
2020-08-31T17:42:54.038Z,1598895774.039 [NAL9602](INFO): NAL9602 initialized
2020-08-31T17:44:33.442Z,1598895873.442 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-08-31T17:44:33.442Z,1598895873.442 [Default:CheckIn:C.Wait] Stopped
2020-08-31T17:44:33.442Z,1598895873.442 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-08-31T17:44:33.442Z,1598895873.442 [Default:CheckIn:D] Running Loop=1
2020-08-31T17:44:33.847Z,1598895873.847 [Default:CheckIn:D] Stopped
2020-08-31T17:44:33.847Z,1598895873.847 [Default:CheckIn:E] Running Loop=1
2020-08-31T17:44:34.267Z,1598895874.267 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 41.805900 min 
2020-08-31T17:44:34.267Z,1598895874.267 [Default:CheckIn:E] Stopped
2020-08-31T17:44:34.268Z,1598895874.268 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-08-31T17:44:34.268Z,1598895874.268 [Default:CheckIn] Stopped
2020-08-31T17:44:34.268Z,1598895874.268 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-08-31T17:44:34.268Z,1598895874.268 [Default:CheckIn](INFO): Running loop #5
2020-08-31T17:44:34.268Z,1598895874.268 [Default:CheckIn] Running Loop=5
2020-08-31T17:44:34.268Z,1598895874.268 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-08-31T17:44:34.268Z,1598895874.268 [Default:CheckIn:Read_GPS] Running Loop=1
2020-08-31T17:44:36.658Z,1598895876.658 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2020-08-31T17:44:36.658Z,1598895876.658 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-08-31T17:44:36.668Z,1598895876.668 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-08-31T17:44:37.094Z,1598895877.094 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-08-31T17:44:37.094Z,1598895877.094 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2020-08-31T17:47:37.673Z,1598896057.673 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2020-08-31T17:47:37.673Z,1598896057.673 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-08-31T17:47:37.683Z,1598896057.683 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-08-31T17:47:38.082Z,1598896058.082 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-08-31T17:47:38.082Z,1598896058.082 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2020-08-31T17:49:34.442Z,1598896174.442 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-08-31T17:44:34.3Z
2020-08-31T17:49:34.442Z,1598896174.442 [Default:CheckIn:Read_GPS] Stopped
2020-08-31T17:49:34.442Z,1598896174.442 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-08-31T17:49:35.223Z,1598896175.223 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2020-08-31T17:49:35.642Z,1598896175.642 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2020-08-31T17:49:38.876Z,1598896178.876 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2020-08-31T17:49:41.360Z,1598896181.360 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20200831T165910/Courier0016.lzma
2020-08-31T17:49:42.363Z,1598896182.363 [DataOverHttps](INFO): Moved sent file to Logs/20200831T165910/Courier0016.lzma.bak
2020-08-31T17:49:42.363Z,1598896182.363 [DataOverHttps](INFO): SBD MOMSN=12625821
2020-08-31T17:49:55.849Z,1598896195.849 [DAT](FAULT): Ack receipt timeout failure.
2020-08-31T17:49:55.853Z,1598896195.853 [DAT](DEBUG): In sendingAckWaiting, hit max timeouts so set commsState_ = SENDING_VERIFIED
2020-08-31T17:49:56.216Z,1598896196.216 [DAT](INFO): Sent 38 bytes from file Logs/20200831T165910/Courier0016.lzma.parts
2020-08-31T17:49:56.216Z,1598896196.216 [DAT](INFO): Packets left to send: 0
2020-08-31T17:49:56.219Z,1598896196.219 [DAT](DEBUG): In sendingVerified, sbd waiting so set commsState_ = SENDING_FILL_BUFFER
2020-08-31T17:49:56.636Z,1598896196.636 [Default:CheckIn:Read_Iridium] Stopped
2020-08-31T17:49:56.636Z,1598896196.636 [Default:CheckIn:C.Wait] Running Loop=1
2020-08-31T17:49:56.636Z,1598896196.636 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-08-31T17:49:58.149Z,1598896198.149 [DataOverHttps](INFO): Sending 187 bytes from file Logs/20200831T165910/Express0011.lzma
2020-08-31T17:49:59.151Z,1598896199.151 [DataOverHttps](INFO): Moved sent file to Logs/20200831T165910/Express0011.lzma.bak
2020-08-31T17:49:59.152Z,1598896199.152 [DataOverHttps](INFO): SBD MOMSN=12625823
2020-08-31T17:50:38.718Z,1598896238.718 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17
2020-08-31T17:50:38.718Z,1598896238.718 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-08-31T17:50:38.728Z,1598896238.728 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-08-31T17:50:39.090Z,1598896239.090 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-08-31T17:50:39.090Z,1598896239.090 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17
2020-08-31T17:52:57.287Z,1598896377.287 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-08-31T17:52:57.287Z,1598896377.287 [NAL9602] Data Fault, FailCount= 5
2020-08-31T17:52:57.287Z,1598896377.287 [NAL9602](ERROR): Data Fault
2020-08-31T17:52:57.350Z,1598896377.350 [CBIT](ERROR): Data Fault in component: NAL9602
2020-08-31T17:52:57.351Z,1598896377.351 [CBIT](CRITICAL): Data Fault in component: NAL9602
2020-08-31T17:52:57.691Z,1598896377.691 [NAL9602](INFO): Powering down
2020-08-31T17:52:57.711Z,1598896377.711 [CommandLine](FAULT): Scheduling is paused
2020-08-31T17:52:57.711Z,1598896377.711 [CBIT](INFO): Critical error at 20200831T175257
2020-08-31T17:52:57.712Z,1598896377.712 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2020-08-31T17:53:39.751Z,1598896419.751 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18
2020-08-31T17:53:39.751Z,1598896419.751 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-08-31T17:53:39.762Z,1598896419.762 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-08-31T17:53:40.158Z,1598896420.158 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-08-31T17:53:40.158Z,1598896420.158 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18
2020-08-31T17:54:57.318Z,1598896497.318 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-08-31T17:54:57.319Z,1598896497.319 [Default:CheckIn:C.Wait] Stopped
2020-08-31T17:54:57.319Z,1598896497.319 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-08-31T17:54:57.319Z,1598896497.319 [Default:CheckIn:D] Running Loop=1
2020-08-31T17:54:57.328Z,1598896497.328 [CBIT](INFO): Clearing failed state for component NAL9602
2020-08-31T17:54:57.328Z,1598896497.328 [NAL9602] No Fault, FailCount= 5
2020-08-31T17:54:57.698Z,1598896497.698 [NAL9602](INFO): Powering up NAL9602
2020-08-31T17:54:57.725Z,1598896497.725 [Default:CheckIn:D] Stopped
2020-08-31T17:54:57.725Z,1598896497.725 [Default:CheckIn:E] Running Loop=1
2020-08-31T17:54:58.110Z,1598896498.110 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.203882 min 
2020-08-31T17:54:58.110Z,1598896498.110 [Default:CheckIn:E] Stopped
2020-08-31T17:54:58.110Z,1598896498.110 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-08-31T17:54:58.110Z,1598896498.110 [Default:CheckIn] Stopped
2020-08-31T17:54:58.110Z,1598896498.110 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-08-31T17:54:58.110Z,1598896498.110 [Default:CheckIn](INFO): Running loop #6
2020-08-31T17:54:58.110Z,1598896498.110 [Default:CheckIn] Running Loop=6
2020-08-31T17:54:58.110Z,1598896498.110 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-08-31T17:54:58.110Z,1598896498.110 [Default:CheckIn:Read_GPS] Running Loop=1
2020-08-31T17:55:08.607Z,1598896508.607 [NAL9602](INFO): NAL9602 initialized
2020-08-31T17:56:40.729Z,1598896600.729 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19
2020-08-31T17:56:40.729Z,1598896600.729 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-08-31T17:56:40.740Z,1598896600.740 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-08-31T17:56:41.146Z,1598896601.146 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-08-31T17:56:41.146Z,1598896601.146 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19
2020-08-31T17:59:41.732Z,1598896781.732 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 20
2020-08-31T17:59:41.732Z,1598896781.732 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-08-31T17:59:41.742Z,1598896781.742 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-08-31T17:59:42.160Z,1598896782.160 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-08-31T17:59:42.160Z,1598896782.160 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 20
2020-08-31T17:59:58.319Z,1598896798.319 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-08-31T17:54:58.1Z
2020-08-31T17:59:58.320Z,1598896798.320 [Default:CheckIn:Read_GPS] Stopped
2020-08-31T17:59:58.320Z,1598896798.320 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-08-31T17:59:59.103Z,1598896799.103 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2020-08-31T17:59:59.528Z,1598896799.528 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2020-08-31T18:00:03.164Z,1598896803.164 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2020-08-31T18:00:05.893Z,1598896805.893 [DataOverHttps](INFO): Sending 105 bytes from file Logs/20200831T165910/Courier0019.lzma
2020-08-31T18:00:06.895Z,1598896806.895 [DataOverHttps](INFO): Moved sent file to Logs/20200831T165910/Courier0019.lzma.bak
2020-08-31T18:00:06.895Z,1598896806.895 [DataOverHttps](INFO): SBD MOMSN=12625834
2020-08-31T18:00:19.742Z,1598896819.742 [DAT](FAULT): Ack receipt timeout failure.
2020-08-31T18:00:19.742Z,1598896819.742 [DAT](DEBUG): In sendingAckWaiting, hit max timeouts so set commsState_ = SENDING_VERIFIED
2020-08-31T18:00:20.146Z,1598896820.146 [DAT](INFO): Sent 105 bytes from file Logs/20200831T165910/Courier0019.lzma.parts
2020-08-31T18:00:20.146Z,1598896820.146 [DAT](INFO): Packets left to send: 0
2020-08-31T18:00:20.149Z,1598896820.149 [DAT](DEBUG): In sendingVerified, sbd waiting so set commsState_ = SENDING_FILL_BUFFER
2020-08-31T18:00:20.642Z,1598896820.642 [Default:CheckIn:Read_Iridium] Stopped
2020-08-31T18:00:20.642Z,1598896820.642 [Default:CheckIn:C.Wait] Running Loop=1
2020-08-31T18:00:20.642Z,1598896820.642 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-08-31T18:00:22.563Z,1598896822.563 [DataOverHttps](INFO): Sending 177 bytes from file Logs/20200831T165910/Express0014.lzma
2020-08-31T18:00:23.563Z,1598896823.563 [DataOverHttps](INFO): Moved sent file to Logs/20200831T165910/Express0014.lzma.bak
2020-08-31T18:00:23.563Z,1598896823.563 [DataOverHttps](INFO): SBD MOMSN=12625837
2020-08-31T18:02:42.773Z,1598896962.773 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 21
2020-08-31T18:02:42.773Z,1598896962.773 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-08-31T18:02:42.783Z,1598896962.783 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-08-31T18:02:43.194Z,1598896963.194 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-08-31T18:02:43.194Z,1598896963.194 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 21
2020-08-31T18:04:26.178Z,1598897066.178 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-08-31T18:05:09.803Z,1598897109.803 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-08-31T18:05:09.803Z,1598897109.803 [NAL9602] Data Fault, FailCount= 1
2020-08-31T18:05:09.803Z,1598897109.803 [NAL9602](ERROR): Data Fault
2020-08-31T18:05:09.858Z,1598897109.858 [CBIT](ERROR): Data Fault in component: NAL9602
2020-08-31T18:05:10.202Z,1598897110.202 [NAL9602](INFO): Powering down
2020-08-31T18:05:11.090Z,1598897111.090 [CBIT](INFO): Clearing failed state for component NAL9602
2020-08-31T18:05:11.090Z,1598897111.090 [NAL9602] No Fault, FailCount= 1
2020-08-31T18:05:21.122Z,1598897121.122 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-08-31T18:05:21.122Z,1598897121.122 [Default:CheckIn:C.Wait] Stopped
2020-08-31T18:05:21.122Z,1598897121.122 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-08-31T18:05:21.122Z,1598897121.122 [Default:CheckIn:D] Running Loop=1
2020-08-31T18:05:21.537Z,1598897121.537 [Default:CheckIn:D] Stopped
2020-08-31T18:05:21.537Z,1598897121.537 [Default:CheckIn:E] Running Loop=1
2020-08-31T18:05:21.938Z,1598897121.938 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 62.600732 min 
2020-08-31T18:05:21.938Z,1598897121.938 [Default:CheckIn:E] Stopped
2020-08-31T18:05:21.939Z,1598897121.939 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-08-31T18:05:21.939Z,1598897121.939 [Default:CheckIn] Stopped
2020-08-31T18:05:21.939Z,1598897121.939 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-08-31T18:05:21.939Z,1598897121.939 [Default:CheckIn](INFO): Running loop #7
2020-08-31T18:05:21.939Z,1598897121.939 [Default:CheckIn] Running Loop=7
2020-08-31T18:05:21.939Z,1598897121.939 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-08-31T18:05:21.939Z,1598897121.939 [Default:CheckIn:Read_GPS] Running Loop=1
2020-08-31T18:05:40.502Z,1598897140.502 [NAL9602](INFO): Powering up NAL9602
2020-08-31T18:05:43.751Z,1598897143.751 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 22
2020-08-31T18:05:43.752Z,1598897143.752 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-08-31T18:05:43.762Z,1598897143.762 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-08-31T18:05:44.175Z,1598897144.175 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-08-31T18:05:44.175Z,1598897144.175 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 22
2020-08-31T18:05:51.411Z,1598897151.411 [NAL9602](INFO): NAL9602 initialized
2020-08-31T18:08:44.765Z,1598897324.765 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 23
2020-08-31T18:08:44.765Z,1598897324.765 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-08-31T18:08:44.775Z,1598897324.775 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-08-31T18:08:45.231Z,1598897325.231 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-08-31T18:08:45.231Z,1598897325.231 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 23
2020-08-31T18:10:22.190Z,1598897422.190 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-08-31T18:05:21.9Z
2020-08-31T18:10:22.190Z,1598897422.190 [Default:CheckIn:Read_GPS] Stopped
2020-08-31T18:10:22.190Z,1598897422.190 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-08-31T18:10:22.935Z,1598897422.935 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2020-08-31T18:10:23.358Z,1598897423.358 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2020-08-31T18:10:26.592Z,1598897426.592 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2020-08-31T18:10:29.568Z,1598897429.568 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200831T165910/Courier0022.lzma
2020-08-31T18:10:30.571Z,1598897430.571 [DataOverHttps](INFO): Moved sent file to Logs/20200831T165910/Courier0022.lzma.bak
2020-08-31T18:10:30.571Z,1598897430.571 [DataOverHttps](INFO): SBD MOMSN=12625848
2020-08-31T18:10:43.557Z,1598897443.557 [DAT](FAULT): Ack receipt timeout failure.
2020-08-31T18:10:43.557Z,1598897443.557 [DAT](DEBUG): In sendingAckWaiting, hit max timeouts so set commsState_ = SENDING_VERIFIED
2020-08-31T18:10:43.944Z,1598897443.944 [DAT](INFO): Sent 39 bytes from file Logs/20200831T165910/Courier0022.lzma.parts
2020-08-31T18:10:43.944Z,1598897443.944 [DAT](INFO): Packets left to send: 0
2020-08-31T18:10:43.946Z,1598897443.946 [DAT](DEBUG): In sendingVerified, sbd waiting so set commsState_ = SENDING_FILL_BUFFER
2020-08-31T18:10:44.418Z,1598897444.418 [Default:CheckIn:Read_Iridium] Stopped
2020-08-31T18:10:44.418Z,1598897444.418 [Default:CheckIn:C.Wait] Running Loop=1
2020-08-31T18:10:44.418Z,1598897444.418 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-08-31T18:10:47.934Z,1598897447.934 [DataOverHttps](INFO): Sending 187 bytes from file Logs/20200831T165910/Express0017.lzma
2020-08-31T18:10:48.935Z,1598897448.935 [DataOverHttps](INFO): Moved sent file to Logs/20200831T165910/Express0017.lzma.bak
2020-08-31T18:10:48.935Z,1598897448.935 [DataOverHttps](INFO): SBD MOMSN=12625850
2020-08-31T18:11:45.769Z,1598897505.769 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 24
2020-08-31T18:11:45.769Z,1598897505.769 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-08-31T18:11:45.800Z,1598897505.800 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-08-31T18:11:46.249Z,1598897506.249 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-08-31T18:11:46.250Z,1598897506.250 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 24
2020-08-31T18:14:46.774Z,1598897686.774 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 25
2020-08-31T18:14:46.774Z,1598897686.774 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-08-31T18:14:46.788Z,1598897686.788 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-08-31T18:14:47.190Z,1598897687.190 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-08-31T18:14:47.190Z,1598897687.190 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 25
2020-08-31T18:15:36.063Z,1598897736.063 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-08-31T18:15:36.066Z,1598897736.066 [BPC1](INFO): Received data from all battery sticks.
2020-08-31T18:15:44.953Z,1598897744.953 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-08-31T18:15:44.953Z,1598897744.953 [Default:CheckIn:C.Wait] Stopped
2020-08-31T18:15:44.953Z,1598897744.953 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-08-31T18:15:44.953Z,1598897744.953 [Default:CheckIn:D] Running Loop=1
2020-08-31T18:15:45.357Z,1598897745.357 [Default:CheckIn:D] Stopped
2020-08-31T18:15:45.357Z,1598897745.357 [Default:CheckIn:E] Running Loop=1
2020-08-31T18:15:45.755Z,1598897745.755 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 72.997729 min 
2020-08-31T18:15:45.755Z,1598897745.755 [Default:CheckIn:E] Stopped
2020-08-31T18:15:45.756Z,1598897745.756 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-08-31T18:15:45.756Z,1598897745.756 [Default:CheckIn] Stopped
2020-08-31T18:15:45.756Z,1598897745.756 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-08-31T18:15:45.756Z,1598897745.756 [Default:CheckIn](INFO): Running loop #8
2020-08-31T18:15:45.756Z,1598897745.756 [Default:CheckIn] Running Loop=8
2020-08-31T18:15:45.756Z,1598897745.756 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-08-31T18:15:45.756Z,1598897745.756 [Default:CheckIn:Read_GPS] Running Loop=1
2020-08-31T18:15:52.619Z,1598897752.619 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-08-31T18:15:52.619Z,1598897752.619 [NAL9602] Data Fault, FailCount= 2
2020-08-31T18:15:52.619Z,1598897752.619 [NAL9602](ERROR): Data Fault
2020-08-31T18:15:52.635Z,1598897752.635 [CBIT](ERROR): Data Fault in component: NAL9602
2020-08-31T18:15:53.014Z,1598897753.014 [NAL9602](INFO): Powering down
2020-08-31T18:15:53.838Z,1598897753.838 [CBIT](INFO): Clearing failed state for component NAL9602
2020-08-31T18:15:53.838Z,1598897753.838 [NAL9602] No Fault, FailCount= 2
2020-08-31T18:16:23.319Z,1598897783.319 [NAL9602](INFO): Powering up NAL9602
2020-08-31T18:16:34.230Z,1598897794.230 [NAL9602](INFO): NAL9602 initialized
2020-08-31T18:17:47.765Z,1598897867.765 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 26
2020-08-31T18:17:47.765Z,1598897867.765 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-08-31T18:17:47.779Z,1598897867.779 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-08-31T18:17:48.193Z,1598897868.193 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-08-31T18:17:48.193Z,1598897868.193 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 26
2020-08-31T18:20:45.942Z,1598898045.942 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-08-31T18:15:45.8Z
2020-08-31T18:20:45.942Z,1598898045.942 [Default:CheckIn:Read_GPS] Stopped
2020-08-31T18:20:45.942Z,1598898045.942 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-08-31T18:20:46.751Z,1598898046.751 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2020-08-31T18:20:47.143Z,1598898047.143 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2020-08-31T18:20:48.753Z,1598898048.753 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 27
2020-08-31T18:20:48.753Z,1598898048.753 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-08-31T18:20:48.787Z,1598898048.787 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-08-31T18:20:49.164Z,1598898049.164 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-08-31T18:20:49.164Z,1598898049.164 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 27
2020-08-31T18:20:50.388Z,1598898050.388 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2020-08-31T18:20:53.488Z,1598898053.488 [DataOverHttps](INFO): Sending 50 bytes from file Logs/20200831T165910/Courier0025.lzma
2020-08-31T18:20:54.491Z,1598898054.491 [DataOverHttps](INFO): Moved sent file to Logs/20200831T165910/Courier0025.lzma.bak
2020-08-31T18:20:54.491Z,1598898054.491 [DataOverHttps](INFO): SBD MOMSN=12625876
2020-08-31T18:21:07.342Z,1598898067.342 [DAT](FAULT): Ack receipt timeout failure.
2020-08-31T18:21:07.342Z,1598898067.342 [DAT](DEBUG): In sendingAckWaiting, hit max timeouts so set commsState_ = SENDING_VERIFIED
2020-08-31T18:21:07.740Z,1598898067.740 [DAT](INFO): Sent 50 bytes from file Logs/20200831T165910/Courier0025.lzma.parts
2020-08-31T18:21:07.740Z,1598898067.740 [DAT](INFO): Packets left to send: 0
2020-08-31T18:21:07.742Z,1598898067.742 [DAT](DEBUG): In sendingVerified, sbd waiting so set commsState_ = SENDING_FILL_BUFFER
2020-08-31T18:21:08.183Z,1598898068.183 [Default:CheckIn:Read_Iridium] Stopped
2020-08-31T18:21:08.183Z,1598898068.183 [Default:CheckIn:C.Wait] Running Loop=1
2020-08-31T18:21:08.184Z,1598898068.184 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-08-31T18:21:10.377Z,1598898070.377 [DataOverHttps](INFO): Sending 210 bytes from file Logs/20200831T165910/Express0020.lzma
2020-08-31T18:21:11.379Z,1598898071.379 [DataOverHttps](INFO): Moved sent file to Logs/20200831T165910/Express0020.lzma.bak
2020-08-31T18:21:11.379Z,1598898071.379 [DataOverHttps](INFO): SBD MOMSN=12625878
2020-08-31T18:22:08.346Z,1598898128.346 [Depth_Keller](ERROR): Pressure reading out of range: 1650.297119 decibar
2020-08-31T18:22:10.765Z,1598898130.765 [MassServo](ERROR): getPosition uart error serial timeout
2020-08-31T18:22:10.766Z,1598898130.766 [MassServo](FAULT): Invalid EZ Servo response:""
2020-08-31T18:22:10.766Z,1598898130.766 [MassServo] Communications Fault, FailCount= 1
2020-08-31T18:22:10.766Z,1598898130.766 [MassServo](ERROR): Communications Fault
2020-08-31T18:22:10.788Z,1598898130.788 [CBIT](ERROR): Communications Fault in component: MassServo
2020-08-31T18:22:10.901Z,1598898130.901 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-08-31T18:22:10.902Z,1598898130.902 [MassServo](INFO): Powering down
2020-08-31T18:22:11.652Z,1598898131.652 [MassServo](DEBUG): Initializing EZServoServo.
2020-08-31T18:22:11.653Z,1598898131.653 [MassServo](DEBUG): Initializing MassServo.
2020-08-31T18:22:11.698Z,1598898131.698 [CBIT](INFO): Clearing failed state for component MassServo
2020-08-31T18:22:11.699Z,1598898131.699 [MassServo] No Fault, FailCount= 1
2020-08-31T18:22:13.322Z,1598898133.322 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-08-31T18:22:14.153Z,1598898134.153 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2020-08-31T18:22:14.153Z,1598898134.153 [MassServo] Communications Fault, FailCount= 2
2020-08-31T18:22:14.153Z,1598898134.153 [MassServo](ERROR): Communications Fault
2020-08-31T18:22:14.157Z,1598898134.157 [CBIT](ERROR): Communications Fault in component: MassServo
2020-08-31T18:22:14.310Z,1598898134.310 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-08-31T18:22:14.310Z,1598898134.310 [MassServo](INFO): Powering down
2020-08-31T18:22:14.986Z,1598898134.986 [Depth_Keller](ERROR): Pressure reading out of range: 1650.297119 decibar
2020-08-31T18:22:15.100Z,1598898135.100 [MassServo](DEBUG): Initializing EZServoServo.
2020-08-31T18:22:15.117Z,1598898135.117 [MassServo](DEBUG): Initializing MassServo.
2020-08-31T18:22:15.143Z,1598898135.143 [CBIT](INFO): Clearing failed state for component MassServo
2020-08-31T18:22:15.143Z,1598898135.143 [MassServo] No Fault, FailCount= 2
2020-08-31T18:22:17.826Z,1598898137.826 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2020-08-31T18:22:17.826Z,1598898137.826 [MassServo] Communications Fault, FailCount= 3
2020-08-31T18:22:17.827Z,1598898137.827 [MassServo](ERROR): Communications Fault
2020-08-31T18:22:17.842Z,1598898137.842 [CBIT](ERROR): Communications Fault in component: MassServo
2020-08-31T18:22:18.246Z,1598898138.246 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-08-31T18:22:18.247Z,1598898138.247 [MassServo](INFO): Powering down
2020-08-31T18:22:18.790Z,1598898138.790 [MassServo](DEBUG): Initializing EZServoServo.
2020-08-31T18:22:18.790Z,1598898138.790 [MassServo](DEBUG): Initializing MassServo.
2020-08-31T18:22:18.795Z,1598898138.795 [CBIT](INFO): Clearing failed state for component MassServo
2020-08-31T18:22:18.795Z,1598898138.795 [MassServo] No Fault, FailCount= 3
2020-08-31T18:22:21.025Z,1598898141.025 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2020-08-31T18:22:21.025Z,1598898141.025 [MassServo] Communications Fault, FailCount= 4
2020-08-31T18:22:21.025Z,1598898141.025 [MassServo](ERROR): Communications Fault
2020-08-31T18:22:21.029Z,1598898141.029 [CBIT](ERROR): Communications Fault in component: MassServo
2020-08-31T18:22:21.123Z,1598898141.123 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-08-31T18:22:21.123Z,1598898141.123 [MassServo](INFO): Powering down
2020-08-31T18:22:21.955Z,1598898141.955 [MassServo](DEBUG): Initializing EZServoServo.
2020-08-31T18:22:21.956Z,1598898141.956 [MassServo](DEBUG): Initializing MassServo.
2020-08-31T18:22:21.972Z,1598898141.972 [CBIT](INFO): Clearing failed state for component MassServo
2020-08-31T18:22:21.972Z,1598898141.972 [MassServo] No Fault, FailCount= 4
2020-08-31T18:22:25.058Z,1598898145.058 [MassServo](FAULT): Mass initialization uart error:serial timeout
2020-08-31T18:22:25.058Z,1598898145.058 [MassServo] Communications Fault, FailCount= 5
2020-08-31T18:22:25.058Z,1598898145.058 [MassServo](ERROR): Communications Fault
2020-08-31T18:22:25.062Z,1598898145.062 [CBIT](ERROR): Communications Fault in component: MassServo
2020-08-31T18:22:25.063Z,1598898145.063 [CBIT](CRITICAL): Communications Fault in component: MassServo
2020-08-31T18:22:25.198Z,1598898145.198 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-08-31T18:22:25.198Z,1598898145.198 [MassServo](INFO): Powering down
2020-08-31T18:22:25.200Z,1598898145.200 [CBIT](INFO): Critical error at 20200831T182225
2020-08-31T18:22:25.992Z,1598898145.992 [MassServo](DEBUG): Initializing EZServoServo.
2020-08-31T18:22:25.992Z,1598898145.992 [MassServo](DEBUG): Initializing MassServo.
2020-08-31T18:22:28.374Z,1598898148.374 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-08-31T18:22:46.547Z,1598898166.547 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-08-31T18:22:46.547Z,1598898166.547 [RDI_Pathfinder] Communications Fault, FailCount= 1
2020-08-31T18:22:46.547Z,1598898166.547 [RDI_Pathfinder](ERROR): Communications Fault
2020-08-31T18:22:46.562Z,1598898166.562 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-08-31T18:22:46.951Z,1598898166.951 [RDI_Pathfinder](INFO): Powering down
2020-08-31T18:22:46.952Z,1598898166.952 [AMEcho](INFO): Powering up
2020-08-31T18:22:47.776Z,1598898167.776 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-08-31T18:22:47.776Z,1598898167.776 [RDI_Pathfinder] No Fault, FailCount= 1
2020-08-31T18:22:51.161Z,1598898171.161 [AMEcho](INFO): Powering down
2020-08-31T18:22:53.890Z,1598898173.890 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-08-31T18:22:58.910Z,1598898178.910 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.271961
2020-08-31T18:23:15.244Z,1598898195.244 [NAL9602](ERROR): parseGSV uart error: serial timeout
2020-08-31T18:23:19.681Z,1598898199.681 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-08-31T18:23:24.558Z,1598898204.558 [DAT](INFO): unknown deviceResponse_: [Bearing]

2020-08-31T18:23:24.559Z,1598898204.559 [DAT](INFO): commRate: 800
2020-08-31T18:23:36.661Z,1598898216.661 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-08-31T18:23:36.662Z,1598898216.662 [RDI_Pathfinder] Communications Fault, FailCount= 1
2020-08-31T18:23:36.662Z,1598898216.662 [RDI_Pathfinder](ERROR): Communications Fault
2020-08-31T18:23:36.710Z,1598898216.710 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-08-31T18:23:37.078Z,1598898217.078 [RDI_Pathfinder](INFO): Powering down
2020-08-31T18:23:37.079Z,1598898217.079 [AMEcho](INFO): Powering up
2020-08-31T18:23:37.898Z,1598898217.898 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-08-31T18:23:37.898Z,1598898217.898 [RDI_Pathfinder] No Fault, FailCount= 1
2020-08-31T18:23:41.262Z,1598898221.262 [AMEcho](INFO): Powering down
2020-08-31T18:23:49.599Z,1598898229.599 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 28
2020-08-31T18:23:49.599Z,1598898229.599 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-08-31T18:23:49.610Z,1598898229.610 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-08-31T18:23:50.018Z,1598898230.018 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-08-31T18:23:50.018Z,1598898230.018 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 28
2020-08-31T18:23:57.679Z,1598898237.679 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-08-31T18:24:04.231Z,1598898244.231 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-08-31T18:24:07.363Z,1598898247.363 [NAL9602](ERROR): parseGSV uart error: serial timeout
2020-08-31T18:24:28.382Z,1598898268.382 [DAT](INFO): unknown deviceResponse_: [Bearing]

2020-08-31T18:24:28.383Z,1598898268.383 [DAT](INFO): commRate: 800
2020-08-31T18:24:29.177Z,1598898269.177 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-08-31T18:24:29.177Z,1598898269.177 [RDI_Pathfinder] Communications Fault, FailCount= 1
2020-08-31T18:24:29.177Z,1598898269.177 [RDI_Pathfinder](ERROR): Communications Fault
2020-08-31T18:24:29.199Z,1598898269.199 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-08-31T18:24:29.580Z,1598898269.580 [RDI_Pathfinder](INFO): Powering down
2020-08-31T18:24:29.583Z,1598898269.583 [AMEcho](INFO): Powering up
2020-08-31T18:24:30.424Z,1598898270.424 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-08-31T18:24:30.425Z,1598898270.425 [RDI_Pathfinder] No Fault, FailCount= 1
2020-08-31T18:24:33.781Z,1598898273.781 [AMEcho](INFO): Powering down
2020-08-31T18:24:39.382Z,1598898279.382 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-08-31T18:24:55.975Z,1598898295.975 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.008430
2020-08-31T18:24:58.281Z,1598898298.281 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2020-08-31T18:24:58.281Z,1598898298.281 [AHRS_M2] Data Fault, FailCount= 1
2020-08-31T18:24:58.282Z,1598898298.282 [AHRS_M2](ERROR): Data Fault
2020-08-31T18:24:58.312Z,1598898298.312 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-08-31T18:24:58.313Z,1598898298.313 [RDI_Pathfinder] Communications Fault, FailCount= 1
2020-08-31T18:24:58.313Z,1598898298.313 [RDI_Pathfinder](ERROR): Communications Fault
2020-08-31T18:24:58.375Z,1598898298.375 [CBIT](ERROR): Data Fault in component: AHRS_M2
2020-08-31T18:24:58.376Z,1598898298.376 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-08-31T18:24:58.673Z,1598898298.673 [AHRS_M2](INFO): Powering down
2020-08-31T18:24:58.701Z,1598898298.701 [NAL9602](ERROR): parseGSV uart error: serial timeout
2020-08-31T18:24:58.701Z,1598898298.701 [RDI_Pathfinder](INFO): Powering down
2020-08-31T18:24:58.704Z,1598898298.704 [AMEcho](INFO): Powering up
2020-08-31T18:24:59.559Z,1598898299.559 [CBIT](INFO): Clearing failed state for component AHRS_M2
2020-08-31T18:24:59.559Z,1598898299.559 [AHRS_M2] No Fault, FailCount= 1
2020-08-31T18:24:59.559Z,1598898299.559 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-08-31T18:24:59.559Z,1598898299.559 [RDI_Pathfinder] No Fault, FailCount= 1
2020-08-31T18:24:59.885Z,1598898299.885 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-08-31T18:25:02.885Z,1598898302.885 [AMEcho](INFO): Powering down
2020-08-31T18:25:04.742Z,1598898304.742 [Depth_Keller](ERROR): Pressure reading out of range: 1650.297119 decibar
2020-08-31T18:25:18.101Z,1598898318.101 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2020-08-31T18:25:18.101Z,1598898318.101 [AHRS_M2] Data Fault, FailCount= 1
2020-08-31T18:25:18.101Z,1598898318.101 [AHRS_M2](ERROR): Data Fault
2020-08-31T18:25:18.173Z,1598898318.173 [CBIT](ERROR): Data Fault in component: AHRS_M2
2020-08-31T18:25:18.493Z,1598898318.493 [AHRS_M2](INFO): Powering down
2020-08-31T18:25:19.459Z,1598898319.459 [CBIT](INFO): Clearing failed state for component AHRS_M2
2020-08-31T18:25:19.459Z,1598898319.459 [AHRS_M2] No Fault, FailCount= 1
2020-08-31T18:25:19.705Z,1598898319.705 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-08-31T18:25:31.443Z,1598898331.443 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-08-31T18:25:31.444Z,1598898331.444 [RDI_Pathfinder] Communications Fault, FailCount= 1
2020-08-31T18:25:31.444Z,1598898331.444 [RDI_Pathfinder](ERROR): Communications Fault
2020-08-31T18:25:31.508Z,1598898331.508 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-08-31T18:25:31.842Z,1598898331.842 [Depth_Keller](ERROR): Pressure reading out of range: 1650.297119 decibar
2020-08-31T18:25:31.843Z,1598898331.843 [RDI_Pathfinder](INFO): Powering down
2020-08-31T18:25:31.845Z,1598898331.845 [AMEcho](INFO): Powering up
2020-08-31T18:25:32.747Z,1598898332.747 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-08-31T18:25:32.747Z,1598898332.747 [RDI_Pathfinder] No Fault, FailCount= 1
2020-08-31T18:25:36.050Z,1598898336.050 [AMEcho](INFO): Powering down
2020-08-31T18:25:43.962Z,1598898343.962 [Depth_Keller](ERROR): Pressure reading out of range: 1650.297119 decibar
2020-08-31T18:25:44.361Z,1598898344.361 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2020-08-31T18:25:44.361Z,1598898344.361 [AHRS_M2] Data Fault, FailCount= 1
2020-08-31T18:25:44.361Z,1598898344.361 [AHRS_M2](ERROR): Data Fault
2020-08-31T18:25:44.434Z,1598898344.434 [CBIT](ERROR): Data Fault in component: AHRS_M2
2020-08-31T18:25:44.753Z,1598898344.753 [AHRS_M2](INFO): Powering down
2020-08-31T18:25:45.566Z,1598898345.566 [Depth_Keller](ERROR): Pressure reading out of range: 1650.297119 decibar
2020-08-31T18:25:45.649Z,1598898345.649 [CBIT](INFO): Clearing failed state for component AHRS_M2
2020-08-31T18:25:45.649Z,1598898345.649 [AHRS_M2] No Fault, FailCount= 1
2020-08-31T18:25:45.966Z,1598898345.966 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-08-31T18:25:49.655Z,1598898349.655 [NAL9602](ERROR): parseGSV uart error: serial timeout
2020-08-31T18:26:04.973Z,1598898364.973 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2020-08-31T18:26:04.973Z,1598898364.973 [AHRS_M2] Data Fault, FailCount= 1
2020-08-31T18:26:04.974Z,1598898364.974 [AHRS_M2](ERROR): Data Fault
2020-08-31T18:26:05.023Z,1598898365.023 [CBIT](ERROR): Data Fault in component: AHRS_M2
2020-08-31T18:26:05.366Z,1598898365.366 [AHRS_M2](INFO): Powering down
2020-08-31T18:26:06.256Z,1598898366.256 [CBIT](INFO): Clearing failed state for component AHRS_M2
2020-08-31T18:26:06.256Z,1598898366.256 [AHRS_M2] No Fault, FailCount= 1
2020-08-31T18:26:06.577Z,1598898366.577 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-08-31T18:26:06.995Z,1598898366.995 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-08-31T18:26:06.995Z,1598898366.995 [RDI_Pathfinder] Communications Fault, FailCount= 1
2020-08-31T18:26:06.995Z,1598898366.995 [RDI_Pathfinder](ERROR): Communications Fault
2020-08-31T18:26:07.034Z,1598898367.034 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-08-31T18:26:07.400Z,1598898367.400 [RDI_Pathfinder](INFO): Powering down
2020-08-31T18:26:07.403Z,1598898367.403 [AMEcho](INFO): Powering up
2020-08-31T18:26:08.220Z,1598898368.220 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-08-31T18:26:08.220Z,1598898368.220 [RDI_Pathfinder] No Fault, FailCount= 1
2020-08-31T18:26:08.614Z,1598898368.614 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-08-31T18:26:08.614Z,1598898368.614 [Default:CheckIn:C.Wait] Stopped
2020-08-31T18:26:08.614Z,1598898368.614 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-08-31T18:26:08.615Z,1598898368.615 [Default:CheckIn:D] Running Loop=1
2020-08-31T18:26:09.014Z,1598898369.014 [Default:CheckIn:D] Stopped
2020-08-31T18:26:09.014Z,1598898369.014 [Default:CheckIn:E] Running Loop=1
2020-08-31T18:26:09.418Z,1598898369.418 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 83.392017 min 
2020-08-31T18:26:09.418Z,1598898369.418 [Default:CheckIn:E] Stopped
2020-08-31T18:26:09.418Z,1598898369.418 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-08-31T18:26:09.418Z,1598898369.418 [Default:CheckIn] Stopped
2020-08-31T18:26:09.418Z,1598898369.418 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-08-31T18:26:09.418Z,1598898369.418 [Default:CheckIn](INFO): Running loop #9
2020-08-31T18:26:09.419Z,1598898369.419 [Default:CheckIn] Running Loop=9
2020-08-31T18:26:09.419Z,1598898369.419 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-08-31T18:26:09.419Z,1598898369.419 [Default:CheckIn:Read_GPS] Running Loop=1
2020-08-31T18:26:11.601Z,1598898371.601 [AMEcho](INFO): Powering down
2020-08-31T18:26:25.986Z,1598898385.986 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2020-08-31T18:26:25.986Z,1598898385.986 [AHRS_M2] Data Fault, FailCount= 1
2020-08-31T18:26:25.986Z,1598898385.986 [AHRS_M2](ERROR): Data Fault
2020-08-31T18:26:25.990Z,1598898385.990 [Depth_Keller](ERROR): Pressure reading out of range: 1650.297119 decibar
2020-08-31T18:26:26.016Z,1598898386.016 [CBIT](ERROR): Data Fault in component: AHRS_M2
2020-08-31T18:26:26.377Z,1598898386.377 [AHRS_M2](INFO): Powering down
2020-08-31T18:26:27.325Z,1598898387.325 [CBIT](INFO): Clearing failed state for component AHRS_M2
2020-08-31T18:26:27.325Z,1598898387.325 [AHRS_M2] No Fault, FailCount= 1
2020-08-31T18:26:27.591Z,1598898387.591 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-08-31T18:26:40.930Z,1598898400.930 [Depth_Keller](ERROR): Pressure reading out of range: 1650.297119 decibar
2020-08-31T18:26:40.931Z,1598898400.931 [NAL9602](ERROR): parseGSV uart error: serial timeout
2020-08-31T18:26:41.751Z,1598898401.751 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-08-31T18:26:41.752Z,1598898401.752 [NAL9602] Data Fault, FailCount= 3
2020-08-31T18:26:41.752Z,1598898401.752 [NAL9602](ERROR): Data Fault
2020-08-31T18:26:41.989Z,1598898401.989 [CBIT](ERROR): Data Fault in component: NAL9602
2020-08-31T18:26:42.142Z,1598898402.142 [NAL9602](INFO): Powering down
2020-08-31T18:26:42.469Z,1598898402.469 [ElevatorServo](ERROR): getPosition uart error serial timeout
2020-08-31T18:26:42.469Z,1598898402.469 [ElevatorServo](FAULT): Elevator uart error - getPosition.serial timeout
2020-08-31T18:26:42.469Z,1598898402.469 [ElevatorServo] Communications Fault, FailCount= 1
2020-08-31T18:26:42.469Z,1598898402.469 [ElevatorServo](ERROR): Communications Fault
2020-08-31T18:26:42.474Z,1598898402.474 [CBIT](ERROR): Communications Fault in component: ElevatorServo
2020-08-31T18:26:42.740Z,1598898402.740 [NAL9602](FAULT): LCB fault: LCB Watchdog Reset. Invalid Command. Hardware Overcurrent Shutdown. Software Overcurrent. Current Limiter Activated.
2020-08-31T18:26:42.740Z,1598898402.740 [NAL9602] Hardware Fault, FailCount= 3
2020-08-31T18:26:42.740Z,1598898402.740 [NAL9602](ERROR): Hardware Fault
2020-08-31T18:26:42.811Z,1598898402.811 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-08-31T18:26:42.811Z,1598898402.811 [ElevatorServo](INFO): Powering down
2020-08-31T18:26:43.099Z,1598898403.099 [CBIT](INFO): Clearing failed state for component NAL9602
2020-08-31T18:26:43.099Z,1598898403.099 [NAL9602] No Fault, FailCount= 3
2020-08-31T18:26:43.412Z,1598898403.412 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-08-31T18:26:43.534Z,1598898403.534 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-08-31T18:26:43.539Z,1598898403.539 [CBIT](INFO): Clearing failed state for component ElevatorServo
2020-08-31T18:26:43.539Z,1598898403.539 [ElevatorServo] No Fault, FailCount= 1
2020-08-31T18:26:49.826Z,1598898409.826 [Depth_Keller](ERROR): Pressure reading out of range: 1650.297119 decibar
2020-08-31T18:26:50.423Z,1598898410.423 [CBIT](CRITICAL): run-time exception #7
2020-08-31T18:26:50.423Z,1598898410.423 [CBIT](CRITICAL): Bad address: 0x4041E008
2020-08-31T18:26:50.423Z,1598898410.423 [CBIT](CRITICAL): Backtrace: 
./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x17e158]
./bin/LRAUV(_ZN7PCaller10PThreadRunEPv+0xc8) [0x17dca4]

2020-08-31T18:26:50.423Z,1598898410.423 [CBIT] Software Fault, FailCount= 1
2020-08-31T18:26:50.423Z,1598898410.423 [CBIT](ERROR): Software Fault
2020-08-31T18:26:50.502Z,1598898410.502 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-08-31T18:26:50.502Z,1598898410.502 [SyncHandler](INFO): Protected caller Thread ID is 1416
2020-08-31T18:26:50.646Z,1598898410.646 [Depth_Keller](ERROR): Pressure reading out of range: 1650.297119 decibar
2020-08-31T18:26:50.653Z,1598898410.653 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 29
2020-08-31T18:26:50.670Z,1598898410.670 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-08-31T18:26:51.039Z,1598898411.039 [ElevatorServo](ERROR): getPosition uart error serial timeout
2020-08-31T18:26:51.039Z,1598898411.039 [ElevatorServo](FAULT): Elevator uart error - getPosition.serial timeout
2020-08-31T18:26:51.039Z,1598898411.039 [ElevatorServo] Communications Fault, FailCount= 2
2020-08-31T18:26:51.039Z,1598898411.039 [ElevatorServo](ERROR): Communications Fault
2020-08-31T18:26:51.040Z,1598898411.040 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-08-31T18:26:51.041Z,1598898411.041 [CBIT](DEBUG): Powering off loads.
2020-08-31T18:26:51.053Z,1598898411.053 [CBIT](DEBUG): Disabling WDT.
2020-08-31T18:26:51.065Z,1598898411.065 [CBIT](DEBUG): Opening all GF detection circuits.
2020-08-31T18:26:51.195Z,1598898411.195 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-08-31T18:26:51.195Z,1598898411.195 [ElevatorServo](INFO): Powering down
2020-08-31T18:26:52.015Z,1598898412.015 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-08-31T18:26:52.015Z,1598898412.015 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-08-31T18:26:58.017Z,1598898418.017 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2020-08-31T18:26:58.017Z,1598898418.017 [AHRS_M2] Data Fault, FailCount= 1
2020-08-31T18:26:58.017Z,1598898418.017 [AHRS_M2](ERROR): Data Fault
2020-08-31T18:26:58.047Z,1598898418.047 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020