2020-09-29T18:02:33.074Z,1601402553.074 [Supervisor](DEBUG): Initializing supervisor.
2020-09-29T18:02:33.076Z,1601402553.076 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-09-29T18:02:33.077Z,1601402553.077 [SyncHandler](INFO): Protected caller Thread ID is 1767
2020-09-29T18:02:33.077Z,1601402553.077 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-09-29T18:02:33.078Z,1601402553.078 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-09-29T18:02:33.079Z,1601402553.079 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1768
2020-09-29T18:02:33.081Z,1601402553.081 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-09-29T18:02:33.095Z,1601402553.095 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-09-29T18:02:33.096Z,1601402553.096 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-09-29T18:02:33.097Z,1601402553.097 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1769
2020-09-29T18:02:33.098Z,1601402553.098 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-09-29T18:02:33.098Z,1601402553.098 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-09-29T18:02:33.099Z,1601402553.099 [logger ThreadHandler](INFO): Protected caller Thread ID is 1770
2020-09-29T18:02:33.101Z,1601402553.101 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-09-29T18:02:33.101Z,1601402553.101 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-09-29T18:02:33.103Z,1601402553.103 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-09-29T18:02:33.514Z,1601402553.514 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-09-29T18:02:33.515Z,1601402553.515 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-09-29T18:02:33.610Z,1601402553.610 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-09-29T18:02:33.611Z,1601402553.611 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-09-29T18:02:33.906Z,1601402553.906 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-09-29T18:02:33.907Z,1601402553.907 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-09-29T18:02:34.043Z,1601402554.043 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-09-29T18:02:34.043Z,1601402554.043 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-09-29T18:02:34.224Z,1601402554.224 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-09-29T18:02:34.225Z,1601402554.225 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-09-29T18:02:34.657Z,1601402554.657 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-09-29T18:02:34.658Z,1601402554.658 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-09-29T18:02:34.859Z,1601402554.859 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-09-29T18:02:34.859Z,1601402554.859 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-09-29T18:02:34.000Z,1601402555.000 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-09-29T18:02:35.000Z,1601402555.000 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-09-29T18:02:35.178Z,1601402555.178 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-09-29T18:02:35.178Z,1601402555.178 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-09-29T18:02:35.274Z,1601402555.274 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-09-29T18:02:35.274Z,1601402555.274 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2020-09-29T18:02:35.463Z,1601402555.463 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2020-09-29T18:02:35.463Z,1601402555.463 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-09-29T18:02:35.748Z,1601402555.748 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-09-29T18:02:35.749Z,1601402555.749 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-09-29T18:02:35.829Z,1601402555.829 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-09-29T18:02:35.930Z,1601402555.930 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-09-29T18:02:35.931Z,1601402555.931 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-09-29T18:02:37.062Z,1601402557.062 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-09-29T18:02:37.063Z,1601402557.063 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-09-29T18:02:37.458Z,1601402557.458 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-09-29T18:02:37.460Z,1601402557.460 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2020-09-29T18:02:37.461Z,1601402557.461 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2020-09-29T18:02:37.656Z,1601402557.656 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2020-09-29T18:02:37.752Z,1601402557.752 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2020-09-29T18:02:37.847Z,1601402557.847 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2020-09-29T18:02:38.071Z,1601402558.071 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-09-29T18:02:38.071Z,1601402558.071 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2020-09-29T18:02:38.155Z,1601402558.155 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2020-09-29T18:02:38.249Z,1601402558.249 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2020-09-29T18:02:38.353Z,1601402558.353 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2020-09-29T18:02:38.437Z,1601402558.437 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Dock.cfg
2020-09-29T18:02:38.522Z,1601402558.522 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2020-09-29T18:02:38.628Z,1601402558.628 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/workSite.cfg
2020-09-29T18:02:38.720Z,1601402558.720 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2020-09-29T18:02:38.898Z,1601402558.898 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2020-09-29T18:02:39.031Z,1601402559.031 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-09-29T18:02:39.039Z,1601402559.039 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-09-29T18:02:39.360Z,1601402559.360 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-09-29T18:02:39.365Z,1601402559.365 [AHRS_M2](INFO): created writer for : platform_orientation
2020-09-29T18:02:39.366Z,1601402559.366 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-09-29T18:02:39.371Z,1601402559.371 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-09-29T18:02:39.372Z,1601402559.372 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-09-29T18:02:39.377Z,1601402559.377 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-09-29T18:02:39.377Z,1601402559.377 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-09-29T18:02:39.382Z,1601402559.382 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-09-29T18:02:39.450Z,1601402559.450 [AHRS_M2] Loaded
2020-09-29T18:02:39.451Z,1601402559.451 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-09-29T18:02:39.526Z,1601402559.526 [DataOverHttps] Loaded
2020-09-29T18:02:39.526Z,1601402559.526 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-09-29T18:02:39.527Z,1601402559.527 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4077A4E0
2020-09-29T18:02:39.528Z,1601402559.528 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1858
2020-09-29T18:02:39.559Z,1601402559.559 [DDM] Loaded
2020-09-29T18:02:39.559Z,1601402559.559 [ComponentRegistry](DEBUG): SyncComponent "DDM" handled in the control thread.
2020-09-29T18:02:39.588Z,1601402559.588 [Depth_Keller] Loaded
2020-09-29T18:02:39.588Z,1601402559.588 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-09-29T18:02:39.663Z,1601402559.663 [DUSBL_Hydroid] Loaded
2020-09-29T18:02:39.663Z,1601402559.663 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2020-09-29T18:02:39.725Z,1601402559.725 [Micromodem] Loaded
2020-09-29T18:02:39.725Z,1601402559.725 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2020-09-29T18:02:39.832Z,1601402559.832 [NAL9602] Loaded
2020-09-29T18:02:39.832Z,1601402559.832 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-09-29T18:02:39.877Z,1601402559.877 [Onboard] Loaded
2020-09-29T18:02:39.878Z,1601402559.878 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2020-09-29T18:02:39.879Z,1601402559.879 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 407AA4E0
2020-09-29T18:02:39.879Z,1601402559.879 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1859
2020-09-29T18:02:39.885Z,1601402559.885 [PowerOnly](INFO): Adding load control power supply at /dev/loadC1
2020-09-29T18:02:39.905Z,1601402559.905 [PowerOnly] Loaded
2020-09-29T18:02:39.905Z,1601402559.905 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2020-09-29T18:02:39.926Z,1601402559.926 [Radio_Surface] Loaded
2020-09-29T18:02:39.927Z,1601402559.927 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-09-29T18:02:39.928Z,1601402559.928 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407DA4E0
2020-09-29T18:02:39.928Z,1601402559.928 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1860
2020-09-29T18:02:39.984Z,1601402559.984 [RDI_Pathfinder] Loaded
2020-09-29T18:02:39.985Z,1601402559.985 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2020-09-29T18:02:40.034Z,1601402560.034 [RDI_PathfinderUp] Loaded
2020-09-29T18:02:40.034Z,1601402560.034 [ComponentRegistry](DEBUG): SyncComponent "RDI_PathfinderUp" handled in the control thread.
2020-09-29T18:02:41.467Z,1601402561.467 [BPC1] Loaded
2020-09-29T18:02:41.468Z,1601402561.468 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-09-29T18:02:41.468Z,1601402561.468 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-09-29T18:02:41.469Z,1601402561.469 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-09-29T18:02:41.486Z,1601402561.486 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-09-29T18:02:41.487Z,1601402561.487 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-09-29T18:02:41.601Z,1601402561.601 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-09-29T18:02:41.602Z,1601402561.602 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-09-29T18:02:41.621Z,1601402561.621 [NavChart] Loaded
2020-09-29T18:02:41.621Z,1601402561.621 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-09-29T18:02:41.625Z,1601402561.625 [UniversalFixResidualReporter] Loaded
2020-09-29T18:02:41.625Z,1601402561.625 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-09-29T18:02:41.642Z,1601402561.642 [WorkSite] Loaded
2020-09-29T18:02:41.642Z,1601402561.642 [ComponentRegistry](DEBUG): SyncComponent "WorkSite" handled in the control thread.
2020-09-29T18:02:41.642Z,1601402561.642 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-09-29T18:02:41.643Z,1601402561.643 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2020-09-29T18:02:41.716Z,1601402561.716 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2020-09-29T18:02:41.717Z,1601402561.717 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-09-29T18:02:42.312Z,1601402562.312 [BuoyancyServo] Loaded
2020-09-29T18:02:42.313Z,1601402562.313 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-09-29T18:02:42.427Z,1601402562.427 [ElevatorServo] Loaded
2020-09-29T18:02:42.427Z,1601402562.427 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-09-29T18:02:42.515Z,1601402562.515 [MassServo] Loaded
2020-09-29T18:02:42.557Z,1601402562.557 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-09-29T18:02:42.597Z,1601402562.597 [RudderServo] Loaded
2020-09-29T18:02:42.597Z,1601402562.597 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-09-29T18:02:42.626Z,1601402562.626 [ThrusterServo] Loaded
2020-09-29T18:02:42.626Z,1601402562.626 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-09-29T18:02:42.627Z,1601402562.627 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-09-29T18:02:42.627Z,1601402562.627 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-09-29T18:02:42.971Z,1601402562.971 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2020-09-29T18:02:42.976Z,1601402562.976 [CTD_Seabird](INFO): created writer for : sea_water_density
2020-09-29T18:02:42.976Z,1601402562.976 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2020-09-29T18:02:42.981Z,1601402562.981 [CTD_Seabird](INFO): created writer for : depth
2020-09-29T18:02:42.981Z,1601402562.981 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2020-09-29T18:02:42.986Z,1601402562.986 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2020-09-29T18:02:42.986Z,1601402562.986 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2020-09-29T18:02:42.991Z,1601402562.991 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2020-09-29T18:02:42.992Z,1601402562.992 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2020-09-29T18:02:42.997Z,1601402562.997 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2020-09-29T18:02:42.998Z,1601402562.998 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2020-09-29T18:02:43.003Z,1601402563.003 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2020-09-29T18:02:43.003Z,1601402563.003 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2020-09-29T18:02:43.008Z,1601402563.008 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2020-09-29T18:02:43.008Z,1601402563.008 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): mass_concentration_of_oxygen_in_sea_water
2020-09-29T18:02:43.014Z,1601402563.014 [CTD_Seabird](INFO): created writer for : mass_concentration_of_oxygen_in_sea_water
2020-09-29T18:02:43.014Z,1601402563.014 [CTD_Seabird](DEBUG): LcmSlateWriter::add(DAtaURI): CTD_Seabird.sea_water_dissolved_oxygen_freq
2020-09-29T18:02:43.014Z,1601402563.014 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): dataName: sea_water_dissolved_oxygen_freq
2020-09-29T18:02:43.045Z,1601402563.045 [CTD_Seabird] Loaded
2020-09-29T18:02:43.046Z,1601402563.046 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2020-09-29T18:02:43.047Z,1601402563.047 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 4095E4E0
2020-09-29T18:02:43.047Z,1601402563.047 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1861
2020-09-29T18:02:43.077Z,1601402563.077 [PAR_Licor] Loaded
2020-09-29T18:02:43.077Z,1601402563.077 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2020-09-29T18:02:43.133Z,1601402563.133 [WetLabsSeaOWL_UV_A] Loaded
2020-09-29T18:02:43.133Z,1601402563.133 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2020-09-29T18:02:43.134Z,1601402563.134 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 4098E4E0
2020-09-29T18:02:43.134Z,1601402563.134 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 1862
2020-09-29T18:02:43.135Z,1601402563.135 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-09-29T18:02:43.135Z,1601402563.135 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-09-29T18:02:43.412Z,1601402563.412 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-09-29T18:02:43.412Z,1601402563.412 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-09-29T18:02:43.451Z,1601402563.451 [DepthRateCalculator] Loaded
2020-09-29T18:02:43.452Z,1601402563.452 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-09-29T18:02:43.457Z,1601402563.457 [PitchRateCalculator] Loaded
2020-09-29T18:02:43.457Z,1601402563.457 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-09-29T18:02:43.469Z,1601402563.469 [SpeedCalculator] Loaded
2020-09-29T18:02:43.469Z,1601402563.469 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-09-29T18:02:43.490Z,1601402563.490 [TempGradientCalculator] Loaded
2020-09-29T18:02:43.490Z,1601402563.490 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-09-29T18:02:43.495Z,1601402563.495 [YawRateCalculator] Loaded
2020-09-29T18:02:43.495Z,1601402563.495 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-09-29T18:02:43.534Z,1601402563.534 [ElevatorOffsetCalculator] Loaded
2020-09-29T18:02:43.534Z,1601402563.534 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-09-29T18:02:43.534Z,1601402563.534 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-09-29T18:02:43.535Z,1601402563.535 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-09-29T18:02:43.663Z,1601402563.663 [SBIT](DEBUG): Construct Startup Built In Test.
2020-09-29T18:02:43.681Z,1601402563.681 [SBIT] Loaded
2020-09-29T18:02:43.681Z,1601402563.681 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-09-29T18:02:43.682Z,1601402563.682 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-09-29T18:02:43.694Z,1601402563.694 [IBIT] Loaded
2020-09-29T18:02:43.694Z,1601402563.694 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-09-29T18:02:43.697Z,1601402563.697 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-09-29T18:02:43.831Z,1601402563.831 [CBIT] Loaded
2020-09-29T18:02:43.831Z,1601402563.831 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-09-29T18:02:43.832Z,1601402563.832 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-09-29T18:02:43.832Z,1601402563.832 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-09-29T18:02:43.913Z,1601402563.913 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-09-29T18:02:43.914Z,1601402563.914 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-09-29T18:02:44.008Z,1601402564.008 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-09-29T18:02:44.008Z,1601402564.008 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-09-29T18:02:44.055Z,1601402564.055 [VerticalControl](DEBUG): Construct VerticalControl.
2020-09-29T18:02:44.135Z,1601402564.135 [VerticalControl] Loaded
2020-09-29T18:02:44.135Z,1601402564.135 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-09-29T18:02:44.136Z,1601402564.136 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-09-29T18:02:44.202Z,1601402564.202 [HorizontalControl] Loaded
2020-09-29T18:02:44.202Z,1601402564.202 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-09-29T18:02:44.203Z,1601402564.203 [SpeedControl](DEBUG): Construct SpeedControl.
2020-09-29T18:02:44.204Z,1601402564.204 [SpeedControl] Loaded
2020-09-29T18:02:44.205Z,1601402564.205 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-09-29T18:02:44.205Z,1601402564.205 [LoopControl](DEBUG): Construct LoopControl.
2020-09-29T18:02:44.206Z,1601402564.206 [LoopControl] Loaded
2020-09-29T18:02:44.206Z,1601402564.206 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-09-29T18:02:44.206Z,1601402564.206 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-09-29T18:02:44.207Z,1601402564.207 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-09-29T18:02:44.233Z,1601402564.233 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-09-29T18:02:44.237Z,1601402564.237 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-09-29T18:02:44.238Z,1601402564.238 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-09-29T18:02:44.244Z,1601402564.244 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-09-29T18:02:44.245Z,1601402564.245 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B644E0
2020-09-29T18:02:44.246Z,1601402564.246 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1863
2020-09-29T18:02:44.251Z,1601402564.251 [Supervisor](INFO): Main Thread ID is 824
2020-09-29T18:02:44.251Z,1601402564.251 [Supervisor](DEBUG): Running supervisor.
2020-09-29T18:02:44.251Z,1601402564.251 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1864
2020-09-29T18:02:44.254Z,1601402564.254 [controlThread ThreadHandler](INFO): Handler Thread ID is 1865
2020-09-29T18:02:44.254Z,1601402564.254 [controlThread](DEBUG): Initializing ControlThread
2020-09-29T18:02:44.258Z,1601402564.258 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-09-29T18:02:44.259Z,1601402564.259 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-09-29T18:02:44.259Z,1601402564.259 [WorkSite](DEBUG): Initializing WorkSite component.
2020-09-29T18:02:44.265Z,1601402564.265 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-09-29T18:02:44.265Z,1601402564.265 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-09-29T18:02:44.266Z,1601402564.266 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-09-29T18:02:44.266Z,1601402564.266 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-09-29T18:02:44.267Z,1601402564.267 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-09-29T18:02:44.267Z,1601402564.267 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-09-29T18:02:44.268Z,1601402564.268 [SBIT](INFO): Initialize SBIT Component.
2020-09-29T18:02:44.268Z,1601402564.268 [SBIT](IMPORTANT): git: 2020-09-24
2020-09-29T18:02:44.268Z,1601402564.268 [SBIT](INFO): git hash: 5d0e7d8c58095398299d5a0f1e62866c13d9facc
2020-09-29T18:02:44.269Z,1601402564.269 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-09-29T18:02:44.270Z,1601402564.270 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Thu Aug 6 12:04:53 PDT 2020
2020-09-29T18:02:44.271Z,1601402564.271 [SBIT](INFO): Beginning SBIT in 30.000000 seconds.
2020-09-29T18:02:44.271Z,1601402564.271 [IBIT](INFO): Initialize IBIT Component.
2020-09-29T18:02:44.273Z,1601402564.273 [CBIT](DEBUG): Initialize CBIT Component.
2020-09-29T18:02:44.273Z,1601402564.273 [logger ThreadHandler](INFO): Handler Thread ID is 1866
2020-09-29T18:02:44.285Z,1601402564.285 [CBIT](DEBUG): Initialized mux pins.
2020-09-29T18:02:44.285Z,1601402564.285 [CBIT](DEBUG): Initializing the watchdog timer.
2020-09-29T18:02:44.293Z,1601402564.293 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1867
2020-09-29T18:02:44.294Z,1601402564.294 [DataOverHttps](DEBUG): dashIP=128.128.181.155 starts with a digit so assuming it is a numeric IP
2020-09-29T18:02:44.305Z,1601402564.305 [Onboard ThreadHandler](INFO): Handler Thread ID is 1868
2020-09-29T18:02:44.309Z,1601402564.309 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-09-29T18:02:44.309Z,1601402564.309 [CBIT](DEBUG): Initializing heartbeat.
2020-09-29T18:02:44.323Z,1601402564.323 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1869
2020-09-29T18:02:44.338Z,1601402564.338 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1870
2020-09-29T18:02:44.338Z,1601402564.338 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-09-29T18:02:44.342Z,1601402564.342 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 1872
2020-09-29T18:02:44.343Z,1601402564.343 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-09-29T18:02:44.354Z,1601402564.354 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1873
2020-09-29T18:02:44.357Z,1601402564.357 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-09-29T18:02:44.357Z,1601402564.357 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-09-29T18:02:44.357Z,1601402564.357 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-09-29T18:02:44.357Z,1601402564.357 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-09-29T18:02:44.357Z,1601402564.357 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-09-29T18:02:44.358Z,1601402564.358 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-09-29T18:02:44.358Z,1601402564.358 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-09-29T18:02:44.358Z,1601402564.358 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-09-29T18:02:44.358Z,1601402564.358 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-09-29T18:02:44.358Z,1601402564.358 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-09-29T18:02:44.359Z,1601402564.359 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-09-29T18:02:44.359Z,1601402564.359 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-09-29T18:02:44.359Z,1601402564.359 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-09-29T18:02:44.359Z,1601402564.359 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-09-29T18:02:44.359Z,1601402564.359 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-09-29T18:02:44.359Z,1601402564.359 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-09-29T18:02:44.381Z,1601402564.381 [CBIT](DEBUG): Deactivating GF circuits.
2020-09-29T18:02:44.381Z,1601402564.381 [CBIT](DEBUG): Deactivating emergency mode.
2020-09-29T18:02:44.417Z,1601402564.417 [CBIT](DEBUG): Backplane powered.
2020-09-29T18:02:44.417Z,1601402564.417 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-09-29T18:02:44.419Z,1601402564.419 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-09-29T18:02:44.419Z,1601402564.419 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-09-29T18:02:44.420Z,1601402564.420 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-09-29T18:02:44.421Z,1601402564.421 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-09-29T18:02:44.431Z,1601402564.431 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-09-29T18:02:44.453Z,1601402564.453 [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-09-29T18:02:44.454Z,1601402564.454 [MissionManager](INFO): Loading Mission: Missions/underIce/DefaultWithUndock.xml
2020-09-29T18:02:44.553Z,1601402564.553 [MissionManager](INFO): DefineArg DefaultWithUndock.ElapsedSinceDefaultStarted = 0.000000 min
2020-09-29T18:02:44.557Z,1601402564.557 [MissionManager](INFO): DefineArg DefaultWithUndock.DetachTimeout = 30.000000 min
2020-09-29T18:02:44.560Z,1601402564.560 [MissionManager](INFO): DefineArg DefaultWithUndock.MicromodemCommsInterval = 2.000000 min
2020-09-29T18:02:44.577Z,1601402564.577 [DefaultWithUndock:A.Wait](DEBUG): Construct Wait.
2020-09-29T18:02:44.579Z,1601402564.579 [DefaultWithUndock:B.Undock](DEBUG): Construct.
2020-09-29T18:02:44.579Z,1601402564.579 [DefaultWithUndock:B.Undock](DEBUG): Initializing internal variables to default values.
2020-09-29T18:02:44.607Z,1601402564.607 [DefaultWithUndock:C.GoToSurface](DEBUG): Construct GoToSurface.
2020-09-29T18:02:44.655Z,1601402564.655 [DefaultWithUndock:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-09-29T18:02:44.657Z,1601402564.657 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-09-29T18:02:44.679Z,1601402564.679 [DefaultWithUndock:F.Execute](DEBUG): Construct Execute.
2020-09-29T18:02:44.683Z,1601402564.683 [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="DefaultWithUndock">

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

    <DefineArg Name="DetachTimeout"><Description>
        Maximum time duration for the vehicle to reach the approach depth after detaching form the dock.
        The vehicle will switch over to midcourse guidance after the timeout is expired.
    </Description><Units:minute/><Value>30</Value></DefineArg>

    <DefineArg Name="MicromodemCommsInterval"><Description>
        How often to trigger Umodem communications
    </Description><Units:minute/><Value>2</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>30</Value></Setting>
    </Guidance:Wait>

    <!-- Leave the dock if necessary --> 
    <Guidance:Undock>
        <Sequence/>
        <Timeout Duration="DetachTimeout">
            <Syslog Severity="Important">Timed out while trying to detach from dock at current depth of <Universal:depth/><Units:meter/>.</Syslog>
        </Timeout>
    </Guidance:Undock>

    <!-- This mission assumes open water so from here on it's the same old default that's always used -->
    <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-09-29T18:02:44.701Z,1601402564.701 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,DDM,Depth_Keller,DUSBL_Hydroid,Micromodem,NAL9602,PowerOnly,RDI_Pathfinder,RDI_PathfinderUp,BPC1,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,WorkSite,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2020-09-29T18:02:44.706Z,1601402564.706 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-09-29T18:02:44.729Z,1601402564.729 [Radio_Surface](INFO): Powering up
2020-09-29T18:02:44.739Z,1601402564.739 [DDM](INFO): Powering up
2020-09-29T18:02:44.739Z,1601402564.739 [DDM](DEBUG): Initializing DDM.
2020-09-29T18:02:44.761Z,1601402564.761 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar
2020-09-29T18:02:44.762Z,1601402564.762 [DUSBL_Hydroid](INFO): Powering up
2020-09-29T18:02:44.762Z,1601402564.762 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2020-09-29T18:02:44.861Z,1601402564.861 [DepthRateCalculator](ERROR): Depth measurement is not active
2020-09-29T18:02:44.878Z,1601402564.878 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-09-29T18:02:44.885Z,1601402564.885 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-09-29T18:02:44.886Z,1601402564.886 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-09-29T18:02:44.893Z,1601402564.893 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-09-29T18:02:44.894Z,1601402564.894 [MassServo](DEBUG): Initializing EZServoServo.
2020-09-29T18:02:44.901Z,1601402564.901 [MassServo](DEBUG): Initializing MassServo.
2020-09-29T18:02:44.902Z,1601402564.902 [RudderServo](DEBUG): Initializing EZServoServo.
2020-09-29T18:02:44.909Z,1601402564.909 [RudderServo](DEBUG): Initializing RudderServo.
2020-09-29T18:02:44.910Z,1601402564.910 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-09-29T18:02:44.917Z,1601402564.917 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-09-29T18:02:45.130Z,1601402565.130 [Micromodem](INFO): Powering up
2020-09-29T18:02:45.130Z,1601402565.130 [Micromodem](DEBUG): Initializing Micromodem.
2020-09-29T18:02:45.177Z,1601402565.177 [DepthRateCalculator](ERROR): Depth measurement is not active
2020-09-29T18:02:45.869Z,1601402565.869 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2020-09-29T18:02:45.869Z,1601402565.869 [RudderServo](FAULT): Rudder failed to initialize
2020-09-29T18:02:45.869Z,1601402565.869 [RudderServo] Communications Fault, FailCount= 1
2020-09-29T18:02:45.869Z,1601402565.869 [RudderServo](ERROR): Communications Fault
2020-09-29T18:02:45.980Z,1601402565.980 [CBIT](ERROR): Communications Fault in component: RudderServo
2020-09-29T18:02:46.130Z,1601402566.130 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-09-29T18:02:46.130Z,1601402566.130 [RudderServo](INFO): Powering down
2020-09-29T18:02:46.812Z,1601402566.812 [RudderServo](DEBUG): Initializing EZServoServo.
2020-09-29T18:02:46.930Z,1601402566.930 [RudderServo](DEBUG): Initializing RudderServo.
2020-09-29T18:02:46.934Z,1601402566.934 [CBIT](INFO): Clearing failed state for component RudderServo
2020-09-29T18:02:46.934Z,1601402566.934 [RudderServo] No Fault, FailCount= 1
2020-09-29T18:02:50.071Z,1601402570.071 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2020-09-29T18:02:52.107Z,1601402572.107 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31

2020-09-29T18:02:52.107Z,1601402572.107 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2020-09-29T18:02:52.511Z,1601402572.511 [Micromodem](INFO): Nmea in: $CATMG,2020-09-29T18:02:51.508869Z,RTC,RTC*57

2020-09-29T18:02:52.511Z,1601402572.511 [Micromodem](ERROR): CFG response from modem unexpected: $CATMG,2020-09-29T18:02:51.508869Z,RTC,RTC*57

2020-09-29T18:02:52.911Z,1601402572.911 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33

2020-09-29T18:02:52.911Z,1601402572.911 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64

2020-09-29T18:02:53.327Z,1601402573.327 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66

2020-09-29T18:02:53.327Z,1601402573.327 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F

2020-09-29T18:02:53.697Z,1601402573.697 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D

2020-09-29T18:02:53.697Z,1601402573.697 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E

2020-09-29T18:02:54.095Z,1601402574.095 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C

2020-09-29T18:02:54.095Z,1601402574.095 [Micromodem](INFO): Nmea out: $CCCFG,FC0,14500*47

2020-09-29T18:02:54.503Z,1601402574.503 [Micromodem](INFO): Nmea in: $CACFG,FC0,14500*45

2020-09-29T18:02:54.503Z,1601402574.503 [Micromodem](INFO): Nmea out: $CCCFG,BW0,4000*63

2020-09-29T18:02:54.902Z,1601402574.902 [Micromodem](INFO): Nmea in: $CACFG,BW0,4000*61

2020-09-29T18:02:54.903Z,1601402574.903 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A

2020-09-29T18:02:55.319Z,1601402575.319 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38

2020-09-29T18:02:55.319Z,1601402575.319 [Micromodem](INFO): Nmea out: $CCCLK,2020,09,29,18,02,56*4E

2020-09-29T18:02:55.712Z,1601402575.712 [Micromodem](INFO): Nmea in: $CACLK,2020,9,29,18,2,56*4C

2020-09-29T18:02:55.714Z,1601402575.714 [Micromodem](INFO): Nmea in: $CATMS,0,2020-09-29T18:02:57Z*7D

2020-09-29T18:02:55.715Z,1601402575.715 [Micromodem](INFO): Nmea in: $CATMG,2020-09-29T18:02:57.026719Z,USER_CMD,RTC*11

2020-09-29T18:03:00.550Z,1601402580.550 [DDM](INFO): Dynamic Docking Module:C REMUS Capture Rev 1.1
2020-09-29T18:03:03.013Z,1601402583.013 [DUSBL_Hydroid](INFO): DUSBL Version:O
2020-09-29T18:03:09.847Z,1601402589.847 [NAL9602](INFO): Powering up NAL9602
2020-09-29T18:03:14.735Z,1601402594.735 [SBIT](IMPORTANT): Beginning Startup BIT
2020-09-29T18:03:14.743Z,1601402594.743 [CBIT](IMPORTANT): Beginning ground fault scan
2020-09-29T18:03:20.759Z,1601402600.759 [NAL9602](INFO): NAL9602 initialized
2020-09-29T18:03:25.693Z,1601402605.693 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.009112
CHAN A1 (24V): -0.027602
CHAN A2 (12V): -0.007238
CHAN A3 (5V): -0.002277
CHAN B0 (3.3V): 0.000204
CHAN B1 (3.15aV): 0.000074
CHAN B2 (3.15bV): 0.000105
CHAN B3 (GND): 0.001801
OPEN: 0.005435
Full Scale Calc: 4.765 mA, -1.589 mA
2020-09-29T18:04:08.864Z,1601402648.864 [SBIT](IMPORTANT): SBIT PASSED
2020-09-29T18:04:08.865Z,1601402648.865 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-09-29T18:04:08.866Z,1601402648.866 [SBIT](IMPORTANT): DDM.loadAtStartup=1 bool;

2020-09-29T18:04:08.866Z,1601402648.866 [SBIT](IMPORTANT): DDM.verbosity=1 count;

2020-09-29T18:04:08.866Z,1601402648.866 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2020-09-29T18:04:08.866Z,1601402648.866 [SBIT](IMPORTANT): Express linearApproximation acoustic_contact_range 10.000000 meter;

2020-09-29T18:04:08.867Z,1601402648.867 [SBIT](IMPORTANT): Micromodem.sendExpress=0 bool;

2020-09-29T18:04:08.867Z,1601402648.867 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=288.614464 cubic_centimeter;

2020-09-29T18:04:08.867Z,1601402648.867 [SBIT](IMPORTANT): VerticalControl.massDefault=2.506765 millimeter;

2020-09-29T18:04:09.292Z,1601402649.292 [WorkSite](IMPORTANT): Reading workSite position from Data/workSite.cfg
2020-09-29T18:04:09.299Z,1601402649.299 [WorkSite](IMPORTANT): WorkSite fix at 20200929T180202: (41.552096, -70.708145)
2020-09-29T18:04:09.305Z,1601402649.305 [MissionManager](IMPORTANT): Started mission Startup
2020-09-29T18:04:09.305Z,1601402649.305 [Startup] Running Loop=1
2020-09-29T18:04:09.305Z,1601402649.305 [Startup](DEBUG): Aggregate::initialize Startup
2020-09-29T18:04:09.305Z,1601402649.305 [Startup:A.GoToSurface] Running Loop=1
2020-09-29T18:04:09.305Z,1601402649.305 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-09-29T18:04:09.306Z,1601402649.306 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-09-29T18:04:09.306Z,1601402649.306 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-09-29T18:04:09.306Z,1601402649.306 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-09-29T18:04:09.307Z,1601402649.307 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-09-29T18:04:09.307Z,1601402649.307 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-09-29T18:04:09.313Z,1601402649.313 [Startup:StartupSatComms] Running Loop=1
2020-09-29T18:04:09.313Z,1601402649.313 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-09-29T18:04:09.313Z,1601402649.313 [Startup:StartupSatComms:A] Running Loop=1
2020-09-29T18:04:09.662Z,1601402649.662 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-09-29T18:05:09.487Z,1601402709.487 [Startup:StartupSatComms:A](INFO): Timed out from 2020-09-29T18:04:09.3Z
2020-09-29T18:05:09.487Z,1601402709.487 [Startup:StartupSatComms:A] Stopped
2020-09-29T18:05:09.487Z,1601402709.487 [Startup:StartupSatComms:B] Running Loop=1
2020-09-29T18:05:09.873Z,1601402709.873 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-09-29T18:06:09.665Z,1601402769.665 [Startup:StartupSatComms:B](INFO): Timed out from 2020-09-29T18:05:09.5Z
2020-09-29T18:06:09.665Z,1601402769.665 [Startup:StartupSatComms:B] Stopped
2020-09-29T18:06:09.665Z,1601402769.665 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2020-09-29T18:06:09.665Z,1601402769.665 [Startup:StartupSatComms] Stopped
2020-09-29T18:06:09.665Z,1601402769.665 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-09-29T18:06:09.666Z,1601402769.666 [Startup](INFO): Completed Startup
2020-09-29T18:06:09.666Z,1601402769.666 [MissionManager](INFO): Startup is completed.
2020-09-29T18:06:09.666Z,1601402769.666 [MissionManager](INFO): Uninitializing Mission Startup
2020-09-29T18:06:09.666Z,1601402769.666 [Startup] Stopped
2020-09-29T18:06:09.667Z,1601402769.667 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-09-29T18:06:09.667Z,1601402769.667 [Startup:A.GoToSurface] Stopped
2020-09-29T18:06:09.667Z,1601402769.667 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-09-29T18:06:10.085Z,1601402770.085 [MissionManager](IMPORTANT): Started mission DefaultWithUndock
2020-09-29T18:06:10.085Z,1601402770.085 [DefaultWithUndock] Running Loop=1
2020-09-29T18:06:10.085Z,1601402770.085 [DefaultWithUndock](DEBUG): Aggregate::initialize DefaultWithUndock
2020-09-29T18:06:10.086Z,1601402770.086 [DefaultWithUndock:C.GoToSurface] Running Loop=1
2020-09-29T18:06:10.086Z,1601402770.086 [DefaultWithUndock:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-09-29T18:06:10.086Z,1601402770.086 [DefaultWithUndock:C.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-09-29T18:06:10.086Z,1601402770.086 [DefaultWithUndock:C.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-09-29T18:06:10.086Z,1601402770.086 [DefaultWithUndock:C.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-09-29T18:06:10.087Z,1601402770.087 [DefaultWithUndock:C.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-09-29T18:06:10.087Z,1601402770.087 [DefaultWithUndock:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-09-29T18:06:10.087Z,1601402770.087 [DefaultWithUndock:A.Wait] Running Loop=1
2020-09-29T18:06:10.087Z,1601402770.087 [DefaultWithUndock:A.Wait](DEBUG): Initialize Wait Component.
2020-09-29T18:06:19.168Z,1601402779.168 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005111
2020-09-29T18:06:40.361Z,1601402800.361 [DefaultWithUndock:A.Wait](INFO): Done Waiting.
2020-09-29T18:06:40.362Z,1601402800.362 [DefaultWithUndock:A.Wait] Stopped
2020-09-29T18:06:40.362Z,1601402800.362 [DefaultWithUndock:A.Wait](DEBUG): Uninitialize Wait Component.
2020-09-29T18:06:40.362Z,1601402800.362 [DefaultWithUndock:B.Undock] Running Loop=1
2020-09-29T18:06:40.362Z,1601402800.362 [DefaultWithUndock:B.Undock](DEBUG): Initialize.
2020-09-29T18:06:40.362Z,1601402800.362 [DefaultWithUndock:B.Undock](DEBUG): Initializing internal variables to default values.
2020-09-29T18:06:41.165Z,1601402801.165 [PowerOnly](INFO): Powering up loadControl
2020-09-29T18:06:41.971Z,1601402801.971 [DDM](INFO): Detaching. Opening Latch
2020-09-29T18:06:42.350Z,1601402802.350 [DDM](INFO): Detaching. Opening Latch
2020-09-29T18:06:42.754Z,1601402802.754 [DDM](INFO): Detaching. Opening Latch
2020-09-29T18:06:44.112Z,1601402804.112 [DefaultWithUndock:B.Undock](INFO): Detaching from dock.
2020-09-29T18:06:44.422Z,1601402804.422 [DefaultWithUndock:B.Undock](INFO): Detected possible detachment. Starting timer.
2020-09-29T18:07:04.614Z,1601402824.614 [DefaultWithUndock:B.Undock](INFO): Detached at range: nan m. Transitioning DDM to standby.
2020-09-29T18:07:05.383Z,1601402825.383 [DDM](INFO): Closing Latch
2020-09-29T18:07:05.790Z,1601402825.790 [DDM](INFO): Closing Latch
2020-09-29T18:07:06.191Z,1601402826.191 [DDM](INFO): Closing Latch
2020-09-29T18:07:07.540Z,1601402827.540 [DefaultWithUndock:B.Undock](INFO): DDM at standby.
2020-09-29T18:07:07.883Z,1601402827.883 [DefaultWithUndock:B.Undock](IMPORTANT): Undocking sequence complete.
2020-09-29T18:07:07.883Z,1601402827.883 [DefaultWithUndock:B.Undock] Stopped
2020-09-29T18:07:08.280Z,1601402828.280 [DefaultWithUndock:CheckIn] Running Loop=1
2020-09-29T18:07:08.280Z,1601402828.280 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-09-29T18:07:08.280Z,1601402828.280 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-09-29T18:07:08.665Z,1601402828.665 [DefaultWithUndock:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-09-29T18:08:12.451Z,1601402892.451 [PowerOnly](INFO): Powering down loadControl
2020-09-29T18:08:24.567Z,1601402904.567 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-09-29T18:10:08.050Z,1601403008.050 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-09-29T18:10:08.069Z,1601403008.069 [BPC1](INFO): Received data from all battery sticks.
2020-09-29T18:12:08.421Z,1601403128.421 [DefaultWithUndock:CheckIn:Read_GPS](INFO): Timed out from 2020-09-29T18:07:08.3Z
2020-09-29T18:12:08.421Z,1601403128.421 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-09-29T18:12:08.421Z,1601403128.421 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-09-29T18:12:08.821Z,1601403128.821 [DefaultWithUndock:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-09-29T18:12:09.666Z,1601403129.666 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20200929T174923/Courier0007.lzma
2020-09-29T18:12:10.635Z,1601403130.635 [DataOverHttps](INFO): Moved sent file to Logs/20200929T174923/Courier0007.lzma.bak
2020-09-29T18:12:10.635Z,1601403130.635 [DataOverHttps](INFO): SBD MOMSN=40990
2020-09-29T18:12:19.673Z,1601403139.673 [DataOverHttps](INFO): Sending 269 bytes from file Logs/20200929T180233/Courier0000.lzma
2020-09-29T18:12:20.674Z,1601403140.674 [DataOverHttps](INFO): Moved sent file to Logs/20200929T180233/Courier0000.lzma.bak
2020-09-29T18:12:20.674Z,1601403140.674 [DataOverHttps](INFO): SBD MOMSN=40992
2020-09-29T18:12:29.712Z,1601403149.712 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20200929T180233/Courier0004.lzma
2020-09-29T18:12:30.715Z,1601403150.715 [DataOverHttps](INFO): Moved sent file to Logs/20200929T180233/Courier0004.lzma.bak
2020-09-29T18:12:30.715Z,1601403150.715 [DataOverHttps](INFO): SBD MOMSN=40996
2020-09-29T18:12:39.753Z,1601403159.753 [DataOverHttps](INFO): Sending 253 bytes from file Logs/20200929T174923/Express0008.lzma
2020-09-29T18:12:40.299Z,1601403160.299 [NAL9602](INFO): Not Powering down - fast GPS
2020-09-29T18:12:40.762Z,1601403160.762 [DataOverHttps](INFO): Moved sent file to Logs/20200929T174923/Express0008.lzma.bak
2020-09-29T18:12:40.763Z,1601403160.763 [DataOverHttps](INFO): SBD MOMSN=40998
2020-09-29T18:12:49.802Z,1601403169.802 [DataOverHttps](INFO): Sending 914 bytes from file Logs/20200929T180233/Express0001.lzma
2020-09-29T18:12:50.802Z,1601403170.802 [DataOverHttps](INFO): Moved sent file to Logs/20200929T180233/Express0001.lzma.bak
2020-09-29T18:12:50.802Z,1601403170.802 [DataOverHttps](INFO): SBD MOMSN=41005
2020-09-29T18:12:59.842Z,1601403179.842 [DataOverHttps](INFO): Sending 252 bytes from file Logs/20200929T180233/Express0005.lzma
2020-09-29T18:13:00.842Z,1601403180.842 [DataOverHttps](INFO): Moved sent file to Logs/20200929T180233/Express0005.lzma.bak
2020-09-29T18:13:00.843Z,1601403180.843 [DataOverHttps](INFO): SBD MOMSN=41026
2020-09-29T18:13:02.150Z,1601403182.150 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-09-29T18:13:02.150Z,1601403182.150 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1
2020-09-29T18:13:02.150Z,1601403182.150 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-09-29T18:13:54.342Z,1601403234.342 [CommandLine](IMPORTANT): got command report mod platform_buoyancy_position
2020-09-29T18:13:54.667Z,1601403234.667 [Reporter](INFO): platform_buoyancy_position 953.771362 cc
2020-09-29T18:14:40.295Z,1601403280.295 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-09-29T18:14:40.915Z,1601403280.915 [CommandLine](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 250.000000 cubic_centimeter
2020-09-29T18:14:40.916Z,1601403280.916 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.buoyancyAction" handled in the control thread.
2020-09-29T18:14:41.168Z,1601403281.168 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,DDM,Depth_Keller,DUSBL_Hydroid,Micromodem,NAL9602,PowerOnly,RDI_Pathfinder,RDI_PathfinderUp,BPC1,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,WorkSite,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.buoyancyAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2020-09-29T18:14:46.848Z,1601403286.848 [Reporter](INFO): platform_buoyancy_position 958.740479 cc
2020-09-29T18:14:47.230Z,1601403287.230 [Reporter](INFO): platform_buoyancy_position 958.337524 cc
2020-09-29T18:14:47.646Z,1601403287.646 [Reporter](INFO): platform_buoyancy_position 958.068970 cc
2020-09-29T18:14:48.114Z,1601403288.114 [Reporter](INFO): platform_buoyancy_position 947.593628 cc
2020-09-29T18:14:48.435Z,1601403288.435 [Reporter](INFO): platform_buoyancy_position 945.176147 cc
2020-09-29T18:14:48.835Z,1601403288.835 [Reporter](INFO): platform_buoyancy_position 942.087280 cc
2020-09-29T18:14:49.262Z,1601403289.262 [Reporter](INFO): platform_buoyancy_position 936.715332 cc
2020-09-29T18:14:49.634Z,1601403289.634 [Reporter](INFO): platform_buoyancy_position 932.283325 cc
2020-09-29T18:14:49.999Z,1601403289.999 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-09-29T18:14:50.074Z,1601403290.074 [Reporter](INFO): platform_buoyancy_position 927.045654 cc
2020-09-29T18:14:50.467Z,1601403290.467 [Reporter](INFO): platform_buoyancy_position 921.539429 cc
2020-09-29T18:14:50.843Z,1601403290.843 [Reporter](INFO): platform_buoyancy_position 917.241821 cc
2020-09-29T18:14:51.271Z,1601403291.271 [Reporter](INFO): platform_buoyancy_position 910.929688 cc
2020-09-29T18:14:51.677Z,1601403291.677 [Reporter](INFO): platform_buoyancy_position 906.363525 cc
2020-09-29T18:14:52.086Z,1601403292.086 [Reporter](INFO): platform_buoyancy_position 900.051392 cc
2020-09-29T18:14:52.492Z,1601403292.492 [Reporter](INFO): platform_buoyancy_position 895.350830 cc
2020-09-29T18:14:52.879Z,1601403292.879 [Reporter](INFO): platform_buoyancy_position 889.710327 cc
2020-09-29T18:14:53.339Z,1601403293.339 [Reporter](INFO): platform_buoyancy_position 884.875488 cc
2020-09-29T18:14:53.695Z,1601403293.695 [Reporter](INFO): platform_buoyancy_position 880.577881 cc
2020-09-29T18:14:54.127Z,1601403294.127 [Reporter](INFO): platform_buoyancy_position 874.400024 cc
2020-09-29T18:14:54.495Z,1601403294.495 [Reporter](INFO): platform_buoyancy_position 869.968140 cc
2020-09-29T18:14:54.938Z,1601403294.938 [Reporter](INFO): platform_buoyancy_position 863.656128 cc
2020-09-29T18:14:55.339Z,1601403295.339 [Reporter](INFO): platform_buoyancy_position 859.089844 cc
2020-09-29T18:14:55.695Z,1601403295.695 [Reporter](INFO): platform_buoyancy_position 852.912109 cc
2020-09-29T18:14:56.103Z,1601403296.103 [Reporter](INFO): platform_buoyancy_position 848.480225 cc
2020-09-29T18:14:56.534Z,1601403296.534 [Reporter](INFO): platform_buoyancy_position 842.168091 cc
2020-09-29T18:14:56.922Z,1601403296.922 [Reporter](INFO): platform_buoyancy_position 837.736206 cc
2020-09-29T18:14:57.336Z,1601403297.336 [Reporter](INFO): platform_buoyancy_position 831.289795 cc
2020-09-29T18:14:57.723Z,1601403297.723 [Reporter](INFO): platform_buoyancy_position 827.126465 cc
2020-09-29T18:14:58.146Z,1601403298.146 [Reporter](INFO): platform_buoyancy_position 820.680054 cc
2020-09-29T18:14:58.540Z,1601403298.540 [Reporter](INFO): platform_buoyancy_position 816.248169 cc
2020-09-29T18:14:58.986Z,1601403298.986 [Reporter](INFO): platform_buoyancy_position 809.936096 cc
2020-09-29T18:14:59.343Z,1601403299.343 [Reporter](INFO): platform_buoyancy_position 805.638489 cc
2020-09-29T18:14:59.750Z,1601403299.750 [Reporter](INFO): platform_buoyancy_position 801.072266 cc
2020-09-29T18:15:00.155Z,1601403300.155 [Reporter](INFO): platform_buoyancy_position 794.760193 cc
2020-09-29T18:15:00.555Z,1601403300.555 [Reporter](INFO): platform_buoyancy_position 790.059692 cc
2020-09-29T18:15:00.981Z,1601403300.981 [Reporter](INFO): platform_buoyancy_position 784.150452 cc
2020-09-29T18:15:01.391Z,1601403301.391 [Reporter](INFO): platform_buoyancy_position 778.912781 cc
2020-09-29T18:15:01.770Z,1601403301.770 [Reporter](INFO): platform_buoyancy_position 773.406494 cc
2020-09-29T18:15:02.175Z,1601403302.175 [Reporter](INFO): platform_buoyancy_position 768.974548 cc
2020-09-29T18:15:02.566Z,1601403302.566 [Reporter](INFO): platform_buoyancy_position 764.542664 cc
2020-09-29T18:15:02.979Z,1601403302.979 [Reporter](INFO): platform_buoyancy_position 758.230591 cc
2020-09-29T18:15:03.399Z,1601403303.399 [Reporter](INFO): platform_buoyancy_position 753.395752 cc
2020-09-29T18:15:03.774Z,1601403303.774 [Reporter](INFO): platform_buoyancy_position 747.486572 cc
2020-09-29T18:15:04.202Z,1601403304.202 [Reporter](INFO): platform_buoyancy_position 742.786072 cc
2020-09-29T18:15:04.602Z,1601403304.602 [Reporter](INFO): platform_buoyancy_position 736.876892 cc
2020-09-29T18:15:05.026Z,1601403305.026 [Reporter](INFO): platform_buoyancy_position 730.430481 cc
2020-09-29T18:15:05.399Z,1601403305.399 [Reporter](INFO): platform_buoyancy_position 726.132874 cc
2020-09-29T18:15:05.810Z,1601403305.810 [Reporter](INFO): platform_buoyancy_position 721.566650 cc
2020-09-29T18:15:06.218Z,1601403306.218 [Reporter](INFO): platform_buoyancy_position 715.388855 cc
2020-09-29T18:15:06.623Z,1601403306.623 [Reporter](INFO): platform_buoyancy_position 710.956970 cc
2020-09-29T18:15:07.019Z,1601403307.019 [Reporter](INFO): platform_buoyancy_position 704.510559 cc
2020-09-29T18:15:07.435Z,1601403307.435 [Reporter](INFO): platform_buoyancy_position 700.078674 cc
2020-09-29T18:15:07.810Z,1601403307.810 [Reporter](INFO): platform_buoyancy_position 694.303772 cc
2020-09-29T18:15:08.255Z,1601403308.255 [Reporter](INFO): platform_buoyancy_position 689.468994 cc
2020-09-29T18:15:08.635Z,1601403308.635 [Reporter](INFO): platform_buoyancy_position 683.291138 cc
2020-09-29T18:15:09.062Z,1601403309.062 [Reporter](INFO): platform_buoyancy_position 678.724976 cc
2020-09-29T18:15:09.448Z,1601403309.448 [Reporter](INFO): platform_buoyancy_position 672.412842 cc
2020-09-29T18:15:09.827Z,1601403309.827 [Reporter](INFO): platform_buoyancy_position 668.115234 cc
2020-09-29T18:15:10.192Z,1601403310.192 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-09-29T18:15:10.261Z,1601403310.261 [Reporter](INFO): platform_buoyancy_position 663.011841 cc
2020-09-29T18:15:10.658Z,1601403310.658 [Reporter](INFO): platform_buoyancy_position 657.236938 cc
2020-09-29T18:15:11.118Z,1601403311.118 [Reporter](INFO): platform_buoyancy_position 650.790588 cc
2020-09-29T18:15:11.462Z,1601403311.462 [Reporter](INFO): platform_buoyancy_position 646.627258 cc
2020-09-29T18:15:11.870Z,1601403311.870 [Reporter](INFO): platform_buoyancy_position 642.195374 cc
2020-09-29T18:15:12.311Z,1601403312.311 [Reporter](INFO): platform_buoyancy_position 635.748962 cc
2020-09-29T18:15:12.671Z,1601403312.671 [Reporter](INFO): platform_buoyancy_position 631.451355 cc
2020-09-29T18:15:13.087Z,1601403313.087 [Reporter](INFO): platform_buoyancy_position 625.407837 cc
2020-09-29T18:15:13.474Z,1601403313.474 [Reporter](INFO): platform_buoyancy_position 620.707336 cc
2020-09-29T18:15:13.892Z,1601403313.892 [Reporter](INFO): platform_buoyancy_position 614.395264 cc
2020-09-29T18:15:14.323Z,1601403314.323 [Reporter](INFO): platform_buoyancy_position 609.963379 cc
2020-09-29T18:15:14.682Z,1601403314.682 [Reporter](INFO): platform_buoyancy_position 603.651245 cc
2020-09-29T18:15:15.091Z,1601403315.091 [Reporter](INFO): platform_buoyancy_position 599.353638 cc
2020-09-29T18:15:15.558Z,1601403315.558 [Reporter](INFO): platform_buoyancy_position 592.907288 cc
2020-09-29T18:15:15.934Z,1601403315.934 [Reporter](INFO): platform_buoyancy_position 588.475342 cc
2020-09-29T18:15:16.337Z,1601403316.337 [Reporter](INFO): platform_buoyancy_position 582.163269 cc
2020-09-29T18:15:16.718Z,1601403316.718 [Reporter](INFO): platform_buoyancy_position 577.865662 cc
2020-09-29T18:15:17.138Z,1601403317.138 [Reporter](INFO): platform_buoyancy_position 572.896545 cc
2020-09-29T18:15:17.527Z,1601403317.527 [Reporter](INFO): platform_buoyancy_position 566.987366 cc
2020-09-29T18:15:17.979Z,1601403317.979 [Reporter](INFO): platform_buoyancy_position 560.675232 cc
2020-09-29T18:15:18.363Z,1601403318.363 [Reporter](INFO): platform_buoyancy_position 556.109070 cc
2020-09-29T18:15:18.722Z,1601403318.722 [Reporter](INFO): platform_buoyancy_position 551.945740 cc
2020-09-29T18:15:19.163Z,1601403319.163 [Reporter](INFO): platform_buoyancy_position 545.499329 cc
2020-09-29T18:15:19.543Z,1601403319.543 [Reporter](INFO): platform_buoyancy_position 541.201782 cc
2020-09-29T18:15:19.987Z,1601403319.987 [Reporter](INFO): platform_buoyancy_position 534.889648 cc
2020-09-29T18:15:20.312Z,1601403320.312 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-09-29T18:15:20.386Z,1601403320.386 [Reporter](INFO): platform_buoyancy_position 530.457764 cc
2020-09-29T18:15:20.739Z,1601403320.739 [Reporter](INFO): platform_buoyancy_position 524.145630 cc
2020-09-29T18:15:21.175Z,1601403321.175 [Reporter](INFO): platform_buoyancy_position 519.713745 cc
2020-09-29T18:15:21.574Z,1601403321.574 [Reporter](INFO): platform_buoyancy_position 513.535950 cc
2020-09-29T18:15:21.980Z,1601403321.980 [Reporter](INFO): platform_buoyancy_position 508.969696 cc
2020-09-29T18:15:22.378Z,1601403322.378 [Reporter](INFO): platform_buoyancy_position 502.657684 cc
2020-09-29T18:15:22.789Z,1601403322.789 [Reporter](INFO): platform_buoyancy_position 498.360016 cc
2020-09-29T18:15:23.198Z,1601403323.198 [Reporter](INFO): platform_buoyancy_position 493.525299 cc
2020-09-29T18:15:23.573Z,1601403323.573 [Reporter](INFO): platform_buoyancy_position 487.481750 cc
2020-09-29T18:15:24.003Z,1601403324.003 [Reporter](INFO): platform_buoyancy_position 483.184143 cc
2020-09-29T18:15:24.418Z,1601403324.418 [Reporter](INFO): platform_buoyancy_position 476.872070 cc
2020-09-29T18:15:24.782Z,1601403324.782 [Reporter](INFO): platform_buoyancy_position 472.574463 cc
2020-09-29T18:15:25.267Z,1601403325.267 [Reporter](INFO): platform_buoyancy_position 466.128052 cc
2020-09-29T18:15:25.602Z,1601403325.602 [Reporter](INFO): platform_buoyancy_position 461.696167 cc
2020-09-29T18:15:26.026Z,1601403326.026 [Reporter](INFO): platform_buoyancy_position 455.518372 cc
2020-09-29T18:15:26.439Z,1601403326.439 [Reporter](INFO): platform_buoyancy_position 451.086426 cc
2020-09-29T18:15:26.814Z,1601403326.814 [Reporter](INFO): platform_buoyancy_position 444.640076 cc
2020-09-29T18:15:27.231Z,1601403327.231 [Reporter](INFO): platform_buoyancy_position 440.208130 cc
2020-09-29T18:15:27.646Z,1601403327.646 [Reporter](INFO): platform_buoyancy_position 433.896057 cc
2020-09-29T18:15:28.059Z,1601403328.059 [Reporter](INFO): platform_buoyancy_position 429.464172 cc
2020-09-29T18:15:28.426Z,1601403328.426 [Reporter](INFO): platform_buoyancy_position 425.166565 cc
2020-09-29T18:15:28.839Z,1601403328.839 [Reporter](INFO): platform_buoyancy_position 418.720154 cc
2020-09-29T18:15:29.267Z,1601403329.267 [Reporter](INFO): platform_buoyancy_position 412.408020 cc
2020-09-29T18:15:29.635Z,1601403329.635 [Reporter](INFO): platform_buoyancy_position 408.110474 cc
2020-09-29T18:15:30.098Z,1601403330.098 [Reporter](INFO): platform_buoyancy_position 401.529724 cc
2020-09-29T18:15:30.462Z,1601403330.462 [Reporter](INFO): platform_buoyancy_position 397.366455 cc
2020-09-29T18:15:30.854Z,1601403330.854 [Reporter](INFO): platform_buoyancy_position 390.920044 cc
2020-09-29T18:15:31.274Z,1601403331.274 [Reporter](INFO): platform_buoyancy_position 386.488159 cc
2020-09-29T18:15:31.667Z,1601403331.667 [Reporter](INFO): platform_buoyancy_position 381.921936 cc
2020-09-29T18:15:32.079Z,1601403332.079 [Reporter](INFO): platform_buoyancy_position 375.744141 cc
2020-09-29T18:15:32.486Z,1601403332.486 [Reporter](INFO): platform_buoyancy_position 371.580872 cc
2020-09-29T18:15:32.866Z,1601403332.866 [Reporter](INFO): platform_buoyancy_position 365.134460 cc
2020-09-29T18:15:33.330Z,1601403333.330 [Reporter](INFO): platform_buoyancy_position 358.553711 cc
2020-09-29T18:15:33.695Z,1601403333.695 [Reporter](INFO): platform_buoyancy_position 354.256165 cc
2020-09-29T18:15:34.135Z,1601403334.135 [Reporter](INFO): platform_buoyancy_position 349.824219 cc
2020-09-29T18:15:34.475Z,1601403334.475 [Reporter](INFO): platform_buoyancy_position 345.660950 cc
2020-09-29T18:15:34.904Z,1601403334.904 [Reporter](INFO): platform_buoyancy_position 339.214539 cc
2020-09-29T18:15:35.335Z,1601403335.335 [Reporter](INFO): platform_buoyancy_position 332.902466 cc
2020-09-29T18:15:35.702Z,1601403335.702 [Reporter](INFO): platform_buoyancy_position 328.470520 cc
2020-09-29T18:15:36.098Z,1601403336.098 [Reporter](INFO): platform_buoyancy_position 324.172913 cc
2020-09-29T18:15:36.534Z,1601403336.534 [Reporter](INFO): platform_buoyancy_position 317.860840 cc
2020-09-29T18:15:36.943Z,1601403336.943 [Reporter](INFO): platform_buoyancy_position 313.428955 cc
2020-09-29T18:15:37.370Z,1601403337.370 [Reporter](INFO): platform_buoyancy_position 307.116852 cc
2020-09-29T18:15:37.715Z,1601403337.715 [Reporter](INFO): platform_buoyancy_position 302.684937 cc
2020-09-29T18:15:38.131Z,1601403338.131 [Reporter](INFO): platform_buoyancy_position 296.372833 cc
2020-09-29T18:15:38.534Z,1601403338.534 [Reporter](INFO): platform_buoyancy_position 291.940948 cc
2020-09-29T18:15:38.937Z,1601403338.937 [Reporter](INFO): platform_buoyancy_position 286.837524 cc
2020-09-29T18:15:39.343Z,1601403339.343 [Reporter](INFO): platform_buoyancy_position 281.331238 cc
2020-09-29T18:15:39.746Z,1601403339.746 [Reporter](INFO): platform_buoyancy_position 274.884827 cc
2020-09-29T18:15:40.182Z,1601403340.182 [Reporter](INFO): platform_buoyancy_position 270.587250 cc
2020-09-29T18:15:40.547Z,1601403340.547 [Reporter](INFO): platform_buoyancy_position 264.140839 cc
2020-09-29T18:15:40.994Z,1601403340.994 [Reporter](INFO): platform_buoyancy_position 259.843231 cc
2020-09-29T18:16:24.536Z,1601403384.536 [CommandLine](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 200.000000 cubic_centimeter
2020-09-29T18:16:30.303Z,1601403390.303 [Reporter](INFO): platform_buoyancy_position 253.262558 cc
2020-09-29T18:16:30.647Z,1601403390.647 [Reporter](INFO): platform_buoyancy_position 253.396805 cc
2020-09-29T18:16:31.446Z,1601403391.446 [Reporter](INFO): platform_buoyancy_position 249.636444 cc
2020-09-29T18:16:31.854Z,1601403391.854 [Reporter](INFO): platform_buoyancy_position 249.367813 cc
2020-09-29T18:16:32.311Z,1601403392.311 [Reporter](INFO): platform_buoyancy_position 249.233536 cc
2020-09-29T18:16:32.688Z,1601403392.688 [Reporter](INFO): platform_buoyancy_position 249.099243 cc
2020-09-29T18:16:33.110Z,1601403393.110 [Reporter](INFO): platform_buoyancy_position 246.950424 cc
2020-09-29T18:16:33.471Z,1601403393.471 [Reporter](INFO): platform_buoyancy_position 246.547546 cc
2020-09-29T18:16:33.886Z,1601403393.886 [Reporter](INFO): platform_buoyancy_position 244.667313 cc
2020-09-29T18:16:34.303Z,1601403394.303 [Reporter](INFO): platform_buoyancy_position 244.533035 cc
2020-09-29T18:16:34.687Z,1601403394.687 [Reporter](INFO): platform_buoyancy_position 242.384247 cc
2020-09-29T18:16:35.107Z,1601403395.107 [Reporter](INFO): platform_buoyancy_position 240.504028 cc
2020-09-29T18:16:35.499Z,1601403395.499 [Reporter](INFO): platform_buoyancy_position 240.369720 cc
2020-09-29T18:16:35.907Z,1601403395.907 [Reporter](INFO): platform_buoyancy_position 238.355225 cc
2020-09-29T18:16:36.330Z,1601403396.330 [Reporter](INFO): platform_buoyancy_position 237.415131 cc
2020-09-29T18:16:36.731Z,1601403396.731 [Reporter](INFO): platform_buoyancy_position 236.072144 cc
2020-09-29T18:16:37.126Z,1601403397.126 [Reporter](INFO): platform_buoyancy_position 234.057617 cc
2020-09-29T18:16:37.535Z,1601403397.535 [Reporter](INFO): platform_buoyancy_position 233.923340 cc
2020-09-29T18:16:37.992Z,1601403397.992 [Reporter](INFO): platform_buoyancy_position 231.774536 cc
2020-09-29T18:16:38.339Z,1601403398.339 [Reporter](INFO): platform_buoyancy_position 230.028625 cc
2020-09-29T18:16:38.711Z,1601403398.711 [Reporter](INFO): platform_buoyancy_position 229.491425 cc
2020-09-29T18:16:39.091Z,1601403399.091 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-09-29T18:16:39.143Z,1601403399.143 [Reporter](INFO): platform_buoyancy_position 227.611237 cc
2020-09-29T18:16:39.546Z,1601403399.546 [Reporter](INFO): platform_buoyancy_position 227.476929 cc
2020-09-29T18:16:39.932Z,1601403399.932 [Reporter](INFO): platform_buoyancy_position 225.328125 cc
2020-09-29T18:16:40.379Z,1601403400.379 [Reporter](INFO): platform_buoyancy_position 223.582245 cc
2020-09-29T18:16:40.750Z,1601403400.750 [Reporter](INFO): platform_buoyancy_position 221.164825 cc
2020-09-29T18:16:41.167Z,1601403401.167 [Reporter](INFO): platform_buoyancy_position 221.433441 cc
2020-09-29T18:16:41.555Z,1601403401.555 [Reporter](INFO): platform_buoyancy_position 221.030518 cc
2020-09-29T18:16:41.958Z,1601403401.958 [Reporter](INFO): platform_buoyancy_position 218.881744 cc
2020-09-29T18:16:42.370Z,1601403402.370 [Reporter](INFO): platform_buoyancy_position 217.135834 cc
2020-09-29T18:16:42.774Z,1601403402.774 [Reporter](INFO): platform_buoyancy_position 216.732941 cc
2020-09-29T18:16:43.198Z,1601403403.198 [Reporter](INFO): platform_buoyancy_position 214.584137 cc
2020-09-29T18:16:44.026Z,1601403404.026 [Reporter](INFO): platform_buoyancy_position 212.569641 cc
2020-09-29T18:16:44.399Z,1601403404.399 [Reporter](INFO): platform_buoyancy_position 210.286530 cc
2020-09-29T18:16:45.250Z,1601403405.250 [Reporter](INFO): platform_buoyancy_position 208.137726 cc
2020-09-29T18:18:02.725Z,1601403482.725 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting.
2020-09-29T18:18:02.725Z,1601403482.725 [DefaultWithUndock:CheckIn:C.Wait] Stopped
2020-09-29T18:18:02.725Z,1601403482.725 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-09-29T18:18:02.725Z,1601403482.725 [DefaultWithUndock:CheckIn:D] Running Loop=1
2020-09-29T18:18:03.120Z,1601403483.120 [DefaultWithUndock:CheckIn:D] Stopped
2020-09-29T18:18:03.120Z,1601403483.120 [DefaultWithUndock:CheckIn:E] Running Loop=1
2020-09-29T18:18:03.522Z,1601403483.522 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 11.883902 min 
2020-09-29T18:18:03.522Z,1601403483.522 [DefaultWithUndock:CheckIn:E] Stopped
2020-09-29T18:18:03.522Z,1601403483.522 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn
2020-09-29T18:18:03.522Z,1601403483.522 [DefaultWithUndock:CheckIn] Stopped
2020-09-29T18:18:03.523Z,1601403483.523 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-09-29T18:18:03.523Z,1601403483.523 [DefaultWithUndock:CheckIn](INFO): Running loop #2
2020-09-29T18:18:03.523Z,1601403483.523 [DefaultWithUndock:CheckIn] Running Loop=2
2020-09-29T18:18:03.523Z,1601403483.523 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-09-29T18:18:03.523Z,1601403483.523 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-09-29T18:18:05.154Z,1601403485.154 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-09-29T18:18:05.154Z,1601403485.154 [NAL9602] Data Fault, FailCount= 1
2020-09-29T18:18:05.154Z,1601403485.154 [NAL9602](ERROR): Data Fault
2020-09-29T18:18:05.271Z,1601403485.271 [CBIT](ERROR): Data Fault in component: NAL9602
2020-09-29T18:18:05.522Z,1601403485.522 [NAL9602](INFO): Powering down
2020-09-29T18:18:06.400Z,1601403486.400 [CBIT](INFO): Clearing failed state for component NAL9602
2020-09-29T18:18:06.400Z,1601403486.400 [NAL9602] No Fault, FailCount= 1
2020-09-29T18:18:11.397Z,1601403491.397 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massDefault 2.000000 millimeter
2020-09-29T18:18:11.398Z,1601403491.398 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.massDefault" handled in the control thread.
2020-09-29T18:18:11.632Z,1601403491.632 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,DDM,Depth_Keller,DUSBL_Hydroid,Micromodem,NAL9602,PowerOnly,RDI_Pathfinder,RDI_PathfinderUp,BPC1,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,WorkSite,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.buoyancyAction,Maintain_VerticalControl.massDefault,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2020-09-29T18:18:35.819Z,1601403515.819 [NAL9602](INFO): Powering up NAL9602
2020-09-29T18:18:43.749Z,1601403523.749 [CommandLine](IMPORTANT): got command get VerticalControl.massDefault
2020-09-29T18:18:43.750Z,1601403523.750 [CommandLine](IMPORTANT): VerticalControl.massDefault 0.200000 cm
2020-09-29T18:18:46.731Z,1601403526.731 [NAL9602](INFO): NAL9602 initialized
2020-09-29T18:18:56.831Z,1601403536.831 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massDefault 4.000000 millimeter
2020-09-29T18:19:21.403Z,1601403561.403 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massDefault 10.000000 millimeter
2020-09-29T18:19:50.851Z,1601403590.851 [CommandLine](IMPORTANT): got command maintain clear
2020-09-29T18:19:51.018Z,1601403591.018 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,DDM,Depth_Keller,DUSBL_Hydroid,Micromodem,NAL9602,PowerOnly,RDI_Pathfinder,RDI_PathfinderUp,BPC1,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,WorkSite,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2020-09-29T18:19:56.762Z,1601403596.762 [Reporter](INFO): platform_buoyancy_position 203.974426 cc
2020-09-29T18:19:57.527Z,1601403597.527 [Reporter](INFO): platform_buoyancy_position 203.840118 cc
2020-09-29T18:19:57.947Z,1601403597.947 [Reporter](INFO): platform_buoyancy_position 203.974426 cc
2020-09-29T18:19:58.367Z,1601403598.367 [Reporter](INFO): platform_buoyancy_position 210.420837 cc
2020-09-29T18:19:58.730Z,1601403598.730 [Reporter](INFO): platform_buoyancy_position 214.852722 cc
2020-09-29T18:19:59.171Z,1601403599.171 [Reporter](INFO): platform_buoyancy_position 222.776428 cc
2020-09-29T18:19:59.529Z,1601403599.529 [Reporter](INFO): platform_buoyancy_position 227.476929 cc
2020-09-29T18:19:59.935Z,1601403599.935 [Reporter](INFO): platform_buoyancy_position 233.520447 cc
2020-09-29T18:20:00.391Z,1601403600.391 [Reporter](INFO): platform_buoyancy_position 240.369720 cc
2020-09-29T18:20:00.754Z,1601403600.754 [Reporter](INFO): platform_buoyancy_position 244.801636 cc
2020-09-29T18:20:01.187Z,1601403601.187 [Reporter](INFO): platform_buoyancy_position 251.113724 cc
2020-09-29T18:20:01.550Z,1601403601.550 [Reporter](INFO): platform_buoyancy_position 255.545624 cc
2020-09-29T18:20:01.964Z,1601403601.964 [Reporter](INFO): platform_buoyancy_position 259.843231 cc
2020-09-29T18:20:02.394Z,1601403602.394 [Reporter](INFO): platform_buoyancy_position 266.289642 cc
2020-09-29T18:20:02.790Z,1601403602.790 [Reporter](INFO): platform_buoyancy_position 272.601746 cc
2020-09-29T18:20:03.175Z,1601403603.175 [Reporter](INFO): platform_buoyancy_position 276.899323 cc
2020-09-29T18:20:03.682Z,1601403603.682 [Reporter](INFO): platform_buoyancy_position 281.331238 cc
2020-09-29T18:20:03.991Z,1601403603.991 [Reporter](INFO): platform_buoyancy_position 287.643341 cc
2020-09-29T18:20:04.351Z,1601403604.351 [Reporter](INFO): platform_buoyancy_position 292.075226 cc
2020-09-29T18:20:04.770Z,1601403604.770 [Reporter](INFO): platform_buoyancy_position 298.521637 cc
2020-09-29T18:20:05.243Z,1601403605.243 [Reporter](INFO): platform_buoyancy_position 304.699432 cc
2020-09-29T18:20:05.583Z,1601403605.583 [Reporter](INFO): platform_buoyancy_position 309.265625 cc
2020-09-29T18:20:05.983Z,1601403605.983 [Reporter](INFO): platform_buoyancy_position 313.563232 cc
2020-09-29T18:20:06.416Z,1601403606.416 [Reporter](INFO): platform_buoyancy_position 319.741028 cc
2020-09-29T18:20:06.798Z,1601403606.798 [Reporter](INFO): platform_buoyancy_position 324.307251 cc
2020-09-29T18:20:07.211Z,1601403607.211 [Reporter](INFO): platform_buoyancy_position 328.739136 cc
2020-09-29T18:20:07.638Z,1601403607.638 [Reporter](INFO): platform_buoyancy_position 334.916931 cc
2020-09-29T18:20:08.055Z,1601403608.055 [Reporter](INFO): platform_buoyancy_position 339.483154 cc
2020-09-29T18:20:08.430Z,1601403608.430 [Reporter](INFO): platform_buoyancy_position 345.795227 cc
2020-09-29T18:20:08.798Z,1601403608.798 [Reporter](INFO): platform_buoyancy_position 350.092834 cc
2020-09-29T18:20:09.223Z,1601403609.223 [Reporter](INFO): platform_buoyancy_position 354.390442 cc
2020-09-29T18:20:09.627Z,1601403609.627 [Reporter](INFO): platform_buoyancy_position 360.836853 cc
2020-09-29T18:20:10.079Z,1601403610.079 [Reporter](INFO): platform_buoyancy_position 367.148926 cc
2020-09-29T18:20:10.423Z,1601403610.423 [Reporter](INFO): platform_buoyancy_position 371.446533 cc
2020-09-29T18:20:10.829Z,1601403610.829 [Reporter](INFO): platform_buoyancy_position 378.027222 cc
2020-09-29T18:20:11.267Z,1601403611.267 [Reporter](INFO): platform_buoyancy_position 382.324829 cc
2020-09-29T18:20:11.658Z,1601403611.658 [Reporter](INFO): platform_buoyancy_position 388.636963 cc
2020-09-29T18:20:12.075Z,1601403612.075 [Reporter](INFO): platform_buoyancy_position 393.068848 cc
2020-09-29T18:20:12.463Z,1601403612.463 [Reporter](INFO): platform_buoyancy_position 398.978027 cc
2020-09-29T18:20:12.871Z,1601403612.871 [Reporter](INFO): platform_buoyancy_position 403.678528 cc
2020-09-29T18:20:13.263Z,1601403613.263 [Reporter](INFO): platform_buoyancy_position 408.244751 cc
2020-09-29T18:20:13.662Z,1601403613.662 [Reporter](INFO): platform_buoyancy_position 414.422546 cc
2020-09-29T18:20:14.102Z,1601403614.102 [Reporter](INFO): platform_buoyancy_position 419.123047 cc
2020-09-29T18:20:14.474Z,1601403614.474 [Reporter](INFO): platform_buoyancy_position 425.300842 cc
2020-09-29T18:20:14.870Z,1601403614.870 [Reporter](INFO): platform_buoyancy_position 429.598450 cc
2020-09-29T18:20:15.319Z,1601403615.319 [Reporter](INFO): platform_buoyancy_position 435.910522 cc
2020-09-29T18:20:15.697Z,1601403615.697 [Reporter](INFO): platform_buoyancy_position 440.342468 cc
2020-09-29T18:20:16.075Z,1601403616.075 [Reporter](INFO): platform_buoyancy_position 446.654541 cc
2020-09-29T18:20:16.491Z,1601403616.491 [Reporter](INFO): platform_buoyancy_position 451.086426 cc
2020-09-29T18:20:16.919Z,1601403616.919 [Reporter](INFO): platform_buoyancy_position 457.532837 cc
2020-09-29T18:20:17.319Z,1601403617.319 [Reporter](INFO): platform_buoyancy_position 461.830444 cc
2020-09-29T18:20:17.707Z,1601403617.707 [Reporter](INFO): platform_buoyancy_position 467.202454 cc
2020-09-29T18:20:18.115Z,1601403618.115 [Reporter](INFO): platform_buoyancy_position 472.440125 cc
2020-09-29T18:20:18.506Z,1601403618.506 [Reporter](INFO): platform_buoyancy_position 477.006348 cc
2020-09-29T18:20:18.932Z,1601403618.932 [Reporter](INFO): platform_buoyancy_position 483.318420 cc
2020-09-29T18:20:19.331Z,1601403619.331 [Reporter](INFO): platform_buoyancy_position 487.750366 cc
2020-09-29T18:20:19.706Z,1601403619.706 [Reporter](INFO): platform_buoyancy_position 492.988037 cc
2020-09-29T18:20:20.130Z,1601403620.130 [Reporter](INFO): platform_buoyancy_position 498.360016 cc
2020-09-29T18:20:20.526Z,1601403620.526 [Reporter](INFO): platform_buoyancy_position 504.672180 cc
2020-09-29T18:20:20.926Z,1601403620.926 [Reporter](INFO): platform_buoyancy_position 509.104065 cc
2020-09-29T18:20:21.386Z,1601403621.386 [Reporter](INFO): platform_buoyancy_position 515.416138 cc
2020-09-29T18:20:21.764Z,1601403621.764 [Reporter](INFO): platform_buoyancy_position 519.848022 cc
2020-09-29T18:20:22.143Z,1601403622.143 [Reporter](INFO): platform_buoyancy_position 525.622925 cc
2020-09-29T18:20:22.550Z,1601403622.550 [Reporter](INFO): platform_buoyancy_position 530.726379 cc
2020-09-29T18:20:22.931Z,1601403622.931 [Reporter](INFO): platform_buoyancy_position 536.904175 cc
2020-09-29T18:20:23.383Z,1601403623.383 [Reporter](INFO): platform_buoyancy_position 541.604675 cc
2020-09-29T18:20:23.739Z,1601403623.739 [Reporter](INFO): platform_buoyancy_position 546.573730 cc
2020-09-29T18:20:24.194Z,1601403624.194 [Reporter](INFO): platform_buoyancy_position 552.214355 cc
2020-09-29T18:20:24.659Z,1601403624.659 [Reporter](INFO): platform_buoyancy_position 558.526428 cc
2020-09-29T18:20:24.999Z,1601403624.999 [Reporter](INFO): platform_buoyancy_position 562.689758 cc
2020-09-29T18:20:25.382Z,1601403625.382 [Reporter](INFO): platform_buoyancy_position 567.255981 cc
2020-09-29T18:20:25.782Z,1601403625.782 [Reporter](INFO): platform_buoyancy_position 573.568054 cc
2020-09-29T18:20:26.171Z,1601403626.171 [Reporter](INFO): platform_buoyancy_position 577.865662 cc
2020-09-29T18:20:26.591Z,1601403626.591 [Reporter](INFO): platform_buoyancy_position 584.177734 cc
2020-09-29T18:20:27.014Z,1601403627.014 [Reporter](INFO): platform_buoyancy_position 588.609680 cc
2020-09-29T18:20:27.422Z,1601403627.422 [Reporter](INFO): platform_buoyancy_position 594.921753 cc
2020-09-29T18:20:27.790Z,1601403627.790 [Reporter](INFO): platform_buoyancy_position 599.219360 cc
2020-09-29T18:20:28.203Z,1601403628.203 [Reporter](INFO): platform_buoyancy_position 605.397156 cc
2020-09-29T18:20:28.608Z,1601403628.608 [Reporter](INFO): platform_buoyancy_position 609.963379 cc
2020-09-29T18:20:29.071Z,1601403629.071 [Reporter](INFO): platform_buoyancy_position 616.409790 cc
2020-09-29T18:20:29.418Z,1601403629.418 [Reporter](INFO): platform_buoyancy_position 620.841675 cc
2020-09-29T18:20:29.826Z,1601403629.826 [Reporter](INFO): platform_buoyancy_position 625.139282 cc
2020-09-29T18:20:30.299Z,1601403630.299 [Reporter](INFO): platform_buoyancy_position 631.451355 cc
2020-09-29T18:20:30.607Z,1601403630.607 [Reporter](INFO): platform_buoyancy_position 635.883240 cc
2020-09-29T18:20:31.071Z,1601403631.071 [Reporter](INFO): platform_buoyancy_position 642.061035 cc
2020-09-29T18:20:31.442Z,1601403631.442 [Reporter](INFO): platform_buoyancy_position 646.627258 cc
2020-09-29T18:20:31.818Z,1601403631.818 [Reporter](INFO): platform_buoyancy_position 651.327759 cc
2020-09-29T18:20:32.257Z,1601403632.257 [Reporter](INFO): platform_buoyancy_position 657.371277 cc
2020-09-29T18:20:32.654Z,1601403632.654 [Reporter](INFO): platform_buoyancy_position 663.817688 cc
2020-09-29T18:20:33.074Z,1601403633.074 [Reporter](INFO): platform_buoyancy_position 668.115234 cc
2020-09-29T18:20:33.475Z,1601403633.475 [Reporter](INFO): platform_buoyancy_position 674.427368 cc
2020-09-29T18:20:33.855Z,1601403633.855 [Reporter](INFO): platform_buoyancy_position 678.859253 cc
2020-09-29T18:20:34.282Z,1601403634.282 [Reporter](INFO): platform_buoyancy_position 684.231262 cc
2020-09-29T18:20:34.654Z,1601403634.654 [Reporter](INFO): platform_buoyancy_position 689.468994 cc
2020-09-29T18:20:35.067Z,1601403635.067 [Reporter](INFO): platform_buoyancy_position 693.766541 cc
2020-09-29T18:20:35.483Z,1601403635.483 [Reporter](INFO): platform_buoyancy_position 700.212952 cc
2020-09-29T18:20:35.865Z,1601403635.865 [Reporter](INFO): platform_buoyancy_position 704.510559 cc
2020-09-29T18:20:36.282Z,1601403636.282 [Reporter](INFO): platform_buoyancy_position 710.956970 cc
2020-09-29T18:20:36.703Z,1601403636.703 [Reporter](INFO): platform_buoyancy_position 715.254578 cc
2020-09-29T18:20:37.115Z,1601403637.115 [Reporter](INFO): platform_buoyancy_position 721.700989 cc
2020-09-29T18:20:37.534Z,1601403637.534 [Reporter](INFO): platform_buoyancy_position 726.132874 cc
2020-09-29T18:20:37.909Z,1601403637.909 [Reporter](INFO): platform_buoyancy_position 732.444946 cc
2020-09-29T18:20:38.311Z,1601403638.311 [Reporter](INFO): platform_buoyancy_position 736.876892 cc
2020-09-29T18:20:38.711Z,1601403638.711 [Reporter](INFO): platform_buoyancy_position 743.188965 cc
2020-09-29T18:20:39.131Z,1601403639.131 [Reporter](INFO): platform_buoyancy_position 747.620850 cc
2020-09-29T18:20:39.507Z,1601403639.507 [Reporter](INFO): platform_buoyancy_position 751.784180 cc
2020-09-29T18:20:39.956Z,1601403639.956 [Reporter](INFO): platform_buoyancy_position 758.230591 cc
2020-09-29T18:20:40.387Z,1601403640.387 [Reporter](INFO): platform_buoyancy_position 764.811279 cc
2020-09-29T18:20:40.735Z,1601403640.735 [Reporter](INFO): platform_buoyancy_position 769.108887 cc
2020-09-29T18:20:41.235Z,1601403641.235 [Reporter](INFO): platform_buoyancy_position 775.420959 cc
2020-09-29T18:20:41.534Z,1601403641.534 [Reporter](INFO): platform_buoyancy_position 779.718567 cc
2020-09-29T18:20:41.977Z,1601403641.977 [Reporter](INFO): platform_buoyancy_position 784.150452 cc
2020-09-29T18:20:42.415Z,1601403642.415 [Reporter](INFO): platform_buoyancy_position 790.462585 cc
2020-09-29T18:20:42.768Z,1601403642.768 [Reporter](INFO): platform_buoyancy_position 794.760193 cc
2020-09-29T18:20:43.167Z,1601403643.167 [Reporter](INFO): platform_buoyancy_position 801.206543 cc
2020-09-29T18:20:43.580Z,1601403643.580 [Reporter](INFO): platform_buoyancy_position 805.638489 cc
2020-09-29T18:20:44.014Z,1601403644.014 [Reporter](INFO): platform_buoyancy_position 812.084900 cc
2020-09-29T18:20:44.435Z,1601403644.435 [Reporter](INFO): platform_buoyancy_position 817.456848 cc
2020-09-29T18:20:44.790Z,1601403644.790 [Reporter](INFO): platform_buoyancy_position 822.694580 cc
2020-09-29T18:20:45.245Z,1601403645.245 [Reporter](INFO): platform_buoyancy_position 827.126465 cc
2020-09-29T18:20:45.619Z,1601403645.619 [Reporter](INFO): platform_buoyancy_position 833.438599 cc
2020-09-29T18:20:46.038Z,1601403646.038 [Reporter](INFO): platform_buoyancy_position 837.870483 cc
2020-09-29T18:20:46.427Z,1601403646.427 [Reporter](INFO): platform_buoyancy_position 844.182617 cc
2020-09-29T18:20:46.819Z,1601403646.819 [Reporter](INFO): platform_buoyancy_position 848.614502 cc
2020-09-29T18:20:47.207Z,1601403647.207 [Reporter](INFO): platform_buoyancy_position 852.912109 cc
2020-09-29T18:20:47.610Z,1601403647.610 [Reporter](INFO): platform_buoyancy_position 859.224121 cc
2020-09-29T18:20:48.019Z,1601403648.019 [Reporter](INFO): platform_buoyancy_position 863.790405 cc
2020-09-29T18:20:48.407Z,1601403648.407 [Reporter](INFO): platform_buoyancy_position 869.968140 cc
2020-09-29T18:20:48.787Z,1601403648.787 [Reporter](INFO): platform_buoyancy_position 874.265747 cc
2020-09-29T18:20:49.248Z,1601403649.248 [Reporter](INFO): platform_buoyancy_position 880.712158 cc
2020-09-29T18:20:49.599Z,1601403649.599 [Reporter](INFO): platform_buoyancy_position 885.144043 cc
2020-09-29T18:20:50.139Z,1601403650.139 [Reporter](INFO): platform_buoyancy_position 891.590454 cc
2020-09-29T18:20:50.447Z,1601403650.447 [Reporter](INFO): platform_buoyancy_position 895.753784 cc
2020-09-29T18:20:50.834Z,1601403650.834 [Reporter](INFO): platform_buoyancy_position 902.200195 cc
2020-09-29T18:20:51.287Z,1601403651.287 [Reporter](INFO): platform_buoyancy_position 908.646606 cc
2020-09-29T18:20:51.630Z,1601403651.630 [Reporter](INFO): platform_buoyancy_position 913.078491 cc
2020-09-29T18:20:52.066Z,1601403652.066 [Reporter](INFO): platform_buoyancy_position 917.376099 cc
2020-09-29T18:20:52.439Z,1601403652.439 [Reporter](INFO): platform_buoyancy_position 923.688232 cc
2020-09-29T18:20:52.846Z,1601403652.846 [Reporter](INFO): platform_buoyancy_position 927.985840 cc
2020-09-29T18:20:53.236Z,1601403653.236 [CommandLine](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 200.000000 cubic_centimeter
2020-09-29T18:20:53.247Z,1601403653.247 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.buoyancyAction" handled in the control thread.
2020-09-29T18:20:53.307Z,1601403653.307 [Reporter](INFO): platform_buoyancy_position 934.566528 cc
2020-09-29T18:20:53.312Z,1601403653.312 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,DDM,Depth_Keller,DUSBL_Hydroid,Micromodem,NAL9602,PowerOnly,RDI_Pathfinder,RDI_PathfinderUp,BPC1,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,WorkSite,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.buoyancyAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2020-09-29T18:20:53.696Z,1601403653.696 [Reporter](INFO): platform_buoyancy_position 938.729736 cc
2020-09-29T18:20:54.134Z,1601403654.134 [Reporter](INFO): platform_buoyancy_position 943.027344 cc
2020-09-29T18:20:54.498Z,1601403654.498 [Reporter](INFO): platform_buoyancy_position 945.310425 cc
2020-09-29T18:20:54.874Z,1601403654.874 [Reporter](INFO): platform_buoyancy_position 945.176147 cc
2020-09-29T18:20:55.334Z,1601403655.334 [Reporter](INFO): platform_buoyancy_position 945.310425 cc
2020-09-29T18:20:55.699Z,1601403655.699 [Reporter](INFO): platform_buoyancy_position 941.012939 cc
2020-09-29T18:20:56.067Z,1601403656.067 [Reporter](INFO): platform_buoyancy_position 936.715332 cc
2020-09-29T18:20:56.483Z,1601403656.483 [Reporter](INFO): platform_buoyancy_position 932.014771 cc
2020-09-29T18:20:56.902Z,1601403656.902 [Reporter](INFO): platform_buoyancy_position 925.971313 cc
2020-09-29T18:20:57.285Z,1601403657.285 [Reporter](INFO): platform_buoyancy_position 921.405029 cc
2020-09-29T18:20:57.691Z,1601403657.691 [Reporter](INFO): platform_buoyancy_position 915.227295 cc
2020-09-29T18:20:58.127Z,1601403658.127 [Reporter](INFO): platform_buoyancy_position 908.780884 cc
2020-09-29T18:20:58.483Z,1601403658.483 [Reporter](INFO): platform_buoyancy_position 904.483276 cc
2020-09-29T18:20:58.922Z,1601403658.922 [Reporter](INFO): platform_buoyancy_position 898.036865 cc
2020-09-29T18:20:59.335Z,1601403659.335 [Reporter](INFO): platform_buoyancy_position 893.604980 cc
2020-09-29T18:20:59.734Z,1601403659.734 [Reporter](INFO): platform_buoyancy_position 889.173096 cc
2020-09-29T18:21:00.159Z,1601403660.159 [Reporter](INFO): platform_buoyancy_position 882.860962 cc
2020-09-29T18:21:00.522Z,1601403660.522 [Reporter](INFO): platform_buoyancy_position 878.563354 cc
2020-09-29T18:21:00.930Z,1601403660.930 [Reporter](INFO): platform_buoyancy_position 872.251221 cc
2020-09-29T18:21:01.359Z,1601403661.359 [Reporter](INFO): platform_buoyancy_position 867.685059 cc
2020-09-29T18:21:01.759Z,1601403661.759 [Reporter](INFO): platform_buoyancy_position 861.507324 cc
2020-09-29T18:21:02.171Z,1601403662.171 [Reporter](INFO): platform_buoyancy_position 857.075317 cc
2020-09-29T18:21:02.550Z,1601403662.550 [Reporter](INFO): platform_buoyancy_position 850.763306 cc
2020-09-29T18:21:02.952Z,1601403662.952 [Reporter](INFO): platform_buoyancy_position 846.331421 cc
2020-09-29T18:21:03.387Z,1601403663.387 [Reporter](INFO): platform_buoyancy_position 840.019287 cc
2020-09-29T18:21:03.751Z,1601403663.751 [Reporter](INFO): platform_buoyancy_position 835.721680 cc
2020-09-29T18:21:04.163Z,1601403664.163 [Reporter](INFO): platform_buoyancy_position 829.812500 cc
2020-09-29T18:21:04.564Z,1601403664.564 [Reporter](INFO): platform_buoyancy_position 824.977661 cc
2020-09-29T18:21:04.994Z,1601403664.994 [Reporter](INFO): platform_buoyancy_position 818.665588 cc
2020-09-29T18:21:05.359Z,1601403665.359 [Reporter](INFO): platform_buoyancy_position 814.233704 cc
2020-09-29T18:21:05.774Z,1601403665.774 [Reporter](INFO): platform_buoyancy_position 809.801758 cc
2020-09-29T18:21:06.182Z,1601403666.182 [Reporter](INFO): platform_buoyancy_position 803.489685 cc
2020-09-29T18:21:06.591Z,1601403666.591 [Reporter](INFO): platform_buoyancy_position 799.057800 cc
2020-09-29T18:21:06.983Z,1601403666.983 [Reporter](INFO): platform_buoyancy_position 792.745667 cc
2020-09-29T18:21:07.402Z,1601403667.402 [Reporter](INFO): platform_buoyancy_position 788.448059 cc
2020-09-29T18:21:07.775Z,1601403667.775 [Reporter](INFO): platform_buoyancy_position 782.135986 cc
2020-09-29T18:21:08.199Z,1601403668.199 [Reporter](INFO): platform_buoyancy_position 777.569763 cc
2020-09-29T18:21:08.610Z,1601403668.610 [Reporter](INFO): platform_buoyancy_position 771.257690 cc
2020-09-29T18:21:09.067Z,1601403669.067 [Reporter](INFO): platform_buoyancy_position 766.825745 cc
2020-09-29T18:21:09.398Z,1601403669.398 [Reporter](INFO): platform_buoyancy_position 762.528198 cc
2020-09-29T18:21:09.810Z,1601403669.810 [Reporter](INFO): platform_buoyancy_position 756.216064 cc
2020-09-29T18:21:10.299Z,1601403670.299 [Reporter](INFO): platform_buoyancy_position 749.903992 cc
2020-09-29T18:21:10.634Z,1601403670.634 [Reporter](INFO): platform_buoyancy_position 745.472046 cc
2020-09-29T18:21:11.073Z,1601403671.073 [Reporter](INFO): platform_buoyancy_position 740.771545 cc
2020-09-29T18:21:11.427Z,1601403671.427 [Reporter](INFO): platform_buoyancy_position 734.862366 cc
2020-09-29T18:21:11.834Z,1601403671.834 [Reporter](INFO): platform_buoyancy_position 730.161865 cc
2020-09-29T18:21:12.267Z,1601403672.267 [Reporter](INFO): platform_buoyancy_position 724.118347 cc
2020-09-29T18:21:12.635Z,1601403672.635 [Reporter](INFO): platform_buoyancy_position 719.686462 cc
2020-09-29T18:21:13.063Z,1601403673.063 [Reporter](INFO): platform_buoyancy_position 713.105774 cc
2020-09-29T18:21:13.438Z,1601403673.438 [Reporter](INFO): platform_buoyancy_position 708.808167 cc
2020-09-29T18:21:13.850Z,1601403673.850 [Reporter](INFO): platform_buoyancy_position 702.496094 cc
2020-09-29T18:21:14.267Z,1601403674.267 [Reporter](INFO): platform_buoyancy_position 698.198486 cc
2020-09-29T18:21:14.686Z,1601403674.686 [Reporter](INFO): platform_buoyancy_position 691.752075 cc
2020-09-29T18:21:15.103Z,1601403675.103 [Reporter](INFO): platform_buoyancy_position 687.320190 cc
2020-09-29T18:21:15.471Z,1601403675.471 [Reporter](INFO): platform_buoyancy_position 680.873779 cc
2020-09-29T18:21:15.863Z,1601403675.863 [Reporter](INFO): platform_buoyancy_position 676.710449 cc
2020-09-29T18:21:16.282Z,1601403676.282 [Reporter](INFO): platform_buoyancy_position 672.144287 cc
2020-09-29T18:21:16.690Z,1601403676.690 [Reporter](INFO): platform_buoyancy_position 666.100769 cc
2020-09-29T18:21:17.092Z,1601403677.092 [Reporter](INFO): platform_buoyancy_position 661.400269 cc
2020-09-29T18:21:17.479Z,1601403677.479 [Reporter](INFO): platform_buoyancy_position 655.222473 cc
2020-09-29T18:21:17.914Z,1601403677.914 [Reporter](INFO): platform_buoyancy_position 650.790588 cc
2020-09-29T18:21:18.329Z,1601403678.329 [Reporter](INFO): platform_buoyancy_position 644.344177 cc
2020-09-29T18:21:18.679Z,1601403678.679 [Reporter](INFO): platform_buoyancy_position 640.180847 cc
2020-09-29T18:21:19.137Z,1601403679.137 [Reporter](INFO): platform_buoyancy_position 633.600159 cc
2020-09-29T18:21:19.531Z,1601403679.531 [Reporter](INFO): platform_buoyancy_position 629.302551 cc
2020-09-29T18:21:19.898Z,1601403679.898 [Reporter](INFO): platform_buoyancy_position 625.004944 cc
2020-09-29T18:21:20.391Z,1601403680.391 [Reporter](INFO): platform_buoyancy_position 618.558533 cc
2020-09-29T18:21:20.715Z,1601403680.715 [Reporter](INFO): platform_buoyancy_position 614.126648 cc
2020-09-29T18:21:21.175Z,1601403681.175 [Reporter](INFO): platform_buoyancy_position 607.277344 cc
2020-09-29T18:21:21.515Z,1601403681.515 [Reporter](INFO): platform_buoyancy_position 602.442566 cc
2020-09-29T18:21:21.944Z,1601403681.944 [Reporter](INFO): platform_buoyancy_position 597.204834 cc
2020-09-29T18:21:22.351Z,1601403682.351 [Reporter](INFO): platform_buoyancy_position 592.772949 cc
2020-09-29T18:21:22.748Z,1601403682.748 [Reporter](INFO): platform_buoyancy_position 586.460876 cc
2020-09-29T18:21:23.171Z,1601403683.171 [Reporter](INFO): platform_buoyancy_position 580.954529 cc
2020-09-29T18:21:23.578Z,1601403683.578 [Reporter](INFO): platform_buoyancy_position 575.582581 cc
2020-09-29T18:21:23.990Z,1601403683.990 [Reporter](INFO): platform_buoyancy_position 571.284973 cc
2020-09-29T18:21:24.383Z,1601403684.383 [Reporter](INFO): platform_buoyancy_position 564.838562 cc
2020-09-29T18:21:24.750Z,1601403684.750 [Reporter](INFO): platform_buoyancy_position 560.540955 cc
2020-09-29T18:21:25.211Z,1601403685.211 [Reporter](INFO): platform_buoyancy_position 554.228882 cc
2020-09-29T18:21:25.595Z,1601403685.595 [Reporter](INFO): platform_buoyancy_position 549.796936 cc
2020-09-29T18:21:25.999Z,1601403685.999 [Reporter](INFO): platform_buoyancy_position 543.484863 cc
2020-09-29T18:21:26.411Z,1601403686.411 [Reporter](INFO): platform_buoyancy_position 539.052979 cc
2020-09-29T18:21:26.779Z,1601403686.779 [Reporter](INFO): platform_buoyancy_position 532.875183 cc
2020-09-29T18:21:27.199Z,1601403687.199 [Reporter](INFO): platform_buoyancy_position 528.308960 cc
2020-09-29T18:21:27.623Z,1601403687.623 [Reporter](INFO): platform_buoyancy_position 521.996826 cc
2020-09-29T18:21:28.047Z,1601403688.047 [Reporter](INFO): platform_buoyancy_position 517.564941 cc
2020-09-29T18:21:28.399Z,1601403688.399 [Reporter](INFO): platform_buoyancy_position 513.267334 cc
2020-09-29T18:21:28.810Z,1601403688.810 [Reporter](INFO): platform_buoyancy_position 506.955261 cc
2020-09-29T18:21:29.246Z,1601403689.246 [Reporter](INFO): platform_buoyancy_position 501.986115 cc
2020-09-29T18:21:29.591Z,1601403689.591 [Reporter](INFO): platform_buoyancy_position 496.211243 cc
2020-09-29T18:21:30.059Z,1601403690.059 [Reporter](INFO): platform_buoyancy_position 490.436340 cc
2020-09-29T18:21:30.426Z,1601403690.426 [Reporter](INFO): platform_buoyancy_position 487.481750 cc
2020-09-29T18:21:30.802Z,1601403690.802 [Reporter](INFO): platform_buoyancy_position 481.035339 cc
2020-09-29T18:21:31.251Z,1601403691.251 [Reporter](INFO): platform_buoyancy_position 474.723267 cc
2020-09-29T18:21:31.623Z,1601403691.623 [Reporter](INFO): platform_buoyancy_position 470.425659 cc
2020-09-29T18:21:32.047Z,1601403692.047 [Reporter](INFO): platform_buoyancy_position 463.844971 cc
2020-09-29T18:21:32.444Z,1601403692.444 [Reporter](INFO): platform_buoyancy_position 459.547363 cc
2020-09-29T18:21:32.830Z,1601403692.830 [Reporter](INFO): platform_buoyancy_position 455.249756 cc
2020-09-29T18:21:33.259Z,1601403693.259 [Reporter](INFO): platform_buoyancy_position 448.803345 cc
2020-09-29T18:21:33.651Z,1601403693.651 [Reporter](INFO): platform_buoyancy_position 442.491272 cc
2020-09-29T18:21:34.091Z,1601403694.091 [Reporter](INFO): platform_buoyancy_position 438.059326 cc
2020-09-29T18:21:34.447Z,1601403694.447 [Reporter](INFO): platform_buoyancy_position 433.761719 cc
2020-09-29T18:21:34.866Z,1601403694.866 [Reporter](INFO): platform_buoyancy_position 427.449646 cc
2020-09-29T18:21:35.302Z,1601403695.302 [Reporter](INFO): platform_buoyancy_position 421.406128 cc
2020-09-29T18:21:35.663Z,1601403695.663 [Reporter](INFO): platform_buoyancy_position 416.705627 cc
2020-09-29T18:21:36.082Z,1601403696.082 [Reporter](INFO): platform_buoyancy_position 410.393555 cc
2020-09-29T18:21:36.498Z,1601403696.498 [Reporter](INFO): platform_buoyancy_position 405.827332 cc
2020-09-29T18:21:36.899Z,1601403696.899 [Reporter](INFO): platform_buoyancy_position 401.529724 cc
2020-09-29T18:21:37.292Z,1601403697.292 [Reporter](INFO): platform_buoyancy_position 395.217651 cc
2020-09-29T18:21:37.691Z,1601403697.691 [Reporter](INFO): platform_buoyancy_position 390.920044 cc
2020-09-29T18:21:38.115Z,1601403698.115 [Reporter](INFO): platform_buoyancy_position 384.473633 cc
2020-09-29T18:21:38.499Z,1601403698.499 [Reporter](INFO): platform_buoyancy_position 380.176025 cc
2020-09-29T18:21:38.917Z,1601403698.917 [Reporter](INFO): platform_buoyancy_position 374.266846 cc
2020-09-29T18:21:39.327Z,1601403699.327 [Reporter](INFO): platform_buoyancy_position 369.432068 cc
2020-09-29T18:21:39.698Z,1601403699.698 [Reporter](INFO): platform_buoyancy_position 364.731567 cc
2020-09-29T18:21:40.123Z,1601403700.123 [Reporter](INFO): platform_buoyancy_position 358.688049 cc
2020-09-29T18:21:40.527Z,1601403700.527 [Reporter](INFO): platform_buoyancy_position 354.256165 cc
2020-09-29T18:21:40.959Z,1601403700.959 [Reporter](INFO): platform_buoyancy_position 347.809753 cc
2020-09-29T18:21:41.374Z,1601403701.374 [Reporter](INFO): platform_buoyancy_position 343.512146 cc
2020-09-29T18:21:41.726Z,1601403701.726 [Reporter](INFO): platform_buoyancy_position 339.214539 cc
2020-09-29T18:21:42.135Z,1601403702.135 [Reporter](INFO): platform_buoyancy_position 332.768127 cc
2020-09-29T18:21:42.550Z,1601403702.550 [Reporter](INFO): platform_buoyancy_position 326.590332 cc
2020-09-29T18:21:42.930Z,1601403702.930 [Reporter](INFO): platform_buoyancy_position 322.158447 cc
2020-09-29T18:21:43.354Z,1601403703.354 [Reporter](INFO): platform_buoyancy_position 315.712036 cc
2020-09-29T18:21:43.754Z,1601403703.754 [Reporter](INFO): platform_buoyancy_position 311.280151 cc
2020-09-29T18:21:44.183Z,1601403704.183 [Reporter](INFO): platform_buoyancy_position 304.833740 cc
2020-09-29T18:21:44.543Z,1601403704.543 [Reporter](INFO): platform_buoyancy_position 300.670441 cc
2020-09-29T18:21:45.011Z,1601403705.011 [Reporter](INFO): platform_buoyancy_position 294.224030 cc
2020-09-29T18:21:45.375Z,1601403705.375 [Reporter](INFO): platform_buoyancy_position 289.792145 cc
2020-09-29T18:21:45.758Z,1601403705.758 [Reporter](INFO): platform_buoyancy_position 285.360229 cc
2020-09-29T18:21:46.174Z,1601403706.174 [Reporter](INFO): platform_buoyancy_position 279.048126 cc
2020-09-29T18:21:46.590Z,1601403706.590 [Reporter](INFO): platform_buoyancy_position 274.750549 cc
2020-09-29T18:21:47.006Z,1601403707.006 [Reporter](INFO): platform_buoyancy_position 268.169830 cc
2020-09-29T18:21:47.423Z,1601403707.423 [Reporter](INFO): platform_buoyancy_position 261.992035 cc
2020-09-29T18:21:47.786Z,1601403707.786 [Reporter](INFO): platform_buoyancy_position 257.828735 cc
2020-09-29T18:21:48.202Z,1601403708.202 [Reporter](INFO): platform_buoyancy_position 253.262558 cc
2020-09-29T18:21:48.606Z,1601403708.606 [Reporter](INFO): platform_buoyancy_position 246.950424 cc
2020-09-29T18:21:49.039Z,1601403709.039 [Reporter](INFO): platform_buoyancy_position 242.518524 cc
2020-09-29T18:21:49.399Z,1601403709.399 [Reporter](INFO): platform_buoyancy_position 236.340729 cc
2020-09-29T18:21:49.810Z,1601403709.810 [Reporter](INFO): platform_buoyancy_position 231.908844 cc
2020-09-29T18:21:50.235Z,1601403710.235 [Reporter](INFO): platform_buoyancy_position 225.462433 cc
2020-09-29T18:21:50.611Z,1601403710.611 [Reporter](INFO): platform_buoyancy_position 221.030518 cc
2020-09-29T18:21:51.076Z,1601403711.076 [Reporter](INFO): platform_buoyancy_position 214.718445 cc
2020-09-29T18:21:55.102Z,1601403715.102 [Reporter](INFO): platform_buoyancy_position 199.811127 cc
2020-09-29T18:22:17.217Z,1601403737.217 [NAL9602](INFO): SBD MO Status=0, MOMSN=11379, MT Status=0, MTMSN=0
2020-09-29T18:22:17.217Z,1601403737.217 [NAL9602](INFO): No messages in MT queue
2020-09-29T18:22:18.429Z,1601403738.429 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182216.00,A,4131.46051,N,07040.28520,W,0.389,213.42,290920,,,A*79
2020-09-29T18:22:18.432Z,1601403738.432 [NAL9602](INFO): GPS fix at 20200929T182216: (41.524342, -70.671420)
2020-09-29T18:22:18.443Z,1601403738.443 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-09-29T18:22:18.443Z,1601403738.443 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-09-29T18:22:22.160Z,1601403742.160 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20200929T180233/Courier0007.lzma
2020-09-29T18:22:23.163Z,1601403743.163 [DataOverHttps](INFO): Moved sent file to Logs/20200929T180233/Courier0007.lzma.bak
2020-09-29T18:22:23.163Z,1601403743.163 [DataOverHttps](INFO): SBD MOMSN=41031
2020-09-29T18:22:32.201Z,1601403752.201 [DataOverHttps](INFO): Sending 426 bytes from file Logs/20200929T180233/Express0008.lzma
2020-09-29T18:22:33.202Z,1601403753.202 [DataOverHttps](INFO): Moved sent file to Logs/20200929T180233/Express0008.lzma.bak
2020-09-29T18:22:33.203Z,1601403753.203 [DataOverHttps](INFO): SBD MOMSN=41034
2020-09-29T18:22:34.613Z,1601403754.613 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-09-29T18:22:34.613Z,1601403754.613 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1
2020-09-29T18:22:34.613Z,1601403754.613 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-09-29T18:22:50.371Z,1601403770.371 [NAL9602](INFO): Not Powering down - fast GPS
2020-09-29T18:27:35.201Z,1601404055.201 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting.
2020-09-29T18:27:35.201Z,1601404055.201 [DefaultWithUndock:CheckIn:C.Wait] Stopped
2020-09-29T18:27:35.201Z,1601404055.201 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-09-29T18:27:35.201Z,1601404055.201 [DefaultWithUndock:CheckIn:D] Running Loop=1
2020-09-29T18:27:35.611Z,1601404055.611 [DefaultWithUndock:CheckIn:D] Stopped
2020-09-29T18:27:35.611Z,1601404055.611 [DefaultWithUndock:CheckIn:E] Running Loop=1
2020-09-29T18:27:35.991Z,1601404055.991 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 21.425427 min 
2020-09-29T18:27:35.991Z,1601404055.991 [DefaultWithUndock:CheckIn:E] Stopped
2020-09-29T18:27:35.991Z,1601404055.991 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn
2020-09-29T18:27:35.991Z,1601404055.991 [DefaultWithUndock:CheckIn] Stopped
2020-09-29T18:27:35.991Z,1601404055.991 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-09-29T18:27:35.991Z,1601404055.991 [DefaultWithUndock:CheckIn](INFO): Running loop #3
2020-09-29T18:27:35.991Z,1601404055.991 [DefaultWithUndock:CheckIn] Running Loop=3
2020-09-29T18:27:35.991Z,1601404055.991 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-09-29T18:27:35.991Z,1601404055.991 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-09-29T18:27:38.001Z,1601404058.001 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182737.00,A,4131.45823,N,07040.27979,W,0.292,271.01,290920,,,A*76
2020-09-29T18:27:38.003Z,1601404058.003 [NAL9602](INFO): GPS fix at 20200929T182737: (41.524304, -70.671330)
2020-09-29T18:27:38.062Z,1601404058.062 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-09-29T18:27:38.062Z,1601404058.062 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-09-29T18:27:41.560Z,1601404061.560 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200929T180233/Courier0010.lzma
2020-09-29T18:27:42.562Z,1601404062.562 [DataOverHttps](INFO): Moved sent file to Logs/20200929T180233/Courier0010.lzma.bak
2020-09-29T18:27:42.563Z,1601404062.563 [DataOverHttps](INFO): SBD MOMSN=41048
2020-09-29T18:27:51.600Z,1601404071.600 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20200929T180233/Express0011.lzma
2020-09-29T18:27:52.603Z,1601404072.603 [DataOverHttps](INFO): Moved sent file to Logs/20200929T180233/Express0011.lzma.bak
2020-09-29T18:27:52.603Z,1601404072.603 [DataOverHttps](INFO): SBD MOMSN=41051
2020-09-29T18:27:53.770Z,1601404073.770 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-09-29T18:27:53.770Z,1601404073.770 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1
2020-09-29T18:27:53.770Z,1601404073.770 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-09-29T18:28:07.076Z,1601404087.076 [NAL9602](INFO): SBD MO Status=2, MOMSN=11380, MT Status=2, MTMSN=0
2020-09-29T18:28:07.076Z,1601404087.076 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-09-29T18:28:27.322Z,1601404107.322 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-09-29T18:30:59.343Z,1601404259.343 [CommandLine](IMPORTANT): got command report mod platform_mass_position
2020-09-29T18:30:59.637Z,1601404259.637 [Reporter](INFO): platform_mass_position 0.002507 m
2020-09-29T18:31:05.240Z,1601404265.240 [NAL9602](INFO): SBD MO Status=0, MOMSN=11380, MT Status=0, MTMSN=0
2020-09-29T18:31:05.240Z,1601404265.240 [NAL9602](INFO): No messages in MT queue
2020-09-29T18:31:35.955Z,1601404295.955 [NAL9602](INFO): Not Powering down - fast GPS
2020-09-29T18:32:54.355Z,1601404374.355 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting.
2020-09-29T18:32:54.355Z,1601404374.355 [DefaultWithUndock:CheckIn:C.Wait] Stopped
2020-09-29T18:32:54.355Z,1601404374.355 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-09-29T18:32:54.355Z,1601404374.355 [DefaultWithUndock:CheckIn:D] Running Loop=1
2020-09-29T18:32:54.757Z,1601404374.757 [DefaultWithUndock:CheckIn:D] Stopped
2020-09-29T18:32:54.757Z,1601404374.757 [DefaultWithUndock:CheckIn:E] Running Loop=1
2020-09-29T18:32:55.178Z,1601404375.178 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 26.744521 min 
2020-09-29T18:32:55.178Z,1601404375.178 [DefaultWithUndock:CheckIn:E] Stopped
2020-09-29T18:32:55.178Z,1601404375.178 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn
2020-09-29T18:32:55.178Z,1601404375.178 [DefaultWithUndock:CheckIn] Stopped
2020-09-29T18:32:55.178Z,1601404375.178 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-09-29T18:32:55.178Z,1601404375.178 [DefaultWithUndock:CheckIn](INFO): Running loop #4
2020-09-29T18:32:55.178Z,1601404375.178 [DefaultWithUndock:CheckIn] Running Loop=4
2020-09-29T18:32:55.179Z,1601404375.179 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-09-29T18:32:55.179Z,1601404375.179 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-09-29T18:32:57.157Z,1601404377.157 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183256.00,A,4131.45975,N,07040.27916,W,0.019,271.01,290920,,,A*7F
2020-09-29T18:32:57.159Z,1601404377.159 [NAL9602](INFO): GPS fix at 20200929T183256: (41.524329, -70.671319)
2020-09-29T18:32:57.179Z,1601404377.179 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-09-29T18:32:57.179Z,1601404377.179 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-09-29T18:33:00.840Z,1601404380.840 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20200929T180233/Courier0013.lzma
2020-09-29T18:33:01.842Z,1601404381.842 [DataOverHttps](INFO): Moved sent file to Logs/20200929T180233/Courier0013.lzma.bak
2020-09-29T18:33:01.842Z,1601404381.842 [DataOverHttps](INFO): SBD MOMSN=41054
2020-09-29T18:33:10.881Z,1601404390.881 [DataOverHttps](INFO): Sending 167 bytes from file Logs/20200929T180233/Express0014.lzma
2020-09-29T18:33:11.882Z,1601404391.882 [DataOverHttps](INFO): Moved sent file to Logs/20200929T180233/Express0014.lzma.bak
2020-09-29T18:33:11.883Z,1601404391.883 [DataOverHttps](INFO): SBD MOMSN=41057
2020-09-29T18:33:12.944Z,1601404392.944 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-09-29T18:33:12.945Z,1601404392.945 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1
2020-09-29T18:33:12.945Z,1601404392.945 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-09-29T18:33:24.624Z,1601404404.624 [NAL9602](INFO): SBD MO Status=2, MOMSN=11381, MT Status=2, MTMSN=0
2020-09-29T18:33:24.624Z,1601404404.624 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-09-29T18:33:32.716Z,1601404412.716 [NAL9602](INFO): SBD MO Status=0, MOMSN=11381, MT Status=0, MTMSN=0
2020-09-29T18:33:32.716Z,1601404412.716 [NAL9602](INFO): No messages in MT queue
2020-09-29T18:34:03.418Z,1601404443.418 [NAL9602](INFO): Not Powering down - fast GPS
2020-09-29T18:34:41.003Z,1601404481.003 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-09-29T18:35:45.052Z,1601404545.052 [CommandLine](IMPORTANT): got command ibit
2020-09-29T18:35:45.328Z,1601404545.328 [IBIT](IMPORTANT): Beginning Initiated BIT
2020-09-29T18:35:45.328Z,1601404545.328 [IBIT](IMPORTANT): Beginning control surface checks.
2020-09-29T18:35:45.335Z,1601404545.335 [CBIT](IMPORTANT): Beginning ground fault scan
2020-09-29T18:35:46.837Z,1601404546.837 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183546.00,A,4131.45911,N,07040.27681,W,0.136,54.30,290920,,,A*41
2020-09-29T18:35:46.839Z,1601404546.839 [NAL9602](INFO): GPS fix at 20200929T183546: (41.524318, -70.671280)
2020-09-29T18:35:56.175Z,1601404556.175 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.007616
CHAN A1 (24V): -0.027589
CHAN A2 (12V): -0.006971
CHAN A3 (5V): -0.001704
CHAN B0 (3.3V): 0.000101
CHAN B1 (3.15aV): -0.000082
CHAN B2 (3.15bV): -0.000104
CHAN B3 (GND): 0.002068
OPEN: 0.006062
Full Scale Calc: 4.765 mA, -1.589 mA
2020-09-29T18:36:06.640Z,1601404566.640 [NAL9602](INFO): SBD MO Status=0, MOMSN=11382, MT Status=0, MTMSN=0
2020-09-29T18:36:06.640Z,1601404566.640 [NAL9602](INFO): No messages in MT queue
2020-09-29T18:36:07.845Z,1601404567.845 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183607.00,A,4131.45856,N,07040.27726,W,0.253,54.30,290920,,,A*49
2020-09-29T18:36:07.847Z,1601404567.847 [NAL9602](INFO): GPS fix at 20200929T183607: (41.524309, -70.671288)
2020-09-29T18:36:10.676Z,1601404570.676 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183610.00,A,4131.45849,N,07040.27759,W,0.058,54.30,290920,,,A*40
2020-09-29T18:36:10.678Z,1601404570.678 [NAL9602](INFO): GPS fix at 20200929T183610: (41.524308, -70.671293)
2020-09-29T18:36:13.909Z,1601404573.909 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183613.00,A,4131.45849,N,07040.27769,W,0.019,54.30,290920,,,A*45
2020-09-29T18:36:13.911Z,1601404573.911 [NAL9602](INFO): GPS fix at 20200929T183613: (41.524308, -70.671295)
2020-09-29T18:36:16.363Z,1601404576.363 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-09-29T18:36:16.733Z,1601404576.733 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183616.00,A,4131.45855,N,07040.27782,W,0.019,54.30,290920,,,A*48
2020-09-29T18:36:16.735Z,1601404576.735 [NAL9602](INFO): GPS fix at 20200929T183616: (41.524309, -70.671297)
2020-09-29T18:36:19.983Z,1601404579.983 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183619.00,A,4131.45861,N,07040.27778,W,0.156,54.30,290920,,,A*4F
2020-09-29T18:36:19.986Z,1601404579.986 [NAL9602](INFO): GPS fix at 20200929T183619: (41.524310, -70.671296)
2020-09-29T18:36:22.791Z,1601404582.791 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183622.00,A,4131.45866,N,07040.27754,W,0.156,54.30,290920,,,A*4E
2020-09-29T18:36:22.793Z,1601404582.793 [NAL9602](INFO): GPS fix at 20200929T183622: (41.524311, -70.671292)
2020-09-29T18:36:26.057Z,1601404586.057 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183625.00,A,4131.45877,N,07040.27743,W,0.039,54.30,290920,,,A*47
2020-09-29T18:36:26.059Z,1601404586.059 [NAL9602](INFO): GPS fix at 20200929T183625: (41.524313, -70.671290)
2020-09-29T18:36:28.854Z,1601404588.854 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183628.00,A,4131.45885,N,07040.27730,W,0.136,54.30,290920,,,A*4D
2020-09-29T18:36:28.858Z,1601404588.858 [NAL9602](INFO): GPS fix at 20200929T183628: (41.524314, -70.671288)
2020-09-29T18:36:31.681Z,1601404591.681 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183631.00,A,4131.45891,N,07040.27718,W,0.078,54.30,290920,,,A*41
2020-09-29T18:36:31.683Z,1601404591.683 [NAL9602](INFO): GPS fix at 20200929T183631: (41.524315, -70.671286)
2020-09-29T18:36:31.729Z,1601404591.729 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 4
Latitude: 41.524315 Longitude: -70.671288
2020-09-29T18:36:32.146Z,1601404592.146 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.611000
2020-09-29T18:36:32.147Z,1601404592.147 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2020-09-29T18:36:32.147Z,1601404592.147 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2020-09-29T18:36:32.513Z,1601404592.513 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2020-09-29T18:36:32.513Z,1601404592.513 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2020-09-29T18:36:32.514Z,1601404592.514 [IBIT](IMPORTANT): Pressure:7.018530 PSI
2020-09-29T18:36:32.514Z,1601404592.514 [IBIT](IMPORTANT): Humidity:21.218410 %
2020-09-29T18:36:32.934Z,1601404592.934 [IBIT](IMPORTANT): Vehicle Pitch:1.108615 degrees
2020-09-29T18:36:32.935Z,1601404592.935 [IBIT](IMPORTANT): Vehicle  Roll:-3.368777 degrees
2020-09-29T18:36:32.935Z,1601404592.935 [IBIT](IMPORTANT): Vehicle  Heading:55.035820 degrees
2020-09-29T18:36:33.347Z,1601404593.347 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2020-09-29T18:36:33.347Z,1601404593.347 [IBIT](IMPORTANT): buoyancyNeutral: 288.614471 cc
2020-09-29T18:36:33.347Z,1601404593.347 [IBIT](IMPORTANT): massDefault: 0.250676 cm
2020-09-29T18:36:33.348Z,1601404593.348 [IBIT](IMPORTANT): stopDepth: 11.000000 m
2020-09-29T18:36:33.348Z,1601404593.348 [IBIT](IMPORTANT): abortDepth: 15.000000 m
2020-09-29T18:36:33.348Z,1601404593.348 [IBIT](IMPORTANT): IBIT PASSED
2020-09-29T18:36:51.105Z,1601404611.105 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-09-29T18:37:04.407Z,1601404624.407 [NAL9602](INFO): Not Powering down - fast GPS
2020-09-29T18:37:47.444Z,1601404667.444 [CommandLine](IMPORTANT): got command failComponent
2020-09-29T18:37:47.444Z,1601404667.444 [CommandLine](IMPORTANT): Failed components:
2020-09-29T18:37:47.445Z,1601404667.445 [CommandLine](IMPORTANT): No failed Components.
2020-09-29T18:38:13.516Z,1601404693.516 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting.
2020-09-29T18:38:13.516Z,1601404693.516 [DefaultWithUndock:CheckIn:C.Wait] Stopped
2020-09-29T18:38:13.521Z,1601404693.521 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-09-29T18:38:13.521Z,1601404693.521 [DefaultWithUndock:CheckIn:D] Running Loop=1
2020-09-29T18:38:13.904Z,1601404693.904 [DefaultWithUndock:CheckIn:D] Stopped
2020-09-29T18:38:13.904Z,1601404693.904 [DefaultWithUndock:CheckIn:E] Running Loop=1
2020-09-29T18:38:14.348Z,1601404694.348 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 32.063645 min 
2020-09-29T18:38:14.348Z,1601404694.348 [DefaultWithUndock:CheckIn:E] Stopped
2020-09-29T18:38:14.348Z,1601404694.348 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn
2020-09-29T18:38:14.348Z,1601404694.348 [DefaultWithUndock:CheckIn] Stopped
2020-09-29T18:38:14.348Z,1601404694.348 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-09-29T18:38:14.348Z,1601404694.348 [DefaultWithUndock:CheckIn](INFO): Running loop #5
2020-09-29T18:38:14.353Z,1601404694.353 [DefaultWithUndock:CheckIn] Running Loop=5
2020-09-29T18:38:14.353Z,1601404694.353 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-09-29T18:38:14.353Z,1601404694.353 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-09-29T18:38:16.337Z,1601404696.337 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183815.00,A,4131.45897,N,07040.27645,W,0.194,54.30,290920,,,A*45
2020-09-29T18:38:16.339Z,1601404696.339 [NAL9602](INFO): GPS fix at 20200929T183815: (41.524316, -70.671274)
2020-09-29T18:38:16.363Z,1601404696.363 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-09-29T18:38:16.363Z,1601404696.363 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-09-29T18:38:20.221Z,1601404700.221 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200929T180233/Courier0016.lzma
2020-09-29T18:38:21.162Z,1601404701.162 [DataOverHttps](INFO): Moved sent file to Logs/20200929T180233/Courier0016.lzma.bak
2020-09-29T18:38:21.163Z,1601404701.163 [DataOverHttps](INFO): SBD MOMSN=41061
2020-09-29T18:38:30.202Z,1601404710.202 [DataOverHttps](INFO): Sending 800 bytes from file Logs/20200929T180233/Express0017.lzma
2020-09-29T18:38:31.202Z,1601404711.202 [DataOverHttps](INFO): Moved sent file to Logs/20200929T180233/Express0017.lzma.bak
2020-09-29T18:38:31.203Z,1601404711.203 [DataOverHttps](INFO): SBD MOMSN=41064
2020-09-29T18:38:32.517Z,1601404712.517 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-09-29T18:38:32.517Z,1601404712.517 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1
2020-09-29T18:38:32.517Z,1601404712.517 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-09-29T18:39:32.276Z,1601404772.276 [NAL9602](INFO): SBD MO Status=0, MOMSN=11383, MT Status=0, MTMSN=0
2020-09-29T18:39:32.276Z,1601404772.276 [NAL9602](INFO): No messages in MT queue
2020-09-29T18:40:02.997Z,1601404802.997 [NAL9602](INFO): Not Powering down - fast GPS
2020-09-29T18:43:33.131Z,1601405013.131 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting.
2020-09-29T18:43:33.131Z,1601405013.131 [DefaultWithUndock:CheckIn:C.Wait] Stopped
2020-09-29T18:43:33.132Z,1601405013.132 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-09-29T18:43:33.132Z,1601405013.132 [DefaultWithUndock:CheckIn:D] Running Loop=1
2020-09-29T18:43:33.480Z,1601405013.480 [DefaultWithUndock:CheckIn:D] Stopped
2020-09-29T18:43:33.480Z,1601405013.480 [DefaultWithUndock:CheckIn:E] Running Loop=1
2020-09-29T18:43:33.897Z,1601405013.897 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 37.389905 min 
2020-09-29T18:43:33.897Z,1601405013.897 [DefaultWithUndock:CheckIn:E] Stopped
2020-09-29T18:43:33.897Z,1601405013.897 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn
2020-09-29T18:43:33.897Z,1601405013.897 [DefaultWithUndock:CheckIn] Stopped
2020-09-29T18:43:33.897Z,1601405013.897 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-09-29T18:43:33.898Z,1601405013.898 [DefaultWithUndock:CheckIn](INFO): Running loop #6
2020-09-29T18:43:33.898Z,1601405013.898 [DefaultWithUndock:CheckIn] Running Loop=6
2020-09-29T18:43:33.898Z,1601405013.898 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-09-29T18:43:33.898Z,1601405013.898 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-09-29T18:43:35.935Z,1601405015.935 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184335.00,A,4131.45906,N,07040.27573,W,0.136,345.43,290920,,,D*7E
2020-09-29T18:43:35.937Z,1601405015.937 [NAL9602](INFO): GPS fix at 20200929T184335: (41.524318, -70.671262)
2020-09-29T18:43:35.957Z,1601405015.957 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-09-29T18:43:35.957Z,1601405015.957 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-09-29T18:43:39.484Z,1601405019.484 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200929T180233/Courier0019.lzma
2020-09-29T18:43:40.486Z,1601405020.486 [DataOverHttps](INFO): Moved sent file to Logs/20200929T180233/Courier0019.lzma.bak
2020-09-29T18:43:40.487Z,1601405020.487 [DataOverHttps](INFO): SBD MOMSN=41092
2020-09-29T18:43:49.528Z,1601405029.528 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20200929T180233/Express0020.lzma
2020-09-29T18:43:50.530Z,1601405030.530 [DataOverHttps](INFO): Moved sent file to Logs/20200929T180233/Express0020.lzma.bak
2020-09-29T18:43:50.531Z,1601405030.531 [DataOverHttps](INFO): SBD MOMSN=41095
2020-09-29T18:43:51.800Z,1601405031.800 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-09-29T18:43:51.800Z,1601405031.800 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1
2020-09-29T18:43:51.822Z,1601405031.822 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-09-29T18:43:53.308Z,1601405033.308 [NAL9602](INFO): SBD MO Status=0, MOMSN=11384, MT Status=0, MTMSN=0
2020-09-29T18:43:53.308Z,1601405033.308 [NAL9602](INFO): No messages in MT queue
2020-09-29T18:44:24.044Z,1601405064.044 [NAL9602](INFO): Not Powering down - fast GPS
2020-09-29T18:48:05.442Z,1601405285.442 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-09-29T18:48:52.343Z,1601405332.343 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting.
2020-09-29T18:48:52.343Z,1601405332.343 [DefaultWithUndock:CheckIn:C.Wait] Stopped
2020-09-29T18:48:52.343Z,1601405332.343 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-09-29T18:48:52.343Z,1601405332.343 [DefaultWithUndock:CheckIn:D] Running Loop=1
2020-09-29T18:48:52.705Z,1601405332.705 [DefaultWithUndock:CheckIn:D] Stopped
2020-09-29T18:48:52.705Z,1601405332.705 [DefaultWithUndock:CheckIn:E] Running Loop=1
2020-09-29T18:48:53.166Z,1601405333.166 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 42.710331 min 
2020-09-29T18:48:53.166Z,1601405333.166 [DefaultWithUndock:CheckIn:E] Stopped
2020-09-29T18:48:53.166Z,1601405333.166 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn
2020-09-29T18:48:53.166Z,1601405333.166 [DefaultWithUndock:CheckIn] Stopped
2020-09-29T18:48:53.166Z,1601405333.166 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-09-29T18:48:53.166Z,1601405333.166 [DefaultWithUndock:CheckIn](INFO): Running loop #7
2020-09-29T18:48:53.166Z,1601405333.166 [DefaultWithUndock:CheckIn] Running Loop=7
2020-09-29T18:48:53.167Z,1601405333.167 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-09-29T18:48:53.167Z,1601405333.167 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-09-29T18:48:55.151Z,1601405335.151 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184854.00,A,4131.46261,N,07040.27755,W,0.058,345.43,290920,,,D*74
2020-09-29T18:48:55.153Z,1601405335.153 [NAL9602](INFO): GPS fix at 20200929T184854: (41.524377, -70.671293)
2020-09-29T18:48:55.199Z,1601405335.199 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-09-29T18:48:55.199Z,1601405335.199 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-09-29T18:48:58.780Z,1601405338.780 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200929T180233/Courier0022.lzma
2020-09-29T18:48:59.783Z,1601405339.783 [DataOverHttps](INFO): Moved sent file to Logs/20200929T180233/Courier0022.lzma.bak
2020-09-29T18:48:59.783Z,1601405339.783 [DataOverHttps](INFO): SBD MOMSN=41098
2020-09-29T18:49:08.820Z,1601405348.820 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20200929T180233/Express0023.lzma
2020-09-29T18:49:09.823Z,1601405349.823 [DataOverHttps](INFO): Moved sent file to Logs/20200929T180233/Express0023.lzma.bak
2020-09-29T18:49:09.823Z,1601405349.823 [DataOverHttps](INFO): SBD MOMSN=41101
2020-09-29T18:49:10.963Z,1601405350.963 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-09-29T18:49:10.963Z,1601405350.963 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1
2020-09-29T18:49:10.963Z,1601405350.963 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-09-29T18:49:27.849Z,1601405367.849 [NAL9602](INFO): SBD MO Status=2, MOMSN=11385, MT Status=2, MTMSN=0
2020-09-29T18:49:27.849Z,1601405367.849 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-09-29T18:49:46.028Z,1601405386.028 [NAL9602](INFO): SBD MO Status=0, MOMSN=11385, MT Status=0, MTMSN=0
2020-09-29T18:49:46.028Z,1601405386.028 [NAL9602](INFO): No messages in MT queue
2020-09-29T18:50:16.740Z,1601405416.740 [NAL9602](INFO): Not Powering down - fast GPS
2020-09-29T18:51:11.275Z,1601405471.275 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-09-29T18:53:07.241Z,1601405587.241 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-09-29T18:53:37.134Z,1601405617.134 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items
2020-09-29T18:54:11.490Z,1601405651.490 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting.
2020-09-29T18:54:11.490Z,1601405651.490 [DefaultWithUndock:CheckIn:C.Wait] Stopped
2020-09-29T18:54:11.490Z,1601405651.490 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-09-29T18:54:11.490Z,1601405651.490 [DefaultWithUndock:CheckIn:D] Running Loop=1
2020-09-29T18:54:11.885Z,1601405651.885 [DefaultWithUndock:CheckIn:D] Stopped
2020-09-29T18:54:11.885Z,1601405651.885 [DefaultWithUndock:CheckIn:E] Running Loop=1
2020-09-29T18:54:12.322Z,1601405652.322 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 48.029985 min 
2020-09-29T18:54:12.322Z,1601405652.322 [DefaultWithUndock:CheckIn:E] Stopped
2020-09-29T18:54:12.322Z,1601405652.322 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn
2020-09-29T18:54:12.322Z,1601405652.322 [DefaultWithUndock:CheckIn] Stopped
2020-09-29T18:54:12.322Z,1601405652.322 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-09-29T18:54:12.322Z,1601405652.322 [DefaultWithUndock:CheckIn](INFO): Running loop #8
2020-09-29T18:54:12.322Z,1601405652.322 [DefaultWithUndock:CheckIn] Running Loop=8
2020-09-29T18:54:12.322Z,1601405652.322 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-09-29T18:54:12.322Z,1601405652.322 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-09-29T18:54:14.300Z,1601405654.300 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185413.00,A,4131.46562,N,07040.27753,W,0.039,345.43,290920,,,D*7F
2020-09-29T18:54:14.311Z,1601405654.311 [NAL9602](INFO): GPS fix at 20200929T185413: (41.524427, -70.671292)
2020-09-29T18:54:14.348Z,1601405654.348 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-09-29T18:54:14.348Z,1601405654.348 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-09-29T18:54:18.104Z,1601405658.104 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200929T180233/Courier0025.lzma
2020-09-29T18:54:19.106Z,1601405659.106 [DataOverHttps](INFO): Moved sent file to Logs/20200929T180233/Courier0025.lzma.bak
2020-09-29T18:54:19.107Z,1601405659.107 [DataOverHttps](INFO): SBD MOMSN=41104
2020-09-29T18:54:28.144Z,1601405668.144 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20200929T180233/Express0026.lzma
2020-09-29T18:54:29.147Z,1601405669.147 [DataOverHttps](INFO): Moved sent file to Logs/20200929T180233/Express0026.lzma.bak
2020-09-29T18:54:29.147Z,1601405669.147 [DataOverHttps](INFO): SBD MOMSN=41107
2020-09-29T18:54:29.648Z,1601405669.648 [NAL9602](INFO): SBD MO Status=0, MOMSN=11386, MT Status=0, MTMSN=0
2020-09-29T18:54:29.648Z,1601405669.648 [NAL9602](INFO): No messages in MT queue
2020-09-29T18:54:30.477Z,1601405670.477 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-09-29T18:54:30.477Z,1601405670.477 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1
2020-09-29T18:54:30.477Z,1601405670.477 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-09-29T18:54:52.309Z,1601405692.309 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-09-29T18:55:00.356Z,1601405700.356 [NAL9602](INFO): Not Powering down - fast GPS
2020-09-29T18:59:31.111Z,1601405971.111 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting.
2020-09-29T18:59:31.111Z,1601405971.111 [DefaultWithUndock:CheckIn:C.Wait] Stopped
2020-09-29T18:59:31.111Z,1601405971.111 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-09-29T18:59:31.111Z,1601405971.111 [DefaultWithUndock:CheckIn:D] Running Loop=1
2020-09-29T18:59:31.469Z,1601405971.469 [DefaultWithUndock:CheckIn:D] Stopped
2020-09-29T18:59:31.469Z,1601405971.469 [DefaultWithUndock:CheckIn:E] Running Loop=1
2020-09-29T18:59:31.873Z,1601405971.873 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 53.356392 min 
2020-09-29T18:59:31.873Z,1601405971.873 [DefaultWithUndock:CheckIn:E] Stopped
2020-09-29T18:59:31.873Z,1601405971.873 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn
2020-09-29T18:59:31.873Z,1601405971.873 [DefaultWithUndock:CheckIn] Stopped
2020-09-29T18:59:31.873Z,1601405971.873 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-09-29T18:59:31.873Z,1601405971.873 [DefaultWithUndock:CheckIn](INFO): Running loop #9
2020-09-29T18:59:31.874Z,1601405971.874 [DefaultWithUndock:CheckIn] Running Loop=9
2020-09-29T18:59:31.874Z,1601405971.874 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-09-29T18:59:31.874Z,1601405971.874 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-09-29T18:59:33.885Z,1601405973.885 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185933.00,A,4131.46437,N,07040.27640,W,0.058,345.43,290920,,,D*75
2020-09-29T18:59:33.887Z,1601405973.887 [NAL9602](INFO): GPS fix at 20200929T185933: (41.524406, -70.671273)
2020-09-29T18:59:33.921Z,1601405973.921 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-09-29T18:59:33.921Z,1601405973.921 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-09-29T18:59:37.424Z,1601405977.424 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200929T180233/Courier0028.lzma
2020-09-29T18:59:38.426Z,1601405978.426 [DataOverHttps](INFO): Moved sent file to Logs/20200929T180233/Courier0028.lzma.bak
2020-09-29T18:59:38.427Z,1601405978.427 [DataOverHttps](INFO): SBD MOMSN=41110
2020-09-29T18:59:47.464Z,1601405987.464 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20200929T180233/Express0029.lzma
2020-09-29T18:59:48.467Z,1601405988.467 [DataOverHttps](INFO): Moved sent file to Logs/20200929T180233/Express0029.lzma.bak
2020-09-29T18:59:48.467Z,1601405988.467 [DataOverHttps](INFO): SBD MOMSN=41113
2020-09-29T18:59:49.667Z,1601405989.667 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-09-29T18:59:49.667Z,1601405989.667 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1
2020-09-29T18:59:49.667Z,1601405989.667 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-09-29T19:01:15.715Z,1601406075.715 [NAL9602](INFO): SBD MO Status=2, MOMSN=11387, MT Status=2, MTMSN=0
2020-09-29T19:01:15.716Z,1601406075.716 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-09-29T19:01:43.194Z,1601406103.194 [NAL9602](INFO): SBD MO Status=2, MOMSN=11387, MT Status=2, MTMSN=0
2020-09-29T19:01:43.195Z,1601406103.195 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-09-29T19:02:03.388Z,1601406123.388 [NAL9602](INFO): SBD MO Status=0, MOMSN=11387, MT Status=0, MTMSN=0
2020-09-29T19:02:03.388Z,1601406123.388 [NAL9602](INFO): No messages in MT queue
2020-09-29T19:02:34.091Z,1601406154.091 [NAL9602](INFO): Not Powering down - fast GPS
2020-09-29T19:04:50.334Z,1601406290.334 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting.
2020-09-29T19:04:50.335Z,1601406290.335 [DefaultWithUndock:CheckIn:C.Wait] Stopped
2020-09-29T19:04:50.335Z,1601406290.335 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-09-29T19:04:50.335Z,1601406290.335 [DefaultWithUndock:CheckIn:D] Running Loop=1
2020-09-29T19:04:50.665Z,1601406290.665 [DefaultWithUndock:CheckIn:D] Stopped
2020-09-29T19:04:50.665Z,1601406290.665 [DefaultWithUndock:CheckIn:E] Running Loop=1
2020-09-29T19:04:51.112Z,1601406291.112 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 58.676318 min 
2020-09-29T19:04:51.112Z,1601406291.112 [DefaultWithUndock:CheckIn:E] Stopped
2020-09-29T19:04:51.113Z,1601406291.113 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn
2020-09-29T19:04:51.113Z,1601406291.113 [DefaultWithUndock:CheckIn] Stopped
2020-09-29T19:04:51.113Z,1601406291.113 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-09-29T19:04:51.113Z,1601406291.113 [DefaultWithUndock:CheckIn](INFO): Running loop #10
2020-09-29T19:04:51.113Z,1601406291.113 [DefaultWithUndock:CheckIn] Running Loop=10
2020-09-29T19:04:51.113Z,1601406291.113 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-09-29T19:04:51.113Z,1601406291.113 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-09-29T19:04:53.097Z,1601406293.097 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190452.00,A,4131.46799,N,07040.27807,W,0.097,0.00,290920,,,D*77
2020-09-29T19:04:53.099Z,1601406293.099 [NAL9602](INFO): GPS fix at 20200929T190452: (41.524467, -70.671301)
2020-09-29T19:04:53.150Z,1601406293.150 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-09-29T19:04:53.150Z,1601406293.150 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-09-29T19:04:56.732Z,1601406296.732 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20200929T180233/Courier0031.lzma
2020-09-29T19:04:57.735Z,1601406297.735 [DataOverHttps](INFO): Moved sent file to Logs/20200929T180233/Courier0031.lzma.bak
2020-09-29T19:04:57.735Z,1601406297.735 [DataOverHttps](INFO): SBD MOMSN=41116
2020-09-29T19:05:01.000Z,1601406302.000 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-09-29T19:05:06.776Z,1601406306.776 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20200929T180233/Express0032.lzma
2020-09-29T19:05:07.778Z,1601406307.778 [DataOverHttps](INFO): Moved sent file to Logs/20200929T180233/Express0032.lzma.bak
2020-09-29T19:05:07.779Z,1601406307.779 [DataOverHttps](INFO): SBD MOMSN=41119
2020-09-29T19:05:08.938Z,1601406308.938 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-09-29T19:05:08.938Z,1601406308.938 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1
2020-09-29T19:05:08.939Z,1601406308.939 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-09-29T19:07:29.440Z,1601406449.440 [NAL9602](INFO): SBD MO Status=0, MOMSN=11388, MT Status=0, MTMSN=0
2020-09-29T19:07:29.440Z,1601406449.440 [NAL9602](INFO): No messages in MT queue
2020-09-29T19:08:00.147Z,1601406480.147 [NAL9602](INFO): Not Powering down - fast GPS
2020-09-29T19:09:01.991Z,1601406541.991 [RDI_Pathfinder](ERROR): only read -1 of 1 data item for altitude
2020-09-29T19:09:02.012Z,1601406542.012 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items
2020-09-29T19:10:09.457Z,1601406609.457 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting.
2020-09-29T19:10:09.457Z,1601406609.457 [DefaultWithUndock:CheckIn:C.Wait] Stopped
2020-09-29T19:10:09.457Z,1601406609.457 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-09-29T19:10:09.457Z,1601406609.457 [DefaultWithUndock:CheckIn:D] Running Loop=1
2020-09-29T19:10:09.951Z,1601406609.951 [DefaultWithUndock:CheckIn:D] Stopped
2020-09-29T19:10:09.951Z,1601406609.951 [DefaultWithUndock:CheckIn:E] Running Loop=1
2020-09-29T19:10:10.269Z,1601406610.269 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 63.997754 min 
2020-09-29T19:10:10.269Z,1601406610.269 [DefaultWithUndock:CheckIn:E] Stopped
2020-09-29T19:10:10.269Z,1601406610.269 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn
2020-09-29T19:10:10.269Z,1601406610.269 [DefaultWithUndock:CheckIn] Stopped
2020-09-29T19:10:10.269Z,1601406610.269 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-09-29T19:10:10.270Z,1601406610.270 [DefaultWithUndock:CheckIn](INFO): Running loop #11
2020-09-29T19:10:10.270Z,1601406610.270 [DefaultWithUndock:CheckIn] Running Loop=11
2020-09-29T19:10:10.270Z,1601406610.270 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-09-29T19:10:10.270Z,1601406610.270 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-09-29T19:10:12.281Z,1601406612.281 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191011.00,A,4131.46026,N,07040.31135,W,0.058,221.20,290920,,,D*79
2020-09-29T19:10:12.283Z,1601406612.283 [NAL9602](INFO): GPS fix at 20200929T191011: (41.524338, -70.671856)
2020-09-29T19:10:12.314Z,1601406612.314 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-09-29T19:10:12.314Z,1601406612.314 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-09-29T19:10:16.084Z,1601406616.084 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200929T180233/Courier0034.lzma
2020-09-29T19:10:17.086Z,1601406617.086 [DataOverHttps](INFO): Moved sent file to Logs/20200929T180233/Courier0034.lzma.bak
2020-09-29T19:10:17.087Z,1601406617.087 [DataOverHttps](INFO): SBD MOMSN=41122
2020-09-29T19:10:26.124Z,1601406626.124 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20200929T180233/Express0035.lzma
2020-09-29T19:10:27.127Z,1601406627.127 [DataOverHttps](INFO): Moved sent file to Logs/20200929T180233/Express0035.lzma.bak
2020-09-29T19:10:27.127Z,1601406627.127 [DataOverHttps](INFO): SBD MOMSN=41125
2020-09-29T19:10:28.465Z,1601406628.465 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-09-29T19:10:28.465Z,1601406628.465 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1
2020-09-29T19:10:28.465Z,1601406628.465 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-09-29T19:10:48.624Z,1601406648.624 [NAL9602](INFO): SBD MO Status=0, MOMSN=11389, MT Status=0, MTMSN=0
2020-09-29T19:10:48.624Z,1601406648.624 [NAL9602](INFO): No messages in MT queue
2020-09-29T19:11:19.335Z,1601406679.335 [NAL9602](INFO): Not Powering down - fast GPS
2020-09-29T19:12:48.249Z,1601406768.249 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-09-29T19:13:08.048Z,1601406788.048 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items
2020-09-29T19:13:18.221Z,1601406798.221 [RDI_PathfinderUp](ERROR): DVL uart error: serial timeout
2020-09-29T19:13:18.221Z,1601406798.221 [RDI_PathfinderUp] Communications Fault, FailCount= 1
2020-09-29T19:13:18.221Z,1601406798.221 [RDI_PathfinderUp](ERROR): Communications Fault
2020-09-29T19:13:18.268Z,1601406798.268 [CBIT](ERROR): Communications Fault in component: RDI_PathfinderUp
2020-09-29T19:13:18.589Z,1601406798.589 [RDI_PathfinderUp](INFO): Powering down
2020-09-29T19:13:19.367Z,1601406799.367 [CBIT](INFO): Clearing failed state for component RDI_PathfinderUp
2020-09-29T19:13:19.367Z,1601406799.367 [RDI_PathfinderUp] No Fault, FailCount= 1
2020-09-29T19:13:48.024Z,1601406828.024 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-09-29T19:14:13.094Z,1601406853.094 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-09-29T19:14:43.022Z,1601406883.022 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-09-29T19:15:29.088Z,1601406929.088 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting.
2020-09-29T19:15:29.088Z,1601406929.088 [DefaultWithUndock:CheckIn:C.Wait] Stopped
2020-09-29T19:15:29.088Z,1601406929.088 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-09-29T19:15:29.088Z,1601406929.088 [DefaultWithUndock:CheckIn:D] Running Loop=1
2020-09-29T19:15:29.493Z,1601406929.493 [DefaultWithUndock:CheckIn:D] Stopped
2020-09-29T19:15:29.493Z,1601406929.493 [DefaultWithUndock:CheckIn:E] Running Loop=1
2020-09-29T19:15:29.879Z,1601406929.879 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 69.323454 min 
2020-09-29T19:15:29.879Z,1601406929.879 [DefaultWithUndock:CheckIn:E] Stopped
2020-09-29T19:15:29.879Z,1601406929.879 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn
2020-09-29T19:15:29.879Z,1601406929.879 [DefaultWithUndock:CheckIn] Stopped
2020-09-29T19:15:29.880Z,1601406929.880 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-09-29T19:15:29.880Z,1601406929.880 [DefaultWithUndock:CheckIn](INFO): Running loop #12
2020-09-29T19:15:29.880Z,1601406929.880 [DefaultWithUndock:CheckIn] Running Loop=12
2020-09-29T19:15:29.880Z,1601406929.880 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-09-29T19:15:29.880Z,1601406929.880 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-09-29T19:15:31.880Z,1601406931.880 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191531.00,A,4131.45870,N,07040.31429,W,0.933,269.94,290920,,,D*79
2020-09-29T19:15:31.882Z,1601406931.882 [NAL9602](INFO): GPS fix at 20200929T191531: (41.524312, -70.671905)
2020-09-29T19:15:32.044Z,1601406932.044 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-09-29T19:15:32.044Z,1601406932.044 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-09-29T19:15:35.412Z,1601406935.412 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200929T180233/Courier0037.lzma
2020-09-29T19:15:36.414Z,1601406936.414 [DataOverHttps](INFO): Moved sent file to Logs/20200929T180233/Courier0037.lzma.bak
2020-09-29T19:15:36.414Z,1601406936.414 [DataOverHttps](INFO): SBD MOMSN=41128
2020-09-29T19:15:45.452Z,1601406945.452 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20200929T180233/Express0038.lzma
2020-09-29T19:15:46.454Z,1601406946.454 [DataOverHttps](INFO): Moved sent file to Logs/20200929T180233/Express0038.lzma.bak
2020-09-29T19:15:46.455Z,1601406946.455 [DataOverHttps](INFO): SBD MOMSN=41131
2020-09-29T19:15:47.773Z,1601406947.773 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-09-29T19:15:47.773Z,1601406947.773 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1
2020-09-29T19:15:47.773Z,1601406947.773 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-09-29T19:16:28.532Z,1601406988.532 [NAL9602](INFO): SBD MO Status=0, MOMSN=11390, MT Status=0, MTMSN=0
2020-09-29T19:16:28.532Z,1601406988.532 [NAL9602](INFO): No messages in MT queue
2020-09-29T19:16:59.273Z,1601407019.273 [NAL9602](INFO): Not Powering down - fast GPS
2020-09-29T19:17:28.007Z,1601407048.007 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-09-29T19:17:28.009Z,1601407048.009 [BPC1](INFO): Received data from all battery sticks.
2020-09-29T19:19:21.087Z,1601407161.087 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-09-29T19:20:48.368Z,1601407248.368 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting.
2020-09-29T19:20:48.368Z,1601407248.368 [DefaultWithUndock:CheckIn:C.Wait] Stopped
2020-09-29T19:20:48.369Z,1601407248.369 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-09-29T19:20:48.369Z,1601407248.369 [DefaultWithUndock:CheckIn:D] Running Loop=1
2020-09-29T19:20:48.786Z,1601407248.786 [DefaultWithUndock:CheckIn:D] Stopped
2020-09-29T19:20:48.787Z,1601407248.787 [DefaultWithUndock:CheckIn:E] Running Loop=1
2020-09-29T19:20:49.179Z,1601407249.179 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 74.645020 min 
2020-09-29T19:20:49.179Z,1601407249.179 [DefaultWithUndock:CheckIn:E] Stopped
2020-09-29T19:20:49.180Z,1601407249.180 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn
2020-09-29T19:20:49.180Z,1601407249.180 [DefaultWithUndock:CheckIn] Stopped
2020-09-29T19:20:49.180Z,1601407249.180 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-09-29T19:20:49.180Z,1601407249.180 [DefaultWithUndock:CheckIn](INFO): Running loop #13
2020-09-29T19:20:49.180Z,1601407249.180 [DefaultWithUndock:CheckIn] Running Loop=13
2020-09-29T19:20:49.180Z,1601407249.180 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-09-29T19:20:49.180Z,1601407249.180 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-09-29T19:20:51.185Z,1601407251.185 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192050.00,A,4131.46000,N,07040.31350,W,0.019,269.94,290920,,,D*7C
2020-09-29T19:20:51.187Z,1601407251.187 [NAL9602](INFO): GPS fix at 20200929T192050: (41.524333, -70.671892)
2020-09-29T19:20:51.223Z,1601407251.223 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-09-29T19:20:51.223Z,1601407251.223 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-09-29T19:20:54.728Z,1601407254.728 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20200929T180233/Courier0040.lzma
2020-09-29T19:20:55.730Z,1601407255.730 [DataOverHttps](INFO): Moved sent file to Logs/20200929T180233/Courier0040.lzma.bak
2020-09-29T19:20:55.731Z,1601407255.731 [DataOverHttps](INFO): SBD MOMSN=41134
2020-09-29T19:21:03.812Z,1601407263.812 [NAL9602](INFO): SBD MO Status=0, MOMSN=11391, MT Status=0, MTMSN=0
2020-09-29T19:21:03.812Z,1601407263.812 [NAL9602](INFO): No messages in MT queue
2020-09-29T19:21:04.768Z,1601407264.768 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20200929T180233/Express0041.lzma
2020-09-29T19:21:05.770Z,1601407265.770 [DataOverHttps](INFO): Moved sent file to Logs/20200929T180233/Express0041.lzma.bak
2020-09-29T19:21:05.771Z,1601407265.771 [DataOverHttps](INFO): SBD MOMSN=41137
2020-09-29T19:21:07.025Z,1601407267.025 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-09-29T19:21:07.025Z,1601407267.025 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1
2020-09-29T19:21:07.025Z,1601407267.025 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-09-29T19:21:34.482Z,1601407294.482 [NAL9602](INFO): Not Powering down - fast GPS
2020-09-29T19:26:07.698Z,1601407567.698 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting.
2020-09-29T19:26:07.698Z,1601407567.698 [DefaultWithUndock:CheckIn:C.Wait] Stopped
2020-09-29T19:26:07.698Z,1601407567.698 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-09-29T19:26:07.698Z,1601407567.698 [DefaultWithUndock:CheckIn:D] Running Loop=1
2020-09-29T19:26:08.060Z,1601407568.060 [DefaultWithUndock:CheckIn:D] Stopped
2020-09-29T19:26:08.060Z,1601407568.060 [DefaultWithUndock:CheckIn:E] Running Loop=1
2020-09-29T19:26:08.463Z,1601407568.463 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 79.966235 min 
2020-09-29T19:26:08.463Z,1601407568.463 [DefaultWithUndock:CheckIn:E] Stopped
2020-09-29T19:26:08.463Z,1601407568.463 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn
2020-09-29T19:26:08.464Z,1601407568.464 [DefaultWithUndock:CheckIn] Stopped
2020-09-29T19:26:08.464Z,1601407568.464 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-09-29T19:26:08.464Z,1601407568.464 [DefaultWithUndock:CheckIn](INFO): Running loop #14
2020-09-29T19:26:08.464Z,1601407568.464 [DefaultWithUndock:CheckIn] Running Loop=14
2020-09-29T19:26:08.464Z,1601407568.464 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-09-29T19:26:08.464Z,1601407568.464 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-09-29T19:26:10.452Z,1601407570.452 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192609.00,A,4131.46037,N,07040.31284,W,0.097,299.99,290920,,,D*7E
2020-09-29T19:26:10.455Z,1601407570.455 [NAL9602](INFO): GPS fix at 20200929T192609: (41.524340, -70.671881)
2020-09-29T19:26:10.489Z,1601407570.489 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-09-29T19:26:10.490Z,1601407570.490 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-09-29T19:26:14.080Z,1601407574.080 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200929T180233/Courier0043.lzma
2020-09-29T19:26:15.113Z,1601407575.113 [DataOverHttps](INFO): Moved sent file to Logs/20200929T180233/Courier0043.lzma.bak
2020-09-29T19:26:15.113Z,1601407575.113 [DataOverHttps](INFO): SBD MOMSN=41140
2020-09-29T19:26:24.120Z,1601407584.120 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20200929T180233/Express0044.lzma
2020-09-29T19:26:25.123Z,1601407585.123 [DataOverHttps](INFO): Moved sent file to Logs/20200929T180233/Express0044.lzma.bak
2020-09-29T19:26:25.123Z,1601407585.123 [DataOverHttps](INFO): SBD MOMSN=41143
2020-09-29T19:26:26.348Z,1601407586.348 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-09-29T19:26:26.348Z,1601407586.348 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1
2020-09-29T19:26:26.348Z,1601407586.348 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-09-29T19:26:38.416Z,1601407598.416 [NAL9602](INFO): SBD MO Status=0, MOMSN=11392, MT Status=0, MTMSN=0
2020-09-29T19:26:38.416Z,1601407598.416 [NAL9602](INFO): No messages in MT queue
2020-09-29T19:26:53.767Z,1601407613.767 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items
2020-09-29T19:27:09.110Z,1601407629.110 [NAL9602](INFO): Not Powering down - fast GPS
2020-09-29T19:28:19.031Z,1601407699.031 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items
2020-09-29T19:31:26.893Z,1601407886.893 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting.
2020-09-29T19:31:26.893Z,1601407886.893 [DefaultWithUndock:CheckIn:C.Wait] Stopped
2020-09-29T19:31:26.894Z,1601407886.894 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-09-29T19:31:26.894Z,1601407886.894 [DefaultWithUndock:CheckIn:D] Running Loop=1
2020-09-29T19:31:27.327Z,1601407887.327 [DefaultWithUndock:CheckIn:D] Stopped
2020-09-29T19:31:27.327Z,1601407887.327 [DefaultWithUndock:CheckIn:E] Running Loop=1
2020-09-29T19:31:27.695Z,1601407887.695 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 85.287354 min 
2020-09-29T19:31:27.695Z,1601407887.695 [DefaultWithUndock:CheckIn:E] Stopped
2020-09-29T19:31:27.695Z,1601407887.695 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn
2020-09-29T19:31:27.695Z,1601407887.695 [DefaultWithUndock:CheckIn] Stopped
2020-09-29T19:31:27.695Z,1601407887.695 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-09-29T19:31:27.695Z,1601407887.695 [DefaultWithUndock:CheckIn](INFO): Running loop #15
2020-09-29T19:31:27.695Z,1601407887.695 [DefaultWithUndock:CheckIn] Running Loop=15
2020-09-29T19:31:27.695Z,1601407887.695 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-09-29T19:31:27.696Z,1601407887.696 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-09-29T19:31:29.701Z,1601407889.701 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193129.00,A,4131.34225,N,07040.41790,W,4.626,200.44,290920,,,D*71
2020-09-29T19:31:29.703Z,1601407889.703 [NAL9602](INFO): GPS fix at 20200929T193129: (41.522371, -70.673632)
2020-09-29T19:31:29.714Z,1601407889.714 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-09-29T19:31:29.714Z,1601407889.714 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-09-29T19:31:33.364Z,1601407893.364 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200929T180233/Courier0046.lzma
2020-09-29T19:31:34.367Z,1601407894.367 [DataOverHttps](INFO): Moved sent file to Logs/20200929T180233/Courier0046.lzma.bak
2020-09-29T19:31:34.367Z,1601407894.367 [DataOverHttps](IMPORTANT): SBD MOMSN=41147, MTMSN=20200929T193133
2020-09-29T19:31:38.385Z,1601407898.385 [DataOverHttps](INFO): Received command:restart app
2020-09-29T19:31:38.477Z,1601407898.477 [CommandLine](IMPORTANT): got command restart application
2020-09-29T19:31:39.481Z,1601407899.481 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2020-09-29T19:31:39.481Z,1601407899.481 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-29T19:31:39.481Z,1601407899.481 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-09-29T19:31:39.585Z,1601407899.585 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-09-29T19:31:39.585Z,1601407899.585 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-09-29T19:31:39.585Z,1601407899.585 [CommandLine](INFO): Join timeout helper Thread ID is 1972
2020-09-29T19:31:39.593Z,1601407899.593 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-09-29T19:31:39.593Z,1601407899.593 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-09-29T19:31:39.593Z,1601407899.593 [NavChartDb](INFO): Join timeout helper Thread ID is 1973
2020-09-29T19:31:39.941Z,1601407899.941 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-29T19:31:39.941Z,1601407899.941 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-09-29T19:31:39.953Z,1601407899.953 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2020-09-29T19:31:39.953Z,1601407899.953 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-09-29T19:31:39.953Z,1601407899.953 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 1974
2020-09-29T19:31:40.301Z,1601407900.301 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-29T19:31:40.301Z,1601407900.301 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-09-29T19:31:40.302Z,1601407900.302 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-09-29T19:31:40.317Z,1601407900.317 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2020-09-29T19:31:40.317Z,1601407900.317 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-09-29T19:31:40.317Z,1601407900.317 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1975
2020-09-29T19:31:40.621Z,1601407900.621 [CTD_Seabird](INFO): Powering down
2020-09-29T19:31:40.633Z,1601407900.633 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-29T19:31:40.633Z,1601407900.633 [CTD_Seabird](INFO): Powering down
2020-09-29T19:31:40.645Z,1601407900.645 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-09-29T19:31:40.661Z,1601407900.661 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-09-29T19:31:40.661Z,1601407900.661 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-09-29T19:31:40.661Z,1601407900.661 [Radio_Surface](INFO): Join timeout helper Thread ID is 1976
2020-09-29T19:31:40.709Z,1601407900.709 [Radio_Surface](INFO): Powering down
2020-09-29T19:31:40.710Z,1601407900.710 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-29T19:31:40.710Z,1601407900.710 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-09-29T19:31:40.722Z,1601407900.722 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2020-09-29T19:31:40.722Z,1601407900.722 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-09-29T19:31:40.722Z,1601407900.722 [Onboard](INFO): Join timeout helper Thread ID is 1977
2020-09-29T19:31:43.789Z,1601407903.789 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-29T19:31:43.789Z,1601407903.789 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-09-29T19:31:43.809Z,1601407903.809 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-09-29T19:31:43.809Z,1601407903.809 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-09-29T19:31:43.810Z,1601407903.810 [DataOverHttps](INFO): Join timeout helper Thread ID is 1978
2020-09-29T19:31:44.423Z,1601407904.423 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-29T19:31:44.426Z,1601407904.426 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-09-29T19:31:44.437Z,1601407904.437 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-09-29T19:31:44.438Z,1601407904.438 [logger ThreadHandler](INFO): Thread cancelled.
2020-09-29T19:31:44.438Z,1601407904.438 [logger](INFO): Join timeout helper Thread ID is 1979
2020-09-29T19:31:44.481Z,1601407904.481 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-29T19:31:44.481Z,1601407904.481 [logger ThreadHandler](INFO): Thread cancelled.
2020-09-29T19:31:44.497Z,1601407904.497 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-09-29T19:31:44.497Z,1601407904.497 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-09-29T19:31:44.498Z,1601407904.498 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-09-29T19:31:44.498Z,1601407904.498 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-09-29T19:31:44.498Z,1601407904.498 [controlThread](INFO): Join timeout helper Thread ID is 1980
2020-09-29T19:31:44.625Z,1601407904.625 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-29T19:31:44.625Z,1601407904.625 [controlThread](DEBUG): Uninitializing ControlThread
2020-09-29T19:31:44.626Z,1601407904.626 [AHRS_M2](INFO): Powering down
2020-09-29T19:31:44.697Z,1601407904.697 [DDM](INFO): Powering down
2020-09-29T19:31:44.698Z,1601407904.698 [DUSBL_Hydroid](INFO): Powering down
2020-09-29T19:31:44.769Z,1601407904.769 [Micromodem](INFO): Powering down
2020-09-29T19:31:44.865Z,1601407904.865 [NAL9602](INFO): Powering down
2020-09-29T19:31:44.937Z,1601407904.937 [RDI_Pathfinder](INFO): Powering down
2020-09-29T19:31:45.009Z,1601407905.009 [RDI_PathfinderUp](INFO): Powering down
2020-09-29T19:31:45.010Z,1601407905.010 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-09-29T19:31:45.011Z,1601407905.011 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-09-29T19:31:45.012Z,1601407905.012 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-09-29T19:31:45.012Z,1601407905.012 [MissionManager](INFO): Uninitializing Mission DefaultWithUndock
2020-09-29T19:31:45.012Z,1601407905.012 [DefaultWithUndock] Stopped
2020-09-29T19:31:45.013Z,1601407905.013 [DefaultWithUndock](DEBUG): Aggregate::uninitialize DefaultWithUndock
2020-09-29T19:31:45.013Z,1601407905.013 [DefaultWithUndock:C.GoToSurface] Stopped
2020-09-29T19:31:45.013Z,1601407905.013 [DefaultWithUndock:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-09-29T19:31:45.013Z,1601407905.013 [DefaultWithUndock:CheckIn] Stopped
2020-09-29T19:31:45.013Z,1601407905.013 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-09-29T19:31:45.013Z,1601407905.013 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-09-29T19:31:45.016Z,1601407905.016 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-09-29T19:31:45.016Z,1601407905.016 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-09-29T19:31:45.017Z,1601407905.017 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-09-29T19:31:45.017Z,1601407905.017 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-09-29T19:31:45.018Z,1601407905.018 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-09-29T19:31:45.018Z,1601407905.018 [BuoyancyServo](INFO): Powering down
2020-09-29T19:31:45.029Z,1601407905.029 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-09-29T19:31:45.029Z,1601407905.029 [ElevatorServo](INFO): Powering down
2020-09-29T19:31:45.030Z,1601407905.030 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-09-29T19:31:45.030Z,1601407905.030 [MassServo](INFO): Powering down
2020-09-29T19:31:45.031Z,1601407905.031 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-09-29T19:31:45.031Z,1601407905.031 [RudderServo](INFO): Powering down
2020-09-29T19:31:45.031Z,1601407905.031 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-09-29T19:31:45.031Z,1601407905.031 [ThrusterServo](INFO): Powering down
2020-09-29T19:31:45.032Z,1601407905.032 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-09-29T19:31:45.033Z,1601407905.033 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-09-29T19:31:45.033Z,1601407905.033 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-09-29T19:31:45.033Z,1601407905.033 [CBIT](DEBUG): Powering off loads.
2020-09-29T19:31:45.044Z,1601407905.044 [CBIT](DEBUG): Disabling WDT.
2020-09-29T19:31:45.056Z,1601407905.056 [CBIT](DEBUG): Opening all GF detection circuits.
2020-09-29T19:31:45.057Z,1601407905.057 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-09-29T19:31:45.116Z,1601407905.116 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-09-29T19:31:45.119Z,1601407905.119 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-09-29T19:31:45.128Z,1601407905.128 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-09-29T19:31:45.175Z,1601407905.175 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-09-29T19:31:45.178Z,1601407905.178 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-09-29T19:31:45.236Z,1601407905.236 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-09-29T19:31:45.305Z,1601407905.305 [logger ThreadHandler](INFO): Thread cancelled.