2021-03-25T17:14:51.521Z,1616692491.521 [Supervisor](DEBUG): Initializing supervisor.
2021-03-25T17:14:51.523Z,1616692491.523 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2021-03-25T17:14:51.524Z,1616692491.524 [SyncHandler](INFO): Protected caller Thread ID is 379
2021-03-25T17:14:51.524Z,1616692491.524 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2021-03-25T17:14:51.525Z,1616692491.525 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2021-03-25T17:14:51.526Z,1616692491.526 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 380
2021-03-25T17:14:51.528Z,1616692491.528 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2021-03-25T17:14:51.545Z,1616692491.545 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2021-03-25T17:14:51.546Z,1616692491.546 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2021-03-25T17:14:51.546Z,1616692491.546 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 381
2021-03-25T17:14:51.547Z,1616692491.547 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2021-03-25T17:14:51.548Z,1616692491.548 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2021-03-25T17:14:51.548Z,1616692491.548 [logger ThreadHandler](INFO): Protected caller Thread ID is 382
2021-03-25T17:14:51.550Z,1616692491.550 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2021-03-25T17:14:51.551Z,1616692491.551 [Supervisor](INFO): Looking for Config files in directory: Config/
2021-03-25T17:14:51.553Z,1616692491.553 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2021-03-25T17:14:52.047Z,1616692492.047 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2021-03-25T17:14:52.047Z,1616692492.047 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2021-03-25T17:14:52.188Z,1616692492.188 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2021-03-25T17:14:52.189Z,1616692492.189 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2021-03-25T17:14:52.273Z,1616692492.273 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2021-03-25T17:14:52.479Z,1616692492.479 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2021-03-25T17:14:52.480Z,1616692492.480 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2021-03-25T17:14:52.578Z,1616692492.578 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2021-03-25T17:14:52.579Z,1616692492.579 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2021-03-25T17:14:52.754Z,1616692492.754 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2021-03-25T17:14:52.754Z,1616692492.754 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2021-03-25T17:14:53.145Z,1616692493.145 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2021-03-25T17:14:53.146Z,1616692493.146 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2021-03-25T17:14:53.253Z,1616692493.253 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2021-03-25T17:14:53.255Z,1616692493.255 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2021-03-25T17:14:53.742Z,1616692493.742 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2021-03-25T17:14:53.744Z,1616692493.744 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2021-03-25T17:14:53.930Z,1616692493.930 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2021-03-25T17:14:53.931Z,1616692493.931 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2021-03-25T17:14:54.232Z,1616692494.232 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2021-03-25T17:14:54.233Z,1616692494.233 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2021-03-25T17:14:54.445Z,1616692494.445 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2021-03-25T17:14:54.446Z,1616692494.446 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2021-03-25T17:14:54.586Z,1616692494.586 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2021-03-25T17:14:54.587Z,1616692494.587 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2021-03-25T17:14:55.644Z,1616692495.644 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2021-03-25T17:14:55.645Z,1616692495.645 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2021-03-25T17:14:55.974Z,1616692495.974 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2021-03-25T17:14:55.974Z,1616692495.974 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2021-03-25T17:14:56.177Z,1616692496.177 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2021-03-25T17:14:56.179Z,1616692496.179 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2021-03-25T17:14:56.180Z,1616692496.180 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2021-03-25T17:14:56.368Z,1616692496.368 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2021-03-25T17:14:56.454Z,1616692496.454 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2021-03-25T17:14:56.602Z,1616692496.602 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2021-03-25T17:14:56.689Z,1616692496.689 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2021-03-25T17:14:56.938Z,1616692496.938 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2021-03-25T17:14:56.939Z,1616692496.939 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2021-03-25T17:14:57.045Z,1616692497.045 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2021-03-25T17:14:57.153Z,1616692497.153 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2021-03-25T17:14:57.249Z,1616692497.249 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2021-03-25T17:14:57.393Z,1616692497.393 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2021-03-25T17:14:57.493Z,1616692497.493 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2021-03-25T17:14:57.595Z,1616692497.595 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/root/
2021-03-25T17:14:57.596Z,1616692497.596 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2021-03-25T17:14:57.597Z,1616692497.597 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2021-03-25T17:14:57.626Z,1616692497.626 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2021-03-25T17:14:57.626Z,1616692497.626 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2021-03-25T17:14:57.716Z,1616692497.716 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2021-03-25T17:14:57.716Z,1616692497.716 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2021-03-25T17:14:57.777Z,1616692497.777 [VerticalControl](DEBUG): Construct VerticalControl.
2021-03-25T17:14:57.833Z,1616692497.833 [VerticalControl] Loaded
2021-03-25T17:14:57.833Z,1616692497.833 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2021-03-25T17:14:57.834Z,1616692497.834 [HorizontalControl](DEBUG): Construct HorizontalControl.
2021-03-25T17:14:57.870Z,1616692497.870 [HorizontalControl] Loaded
2021-03-25T17:14:57.870Z,1616692497.870 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2021-03-25T17:14:57.871Z,1616692497.871 [SpeedControl](DEBUG): Construct SpeedControl.
2021-03-25T17:14:57.874Z,1616692497.874 [SpeedControl] Loaded
2021-03-25T17:14:57.874Z,1616692497.874 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2021-03-25T17:14:57.875Z,1616692497.875 [LoopControl](DEBUG): Construct LoopControl.
2021-03-25T17:14:57.875Z,1616692497.875 [LoopControl] Loaded
2021-03-25T17:14:57.875Z,1616692497.875 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2021-03-25T17:14:57.876Z,1616692497.876 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2021-03-25T17:14:57.876Z,1616692497.876 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2021-03-25T17:14:57.998Z,1616692497.998 [BuoyancyServo] Loaded
2021-03-25T17:14:57.999Z,1616692497.999 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2021-03-25T17:14:58.014Z,1616692498.014 [ElevatorServo] Loaded
2021-03-25T17:14:58.015Z,1616692498.015 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2021-03-25T17:14:58.029Z,1616692498.029 [MassServo] Loaded
2021-03-25T17:14:58.030Z,1616692498.030 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2021-03-25T17:14:58.044Z,1616692498.044 [RudderServo] Loaded
2021-03-25T17:14:58.044Z,1616692498.044 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2021-03-25T17:14:58.059Z,1616692498.059 [ThrusterServo] Loaded
2021-03-25T17:14:58.059Z,1616692498.059 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2021-03-25T17:14:58.059Z,1616692498.059 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2021-03-25T17:14:58.060Z,1616692498.060 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2021-03-25T17:14:58.109Z,1616692498.109 [DepthRateCalculator] Loaded
2021-03-25T17:14:58.109Z,1616692498.109 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2021-03-25T17:14:58.112Z,1616692498.112 [PitchRateCalculator] Loaded
2021-03-25T17:14:58.112Z,1616692498.112 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2021-03-25T17:14:58.120Z,1616692498.120 [SpeedCalculator] Loaded
2021-03-25T17:14:58.120Z,1616692498.120 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2021-03-25T17:14:58.131Z,1616692498.131 [TempGradientCalculator] Loaded
2021-03-25T17:14:58.131Z,1616692498.131 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2021-03-25T17:14:58.133Z,1616692498.133 [YawRateCalculator] Loaded
2021-03-25T17:14:58.133Z,1616692498.133 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2021-03-25T17:14:58.148Z,1616692498.148 [ElevatorOffsetCalculator] Loaded
2021-03-25T17:14:58.148Z,1616692498.148 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2021-03-25T17:14:58.148Z,1616692498.148 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2021-03-25T17:14:58.149Z,1616692498.149 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2021-03-25T17:14:58.242Z,1616692498.242 [DeadReckonUsingMultipleVelocitySources] Loaded
2021-03-25T17:14:58.242Z,1616692498.242 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2021-03-25T17:14:58.252Z,1616692498.252 [NavChart] Loaded
2021-03-25T17:14:58.253Z,1616692498.253 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2021-03-25T17:14:58.256Z,1616692498.256 [UniversalFixResidualReporter] Loaded
2021-03-25T17:14:58.256Z,1616692498.256 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2021-03-25T17:14:58.257Z,1616692498.257 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2021-03-25T17:14:58.257Z,1616692498.257 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2021-03-25T17:14:58.772Z,1616692498.772 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2021-03-25T17:14:58.775Z,1616692498.775 [AHRS_M2](INFO): created writer for : platform_orientation
2021-03-25T17:14:58.776Z,1616692498.776 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2021-03-25T17:14:58.779Z,1616692498.779 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2021-03-25T17:14:58.780Z,1616692498.780 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2021-03-25T17:14:58.782Z,1616692498.782 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2021-03-25T17:14:58.783Z,1616692498.783 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2021-03-25T17:14:58.786Z,1616692498.786 [AHRS_M2](INFO): created writer for : platform_roll_angle
2021-03-25T17:14:58.928Z,1616692498.928 [AHRS_M2] Loaded
2021-03-25T17:14:58.928Z,1616692498.928 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2021-03-25T17:14:59.021Z,1616692499.021 [DataOverHttps] Loaded
2021-03-25T17:14:59.022Z,1616692499.022 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2021-03-25T17:14:59.023Z,1616692499.023 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408514E0
2021-03-25T17:14:59.023Z,1616692499.023 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 468
2021-03-25T17:14:59.039Z,1616692499.039 [Depth_Keller] Loaded
2021-03-25T17:14:59.040Z,1616692499.040 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2021-03-25T17:14:59.042Z,1616692499.042 [DropWeight] Loaded
2021-03-25T17:14:59.042Z,1616692499.042 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2021-03-25T17:14:59.073Z,1616692499.073 [DVL_micro] Loaded
2021-03-25T17:14:59.073Z,1616692499.073 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2021-03-25T17:14:59.125Z,1616692499.125 [NAL9602] Loaded
2021-03-25T17:14:59.125Z,1616692499.125 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2021-03-25T17:14:59.150Z,1616692499.150 [Onboard] Loaded
2021-03-25T17:14:59.150Z,1616692499.150 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2021-03-25T17:14:59.151Z,1616692499.151 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408814E0
2021-03-25T17:14:59.152Z,1616692499.152 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 469
2021-03-25T17:14:59.162Z,1616692499.162 [Radio_Surface] Loaded
2021-03-25T17:14:59.162Z,1616692499.162 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2021-03-25T17:14:59.163Z,1616692499.163 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408B14E0
2021-03-25T17:14:59.163Z,1616692499.163 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 470
2021-03-25T17:14:59.237Z,1616692499.237 [DAT] Loaded
2021-03-25T17:14:59.237Z,1616692499.237 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2021-03-25T17:14:59.000Z,1616692500.000 [BPC1] Loaded
2021-03-25T17:15:00.000Z,1616692500.000 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2021-03-25T17:15:00.001Z,1616692500.001 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2021-03-25T17:15:00.001Z,1616692500.001 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2021-03-25T17:15:00.121Z,1616692500.121 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2021-03-25T17:15:00.121Z,1616692500.121 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2021-03-25T17:15:00.235Z,1616692500.235 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2021-03-25T17:15:00.235Z,1616692500.235 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2021-03-25T17:15:00.271Z,1616692500.271 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2021-03-25T17:15:00.272Z,1616692500.272 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2021-03-25T17:15:00.541Z,1616692500.541 [Aanderaa_O2] Loaded
2021-03-25T17:15:00.541Z,1616692500.541 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2021-03-25T17:15:00.560Z,1616692500.560 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2021-03-25T17:15:00.562Z,1616692500.562 [CTD_Seabird](INFO): created writer for : sea_water_density
2021-03-25T17:15:00.563Z,1616692500.563 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2021-03-25T17:15:00.565Z,1616692500.565 [CTD_Seabird](INFO): created writer for : depth
2021-03-25T17:15:00.566Z,1616692500.566 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2021-03-25T17:15:00.568Z,1616692500.568 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2021-03-25T17:15:00.569Z,1616692500.569 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2021-03-25T17:15:00.572Z,1616692500.572 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2021-03-25T17:15:00.572Z,1616692500.572 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2021-03-25T17:15:00.575Z,1616692500.575 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2021-03-25T17:15:00.575Z,1616692500.575 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2021-03-25T17:15:00.578Z,1616692500.578 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2021-03-25T17:15:00.579Z,1616692500.579 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2021-03-25T17:15:00.581Z,1616692500.581 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2021-03-25T17:15:00.594Z,1616692500.594 [CTD_Seabird] Loaded
2021-03-25T17:15:00.594Z,1616692500.594 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2021-03-25T17:15:00.595Z,1616692500.595 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A594E0
2021-03-25T17:15:00.596Z,1616692500.596 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 471
2021-03-25T17:15:00.624Z,1616692500.624 [ESPComponent] Loaded
2021-03-25T17:15:00.625Z,1616692500.625 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2021-03-25T17:15:00.641Z,1616692500.641 [PAR_Licor] Loaded
2021-03-25T17:15:00.641Z,1616692500.641 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2021-03-25T17:15:00.653Z,1616692500.653 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470
2021-03-25T17:15:00.653Z,1616692500.653 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470
2021-03-25T17:15:00.655Z,1616692500.655 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650
2021-03-25T17:15:00.655Z,1616692500.655 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650
2021-03-25T17:15:00.657Z,1616692500.657 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl
2021-03-25T17:15:00.657Z,1616692500.657 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl
2021-03-25T17:15:00.659Z,1616692500.659 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm
2021-03-25T17:15:00.659Z,1616692500.659 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm
2021-03-25T17:15:00.661Z,1616692500.661 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm
2021-03-25T17:15:00.661Z,1616692500.661 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm
2021-03-25T17:15:00.664Z,1616692500.664 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm
2021-03-25T17:15:00.664Z,1616692500.664 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm
2021-03-25T17:15:00.665Z,1616692500.665 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm
2021-03-25T17:15:00.666Z,1616692500.666 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm
2021-03-25T17:15:00.667Z,1616692500.667 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water
2021-03-25T17:15:00.670Z,1616692500.670 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water
2021-03-25T17:15:00.670Z,1616692500.670 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water
2021-03-25T17:15:00.670Z,1616692500.670 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water
2021-03-25T17:15:00.672Z,1616692500.672 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2021-03-25T17:15:00.672Z,1616692500.672 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2021-03-25T17:15:00.674Z,1616692500.674 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2021-03-25T17:15:00.674Z,1616692500.674 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2021-03-25T17:15:00.676Z,1616692500.676 [WetLabsBB2FL] Loaded
2021-03-25T17:15:00.676Z,1616692500.676 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2021-03-25T17:15:00.677Z,1616692500.677 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A894E0
2021-03-25T17:15:00.678Z,1616692500.678 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 472
2021-03-25T17:15:00.678Z,1616692500.678 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2021-03-25T17:15:00.679Z,1616692500.679 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2021-03-25T17:15:01.760Z,1616692501.760 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2021-03-25T17:15:01.761Z,1616692501.761 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2021-03-25T17:15:01.909Z,1616692501.909 [SBIT](DEBUG): Construct Startup Built In Test.
2021-03-25T17:15:01.918Z,1616692501.918 [SBIT] Loaded
2021-03-25T17:15:01.918Z,1616692501.918 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2021-03-25T17:15:01.918Z,1616692501.918 [IBIT](DEBUG): Construct Initiated Built In Test.
2021-03-25T17:15:01.930Z,1616692501.930 [IBIT] Loaded
2021-03-25T17:15:01.931Z,1616692501.931 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2021-03-25T17:15:01.934Z,1616692501.934 [CBIT](DEBUG): Construct Continuous Built In Test.
2021-03-25T17:15:02.027Z,1616692502.027 [CBIT] Loaded
2021-03-25T17:15:02.027Z,1616692502.027 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2021-03-25T17:15:02.028Z,1616692502.028 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2021-03-25T17:15:02.032Z,1616692502.032 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2021-03-25T17:15:02.032Z,1616692502.032 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2021-03-25T17:15:02.040Z,1616692502.040 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2021-03-25T17:15:02.041Z,1616692502.041 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B674E0
2021-03-25T17:15:02.042Z,1616692502.042 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 473
2021-03-25T17:15:02.046Z,1616692502.046 [Supervisor](INFO): Main Thread ID is 378
2021-03-25T17:15:02.046Z,1616692502.046 [Supervisor](DEBUG): Running supervisor.
2021-03-25T17:15:02.047Z,1616692502.047 [CommandLine ThreadHandler](INFO): Handler Thread ID is 474
2021-03-25T17:15:02.050Z,1616692502.050 [controlThread ThreadHandler](INFO): Handler Thread ID is 475
2021-03-25T17:15:02.051Z,1616692502.051 [controlThread](DEBUG): Initializing ControlThread
2021-03-25T17:15:02.052Z,1616692502.052 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2021-03-25T17:15:02.053Z,1616692502.053 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2021-03-25T17:15:02.054Z,1616692502.054 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2021-03-25T17:15:02.054Z,1616692502.054 [LoopControl](DEBUG): Initialize LoopControlComponent.
2021-03-25T17:15:02.056Z,1616692502.056 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2021-03-25T17:15:02.056Z,1616692502.056 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2021-03-25T17:15:02.056Z,1616692502.056 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2021-03-25T17:15:02.056Z,1616692502.056 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2021-03-25T17:15:02.057Z,1616692502.057 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2021-03-25T17:15:02.057Z,1616692502.057 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2021-03-25T17:15:02.058Z,1616692502.058 [NavChart](DEBUG): Initialize NavChart Navigation.
2021-03-25T17:15:02.059Z,1616692502.059 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2021-03-25T17:15:02.068Z,1616692502.068 [SBIT](INFO): Initialize SBIT Component.
2021-03-25T17:15:02.068Z,1616692502.068 [SBIT](IMPORTANT): git: 2020-11-24-6-gcb638d650
2021-03-25T17:15:02.068Z,1616692502.068 [SBIT](INFO): git hash: cb638d650ac65501896d406bf5988c2fe747cbc6
2021-03-25T17:15:02.069Z,1616692502.069 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2021-03-25T17:15:02.070Z,1616692502.070 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
Kernel Reported: #1 PREEMPT Tue May 26 08:17:09 PDT 2020
2021-03-25T17:15:02.071Z,1616692502.071 [SBIT](INFO): Beginning SBIT in 20.000000 seconds.
2021-03-25T17:15:02.071Z,1616692502.071 [IBIT](INFO): Initialize IBIT Component.
2021-03-25T17:15:02.072Z,1616692502.072 [CBIT](DEBUG): Initialize CBIT Component.
2021-03-25T17:15:02.073Z,1616692502.073 [logger ThreadHandler](INFO): Handler Thread ID is 476
2021-03-25T17:15:02.083Z,1616692502.083 [CBIT](DEBUG): Initialized mux pins.
2021-03-25T17:15:02.083Z,1616692502.083 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2021-03-25T17:15:02.084Z,1616692502.084 [CBIT](DEBUG): Initializing the watchdog timer.
2021-03-25T17:15:02.092Z,1616692502.092 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 477
2021-03-25T17:15:02.093Z,1616692502.093 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2021-03-25T17:15:02.104Z,1616692502.104 [Onboard ThreadHandler](INFO): Handler Thread ID is 478
2021-03-25T17:15:02.108Z,1616692502.108 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2021-03-25T17:15:02.108Z,1616692502.108 [CBIT](DEBUG): Initializing heartbeat.
2021-03-25T17:15:02.121Z,1616692502.121 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 479
2021-03-25T17:15:02.140Z,1616692502.140 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 480
2021-03-25T17:15:02.140Z,1616692502.140 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2021-03-25T17:15:02.145Z,1616692502.145 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 482
2021-03-25T17:15:02.146Z,1616692502.146 [WetLabsBB2FL](INFO): Powering down
2021-03-25T17:15:02.179Z,1616692502.179 [CBIT](DEBUG): Deactivating GF circuits.
2021-03-25T17:15:02.180Z,1616692502.180 [CBIT](DEBUG): Deactivating emergency mode.
2021-03-25T17:15:02.215Z,1616692502.215 [CBIT](DEBUG): Backplane powered.
2021-03-25T17:15:02.257Z,1616692502.257 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2021-03-25T17:15:02.267Z,1616692502.267 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 483
2021-03-25T17:15:02.371Z,1616692502.371 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2021-03-25T17:15:02.371Z,1616692502.371 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2021-03-25T17:15:02.371Z,1616692502.371 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2021-03-25T17:15:02.371Z,1616692502.371 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2021-03-25T17:15:02.371Z,1616692502.371 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2021-03-25T17:15:02.371Z,1616692502.371 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2021-03-25T17:15:02.372Z,1616692502.372 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2021-03-25T17:15:02.372Z,1616692502.372 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2021-03-25T17:15:02.372Z,1616692502.372 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2021-03-25T17:15:02.372Z,1616692502.372 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2021-03-25T17:15:02.372Z,1616692502.372 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2021-03-25T17:15:02.372Z,1616692502.372 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2021-03-25T17:15:02.372Z,1616692502.372 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2021-03-25T17:15:02.373Z,1616692502.373 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2021-03-25T17:15:02.373Z,1616692502.373 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2021-03-25T17:15:02.373Z,1616692502.373 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2021-03-25T17:15:02.377Z,1616692502.377 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2021-03-25T17:15:02.406Z,1616692502.406 [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>


2021-03-25T17:15:02.407Z,1616692502.407 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2021-03-25T17:15:02.528Z,1616692502.528 [Radio_Surface](INFO): Powering up
2021-03-25T17:15:02.625Z,1616692502.625 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2021-03-25T17:15:02.626Z,1616692502.626 [Default:A.Wait](DEBUG): Construct Wait.
2021-03-25T17:15:02.628Z,1616692502.628 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2021-03-25T17:15:02.658Z,1616692502.658 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2021-03-25T17:15:02.684Z,1616692502.684 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2021-03-25T17:15:02.690Z,1616692502.690 [Default:E.Execute](DEBUG): Construct Execute.
2021-03-25T17:15:02.703Z,1616692502.703 [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>


2021-03-25T17:15:02.723Z,1616692502.723 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,DVL_micro,NAL9602,DAT,BPC1,Aanderaa_O2,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2021-03-25T17:15:02.729Z,1616692502.729 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2021-03-25T17:15:02.895Z,1616692502.895 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2021-03-25T17:15:02.895Z,1616692502.895 [DAT](INFO): Powering up
2021-03-25T17:15:02.896Z,1616692502.896 [DAT](DEBUG): Initializing DAT.
2021-03-25T17:15:02.952Z,1616692502.952 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2021-03-25T17:15:03.026Z,1616692503.026 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2021-03-25T17:15:03.040Z,1616692503.040 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2021-03-25T17:15:03.042Z,1616692503.042 [ElevatorServo](DEBUG): Initializing EZServoServo.
2021-03-25T17:15:03.048Z,1616692503.048 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2021-03-25T17:15:03.049Z,1616692503.049 [MassServo](DEBUG): Initializing EZServoServo.
2021-03-25T17:15:03.056Z,1616692503.056 [MassServo](DEBUG): Initializing MassServo.
2021-03-25T17:15:03.057Z,1616692503.057 [RudderServo](DEBUG): Initializing EZServoServo.
2021-03-25T17:15:03.064Z,1616692503.064 [RudderServo](DEBUG): Initializing RudderServo.
2021-03-25T17:15:03.065Z,1616692503.065 [ThrusterServo](DEBUG): Initializing EZServoServo.
2021-03-25T17:15:03.072Z,1616692503.072 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2021-03-25T17:15:03.260Z,1616692503.260 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2021-03-25T17:15:03.261Z,1616692503.261 [DropWeight] Hardware Fault, FailCount= 1
2021-03-25T17:15:03.261Z,1616692503.261 [DropWeight](ERROR): Hardware Fault
2021-03-25T17:15:03.293Z,1616692503.293 [CommandLine](FAULT): Scheduling is paused
2021-03-25T17:15:03.294Z,1616692503.294 [CBIT](INFO): Critical error at 20210325T171503
2021-03-25T17:15:03.294Z,1616692503.294 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2021-03-25T17:15:03.308Z,1616692503.308 [CBIT](ERROR): Hardware Fault in component: DropWeight
2021-03-25T17:15:03.309Z,1616692503.309 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2021-03-25T17:15:03.556Z,1616692503.556 [CBIT](INFO): Critical error at 20210325T171503
2021-03-25T17:15:09.174Z,1616692509.174 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2021-03-25T17:15:13.257Z,1616692513.257 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2021-03-25T17:15:13.258Z,1616692513.258 [Aanderaa_O2] Communications Fault, FailCount= 1
2021-03-25T17:15:13.258Z,1616692513.258 [Aanderaa_O2](ERROR): Communications Fault
2021-03-25T17:15:13.383Z,1616692513.383 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2021-03-25T17:15:13.780Z,1616692513.780 [Aanderaa_O2](INFO): Powering down
2021-03-25T17:15:14.572Z,1616692514.572 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2021-03-25T17:15:14.572Z,1616692514.572 [Aanderaa_O2] No Fault, FailCount= 1
2021-03-25T17:15:15.683Z,1616692515.683 [DAT](INFO): commRate: 800
2021-03-25T17:15:16.237Z,1616692516.237 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004457
2021-03-25T17:15:17.697Z,1616692517.697 [DAT](INFO): entering command mode
2021-03-25T17:15:18.109Z,1616692518.109 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T17:15:18.509Z,1616692518.509 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T17:15:18.913Z,1616692518.913 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T17:15:19.317Z,1616692519.317 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T17:15:19.721Z,1616692519.721 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T17:15:20.124Z,1616692520.124 [DVL_micro](FAULT): Failed to initialize within timeout.
2021-03-25T17:15:20.124Z,1616692520.124 [DVL_micro] Communications Fault, FailCount= 1
2021-03-25T17:15:20.124Z,1616692520.124 [DVL_micro](ERROR): Communications Fault
2021-03-25T17:15:20.125Z,1616692520.125 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T17:15:20.189Z,1616692520.189 [CBIT](ERROR): Communications Fault in component: DVL_micro
2021-03-25T17:15:20.600Z,1616692520.600 [DVL_micro](INFO): Powering down
2021-03-25T17:15:20.601Z,1616692520.601 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T17:15:20.933Z,1616692520.933 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T17:15:21.337Z,1616692521.337 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T17:15:21.384Z,1616692521.384 [CBIT](INFO): Clearing failed state for component DVL_micro
2021-03-25T17:15:21.385Z,1616692521.385 [DVL_micro] No Fault, FailCount= 1
2021-03-25T17:15:21.742Z,1616692521.742 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T17:15:22.145Z,1616692522.145 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T17:15:22.549Z,1616692522.549 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T17:15:22.642Z,1616692522.642 [SBIT](IMPORTANT): Beginning Startup BIT
2021-03-25T17:15:22.646Z,1616692522.646 [CBIT](IMPORTANT): Beginning ground fault scan
2021-03-25T17:15:22.965Z,1616692522.965 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T17:15:23.357Z,1616692523.357 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T17:15:23.761Z,1616692523.761 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T17:15:24.165Z,1616692524.165 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T17:15:24.569Z,1616692524.569 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T17:15:24.985Z,1616692524.985 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T17:15:24.986Z,1616692524.986 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2021-03-25T17:15:24.986Z,1616692524.986 [Aanderaa_O2] Communications Fault, FailCount= 2
2021-03-25T17:15:24.986Z,1616692524.986 [Aanderaa_O2](ERROR): Communications Fault
2021-03-25T17:15:25.216Z,1616692525.216 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2021-03-25T17:15:25.385Z,1616692525.385 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T17:15:25.503Z,1616692525.503 [Aanderaa_O2](INFO): Powering down
2021-03-25T17:15:25.785Z,1616692525.785 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T17:15:26.185Z,1616692526.185 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T17:15:26.417Z,1616692526.417 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2021-03-25T17:15:26.417Z,1616692526.417 [Aanderaa_O2] No Fault, FailCount= 2
2021-03-25T17:15:26.597Z,1616692526.597 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T17:15:26.989Z,1616692526.989 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T17:15:27.397Z,1616692527.397 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T17:15:27.801Z,1616692527.801 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T17:15:28.201Z,1616692528.201 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T17:15:28.605Z,1616692528.605 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T17:15:29.013Z,1616692529.013 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T17:15:29.417Z,1616692529.417 [NAL9602](INFO): Powering up NAL9602
2021-03-25T17:15:29.418Z,1616692529.418 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T17:15:29.822Z,1616692529.822 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T17:15:30.243Z,1616692530.243 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T17:15:30.625Z,1616692530.625 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T17:15:31.029Z,1616692531.029 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T17:15:31.445Z,1616692531.445 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T17:15:31.837Z,1616692531.837 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T17:15:32.241Z,1616692532.241 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T17:15:32.649Z,1616692532.649 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T17:15:33.053Z,1616692533.053 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T17:15:33.053Z,1616692533.053 [DAT](FAULT): failed to enter command mode
2021-03-25T17:15:33.453Z,1616692533.453 [DAT](INFO): entering command mode
2021-03-25T17:15:33.866Z,1616692533.866 [DAT](INFO): setting verbose to 3
2021-03-25T17:15:33.960Z,1616692533.960 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006569
CHAN A1 (24V): -0.007880
CHAN A2 (12V): -0.004451
CHAN A3 (5V): -0.003401
CHAN B0 (3.3V): -0.001679
CHAN B1 (3.15aV): -0.000667
CHAN B2 (3.15bV): -0.001780
CHAN B3 (GND): 0.000183
OPEN: 0.004924
Full Scale Calc: 4.765 mA, -1.589 mA
2021-03-25T17:15:34.262Z,1616692534.262 [DAT](INFO): set verbose to 3
2021-03-25T17:15:34.263Z,1616692534.263 [DAT](INFO): setting DatVerbose to 27440
2021-03-25T17:15:34.682Z,1616692534.682 [DAT](INFO): set DatVerbose to 27440
2021-03-25T17:15:34.682Z,1616692534.682 [DAT](INFO): setting transmit power to 8
2021-03-25T17:15:35.074Z,1616692535.074 [DAT](INFO): set transmit power to 8
2021-03-25T17:15:35.074Z,1616692535.074 [DAT](INFO): setting local address to 5
2021-03-25T17:15:35.474Z,1616692535.474 [DAT](INFO): set local address to 5
2021-03-25T17:15:36.686Z,1616692536.686 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2021-03-25T17:15:36.686Z,1616692536.686 [Aanderaa_O2] Communications Fault, FailCount= 3
2021-03-25T17:15:36.686Z,1616692536.686 [Aanderaa_O2](ERROR): Communications Fault
2021-03-25T17:15:36.802Z,1616692536.802 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2021-03-25T17:15:36.802Z,1616692536.802 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2021-03-25T17:15:37.219Z,1616692537.219 [Aanderaa_O2](INFO): Powering down
2021-03-25T17:15:39.112Z,1616692539.112 [DVL_micro](FAULT): Failed to initialize within timeout.
2021-03-25T17:15:39.112Z,1616692539.112 [DVL_micro] Communications Fault, FailCount= 2
2021-03-25T17:15:39.112Z,1616692539.112 [DVL_micro](ERROR): Communications Fault
2021-03-25T17:15:39.308Z,1616692539.308 [CBIT](ERROR): Communications Fault in component: DVL_micro
2021-03-25T17:15:39.591Z,1616692539.592 [DVL_micro](INFO): Powering down
2021-03-25T17:15:40.341Z,1616692540.341 [NAL9602](INFO): NAL9602 initialized
2021-03-25T17:15:40.497Z,1616692540.497 [CBIT](INFO): Clearing failed state for component DVL_micro
2021-03-25T17:15:40.497Z,1616692540.497 [DVL_micro] No Fault, FailCount= 2
2021-03-25T17:15:41.594Z,1616692541.594 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171431.00,A,3648.16632,N,12147.28209,W,0.058,87.73,250321,,,A*45
2021-03-25T17:15:41.618Z,1616692541.618 [NAL9602](INFO): GPS fix at 20210325T171431: (36.802772, -121.788035)
2021-03-25T17:15:47.922Z,1616692547.922 [CommandLine](IMPORTANT): got command failComponent
2021-03-25T17:15:47.922Z,1616692547.922 [CommandLine](IMPORTANT): Failed components:
2021-03-25T17:15:47.922Z,1616692547.922 [CommandLine](IMPORTANT): DropWeight: Hardware Fault
2021-03-25T17:15:47.922Z,1616692547.922 [CommandLine](IMPORTANT): Aanderaa_O2: Communications Fault
2021-03-25T17:15:58.151Z,1616692558.151 [CommandLine](IMPORTANT): got command burn off
2021-03-25T17:15:58.151Z,1616692558.151 [CommandLine](IMPORTANT): Deactivating dropweight wire
2021-03-25T17:15:58.272Z,1616692558.272 [DVL_micro](FAULT): Failed to initialize within timeout.
2021-03-25T17:15:58.272Z,1616692558.272 [DVL_micro] Communications Fault, FailCount= 3
2021-03-25T17:15:58.272Z,1616692558.272 [DVL_micro](ERROR): Communications Fault
2021-03-25T17:15:58.385Z,1616692558.385 [CBIT](ERROR): Communications Fault in component: DVL_micro
2021-03-25T17:15:58.385Z,1616692558.385 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2021-03-25T17:15:58.752Z,1616692558.752 [DVL_micro](INFO): Powering down
2021-03-25T17:15:58.882Z,1616692558.882 [CBIT](INFO): Critical error at 20210325T171558
2021-03-25T17:16:16.576Z,1616692576.576 [SBIT](IMPORTANT): SBIT PASSED
2021-03-25T17:16:16.576Z,1616692576.576 [SBIT](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2021-03-25T17:16:16.935Z,1616692576.935 [MissionManager](IMPORTANT): Started mission Startup
2021-03-25T17:16:16.939Z,1616692576.939 [Startup] Running Loop=1
2021-03-25T17:16:16.939Z,1616692576.939 [Startup](DEBUG): Aggregate::initialize Startup
2021-03-25T17:16:16.939Z,1616692576.939 [Startup:A.GoToSurface] Running Loop=1
2021-03-25T17:16:16.940Z,1616692576.940 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-03-25T17:16:16.940Z,1616692576.940 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-03-25T17:16:16.941Z,1616692576.941 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-03-25T17:16:16.941Z,1616692576.941 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-03-25T17:16:16.941Z,1616692576.941 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-03-25T17:16:16.942Z,1616692576.942 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-03-25T17:16:16.943Z,1616692576.943 [Startup:StartupSatComms] Running Loop=1
2021-03-25T17:16:16.943Z,1616692576.943 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2021-03-25T17:16:16.947Z,1616692576.947 [Startup:StartupSatComms:A] Running Loop=1
2021-03-25T17:16:17.360Z,1616692577.360 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2021-03-25T17:17:17.138Z,1616692637.138 [Startup:StartupSatComms:A](INFO): Timed out from 2021-03-25T17:16:16.9Z
2021-03-25T17:17:17.138Z,1616692637.138 [Startup:StartupSatComms:A] Stopped
2021-03-25T17:17:17.138Z,1616692637.138 [Startup:StartupSatComms:B] Running Loop=1
2021-03-25T17:17:17.542Z,1616692637.542 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2021-03-25T17:17:27.135Z,1616692647.135 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20210325T161942/Courier0013.lzma
2021-03-25T17:17:28.137Z,1616692648.137 [DataOverHttps](INFO): Moved sent file to Logs/20210325T161942/Courier0013.lzma.bak
2021-03-25T17:17:28.137Z,1616692648.137 [DataOverHttps](INFO): SBD MOMSN=15446458
2021-03-25T17:17:39.350Z,1616692659.350 [NAL9602](INFO): SBD MO Status=2, MOMSN=4078, MT Status=2, MTMSN=0
2021-03-25T17:17:39.350Z,1616692659.350 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-03-25T17:17:49.992Z,1616692669.992 [DataOverHttps](INFO): Sending 391 bytes from file Logs/20210325T171451/Courier0000.lzma
2021-03-25T17:17:52.774Z,1616692672.774 [DataOverHttps](INFO): Moved sent file to Logs/20210325T171451/Courier0000.lzma.bak
2021-03-25T17:17:52.775Z,1616692672.775 [DataOverHttps](INFO): SBD MOMSN=15446460
2021-03-25T17:17:57.190Z,1616692677.190 [CommandLine](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 200.000000 cubic_centimeter
2021-03-25T17:17:57.192Z,1616692677.192 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.buoyancyAction" handled in the control thread.
2021-03-25T17:17:57.397Z,1616692677.397 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,DVL_micro,NAL9602,DAT,BPC1,Aanderaa_O2,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.buoyancyAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2021-03-25T17:18:06.705Z,1616692686.705 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2021-03-25T17:18:06.705Z,1616692686.705 [Aanderaa_O2] No Fault, FailCount= 3
2021-03-25T17:18:16.232Z,1616692696.232 [CommandLine](IMPORTANT): got command report mod platform_buoyancy_position
2021-03-25T17:18:16.441Z,1616692696.441 [Reporter](INFO): platform_buoyancy_position 801.340881 cc
2021-03-25T17:18:16.794Z,1616692696.794 [Reporter](INFO): platform_buoyancy_position 797.043274 cc
2021-03-25T17:18:17.144Z,1616692697.144 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2021-03-25T17:18:17.144Z,1616692697.144 [Aanderaa_O2] Communications Fault, FailCount= 1
2021-03-25T17:18:17.144Z,1616692697.144 [Aanderaa_O2](ERROR): Communications Fault
2021-03-25T17:18:17.199Z,1616692697.199 [Startup:StartupSatComms:B](INFO): Timed out from 2021-03-25T17:17:17.1Z
2021-03-25T17:18:17.200Z,1616692697.200 [Startup:StartupSatComms:B] Stopped
2021-03-25T17:18:17.200Z,1616692697.200 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2021-03-25T17:18:17.200Z,1616692697.200 [Startup:StartupSatComms] Stopped
2021-03-25T17:18:17.200Z,1616692697.200 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2021-03-25T17:18:17.201Z,1616692697.201 [Startup](INFO): Completed Startup
2021-03-25T17:18:17.201Z,1616692697.201 [MissionManager](INFO): Startup is completed.
2021-03-25T17:18:17.201Z,1616692697.201 [MissionManager](INFO): Uninitializing Mission Startup
2021-03-25T17:18:17.201Z,1616692697.201 [Startup] Stopped
2021-03-25T17:18:17.201Z,1616692697.201 [Startup](DEBUG): Aggregate::uninitialize Startup
2021-03-25T17:18:17.201Z,1616692697.201 [Startup:A.GoToSurface] Stopped
2021-03-25T17:18:17.201Z,1616692697.201 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-03-25T17:18:17.254Z,1616692697.254 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2021-03-25T17:18:17.264Z,1616692697.264 [Reporter](INFO): platform_buoyancy_position 790.596863 cc
2021-03-25T17:18:17.671Z,1616692697.671 [Aanderaa_O2](INFO): Powering down
2021-03-25T17:18:17.698Z,1616692697.698 [MissionManager](IMPORTANT): Started mission Default
2021-03-25T17:18:17.698Z,1616692697.698 [Default] Running Loop=1
2021-03-25T17:18:17.698Z,1616692697.698 [Default](DEBUG): Aggregate::initialize Default
2021-03-25T17:18:17.698Z,1616692697.698 [Default:B.GoToSurface] Running Loop=1
2021-03-25T17:18:17.698Z,1616692697.698 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-03-25T17:18:17.699Z,1616692697.699 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-03-25T17:18:17.699Z,1616692697.699 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-03-25T17:18:17.739Z,1616692697.739 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-03-25T17:18:17.740Z,1616692697.740 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-03-25T17:18:17.740Z,1616692697.740 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-03-25T17:18:17.740Z,1616692697.740 [Default:A.Wait] Running Loop=1
2021-03-25T17:18:17.740Z,1616692697.740 [Default:A.Wait](DEBUG): Initialize Wait Component.
2021-03-25T17:18:17.782Z,1616692697.782 [Reporter](INFO): platform_buoyancy_position 784.016174 cc
2021-03-25T17:18:18.026Z,1616692698.026 [Reporter](INFO): platform_buoyancy_position 780.792969 cc
2021-03-25T17:18:18.424Z,1616692698.424 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2021-03-25T17:18:18.425Z,1616692698.425 [Aanderaa_O2] No Fault, FailCount= 1
2021-03-25T17:18:18.426Z,1616692698.426 [Reporter](INFO): platform_buoyancy_position 775.420959 cc
2021-03-25T17:18:18.821Z,1616692698.821 [Reporter](INFO): platform_buoyancy_position 771.257690 cc
2021-03-25T17:18:19.230Z,1616692699.230 [Reporter](INFO): platform_buoyancy_position 765.079895 cc
2021-03-25T17:18:19.626Z,1616692699.626 [Reporter](INFO): platform_buoyancy_position 760.513672 cc
2021-03-25T17:18:20.028Z,1616692700.028 [Reporter](INFO): platform_buoyancy_position 754.201599 cc
2021-03-25T17:18:20.436Z,1616692700.436 [Reporter](INFO): platform_buoyancy_position 749.769653 cc
2021-03-25T17:18:20.830Z,1616692700.830 [Reporter](INFO): platform_buoyancy_position 744.800598 cc
2021-03-25T17:18:21.225Z,1616692701.225 [Reporter](INFO): platform_buoyancy_position 738.891357 cc
2021-03-25T17:18:21.555Z,1616692701.555 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20210325T161942/Express0014.lzma
2021-03-25T17:18:21.647Z,1616692701.647 [Reporter](INFO): platform_buoyancy_position 734.325195 cc
2021-03-25T17:18:22.109Z,1616692702.109 [Reporter](INFO): platform_buoyancy_position 728.147339 cc
2021-03-25T17:18:22.473Z,1616692702.473 [Reporter](INFO): platform_buoyancy_position 721.835266 cc
2021-03-25T17:18:22.557Z,1616692702.557 [DataOverHttps](INFO): Moved sent file to Logs/20210325T161942/Express0014.lzma.bak
2021-03-25T17:18:22.557Z,1616692702.557 [DataOverHttps](INFO): SBD MOMSN=15446468
2021-03-25T17:18:22.874Z,1616692702.874 [Reporter](INFO): platform_buoyancy_position 717.403381 cc
2021-03-25T17:18:23.258Z,1616692703.258 [Reporter](INFO): platform_buoyancy_position 712.971497 cc
2021-03-25T17:18:23.649Z,1616692703.649 [Reporter](INFO): platform_buoyancy_position 706.659363 cc
2021-03-25T17:18:24.045Z,1616692704.045 [Reporter](INFO): platform_buoyancy_position 702.361755 cc
2021-03-25T17:18:24.454Z,1616692704.454 [Reporter](INFO): platform_buoyancy_position 695.915344 cc
2021-03-25T17:18:24.875Z,1616692704.875 [Reporter](INFO): platform_buoyancy_position 691.617737 cc
2021-03-25T17:18:25.290Z,1616692705.290 [Reporter](INFO): platform_buoyancy_position 685.439941 cc
2021-03-25T17:18:25.700Z,1616692705.700 [Reporter](INFO): platform_buoyancy_position 680.873779 cc
2021-03-25T17:18:26.120Z,1616692706.120 [Reporter](INFO): platform_buoyancy_position 674.427368 cc
2021-03-25T17:18:26.498Z,1616692706.498 [Reporter](INFO): platform_buoyancy_position 670.264038 cc
2021-03-25T17:18:26.908Z,1616692706.908 [Reporter](INFO): platform_buoyancy_position 663.817688 cc
2021-03-25T17:18:27.368Z,1616692707.368 [Reporter](INFO): platform_buoyancy_position 657.505554 cc
2021-03-25T17:18:27.723Z,1616692707.723 [Reporter](INFO): platform_buoyancy_position 653.073669 cc
2021-03-25T17:18:28.112Z,1616692708.112 [Reporter](INFO): platform_buoyancy_position 648.641785 cc
2021-03-25T17:18:28.532Z,1616692708.532 [Reporter](INFO): platform_buoyancy_position 642.329651 cc
2021-03-25T17:18:28.846Z,1616692708.846 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2021-03-25T17:18:28.847Z,1616692708.847 [Aanderaa_O2] Communications Fault, FailCount= 2
2021-03-25T17:18:28.847Z,1616692708.847 [Aanderaa_O2](ERROR): Communications Fault
2021-03-25T17:18:28.936Z,1616692708.936 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2021-03-25T17:18:28.938Z,1616692708.938 [Reporter](INFO): platform_buoyancy_position 637.897766 cc
2021-03-25T17:18:29.376Z,1616692709.376 [Aanderaa_O2](INFO): Powering down
2021-03-25T17:18:29.435Z,1616692709.435 [Reporter](INFO): platform_buoyancy_position 631.585632 cc
2021-03-25T17:18:29.713Z,1616692709.713 [Reporter](INFO): platform_buoyancy_position 627.422363 cc
2021-03-25T17:18:30.180Z,1616692710.180 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2021-03-25T17:18:30.180Z,1616692710.180 [Aanderaa_O2] No Fault, FailCount= 2
2021-03-25T17:18:30.182Z,1616692710.182 [Reporter](INFO): platform_buoyancy_position 622.721863 cc
2021-03-25T17:18:30.526Z,1616692710.526 [Reporter](INFO): platform_buoyancy_position 616.678345 cc
2021-03-25T17:18:30.907Z,1616692710.907 [Default:A.Wait](INFO): Done Waiting.
2021-03-25T17:18:30.908Z,1616692710.908 [Default:A.Wait] Stopped
2021-03-25T17:18:30.908Z,1616692710.908 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2021-03-25T17:18:30.958Z,1616692710.958 [Reporter](INFO): platform_buoyancy_position 612.246460 cc
2021-03-25T17:18:31.307Z,1616692711.307 [Default:CheckIn] Running Loop=1
2021-03-25T17:18:31.308Z,1616692711.308 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-03-25T17:18:31.308Z,1616692711.308 [Default:CheckIn:Read_GPS] Running Loop=1
2021-03-25T17:18:31.358Z,1616692711.358 [Reporter](INFO): platform_buoyancy_position 607.008789 cc
2021-03-25T17:18:31.687Z,1616692711.687 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2021-03-25T17:18:31.729Z,1616692711.729 [Reporter](INFO): platform_buoyancy_position 601.502441 cc
2021-03-25T17:18:32.121Z,1616692712.121 [Reporter](INFO): platform_buoyancy_position 597.070557 cc
2021-03-25T17:18:32.549Z,1616692712.549 [Reporter](INFO): platform_buoyancy_position 590.758484 cc
2021-03-25T17:18:32.950Z,1616692712.950 [Reporter](INFO): platform_buoyancy_position 585.117859 cc
2021-03-25T17:18:33.344Z,1616692713.344 [Reporter](INFO): platform_buoyancy_position 580.014465 cc
2021-03-25T17:18:33.761Z,1616692713.761 [Reporter](INFO): platform_buoyancy_position 574.642456 cc
2021-03-25T17:18:34.153Z,1616692714.153 [Reporter](INFO): platform_buoyancy_position 569.136169 cc
2021-03-25T17:18:34.546Z,1616692714.546 [Reporter](INFO): platform_buoyancy_position 564.838562 cc
2021-03-25T17:18:35.037Z,1616692715.037 [Reporter](INFO): platform_buoyancy_position 558.392151 cc
2021-03-25T17:18:35.477Z,1616692715.477 [Reporter](INFO): platform_buoyancy_position 552.214355 cc
2021-03-25T17:18:35.842Z,1616692715.842 [Reporter](INFO): platform_buoyancy_position 547.648132 cc
2021-03-25T17:18:36.289Z,1616692716.289 [Reporter](INFO): platform_buoyancy_position 541.201782 cc
2021-03-25T17:18:36.758Z,1616692716.758 [Reporter](INFO): platform_buoyancy_position 534.889648 cc
2021-03-25T17:18:37.082Z,1616692717.082 [Reporter](INFO): platform_buoyancy_position 530.457764 cc
2021-03-25T17:18:37.414Z,1616692717.414 [Reporter](INFO): platform_buoyancy_position 526.294434 cc
2021-03-25T17:18:37.846Z,1616692717.846 [Reporter](INFO): platform_buoyancy_position 521.728271 cc
2021-03-25T17:18:38.200Z,1616692718.200 [Reporter](INFO): platform_buoyancy_position 516.624878 cc
2021-03-25T17:18:38.606Z,1616692718.606 [Reporter](INFO): platform_buoyancy_position 511.118500 cc
2021-03-25T17:18:39.006Z,1616692719.006 [Reporter](INFO): platform_buoyancy_position 504.806458 cc
2021-03-25T17:18:39.397Z,1616692719.397 [Reporter](INFO): platform_buoyancy_position 500.374603 cc
2021-03-25T17:18:39.806Z,1616692719.806 [Reporter](INFO): platform_buoyancy_position 496.076935 cc
2021-03-25T17:18:40.225Z,1616692720.225 [Reporter](INFO): platform_buoyancy_position 489.764862 cc
2021-03-25T17:18:40.562Z,1616692720.562 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2021-03-25T17:18:40.562Z,1616692720.562 [Aanderaa_O2] Communications Fault, FailCount= 3
2021-03-25T17:18:40.562Z,1616692720.562 [Aanderaa_O2](ERROR): Communications Fault
2021-03-25T17:18:40.640Z,1616692720.640 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2021-03-25T17:18:40.640Z,1616692720.640 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2021-03-25T17:18:40.642Z,1616692720.642 [Reporter](INFO): platform_buoyancy_position 485.332947 cc
2021-03-25T17:18:41.084Z,1616692721.084 [Aanderaa_O2](INFO): Powering down
2021-03-25T17:18:41.159Z,1616692721.159 [Reporter](INFO): platform_buoyancy_position 477.140625 cc
2021-03-25T17:18:41.414Z,1616692721.414 [Reporter](INFO): platform_buoyancy_position 474.588928 cc
2021-03-25T17:18:41.829Z,1616692721.829 [Reporter](INFO): platform_buoyancy_position 468.411133 cc
2021-03-25T17:18:42.246Z,1616692722.246 [Reporter](INFO): platform_buoyancy_position 463.844971 cc
2021-03-25T17:18:42.634Z,1616692722.634 [Reporter](INFO): platform_buoyancy_position 459.278748 cc
2021-03-25T17:18:42.990Z,1616692722.990 [NAL9602](INFO): SBD MO Status=1, MOMSN=4078, MT Status=0, MTMSN=0
2021-03-25T17:18:42.990Z,1616692722.990 [NAL9602](INFO): No messages in MT queue
2021-03-25T17:18:43.053Z,1616692723.053 [Reporter](INFO): platform_buoyancy_position 453.100952 cc
2021-03-25T17:18:43.502Z,1616692723.502 [Reporter](INFO): platform_buoyancy_position 446.788818 cc
2021-03-25T17:18:43.902Z,1616692723.902 [Reporter](INFO): platform_buoyancy_position 442.356934 cc
2021-03-25T17:18:44.199Z,1616692724.199 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171734.00,A,3648.16691,N,12147.28545,W,0.253,87.73,250321,,,A*4C
2021-03-25T17:18:44.201Z,1616692724.201 [NAL9602](INFO): GPS fix at 20210325T171734: (36.802782, -121.788091)
2021-03-25T17:18:44.231Z,1616692724.231 [Default:CheckIn:Read_GPS] Stopped
2021-03-25T17:18:44.231Z,1616692724.231 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-03-25T17:18:44.274Z,1616692724.274 [Reporter](INFO): platform_buoyancy_position 436.044861 cc
2021-03-25T17:18:44.639Z,1616692724.639 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2021-03-25T17:18:44.678Z,1616692724.678 [Reporter](INFO): platform_buoyancy_position 431.612976 cc
2021-03-25T17:18:45.050Z,1616692725.050 [Reporter](INFO): platform_buoyancy_position 426.912476 cc
2021-03-25T17:18:45.490Z,1616692725.490 [Reporter](INFO): platform_buoyancy_position 420.868958 cc
2021-03-25T17:18:45.935Z,1616692725.935 [Reporter](INFO): platform_buoyancy_position 414.556824 cc
2021-03-25T17:18:46.335Z,1616692726.335 [Reporter](INFO): platform_buoyancy_position 410.259216 cc
2021-03-25T17:18:46.706Z,1616692726.706 [Reporter](INFO): platform_buoyancy_position 404.081421 cc
2021-03-25T17:18:47.132Z,1616692727.132 [Reporter](INFO): platform_buoyancy_position 399.515259 cc
2021-03-25T17:18:47.490Z,1616692727.490 [Reporter](INFO): platform_buoyancy_position 395.083374 cc
2021-03-25T17:18:47.937Z,1616692727.937 [Reporter](INFO): platform_buoyancy_position 388.636963 cc
2021-03-25T17:18:48.345Z,1616692728.345 [Reporter](INFO): platform_buoyancy_position 382.593445 cc
2021-03-25T17:18:48.881Z,1616692728.881 [Reporter](INFO): platform_buoyancy_position 375.878418 cc
2021-03-25T17:18:49.142Z,1616692729.142 [Reporter](INFO): platform_buoyancy_position 373.192444 cc
2021-03-25T17:18:49.553Z,1616692729.553 [Reporter](INFO): platform_buoyancy_position 367.417542 cc
2021-03-25T17:18:49.954Z,1616692729.954 [Reporter](INFO): platform_buoyancy_position 362.717041 cc
2021-03-25T17:18:50.338Z,1616692730.338 [Reporter](INFO): platform_buoyancy_position 356.539246 cc
2021-03-25T17:18:50.750Z,1616692730.750 [Reporter](INFO): platform_buoyancy_position 352.107361 cc
2021-03-25T17:18:51.138Z,1616692731.138 [Reporter](INFO): platform_buoyancy_position 347.809753 cc
2021-03-25T17:18:51.597Z,1616692731.597 [Reporter](INFO): platform_buoyancy_position 341.363342 cc
2021-03-25T17:18:51.958Z,1616692731.958 [Reporter](INFO): platform_buoyancy_position 336.259949 cc
2021-03-25T17:18:52.382Z,1616692732.382 [Reporter](INFO): platform_buoyancy_position 330.619324 cc
2021-03-25T17:18:52.757Z,1616692732.757 [Reporter](INFO): platform_buoyancy_position 326.187439 cc
2021-03-25T17:18:53.167Z,1616692733.167 [Reporter](INFO): platform_buoyancy_position 320.009644 cc
2021-03-25T17:18:53.584Z,1616692733.584 [Reporter](INFO): platform_buoyancy_position 315.309143 cc
2021-03-25T17:18:53.988Z,1616692733.988 [Reporter](INFO): platform_buoyancy_position 309.131348 cc
2021-03-25T17:18:54.365Z,1616692734.365 [Reporter](INFO): platform_buoyancy_position 304.968048 cc
2021-03-25T17:18:54.781Z,1616692734.781 [Reporter](INFO): platform_buoyancy_position 298.387329 cc
2021-03-25T17:18:55.193Z,1616692735.193 [Reporter](INFO): platform_buoyancy_position 294.089722 cc
2021-03-25T17:18:55.565Z,1616692735.565 [Reporter](INFO): platform_buoyancy_position 287.643341 cc
2021-03-25T17:18:55.990Z,1616692735.990 [Reporter](INFO): platform_buoyancy_position 283.345734 cc
2021-03-25T17:18:56.259Z,1616692736.259 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20210325T171451/Courier0004.lzma
2021-03-25T17:18:56.377Z,1616692736.377 [Reporter](INFO): platform_buoyancy_position 277.033630 cc
2021-03-25T17:18:56.847Z,1616692736.847 [Reporter](INFO): platform_buoyancy_position 272.064545 cc
2021-03-25T17:18:57.206Z,1616692737.206 [Reporter](INFO): platform_buoyancy_position 266.289642 cc
2021-03-25T17:18:57.261Z,1616692737.261 [DataOverHttps](INFO): Moved sent file to Logs/20210325T171451/Courier0004.lzma.bak
2021-03-25T17:18:57.261Z,1616692737.261 [DataOverHttps](INFO): SBD MOMSN=15446470
2021-03-25T17:18:57.606Z,1616692737.606 [Reporter](INFO): platform_buoyancy_position 261.857727 cc
2021-03-25T17:18:58.026Z,1616692738.026 [Reporter](INFO): platform_buoyancy_position 255.411346 cc
2021-03-25T17:18:58.452Z,1616692738.452 [CBIT](INFO): Clearing failed state for component DVL_micro
2021-03-25T17:18:58.453Z,1616692738.453 [DVL_micro] No Fault, FailCount= 3
2021-03-25T17:18:58.454Z,1616692738.454 [Reporter](INFO): platform_buoyancy_position 251.113724 cc
2021-03-25T17:18:58.876Z,1616692738.876 [Reporter](INFO): platform_buoyancy_position 244.801636 cc
2021-03-25T17:18:59.272Z,1616692739.272 [Reporter](INFO): platform_buoyancy_position 240.504028 cc
2021-03-25T17:18:59.635Z,1616692739.635 [Reporter](INFO): platform_buoyancy_position 235.669220 cc
2021-03-25T17:19:00.061Z,1616692740.061 [Reporter](INFO): platform_buoyancy_position 229.625732 cc
2021-03-25T17:19:00.434Z,1616692740.434 [Reporter](INFO): platform_buoyancy_position 223.582245 cc
2021-03-25T17:19:00.846Z,1616692740.846 [Reporter](INFO): platform_buoyancy_position 218.881744 cc
2021-03-25T17:19:01.226Z,1616692741.226 [Reporter](INFO): platform_buoyancy_position 214.852722 cc
2021-03-25T17:19:01.735Z,1616692741.735 [Reporter](INFO): platform_buoyancy_position 208.272034 cc
2021-03-25T17:19:16.118Z,1616692756.118 [DVL_micro](FAULT): Failed to initialize within timeout.
2021-03-25T17:19:16.118Z,1616692756.118 [DVL_micro] Communications Fault, FailCount= 1
2021-03-25T17:19:16.118Z,1616692756.118 [DVL_micro](ERROR): Communications Fault
2021-03-25T17:19:16.264Z,1616692756.264 [CBIT](ERROR): Communications Fault in component: DVL_micro
2021-03-25T17:19:16.588Z,1616692756.588 [DVL_micro](INFO): Powering down
2021-03-25T17:19:16.920Z,1616692756.920 [NAL9602](INFO): Not Powering down - fast GPS
2021-03-25T17:19:17.450Z,1616692757.450 [CBIT](INFO): Clearing failed state for component DVL_micro
2021-03-25T17:19:17.450Z,1616692757.450 [DVL_micro] No Fault, FailCount= 1
2021-03-25T17:19:22.603Z,1616692762.603 [DataOverHttps](INFO): Sending 1018 bytes from file Logs/20210325T171451/Express0001.lzma
2021-03-25T17:19:22.928Z,1616692762.928 [DataOverHttps](INFO): Moved sent file to Logs/20210325T171451/Express0001.lzma.bak
2021-03-25T17:19:22.928Z,1616692762.928 [DataOverHttps](INFO): SBD MOMSN=15446473
2021-03-25T17:19:27.109Z,1616692767.109 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction 15.000000 millimeter
2021-03-25T17:19:27.111Z,1616692767.111 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.massPositionAction" handled in the control thread.
2021-03-25T17:19:27.481Z,1616692767.481 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,DVL_micro,NAL9602,DAT,BPC1,Aanderaa_O2,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.buoyancyAction,Maintain_VerticalControl.massPositionAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2021-03-25T17:19:35.104Z,1616692775.104 [DVL_micro](FAULT): Failed to initialize within timeout.
2021-03-25T17:19:35.104Z,1616692775.104 [DVL_micro] Communications Fault, FailCount= 2
2021-03-25T17:19:35.104Z,1616692775.104 [DVL_micro](ERROR): Communications Fault
2021-03-25T17:19:35.170Z,1616692775.170 [CBIT](ERROR): Communications Fault in component: DVL_micro
2021-03-25T17:19:35.572Z,1616692775.572 [DVL_micro](INFO): Powering down
2021-03-25T17:19:36.388Z,1616692776.388 [CBIT](INFO): Clearing failed state for component DVL_micro
2021-03-25T17:19:36.388Z,1616692776.388 [DVL_micro] No Fault, FailCount= 2
2021-03-25T17:19:37.670Z,1616692777.670 [CommandLine](IMPORTANT): got command report mod platform_mass_position
2021-03-25T17:19:38.005Z,1616692778.005 [Reporter](INFO): platform_mass_position 0.006585 m
2021-03-25T17:19:38.409Z,1616692778.409 [Reporter](INFO): platform_mass_position 0.006905 m
2021-03-25T17:19:38.811Z,1616692778.811 [Reporter](INFO): platform_mass_position 0.007221 m
2021-03-25T17:19:39.201Z,1616692779.201 [Reporter](INFO): platform_mass_position 0.007522 m
2021-03-25T17:19:39.613Z,1616692779.613 [Reporter](INFO): platform_mass_position 0.007839 m
2021-03-25T17:19:40.057Z,1616692780.057 [Reporter](INFO): platform_mass_position 0.008160 m
2021-03-25T17:19:40.517Z,1616692780.517 [Reporter](INFO): platform_mass_position 0.008554 m
2021-03-25T17:19:40.875Z,1616692780.875 [Reporter](INFO): platform_mass_position 0.008805 m
2021-03-25T17:19:41.321Z,1616692781.321 [Reporter](INFO): platform_mass_position 0.009170 m
2021-03-25T17:19:41.708Z,1616692781.708 [Reporter](INFO): platform_mass_position 0.009488 m
2021-03-25T17:19:42.125Z,1616692782.125 [Reporter](INFO): platform_mass_position 0.009792 m
2021-03-25T17:19:42.714Z,1616692782.714 [Reporter](INFO): platform_mass_position 0.010268 m
2021-03-25T17:19:42.958Z,1616692782.958 [Reporter](INFO): platform_mass_position 0.010468 m
2021-03-25T17:19:43.329Z,1616692783.329 [Reporter](INFO): platform_mass_position 0.010762 m
2021-03-25T17:19:43.785Z,1616692783.785 [Reporter](INFO): platform_mass_position 0.011115 m
2021-03-25T17:19:44.036Z,1616692784.036 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2021-03-25T17:19:44.166Z,1616692784.166 [Reporter](INFO): platform_mass_position 0.011417 m
2021-03-25T17:19:44.561Z,1616692784.561 [Reporter](INFO): platform_mass_position 0.011721 m
2021-03-25T17:19:44.986Z,1616692784.986 [Reporter](INFO): platform_mass_position 0.012059 m
2021-03-25T17:19:45.370Z,1616692785.370 [Reporter](INFO): platform_mass_position 0.012360 m
2021-03-25T17:19:45.767Z,1616692785.767 [Reporter](INFO): platform_mass_position 0.012671 m
2021-03-25T17:19:46.184Z,1616692786.184 [Reporter](INFO): platform_mass_position 0.012980 m
2021-03-25T17:19:46.594Z,1616692786.594 [Reporter](INFO): platform_mass_position 0.013311 m
2021-03-25T17:19:46.977Z,1616692786.977 [Reporter](INFO): platform_mass_position 0.013618 m
2021-03-25T17:19:47.430Z,1616692787.430 [Reporter](INFO): platform_mass_position 0.013969 m
2021-03-25T17:19:47.773Z,1616692787.773 [Reporter](INFO): platform_mass_position 0.014242 m
2021-03-25T17:19:48.189Z,1616692788.189 [Reporter](INFO): platform_mass_position 0.014573 m
2021-03-25T17:19:48.460Z,1616692788.460 [DataOverHttps](INFO): Sending 300 bytes from file Logs/20210325T171451/Express0005.lzma
2021-03-25T17:19:48.569Z,1616692788.569 [Reporter](INFO): platform_mass_position 0.014866 m
2021-03-25T17:19:48.954Z,1616692788.954 [Reporter](INFO): platform_mass_position 0.015000 m
2021-03-25T17:19:49.461Z,1616692789.461 [DataOverHttps](INFO): Moved sent file to Logs/20210325T171451/Express0005.lzma.bak
2021-03-25T17:19:49.462Z,1616692789.462 [DataOverHttps](INFO): SBD MOMSN=15446501
2021-03-25T17:19:54.193Z,1616692794.193 [DVL_micro](FAULT): Failed to initialize within timeout.
2021-03-25T17:19:54.193Z,1616692794.193 [DVL_micro] Communications Fault, FailCount= 3
2021-03-25T17:19:54.193Z,1616692794.193 [DVL_micro](ERROR): Communications Fault
2021-03-25T17:19:54.321Z,1616692794.321 [CBIT](ERROR): Communications Fault in component: DVL_micro
2021-03-25T17:19:54.321Z,1616692794.321 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2021-03-25T17:19:54.680Z,1616692794.680 [DVL_micro](INFO): Powering down
2021-03-25T17:19:54.783Z,1616692794.783 [CBIT](INFO): Critical error at 20210325T171954
2021-03-25T17:19:59.918Z,1616692799.918 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2021-03-25T17:20:01.160Z,1616692801.160 [Default:CheckIn:Read_Iridium] Stopped
2021-03-25T17:20:01.160Z,1616692801.160 [Default:CheckIn:C.Wait] Running Loop=1
2021-03-25T17:20:01.161Z,1616692801.161 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-03-25T17:20:15.333Z,1616692815.333 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2021-03-25T17:20:30.689Z,1616692830.689 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2021-03-25T17:20:46.878Z,1616692846.878 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2021-03-25T17:21:02.630Z,1616692862.630 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2021-03-25T17:21:06.268Z,1616692866.268 [CommandLine](IMPORTANT): got command maintain control SpeedControl.propOmegaAction 300.000000 revolution_per_minute
2021-03-25T17:21:06.269Z,1616692866.269 [ComponentRegistry](DEBUG): SyncComponent "Maintain_SpeedControl.propOmegaAction" handled in the control thread.
2021-03-25T17:21:06.329Z,1616692866.329 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,DVL_micro,NAL9602,DAT,BPC1,Aanderaa_O2,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.buoyancyAction,Maintain_VerticalControl.massPositionAction,Maintain_SpeedControl.propOmegaAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2021-03-25T17:21:10.772Z,1616692870.772 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2021-03-25T17:21:10.772Z,1616692870.772 [Aanderaa_O2] No Fault, FailCount= 3
2021-03-25T17:21:18.389Z,1616692878.389 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2021-03-25T17:21:21.214Z,1616692881.214 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2021-03-25T17:21:21.214Z,1616692881.214 [Aanderaa_O2] Communications Fault, FailCount= 1
2021-03-25T17:21:21.214Z,1616692881.214 [Aanderaa_O2](ERROR): Communications Fault
2021-03-25T17:21:21.295Z,1616692881.295 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2021-03-25T17:21:21.740Z,1616692881.740 [Aanderaa_O2](INFO): Powering down
2021-03-25T17:21:22.511Z,1616692882.511 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2021-03-25T17:21:22.511Z,1616692882.511 [Aanderaa_O2] No Fault, FailCount= 1
2021-03-25T17:21:32.930Z,1616692892.930 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2021-03-25T17:21:32.930Z,1616692892.930 [Aanderaa_O2] Communications Fault, FailCount= 2
2021-03-25T17:21:32.930Z,1616692892.930 [Aanderaa_O2](ERROR): Communications Fault
2021-03-25T17:21:33.018Z,1616692893.018 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2021-03-25T17:21:33.451Z,1616692893.451 [Aanderaa_O2](INFO): Powering down
2021-03-25T17:21:34.141Z,1616692894.141 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2021-03-25T17:21:34.259Z,1616692894.259 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2021-03-25T17:21:34.271Z,1616692894.271 [Aanderaa_O2] No Fault, FailCount= 2
2021-03-25T17:21:44.660Z,1616692904.660 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2021-03-25T17:21:44.660Z,1616692904.660 [Aanderaa_O2] Communications Fault, FailCount= 3
2021-03-25T17:21:44.660Z,1616692904.660 [Aanderaa_O2](ERROR): Communications Fault
2021-03-25T17:21:44.724Z,1616692904.724 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2021-03-25T17:21:44.724Z,1616692904.724 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2021-03-25T17:21:45.180Z,1616692905.180 [Aanderaa_O2](INFO): Powering down
2021-03-25T17:21:49.897Z,1616692909.897 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2021-03-25T17:22:54.613Z,1616692974.613 [CBIT](INFO): Clearing failed state for component DVL_micro
2021-03-25T17:22:54.613Z,1616692974.613 [DVL_micro] No Fault, FailCount= 3
2021-03-25T17:23:12.316Z,1616692992.316 [DVL_micro](FAULT): Failed to initialize within timeout.
2021-03-25T17:23:12.316Z,1616692992.316 [DVL_micro] Communications Fault, FailCount= 1
2021-03-25T17:23:12.316Z,1616692992.316 [DVL_micro](ERROR): Communications Fault
2021-03-25T17:23:12.407Z,1616692992.407 [CBIT](ERROR): Communications Fault in component: DVL_micro
2021-03-25T17:23:12.795Z,1616692992.795 [DVL_micro](INFO): Powering down
2021-03-25T17:23:13.663Z,1616692993.663 [CBIT](INFO): Clearing failed state for component DVL_micro
2021-03-25T17:23:13.663Z,1616692993.663 [DVL_micro] No Fault, FailCount= 1
2021-03-25T17:23:31.302Z,1616693011.302 [DVL_micro](FAULT): Failed to initialize within timeout.
2021-03-25T17:23:31.302Z,1616693011.302 [DVL_micro] Communications Fault, FailCount= 2
2021-03-25T17:23:31.302Z,1616693011.302 [DVL_micro](ERROR): Communications Fault
2021-03-25T17:23:31.360Z,1616693011.360 [CBIT](ERROR): Communications Fault in component: DVL_micro
2021-03-25T17:23:31.779Z,1616693011.779 [DVL_micro](INFO): Powering down
2021-03-25T17:23:32.603Z,1616693012.603 [CBIT](INFO): Clearing failed state for component DVL_micro
2021-03-25T17:23:32.603Z,1616693012.603 [DVL_micro] No Fault, FailCount= 2
2021-03-25T17:23:50.292Z,1616693030.292 [DVL_micro](FAULT): Failed to initialize within timeout.
2021-03-25T17:23:50.292Z,1616693030.292 [DVL_micro] Communications Fault, FailCount= 3
2021-03-25T17:23:50.292Z,1616693030.292 [DVL_micro](ERROR): Communications Fault
2021-03-25T17:23:50.413Z,1616693030.413 [CBIT](ERROR): Communications Fault in component: DVL_micro
2021-03-25T17:23:50.415Z,1616693030.415 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2021-03-25T17:23:50.764Z,1616693030.764 [DVL_micro](INFO): Powering down
2021-03-25T17:23:50.845Z,1616693030.845 [CBIT](INFO): Critical error at 20210325T172350
2021-03-25T17:24:14.762Z,1616693054.762 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2021-03-25T17:24:14.762Z,1616693054.762 [Aanderaa_O2] No Fault, FailCount= 3
2021-03-25T17:24:25.270Z,1616693065.270 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2021-03-25T17:24:25.270Z,1616693065.270 [Aanderaa_O2] Communications Fault, FailCount= 1
2021-03-25T17:24:25.270Z,1616693065.270 [Aanderaa_O2](ERROR): Communications Fault
2021-03-25T17:24:25.359Z,1616693065.359 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2021-03-25T17:24:25.732Z,1616693065.732 [Aanderaa_O2](INFO): Powering down
2021-03-25T17:24:26.483Z,1616693066.483 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2021-03-25T17:24:26.499Z,1616693066.499 [Aanderaa_O2] No Fault, FailCount= 1
2021-03-25T17:24:36.918Z,1616693076.918 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2021-03-25T17:24:36.918Z,1616693076.918 [Aanderaa_O2] Communications Fault, FailCount= 2
2021-03-25T17:24:36.918Z,1616693076.918 [Aanderaa_O2](ERROR): Communications Fault
2021-03-25T17:24:36.991Z,1616693076.991 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2021-03-25T17:24:37.440Z,1616693077.440 [Aanderaa_O2](INFO): Powering down
2021-03-25T17:24:38.191Z,1616693078.191 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2021-03-25T17:24:38.191Z,1616693078.191 [Aanderaa_O2] No Fault, FailCount= 2
2021-03-25T17:24:48.640Z,1616693088.640 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2021-03-25T17:24:48.640Z,1616693088.640 [Aanderaa_O2] Communications Fault, FailCount= 3
2021-03-25T17:24:48.640Z,1616693088.640 [Aanderaa_O2](ERROR): Communications Fault
2021-03-25T17:24:48.697Z,1616693088.697 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2021-03-25T17:24:48.697Z,1616693088.697 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2021-03-25T17:24:49.152Z,1616693089.152 [Aanderaa_O2](INFO): Powering down
2021-03-25T17:25:01.579Z,1616693101.579 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-03-25T17:25:01.579Z,1616693101.579 [Default:CheckIn:C.Wait] Stopped
2021-03-25T17:25:01.579Z,1616693101.579 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-03-25T17:25:01.579Z,1616693101.579 [Default:CheckIn:D] Running Loop=1
2021-03-25T17:25:01.988Z,1616693101.988 [Default:CheckIn:D] Stopped
2021-03-25T17:25:01.988Z,1616693101.988 [Default:CheckIn:E] Running Loop=1
2021-03-25T17:25:02.387Z,1616693102.387 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 6.738162 min 
2021-03-25T17:25:02.387Z,1616693102.387 [Default:CheckIn:E] Stopped
2021-03-25T17:25:02.387Z,1616693102.387 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-03-25T17:25:02.387Z,1616693102.387 [Default:CheckIn] Stopped
2021-03-25T17:25:02.387Z,1616693102.387 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-03-25T17:25:02.403Z,1616693102.403 [Default:CheckIn](INFO): Running loop #2
2021-03-25T17:25:02.403Z,1616693102.403 [Default:CheckIn] Running Loop=2
2021-03-25T17:25:02.403Z,1616693102.403 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-03-25T17:25:02.404Z,1616693102.404 [Default:CheckIn:Read_GPS] Running Loop=1
2021-03-25T17:25:04.394Z,1616693104.394 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172355.00,A,3648.16326,N,12147.28214,W,0.583,87.73,250321,,,A*4C
2021-03-25T17:25:04.396Z,1616693104.396 [NAL9602](INFO): GPS fix at 20210325T172355: (36.802721, -121.788036)
2021-03-25T17:25:04.454Z,1616693104.454 [Default:CheckIn:Read_GPS] Stopped
2021-03-25T17:25:04.454Z,1616693104.454 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-03-25T17:25:13.703Z,1616693113.703 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20210325T171451/Courier0007.lzma
2021-03-25T17:25:14.706Z,1616693114.706 [DataOverHttps](INFO): Moved sent file to Logs/20210325T171451/Courier0007.lzma.bak
2021-03-25T17:25:14.706Z,1616693114.706 [DataOverHttps](INFO): SBD MOMSN=15446511
2021-03-25T17:25:36.292Z,1616693136.292 [DataOverHttps](INFO): Sending 488 bytes from file Logs/20210325T171451/Express0008.lzma
2021-03-25T17:25:37.293Z,1616693137.293 [DataOverHttps](INFO): Moved sent file to Logs/20210325T171451/Express0008.lzma.bak
2021-03-25T17:25:37.293Z,1616693137.293 [DataOverHttps](INFO): SBD MOMSN=15446516
2021-03-25T17:25:48.559Z,1616693148.559 [Default:CheckIn:Read_Iridium] Stopped
2021-03-25T17:25:48.593Z,1616693148.593 [Default:CheckIn:C.Wait] Running Loop=1
2021-03-25T17:25:48.593Z,1616693148.593 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-03-25T17:26:03.602Z,1616693163.602 [NAL9602](INFO): SBD MO Status=2, MOMSN=4079, MT Status=2, MTMSN=0
2021-03-25T17:26:03.602Z,1616693163.602 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-03-25T17:26:29.098Z,1616693189.098 [NAL9602](INFO): SBD MO Status=2, MOMSN=4079, MT Status=2, MTMSN=0
2021-03-25T17:26:29.098Z,1616693189.098 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-03-25T17:26:50.591Z,1616693210.591 [CBIT](INFO): Clearing failed state for component DVL_micro
2021-03-25T17:26:50.591Z,1616693210.591 [DVL_micro] No Fault, FailCount= 3
2021-03-25T17:27:08.288Z,1616693228.288 [DVL_micro](FAULT): Failed to initialize within timeout.
2021-03-25T17:27:08.288Z,1616693228.288 [DVL_micro] Communications Fault, FailCount= 1
2021-03-25T17:27:08.288Z,1616693228.288 [DVL_micro](ERROR): Communications Fault
2021-03-25T17:27:08.367Z,1616693228.367 [CBIT](ERROR): Communications Fault in component: DVL_micro
2021-03-25T17:27:08.756Z,1616693228.756 [DVL_micro](INFO): Powering down
2021-03-25T17:27:09.557Z,1616693229.557 [CBIT](INFO): Clearing failed state for component DVL_micro
2021-03-25T17:27:09.557Z,1616693229.557 [DVL_micro] No Fault, FailCount= 1
2021-03-25T17:27:18.859Z,1616693238.859 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2021-03-25T17:27:18.859Z,1616693238.859 [Aanderaa_O2] No Fault, FailCount= 3
2021-03-25T17:27:26.654Z,1616693246.654 [CommandLine](IMPORTANT): got command maintain control SpeedControl.propOmegaAction 0.000000 revolution_per_minute
2021-03-25T17:27:27.272Z,1616693247.272 [DVL_micro](FAULT): Failed to initialize within timeout.
2021-03-25T17:27:27.272Z,1616693247.272 [DVL_micro] Communications Fault, FailCount= 2
2021-03-25T17:27:27.272Z,1616693247.272 [DVL_micro](ERROR): Communications Fault
2021-03-25T17:27:27.419Z,1616693247.419 [CBIT](ERROR): Communications Fault in component: DVL_micro
2021-03-25T17:27:27.763Z,1616693247.763 [DVL_micro](INFO): Powering down
2021-03-25T17:27:28.513Z,1616693248.513 [CBIT](INFO): Clearing failed state for component DVL_micro
2021-03-25T17:27:28.513Z,1616693248.513 [DVL_micro] No Fault, FailCount= 2
2021-03-25T17:27:29.298Z,1616693249.298 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2021-03-25T17:27:29.298Z,1616693249.298 [Aanderaa_O2] Communications Fault, FailCount= 1
2021-03-25T17:27:29.298Z,1616693249.298 [Aanderaa_O2](ERROR): Communications Fault
2021-03-25T17:27:29.317Z,1616693249.317 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2021-03-25T17:27:29.824Z,1616693249.824 [Aanderaa_O2](INFO): Powering down
2021-03-25T17:27:30.558Z,1616693250.558 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2021-03-25T17:27:30.558Z,1616693250.558 [Aanderaa_O2] No Fault, FailCount= 1
2021-03-25T17:27:39.802Z,1616693259.802 [NAL9602](INFO): SBD MO Status=2, MOMSN=4079, MT Status=2, MTMSN=0
2021-03-25T17:27:39.802Z,1616693259.802 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-03-25T17:27:41.014Z,1616693261.014 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2021-03-25T17:27:41.014Z,1616693261.014 [Aanderaa_O2] Communications Fault, FailCount= 2
2021-03-25T17:27:41.014Z,1616693261.014 [Aanderaa_O2](ERROR): Communications Fault
2021-03-25T17:27:41.033Z,1616693261.033 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2021-03-25T17:27:41.528Z,1616693261.528 [Aanderaa_O2](INFO): Powering down
2021-03-25T17:27:42.280Z,1616693262.280 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2021-03-25T17:27:42.280Z,1616693262.280 [Aanderaa_O2] No Fault, FailCount= 2
2021-03-25T17:27:46.267Z,1616693266.267 [DVL_micro](FAULT): Failed to initialize within timeout.
2021-03-25T17:27:46.267Z,1616693266.267 [DVL_micro] Communications Fault, FailCount= 3
2021-03-25T17:27:46.267Z,1616693266.267 [DVL_micro](ERROR): Communications Fault
2021-03-25T17:27:46.422Z,1616693266.422 [CBIT](ERROR): Communications Fault in component: DVL_micro
2021-03-25T17:27:46.423Z,1616693266.423 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2021-03-25T17:27:46.740Z,1616693266.740 [DVL_micro](INFO): Powering down
2021-03-25T17:27:46.803Z,1616693266.803 [CBIT](INFO): Critical error at 20210325T172746
2021-03-25T17:27:52.748Z,1616693272.748 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2021-03-25T17:27:52.748Z,1616693272.748 [Aanderaa_O2] Communications Fault, FailCount= 3
2021-03-25T17:27:52.748Z,1616693272.748 [Aanderaa_O2](ERROR): Communications Fault
2021-03-25T17:27:52.879Z,1616693272.879 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2021-03-25T17:27:52.879Z,1616693272.879 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2021-03-25T17:27:53.272Z,1616693273.272 [Aanderaa_O2](INFO): Powering down
2021-03-25T17:28:41.625Z,1616693321.625 [NAL9602](INFO): SBD MO Status=2, MOMSN=4079, MT Status=2, MTMSN=0
2021-03-25T17:28:41.625Z,1616693321.625 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-03-25T17:29:26.898Z,1616693366.898 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2021-03-25T17:29:43.050Z,1616693383.050 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2021-03-25T17:29:56.834Z,1616693396.834 [NAL9602](INFO): SBD MO Status=2, MOMSN=4079, MT Status=2, MTMSN=0
2021-03-25T17:29:56.834Z,1616693396.834 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-03-25T17:29:58.848Z,1616693398.848 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2021-03-25T17:30:03.340Z,1616693403.340 [CBIT](INFO): Clearing failed state for component DropWeight
2021-03-25T17:30:03.340Z,1616693403.340 [DropWeight] No Fault, FailCount= 1
2021-03-25T17:30:06.934Z,1616693406.934 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2021-03-25T17:30:14.604Z,1616693414.604 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2021-03-25T17:30:22.756Z,1616693422.756 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2021-03-25T17:30:22.756Z,1616693422.756 [Aanderaa_O2] No Fault, FailCount= 3
2021-03-25T17:30:29.966Z,1616693429.966 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2021-03-25T17:30:33.260Z,1616693433.260 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2021-03-25T17:30:33.260Z,1616693433.260 [Aanderaa_O2] Communications Fault, FailCount= 1
2021-03-25T17:30:33.260Z,1616693433.260 [Aanderaa_O2](ERROR): Communications Fault
2021-03-25T17:30:33.283Z,1616693433.283 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2021-03-25T17:30:33.716Z,1616693433.716 [Aanderaa_O2](INFO): Powering down
2021-03-25T17:30:34.437Z,1616693434.437 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2021-03-25T17:30:34.437Z,1616693434.437 [Aanderaa_O2] No Fault, FailCount= 1
2021-03-25T17:30:37.638Z,1616693437.638 [NAL9602](INFO): Not Powering down - fast GPS
2021-03-25T17:30:44.904Z,1616693444.904 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2021-03-25T17:30:44.904Z,1616693444.904 [Aanderaa_O2] Communications Fault, FailCount= 2
2021-03-25T17:30:44.904Z,1616693444.904 [Aanderaa_O2](ERROR): Communications Fault
2021-03-25T17:30:44.923Z,1616693444.923 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2021-03-25T17:30:45.456Z,1616693445.456 [Aanderaa_O2](INFO): Powering down
2021-03-25T17:30:45.719Z,1616693445.719 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2021-03-25T17:30:46.157Z,1616693446.157 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2021-03-25T17:30:46.158Z,1616693446.158 [Aanderaa_O2] No Fault, FailCount= 2
2021-03-25T17:30:46.630Z,1616693446.630 [CBIT](INFO): Clearing failed state for component DVL_micro
2021-03-25T17:30:46.630Z,1616693446.630 [DVL_micro] No Fault, FailCount= 3
2021-03-25T17:30:49.362Z,1616693449.362 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-03-25T17:30:49.362Z,1616693449.362 [Default:CheckIn:C.Wait] Stopped
2021-03-25T17:30:49.362Z,1616693449.362 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-03-25T17:30:49.363Z,1616693449.363 [Default:CheckIn:D] Running Loop=1
2021-03-25T17:30:49.767Z,1616693449.767 [Default:CheckIn:D] Stopped
2021-03-25T17:30:49.767Z,1616693449.767 [Default:CheckIn:E] Running Loop=1
2021-03-25T17:30:50.166Z,1616693450.166 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 12.534477 min 
2021-03-25T17:30:50.166Z,1616693450.166 [Default:CheckIn:E] Stopped
2021-03-25T17:30:50.166Z,1616693450.166 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-03-25T17:30:50.166Z,1616693450.166 [Default:CheckIn] Stopped
2021-03-25T17:30:50.167Z,1616693450.167 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-03-25T17:30:50.167Z,1616693450.167 [Default:CheckIn](INFO): Running loop #3
2021-03-25T17:30:50.167Z,1616693450.167 [Default:CheckIn] Running Loop=3
2021-03-25T17:30:50.167Z,1616693450.167 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-03-25T17:30:50.167Z,1616693450.167 [Default:CheckIn:Read_GPS] Running Loop=1
2021-03-25T17:30:52.182Z,1616693452.182 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172942.00,A,3648.16379,N,12147.28076,W,0.369,87.73,250321,,,A*4E
2021-03-25T17:30:52.194Z,1616693452.194 [NAL9602](INFO): GPS fix at 20210325T172942: (36.802730, -121.788013)
2021-03-25T17:30:52.204Z,1616693452.204 [Default:CheckIn:Read_GPS] Stopped
2021-03-25T17:30:52.204Z,1616693452.204 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-03-25T17:30:56.628Z,1616693456.628 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2021-03-25T17:30:56.628Z,1616693456.628 [Aanderaa_O2] Communications Fault, FailCount= 3
2021-03-25T17:30:56.628Z,1616693456.628 [Aanderaa_O2](ERROR): Communications Fault
2021-03-25T17:30:56.653Z,1616693456.653 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2021-03-25T17:30:56.653Z,1616693456.653 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2021-03-25T17:30:57.140Z,1616693457.140 [Aanderaa_O2](INFO): Powering down
2021-03-25T17:31:01.470Z,1616693461.470 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2021-03-25T17:31:02.635Z,1616693462.635 [DataOverHttps](INFO): Sending 125 bytes from file Logs/20210325T171451/Courier0010.lzma
2021-03-25T17:31:03.637Z,1616693463.637 [DataOverHttps](INFO): Moved sent file to Logs/20210325T171451/Courier0010.lzma.bak
2021-03-25T17:31:03.637Z,1616693463.637 [DataOverHttps](INFO): SBD MOMSN=15446536
2021-03-25T17:31:04.294Z,1616693464.294 [DVL_micro](FAULT): Failed to initialize within timeout.
2021-03-25T17:31:04.294Z,1616693464.294 [DVL_micro] Communications Fault, FailCount= 1
2021-03-25T17:31:04.294Z,1616693464.294 [DVL_micro](ERROR): Communications Fault
2021-03-25T17:31:04.315Z,1616693464.315 [CBIT](ERROR): Communications Fault in component: DVL_micro
2021-03-25T17:31:04.768Z,1616693464.768 [DVL_micro](INFO): Powering down
2021-03-25T17:31:05.583Z,1616693465.583 [CBIT](INFO): Clearing failed state for component DVL_micro
2021-03-25T17:31:05.583Z,1616693465.583 [DVL_micro] No Fault, FailCount= 1
2021-03-25T17:31:17.630Z,1616693477.630 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2021-03-25T17:31:22.881Z,1616693482.881 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2021-03-25T17:31:22.959Z,1616693482.959 [NAL9602](FAULT): received: 
+CSQ:0

OK79, 2, 0, 0, 0

OK
2021-03-25T17:31:22.960Z,1616693482.960 [NAL9602] Data Fault, FailCount= 1
2021-03-25T17:31:22.960Z,1616693482.960 [NAL9602](ERROR): Data Fault
2021-03-25T17:31:23.096Z,1616693483.096 [CBIT](ERROR): Data Fault in component: NAL9602
2021-03-25T17:31:23.284Z,1616693483.284 [DVL_micro](FAULT): Failed to initialize within timeout.
2021-03-25T17:31:23.285Z,1616693483.285 [DVL_micro] Communications Fault, FailCount= 2
2021-03-25T17:31:23.285Z,1616693483.285 [DVL_micro](ERROR): Communications Fault
2021-03-25T17:31:23.285Z,1616693483.285 [NAL9602](INFO): Powering down
2021-03-25T17:31:23.400Z,1616693483.400 [CBIT](ERROR): Communications Fault in component: DVL_micro
2021-03-25T17:31:23.763Z,1616693483.763 [DVL_micro](INFO): Powering down
2021-03-25T17:31:24.127Z,1616693484.127 [CBIT](INFO): Clearing failed state for component NAL9602
2021-03-25T17:31:24.127Z,1616693484.127 [NAL9602] No Fault, FailCount= 1
2021-03-25T17:31:24.544Z,1616693484.544 [CBIT](INFO): Clearing failed state for component DVL_micro
2021-03-25T17:31:24.544Z,1616693484.544 [DVL_micro] No Fault, FailCount= 2
2021-03-25T17:31:25.024Z,1616693485.024 [DataOverHttps](INFO): Sending 419 bytes from file Logs/20210325T171451/Express0011.lzma
2021-03-25T17:31:27.905Z,1616693487.905 [DataOverHttps](INFO): Moved sent file to Logs/20210325T171451/Express0011.lzma.bak
2021-03-25T17:31:27.905Z,1616693487.905 [DataOverHttps](INFO): SBD MOMSN=15446540
2021-03-25T17:31:33.386Z,1616693493.386 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2021-03-25T17:31:33.453Z,1616693493.453 [Default:CheckIn:Read_Iridium] Stopped
2021-03-25T17:31:33.453Z,1616693493.453 [Default:CheckIn:C.Wait] Running Loop=1
2021-03-25T17:31:33.453Z,1616693493.453 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-03-25T17:31:42.296Z,1616693502.296 [DVL_micro](FAULT): Failed to initialize within timeout.
2021-03-25T17:31:42.297Z,1616693502.297 [DVL_micro] Communications Fault, FailCount= 3
2021-03-25T17:31:42.297Z,1616693502.297 [DVL_micro](ERROR): Communications Fault
2021-03-25T17:31:42.406Z,1616693502.406 [CBIT](ERROR): Communications Fault in component: DVL_micro
2021-03-25T17:31:42.406Z,1616693502.406 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2021-03-25T17:31:42.784Z,1616693502.784 [DVL_micro](INFO): Powering down
2021-03-25T17:31:42.863Z,1616693502.863 [CBIT](INFO): Critical error at 20210325T173142
2021-03-25T17:31:53.661Z,1616693513.661 [NAL9602](INFO): Powering up NAL9602
2021-03-25T17:32:04.677Z,1616693524.677 [NAL9602](INFO): NAL9602 initialized
2021-03-25T17:32:35.812Z,1616693555.812 [NAL9602](INFO): Not Powering down - fast GPS
2021-03-25T17:33:26.749Z,1616693606.749 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2021-03-25T17:33:26.749Z,1616693606.749 [Aanderaa_O2] No Fault, FailCount= 3
2021-03-25T17:33:27.879Z,1616693607.879 [CommandLine](IMPORTANT): got command ! echo i > /dev/loadC3
2021-03-25T17:33:37.222Z,1616693617.222 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2021-03-25T17:33:37.222Z,1616693617.222 [Aanderaa_O2] Communications Fault, FailCount= 1
2021-03-25T17:33:37.222Z,1616693617.222 [Aanderaa_O2](ERROR): Communications Fault
2021-03-25T17:33:37.322Z,1616693617.322 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2021-03-25T17:33:37.747Z,1616693617.747 [Aanderaa_O2](INFO): Powering down
2021-03-25T17:33:38.482Z,1616693618.482 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2021-03-25T17:33:38.482Z,1616693618.482 [Aanderaa_O2] No Fault, FailCount= 1
2021-03-25T17:33:46.119Z,1616693626.119 [CommandLine](IMPORTANT): got command restart system
2021-03-25T17:33:48.240Z,1616693628.240 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2021-03-25T17:33:48.240Z,1616693628.240 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-03-25T17:33:48.399Z,1616693628.399 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2021-03-25T17:33:48.400Z,1616693628.400 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-03-25T17:33:48.401Z,1616693628.401 [CommandLine](INFO): Join timeout helper Thread ID is 601
2021-03-25T17:33:48.401Z,1616693628.401 [ComponentReg