2020-11-10T15:26:38.117Z,1605021998.117 [Supervisor](DEBUG): Initializing supervisor.
2020-11-10T15:26:38.120Z,1605021998.120 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-11-10T15:26:38.120Z,1605021998.120 [SyncHandler](INFO): Protected caller Thread ID is 985
2020-11-10T15:26:38.120Z,1605021998.120 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-11-10T15:26:38.121Z,1605021998.121 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-11-10T15:26:38.122Z,1605021998.122 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 986
2020-11-10T15:26:38.125Z,1605021998.125 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-11-10T15:26:38.141Z,1605021998.141 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-11-10T15:26:38.142Z,1605021998.142 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-11-10T15:26:38.142Z,1605021998.142 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 987
2020-11-10T15:26:38.143Z,1605021998.143 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-11-10T15:26:38.144Z,1605021998.144 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-11-10T15:26:38.144Z,1605021998.144 [logger ThreadHandler](INFO): Protected caller Thread ID is 988
2020-11-10T15:26:38.147Z,1605021998.147 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-11-10T15:26:38.147Z,1605021998.147 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-11-10T15:26:38.148Z,1605021998.148 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-11-10T15:26:38.242Z,1605021998.242 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-11-10T15:26:38.242Z,1605021998.242 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-11-10T15:26:38.813Z,1605021998.813 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-11-10T15:26:38.813Z,1605021998.813 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2020-11-10T15:26:39.141Z,1605021999.141 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2020-11-10T15:26:39.142Z,1605021999.142 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-11-10T15:26:39.241Z,1605021999.241 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-11-10T15:26:39.241Z,1605021999.241 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-11-10T15:26:39.344Z,1605021999.344 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-11-10T15:26:39.344Z,1605021999.344 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-11-10T15:26:39.425Z,1605021999.425 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-11-10T15:26:39.561Z,1605021999.561 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-11-10T15:26:39.561Z,1605021999.561 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-11-10T15:26:39.841Z,1605021999.841 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-11-10T15:26:39.842Z,1605021999.842 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-11-10T15:26:40.330Z,1605022000.330 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-11-10T15:26:40.330Z,1605022000.330 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-11-10T15:26:40.470Z,1605022000.470 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-11-10T15:26:40.471Z,1605022000.471 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-11-10T15:26:40.653Z,1605022000.653 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-11-10T15:26:40.654Z,1605022000.654 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-11-10T15:26:41.124Z,1605022001.124 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-11-10T15:26:41.124Z,1605022001.124 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-11-10T15:26:41.335Z,1605022001.335 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-11-10T15:26:41.335Z,1605022001.335 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-11-10T15:26:41.529Z,1605022001.529 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-11-10T15:26:41.530Z,1605022001.530 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-11-10T15:26:41.929Z,1605022001.929 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-11-10T15:26:41.929Z,1605022001.929 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-11-10T15:26:42.242Z,1605022002.242 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-11-10T15:26:42.244Z,1605022002.244 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2020-11-10T15:26:42.245Z,1605022002.245 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2020-11-10T15:26:42.327Z,1605022002.327 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2020-11-10T15:26:42.477Z,1605022002.477 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2020-11-10T15:26:42.582Z,1605022002.582 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2020-11-10T15:26:42.665Z,1605022002.665 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2020-11-10T15:26:42.757Z,1605022002.757 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2020-11-10T15:26:42.945Z,1605022002.945 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2020-11-10T15:26:43.193Z,1605022003.193 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-11-10T15:26:43.194Z,1605022003.194 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2020-11-10T15:26:43.285Z,1605022003.285 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2020-11-10T15:26:43.381Z,1605022003.381 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2020-11-10T15:26:43.508Z,1605022003.508 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2020-11-10T15:26:43.605Z,1605022003.605 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-11-10T15:26:43.618Z,1605022003.618 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2020-11-10T15:26:43.695Z,1605022003.695 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2020-11-10T15:26:43.695Z,1605022003.695 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-11-10T15:26:43.743Z,1605022003.743 [VerticalControl](DEBUG): Construct VerticalControl.
2020-11-10T15:26:43.800Z,1605022003.800 [VerticalControl] Loaded
2020-11-10T15:26:43.800Z,1605022003.800 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-11-10T15:26:43.801Z,1605022003.801 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-11-10T15:26:43.837Z,1605022003.837 [HorizontalControl] Loaded
2020-11-10T15:26:43.837Z,1605022003.837 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-11-10T15:26:43.838Z,1605022003.838 [SpeedControl](DEBUG): Construct SpeedControl.
2020-11-10T15:26:43.841Z,1605022003.841 [SpeedControl] Loaded
2020-11-10T15:26:43.842Z,1605022003.842 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-11-10T15:26:43.842Z,1605022003.842 [LoopControl](DEBUG): Construct LoopControl.
2020-11-10T15:26:43.843Z,1605022003.843 [LoopControl] Loaded
2020-11-10T15:26:43.843Z,1605022003.843 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-11-10T15:26:43.843Z,1605022003.843 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-11-10T15:26:43.844Z,1605022003.844 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-11-10T15:26:43.882Z,1605022003.882 [DepthRateCalculator] Loaded
2020-11-10T15:26:43.883Z,1605022003.883 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-11-10T15:26:43.885Z,1605022003.885 [PitchRateCalculator] Loaded
2020-11-10T15:26:43.886Z,1605022003.886 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-11-10T15:26:43.893Z,1605022003.893 [SpeedCalculator] Loaded
2020-11-10T15:26:43.894Z,1605022003.894 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-11-10T15:26:43.905Z,1605022003.905 [TempGradientCalculator] Loaded
2020-11-10T15:26:43.905Z,1605022003.905 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-11-10T15:26:43.907Z,1605022003.907 [YawRateCalculator] Loaded
2020-11-10T15:26:43.908Z,1605022003.908 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-11-10T15:26:43.922Z,1605022003.922 [ElevatorOffsetCalculator] Loaded
2020-11-10T15:26:43.922Z,1605022003.922 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-11-10T15:26:43.923Z,1605022003.923 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-11-10T15:26:43.923Z,1605022003.923 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-11-10T15:26:43.949Z,1605022003.949 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-11-10T15:26:43.949Z,1605022003.949 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-11-10T15:26:44.031Z,1605022004.031 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-11-10T15:26:44.032Z,1605022004.032 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-11-10T15:26:44.559Z,1605022004.559 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-11-10T15:26:44.559Z,1605022004.559 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-11-10T15:26:44.754Z,1605022004.754 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-11-10T15:26:44.754Z,1605022004.754 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-11-10T15:26:45.177Z,1605022005.177 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-11-10T15:26:45.180Z,1605022005.180 [AHRS_M2](INFO): created writer for : platform_orientation
2020-11-10T15:26:45.181Z,1605022005.181 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-11-10T15:26:45.184Z,1605022005.184 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-11-10T15:26:45.185Z,1605022005.185 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-11-10T15:26:45.188Z,1605022005.188 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-11-10T15:26:45.188Z,1605022005.188 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-11-10T15:26:45.191Z,1605022005.191 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-11-10T15:26:45.228Z,1605022005.228 [AHRS_M2] Loaded
2020-11-10T15:26:45.228Z,1605022005.228 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-11-10T15:26:45.299Z,1605022005.299 [DataOverHttps] Loaded
2020-11-10T15:26:45.299Z,1605022005.299 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-11-10T15:26:45.300Z,1605022005.300 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4090E4E0
2020-11-10T15:26:45.301Z,1605022005.301 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1071
2020-11-10T15:26:45.317Z,1605022005.317 [Depth_Keller] Loaded
2020-11-10T15:26:45.317Z,1605022005.317 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-11-10T15:26:45.320Z,1605022005.320 [DropWeight] Loaded
2020-11-10T15:26:45.320Z,1605022005.320 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2020-11-10T15:26:45.373Z,1605022005.373 [NAL9602] Loaded
2020-11-10T15:26:45.374Z,1605022005.374 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-11-10T15:26:45.399Z,1605022005.399 [Onboard] Loaded
2020-11-10T15:26:45.399Z,1605022005.399 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2020-11-10T15:26:45.400Z,1605022005.400 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4093E4E0
2020-11-10T15:26:45.400Z,1605022005.400 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1072
2020-11-10T15:26:45.411Z,1605022005.411 [Radio_Surface] Loaded
2020-11-10T15:26:45.411Z,1605022005.411 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-11-10T15:26:45.412Z,1605022005.412 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4096E4E0
2020-11-10T15:26:45.413Z,1605022005.413 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1073
2020-11-10T15:26:45.439Z,1605022005.439 [RDI_Pathfinder] Loaded
2020-11-10T15:26:45.439Z,1605022005.439 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2020-11-10T15:26:45.511Z,1605022005.511 [DAT] Loaded
2020-11-10T15:26:45.511Z,1605022005.511 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2020-11-10T15:26:46.297Z,1605022006.297 [BPC1] Loaded
2020-11-10T15:26:46.297Z,1605022006.297 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-11-10T15:26:46.297Z,1605022006.297 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-11-10T15:26:46.298Z,1605022006.298 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-11-10T15:26:46.433Z,1605022006.433 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-11-10T15:26:46.433Z,1605022006.433 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-11-10T15:26:46.444Z,1605022006.444 [NavChart] Loaded
2020-11-10T15:26:46.445Z,1605022006.445 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-11-10T15:26:46.448Z,1605022006.448 [UniversalFixResidualReporter] Loaded
2020-11-10T15:26:46.449Z,1605022006.449 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-11-10T15:26:46.449Z,1605022006.449 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-11-10T15:26:46.450Z,1605022006.450 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-11-10T15:26:46.584Z,1605022006.584 [SBIT](DEBUG): Construct Startup Built In Test.
2020-11-10T15:26:46.593Z,1605022006.593 [SBIT] Loaded
2020-11-10T15:26:46.593Z,1605022006.593 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-11-10T15:26:46.594Z,1605022006.594 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-11-10T15:26:46.606Z,1605022006.606 [IBIT] Loaded
2020-11-10T15:26:46.606Z,1605022006.606 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-11-10T15:26:46.609Z,1605022006.609 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-11-10T15:26:46.704Z,1605022006.704 [CBIT] Loaded
2020-11-10T15:26:46.704Z,1605022006.704 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-11-10T15:26:46.705Z,1605022006.705 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-11-10T15:26:46.705Z,1605022006.705 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-11-10T15:26:46.814Z,1605022006.814 [BuoyancyServo] Loaded
2020-11-10T15:26:46.814Z,1605022006.814 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-11-10T15:26:46.831Z,1605022006.831 [ElevatorServo] Loaded
2020-11-10T15:26:46.831Z,1605022006.831 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-11-10T15:26:46.847Z,1605022006.847 [MassServo] Loaded
2020-11-10T15:26:46.847Z,1605022006.847 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-11-10T15:26:46.862Z,1605022006.862 [RudderServo] Loaded
2020-11-10T15:26:46.863Z,1605022006.863 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-11-10T15:26:46.877Z,1605022006.877 [ThrusterServo] Loaded
2020-11-10T15:26:46.878Z,1605022006.878 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-11-10T15:26:46.878Z,1605022006.878 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-11-10T15:26:46.878Z,1605022006.878 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-11-10T15:26:46.896Z,1605022006.896 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-11-10T15:26:46.897Z,1605022006.897 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-11-10T15:26:47.102Z,1605022007.102 [BackSeatDriver] Loaded
2020-11-10T15:26:47.102Z,1605022007.102 [ComponentRegistry](DEBUG): Component "BackSeatDriver" handled in its own thread.
2020-11-10T15:26:47.103Z,1605022007.103 [BackSeatDriver ThreadHandler](DEBUG): Created PCaller Thread at 40B064E0
2020-11-10T15:26:47.104Z,1605022007.104 [BackSeatDriver ThreadHandler](INFO): Protected caller Thread ID is 1075
2020-11-10T15:26:47.123Z,1605022007.123 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2020-11-10T15:26:47.126Z,1605022007.126 [CTD_Seabird](INFO): created writer for : sea_water_density
2020-11-10T15:26:47.126Z,1605022007.126 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2020-11-10T15:26:47.129Z,1605022007.129 [CTD_Seabird](INFO): created writer for : depth
2020-11-10T15:26:47.129Z,1605022007.129 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2020-11-10T15:26:47.132Z,1605022007.132 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2020-11-10T15:26:47.132Z,1605022007.132 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2020-11-10T15:26:47.135Z,1605022007.135 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2020-11-10T15:26:47.136Z,1605022007.136 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2020-11-10T15:26:47.138Z,1605022007.138 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2020-11-10T15:26:47.139Z,1605022007.139 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2020-11-10T15:26:47.142Z,1605022007.142 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2020-11-10T15:26:47.143Z,1605022007.143 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2020-11-10T15:26:47.146Z,1605022007.146 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2020-11-10T15:26:47.158Z,1605022007.158 [CTD_Seabird] Loaded
2020-11-10T15:26:47.159Z,1605022007.159 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2020-11-10T15:26:47.160Z,1605022007.160 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B364E0
2020-11-10T15:26:47.160Z,1605022007.160 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1076
2020-11-10T15:26:47.177Z,1605022007.177 [PAR_Licor] Loaded
2020-11-10T15:26:47.177Z,1605022007.177 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2020-11-10T15:26:47.190Z,1605022007.190 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470
2020-11-10T15:26:47.191Z,1605022007.191 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470
2020-11-10T15:26:47.193Z,1605022007.193 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650
2020-11-10T15:26:47.193Z,1605022007.193 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650
2020-11-10T15:26:47.195Z,1605022007.195 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl
2020-11-10T15:26:47.195Z,1605022007.195 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl
2020-11-10T15:26:47.197Z,1605022007.197 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm
2020-11-10T15:26:47.197Z,1605022007.197 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm
2020-11-10T15:26:47.199Z,1605022007.199 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm
2020-11-10T15:26:47.199Z,1605022007.199 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm
2020-11-10T15:26:47.201Z,1605022007.201 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm
2020-11-10T15:26:47.202Z,1605022007.202 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm
2020-11-10T15:26:47.203Z,1605022007.203 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm
2020-11-10T15:26:47.204Z,1605022007.204 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm
2020-11-10T15:26:47.205Z,1605022007.205 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water
2020-11-10T15:26:47.208Z,1605022007.208 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water
2020-11-10T15:26:47.208Z,1605022007.208 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-11-10T15:26:47.208Z,1605022007.208 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-11-10T15:26:47.210Z,1605022007.210 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-11-10T15:26:47.210Z,1605022007.210 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-11-10T15:26:47.212Z,1605022007.212 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-11-10T15:26:47.212Z,1605022007.212 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-11-10T15:26:47.214Z,1605022007.214 [WetLabsBB2FL] Loaded
2020-11-10T15:26:47.215Z,1605022007.215 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2020-11-10T15:26:47.216Z,1605022007.216 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B664E0
2020-11-10T15:26:47.216Z,1605022007.216 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1077
2020-11-10T15:26:47.236Z,1605022007.236 [WetLabsUBAT..SerialDriver](INFO): Created logger
2020-11-10T15:26:47.236Z,1605022007.236 [WetLabsUBAT..SerialDriver](INFO): publishing on LCM channel WetLabsUBAT
2020-11-10T15:26:47.237Z,1605022007.237 [WetLabsUBAT..StreamSerialDriver](INFO): Created logger
2020-11-10T15:26:47.242Z,1605022007.242 [WetLabsUBAT](INFO): readConfig(): serialNo_: UBAT0051, uartName_: /dev/ttyC1, optionalArgs_: 
2020-11-10T15:26:47.242Z,1605022007.242 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsUBAT.biolum_potential
2020-11-10T15:26:47.242Z,1605022007.242 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(): dataName: biolum_potential
2020-11-10T15:26:47.244Z,1605022007.244 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsUBAT.flow_rate
2020-11-10T15:26:47.244Z,1605022007.244 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(): dataName: flow_rate
2020-11-10T15:26:47.247Z,1605022007.247 [WetLabsUBAT] Loaded
2020-11-10T15:26:47.247Z,1605022007.247 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread.
2020-11-10T15:26:47.248Z,1605022007.248 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40B964E0
2020-11-10T15:26:47.248Z,1605022007.248 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 1078
2020-11-10T15:26:47.249Z,1605022007.249 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-11-10T15:26:47.253Z,1605022007.253 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-11-10T15:26:47.254Z,1605022007.254 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-11-10T15:26:47.262Z,1605022007.262 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-11-10T15:26:47.263Z,1605022007.263 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BC64E0
2020-11-10T15:26:47.263Z,1605022007.263 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1079
2020-11-10T15:26:47.268Z,1605022007.268 [Supervisor](INFO): Main Thread ID is 829
2020-11-10T15:26:47.268Z,1605022007.268 [Supervisor](DEBUG): Running supervisor.
2020-11-10T15:26:47.268Z,1605022007.268 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1080
2020-11-10T15:26:47.271Z,1605022007.271 [controlThread ThreadHandler](INFO): Handler Thread ID is 1081
2020-11-10T15:26:47.272Z,1605022007.272 [controlThread](DEBUG): Initializing ControlThread
2020-11-10T15:26:47.273Z,1605022007.273 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-11-10T15:26:47.274Z,1605022007.274 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-11-10T15:26:47.275Z,1605022007.275 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-11-10T15:26:47.275Z,1605022007.275 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-11-10T15:26:47.276Z,1605022007.276 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-11-10T15:26:47.276Z,1605022007.276 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-11-10T15:26:47.276Z,1605022007.276 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-11-10T15:26:47.277Z,1605022007.277 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-11-10T15:26:47.277Z,1605022007.277 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-11-10T15:26:47.278Z,1605022007.278 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-11-10T15:26:47.282Z,1605022007.282 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-11-10T15:26:47.282Z,1605022007.282 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-11-10T15:26:47.283Z,1605022007.283 [SBIT](INFO): Initialize SBIT Component.
2020-11-10T15:26:47.283Z,1605022007.283 [SBIT](IMPORTANT): git: 2020-11-09
2020-11-10T15:26:47.284Z,1605022007.284 [SBIT](INFO): git hash: f81f2d5d64572d3a213bb5cdbf8311bdbfca79ef
2020-11-10T15:26:47.284Z,1605022007.284 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-11-10T15:26:47.285Z,1605022007.285 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
Kernel Reported: #1 PREEMPT Thu Aug 6 12:04:53 PDT 2020
2020-11-10T15:26:47.286Z,1605022007.286 [SBIT](INFO): Beginning SBIT in 56.000000 seconds.
2020-11-10T15:26:47.287Z,1605022007.287 [IBIT](INFO): Initialize IBIT Component.
2020-11-10T15:26:47.288Z,1605022007.288 [CBIT](DEBUG): Initialize CBIT Component.
2020-11-10T15:26:47.289Z,1605022007.289 [logger ThreadHandler](INFO): Handler Thread ID is 1082
2020-11-10T15:26:47.299Z,1605022007.299 [CBIT](DEBUG): Initialized mux pins.
2020-11-10T15:26:47.299Z,1605022007.299 [CBIT](DEBUG): Initializing the watchdog timer.
2020-11-10T15:26:47.307Z,1605022007.307 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1083
2020-11-10T15:26:47.308Z,1605022007.308 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-11-10T15:26:47.319Z,1605022007.319 [Onboard ThreadHandler](INFO): Handler Thread ID is 1084
2020-11-10T15:26:47.324Z,1605022007.324 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-11-10T15:26:47.324Z,1605022007.324 [CBIT](DEBUG): Initializing heartbeat.
2020-11-10T15:26:47.337Z,1605022007.337 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1085
2020-11-10T15:26:47.359Z,1605022007.359 [BackSeatDriver ThreadHandler](INFO): Handler Thread ID is 1086
2020-11-10T15:26:47.379Z,1605022007.379 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1087
2020-11-10T15:26:47.380Z,1605022007.380 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-11-10T15:26:47.384Z,1605022007.384 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1089
2020-11-10T15:26:47.385Z,1605022007.385 [WetLabsBB2FL](INFO): Powering down
2020-11-10T15:26:47.395Z,1605022007.395 [CBIT](DEBUG): Deactivating GF circuits.
2020-11-10T15:26:47.395Z,1605022007.395 [CBIT](DEBUG): Deactivating emergency mode.
2020-11-10T15:26:47.396Z,1605022007.396 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 1090
2020-11-10T15:26:47.408Z,1605022007.408 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1091
2020-11-10T15:26:47.410Z,1605022007.410 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-11-10T15:26:47.410Z,1605022007.410 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-11-10T15:26:47.411Z,1605022007.411 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-11-10T15:26:47.411Z,1605022007.411 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-11-10T15:26:47.411Z,1605022007.411 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-11-10T15:26:47.411Z,1605022007.411 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-11-10T15:26:47.411Z,1605022007.411 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-11-10T15:26:47.411Z,1605022007.411 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-11-10T15:26:47.412Z,1605022007.412 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-11-10T15:26:47.412Z,1605022007.412 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-11-10T15:26:47.412Z,1605022007.412 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-11-10T15:26:47.412Z,1605022007.412 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-11-10T15:26:47.412Z,1605022007.412 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-11-10T15:26:47.412Z,1605022007.412 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-11-10T15:26:47.412Z,1605022007.412 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-11-10T15:26:47.413Z,1605022007.413 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-11-10T15:26:47.431Z,1605022007.431 [CBIT](DEBUG): Backplane powered.
2020-11-10T15:26:47.436Z,1605022007.436 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-11-10T15:26:47.463Z,1605022007.463 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-11-10T15:26:47.494Z,1605022007.494 [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-11-10T15:26:47.494Z,1605022007.494 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-11-10T15:26:47.569Z,1605022007.569 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-11-10T15:26:47.570Z,1605022007.570 [Default:A.Wait](DEBUG): Construct Wait.
2020-11-10T15:26:47.572Z,1605022007.572 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-11-10T15:26:47.598Z,1605022007.598 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-11-10T15:26:47.617Z,1605022007.617 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-11-10T15:26:47.622Z,1605022007.622 [Default:E.Execute](DEBUG): Construct Execute.
2020-11-10T15:26:47.648Z,1605022007.648 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2020-11-10T15:26:47.653Z,1605022007.653 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,NAL9602,RDI_Pathfinder,DAT,BPC1,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2020-11-10T15:26:47.673Z,1605022007.673 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-11-10T15:26:47.713Z,1605022007.713 [Depth_Keller](ERROR): Pressure reading out of range: 1650.297119 decibar
2020-11-10T15:26:47.730Z,1605022007.730 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2020-11-10T15:26:47.730Z,1605022007.730 [DAT](INFO): Powering up
2020-11-10T15:26:47.730Z,1605022007.730 [DAT](DEBUG): Initializing DAT.
2020-11-10T15:26:47.743Z,1605022007.743 [Radio_Surface](INFO): Powering up
2020-11-10T15:26:47.779Z,1605022007.779 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2020-11-10T15:26:47.810Z,1605022007.810 [DepthRateCalculator](ERROR): Depth measurement is not active
2020-11-10T15:26:47.857Z,1605022007.857 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-11-10T15:26:47.867Z,1605022007.867 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-11-10T15:26:47.869Z,1605022007.869 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-11-10T15:26:47.879Z,1605022007.879 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-11-10T15:26:47.880Z,1605022007.880 [MassServo](DEBUG): Initializing EZServoServo.
2020-11-10T15:26:47.891Z,1605022007.891 [MassServo](DEBUG): Initializing MassServo.
2020-11-10T15:26:47.892Z,1605022007.892 [RudderServo](DEBUG): Initializing EZServoServo.
2020-11-10T15:26:47.903Z,1605022007.903 [RudderServo](DEBUG): Initializing RudderServo.
2020-11-10T15:26:47.905Z,1605022007.905 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-11-10T15:26:47.911Z,1605022007.911 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-11-10T15:26:48.139Z,1605022008.139 [DepthRateCalculator](ERROR): Depth measurement is not active
2020-11-10T15:26:48.787Z,1605022008.787 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2020-11-10T15:26:48.787Z,1605022008.787 [RudderServo](FAULT): Rudder failed to initialize
2020-11-10T15:26:48.787Z,1605022008.787 [RudderServo] Communications Fault, FailCount= 1
2020-11-10T15:26:48.787Z,1605022008.787 [RudderServo](ERROR): Communications Fault
2020-11-10T15:26:48.898Z,1605022008.898 [CBIT](ERROR): Communications Fault in component: RudderServo
2020-11-10T15:26:49.052Z,1605022009.052 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-11-10T15:26:49.052Z,1605022009.052 [RudderServo](INFO): Powering down
2020-11-10T15:26:49.735Z,1605022009.735 [RudderServo](DEBUG): Initializing EZServoServo.
2020-11-10T15:26:49.856Z,1605022009.856 [RudderServo](DEBUG): Initializing RudderServo.
2020-11-10T15:26:49.860Z,1605022009.860 [CBIT](INFO): Clearing failed state for component RudderServo
2020-11-10T15:26:49.860Z,1605022009.860 [RudderServo] No Fault, FailCount= 1
2020-11-10T15:27:01.843Z,1605022021.843 [DAT](INFO): commRate: 800
2020-11-10T15:27:03.872Z,1605022023.872 [DAT](INFO): entering command mode
2020-11-10T15:27:04.265Z,1605022024.265 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-10T15:27:04.669Z,1605022024.669 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-10T15:27:05.087Z,1605022025.087 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-10T15:27:05.487Z,1605022025.487 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-10T15:27:05.882Z,1605022025.882 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-10T15:27:06.309Z,1605022026.309 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-10T15:27:06.689Z,1605022026.689 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-10T15:27:07.089Z,1605022027.089 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-10T15:27:07.509Z,1605022027.509 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-10T15:27:07.897Z,1605022027.897 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-10T15:27:08.316Z,1605022028.316 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-10T15:27:08.709Z,1605022028.709 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-10T15:27:09.117Z,1605022029.117 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-10T15:27:09.545Z,1605022029.545 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-10T15:27:09.917Z,1605022029.917 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-10T15:27:10.331Z,1605022030.331 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-10T15:27:10.729Z,1605022030.729 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-10T15:27:11.129Z,1605022031.129 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-10T15:27:11.547Z,1605022031.547 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-10T15:27:11.937Z,1605022031.937 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-10T15:27:12.368Z,1605022032.368 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-10T15:27:12.762Z,1605022032.762 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-10T15:27:13.149Z,1605022033.149 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-10T15:27:13.553Z,1605022033.553 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-10T15:27:13.975Z,1605022033.975 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-10T15:27:14.381Z,1605022034.381 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-10T15:27:14.765Z,1605022034.765 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-10T15:27:15.173Z,1605022035.173 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-10T15:27:15.573Z,1605022035.573 [NAL9602](INFO): Powering up NAL9602
2020-11-10T15:27:15.589Z,1605022035.589 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-10T15:27:15.977Z,1605022035.977 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-10T15:27:16.395Z,1605022036.395 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-10T15:27:16.785Z,1605022036.785 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-10T15:27:17.241Z,1605022037.241 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-10T15:27:17.593Z,1605022037.593 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-10T15:27:18.001Z,1605022038.001 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-10T15:27:18.422Z,1605022038.422 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-10T15:27:18.805Z,1605022038.805 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-10T15:27:19.209Z,1605022039.209 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-10T15:27:19.209Z,1605022039.209 [DAT](FAULT): failed to enter command mode
2020-11-10T15:27:19.634Z,1605022039.634 [DAT](INFO): entering command mode
2020-11-10T15:27:20.022Z,1605022040.022 [DAT](INFO): setting verbose to 3
2020-11-10T15:27:20.437Z,1605022040.437 [DAT](INFO): set verbose to 3
2020-11-10T15:27:20.437Z,1605022040.437 [DAT](INFO): setting DatVerbose to 27440
2020-11-10T15:27:20.827Z,1605022040.827 [DAT](INFO): set DatVerbose to 27440
2020-11-10T15:27:20.827Z,1605022040.827 [DAT](INFO): setting transmit power to 8
2020-11-10T15:27:21.240Z,1605022041.240 [DAT](INFO): set transmit power to 8
2020-11-10T15:27:21.241Z,1605022041.241 [DAT](INFO): setting local address to 9
2020-11-10T15:27:21.634Z,1605022041.634 [DAT](INFO): set local address to 9
2020-11-10T15:27:26.481Z,1605022046.481 [NAL9602](INFO): NAL9602 initialized
2020-11-10T15:27:43.875Z,1605022063.875 [SBIT](IMPORTANT): Beginning Startup BIT
2020-11-10T15:27:43.879Z,1605022063.879 [CBIT](IMPORTANT): Beginning ground fault scan
2020-11-10T15:27:54.831Z,1605022074.831 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.009735
CHAN A1 (24V): -0.001566
CHAN A2 (12V): -0.006949
CHAN A3 (5V): -0.002480
CHAN B0 (3.3V): -0.000531
CHAN B1 (3.15aV): -0.000149
CHAN B2 (3.15bV): -0.000479
CHAN B3 (GND): 0.002021
OPEN: 0.005147
Full Scale Calc: 4.765 mA, -1.589 mA
2020-11-10T15:28:08.178Z,1605022088.178 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-11-10T15:28:37.637Z,1605022117.637 [SBIT](IMPORTANT): SBIT PASSED
2020-11-10T15:28:37.638Z,1605022117.638 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-11-10T15:28:37.671Z,1605022117.671 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=6 count;

2020-11-10T15:28:37.671Z,1605022117.671 [SBIT](IMPORTANT): CBIT.gf24Offset=145 microampere;

2020-11-10T15:28:37.671Z,1605022117.671 [SBIT](IMPORTANT): DAT.loadAtStartup=1 bool;

2020-11-10T15:28:37.671Z,1605022117.671 [SBIT](IMPORTANT): DAT.sbdAddress=-1 enum;

2020-11-10T15:28:37.671Z,1605022117.671 [SBIT](IMPORTANT): DAT.surfaceThreshold=100 meter;

2020-11-10T15:28:37.672Z,1605022117.672 [SBIT](IMPORTANT): DAT.verbosity=0 count;

2020-11-10T15:28:37.672Z,1605022117.672 [SBIT](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 1.000000 liter_per_second;

2020-11-10T15:28:37.672Z,1605022117.672 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 5.000000 meter;

2020-11-10T15:28:37.672Z,1605022117.672 [SBIT](IMPORTANT): PAR_Licor.loadAtStartup=1 bool;

2020-11-10T15:28:37.672Z,1605022117.672 [SBIT](IMPORTANT): PAR_Licor.parCal=1.01146 none;

2020-11-10T15:28:37.672Z,1605022117.672 [SBIT](IMPORTANT): RDI_Pathfinder.loadAtStartup=1 bool;

2020-11-10T15:28:37.672Z,1605022117.672 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=203.794028 cubic_centimeter;

2020-11-10T15:28:37.672Z,1605022117.672 [SBIT](IMPORTANT): VerticalControl.massDefault=9.170314 millimeter;

2020-11-10T15:28:37.672Z,1605022117.672 [SBIT](IMPORTANT): WetLabsUBAT.loadAtStartup=1 bool;

2020-11-10T15:28:38.026Z,1605022118.026 [MissionManager](IMPORTANT): Started mission Startup
2020-11-10T15:28:38.026Z,1605022118.026 [Startup] Running Loop=1
2020-11-10T15:28:38.026Z,1605022118.026 [Startup](DEBUG): Aggregate::initialize Startup
2020-11-10T15:28:38.026Z,1605022118.026 [Startup:A.GoToSurface] Running Loop=1
2020-11-10T15:28:38.026Z,1605022118.026 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-11-10T15:28:38.027Z,1605022118.027 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-11-10T15:28:38.028Z,1605022118.028 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-11-10T15:28:38.028Z,1605022118.028 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-11-10T15:28:38.028Z,1605022118.028 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-11-10T15:28:38.029Z,1605022118.029 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-11-10T15:28:38.030Z,1605022118.030 [Startup:StartupSatComms] Running Loop=1
2020-11-10T15:28:38.030Z,1605022118.030 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-11-10T15:28:38.030Z,1605022118.030 [Startup:StartupSatComms:A] Running Loop=1
2020-11-10T15:28:38.452Z,1605022118.452 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-11-10T15:29:13.993Z,1605022153.993 [CommandLine](IMPORTANT): got command burn on
2020-11-10T15:29:13.993Z,1605022153.993 [CommandLine](IMPORTANT): Activating dropweight wire
2020-11-10T15:29:15.390Z,1605022155.390 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005085
2020-11-10T15:29:38.237Z,1605022178.237 [Startup:StartupSatComms:A](INFO): Timed out from 2020-11-10T15:28:38.0Z
2020-11-10T15:29:38.237Z,1605022178.237 [Startup:StartupSatComms:A] Stopped
2020-11-10T15:29:38.237Z,1605022178.237 [Startup:StartupSatComms:B] Running Loop=1
2020-11-10T15:29:38.636Z,1605022178.636 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-11-10T15:29:45.862Z,1605022185.862 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201110T152224/Courier0004.lzma
2020-11-10T15:29:46.865Z,1605022186.865 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152224/Courier0004.lzma.bak
2020-11-10T15:29:46.865Z,1605022186.865 [DataOverHttps](INFO): SBD MOMSN=13164274
2020-11-10T15:29:47.547Z,1605022187.547 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2020-11-10T15:29:47.547Z,1605022187.547 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-11-10T15:29:47.561Z,1605022187.561 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-11-10T15:29:48.030Z,1605022188.030 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-11-10T15:29:48.030Z,1605022188.030 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2020-11-10T15:30:02.779Z,1605022202.779 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20201110T152638/Courier0000.lzma
2020-11-10T15:30:03.781Z,1605022203.781 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Courier0000.lzma.bak
2020-11-10T15:30:03.781Z,1605022203.781 [DataOverHttps](INFO): SBD MOMSN=13164276
2020-11-10T15:30:12.553Z,1605022212.553 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2020-11-10T15:30:12.555Z,1605022212.555 [BPC1](ERROR): BPC1A failed to parse battery stick message.
2020-11-10T15:30:19.757Z,1605022219.757 [DataOverHttps](INFO): Sending 1154 bytes from file Logs/20201110T152224/Express0001.lzma
2020-11-10T15:30:20.757Z,1605022220.757 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152224/Express0001.lzma.bak
2020-11-10T15:30:20.757Z,1605022220.757 [DataOverHttps](INFO): SBD MOMSN=13164279
2020-11-10T15:30:27.353Z,1605022227.353 [CommandLine](IMPORTANT): got command burn off
2020-11-10T15:30:27.353Z,1605022227.353 [CommandLine](IMPORTANT): Deactivating dropweight wire
2020-11-10T15:30:36.654Z,1605022236.654 [DataOverHttps](INFO): Sending 116 bytes from file Logs/20201110T152224/Express0005.lzma
2020-11-10T15:30:37.657Z,1605022237.657 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152224/Express0005.lzma.bak
2020-11-10T15:30:37.657Z,1605022237.657 [DataOverHttps](INFO): SBD MOMSN=13164312
2020-11-10T15:30:38.422Z,1605022238.422 [Startup:StartupSatComms:B](INFO): Timed out from 2020-11-10T15:29:38.2Z
2020-11-10T15:30:38.422Z,1605022238.422 [Startup:StartupSatComms:B] Stopped
2020-11-10T15:30:38.422Z,1605022238.422 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2020-11-10T15:30:38.422Z,1605022238.422 [Startup:StartupSatComms] Stopped
2020-11-10T15:30:38.423Z,1605022238.423 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-11-10T15:30:38.423Z,1605022238.423 [Startup](INFO): Completed Startup
2020-11-10T15:30:38.424Z,1605022238.424 [MissionManager](INFO): Startup is completed.
2020-11-10T15:30:38.424Z,1605022238.424 [MissionManager](INFO): Uninitializing Mission Startup
2020-11-10T15:30:38.424Z,1605022238.424 [Startup] Stopped
2020-11-10T15:30:38.424Z,1605022238.424 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-11-10T15:30:38.424Z,1605022238.424 [Startup:A.GoToSurface] Stopped
2020-11-10T15:30:38.424Z,1605022238.424 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-11-10T15:30:38.826Z,1605022238.826 [MissionManager](IMPORTANT): Started mission Default
2020-11-10T15:30:38.826Z,1605022238.826 [Default] Running Loop=1
2020-11-10T15:30:38.826Z,1605022238.826 [Default](DEBUG): Aggregate::initialize Default
2020-11-10T15:30:38.826Z,1605022238.826 [Default:B.GoToSurface] Running Loop=1
2020-11-10T15:30:38.826Z,1605022238.826 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-11-10T15:30:38.827Z,1605022238.827 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-11-10T15:30:38.827Z,1605022238.827 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-11-10T15:30:38.827Z,1605022238.827 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-11-10T15:30:38.828Z,1605022238.828 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-11-10T15:30:38.828Z,1605022238.828 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-11-10T15:30:38.828Z,1605022238.828 [Default:A.Wait] Running Loop=1
2020-11-10T15:30:38.828Z,1605022238.828 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-11-10T15:30:52.130Z,1605022252.130 [Default:A.Wait](INFO): Done Waiting.
2020-11-10T15:30:52.130Z,1605022252.130 [Default:A.Wait] Stopped
2020-11-10T15:30:52.130Z,1605022252.130 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-11-10T15:30:52.592Z,1605022252.592 [Default:CheckIn] Running Loop=1
2020-11-10T15:30:52.592Z,1605022252.592 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-10T15:30:52.592Z,1605022252.592 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-10T15:30:52.990Z,1605022252.990 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-11-10T15:31:56.015Z,1605022316.015 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-11-10T15:32:25.979Z,1605022345.979 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-11-10T15:32:29.490Z,1605022349.490 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-11-10T15:32:48.523Z,1605022368.523 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2020-11-10T15:32:48.523Z,1605022368.523 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-11-10T15:32:48.533Z,1605022368.533 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-11-10T15:32:48.897Z,1605022368.897 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-11-10T15:32:48.897Z,1605022368.897 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2020-11-10T15:33:08.737Z,1605022388.737 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-11-10T15:33:54.419Z,1605022434.419 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-11-10T15:35:10.712Z,1605022510.712 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-11-10T15:35:10.715Z,1605022510.715 [BPC1](INFO): Received data from all battery sticks.
2020-11-10T15:35:49.486Z,1605022549.486 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2020-11-10T15:35:49.486Z,1605022549.486 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-11-10T15:35:49.496Z,1605022549.496 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-11-10T15:35:49.901Z,1605022549.901 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-11-10T15:35:49.901Z,1605022549.901 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2020-11-10T15:35:52.713Z,1605022552.713 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-11-10T15:30:52.6Z
2020-11-10T15:35:52.713Z,1605022552.713 [Default:CheckIn:Read_GPS] Stopped
2020-11-10T15:35:52.713Z,1605022552.713 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-10T15:35:53.119Z,1605022553.119 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-11-10T15:35:59.554Z,1605022559.554 [DataOverHttps](INFO): Sending 167 bytes from file Logs/20201110T152638/Courier0004.lzma
2020-11-10T15:36:00.557Z,1605022560.557 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Courier0004.lzma.bak
2020-11-10T15:36:00.557Z,1605022560.557 [DataOverHttps](INFO): SBD MOMSN=13164368
2020-11-10T15:36:16.496Z,1605022576.496 [DataOverHttps](INFO): Sending 1056 bytes from file Logs/20201110T152638/Express0001.lzma
2020-11-10T15:36:17.497Z,1605022577.497 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Express0001.lzma.bak
2020-11-10T15:36:17.497Z,1605022577.497 [DataOverHttps](INFO): SBD MOMSN=13164371
2020-11-10T15:36:33.243Z,1605022593.243 [DataOverHttps](INFO): Sending 236 bytes from file Logs/20201110T152638/Express0005.lzma
2020-11-10T15:36:34.245Z,1605022594.245 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Express0005.lzma.bak
2020-11-10T15:36:34.245Z,1605022594.245 [DataOverHttps](INFO): SBD MOMSN=13164408
2020-11-10T15:36:35.560Z,1605022595.560 [Default:CheckIn:Read_Iridium] Stopped
2020-11-10T15:36:35.560Z,1605022595.560 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-10T15:36:35.560Z,1605022595.560 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-10T15:37:27.641Z,1605022647.641 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-11-10T15:37:27.641Z,1605022647.641 [NAL9602] Data Fault, FailCount= 1
2020-11-10T15:37:27.641Z,1605022647.641 [NAL9602](ERROR): Data Fault
2020-11-10T15:37:27.658Z,1605022647.658 [CBIT](ERROR): Data Fault in component: NAL9602
2020-11-10T15:37:28.041Z,1605022648.041 [NAL9602](INFO): Powering down
2020-11-10T15:37:28.884Z,1605022648.884 [CBIT](INFO): Clearing failed state for component NAL9602
2020-11-10T15:37:28.884Z,1605022648.884 [NAL9602] No Fault, FailCount= 1
2020-11-10T15:37:58.344Z,1605022678.344 [NAL9602](INFO): Powering up NAL9602
2020-11-10T15:38:09.258Z,1605022689.258 [NAL9602](INFO): NAL9602 initialized
2020-11-10T15:38:50.510Z,1605022730.510 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2020-11-10T15:38:50.510Z,1605022730.510 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-11-10T15:38:50.520Z,1605022730.520 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-11-10T15:38:50.884Z,1605022730.884 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-11-10T15:38:50.884Z,1605022730.884 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2020-11-10T15:41:36.169Z,1605022896.169 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-10T15:41:36.169Z,1605022896.169 [Default:CheckIn:C.Wait] Stopped
2020-11-10T15:41:36.169Z,1605022896.169 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-10T15:41:36.169Z,1605022896.169 [Default:CheckIn:D] Running Loop=1
2020-11-10T15:41:36.577Z,1605022896.577 [Default:CheckIn:D] Stopped
2020-11-10T15:41:36.577Z,1605022896.577 [Default:CheckIn:E] Running Loop=1
2020-11-10T15:41:36.990Z,1605022896.990 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.962514 min 
2020-11-10T15:41:36.990Z,1605022896.990 [Default:CheckIn:E] Stopped
2020-11-10T15:41:36.990Z,1605022896.990 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-10T15:41:36.990Z,1605022896.990 [Default:CheckIn] Stopped
2020-11-10T15:41:36.990Z,1605022896.990 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-10T15:41:36.990Z,1605022896.990 [Default:CheckIn](INFO): Running loop #2
2020-11-10T15:41:36.991Z,1605022896.991 [Default:CheckIn] Running Loop=2
2020-11-10T15:41:36.991Z,1605022896.991 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-10T15:41:36.991Z,1605022896.991 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-10T15:41:51.536Z,1605022911.536 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2020-11-10T15:41:51.536Z,1605022911.536 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-11-10T15:41:51.546Z,1605022911.546 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-11-10T15:41:51.949Z,1605022911.949 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-11-10T15:41:51.949Z,1605022911.949 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2020-11-10T15:44:52.537Z,1605023092.537 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2020-11-10T15:44:52.537Z,1605023092.537 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-11-10T15:44:52.547Z,1605023092.547 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-11-10T15:44:52.929Z,1605023092.929 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-11-10T15:44:52.929Z,1605023092.929 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2020-11-10T15:46:19.364Z,1605023179.364 [CommandLine](IMPORTANT): got command failComponent
2020-11-10T15:46:19.364Z,1605023179.364 [CommandLine](IMPORTANT): Failed components:
2020-11-10T15:46:19.364Z,1605023179.364 [CommandLine](IMPORTANT): No failed Components.
2020-11-10T15:46:37.153Z,1605023197.153 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-11-10T15:41:36.0Z
2020-11-10T15:46:37.153Z,1605023197.153 [Default:CheckIn:Read_GPS] Stopped
2020-11-10T15:46:37.154Z,1605023197.154 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-10T15:46:44.538Z,1605023204.538 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201110T152638/Courier0007.lzma
2020-11-10T15:46:45.541Z,1605023205.541 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Courier0007.lzma.bak
2020-11-10T15:46:45.541Z,1605023205.541 [DataOverHttps](INFO): SBD MOMSN=13164516
2020-11-10T15:47:01.247Z,1605023221.247 [DataOverHttps](INFO): Sending 231 bytes from file Logs/20201110T152638/Express0008.lzma
2020-11-10T15:47:02.249Z,1605023222.249 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Express0008.lzma.bak
2020-11-10T15:47:02.249Z,1605023222.249 [DataOverHttps](INFO): SBD MOMSN=13164527
2020-11-10T15:47:03.472Z,1605023223.472 [Default:CheckIn:Read_Iridium] Stopped
2020-11-10T15:47:03.472Z,1605023223.472 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-10T15:47:03.472Z,1605023223.472 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-10T15:47:23.229Z,1605023243.229 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-11-10T15:47:53.535Z,1605023273.535 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2020-11-10T15:47:53.535Z,1605023273.535 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-11-10T15:47:53.550Z,1605023273.550 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-11-10T15:47:53.953Z,1605023273.953 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-11-10T15:47:53.953Z,1605023273.953 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2020-11-10T15:48:11.293Z,1605023291.293 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-11-10T15:48:11.293Z,1605023291.293 [NAL9602] Data Fault, FailCount= 2
2020-11-10T15:48:11.293Z,1605023291.293 [NAL9602](ERROR): Data Fault
2020-11-10T15:48:11.315Z,1605023291.315 [CBIT](ERROR): Data Fault in component: NAL9602
2020-11-10T15:48:11.704Z,1605023291.704 [NAL9602](INFO): Powering down
2020-11-10T15:48:12.526Z,1605023292.526 [CBIT](INFO): Clearing failed state for component NAL9602
2020-11-10T15:48:12.526Z,1605023292.526 [NAL9602] No Fault, FailCount= 2
2020-11-10T15:48:41.997Z,1605023321.997 [NAL9602](INFO): Powering up NAL9602
2020-11-10T15:48:52.905Z,1605023332.905 [NAL9602](INFO): NAL9602 initialized
2020-11-10T15:49:47.601Z,1605023387.601 [CommandLine](IMPORTANT): got command maintain control SpeedControl.propOmegaAction 300.000000 revolution_per_minute
2020-11-10T15:49:47.602Z,1605023387.602 [ComponentRegistry](DEBUG): SyncComponent "Maintain_SpeedControl.propOmegaAction" handled in the control thread.
2020-11-10T15:49:47.874Z,1605023387.874 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,NAL9602,RDI_Pathfinder,DAT,BPC1,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_SpeedControl.propOmegaAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2020-11-10T15:50:26.885Z,1605023426.885 [CommandLine](IMPORTANT): got command report mod ThrusterServo.component_avgCurrent
2020-11-10T15:50:27.096Z,1605023427.096 [Reporter](INFO): ThrusterServo.component_avgCurrent 146.800003 mA
2020-11-10T15:50:29.544Z,1605023429.544 [Reporter](INFO): ThrusterServo.component_avgCurrent 143.130005 mA
2020-11-10T15:50:31.956Z,1605023431.956 [Reporter](INFO): ThrusterServo.component_avgCurrent 139.460007 mA
2020-11-10T15:50:34.400Z,1605023434.400 [Reporter](INFO): ThrusterServo.component_avgCurrent 135.790009 mA
2020-11-10T15:50:39.276Z,1605023439.276 [Reporter](INFO): ThrusterServo.component_avgCurrent 113.770004 mA
2020-11-10T15:50:41.697Z,1605023441.697 [Reporter](INFO): ThrusterServo.component_avgCurrent 132.119995 mA
2020-11-10T15:50:44.076Z,1605023444.076 [Reporter](INFO): ThrusterServo.component_avgCurrent 124.779999 mA
2020-11-10T15:50:46.496Z,1605023446.496 [Reporter](INFO): ThrusterServo.component_avgCurrent 146.800003 mA
2020-11-10T15:50:48.904Z,1605023448.904 [Reporter](INFO): ThrusterServo.component_avgCurrent 143.130005 mA
2020-11-10T15:50:51.351Z,1605023451.351 [Reporter](INFO): ThrusterServo.component_avgCurrent 124.779999 mA
2020-11-10T15:50:53.792Z,1605023453.792 [Reporter](INFO): ThrusterServo.component_avgCurrent 121.110001 mA
2020-11-10T15:50:54.528Z,1605023454.528 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2020-11-10T15:50:54.528Z,1605023454.528 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-11-10T15:50:54.587Z,1605023454.587 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-11-10T15:50:54.983Z,1605023454.983 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-11-10T15:50:54.983Z,1605023454.983 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2020-11-10T15:50:56.212Z,1605023456.212 [Reporter](INFO): ThrusterServo.component_avgCurrent 135.790009 mA
2020-11-10T15:50:58.640Z,1605023458.640 [Reporter](INFO): ThrusterServo.component_avgCurrent 150.470001 mA
2020-11-10T15:51:01.032Z,1605023461.032 [Reporter](INFO): ThrusterServo.component_avgCurrent 143.130005 mA
2020-11-10T15:51:03.448Z,1605023463.448 [Reporter](INFO): ThrusterServo.component_avgCurrent 150.470001 mA
2020-11-10T15:51:05.897Z,1605023465.897 [Reporter](INFO): ThrusterServo.component_avgCurrent 139.460007 mA
2020-11-10T15:51:08.328Z,1605023468.328 [Reporter](INFO): ThrusterServo.component_avgCurrent 143.130005 mA
2020-11-10T15:51:10.724Z,1605023470.724 [Reporter](INFO): ThrusterServo.component_avgCurrent 135.790009 mA
2020-11-10T15:51:13.160Z,1605023473.160 [Reporter](INFO): ThrusterServo.component_avgCurrent 121.110001 mA
2020-11-10T15:51:15.672Z,1605023475.672 [Reporter](INFO): ThrusterServo.component_avgCurrent 143.130005 mA
2020-11-10T15:51:18.124Z,1605023478.124 [Reporter](INFO): ThrusterServo.component_avgCurrent 216.529999 mA
2020-11-10T15:51:20.550Z,1605023480.550 [Reporter](INFO): ThrusterServo.component_avgCurrent 227.540009 mA
2020-11-10T15:51:22.920Z,1605023482.920 [Reporter](INFO): ThrusterServo.component_avgCurrent 245.889999 mA
2020-11-10T15:51:25.376Z,1605023485.376 [Reporter](INFO): ThrusterServo.component_avgCurrent 157.809998 mA
2020-11-10T15:51:27.735Z,1605023487.735 [Reporter](INFO): ThrusterServo.component_avgCurrent 117.440002 mA
2020-11-10T15:51:30.178Z,1605023490.178 [Reporter](INFO): ThrusterServo.component_avgCurrent 121.110001 mA
2020-11-10T15:51:32.624Z,1605023492.624 [Reporter](INFO): ThrusterServo.component_avgCurrent 139.460007 mA
2020-11-10T15:51:35.032Z,1605023495.032 [Reporter](INFO): ThrusterServo.component_avgCurrent 154.139999 mA
2020-11-10T15:51:37.464Z,1605023497.464 [Reporter](INFO): ThrusterServo.component_avgCurrent 143.130005 mA
2020-11-10T15:51:42.312Z,1605023502.312 [Reporter](INFO): ThrusterServo.component_avgCurrent 139.460007 mA
2020-11-10T15:51:44.109Z,1605023504.109 [CommandLine](IMPORTANT): got command maintain clear
2020-11-10T15:51:44.348Z,1605023504.348 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,NAL9602,RDI_Pathfinder,DAT,BPC1,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2020-11-10T15:51:45.223Z,1605023505.223 [Reporter](INFO): ThrusterServo.component_avgCurrent 0.000000 mA
2020-11-10T15:52:04.080Z,1605023524.080 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-10T15:52:04.080Z,1605023524.080 [Default:CheckIn:C.Wait] Stopped
2020-11-10T15:52:04.080Z,1605023524.080 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-10T15:52:04.080Z,1605023524.080 [Default:CheckIn:D] Running Loop=1
2020-11-10T15:52:04.474Z,1605023524.474 [Default:CheckIn:D] Stopped
2020-11-10T15:52:04.474Z,1605023524.474 [Default:CheckIn:E] Running Loop=1
2020-11-10T15:52:04.865Z,1605023524.865 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.427458 min 
2020-11-10T15:52:04.865Z,1605023524.865 [Default:CheckIn:E] Stopped
2020-11-10T15:52:04.865Z,1605023524.865 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-10T15:52:04.865Z,1605023524.865 [Default:CheckIn] Stopped
2020-11-10T15:52:04.865Z,1605023524.865 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-10T15:52:04.866Z,1605023524.866 [Default:CheckIn](INFO): Running loop #3
2020-11-10T15:52:04.866Z,1605023524.866 [Default:CheckIn] Running Loop=3
2020-11-10T15:52:04.866Z,1605023524.866 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-10T15:52:04.866Z,1605023524.866 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-10T15:53:55.559Z,1605023635.559 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2020-11-10T15:53:55.560Z,1605023635.560 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-11-10T15:53:55.569Z,1605023635.569 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-11-10T15:53:56.002Z,1605023636.002 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-11-10T15:53:56.002Z,1605023636.002 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2020-11-10T15:56:56.554Z,1605023816.554 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2020-11-10T15:56:56.555Z,1605023816.555 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-11-10T15:56:56.564Z,1605023816.564 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-11-10T15:56:56.981Z,1605023816.981 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-11-10T15:56:56.981Z,1605023816.981 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2020-11-10T15:57:05.044Z,1605023825.044 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-11-10T15:52:04.9Z
2020-11-10T15:57:05.044Z,1605023825.044 [Default:CheckIn:Read_GPS] Stopped
2020-11-10T15:57:05.044Z,1605023825.044 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-10T15:57:12.174Z,1605023832.174 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201110T152638/Courier0010.lzma
2020-11-10T15:57:13.177Z,1605023833.177 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Courier0010.lzma.bak
2020-11-10T15:57:13.177Z,1605023833.177 [DataOverHttps](INFO): SBD MOMSN=13164665
2020-11-10T15:57:28.855Z,1605023848.855 [DataOverHttps](INFO): Sending 282 bytes from file Logs/20201110T152638/Express0011.lzma
2020-11-10T15:57:29.857Z,1605023849.857 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Express0011.lzma.bak
2020-11-10T15:57:29.857Z,1605023849.857 [DataOverHttps](INFO): SBD MOMSN=13164675
2020-11-10T15:57:30.929Z,1605023850.929 [Default:CheckIn:Read_Iridium] Stopped
2020-11-10T15:57:30.929Z,1605023850.929 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-10T15:57:30.929Z,1605023850.929 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-10T15:58:54.938Z,1605023934.938 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-11-10T15:58:54.938Z,1605023934.938 [NAL9602] Data Fault, FailCount= 3
2020-11-10T15:58:54.938Z,1605023934.938 [NAL9602](ERROR): Data Fault
2020-11-10T15:58:54.959Z,1605023934.959 [CBIT](ERROR): Data Fault in component: NAL9602
2020-11-10T15:58:55.341Z,1605023935.341 [NAL9602](INFO): Powering down
2020-11-10T15:58:56.206Z,1605023936.206 [CBIT](INFO): Clearing failed state for component NAL9602
2020-11-10T15:58:56.206Z,1605023936.206 [NAL9602] No Fault, FailCount= 3
2020-11-10T15:59:25.640Z,1605023965.640 [NAL9602](INFO): Powering up NAL9602
2020-11-10T15:59:36.548Z,1605023976.548 [NAL9602](INFO): NAL9602 initialized
2020-11-10T15:59:57.599Z,1605023997.599 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2020-11-10T15:59:57.599Z,1605023997.599 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-11-10T15:59:57.657Z,1605023997.657 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-11-10T15:59:58.005Z,1605023998.005 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-11-10T15:59:58.005Z,1605023998.005 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2020-11-10T16:02:31.584Z,1605024151.584 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-10T16:02:31.584Z,1605024151.584 [Default:CheckIn:C.Wait] Stopped
2020-11-10T16:02:31.584Z,1605024151.584 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-10T16:02:31.585Z,1605024151.585 [Default:CheckIn:D] Running Loop=1
2020-11-10T16:02:31.957Z,1605024151.957 [Default:CheckIn:D] Stopped
2020-11-10T16:02:31.957Z,1605024151.957 [Default:CheckIn:E] Running Loop=1
2020-11-10T16:02:32.372Z,1605024152.372 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.885514 min 
2020-11-10T16:02:32.372Z,1605024152.372 [Default:CheckIn:E] Stopped
2020-11-10T16:02:32.373Z,1605024152.373 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-10T16:02:32.373Z,1605024152.373 [Default:CheckIn] Stopped
2020-11-10T16:02:32.373Z,1605024152.373 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-10T16:02:32.373Z,1605024152.373 [Default:CheckIn](INFO): Running loop #4
2020-11-10T16:02:32.373Z,1605024152.373 [Default:CheckIn] Running Loop=4
2020-11-10T16:02:32.373Z,1605024152.373 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-10T16:02:32.373Z,1605024152.373 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-10T16:02:58.706Z,1605024178.706 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2020-11-10T16:02:58.706Z,1605024178.706 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-11-10T16:02:58.716Z,1605024178.716 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-11-10T16:02:59.038Z,1605024179.038 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-11-10T16:02:59.038Z,1605024179.038 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2020-11-10T16:05:59.612Z,1605024359.612 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2020-11-10T16:05:59.612Z,1605024359.612 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-11-10T16:05:59.622Z,1605024359.622 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-11-10T16:06:00.032Z,1605024360.032 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-11-10T16:06:00.032Z,1605024360.032 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2020-11-10T16:06:29.513Z,1605024389.513 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-11-10T16:07:32.558Z,1605024452.558 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-11-10T16:02:32.4Z
2020-11-10T16:07:32.558Z,1605024452.558 [Default:CheckIn:Read_GPS] Stopped
2020-11-10T16:07:32.558Z,1605024452.558 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-10T16:07:39.970Z,1605024459.970 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201110T152638/Courier0013.lzma
2020-11-10T16:07:40.973Z,1605024460.973 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Courier0013.lzma.bak
2020-11-10T16:07:40.973Z,1605024460.973 [DataOverHttps](INFO): SBD MOMSN=13164787
2020-11-10T16:07:56.791Z,1605024476.791 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20201110T152638/Express0014.lzma
2020-11-10T16:07:57.793Z,1605024477.793 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Express0014.lzma.bak
2020-11-10T16:07:57.793Z,1605024477.793 [DataOverHttps](INFO): SBD MOMSN=13164789
2020-11-10T16:07:58.847Z,1605024478.847 [Default:CheckIn:Read_Iridium] Stopped
2020-11-10T16:07:58.847Z,1605024478.847 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-10T16:07:58.847Z,1605024478.847 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-10T16:09:00.638Z,1605024540.638 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2020-11-10T16:09:00.639Z,1605024540.639 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-11-10T16:09:00.648Z,1605024540.648 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-11-10T16:09:01.060Z,1605024541.060 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-11-10T16:09:01.060Z,1605024541.060 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2020-11-10T16:09:38.202Z,1605024578.202 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-11-10T16:09:38.202Z,1605024578.202 [NAL9602] Data Fault, FailCount= 4
2020-11-10T16:09:38.202Z,1605024578.202 [NAL9602](ERROR): Data Fault
2020-11-10T16:09:38.219Z,1605024578.219 [CBIT](ERROR): Data Fault in component: NAL9602
2020-11-10T16:09:38.596Z,1605024578.596 [NAL9602](INFO): Powering down
2020-11-10T16:09:39.430Z,1605024579.430 [CBIT](INFO): Clearing failed state for component NAL9602
2020-11-10T16:09:39.430Z,1605024579.430 [NAL9602] No Fault, FailCount= 4
2020-11-10T16:10:08.906Z,1605024608.906 [NAL9602](INFO): Powering up NAL9602
2020-11-10T16:10:19.809Z,1605024619.809 [NAL9602](INFO): NAL9602 initialized
2020-11-10T16:12:01.661Z,1605024721.661 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2020-11-10T16:12:01.661Z,1605024721.661 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-11-10T16:12:01.670Z,1605024721.670 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-11-10T16:12:02.056Z,1605024722.056 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-11-10T16:12:02.056Z,1605024722.056 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2020-11-10T16:12:59.423Z,1605024779.423 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-10T16:12:59.424Z,1605024779.424 [Default:CheckIn:C.Wait] Stopped
2020-11-10T16:12:59.424Z,1605024779.424 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-10T16:12:59.424Z,1605024779.424 [Default:CheckIn:D] Running Loop=1
2020-11-10T16:12:59.809Z,1605024779.809 [Default:CheckIn:D] Stopped
2020-11-10T16:12:59.809Z,1605024779.809 [Default:CheckIn:E] Running Loop=1
2020-11-10T16:13:00.255Z,1605024780.255 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.349715 min 
2020-11-10T16:13:00.255Z,1605024780.255 [Default:CheckIn:E] Stopped
2020-11-10T16:13:00.255Z,1605024780.255 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-10T16:13:00.255Z,1605024780.255 [Default:CheckIn] Stopped
2020-11-10T16:13:00.256Z,1605024780.256 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-10T16:13:00.256Z,1605024780.256 [Default:CheckIn](INFO): Running loop #5
2020-11-10T16:13:00.256Z,1605024780.256 [Default:CheckIn] Running Loop=5
2020-11-10T16:13:00.256Z,1605024780.256 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-10T16:13:00.256Z,1605024780.256 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-10T16:15:02.665Z,1605024902.665 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2020-11-10T16:15:02.665Z,1605024902.665 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-11-10T16:15:02.679Z,1605024902.679 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-11-10T16:15:03.061Z,1605024903.061 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-11-10T16:15:03.061Z,1605024903.061 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2020-11-10T16:18:00.392Z,1605025080.392 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-11-10T16:13:00.3Z
2020-11-10T16:18:00.392Z,1605025080.392 [Default:CheckIn:Read_GPS] Stopped
2020-11-10T16:18:00.392Z,1605025080.392 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-10T16:18:03.622Z,1605025083.622 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17
2020-11-10T16:18:03.622Z,1605025083.622 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-11-10T16:18:03.656Z,1605025083.656 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-11-10T16:18:04.065Z,1605025084.065 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-11-10T16:18:04.066Z,1605025084.066 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17
2020-11-10T16:18:07.998Z,1605025087.998 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201110T152638/Courier0016.lzma
2020-11-10T16:18:09.001Z,1605025089.001 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Courier0016.lzma.bak
2020-11-10T16:18:09.001Z,1605025089.001 [DataOverHttps](INFO): SBD MOMSN=13164898
2020-11-10T16:18:24.787Z,1605025104.787 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20201110T152638/Express0017.lzma
2020-11-10T16:18:25.789Z,1605025105.789 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Express0017.lzma.bak
2020-11-10T16:18:25.789Z,1605025105.789 [DataOverHttps](INFO): SBD MOMSN=13164900
2020-11-10T16:18:27.049Z,1605025107.049 [Default:CheckIn:Read_Iridium] Stopped
2020-11-10T16:18:27.049Z,1605025107.049 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-10T16:18:27.049Z,1605025107.049 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-10T16:20:22.228Z,1605025222.228 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-11-10T16:20:22.228Z,1605025222.228 [NAL9602] Data Fault, FailCount= 5
2020-11-10T16:20:22.228Z,1605025222.228 [NAL9602](ERROR): Data Fault
2020-11-10T16:20:22.255Z,1605025222.255 [CBIT](ERROR): Data Fault in component: NAL9602
2020-11-10T16:20:22.256Z,1605025222.256 [CBIT](CRITICAL): Data Fault in component: NAL9602
2020-11-10T16:20:22.612Z,1605025222.612 [NAL9602](INFO): Powering down
2020-11-10T16:20:22.660Z,1605025222.660 [CommandLine](FAULT): Scheduling is paused
2020-11-10T16:20:22.661Z,1605025222.661 [CBIT](INFO): Critical error at 20201110T162022
2020-11-10T16:20:22.661Z,1605025222.661 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2020-11-10T16:21:04.639Z,1605025264.639 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18
2020-11-10T16:21:04.639Z,1605025264.639 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-11-10T16:21:04.649Z,1605025264.649 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-11-10T16:21:05.068Z,1605025265.068 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-11-10T16:21:05.068Z,1605025265.068 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18
2020-11-10T16:22:22.234Z,1605025342.234 [CBIT](INFO): Clearing failed state for component NAL9602
2020-11-10T16:22:22.234Z,1605025342.234 [NAL9602] No Fault, FailCount= 5
2020-11-10T16:22:22.605Z,1605025342.605 [NAL9602](INFO): Powering up NAL9602
2020-11-10T16:22:33.512Z,1605025353.512 [NAL9602](INFO): NAL9602 initialized
2020-11-10T16:23:27.657Z,1605025407.657 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-10T16:23:27.657Z,1605025407.657 [Default:CheckIn:C.Wait] Stopped
2020-11-10T16:23:27.657Z,1605025407.657 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-10T16:23:27.657Z,1605025407.657 [Default:CheckIn:D] Running Loop=1
2020-11-10T16:23:28.092Z,1605025408.092 [Default:CheckIn:D] Stopped
2020-11-10T16:23:28.092Z,1605025408.092 [Default:CheckIn:E] Running Loop=1
2020-11-10T16:23:28.465Z,1605025408.465 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.821090 min 
2020-11-10T16:23:28.465Z,1605025408.465 [Default:CheckIn:E] Stopped
2020-11-10T16:23:28.465Z,1605025408.465 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-10T16:23:28.465Z,1605025408.465 [Default:CheckIn] Stopped
2020-11-10T16:23:28.465Z,1605025408.465 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-10T16:23:28.465Z,1605025408.465 [Default:CheckIn](INFO): Running loop #6
2020-11-10T16:23:28.466Z,1605025408.466 [Default:CheckIn] Running Loop=6
2020-11-10T16:23:28.466Z,1605025408.466 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-10T16:23:28.466Z,1605025408.466 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-10T16:24:05.659Z,1605025445.659 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19
2020-11-10T16:24:05.659Z,1605025445.659 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-11-10T16:24:05.669Z,1605025445.669 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-11-10T16:24:06.072Z,1605025446.072 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-11-10T16:24:06.072Z,1605025446.072 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19
2020-11-10T16:27:06.630Z,1605025626.630 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 20
2020-11-10T16:27:06.630Z,1605025626.630 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-11-10T16:27:06.639Z,1605025626.639 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-11-10T16:27:07.053Z,1605025627.053 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-11-10T16:27:07.053Z,1605025627.053 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 20
2020-11-10T16:28:28.648Z,1605025708.648 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-11-10T16:23:28.5Z
2020-11-10T16:28:28.648Z,1605025708.648 [Default:CheckIn:Read_GPS] Stopped
2020-11-10T16:28:28.648Z,1605025708.648 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-10T16:28:35.515Z,1605025715.515 [DataOverHttps](INFO): Sending 105 bytes from file Logs/20201110T152638/Courier0019.lzma
2020-11-10T16:28:36.517Z,1605025716.517 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Courier0019.lzma.bak
2020-11-10T16:28:36.517Z,1605025716.517 [DataOverHttps](INFO): SBD MOMSN=13165006
2020-11-10T16:28:52.375Z,1605025732.375 [DataOverHttps](INFO): Sending 174 bytes from file Logs/20201110T152638/Express0020.lzma
2020-11-10T16:28:53.377Z,1605025733.377 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Express0020.lzma.bak
2020-11-10T16:28:53.377Z,1605025733.377 [DataOverHttps](INFO): SBD MOMSN=13165009
2020-11-10T16:28:54.510Z,1605025734.510 [Default:CheckIn:Read_Iridium] Stopped
2020-11-10T16:28:54.510Z,1605025734.510 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-10T16:28:54.510Z,1605025734.510 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-10T16:28:55.306Z,1605025735.306 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-11-10T16:30:07.635Z,1605025807.635 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 21
2020-11-10T16:30:07.635Z,1605025807.635 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-11-10T16:30:07.650Z,1605025807.650 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-11-10T16:30:08.049Z,1605025808.049 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-11-10T16:30:08.049Z,1605025808.049 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 21
2020-11-10T16:32:35.478Z,1605025955.478 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-11-10T16:32:35.478Z,1605025955.478 [NAL9602] Data Fault, FailCount= 1
2020-11-10T16:32:35.478Z,1605025955.478 [NAL9602](ERROR): Data Fault
2020-11-10T16:32:35.506Z,1605025955.506 [CBIT](ERROR): Data Fault in component: NAL9602
2020-11-10T16:32:35.872Z,1605025955.872 [NAL9602](INFO): Powering down
2020-11-10T16:32:36.717Z,1605025956.717 [CBIT](INFO): Clearing failed state for component NAL9602
2020-11-10T16:32:36.717Z,1605025956.717 [NAL9602] No Fault, FailCount= 1
2020-11-10T16:33:06.180Z,1605025986.180 [NAL9602](INFO): Powering up NAL9602
2020-11-10T16:33:08.610Z,1605025988.610 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 22
2020-11-10T16:33:08.610Z,1605025988.610 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-11-10T16:33:08.621Z,1605025988.621 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-11-10T16:33:09.024Z,1605025989.024 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-11-10T16:33:09.024Z,1605025989.024 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 22
2020-11-10T16:33:17.085Z,1605025997.085 [NAL9602](INFO): NAL9602 initialized
2020-11-10T16:33:55.087Z,1605026035.087 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-10T16:33:55.087Z,1605026035.087 [Default:CheckIn:C.Wait] Stopped
2020-11-10T16:33:55.087Z,1605026035.087 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-10T16:33:55.087Z,1605026035.087 [Default:CheckIn:D] Running Loop=1
2020-11-10T16:33:55.500Z,1605026035.500 [Default:CheckIn:D] Stopped
2020-11-10T16:33:55.500Z,1605026035.500 [Default:CheckIn:E] Running Loop=1
2020-11-10T16:33:55.877Z,1605026035.877 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 63.277893 min 
2020-11-10T16:33:55.877Z,1605026035.877 [Default:CheckIn:E] Stopped
2020-11-10T16:33:55.878Z,1605026035.878 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-10T16:33:55.878Z,1605026035.878 [Default:CheckIn] Stopped
2020-11-10T16:33:55.878Z,1605026035.878 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-10T16:33:55.878Z,1605026035.878 [Default:CheckIn](INFO): Running loop #7
2020-11-10T16:33:55.878Z,1605026035.878 [Default:CheckIn] Running Loop=7
2020-11-10T16:33:55.878Z,1605026035.878 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-10T16:33:55.878Z,1605026035.878 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-10T16:36:09.642Z,1605026169.642 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 23
2020-11-10T16:36:09.642Z,1605026169.642 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-11-10T16:36:09.652Z,1605026169.652 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-11-10T16:36:10.050Z,1605026170.050 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-11-10T16:36:10.050Z,1605026170.050 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 23
2020-11-10T16:36:51.323Z,1605026211.323 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-11-10T16:38:56.223Z,1605026336.223 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-11-10T16:33:55.9Z
2020-11-10T16:38:56.223Z,1605026336.223 [Default:CheckIn:Read_GPS] Stopped
2020-11-10T16:38:56.223Z,1605026336.223 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-10T16:39:04.066Z,1605026344.066 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201110T152638/Courier0022.lzma
2020-11-10T16:39:05.069Z,1605026345.069 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Courier0022.lzma.bak
2020-11-10T16:39:05.069Z,1605026345.069 [DataOverHttps](INFO): SBD MOMSN=13165131
2020-11-10T16:39:10.716Z,1605026350.716 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 24
2020-11-10T16:39:10.716Z,1605026350.716 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-11-10T16:39:10.730Z,1605026350.730 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-11-10T16:39:11.129Z,1605026351.129 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-11-10T16:39:11.129Z,1605026351.129 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 24
2020-11-10T16:39:21.119Z,1605026361.119 [DataOverHttps](INFO): Sending 263 bytes from file Logs/20201110T152638/Express0023.lzma
2020-11-10T16:39:22.121Z,1605026362.121 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Express0023.lzma.bak
2020-11-10T16:39:22.121Z,1605026362.121 [DataOverHttps](INFO): SBD MOMSN=13165133
2020-11-10T16:39:23.262Z,1605026363.262 [Default:CheckIn:Read_Iridium] Stopped
2020-11-10T16:39:23.262Z,1605026363.262 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-10T16:39:23.262Z,1605026363.262 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-10T16:42:11.688Z,1605026531.688 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 25
2020-11-10T16:42:11.688Z,1605026531.688 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-11-10T16:42:11.705Z,1605026531.705 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-11-10T16:42:12.113Z,1605026532.113 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-11-10T16:42:12.113Z,1605026532.113 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 25
2020-11-10T16:42:45.333Z,1605026565.333 [BPC1](INFO): Calculating totals. Valid battery stick count: 50. Valid reserve battery stick count: 6.
2020-11-10T16:42:45.340Z,1605026565.340 [BPC1](INFO): Received data from all battery sticks.
2020-11-10T16:43:07.102Z,1605026587.102 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164305.00,A,3648.16333,N,12147.27804,W,0.156,244.99,101120,,,A*7F
2020-11-10T16:43:07.105Z,1605026587.105 [NAL9602](INFO): GPS fix at 20201110T164305: (36.802722, -121.787967)
2020-11-10T16:43:39.422Z,1605026619.422 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-10T16:44:23.885Z,1605026663.885 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-10T16:44:23.885Z,1605026663.885 [Default:CheckIn:C.Wait] Stopped
2020-11-10T16:44:23.885Z,1605026663.885 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-10T16:44:23.885Z,1605026663.885 [Default:CheckIn:D] Running Loop=1
2020-11-10T16:44:24.357Z,1605026664.357 [Default:CheckIn:D] Stopped
2020-11-10T16:44:24.357Z,1605026664.357 [Default:CheckIn:E] Running Loop=1
2020-11-10T16:44:24.719Z,1605026664.719 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 73.758846 min 
2020-11-10T16:44:24.719Z,1605026664.719 [Default:CheckIn:E] Stopped
2020-11-10T16:44:24.719Z,1605026664.719 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-10T16:44:24.719Z,1605026664.719 [Default:CheckIn] Stopped
2020-11-10T16:44:24.720Z,1605026664.720 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-10T16:44:24.720Z,1605026664.720 [Default:CheckIn](INFO): Running loop #8
2020-11-10T16:44:24.720Z,1605026664.720 [Default:CheckIn] Running Loop=8
2020-11-10T16:44:24.720Z,1605026664.720 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-10T16:44:24.720Z,1605026664.720 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-10T16:45:39.833Z,1605026739.833 [NAL9602](INFO): SBD MO Status=2, MOMSN=11128, MT Status=2, MTMSN=0
2020-11-10T16:45:39.834Z,1605026739.834 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-11-10T16:45:52.072Z,1605026752.072 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.783939
2020-11-10T16:46:02.640Z,1605026762.640 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003742
2020-11-10T16:46:04.877Z,1605026764.877 [NAL9602](INFO): SBD MO Status=2, MOMSN=11128, MT Status=2, MTMSN=0
2020-11-10T16:46:04.878Z,1605026764.878 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-11-10T16:46:14.173Z,1605026774.173 [NAL9602](INFO): SBD MO Status=0, MOMSN=11128, MT Status=0, MTMSN=0
2020-11-10T16:46:14.174Z,1605026774.174 [NAL9602](INFO): No messages in MT queue
2020-11-10T16:46:15.386Z,1605026775.386 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164613.00,A,3648.16174,N,12147.24873,W,3.032,182.95,101120,,,A*7A
2020-11-10T16:46:15.399Z,1605026775.399 [NAL9602](INFO): GPS fix at 20201110T164613: (36.802696, -121.787479)
2020-11-10T16:46:15.409Z,1605026775.409 [Default:CheckIn:Read_GPS] Stopped
2020-11-10T16:46:15.409Z,1605026775.409 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-10T16:46:26.467Z,1605026786.467 [DataOverHttps](INFO): Sending 236 bytes from file Logs/20201110T152638/Courier0025.lzma
2020-11-10T16:46:27.469Z,1605026787.469 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Courier0025.lzma.bak
2020-11-10T16:46:27.469Z,1605026787.469 [DataOverHttps](INFO): SBD MOMSN=13165222
2020-11-10T16:46:47.072Z,1605026807.072 [DataOverHttps](INFO): Sending 272 bytes from file Logs/20201110T152638/Express0026.lzma
2020-11-10T16:46:47.424Z,1605026807.424 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-10T16:46:48.073Z,1605026808.073 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Express0026.lzma.bak
2020-11-10T16:46:48.073Z,1605026808.073 [DataOverHttps](INFO): SBD MOMSN=13165236
2020-11-10T16:46:49.469Z,1605026809.469 [Default:CheckIn:Read_Iridium] Stopped
2020-11-10T16:46:49.469Z,1605026809.469 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-10T16:46:49.470Z,1605026809.470 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-10T16:50:34.156Z,1605027034.156 [DataOverHttps](IMPORTANT): SBD MTMSN=20201110T165033
2020-11-10T16:50:42.095Z,1605027042.095 [DataOverHttps](INFO): Received command:Ibit
2020-11-10T16:50:42.128Z,1605027042.128 [CommandLine](IMPORTANT): got command ibit
2020-11-10T16:50:42.241Z,1605027042.241 [IBIT](IMPORTANT): Beginning Initiated BIT
2020-11-10T16:50:42.241Z,1605027042.241 [IBIT](IMPORTANT): Beginning control surface checks.
2020-11-10T16:50:42.244Z,1605027042.244 [CBIT](IMPORTANT): Beginning ground fault scan
2020-11-10T16:50:43.840Z,1605027043.840 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165042.00,A,3648.14147,N,12147.20891,W,0.039,29.72,101120,,,A*42
2020-11-10T16:50:43.842Z,1605027043.842 [NAL9602](INFO): GPS fix at 20201110T165042: (36.802358, -121.786815)
2020-11-10T16:50:53.158Z,1605027053.158 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.001984
CHAN A1 (24V): 0.013205
CHAN A2 (12V): -0.001655
CHAN A3 (5V): -0.000129
CHAN B0 (3.3V): 0.000809
CHAN B1 (3.15aV): 0.000578
CHAN B2 (3.15bV): 0.000593
CHAN B3 (GND): 0.002027
OPEN: 0.005394
Full Scale Calc: 4.765 mA, -1.589 mA
2020-11-10T16:51:28.735Z,1605027088.735 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 1
Latitude: 36.802357 Longitude: -121.786812
2020-11-10T16:51:29.104Z,1605027089.104 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.770000
2020-11-10T16:51:29.104Z,1605027089.104 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2020-11-10T16:51:29.104Z,1605027089.104 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2020-11-10T16:51:29.528Z,1605027089.528 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2020-11-10T16:51:29.529Z,1605027089.529 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2020-11-10T16:51:29.529Z,1605027089.529 [IBIT](IMPORTANT): Pressure:7.116151 PSI
2020-11-10T16:51:29.529Z,1605027089.529 [IBIT](IMPORTANT): Humidity:12.318398 %
2020-11-10T16:51:29.940Z,1605027089.940 [IBIT](IMPORTANT): Vehicle Pitch:-0.631987 degrees
2020-11-10T16:51:29.941Z,1605027089.941 [IBIT](IMPORTANT): Vehicle  Roll:-2.943065 degrees
2020-11-10T16:51:29.941Z,1605027089.941 [IBIT](IMPORTANT): Vehicle  Heading:202.925598 degrees
2020-11-10T16:51:30.312Z,1605027090.312 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2020-11-10T16:51:30.312Z,1605027090.312 [IBIT](IMPORTANT): buoyancyNeutral: 203.794022 cc
2020-11-10T16:51:30.312Z,1605027090.312 [IBIT](IMPORTANT): massDefault: 0.917031 cm
2020-11-10T16:51:30.312Z,1605027090.312 [IBIT](IMPORTANT): stopDepth: 215.000000 m
2020-11-10T16:51:30.313Z,1605027090.313 [IBIT](IMPORTANT): abortDepth: 255.000000 m
2020-11-10T16:51:30.313Z,1605027090.313 [IBIT](IMPORTANT): IBIT PASSED
2020-11-10T16:51:50.132Z,1605027110.132 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-10T16:51:50.132Z,1605027110.132 [Default:CheckIn:C.Wait] Stopped
2020-11-10T16:51:50.132Z,1605027110.132 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-10T16:51:50.132Z,1605027110.132 [Default:CheckIn:D] Running Loop=1
2020-11-10T16:51:50.558Z,1605027110.558 [Default:CheckIn:D] Stopped
2020-11-10T16:51:50.558Z,1605027110.558 [Default:CheckIn:E] Running Loop=1
2020-11-10T16:51:50.924Z,1605027110.924 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 81.195532 min 
2020-11-10T16:51:50.924Z,1605027110.924 [Default:CheckIn:E] Stopped
2020-11-10T16:51:50.924Z,1605027110.924 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-10T16:51:50.924Z,1605027110.924 [Default:CheckIn] Stopped
2020-11-10T16:51:50.924Z,1605027110.924 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-10T16:51:50.924Z,1605027110.924 [Default:CheckIn](INFO): Running loop #9
2020-11-10T16:51:50.924Z,1605027110.924 [Default:CheckIn] Running Loop=9
2020-11-10T16:51:50.924Z,1605027110.924 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-10T16:51:50.924Z,1605027110.924 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-10T16:52:01.810Z,1605027121.810 [NAL9602](INFO): SBD MO Status=0, MOMSN=11129, MT Status=0, MTMSN=0
2020-11-10T16:52:01.810Z,1605027121.810 [NAL9602](INFO): No messages in MT queue
2020-11-10T16:52:01.810Z,1605027121.810 [NAL9602](INFO): Failure count cleared after critical for NAL9602
2020-11-10T16:52:03.026Z,1605027123.026 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165201.00,A,3648.14103,N,12147.21018,W,0.097,29.72,101120,,,A*4B
2020-11-10T16:52:03.028Z,1605027123.028 [NAL9602](INFO): GPS fix at 20201110T165201: (36.802351, -121.786836)
2020-11-10T16:52:03.039Z,1605027123.039 [Default:CheckIn:Read_GPS] Stopped
2020-11-10T16:52:03.039Z,1605027123.039 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-10T16:52:10.619Z,1605027130.619 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201110T152638/Courier0028.lzma
2020-11-10T16:52:11.621Z,1605027131.621 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Courier0028.lzma.bak
2020-11-10T16:52:11.621Z,1605027131.621 [DataOverHttps](INFO): SBD MOMSN=13165343
2020-11-10T16:52:32.240Z,1605027152.240 [DataOverHttps](INFO): Sending 810 bytes from file Logs/20201110T152638/Express0029.lzma
2020-11-10T16:52:33.241Z,1605027153.241 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Express0029.lzma.bak
2020-11-10T16:52:33.242Z,1605027153.242 [DataOverHttps](INFO): SBD MOMSN=13165371
2020-11-10T16:52:35.416Z,1605027155.416 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-10T16:52:52.362Z,1605027172.362 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20201110T152638/Express0032.lzma
2020-11-10T16:52:53.365Z,1605027173.365 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Express0032.lzma.bak
2020-11-10T16:52:53.365Z,1605027173.365 [DataOverHttps](INFO): SBD MOMSN=13165398
2020-11-10T16:52:54.485Z,1605027174.485 [Default:CheckIn:Read_Iridium] Stopped
2020-11-10T16:52:54.485Z,1605027174.485 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-10T16:52:54.485Z,1605027174.485 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-10T16:57:55.122Z,1605027475.122 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-10T16:57:55.122Z,1605027475.122 [Default:CheckIn:C.Wait] Stopped
2020-11-10T16:57:55.122Z,1605027475.122 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-10T16:57:55.122Z,1605027475.122 [Default:CheckIn:D] Running Loop=1
2020-11-10T16:57:55.548Z,1605027475.548 [Default:CheckIn:D] Stopped
2020-11-10T16:57:55.548Z,1605027475.548 [Default:CheckIn:E] Running Loop=1
2020-11-10T16:57:55.923Z,1605027475.923 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 87.278695 min 
2020-11-10T16:57:55.923Z,1605027475.923 [Default:CheckIn:E] Stopped
2020-11-10T16:57:55.923Z,1605027475.923 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-10T16:57:55.923Z,1605027475.923 [Default:CheckIn] Stopped
2020-11-10T16:57:55.923Z,1605027475.923 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-10T16:57:55.924Z,1605027475.924 [Default:CheckIn](INFO): Running loop #10
2020-11-10T16:57:55.924Z,1605027475.924 [Default:CheckIn] Running Loop=10
2020-11-10T16:57:55.924Z,1605027475.924 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-10T16:57:55.924Z,1605027475.924 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-10T16:57:57.933Z,1605027477.933 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165757.00,A,3648.14170,N,12147.20731,W,0.078,29.72,101120,,,A*45
2020-11-10T16:57:57.935Z,1605027477.935 [NAL9602](INFO): GPS fix at 20201110T165757: (36.802362, -121.786789)
2020-11-10T16:57:57.955Z,1605027477.955 [Default:CheckIn:Read_GPS] Stopped
2020-11-10T16:57:57.955Z,1605027477.955 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-10T16:58:05.720Z,1605027485.720 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201110T152638/Courier0034.lzma
2020-11-10T16:58:06.713Z,1605027486.713 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Courier0034.lzma.bak
2020-11-10T16:58:06.713Z,1605027486.713 [DataOverHttps](INFO): SBD MOMSN=13165454
2020-11-10T16:58:21.806Z,1605027501.806 [NAL9602](INFO): SBD MO Status=0, MOMSN=11130, MT Status=0, MTMSN=0
2020-11-10T16:58:21.806Z,1605027501.806 [NAL9602](INFO): No messages in MT queue
2020-11-10T16:58:25.991Z,1605027505.991 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20201110T152638/Express0035.lzma
2020-11-10T16:58:26.993Z,1605027506.993 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Express0035.lzma.bak
2020-11-10T16:58:26.993Z,1605027506.993 [DataOverHttps](INFO): SBD MOMSN=13165457
2020-11-10T16:58:28.341Z,1605027508.341 [Default:CheckIn:Read_Iridium] Stopped
2020-11-10T16:58:28.341Z,1605027508.341 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-10T16:58:28.341Z,1605027508.341 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-10T16:58:52.604Z,1605027532.604 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-10T17:00:43.366Z,1605027643.366 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-11-10T17:03:29.021Z,1605027809.021 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-10T17:03:29.022Z,1605027809.022 [Default:CheckIn:C.Wait] Stopped
2020-11-10T17:03:29.022Z,1605027809.022 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-10T17:03:29.022Z,1605027809.022 [Default:CheckIn:D] Running Loop=1
2020-11-10T17:03:29.428Z,1605027809.428 [Default:CheckIn:D] Stopped
2020-11-10T17:03:29.428Z,1605027809.428 [Default:CheckIn:E] Running Loop=1
2020-11-10T17:03:29.848Z,1605027809.848 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 92.843359 min 
2020-11-10T17:03:29.848Z,1605027809.848 [Default:CheckIn:E] Stopped
2020-11-10T17:03:29.848Z,1605027809.848 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-10T17:03:29.848Z,1605027809.848 [Default:CheckIn] Stopped
2020-11-10T17:03:29.848Z,1605027809.848 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-10T17:03:29.848Z,1605027809.848 [Default:CheckIn](INFO): Running loop #11
2020-11-10T17:03:29.848Z,1605027809.848 [Default:CheckIn] Running Loop=11
2020-11-10T17:03:29.848Z,1605027809.848 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-10T17:03:29.849Z,1605027809.849 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-10T17:03:31.838Z,1605027811.838 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170331.00,A,3648.14095,N,12147.20843,W,0.039,29.72,101120,,,D*45
2020-11-10T17:03:31.841Z,1605027811.841 [NAL9602](INFO): GPS fix at 20201110T170331: (36.802349, -121.786807)
2020-11-10T17:03:31.852Z,1605027811.852 [Default:CheckIn:Read_GPS] Stopped
2020-11-10T17:03:31.852Z,1605027811.852 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-10T17:03:41.066Z,1605027821.066 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201110T152638/Courier0037.lzma
2020-11-10T17:03:42.069Z,1605027822.069 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Courier0037.lzma.bak
2020-11-10T17:03:42.069Z,1605027822.069 [DataOverHttps](INFO): SBD MOMSN=13165517
2020-11-10T17:03:51.650Z,1605027831.650 [NAL9602](INFO): SBD MO Status=2, MOMSN=11131, MT Status=2, MTMSN=0
2020-11-10T17:03:51.650Z,1605027831.650 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-11-10T17:03:58.167Z,1605027838.167 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20201110T152638/Express0038.lzma
2020-11-10T17:03:59.169Z,1605027839.169 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Express0038.lzma.bak
2020-11-10T17:03:59.169Z,1605027839.169 [DataOverHttps](INFO): SBD MOMSN=13165527
2020-11-10T17:04:00.562Z,1605027840.562 [Default:CheckIn:Read_Iridium] Stopped
2020-11-10T17:04:00.563Z,1605027840.563 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-10T17:04:00.563Z,1605027840.563 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-10T17:04:10.250Z,1605027850.250 [NAL9602](INFO): SBD MO Status=2, MOMSN=11131, MT Status=2, MTMSN=0
2020-11-10T17:04:10.250Z,1605027850.250 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-11-10T17:04:33.274Z,1605027873.274 [NAL9602](INFO): SBD MO Status=0, MOMSN=11131, MT Status=0, MTMSN=0
2020-11-10T17:04:33.274Z,1605027873.274 [NAL9602](INFO): No messages in MT queue
2020-11-10T17:05:03.972Z,1605027903.972 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-10T17:09:01.227Z,1605028141.227 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-10T17:09:01.227Z,1605028141.227 [Default:CheckIn:C.Wait] Stopped
2020-11-10T17:09:01.227Z,1605028141.227 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-10T17:09:01.227Z,1605028141.227 [Default:CheckIn:D] Running Loop=1
2020-11-10T17:09:01.645Z,1605028141.645 [Default:CheckIn:D] Stopped
2020-11-10T17:09:01.646Z,1605028141.646 [Default:CheckIn:E] Running Loop=1
2020-11-10T17:09:02.022Z,1605028142.022 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 98.380322 min 
2020-11-10T17:09:02.022Z,1605028142.022 [Default:CheckIn:E] Stopped
2020-11-10T17:09:02.023Z,1605028142.023 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-10T17:09:02.023Z,1605028142.023 [Default:CheckIn] Stopped
2020-11-10T17:09:02.023Z,1605028142.023 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-10T17:09:02.023Z,1605028142.023 [Default:CheckIn](INFO): Running loop #12
2020-11-10T17:09:02.023Z,1605028142.023 [Default:CheckIn] Running Loop=12
2020-11-10T17:09:02.023Z,1605028142.023 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-10T17:09:02.023Z,1605028142.023 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-10T17:09:04.030Z,1605028144.030 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170903.00,A,3648.14126,N,12147.20819,W,0.019,29.72,101120,,,D*4A
2020-11-10T17:09:04.032Z,1605028144.032 [NAL9602](INFO): GPS fix at 20201110T170903: (36.802354, -121.786803)
2020-11-10T17:09:04.082Z,1605028144.082 [Default:CheckIn:Read_GPS] Stopped
2020-11-10T17:09:04.082Z,1605028144.082 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-10T17:09:11.602Z,1605028151.602 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201110T152638/Courier0040.lzma
2020-11-10T17:09:12.605Z,1605028152.605 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Courier0040.lzma.bak
2020-11-10T17:09:12.605Z,1605028152.605 [DataOverHttps](INFO): SBD MOMSN=13165587
2020-11-10T17:09:28.839Z,1605028168.839 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20201110T152638/Express0041.lzma
2020-11-10T17:09:29.841Z,1605028169.841 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Express0041.lzma.bak
2020-11-10T17:09:29.841Z,1605028169.841 [DataOverHttps](INFO): SBD MOMSN=13165611
2020-11-10T17:09:31.149Z,1605028171.149 [Default:CheckIn:Read_Iridium] Stopped
2020-11-10T17:09:31.149Z,1605028171.149 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-10T17:09:31.149Z,1605028171.149 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-10T17:10:05.449Z,1605028205.449 [NAL9602](INFO): SBD MO Status=0, MOMSN=11132, MT Status=0, MTMSN=0
2020-11-10T17:10:05.450Z,1605028205.450 [NAL9602](INFO): No messages in MT queue
2020-11-10T17:10:36.158Z,1605028236.158 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-10T17:14:31.818Z,1605028471.818 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-10T17:14:31.818Z,1605028471.818 [Default:CheckIn:C.Wait] Stopped
2020-11-10T17:14:31.818Z,1605028471.818 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-10T17:14:31.818Z,1605028471.818 [Default:CheckIn:D] Running Loop=1
2020-11-10T17:14:32.206Z,1605028472.206 [Default:CheckIn:D] Stopped
2020-11-10T17:14:32.206Z,1605028472.206 [Default:CheckIn:E] Running Loop=1
2020-11-10T17:14:32.627Z,1605028472.627 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 103.889665 min 
2020-11-10T17:14:32.628Z,1605028472.628 [Default:CheckIn:E] Stopped
2020-11-10T17:14:32.628Z,1605028472.628 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-10T17:14:32.628Z,1605028472.628 [Default:CheckIn] Stopped
2020-11-10T17:14:32.628Z,1605028472.628 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-10T17:14:32.628Z,1605028472.628 [Default:CheckIn](INFO): Running loop #13
2020-11-10T17:14:32.628Z,1605028472.628 [Default:CheckIn] Running Loop=13
2020-11-10T17:14:32.628Z,1605028472.628 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-10T17:14:32.628Z,1605028472.628 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-10T17:14:34.610Z,1605028474.610 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171433.00,A,3648.13989,N,12147.20747,W,0.078,108.02,101120,,,D*79
2020-11-10T17:14:34.616Z,1605028474.616 [NAL9602](INFO): GPS fix at 20201110T171433: (36.802332, -121.786791)
2020-11-10T17:14:34.631Z,1605028474.631 [Default:CheckIn:Read_GPS] Stopped
2020-11-10T17:14:34.631Z,1605028474.631 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-10T17:14:42.678Z,1605028482.678 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201110T152638/Courier0043.lzma
2020-11-10T17:14:43.681Z,1605028483.681 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Courier0043.lzma.bak
2020-11-10T17:14:43.681Z,1605028483.681 [DataOverHttps](INFO): SBD MOMSN=13165674
2020-11-10T17:15:00.014Z,1605028500.014 [DataOverHttps](INFO): Sending 142 bytes from file Logs/20201110T152638/Express0044.lzma
2020-11-10T17:15:01.017Z,1605028501.017 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Express0044.lzma.bak
2020-11-10T17:15:01.017Z,1605028501.017 [DataOverHttps](INFO): SBD MOMSN=13165680
2020-11-10T17:15:02.092Z,1605028502.092 [Default:CheckIn:Read_Iridium] Stopped
2020-11-10T17:15:02.092Z,1605028502.092 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-10T17:15:02.092Z,1605028502.092 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-10T17:15:29.553Z,1605028529.553 [NAL9602](INFO): SBD MO Status=0, MOMSN=11133, MT Status=0, MTMSN=0
2020-11-10T17:15:29.554Z,1605028529.554 [NAL9602](INFO): No messages in MT queue
2020-11-10T17:16:00.256Z,1605028560.256 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-10T17:20:02.745Z,1605028802.745 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-10T17:20:02.745Z,1605028802.745 [Default:CheckIn:C.Wait] Stopped
2020-11-10T17:20:02.745Z,1605028802.745 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-10T17:20:02.745Z,1605028802.745 [Default:CheckIn:D] Running Loop=1
2020-11-10T17:20:03.156Z,1605028803.156 [Default:CheckIn:D] Stopped
2020-11-10T17:20:03.156Z,1605028803.156 [Default:CheckIn:E] Running Loop=1
2020-11-10T17:20:03.544Z,1605028803.544 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 109.405485 min 
2020-11-10T17:20:03.544Z,1605028803.544 [Default:CheckIn:E] Stopped
2020-11-10T17:20:03.544Z,1605028803.544 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-10T17:20:03.544Z,1605028803.544 [Default:CheckIn] Stopped
2020-11-10T17:20:03.544Z,1605028803.544 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-10T17:20:03.545Z,1605028803.545 [Default:CheckIn](INFO): Running loop #14
2020-11-10T17:20:03.545Z,1605028803.545 [Default:CheckIn] Running Loop=14
2020-11-10T17:20:03.545Z,1605028803.545 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-10T17:20:03.545Z,1605028803.545 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-10T17:20:05.558Z,1605028805.558 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172004.00,A,3648.14243,N,12147.20820,W,0.039,108.02,101120,,,D*7B
2020-11-10T17:20:05.572Z,1605028805.572 [NAL9602](INFO): GPS fix at 20201110T172004: (36.802374, -121.786803)
2020-11-10T17:20:05.593Z,1605028805.593 [Default:CheckIn:Read_GPS] Stopped
2020-11-10T17:20:05.593Z,1605028805.593 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-10T17:20:06.376Z,1605028806.376 [Default:CheckIn:Read_Iridium] Stopped
2020-11-10T17:20:06.376Z,1605028806.376 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-10T17:20:06.376Z,1605028806.376 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-10T17:20:18.074Z,1605028818.074 [NAL9602](INFO): SBD MO Status=0, MOMSN=11134, MT Status=0, MTMSN=0
2020-11-10T17:20:18.074Z,1605028818.074 [NAL9602](INFO): No messages in MT queue
2020-11-10T17:20:48.800Z,1605028848.800 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-10T17:25:07.071Z,1605029107.071 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-10T17:25:07.071Z,1605029107.071 [Default:CheckIn:C.Wait] Stopped
2020-11-10T17:25:07.071Z,1605029107.071 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-10T17:25:07.071Z,1605029107.071 [Default:CheckIn:D] Running Loop=1
2020-11-10T17:25:07.486Z,1605029107.486 [Default:CheckIn:D] Stopped
2020-11-10T17:25:07.486Z,1605029107.486 [Default:CheckIn:E] Running Loop=1
2020-11-10T17:25:07.891Z,1605029107.891 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 114.477669 min 
2020-11-10T17:25:07.891Z,1605029107.891 [Default:CheckIn:E] Stopped
2020-11-10T17:25:07.891Z,1605029107.891 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-10T17:25:07.891Z,1605029107.891 [Default:CheckIn] Stopped
2020-11-10T17:25:07.891Z,1605029107.891 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-10T17:25:07.892Z,1605029107.892 [Default:CheckIn](INFO): Running loop #15
2020-11-10T17:25:07.892Z,1605029107.892 [Default:CheckIn] Running Loop=15
2020-11-10T17:25:07.892Z,1605029107.892 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-10T17:25:07.892Z,1605029107.892 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-10T17:25:09.885Z,1605029109.885 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172509.00,A,3648.13468,N,12147.20572,W,0.039,108.02,101120,,,D*71
2020-11-10T17:25:09.887Z,1605029109.887 [NAL9602](INFO): GPS fix at 20201110T172509: (36.802245, -121.786762)
2020-11-10T17:25:09.913Z,1605029109.913 [Default:CheckIn:Read_GPS] Stopped
2020-11-10T17:25:09.913Z,1605029109.913 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-10T17:25:17.195Z,1605029117.195 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20201110T152638/Courier0046.lzma
2020-11-10T17:25:18.199Z,1605029118.199 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Courier0046.lzma.bak
2020-11-10T17:25:18.200Z,1605029118.200 [DataOverHttps](INFO): SBD MOMSN=13165798
2020-11-10T17:25:25.270Z,1605029125.270 [NAL9602](INFO): SBD MO Status=2, MOMSN=11135, MT Status=2, MTMSN=0
2020-11-10T17:25:25.270Z,1605029125.270 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-11-10T17:25:34.494Z,1605029134.494 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201110T152638/Courier0049.lzma
2020-11-10T17:25:35.497Z,1605029135.497 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Courier0049.lzma.bak
2020-11-10T17:25:35.497Z,1605029135.497 [DataOverHttps](INFO): SBD MOMSN=13165802
2020-11-10T17:25:42.630Z,1605029142.630 [NAL9602](INFO): SBD MO Status=2, MOMSN=11135, MT Status=2, MTMSN=0
2020-11-10T17:25:42.630Z,1605029142.630 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-11-10T17:25:51.830Z,1605029151.830 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20201110T152638/Express0047.lzma
2020-11-10T17:25:52.833Z,1605029152.833 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Express0047.lzma.bak
2020-11-10T17:25:52.834Z,1605029152.834 [DataOverHttps](INFO): SBD MOMSN=13165808
2020-11-10T17:26:05.662Z,1605029165.662 [NAL9602](INFO): SBD MO Status=2, MOMSN=11135, MT Status=2, MTMSN=0
2020-11-10T17:26:05.662Z,1605029165.662 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-11-10T17:26:09.115Z,1605029169.115 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20201110T152638/Express0050.lzma
2020-11-10T17:26:10.117Z,1605029170.117 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Express0050.lzma.bak
2020-11-10T17:26:10.117Z,1605029170.117 [DataOverHttps](INFO): SBD MOMSN=13165814
2020-11-10T17:26:11.337Z,1605029171.337 [Default:CheckIn:Read_Iridium] Stopped
2020-11-10T17:26:11.337Z,1605029171.337 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-10T17:26:11.337Z,1605029171.337 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-10T17:26:26.266Z,1605029186.266 [NAL9602](INFO): SBD MO Status=0, MOMSN=11135, MT Status=0, MTMSN=0
2020-11-10T17:26:26.266Z,1605029186.266 [NAL9602](INFO): No messages in MT queue
2020-11-10T17:26:56.970Z,1605029216.970 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-10T17:31:11.970Z,1605029471.970 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-10T17:31:11.970Z,1605029471.970 [Default:CheckIn:C.Wait] Stopped
2020-11-10T17:31:11.970Z,1605029471.970 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-10T17:31:11.970Z,1605029471.970 [Default:CheckIn:D] Running Loop=1
2020-11-10T17:31:12.386Z,1605029472.386 [Default:CheckIn:D] Stopped
2020-11-10T17:31:12.387Z,1605029472.387 [Default:CheckIn:E] Running Loop=1
2020-11-10T17:31:12.790Z,1605029472.790 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 120.559334 min 
2020-11-10T17:31:12.790Z,1605029472.790 [Default:CheckIn:E] Stopped
2020-11-10T17:31:12.790Z,1605029472.790 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-10T17:31:12.791Z,1605029472.791 [Default:CheckIn] Stopped
2020-11-10T17:31:12.791Z,1605029472.791 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-10T17:31:12.791Z,1605029472.791 [Default:CheckIn](INFO): Running loop #16
2020-11-10T17:31:12.791Z,1605029472.791 [Default:CheckIn] Running Loop=16
2020-11-10T17:31:12.791Z,1605029472.791 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-10T17:31:12.791Z,1605029472.791 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-10T17:31:14.786Z,1605029474.786 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173114.00,A,3648.14490,N,12147.20766,W,0.058,108.02,101120,,,D*78
2020-11-10T17:31:14.788Z,1605029474.788 [NAL9602](INFO): GPS fix at 20201110T173114: (36.802415, -121.786794)
2020-11-10T17:31:14.803Z,1605029474.803 [Default:CheckIn:Read_GPS] Stopped
2020-11-10T17:31:14.803Z,1605029474.803 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-10T17:31:22.670Z,1605029482.670 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201110T152638/Courier0052.lzma
2020-11-10T17:31:23.673Z,1605029483.673 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Courier0052.lzma.bak
2020-11-10T17:31:23.673Z,1605029483.673 [DataOverHttps](INFO): SBD MOMSN=13165880
2020-11-10T17:31:29.330Z,1605029489.330 [NAL9602](INFO): SBD MO Status=0, MOMSN=11136, MT Status=0, MTMSN=0
2020-11-10T17:31:29.330Z,1605029489.330 [NAL9602](INFO): No messages in MT queue
2020-11-10T17:31:39.959Z,1605029499.959 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20201110T152638/Express0053.lzma
2020-11-10T17:31:40.961Z,1605029500.961 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Express0053.lzma.bak
2020-11-10T17:31:40.961Z,1605029500.961 [DataOverHttps](INFO): SBD MOMSN=13165886
2020-11-10T17:31:42.277Z,1605029502.277 [Default:CheckIn:Read_Iridium] Stopped
2020-11-10T17:31:42.277Z,1605029502.277 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-10T17:31:42.277Z,1605029502.277 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-10T17:32:00.036Z,1605029520.036 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-10T17:36:43.029Z,1605029803.029 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-10T17:36:43.029Z,1605029803.029 [Default:CheckIn:C.Wait] Stopped
2020-11-10T17:36:43.029Z,1605029803.029 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-10T17:36:43.029Z,1605029803.029 [Default:CheckIn:D] Running Loop=1
2020-11-10T17:36:43.426Z,1605029803.426 [Default:CheckIn:D] Stopped
2020-11-10T17:36:43.426Z,1605029803.426 [Default:CheckIn:E] Running Loop=1
2020-11-10T17:36:43.875Z,1605029803.875 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 126.076660 min 
2020-11-10T17:36:43.876Z,1605029803.876 [Default:CheckIn:E] Stopped
2020-11-10T17:36:43.876Z,1605029803.876 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-10T17:36:43.876Z,1605029803.876 [Default:CheckIn] Stopped
2020-11-10T17:36:43.876Z,1605029803.876 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-10T17:36:43.876Z,1605029803.876 [Default:CheckIn](INFO): Running loop #17
2020-11-10T17:36:43.876Z,1605029803.876 [Default:CheckIn] Running Loop=17
2020-11-10T17:36:43.876Z,1605029803.876 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-10T17:36:43.876Z,1605029803.876 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-10T17:36:45.834Z,1605029805.834 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173645.00,A,3648.14368,N,12147.20701,W,0.039,108.02,101120,,,D*7D
2020-11-10T17:36:45.836Z,1605029805.836 [NAL9602](INFO): GPS fix at 20201110T173645: (36.802395, -121.786783)
2020-11-10T17:36:45.847Z,1605029805.847 [Default:CheckIn:Read_GPS] Stopped
2020-11-10T17:36:45.847Z,1605029805.847 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-10T17:36:53.638Z,1605029813.638 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201110T152638/Courier0055.lzma
2020-11-10T17:36:54.641Z,1605029814.641 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Courier0055.lzma.bak
2020-11-10T17:36:54.641Z,1605029814.641 [DataOverHttps](INFO): SBD MOMSN=13165944
2020-11-10T17:37:01.601Z,1605029821.601 [NAL9602](INFO): SBD MO Status=0, MOMSN=11137, MT Status=0, MTMSN=0
2020-11-10T17:37:01.602Z,1605029821.602 [NAL9602](INFO): No messages in MT queue
2020-11-10T17:37:10.794Z,1605029830.794 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20201110T152638/Express0056.lzma
2020-11-10T17:37:11.797Z,1605029831.797 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Express0056.lzma.bak
2020-11-10T17:37:11.797Z,1605029831.797 [DataOverHttps](INFO): SBD MOMSN=13165950
2020-11-10T17:37:12.924Z,1605029832.924 [Default:CheckIn:Read_Iridium] Stopped
2020-11-10T17:37:12.924Z,1605029832.924 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-10T17:37:12.924Z,1605029832.924 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-10T17:37:32.304Z,1605029852.304 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-10T17:42:13.601Z,1605030133.601 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-10T17:42:13.601Z,1605030133.601 [Default:CheckIn:C.Wait] Stopped
2020-11-10T17:42:13.601Z,1605030133.601 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-10T17:42:13.601Z,1605030133.601 [Default:CheckIn:D] Running Loop=1
2020-11-10T17:42:14.007Z,1605030134.007 [Default:CheckIn:D] Stopped
2020-11-10T17:42:14.007Z,1605030134.007 [Default:CheckIn:E] Running Loop=1
2020-11-10T17:42:14.432Z,1605030134.432 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 131.586344 min 
2020-11-10T17:42:14.432Z,1605030134.432 [Default:CheckIn:E] Stopped
2020-11-10T17:42:14.432Z,1605030134.432 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-10T17:42:14.432Z,1605030134.432 [Default:CheckIn] Stopped
2020-11-10T17:42:14.433Z,1605030134.433 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-10T17:42:14.433Z,1605030134.433 [Default:CheckIn](INFO): Running loop #18
2020-11-10T17:42:14.433Z,1605030134.433 [Default:CheckIn] Running Loop=18
2020-11-10T17:42:14.433Z,1605030134.433 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-10T17:42:14.433Z,1605030134.433 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-10T17:42:16.410Z,1605030136.410 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174215.00,A,3648.14077,N,12147.20784,W,0.039,108.02,101120,,,D*7B
2020-11-10T17:42:16.412Z,1605030136.412 [NAL9602](INFO): GPS fix at 20201110T174215: (36.802346, -121.786797)
2020-11-10T17:42:16.424Z,1605030136.424 [Default:CheckIn:Read_GPS] Stopped
2020-11-10T17:42:16.424Z,1605030136.424 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-10T17:42:27.418Z,1605030147.418 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201110T152638/Courier0058.lzma
2020-11-10T17:42:28.421Z,1605030148.421 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Courier0058.lzma.bak
2020-11-10T17:42:28.421Z,1605030148.421 [DataOverHttps](INFO): SBD MOMSN=13166002
2020-11-10T17:42:48.115Z,1605030168.115 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20201110T152638/Express0059.lzma
2020-11-10T17:42:49.117Z,1605030169.117 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Express0059.lzma.bak
2020-11-10T17:42:49.117Z,1605030169.117 [DataOverHttps](INFO): SBD MOMSN=13166008
2020-11-10T17:42:50.481Z,1605030170.481 [NAL9602](INFO): SBD MO Status=2, MOMSN=11138, MT Status=2, MTMSN=0
2020-11-10T17:42:50.482Z,1605030170.482 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-11-10T17:42:50.534Z,1605030170.534 [Default:CheckIn:Read_Iridium] Stopped
2020-11-10T17:42:50.534Z,1605030170.534 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-10T17:42:50.535Z,1605030170.535 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-10T17:43:27.646Z,1605030207.646 [NAL9602](INFO): SBD MO Status=0, MOMSN=11138, MT Status=0, MTMSN=0
2020-11-10T17:43:27.646Z,1605030207.646 [NAL9602](INFO): No messages in MT queue
2020-11-10T17:43:58.352Z,1605030238.352 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-10T17:47:51.151Z,1605030471.151 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-10T17:47:51.151Z,1605030471.151 [Default:CheckIn:C.Wait] Stopped
2020-11-10T17:47:51.151Z,1605030471.151 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-10T17:47:51.151Z,1605030471.151 [Default:CheckIn:D] Running Loop=1
2020-11-10T17:47:51.548Z,1605030471.548 [Default:CheckIn:D] Stopped
2020-11-10T17:47:51.548Z,1605030471.548 [Default:CheckIn:E] Running Loop=1
2020-11-10T17:47:51.947Z,1605030471.947 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 137.212028 min 
2020-11-10T17:47:51.947Z,1605030471.947 [Default:CheckIn:E] Stopped
2020-11-10T17:47:51.948Z,1605030471.948 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-10T17:47:51.948Z,1605030471.948 [Default:CheckIn] Stopped
2020-11-10T17:47:51.948Z,1605030471.948 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-10T17:47:51.948Z,1605030471.948 [Default:CheckIn](INFO): Running loop #19
2020-11-10T17:47:51.948Z,1605030471.948 [Default:CheckIn] Running Loop=19
2020-11-10T17:47:51.948Z,1605030471.948 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-10T17:47:51.948Z,1605030471.948 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-10T17:47:53.954Z,1605030473.954 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174753.00,A,3648.14244,N,12147.20874,W,0.039,108.02,101120,,,D*7E
2020-11-10T17:47:53.957Z,1605030473.957 [NAL9602](INFO): GPS fix at 20201110T174753: (36.802374, -121.786812)
2020-11-10T17:47:53.978Z,1605030473.978 [Default:CheckIn:Read_GPS] Stopped
2020-11-10T17:47:53.978Z,1605030473.978 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-10T17:48:01.745Z,1605030481.745 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201110T152638/Courier0061.lzma
2020-11-10T17:48:02.752Z,1605030482.752 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Courier0061.lzma.bak
2020-11-10T17:48:02.752Z,1605030482.752 [DataOverHttps](INFO): SBD MOMSN=13166076
2020-11-10T17:48:10.118Z,1605030490.118 [NAL9602](INFO): SBD MO Status=0, MOMSN=11139, MT Status=0, MTMSN=0
2020-11-10T17:48:10.118Z,1605030490.118 [NAL9602](INFO): No messages in MT queue
2020-11-10T17:48:20.215Z,1605030500.215 [DataOverHttps](INFO): Sending 138 bytes from file Logs/20201110T152638/Express0062.lzma
2020-11-10T17:48:21.221Z,1605030501.221 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Express0062.lzma.bak
2020-11-10T17:48:21.221Z,1605030501.221 [DataOverHttps](INFO): SBD MOMSN=13166080
2020-11-10T17:48:22.337Z,1605030502.337 [Default:CheckIn:Read_Iridium] Stopped
2020-11-10T17:48:22.337Z,1605030502.337 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-10T17:48:22.337Z,1605030502.337 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-10T17:48:40.900Z,1605030520.900 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-10T17:50:12.641Z,1605030612.641 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-11-10T17:50:12.648Z,1605030612.648 [BPC1](INFO): Received data from all battery sticks.
2020-11-10T17:53:22.897Z,1605030802.897 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-10T17:53:22.897Z,1605030802.897 [Default:CheckIn:C.Wait] Stopped
2020-11-10T17:53:22.897Z,1605030802.897 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-10T17:53:22.897Z,1605030802.897 [Default:CheckIn:D] Running Loop=1
2020-11-10T17:53:23.359Z,1605030803.359 [Default:CheckIn:D] Stopped
2020-11-10T17:53:23.359Z,1605030803.359 [Default:CheckIn:E] Running Loop=1
2020-11-10T17:53:23.717Z,1605030803.717 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 142.742220 min 
2020-11-10T17:53:23.717Z,1605030803.717 [Default:CheckIn:E] Stopped
2020-11-10T17:53:23.717Z,1605030803.717 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-10T17:53:23.717Z,1605030803.717 [Default:CheckIn] Stopped
2020-11-10T17:53:23.717Z,1605030803.717 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-10T17:53:23.717Z,1605030803.717 [Default:CheckIn](INFO): Running loop #20
2020-11-10T17:53:23.717Z,1605030803.717 [Default:CheckIn] Running Loop=20
2020-11-10T17:53:23.717Z,1605030803.717 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-10T17:53:23.718Z,1605030803.718 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-10T17:53:25.742Z,1605030805.742 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175324.00,A,3648.14300,N,12147.20849,W,0.000,0.00,101120,,,D*75
2020-11-10T17:53:25.752Z,1605030805.752 [NAL9602](INFO): GPS fix at 20201110T175324: (36.802383, -121.786808)
2020-11-10T17:53:25.774Z,1605030805.774 [Default:CheckIn:Read_GPS] Stopped
2020-11-10T17:53:25.774Z,1605030805.774 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-10T17:53:33.409Z,1605030813.409 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20201110T152638/Courier0064.lzma
2020-11-10T17:53:34.405Z,1605030814.405 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Courier0064.lzma.bak
2020-11-10T17:53:34.405Z,1605030814.405 [DataOverHttps](INFO): SBD MOMSN=13166144
2020-11-10T17:53:46.770Z,1605030826.770 [NAL9602](INFO): SBD MO Status=0, MOMSN=11140, MT Status=0, MTMSN=0
2020-11-10T17:53:46.770Z,1605030826.770 [NAL9602](INFO): No messages in MT queue
2020-11-10T17:53:50.675Z,1605030830.675 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20201110T152638/Express0065.lzma
2020-11-10T17:53:51.677Z,1605030831.677 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Express0065.lzma.bak
2020-11-10T17:53:51.677Z,1605030831.677 [DataOverHttps](INFO): SBD MOMSN=13166154
2020-11-10T17:53:52.843Z,1605030832.843 [Default:CheckIn:Read_Iridium] Stopped
2020-11-10T17:53:52.843Z,1605030832.843 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-10T17:53:52.843Z,1605030832.843 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-10T17:54:17.465Z,1605030857.465 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-10T17:58:53.527Z,1605031133.527 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-10T17:58:53.527Z,1605031133.527 [Default:CheckIn:C.Wait] Stopped
2020-11-10T17:58:53.527Z,1605031133.527 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-10T17:58:53.527Z,1605031133.527 [Default:CheckIn:D] Running Loop=1
2020-11-10T17:58:53.908Z,1605031133.908 [Default:CheckIn:D] Stopped
2020-11-10T17:58:53.908Z,1605031133.908 [Default:CheckIn:E] Running Loop=1
2020-11-10T17:58:54.325Z,1605031134.325 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 148.251367 min 
2020-11-10T17:58:54.326Z,1605031134.326 [Default:CheckIn:E] Stopped
2020-11-10T17:58:54.326Z,1605031134.326 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-10T17:58:54.326Z,1605031134.326 [Default:CheckIn] Stopped
2020-11-10T17:58:54.326Z,1605031134.326 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-10T17:58:54.326Z,1605031134.326 [Default:CheckIn](INFO): Running loop #21
2020-11-10T17:58:54.326Z,1605031134.326 [Default:CheckIn] Running Loop=21
2020-11-10T17:58:54.326Z,1605031134.326 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-10T17:58:54.326Z,1605031134.326 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-10T17:58:56.334Z,1605031136.334 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175855.00,A,3648.14294,N,12147.21087,W,0.019,0.00,101120,,,D*77
2020-11-10T17:58:56.336Z,1605031136.336 [NAL9602](INFO): GPS fix at 20201110T175855: (36.802382, -121.786848)
2020-11-10T17:58:56.358Z,1605031136.358 [Default:CheckIn:Read_GPS] Stopped
2020-11-10T17:58:56.358Z,1605031136.358 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-10T17:59:12.658Z,1605031152.658 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201110T152638/Courier0067.lzma
2020-11-10T17:59:13.661Z,1605031153.661 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Courier0067.lzma.bak
2020-11-10T17:59:13.661Z,1605031153.661 [DataOverHttps](INFO): SBD MOMSN=13166224
2020-11-10T17:59:14.505Z,1605031154.505 [NAL9602](INFO): SBD MO Status=0, MOMSN=11141, MT Status=0, MTMSN=0
2020-11-10T17:59:14.506Z,1605031154.506 [NAL9602](INFO): No messages in MT queue
2020-11-10T17:59:29.966Z,1605031169.966 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20201110T152638/Express0068.lzma
2020-11-10T17:59:30.969Z,1605031170.969 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Express0068.lzma.bak
2020-11-10T17:59:30.969Z,1605031170.969 [DataOverHttps](INFO): SBD MOMSN=13166227
2020-11-10T17:59:32.335Z,1605031172.335 [Default:CheckIn:Read_Iridium] Stopped
2020-11-10T17:59:32.335Z,1605031172.335 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-10T17:59:32.335Z,1605031172.335 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-10T17:59:45.218Z,1605031185.218 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-10T18:04:32.968Z,1605031472.968 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-10T18:04:32.968Z,1605031472.968 [Default:CheckIn:C.Wait] Stopped
2020-11-10T18:04:32.968Z,1605031472.968 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-10T18:04:32.968Z,1605031472.968 [Default:CheckIn:D] Running Loop=1
2020-11-10T18:04:33.383Z,1605031473.383 [Default:CheckIn:D] Stopped
2020-11-10T18:04:33.383Z,1605031473.383 [Default:CheckIn:E] Running Loop=1
2020-11-10T18:04:33.777Z,1605031473.777 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 153.909277 min 
2020-11-10T18:04:33.777Z,1605031473.777 [Default:CheckIn:E] Stopped
2020-11-10T18:04:33.777Z,1605031473.777 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-10T18:04:33.777Z,1605031473.777 [Default:CheckIn] Stopped
2020-11-10T18:04:33.777Z,1605031473.777 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-10T18:04:33.778Z,1605031473.778 [Default:CheckIn](INFO): Running loop #22
2020-11-10T18:04:33.778Z,1605031473.778 [Default:CheckIn] Running Loop=22
2020-11-10T18:04:33.778Z,1605031473.778 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-10T18:04:33.778Z,1605031473.778 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-10T18:04:35.770Z,1605031475.770 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180435.00,A,3648.14209,N,12147.20857,W,0.058,0.00,101120,,,D*72
2020-11-10T18:04:35.772Z,1605031475.772 [NAL9602](INFO): GPS fix at 20201110T180435: (36.802368, -121.786810)
2020-11-10T18:04:35.792Z,1605031475.792 [Default:CheckIn:Read_GPS] Stopped
2020-11-10T18:04:35.792Z,1605031475.792 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-10T18:04:43.238Z,1605031483.238 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201110T152638/Courier0070.lzma
2020-11-10T18:04:44.236Z,1605031484.236 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Courier0070.lzma.bak
2020-11-10T18:04:44.237Z,1605031484.237 [DataOverHttps](INFO): SBD MOMSN=13166328
2020-11-10T18:04:49.946Z,1605031489.946 [NAL9602](INFO): SBD MO Status=0, MOMSN=11142, MT Status=0, MTMSN=0
2020-11-10T18:04:49.946Z,1605031489.946 [NAL9602](INFO): No messages in MT queue
2020-11-10T18:05:00.535Z,1605031500.535 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20201110T152638/Express0071.lzma
2020-11-10T18:05:01.537Z,1605031501.537 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Express0071.lzma.bak
2020-11-10T18:05:01.537Z,1605031501.537 [DataOverHttps](INFO): SBD MOMSN=13166331
2020-11-10T18:05:02.894Z,1605031502.894 [Default:CheckIn:Read_Iridium] Stopped
2020-11-10T18:05:02.894Z,1605031502.894 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-10T18:05:02.894Z,1605031502.894 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-10T18:05:20.652Z,1605031520.652 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-10T18:10:03.527Z,1605031803.527 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-10T18:10:03.527Z,1605031803.527 [Default:CheckIn:C.Wait] Stopped
2020-11-10T18:10:03.527Z,1605031803.527 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-10T18:10:03.527Z,1605031803.527 [Default:CheckIn:D] Running Loop=1
2020-11-10T18:10:03.928Z,1605031803.928 [Default:CheckIn:D] Stopped
2020-11-10T18:10:03.928Z,1605031803.928 [Default:CheckIn:E] Running Loop=1
2020-11-10T18:10:04.343Z,1605031804.343 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 159.418359 min 
2020-11-10T18:10:04.343Z,1605031804.343 [Default:CheckIn:E] Stopped
2020-11-10T18:10:04.344Z,1605031804.344 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-10T18:10:04.344Z,1605031804.344 [Default:CheckIn] Stopped
2020-11-10T18:10:04.344Z,1605031804.344 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-10T18:10:04.344Z,1605031804.344 [Default:CheckIn](INFO): Running loop #23
2020-11-10T18:10:04.344Z,1605031804.344 [Default:CheckIn] Running Loop=23
2020-11-10T18:10:04.344Z,1605031804.344 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-10T18:10:04.344Z,1605031804.344 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-10T18:10:06.333Z,1605031806.333 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181005.00,A,3648.14055,N,12147.21034,W,0.039,0.00,101120,,,D*74
2020-11-10T18:10:06.335Z,1605031806.335 [NAL9602](INFO): GPS fix at 20201110T181005: (36.802343, -121.786839)
2020-11-10T18:10:06.355Z,1605031806.355 [Default:CheckIn:Read_GPS] Stopped
2020-11-10T18:10:06.355Z,1605031806.355 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-10T18:10:13.970Z,1605031813.970 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201110T152638/Courier0073.lzma
2020-11-10T18:10:14.973Z,1605031814.973 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Courier0073.lzma.bak
2020-11-10T18:10:14.973Z,1605031814.973 [DataOverHttps](INFO): SBD MOMSN=13166346
2020-11-10T18:10:37.671Z,1605031837.671 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20201110T152638/Express0074.lzma
2020-11-10T18:10:38.673Z,1605031838.673 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Express0074.lzma.bak
2020-11-10T18:10:38.673Z,1605031838.673 [DataOverHttps](INFO): SBD MOMSN=13166349
2020-11-10T18:10:40.024Z,1605031840.024 [Default:CheckIn:Read_Iridium] Stopped
2020-11-10T18:10:40.024Z,1605031840.024 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-10T18:10:40.024Z,1605031840.024 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-10T18:10:50.895Z,1605031850.895 [NAL9602](INFO): SBD MO Status=0, MOMSN=11143, MT Status=0, MTMSN=0
2020-11-10T18:10:50.896Z,1605031850.896 [NAL9602](INFO): No messages in MT queue
2020-11-10T18:11:21.600Z,1605031881.600 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-10T18:12:55.394Z,1605031975.394 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-11-10T18:15:40.715Z,1605032140.715 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-10T18:15:40.715Z,1605032140.715 [Default:CheckIn:C.Wait] Stopped
2020-11-10T18:15:40.715Z,1605032140.715 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-10T18:15:40.715Z,1605032140.715 [Default:CheckIn:D] Running Loop=1
2020-11-10T18:15:41.103Z,1605032141.103 [Default:CheckIn:D] Stopped
2020-11-10T18:15:41.103Z,1605032141.103 [Default:CheckIn:E] Running Loop=1
2020-11-10T18:15:41.498Z,1605032141.498 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 165.037956 min 
2020-11-10T18:15:41.498Z,1605032141.498 [Default:CheckIn:E] Stopped
2020-11-10T18:15:41.498Z,1605032141.498 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-10T18:15:41.498Z,1605032141.498 [Default:CheckIn] Stopped
2020-11-10T18:15:41.498Z,1605032141.498 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-10T18:15:41.498Z,1605032141.498 [Default:CheckIn](INFO): Running loop #24
2020-11-10T18:15:41.498Z,1605032141.498 [Default:CheckIn] Running Loop=24
2020-11-10T18:15:41.498Z,1605032141.498 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-10T18:15:41.498Z,1605032141.498 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-10T18:15:43.514Z,1605032143.514 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181542.00,A,3648.14137,N,12147.21075,W,0.039,0.00,101120,,,D*72
2020-11-10T18:15:43.516Z,1605032143.516 [NAL9602](INFO): GPS fix at 20201110T181542: (36.802356, -121.786846)
2020-11-10T18:15:43.537Z,1605032143.537 [Default:CheckIn:Read_GPS] Stopped
2020-11-10T18:15:43.537Z,1605032143.537 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-10T18:15:51.186Z,1605032151.186 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201110T152638/Courier0076.lzma
2020-11-10T18:15:52.190Z,1605032152.190 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Courier0076.lzma.bak
2020-11-10T18:15:52.190Z,1605032152.190 [DataOverHttps](INFO): SBD MOMSN=13166465
2020-11-10T18:15:58.861Z,1605032158.861 [NAL9602](INFO): SBD MO Status=0, MOMSN=11144, MT Status=0, MTMSN=0
2020-11-10T18:15:58.862Z,1605032158.862 [NAL9602](INFO): No messages in MT queue
2020-11-10T18:16:08.442Z,1605032168.442 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20201110T152638/Express0077.lzma
2020-11-10T18:16:09.445Z,1605032169.445 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Express0077.lzma.bak
2020-11-10T18:16:09.445Z,1605032169.445 [DataOverHttps](INFO): SBD MOMSN=13166468
2020-11-10T18:16:10.597Z,1605032170.597 [Default:CheckIn:Read_Iridium] Stopped
2020-11-10T18:16:10.597Z,1605032170.597 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-10T18:16:10.597Z,1605032170.597 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-10T18:16:29.564Z,1605032189.564 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-10T18:21:11.284Z,1605032471.284 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-10T18:21:11.284Z,1605032471.284 [Default:CheckIn:C.Wait] Stopped
2020-11-10T18:21:11.284Z,1605032471.284 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-10T18:21:11.284Z,1605032471.284 [Default:CheckIn:D] Running Loop=1
2020-11-10T18:21:11.634Z,1605032471.634 [Default:CheckIn:D] Stopped
2020-11-10T18:21:11.634Z,1605032471.634 [Default:CheckIn:E] Running Loop=1
2020-11-10T18:21:12.030Z,1605032472.030 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 170.546794 min 
2020-11-10T18:21:12.031Z,1605032472.031 [Default:CheckIn:E] Stopped
2020-11-10T18:21:12.031Z,1605032472.031 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-10T18:21:12.031Z,1605032472.031 [Default:CheckIn] Stopped
2020-11-10T18:21:12.031Z,1605032472.031 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-10T18:21:12.031Z,1605032472.031 [Default:CheckIn](INFO): Running loop #25
2020-11-10T18:21:12.031Z,1605032472.031 [Default:CheckIn] Running Loop=25
2020-11-10T18:21:12.031Z,1605032472.031 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-10T18:21:12.032Z,1605032472.032 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-10T18:21:14.038Z,1605032474.038 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182113.00,A,3648.14050,N,12147.21044,W,0.019,0.00,101120,,,D*71
2020-11-10T18:21:14.040Z,1605032474.040 [NAL9602](INFO): GPS fix at 20201110T182113: (36.802342, -121.786841)
2020-11-10T18:21:14.051Z,1605032474.051 [Default:CheckIn:Read_GPS] Stopped
2020-11-10T18:21:14.051Z,1605032474.051 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-10T18:21:21.590Z,1605032481.590 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201110T152638/Courier0079.lzma
2020-11-10T18:21:22.593Z,1605032482.593 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Courier0079.lzma.bak
2020-11-10T18:21:22.593Z,1605032482.593 [DataOverHttps](INFO): SBD MOMSN=13166479
2020-11-10T18:21:36.258Z,1605032496.258 [NAL9602](INFO): SBD MO Status=0, MOMSN=11145, MT Status=0, MTMSN=0
2020-11-10T18:21:36.258Z,1605032496.258 [NAL9602](INFO): No messages in MT queue
2020-11-10T18:21:38.827Z,1605032498.827 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20201110T152638/Express0080.lzma
2020-11-10T18:21:39.829Z,1605032499.829 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Express0080.lzma.bak
2020-11-10T18:21:39.829Z,1605032499.829 [DataOverHttps](INFO): SBD MOMSN=13166482
2020-11-10T18:21:41.120Z,1605032501.120 [Default:CheckIn:Read_Iridium] Stopped
2020-11-10T18:21:41.120Z,1605032501.120 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-10T18:21:41.120Z,1605032501.120 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-10T18:22:06.964Z,1605032526.964 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-10T18:26:41.839Z,1605032801.839 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-10T18:26:41.839Z,1605032801.839 [Default:CheckIn:C.Wait] Stopped
2020-11-10T18:26:41.839Z,1605032801.839 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-10T18:26:41.839Z,1605032801.839 [Default:CheckIn:D] Running Loop=1
2020-11-10T18:26:42.298Z,1605032802.298 [Default:CheckIn:D] Stopped
2020-11-10T18:26:42.298Z,1605032802.298 [Default:CheckIn:E] Running Loop=1
2020-11-10T18:26:42.652Z,1605032802.652 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 176.057861 min 
2020-11-10T18:26:42.652Z,1605032802.652 [Default:CheckIn:E] Stopped
2020-11-10T18:26:42.652Z,1605032802.652 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-10T18:26:42.652Z,1605032802.652 [Default:CheckIn] Stopped
2020-11-10T18:26:42.652Z,1605032802.652 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-10T18:26:42.652Z,1605032802.652 [Default:CheckIn](INFO): Running loop #26
2020-11-10T18:26:42.652Z,1605032802.652 [Default:CheckIn] Running Loop=26
2020-11-10T18:26:42.652Z,1605032802.652 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-10T18:26:42.652Z,1605032802.652 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-10T18:26:44.652Z,1605032804.652 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182643.00,A,3648.13769,N,12147.20570,W,0.058,322.98,101120,,,D*7D
2020-11-10T18:26:44.654Z,1605032804.654 [NAL9602](INFO): GPS fix at 20201110T182643: (36.802295, -121.786762)
2020-11-10T18:26:44.665Z,1605032804.665 [Default:CheckIn:Read_GPS] Stopped
2020-11-10T18:26:44.665Z,1605032804.665 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-10T18:26:52.666Z,1605032812.666 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201110T152638/Courier0082.lzma
2020-11-10T18:26:53.669Z,1605032813.669 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Courier0082.lzma.bak
2020-11-10T18:26:53.669Z,1605032813.669 [DataOverHttps](INFO): SBD MOMSN=13166596
2020-11-10T18:27:10.025Z,1605032830.025 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20201110T152638/Express0083.lzma
2020-11-10T18:27:11.021Z,1605032831.021 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Express0083.lzma.bak
2020-11-10T18:27:11.021Z,1605032831.021 [DataOverHttps](INFO): SBD MOMSN=13166599
2020-11-10T18:27:11.321Z,1605032831.321 [NAL9602](INFO): SBD MO Status=0, MOMSN=11146, MT Status=0, MTMSN=0
2020-11-10T18:27:11.322Z,1605032831.322 [NAL9602](INFO): No messages in MT queue
2020-11-10T18:27:12.135Z,1605032832.135 [Default:CheckIn:Read_Iridium] Stopped
2020-11-10T18:27:12.135Z,1605032832.135 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-10T18:27:12.135Z,1605032832.135 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-10T18:27:42.020Z,1605032862.020 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-10T18:32:12.836Z,1605033132.836 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-10T18:32:12.836Z,1605033132.836 [Default:CheckIn:C.Wait] Stopped
2020-11-10T18:32:12.836Z,1605033132.836 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-10T18:32:12.836Z,1605033132.836 [Default:CheckIn:D] Running Loop=1
2020-11-10T18:32:13.263Z,1605033133.263 [Default:CheckIn:D] Stopped
2020-11-10T18:32:13.264Z,1605033133.264 [Default:CheckIn:E] Running Loop=1
2020-11-10T18:32:13.640Z,1605033133.640 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 181.573958 min 
2020-11-10T18:32:13.640Z,1605033133.640 [Default:CheckIn:E] Stopped
2020-11-10T18:32:13.640Z,1605033133.640 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-10T18:32:13.640Z,1605033133.640 [Default:CheckIn] Stopped
2020-11-10T18:32:13.640Z,1605033133.640 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-10T18:32:13.640Z,1605033133.640 [Default:CheckIn](INFO): Running loop #27
2020-11-10T18:32:13.641Z,1605033133.641 [Default:CheckIn] Running Loop=27
2020-11-10T18:32:13.641Z,1605033133.641 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-10T18:32:13.641Z,1605033133.641 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-10T18:32:15.646Z,1605033135.646 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183214.00,A,3648.13865,N,12147.20577,W,0.019,322.98,101120,,,D*7B
2020-11-10T18:32:15.648Z,1605033135.648 [NAL9602](INFO): GPS fix at 20201110T183214: (36.802311, -121.786763)
2020-11-10T18:32:15.659Z,1605033135.659 [Default:CheckIn:Read_GPS] Stopped
2020-11-10T18:32:15.659Z,1605033135.659 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-10T18:32:32.338Z,1605033152.338 [NAL9602](INFO): SBD MO Status=0, MOMSN=11147, MT Status=0, MTMSN=0
2020-11-10T18:32:32.338Z,1605033152.338 [NAL9602](INFO): No messages in MT queue
2020-11-10T18:32:44.946Z,1605033164.946 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201110T152638/Courier0085.lzma
2020-11-10T18:32:45.949Z,1605033165.949 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Courier0085.lzma.bak
2020-11-10T18:32:45.949Z,1605033165.949 [DataOverHttps](INFO): SBD MOMSN=13166612
2020-11-10T18:33:02.298Z,1605033182.298 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20201110T152638/Express0086.lzma
2020-11-10T18:33:03.140Z,1605033183.140 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-10T18:33:03.302Z,1605033183.302 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Express0086.lzma.bak
2020-11-10T18:33:03.302Z,1605033183.302 [DataOverHttps](INFO): SBD MOMSN=13166615
2020-11-10T18:33:04.368Z,1605033184.368 [Default:CheckIn:Read_Iridium] Stopped
2020-11-10T18:33:04.368Z,1605033184.368 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-10T18:33:04.369Z,1605033184.369 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-10T18:38:05.015Z,1605033485.015 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-10T18:38:05.015Z,1605033485.015 [Default:CheckIn:C.Wait] Stopped
2020-11-10T18:38:05.015Z,1605033485.015 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-10T18:38:05.015Z,1605033485.015 [Default:CheckIn:D] Running Loop=1
2020-11-10T18:38:05.446Z,1605033485.446 [Default:CheckIn:D] Stopped
2020-11-10T18:38:05.446Z,1605033485.446 [Default:CheckIn:E] Running Loop=1
2020-11-10T18:38:05.811Z,1605033485.811 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 187.443669 min 
2020-11-10T18:38:05.812Z,1605033485.812 [Default:CheckIn:E] Stopped
2020-11-10T18:38:05.812Z,1605033485.812 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-10T18:38:05.812Z,1605033485.812 [Default:CheckIn] Stopped
2020-11-10T18:38:05.812Z,1605033485.812 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-10T18:38:05.812Z,1605033485.812 [Default:CheckIn](INFO): Running loop #28
2020-11-10T18:38:05.812Z,1605033485.812 [Default:CheckIn] Running Loop=28
2020-11-10T18:38:05.812Z,1605033485.812 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-10T18:38:05.812Z,1605033485.812 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-10T18:38:07.818Z,1605033487.818 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183807.00,A,3648.34090,N,12147.11701,W,4.607,5.65,101120,,,D*7C
2020-11-10T18:38:07.820Z,1605033487.820 [NAL9602](INFO): GPS fix at 20201110T183807: (36.805682, -121.785284)
2020-11-10T18:38:07.841Z,1605033487.841 [Default:CheckIn:Read_GPS] Stopped
2020-11-10T18:38:07.841Z,1605033487.841 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-10T18:38:15.322Z,1605033495.322 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201110T152638/Courier0088.lzma
2020-11-10T18:38:16.325Z,1605033496.325 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Courier0088.lzma.bak
2020-11-10T18:38:16.325Z,1605033496.325 [DataOverHttps](INFO): SBD MOMSN=13166724
2020-11-10T18:38:28.489Z,1605033508.489 [NAL9602](INFO): SBD MO Status=0, MOMSN=11148, MT Status=0, MTMSN=0
2020-11-10T18:38:28.489Z,1605033508.489 [NAL9602](INFO): No messages in MT queue
2020-11-10T18:38:38.663Z,1605033518.663 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20201110T152638/Express0089.lzma
2020-11-10T18:38:39.665Z,1605033519.665 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Express0089.lzma.bak
2020-11-10T18:38:39.666Z,1605033519.666 [DataOverHttps](INFO): SBD MOMSN=13166727
2020-11-10T18:38:40.722Z,1605033520.722 [Default:CheckIn:Read_Iridium] Stopped
2020-11-10T18:38:40.722Z,1605033520.722 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-10T18:38:40.722Z,1605033520.722 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-10T18:38:59.252Z,1605033539.252 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-10T18:43:41.393Z,1605033821.393 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-10T18:43:41.393Z,1605033821.393 [Default:CheckIn:C.Wait] Stopped
2020-11-10T18:43:41.393Z,1605033821.393 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-10T18:43:41.393Z,1605033821.393 [Default:CheckIn:D] Running Loop=1
2020-11-10T18:43:41.791Z,1605033821.791 [Default:CheckIn:D] Stopped
2020-11-10T18:43:41.791Z,1605033821.791 [Default:CheckIn:E] Running Loop=1
2020-11-10T18:43:42.181Z,1605033822.181 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 193.049414 min 
2020-11-10T18:43:42.181Z,1605033822.181 [Default:CheckIn:E] Stopped
2020-11-10T18:43:42.181Z,1605033822.181 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-10T18:43:42.181Z,1605033822.181 [Default:CheckIn] Stopped
2020-11-10T18:43:42.181Z,1605033822.181 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-10T18:43:42.182Z,1605033822.182 [Default:CheckIn](INFO): Running loop #29
2020-11-10T18:43:42.182Z,1605033822.182 [Default:CheckIn] Running Loop=29
2020-11-10T18:43:42.182Z,1605033822.182 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-10T18:43:42.182Z,1605033822.182 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-10T18:43:44.262Z,1605033824.262 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184343.00,A,3648.21797,N,12148.21580,W,23.229,252.69,101120,,,D*42
2020-11-10T18:43:44.264Z,1605033824.264 [NAL9602](INFO): GPS fix at 20201110T184343: (36.803633, -121.803597)
2020-11-10T18:43:44.360Z,1605033824.360 [Default:CheckIn:Read_GPS] Stopped
2020-11-10T18:43:44.361Z,1605033824.361 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-10T18:43:51.891Z,1605033831.891 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201110T152638/Courier0091.lzma
2020-11-10T18:43:52.893Z,1605033832.893 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Courier0091.lzma.bak
2020-11-10T18:43:52.893Z,1605033832.893 [DataOverHttps](INFO): SBD MOMSN=13166738
2020-11-10T18:44:08.839Z,1605033848.839 [NAL9602](INFO): SBD MO Status=2, MOMSN=11149, MT Status=2, MTMSN=0
2020-11-10T18:44:08.840Z,1605033848.840 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-11-10T18:44:09.007Z,1605033849.007 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20201110T152638/Express0092.lzma
2020-11-10T18:44:10.010Z,1605033850.010 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Express0092.lzma.bak
2020-11-10T18:44:10.011Z,1605033850.011 [DataOverHttps](INFO): SBD MOMSN=13166741
2020-11-10T18:44:11.281Z,1605033851.281 [Default:CheckIn:Read_Iridium] Stopped
2020-11-10T18:44:11.281Z,1605033851.281 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-10T18:44:11.281Z,1605033851.281 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-10T18:44:18.128Z,1605033858.128 [NAL9602](INFO): SBD MO Status=0, MOMSN=11149, MT Status=0, MTMSN=0
2020-11-10T18:44:18.128Z,1605033858.128 [NAL9602](INFO): No messages in MT queue
2020-11-10T18:44:48.832Z,1605033888.832 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-10T18:49:11.922Z,1605034151.922 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-10T18:49:11.922Z,1605034151.922 [Default:CheckIn:C.Wait] Stopped
2020-11-10T18:49:11.922Z,1605034151.922 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-10T18:49:11.922Z,1605034151.922 [Default:CheckIn:D] Running Loop=1
2020-11-10T18:49:12.330Z,1605034152.330 [Default:CheckIn:D] Stopped
2020-11-10T18:49:12.330Z,1605034152.330 [Default:CheckIn:E] Running Loop=1
2020-11-10T18:49:12.784Z,1605034152.784 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 198.558398 min 
2020-11-10T18:49:12.784Z,1605034152.784 [Default:CheckIn:E] Stopped
2020-11-10T18:49:12.784Z,1605034152.784 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-10T18:49:12.784Z,1605034152.784 [Default:CheckIn] Stopped
2020-11-10T18:49:12.785Z,1605034152.785 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-10T18:49:12.785Z,1605034152.785 [Default:CheckIn](INFO): Running loop #30
2020-11-10T18:49:12.785Z,1605034152.785 [Default:CheckIn] Running Loop=30
2020-11-10T18:49:12.785Z,1605034152.785 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-10T18:49:12.785Z,1605034152.785 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-10T18:49:14.742Z,1605034154.742 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184914.00,A,3647.85693,N,12150.69995,W,19.283,266.81,101120,,,D*4B
2020-11-10T18:49:14.754Z,1605034154.754 [NAL9602](INFO): GPS fix at 20201110T184914: (36.797615, -121.844999)
2020-11-10T18:49:14.765Z,1605034154.765 [Default:CheckIn:Read_GPS] Stopped
2020-11-10T18:49:14.765Z,1605034154.765 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-10T18:49:22.330Z,1605034162.330 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201110T152638/Courier0094.lzma
2020-11-10T18:49:24.337Z,1605034164.337 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Courier0094.lzma.bak
2020-11-10T18:49:24.337Z,1605034164.337 [DataOverHttps](INFO): SBD MOMSN=13166849
2020-11-10T18:49:41.563Z,1605034181.563 [DataOverHttps](INFO): Sending 147 bytes from file Logs/20201110T152638/Express0095.lzma
2020-11-10T18:49:42.565Z,1605034182.565 [DataOverHttps](INFO): Moved sent file to Logs/20201110T152638/Express0095.lzma.bak
2020-11-10T18:49:42.565Z,1605034182.565 [DataOverHttps](INFO): SBD MOMSN=13166854
2020-11-10T18:49:43.425Z,1605034183.425 [NAL9602](INFO): SBD MO Status=0, MOMSN=11150, MT Status=0, MTMSN=0
2020-11-10T18:49:43.426Z,1605034183.426 [NAL9602](INFO): No messages in MT queue
2020-11-10T18:49:43.839Z,1605034183.839 [Default:CheckIn:Read_Iridium] Stopped
2020-11-10T18:49:43.839Z,1605034183.839 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-10T18:49:43.839Z,1605034183.839 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-10T18:50:14.125Z,1605034214.125 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-10T18:54:42.628Z,1605034482.628 [DataOverHttps](IMPORTANT): SBD MTMSN=20201110T185439
2020-11-10T18:54:44.625Z,1605034484.625 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-10T18:54:44.625Z,1605034484.625 [Default:CheckIn:C.Wait] Stopped
2020-11-10T18:54:44.625Z,1605034484.625 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-10T18:54:44.625Z,1605034484.625 [Default:CheckIn:D] Running Loop=1
2020-11-10T18:54:44.950Z,1605034484.950 [Default:CheckIn:D] Stopped
2020-11-10T18:54:44.950Z,1605034484.950 [Default:CheckIn:E] Running Loop=1
2020-11-10T18:54:45.387Z,1605034485.387 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 204.102051 min 
2020-11-10T18:54:45.388Z,1605034485.388 [Default:CheckIn:E] Stopped
2020-11-10T18:54:45.388Z,1605034485.388 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-10T18:54:45.388Z,1605034485.388 [Default:CheckIn] Stopped
2020-11-10T18:54:45.388Z,1605034485.388 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-10T18:54:45.388Z,1605034485.388 [Default:CheckIn](INFO): Running loop #31
2020-11-10T18:54:45.388Z,1605034485.388 [Default:CheckIn] Running Loop=31
2020-11-10T18:54:45.388Z,1605034485.388 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-10T18:54:45.388Z,1605034485.388 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-10T18:54:47.368Z,1605034487.368 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185446.00,A,3647.84297,N,12150.78790,W,0.350,303.26,101120,,,D*72
2020-11-10T18:54:47.370Z,1605034487.370 [NAL9602](INFO): GPS fix at 20201110T185446: (36.797383, -121.846465)
2020-11-10T18:54:47.391Z,1605034487.391 [Default:CheckIn:Read_GPS] Stopped
2020-11-10T18:54:47.391Z,1605034487.391 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-10T18:54:55.398Z,1605034495.398 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201110T152638/Courier0097.lzma
2020-11-10T18:54:55.401Z,1605034495.401 [DataOverHttps](INFO): Received command:restart logs