2020-06-22T19:39:21.970Z,1592854761.970 [Supervisor](DEBUG): Initializing supervisor. 2020-06-22T19:39:21.974Z,1592854761.974 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2020-06-22T19:39:21.974Z,1592854761.974 [SyncHandler](INFO): Protected caller Thread ID is 990 2020-06-22T19:39:21.974Z,1592854761.974 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2020-06-22T19:39:21.975Z,1592854761.975 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2020-06-22T19:39:21.976Z,1592854761.976 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 991 2020-06-22T19:39:21.980Z,1592854761.980 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2020-06-22T19:39:21.994Z,1592854761.994 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2020-06-22T19:39:21.995Z,1592854761.995 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2020-06-22T19:39:21.995Z,1592854761.995 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 992 2020-06-22T19:39:21.996Z,1592854761.996 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2020-06-22T19:39:21.997Z,1592854761.997 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2020-06-22T19:39:21.997Z,1592854761.997 [logger ThreadHandler](INFO): Protected caller Thread ID is 993 2020-06-22T19:39:21.999Z,1592854761.999 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2020-06-22T19:39:21.999Z,1592854761.999 [Supervisor](INFO): Looking for Config files in directory: Config/ 2020-06-22T19:39:22.001Z,1592854762.001 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2020-06-22T19:39:22.506Z,1592854762.506 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2020-06-22T19:39:22.507Z,1592854762.507 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2020-06-22T19:39:22.660Z,1592854762.660 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2020-06-22T19:39:22.661Z,1592854762.661 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2020-06-22T19:39:22.744Z,1592854762.744 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2020-06-22T19:39:22.962Z,1592854762.962 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2020-06-22T19:39:22.962Z,1592854762.962 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2020-06-22T19:39:23.065Z,1592854763.065 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2020-06-22T19:39:23.065Z,1592854763.065 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2020-06-22T19:39:23.165Z,1592854763.165 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2020-06-22T19:39:23.165Z,1592854763.165 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2020-06-22T19:39:23.681Z,1592854763.681 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2020-06-22T19:39:23.682Z,1592854763.682 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2020-06-22T19:39:23.938Z,1592854763.938 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2020-06-22T19:39:23.939Z,1592854763.939 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2020-06-22T19:39:24.446Z,1592854764.446 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2020-06-22T19:39:24.447Z,1592854764.447 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2020-06-22T19:39:24.676Z,1592854764.676 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2020-06-22T19:39:24.676Z,1592854764.676 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2020-06-22T19:39:25.030Z,1592854765.030 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2020-06-22T19:39:25.030Z,1592854765.030 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2020-06-22T19:39:25.270Z,1592854765.270 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2020-06-22T19:39:25.270Z,1592854765.270 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2020-06-22T19:39:25.422Z,1592854765.422 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2020-06-22T19:39:25.423Z,1592854765.423 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2020-06-22T19:39:26.154Z,1592854766.154 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2020-06-22T19:39:26.154Z,1592854766.154 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2020-06-22T19:39:26.544Z,1592854766.544 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2020-06-22T19:39:26.545Z,1592854766.545 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2020-06-22T19:39:26.773Z,1592854766.773 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2020-06-22T19:39:26.775Z,1592854766.775 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/ 2020-06-22T19:39:26.776Z,1592854766.776 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg 2020-06-22T19:39:26.992Z,1592854766.992 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg 2020-06-22T19:39:27.078Z,1592854767.078 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg 2020-06-22T19:39:27.246Z,1592854767.246 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg 2020-06-22T19:39:27.335Z,1592854767.335 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg 2020-06-22T19:39:27.576Z,1592854767.576 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2020-06-22T19:39:27.576Z,1592854767.576 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg 2020-06-22T19:39:27.690Z,1592854767.690 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg 2020-06-22T19:39:27.805Z,1592854767.805 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg 2020-06-22T19:39:27.903Z,1592854767.903 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg 2020-06-22T19:39:28.064Z,1592854768.064 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg 2020-06-22T19:39:28.167Z,1592854768.167 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg 2020-06-22T19:39:28.273Z,1592854768.273 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/root/ 2020-06-22T19:39:28.274Z,1592854768.274 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2020-06-22T19:39:28.284Z,1592854768.284 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2020-06-22T19:39:28.315Z,1592854768.315 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2020-06-22T19:39:28.316Z,1592854768.316 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2020-06-22T19:39:28.412Z,1592854768.412 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2020-06-22T19:39:28.413Z,1592854768.413 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2020-06-22T19:39:28.477Z,1592854768.477 [VerticalControl](DEBUG): Construct VerticalControl. 2020-06-22T19:39:28.593Z,1592854768.593 [VerticalControl] Loaded 2020-06-22T19:39:28.593Z,1592854768.593 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2020-06-22T19:39:28.594Z,1592854768.594 [HorizontalControl](DEBUG): Construct HorizontalControl. 2020-06-22T19:39:28.676Z,1592854768.676 [HorizontalControl] Loaded 2020-06-22T19:39:28.676Z,1592854768.676 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2020-06-22T19:39:28.676Z,1592854768.676 [SpeedControl](DEBUG): Construct SpeedControl. 2020-06-22T19:39:28.682Z,1592854768.682 [SpeedControl] Loaded 2020-06-22T19:39:28.682Z,1592854768.682 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2020-06-22T19:39:28.683Z,1592854768.683 [LoopControl](DEBUG): Construct LoopControl. 2020-06-22T19:39:28.683Z,1592854768.683 [LoopControl] Loaded 2020-06-22T19:39:28.684Z,1592854768.684 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2020-06-22T19:39:28.684Z,1592854768.684 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2020-06-22T19:39:28.685Z,1592854768.685 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2020-06-22T19:39:29.631Z,1592854769.631 [BuoyancyServo] Loaded 2020-06-22T19:39:29.631Z,1592854769.631 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2020-06-22T19:39:29.675Z,1592854769.675 [ElevatorServo] Loaded 2020-06-22T19:39:29.675Z,1592854769.675 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2020-06-22T19:39:29.702Z,1592854769.702 [MassServo] Loaded 2020-06-22T19:39:29.703Z,1592854769.703 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2020-06-22T19:39:29.762Z,1592854769.762 [RudderServo] Loaded 2020-06-22T19:39:29.762Z,1592854769.762 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2020-06-22T19:39:29.781Z,1592854769.781 [ThrusterServo] Loaded 2020-06-22T19:39:29.782Z,1592854769.782 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2020-06-22T19:39:29.782Z,1592854769.782 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2020-06-22T19:39:29.783Z,1592854769.783 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2020-06-22T19:39:29.877Z,1592854769.877 [DepthRateCalculator] Loaded 2020-06-22T19:39:29.877Z,1592854769.877 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2020-06-22T19:39:29.882Z,1592854769.882 [PitchRateCalculator] Loaded 2020-06-22T19:39:29.883Z,1592854769.883 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2020-06-22T19:39:29.898Z,1592854769.898 [SpeedCalculator] Loaded 2020-06-22T19:39:29.898Z,1592854769.898 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2020-06-22T19:39:29.919Z,1592854769.919 [TempGradientCalculator] Loaded 2020-06-22T19:39:29.919Z,1592854769.919 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2020-06-22T19:39:29.924Z,1592854769.924 [YawRateCalculator] Loaded 2020-06-22T19:39:29.925Z,1592854769.925 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2020-06-22T19:39:29.954Z,1592854769.954 [ElevatorOffsetCalculator] Loaded 2020-06-22T19:39:29.954Z,1592854769.954 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2020-06-22T19:39:29.955Z,1592854769.955 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2020-06-22T19:39:29.955Z,1592854769.955 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2020-06-22T19:39:30.092Z,1592854770.092 [DeadReckonUsingMultipleVelocitySources] Loaded 2020-06-22T19:39:30.093Z,1592854770.093 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2020-06-22T19:39:30.113Z,1592854770.113 [NavChart] Loaded 2020-06-22T19:39:30.114Z,1592854770.114 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2020-06-22T19:39:30.118Z,1592854770.118 [UniversalFixResidualReporter] Loaded 2020-06-22T19:39:30.118Z,1592854770.118 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2020-06-22T19:39:30.118Z,1592854770.118 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2020-06-22T19:39:30.119Z,1592854770.119 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2020-06-22T19:39:30.509Z,1592854770.509 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation 2020-06-22T19:39:30.514Z,1592854770.514 [AHRS_M2](INFO): created writer for : platform_orientation 2020-06-22T19:39:30.516Z,1592854770.516 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation 2020-06-22T19:39:30.521Z,1592854770.521 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation 2020-06-22T19:39:30.522Z,1592854770.522 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle 2020-06-22T19:39:30.527Z,1592854770.527 [AHRS_M2](INFO): created writer for : platform_pitch_angle 2020-06-22T19:39:30.527Z,1592854770.527 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle 2020-06-22T19:39:30.533Z,1592854770.533 [AHRS_M2](INFO): created writer for : platform_roll_angle 2020-06-22T19:39:30.606Z,1592854770.606 [AHRS_M2] Loaded 2020-06-22T19:39:30.606Z,1592854770.606 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2020-06-22T19:39:30.683Z,1592854770.683 [DataOverHttps] Loaded 2020-06-22T19:39:30.683Z,1592854770.683 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2020-06-22T19:39:30.684Z,1592854770.684 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4084A4E0 2020-06-22T19:39:30.685Z,1592854770.685 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1075 2020-06-22T19:39:30.698Z,1592854770.698 [Depth_Keller] Loaded 2020-06-22T19:39:30.699Z,1592854770.699 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2020-06-22T19:39:30.797Z,1592854770.797 [NAL9602] Loaded 2020-06-22T19:39:30.797Z,1592854770.797 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2020-06-22T19:39:30.846Z,1592854770.846 [Onboard] Loaded 2020-06-22T19:39:30.846Z,1592854770.846 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2020-06-22T19:39:30.847Z,1592854770.847 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4087A4E0 2020-06-22T19:39:30.847Z,1592854770.847 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1076 2020-06-22T19:39:30.851Z,1592854770.851 [Radio_Surface] Loaded 2020-06-22T19:39:30.851Z,1592854770.851 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2020-06-22T19:39:30.852Z,1592854770.852 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408AA4E0 2020-06-22T19:39:30.852Z,1592854770.852 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1077 2020-06-22T19:39:30.987Z,1592854770.987 [DAT] Loaded 2020-06-22T19:39:30.988Z,1592854770.988 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2020-06-22T19:39:32.502Z,1592854772.502 [BPC1] Loaded 2020-06-22T19:39:32.503Z,1592854772.503 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2020-06-22T19:39:32.503Z,1592854772.503 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2020-06-22T19:39:32.504Z,1592854772.504 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2020-06-22T19:39:32.569Z,1592854772.569 [Module Loader](ERROR): Cannot load library: Modules/Estimation.so: undefined symbol: _ZN9Component15executeRunStateEv 2020-06-22T19:39:32.569Z,1592854772.569 [Module Loader](ERROR): Could not load the module at Modules/Estimation.so 2020-06-22T19:39:32.570Z,1592854772.570 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2020-06-22T19:39:32.695Z,1592854772.695 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2020-06-22T19:39:32.695Z,1592854772.695 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2020-06-22T19:39:32.736Z,1592854772.736 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2020-06-22T19:39:32.737Z,1592854772.737 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2020-06-22T19:39:33.018Z,1592854773.018 [Aanderaa_O2] Loaded 2020-06-22T19:39:33.019Z,1592854773.019 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2020-06-22T19:39:33.028Z,1592854773.028 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density 2020-06-22T19:39:33.034Z,1592854773.034 [CTD_Seabird](INFO): created writer for : sea_water_density 2020-06-22T19:39:33.034Z,1592854773.034 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth 2020-06-22T19:39:33.039Z,1592854773.039 [CTD_Seabird](INFO): created writer for : depth 2020-06-22T19:39:33.040Z,1592854773.040 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure 2020-06-22T19:39:33.045Z,1592854773.045 [CTD_Seabird](INFO): created writer for : sea_water_pressure 2020-06-22T19:39:33.045Z,1592854773.045 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity 2020-06-22T19:39:33.051Z,1592854773.051 [CTD_Seabird](INFO): created writer for : sea_water_salinity 2020-06-22T19:39:33.051Z,1592854773.051 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature 2020-06-22T19:39:33.056Z,1592854773.056 [CTD_Seabird](INFO): created writer for : sea_water_temperature 2020-06-22T19:39:33.057Z,1592854773.057 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity 2020-06-22T19:39:33.062Z,1592854773.062 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity 2020-06-22T19:39:33.063Z,1592854773.063 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water 2020-06-22T19:39:33.068Z,1592854773.068 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water 2020-06-22T19:39:33.096Z,1592854773.096 [CTD_Seabird] Loaded 2020-06-22T19:39:33.096Z,1592854773.096 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2020-06-22T19:39:33.097Z,1592854773.097 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409F24E0 2020-06-22T19:39:33.097Z,1592854773.097 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1078 2020-06-22T19:39:33.128Z,1592854773.128 [ESPComponent] Loaded 2020-06-22T19:39:33.129Z,1592854773.129 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2020-06-22T19:39:33.144Z,1592854773.144 [PAR_Licor] Loaded 2020-06-22T19:39:33.144Z,1592854773.144 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2020-06-22T19:39:33.150Z,1592854773.150 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470 2020-06-22T19:39:33.151Z,1592854773.151 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470 2020-06-22T19:39:33.155Z,1592854773.155 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650 2020-06-22T19:39:33.155Z,1592854773.155 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650 2020-06-22T19:39:33.159Z,1592854773.159 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl 2020-06-22T19:39:33.159Z,1592854773.159 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl 2020-06-22T19:39:33.164Z,1592854773.164 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm 2020-06-22T19:39:33.164Z,1592854773.164 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm 2020-06-22T19:39:33.168Z,1592854773.168 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm 2020-06-22T19:39:33.168Z,1592854773.168 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm 2020-06-22T19:39:33.173Z,1592854773.173 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm 2020-06-22T19:39:33.173Z,1592854773.173 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm 2020-06-22T19:39:33.177Z,1592854773.177 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm 2020-06-22T19:39:33.177Z,1592854773.177 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm 2020-06-22T19:39:33.181Z,1592854773.181 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water 2020-06-22T19:39:33.186Z,1592854773.186 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water 2020-06-22T19:39:33.186Z,1592854773.186 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water 2020-06-22T19:39:33.187Z,1592854773.187 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water 2020-06-22T19:39:33.191Z,1592854773.191 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water 2020-06-22T19:39:33.191Z,1592854773.191 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water 2020-06-22T19:39:33.195Z,1592854773.195 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water 2020-06-22T19:39:33.196Z,1592854773.196 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water 2020-06-22T19:39:33.200Z,1592854773.200 [WetLabsBB2FL] Loaded 2020-06-22T19:39:33.200Z,1592854773.200 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2020-06-22T19:39:33.201Z,1592854773.201 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A224E0 2020-06-22T19:39:33.202Z,1592854773.202 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1079 2020-06-22T19:39:33.202Z,1592854773.202 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2020-06-22T19:39:33.203Z,1592854773.203 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2020-06-22T19:39:33.541Z,1592854773.541 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2020-06-22T19:39:33.542Z,1592854773.542 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2020-06-22T19:39:33.701Z,1592854773.701 [SBIT](DEBUG): Construct Startup Built In Test. 2020-06-22T19:39:33.713Z,1592854773.713 [SBIT] Loaded 2020-06-22T19:39:33.713Z,1592854773.713 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2020-06-22T19:39:33.714Z,1592854773.714 [IBIT](DEBUG): Construct Initiated Built In Test. 2020-06-22T19:39:33.727Z,1592854773.727 [IBIT] Loaded 2020-06-22T19:39:33.727Z,1592854773.727 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2020-06-22T19:39:33.730Z,1592854773.730 [CBIT](DEBUG): Construct Continuous Built In Test. 2020-06-22T19:39:33.872Z,1592854773.872 [CBIT] Loaded 2020-06-22T19:39:33.873Z,1592854773.873 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2020-06-22T19:39:33.873Z,1592854773.873 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2020-06-22T19:39:33.877Z,1592854773.877 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2020-06-22T19:39:33.878Z,1592854773.878 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2020-06-22T19:39:33.885Z,1592854773.885 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2020-06-22T19:39:33.886Z,1592854773.886 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B004E0 2020-06-22T19:39:33.886Z,1592854773.886 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1080 2020-06-22T19:39:33.891Z,1592854773.891 [Supervisor](INFO): Main Thread ID is 821 2020-06-22T19:39:33.891Z,1592854773.891 [Supervisor](DEBUG): Running supervisor. 2020-06-22T19:39:33.892Z,1592854773.892 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1081 2020-06-22T19:39:33.895Z,1592854773.895 [controlThread ThreadHandler](INFO): Handler Thread ID is 1082 2020-06-22T19:39:33.895Z,1592854773.895 [controlThread](DEBUG): Initializing ControlThread 2020-06-22T19:39:33.896Z,1592854773.896 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2020-06-22T19:39:33.897Z,1592854773.897 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2020-06-22T19:39:33.898Z,1592854773.898 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2020-06-22T19:39:33.899Z,1592854773.899 [LoopControl](DEBUG): Initialize LoopControlComponent. 2020-06-22T19:39:33.900Z,1592854773.900 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2020-06-22T19:39:33.900Z,1592854773.900 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2020-06-22T19:39:33.901Z,1592854773.901 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2020-06-22T19:39:33.901Z,1592854773.901 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2020-06-22T19:39:33.902Z,1592854773.902 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2020-06-22T19:39:33.902Z,1592854773.902 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2020-06-22T19:39:33.903Z,1592854773.903 [NavChart](DEBUG): Initialize NavChart Navigation. 2020-06-22T19:39:33.903Z,1592854773.903 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2020-06-22T19:39:33.909Z,1592854773.909 [SBIT](INFO): Initialize SBIT Component. 2020-06-22T19:39:33.910Z,1592854773.910 [SBIT](IMPORTANT): git: 2020-06-09-14-g94a1ff8 2020-06-22T19:39:33.910Z,1592854773.910 [SBIT](INFO): git hash: 94a1ff8b2ec345e644fe444b25c1d1ae66544d5b 2020-06-22T19:39:33.910Z,1592854773.910 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2020-06-22T19:39:33.911Z,1592854773.911 [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 2020-06-22T19:39:33.912Z,1592854773.912 [SBIT](INFO): Beginning SBIT in 34.000000 seconds. 2020-06-22T19:39:33.913Z,1592854773.913 [IBIT](INFO): Initialize IBIT Component. 2020-06-22T19:39:33.914Z,1592854773.914 [CBIT](DEBUG): Initialize CBIT Component. 2020-06-22T19:39:33.915Z,1592854773.915 [logger ThreadHandler](INFO): Handler Thread ID is 1083 2020-06-22T19:39:33.925Z,1592854773.925 [CBIT](DEBUG): Initialized mux pins. 2020-06-22T19:39:33.925Z,1592854773.925 [CBIT](DEBUG): Initializing the watchdog timer. 2020-06-22T19:39:33.933Z,1592854773.933 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1084 2020-06-22T19:39:33.935Z,1592854773.935 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2020-06-22T19:39:33.945Z,1592854773.945 [Onboard ThreadHandler](INFO): Handler Thread ID is 1085 2020-06-22T19:39:33.950Z,1592854773.950 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2020-06-22T19:39:33.950Z,1592854773.950 [CBIT](DEBUG): Initializing heartbeat. 2020-06-22T19:39:33.963Z,1592854773.963 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1086 2020-06-22T19:39:33.981Z,1592854773.981 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1087 2020-06-22T19:39:33.982Z,1592854773.982 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2020-06-22T19:39:33.987Z,1592854773.987 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1089 2020-06-22T19:39:33.988Z,1592854773.988 [WetLabsBB2FL](INFO): Powering down 2020-06-22T19:39:34.018Z,1592854774.018 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1090 2020-06-22T19:39:34.021Z,1592854774.021 [CBIT](DEBUG): Deactivating GF circuits. 2020-06-22T19:39:34.021Z,1592854774.021 [CBIT](DEBUG): Deactivating emergency mode. 2020-06-22T19:39:34.023Z,1592854774.023 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2020-06-22T19:39:34.023Z,1592854774.023 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2020-06-22T19:39:34.023Z,1592854774.023 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2020-06-22T19:39:34.023Z,1592854774.023 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2020-06-22T19:39:34.023Z,1592854774.023 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2020-06-22T19:39:34.024Z,1592854774.024 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2020-06-22T19:39:34.024Z,1592854774.024 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2020-06-22T19:39:34.024Z,1592854774.024 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2020-06-22T19:39:34.024Z,1592854774.024 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2020-06-22T19:39:34.024Z,1592854774.024 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2020-06-22T19:39:34.024Z,1592854774.024 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2020-06-22T19:39:34.025Z,1592854774.025 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2020-06-22T19:39:34.025Z,1592854774.025 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2020-06-22T19:39:34.025Z,1592854774.025 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2020-06-22T19:39:34.025Z,1592854774.025 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2020-06-22T19:39:34.026Z,1592854774.026 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2020-06-22T19:39:34.057Z,1592854774.057 [CBIT](DEBUG): Backplane powered. 2020-06-22T19:39:34.058Z,1592854774.058 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2020-06-22T19:39:34.079Z,1592854774.079 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2020-06-22T19:39:34.105Z,1592854774.105 [MissionManager](DEBUG): 2020-06-22T19:39:34.106Z,1592854774.106 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2020-06-22T19:39:34.184Z,1592854774.184 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2020-06-22T19:39:34.186Z,1592854774.186 [Default:A.Wait](DEBUG): Construct Wait. 2020-06-22T19:39:34.187Z,1592854774.187 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2020-06-22T19:39:34.226Z,1592854774.226 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2020-06-22T19:39:34.229Z,1592854774.229 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2020-06-22T19:39:34.251Z,1592854774.251 [Default:E.Execute](DEBUG): Construct Execute. 2020-06-22T19:39:34.263Z,1592854774.263 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2020-06-22T19:39:34.285Z,1592854774.285 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,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, 2020-06-22T19:39:34.293Z,1592854774.293 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2020-06-22T19:39:34.327Z,1592854774.327 [Depth_Keller](ERROR): Pressure reading out of range: 1925.893799 decibar 2020-06-22T19:39:34.330Z,1592854774.330 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2 2020-06-22T19:39:34.330Z,1592854774.330 [DAT](INFO): Powering up 2020-06-22T19:39:34.330Z,1592854774.330 [DAT](DEBUG): Initializing DAT. 2020-06-22T19:39:34.369Z,1592854774.369 [Radio_Surface](INFO): Powering up 2020-06-22T19:39:34.393Z,1592854774.393 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2 2020-06-22T19:39:34.524Z,1592854774.524 [DepthRateCalculator](ERROR): Depth measurement is not active 2020-06-22T19:39:34.569Z,1592854774.569 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2020-06-22T19:39:34.577Z,1592854774.577 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2020-06-22T19:39:34.579Z,1592854774.579 [ElevatorServo](DEBUG): Initializing EZServoServo. 2020-06-22T19:39:34.585Z,1592854774.585 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2020-06-22T19:39:34.586Z,1592854774.586 [MassServo](DEBUG): Initializing EZServoServo. 2020-06-22T19:39:34.593Z,1592854774.593 [MassServo](DEBUG): Initializing MassServo. 2020-06-22T19:39:34.594Z,1592854774.594 [RudderServo](DEBUG): Initializing EZServoServo. 2020-06-22T19:39:34.601Z,1592854774.601 [RudderServo](DEBUG): Initializing RudderServo. 2020-06-22T19:39:34.602Z,1592854774.602 [ThrusterServo](DEBUG): Initializing EZServoServo. 2020-06-22T19:39:34.609Z,1592854774.609 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2020-06-22T19:39:35.477Z,1592854775.477 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2020-06-22T19:39:35.477Z,1592854775.477 [RudderServo](FAULT): Rudder failed to initialize 2020-06-22T19:39:35.477Z,1592854775.477 [RudderServo] Communications Fault, FailCount= 1 2020-06-22T19:39:35.477Z,1592854775.477 [RudderServo](ERROR): Communications Fault 2020-06-22T19:39:35.588Z,1592854775.588 [CBIT](ERROR): Communications Fault in component: RudderServo 2020-06-22T19:39:35.798Z,1592854775.798 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2020-06-22T19:39:35.798Z,1592854775.798 [RudderServo](INFO): Powering down 2020-06-22T19:39:36.425Z,1592854776.425 [RudderServo](DEBUG): Initializing EZServoServo. 2020-06-22T19:39:36.546Z,1592854776.546 [RudderServo](DEBUG): Initializing RudderServo. 2020-06-22T19:39:36.550Z,1592854776.550 [CBIT](INFO): Clearing failed state for component RudderServo 2020-06-22T19:39:36.550Z,1592854776.550 [RudderServo] No Fault, FailCount= 1 2020-06-22T19:39:41.013Z,1592854781.013 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2020-06-22T19:39:44.182Z,1592854784.182 [CBIT](CRITICAL): Environmental Failure. Press:14.922822 PSI. Humidity:58%. Temp:21 C. ABORTING MISSION 2020-06-22T19:39:44.182Z,1592854784.182 [Supervisor](INFO): Stop Mission called by CBIT::monitorEnvironmentals 2020-06-22T19:39:44.507Z,1592854784.507 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2020-06-22T19:39:44.507Z,1592854784.507 [Aanderaa_O2] Communications Fault, FailCount= 1 2020-06-22T19:39:44.507Z,1592854784.507 [Aanderaa_O2](ERROR): Communications Fault 2020-06-22T19:39:44.615Z,1592854784.615 [CommandLine](FAULT): Scheduling is paused 2020-06-22T19:39:44.615Z,1592854784.615 [CBIT](INFO): Critical error at 20200622T193944 2020-06-22T19:39:44.618Z,1592854784.618 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2020-06-22T19:39:45.049Z,1592854785.049 [Aanderaa_O2](INFO): Powering down 2020-06-22T19:39:45.798Z,1592854785.798 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2020-06-22T19:39:45.798Z,1592854785.798 [Aanderaa_O2] No Fault, FailCount= 1 2020-06-22T19:39:46.928Z,1592854786.928 [DAT](INFO): commRate: 800 2020-06-22T19:39:46.929Z,1592854786.929 [DAT](INFO): commRate: 800 2020-06-22T19:39:47.343Z,1592854787.343 [DAT](INFO): entering command mode 2020-06-22T19:39:47.739Z,1592854787.739 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:39:48.138Z,1592854788.138 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:39:48.555Z,1592854788.555 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:39:48.946Z,1592854788.946 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:39:49.350Z,1592854789.350 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:39:49.763Z,1592854789.763 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:39:50.159Z,1592854790.159 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:39:50.562Z,1592854790.562 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:39:50.967Z,1592854790.967 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:39:51.370Z,1592854791.370 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:39:51.774Z,1592854791.774 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:39:52.178Z,1592854792.178 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:39:52.591Z,1592854792.591 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:39:52.986Z,1592854792.986 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:39:53.390Z,1592854793.390 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:39:53.791Z,1592854793.791 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:39:54.204Z,1592854794.204 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:39:54.603Z,1592854794.603 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:39:55.003Z,1592854795.003 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:39:55.406Z,1592854795.406 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:39:55.818Z,1592854795.818 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:39:56.215Z,1592854796.215 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:39:56.215Z,1592854796.215 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2020-06-22T19:39:56.215Z,1592854796.215 [Aanderaa_O2] Communications Fault, FailCount= 2 2020-06-22T19:39:56.215Z,1592854796.215 [Aanderaa_O2](ERROR): Communications Fault 2020-06-22T19:39:56.289Z,1592854796.289 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2020-06-22T19:39:56.618Z,1592854796.618 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:39:56.737Z,1592854796.737 [Aanderaa_O2](INFO): Powering down 2020-06-22T19:39:57.035Z,1592854797.035 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:39:57.426Z,1592854797.426 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:39:57.448Z,1592854797.448 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2020-06-22T19:39:57.449Z,1592854797.449 [Aanderaa_O2] No Fault, FailCount= 2 2020-06-22T19:39:57.830Z,1592854797.830 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:39:58.238Z,1592854798.238 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:39:58.639Z,1592854798.639 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:39:59.046Z,1592854799.046 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:39:59.450Z,1592854799.450 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:39:59.851Z,1592854799.851 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:40:00.254Z,1592854800.254 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:40:00.662Z,1592854800.662 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:40:01.062Z,1592854801.062 [NAL9602](INFO): Powering up NAL9602 2020-06-22T19:40:01.064Z,1592854801.064 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:40:01.470Z,1592854801.470 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:40:01.886Z,1592854801.886 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:40:02.278Z,1592854802.278 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:40:02.684Z,1592854802.684 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:40:02.684Z,1592854802.684 [DAT](FAULT): failed to enter command mode 2020-06-22T19:40:03.087Z,1592854803.087 [DAT](INFO): entering command mode 2020-06-22T19:40:03.488Z,1592854803.488 [DAT](INFO): setting verbose to 3 2020-06-22T19:40:03.892Z,1592854803.892 [DAT](INFO): set verbose to 3 2020-06-22T19:40:03.892Z,1592854803.892 [DAT](INFO): setting DatVerbose to 27440 2020-06-22T19:40:04.308Z,1592854804.308 [DAT](INFO): set DatVerbose to 27440 2020-06-22T19:40:04.308Z,1592854804.308 [DAT](INFO): setting transmit power to 8 2020-06-22T19:40:04.700Z,1592854804.700 [DAT](INFO): set transmit power to 8 2020-06-22T19:40:04.700Z,1592854804.700 [DAT](INFO): setting local address to 5 2020-06-22T19:40:05.104Z,1592854805.104 [DAT](INFO): set local address to 5 2020-06-22T19:40:07.939Z,1592854807.939 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2020-06-22T19:40:07.939Z,1592854807.939 [Aanderaa_O2] Communications Fault, FailCount= 3 2020-06-22T19:40:07.939Z,1592854807.939 [Aanderaa_O2](ERROR): Communications Fault 2020-06-22T19:40:07.967Z,1592854807.967 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2020-06-22T19:40:07.968Z,1592854807.968 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2 2020-06-22T19:40:08.453Z,1592854808.453 [Aanderaa_O2](INFO): Powering down 2020-06-22T19:40:08.487Z,1592854808.487 [SBIT](IMPORTANT): Beginning Startup BIT 2020-06-22T19:40:08.496Z,1592854808.496 [CBIT](IMPORTANT): Beginning ground fault scan 2020-06-22T19:40:11.974Z,1592854811.974 [NAL9602](INFO): NAL9602 initialized 2020-06-22T19:40:19.300Z,1592854819.300 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.008823 CHAN A1 (24V): -0.007474 CHAN A2 (12V): -0.004648 CHAN A3 (5V): -0.002457 CHAN B0 (3.3V): -0.001142 CHAN B1 (3.15aV): -0.001031 CHAN B2 (3.15bV): -0.001199 CHAN B3 (GND): 0.000903 OPEN: 0.004558 Full Scale Calc: 4.765 mA, -1.589 mA 2020-06-22T19:40:50.360Z,1592854850.360 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2020-06-22T19:41:02.536Z,1592854862.536 [SBIT](IMPORTANT): SBIT PASSED 2020-06-22T19:41:02.536Z,1592854862.536 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2020-06-22T19:41:02.537Z,1592854862.537 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count; 2020-06-22T19:41:02.537Z,1592854862.537 [SBIT](IMPORTANT): DAT.loadAtStartup=1 bool; 2020-06-22T19:41:02.537Z,1592854862.537 [SBIT](IMPORTANT): DVL_micro.loadAtStartup=0 bool; 2020-06-22T19:41:02.537Z,1592854862.537 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool; 2020-06-22T19:41:02.537Z,1592854862.537 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_salinity; 2020-06-22T19:41:02.538Z,1592854862.538 [SBIT](IMPORTANT): Express linearApproximation WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water 0.100000 microgram_per_liter; 2020-06-22T19:41:02.538Z,1592854862.538 [SBIT](IMPORTANT): Express linearApproximation depth 5.000000 meter; 2020-06-22T19:41:02.538Z,1592854862.538 [SBIT](IMPORTANT): Express linearApproximation latitude 0.005000 degree; 2020-06-22T19:41:02.538Z,1592854862.538 [SBIT](IMPORTANT): Express linearApproximation longitude 0.005000 degree; 2020-06-22T19:41:02.538Z,1592854862.538 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=168 cubic_centimeter; 2020-06-22T19:41:02.538Z,1592854862.538 [SBIT](IMPORTANT): VerticalControl.massDefault=3.7 millimeter; 2020-06-22T19:41:02.910Z,1592854862.910 [MissionManager](IMPORTANT): Started mission Startup 2020-06-22T19:41:02.911Z,1592854862.911 [Startup] Running Loop=1 2020-06-22T19:41:02.911Z,1592854862.911 [Startup](DEBUG): Aggregate::initialize Startup 2020-06-22T19:41:02.911Z,1592854862.911 [Startup:A.GoToSurface] Running Loop=1 2020-06-22T19:41:02.911Z,1592854862.911 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2020-06-22T19:41:02.912Z,1592854862.912 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2020-06-22T19:41:02.912Z,1592854862.912 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2020-06-22T19:41:02.912Z,1592854862.912 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2020-06-22T19:41:02.913Z,1592854862.913 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2020-06-22T19:41:02.913Z,1592854862.913 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2020-06-22T19:41:02.915Z,1592854862.915 [Startup:StartupSatComms] Running Loop=1 2020-06-22T19:41:02.915Z,1592854862.915 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2020-06-22T19:41:02.915Z,1592854862.915 [Startup:StartupSatComms:A] Running Loop=1 2020-06-22T19:41:03.295Z,1592854863.295 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2020-06-22T19:41:05.304Z,1592854865.304 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2020-06-22T19:41:20.661Z,1592854880.661 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2020-06-22T19:41:35.204Z,1592854895.204 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2020-06-22T19:41:51.364Z,1592854911.364 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2020-06-22T19:42:01.724Z,1592854921.724 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005352 2020-06-22T19:42:03.089Z,1592854923.089 [Startup:StartupSatComms:A](INFO): Timed out from 2020-06-22T19:41:02.9Z 2020-06-22T19:42:03.089Z,1592854923.089 [Startup:StartupSatComms:A] Stopped 2020-06-22T19:42:03.089Z,1592854923.089 [Startup:StartupSatComms:B] Running Loop=1 2020-06-22T19:42:03.551Z,1592854923.551 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2020-06-22T19:42:09.953Z,1592854929.953 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200622T192752/Courier0007.lzma 2020-06-22T19:42:10.951Z,1592854930.951 [DataOverHttps](INFO): Moved sent file to Logs/20200622T192752/Courier0007.lzma.bak 2020-06-22T19:42:10.951Z,1592854930.951 [DataOverHttps](INFO): SBD MOMSN=12398718 2020-06-22T19:42:24.493Z,1592854944.493 [DataOverHttps](INFO): Sending 249 bytes from file Logs/20200622T193921/Courier0000.lzma 2020-06-22T19:42:25.495Z,1592854945.495 [DataOverHttps](INFO): Moved sent file to Logs/20200622T193921/Courier0000.lzma.bak 2020-06-22T19:42:25.495Z,1592854945.495 [DataOverHttps](INFO): SBD MOMSN=12398720 2020-06-22T19:42:34.194Z,1592854954.194 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2020-06-22T19:42:34.194Z,1592854954.194 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-22T19:42:34.209Z,1592854954.209 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-22T19:42:34.617Z,1592854954.617 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-22T19:42:34.617Z,1592854954.617 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2020-06-22T19:42:38.278Z,1592854958.278 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2020-06-22T19:42:38.278Z,1592854958.278 [Aanderaa_O2] No Fault, FailCount= 3 2020-06-22T19:42:40.996Z,1592854960.996 [DataOverHttps](INFO): Sending 1200 bytes from file Logs/20200622T192444/Express0001.lzma 2020-06-22T19:42:41.995Z,1592854961.995 [DataOverHttps](INFO): Moved sent file to Logs/20200622T192444/Express0001.lzma.bak 2020-06-22T19:42:41.995Z,1592854961.995 [DataOverHttps](INFO): SBD MOMSN=12398727 2020-06-22T19:42:48.729Z,1592854968.729 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2020-06-22T19:42:48.729Z,1592854968.729 [Aanderaa_O2] Communications Fault, FailCount= 1 2020-06-22T19:42:48.729Z,1592854968.729 [Aanderaa_O2](ERROR): Communications Fault 2020-06-22T19:42:48.745Z,1592854968.745 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2020-06-22T19:42:49.265Z,1592854969.265 [Aanderaa_O2](INFO): Powering down 2020-06-22T19:42:49.956Z,1592854969.956 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2020-06-22T19:42:49.956Z,1592854969.956 [Aanderaa_O2] No Fault, FailCount= 1 2020-06-22T19:42:57.327Z,1592854977.327 [DataOverHttps](INFO): Sending 1079 bytes from file Logs/20200622T192752/Express0001.lzma 2020-06-22T19:42:58.327Z,1592854978.327 [DataOverHttps](INFO): Moved sent file to Logs/20200622T192752/Express0001.lzma.bak 2020-06-22T19:42:58.327Z,1592854978.327 [DataOverHttps](INFO): SBD MOMSN=12398773 2020-06-22T19:43:00.468Z,1592854980.468 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2020-06-22T19:43:00.468Z,1592854980.468 [Aanderaa_O2] Communications Fault, FailCount= 2 2020-06-22T19:43:00.468Z,1592854980.468 [Aanderaa_O2](ERROR): Communications Fault 2020-06-22T19:43:00.484Z,1592854980.484 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2020-06-22T19:43:00.985Z,1592854980.985 [Aanderaa_O2](INFO): Powering down 2020-06-22T19:43:01.727Z,1592854981.727 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2020-06-22T19:43:01.727Z,1592854981.727 [Aanderaa_O2] No Fault, FailCount= 2 2020-06-22T19:43:03.331Z,1592854983.331 [Startup:StartupSatComms:B](INFO): Timed out from 2020-06-22T19:42:03.1Z 2020-06-22T19:43:03.331Z,1592854983.331 [Startup:StartupSatComms:B] Stopped 2020-06-22T19:43:03.331Z,1592854983.331 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2020-06-22T19:43:03.331Z,1592854983.331 [Startup:StartupSatComms] Stopped 2020-06-22T19:43:03.331Z,1592854983.331 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2020-06-22T19:43:03.332Z,1592854983.332 [Startup](INFO): Completed Startup 2020-06-22T19:43:03.332Z,1592854983.332 [MissionManager](INFO): Startup is completed. 2020-06-22T19:43:03.332Z,1592854983.332 [MissionManager](INFO): Uninitializing Mission Startup 2020-06-22T19:43:03.332Z,1592854983.332 [Startup] Stopped 2020-06-22T19:43:03.332Z,1592854983.332 [Startup](DEBUG): Aggregate::uninitialize Startup 2020-06-22T19:43:03.332Z,1592854983.332 [Startup:A.GoToSurface] Stopped 2020-06-22T19:43:03.333Z,1592854983.333 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2020-06-22T19:43:03.839Z,1592854983.839 [MissionManager](IMPORTANT): Started mission Default 2020-06-22T19:43:03.839Z,1592854983.839 [Default] Running Loop=1 2020-06-22T19:43:03.839Z,1592854983.839 [Default](DEBUG): Aggregate::initialize Default 2020-06-22T19:43:03.839Z,1592854983.839 [Default:B.GoToSurface] Running Loop=1 2020-06-22T19:43:03.840Z,1592854983.840 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2020-06-22T19:43:03.840Z,1592854983.840 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2020-06-22T19:43:03.840Z,1592854983.840 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2020-06-22T19:43:03.840Z,1592854983.840 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2020-06-22T19:43:03.841Z,1592854983.841 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2020-06-22T19:43:03.845Z,1592854983.845 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2020-06-22T19:43:03.846Z,1592854983.846 [Default:A.Wait] Running Loop=1 2020-06-22T19:43:03.846Z,1592854983.846 [Default:A.Wait](DEBUG): Initialize Wait Component. 2020-06-22T19:43:12.188Z,1592854992.188 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2020-06-22T19:43:12.188Z,1592854992.188 [Aanderaa_O2] Communications Fault, FailCount= 3 2020-06-22T19:43:12.188Z,1592854992.188 [Aanderaa_O2](ERROR): Communications Fault 2020-06-22T19:43:12.248Z,1592854992.248 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2020-06-22T19:43:12.249Z,1592854992.249 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2 2020-06-22T19:43:12.701Z,1592854992.701 [Aanderaa_O2](INFO): Powering down 2020-06-22T19:43:13.939Z,1592854993.939 [DataOverHttps](INFO): Sending 287 bytes from file Logs/20200622T192752/Express0005.lzma 2020-06-22T19:43:14.939Z,1592854994.939 [DataOverHttps](INFO): Moved sent file to Logs/20200622T192752/Express0005.lzma.bak 2020-06-22T19:43:14.939Z,1592854994.939 [DataOverHttps](INFO): SBD MOMSN=12398803 2020-06-22T19:43:17.060Z,1592854997.060 [Default:A.Wait](INFO): Done Waiting. 2020-06-22T19:43:17.060Z,1592854997.060 [Default:A.Wait] Stopped 2020-06-22T19:43:17.060Z,1592854997.060 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2020-06-22T19:43:17.460Z,1592854997.460 [Default:CheckIn] Running Loop=1 2020-06-22T19:43:17.460Z,1592854997.460 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-06-22T19:43:17.460Z,1592854997.460 [Default:CheckIn:Read_GPS] Running Loop=1 2020-06-22T19:43:17.844Z,1592854997.844 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2020-06-22T19:45:15.005Z,1592855115.005 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2020-06-22T19:45:32.986Z,1592855132.986 [CommandLine](IMPORTANT): got command show variable address 2020-06-22T19:45:33.088Z,1592855133.088 [CommandLine](IMPORTANT): acoustic_contact_address (unknown) 2020-06-22T19:45:33.287Z,1592855133.287 [CommandLine](IMPORTANT): AcousticModem_Benthos_ATM900.localAddress (count) 2020-06-22T19:45:33.287Z,1592855133.287 [CommandLine](IMPORTANT): AcousticModem_Benthos_ATM900.sbdAddress (enum) 2020-06-22T19:45:33.288Z,1592855133.288 [CommandLine](IMPORTANT): AcousticModem_Benthos_ATM900.transponderAddress (enum) 2020-06-22T19:45:33.294Z,1592855133.294 [CommandLine](IMPORTANT): DAT.localAddress (count) 2020-06-22T19:45:33.295Z,1592855133.295 [CommandLine](IMPORTANT): DAT.sbdAddress (enum) 2020-06-22T19:45:33.295Z,1592855133.295 [CommandLine](IMPORTANT): DAT.transponderAddress (enum) 2020-06-22T19:45:33.302Z,1592855133.302 [CommandLine](IMPORTANT): Micromodem.localAddress (enum) 2020-06-22T19:45:33.302Z,1592855133.302 [CommandLine](IMPORTANT): Micromodem.destinationAddress (enum) 2020-06-22T19:45:33.415Z,1592855133.415 [CommandLine](IMPORTANT): DAT.remoteAddress (enum) 2020-06-22T19:45:33.415Z,1592855133.415 [CommandLine](IMPORTANT): DAT.localAddressReading (enum) 2020-06-22T19:45:35.221Z,1592855135.221 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2020-06-22T19:45:35.221Z,1592855135.221 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-22T19:45:35.231Z,1592855135.231 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-22T19:45:35.642Z,1592855135.642 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-22T19:45:35.642Z,1592855135.642 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2020-06-22T19:45:39.095Z,1592855139.095 [CommandLine](IMPORTANT): got command get DAT.localAddress 2020-06-22T19:45:39.096Z,1592855139.096 [CommandLine](IMPORTANT): DAT.localAddress 5 count 2020-06-22T19:45:42.564Z,1592855142.564 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2020-06-22T19:45:42.564Z,1592855142.564 [Aanderaa_O2] No Fault, FailCount= 3 2020-06-22T19:45:52.995Z,1592855152.995 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2020-06-22T19:45:52.995Z,1592855152.995 [Aanderaa_O2] Communications Fault, FailCount= 1 2020-06-22T19:45:52.995Z,1592855152.995 [Aanderaa_O2](ERROR): Communications Fault 2020-06-22T19:45:53.016Z,1592855153.016 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2020-06-22T19:45:53.501Z,1592855153.501 [Aanderaa_O2](INFO): Powering down 2020-06-22T19:45:54.214Z,1592855154.214 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2020-06-22T19:45:54.215Z,1592855154.215 [Aanderaa_O2] No Fault, FailCount= 1 2020-06-22T19:46:04.689Z,1592855164.689 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2020-06-22T19:46:04.689Z,1592855164.689 [Aanderaa_O2] Communications Fault, FailCount= 2 2020-06-22T19:46:04.689Z,1592855164.689 [Aanderaa_O2](ERROR): Communications Fault 2020-06-22T19:46:04.743Z,1592855164.743 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2020-06-22T19:46:05.205Z,1592855165.205 [Aanderaa_O2](INFO): Powering down 2020-06-22T19:46:05.959Z,1592855165.959 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2020-06-22T19:46:05.959Z,1592855165.959 [Aanderaa_O2] No Fault, FailCount= 2 2020-06-22T19:46:16.426Z,1592855176.426 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2020-06-22T19:46:16.426Z,1592855176.426 [Aanderaa_O2] Communications Fault, FailCount= 3 2020-06-22T19:46:16.426Z,1592855176.426 [Aanderaa_O2](ERROR): Communications Fault 2020-06-22T19:46:16.448Z,1592855176.448 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2020-06-22T19:46:16.448Z,1592855176.448 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2 2020-06-22T19:46:16.921Z,1592855176.921 [Aanderaa_O2](INFO): Powering down 2020-06-22T19:46:22.651Z,1592855182.651 [CommandLine](IMPORTANT): got command failComponent 2020-06-22T19:46:22.651Z,1592855182.651 [CommandLine](IMPORTANT): Failed components: 2020-06-22T19:46:22.651Z,1592855182.651 [CommandLine](IMPORTANT): Aanderaa_O2: Communications Fault 2020-06-22T19:47:00.848Z,1592855220.848 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2020-06-22T19:47:16.620Z,1592855236.620 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2020-06-22T19:47:31.570Z,1592855251.570 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2020-06-22T19:47:46.904Z,1592855266.904 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2020-06-22T19:48:01.855Z,1592855281.855 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2020-06-22T19:48:16.800Z,1592855296.800 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2020-06-22T19:48:17.656Z,1592855297.656 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-06-22T19:43:17.5Z 2020-06-22T19:48:17.656Z,1592855297.656 [Default:CheckIn:Read_GPS] Stopped 2020-06-22T19:48:17.656Z,1592855297.656 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-06-22T19:48:18.044Z,1592855298.044 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2020-06-22T19:48:25.100Z,1592855305.100 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200622T193921/Courier0004.lzma 2020-06-22T19:48:26.103Z,1592855306.103 [DataOverHttps](INFO): Moved sent file to Logs/20200622T193921/Courier0004.lzma.bak 2020-06-22T19:48:26.103Z,1592855306.103 [DataOverHttps](INFO): SBD MOMSN=12398815 2020-06-22T19:48:32.170Z,1592855312.170 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2020-06-22T19:48:36.218Z,1592855316.218 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2020-06-22T19:48:36.218Z,1592855316.218 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-22T19:48:36.228Z,1592855316.228 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-22T19:48:36.627Z,1592855316.627 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-22T19:48:36.627Z,1592855316.627 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2020-06-22T19:48:41.789Z,1592855321.789 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20200622T192752/Express0008.lzma 2020-06-22T19:48:42.791Z,1592855322.791 [DataOverHttps](INFO): Moved sent file to Logs/20200622T192752/Express0008.lzma.bak 2020-06-22T19:48:42.791Z,1592855322.791 [DataOverHttps](INFO): SBD MOMSN=12398817 2020-06-22T19:48:46.735Z,1592855326.735 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2020-06-22T19:48:46.736Z,1592855326.736 [Aanderaa_O2] No Fault, FailCount= 3 2020-06-22T19:48:48.320Z,1592855328.320 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2020-06-22T19:48:57.207Z,1592855337.207 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2020-06-22T19:48:57.207Z,1592855337.207 [Aanderaa_O2] Communications Fault, FailCount= 1 2020-06-22T19:48:57.207Z,1592855337.207 [Aanderaa_O2](ERROR): Communications Fault 2020-06-22T19:48:57.262Z,1592855337.262 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2020-06-22T19:48:57.738Z,1592855337.738 [Aanderaa_O2](INFO): Powering down 2020-06-22T19:48:58.386Z,1592855338.386 [DataOverHttps](INFO): Sending 1097 bytes from file Logs/20200622T193921/Express0001.lzma 2020-06-22T19:48:58.440Z,1592855338.440 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2020-06-22T19:48:58.440Z,1592855338.440 [Aanderaa_O2] No Fault, FailCount= 1 2020-06-22T19:48:59.387Z,1592855339.387 [DataOverHttps](INFO): Moved sent file to Logs/20200622T193921/Express0001.lzma.bak 2020-06-22T19:48:59.387Z,1592855339.387 [DataOverHttps](INFO): SBD MOMSN=12398825 2020-06-22T19:49:04.468Z,1592855344.468 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2020-06-22T19:49:08.912Z,1592855348.912 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2020-06-22T19:49:08.912Z,1592855348.912 [Aanderaa_O2] Communications Fault, FailCount= 2 2020-06-22T19:49:08.912Z,1592855348.912 [Aanderaa_O2](ERROR): Communications Fault 2020-06-22T19:49:08.952Z,1592855348.952 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2020-06-22T19:49:09.448Z,1592855349.448 [Aanderaa_O2](INFO): Powering down 2020-06-22T19:49:10.168Z,1592855350.168 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2020-06-22T19:49:10.168Z,1592855350.168 [Aanderaa_O2] No Fault, FailCount= 2 2020-06-22T19:49:14.870Z,1592855354.870 [DataOverHttps](INFO): Sending 537 bytes from file Logs/20200622T193921/Express0005.lzma 2020-06-22T19:49:15.871Z,1592855355.871 [DataOverHttps](INFO): Moved sent file to Logs/20200622T193921/Express0005.lzma.bak 2020-06-22T19:49:15.871Z,1592855355.871 [DataOverHttps](INFO): SBD MOMSN=12398855 2020-06-22T19:49:19.838Z,1592855359.838 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2020-06-22T19:49:20.658Z,1592855360.658 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2020-06-22T19:49:20.658Z,1592855360.658 [Aanderaa_O2] Communications Fault, FailCount= 3 2020-06-22T19:49:20.658Z,1592855360.658 [Aanderaa_O2](ERROR): Communications Fault 2020-06-22T19:49:20.711Z,1592855360.711 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2020-06-22T19:49:20.712Z,1592855360.712 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2 2020-06-22T19:49:21.149Z,1592855361.149 [Aanderaa_O2](INFO): Powering down 2020-06-22T19:49:31.506Z,1592855371.506 [DataOverHttps](INFO): Sending 366 bytes from file Logs/20251022T070629/Express0001.lzma 2020-06-22T19:49:32.507Z,1592855372.507 [DataOverHttps](INFO): Moved sent file to Logs/20251022T070629/Express0001.lzma.bak 2020-06-22T19:49:32.507Z,1592855372.507 [DataOverHttps](INFO): SBD MOMSN=12398884 2020-06-22T19:49:33.994Z,1592855373.994 [Default:CheckIn:Read_Iridium] Stopped 2020-06-22T19:49:33.994Z,1592855373.994 [Default:CheckIn:C.Wait] Running Loop=1 2020-06-22T19:49:33.995Z,1592855373.995 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-06-22T19:49:35.190Z,1592855375.190 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2020-06-22T19:49:50.524Z,1592855390.524 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2020-06-22T19:50:05.490Z,1592855405.490 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2020-06-22T19:50:14.358Z,1592855414.358 [NAL9602](FAULT): GPS failed to acquire within timeout. 2020-06-22T19:50:14.358Z,1592855414.358 [NAL9602] Data Fault, FailCount= 1 2020-06-22T19:50:14.359Z,1592855414.359 [NAL9602](ERROR): Data Fault 2020-06-22T19:50:14.412Z,1592855414.412 [CBIT](ERROR): Data Fault in component: NAL9602 2020-06-22T19:50:14.762Z,1592855414.762 [NAL9602](INFO): Powering down 2020-06-22T19:50:15.606Z,1592855415.606 [CBIT](INFO): Clearing failed state for component NAL9602 2020-06-22T19:50:15.606Z,1592855415.606 [NAL9602] No Fault, FailCount= 1 2020-06-22T19:50:20.827Z,1592855420.827 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2020-06-22T19:50:36.191Z,1592855436.191 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2020-06-22T19:50:45.066Z,1592855445.066 [NAL9602](INFO): Powering up NAL9602 2020-06-22T19:50:51.127Z,1592855451.127 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2020-06-22T19:50:55.974Z,1592855455.974 [NAL9602](INFO): NAL9602 initialized 2020-06-22T19:51:06.480Z,1592855466.480 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2020-06-22T19:51:21.846Z,1592855481.846 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2020-06-22T19:51:37.202Z,1592855497.202 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2020-06-22T19:51:37.218Z,1592855497.218 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2020-06-22T19:51:37.218Z,1592855497.218 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-22T19:51:37.229Z,1592855497.229 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-22T19:51:37.623Z,1592855497.623 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-22T19:51:37.623Z,1592855497.623 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2020-06-22T19:51:50.964Z,1592855510.964 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2020-06-22T19:51:50.964Z,1592855510.964 [Aanderaa_O2] No Fault, FailCount= 3 2020-06-22T19:52:01.430Z,1592855521.430 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2020-06-22T19:52:01.430Z,1592855521.430 [Aanderaa_O2] Communications Fault, FailCount= 1 2020-06-22T19:52:01.430Z,1592855521.430 [Aanderaa_O2](ERROR): Communications Fault 2020-06-22T19:52:01.446Z,1592855521.446 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2020-06-22T19:52:01.945Z,1592855521.945 [Aanderaa_O2](INFO): Powering down 2020-06-22T19:52:02.659Z,1592855522.659 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2020-06-22T19:52:02.659Z,1592855522.659 [Aanderaa_O2] No Fault, FailCount= 1 2020-06-22T19:52:13.163Z,1592855533.163 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2020-06-22T19:52:13.163Z,1592855533.163 [Aanderaa_O2] Communications Fault, FailCount= 2 2020-06-22T19:52:13.163Z,1592855533.163 [Aanderaa_O2](ERROR): Communications Fault 2020-06-22T19:52:13.184Z,1592855533.184 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2020-06-22T19:52:13.657Z,1592855533.657 [Aanderaa_O2](INFO): Powering down 2020-06-22T19:52:14.369Z,1592855534.369 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2020-06-22T19:52:14.369Z,1592855534.369 [Aanderaa_O2] No Fault, FailCount= 2 2020-06-22T19:52:24.860Z,1592855544.860 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2020-06-22T19:52:24.860Z,1592855544.860 [Aanderaa_O2] Communications Fault, FailCount= 3 2020-06-22T19:52:24.860Z,1592855544.860 [Aanderaa_O2](ERROR): Communications Fault 2020-06-22T19:52:24.900Z,1592855544.900 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2020-06-22T19:52:24.900Z,1592855544.900 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2 2020-06-22T19:52:25.385Z,1592855545.385 [Aanderaa_O2](INFO): Powering down 2020-06-22T19:54:34.552Z,1592855674.552 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-06-22T19:54:34.552Z,1592855674.552 [Default:CheckIn:C.Wait] Stopped 2020-06-22T19:54:34.552Z,1592855674.552 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-06-22T19:54:34.552Z,1592855674.552 [Default:CheckIn:D] Running Loop=1 2020-06-22T19:54:34.967Z,1592855674.967 [Default:CheckIn:D] Stopped 2020-06-22T19:54:34.967Z,1592855674.967 [Default:CheckIn:E] Running Loop=1 2020-06-22T19:54:35.363Z,1592855675.363 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.518798 min 2020-06-22T19:54:35.363Z,1592855675.363 [Default:CheckIn:E] Stopped 2020-06-22T19:54:35.363Z,1592855675.363 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-06-22T19:54:35.363Z,1592855675.363 [Default:CheckIn] Stopped 2020-06-22T19:54:35.363Z,1592855675.363 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-06-22T19:54:35.363Z,1592855675.363 [Default:CheckIn](INFO): Running loop #2 2020-06-22T19:54:35.363Z,1592855675.363 [Default:CheckIn] Running Loop=2 2020-06-22T19:54:35.363Z,1592855675.363 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-06-22T19:54:35.364Z,1592855675.364 [Default:CheckIn:Read_GPS] Running Loop=1 2020-06-22T19:54:38.196Z,1592855678.196 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2020-06-22T19:54:38.196Z,1592855678.196 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-22T19:54:38.206Z,1592855678.206 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-22T19:54:38.626Z,1592855678.626 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-22T19:54:38.627Z,1592855678.627 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2020-06-22T19:54:55.191Z,1592855695.191 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2020-06-22T19:54:55.191Z,1592855695.191 [Aanderaa_O2] No Fault, FailCount= 3 2020-06-22T19:55:05.656Z,1592855705.656 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2020-06-22T19:55:05.656Z,1592855705.656 [Aanderaa_O2] Communications Fault, FailCount= 1 2020-06-22T19:55:05.656Z,1592855705.656 [Aanderaa_O2](ERROR): Communications Fault 2020-06-22T19:55:05.672Z,1592855705.672 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2020-06-22T19:55:06.165Z,1592855706.165 [Aanderaa_O2](INFO): Powering down 2020-06-22T19:55:06.880Z,1592855706.880 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2020-06-22T19:55:06.880Z,1592855706.880 [Aanderaa_O2] No Fault, FailCount= 1 2020-06-22T19:55:17.375Z,1592855717.375 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2020-06-22T19:55:17.375Z,1592855717.375 [Aanderaa_O2] Communications Fault, FailCount= 2 2020-06-22T19:55:17.375Z,1592855717.375 [Aanderaa_O2](ERROR): Communications Fault 2020-06-22T19:55:17.391Z,1592855717.391 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2020-06-22T19:55:17.877Z,1592855717.877 [Aanderaa_O2](INFO): Powering down 2020-06-22T19:55:18.600Z,1592855718.600 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2020-06-22T19:55:18.600Z,1592855718.600 [Aanderaa_O2] No Fault, FailCount= 2 2020-06-22T19:55:29.102Z,1592855729.102 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2020-06-22T19:55:29.103Z,1592855729.103 [Aanderaa_O2] Communications Fault, FailCount= 3 2020-06-22T19:55:29.103Z,1592855729.103 [Aanderaa_O2](ERROR): Communications Fault 2020-06-22T19:55:29.164Z,1592855729.164 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2020-06-22T19:55:29.164Z,1592855729.164 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2 2020-06-22T19:55:29.597Z,1592855729.597 [Aanderaa_O2](INFO): Powering down 2020-06-22T19:56:34.528Z,1592855794.528 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2020-06-22T19:56:49.476Z,1592855809.476 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2020-06-22T19:57:04.441Z,1592855824.441 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2020-06-22T19:57:19.791Z,1592855839.791 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2020-06-22T19:57:35.532Z,1592855855.532 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2020-06-22T19:57:39.202Z,1592855859.202 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2020-06-22T19:57:39.202Z,1592855859.202 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-22T19:57:39.212Z,1592855859.212 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-22T19:57:39.622Z,1592855859.622 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-22T19:57:39.622Z,1592855859.622 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2020-06-22T19:57:48.875Z,1592855868.875 [CommandLine](IMPORTANT): got command show variable address 2020-06-22T19:57:48.881Z,1592855868.881 [CommandLine](IMPORTANT): acoustic_contact_address (unknown) 2020-06-22T19:57:49.005Z,1592855869.005 [CommandLine](IMPORTANT): AcousticModem_Benthos_ATM900.localAddress (count) 2020-06-22T19:57:49.006Z,1592855869.006 [CommandLine](IMPORTANT): AcousticModem_Benthos_ATM900.sbdAddress (enum) 2020-06-22T19:57:49.006Z,1592855869.006 [CommandLine](IMPORTANT): AcousticModem_Benthos_ATM900.transponderAddress (enum) 2020-06-22T19:57:49.009Z,1592855869.009 [CommandLine](IMPORTANT): DAT.localAddress (count) 2020-06-22T19:57:49.010Z,1592855869.010 [CommandLine](IMPORTANT): DAT.sbdAddress (enum) 2020-06-22T19:57:49.010Z,1592855869.010 [CommandLine](IMPORTANT): DAT.transponderAddress (enum) 2020-06-22T19:57:49.014Z,1592855869.014 [CommandLine](IMPORTANT): Micromodem.localAddress (enum) 2020-06-22T19:57:49.014Z,1592855869.014 [CommandLine](IMPORTANT): Micromodem.destinationAddress (enum) 2020-06-22T19:57:49.097Z,1592855869.097 [CommandLine](IMPORTANT): DAT.remoteAddress (enum) 2020-06-22T19:57:49.097Z,1592855869.097 [CommandLine](IMPORTANT): DAT.localAddressReading (enum) 2020-06-22T19:57:50.883Z,1592855870.883 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2020-06-22T19:57:59.388Z,1592855879.388 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2020-06-22T19:57:59.388Z,1592855879.388 [Aanderaa_O2] No Fault, FailCount= 3 2020-06-22T19:58:01.010Z,1592855881.010 [CommandLine](IMPORTANT): got command get DAT.localAddress 2020-06-22T19:58:01.010Z,1592855881.010 [CommandLine](IMPORTANT): DAT.localAddress 5 count 2020-06-22T19:58:06.246Z,1592855886.246 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2020-06-22T19:58:09.895Z,1592855889.895 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2020-06-22T19:58:09.896Z,1592855889.896 [Aanderaa_O2] Communications Fault, FailCount= 1 2020-06-22T19:58:09.896Z,1592855889.896 [Aanderaa_O2](ERROR): Communications Fault 2020-06-22T19:58:09.916Z,1592855889.916 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2020-06-22T19:58:10.401Z,1592855890.401 [Aanderaa_O2](INFO): Powering down 2020-06-22T19:58:11.109Z,1592855891.109 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2020-06-22T19:58:11.109Z,1592855891.109 [Aanderaa_O2] No Fault, FailCount= 1 2020-06-22T19:58:21.641Z,1592855901.641 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2020-06-22T19:58:21.642Z,1592855901.642 [Aanderaa_O2] Communications Fault, FailCount= 2 2020-06-22T19:58:21.642Z,1592855901.642 [Aanderaa_O2](ERROR): Communications Fault 2020-06-22T19:58:21.681Z,1592855901.681 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2020-06-22T19:58:21.996Z,1592855901.996 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2020-06-22T19:58:22.121Z,1592855902.121 [Aanderaa_O2](INFO): Powering down 2020-06-22T19:58:22.840Z,1592855902.840 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2020-06-22T19:58:22.840Z,1592855902.840 [Aanderaa_O2] No Fault, FailCount= 2 2020-06-22T19:58:33.318Z,1592855913.318 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2020-06-22T19:58:33.319Z,1592855913.319 [Aanderaa_O2] Communications Fault, FailCount= 3 2020-06-22T19:58:33.319Z,1592855913.319 [Aanderaa_O2](ERROR): Communications Fault 2020-06-22T19:58:33.391Z,1592855913.391 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2020-06-22T19:58:33.392Z,1592855913.392 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2 2020-06-22T19:58:33.825Z,1592855913.825 [Aanderaa_O2](INFO): Powering down 2020-06-22T19:58:36.511Z,1592855916.511 [CommandLine](IMPORTANT): got command configSet Aanderaa_O2.loadAtStartup 0.000000 bool persist 2020-06-22T19:58:36.512Z,1592855916.512 [CommandLine](IMPORTANT): configSet Aanderaa_O2.loadAtStartup requires a restart to take effect. 2020-06-22T19:58:36.944Z,1592855916.944 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2020-06-22T19:58:40.942Z,1592855920.942 [CommandLine](IMPORTANT): got command failComponent 2020-06-22T19:58:40.942Z,1592855920.942 [CommandLine](IMPORTANT): Failed components: 2020-06-22T19:58:40.942Z,1592855920.942 [CommandLine](IMPORTANT): Aanderaa_O2: Communications Fault 2020-06-22T19:58:43.991Z,1592855923.991 [CommandLine](IMPORTANT): got command failComponent hardware DAT 2020-06-22T19:58:43.991Z,1592855923.991 [DAT] Hardware Fault, FailCount= 1 2020-06-22T19:58:43.991Z,1592855923.991 [DAT](ERROR): Hardware Fault 2020-06-22T19:58:43.991Z,1592855923.991 [CommandLine](IMPORTANT): DAT failureMode is Hardware Fault 2020-06-22T19:58:44.211Z,1592855924.211 [DAT](INFO): Powering down 2020-06-22T19:58:44.348Z,1592855924.348 [CBIT](ERROR): Hardware Fault in component: DAT 2020-06-22T19:58:45.049Z,1592855925.049 [CBIT](INFO): Clearing failed state for component DAT 2020-06-22T19:58:45.049Z,1592855925.049 [DAT] No Fault, FailCount= 1 2020-06-22T19:58:47.442Z,1592855927.442 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2 2020-06-22T19:58:47.442Z,1592855927.442 [DAT](INFO): Powering up 2020-06-22T19:58:47.443Z,1592855927.443 [DAT](DEBUG): Initializing DAT. 2020-06-22T19:58:47.501Z,1592855927.501 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2 2020-06-22T19:58:52.309Z,1592855932.309 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2020-06-22T19:58:59.971Z,1592855939.971 [DAT](INFO): commRate: 800 2020-06-22T19:58:59.971Z,1592855939.971 [DAT](INFO): commRate: 800 2020-06-22T19:59:00.378Z,1592855940.378 [DAT](INFO): entering command mode 2020-06-22T19:59:00.790Z,1592855940.790 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:59:01.186Z,1592855941.186 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:59:01.579Z,1592855941.579 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:59:01.988Z,1592855941.988 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:59:02.396Z,1592855942.396 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:59:02.799Z,1592855942.799 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:59:03.214Z,1592855943.214 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:59:03.614Z,1592855943.614 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:59:04.022Z,1592855944.022 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:59:04.407Z,1592855944.407 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:59:04.816Z,1592855944.816 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:59:05.219Z,1592855945.219 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:59:05.630Z,1592855945.630 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:59:06.028Z,1592855946.028 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:59:06.446Z,1592855946.446 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:59:06.849Z,1592855946.849 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:59:07.258Z,1592855947.258 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:59:07.639Z,1592855947.639 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:59:07.640Z,1592855947.640 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2020-06-22T19:59:08.043Z,1592855948.043 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:59:08.452Z,1592855948.452 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:59:08.859Z,1592855948.859 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:59:09.274Z,1592855949.274 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:59:09.678Z,1592855949.678 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:59:10.082Z,1592855950.082 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:59:10.479Z,1592855950.479 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:59:10.875Z,1592855950.875 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:59:11.279Z,1592855951.279 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:59:11.684Z,1592855951.684 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:59:12.093Z,1592855952.093 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:59:12.506Z,1592855952.506 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:59:12.909Z,1592855952.909 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:59:13.314Z,1592855953.314 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:59:13.707Z,1592855953.707 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:59:14.108Z,1592855954.108 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:59:14.512Z,1592855954.512 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:59:14.912Z,1592855954.912 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:59:15.333Z,1592855955.333 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:59:15.738Z,1592855955.738 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-22T19:59:15.738Z,1592855955.738 [DAT](FAULT): failed to enter command mode 2020-06-22T19:59:16.142Z,1592855956.142 [DAT](INFO): entering command mode 2020-06-22T19:59:16.533Z,1592855956.533 [DAT](INFO): setting verbose to 3 2020-06-22T19:59:16.935Z,1592855956.935 [DAT](INFO): set verbose to 3 2020-06-22T19:59:16.935Z,1592855956.935 [DAT](INFO): setting DatVerbose to 27440 2020-06-22T19:59:17.336Z,1592855957.336 [DAT](INFO): set DatVerbose to 27440 2020-06-22T19:59:17.336Z,1592855957.336 [DAT](INFO): setting transmit power to 8 2020-06-22T19:59:17.741Z,1592855957.741 [DAT](INFO): set transmit power to 8 2020-06-22T19:59:17.742Z,1592855957.742 [DAT](INFO): setting local address to 5 2020-06-22T19:59:18.149Z,1592855958.149 [DAT](INFO): set local address to 5 2020-06-22T19:59:23.467Z,1592855963.467 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2020-06-22T19:59:35.528Z,1592855975.528 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-06-22T19:54:35.4Z 2020-06-22T19:59:35.529Z,1592855975.529 [Default:CheckIn:Read_GPS] Stopped 2020-06-22T19:59:35.529Z,1592855975.529 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-06-22T19:59:38.762Z,1592855978.762 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2020-06-22T19:59:40.608Z,1592855980.608 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200622T193921/Courier0007.lzma 2020-06-22T19:59:41.611Z,1592855981.611 [DataOverHttps](INFO): Moved sent file to Logs/20200622T193921/Courier0007.lzma.bak 2020-06-22T19:59:41.611Z,1592855981.611 [DataOverHttps](INFO): SBD MOMSN=12398932 2020-06-22T19:59:54.108Z,1592855994.108 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2020-06-22T19:59:57.046Z,1592855997.046 [DataOverHttps](INFO): Sending 724 bytes from file Logs/20200622T193921/Express0008.lzma 2020-06-22T19:59:58.047Z,1592855998.047 [DataOverHttps](INFO): Moved sent file to Logs/20200622T193921/Express0008.lzma.bak 2020-06-22T19:59:58.047Z,1592855998.047 [DataOverHttps](INFO): SBD MOMSN=12398934 2020-06-22T19:59:59.383Z,1592855999.383 [Default:CheckIn:Read_Iridium] Stopped 2020-06-22T19:59:59.383Z,1592855999.383 [Default:CheckIn:C.Wait] Running Loop=1 2020-06-22T19:59:59.383Z,1592855999.383 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-06-22T20:00:09.052Z,1592856009.052 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2020-06-22T20:00:24.412Z,1592856024.412 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2020-06-22T20:00:39.760Z,1592856039.760 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2020-06-22T20:00:40.183Z,1592856040.183 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7 2020-06-22T20:00:40.183Z,1592856040.183 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-22T20:00:40.217Z,1592856040.217 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-22T20:00:40.608Z,1592856040.608 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-22T20:00:40.608Z,1592856040.608 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7 2020-06-22T20:00:55.509Z,1592856055.509 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2020-06-22T20:00:59.542Z,1592856059.542 [NAL9602](FAULT): GPS failed to acquire within timeout. 2020-06-22T20:00:59.542Z,1592856059.542 [NAL9602] Data Fault, FailCount= 2 2020-06-22T20:00:59.543Z,1592856059.543 [NAL9602](ERROR): Data Fault 2020-06-22T20:00:59.570Z,1592856059.570 [CBIT](ERROR): Data Fault in component: NAL9602 2020-06-22T20:00:59.956Z,1592856059.956 [NAL9602](INFO): Powering down 2020-06-22T20:01:00.777Z,1592856060.777 [CBIT](INFO): Clearing failed state for component NAL9602 2020-06-22T20:01:00.777Z,1592856060.777 [NAL9602] No Fault, FailCount= 2 2020-06-22T20:01:03.616Z,1592856063.616 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2020-06-22T20:01:03.616Z,1592856063.616 [Aanderaa_O2] No Fault, FailCount= 3 2020-06-22T20:01:10.521Z,1592856070.521 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2020-06-22T20:01:14.092Z,1592856074.092 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2020-06-22T20:01:14.092Z,1592856074.092 [Aanderaa_O2] Communications Fault, FailCount= 1 2020-06-22T20:01:14.092Z,1592856074.092 [Aanderaa_O2](ERROR): Communications Fault 2020-06-22T20:01:14.117Z,1592856074.117 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2020-06-22T20:01:14.613Z,1592856074.613 [Aanderaa_O2](INFO): Powering down 2020-06-22T20:01:15.319Z,1592856075.319 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2020-06-22T20:01:15.319Z,1592856075.319 [Aanderaa_O2] No Fault, FailCount= 1 2020-06-22T20:01:25.807Z,1592856085.807 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2020-06-22T20:01:25.808Z,1592856085.808 [Aanderaa_O2] Communications Fault, FailCount= 2 2020-06-22T20:01:25.808Z,1592856085.808 [Aanderaa_O2](ERROR): Communications Fault 2020-06-22T20:01:25.857Z,1592856085.857 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2020-06-22T20:01:26.329Z,1592856086.329 [Aanderaa_O2](INFO): Powering down 2020-06-22T20:01:27.041Z,1592856087.041 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2020-06-22T20:01:27.041Z,1592856087.041 [Aanderaa_O2] No Fault, FailCount= 2 2020-06-22T20:01:29.448Z,1592856089.448 [DAT](INFO): Ping request sent. 2020-06-22T20:01:30.252Z,1592856090.252 [NAL9602](INFO): Powering up NAL9602 2020-06-22T20:01:37.528Z,1592856097.528 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2020-06-22T20:01:37.528Z,1592856097.528 [Aanderaa_O2] Communications Fault, FailCount= 3 2020-06-22T20:01:37.528Z,1592856097.528 [Aanderaa_O2](ERROR): Communications Fault 2020-06-22T20:01:37.572Z,1592856097.572 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2020-06-22T20:01:37.573Z,1592856097.573 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2 2020-06-22T20:01:38.037Z,1592856098.037 [Aanderaa_O2](INFO): Powering down 2020-06-22T20:01:41.162Z,1592856101.162 [NAL9602](INFO): NAL9602 initialized 2020-06-22T20:03:41.184Z,1592856221.184 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8 2020-06-22T20:03:41.184Z,1592856221.184 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-22T20:03:41.194Z,1592856221.194 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-22T20:03:41.576Z,1592856221.576 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-22T20:03:41.576Z,1592856221.576 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8 2020-06-22T20:04:07.829Z,1592856247.829 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2020-06-22T20:04:07.847Z,1592856247.847 [Aanderaa_O2] No Fault, FailCount= 3 2020-06-22T20:04:18.333Z,1592856258.333 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2020-06-22T20:04:18.334Z,1592856258.334 [Aanderaa_O2] Communications Fault, FailCount= 1 2020-06-22T20:04:18.334Z,1592856258.334 [Aanderaa_O2](ERROR): Communications Fault 2020-06-22T20:04:18.390Z,1592856258.390 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2020-06-22T20:04:18.857Z,1592856258.857 [Aanderaa_O2](INFO): Powering down 2020-06-22T20:04:19.543Z,1592856259.543 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2020-06-22T20:04:19.543Z,1592856259.543 [Aanderaa_O2] No Fault, FailCount= 1 2020-06-22T20:04:30.051Z,1592856270.051 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2020-06-22T20:04:30.051Z,1592856270.051 [Aanderaa_O2] Communications Fault, FailCount= 2 2020-06-22T20:04:30.051Z,1592856270.051 [Aanderaa_O2](ERROR): Communications Fault 2020-06-22T20:04:30.072Z,1592856270.072 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2020-06-22T20:04:30.569Z,1592856270.569 [Aanderaa_O2](INFO): Powering down 2020-06-22T20:04:31.340Z,1592856271.340 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2020-06-22T20:04:31.340Z,1592856271.340 [Aanderaa_O2] No Fault, FailCount= 2 2020-06-22T20:04:41.767Z,1592856281.767 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2020-06-22T20:04:41.767Z,1592856281.767 [Aanderaa_O2] Communications Fault, FailCount= 3 2020-06-22T20:04:41.767Z,1592856281.767 [Aanderaa_O2](ERROR): Communications Fault 2020-06-22T20:04:41.788Z,1592856281.788 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2020-06-22T20:04:41.788Z,1592856281.788 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2 2020-06-22T20:04:42.285Z,1592856282.285 [Aanderaa_O2](INFO): Powering down 2020-06-22T20:04:59.953Z,1592856299.953 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-06-22T20:04:59.953Z,1592856299.953 [Default:CheckIn:C.Wait] Stopped 2020-06-22T20:04:59.953Z,1592856299.953 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-06-22T20:04:59.954Z,1592856299.954 [Default:CheckIn:D] Running Loop=1 2020-06-22T20:05:00.371Z,1592856300.371 [Default:CheckIn:D] Stopped 2020-06-22T20:05:00.371Z,1592856300.371 [Default:CheckIn:E] Running Loop=1 2020-06-22T20:05:00.743Z,1592856300.743 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.942202 min 2020-06-22T20:05:00.743Z,1592856300.743 [Default:CheckIn:E] Stopped 2020-06-22T20:05:00.743Z,1592856300.743 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-06-22T20:05:00.743Z,1592856300.743 [Default:CheckIn] Stopped 2020-06-22T20:05:00.743Z,1592856300.743 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-06-22T20:05:00.744Z,1592856300.744 [Default:CheckIn](INFO): Running loop #3 2020-06-22T20:05:00.744Z,1592856300.744 [Default:CheckIn] Running Loop=3 2020-06-22T20:05:00.744Z,1592856300.744 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-06-22T20:05:00.744Z,1592856300.744 [Default:CheckIn:Read_GPS] Running Loop=1 2020-06-22T20:06:12.284Z,1592856372.284 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2020-06-22T20:06:27.631Z,1592856387.631 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2020-06-22T20:06:42.227Z,1592856402.227 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9 2020-06-22T20:06:42.227Z,1592856402.227 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-22T20:06:42.237Z,1592856402.237 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-22T20:06:42.568Z,1592856402.568 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2020-06-22T20:06:42.606Z,1592856402.606 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-22T20:06:42.606Z,1592856402.606 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9 2020-06-22T20:06:57.537Z,1592856417.537 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2020-06-22T20:07:12.080Z,1592856432.080 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2020-06-22T20:07:12.080Z,1592856432.080 [Aanderaa_O2] No Fault, FailCount= 3 2020-06-22T20:07:12.874Z,1592856432.874 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2020-06-22T20:07:22.582Z,1592856442.582 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2020-06-22T20:07:22.583Z,1592856442.583 [Aanderaa_O2] Communications Fault, FailCount= 1 2020-06-22T20:07:22.583Z,1592856442.583 [Aanderaa_O2](ERROR): Communications Fault 2020-06-22T20:07:22.598Z,1592856442.598 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2020-06-22T20:07:23.101Z,1592856443.101 [Aanderaa_O2](INFO): Powering down 2020-06-22T20:07:23.791Z,1592856443.791 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2020-06-22T20:07:23.791Z,1592856443.791 [Aanderaa_O2] No Fault, FailCount= 1 2020-06-22T20:07:27.614Z,1592856447.614 [CommandLine](IMPORTANT): got command configSet list 2020-06-22T20:07:27.614Z,1592856447.614 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2020-06-22T20:07:27.615Z,1592856447.615 [CommandLine](IMPORTANT): Aanderaa_O2.loadAtStartup=0 bool; 2020-06-22T20:07:27.615Z,1592856447.615 [CommandLine](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count; 2020-06-22T20:07:27.615Z,1592856447.615 [CommandLine](IMPORTANT): DAT.loadAtStartup=1 bool; 2020-06-22T20:07:27.615Z,1592856447.615 [CommandLine](IMPORTANT): DVL_micro.loadAtStartup=0 bool; 2020-06-22T20:07:27.616Z,1592856447.616 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool; 2020-06-22T20:07:27.616Z,1592856447.616 [CommandLine](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_salinity; 2020-06-22T20:07:27.616Z,1592856447.616 [CommandLine](IMPORTANT): Express linearApproximation WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water 0.100000 microgram_per_liter; 2020-06-22T20:07:27.616Z,1592856447.616 [CommandLine](IMPORTANT): Express linearApproximation depth 5.000000 meter; 2020-06-22T20:07:27.616Z,1592856447.616 [CommandLine](IMPORTANT): Express linearApproximation latitude 0.005000 degree; 2020-06-22T20:07:27.616Z,1592856447.616 [CommandLine](IMPORTANT): Express linearApproximation longitude 0.005000 degree; 2020-06-22T20:07:27.616Z,1592856447.616 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=168 cubic_centimeter; 2020-06-22T20:07:27.616Z,1592856447.616 [CommandLine](IMPORTANT): VerticalControl.massDefault=3.7 millimeter; 2020-06-22T20:07:28.220Z,1592856448.220 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2020-06-22T20:07:34.079Z,1592856454.079 [CommandLine](IMPORTANT): got command restart application 2020-06-22T20:07:34.280Z,1592856454.280 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2020-06-22T20:07:34.280Z,1592856454.280 [Aanderaa_O2] Communications Fault, FailCount= 2 2020-06-22T20:07:34.280Z,1592856454.280 [Aanderaa_O2](ERROR): Communications Fault 2020-06-22T20:07:34.308Z,1592856454.308 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2020-06-22T20:07:34.810Z,1592856454.810 [Aanderaa_O2](INFO): Powering down 2020-06-22T20:07:35.085Z,1592856455.085 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2020-06-22T20:07:35.086Z,1592856455.086 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-06-22T20:07:35.185Z,1592856455.185 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2020-06-22T20:07:35.185Z,1592856455.185 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-06-22T20:07:35.186Z,1592856455.186 [CommandLine](INFO): Join timeout helper Thread ID is 1171 2020-06-22T20:07:35.186Z,1592856455.186 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2020-06-22T20:07:35.186Z,1592856455.186 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-06-22T20:07:35.187Z,1592856455.187 [NavChartDb](INFO): Join timeout helper Thread ID is 1172 2020-06-22T20:07:35.253Z,1592856455.253 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2020-06-22T20:07:35.253Z,1592856455.253 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-06-22T20:07:35.265Z,1592856455.265 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2020-06-22T20:07:35.265Z,1592856455.265 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2020-06-22T20:07:35.265Z,1592856455.265 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1173 2020-06-22T20:07:35.527Z,1592856455.527 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2020-06-22T20:07:35.527Z,1592856455.527 [Aanderaa_O2] No Fault, FailCount= 2 2020-06-22T20:07:35.549Z,1592856455.549 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2020-06-22T20:07:35.550Z,1592856455.550 [WetLabsBB2FL](INFO): Powering down 2020-06-22T20:07:35.550Z,1592856455.550 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2020-06-22T20:07:35.553Z,1592856455.553 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2020-06-22T20:07:35.553Z,1592856455.553 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2020-06-22T20:07:35.554Z,1592856455.554 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1174 2020-06-22T20:07:35.893Z,1592856455.893 [CTD_Seabird](INFO): Powering down 2020-06-22T20:07:35.905Z,1592856455.905 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2020-06-22T20:07:35.905Z,1592856455.905 [CTD_Seabird](INFO): Powering down 2020-06-22T20:07:35.917Z,1592856455.917 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2020-06-22T20:07:35.921Z,1592856455.921 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2020-06-22T20:07:35.921Z,1592856455.921 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-06-22T20:07:35.921Z,1592856455.921 [Radio_Surface](INFO): Join timeout helper Thread ID is 1176 2020-06-22T20:07:36.009Z,1592856456.009 [Radio_Surface](INFO): Powering down 2020-06-22T20:07:36.010Z,1592856456.010 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2020-06-22T20:07:36.011Z,1592856456.011 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-06-22T20:07:36.018Z,1592856456.018 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2020-06-22T20:07:36.018Z,1592856456.018 [Onboard ThreadHandler](INFO): Thread cancelled. 2020-06-22T20:07:36.018Z,1592856456.018 [Onboard](INFO): Join timeout helper Thread ID is 1177 2020-06-22T20:07:36.509Z,1592856456.509 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2020-06-22T20:07:40.330Z,1592856460.330 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2020-06-22T20:07:40.331Z,1592856460.331 [Onboard ThreadHandler](INFO): Thread cancelled. 2020-06-22T20:07:40.342Z,1592856460.342 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2020-06-22T20:07:40.342Z,1592856460.342 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-06-22T20:07:40.342Z,1592856460.342 [DataOverHttps](INFO): Join timeout helper Thread ID is 1178 2020-06-22T20:07:40.380Z,1592856460.380 [CBIT](FAULT): Humidity exceeds 50% of running average: Humidity:2285320 %. Average:281880.406250 2020-06-22T20:07:40.525Z,1592856460.525 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2020-06-22T20:07:40.525Z,1592856460.525 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-06-22T20:07:40.541Z,1592856460.541 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2020-06-22T20:07:40.541Z,1592856460.541 [logger ThreadHandler](INFO): Thread cancelled. 2020-06-22T20:07:40.542Z,1592856460.542 [logger](INFO): Join timeout helper Thread ID is 1179 2020-06-22T20:07:40.589Z,1592856460.589 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2020-06-22T20:07:40.589Z,1592856460.589 [logger ThreadHandler](INFO): Thread cancelled. 2020-06-22T20:07:40.602Z,1592856460.602 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2020-06-22T20:07:40.602Z,1592856460.602 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-06-22T20:07:40.602Z,1592856460.602 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2020-06-22T20:07:40.602Z,1592856460.602 [controlThread ThreadHandler](INFO): Thread cancelled. 2020-06-22T20:07:40.602Z,1592856460.602 [controlThread](INFO): Join timeout helper Thread ID is 1180 2020-06-22T20:07:40.729Z,1592856460.729 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2020-06-22T20:07:40.729Z,1592856460.729 [controlThread](DEBUG): Uninitializing ControlThread 2020-06-22T20:07:40.730Z,1592856460.730 [AHRS_M2](INFO): Powering down 2020-06-22T20:07:40.802Z,1592856460.802 [NAL9602](INFO): Powering down 2020-06-22T20:07:40.803Z,1592856460.803 [DAT](INFO): Powering down 2020-06-22T20:07:41.041Z,1592856461.041 [Aanderaa_O2](INFO): Powering down 2020-06-22T20:07:41.062Z,1592856461.062 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2020-06-22T20:07:41.063Z,1592856461.063 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2020-06-22T20:07:41.063Z,1592856461.063 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2020-06-22T20:07:41.064Z,1592856461.064 [MissionManager](INFO): Uninitializing Mission Default 2020-06-22T20:07:41.064Z,1592856461.064 [Default] Stopped 2020-06-22T20:07:41.064Z,1592856461.064 [Default](DEBUG): Aggregate::uninitialize Default 2020-06-22T20:07:41.064Z,1592856461.064 [Default:B.GoToSurface] Stopped 2020-06-22T20:07:41.064Z,1592856461.064 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2020-06-22T20:07:41.064Z,1592856461.064 [Default:CheckIn] Stopped 2020-06-22T20:07:41.064Z,1592856461.064 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-06-22T20:07:41.064Z,1592856461.064 [Default:CheckIn:Read_GPS] Stopped 2020-06-22T20:07:41.067Z,1592856461.067 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2020-06-22T20:07:41.067Z,1592856461.067 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2020-06-22T20:07:41.067Z,1592856461.067 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2020-06-22T20:07:41.067Z,1592856461.067 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2020-06-22T20:07:41.068Z,1592856461.068 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2020-06-22T20:07:41.068Z,1592856461.068 [BuoyancyServo](INFO): Powering down 2020-06-22T20:07:41.081Z,1592856461.081 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2020-06-22T20:07:41.081Z,1592856461.081 [ElevatorServo](INFO): Powering down 2020-06-22T20:07:41.082Z,1592856461.082 [MassServo](DEBUG): Uninitialize Mass Servo. 2020-06-22T20:07:41.082Z,1592856461.082 [MassServo](INFO): Powering down 2020-06-22T20:07:41.083Z,1592856461.083 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2020-06-22T20:07:41.083Z,1592856461.083 [RudderServo](INFO): Powering down 2020-06-22T20:07:41.084Z,1592856461.084 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2020-06-22T20:07:41.084Z,1592856461.084 [ThrusterServo](INFO): Powering down 2020-06-22T20:07:41.085Z,1592856461.085 [SBIT](DEBUG): Uninitialize SBIT Component. 2020-06-22T20:07:41.085Z,1592856461.085 [IBIT](DEBUG): Uninitialize IBIT Component. 2020-06-22T20:07:41.085Z,1592856461.085 [CBIT](DEBUG): Uninitialize CBIT Component. 2020-06-22T20:07:41.086Z,1592856461.086 [CBIT](DEBUG): Powering off loads. 2020-06-22T20:07:41.097Z,1592856461.097 [CBIT](DEBUG): Disabling WDT. 2020-06-22T20:07:41.109Z,1592856461.109 [CBIT](DEBUG): Opening all GF detection circuits. 2020-06-22T20:07:41.110Z,1592856461.110 [controlThread ThreadHandler](INFO): Thread cancelled. 2020-06-22T20:07:41.198Z,1592856461.198 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-06-22T20:07:41.200Z,1592856461.200 [Onboard ThreadHandler](INFO): Thread cancelled. 2020-06-22T20:07:41.205Z,1592856461.205 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-06-22T20:07:41.240Z,1592856461.240 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2020-06-22T20:07:41.246Z,1592856461.246 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2020-06-22T20:07:41.282Z,1592856461.282 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-06-22T20:07:41.343Z,1592856461.343 [logger ThreadHandler](INFO): Thread cancelled.