2020-06-09T17:24:51.235Z,1591723491.235 [Supervisor](DEBUG): Initializing supervisor.
2020-06-09T17:24:51.238Z,1591723491.238 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-06-09T17:24:51.238Z,1591723491.238 [SyncHandler](INFO): Protected caller Thread ID is 821
2020-06-09T17:24:51.239Z,1591723491.239 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-06-09T17:24:51.240Z,1591723491.240 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-06-09T17:24:51.240Z,1591723491.240 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 822
2020-06-09T17:24:51.243Z,1591723491.243 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-06-09T17:24:51.255Z,1591723491.255 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-06-09T17:24:51.256Z,1591723491.256 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-06-09T17:24:51.257Z,1591723491.257 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 823
2020-06-09T17:24:51.258Z,1591723491.258 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-06-09T17:24:51.258Z,1591723491.258 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-06-09T17:24:51.259Z,1591723491.259 [logger ThreadHandler](INFO): Protected caller Thread ID is 824
2020-06-09T17:24:51.261Z,1591723491.261 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-06-09T17:24:51.261Z,1591723491.261 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-06-09T17:24:51.265Z,1591723491.265 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-06-09T17:24:51.585Z,1591723491.585 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-06-09T17:24:51.587Z,1591723491.587 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-06-09T17:24:52.225Z,1591723492.225 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-06-09T17:24:52.227Z,1591723492.227 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-06-09T17:24:52.367Z,1591723492.367 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-06-09T17:24:52.369Z,1591723492.369 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-06-09T17:24:52.867Z,1591723492.867 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-06-09T17:24:52.869Z,1591723492.869 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-06-09T17:24:52.965Z,1591723492.965 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-06-09T17:24:52.967Z,1591723492.967 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-06-09T17:24:53.048Z,1591723493.048 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-06-09T17:24:53.352Z,1591723493.352 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-06-09T17:24:53.352Z,1591723493.352 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-06-09T17:24:53.499Z,1591723493.499 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-06-09T17:24:53.500Z,1591723493.500 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-06-09T17:24:53.604Z,1591723493.604 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-06-09T17:24:53.605Z,1591723493.605 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2020-06-09T17:24:53.808Z,1591723493.808 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2020-06-09T17:24:53.809Z,1591723493.809 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-06-09T17:24:54.449Z,1591723494.449 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-06-09T17:24:54.699Z,1591723494.699 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-06-09T17:24:55.281Z,1591723495.281 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-06-09T17:24:55.283Z,1591723495.283 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-06-09T17:24:55.385Z,1591723495.385 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-06-09T17:24:55.386Z,1591723495.386 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-06-09T17:24:55.582Z,1591723495.582 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-06-09T17:24:55.584Z,1591723495.584 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-06-09T17:24:55.793Z,1591723495.793 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-06-09T17:24:55.795Z,1591723495.795 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-06-09T17:24:56.023Z,1591723496.023 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-06-09T17:24:56.025Z,1591723496.025 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/
2020-06-09T17:24:56.028Z,1591723496.028 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Control.cfg
2020-06-09T17:24:56.123Z,1591723496.123 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Sensor.cfg
2020-06-09T17:24:56.265Z,1591723496.265 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Simulator.cfg
2020-06-09T17:24:56.352Z,1591723496.352 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/secure.cfg
2020-06-09T17:24:56.436Z,1591723496.436 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Servo.cfg
2020-06-09T17:24:56.544Z,1591723496.544 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/vehicle.cfg
2020-06-09T17:24:56.730Z,1591723496.730 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Battery.cfg
2020-06-09T17:24:56.958Z,1591723496.958 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-06-09T17:24:56.959Z,1591723496.959 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Science.cfg
2020-06-09T17:24:57.071Z,1591723497.071 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/logger.cfg
2020-06-09T17:24:57.167Z,1591723497.167 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/BIT.cfg
2020-06-09T17:24:57.267Z,1591723497.267 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Navigation.cfg
2020-06-09T17:24:57.361Z,1591723497.361 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-06-09T17:24:57.370Z,1591723497.370 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2020-06-09T17:24:57.466Z,1591723497.466 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2020-06-09T17:24:57.467Z,1591723497.467 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-06-09T17:24:57.497Z,1591723497.497 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-06-09T17:24:57.499Z,1591723497.499 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-06-09T17:24:57.555Z,1591723497.555 [DepthRateCalculator] Loaded
2020-06-09T17:24:57.555Z,1591723497.555 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-06-09T17:24:57.561Z,1591723497.561 [PitchRateCalculator] Loaded
2020-06-09T17:24:57.561Z,1591723497.561 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-06-09T17:24:57.577Z,1591723497.577 [SpeedCalculator] Loaded
2020-06-09T17:24:57.577Z,1591723497.577 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-06-09T17:24:57.598Z,1591723497.598 [TempGradientCalculator] Loaded
2020-06-09T17:24:57.598Z,1591723497.598 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-06-09T17:24:57.604Z,1591723497.604 [YawRateCalculator] Loaded
2020-06-09T17:24:57.604Z,1591723497.604 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-06-09T17:24:57.643Z,1591723497.643 [ElevatorOffsetCalculator] Loaded
2020-06-09T17:24:57.643Z,1591723497.643 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-06-09T17:24:57.644Z,1591723497.644 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-06-09T17:24:57.644Z,1591723497.644 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-06-09T17:24:57.707Z,1591723497.707 [VerticalControl](DEBUG): Construct VerticalControl.
2020-06-09T17:24:57.805Z,1591723497.805 [VerticalControl] Loaded
2020-06-09T17:24:57.805Z,1591723497.805 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-06-09T17:24:57.806Z,1591723497.806 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-06-09T17:24:57.885Z,1591723497.885 [HorizontalControl] Loaded
2020-06-09T17:24:57.886Z,1591723497.886 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-06-09T17:24:57.886Z,1591723497.886 [SpeedControl](DEBUG): Construct SpeedControl.
2020-06-09T17:24:57.891Z,1591723497.891 [SpeedControl] Loaded
2020-06-09T17:24:57.892Z,1591723497.892 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-06-09T17:24:57.892Z,1591723497.892 [LoopControl](DEBUG): Construct LoopControl.
2020-06-09T17:24:57.893Z,1591723497.893 [LoopControl] Loaded
2020-06-09T17:24:57.893Z,1591723497.893 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-06-09T17:24:57.893Z,1591723497.893 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-06-09T17:24:57.895Z,1591723497.895 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-06-09T17:24:58.030Z,1591723498.030 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-06-09T17:24:58.030Z,1591723498.030 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-06-09T17:24:58.050Z,1591723498.050 [NavChart] Loaded
2020-06-09T17:24:58.050Z,1591723498.050 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-06-09T17:24:58.054Z,1591723498.054 [UniversalFixResidualReporter] Loaded
2020-06-09T17:24:58.054Z,1591723498.054 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-06-09T17:24:58.055Z,1591723498.055 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-06-09T17:24:58.055Z,1591723498.055 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-06-09T17:24:58.183Z,1591723498.183 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-06-09T17:24:58.183Z,1591723498.183 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-06-09T17:24:58.727Z,1591723498.727 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-06-09T17:24:58.731Z,1591723498.731 [AHRS_M2](INFO): created writer for : platform_orientation
2020-06-09T17:24:58.733Z,1591723498.733 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-06-09T17:24:58.738Z,1591723498.738 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-06-09T17:24:58.739Z,1591723498.739 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-06-09T17:24:58.743Z,1591723498.743 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-06-09T17:24:58.744Z,1591723498.744 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-06-09T17:24:58.749Z,1591723498.749 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-06-09T17:24:58.925Z,1591723498.925 [AHRS_M2] Loaded
2020-06-09T17:24:58.925Z,1591723498.925 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-06-09T17:24:59.071Z,1591723499.071 [DataOverHttps] Loaded
2020-06-09T17:24:59.071Z,1591723499.071 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-06-09T17:24:59.072Z,1591723499.072 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408544E0
2020-06-09T17:24:59.073Z,1591723499.073 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 906
2020-06-09T17:24:59.086Z,1591723499.086 [Depth_Keller] Loaded
2020-06-09T17:24:59.087Z,1591723499.087 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-06-09T17:24:59.091Z,1591723499.091 [DropWeight] Loaded
2020-06-09T17:24:59.092Z,1591723499.092 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2020-06-09T17:24:59.136Z,1591723499.136 [DVL_micro] Loaded
2020-06-09T17:24:59.136Z,1591723499.136 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2020-06-09T17:24:59.231Z,1591723499.231 [NAL9602] Loaded
2020-06-09T17:24:59.231Z,1591723499.231 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-06-09T17:24:59.270Z,1591723499.270 [Onboard] Loaded
2020-06-09T17:24:59.270Z,1591723499.270 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2020-06-09T17:24:59.274Z,1591723499.274 [Radio_Surface] Loaded
2020-06-09T17:24:59.274Z,1591723499.274 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-06-09T17:24:59.275Z,1591723499.275 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408844E0
2020-06-09T17:24:59.275Z,1591723499.275 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 907
2020-06-09T17:24:59.406Z,1591723499.406 [DAT] Loaded
2020-06-09T17:24:59.406Z,1591723499.406 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2020-06-09T17:25:00.872Z,1591723500.872 [BPC1] Loaded
2020-06-09T17:25:00.873Z,1591723500.873 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-06-09T17:25:00.873Z,1591723500.873 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-06-09T17:25:00.874Z,1591723500.874 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-06-09T17:25:01.325Z,1591723501.325 [BuoyancyServo] Loaded
2020-06-09T17:25:01.325Z,1591723501.325 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-06-09T17:25:01.362Z,1591723501.362 [ElevatorServo] Loaded
2020-06-09T17:25:01.362Z,1591723501.362 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-06-09T17:25:01.373Z,1591723501.373 [MassServo] Loaded
2020-06-09T17:25:01.373Z,1591723501.373 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-06-09T17:25:01.384Z,1591723501.384 [RudderServo] Loaded
2020-06-09T17:25:01.385Z,1591723501.385 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-06-09T17:25:01.395Z,1591723501.395 [ThrusterServo] Loaded
2020-06-09T17:25:01.395Z,1591723501.395 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-06-09T17:25:01.396Z,1591723501.396 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-06-09T17:25:01.396Z,1591723501.396 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-06-09T17:25:01.706Z,1591723501.706 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-06-09T17:25:01.707Z,1591723501.707 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-06-09T17:25:01.747Z,1591723501.747 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-06-09T17:25:01.748Z,1591723501.748 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-06-09T17:25:02.081Z,1591723502.081 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-06-09T17:25:02.081Z,1591723502.081 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-06-09T17:25:02.349Z,1591723502.349 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2020-06-09T17:25:02.355Z,1591723502.355 [CTD_Seabird](INFO): created writer for : sea_water_density
2020-06-09T17:25:02.355Z,1591723502.355 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2020-06-09T17:25:02.360Z,1591723502.360 [CTD_Seabird](INFO): created writer for : depth
2020-06-09T17:25:02.360Z,1591723502.360 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2020-06-09T17:25:02.365Z,1591723502.365 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2020-06-09T17:25:02.366Z,1591723502.366 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2020-06-09T17:25:02.371Z,1591723502.371 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2020-06-09T17:25:02.371Z,1591723502.371 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2020-06-09T17:25:02.376Z,1591723502.376 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2020-06-09T17:25:02.377Z,1591723502.377 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2020-06-09T17:25:02.382Z,1591723502.382 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2020-06-09T17:25:02.383Z,1591723502.383 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2020-06-09T17:25:02.388Z,1591723502.388 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2020-06-09T17:25:02.415Z,1591723502.415 [CTD_Seabird] Loaded
2020-06-09T17:25:02.415Z,1591723502.415 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2020-06-09T17:25:02.416Z,1591723502.416 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A904E0
2020-06-09T17:25:02.416Z,1591723502.416 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 908
2020-06-09T17:25:02.446Z,1591723502.446 [ESPComponent] Loaded
2020-06-09T17:25:02.447Z,1591723502.447 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2020-06-09T17:25:02.461Z,1591723502.461 [PAR_Licor] Loaded
2020-06-09T17:25:02.461Z,1591723502.461 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2020-06-09T17:25:02.467Z,1591723502.467 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470
2020-06-09T17:25:02.468Z,1591723502.468 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470
2020-06-09T17:25:02.472Z,1591723502.472 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650
2020-06-09T17:25:02.472Z,1591723502.472 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650
2020-06-09T17:25:02.476Z,1591723502.476 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl
2020-06-09T17:25:02.476Z,1591723502.476 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl
2020-06-09T17:25:02.480Z,1591723502.480 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm
2020-06-09T17:25:02.480Z,1591723502.480 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm
2020-06-09T17:25:02.484Z,1591723502.484 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm
2020-06-09T17:25:02.484Z,1591723502.484 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm
2020-06-09T17:25:02.489Z,1591723502.489 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm
2020-06-09T17:25:02.489Z,1591723502.489 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm
2020-06-09T17:25:02.493Z,1591723502.493 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm
2020-06-09T17:25:02.493Z,1591723502.493 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm
2020-06-09T17:25:02.497Z,1591723502.497 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water
2020-06-09T17:25:02.501Z,1591723502.501 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water
2020-06-09T17:25:02.502Z,1591723502.502 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-06-09T17:25:02.502Z,1591723502.502 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-06-09T17:25:02.506Z,1591723502.506 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-06-09T17:25:02.506Z,1591723502.506 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-06-09T17:25:02.510Z,1591723502.510 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-06-09T17:25:02.511Z,1591723502.511 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-06-09T17:25:02.515Z,1591723502.515 [WetLabsBB2FL] Loaded
2020-06-09T17:25:02.515Z,1591723502.515 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2020-06-09T17:25:02.516Z,1591723502.516 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AC04E0
2020-06-09T17:25:02.517Z,1591723502.517 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 909
2020-06-09T17:25:02.517Z,1591723502.517 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-06-09T17:25:02.519Z,1591723502.519 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-06-09T17:25:02.679Z,1591723502.679 [SBIT](DEBUG): Construct Startup Built In Test.
2020-06-09T17:25:02.690Z,1591723502.690 [SBIT] Loaded
2020-06-09T17:25:02.690Z,1591723502.690 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-06-09T17:25:02.691Z,1591723502.691 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-06-09T17:25:02.703Z,1591723502.703 [IBIT] Loaded
2020-06-09T17:25:02.704Z,1591723502.704 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-06-09T17:25:02.707Z,1591723502.707 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-06-09T17:25:02.855Z,1591723502.855 [CBIT] Loaded
2020-06-09T17:25:02.855Z,1591723502.855 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-06-09T17:25:02.856Z,1591723502.856 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-06-09T17:25:03.284Z,1591723503.284 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-06-09T17:25:03.285Z,1591723503.285 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-06-09T17:25:03.293Z,1591723503.293 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-06-09T17:25:03.294Z,1591723503.294 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B2B4E0
2020-06-09T17:25:03.294Z,1591723503.294 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 910
2020-06-09T17:25:03.299Z,1591723503.299 [Supervisor](INFO): Main Thread ID is 817
2020-06-09T17:25:03.299Z,1591723503.299 [Supervisor](DEBUG): Running supervisor.
2020-06-09T17:25:03.300Z,1591723503.300 [CommandLine ThreadHandler](INFO): Handler Thread ID is 911
2020-06-09T17:25:03.303Z,1591723503.303 [controlThread ThreadHandler](INFO): Handler Thread ID is 912
2020-06-09T17:25:03.304Z,1591723503.304 [controlThread](DEBUG): Initializing ControlThread
2020-06-09T17:25:03.305Z,1591723503.305 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-06-09T17:25:03.305Z,1591723503.305 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-06-09T17:25:03.305Z,1591723503.305 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-06-09T17:25:03.306Z,1591723503.306 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-06-09T17:25:03.306Z,1591723503.306 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-06-09T17:25:03.307Z,1591723503.307 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-06-09T17:25:03.307Z,1591723503.307 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-06-09T17:25:03.309Z,1591723503.309 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-06-09T17:25:03.310Z,1591723503.310 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-06-09T17:25:03.311Z,1591723503.311 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-06-09T17:25:03.312Z,1591723503.312 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-06-09T17:25:03.313Z,1591723503.313 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-06-09T17:25:03.326Z,1591723503.326 [logger ThreadHandler](INFO): Handler Thread ID is 913
2020-06-09T17:25:03.353Z,1591723503.353 [SBIT](INFO): Initialize SBIT Component.
2020-06-09T17:25:03.354Z,1591723503.354 [SBIT](IMPORTANT): git: 2020-06-09
2020-06-09T17:25:03.354Z,1591723503.354 [SBIT](INFO): git hash: 492c82351796d8646f9bb5d9c938bbc9efa48549
2020-06-09T17:25:03.354Z,1591723503.354 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-06-09T17:25:03.354Z,1591723503.354 [SBIT](IMPORTANT): Kernel Version:#1 PREEMPT Tue Sep 24 11:12:27 PDT 2019
2020-06-09T17:25:03.355Z,1591723503.355 [SBIT](INFO): Beginning SBIT in 59.000000 seconds.
2020-06-09T17:25:03.356Z,1591723503.356 [IBIT](INFO): Initialize IBIT Component.
2020-06-09T17:25:03.357Z,1591723503.357 [CBIT](DEBUG): Initialize CBIT Component.
2020-06-09T17:25:03.365Z,1591723503.365 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 914
2020-06-09T17:25:03.366Z,1591723503.366 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-06-09T17:25:03.369Z,1591723503.369 [CBIT](DEBUG): Initialized mux pins.
2020-06-09T17:25:03.369Z,1591723503.369 [CBIT](DEBUG): Initializing the watchdog timer.
2020-06-09T17:25:03.377Z,1591723503.377 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 915
2020-06-09T17:25:03.389Z,1591723503.389 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 916
2020-06-09T17:25:03.390Z,1591723503.390 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-06-09T17:25:03.393Z,1591723503.393 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-06-09T17:25:03.393Z,1591723503.393 [CBIT](DEBUG): Initializing heartbeat.
2020-06-09T17:25:03.395Z,1591723503.395 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 918
2020-06-09T17:25:03.396Z,1591723503.396 [WetLabsBB2FL](INFO): Powering down
2020-06-09T17:25:03.465Z,1591723503.465 [CBIT](DEBUG): Deactivating GF circuits.
2020-06-09T17:25:03.465Z,1591723503.465 [CBIT](DEBUG): Deactivating emergency mode.
2020-06-09T17:25:03.501Z,1591723503.501 [CBIT](DEBUG): Backplane powered.
2020-06-09T17:25:03.518Z,1591723503.518 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-06-09T17:25:03.519Z,1591723503.519 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 919
2020-06-09T17:25:03.627Z,1591723503.627 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-06-09T17:25:03.631Z,1591723503.631 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-06-09T17:25:03.631Z,1591723503.631 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-06-09T17:25:03.631Z,1591723503.631 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-06-09T17:25:03.632Z,1591723503.632 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-06-09T17:25:03.632Z,1591723503.632 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-06-09T17:25:03.632Z,1591723503.632 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-06-09T17:25:03.632Z,1591723503.632 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-06-09T17:25:03.632Z,1591723503.632 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-06-09T17:25:03.632Z,1591723503.632 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-06-09T17:25:03.633Z,1591723503.633 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-06-09T17:25:03.633Z,1591723503.633 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-06-09T17:25:03.633Z,1591723503.633 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-06-09T17:25:03.633Z,1591723503.633 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-06-09T17:25:03.633Z,1591723503.633 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-06-09T17:25:03.634Z,1591723503.634 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-06-09T17:25:03.634Z,1591723503.634 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-06-09T17:25:03.750Z,1591723503.750 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2020-06-09T17:25:03.751Z,1591723503.751 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-06-09T17:25:03.781Z,1591723503.781 [Radio_Surface](INFO): Powering up
2020-06-09T17:25:03.858Z,1591723503.858 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-06-09T17:25:03.859Z,1591723503.859 [Default:A.Wait](DEBUG): Construct Wait.
2020-06-09T17:25:03.860Z,1591723503.860 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-06-09T17:25:03.895Z,1591723503.895 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-06-09T17:25:03.918Z,1591723503.918 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-06-09T17:25:03.923Z,1591723503.923 [Default:E.Execute](DEBUG): Construct Execute.
2020-06-09T17:25:03.942Z,1591723503.942 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2020-06-09T17:25:03.947Z,1591723503.947 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,DVL_micro,NAL9602,Onboard,DAT,BPC1,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-09T17:25:03.955Z,1591723503.955 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-06-09T17:25:04.211Z,1591723504.211 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2020-06-09T17:25:04.211Z,1591723504.211 [DAT](INFO): Powering up
2020-06-09T17:25:04.211Z,1591723504.211 [DAT](DEBUG): Initializing DAT.
2020-06-09T17:25:04.301Z,1591723504.301 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2020-06-09T17:25:04.396Z,1591723504.396 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-06-09T17:25:04.405Z,1591723504.405 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-06-09T17:25:04.406Z,1591723504.406 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-06-09T17:25:04.417Z,1591723504.417 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-06-09T17:25:04.418Z,1591723504.418 [MassServo](DEBUG): Initializing EZServoServo.
2020-06-09T17:25:04.429Z,1591723504.429 [MassServo](DEBUG): Initializing MassServo.
2020-06-09T17:25:04.430Z,1591723504.430 [RudderServo](DEBUG): Initializing EZServoServo.
2020-06-09T17:25:04.441Z,1591723504.441 [RudderServo](DEBUG): Initializing RudderServo.
2020-06-09T17:25:04.442Z,1591723504.442 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-06-09T17:25:04.449Z,1591723504.449 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-06-09T17:25:04.523Z,1591723504.523 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2020-06-09T17:25:04.523Z,1591723504.523 [DropWeight] Hardware Fault, FailCount= 1
2020-06-09T17:25:04.523Z,1591723504.523 [DropWeight](ERROR): Hardware Fault
2020-06-09T17:25:04.568Z,1591723504.568 [CommandLine](FAULT): Scheduling is paused
2020-06-09T17:25:04.568Z,1591723504.568 [CBIT](INFO): Critical error at 20200609T172504
2020-06-09T17:25:04.568Z,1591723504.568 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2020-06-09T17:25:04.571Z,1591723504.571 [CBIT](ERROR): Hardware Fault in component: DropWeight
2020-06-09T17:25:04.571Z,1591723504.571 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2020-06-09T17:25:04.936Z,1591723504.936 [CBIT](INFO): Critical error at 20200609T172504
2020-06-09T17:25:18.687Z,1591723518.687 [DAT](INFO): commRate: 800
2020-06-09T17:25:18.687Z,1591723518.687 [DAT](INFO): commRate: 800
2020-06-09T17:25:19.110Z,1591723519.110 [DAT](INFO): entering command mode
2020-06-09T17:25:19.492Z,1591723519.492 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-09T17:25:19.907Z,1591723519.907 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-09T17:25:20.301Z,1591723520.301 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-09T17:25:20.718Z,1591723520.718 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-09T17:25:21.125Z,1591723521.125 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-09T17:25:21.513Z,1591723521.513 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-09T17:25:21.927Z,1591723521.927 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-09T17:25:22.322Z,1591723522.322 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-09T17:25:22.725Z,1591723522.725 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-09T17:25:23.137Z,1591723523.137 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-09T17:25:23.545Z,1591723523.545 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-09T17:25:23.937Z,1591723523.937 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-09T17:25:24.351Z,1591723524.351 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-09T17:25:24.754Z,1591723524.754 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-09T17:25:25.151Z,1591723525.151 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-09T17:25:25.553Z,1591723525.553 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-09T17:25:25.953Z,1591723525.953 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-09T17:25:26.360Z,1591723526.360 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-09T17:25:26.782Z,1591723526.782 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-09T17:25:27.166Z,1591723527.166 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-09T17:25:27.569Z,1591723527.569 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-09T17:25:27.977Z,1591723527.977 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-09T17:25:28.388Z,1591723528.388 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-09T17:25:28.781Z,1591723528.781 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-09T17:25:29.189Z,1591723529.189 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-09T17:25:29.589Z,1591723529.589 [NAL9602](INFO): Powering up NAL9602
2020-06-09T17:25:29.606Z,1591723529.606 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-09T17:25:29.999Z,1591723529.999 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-09T17:25:30.415Z,1591723530.415 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-09T17:25:30.801Z,1591723530.801 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-09T17:25:31.209Z,1591723531.209 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-09T17:25:31.626Z,1591723531.626 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-09T17:25:32.033Z,1591723532.033 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-09T17:25:32.417Z,1591723532.417 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-09T17:25:32.825Z,1591723532.825 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-09T17:25:33.230Z,1591723533.230 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-09T17:25:33.648Z,1591723533.648 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-09T17:25:34.055Z,1591723534.055 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-09T17:25:34.448Z,1591723534.448 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-09T17:25:34.448Z,1591723534.448 [DAT](FAULT): failed to enter command mode
2020-06-09T17:25:34.844Z,1591723534.844 [DAT](INFO): entering command mode
2020-06-09T17:25:35.257Z,1591723535.257 [DAT](INFO): setting verbose to 3
2020-06-09T17:25:35.650Z,1591723535.650 [DAT](INFO): set verbose to 3
2020-06-09T17:25:35.651Z,1591723535.651 [DAT](INFO): setting DatVerbose to 27440
2020-06-09T17:25:36.058Z,1591723536.058 [DAT](INFO): set DatVerbose to 27440
2020-06-09T17:25:36.059Z,1591723536.059 [DAT](INFO): setting transmit power to 8
2020-06-09T17:25:36.467Z,1591723536.467 [DAT](INFO): set transmit power to 8
2020-06-09T17:25:36.467Z,1591723536.467 [DAT](INFO): setting local address to 8
2020-06-09T17:25:36.888Z,1591723536.888 [DAT](INFO): set local address to 8
2020-06-09T17:25:40.483Z,1591723540.483 [NAL9602](INFO): NAL9602 initialized
2020-06-09T17:26:02.752Z,1591723562.752 [SBIT](IMPORTANT): Beginning Startup BIT
2020-06-09T17:26:02.756Z,1591723562.756 [CBIT](IMPORTANT): Beginning ground fault scan
2020-06-09T17:26:13.861Z,1591723573.861 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.004646
CHAN A1 (24V): -0.001317
CHAN A2 (12V): 0.000270
CHAN A3 (5V): 0.000152
CHAN B0 (3.3V): 0.000516
CHAN B1 (3.15aV): 0.000589
CHAN B2 (3.15bV): 0.000901
CHAN B3 (GND): 0.000891
OPEN: -0.004328
Full Scale Calc: 4.765 mA, -1.589 mA
2020-06-09T17:26:42.247Z,1591723602.247 [SBIT](FAULT): Rudder: EXPECTED:-15.000000 ACTUAL:-14.797676
2020-06-09T17:26:42.247Z,1591723602.247 [SBIT](FAULT): Control surface position failure.
2020-06-09T17:26:56.444Z,1591723616.444 [SBIT](CRITICAL): SBIT FAILED
2020-06-09T17:26:56.444Z,1591723616.444 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-06-09T17:26:56.445Z,1591723616.445 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=1 bool;

