2020-12-01T17:52:50.680Z,1606845170.680 [Supervisor](DEBUG): Initializing supervisor.
2020-12-01T17:52:50.683Z,1606845170.683 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-12-01T17:52:50.683Z,1606845170.683 [SyncHandler](INFO): Protected caller Thread ID is 6656
2020-12-01T17:52:50.684Z,1606845170.684 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-12-01T17:52:50.685Z,1606845170.685 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-12-01T17:52:50.685Z,1606845170.685 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 6657
2020-12-01T17:52:50.688Z,1606845170.688 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-12-01T17:52:50.705Z,1606845170.705 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-12-01T17:52:50.706Z,1606845170.706 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-12-01T17:52:50.706Z,1606845170.706 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 6658
2020-12-01T17:52:50.706Z,1606845170.706 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-12-01T17:52:50.707Z,1606845170.707 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-12-01T17:52:50.708Z,1606845170.708 [logger ThreadHandler](INFO): Protected caller Thread ID is 6659
2020-12-01T17:52:50.710Z,1606845170.710 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-12-01T17:52:50.710Z,1606845170.710 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-12-01T17:52:50.712Z,1606845170.712 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-12-01T17:52:50.804Z,1606845170.804 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-12-01T17:52:50.805Z,1606845170.805 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-12-01T17:52:51.367Z,1606845171.367 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-12-01T17:52:51.368Z,1606845171.368 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2020-12-01T17:52:51.561Z,1606845171.561 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2020-12-01T17:52:51.562Z,1606845171.562 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-12-01T17:52:51.658Z,1606845171.658 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-12-01T17:52:51.658Z,1606845171.658 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-12-01T17:52:51.760Z,1606845171.760 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-12-01T17:52:51.760Z,1606845171.760 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-12-01T17:52:51.839Z,1606845171.839 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-12-01T17:52:51.974Z,1606845171.974 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-12-01T17:52:51.975Z,1606845171.975 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-12-01T17:52:52.252Z,1606845172.252 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-12-01T17:52:52.252Z,1606845172.252 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-12-01T17:52:52.735Z,1606845172.735 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-12-01T17:52:52.736Z,1606845172.736 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-12-01T17:52:52.875Z,1606845172.875 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-12-01T17:52:52.876Z,1606845172.876 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-12-01T17:52:53.057Z,1606845173.057 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-12-01T17:52:53.057Z,1606845173.057 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-12-01T17:52:53.574Z,1606845173.574 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-12-01T17:52:53.575Z,1606845173.575 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-12-01T17:52:53.885Z,1606845173.885 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-12-01T17:52:53.886Z,1606845173.886 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-12-01T17:52:54.276Z,1606845174.276 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-12-01T17:52:54.277Z,1606845174.277 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-12-01T17:52:54.845Z,1606845174.845 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-12-01T17:52:54.846Z,1606845174.846 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-12-01T17:52:55.156Z,1606845175.156 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-12-01T17:52:55.158Z,1606845175.158 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2020-12-01T17:52:55.159Z,1606845175.159 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2020-12-01T17:52:55.351Z,1606845175.351 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2020-12-01T17:52:55.649Z,1606845175.649 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2020-12-01T17:52:55.753Z,1606845175.753 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2020-12-01T17:52:55.837Z,1606845175.837 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2020-12-01T17:52:55.929Z,1606845175.929 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2020-12-01T17:52:56.114Z,1606845176.114 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2020-12-01T17:52:56.361Z,1606845176.361 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-12-01T17:52:56.362Z,1606845176.362 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2020-12-01T17:52:56.453Z,1606845176.453 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2020-12-01T17:52:56.548Z,1606845176.548 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2020-12-01T17:52:56.685Z,1606845176.685 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2020-12-01T17:52:56.781Z,1606845176.781 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-12-01T17:52:56.793Z,1606845176.793 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2020-12-01T17:52:56.869Z,1606845176.869 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2020-12-01T17:52:56.869Z,1606845176.869 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-12-01T17:52:56.915Z,1606845176.915 [VerticalControl](DEBUG): Construct VerticalControl.
2020-12-01T17:52:56.971Z,1606845176.971 [VerticalControl] Loaded
2020-12-01T17:52:56.971Z,1606845176.971 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-12-01T17:52:56.972Z,1606845176.972 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-12-01T17:52:57.008Z,1606845177.008 [HorizontalControl] Loaded
2020-12-01T17:52:57.008Z,1606845177.008 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-12-01T17:52:57.009Z,1606845177.009 [SpeedControl](DEBUG): Construct SpeedControl.
2020-12-01T17:52:57.011Z,1606845177.011 [SpeedControl] Loaded
2020-12-01T17:52:57.012Z,1606845177.012 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-12-01T17:52:57.012Z,1606845177.012 [LoopControl](DEBUG): Construct LoopControl.
2020-12-01T17:52:57.013Z,1606845177.013 [LoopControl] Loaded
2020-12-01T17:52:57.013Z,1606845177.013 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-12-01T17:52:57.013Z,1606845177.013 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-12-01T17:52:57.014Z,1606845177.014 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-12-01T17:52:57.052Z,1606845177.052 [DepthRateCalculator] Loaded
2020-12-01T17:52:57.053Z,1606845177.053 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-12-01T17:52:57.055Z,1606845177.055 [PitchRateCalculator] Loaded
2020-12-01T17:52:57.055Z,1606845177.055 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-12-01T17:52:57.063Z,1606845177.063 [SpeedCalculator] Loaded
2020-12-01T17:52:57.063Z,1606845177.063 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-12-01T17:52:57.074Z,1606845177.074 [TempGradientCalculator] Loaded
2020-12-01T17:52:57.075Z,1606845177.075 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-12-01T17:52:57.077Z,1606845177.077 [YawRateCalculator] Loaded
2020-12-01T17:52:57.077Z,1606845177.077 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-12-01T17:52:57.091Z,1606845177.091 [ElevatorOffsetCalculator] Loaded
2020-12-01T17:52:57.092Z,1606845177.092 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-12-01T17:52:57.092Z,1606845177.092 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-12-01T17:52:57.093Z,1606845177.093 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-12-01T17:52:57.118Z,1606845177.118 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-12-01T17:52:57.119Z,1606845177.119 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-12-01T17:52:57.201Z,1606845177.201 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-12-01T17:52:57.202Z,1606845177.202 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-12-01T17:52:57.479Z,1606845177.479 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-12-01T17:52:57.479Z,1606845177.479 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-12-01T17:52:57.575Z,1606845177.575 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-12-01T17:52:57.575Z,1606845177.575 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-12-01T17:52:57.894Z,1606845177.894 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-12-01T17:52:57.896Z,1606845177.896 [AHRS_M2](INFO): created writer for : platform_orientation
2020-12-01T17:52:57.898Z,1606845177.898 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-12-01T17:52:57.901Z,1606845177.901 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-12-01T17:52:57.901Z,1606845177.901 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-12-01T17:52:57.904Z,1606845177.904 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-12-01T17:52:57.904Z,1606845177.904 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-12-01T17:52:57.907Z,1606845177.907 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-12-01T17:52:57.943Z,1606845177.943 [AHRS_M2] Loaded
2020-12-01T17:52:57.943Z,1606845177.943 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-12-01T17:52:58.013Z,1606845178.013 [DataOverHttps] Loaded
2020-12-01T17:52:58.013Z,1606845178.013 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-12-01T17:52:58.014Z,1606845178.014 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4090F4E0
2020-12-01T17:52:58.015Z,1606845178.015 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 6741
2020-12-01T17:52:58.031Z,1606845178.031 [Depth_Keller] Loaded
2020-12-01T17:52:58.031Z,1606845178.031 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-12-01T17:52:58.033Z,1606845178.033 [DropWeight] Loaded
2020-12-01T17:52:58.034Z,1606845178.034 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2020-12-01T17:52:58.087Z,1606845178.087 [NAL9602] Loaded
2020-12-01T17:52:58.087Z,1606845178.087 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-12-01T17:52:58.112Z,1606845178.112 [Onboard] Loaded
2020-12-01T17:52:58.112Z,1606845178.112 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2020-12-01T17:52:58.113Z,1606845178.113 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4093F4E0
2020-12-01T17:52:58.114Z,1606845178.114 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 6742
2020-12-01T17:52:58.124Z,1606845178.124 [Radio_Surface] Loaded
2020-12-01T17:52:58.124Z,1606845178.124 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-12-01T17:52:58.125Z,1606845178.125 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4096F4E0
2020-12-01T17:52:58.125Z,1606845178.125 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 6743
2020-12-01T17:52:58.151Z,1606845178.151 [RDI_Pathfinder] Loaded
2020-12-01T17:52:58.152Z,1606845178.152 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2020-12-01T17:52:58.221Z,1606845178.221 [DAT] Loaded
2020-12-01T17:52:58.222Z,1606845178.222 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2020-12-01T17:52:58.991Z,1606845178.991 [BPC1] Loaded
2020-12-01T17:52:58.991Z,1606845178.991 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-12-01T17:52:58.992Z,1606845178.992 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-12-01T17:52:58.992Z,1606845178.992 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-12-01T17:52:59.285Z,1606845179.285 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-12-01T17:52:59.285Z,1606845179.285 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-12-01T17:52:59.314Z,1606845179.314 [NavChart] Loaded
2020-12-01T17:52:59.314Z,1606845179.314 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-12-01T17:52:59.318Z,1606845179.318 [UniversalFixResidualReporter] Loaded
2020-12-01T17:52:59.318Z,1606845179.318 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-12-01T17:52:59.319Z,1606845179.319 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-12-01T17:52:59.319Z,1606845179.319 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-12-01T17:52:59.532Z,1606845179.532 [SBIT](DEBUG): Construct Startup Built In Test.
2020-12-01T17:52:59.540Z,1606845179.540 [SBIT] Loaded
2020-12-01T17:52:59.541Z,1606845179.541 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-12-01T17:52:59.541Z,1606845179.541 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-12-01T17:52:59.553Z,1606845179.553 [IBIT] Loaded
2020-12-01T17:52:59.554Z,1606845179.554 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-12-01T17:52:59.575Z,1606845179.575 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-12-01T17:52:59.726Z,1606845179.726 [CBIT] Loaded
2020-12-01T17:52:59.727Z,1606845179.727 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-12-01T17:52:59.727Z,1606845179.727 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-12-01T17:52:59.727Z,1606845179.727 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-12-01T17:52:59.835Z,1606845179.835 [BuoyancyServo] Loaded
2020-12-01T17:52:59.836Z,1606845179.836 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-12-01T17:52:59.852Z,1606845179.852 [ElevatorServo] Loaded
2020-12-01T17:52:59.853Z,1606845179.853 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-12-01T17:52:59.868Z,1606845179.868 [MassServo] Loaded
2020-12-01T17:52:59.868Z,1606845179.868 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-12-01T17:52:59.883Z,1606845179.883 [RudderServo] Loaded
2020-12-01T17:52:59.883Z,1606845179.883 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-12-01T17:52:59.898Z,1606845179.898 [ThrusterServo] Loaded
2020-12-01T17:52:59.898Z,1606845179.898 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-12-01T17:52:59.899Z,1606845179.899 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-12-01T17:52:59.899Z,1606845179.899 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-12-01T17:52:59.917Z,1606845179.917 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-12-01T17:52:59.917Z,1606845179.917 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-12-01T17:53:00.121Z,1606845180.121 [BackSeatDriver] Loaded
2020-12-01T17:53:00.121Z,1606845180.121 [ComponentRegistry](DEBUG): Component "BackSeatDriver" handled in its own thread.
2020-12-01T17:53:00.122Z,1606845180.122 [BackSeatDriver ThreadHandler](DEBUG): Created PCaller Thread at 40B074E0
2020-12-01T17:53:00.122Z,1606845180.122 [BackSeatDriver ThreadHandler](INFO): Protected caller Thread ID is 6744
2020-12-01T17:53:00.142Z,1606845180.142 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2020-12-01T17:53:00.145Z,1606845180.145 [CTD_Seabird](INFO): created writer for : sea_water_density
2020-12-01T17:53:00.145Z,1606845180.145 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2020-12-01T17:53:00.147Z,1606845180.147 [CTD_Seabird](INFO): created writer for : depth
2020-12-01T17:53:00.148Z,1606845180.148 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2020-12-01T17:53:00.150Z,1606845180.150 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2020-12-01T17:53:00.151Z,1606845180.151 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2020-12-01T17:53:00.154Z,1606845180.154 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2020-12-01T17:53:00.154Z,1606845180.154 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2020-12-01T17:53:00.157Z,1606845180.157 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2020-12-01T17:53:00.158Z,1606845180.158 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2020-12-01T17:53:00.161Z,1606845180.161 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2020-12-01T17:53:00.161Z,1606845180.161 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2020-12-01T17:53:00.164Z,1606845180.164 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2020-12-01T17:53:00.165Z,1606845180.165 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): mass_concentration_of_oxygen_in_sea_water
2020-12-01T17:53:00.167Z,1606845180.167 [CTD_Seabird](INFO): created writer for : mass_concentration_of_oxygen_in_sea_water
2020-12-01T17:53:00.168Z,1606845180.168 [CTD_Seabird](DEBUG): LcmSlateWriter::add(DAtaURI): CTD_Seabird.sea_water_dissolved_oxygen_freq
2020-12-01T17:53:00.168Z,1606845180.168 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): dataName: sea_water_dissolved_oxygen_freq
2020-12-01T17:53:00.184Z,1606845180.184 [CTD_Seabird] Loaded
2020-12-01T17:53:00.185Z,1606845180.185 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2020-12-01T17:53:00.186Z,1606845180.186 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B374E0
2020-12-01T17:53:00.186Z,1606845180.186 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 6745
2020-12-01T17:53:00.203Z,1606845180.203 [PAR_Licor] Loaded
2020-12-01T17:53:00.203Z,1606845180.203 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2020-12-01T17:53:00.215Z,1606845180.215 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470
2020-12-01T17:53:00.215Z,1606845180.215 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470
2020-12-01T17:53:00.217Z,1606845180.217 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650
2020-12-01T17:53:00.217Z,1606845180.217 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650
2020-12-01T17:53:00.219Z,1606845180.219 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl
2020-12-01T17:53:00.219Z,1606845180.219 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl
2020-12-01T17:53:00.221Z,1606845180.221 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm
2020-12-01T17:53:00.222Z,1606845180.222 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm
2020-12-01T17:53:00.223Z,1606845180.223 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm
2020-12-01T17:53:00.224Z,1606845180.224 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm
2020-12-01T17:53:00.226Z,1606845180.226 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm
2020-12-01T17:53:00.226Z,1606845180.226 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm
2020-12-01T17:53:00.228Z,1606845180.228 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm
2020-12-01T17:53:00.228Z,1606845180.228 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm
2020-12-01T17:53:00.230Z,1606845180.230 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water
2020-12-01T17:53:00.232Z,1606845180.232 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water
2020-12-01T17:53:00.232Z,1606845180.232 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-12-01T17:53:00.233Z,1606845180.233 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-12-01T17:53:00.234Z,1606845180.234 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-12-01T17:53:00.235Z,1606845180.235 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-12-01T17:53:00.237Z,1606845180.237 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-12-01T17:53:00.237Z,1606845180.237 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-12-01T17:53:00.239Z,1606845180.239 [WetLabsBB2FL] Loaded
2020-12-01T17:53:00.239Z,1606845180.239 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2020-12-01T17:53:00.240Z,1606845180.240 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B674E0
2020-12-01T17:53:00.240Z,1606845180.240 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 6746
2020-12-01T17:53:00.260Z,1606845180.260 [WetLabsUBAT..SerialDriver](INFO): Created logger
2020-12-01T17:53:00.260Z,1606845180.260 [WetLabsUBAT..SerialDriver](INFO): publishing on LCM channel WetLabsUBAT
2020-12-01T17:53:00.261Z,1606845180.261 [WetLabsUBAT..StreamSerialDriver](INFO): Created logger
2020-12-01T17:53:00.266Z,1606845180.266 [WetLabsUBAT](INFO): readConfig(): serialNo_: UBAT0051, uartName_: /dev/ttyC1, optionalArgs_: 
2020-12-01T17:53:00.266Z,1606845180.266 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsUBAT.biolum_potential
2020-12-01T17:53:00.266Z,1606845180.266 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(): dataName: biolum_potential
2020-12-01T17:53:00.268Z,1606845180.268 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsUBAT.flow_rate
2020-12-01T17:53:00.268Z,1606845180.268 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(): dataName: flow_rate
2020-12-01T17:53:00.271Z,1606845180.271 [WetLabsUBAT] Loaded
2020-12-01T17:53:00.271Z,1606845180.271 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread.
2020-12-01T17:53:00.272Z,1606845180.272 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40B974E0
2020-12-01T17:53:00.272Z,1606845180.272 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 6747
2020-12-01T17:53:00.273Z,1606845180.273 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-12-01T17:53:00.277Z,1606845180.277 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-12-01T17:53:00.278Z,1606845180.278 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-12-01T17:53:00.286Z,1606845180.286 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-12-01T17:53:00.287Z,1606845180.287 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BC74E0
2020-12-01T17:53:00.287Z,1606845180.287 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 6748
2020-12-01T17:53:00.292Z,1606845180.292 [Supervisor](INFO): Main Thread ID is 5457
2020-12-01T17:53:00.292Z,1606845180.292 [Supervisor](DEBUG): Running supervisor.
2020-12-01T17:53:00.292Z,1606845180.292 [CommandLine ThreadHandler](INFO): Handler Thread ID is 6749
2020-12-01T17:53:00.295Z,1606845180.295 [controlThread ThreadHandler](INFO): Handler Thread ID is 6750
2020-12-01T17:53:00.295Z,1606845180.295 [controlThread](DEBUG): Initializing ControlThread
2020-12-01T17:53:00.296Z,1606845180.296 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-12-01T17:53:00.298Z,1606845180.298 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-12-01T17:53:00.299Z,1606845180.299 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-12-01T17:53:00.299Z,1606845180.299 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-12-01T17:53:00.299Z,1606845180.299 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-12-01T17:53:00.300Z,1606845180.300 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-12-01T17:53:00.300Z,1606845180.300 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-12-01T17:53:00.300Z,1606845180.300 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-12-01T17:53:00.301Z,1606845180.301 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-12-01T17:53:00.301Z,1606845180.301 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-12-01T17:53:00.307Z,1606845180.307 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-12-01T17:53:00.307Z,1606845180.307 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-12-01T17:53:00.308Z,1606845180.308 [SBIT](INFO): Initialize SBIT Component.
2020-12-01T17:53:00.309Z,1606845180.309 [SBIT](IMPORTANT): git: 2020-11-24
2020-12-01T17:53:00.309Z,1606845180.309 [SBIT](INFO): git hash: adde971cea593344b7c42ced3277a520a74b3766
2020-12-01T17:53:00.309Z,1606845180.309 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-12-01T17:53:00.310Z,1606845180.310 [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-12-01T17:53:00.312Z,1606845180.312 [SBIT](INFO): Beginning SBIT in 49.000000 seconds.
2020-12-01T17:53:00.312Z,1606845180.312 [IBIT](INFO): Initialize IBIT Component.
2020-12-01T17:53:00.313Z,1606845180.313 [CBIT](DEBUG): Initialize CBIT Component.
2020-12-01T17:53:00.314Z,1606845180.314 [logger ThreadHandler](INFO): Handler Thread ID is 6751
2020-12-01T17:53:00.324Z,1606845180.324 [CBIT](DEBUG): Initialized mux pins.
2020-12-01T17:53:00.324Z,1606845180.324 [CBIT](DEBUG): Initializing the watchdog timer.
2020-12-01T17:53:00.332Z,1606845180.332 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 6752
2020-12-01T17:53:00.333Z,1606845180.333 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-12-01T17:53:00.344Z,1606845180.344 [Onboard ThreadHandler](INFO): Handler Thread ID is 6753
2020-12-01T17:53:00.348Z,1606845180.348 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-12-01T17:53:00.349Z,1606845180.349 [CBIT](DEBUG): Initializing heartbeat.
2020-12-01T17:53:00.362Z,1606845180.362 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 6754
2020-12-01T17:53:00.384Z,1606845180.384 [BackSeatDriver ThreadHandler](INFO): Handler Thread ID is 6755
2020-12-01T17:53:00.404Z,1606845180.404 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 6756
2020-12-01T17:53:00.405Z,1606845180.405 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-12-01T17:53:00.409Z,1606845180.409 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 6758
2020-12-01T17:53:00.410Z,1606845180.410 [WetLabsBB2FL](INFO): Powering down
2020-12-01T17:53:00.420Z,1606845180.420 [CBIT](DEBUG): Deactivating GF circuits.
2020-12-01T17:53:00.420Z,1606845180.420 [CBIT](DEBUG): Deactivating emergency mode.
2020-12-01T17:53:00.421Z,1606845180.421 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 6759
2020-12-01T17:53:00.432Z,1606845180.432 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 6760
2020-12-01T17:53:00.435Z,1606845180.435 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-12-01T17:53:00.435Z,1606845180.435 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-12-01T17:53:00.436Z,1606845180.436 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-12-01T17:53:00.436Z,1606845180.436 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-12-01T17:53:00.436Z,1606845180.436 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-12-01T17:53:00.436Z,1606845180.436 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-12-01T17:53:00.436Z,1606845180.436 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-12-01T17:53:00.436Z,1606845180.436 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-12-01T17:53:00.437Z,1606845180.437 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-12-01T17:53:00.437Z,1606845180.437 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-12-01T17:53:00.437Z,1606845180.437 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-12-01T17:53:00.437Z,1606845180.437 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-12-01T17:53:00.437Z,1606845180.437 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-12-01T17:53:00.437Z,1606845180.437 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-12-01T17:53:00.438Z,1606845180.438 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-12-01T17:53:00.438Z,1606845180.438 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-12-01T17:53:00.456Z,1606845180.456 [CBIT](DEBUG): Backplane powered.
2020-12-01T17:53:00.461Z,1606845180.461 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-12-01T17:53:00.479Z,1606845180.479 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-12-01T17:53:00.517Z,1606845180.517 [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-12-01T17:53:00.518Z,1606845180.518 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-12-01T17:53:00.591Z,1606845180.591 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-12-01T17:53:00.599Z,1606845180.599 [Default:A.Wait](DEBUG): Construct Wait.
2020-12-01T17:53:00.601Z,1606845180.601 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-12-01T17:53:00.649Z,1606845180.649 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-12-01T17:53:00.651Z,1606845180.651 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-12-01T17:53:00.673Z,1606845180.673 [Default:E.Execute](DEBUG): Construct Execute.
2020-12-01T17:53:00.676Z,1606845180.676 [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-12-01T17:53:00.692Z,1606845180.692 [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-12-01T17:53:00.701Z,1606845180.701 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-12-01T17:53:00.768Z,1606845180.768 [Radio_Surface](INFO): Powering up
2020-12-01T17:53:00.855Z,1606845180.855 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2020-12-01T17:53:00.855Z,1606845180.855 [DAT](INFO): Powering up
2020-12-01T17:53:00.855Z,1606845180.855 [DAT](DEBUG): Initializing DAT.
2020-12-01T17:53:00.892Z,1606845180.892 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2020-12-01T17:53:01.094Z,1606845181.094 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-12-01T17:53:01.100Z,1606845181.100 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-12-01T17:53:01.101Z,1606845181.101 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-12-01T17:53:01.108Z,1606845181.108 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-12-01T17:53:01.109Z,1606845181.109 [MassServo](DEBUG): Initializing EZServoServo.
2020-12-01T17:53:01.116Z,1606845181.116 [MassServo](DEBUG): Initializing MassServo.
2020-12-01T17:53:01.117Z,1606845181.117 [RudderServo](DEBUG): Initializing EZServoServo.
2020-12-01T17:53:01.124Z,1606845181.124 [RudderServo](DEBUG): Initializing RudderServo.
2020-12-01T17:53:01.125Z,1606845181.125 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-12-01T17:53:01.132Z,1606845181.132 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-12-01T17:53:14.944Z,1606845194.944 [DAT](INFO): commRate: 800
2020-12-01T17:53:16.969Z,1606845196.969 [DAT](INFO): entering command mode
2020-12-01T17:53:17.370Z,1606845197.370 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:53:17.770Z,1606845197.770 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:53:18.189Z,1606845198.189 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:53:18.599Z,1606845198.599 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:53:18.966Z,1606845198.966 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:53:19.396Z,1606845199.396 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:53:19.782Z,1606845199.782 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:53:20.178Z,1606845200.178 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:53:20.593Z,1606845200.593 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:53:20.998Z,1606845200.998 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:53:21.412Z,1606845201.412 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:53:21.794Z,1606845201.794 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:53:22.206Z,1606845202.206 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:53:22.625Z,1606845202.625 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:53:23.006Z,1606845203.006 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:53:23.456Z,1606845203.456 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:53:23.814Z,1606845203.814 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:53:24.222Z,1606845204.222 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:53:24.644Z,1606845204.644 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:53:25.030Z,1606845205.030 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:53:25.430Z,1606845205.430 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:53:25.892Z,1606845205.892 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:53:26.238Z,1606845206.238 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:53:26.646Z,1606845206.646 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:53:27.068Z,1606845207.068 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:53:27.465Z,1606845207.465 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:53:27.854Z,1606845207.854 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:53:28.265Z,1606845208.265 [NAL9602](INFO): Powering up NAL9602
2020-12-01T17:53:28.267Z,1606845208.267 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:53:28.673Z,1606845208.673 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:53:29.066Z,1606845209.066 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:53:29.504Z,1606845209.504 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:53:29.874Z,1606845209.874 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:53:30.282Z,1606845210.282 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:53:30.704Z,1606845210.704 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:53:31.102Z,1606845211.102 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:53:31.524Z,1606845211.524 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:53:31.898Z,1606845211.898 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:53:32.302Z,1606845212.302 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-01T17:53:32.303Z,1606845212.303 [DAT](FAULT): failed to enter command mode
2020-12-01T17:53:32.723Z,1606845212.723 [DAT](INFO): entering command mode
2020-12-01T17:53:33.111Z,1606845213.111 [DAT](INFO): setting verbose to 3
2020-12-01T17:53:33.564Z,1606845213.564 [DAT](INFO): set verbose to 3
2020-12-01T17:53:33.564Z,1606845213.564 [DAT](INFO): setting DatVerbose to 27440
2020-12-01T17:53:33.915Z,1606845213.915 [DAT](INFO): set DatVerbose to 27440
2020-12-01T17:53:33.915Z,1606845213.915 [DAT](INFO): setting transmit power to 8
2020-12-01T17:53:34.331Z,1606845214.331 [DAT](INFO): set transmit power to 8
2020-12-01T17:53:34.331Z,1606845214.331 [DAT](INFO): setting local address to 9
2020-12-01T17:53:34.723Z,1606845214.723 [DAT](INFO): set local address to 9
2020-12-01T17:53:39.173Z,1606845219.173 [NAL9602](INFO): NAL9602 initialized
2020-12-01T17:53:50.107Z,1606845230.107 [SBIT](IMPORTANT): Beginning Startup BIT
2020-12-01T17:53:50.111Z,1606845230.111 [CBIT](IMPORTANT): Beginning ground fault scan
2020-12-01T17:53:59.372Z,1606845239.372 [NAL9602](INFO): SBD MO Status=2, MOMSN=11385, MT Status=2, MTMSN=0
2020-12-01T17:53:59.372Z,1606845239.372 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-12-01T17:54:01.066Z,1606845241.066 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.010867
CHAN A1 (24V): -0.002020
CHAN A2 (12V): -0.007109
CHAN A3 (5V): -0.002910
CHAN B0 (3.3V): 0.000140
CHAN B1 (3.15aV): 0.000066
CHAN B2 (3.15bV): 0.000358
CHAN B3 (GND): 0.002263
OPEN: 0.005044
Full Scale Calc: 4.765 mA, -1.589 mA
2020-12-01T17:54:21.187Z,1606845261.187 [NAL9602](INFO): SBD MO Status=2, MOMSN=11385, MT Status=2, MTMSN=0
2020-12-01T17:54:21.187Z,1606845261.187 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-12-01T17:54:43.834Z,1606845283.834 [SBIT](IMPORTANT): SBIT PASSED
2020-12-01T17:54:43.834Z,1606845283.834 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-12-01T17:54:43.835Z,1606845283.835 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=6 count;

2020-12-01T17:54:43.835Z,1606845283.835 [SBIT](IMPORTANT): CBIT.gf24Offset=145 microampere;

2020-12-01T17:54:43.835Z,1606845283.835 [SBIT](IMPORTANT): DAT.loadAtStartup=1 bool;

2020-12-01T17:54:43.835Z,1606845283.835 [SBIT](IMPORTANT): DAT.sbdAddress=-1 enum;

2020-12-01T17:54:43.835Z,1606845283.835 [SBIT](IMPORTANT): DAT.surfaceThreshold=100 meter;

2020-12-01T17:54:43.836Z,1606845283.836 [SBIT](IMPORTANT): DAT.verbosity=0 count;

2020-12-01T17:54:43.836Z,1606845283.836 [SBIT](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 1.000000 liter_per_second;

2020-12-01T17:54:43.836Z,1606845283.836 [SBIT](IMPORTANT): PAR_Licor.loadAtStartup=1 bool;

2020-12-01T17:54:43.836Z,1606845283.836 [SBIT](IMPORTANT): PAR_Licor.parCal=1.01146 none;

2020-12-01T17:54:43.836Z,1606845283.836 [SBIT](IMPORTANT): RDI_Pathfinder.loadAtStartup=1 bool;

2020-12-01T17:54:43.836Z,1606845283.836 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=254.6 cubic_centimeter;

2020-12-01T17:54:43.837Z,1606845283.837 [SBIT](IMPORTANT): VerticalControl.massDefault=7.48 millimeter;

2020-12-01T17:54:43.837Z,1606845283.837 [SBIT](IMPORTANT): WetLabsUBAT.loadAtStartup=1 bool;

2020-12-01T17:54:44.219Z,1606845284.219 [NAL9602](INFO): SBD MO Status=2, MOMSN=11385, MT Status=2, MTMSN=0
2020-12-01T17:54:44.219Z,1606845284.219 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-12-01T17:54:44.234Z,1606845284.234 [MissionManager](IMPORTANT): Started mission Startup
2020-12-01T17:54:44.234Z,1606845284.234 [Startup] Running Loop=1
2020-12-01T17:54:44.234Z,1606845284.234 [Startup](DEBUG): Aggregate::initialize Startup
2020-12-01T17:54:44.234Z,1606845284.234 [Startup:A.GoToSurface] Running Loop=1
2020-12-01T17:54:44.235Z,1606845284.235 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-12-01T17:54:44.235Z,1606845284.235 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-12-01T17:54:44.256Z,1606845284.256 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-12-01T17:54:44.256Z,1606845284.256 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-12-01T17:54:44.257Z,1606845284.257 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-12-01T17:54:44.257Z,1606845284.257 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-12-01T17:54:44.258Z,1606845284.258 [Startup:StartupSatComms] Running Loop=1
2020-12-01T17:54:44.258Z,1606845284.258 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-12-01T17:54:44.258Z,1606845284.258 [Startup:StartupSatComms:A] Running Loop=1
2020-12-01T17:54:44.668Z,1606845284.668 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-12-01T17:55:08.455Z,1606845308.455 [NAL9602](INFO): SBD MO Status=2, MOMSN=11385, MT Status=2, MTMSN=0
2020-12-01T17:55:08.455Z,1606845308.455 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-12-01T17:55:18.955Z,1606845318.955 [NAL9602](INFO): SBD MO Status=0, MOMSN=11385, MT Status=0, MTMSN=0
2020-12-01T17:55:18.955Z,1606845318.955 [NAL9602](INFO): No messages in MT queue
2020-12-01T17:55:44.458Z,1606845344.458 [Startup:StartupSatComms:A](INFO): Timed out from 2020-12-01T17:54:44.3Z
2020-12-01T17:55:44.458Z,1606845344.458 [Startup:StartupSatComms:A] Stopped
2020-12-01T17:55:44.458Z,1606845344.458 [Startup:StartupSatComms:B] Running Loop=1
2020-12-01T17:55:44.834Z,1606845344.834 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-12-01T17:56:00.441Z,1606845360.441 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2020-12-01T17:56:00.441Z,1606845360.441 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-12-01T17:56:00.459Z,1606845360.459 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-12-01T17:56:00.826Z,1606845360.826 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-12-01T17:56:00.826Z,1606845360.826 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2020-12-01T17:56:16.161Z,1606845376.161 [NAL9602](INFO): SBD MO Status=2, MOMSN=11386, MT Status=2, MTMSN=0
2020-12-01T17:56:16.161Z,1606845376.161 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-12-01T17:56:25.863Z,1606845385.863 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.010855
2020-12-01T17:56:35.988Z,1606845395.988 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20201201T172947/Courier0010.lzma
2020-12-01T17:56:36.990Z,1606845396.990 [DataOverHttps](INFO): Moved sent file to Logs/20201201T172947/Courier0010.lzma.bak
2020-12-01T17:56:36.990Z,1606845396.990 [DataOverHttps](INFO): SBD MOMSN=13372984
2020-12-01T17:56:39.487Z,1606845399.487 [NAL9602](INFO): SBD MO Status=2, MOMSN=11386, MT Status=2, MTMSN=0
2020-12-01T17:56:39.487Z,1606845399.487 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-12-01T17:56:40.306Z,1606845400.306 [CommandLine](IMPORTANT): got command failComponent
2020-12-01T17:56:40.306Z,1606845400.306 [CommandLine](IMPORTANT): Failed components:
2020-12-01T17:56:40.307Z,1606845400.307 [CommandLine](IMPORTANT): No failed Components.
2020-12-01T17:56:44.843Z,1606845404.843 [Startup:StartupSatComms:B](INFO): Timed out from 2020-12-01T17:55:44.5Z
2020-12-01T17:56:44.843Z,1606845404.843 [Startup:StartupSatComms:B] Stopped
2020-12-01T17:56:44.843Z,1606845404.843 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2020-12-01T17:56:44.852Z,1606845404.852 [Startup:StartupSatComms] Stopped
2020-12-01T17:56:44.852Z,1606845404.852 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-12-01T17:56:44.853Z,1606845404.853 [Startup](INFO): Completed Startup
2020-12-01T17:56:44.853Z,1606845404.853 [MissionManager](INFO): Startup is completed.
2020-12-01T17:56:44.853Z,1606845404.853 [MissionManager](INFO): Uninitializing Mission Startup
2020-12-01T17:56:44.853Z,1606845404.853 [Startup] Stopped
2020-12-01T17:56:44.853Z,1606845404.853 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-12-01T17:56:44.853Z,1606845404.853 [Startup:A.GoToSurface] Stopped
2020-12-01T17:56:44.853Z,1606845404.853 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-12-01T17:56:45.266Z,1606845405.266 [MissionManager](IMPORTANT): Started mission Default
2020-12-01T17:56:45.266Z,1606845405.266 [Default] Running Loop=1
2020-12-01T17:56:45.266Z,1606845405.266 [Default](DEBUG): Aggregate::initialize Default
2020-12-01T17:56:45.266Z,1606845405.266 [Default:B.GoToSurface] Running Loop=1
2020-12-01T17:56:45.266Z,1606845405.266 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-12-01T17:56:45.267Z,1606845405.267 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-12-01T17:56:45.267Z,1606845405.267 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-12-01T17:56:45.267Z,1606845405.267 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-12-01T17:56:45.267Z,1606845405.267 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-12-01T17:56:45.276Z,1606845405.276 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-12-01T17:56:45.276Z,1606845405.276 [Default:A.Wait] Running Loop=1
2020-12-01T17:56:45.276Z,1606845405.276 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-12-01T17:56:56.115Z,1606845416.115 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201201T172947/Courier0013.lzma
2020-12-01T17:56:57.117Z,1606845417.117 [DataOverHttps](INFO): Moved sent file to Logs/20201201T172947/Courier0013.lzma.bak
2020-12-01T17:56:57.118Z,1606845417.118 [DataOverHttps](INFO): SBD MOMSN=13372987
2020-12-01T17:56:58.566Z,1606845418.566 [Default:A.Wait](INFO): Done Waiting.
2020-12-01T17:56:58.566Z,1606845418.566 [Default:A.Wait] Stopped
2020-12-01T17:56:58.566Z,1606845418.566 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-12-01T17:56:58.975Z,1606845418.975 [Default:CheckIn] Running Loop=1
2020-12-01T17:56:58.976Z,1606845418.976 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-12-01T17:56:58.976Z,1606845418.976 [Default:CheckIn:Read_GPS] Running Loop=1
2020-12-01T17:56:59.370Z,1606845419.370 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-12-01T17:57:46.642Z,1606845466.642 [CommandLine](IMPORTANT): got command strobe off
2020-12-01T17:57:46.642Z,1606845466.642 [CommandLine](IMPORTANT): Deactivating strobe
2020-12-01T17:59:01.410Z,1606845541.410 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2020-12-01T17:59:01.411Z,1606845541.411 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-12-01T17:59:01.441Z,1606845541.441 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-12-01T17:59:01.767Z,1606845541.767 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175901.00,A,3648.16650,N,12147.28097,W,1.050,0.00,011220,,,D*7E
2020-12-01T17:59:01.770Z,1606845541.770 [NAL9602](INFO): GPS fix at 20201201T175901: (36.802775, -121.788016)
2020-12-01T17:59:01.779Z,1606845541.779 [Default:CheckIn:Read_GPS] Stopped
2020-12-01T17:59:01.779Z,1606845541.779 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-12-01T17:59:01.804Z,1606845541.804 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-12-01T17:59:01.804Z,1606845541.804 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2020-12-01T17:59:02.194Z,1606845542.194 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-12-01T17:59:09.719Z,1606845549.719 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20201201T175250/Courier0000.lzma
2020-12-01T17:59:10.722Z,1606845550.722 [DataOverHttps](INFO): Moved sent file to Logs/20201201T175250/Courier0000.lzma.bak
2020-12-01T17:59:10.722Z,1606845550.722 [DataOverHttps](INFO): SBD MOMSN=13372990
2020-12-01T17:59:26.764Z,1606845566.764 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20201201T175250/Courier0004.lzma
2020-12-01T17:59:27.766Z,1606845567.766 [DataOverHttps](INFO): Moved sent file to Logs/20201201T175250/Courier0004.lzma.bak
2020-12-01T17:59:27.766Z,1606845567.766 [DataOverHttps](INFO): SBD MOMSN=13372993
2020-12-01T17:59:34.510Z,1606845574.510 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-01T17:59:43.884Z,1606845583.884 [DataOverHttps](INFO): Sending 191 bytes from file Logs/20201201T172947/Express0011.lzma
2020-12-01T17:59:44.888Z,1606845584.888 [DataOverHttps](INFO): Moved sent file to Logs/20201201T172947/Express0011.lzma.bak
2020-12-01T17:59:44.889Z,1606845584.889 [DataOverHttps](INFO): SBD MOMSN=13372998
2020-12-01T18:00:00.891Z,1606845600.891 [DataOverHttps](INFO): Sending 100 bytes from file Logs/20201201T172947/Express0014.lzma
2020-12-01T18:00:01.894Z,1606845601.894 [DataOverHttps](INFO): Moved sent file to Logs/20201201T172947/Express0014.lzma.bak
2020-12-01T18:00:01.894Z,1606845601.894 [DataOverHttps](IMPORTANT): SBD MOMSN=13373006, MTMSN=20201201T180000
2020-12-01T18:00:09.344Z,1606845609.344 [DataOverHttps](INFO): Received command:ibit
2020-12-01T18:00:09.422Z,1606845609.422 [CommandLine](IMPORTANT): got command ibit
2020-12-01T18:00:09.703Z,1606845609.703 [IBIT](IMPORTANT): Beginning Initiated BIT
2020-12-01T18:00:09.703Z,1606845609.703 [IBIT](IMPORTANT): Beginning control surface checks.
2020-12-01T18:00:09.716Z,1606845609.716 [CBIT](IMPORTANT): Beginning ground fault scan
2020-12-01T18:00:11.273Z,1606845611.273 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180010.00,A,3648.16832,N,12147.28155,W,0.117,229.80,011220,,,D*7A
2020-12-01T18:00:11.275Z,1606845611.275 [NAL9602](INFO): GPS fix at 20201201T180010: (36.802805, -121.788026)
2020-12-01T18:00:20.811Z,1606845620.811 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.010850
CHAN A1 (24V): -0.002078
CHAN A2 (12V): -0.007515
CHAN A3 (5V): -0.002349
CHAN B0 (3.3V): 0.000756
CHAN B1 (3.15aV): 0.000189
CHAN B2 (3.15bV): -0.000123
CHAN B3 (GND): 0.002983
OPEN: 0.005223
Full Scale Calc: 4.765 mA, -1.589 mA
2020-12-01T18:00:24.191Z,1606845624.191 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20201201T172947/Express0017.lzma
2020-12-01T18:00:25.194Z,1606845625.194 [DataOverHttps](INFO): Moved sent file to Logs/20201201T172947/Express0017.lzma.bak
2020-12-01T18:00:25.194Z,1606845625.194 [DataOverHttps](INFO): SBD MOMSN=13373013
2020-12-01T18:00:27.606Z,1606845627.606 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-12-01T18:00:27.617Z,1606845627.617 [BPC1](INFO): Received data from all battery sticks.
2020-12-01T18:00:39.846Z,1606845639.846 [IBIT](FAULT): Rudder: EXPECTED:-15.000000 ACTUAL:-14.764685
2020-12-01T18:00:39.846Z,1606845639.846 [IBIT](FAULT): Control surface position failure.
2020-12-01T18:00:49.423Z,1606845649.423 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201201T175250/Courier0007.lzma
2020-12-01T18:00:50.426Z,1606845650.426 [DataOverHttps](INFO): Moved sent file to Logs/20201201T175250/Courier0007.lzma.bak
2020-12-01T18:00:50.426Z,1606845650.426 [DataOverHttps](INFO): SBD MOMSN=13373015
2020-12-01T18:00:55.542Z,1606845655.542 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 1
Latitude: 36.802807 Longitude: -121.788025
2020-12-01T18:00:55.000Z,1606845656.000 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.730000
2020-12-01T18:00:56.000Z,1606845656.000 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2020-12-01T18:00:56.000Z,1606845656.000 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2020-12-01T18:00:56.357Z,1606845656.357 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2020-12-01T18:00:56.358Z,1606845656.358 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2020-12-01T18:00:56.358Z,1606845656.358 [IBIT](IMPORTANT): Pressure:7.181334 PSI
2020-12-01T18:00:56.358Z,1606845656.358 [IBIT](IMPORTANT): Humidity:17.317787 %
2020-12-01T18:00:56.764Z,1606845656.764 [IBIT](IMPORTANT): Vehicle Pitch:0.150830 degrees
2020-12-01T18:00:56.765Z,1606845656.765 [IBIT](IMPORTANT): Vehicle  Roll:1.168749 degrees
2020-12-01T18:00:56.765Z,1606845656.765 [IBIT](IMPORTANT): Vehicle  Heading:27.391817 degrees
2020-12-01T18:00:57.151Z,1606845657.151 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2020-12-01T18:00:57.151Z,1606845657.151 [IBIT](IMPORTANT): buoyancyNeutral: 254.600021 cc
2020-12-01T18:00:57.176Z,1606845657.176 [IBIT](IMPORTANT): massDefault: 0.748000 cm
2020-12-01T18:00:57.176Z,1606845657.176 [IBIT](IMPORTANT): stopDepth: 215.000000 m
2020-12-01T18:00:57.177Z,1606845657.177 [IBIT](IMPORTANT): abortDepth: 255.000000 m
2020-12-01T18:00:57.177Z,1606845657.177 [IBIT](IMPORTANT): IBIT FAILED
2020-12-01T18:01:06.921Z,1606845666.921 [DataOverHttps](INFO): Sending 953 bytes from file Logs/20201201T175250/Express0001.lzma
2020-12-01T18:01:07.921Z,1606845667.921 [DataOverHttps](INFO): Moved sent file to Logs/20201201T175250/Express0001.lzma.bak
2020-12-01T18:01:07.922Z,1606845667.922 [DataOverHttps](INFO): SBD MOMSN=13373017
2020-12-01T18:01:24.115Z,1606845684.115 [DataOverHttps](INFO): Sending 171 bytes from file Logs/20201201T175250/Express0005.lzma
2020-12-01T18:01:25.120Z,1606845685.120 [DataOverHttps](INFO): Moved sent file to Logs/20201201T175250/Express0005.lzma.bak
2020-12-01T18:01:25.120Z,1606845685.120 [DataOverHttps](INFO): SBD MOMSN=13373043
2020-12-01T18:01:41.212Z,1606845701.212 [DataOverHttps](INFO): Sending 366 bytes from file Logs/20201201T175250/Express0008.lzma
2020-12-01T18:01:42.214Z,1606845702.214 [DataOverHttps](INFO): Moved sent file to Logs/20201201T175250/Express0008.lzma.bak
2020-12-01T18:01:42.214Z,1606845702.214 [DataOverHttps](INFO): SBD MOMSN=13373051
2020-12-01T18:01:43.615Z,1606845703.615 [Default:CheckIn:Read_Iridium] Stopped
2020-12-01T18:01:43.615Z,1606845703.615 [Default:CheckIn:C.Wait] Running Loop=1
2020-12-01T18:01:43.615Z,1606845703.615 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-01T18:01:46.422Z,1606845706.422 [NAL9602](INFO): SBD MO Status=1, MOMSN=11386, MT Status=0, MTMSN=0
2020-12-01T18:01:46.423Z,1606845706.423 [NAL9602](INFO): No messages in MT queue
2020-12-01T18:02:17.125Z,1606845737.125 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-01T18:05:25.209Z,1606845925.209 [DataOverHttps](IMPORTANT): SBD MTMSN=20201201T180524
2020-12-01T18:05:34.895Z,1606845934.895 [DataOverHttps](INFO): Received command:ibit
2020-12-01T18:05:34.907Z,1606845934.907 [CommandLine](IMPORTANT): got command ibit
2020-12-01T18:05:35.182Z,1606845935.182 [IBIT](IMPORTANT): Beginning Initiated BIT
2020-12-01T18:05:35.182Z,1606845935.182 [IBIT](IMPORTANT): Beginning control surface checks.
2020-12-01T18:05:35.205Z,1606845935.205 [CBIT](IMPORTANT): Beginning ground fault scan
2020-12-01T18:05:36.783Z,1606845936.783 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180536.00,A,3648.17408,N,12147.27780,W,0.058,27.09,011220,,,D*49
2020-12-01T18:05:36.785Z,1606845936.785 [NAL9602](INFO): GPS fix at 20201201T180536: (36.802901, -121.787963)
2020-12-01T18:05:46.095Z,1606845946.095 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.011423
CHAN A1 (24V): -0.002253
CHAN A2 (12V): -0.007876
CHAN A3 (5V): -0.002861
CHAN B0 (3.3V): 0.000608
CHAN B1 (3.15aV): 0.000545
CHAN B2 (3.15bV): -0.000428
CHAN B3 (GND): 0.002312
OPEN: 0.004911
Full Scale Calc: 4.765 mA, -1.589 mA
2020-12-01T18:05:53.361Z,1606845953.361 [NAL9602](INFO): SBD MO Status=1, MOMSN=11387, MT Status=0, MTMSN=0
2020-12-01T18:05:53.361Z,1606845953.361 [NAL9602](INFO): No messages in MT queue
2020-12-01T18:05:54.555Z,1606845954.555 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180553.00,A,3648.17425,N,12147.27815,W,0.078,27.09,011220,,,D*44
2020-12-01T18:05:54.557Z,1606845954.557 [NAL9602](INFO): GPS fix at 20201201T180553: (36.802904, -121.787969)
2020-12-01T18:05:56.978Z,1606845956.978 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180556.00,A,3648.17418,N,12147.27818,W,0.078,27.09,011220,,,D*42
2020-12-01T18:05:56.981Z,1606845956.981 [NAL9602](INFO): GPS fix at 20201201T180556: (36.802903, -121.787970)
2020-12-01T18:05:59.807Z,1606845959.807 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180559.00,A,3648.17410,N,12147.27819,W,0.000,27.09,011220,,,D*4B
2020-12-01T18:05:59.810Z,1606845959.810 [NAL9602](INFO): GPS fix at 20201201T180559: (36.802902, -121.787970)
2020-12-01T18:06:03.043Z,1606845963.043 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180602.00,A,3648.17399,N,12147.27822,W,0.019,27.09,011220,,,D*40
2020-12-01T18:06:03.046Z,1606845963.046 [NAL9602](INFO): GPS fix at 20201201T180602: (36.802900, -121.787970)
2020-12-01T18:06:05.578Z,1606845965.578 [IBIT](FAULT): Rudder: EXPECTED:-15.000000 ACTUAL:-14.762486
2020-12-01T18:06:05.578Z,1606845965.578 [IBIT](FAULT): Control surface position failure.
2020-12-01T18:06:05.883Z,1606845965.883 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180605.00,A,3648.17384,N,12147.27826,W,0.019,27.09,011220,,,D*4F
2020-12-01T18:06:05.889Z,1606845965.889 [NAL9602](INFO): GPS fix at 20201201T180605: (36.802897, -121.787971)
2020-12-01T18:06:09.103Z,1606845969.103 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180608.00,A,3648.17367,N,12147.27841,W,0.078,27.09,011220,,,D*49
2020-12-01T18:06:09.105Z,1606845969.105 [NAL9602](INFO): GPS fix at 20201201T180608: (36.802895, -121.787974)
2020-12-01T18:06:11.931Z,1606845971.931 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180611.00,A,3648.17360,N,12147.27849,W,0.019,27.09,011220,,,D*49
2020-12-01T18:06:11.933Z,1606845971.933 [NAL9602](INFO): GPS fix at 20201201T180611: (36.802893, -121.787975)
2020-12-01T18:06:15.159Z,1606845975.159 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180614.00,A,3648.17349,N,12147.27857,W,0.058,27.09,011220,,,D*4D
2020-12-01T18:06:15.161Z,1606845975.161 [NAL9602](INFO): GPS fix at 20201201T180614: (36.802892, -121.787976)
2020-12-01T18:06:17.986Z,1606845977.986 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180617.00,A,3648.17338,N,12147.27870,W,0.078,27.09,011220,,,D*4F
2020-12-01T18:06:17.989Z,1606845977.989 [NAL9602](INFO): GPS fix at 20201201T180617: (36.802890, -121.787978)
2020-12-01T18:06:20.821Z,1606845980.821 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180620.00,A,3648.17327,N,12147.27888,W,0.039,27.09,011220,,,D*47
2020-12-01T18:06:20.823Z,1606845980.823 [NAL9602](INFO): GPS fix at 20201201T180620: (36.802888, -121.787981)
2020-12-01T18:06:21.253Z,1606845981.253 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 5
Latitude: 36.802887 Longitude: -121.787979
2020-12-01T18:06:21.685Z,1606845981.685 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.730000
2020-12-01T18:06:21.685Z,1606845981.685 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2020-12-01T18:06:21.686Z,1606845981.686 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2020-12-01T18:06:22.125Z,1606845982.125 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2020-12-01T18:06:22.126Z,1606845982.126 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2020-12-01T18:06:22.126Z,1606845982.126 [IBIT](IMPORTANT): Pressure:7.098183 PSI
2020-12-01T18:06:22.126Z,1606845982.126 [IBIT](IMPORTANT): Humidity:17.329996 %
2020-12-01T18:06:22.495Z,1606845982.495 [IBIT](IMPORTANT): Vehicle Pitch:-0.146525 degrees
2020-12-01T18:06:22.505Z,1606845982.505 [IBIT](IMPORTANT): Vehicle  Roll:-0.467655 degrees
2020-12-01T18:06:22.506Z,1606845982.506 [IBIT](IMPORTANT): Vehicle  Heading:30.780886 degrees
2020-12-01T18:06:22.921Z,1606845982.921 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2020-12-01T18:06:22.921Z,1606845982.921 [IBIT](IMPORTANT): buoyancyNeutral: 254.600021 cc
2020-12-01T18:06:22.921Z,1606845982.921 [IBIT](IMPORTANT): massDefault: 0.748000 cm
2020-12-01T18:06:22.922Z,1606845982.922 [IBIT](IMPORTANT): stopDepth: 215.000000 m
2020-12-01T18:06:22.922Z,1606845982.922 [IBIT](IMPORTANT): abortDepth: 255.000000 m
2020-12-01T18:06:22.922Z,1606845982.922 [IBIT](IMPORTANT): IBIT FAILED
2020-12-01T18:06:44.278Z,1606846004.278 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-12-01T18:06:44.279Z,1606846004.279 [Default:CheckIn:C.Wait] Stopped
2020-12-01T18:06:44.279Z,1606846004.279 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-01T18:06:44.279Z,1606846004.279 [Default:CheckIn:D] Running Loop=1
2020-12-01T18:06:44.696Z,1606846004.696 [Default:CheckIn:D] Stopped
2020-12-01T18:06:44.696Z,1606846004.696 [Default:CheckIn:E] Running Loop=1
2020-12-01T18:06:45.069Z,1606846005.069 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 9.990499 min 
2020-12-01T18:06:45.069Z,1606846005.069 [Default:CheckIn:E] Stopped
2020-12-01T18:06:45.070Z,1606846005.070 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-12-01T18:06:45.070Z,1606846005.070 [Default:CheckIn] Stopped
2020-12-01T18:06:45.070Z,1606846005.070 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-12-01T18:06:45.070Z,1606846005.070 [Default:CheckIn](INFO): Running loop #2
2020-12-01T18:06:45.070Z,1606846005.070 [Default:CheckIn] Running Loop=2
2020-12-01T18:06:45.070Z,1606846005.070 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-12-01T18:06:45.070Z,1606846005.070 [Default:CheckIn:Read_GPS] Running Loop=1
2020-12-01T18:06:46.617Z,1606846006.617 [DataOverHttps](IMPORTANT): SBD MTMSN=20201201T180645
2020-12-01T18:06:47.091Z,1606846007.091 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180646.00,A,3648.17295,N,12147.27909,W,0.019,27.09,011220,,,D*45
2020-12-01T18:06:47.093Z,1606846007.093 [NAL9602](INFO): GPS fix at 20201201T180646: (36.802883, -121.787985)
2020-12-01T18:06:47.122Z,1606846007.122 [Default:CheckIn:Read_GPS] Stopped
2020-12-01T18:06:47.122Z,1606846007.122 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-12-01T18:06:54.264Z,1606846014.264 [DataOverHttps](INFO): Received command:ibit
2020-12-01T18:06:54.365Z,1606846014.365 [CommandLine](IMPORTANT): got command ibit
2020-12-01T18:06:54.425Z,1606846014.425 [IBIT](IMPORTANT): Beginning Initiated BIT
2020-12-01T18:06:54.425Z,1606846014.425 [IBIT](IMPORTANT): Beginning control surface checks.
2020-12-01T18:06:54.428Z,1606846014.428 [CBIT](IMPORTANT): Beginning ground fault scan
2020-12-01T18:06:55.975Z,1606846015.975 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180655.00,A,3648.17333,N,12147.27854,W,0.117,27.09,011220,,,D*4C
2020-12-01T18:06:55.977Z,1606846015.977 [NAL9602](INFO): GPS fix at 20201201T180655: (36.802889, -121.787976)
2020-12-01T18:06:59.203Z,1606846019.203 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180658.00,A,3648.17354,N,12147.27835,W,0.078,27.09,011220,,,D*4F
2020-12-01T18:06:59.205Z,1606846019.205 [NAL9602](INFO): GPS fix at 20201201T180658: (36.802892, -121.787972)
2020-12-01T18:07:02.052Z,1606846022.052 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180701.00,A,3648.17365,N,12147.27816,W,0.117,27.09,011220,,,D*49
2020-12-01T18:07:02.054Z,1606846022.054 [NAL9602](INFO): GPS fix at 20201201T180701: (36.802894, -121.787969)
2020-12-01T18:07:04.875Z,1606846024.875 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180704.00,A,3648.17432,N,12147.27716,W,0.117,27.09,011220,,,D*46
2020-12-01T18:07:04.877Z,1606846024.877 [NAL9602](INFO): GPS fix at 20201201T180704: (36.802905, -121.787953)
2020-12-01T18:07:05.329Z,1606846025.329 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.011421
CHAN A1 (24V): -0.002415
CHAN A2 (12V): -0.006974
CHAN A3 (5V): -0.002313
CHAN B0 (3.3V): -0.000216
CHAN B1 (3.15aV): 0.000071
CHAN B2 (3.15bV): -0.000157
CHAN B3 (GND): 0.002172
OPEN: 0.005194
Full Scale Calc: 4.765 mA, -1.589 mA
2020-12-01T18:07:08.107Z,1606846028.107 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180707.00,A,3648.17461,N,12147.27678,W,0.117,27.09,011220,,,D*4A
2020-12-01T18:07:08.109Z,1606846028.109 [NAL9602](INFO): GPS fix at 20201201T180707: (36.802910, -121.787946)
2020-12-01T18:07:10.931Z,1606846030.931 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180710.00,A,3648.17528,N,12147.27576,W,0.156,27.09,011220,,,D*48
2020-12-01T18:07:10.933Z,1606846030.933 [NAL9602](INFO): GPS fix at 20201201T180710: (36.802921, -121.787929)
2020-12-01T18:07:14.167Z,1606846034.167 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180713.00,A,3648.17532,N,12147.27560,W,0.156,27.09,011220,,,D*47
2020-12-01T18:07:14.169Z,1606846034.169 [NAL9602](INFO): GPS fix at 20201201T180713: (36.802922, -121.787927)
2020-12-01T18:07:16.995Z,1606846036.995 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180716.00,A,3648.17578,N,12147.27490,W,0.156,27.09,011220,,,D*42
2020-12-01T18:07:16.997Z,1606846036.997 [NAL9602](INFO): GPS fix at 20201201T180716: (36.802930, -121.787915)
2020-12-01T18:07:19.823Z,1606846039.823 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180719.00,A,3648.17625,N,12147.27418,W,0.156,27.09,011220,,,D*46
2020-12-01T18:07:19.825Z,1606846039.825 [NAL9602](INFO): GPS fix at 20201201T180719: (36.802937, -121.787903)
2020-12-01T18:07:23.054Z,1606846043.054 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180722.00,A,3648.17660,N,12147.27362,W,0.156,27.09,011220,,,D*45
2020-12-01T18:07:23.056Z,1606846043.056 [NAL9602](INFO): GPS fix at 20201201T180722: (36.802943, -121.787894)
2020-12-01T18:07:24.794Z,1606846044.794 [IBIT](FAULT): Rudder: EXPECTED:-15.000000 ACTUAL:-14.797676
2020-12-01T18:07:24.794Z,1606846044.794 [IBIT](FAULT): Control surface position failure.
2020-12-01T18:07:25.883Z,1606846045.883 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180725.00,A,3648.17674,N,12147.27336,W,0.136,27.09,011220,,,D*40
2020-12-01T18:07:25.885Z,1606846045.885 [NAL9602](INFO): GPS fix at 20201201T180725: (36.802946, -121.787889)
2020-12-01T18:07:29.115Z,1606846049.115 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180728.00,A,3648.17685,N,12147.27320,W,0.117,27.09,011220,,,D*47
2020-12-01T18:07:29.117Z,1606846049.117 [NAL9602](INFO): GPS fix at 20201201T180728: (36.802948, -121.787887)
2020-12-01T18:07:30.688Z,1606846050.688 [DataOverHttps](INFO): Sending 201 bytes from file Logs/20201201T175250/Courier0010.lzma
2020-12-01T18:07:31.690Z,1606846051.690 [DataOverHttps](INFO): Moved sent file to Logs/20201201T175250/Courier0010.lzma.bak
2020-12-01T18:07:31.690Z,1606846051.690 [DataOverHttps](INFO): SBD MOMSN=13373118
2020-12-01T18:07:31.943Z,1606846051.943 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180731.00,A,3648.17717,N,12147.27284,W,0.117,27.09,011220,,,D*4A
2020-12-01T18:07:31.956Z,1606846051.956 [NAL9602](INFO): GPS fix at 20201201T180731: (36.802953, -121.787881)
2020-12-01T18:07:35.171Z,1606846055.171 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180734.00,A,3648.17740,N,12147.27275,W,0.058,27.09,011220,,,D*49
2020-12-01T18:07:35.173Z,1606846055.173 [NAL9602](INFO): GPS fix at 20201201T180734: (36.802957, -121.787879)
2020-12-01T18:07:37.999Z,1606846057.999 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180737.00,A,3648.17767,N,12147.27254,W,0.175,27.09,011220,,,D*42
2020-12-01T18:07:38.001Z,1606846058.001 [NAL9602](INFO): GPS fix at 20201201T180737: (36.802961, -121.787876)
2020-12-01T18:07:40.486Z,1606846060.486 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 5
Latitude: 36.802959 Longitude: -121.787872
2020-12-01T18:07:40.831Z,1606846060.831 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180740.00,A,3648.17775,N,12147.27264,W,0.078,27.09,011220,,,D*4E
2020-12-01T18:07:40.833Z,1606846060.833 [NAL9602](INFO): GPS fix at 20201201T180740: (36.802962, -121.787877)
2020-12-01T18:07:40.849Z,1606846060.849 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.730000
2020-12-01T18:07:40.849Z,1606846060.849 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2020-12-01T18:07:40.850Z,1606846060.850 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2020-12-01T18:07:41.273Z,1606846061.273 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2020-12-01T18:07:41.273Z,1606846061.273 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2020-12-01T18:07:41.274Z,1606846061.274 [IBIT](IMPORTANT): Pressure:7.207832 PSI
2020-12-01T18:07:41.274Z,1606846061.274 [IBIT](IMPORTANT): Humidity:17.354414 %
2020-12-01T18:07:41.671Z,1606846061.671 [IBIT](IMPORTANT): Vehicle Pitch:-0.149782 degrees
2020-12-01T18:07:41.671Z,1606846061.671 [IBIT](IMPORTANT): Vehicle  Roll:-0.464005 degrees
2020-12-01T18:07:41.671Z,1606846061.671 [IBIT](IMPORTANT): Vehicle  Heading:30.748705 degrees
2020-12-01T18:07:42.097Z,1606846062.097 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2020-12-01T18:07:42.097Z,1606846062.097 [IBIT](IMPORTANT): buoyancyNeutral: 254.600021 cc
2020-12-01T18:07:42.098Z,1606846062.098 [IBIT](IMPORTANT): massDefault: 0.748000 cm
2020-12-01T18:07:42.098Z,1606846062.098 [IBIT](IMPORTANT): stopDepth: 215.000000 m
2020-12-01T18:07:42.098Z,1606846062.098 [IBIT](IMPORTANT): abortDepth: 255.000000 m
2020-12-01T18:07:42.098Z,1606846062.098 [IBIT](IMPORTANT): IBIT FAILED
2020-12-01T18:07:45.645Z,1606846065.645 [CommandLine](IMPORTANT): got command ibit
2020-12-01T18:07:45.754Z,1606846065.754 [IBIT](IMPORTANT): Beginning Initiated BIT
2020-12-01T18:07:45.754Z,1606846065.754 [IBIT](IMPORTANT): Beginning control surface checks.
2020-12-01T18:07:45.765Z,1606846065.765 [CBIT](IMPORTANT): Beginning ground fault scan
2020-12-01T18:07:47.295Z,1606846067.295 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180746.00,A,3648.17777,N,12147.27280,W,0.097,27.09,011220,,,D*41
2020-12-01T18:07:47.311Z,1606846067.311 [NAL9602](INFO): GPS fix at 20201201T180746: (36.802963, -121.787880)
2020-12-01T18:07:48.450Z,1606846068.450 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201201T175250/Courier0013.lzma
2020-12-01T18:07:49.450Z,1606846069.450 [DataOverHttps](INFO): Moved sent file to Logs/20201201T175250/Courier0013.lzma.bak
2020-12-01T18:07:49.450Z,1606846069.450 [DataOverHttps](INFO): SBD MOMSN=13373125
2020-12-01T18:07:50.128Z,1606846070.128 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180749.00,A,3648.17786,N,12147.27269,W,0.019,27.09,011220,,,D*41
2020-12-01T18:07:50.130Z,1606846070.130 [NAL9602](INFO): GPS fix at 20201201T180749: (36.802964, -121.787878)
2020-12-01T18:07:52.954Z,1606846072.954 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180752.00,A,3648.17791,N,12147.27264,W,0.078,27.09,011220,,,D*47
2020-12-01T18:07:52.957Z,1606846072.957 [NAL9602](INFO): GPS fix at 20201201T180752: (36.802965, -121.787877)
2020-12-01T18:07:56.187Z,1606846076.187 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180755.00,A,3648.17790,N,12147.27261,W,0.039,27.09,011220,,,D*41
2020-12-01T18:07:56.189Z,1606846076.189 [NAL9602](INFO): GPS fix at 20201201T180755: (36.802965, -121.787877)
2020-12-01T18:07:56.656Z,1606846076.656 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.012068
CHAN A1 (24V): -0.002485
CHAN A2 (12V): -0.007474
CHAN A3 (5V): -0.001924
CHAN B0 (3.3V): 0.000399
CHAN B1 (3.15aV): 0.000431
CHAN B2 (3.15bV): 0.000184
CHAN B3 (GND): 0.001669
OPEN: 0.005373
Full Scale Calc: 4.765 mA, -1.589 mA
2020-12-01T18:07:59.019Z,1606846079.019 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180758.00,A,3648.17781,N,12147.27271,W,0.039,27.09,011220,,,D*4D
2020-12-01T18:07:59.021Z,1606846079.021 [NAL9602](INFO): GPS fix at 20201201T180758: (36.802963, -121.787879)
2020-12-01T18:08:01.843Z,1606846081.843 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180801.00,A,3648.17761,N,12147.27306,W,0.019,27.09,011220,,,D*43
2020-12-01T18:08:01.845Z,1606846081.845 [NAL9602](INFO): GPS fix at 20201201T180801: (36.802960, -121.787884)
2020-12-01T18:08:05.074Z,1606846085.074 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180804.00,A,3648.17758,N,12147.27317,W,0.000,27.09,011220,,,D*44
2020-12-01T18:08:05.077Z,1606846085.077 [NAL9602](INFO): GPS fix at 20201201T180804: (36.802960, -121.787886)
2020-12-01T18:08:06.497Z,1606846086.497 [DataOverHttps](INFO): Sending 1041 bytes from file Logs/20201201T175250/Express0011.lzma
2020-12-01T18:08:07.498Z,1606846087.498 [DataOverHttps](INFO): Moved sent file to Logs/20201201T175250/Express0011.lzma.bak
2020-12-01T18:08:07.498Z,1606846087.498 [DataOverHttps](INFO): SBD MOMSN=13373127
2020-12-01T18:08:07.902Z,1606846087.902 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180807.00,A,3648.17762,N,12147.27312,W,0.058,27.09,011220,,,D*46
2020-12-01T18:08:07.904Z,1606846087.904 [NAL9602](INFO): GPS fix at 20201201T180807: (36.802960, -121.787885)
2020-12-01T18:08:11.134Z,1606846091.134 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180810.00,A,3648.17758,N,12147.27320,W,0.078,27.09,011220,,,D*4A
2020-12-01T18:08:11.137Z,1606846091.137 [NAL9602](INFO): GPS fix at 20201201T180810: (36.802960, -121.787887)
2020-12-01T18:08:13.971Z,1606846093.971 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180813.00,A,3648.17764,N,12147.27318,W,0.039,27.09,011220,,,D*48
2020-12-01T18:08:13.973Z,1606846093.973 [NAL9602](INFO): GPS fix at 20201201T180813: (36.802961, -121.787886)
2020-12-01T18:08:17.220Z,1606846097.220 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180816.00,A,3648.17767,N,12147.27315,W,0.019,27.09,011220,,,D*41
2020-12-01T18:08:17.222Z,1606846097.222 [NAL9602](INFO): GPS fix at 20201201T180816: (36.802961, -121.787886)
2020-12-01T18:08:20.039Z,1606846100.039 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180819.00,A,3648.17771,N,12147.27320,W,0.039,27.09,011220,,,D*4D
2020-12-01T18:08:20.041Z,1606846100.041 [NAL9602](INFO): GPS fix at 20201201T180819: (36.802962, -121.787887)
2020-12-01T18:08:22.887Z,1606846102.887 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180822.00,A,3648.17774,N,12147.27338,W,0.039,27.09,011220,,,D*49
2020-12-01T18:08:22.889Z,1606846102.889 [NAL9602](INFO): GPS fix at 20201201T180822: (36.802962, -121.787890)
2020-12-01T18:08:26.194Z,1606846106.194 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180825.00,A,3648.17795,N,12147.27324,W,0.039,27.09,011220,,,D*4C
2020-12-01T18:08:26.204Z,1606846106.204 [NAL9602](INFO): GPS fix at 20201201T180825: (36.802966, -121.787887)
2020-12-01T18:08:28.983Z,1606846108.983 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180828.00,A,3648.17806,N,12147.27321,W,0.019,27.09,011220,,,D*43
2020-12-01T18:08:28.985Z,1606846108.985 [NAL9602](INFO): GPS fix at 20201201T180828: (36.802968, -121.787887)
2020-12-01T18:08:29.008Z,1606846109.008 [DataOverHttps](INFO): Sending 163 bytes from file Logs/20201201T175250/Express0015.lzma
2020-12-01T18:08:30.014Z,1606846110.014 [DataOverHttps](INFO): Moved sent file to Logs/20201201T175250/Express0015.lzma.bak
2020-12-01T18:08:30.014Z,1606846110.014 [DataOverHttps](INFO): SBD MOMSN=13373176
2020-12-01T18:08:31.495Z,1606846111.495 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 5
Latitude: 36.802967 Longitude: -121.787888
2020-12-01T18:08:31.862Z,1606846111.862 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.730000
2020-12-01T18:08:31.863Z,1606846111.863 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2020-12-01T18:08:31.863Z,1606846111.863 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2020-12-01T18:08:32.228Z,1606846112.228 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180831.00,A,3648.17808,N,12147.27326,W,0.019,27.09,011220,,,D*42
2020-12-01T18:08:32.230Z,1606846112.230 [NAL9602](INFO): GPS fix at 20201201T180831: (36.802968, -121.787888)
2020-12-01T18:08:32.255Z,1606846112.255 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2020-12-01T18:08:32.255Z,1606846112.255 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2020-12-01T18:08:32.255Z,1606846112.255 [IBIT](IMPORTANT): Pressure:7.103806 PSI
2020-12-01T18:08:32.255Z,1606846112.255 [IBIT](IMPORTANT): Humidity:17.366621 %
2020-12-01T18:08:32.676Z,1606846112.676 [IBIT](IMPORTANT): Vehicle Pitch:-0.149664 degrees
2020-12-01T18:08:32.676Z,1606846112.676 [IBIT](IMPORTANT): Vehicle  Roll:-0.475641 degrees
2020-12-01T18:08:32.677Z,1606846112.677 [IBIT](IMPORTANT): Vehicle  Heading:30.826044 degrees
2020-12-01T18:08:33.231Z,1606846113.231 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2020-12-01T18:08:33.231Z,1606846113.231 [IBIT](IMPORTANT): buoyancyNeutral: 254.600021 cc
2020-12-01T18:08:33.231Z,1606846113.231 [IBIT](IMPORTANT): massDefault: 0.748000 cm
2020-12-01T18:08:33.231Z,1606846113.231 [IBIT](IMPORTANT): stopDepth: 215.000000 m
2020-12-01T18:08:33.264Z,1606846113.264 [IBIT](IMPORTANT): abortDepth: 255.000000 m
2020-12-01T18:08:33.264Z,1606846113.264 [IBIT](IMPORTANT): IBIT PASSED
2020-12-01T18:08:33.555Z,1606846113.555 [Default:CheckIn:Read_Iridium] Stopped
2020-12-01T18:08:33.568Z,1606846113.568 [Default:CheckIn:C.Wait] Running Loop=1
2020-12-01T18:08:33.568Z,1606846113.568 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-01T18:09:04.605Z,1606846144.605 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-01T18:13:34.156Z,1606846414.156 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-12-01T18:13:34.156Z,1606846414.156 [Default:CheckIn:C.Wait] Stopped
2020-12-01T18:13:34.156Z,1606846414.156 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-01T18:13:34.156Z,1606846414.156 [Default:CheckIn:D] Running Loop=1
2020-12-01T18:13:34.573Z,1606846414.573 [Default:CheckIn:D] Stopped
2020-12-01T18:13:34.573Z,1606846414.573 [Default:CheckIn:E] Running Loop=1
2020-12-01T18:13:34.963Z,1606846414.963 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 16.821779 min 
2020-12-01T18:13:34.964Z,1606846414.964 [Default:CheckIn:E] Stopped
2020-12-01T18:13:34.964Z,1606846414.964 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-12-01T18:13:34.964Z,1606846414.964 [Default:CheckIn] Stopped
2020-12-01T18:13:34.964Z,1606846414.964 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-12-01T18:13:34.964Z,1606846414.964 [Default:CheckIn](INFO): Running loop #3
2020-12-01T18:13:34.964Z,1606846414.964 [Default:CheckIn] Running Loop=3
2020-12-01T18:13:34.964Z,1606846414.964 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-12-01T18:13:34.964Z,1606846414.964 [Default:CheckIn:Read_GPS] Running Loop=1
2020-12-01T18:13:36.981Z,1606846416.981 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181336.00,A,3648.14668,N,12147.25427,W,3.207,163.61,011220,,,D*71
2020-12-01T18:13:36.983Z,1606846416.983 [NAL9602](INFO): GPS fix at 20201201T181336: (36.802445, -121.787571)
2020-12-01T18:13:37.005Z,1606846417.005 [Default:CheckIn:Read_GPS] Stopped
2020-12-01T18:13:37.005Z,1606846417.005 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-12-01T18:13:44.775Z,1606846424.775 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20201201T175250/Courier0019.lzma
2020-12-01T18:13:45.778Z,1606846425.778 [DataOverHttps](INFO): Moved sent file to Logs/20201201T175250/Courier0019.lzma.bak
2020-12-01T18:13:45.778Z,1606846425.778 [DataOverHttps](INFO): SBD MOMSN=13373188
2020-12-01T18:14:01.966Z,1606846441.966 [DataOverHttps](INFO): Sending 956 bytes from file Logs/20201201T175250/Express0020.lzma
2020-12-01T18:14:02.966Z,1606846442.966 [DataOverHttps](INFO): Moved sent file to Logs/20201201T175250/Express0020.lzma.bak
2020-12-01T18:14:02.966Z,1606846442.966 [DataOverHttps](INFO): SBD MOMSN=13373191
2020-12-01T18:14:03.642Z,1606846443.642 [NAL9602](INFO): SBD MO Status=1, MOMSN=11388, MT Status=0, MTMSN=0
2020-12-01T18:14:03.643Z,1606846443.643 [NAL9602](INFO): No messages in MT queue
2020-12-01T18:14:04.066Z,1606846444.066 [Default:CheckIn:Read_Iridium] Stopped
2020-12-01T18:14:04.066Z,1606846444.066 [Default:CheckIn:C.Wait] Running Loop=1
2020-12-01T18:14:04.066Z,1606846444.066 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-01T18:14:34.360Z,1606846474.360 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-01T18:19:04.734Z,1606846744.734 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-12-01T18:19:04.734Z,1606846744.734 [Default:CheckIn:C.Wait] Stopped
2020-12-01T18:19:04.735Z,1606846744.735 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-01T18:19:04.735Z,1606846744.735 [Default:CheckIn:D] Running Loop=1
2020-12-01T18:19:05.136Z,1606846745.136 [Default:CheckIn:D] Stopped
2020-12-01T18:19:05.136Z,1606846745.136 [Default:CheckIn:E] Running Loop=1
2020-12-01T18:19:05.564Z,1606846745.564 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 22.331167 min 
2020-12-01T18:19:05.564Z,1606846745.564 [Default:CheckIn:E] Stopped
2020-12-01T18:19:05.564Z,1606846745.564 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-12-01T18:19:05.564Z,1606846745.564 [Default:CheckIn] Stopped
2020-12-01T18:19:05.564Z,1606846745.564 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-12-01T18:19:05.564Z,1606846745.564 [Default:CheckIn](INFO): Running loop #4
2020-12-01T18:19:05.564Z,1606846745.564 [Default:CheckIn] Running Loop=4
2020-12-01T18:19:05.564Z,1606846745.564 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-12-01T18:19:05.565Z,1606846745.565 [Default:CheckIn:Read_GPS] Running Loop=1
2020-12-01T18:19:07.546Z,1606846747.546 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181906.00,A,3648.13978,N,12147.20726,W,0.097,59.11,011220,,,D*41
2020-12-01T18:19:07.549Z,1606846747.549 [NAL9602](INFO): GPS fix at 20201201T181906: (36.802330, -121.786788)
2020-12-01T18:19:07.570Z,1606846747.570 [Default:CheckIn:Read_GPS] Stopped
2020-12-01T18:19:07.570Z,1606846747.570 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-12-01T18:19:28.950Z,1606846768.950 [NAL9602](INFO): SBD MO Status=1, MOMSN=11389, MT Status=0, MTMSN=0
2020-12-01T18:19:28.951Z,1606846768.951 [NAL9602](INFO): No messages in MT queue
2020-12-01T18:19:59.673Z,1606846799.673 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-01T18:20:39.148Z,1606846839.148 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201201T175250/Courier0022.lzma
2020-12-01T18:20:40.150Z,1606846840.150 [DataOverHttps](INFO): Moved sent file to Logs/20201201T175250/Courier0022.lzma.bak
2020-12-01T18:20:40.150Z,1606846840.150 [DataOverHttps](INFO): SBD MOMSN=13373295
2020-12-01T18:20:56.203Z,1606846856.203 [DataOverHttps](INFO): Sending 127 bytes from file Logs/20201201T175250/Express0023.lzma
2020-12-01T18:20:57.206Z,1606846857.206 [DataOverHttps](INFO): Moved sent file to Logs/20201201T175250/Express0023.lzma.bak
2020-12-01T18:20:57.206Z,1606846857.206 [DataOverHttps](INFO): SBD MOMSN=13373302
2020-12-01T18:20:58.432Z,1606846858.432 [Default:CheckIn:Read_Iridium] Stopped
2020-12-01T18:20:58.432Z,1606846858.432 [Default:CheckIn:C.Wait] Running Loop=1
2020-12-01T18:20:58.432Z,1606846858.432 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-01T18:25:51.533Z,1606847151.533 [DataOverHttps](IMPORTANT): SBD MTMSN=20201201T182550
2020-12-01T18:25:58.984Z,1606847158.984 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-12-01T18:25:58.984Z,1606847158.984 [Default:CheckIn:C.Wait] Stopped
2020-12-01T18:25:58.984Z,1606847158.984 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-01T18:25:58.984Z,1606847158.984 [Default:CheckIn:D] Running Loop=1
2020-12-01T18:25:59.338Z,1606847159.338 [DataOverHttps](INFO): Received command:strobe off
2020-12-01T18:25:59.373Z,1606847159.373 [CommandLine](IMPORTANT): got command strobe off
2020-12-01T18:25:59.373Z,1606847159.373 [CommandLine](IMPORTANT): Deactivating strobe
2020-12-01T18:25:59.434Z,1606847159.434 [Default:CheckIn:D] Stopped
2020-12-01T18:25:59.434Z,1606847159.434 [Default:CheckIn:E] Running Loop=1
2020-12-01T18:25:59.792Z,1606847159.792 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 29.236131 min 
2020-12-01T18:25:59.792Z,1606847159.792 [Default:CheckIn:E] Stopped
2020-12-01T18:25:59.793Z,1606847159.793 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-12-01T18:25:59.793Z,1606847159.793 [Default:CheckIn] Stopped
2020-12-01T18:25:59.793Z,1606847159.793 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-12-01T18:25:59.793Z,1606847159.793 [Default:CheckIn](INFO): Running loop #5
2020-12-01T18:25:59.793Z,1606847159.793 [Default:CheckIn] Running Loop=5
2020-12-01T18:25:59.793Z,1606847159.793 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-12-01T18:25:59.793Z,1606847159.793 [Default:CheckIn:Read_GPS] Running Loop=1
2020-12-01T18:26:01.799Z,1606847161.799 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182601.00,A,3648.13777,N,12147.20645,W,0.097,136.56,011220,,,D*74
2020-12-01T18:26:01.802Z,1606847161.802 [NAL9602](INFO): GPS fix at 20201201T182601: (36.802296, -121.786774)
2020-12-01T18:26:01.823Z,1606847161.823 [Default:CheckIn:Read_GPS] Stopped
2020-12-01T18:26:01.823Z,1606847161.823 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-12-01T18:26:08.979Z,1606847168.979 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201201T175250/Courier0025.lzma
2020-12-01T18:26:09.982Z,1606847169.982 [DataOverHttps](INFO): Moved sent file to Logs/20201201T175250/Courier0025.lzma.bak
2020-12-01T18:26:09.982Z,1606847169.982 [DataOverHttps](INFO): SBD MOMSN=13373312
2020-12-01T18:26:14.332Z,1606847174.332 [NAL9602](INFO): SBD MO Status=1, MOMSN=11390, MT Status=0, MTMSN=0
2020-12-01T18:26:14.332Z,1606847174.332 [NAL9602](INFO): No messages in MT queue
2020-12-01T18:26:29.416Z,1606847189.416 [DataOverHttps](INFO): Sending 212 bytes from file Logs/20201201T175250/Express0026.lzma
2020-12-01T18:26:30.420Z,1606847190.420 [DataOverHttps](INFO): Moved sent file to Logs/20201201T175250/Express0026.lzma.bak
2020-12-01T18:26:30.420Z,1606847190.420 [DataOverHttps](INFO): SBD MOMSN=13373322
2020-12-01T18:26:31.470Z,1606847191.470 [Default:CheckIn:Read_Iridium] Stopped
2020-12-01T18:26:31.470Z,1606847191.470 [Default:CheckIn:C.Wait] Running Loop=1
2020-12-01T18:26:31.470Z,1606847191.470 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-01T18:26:44.741Z,1606847204.741 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-01T18:31:32.063Z,1606847492.063 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-12-01T18:31:32.063Z,1606847492.063 [Default:CheckIn:C.Wait] Stopped
2020-12-01T18:31:32.063Z,1606847492.063 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-01T18:31:32.063Z,1606847492.063 [Default:CheckIn:D] Running Loop=1
2020-12-01T18:31:32.480Z,1606847492.480 [Default:CheckIn:D] Stopped
2020-12-01T18:31:32.480Z,1606847492.480 [Default:CheckIn:E] Running Loop=1
2020-12-01T18:31:32.883Z,1606847492.883 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 34.786902 min 
2020-12-01T18:31:32.883Z,1606847492.883 [Default:CheckIn:E] Stopped
2020-12-01T18:31:32.884Z,1606847492.884 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-12-01T18:31:32.884Z,1606847492.884 [Default:CheckIn] Stopped
2020-12-01T18:31:32.884Z,1606847492.884 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-12-01T18:31:32.884Z,1606847492.884 [Default:CheckIn](INFO): Running loop #6
2020-12-01T18:31:32.884Z,1606847492.884 [Default:CheckIn] Running Loop=6
2020-12-01T18:31:32.884Z,1606847492.884 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-12-01T18:31:32.884Z,1606847492.884 [Default:CheckIn:Read_GPS] Running Loop=1
2020-12-01T18:31:34.876Z,1606847494.876 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183134.00,A,3648.13870,N,12147.20709,W,0.058,150.50,011220,,,A*75
2020-12-01T18:31:34.878Z,1606847494.878 [NAL9602](INFO): GPS fix at 20201201T183134: (36.802312, -121.786785)
2020-12-01T18:31:34.919Z,1606847494.919 [Default:CheckIn:Read_GPS] Stopped
2020-12-01T18:31:34.919Z,1606847494.919 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-12-01T18:31:50.230Z,1606847510.230 [NAL9602](INFO): SBD MO Status=1, MOMSN=11391, MT Status=0, MTMSN=0
2020-12-01T18:31:50.231Z,1606847510.231 [NAL9602](INFO): No messages in MT queue
2020-12-01T18:32:20.937Z,1606847540.937 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-01T18:33:31.509Z,1606847611.509 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20201201T175250/Courier0028.lzma
2020-12-01T18:33:32.506Z,1606847612.506 [DataOverHttps](INFO): Moved sent file to Logs/20201201T175250/Courier0028.lzma.bak
2020-12-01T18:33:32.506Z,1606847612.506 [DataOverHttps](INFO): SBD MOMSN=13373380
2020-12-01T18:33:48.675Z,1606847628.675 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20201201T175250/Express0030.lzma
2020-12-01T18:33:49.678Z,1606847629.678 [DataOverHttps](INFO): Moved sent file to Logs/20201201T175250/Express0030.lzma.bak
2020-12-01T18:33:49.678Z,1606847629.678 [DataOverHttps](INFO): SBD MOMSN=13373387
2020-12-01T18:33:50.739Z,1606847630.739 [Default:CheckIn:Read_Iridium] Stopped
2020-12-01T18:33:50.739Z,1606847630.739 [Default:CheckIn:C.Wait] Running Loop=1
2020-12-01T18:33:50.739Z,1606847630.739 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-01T18:38:51.454Z,1606847931.454 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-12-01T18:38:51.454Z,1606847931.454 [Default:CheckIn:C.Wait] Stopped
2020-12-01T18:38:51.454Z,1606847931.454 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-01T18:38:51.454Z,1606847931.454 [Default:CheckIn:D] Running Loop=1
2020-12-01T18:38:51.835Z,1606847931.835 [Default:CheckIn:D] Stopped
2020-12-01T18:38:51.835Z,1606847931.835 [Default:CheckIn:E] Running Loop=1
2020-12-01T18:38:52.243Z,1606847932.243 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.109481 min 
2020-12-01T18:38:52.243Z,1606847932.243 [Default:CheckIn:E] Stopped
2020-12-01T18:38:52.243Z,1606847932.243 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-12-01T18:38:52.244Z,1606847932.244 [Default:CheckIn] Stopped
2020-12-01T18:38:52.244Z,1606847932.244 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-12-01T18:38:52.244Z,1606847932.244 [Default:CheckIn](INFO): Running loop #7
2020-12-01T18:38:52.244Z,1606847932.244 [Default:CheckIn] Running Loop=7
2020-12-01T18:38:52.244Z,1606847932.244 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-12-01T18:38:52.244Z,1606847932.244 [Default:CheckIn:Read_GPS] Running Loop=1
2020-12-01T18:38:54.246Z,1606847934.246 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183853.00,A,3648.13805,N,12147.20597,W,0.000,38.41,011220,,,A*48
2020-12-01T18:38:54.248Z,1606847934.248 [NAL9602](INFO): GPS fix at 20201201T183853: (36.802301, -121.786766)
2020-12-01T18:38:54.259Z,1606847934.259 [Default:CheckIn:Read_GPS] Stopped
2020-12-01T18:38:54.259Z,1606847934.259 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-12-01T18:39:01.495Z,1606847941.495 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20201201T175250/Courier0034.lzma
2020-12-01T18:39:02.498Z,1606847942.498 [DataOverHttps](INFO): Moved sent file to Logs/20201201T175250/Courier0034.lzma.bak
2020-12-01T18:39:02.498Z,1606847942.498 [DataOverHttps](INFO): SBD MOMSN=13373395
2020-12-01T18:39:06.367Z,1606847946.367 [NAL9602](INFO): SBD MO Status=1, MOMSN=11392, MT Status=0, MTMSN=0
2020-12-01T18:39:06.367Z,1606847946.367 [NAL9602](INFO): No messages in MT queue
2020-12-01T18:39:18.848Z,1606847958.848 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20201201T175250/Express0035.lzma
2020-12-01T18:39:19.851Z,1606847959.851 [DataOverHttps](INFO): Moved sent file to Logs/20201201T175250/Express0035.lzma.bak
2020-12-01T18:39:19.851Z,1606847959.851 [DataOverHttps](INFO): SBD MOMSN=13373403
2020-12-01T18:39:20.917Z,1606847960.917 [Default:CheckIn:Read_Iridium] Stopped
2020-12-01T18:39:20.917Z,1606847960.917 [Default:CheckIn:C.Wait] Running Loop=1
2020-12-01T18:39:20.917Z,1606847960.917 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-01T18:39:37.073Z,1606847977.073 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-01T18:44:21.615Z,1606848261.615 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-12-01T18:44:21.615Z,1606848261.615 [Default:CheckIn:C.Wait] Stopped
2020-12-01T18:44:21.615Z,1606848261.615 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-01T18:44:21.615Z,1606848261.615 [Default:CheckIn:D] Running Loop=1
2020-12-01T18:44:21.991Z,1606848261.991 [Default:CheckIn:D] Stopped
2020-12-01T18:44:21.992Z,1606848261.992 [Default:CheckIn:E] Running Loop=1
2020-12-01T18:44:22.419Z,1606848262.419 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 47.612089 min 
2020-12-01T18:44:22.419Z,1606848262.419 [Default:CheckIn:E] Stopped
2020-12-01T18:44:22.419Z,1606848262.419 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-12-01T18:44:22.419Z,1606848262.419 [Default:CheckIn] Stopped
2020-12-01T18:44:22.419Z,1606848262.419 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-12-01T18:44:22.419Z,1606848262.419 [Default:CheckIn](INFO): Running loop #8
2020-12-01T18:44:22.419Z,1606848262.419 [Default:CheckIn] Running Loop=8
2020-12-01T18:44:22.419Z,1606848262.419 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-12-01T18:44:22.444Z,1606848262.444 [Default:CheckIn:Read_GPS] Running Loop=1
2020-12-01T18:44:24.379Z,1606848264.379 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184423.00,A,3648.25834,N,12147.12827,W,3.168,4.70,011220,,,A*75
2020-12-01T18:44:24.381Z,1606848264.381 [NAL9602](INFO): GPS fix at 20201201T184423: (36.804306, -121.785471)
2020-12-01T18:44:24.452Z,1606848264.452 [Default:CheckIn:Read_GPS] Stopped
2020-12-01T18:44:24.452Z,1606848264.452 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-12-01T18:44:25.240Z,1606848265.240 [Default:CheckIn:Read_Iridium] Stopped
2020-12-01T18:44:25.241Z,1606848265.241 [Default:CheckIn:C.Wait] Running Loop=1
2020-12-01T18:44:25.242Z,1606848265.242 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-01T18:44:38.598Z,1606848278.598 [NAL9602](INFO): SBD MO Status=1, MOMSN=11393, MT Status=0, MTMSN=0
2020-12-01T18:44:38.598Z,1606848278.598 [NAL9602](INFO): No messages in MT queue
2020-12-01T18:45:09.307Z,1606848309.307 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-01T18:49:25.886Z,1606848565.886 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-12-01T18:49:25.886Z,1606848565.886 [Default:CheckIn:C.Wait] Stopped
2020-12-01T18:49:25.886Z,1606848565.886 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-01T18:49:25.886Z,1606848565.886 [Default:CheckIn:D] Running Loop=1
2020-12-01T18:49:26.267Z,1606848566.267 [Default:CheckIn:D] Stopped
2020-12-01T18:49:26.267Z,1606848566.267 [Default:CheckIn:E] Running Loop=1
2020-12-01T18:49:26.675Z,1606848566.675 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.683350 min 
2020-12-01T18:49:26.675Z,1606848566.675 [Default:CheckIn:E] Stopped
2020-12-01T18:49:26.675Z,1606848566.675 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-12-01T18:49:26.675Z,1606848566.675 [Default:CheckIn] Stopped
2020-12-01T18:49:26.675Z,1606848566.675 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-12-01T18:49:26.675Z,1606848566.675 [Default:CheckIn](INFO): Running loop #9
2020-12-01T18:49:26.675Z,1606848566.675 [Default:CheckIn] Running Loop=9
2020-12-01T18:49:26.675Z,1606848566.675 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-12-01T18:49:26.676Z,1606848566.676 [Default:CheckIn:Read_GPS] Running Loop=1
2020-12-01T18:49:28.683Z,1606848568.683 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184927.00,A,3648.49482,N,12147.22949,W,7.775,239.22,011220,,,D*7D
2020-12-01T18:49:28.694Z,1606848568.694 [NAL9602](INFO): GPS fix at 20201201T184927: (36.808247, -121.787158)
2020-12-01T18:49:28.717Z,1606848568.717 [Default:CheckIn:Read_GPS] Stopped
2020-12-01T18:49:28.717Z,1606848568.717 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-12-01T18:49:36.527Z,1606848576.527 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201201T175250/Courier0037.lzma
2020-12-01T18:49:37.530Z,1606848577.530 [DataOverHttps](INFO): Moved sent file to Logs/20201201T175250/Courier0037.lzma.bak
2020-12-01T18:49:37.530Z,1606848577.530 [DataOverHttps](INFO): SBD MOMSN=13373476
2020-12-01T18:49:42.454Z,1606848582.454 [NAL9602](INFO): SBD MO Status=1, MOMSN=11394, MT Status=0, MTMSN=0
2020-12-01T18:49:42.454Z,1606848582.454 [NAL9602](INFO): No messages in MT queue
2020-12-01T18:49:53.715Z,1606848593.715 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201201T175250/Courier0040.lzma
2020-12-01T18:49:54.718Z,1606848594.718 [DataOverHttps](INFO): Moved sent file to Logs/20201201T175250/Courier0040.lzma.bak
2020-12-01T18:49:54.718Z,1606848594.718 [DataOverHttps](INFO): SBD MOMSN=13373479
2020-12-01T18:50:10.975Z,1606848610.975 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20201201T175250/Express0038.lzma
2020-12-01T18:50:11.978Z,1606848611.978 [DataOverHttps](INFO): Moved sent file to Logs/20201201T175250/Express0038.lzma.bak
2020-12-01T18:50:11.978Z,1606848611.978 [DataOverHttps](INFO): SBD MOMSN=13373482
2020-12-01T18:50:13.123Z,1606848613.123 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-01T18:50:32.832Z,1606848632.832 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20201201T175250/Express0041.lzma
2020-12-01T18:50:33.834Z,1606848633.834 [DataOverHttps](INFO): Moved sent file to Logs/20201201T175250/Express0041.lzma.bak
2020-12-01T18:50:33.834Z,1606848633.834 [DataOverHttps](INFO): SBD MOMSN=13373487
2020-12-01T18:50:35.036Z,1606848635.036 [Default:CheckIn:Read_Iridium] Stopped
2020-12-01T18:50:35.036Z,1606848635.036 [Default:CheckIn:C.Wait] Running Loop=1
2020-12-01T18:50:35.036Z,1606848635.036 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-01T18:55:35.697Z,1606848935.697 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-12-01T18:55:35.698Z,1606848935.698 [Default:CheckIn:C.Wait] Stopped
2020-12-01T18:55:35.698Z,1606848935.698 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-01T18:55:35.698Z,1606848935.698 [Default:CheckIn:D] Running Loop=1
2020-12-01T18:55:36.119Z,1606848936.119 [Default:CheckIn:D] Stopped
2020-12-01T18:55:36.119Z,1606848936.119 [Default:CheckIn:E] Running Loop=1
2020-12-01T18:55:36.529Z,1606848936.529 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 58.847542 min 
2020-12-01T18:55:36.529Z,1606848936.529 [Default:CheckIn:E] Stopped
2020-12-01T18:55:36.529Z,1606848936.529 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-12-01T18:55:36.529Z,1606848936.529 [Default:CheckIn] Stopped
2020-12-01T18:55:36.529Z,1606848936.529 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-12-01T18:55:36.529Z,1606848936.529 [Default:CheckIn](INFO): Running loop #10
2020-12-01T18:55:36.530Z,1606848936.530 [Default:CheckIn] Running Loop=10
2020-12-01T18:55:36.530Z,1606848936.530 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-12-01T18:55:36.530Z,1606848936.530 [Default:CheckIn:Read_GPS] Running Loop=1
2020-12-01T18:55:38.523Z,1606848938.523 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185537.00,A,3647.92262,N,12149.17967,W,18.233,257.54,011220,,,D*44
2020-12-01T18:55:38.525Z,1606848938.525 [NAL9602](INFO): GPS fix at 20201201T185537: (36.798710, -121.819661)
2020-12-01T18:55:38.586Z,1606848938.586 [Default:CheckIn:Read_GPS] Stopped
2020-12-01T18:55:38.586Z,1606848938.586 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-12-01T18:55:51.446Z,1606848951.446 [NAL9602](INFO): SBD MO Status=2, MOMSN=11395, MT Status=2, MTMSN=0
2020-12-01T18:55:51.447Z,1606848951.447 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-12-01T18:56:45.867Z,1606849005.867 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201201T175250/Courier0043.lzma
2020-12-01T18:56:46.870Z,1606849006.870 [DataOverHttps](INFO): Moved sent file to Logs/20201201T175250/Courier0043.lzma.bak
2020-12-01T18:56:46.870Z,1606849006.870 [DataOverHttps](INFO): SBD MOMSN=13373514
2020-12-01T18:56:50.958Z,1606849010.958 [NAL9602](INFO): SBD MO Status=1, MOMSN=11395, MT Status=0, MTMSN=0
2020-12-01T18:56:50.959Z,1606849010.959 [NAL9602](INFO): No messages in MT queue
2020-12-01T18:57:21.666Z,1606849041.666 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-01T18:59:23.903Z,1606849163.903 [DataOverHttps](INFO): Sending 146 bytes from file Logs/20201201T175250/Express0044.lzma
2020-12-01T18:59:24.906Z,1606849164.906 [DataOverHttps](INFO): Moved sent file to Logs/20201201T175250/Express0044.lzma.bak
2020-12-01T18:59:24.906Z,1606849164.906 [DataOverHttps](INFO): SBD MOMSN=13373562
2020-12-01T18:59:26.258Z,1606849166.258 [Default:CheckIn:Read_Iridium] Stopped
2020-12-01T18:59:26.258Z,1606849166.258 [Default:CheckIn:C.Wait] Running Loop=1
2020-12-01T18:59:26.258Z,1606849166.258 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-01T19:01:45.428Z,1606849305.428 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.267208
2020-12-01T19:02:53.708Z,1606849373.708 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-12-01T19:03:28.877Z,1606849408.877 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-12-01T19:04:04.020Z,1606849444.020 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-12-01T19:04:26.808Z,1606849466.808 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-12-01T19:04:26.808Z,1606849466.808 [Default:CheckIn:C.Wait] Stopped
2020-12-01T19:04:26.808Z,1606849466.808 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-01T19:04:26.808Z,1606849466.808 [Default:CheckIn:D] Running Loop=1
2020-12-01T19:04:27.212Z,1606849467.212 [Default:CheckIn:D] Stopped
2020-12-01T19:04:27.212Z,1606849467.212 [Default:CheckIn:E] Running Loop=1
2020-12-01T19:04:27.632Z,1606849467.632 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 67.699105 min 
2020-12-01T19:04:27.632Z,1606849467.632 [Default:CheckIn:E] Stopped
2020-12-01T19:04:27.632Z,1606849467.632 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-12-01T19:04:27.632Z,1606849467.632 [Default:CheckIn] Stopped
2020-12-01T19:04:27.632Z,1606849467.632 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-12-01T19:04:27.633Z,1606849467.633 [Default:CheckIn](INFO): Running loop #11
2020-12-01T19:04:27.633Z,1606849467.633 [Default:CheckIn] Running Loop=11
2020-12-01T19:04:27.633Z,1606849467.633 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-12-01T19:04:27.633Z,1606849467.633 [Default:CheckIn:Read_GPS] Running Loop=1
2020-12-01T19:04:29.621Z,1606849469.621 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190428.00,A,3647.73150,N,12150.15372,W,0.641,189.36,011220,,,A*7F
2020-12-01T19:04:29.623Z,1606849469.623 [NAL9602](INFO): GPS fix at 20201201T190428: (36.795525, -121.835895)
2020-12-01T19:04:29.660Z,1606849469.660 [Default:CheckIn:Read_GPS] Stopped
2020-12-01T19:04:29.660Z,1606849469.660 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-12-01T19:04:39.168Z,1606849479.168 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-12-01T19:04:48.284Z,1606849488.284 [NAL9602](INFO): SBD MO Status=1, MOMSN=11396, MT Status=0, MTMSN=0
2020-12-01T19:04:48.333Z,1606849488.333 [NAL9602](INFO): Sent 72 bytes from file Logs/20201201T175250/Courier0046.lzma
2020-12-01T19:04:48.334Z,1606849488.334 [NAL9602](INFO): Packets left to send: 0
2020-12-01T19:04:54.030Z,1606849494.030 [NAL9602](INFO): SBD MO Status=1, MOMSN=11397, MT Status=0, MTMSN=0
2020-12-01T19:04:54.081Z,1606849494.081 [NAL9602](INFO): Sent 140 bytes from file Logs/20201201T175250/Express0047.lzma
2020-12-01T19:04:54.081Z,1606849494.081 [NAL9602](INFO): Packets left to send: 0
2020-12-01T19:05:02.918Z,1606849502.918 [NAL9602](INFO): SBD MO Status=0, MOMSN=11398, MT Status=0, MTMSN=0
2020-12-01T19:05:02.996Z,1606849502.996 [Default:CheckIn:Read_Iridium] Stopped
2020-12-01T19:05:02.997Z,1606849502.997 [Default:CheckIn:C.Wait] Running Loop=1
2020-12-01T19:05:02.997Z,1606849502.997 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-01T19:05:14.324Z,1606849514.324 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-12-01T19:05:33.621Z,1606849533.621 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-01T19:05:49.465Z,1606849549.465 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-12-01T19:06:24.604Z,1606849584.604 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-12-01T19:06:59.744Z,1606849619.744 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-12-01T19:07:34.889Z,1606849654.889 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-12-01T19:07:49.046Z,1606849669.046 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-12-01T19:07:55.459Z,1606849675.459 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-12-01T19:07:55.461Z,1606849675.461 [BPC1](INFO): Received data from all battery sticks.
2020-12-01T19:08:07.444Z,1606849687.444 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003248
2020-12-01T19:10:03.572Z,1606849803.572 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-12-01T19:10:03.572Z,1606849803.572 [Default:CheckIn:C.Wait] Stopped
2020-12-01T19:10:03.572Z,1606849803.572 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-01T19:10:03.572Z,1606849803.572 [Default:CheckIn:D] Running Loop=1
2020-12-01T19:10:03.971Z,1606849803.971 [Default:CheckIn:D] Stopped
2020-12-01T19:10:03.971Z,1606849803.971 [Default:CheckIn:E] Running Loop=1
2020-12-01T19:10:04.372Z,1606849804.372 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 73.311751 min 
2020-12-01T19:10:04.372Z,1606849804.372 [Default:CheckIn:E] Stopped
2020-12-01T19:10:04.373Z,1606849804.373 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-12-01T19:10:04.373Z,1606849804.373 [Default:CheckIn] Stopped
2020-12-01T19:10:04.373Z,1606849804.373 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-12-01T19:10:04.373Z,1606849804.373 [Default:CheckIn](INFO): Running loop #12
2020-12-01T19:10:04.373Z,1606849804.373 [Default:CheckIn] Running Loop=12
2020-12-01T19:10:04.373Z,1606849804.373 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-12-01T19:10:04.373Z,1606849804.373 [Default:CheckIn:Read_GPS] Running Loop=1
2020-12-01T19:10:06.388Z,1606849806.388 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191005.00,A,3647.67704,N,12150.13489,W,0.641,174.67,011220,,,A*74
2020-12-01T19:10:06.390Z,1606849806.390 [NAL9602](INFO): GPS fix at 20201201T191005: (36.794617, -121.835582)
2020-12-01T19:10:06.465Z,1606849806.465 [Default:CheckIn:Read_GPS] Stopped
2020-12-01T19:10:06.465Z,1606849806.465 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-12-01T19:10:09.815Z,1606849809.815 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20201201T175250/Courier0049.lzma
2020-12-01T19:10:10.818Z,1606849810.818 [DataOverHttps](INFO): Moved sent file to Logs/20201201T175250/Courier0049.lzma.bak
2020-12-01T19:10:10.818Z,1606849810.818 [DataOverHttps](INFO): SBD MOMSN=13373612
2020-12-01T19:11:24.898Z,1606849884.898 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=11399, MT Status=1, MTMSN=632
2020-12-01T19:11:24.898Z,1606849884.898 [NAL9602](INFO): Data available in MT queue