2020-07-06T18:48:00.557Z,1594061280.557 [Supervisor](DEBUG): Initializing supervisor.
2020-07-06T18:48:00.560Z,1594061280.560 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-07-06T18:48:00.560Z,1594061280.560 [SyncHandler](INFO): Protected caller Thread ID is 1099
2020-07-06T18:48:00.561Z,1594061280.561 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-07-06T18:48:00.562Z,1594061280.562 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-07-06T18:48:00.562Z,1594061280.562 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1100
2020-07-06T18:48:00.565Z,1594061280.565 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-07-06T18:48:00.578Z,1594061280.578 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-07-06T18:48:00.579Z,1594061280.579 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-07-06T18:48:00.579Z,1594061280.579 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1101
2020-07-06T18:48:00.580Z,1594061280.580 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-07-06T18:48:00.581Z,1594061280.581 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-07-06T18:48:00.581Z,1594061280.581 [logger ThreadHandler](INFO): Protected caller Thread ID is 1102
2020-07-06T18:48:00.583Z,1594061280.583 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-07-06T18:48:00.583Z,1594061280.583 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-07-06T18:48:00.585Z,1594061280.585 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-07-06T18:48:00.787Z,1594061280.787 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-07-06T18:48:00.788Z,1594061280.788 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-07-06T18:48:00.867Z,1594061280.867 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-07-06T18:48:01.296Z,1594061281.296 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-07-06T18:48:01.296Z,1594061281.296 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-07-06T18:48:01.624Z,1594061281.624 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-07-06T18:48:01.624Z,1594061281.624 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-07-06T18:48:01.720Z,1594061281.720 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-07-06T18:48:01.720Z,1594061281.720 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-07-06T18:48:02.013Z,1594061282.013 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-07-06T18:48:02.013Z,1594061282.013 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-07-06T18:48:02.205Z,1594061282.205 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-07-06T18:48:02.205Z,1594061282.205 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-07-06T18:48:02.671Z,1594061282.671 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-07-06T18:48:02.672Z,1594061282.672 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-07-06T18:48:02.774Z,1594061282.774 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-07-06T18:48:02.775Z,1594061282.775 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-07-06T18:48:02.874Z,1594061282.874 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-07-06T18:48:02.875Z,1594061282.875 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-07-06T18:48:03.515Z,1594061283.515 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-07-06T18:48:03.516Z,1594061283.516 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-07-06T18:48:03.944Z,1594061283.944 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-07-06T18:48:03.945Z,1594061283.945 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-07-06T18:48:04.135Z,1594061284.135 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-07-06T18:48:04.136Z,1594061284.136 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-07-06T18:48:04.280Z,1594061284.280 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-07-06T18:48:04.281Z,1594061284.281 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-07-06T18:48:04.428Z,1594061284.428 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-07-06T18:48:04.429Z,1594061284.429 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2020-07-06T18:48:04.633Z,1594061284.633 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2020-07-06T18:48:04.635Z,1594061284.635 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2020-07-06T18:48:04.636Z,1594061284.636 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2020-07-06T18:48:04.763Z,1594061284.763 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2020-07-06T18:48:04.890Z,1594061284.890 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2020-07-06T18:48:05.225Z,1594061285.225 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2020-07-06T18:48:05.340Z,1594061285.340 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2020-07-06T18:48:05.551Z,1594061285.551 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2020-07-06T18:48:05.657Z,1594061285.657 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2020-07-06T18:48:05.861Z,1594061285.861 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2020-07-06T18:48:05.937Z,1594061285.937 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2020-07-06T18:48:06.099Z,1594061286.099 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2020-07-06T18:48:06.243Z,1594061286.243 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2020-07-06T18:48:06.358Z,1594061286.358 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2020-07-06T18:48:06.591Z,1594061286.591 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-07-06T18:48:06.591Z,1594061286.591 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-07-06T18:48:06.597Z,1594061286.597 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-07-06T18:48:06.645Z,1594061286.645 [VerticalControl](DEBUG): Construct VerticalControl.
2020-07-06T18:48:06.758Z,1594061286.758 [VerticalControl] Loaded
2020-07-06T18:48:06.758Z,1594061286.758 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-07-06T18:48:06.759Z,1594061286.759 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-07-06T18:48:06.839Z,1594061286.839 [HorizontalControl] Loaded
2020-07-06T18:48:06.840Z,1594061286.840 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-07-06T18:48:06.840Z,1594061286.840 [SpeedControl](DEBUG): Construct SpeedControl.
2020-07-06T18:48:06.846Z,1594061286.846 [SpeedControl] Loaded
2020-07-06T18:48:06.846Z,1594061286.846 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-07-06T18:48:06.847Z,1594061286.847 [LoopControl](DEBUG): Construct LoopControl.
2020-07-06T18:48:06.847Z,1594061286.847 [LoopControl] Loaded
2020-07-06T18:48:06.847Z,1594061286.847 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-07-06T18:48:06.848Z,1594061286.848 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-07-06T18:48:06.848Z,1594061286.848 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-07-06T18:48:06.867Z,1594061286.867 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-07-06T18:48:06.867Z,1594061286.867 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-07-06T18:48:06.965Z,1594061286.965 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-07-06T18:48:06.966Z,1594061286.966 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-07-06T18:48:07.115Z,1594061287.115 [BuoyancyServo] Loaded
2020-07-06T18:48:07.115Z,1594061287.115 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-07-06T18:48:07.143Z,1594061287.143 [ElevatorServo] Loaded
2020-07-06T18:48:07.143Z,1594061287.143 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-07-06T18:48:07.171Z,1594061287.171 [MassServo] Loaded
2020-07-06T18:48:07.171Z,1594061287.171 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-07-06T18:48:07.198Z,1594061287.198 [RudderServo] Loaded
2020-07-06T18:48:07.199Z,1594061287.199 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-07-06T18:48:07.225Z,1594061287.225 [ThrusterServo] Loaded
2020-07-06T18:48:07.225Z,1594061287.225 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-07-06T18:48:07.226Z,1594061287.226 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-07-06T18:48:07.226Z,1594061287.226 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-07-06T18:48:07.347Z,1594061287.347 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-07-06T18:48:07.347Z,1594061287.347 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-07-06T18:48:07.368Z,1594061287.368 [NavChart] Loaded
2020-07-06T18:48:07.368Z,1594061287.368 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-07-06T18:48:07.372Z,1594061287.372 [UniversalFixResidualReporter] Loaded
2020-07-06T18:48:07.372Z,1594061287.372 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-07-06T18:48:07.372Z,1594061287.372 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-07-06T18:48:07.373Z,1594061287.373 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-07-06T18:48:07.454Z,1594061287.454 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-07-06T18:48:07.454Z,1594061287.454 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-07-06T18:48:07.762Z,1594061287.762 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-07-06T18:48:07.767Z,1594061287.767 [AHRS_M2](INFO): created writer for : platform_orientation
2020-07-06T18:48:07.769Z,1594061287.769 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-07-06T18:48:07.774Z,1594061287.774 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-07-06T18:48:07.775Z,1594061287.775 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-07-06T18:48:07.779Z,1594061287.779 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-07-06T18:48:07.780Z,1594061287.780 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-07-06T18:48:07.785Z,1594061287.785 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-07-06T18:48:07.857Z,1594061287.857 [AHRS_M2] Loaded
2020-07-06T18:48:07.857Z,1594061287.857 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-07-06T18:48:07.934Z,1594061287.934 [DataOverHttps] Loaded
2020-07-06T18:48:07.934Z,1594061287.934 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-07-06T18:48:07.936Z,1594061287.936 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4089B4E0
2020-07-06T18:48:07.936Z,1594061287.936 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1187
2020-07-06T18:48:07.966Z,1594061287.966 [Depth_Keller] Loaded
2020-07-06T18:48:07.967Z,1594061287.967 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-07-06T18:48:07.971Z,1594061287.971 [DropWeight] Loaded
2020-07-06T18:48:07.971Z,1594061287.971 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2020-07-06T18:48:08.085Z,1594061288.085 [NAL9602] Loaded
2020-07-06T18:48:08.085Z,1594061288.085 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-07-06T18:48:08.133Z,1594061288.133 [Onboard] Loaded
2020-07-06T18:48:08.133Z,1594061288.133 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2020-07-06T18:48:08.134Z,1594061288.134 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408CB4E0
2020-07-06T18:48:08.135Z,1594061288.135 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1188
2020-07-06T18:48:08.154Z,1594061288.154 [Radio_Surface] Loaded
2020-07-06T18:48:08.154Z,1594061288.154 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-07-06T18:48:08.155Z,1594061288.155 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408FB4E0
2020-07-06T18:48:08.156Z,1594061288.156 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1189
2020-07-06T18:48:08.307Z,1594061288.307 [DAT] Loaded
2020-07-06T18:48:08.307Z,1594061288.307 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2020-07-06T18:48:09.831Z,1594061289.831 [BPC1] Loaded
2020-07-06T18:48:09.832Z,1594061289.832 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-07-06T18:48:09.832Z,1594061289.832 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-07-06T18:48:09.833Z,1594061289.833 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-07-06T18:48:09.973Z,1594061289.973 [DepthRateCalculator] Loaded
2020-07-06T18:48:09.973Z,1594061289.973 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-07-06T18:48:09.998Z,1594061289.998 [PitchRateCalculator] Loaded
2020-07-06T18:48:09.998Z,1594061289.998 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-07-06T18:48:10.010Z,1594061290.010 [SpeedCalculator] Loaded
2020-07-06T18:48:10.010Z,1594061290.010 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-07-06T18:48:10.031Z,1594061290.031 [TempGradientCalculator] Loaded
2020-07-06T18:48:10.031Z,1594061290.031 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-07-06T18:48:10.036Z,1594061290.036 [YawRateCalculator] Loaded
2020-07-06T18:48:10.036Z,1594061290.036 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-07-06T18:48:10.193Z,1594061290.193 [ElevatorOffsetCalculator] Loaded
2020-07-06T18:48:10.194Z,1594061290.194 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-07-06T18:48:10.194Z,1594061290.194 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-07-06T18:48:10.195Z,1594061290.195 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-07-06T18:48:10.411Z,1594061290.411 [CANONSampler] Loaded
2020-07-06T18:48:10.412Z,1594061290.412 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2020-07-06T18:48:10.440Z,1594061290.440 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2020-07-06T18:48:10.445Z,1594061290.445 [CTD_Seabird](INFO): created writer for : sea_water_density
2020-07-06T18:48:10.446Z,1594061290.446 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2020-07-06T18:48:10.450Z,1594061290.450 [CTD_Seabird](INFO): created writer for : depth
2020-07-06T18:48:10.451Z,1594061290.451 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2020-07-06T18:48:10.456Z,1594061290.456 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2020-07-06T18:48:10.456Z,1594061290.456 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2020-07-06T18:48:10.462Z,1594061290.462 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2020-07-06T18:48:10.462Z,1594061290.462 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2020-07-06T18:48:10.467Z,1594061290.467 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2020-07-06T18:48:10.468Z,1594061290.468 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2020-07-06T18:48:10.473Z,1594061290.473 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2020-07-06T18:48:10.474Z,1594061290.474 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2020-07-06T18:48:10.479Z,1594061290.479 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2020-07-06T18:48:10.479Z,1594061290.480 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): mass_concentration_of_oxygen_in_sea_water
2020-07-06T18:48:10.485Z,1594061290.485 [CTD_Seabird](INFO): created writer for : mass_concentration_of_oxygen_in_sea_water
2020-07-06T18:48:10.485Z,1594061290.485 [CTD_Seabird](DEBUG): LcmSlateWriter::add(DAtaURI): CTD_Seabird.sea_water_dissolved_oxygen_freq
2020-07-06T18:48:10.486Z,1594061290.486 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): dataName: sea_water_dissolved_oxygen_freq
2020-07-06T18:48:10.518Z,1594061290.518 [CTD_Seabird] Loaded
2020-07-06T18:48:10.518Z,1594061290.518 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2020-07-06T18:48:10.519Z,1594061290.519 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A074E0
2020-07-06T18:48:10.520Z,1594061290.520 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1190
2020-07-06T18:48:10.552Z,1594061290.552 [PAR_Licor] Loaded
2020-07-06T18:48:10.552Z,1594061290.552 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2020-07-06T18:48:10.574Z,1594061290.574 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470
2020-07-06T18:48:10.574Z,1594061290.574 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470
2020-07-06T18:48:10.578Z,1594061290.578 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650
2020-07-06T18:48:10.579Z,1594061290.579 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650
2020-07-06T18:48:10.583Z,1594061290.583 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl
2020-07-06T18:48:10.583Z,1594061290.583 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl
2020-07-06T18:48:10.587Z,1594061290.587 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm
2020-07-06T18:48:10.587Z,1594061290.587 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm
2020-07-06T18:48:10.592Z,1594061290.592 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm
2020-07-06T18:48:10.592Z,1594061290.592 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm
2020-07-06T18:48:10.596Z,1594061290.596 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm
2020-07-06T18:48:10.596Z,1594061290.596 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm
2020-07-06T18:48:10.600Z,1594061290.600 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm
2020-07-06T18:48:10.601Z,1594061290.601 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm
2020-07-06T18:48:10.605Z,1594061290.605 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water
2020-07-06T18:48:10.609Z,1594061290.609 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water
2020-07-06T18:48:10.610Z,1594061290.610 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-07-06T18:48:10.610Z,1594061290.610 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-07-06T18:48:10.614Z,1594061290.614 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-07-06T18:48:10.614Z,1594061290.614 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-07-06T18:48:10.619Z,1594061290.619 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-07-06T18:48:10.619Z,1594061290.619 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-07-06T18:48:10.623Z,1594061290.623 [WetLabsBB2FL] Loaded
2020-07-06T18:48:10.624Z,1594061290.624 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2020-07-06T18:48:10.625Z,1594061290.625 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A374E0
2020-07-06T18:48:10.625Z,1594061290.625 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1191
2020-07-06T18:48:10.626Z,1594061290.626 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-07-06T18:48:10.626Z,1594061290.626 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-07-06T18:48:10.654Z,1594061290.654 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-07-06T18:48:10.654Z,1594061290.654 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-07-06T18:48:10.949Z,1594061290.949 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-07-06T18:48:10.950Z,1594061290.950 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-07-06T18:48:11.089Z,1594061291.089 [SBIT](DEBUG): Construct Startup Built In Test.
2020-07-06T18:48:11.100Z,1594061291.100 [SBIT] Loaded
2020-07-06T18:48:11.100Z,1594061291.100 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-07-06T18:48:11.101Z,1594061291.101 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-07-06T18:48:11.114Z,1594061291.114 [IBIT] Loaded
2020-07-06T18:48:11.114Z,1594061291.114 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-07-06T18:48:11.117Z,1594061291.117 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-07-06T18:48:11.258Z,1594061291.258 [CBIT] Loaded
2020-07-06T18:48:11.258Z,1594061291.258 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-07-06T18:48:11.259Z,1594061291.259 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-07-06T18:48:11.259Z,1594061291.259 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2020-07-06T18:48:11.338Z,1594061291.338 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2020-07-06T18:48:11.342Z,1594061291.342 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-07-06T18:48:11.343Z,1594061291.343 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-07-06T18:48:11.350Z,1594061291.350 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-07-06T18:48:11.351Z,1594061291.351 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B5D4E0
2020-07-06T18:48:11.351Z,1594061291.351 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1192
2020-07-06T18:48:11.356Z,1594061291.356 [Supervisor](INFO): Main Thread ID is 815
2020-07-06T18:48:11.356Z,1594061291.356 [Supervisor](DEBUG): Running supervisor.
2020-07-06T18:48:11.356Z,1594061291.356 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1193
2020-07-06T18:48:11.359Z,1594061291.359 [controlThread ThreadHandler](INFO): Handler Thread ID is 1194
2020-07-06T18:48:11.359Z,1594061291.359 [controlThread](DEBUG): Initializing ControlThread
2020-07-06T18:48:11.360Z,1594061291.360 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-07-06T18:48:11.362Z,1594061291.362 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-07-06T18:48:11.362Z,1594061291.362 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-07-06T18:48:11.363Z,1594061291.363 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-07-06T18:48:11.365Z,1594061291.365 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-07-06T18:48:11.365Z,1594061291.365 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-07-06T18:48:11.371Z,1594061291.371 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-07-06T18:48:11.371Z,1594061291.371 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-07-06T18:48:11.371Z,1594061291.371 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-07-06T18:48:11.372Z,1594061291.372 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-07-06T18:48:11.372Z,1594061291.372 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-07-06T18:48:11.373Z,1594061291.373 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-07-06T18:48:11.377Z,1594061291.377 [SBIT](INFO): Initialize SBIT Component.
2020-07-06T18:48:11.378Z,1594061291.378 [SBIT](IMPORTANT): git: 2020-06-09-119-gf51179b
2020-07-06T18:48:11.378Z,1594061291.378 [SBIT](INFO): git hash: f51179b25bbff3b65d8e06753bebb0790d3f8ed6
2020-07-06T18:48:11.378Z,1594061291.378 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-07-06T18:48:11.379Z,1594061291.379 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Nov 13 12:38:10 PST 2019
2020-07-06T18:48:11.380Z,1594061291.380 [SBIT](INFO): Beginning SBIT in 67.000000 seconds.
2020-07-06T18:48:11.381Z,1594061291.381 [IBIT](INFO): Initialize IBIT Component.
2020-07-06T18:48:11.382Z,1594061291.382 [CBIT](DEBUG): Initialize CBIT Component.
2020-07-06T18:48:11.383Z,1594061291.383 [logger ThreadHandler](INFO): Handler Thread ID is 1195
2020-07-06T18:48:11.393Z,1594061291.393 [CBIT](DEBUG): Initialized mux pins.
2020-07-06T18:48:11.393Z,1594061291.393 [CBIT](DEBUG): Initializing the watchdog timer.
2020-07-06T18:48:11.401Z,1594061291.401 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1196
2020-07-06T18:48:11.402Z,1594061291.402 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-07-06T18:48:11.413Z,1594061291.413 [Onboard ThreadHandler](INFO): Handler Thread ID is 1197
2020-07-06T18:48:11.418Z,1594061291.418 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-07-06T18:48:11.418Z,1594061291.418 [CBIT](DEBUG): Initializing heartbeat.
2020-07-06T18:48:11.431Z,1594061291.431 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1198
2020-07-06T18:48:11.447Z,1594061291.447 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1199
2020-07-06T18:48:11.447Z,1594061291.447 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-07-06T18:48:11.450Z,1594061291.450 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1201
2020-07-06T18:48:11.451Z,1594061291.451 [WetLabsBB2FL](INFO): Powering down
2020-07-06T18:48:11.473Z,1594061291.473 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1202
2020-07-06T18:48:11.476Z,1594061291.476 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-07-06T18:48:11.476Z,1594061291.476 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-07-06T18:48:11.477Z,1594061291.477 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-07-06T18:48:11.477Z,1594061291.477 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-07-06T18:48:11.477Z,1594061291.477 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-07-06T18:48:11.477Z,1594061291.477 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-07-06T18:48:11.477Z,1594061291.477 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-07-06T18:48:11.478Z,1594061291.478 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-07-06T18:48:11.478Z,1594061291.478 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-07-06T18:48:11.478Z,1594061291.478 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-07-06T18:48:11.478Z,1594061291.478 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-07-06T18:48:11.478Z,1594061291.478 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-07-06T18:48:11.478Z,1594061291.478 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-07-06T18:48:11.478Z,1594061291.478 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-07-06T18:48:11.479Z,1594061291.479 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-07-06T18:48:11.479Z,1594061291.479 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-07-06T18:48:11.489Z,1594061291.489 [CBIT](DEBUG): Deactivating GF circuits.
2020-07-06T18:48:11.489Z,1594061291.489 [CBIT](DEBUG): Deactivating emergency mode.
2020-07-06T18:48:11.525Z,1594061291.525 [CBIT](DEBUG): Backplane powered.
2020-07-06T18:48:11.526Z,1594061291.526 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-07-06T18:48:11.536Z,1594061291.536 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-07-06T18:48:11.546Z,1594061291.546 [MissionManager](DEBUG):
2020-07-06T18:48:11.547Z,1594061291.547 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-07-06T18:48:11.615Z,1594061291.615 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-07-06T18:48:11.616Z,1594061291.616 [Default:A.Wait](DEBUG): Construct Wait.
2020-07-06T18:48:11.634Z,1594061291.634 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-07-06T18:48:11.660Z,1594061291.660 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-07-06T18:48:11.663Z,1594061291.663 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-07-06T18:48:11.696Z,1594061291.696 [Default:E.Execute](DEBUG): Construct Execute.
2020-07-06T18:48:11.699Z,1594061291.699 [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-07-06T18:48:11.709Z,1594061291.709 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,NAL9602,DAT,BPC1,CANONSampler,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-07-06T18:48:11.714Z,1594061291.714 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-07-06T18:48:11.837Z,1594061291.837 [Radio_Surface](INFO): Powering up
2020-07-06T18:48:11.881Z,1594061291.881 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2020-07-06T18:48:11.881Z,1594061291.881 [DAT](INFO): Powering up
2020-07-06T18:48:11.882Z,1594061291.882 [DAT](DEBUG): Initializing DAT.
2020-07-06T18:48:11.929Z,1594061291.929 [CANONSampler](INFO): Powering down
2020-07-06T18:48:11.961Z,1594061291.961 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2020-07-06T18:48:11.999Z,1594061291.999 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-07-06T18:48:12.001Z,1594061292.001 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-07-06T18:48:12.003Z,1594061292.003 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-07-06T18:48:12.013Z,1594061292.013 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-07-06T18:48:12.015Z,1594061292.015 [MassServo](DEBUG): Initializing EZServoServo.
2020-07-06T18:48:12.025Z,1594061292.025 [MassServo](DEBUG): Initializing MassServo.
2020-07-06T18:48:12.026Z,1594061292.026 [RudderServo](DEBUG): Initializing EZServoServo.
2020-07-06T18:48:12.037Z,1594061292.037 [RudderServo](DEBUG): Initializing RudderServo.
2020-07-06T18:48:12.038Z,1594061292.038 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-07-06T18:48:12.049Z,1594061292.049 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-07-06T18:48:12.857Z,1594061292.857 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2020-07-06T18:48:12.857Z,1594061292.857 [RudderServo](FAULT): Rudder failed to initialize
2020-07-06T18:48:12.857Z,1594061292.857 [RudderServo] Communications Fault, FailCount= 1
2020-07-06T18:48:12.857Z,1594061292.857 [RudderServo](ERROR): Communications Fault
2020-07-06T18:48:13.016Z,1594061293.016 [CBIT](ERROR): Communications Fault in component: RudderServo
2020-07-06T18:48:13.218Z,1594061293.218 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-07-06T18:48:13.218Z,1594061293.218 [RudderServo](INFO): Powering down
2020-07-06T18:48:13.848Z,1594061293.848 [RudderServo](DEBUG): Initializing EZServoServo.
2020-07-06T18:48:13.970Z,1594061293.970 [RudderServo](DEBUG): Initializing RudderServo.
2020-07-06T18:48:13.974Z,1594061293.974 [CBIT](INFO): Clearing failed state for component RudderServo
2020-07-06T18:48:13.975Z,1594061293.975 [RudderServo] No Fault, FailCount= 1
2020-07-06T18:48:18.477Z,1594061298.477 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2020-07-06T18:48:24.344Z,1594061304.344 [DAT](INFO): commRate: 800
2020-07-06T18:48:24.345Z,1594061304.345 [DAT](INFO): commRate: 800
2020-07-06T18:48:24.747Z,1594061304.747 [DAT](INFO): entering command mode
2020-07-06T18:48:25.151Z,1594061305.151 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-06T18:48:25.559Z,1594061305.559 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-06T18:48:25.959Z,1594061305.959 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-06T18:48:26.362Z,1594061306.362 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-06T18:48:26.766Z,1594061306.766 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-06T18:48:27.170Z,1594061307.170 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-06T18:48:27.575Z,1594061307.575 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-06T18:48:27.979Z,1594061307.979 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-06T18:48:28.383Z,1594061308.383 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-06T18:48:28.787Z,1594061308.787 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-06T18:48:29.191Z,1594061309.191 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-06T18:48:29.595Z,1594061309.595 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-06T18:48:29.998Z,1594061309.998 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-06T18:48:30.402Z,1594061310.402 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-06T18:48:30.806Z,1594061310.806 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-06T18:48:31.211Z,1594061311.211 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-06T18:48:31.614Z,1594061311.614 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-06T18:48:32.018Z,1594061312.018 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-06T18:48:32.422Z,1594061312.422 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-06T18:48:32.822Z,1594061312.822 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-06T18:48:33.233Z,1594061313.233 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-06T18:48:33.631Z,1594061313.631 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-06T18:48:34.035Z,1594061314.035 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-06T18:48:34.442Z,1594061314.442 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-06T18:48:34.848Z,1594061314.848 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-06T18:48:35.247Z,1594061315.247 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-06T18:48:35.651Z,1594061315.651 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-06T18:48:36.055Z,1594061316.055 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-06T18:48:36.462Z,1594061316.462 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-06T18:48:36.867Z,1594061316.867 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-06T18:48:37.267Z,1594061317.267 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-06T18:48:37.674Z,1594061317.674 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-06T18:48:38.078Z,1594061318.078 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-06T18:48:38.489Z,1594061318.489 [NAL9602](INFO): Powering up NAL9602
2020-07-06T18:48:38.490Z,1594061318.490 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-06T18:48:38.883Z,1594061318.883 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-06T18:48:39.291Z,1594061319.291 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-06T18:48:39.694Z,1594061319.694 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-06T18:48:40.100Z,1594061320.100 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-06T18:48:40.100Z,1594061320.100 [DAT](FAULT): failed to enter command mode
2020-07-06T18:48:40.503Z,1594061320.503 [DAT](INFO): entering command mode
2020-07-06T18:48:40.908Z,1594061320.908 [DAT](INFO): setting verbose to 3
2020-07-06T18:48:41.308Z,1594061321.308 [DAT](INFO): set verbose to 3
2020-07-06T18:48:41.308Z,1594061321.308 [DAT](INFO): setting DatVerbose to 27440
2020-07-06T18:48:41.716Z,1594061321.716 [DAT](INFO): set DatVerbose to 27440
2020-07-06T18:48:41.716Z,1594061321.716 [DAT](INFO): setting transmit power to 8
2020-07-06T18:48:42.116Z,1594061322.116 [DAT](INFO): set transmit power to 8
2020-07-06T18:48:42.116Z,1594061322.116 [DAT](INFO): setting local address to 7
2020-07-06T18:48:42.520Z,1594061322.520 [DAT](INFO): set local address to 7
2020-07-06T18:48:49.387Z,1594061329.387 [NAL9602](INFO): NAL9602 initialized
2020-07-06T18:49:18.919Z,1594061358.919 [SBIT](IMPORTANT): Beginning Startup BIT
2020-07-06T18:49:18.927Z,1594061358.927 [CBIT](IMPORTANT): Beginning ground fault scan
2020-07-06T18:49:23.003Z,1594061363.003 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-06T18:49:23.077Z,1594061363.077 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-07-06T18:49:29.835Z,1594061369.835 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 4.664651
CHAN A1 (24V): 0.180156
CHAN A2 (12V): -0.002064
CHAN A3 (5V): -0.001319
CHAN B0 (3.3V): -0.000315
CHAN B1 (3.15aV): 0.000075
CHAN B2 (3.15bV): -0.000240
CHAN B3 (GND): 0.000380
OPEN: -0.000623
Full Scale Calc: 4.765 mA, -1.589 mA
2020-07-06T18:49:33.903Z,1594061373.903 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-06T18:49:33.974Z,1594061373.974 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-07-06T18:49:44.833Z,1594061384.833 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-06T18:49:44.908Z,1594061384.908 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-07-06T18:49:55.325Z,1594061395.325 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-07-06T18:49:55.726Z,1594061395.726 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-06T18:50:06.248Z,1594061406.248 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-07-06T18:50:06.659Z,1594061406.659 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-06T18:50:13.068Z,1594061413.068 [SBIT](IMPORTANT): SBIT PASSED
2020-07-06T18:50:13.069Z,1594061413.069 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-07-06T18:50:13.070Z,1594061413.070 [SBIT](IMPORTANT): CBIT.gf24Offset=-26 microampere;
2020-07-06T18:50:13.070Z,1594061413.070 [SBIT](IMPORTANT): Express linearApproximation platform_average_power 1.000000 watt;
2020-07-06T18:50:13.070Z,1594061413.070 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=281.405558 cubic_centimeter;
2020-07-06T18:50:13.070Z,1594061413.070 [SBIT](IMPORTANT): VerticalControl.massDefault=-11.988931 millimeter;
2020-07-06T18:50:13.455Z,1594061413.455 [MissionManager](IMPORTANT): Started mission Startup
2020-07-06T18:50:13.455Z,1594061413.455 [Startup] Running Loop=1
2020-07-06T18:50:13.455Z,1594061413.455 [Startup](DEBUG): Aggregate::initialize Startup
2020-07-06T18:50:13.456Z,1594061413.456 [Startup:A.GoToSurface] Running Loop=1
2020-07-06T18:50:13.456Z,1594061413.456 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-07-06T18:50:13.456Z,1594061413.456 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-07-06T18:50:13.457Z,1594061413.457 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-07-06T18:50:13.457Z,1594061413.457 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-07-06T18:50:13.458Z,1594061413.458 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-07-06T18:50:13.458Z,1594061413.458 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-07-06T18:50:13.459Z,1594061413.459 [Startup:StartupSatComms] Running Loop=1
2020-07-06T18:50:13.459Z,1594061413.459 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-07-06T18:50:13.459Z,1594061413.459 [Startup:StartupSatComms:A] Running Loop=1
2020-07-06T18:50:13.848Z,1594061413.848 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-07-06T18:50:17.550Z,1594061417.550 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-06T18:50:28.443Z,1594061428.443 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-06T18:50:39.351Z,1594061439.351 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-06T18:50:54.183Z,1594061454.183 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004677
2020-07-06T18:51:11.618Z,1594061471.618 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2020-07-06T18:51:11.618Z,1594061471.618 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-06T18:51:11.628Z,1594061471.628 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-06T18:51:12.039Z,1594061472.039 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-06T18:51:12.039Z,1594061472.039 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2020-07-06T18:51:13.639Z,1594061473.639 [Startup:StartupSatComms:A](INFO): Timed out from 2020-07-06T18:50:13.5Z
2020-07-06T18:51:13.639Z,1594061473.639 [Startup:StartupSatComms:A] Stopped
2020-07-06T18:51:13.640Z,1594061473.640 [Startup:StartupSatComms:B] Running Loop=1
2020-07-06T18:51:14.068Z,1594061474.068 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-07-06T18:51:20.448Z,1594061480.448 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200706T170557/Courier0031.lzma
2020-07-06T18:51:21.451Z,1594061481.451 [DataOverHttps](INFO): Moved sent file to Logs/20200706T170557/Courier0031.lzma.bak
2020-07-06T18:51:21.451Z,1594061481.451 [DataOverHttps](INFO): SBD MOMSN=12418823
2020-07-06T18:51:36.896Z,1594061496.896 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20200706T184800/Courier0000.lzma
2020-07-06T18:51:37.898Z,1594061497.898 [DataOverHttps](INFO): Moved sent file to Logs/20200706T184800/Courier0000.lzma.bak
2020-07-06T18:51:37.899Z,1594061497.899 [DataOverHttps](INFO): SBD MOMSN=12418828
2020-07-06T18:51:53.393Z,1594061513.393 [DataOverHttps](INFO): Sending 407 bytes from file Logs/20200706T170557/Express0032.lzma
2020-07-06T18:51:54.395Z,1594061514.395 [DataOverHttps](INFO): Moved sent file to Logs/20200706T170557/Express0032.lzma.bak
2020-07-06T18:51:54.395Z,1594061514.395 [DataOverHttps](INFO): SBD MOMSN=12418847
2020-07-06T18:52:09.802Z,1594061529.802 [DataOverHttps](INFO): Sending 880 bytes from file Logs/20200706T184800/Express0001.lzma
2020-07-06T18:52:10.803Z,1594061530.803 [DataOverHttps](INFO): Moved sent file to Logs/20200706T184800/Express0001.lzma.bak
2020-07-06T18:52:10.803Z,1594061530.803 [DataOverHttps](INFO): SBD MOMSN=12418859
2020-07-06T18:52:12.222Z,1594061532.222 [Startup:StartupSatComms:B] Stopped
2020-07-06T18:52:12.222Z,1594061532.222 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2020-07-06T18:52:12.222Z,1594061532.222 [Startup:StartupSatComms] Stopped
2020-07-06T18:52:12.222Z,1594061532.222 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-07-06T18:52:12.223Z,1594061532.223 [Startup](INFO): Completed Startup
2020-07-06T18:52:12.223Z,1594061532.223 [MissionManager](INFO): Startup is completed.
2020-07-06T18:52:12.223Z,1594061532.223 [MissionManager](INFO): Uninitializing Mission Startup
2020-07-06T18:52:12.223Z,1594061532.223 [Startup] Stopped
2020-07-06T18:52:12.223Z,1594061532.223 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-07-06T18:52:12.223Z,1594061532.223 [Startup:A.GoToSurface] Stopped
2020-07-06T18:52:12.223Z,1594061532.223 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-07-06T18:52:12.639Z,1594061532.639 [MissionManager](IMPORTANT): Started mission Default
2020-07-06T18:52:12.640Z,1594061532.640 [Default] Running Loop=1
2020-07-06T18:52:12.640Z,1594061532.640 [Default](DEBUG): Aggregate::initialize Default
2020-07-06T18:52:12.640Z,1594061532.640 [Default:B.GoToSurface] Running Loop=1
2020-07-06T18:52:12.640Z,1594061532.640 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-07-06T18:52:12.640Z,1594061532.640 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-07-06T18:52:12.640Z,1594061532.640 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-07-06T18:52:12.641Z,1594061532.641 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-07-06T18:52:12.641Z,1594061532.641 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-07-06T18:52:12.641Z,1594061532.641 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-07-06T18:52:12.642Z,1594061532.642 [Default:A.Wait] Running Loop=1
2020-07-06T18:52:12.642Z,1594061532.642 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-07-06T18:52:26.002Z,1594061546.002 [Default:A.Wait](INFO): Done Waiting.
2020-07-06T18:52:26.002Z,1594061546.002 [Default:A.Wait] Stopped
2020-07-06T18:52:26.003Z,1594061546.003 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-07-06T18:52:26.366Z,1594061546.366 [Default:CheckIn] Running Loop=1
2020-07-06T18:52:26.367Z,1594061546.367 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-07-06T18:52:26.367Z,1594061546.367 [Default:CheckIn:Read_GPS] Running Loop=1
2020-07-06T18:52:26.780Z,1594061546.780 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-07-06T18:52:40.130Z,1594061560.130 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-07-06T18:52:51.077Z,1594061571.077 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-07-06T18:53:01.563Z,1594061581.563 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-07-06T18:53:08.372Z,1594061588.372 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-06T18:53:12.475Z,1594061592.475 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-07-06T18:53:19.333Z,1594061599.333 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-06T18:53:23.378Z,1594061603.378 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-07-06T18:53:29.838Z,1594061609.838 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-06T18:53:34.688Z,1594061614.688 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-07-06T18:53:41.167Z,1594061621.167 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-06T18:53:45.651Z,1594061625.651 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-07-06T18:53:52.073Z,1594061632.073 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-06T18:53:52.802Z,1594061632.802 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-07-06T18:53:56.596Z,1594061636.596 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-07-06T18:54:02.991Z,1594061642.991 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-06T18:54:07.007Z,1594061647.007 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-07-06T18:54:12.605Z,1594061652.605 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2020-07-06T18:54:12.605Z,1594061652.605 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-06T18:54:12.616Z,1594061652.616 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-06T18:54:13.028Z,1594061653.028 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-06T18:54:13.028Z,1594061653.028 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2020-07-06T18:54:13.895Z,1594061653.895 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-06T18:54:17.986Z,1594061657.986 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-07-06T18:54:24.784Z,1594061664.784 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-06T18:54:28.906Z,1594061668.906 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-07-06T18:54:35.695Z,1594061675.695 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-06T18:54:40.140Z,1594061680.140 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-07-06T18:54:46.670Z,1594061686.670 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-06T18:54:51.057Z,1594061691.057 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-07-06T18:54:57.505Z,1594061697.505 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-06T18:55:01.956Z,1594061701.956 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-07-06T18:55:08.838Z,1594061708.838 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-06T18:55:12.855Z,1594061712.855 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-07-06T18:55:19.763Z,1594061719.763 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-06T18:55:23.791Z,1594061723.791 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-07-06T18:55:30.257Z,1594061730.257 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-06T18:55:34.708Z,1594061734.708 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-07-06T18:55:41.166Z,1594061741.166 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-06T18:55:45.608Z,1594061745.608 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-07-06T18:55:52.480Z,1594061752.480 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-06T18:55:56.123Z,1594061756.123 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-07-06T18:56:03.394Z,1594061763.394 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-06T18:56:07.030Z,1594061767.030 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-07-06T18:56:14.298Z,1594061774.298 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-06T18:56:18.398Z,1594061778.398 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-07-06T18:56:25.209Z,1594061785.209 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-06T18:56:28.849Z,1594061788.849 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-07-06T18:56:36.530Z,1594061796.530 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-06T18:56:39.767Z,1594061799.767 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-07-06T18:56:47.880Z,1594061807.880 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-06T18:56:50.662Z,1594061810.662 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-07-06T18:56:58.740Z,1594061818.740 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-06T18:57:01.173Z,1594061821.173 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-07-06T18:57:09.655Z,1594061829.655 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-06T18:57:11.664Z,1594061831.664 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-07-06T18:57:13.619Z,1594061833.619 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2020-07-06T18:57:13.619Z,1594061833.619 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-06T18:57:13.629Z,1594061833.629 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-06T18:57:14.037Z,1594061834.037 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-06T18:57:14.037Z,1594061834.037 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2020-07-06T18:57:20.558Z,1594061840.558 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-06T18:57:22.577Z,1594061842.577 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-07-06T18:57:26.570Z,1594061846.570 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-07-06T18:52:26.4Z
2020-07-06T18:57:26.570Z,1594061846.570 [Default:CheckIn:Read_GPS] Stopped
2020-07-06T18:57:26.570Z,1594061846.570 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-07-06T18:57:26.956Z,1594061846.956 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-07-06T18:57:31.522Z,1594061851.522 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-06T18:57:33.496Z,1594061853.496 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-07-06T18:57:33.744Z,1594061853.744 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200706T184800/Courier0004.lzma
2020-07-06T18:57:34.746Z,1594061854.746 [DataOverHttps](INFO): Moved sent file to Logs/20200706T184800/Courier0004.lzma.bak
2020-07-06T18:57:34.747Z,1594061854.747 [DataOverHttps](INFO): SBD MOMSN=12418907
2020-07-06T18:57:42.372Z,1594061862.372 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-06T18:57:44.388Z,1594061864.388 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-07-06T18:57:50.133Z,1594061870.133 [DataOverHttps](INFO): Sending 194 bytes from file Logs/20200706T184800/Express0005.lzma
2020-07-06T18:57:51.135Z,1594061871.135 [DataOverHttps](INFO): Moved sent file to Logs/20200706T184800/Express0005.lzma.bak
2020-07-06T18:57:51.135Z,1594061871.135 [DataOverHttps](INFO): SBD MOMSN=12418909
2020-07-06T18:57:52.423Z,1594061872.423 [Default:CheckIn:Read_Iridium] Stopped
2020-07-06T18:57:52.424Z,1594061872.424 [Default:CheckIn:C.Wait] Running Loop=1
2020-07-06T18:57:52.424Z,1594061872.424 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-07-06T18:57:53.292Z,1594061873.292 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-06T18:57:55.374Z,1594061875.374 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-07-06T18:58:02.900Z,1594061882.900 [BPC1](ERROR): Failed to receive data from all battery packs.
2020-07-06T18:58:02.900Z,1594061882.900 [BPC1] Communications Fault, FailCount= 1
2020-07-06T18:58:02.900Z,1594061882.900 [BPC1](ERROR): Communications Fault
2020-07-06T18:58:02.945Z,1594061882.945 [CBIT](ERROR): Communications Fault in component: BPC1
2020-07-06T18:58:50.576Z,1594061930.576 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-07-06T18:58:50.576Z,1594061930.576 [NAL9602] Data Fault, FailCount= 1
2020-07-06T18:58:50.576Z,1594061930.576 [NAL9602](ERROR): Data Fault
2020-07-06T18:58:50.594Z,1594061930.594 [CBIT](ERROR): Data Fault in component: NAL9602
2020-07-06T18:58:50.978Z,1594061930.978 [NAL9602](INFO): Powering down
2020-07-06T18:58:51.804Z,1594061931.804 [CBIT](INFO): Clearing failed state for component NAL9602
2020-07-06T18:58:51.804Z,1594061931.804 [NAL9602] No Fault, FailCount= 1
2020-07-06T18:59:21.286Z,1594061961.286 [NAL9602](INFO): Powering up NAL9602
2020-07-06T18:59:32.204Z,1594061972.204 [NAL9602](INFO): NAL9602 initialized
2020-07-06T19:00:14.654Z,1594062014.654 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2020-07-06T19:00:14.654Z,1594062014.654 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-06T19:00:14.664Z,1594062014.664 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-06T19:00:15.043Z,1594062015.043 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-06T19:00:15.043Z,1594062015.043 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2020-07-06T19:02:53.001Z,1594062173.001 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-07-06T19:02:53.001Z,1594062173.001 [Default:CheckIn:C.Wait] Stopped
2020-07-06T19:02:53.001Z,1594062173.001 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-07-06T19:02:53.001Z,1594062173.001 [Default:CheckIn:D] Running Loop=1
2020-07-06T19:02:53.414Z,1594062173.414 [Default:CheckIn:D] Stopped
2020-07-06T19:02:53.415Z,1594062173.415 [Default:CheckIn:E] Running Loop=1
2020-07-06T19:02:53.829Z,1594062173.829 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.679565 min
2020-07-06T19:02:53.830Z,1594062173.830 [Default:CheckIn:E] Stopped
2020-07-06T19:02:53.830Z,1594062173.830 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-07-06T19:02:53.830Z,1594062173.830 [Default:CheckIn] Stopped
2020-07-06T19:02:53.830Z,1594062173.830 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-07-06T19:02:53.830Z,1594062173.830 [Default:CheckIn](INFO): Running loop #2
2020-07-06T19:02:53.830Z,1594062173.830 [Default:CheckIn] Running Loop=2
2020-07-06T19:02:53.830Z,1594062173.830 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-07-06T19:02:53.830Z,1594062173.830 [Default:CheckIn:Read_GPS] Running Loop=1
2020-07-06T19:03:15.635Z,1594062195.635 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2020-07-06T19:03:15.636Z,1594062195.636 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-06T19:03:15.646Z,1594062195.646 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-06T19:03:16.045Z,1594062196.045 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-06T19:03:16.045Z,1594062196.045 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2020-07-06T19:06:16.634Z,1594062376.634 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2020-07-06T19:06:16.634Z,1594062376.634 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-06T19:06:16.645Z,1594062376.645 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-06T19:06:17.023Z,1594062377.023 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-06T19:06:17.023Z,1594062377.023 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2020-07-06T19:07:54.015Z,1594062474.015 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-07-06T19:02:53.8Z
2020-07-06T19:07:54.015Z,1594062474.015 [Default:CheckIn:Read_GPS] Stopped
2020-07-06T19:07:54.015Z,1594062474.015 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-07-06T19:08:01.620Z,1594062481.620 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200706T184800/Courier0007.lzma
2020-07-06T19:08:02.623Z,1594062482.623 [DataOverHttps](INFO): Moved sent file to Logs/20200706T184800/Courier0007.lzma.bak
2020-07-06T19:08:02.623Z,1594062482.623 [DataOverHttps](INFO): SBD MOMSN=12418949
2020-07-06T19:08:03.952Z,1594062483.952 [CBIT](INFO): Clearing failed state for component BPC1
2020-07-06T19:08:03.952Z,1594062483.952 [BPC1] No Fault, FailCount= 1
2020-07-06T19:08:17.960Z,1594062497.960 [DataOverHttps](INFO): Sending 187 bytes from file Logs/20200706T184800/Express0008.lzma
2020-07-06T19:08:18.963Z,1594062498.963 [DataOverHttps](INFO): Moved sent file to Logs/20200706T184800/Express0008.lzma.bak
2020-07-06T19:08:18.963Z,1594062498.963 [DataOverHttps](INFO): SBD MOMSN=12418955
2020-07-06T19:08:20.515Z,1594062500.515 [Default:CheckIn:Read_Iridium] Stopped
2020-07-06T19:08:20.515Z,1594062500.515 [Default:CheckIn:C.Wait] Running Loop=1
2020-07-06T19:08:20.515Z,1594062500.515 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-07-06T19:09:15.519Z,1594062555.519 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-06T19:09:15.590Z,1594062555.590 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-07-06T19:09:17.542Z,1594062557.542 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2020-07-06T19:09:17.542Z,1594062557.542 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-06T19:09:17.569Z,1594062557.569 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-06T19:09:17.900Z,1594062557.900 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-06T19:09:17.900Z,1594062557.900 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2020-07-06T19:09:26.436Z,1594062566.436 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-06T19:09:33.231Z,1594062573.231 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-07-06T19:09:33.231Z,1594062573.231 [NAL9602] Data Fault, FailCount= 2
2020-07-06T19:09:33.231Z,1594062573.231 [NAL9602](ERROR): Data Fault
2020-07-06T19:09:33.277Z,1594062573.277 [CBIT](ERROR): Data Fault in component: NAL9602
2020-07-06T19:09:33.630Z,1594062573.630 [NAL9602](INFO): Powering down
2020-07-06T19:09:34.481Z,1594062574.481 [CBIT](INFO): Clearing failed state for component NAL9602
2020-07-06T19:09:34.481Z,1594062574.481 [NAL9602] No Fault, FailCount= 2
2020-07-06T19:09:37.343Z,1594062577.343 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-06T19:10:03.934Z,1594062603.934 [NAL9602](INFO): Powering up NAL9602
2020-07-06T19:10:14.834Z,1594062614.834 [NAL9602](INFO): NAL9602 initialized
2020-07-06T19:10:28.542Z,1594062628.542 [DataOverHttps](IMPORTANT): SBD MTMSN=20200706T190651
2020-07-06T19:10:35.839Z,1594062635.839 [DataOverHttps](INFO): Received command:restart app
2020-07-06T19:10:35.905Z,1594062635.905 [CommandLine](IMPORTANT): got command restart application
2020-07-06T19:10:36.909Z,1594062636.909 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2020-07-06T19:10:36.910Z,1594062636.910 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-07-06T19:10:36.910Z,1594062636.910 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-07-06T19:10:37.085Z,1594062637.085 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2020-07-06T19:10:37.085Z,1594062637.085 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-07-06T19:10:37.086Z,1594062637.086 [CommandLine](INFO): Join timeout helper Thread ID is 1264
2020-07-06T19:10:37.109Z,1594062637.109 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-07-06T19:10:37.109Z,1594062637.109 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-07-06T19:10:37.109Z,1594062637.109 [NavChartDb](INFO): Join timeout helper Thread ID is 1265
2020-07-06T19:10:37.357Z,1594062637.357 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-07-06T19:10:37.357Z,1594062637.357 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-07-06T19:10:37.377Z,1594062637.377 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2020-07-06T19:10:37.377Z,1594062637.377 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-07-06T19:10:37.377Z,1594062637.377 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1266
2020-07-06T19:10:37.621Z,1594062637.621 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2020-07-06T19:10:37.622Z,1594062637.622 [WetLabsBB2FL](INFO): Powering down
2020-07-06T19:10:37.622Z,1594062637.622 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-07-06T19:10:37.637Z,1594062637.637 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2020-07-06T19:10:37.637Z,1594062637.637 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-07-06T19:10:37.637Z,1594062637.637 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1267
2020-07-06T19:10:38.057Z,1594062638.057 [CTD_Seabird](INFO): Powering down
2020-07-06T19:10:38.069Z,1594062638.069 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2020-07-06T19:10:38.069Z,1594062638.069 [CTD_Seabird](INFO): Powering down
2020-07-06T19:10:38.085Z,1594062638.085 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-07-06T19:10:38.097Z,1594062638.097 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-07-06T19:10:38.097Z,1594062638.097 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-07-06T19:10:38.097Z,1594062638.097 [Radio_Surface](INFO): Join timeout helper Thread ID is 1268
2020-07-06T19:10:38.141Z,1594062638.141 [Radio_Surface](INFO): Powering down
2020-07-06T19:10:38.142Z,1594062638.142 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-07-06T19:10:38.142Z,1594062638.142 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-07-06T19:10:38.158Z,1594062638.158 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2020-07-06T19:10:38.158Z,1594062638.158 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-07-06T19:10:38.158Z,1594062638.158 [Onboard](INFO): Join timeout helper Thread ID is 1269
2020-07-06T19:10:38.845Z,1594062638.845 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2020-07-06T19:10:42.541Z,1594062642.541 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2020-07-06T19:10:42.541Z,1594062642.541 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-07-06T19:10:42.546Z,1594062642.546 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-07-06T19:10:42.546Z,1594062642.546 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-07-06T19:10:42.546Z,1594062642.546 [DataOverHttps](INFO): Join timeout helper Thread ID is 1270
2020-07-06T19:10:42.752Z,1594062642.752 [CBIT](FAULT): Humidity exceeds 50% of running average: Humidity:2285304 %. Average:282827.843750
2020-07-06T19:10:42.861Z,1594062642.861 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-07-06T19:10:42.861Z,1594062642.861 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-07-06T19:10:42.866Z,1594062642.866 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-07-06T19:10:42.866Z,1594062642.866 [logger ThreadHandler](INFO): Thread cancelled.
2020-07-06T19:10:42.866Z,1594062642.866 [logger](INFO): Join timeout helper Thread ID is 1271
2020-07-06T19:10:42.877Z,1594062642.877 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-07-06T19:10:42.877Z,1594062642.877 [logger ThreadHandler](INFO): Thread cancelled.
2020-07-06T19:10:42.886Z,1594062642.886 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-07-06T19:10:42.886Z,1594062642.886 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-07-06T19:10:42.886Z,1594062642.886 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-07-06T19:10:42.886Z,1594062642.886 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-07-06T19:10:42.886Z,1594062642.886 [controlThread](INFO): Join timeout helper Thread ID is 1272
2020-07-06T19:10:43.113Z,1594062643.113 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-07-06T19:10:43.113Z,1594062643.113 [controlThread](DEBUG): Uninitializing ControlThread
2020-07-06T19:10:43.114Z,1594062643.114 [AHRS_M2](INFO): Powering down
2020-07-06T19:10:43.186Z,1594062643.186 [NAL9602](INFO): Powering down
2020-07-06T19:10:43.187Z,1594062643.187 [DAT](INFO): Powering down
2020-07-06T19:10:43.307Z,1594062643.307 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-07-06T19:10:43.308Z,1594062643.308 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-07-06T19:10:43.308Z,1594062643.308 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-07-06T19:10:43.309Z,1594062643.309 [MissionManager](INFO): Uninitializing Mission Default
2020-07-06T19:10:43.309Z,1594062643.309 [Default] Stopped
2020-07-06T19:10:43.309Z,1594062643.309 [Default](DEBUG): Aggregate::uninitialize Default
2020-07-06T19:10:43.309Z,1594062643.309 [Default:B.GoToSurface] Stopped
2020-07-06T19:10:43.309Z,1594062643.309 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-07-06T19:10:43.309Z,1594062643.309 [Default:CheckIn] Stopped
2020-07-06T19:10:43.309Z,1594062643.309 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-07-06T19:10:43.310Z,1594062643.310 [Default:CheckIn:C.Wait] Stopped
2020-07-06T19:10:43.310Z,1594062643.310 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-07-06T19:10:43.312Z,1594062643.312 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-07-06T19:10:43.312Z,1594062643.312 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-07-06T19:10:43.313Z,1594062643.313 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-07-06T19:10:43.313Z,1594062643.313 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-07-06T19:10:43.313Z,1594062643.313 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-07-06T19:10:43.313Z,1594062643.313 [BuoyancyServo](INFO): Powering down
2020-07-06T19:10:43.325Z,1594062643.325 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-07-06T19:10:43.325Z,1594062643.325 [ElevatorServo](INFO): Powering down
2020-07-06T19:10:43.326Z,1594062643.326 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-07-06T19:10:43.326Z,1594062643.326 [MassServo](INFO): Powering down
2020-07-06T19:10:43.327Z,1594062643.327 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-07-06T19:10:43.327Z,1594062643.327 [RudderServo](INFO): Powering down
2020-07-06T19:10:43.328Z,1594062643.328 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-07-06T19:10:43.328Z,1594062643.328 [ThrusterServo](INFO): Powering down
2020-07-06T19:10:43.328Z,1594062643.328 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-07-06T19:10:43.329Z,1594062643.329 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-07-06T19:10:43.329Z,1594062643.329 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-07-06T19:10:43.329Z,1594062643.329 [CBIT](DEBUG): Powering off loads.
2020-07-06T19:10:43.341Z,1594062643.341 [CBIT](DEBUG): Disabling WDT.
2020-07-06T19:10:43.353Z,1594062643.353 [CBIT](DEBUG): Opening all GF detection circuits.
2020-07-06T19:10:43.354Z,1594062643.354 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-07-06T19:10:43.451Z,1594062643.451 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-07-06T19:10:43.453Z,1594062643.453 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-07-06T19:10:43.459Z,1594062643.459 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-07-06T19:10:43.497Z,1594062643.497 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-07-06T19:10:43.501Z,1594062643.501 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-07-06T19:10:43.555Z,1594062643.555 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-07-06T19:10:43.617Z,1594062643.617 [logger ThreadHandler](INFO): Thread cancelled.