2020-06-09T17:26:56.445Z,1591723616.445 [SBIT](IMPORTANT): ESPComponent.simulateHardware=0 bool;

2020-06-09T17:26:56.445Z,1591723616.445 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=242.907926 cubic_centimeter;

2020-06-09T17:26:56.445Z,1591723616.445 [SBIT](IMPORTANT): VerticalControl.massDefault=9.890285 millimeter;

2020-06-09T17:26:56.447Z,1591723616.447 [CBIT](INFO): Critical error at 20200609T172656
2020-06-09T17:26:56.820Z,1591723616.820 [MissionManager](IMPORTANT): Started mission Startup
2020-06-09T17:26:56.820Z,1591723616.820 [Startup] Running Loop=1
2020-06-09T17:26:56.820Z,1591723616.820 [Startup](DEBUG): Aggregate::initialize Startup
2020-06-09T17:26:56.820Z,1591723616.820 [Startup:A.GoToSurface] Running Loop=1
2020-06-09T17:26:56.820Z,1591723616.820 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-06-09T17:26:56.821Z,1591723616.821 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-06-09T17:26:56.821Z,1591723616.821 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-06-09T17:26:56.822Z,1591723616.822 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-06-09T17:26:56.822Z,1591723616.822 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-06-09T17:26:56.822Z,1591723616.822 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-06-09T17:26:56.824Z,1591723616.824 [Startup:StartupSatComms] Running Loop=1
2020-06-09T17:26:56.824Z,1591723616.824 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-06-09T17:26:56.824Z,1591723616.824 [Startup:StartupSatComms:A] Running Loop=1
2020-06-09T17:26:57.239Z,1591723617.239 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-06-09T17:27:39.212Z,1591723659.212 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-09T17:27:39.212Z,1591723659.212 [DVL_micro](ERROR): Failed to parse::TS,00000000000000,35.0,+21.3,0000.0,14
2020-06-09T17:27:57.039Z,1591723677.039 [Startup:StartupSatComms:A](INFO): Timed out from 2020-06-09T17:26:56.8Z
2020-06-09T17:27:57.039Z,1591723677.039 [Startup:StartupSatComms:A] Stopped
2020-06-09T17:27:57.039Z,1591723677.039 [Startup:StartupSatComms:B] Running Loop=1
2020-06-09T17:27:57.406Z,1591723677.406 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-06-09T17:28:03.469Z,1591723683.469 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2020-06-09T17:28:03.469Z,1591723683.469 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-06-09T17:28:03.499Z,1591723683.499 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-06-09T17:28:03.887Z,1591723683.887 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-06-09T17:28:03.887Z,1591723683.887 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2020-06-09T17:28:09.743Z,1591723689.743 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004623
2020-06-09T17:28:17.121Z,1591723697.121 [DataOverHttps](INFO): Sending 264 bytes from file Logs/20200609T172451/Courier0000.lzma
2020-06-09T17:28:18.123Z,1591723698.123 [DataOverHttps](INFO): Moved sent file to Logs/20200609T172451/Courier0000.lzma.bak
2020-06-09T17:28:18.123Z,1591723698.123 [DataOverHttps](INFO): SBD MOMSN=12374541
2020-06-09T17:28:33.744Z,1591723713.744 [NAL9602](INFO): SBD MO Status=2, MOMSN=95, MT Status=2, MTMSN=0
2020-06-09T17:28:33.744Z,1591723713.744 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-06-09T17:28:33.790Z,1591723713.790 [DataOverHttps](INFO): Sending 889 bytes from file Logs/20200609T172451/Express0001.lzma
2020-06-09T17:28:34.791Z,1591723714.791 [DataOverHttps](INFO): Moved sent file to Logs/20200609T172451/Express0001.lzma.bak
2020-06-09T17:28:34.791Z,1591723714.791 [DataOverHttps](INFO): SBD MOMSN=12374547
2020-06-09T17:28:35.817Z,1591723715.817 [Startup:StartupSatComms:B] Stopped
2020-06-09T17:28:35.818Z,1591723715.818 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2020-06-09T17:28:35.818Z,1591723715.818 [Startup:StartupSatComms] Stopped
2020-06-09T17:28:35.818Z,1591723715.818 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-06-09T17:28:35.818Z,1591723715.818 [Startup](INFO): Completed Startup
2020-06-09T17:28:35.819Z,1591723715.819 [MissionManager](INFO): Startup is completed.
2020-06-09T17:28:35.819Z,1591723715.819 [MissionManager](INFO): Uninitializing Mission Startup
2020-06-09T17:28:35.819Z,1591723715.819 [Startup] Stopped
2020-06-09T17:28:35.819Z,1591723715.819 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-06-09T17:28:35.819Z,1591723715.819 [Startup:A.GoToSurface] Stopped
2020-06-09T17:28:35.819Z,1591723715.819 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-06-09T17:28:36.195Z,1591723716.195 [MissionManager](IMPORTANT): Started mission Default
2020-06-09T17:28:36.195Z,1591723716.195 [Default] Running Loop=1
2020-06-09T17:28:36.195Z,1591723716.195 [Default](DEBUG): Aggregate::initialize Default
2020-06-09T17:28:36.195Z,1591723716.195 [Default:B.GoToSurface] Running Loop=1
2020-06-09T17:28:36.195Z,1591723716.195 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-06-09T17:28:36.196Z,1591723716.196 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-06-09T17:28:36.196Z,1591723716.196 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-06-09T17:28:36.196Z,1591723716.196 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-06-09T17:28:36.196Z,1591723716.196 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-06-09T17:28:36.197Z,1591723716.197 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-06-09T17:28:36.197Z,1591723716.197 [Default:A.Wait] Running Loop=1
2020-06-09T17:28:36.197Z,1591723716.197 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-06-09T17:28:42.644Z,1591723722.644 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-09T17:28:42.644Z,1591723722.644 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,00.0,1489.0,000
2020-06-09T17:28:49.547Z,1591723729.547 [Default:A.Wait](INFO): Done Waiting.
2020-06-09T17:28:49.548Z,1591723729.548 [Default:A.Wait] Stopped
2020-06-09T17:28:49.548Z,1591723729.548 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-06-09T17:28:49.951Z,1591723729.951 [Default:CheckIn] Running Loop=1
2020-06-09T17:28:49.951Z,1591723729.951 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-06-09T17:28:49.951Z,1591723729.951 [Default:CheckIn:Read_GPS] Running Loop=1
2020-06-09T17:28:50.379Z,1591723730.379 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-06-09T17:28:57.990Z,1591723737.990 [DVL_micro](ERROR): only read 3 of 4 data items
2020-06-09T17:28:57.991Z,1591723737.991 [DVL_micro](ERROR): Failed to parse:
:BI,+02431,-00983,+01048,
2020-06-09T17:29:08.896Z,1591723748.896 [NAL9602](INFO): SBD MO Status=2, MOMSN=95, MT Status=2, MTMSN=0
2020-06-09T17:29:08.896Z,1591723748.896 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-06-09T17:29:15.781Z,1591723755.781 [DVL_micro](ERROR): only read 3 of 4 data items
2020-06-09T17:29:15.781Z,1591723755.781 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.99,+9999.99,
2020-06-09T17:29:27.072Z,1591723767.072 [NAL9602](INFO): SBD MO Status=0, MOMSN=95, MT Status=0, MTMSN=0
2020-06-09T17:29:27.072Z,1591723767.072 [NAL9602](INFO): No messages in MT queue
2020-06-09T17:29:33.544Z,1591723773.544 [DVL_micro](ERROR): Failed to parse:
:WI,+02108,-00169,+01302,+00000,A
2020-06-09T17:31:04.470Z,1591723864.470 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2020-06-09T17:31:04.470Z,1591723864.470 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-06-09T17:31:04.480Z,1591723864.480 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-06-09T17:31:04.907Z,1591723864.907 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-06-09T17:31:04.907Z,1591723864.907 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2020-06-09T17:31:27.880Z,1591723887.880 [DVL_micro](ERROR): only read 3 of 4 data items
2020-06-09T17:31:27.880Z,1591723887.880 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.99,+9999.99,+

2020-06-09T17:31:48.080Z,1591723908.080 [DVL_micro](ERROR): Failed to parse:
:SA,+00.17,-01.71,097.1
2020-06-09T17:32:03.428Z,1591723923.428 [DVL_micro](ERROR): only read 0 of 4 data items
2020-06-09T17:32:03.429Z,1591723923.429 [DVL_micro](ERROR): Failed to parse:
:RD,,+9999.99,+9999.99,+9999.99
2020-06-09T17:32:41.406Z,1591723961.406 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+21.3,0000.0,1489.0,000
2020-06-09T17:32:56.753Z,1591723976.753 [DVL_micro](ERROR): Failed to parse:
:WI,+01797,-0031,+00000,A
2020-06-09T17:33:39.190Z,1591724019.190 [BPC1](ERROR): Battery stick #6 (s/n: 00CA) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2020-06-09T17:33:39.190Z,1591724019.190 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 6.
2020-06-09T17:33:39.194Z,1591724019.194 [BPC1](INFO): Received data from all battery sticks.
2020-06-09T17:33:50.128Z,1591724030.128 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-06-09T17:28:49.0Z
2020-06-09T17:33:50.128Z,1591724030.128 [Default:CheckIn:Read_GPS] Stopped
2020-06-09T17:33:50.128Z,1591724030.128 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-06-09T17:33:50.508Z,1591724030.508 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-06-09T17:33:59.920Z,1591724039.920 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20200609T172451/Courier0004.lzma
2020-06-09T17:34:00.923Z,1591724040.923 [DataOverHttps](INFO): Moved sent file to Logs/20200609T172451/Courier0004.lzma.bak
2020-06-09T17:34:00.923Z,1591724040.923 [DataOverHttps](INFO): SBD MOMSN=12374572
2020-06-09T17:34:05.525Z,1591724045.525 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2020-06-09T17:34:05.525Z,1591724045.525 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-06-09T17:34:05.567Z,1591724045.567 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-06-09T17:34:05.967Z,1591724045.967 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-06-09T17:34:05.967Z,1591724045.967 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2020-06-09T17:34:16.656Z,1591724056.656 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20200609T172451/Express0005.lzma
2020-06-09T17:34:17.659Z,1591724057.659 [DataOverHttps](INFO): Moved sent file to Logs/20200609T172451/Express0005.lzma.bak
2020-06-09T17:34:17.659Z,1591724057.659 [DataOverHttps](INFO): SBD MOMSN=12374574
2020-06-09T17:34:18.882Z,1591724058.882 [Default:CheckIn:Read_Iridium] Stopped
2020-06-09T17:34:18.882Z,1591724058.882 [Default:CheckIn:C.Wait] Running Loop=1
2020-06-09T17:34:18.882Z,1591724058.882 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-09T17:34:25.712Z,1591724065.712 [DVL_micro](ERROR): only read 3 of 4 data items
2020-06-09T17:34:25.712Z,1591724065.712 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.99,+9999.99,
2020-06-09T17:34:34.190Z,1591724074.190 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173433.00,A,3648.14381,N,12147.24537,W,2.177,88.20,090620,,,A*41
2020-06-09T17:34:34.193Z,1591724074.193 [NAL9602](INFO): GPS fix at 20200609T173433: (36.802397, -121.787423)
2020-06-09T17:34:43.480Z,1591724083.480 [DVL_micro](ERROR): Failed to parse:
:T00000000,35.0,+21.4,0000.0,1489.0,000
2020-06-09T17:35:06.510Z,1591724106.510 [NAL9602](INFO): Not Powering down - fast GPS
2020-06-09T17:35:36.099Z,1591724136.099 [CommandLine](IMPORTANT): got command ibit
2020-06-09T17:35:36.439Z,1591724136.439 [IBIT](IMPORTANT): Beginning Initiated BIT
2020-06-09T17:35:36.439Z,1591724136.439 [IBIT](IMPORTANT): Beginning control surface checks.
2020-06-09T17:35:36.442Z,1591724136.442 [CBIT](IMPORTANT): Beginning ground fault scan
2020-06-09T17:35:38.020Z,1591724138.020 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173537.00,A,3648.14447,N,12147.22843,W,0.019,64.28,090620,,,A*40
2020-06-09T17:35:38.023Z,1591724138.023 [NAL9602](INFO): GPS fix at 20200609T173537: (36.802408, -121.787141)
2020-06-09T17:35:38.845Z,1591724138.845 [CommandLine](IMPORTANT): got command failComponent
2020-06-09T17:35:38.845Z,1591724138.845 [CommandLine](IMPORTANT): Failed components:
2020-06-09T17:35:38.845Z,1591724138.845 [CommandLine](IMPORTANT): DropWeight: Hardware Fault
2020-06-09T17:35:44.494Z,1591724144.494 [DVL_micro](ERROR): Failed to parse:
:T00000000,35.0,+21.4,0000.0,1489.0,000
2020-06-09T17:35:47.382Z,1591724147.382 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.004419
CHAN A1 (24V): -0.001267
CHAN A2 (12V): -0.001122
CHAN A3 (5V): 0.001129
CHAN B0 (3.3V): 0.000324
CHAN B1 (3.15aV): 0.000263
CHAN B2 (3.15bV): 0.001283
CHAN B3 (GND): 0.001108
OPEN: 0.003734
Full Scale Calc: 4.765 mA, -1.589 mA
2020-06-09T17:36:07.223Z,1591724167.223 [IBIT](FAULT): Rudder: EXPECTED:-15.000000 ACTUAL:-14.797676
2020-06-09T17:36:07.223Z,1591724167.223 [IBIT](FAULT): Control surface position failure.
2020-06-09T17:36:08.720Z,1591724168.720 [NAL9602](INFO): SBD MO Status=2, MOMSN=96, MT Status=2, MTMSN=0
2020-06-09T17:36:08.720Z,1591724168.720 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-06-09T17:36:22.903Z,1591724182.903 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 3
Latitude: 36.802406 Longitude: -121.787140
2020-06-09T17:36:23.312Z,1591724183.312 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.413000
2020-06-09T17:36:23.312Z,1591724183.312 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2020-06-09T17:36:23.312Z,1591724183.312 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2020-06-09T17:36:23.706Z,1591724183.706 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2020-06-09T17:36:23.707Z,1591724183.707 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2020-06-09T17:36:23.707Z,1591724183.707 [IBIT](IMPORTANT): Pressure:7.534908 PSI
2020-06-09T17:36:23.707Z,1591724183.707 [IBIT](IMPORTANT): Humidity:10.218533 %
2020-06-09T17:36:24.108Z,1591724184.108 [IBIT](IMPORTANT): Vehicle Pitch:-1.857460 degrees
2020-06-09T17:36:24.109Z,1591724184.109 [IBIT](IMPORTANT): Vehicle  Roll:-2.665115 degrees
2020-06-09T17:36:24.109Z,1591724184.109 [IBIT](IMPORTANT): Vehicle  Heading:209.555176 degrees
2020-06-09T17:36:24.511Z,1591724184.511 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2020-06-09T17:36:24.511Z,1591724184.511 [IBIT](IMPORTANT): buoyancyNeutral: 242.907928 cc
2020-06-09T17:36:24.512Z,1591724184.512 [IBIT](IMPORTANT): massDefault: 0.989028 cm
2020-06-09T17:36:24.512Z,1591724184.512 [IBIT](IMPORTANT): stopDepth: 275.000000 m
2020-06-09T17:36:24.512Z,1591724184.512 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2020-06-09T17:36:24.512Z,1591724184.512 [IBIT](IMPORTANT): IBIT FAILED
2020-06-09T17:36:29.732Z,1591724189.732 [NAL9602](INFO): SBD MO Status=0, MOMSN=96, MT Status=0, MTMSN=0
2020-06-09T17:36:29.732Z,1591724189.732 [NAL9602](INFO): No messages in MT queue
2020-06-09T17:37:00.439Z,1591724220.439 [NAL9602](INFO): Not Powering down - fast GPS
2020-06-09T17:38:04.267Z,1591724284.267 [DVL_micro](ERROR): only read 3 of 4 data items
2020-06-09T17:38:04.267Z,1591724284.267 [DVL_micro](ERROR): Failed to parse:
:RD,+99999.99,+9999.99,+9999.99
2020-06-09T17:39:19.445Z,1591724359.445 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-09T17:39:19.445Z,1591724359.445 [Default:CheckIn:C.Wait] Stopped
2020-06-09T17:39:19.445Z,1591724359.445 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-09T17:39:19.445Z,1591724359.445 [Default:CheckIn:D] Running Loop=1
2020-06-09T17:39:19.845Z,1591724359.845 [Default:CheckIn:D] Stopped
2020-06-09T17:39:19.845Z,1591724359.845 [Default:CheckIn:E] Running Loop=1
2020-06-09T17:39:20.270Z,1591724360.270 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.727500 min 
2020-06-09T17:39:20.270Z,1591724360.270 [Default:CheckIn:E] Stopped
2020-06-09T17:39:20.270Z,1591724360.270 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-06-09T17:39:20.270Z,1591724360.270 [Default:CheckIn] Stopped
2020-06-09T17:39:20.270Z,1591724360.270 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-06-09T17:39:20.271Z,1591724360.271 [Default:CheckIn](INFO): Running loop #2
2020-06-09T17:39:20.271Z,1591724360.271 [Default:CheckIn] Running Loop=2
2020-06-09T17:39:20.271Z,1591724360.271 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-06-09T17:39:20.271Z,1591724360.271 [Default:CheckIn:Read_GPS] Running Loop=1
2020-06-09T17:39:22.236Z,1591724362.236 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173921.00,A,3648.14389,N,12147.22769,W,0.039,64.28,090620,,,D*4E
2020-06-09T17:39:22.238Z,1591724362.238 [NAL9602](INFO): GPS fix at 20200609T173921: (36.802398, -121.787128)
2020-06-09T17:39:22.274Z,1591724362.274 [Default:CheckIn:Read_GPS] Stopped
2020-06-09T17:39:22.274Z,1591724362.274 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-06-09T17:39:29.848Z,1591724369.848 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20200609T172451/Courier0007.lzma
2020-06-09T17:39:30.851Z,1591724370.851 [DataOverHttps](INFO): Moved sent file to Logs/20200609T172451/Courier0007.lzma.bak
2020-06-09T17:39:30.851Z,1591724370.851 [DataOverHttps](INFO): SBD MOMSN=12374579
2020-06-09T17:39:32.744Z,1591724372.744 [NAL9602](INFO): SBD MO Status=0, MOMSN=97, MT Status=0, MTMSN=0
2020-06-09T17:39:32.744Z,1591724372.744 [NAL9602](INFO): No messages in MT queue
2020-06-09T17:39:46.966Z,1591724386.966 [DataOverHttps](INFO): Sending 1001 bytes from file Logs/20200609T172451/Express0008.lzma
2020-06-09T17:39:47.966Z,1591724387.966 [DataOverHttps](INFO): Moved sent file to Logs/20200609T172451/Express0008.lzma.bak
2020-06-09T17:39:47.967Z,1591724387.967 [DataOverHttps](INFO): SBD MOMSN=12374582
2020-06-09T17:39:49.327Z,1591724389.327 [Default:CheckIn:Read_Iridium] Stopped
2020-06-09T17:39:49.327Z,1591724389.327 [Default:CheckIn:C.Wait] Running Loop=1
2020-06-09T17:39:49.327Z,1591724389.327 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-09T17:40:03.461Z,1591724403.461 [NAL9602](INFO): Not Powering down - fast GPS
2020-06-09T17:40:04.686Z,1591724404.686 [CBIT](INFO): Clearing failed state for component DropWeight
2020-06-09T17:40:04.686Z,1591724404.686 [DropWeight] No Fault, FailCount= 1
2020-06-09T17:40:18.795Z,1591724418.795 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-09T17:40:18.795Z,1591724418.795 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+21.51489.0,000
2020-06-09T17:41:52.935Z,1591724512.935 [DVL_micro](ERROR): Failed to parse:
:BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00
2020-06-09T17:44:05.045Z,1591724645.045 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-09T17:44:05.045Z,1591724645.045 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+210,1489.0,,+00491,-1772,+00000,A
2020-06-09T17:44:49.906Z,1591724689.906 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-09T17:44:49.906Z,1591724689.906 [Default:CheckIn:C.Wait] Stopped
2020-06-09T17:44:49.906Z,1591724689.906 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-09T17:44:49.906Z,1591724689.906 [Default:CheckIn:D] Running Loop=1
2020-06-09T17:44:50.319Z,1591724690.319 [Default:CheckIn:D] Stopped
2020-06-09T17:44:50.320Z,1591724690.320 [Default:CheckIn:E] Running Loop=1
2020-06-09T17:44:50.740Z,1591724690.740 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 16.235405 min 
2020-06-09T17:44:50.740Z,1591724690.740 [Default:CheckIn:E] Stopped
2020-06-09T17:44:50.740Z,1591724690.740 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-06-09T17:44:50.740Z,1591724690.740 [Default:CheckIn] Stopped
2020-06-09T17:44:50.740Z,1591724690.740 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-06-09T17:44:50.740Z,1591724690.740 [Default:CheckIn](INFO): Running loop #3
2020-06-09T17:44:50.740Z,1591724690.740 [Default:CheckIn] Running Loop=3
2020-06-09T17:44:50.740Z,1591724690.740 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-06-09T17:44:50.741Z,1591724690.741 [Default:CheckIn:Read_GPS] Running Loop=1
2020-06-09T17:44:52.716Z,1591724692.716 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174452.00,A,3648.14220,N,12147.22985,W,0.019,64.28,090620,,,D*4C
2020-06-09T17:44:52.718Z,1591724692.718 [NAL9602](INFO): GPS fix at 20200609T174452: (36.802370, -121.787164)
2020-06-09T17:44:52.767Z,1591724692.767 [Default:CheckIn:Read_GPS] Stopped
2020-06-09T17:44:52.767Z,1591724692.767 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-06-09T17:45:00.320Z,1591724700.320 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20200609T172451/Courier0010.lzma
2020-06-09T17:45:01.323Z,1591724701.323 [DataOverHttps](INFO): Moved sent file to Logs/20200609T172451/Courier0010.lzma.bak
2020-06-09T17:45:01.323Z,1591724701.323 [DataOverHttps](INFO): SBD MOMSN=12374618
2020-06-09T17:45:09.680Z,1591724709.680 [NAL9602](INFO): SBD MO Status=0, MOMSN=98, MT Status=0, MTMSN=0
2020-06-09T17:45:09.681Z,1591724709.681 [NAL9602](INFO): No messages in MT queue
2020-06-09T17:45:17.500Z,1591724717.500 [DataOverHttps](INFO): Sending 127 bytes from file Logs/20200609T172451/Express0011.lzma
2020-06-09T17:45:18.503Z,1591724718.503 [DataOverHttps](INFO): Moved sent file to Logs/20200609T172451/Express0011.lzma.bak
2020-06-09T17:45:18.503Z,1591724718.503 [DataOverHttps](INFO): SBD MOMSN=12374621
2020-06-09T17:45:19.809Z,1591724719.809 [Default:CheckIn:Read_Iridium] Stopped
2020-06-09T17:45:19.809Z,1591724719.809 [Default:CheckIn:C.Wait] Running Loop=1
2020-06-09T17:45:19.810Z,1591724719.810 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-09T17:45:40.386Z,1591724740.386 [NAL9602](INFO): Not Powering down - fast GPS
2020-06-09T17:45:41.600Z,1591724741.600 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-09T17:45:41.600Z,1591724741.600 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+21.7,489.0,000
2020-06-09T17:47:53.710Z,1591724873.710 [DVL_micro](ERROR): only read 3 of 4 data items
2020-06-09T17:47:53.710Z,1591724873.710 [DVL_micro](ERROR): Failed to parse:
:BI,+01763,-0268,+00000,I
2020-06-09T17:48:13.908Z,1591724893.908 [DVL_micro](ERROR): Failed to parse:
:BD,+0000+0000000000000.00,000.00
2020-06-09T17:48:29.261Z,1591724909.261 [DVL_micro](ERROR): DVL BIT error. See manual. Result code: -3039
2020-06-09T17:48:29.263Z,1591724909.263 [DVL_micro](ERROR): Failed to parse:
:BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00
2020-06-09T17:50:20.385Z,1591725020.385 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-09T17:50:20.385Z,1591725020.385 [Default:CheckIn:C.Wait] Stopped
2020-06-09T17:50:20.385Z,1591725020.385 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-09T17:50:20.385Z,1591725020.385 [Default:CheckIn:D] Running Loop=1
2020-06-09T17:50:20.798Z,1591725020.798 [Default:CheckIn:D] Stopped
2020-06-09T17:50:20.798Z,1591725020.798 [Default:CheckIn:E] Running Loop=1
2020-06-09T17:50:21.196Z,1591725021.196 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.743374 min 
2020-06-09T17:50:21.196Z,1591725021.196 [Default:CheckIn:E] Stopped
2020-06-09T17:50:21.197Z,1591725021.197 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-06-09T17:50:21.197Z,1591725021.197 [Default:CheckIn] Stopped
2020-06-09T17:50:21.197Z,1591725021.197 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-06-09T17:50:21.197Z,1591725021.197 [Default:CheckIn](INFO): Running loop #4
2020-06-09T17:50:21.197Z,1591725021.197 [Default:CheckIn] Running Loop=4
2020-06-09T17:50:21.197Z,1591725021.197 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-06-09T17:50:21.197Z,1591725021.197 [Default:CheckIn:Read_GPS] Running Loop=1
2020-06-09T17:50:23.188Z,1591725023.188 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175022.00,A,3648.14233,N,12147.22941,W,0.039,64.28,090620,,,D*46
2020-06-09T17:50:23.190Z,1591725023.190 [NAL9602](INFO): GPS fix at 20200609T175022: (36.802372, -121.787157)
2020-06-09T17:50:23.247Z,1591725023.247 [Default:CheckIn:Read_GPS] Stopped
2020-06-09T17:50:23.247Z,1591725023.247 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-06-09T17:50:30.784Z,1591725030.784 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20200609T172451/Courier0013.lzma
2020-06-09T17:50:31.787Z,1591725031.787 [DataOverHttps](INFO): Moved sent file to Logs/20200609T172451/Courier0013.lzma.bak
2020-06-09T17:50:31.787Z,1591725031.787 [DataOverHttps](INFO): SBD MOMSN=12374625
2020-06-09T17:50:37.728Z,1591725037.728 [NAL9602](INFO): SBD MO Status=0, MOMSN=99, MT Status=0, MTMSN=0
2020-06-09T17:50:37.728Z,1591725037.728 [NAL9602](INFO): No messages in MT queue
2020-06-09T17:50:47.905Z,1591725047.905 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20200609T172451/Express0014.lzma
2020-06-09T17:50:48.911Z,1591725048.911 [DataOverHttps](INFO): Moved sent file to Logs/20200609T172451/Express0014.lzma.bak
2020-06-09T17:50:48.911Z,1591725048.911 [DataOverHttps](INFO): SBD MOMSN=12374628
2020-06-09T17:50:50.281Z,1591725050.281 [Default:CheckIn:Read_Iridium] Stopped
2020-06-09T17:50:50.281Z,1591725050.281 [Default:CheckIn:C.Wait] Running Loop=1
2020-06-09T17:50:50.281Z,1591725050.281 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-09T17:51:08.428Z,1591725068.428 [NAL9602](INFO): Not Powering down - fast GPS
2020-06-09T17:51:24.596Z,1591725084.596 [DVL_micro](ERROR): Failed to parse:
:WI,+0741,+00860,+

2020-06-09T17:51:42.367Z,1591725102.367 [DVL_micro](ERROR): DVL BIT error. See manual. Result code: -3905
2020-06-09T17:51:42.380Z,1591725102.380 [DVL_micro](ERROR): Failed to parse:
:BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00
2020-06-09T17:52:00.148Z,1591725120.148 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-09T17:52:00.148Z,1591725120.148 [DVL_micro](ERROR): Failed to parse:
:TS0000000,30,0000.0000
2020-06-09T17:53:36.704Z,1591725216.704 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-09T17:53:36.704Z,1591725216.704 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+22.,1489.0,000
2020-06-09T17:54:32.460Z,1591725272.460 [DVL_micro](ERROR): only read 3 of 4 data items
2020-06-09T17:54:32.460Z,1591725272.460 [DVL_micro](ERROR): Failed to parse:
:BI,+01504,-03100,+0110,I
2020-06-09T17:55:48.812Z,1591725348.812 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-09T17:55:48.812Z,1591725348.812 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+220,1489.0,000
2020-06-09T17:55:50.853Z,1591725350.853 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-09T17:55:50.853Z,1591725350.853 [Default:CheckIn:C.Wait] Stopped
2020-06-09T17:55:50.853Z,1591725350.853 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-09T17:55:50.853Z,1591725350.853 [Default:CheckIn:D] Running Loop=1
2020-06-09T17:55:51.267Z,1591725351.267 [Default:CheckIn:D] Stopped
2020-06-09T17:55:51.268Z,1591725351.268 [Default:CheckIn:E] Running Loop=1
2020-06-09T17:55:51.668Z,1591725351.668 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 27.251204 min 
2020-06-09T17:55:51.668Z,1591725351.668 [Default:CheckIn:E] Stopped
2020-06-09T17:55:51.668Z,1591725351.668 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-06-09T17:55:51.668Z,1591725351.668 [Default:CheckIn] Stopped
2020-06-09T17:55:51.668Z,1591725351.668 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-06-09T17:55:51.669Z,1591725351.669 [Default:CheckIn](INFO): Running loop #5
2020-06-09T17:55:51.669Z,1591725351.669 [Default:CheckIn] Running Loop=5
2020-06-09T17:55:51.669Z,1591725351.669 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-06-09T17:55:51.669Z,1591725351.669 [Default:CheckIn:Read_GPS] Running Loop=1
2020-06-09T17:55:53.686Z,1591725353.686 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175552.00,A,3648.14257,N,12147.22768,W,0.019,64.28,090620,,,D*41
2020-06-09T17:55:53.688Z,1591725353.688 [NAL9602](INFO): GPS fix at 20200609T175552: (36.802376, -121.787128)
2020-06-09T17:55:53.733Z,1591725353.733 [Default:CheckIn:Read_GPS] Stopped
2020-06-09T17:55:53.734Z,1591725353.734 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-06-09T17:56:01.012Z,1591725361.012 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20200609T172451/Courier0016.lzma
2020-06-09T17:56:02.015Z,1591725362.015 [DataOverHttps](INFO): Moved sent file to Logs/20200609T172451/Courier0016.lzma.bak
2020-06-09T17:56:02.015Z,1591725362.015 [DataOverHttps](INFO): SBD MOMSN=12374636
2020-06-09T17:56:06.584Z,1591725366.584 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-09T17:56:06.595Z,1591725366.595 [DVL_micro](ERROR): Failed to parse:
:TS0000000,35.0,+22.2,0000.0,1489.0,000
2020-06-09T17:56:07.392Z,1591725367.392 [NAL9602](INFO): SBD MO Status=0, MOMSN=100, MT Status=0, MTMSN=0
2020-06-09T17:56:07.392Z,1591725367.392 [NAL9602](INFO): No messages in MT queue
2020-06-09T17:56:18.052Z,1591725378.052 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20200609T172451/Express0017.lzma
2020-06-09T17:56:19.056Z,1591725379.056 [DataOverHttps](INFO): Moved sent file to Logs/20200609T172451/Express0017.lzma.bak
2020-06-09T17:56:19.057Z,1591725379.057 [DataOverHttps](INFO): SBD MOMSN=12374639
2020-06-09T17:56:20.346Z,1591725380.346 [Default:CheckIn:Read_Iridium] Stopped
2020-06-09T17:56:20.346Z,1591725380.346 [Default:CheckIn:C.Wait] Running Loop=1
2020-06-09T17:56:20.346Z,1591725380.346 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-09T17:56:38.094Z,1591725398.094 [NAL9602](INFO): Not Powering down - fast GPS
2020-06-09T17:58:44.157Z,1591725524.157 [DVL_micro](ERROR): only read 3 of 4 data items
2020-06-09T17:58:44.157Z,1591725524.157 [DVL_micro](ERROR): Failed to parse:
:BI,+02119,-0278,+00000,I
2020-06-09T18:00:02.926Z,1591725602.926 [DVL_micro](ERROR): only read 1 of 4 data items
2020-06-09T18:00:02.926Z,1591725602.926 [DVL_micro](ERROR): Failed to parse:
:BI,+01429+01172,+00000,I
2020-06-09T18:00:23.126Z,1591725623.126 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-09T18:00:23.126Z,1591725623.126 [DVL_micro](ERROR): Failed to parse:
:TS,000000,35.0,+22.4,0000.0,1489:WI,+01977+00488,+

2020-06-09T18:00:38.481Z,1591725638.481 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-09T18:00:38.481Z,1591725638.481 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+22.4,0000.0,1489.0,,+03584,-0752,+00000,A
2020-06-09T18:01:20.927Z,1591725680.927 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-09T18:01:20.927Z,1591725680.927 [Default:CheckIn:C.Wait] Stopped
2020-06-09T18:01:20.927Z,1591725680.927 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-09T18:01:20.927Z,1591725680.927 [Default:CheckIn:D] Running Loop=1
2020-06-09T18:01:21.356Z,1591725681.356 [Default:CheckIn:D] Stopped
2020-06-09T18:01:21.357Z,1591725681.357 [Default:CheckIn:E] Running Loop=1
2020-06-09T18:01:21.747Z,1591725681.747 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 32.752688 min 
2020-06-09T18:01:21.747Z,1591725681.747 [Default:CheckIn:E] Stopped
2020-06-09T18:01:21.747Z,1591725681.747 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-06-09T18:01:21.747Z,1591725681.747 [Default:CheckIn] Stopped
2020-06-09T18:01:21.747Z,1591725681.747 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-06-09T18:01:21.748Z,1591725681.747 [Default:CheckIn](INFO): Running loop #6
2020-06-09T18:01:21.748Z,1591725681.748 [Default:CheckIn] Running Loop=6
2020-06-09T18:01:21.748Z,1591725681.748 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-06-09T18:01:21.748Z,1591725681.748 [Default:CheckIn:Read_GPS] Running Loop=1
2020-06-09T18:01:23.729Z,1591725683.729 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180123.00,A,3648.14267,N,12147.22793,W,0.000,64.28,090620,,,D*46
2020-06-09T18:01:23.731Z,1591725683.731 [NAL9602](INFO): GPS fix at 20200609T180123: (36.802378, -121.787132)
2020-06-09T18:01:23.755Z,1591725683.755 [Default:CheckIn:Read_GPS] Stopped
2020-06-09T18:01:23.755Z,1591725683.755 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-06-09T18:01:31.072Z,1591725691.072 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200609T172451/Courier0019.lzma
2020-06-09T18:01:32.075Z,1591725692.075 [DataOverHttps](INFO): Moved sent file to Logs/20200609T172451/Courier0019.lzma.bak
2020-06-09T18:01:32.075Z,1591725692.075 [DataOverHttps](INFO): SBD MOMSN=12374646
2020-06-09T18:01:40.292Z,1591725700.292 [NAL9602](INFO): SBD MO Status=0, MOMSN=101, MT Status=0, MTMSN=0
2020-06-09T18:01:40.292Z,1591725700.292 [NAL9602](INFO): No messages in MT queue
2020-06-09T18:01:48.128Z,1591725708.128 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20200609T172451/Express0020.lzma
2020-06-09T18:01:49.131Z,1591725709.131 [DataOverHttps](INFO): Moved sent file to Logs/20200609T172451/Express0020.lzma.bak
2020-06-09T18:01:49.131Z,1591725709.131 [DataOverHttps](INFO): SBD MOMSN=12374654
2020-06-09T18:01:50.416Z,1591725710.416 [Default:CheckIn:Read_Iridium] Stopped
2020-06-09T18:01:50.416Z,1591725710.416 [Default:CheckIn:C.Wait] Running Loop=1
2020-06-09T18:01:50.416Z,1591725710.416 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-09T18:02:10.998Z,1591725730.998 [NAL9602](INFO): Not Powering down - fast GPS
2020-06-09T18:02:50.588Z,1591725770.588 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-09T18:02:50.588Z,1591725770.588 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+22.5,0000.0,10
2020-06-09T18:05:05.129Z,1591725905.129 [DVL_micro](ERROR): Failed to parse:
:SA,-02.13,-02.92,201.3
2020-06-09T18:05:20.478Z,1591725920.478 [DVL_micro](ERROR): only read 3 of 4 data items
2020-06-09T18:05:20.478Z,1591725920.478 [DVL_micro](ERROR): Failed to parse:
:BI,+02626,-0201,+00000,I
2020-06-09T18:06:31.576Z,1591725991.576 [DVL_micro](ERROR): Failed to parse:
:0000000022.6,0000.0,1489.0,000
2020-06-09T18:06:49.362Z,1591726009.362 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-09T18:06:49.362Z,1591726009.362 [DVL_micro](ERROR): Failed to parse:
:TS,000000,35.0,+22.,1489.0,000
2020-06-09T18:06:50.999Z,1591726010.999 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-09T18:06:50.999Z,1591726010.999 [Default:CheckIn:C.Wait] Stopped
2020-06-09T18:06:50.999Z,1591726010.999 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-09T18:06:50.999Z,1591726010.999 [Default:CheckIn:D] Running Loop=1
2020-06-09T18:06:51.402Z,1591726011.402 [Default:CheckIn:D] Stopped
2020-06-09T18:06:51.402Z,1591726011.402 [Default:CheckIn:E] Running Loop=1
2020-06-09T18:06:51.828Z,1591726011.828 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 38.253438 min 
2020-06-09T18:06:51.828Z,1591726011.828 [Default:CheckIn:E] Stopped
2020-06-09T18:06:51.828Z,1591726011.828 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-06-09T18:06:51.828Z,1591726011.828 [Default:CheckIn] Stopped
2020-06-09T18:06:51.829Z,1591726011.829 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-06-09T18:06:51.829Z,1591726011.829 [Default:CheckIn](INFO): Running loop #7
2020-06-09T18:06:51.829Z,1591726011.829 [Default:CheckIn] Running Loop=7
2020-06-09T18:06:51.829Z,1591726011.829 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-06-09T18:06:51.829Z,1591726011.829 [Default:CheckIn:Read_GPS] Running Loop=1
2020-06-09T18:06:53.792Z,1591726013.792 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180653.00,A,3648.14195,N,12147.22838,W,0.039,64.28,090620,,,D*4C
2020-06-09T18:06:53.794Z,1591726013.794 [NAL9602](INFO): GPS fix at 20200609T180653: (36.802366, -121.787140)
2020-06-09T18:06:53.819Z,1591726013.819 [Default:CheckIn:Read_GPS] Stopped
2020-06-09T18:06:53.819Z,1591726013.819 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-06-09T18:07:01.104Z,1591726021.104 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200609T172451/Courier0022.lzma
2020-06-09T18:07:02.107Z,1591726022.107 [DataOverHttps](INFO): Moved sent file to Logs/20200609T172451/Courier0022.lzma.bak
2020-06-09T18:07:02.107Z,1591726022.107 [DataOverHttps](INFO): SBD MOMSN=12374658
2020-06-09T18:07:07.142Z,1591726027.142 [NAL9602](INFO): SBD MO Status=0, MOMSN=102, MT Status=0, MTMSN=0
2020-06-09T18:07:07.142Z,1591726027.142 [NAL9602](INFO): No messages in MT queue
2020-06-09T18:07:18.184Z,1591726038.184 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20200609T172451/Express0023.lzma
2020-06-09T18:07:19.187Z,1591726039.187 [DataOverHttps](INFO): Moved sent file to Logs/20200609T172451/Express0023.lzma.bak
2020-06-09T18:07:19.187Z,1591726039.187 [DataOverHttps](INFO): SBD MOMSN=12374662
2020-06-09T18:07:20.479Z,1591726040.479 [Default:CheckIn:Read_Iridium] Stopped
2020-06-09T18:07:20.479Z,1591726040.479 [Default:CheckIn:C.Wait] Running Loop=1
2020-06-09T18:07:20.479Z,1591726040.479 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-09T18:07:37.834Z,1591726057.834 [NAL9602](INFO): Not Powering down - fast GPS
2020-06-09T18:11:23.674Z,1591726283.674 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-09T18:11:23.674Z,1591726283.674 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+22.81489.0,0+04752,-0577,+00000,A
2020-06-09T18:11:41.444Z,1591726301.444 [DVL_micro](ERROR): Failed to parse:
:WI,+00485,-02741,+01496,+00000,A
2020-06-09T18:12:01.647Z,1591726321.647 [DVL_micro](ERROR): Failed to parse:99.99,+9999.99,+9999.99,+9999.99
2020-06-09T18:12:19.418Z,1591726339.418 [DVL_micro](ERROR): Failed to parse:
:BI,+03540,00790,+0:BD,+0000+00000000.00,+00000000.00,9999.99,000.00
2020-06-09T18:12:21.076Z,1591726341.076 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-09T18:12:21.076Z,1591726341.076 [Default:CheckIn:C.Wait] Stopped
2020-06-09T18:12:21.076Z,1591726341.076 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-09T18:12:21.076Z,1591726341.076 [Default:CheckIn:D] Running Loop=1
2020-06-09T18:12:21.462Z,1591726341.462 [Default:CheckIn:D] Stopped
2020-06-09T18:12:21.462Z,1591726341.462 [Default:CheckIn:E] Running Loop=1
2020-06-09T18:12:21.889Z,1591726341.889 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 43.754451 min 
2020-06-09T18:12:21.889Z,1591726341.889 [Default:CheckIn:E] Stopped
2020-06-09T18:12:21.889Z,1591726341.889 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-06-09T18:12:21.890Z,1591726341.890 [Default:CheckIn] Stopped
2020-06-09T18:12:21.890Z,1591726341.890 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-06-09T18:12:21.890Z,1591726341.890 [Default:CheckIn](INFO): Running loop #8
2020-06-09T18:12:21.890Z,1591726341.890 [Default:CheckIn] Running Loop=8
2020-06-09T18:12:21.890Z,1591726341.890 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-06-09T18:12:21.890Z,1591726341.890 [Default:CheckIn:Read_GPS] Running Loop=1
2020-06-09T18:12:23.858Z,1591726343.858 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181223.00,A,3648.14156,N,12147.22776,W,0.000,64.28,090620,,,D*4E
2020-06-09T18:12:23.860Z,1591726343.860 [NAL9602](INFO): GPS fix at 20200609T181223: (36.802359, -121.787129)
2020-06-09T18:12:23.900Z,1591726343.900 [Default:CheckIn:Read_GPS] Stopped
2020-06-09T18:12:23.900Z,1591726343.900 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-06-09T18:12:31.168Z,1591726351.168 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200609T172451/Courier0025.lzma
2020-06-09T18:12:32.171Z,1591726352.171 [DataOverHttps](INFO): Moved sent file to Logs/20200609T172451/Courier0025.lzma.bak
2020-06-09T18:12:32.171Z,1591726352.171 [DataOverHttps](INFO): SBD MOMSN=12374673
2020-06-09T18:12:34.776Z,1591726354.776 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-09T18:12:34.777Z,1591726354.777 [DVL_micro](ERROR): Failed to parse:
:TS,000000000.0,+22.9,0000.0,1489.0,000
2020-06-09T18:12:39.210Z,1591726359.210 [NAL9602](INFO): SBD MO Status=0, MOMSN=103, MT Status=0, MTMSN=0
2020-06-09T18:12:39.210Z,1591726359.210 [NAL9602](INFO): No messages in MT queue
2020-06-09T18:12:48.228Z,1591726368.228 [DataOverHttps](INFO): Sending 134 bytes from file Logs/20200609T172451/Express0026.lzma
2020-06-09T18:12:49.231Z,1591726369.231 [DataOverHttps](INFO): Moved sent file to Logs/20200609T172451/Express0026.lzma.bak
2020-06-09T18:12:49.231Z,1591726369.231 [DataOverHttps](INFO): SBD MOMSN=12374676
2020-06-09T18:12:50.559Z,1591726370.559 [Default:CheckIn:Read_Iridium] Stopped
2020-06-09T18:12:50.559Z,1591726370.559 [Default:CheckIn:C.Wait] Running Loop=1
2020-06-09T18:12:50.559Z,1591726370.559 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-09T18:12:52.549Z,1591726372.549 [DVL_micro](ERROR): Failed to parse:
:BI,+02124,-02625,+01209,+00000,I
2020-06-09T18:13:09.912Z,1591726389.912 [NAL9602](INFO): Not Powering down - fast GPS
2020-06-09T18:13:10.320Z,1591726390.320 [DVL_micro](ERROR): Failed to parse:
:T00000000,35.0,+22.9,0000.0,1489.0,000
2020-06-09T18:13:30.534Z,1591726410.534 [DVL_micro](ERROR): Failed to parse:
:SA,-02.12,-02.89,201.2
2020-06-09T18:14:03.713Z,1591726443.713 [DVL_micro](ERROR): DVL uart error: serial timeout
2020-06-09T18:14:03.713Z,1591726443.713 [DVL_micro] Communications Fault, FailCount= 1
2020-06-09T18:14:03.713Z,1591726443.713 [DVL_micro](ERROR): Communications Fault
2020-06-09T18:14:03.713Z,1591726443.713 [DVL_micro](ERROR): Failed to parse:
2020-06-09T18:14:03.803Z,1591726443.803 [CBIT](ERROR): Communications Fault in component: DVL_micro
2020-06-09T18:14:04.121Z,1591726444.121 [DVL_micro](INFO): Powering down
2020-06-09T18:14:04.934Z,1591726444.934 [CBIT](INFO): Clearing failed state for component DVL_micro
2020-06-09T18:14:04.934Z,1591726444.934 [DVL_micro] No Fault, FailCount= 1
2020-06-09T18:16:55.390Z,1591726615.390 [DVL_micro](ERROR): DVL BIT error. See manual. Result code: 3352
2020-06-09T18:16:55.392Z,1591726615.392 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.99,+9999.99,+9999.99
2020-06-09T18:17:51.188Z,1591726671.188 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-09T18:17:51.188Z,1591726671.188 [Default:CheckIn:C.Wait] Stopped
2020-06-09T18:17:51.188Z,1591726671.188 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-09T18:17:51.188Z,1591726671.188 [Default:CheckIn:D] Running Loop=1
2020-06-09T18:17:51.574Z,1591726671.574 [Default:CheckIn:D] Stopped
2020-06-09T18:17:51.574Z,1591726671.574 [Default:CheckIn:E] Running Loop=1
2020-06-09T18:17:51.965Z,1591726671.965 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 49.256307 min 
2020-06-09T18:17:51.965Z,1591726671.965 [Default:CheckIn:E] Stopped
2020-06-09T18:17:51.965Z,1591726671.965 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-06-09T18:17:51.965Z,1591726671.965 [Default:CheckIn] Stopped
2020-06-09T18:17:51.965Z,1591726671.965 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-06-09T18:17:51.966Z,1591726671.966 [Default:CheckIn](INFO): Running loop #9
2020-06-09T18:17:51.966Z,1591726671.966 [Default:CheckIn] Running Loop=9
2020-06-09T18:17:51.966Z,1591726671.966 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-06-09T18:17:51.966Z,1591726671.966 [Default:CheckIn:Read_GPS] Running Loop=1
2020-06-09T18:17:53.972Z,1591726673.972 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181753.00,A,3648.14210,N,12147.22989,W,0.019,64.28,090620,,,D*4B
2020-06-09T18:17:53.979Z,1591726673.979 [NAL9602](INFO): GPS fix at 20200609T181753: (36.802368, -121.787165)
2020-06-09T18:17:54.042Z,1591726674.042 [Default:CheckIn:Read_GPS] Stopped
2020-06-09T18:17:54.042Z,1591726674.042 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-06-09T18:18:04.672Z,1591726684.672 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200609T172451/Courier0028.lzma
2020-06-09T18:18:05.675Z,1591726685.675 [DataOverHttps](INFO): Moved sent file to Logs/20200609T172451/Courier0028.lzma.bak
2020-06-09T18:18:05.675Z,1591726685.675 [DataOverHttps](INFO): SBD MOMSN=12374681
2020-06-09T18:18:21.913Z,1591726701.913 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20200609T172451/Express0029.lzma
2020-06-09T18:18:22.720Z,1591726702.720 [NAL9602](INFO): SBD MO Status=0, MOMSN=104, MT Status=0, MTMSN=0
2020-06-09T18:18:22.720Z,1591726702.720 [NAL9602](INFO): No messages in MT queue
2020-06-09T18:18:22.915Z,1591726702.915 [DataOverHttps](INFO): Moved sent file to Logs/20200609T172451/Express0029.lzma.bak
2020-06-09T18:18:22.915Z,1591726702.915 [DataOverHttps](INFO): SBD MOMSN=12374684
2020-06-09T18:18:23.972Z,1591726703.972 [Default:CheckIn:Read_Iridium] Stopped
2020-06-09T18:18:23.972Z,1591726703.972 [Default:CheckIn:C.Wait] Running Loop=1
2020-06-09T18:18:23.972Z,1591726703.972 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-09T18:18:53.426Z,1591726733.426 [NAL9602](INFO): Not Powering down - fast GPS
2020-06-09T18:19:01.189Z,1591726741.189 [CommandLine](IMPORTANT): got command failComponent
2020-06-09T18:19:01.189Z,1591726741.189 [CommandLine](IMPORTANT): Failed components:
2020-06-09T18:19:01.189Z,1591726741.189 [CommandLine](IMPORTANT): No failed Components.
2020-06-09T18:19:51.481Z,1591726791.481 [CommandLine](IMPORTANT): got command ibit
2020-06-09T18:19:51.646Z,1591726791.646 [IBIT](IMPORTANT): Beginning Initiated BIT
2020-06-09T18:19:51.646Z,1591726791.646 [IBIT](IMPORTANT): Beginning control surface checks.
2020-06-09T18:19:51.649Z,1591726791.649 [CBIT](IMPORTANT): Beginning ground fault scan
2020-06-09T18:19:53.223Z,1591726793.223 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181952.00,A,3648.14282,N,12147.22757,W,0.039,0.00,090620,,,D*78
2020-06-09T18:19:53.225Z,1591726793.225 [NAL9602](INFO): GPS fix at 20200609T181952: (36.802380, -121.787126)
2020-06-09T18:19:58.071Z,1591726798.071 [DVL_micro](ERROR): DVL BIT error. See manual. Result code: -1569
2020-06-09T18:19:58.076Z,1591726798.076 [DVL_micro](ERROR): Failed to parse:
:BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00
2020-06-09T18:20:02.593Z,1591726802.593 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.005442
CHAN A1 (24V): -0.001129
CHAN A2 (12V): -0.000036
CHAN A3 (5V): 0.000216
CHAN B0 (3.3V): 0.000421
CHAN B1 (3.15aV): 0.000556
CHAN B2 (3.15bV): 0.000194
CHAN B3 (GND): 0.001140
OPEN: 0.003654
Full Scale Calc: 4.765 mA, -1.589 mA
2020-06-09T18:20:13.424Z,1591726813.424 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-09T18:20:13.424Z,1591726813.424 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000.,000
2020-06-09T18:20:17.868Z,1591726817.868 [NAL9602](INFO): SBD MO Status=0, MOMSN=105, MT Status=0, MTMSN=0
2020-06-09T18:20:17.868Z,1591726817.868 [NAL9602](INFO): No messages in MT queue
2020-06-09T18:20:19.081Z,1591726819.081 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182018.00,A,3648.14237,N,12147.22749,W,0.058,0.00,090620,,,D*7A
2020-06-09T18:20:19.083Z,1591726819.083 [NAL9602](INFO): GPS fix at 20200609T182018: (36.802373, -121.787125)
2020-06-09T18:20:21.903Z,1591726821.903 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182021.00,A,3648.14244,N,12147.22743,W,0.019,0.00,090620,,,D*7B
2020-06-09T18:20:21.905Z,1591726821.905 [NAL9602](INFO): GPS fix at 20200609T182021: (36.802374, -121.787124)
2020-06-09T18:20:22.031Z,1591726822.031 [IBIT](FAULT): Rudder: EXPECTED:-15.000000 ACTUAL:-14.797676
2020-06-09T18:20:22.031Z,1591726822.031 [IBIT](FAULT): Control surface position failure.
2020-06-09T18:20:25.134Z,1591726825.134 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182024.00,A,3648.14248,N,12147.22730,W,0.078,0.00,090620,,,D*71
2020-06-09T18:20:25.136Z,1591726825.136 [NAL9602](INFO): GPS fix at 20200609T182024: (36.802375, -121.787122)
2020-06-09T18:20:27.968Z,1591726827.968 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182027.00,A,3648.14245,N,12147.22722,W,0.039,0.00,090620,,,D*79
2020-06-09T18:20:27.977Z,1591726827.977 [NAL9602](INFO): GPS fix at 20200609T182027: (36.802374, -121.787120)
2020-06-09T18:20:31.204Z,1591726831.204 [DVL_micro](ERROR): only read 2 of 4 data items
2020-06-09T18:20:31.204Z,1591726831.204 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.99,,+9999.99
2020-06-09T18:20:31.207Z,1591726831.207 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182030.00,A,3648.14243,N,12147.22709,W,0.039,0.00,090620,,,D*70
2020-06-09T18:20:31.248Z,1591726831.248 [NAL9602](INFO): GPS fix at 20200609T182030: (36.802374, -121.787118)
2020-06-09T18:20:34.034Z,1591726834.034 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182033.00,A,3648.14243,N,12147.22693,W,0.019,0.00,090620,,,D*73
2020-06-09T18:20:34.036Z,1591726834.036 [NAL9602](INFO): GPS fix at 20200609T182033: (36.802374, -121.787115)
2020-06-09T18:20:36.873Z,1591726836.873 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182036.00,A,3648.14240,N,12147.22681,W,0.058,0.00,090620,,,D*73
2020-06-09T18:20:36.875Z,1591726836.875 [NAL9602](INFO): GPS fix at 20200609T182036: (36.802373, -121.787114)
2020-06-09T18:20:37.697Z,1591726837.697 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 5
Latitude: 36.802372 Longitude: -121.787117
2020-06-09T18:20:38.126Z,1591726838.126 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.334000
2020-06-09T18:20:38.126Z,1591726838.126 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2020-06-09T18:20:38.126Z,1591726838.126 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2020-06-09T18:20:38.519Z,1591726838.519 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2020-06-09T18:20:38.519Z,1591726838.519 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2020-06-09T18:20:38.519Z,1591726838.519 [IBIT](IMPORTANT): Pressure:7.695700 PSI
2020-06-09T18:20:38.520Z,1591726838.520 [IBIT](IMPORTANT): Humidity:9.131974 %
2020-06-09T18:20:38.925Z,1591726838.925 [IBIT](IMPORTANT): Vehicle Pitch:-1.976417 degrees
2020-06-09T18:20:38.925Z,1591726838.925 [IBIT](IMPORTANT): Vehicle  Roll:-2.650438 degrees
2020-06-09T18:20:38.925Z,1591726838.925 [IBIT](IMPORTANT): Vehicle  Heading:208.626495 degrees
2020-06-09T18:20:39.325Z,1591726839.325 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2020-06-09T18:20:39.326Z,1591726839.326 [IBIT](IMPORTANT): buoyancyNeutral: 242.907928 cc
2020-06-09T18:20:39.326Z,1591726839.326 [IBIT](IMPORTANT): massDefault: 0.989028 cm
2020-06-09T18:20:39.326Z,1591726839.326 [IBIT](IMPORTANT): stopDepth: 275.000000 m
2020-06-09T18:20:39.327Z,1591726839.327 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2020-06-09T18:20:39.327Z,1591726839.327 [IBIT](IMPORTANT): IBIT FAILED
2020-06-09T18:21:09.574Z,1591726869.574 [NAL9602](INFO): Not Powering down - fast GPS
2020-06-09T18:21:24.536Z,1591726884.536 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-09T18:21:24.537Z,1591726884.537 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+23.,1489.0,000
2020-06-09T18:22:17.858Z,1591726937.858 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-09T18:22:17.858Z,1591726937.858 [DVL_micro](ERROR): Failed to parse:
:TS,000000005.0,+23.21489.0,0001622,-02925,+01277,+00000,A
2020-06-09T18:22:35.636Z,1591726955.636 [DVL_micro](ERROR): only read 0 of 4 data items
2020-06-09T18:22:35.636Z,1591726955.636 [DVL_micro](ERROR): Failed to parse:
:RD9,+9999..99,+9999.99
2020-06-09T18:22:55.841Z,1591726975.841 [DVL_micro](ERROR): Failed to parse:
:SA,-02.18,-02.88,200.6
2020-06-09T18:23:24.547Z,1591727004.547 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-09T18:23:24.547Z,1591727004.547 [Default:CheckIn:C.Wait] Stopped
2020-06-09T18:23:24.547Z,1591727004.547 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-09T18:23:24.547Z,1591727004.547 [Default:CheckIn:D] Running Loop=1
2020-06-09T18:23:24.948Z,1591727004.948 [Default:CheckIn:D] Stopped
2020-06-09T18:23:24.948Z,1591727004.948 [Default:CheckIn:E] Running Loop=1
2020-06-09T18:23:25.343Z,1591727005.343 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 54.812549 min 
2020-06-09T18:23:25.343Z,1591727005.343 [Default:CheckIn:E] Stopped
2020-06-09T18:23:25.343Z,1591727005.343 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-06-09T18:23:25.343Z,1591727005.343 [Default:CheckIn] Stopped
2020-06-09T18:23:25.343Z,1591727005.343 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-06-09T18:23:25.343Z,1591727005.343 [Default:CheckIn](INFO): Running loop #10
2020-06-09T18:23:25.343Z,1591727005.343 [Default:CheckIn] Running Loop=10
2020-06-09T18:23:25.344Z,1591727005.344 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-06-09T18:23:25.344Z,1591727005.344 [Default:CheckIn:Read_GPS] Running Loop=1
2020-06-09T18:23:27.344Z,1591727007.344 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182326.00,A,3648.14246,N,12147.22874,W,0.019,0.00,090620,,,D*76
2020-06-09T18:23:27.354Z,1591727007.354 [NAL9602](INFO): GPS fix at 20200609T182326: (36.802374, -121.787146)
2020-06-09T18:23:27.385Z,1591727007.385 [Default:CheckIn:Read_GPS] Stopped
2020-06-09T18:23:27.385Z,1591727007.385 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-06-09T18:23:34.724Z,1591727014.724 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20200609T172451/Courier0031.lzma
2020-06-09T18:23:35.727Z,1591727015.727 [DataOverHttps](INFO): Moved sent file to Logs/20200609T172451/Courier0031.lzma.bak
2020-06-09T18:23:35.727Z,1591727015.727 [DataOverHttps](INFO): SBD MOMSN=12374697
2020-06-09T18:23:39.060Z,1591727019.060 [DVL_micro](ERROR): only read 3 of 4 data items
2020-06-09T18:23:39.061Z,1591727019.061 [DVL_micro](ERROR): Failed to parse:
:RD,+99999.99,+9999.99,+9999.99
2020-06-09T18:23:45.536Z,1591727025.536 [NAL9602](INFO): SBD MO Status=0, MOMSN=106, MT Status=0, MTMSN=0
2020-06-09T18:23:45.536Z,1591727025.536 [NAL9602](INFO): No messages in MT queue
2020-06-09T18:23:51.750Z,1591727031.750 [DataOverHttps](INFO): Sending 854 bytes from file Logs/20200609T172451/Express0032.lzma
2020-06-09T18:23:52.751Z,1591727032.751 [DataOverHttps](INFO): Moved sent file to Logs/20200609T172451/Express0032.lzma.bak
2020-06-09T18:23:52.751Z,1591727032.751 [DataOverHttps](INFO): SBD MOMSN=12374700
2020-06-09T18:23:54.058Z,1591727034.058 [Default:CheckIn:Read_Iridium] Stopped
2020-06-09T18:23:54.058Z,1591727034.058 [Default:CheckIn:C.Wait] Running Loop=1
2020-06-09T18:23:54.058Z,1591727034.058 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-09T18:24:16.220Z,1591727056.220 [NAL9602](INFO): Not Powering down - fast GPS
2020-06-09T18:26:01.267Z,1591727161.267 [DVL_micro](ERROR): Failed to parse:00000000000,.4,0000.,000
2020-06-09T18:27:39.101Z,1591727259.101 [CommandLine](IMPORTANT): got command ibit
2020-06-09T18:27:39.479Z,1591727259.479 [IBIT](IMPORTANT): Beginning Initiated BIT
2020-06-09T18:27:39.479Z,1591727259.479 [IBIT](IMPORTANT): Beginning control surface checks.
2020-06-09T18:27:39.482Z,1591727259.482 [CBIT](IMPORTANT): Beginning ground fault scan
2020-06-09T18:27:40.248Z,1591727260.248 [DVL_micro](ERROR): Failed to parse:
:WI,+01703,-03017,+01319,+00000,A
2020-06-09T18:27:41.067Z,1591727261.067 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182740.00,A,3648.14230,N,12147.22852,W,0.019,0.00,090620,,,D*77
2020-06-09T18:27:41.069Z,1591727261.069 [NAL9602](INFO): GPS fix at 20200609T182740: (36.802372, -121.787142)
2020-06-09T18:27:50.465Z,1591727270.465 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.004804
CHAN A1 (24V): -0.001291
CHAN A2 (12V): 0.000328
CHAN A3 (5V): 0.000640
CHAN B0 (3.3V): 0.000333
CHAN B1 (3.15aV): 0.001216
CHAN B2 (3.15bV): 0.000262
CHAN B3 (GND): 0.000240
OPEN: 0.003575
Full Scale Calc: 4.765 mA, -1.589 mA
2020-06-09T18:27:52.822Z,1591727272.822 [NAL9602](INFO): SBD MO Status=0, MOMSN=107, MT Status=0, MTMSN=0
2020-06-09T18:27:52.822Z,1591727272.822 [NAL9602](INFO): No messages in MT queue
2020-06-09T18:27:54.012Z,1591727274.012 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182753.00,A,3648.14231,N,12147.22829,W,0.156,0.00,090620,,,D*72
2020-06-09T18:27:54.023Z,1591727274.023 [NAL9602](INFO): GPS fix at 20200609T182753: (36.802372, -121.787138)
2020-06-09T18:27:57.241Z,1591727277.241 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182756.00,A,3648.14228,N,12147.22815,W,0.058,0.00,090620,,,D*7F
2020-06-09T18:27:57.243Z,1591727277.243 [NAL9602](INFO): GPS fix at 20200609T182756: (36.802371, -121.787136)
2020-06-09T18:28:00.078Z,1591727280.078 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182759.00,A,3648.14239,N,12147.22800,W,0.039,0.00,090620,,,D*73
2020-06-09T18:28:00.081Z,1591727280.081 [NAL9602](INFO): GPS fix at 20200609T182759: (36.802373, -121.787133)
2020-06-09T18:28:01.383Z,1591727281.383 [CommandLine](IMPORTANT): got command get platform_orientation degree
2020-06-09T18:28:01.383Z,1591727281.383 [CommandLine](IMPORTANT): platform_orientation 209.123221 arcdeg
2020-06-09T18:28:02.913Z,1591727282.913 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182802.00,A,3648.14242,N,12147.22788,W,0.039,0.00,090620,,,D*71
2020-06-09T18:28:02.915Z,1591727282.915 [NAL9602](INFO): GPS fix at 20200609T182802: (36.802374, -121.787131)
2020-06-09T18:28:06.140Z,1591727286.140 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182805.00,A,3648.14258,N,12147.22775,W,0.136,0.00,090620,,,D*71
2020-06-09T18:28:06.147Z,1591727286.147 [NAL9602](INFO): GPS fix at 20200609T182805: (36.802376, -121.787129)
2020-06-09T18:28:08.961Z,1591727288.961 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182808.00,A,3648.14272,N,12147.22767,W,0.078,0.00,090620,,,D*7C
2020-06-09T18:28:08.963Z,1591727288.963 [NAL9602](INFO): GPS fix at 20200609T182808: (36.802379, -121.787128)
2020-06-09T18:28:09.786Z,1591727289.786 [CommandLine](IMPORTANT): got command failComponent
2020-06-09T18:28:09.786Z,1591727289.786 [CommandLine](IMPORTANT): Failed components:
2020-06-09T18:28:09.786Z,1591727289.786 [CommandLine](IMPORTANT): No failed Components.
2020-06-09T18:28:12.187Z,1591727292.187 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182811.00,A,3648.14275,N,12147.22764,W,0.000,0.00,090620,,,D*7F
2020-06-09T18:28:12.189Z,1591727292.189 [NAL9602](INFO): GPS fix at 20200609T182811: (36.802379, -121.787127)
2020-06-09T18:28:15.015Z,1591727295.015 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182814.00,A,3648.14277,N,12147.22764,W,0.039,0.00,090620,,,D*72
2020-06-09T18:28:15.018Z,1591727295.018 [NAL9602](INFO): GPS fix at 20200609T182814: (36.802380, -121.787127)
2020-06-09T18:28:17.848Z,1591727297.848 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182817.00,A,3648.14287,N,12147.22760,W,0.039,0.00,090620,,,D*7A
2020-06-09T18:28:17.855Z,1591727297.855 [NAL9602](INFO): GPS fix at 20200609T182817: (36.802381, -121.787127)
2020-06-09T18:28:21.081Z,1591727301.081 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182820.00,A,3648.14295,N,12147.22760,W,0.000,0.00,090620,,,D*77
2020-06-09T18:28:21.083Z,1591727301.083 [NAL9602](INFO): GPS fix at 20200609T182820: (36.802383, -121.787127)
2020-06-09T18:28:23.908Z,1591727303.908 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182823.00,A,3648.14309,N,12147.22760,W,0.058,0.00,090620,,,D*7D
2020-06-09T18:28:23.910Z,1591727303.910 [NAL9602](INFO): GPS fix at 20200609T182823: (36.802385, -121.787127)
2020-06-09T18:28:25.595Z,1591727305.595 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 5
Latitude: 36.802383 Longitude: -121.787125
2020-06-09T18:28:26.006Z,1591727306.006 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.334000
2020-06-09T18:28:26.007Z,1591727306.007 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2020-06-09T18:28:26.007Z,1591727306.007 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2020-06-09T18:28:26.380Z,1591727306.380 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2020-06-09T18:28:26.380Z,1591727306.380 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2020-06-09T18:28:26.385Z,1591727306.385 [IBIT](IMPORTANT): Pressure:7.720073 PSI
2020-06-09T18:28:26.385Z,1591727306.385 [IBIT](IMPORTANT): Humidity:9.083140 %
2020-06-09T18:28:26.772Z,1591727306.772 [IBIT](IMPORTANT): Vehicle Pitch:-2.005106 degrees
2020-06-09T18:28:26.772Z,1591727306.772 [IBIT](IMPORTANT): Vehicle  Roll:-2.632378 degrees
2020-06-09T18:28:26.772Z,1591727306.772 [IBIT](IMPORTANT): Vehicle  Heading:209.111618 degrees
2020-06-09T18:28:27.173Z,1591727307.173 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2020-06-09T18:28:27.174Z,1591727307.174 [IBIT](IMPORTANT): buoyancyNeutral: 242.907928 cc
2020-06-09T18:28:27.174Z,1591727307.174 [IBIT](IMPORTANT): massDefault: 0.989028 cm
2020-06-09T18:28:27.174Z,1591727307.174 [IBIT](IMPORTANT): stopDepth: 275.000000 m
2020-06-09T18:28:27.174Z,1591727307.174 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2020-06-09T18:28:27.174Z,1591727307.174 [IBIT](IMPORTANT): IBIT PASSED
2020-06-09T18:28:54.640Z,1591727334.640 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-09T18:28:54.640Z,1591727334.640 [Default:CheckIn:C.Wait] Stopped
2020-06-09T18:28:54.640Z,1591727334.640 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-09T18:28:54.640Z,1591727334.640 [Default:CheckIn:D] Running Loop=1
2020-06-09T18:28:55.033Z,1591727335.033 [Default:CheckIn:D] Stopped
2020-06-09T18:28:55.033Z,1591727335.033 [Default:CheckIn:E] Running Loop=1
2020-06-09T18:28:55.443Z,1591727335.443 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 60.313969 min 
2020-06-09T18:28:55.443Z,1591727335.443 [Default:CheckIn:E] Stopped
2020-06-09T18:28:55.443Z,1591727335.443 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-06-09T18:28:55.443Z,1591727335.443 [Default:CheckIn] Stopped
2020-06-09T18:28:55.443Z,1591727335.443 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-06-09T18:28:55.443Z,1591727335.443 [Default:CheckIn](INFO): Running loop #11
2020-06-09T18:28:55.444Z,1591727335.444 [Default:CheckIn] Running Loop=11
2020-06-09T18:28:55.444Z,1591727335.444 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-06-09T18:28:55.444Z,1591727335.444 [Default:CheckIn:Read_GPS] Running Loop=1
2020-06-09T18:28:57.437Z,1591727337.437 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182856.00,A,3648.14344,N,12147.22791,W,0.019,0.00,090620,,,D*7D
2020-06-09T18:28:57.439Z,1591727337.439 [NAL9602](INFO): GPS fix at 20200609T182856: (36.802391, -121.787132)
2020-06-09T18:28:57.493Z,1591727337.493 [Default:CheckIn:Read_GPS] Stopped
2020-06-09T18:28:57.493Z,1591727337.493 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-06-09T18:29:04.880Z,1591727344.880 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20200609T172451/Courier0034.lzma
2020-06-09T18:29:05.883Z,1591727345.883 [DataOverHttps](INFO): Moved sent file to Logs/20200609T172451/Courier0034.lzma.bak
2020-06-09T18:29:05.883Z,1591727345.883 [DataOverHttps](INFO): SBD MOMSN=12374732
2020-06-09T18:29:21.814Z,1591727361.814 [DataOverHttps](INFO): Sending 839 bytes from file Logs/20200609T172451/Express0035.lzma
2020-06-09T18:29:22.815Z,1591727362.815 [DataOverHttps](INFO): Moved sent file to Logs/20200609T172451/Express0035.lzma.bak
2020-06-09T18:29:22.815Z,1591727362.815 [DataOverHttps](IMPORTANT): SBD MOMSN=12374736, MTMSN=20200609T182921
2020-06-09T18:29:24.157Z,1591727364.157 [Default:CheckIn:Read_Iridium] Stopped
2020-06-09T18:29:24.157Z,1591727364.157 [Default:CheckIn:C.Wait] Running Loop=1
2020-06-09T18:29:24.157Z,1591727364.157 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-09T18:29:29.360Z,1591727369.360 [NAL9602](INFO): Not Powering down - fast GPS
2020-06-09T18:29:30.217Z,1591727370.217 [DataOverHttps](INFO): Received command:restart app
2020-06-09T18:29:30.255Z,1591727370.255 [CommandLine](IMPORTANT): got command restart application
2020-06-09T18:29:31.265Z,1591727371.265 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-06-09T18:29:31.266Z,1591727371.266 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-06-09T18:29:31.349Z,1591727371.349 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-06-09T18:29:31.349Z,1591727371.349 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-06-09T18:29:31.350Z,1591727371.350 [CommandLine](INFO): Join timeout helper Thread ID is 992
2020-06-09T18:29:31.350Z,1591727371.350 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-06-09T18:29:31.350Z,1591727371.350 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-06-09T18:29:31.351Z,1591727371.351 [NavChartDb](INFO): Join timeout helper Thread ID is 993
2020-06-09T18:29:31.649Z,1591727371.649 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-06-09T18:29:31.649Z,1591727371.649 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-06-09T18:29:31.657Z,1591727371.657 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2020-06-09T18:29:31.657Z,1591727371.657 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-06-09T18:29:31.657Z,1591727371.657 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 994
2020-06-09T18:29:32.145Z,1591727372.145 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2020-06-09T18:29:32.146Z,1591727372.146 [WetLabsBB2FL](INFO): Powering down
2020-06-09T18:29:32.146Z,1591727372.146 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-06-09T18:29:32.157Z,1591727372.157 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2020-06-09T18:29:32.157Z,1591727372.157 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-06-09T18:29:32.157Z,1591727372.157 [CTD_Seabird](INFO): Join timeout helper Thread ID is 995
2020-06-09T18:29:32.449Z,1591727372.449 [CTD_Seabird](INFO): Powering down
2020-06-09T18:29:32.461Z,1591727372.461 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2020-06-09T18:29:32.461Z,1591727372.461 [CTD_Seabird](INFO): Powering down
2020-06-09T18:29:32.473Z,1591727372.473 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-06-09T18:29:32.481Z,1591727372.481 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-06-09T18:29:32.481Z,1591727372.481 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-06-09T18:29:32.481Z,1591727372.481 [Radio_Surface](INFO): Join timeout helper Thread ID is 996
2020-06-09T18:29:32.741Z,1591727372.741 [Radio_Surface](INFO): Powering down
2020-06-09T18:29:32.742Z,1591727372.742 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-06-09T18:29:32.742Z,1591727372.742 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-06-09T18:29:32.754Z,1591727372.754 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-06-09T18:29:32.754Z,1591727372.754 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-06-09T18:29:32.754Z,1591727372.754 [DataOverHttps](INFO): Join timeout helper Thread ID is 997
2020-06-09T18:29:33.225Z,1591727373.225 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-06-09T18:29:33.225Z,1591727373.225 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-06-09T18:29:33.238Z,1591727373.238 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-06-09T18:29:33.238Z,1591727373.238 [logger ThreadHandler](INFO): Thread cancelled.
2020-06-09T18:29:33.238Z,1591727373.238 [logger](INFO): Join timeout helper Thread ID is 998
2020-06-09T18:29:33.281Z,1591727373.281 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-06-09T18:29:33.281Z,1591727373.281 [logger ThreadHandler](INFO): Thread cancelled.
2020-06-09T18:29:33.298Z,1591727373.298 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-06-09T18:29:33.298Z,1591727373.298 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-06-09T18:29:33.298Z,1591727373.298 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-06-09T18:29:33.298Z,1591727373.298 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-06-09T18:29:33.298Z,1591727373.298 [controlThread](INFO): Join timeout helper Thread ID is 999
2020-06-09T18:29:33.377Z,1591727373.377 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-06-09T18:29:33.377Z,1591727373.377 [controlThread](DEBUG): Uninitializing ControlThread
2020-06-09T18:29:33.378Z,1591727373.378 [AHRS_M2](INFO): Powering down
2020-06-09T18:29:33.521Z,1591727373.521 [DVL_micro](INFO): Powering down
2020-06-09T18:29:33.522Z,1591727373.522 [NAL9602](INFO): Powering down
2020-06-09T18:29:33.523Z,1591727373.523 [DAT](INFO): Powering down
2020-06-09T18:29:33.661Z,1591727373.661 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-06-09T18:29:33.662Z,1591727373.662 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-06-09T18:29:33.662Z,1591727373.662 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-06-09T18:29:33.663Z,1591727373.663 [MissionManager](INFO): Uninitializing Mission Default
2020-06-09T18:29:33.663Z,1591727373.663 [Default] Stopped
2020-06-09T18:29:33.663Z,1591727373.663 [Default](DEBUG): Aggregate::uninitialize Default
2020-06-09T18:29:33.663Z,1591727373.663 [Default:B.GoToSurface] Stopped
2020-06-09T18:29:33.663Z,1591727373.663 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-06-09T18:29:33.663Z,1591727373.663 [Default:CheckIn] Stopped
2020-06-09T18:29:33.663Z,1591727373.663 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-06-09T18:29:33.663Z,1591727373.663 [Default:CheckIn:C.Wait] Stopped
2020-06-09T18:29:33.663Z,1591727373.663 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-09T18:29:33.666Z,1591727373.666 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-06-09T18:29:33.666Z,1591727373.666 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-06-09T18:29:33.666Z,1591727373.666 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-06-09T18:29:33.666Z,1591727373.666 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-06-09T18:29:33.667Z,1591727373.667 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-06-09T18:29:33.667Z,1591727373.667 [BuoyancyServo](INFO): Powering down
2020-06-09T18:29:33.681Z,1591727373.681 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-06-09T18:29:33.681Z,1591727373.681 [ElevatorServo](INFO): Powering down
2020-06-09T18:29:33.682Z,1591727373.682 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-06-09T18:29:33.682Z,1591727373.682 [MassServo](INFO): Powering down
2020-06-09T18:29:33.683Z,1591727373.683 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-06-09T18:29:33.683Z,1591727373.683 [RudderServo](INFO): Powering down
2020-06-09T18:29:33.684Z,1591727373.684 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-06-09T18:29:33.684Z,1591727373.684 [ThrusterServo](INFO): Powering down
2020-06-09T18:29:33.685Z,1591727373.685 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-06-09T18:29:33.685Z,1591727373.685 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-06-09T18:29:33.685Z,1591727373.685 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-06-09T18:29:33.685Z,1591727373.685 [CBIT](DEBUG): Powering off loads.
2020-06-09T18:29:33.697Z,1591727373.697 [CBIT](DEBUG): Disabling WDT.
2020-06-09T18:29:33.709Z,1591727373.709 [CBIT](DEBUG): Opening all GF detection circuits.
2020-06-09T18:29:33.710Z,1591727373.710 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-06-09T18:29:33.790Z,1591727373.790 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-06-09T18:29:33.798Z,1591727373.798 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-06-09T18:29:33.858Z,1591727373.858 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-06-09T18:29:33.863Z,1591727373.863 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-06-09T18:29:33.889Z,1591727373.889 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-06-09T18:29:33.960Z,1591727373.960 [logger ThreadHandler](INFO): Thread cancelled.