2021-11-10T16:36:54.182Z,1636562214.182 [Supervisor](DEBUG): Initializing supervisor.
2021-11-10T16:36:54.186Z,1636562214.186 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2021-11-10T16:36:54.186Z,1636562214.186 [SyncHandler](INFO): Protected caller Thread ID is 837
2021-11-10T16:36:54.187Z,1636562214.187 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2021-11-10T16:36:54.188Z,1636562214.188 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2021-11-10T16:36:54.188Z,1636562214.188 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 838
2021-11-10T16:36:54.192Z,1636562214.192 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2021-11-10T16:36:54.210Z,1636562214.210 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2021-11-10T16:36:54.211Z,1636562214.211 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2021-11-10T16:36:54.212Z,1636562214.212 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 839
2021-11-10T16:36:54.216Z,1636562214.216 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2021-11-10T16:36:54.217Z,1636562214.217 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2021-11-10T16:36:54.217Z,1636562214.217 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 840
2021-11-10T16:36:54.219Z,1636562214.219 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2021-11-10T16:36:54.220Z,1636562214.220 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2021-11-10T16:36:54.221Z,1636562214.221 [logger ThreadHandler](INFO): Protected caller Thread ID is 841
2021-11-10T16:36:54.225Z,1636562214.225 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2021-11-10T16:36:54.225Z,1636562214.225 [Supervisor](INFO): Looking for Config files in directory: Config/
2021-11-10T16:36:54.228Z,1636562214.228 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2021-11-10T16:36:54.324Z,1636562214.324 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2021-11-10T16:36:54.326Z,1636562214.326 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2021-11-10T16:36:54.920Z,1636562214.920 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2021-11-10T16:36:54.921Z,1636562214.921 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2021-11-10T16:36:55.132Z,1636562215.132 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2021-11-10T16:36:55.134Z,1636562215.134 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2021-11-10T16:36:55.213Z,1636562215.213 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2021-11-10T16:36:55.334Z,1636562215.334 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2021-11-10T16:36:55.335Z,1636562215.335 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2021-11-10T16:36:55.417Z,1636562215.417 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2021-11-10T16:36:55.524Z,1636562215.524 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2021-11-10T16:36:55.525Z,1636562215.525 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2021-11-10T16:36:55.896Z,1636562215.896 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2021-11-10T16:36:55.897Z,1636562215.897 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2021-11-10T16:36:56.411Z,1636562216.411 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2021-11-10T16:36:56.413Z,1636562216.413 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2021-11-10T16:36:56.562Z,1636562216.562 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2021-11-10T16:36:56.562Z,1636562216.562 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2021-11-10T16:36:56.750Z,1636562216.750 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2021-11-10T16:36:56.750Z,1636562216.750 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2021-11-10T16:36:57.208Z,1636562217.208 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2021-11-10T16:36:57.210Z,1636562217.210 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2021-11-10T16:36:57.445Z,1636562217.445 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2021-11-10T16:36:57.447Z,1636562217.447 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2021-11-10T16:36:57.651Z,1636562217.651 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2021-11-10T16:36:57.652Z,1636562217.652 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2021-11-10T16:36:58.885Z,1636562218.885 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2021-11-10T16:36:58.887Z,1636562218.887 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2021-11-10T16:36:59.237Z,1636562219.237 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2021-11-10T16:36:59.239Z,1636562219.239 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2021-11-10T16:36:59.241Z,1636562219.241 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2021-11-10T16:36:59.325Z,1636562219.325 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2021-11-10T16:36:59.475Z,1636562219.475 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2021-11-10T16:36:59.583Z,1636562219.583 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2021-11-10T16:36:59.668Z,1636562219.668 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2021-11-10T16:36:59.762Z,1636562219.762 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2021-11-10T16:36:59.945Z,1636562219.945 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2021-11-10T16:37:00.203Z,1636562220.203 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2021-11-10T16:37:00.203Z,1636562220.203 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2021-11-10T16:37:00.297Z,1636562220.297 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2021-11-10T16:37:00.395Z,1636562220.395 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2021-11-10T16:37:00.532Z,1636562220.532 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2021-11-10T16:37:00.631Z,1636562220.631 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2021-11-10T16:37:00.635Z,1636562220.635 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2021-11-10T16:37:00.756Z,1636562220.756 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2021-11-10T16:37:00.758Z,1636562220.758 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2021-11-10T16:37:00.829Z,1636562220.829 [VerticalControl](DEBUG): Construct VerticalControl.
2021-11-10T16:37:00.892Z,1636562220.892 [VerticalControl] Loaded
2021-11-10T16:37:00.892Z,1636562220.892 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2021-11-10T16:37:00.895Z,1636562220.895 [HorizontalControl](DEBUG): Construct HorizontalControl.
2021-11-10T16:37:00.935Z,1636562220.935 [HorizontalControl] Loaded
2021-11-10T16:37:00.935Z,1636562220.935 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2021-11-10T16:37:00.937Z,1636562220.937 [SpeedControl](DEBUG): Construct SpeedControl.
2021-11-10T16:37:00.941Z,1636562220.941 [SpeedControl] Loaded
2021-11-10T16:37:00.941Z,1636562220.941 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2021-11-10T16:37:00.943Z,1636562220.943 [LoopControl](DEBUG): Construct LoopControl.
2021-11-10T16:37:00.944Z,1636562220.944 [LoopControl] Loaded
2021-11-10T16:37:00.944Z,1636562220.944 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2021-11-10T16:37:00.945Z,1636562220.945 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2021-11-10T16:37:00.945Z,1636562220.945 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2021-11-10T16:37:01.005Z,1636562221.005 [DepthRateCalculator] Loaded
2021-11-10T16:37:01.005Z,1636562221.005 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2021-11-10T16:37:01.010Z,1636562221.010 [PitchRateCalculator] Loaded
2021-11-10T16:37:01.010Z,1636562221.010 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2021-11-10T16:37:01.021Z,1636562221.021 [SpeedCalculator] Loaded
2021-11-10T16:37:01.021Z,1636562221.021 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2021-11-10T16:37:01.035Z,1636562221.035 [TempGradientCalculator] Loaded
2021-11-10T16:37:01.035Z,1636562221.035 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2021-11-10T16:37:01.039Z,1636562221.039 [YawRateCalculator] Loaded
2021-11-10T16:37:01.039Z,1636562221.039 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2021-11-10T16:37:01.057Z,1636562221.057 [ElevatorOffsetCalculator] Loaded
2021-11-10T16:37:01.057Z,1636562221.057 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2021-11-10T16:37:01.058Z,1636562221.058 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2021-11-10T16:37:01.060Z,1636562221.060 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2021-11-10T16:37:01.102Z,1636562221.102 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2021-11-10T16:37:01.103Z,1636562221.103 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2021-11-10T16:37:01.185Z,1636562221.185 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2021-11-10T16:37:01.185Z,1636562221.185 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2021-11-10T16:37:01.559Z,1636562221.559 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2021-11-10T16:37:01.559Z,1636562221.559 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2021-11-10T16:37:01.691Z,1636562221.691 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2021-11-10T16:37:01.693Z,1636562221.693 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2021-11-10T16:37:02.402Z,1636562222.402 [AHRS_M2] Loaded
2021-11-10T16:37:02.402Z,1636562222.402 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2021-11-10T16:37:02.649Z,1636562222.649 [BackseatComponent] Loaded
2021-11-10T16:37:02.650Z,1636562222.650 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2021-11-10T16:37:02.651Z,1636562222.651 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409444E0
2021-11-10T16:37:02.651Z,1636562222.651 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 923
2021-11-10T16:37:02.654Z,1636562222.654 [LcmUniversalReporter] Loaded
2021-11-10T16:37:02.654Z,1636562222.654 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2021-11-10T16:37:03.430Z,1636562223.430 [BPC1] Loaded
2021-11-10T16:37:03.430Z,1636562223.430 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2021-11-10T16:37:03.573Z,1636562223.573 [DataOverHttps] Loaded
2021-11-10T16:37:03.573Z,1636562223.573 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2021-11-10T16:37:03.574Z,1636562223.574 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409744E0
2021-11-10T16:37:03.574Z,1636562223.574 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 924
2021-11-10T16:37:03.594Z,1636562223.594 [Depth_Keller] Loaded
2021-11-10T16:37:03.594Z,1636562223.594 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2021-11-10T16:37:03.599Z,1636562223.599 [DropWeight] Loaded
2021-11-10T16:37:03.599Z,1636562223.599 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2021-11-10T16:37:03.658Z,1636562223.658 [NAL9602] Loaded
2021-11-10T16:37:03.658Z,1636562223.658 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2021-11-10T16:37:03.687Z,1636562223.687 [Onboard] Loaded
2021-11-10T16:37:03.687Z,1636562223.687 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2021-11-10T16:37:03.689Z,1636562223.689 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409A44E0
2021-11-10T16:37:03.689Z,1636562223.689 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 925
2021-11-10T16:37:03.702Z,1636562223.702 [Power24vConverter] Loaded
2021-11-10T16:37:03.703Z,1636562223.703 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2021-11-10T16:37:03.716Z,1636562223.716 [Radio_Surface] Loaded
2021-11-10T16:37:03.716Z,1636562223.716 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2021-11-10T16:37:03.717Z,1636562223.717 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409D44E0
2021-11-10T16:37:03.718Z,1636562223.718 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 926
2021-11-10T16:37:03.760Z,1636562223.760 [RDI_Pathfinder] Loaded
2021-11-10T16:37:03.760Z,1636562223.760 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2021-11-10T16:37:03.841Z,1636562223.841 [DAT] Loaded
2021-11-10T16:37:03.841Z,1636562223.841 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2021-11-10T16:37:03.842Z,1636562223.842 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2021-11-10T16:37:03.843Z,1636562223.843 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2021-11-10T16:37:04.165Z,1636562224.165 [DeadReckonUsingMultipleVelocitySources] Loaded
2021-11-10T16:37:04.165Z,1636562224.165 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2021-11-10T16:37:04.192Z,1636562224.192 [NavChart] Loaded
2021-11-10T16:37:04.192Z,1636562224.192 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2021-11-10T16:37:04.198Z,1636562224.198 [UniversalFixResidualReporter] Loaded
2021-11-10T16:37:04.198Z,1636562224.198 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2021-11-10T16:37:04.199Z,1636562224.199 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2021-11-10T16:37:04.200Z,1636562224.200 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2021-11-10T16:37:04.628Z,1636562224.628 [SBIT](DEBUG): Construct Startup Built In Test.
2021-11-10T16:37:04.638Z,1636562224.638 [SBIT] Loaded
2021-11-10T16:37:04.638Z,1636562224.638 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2021-11-10T16:37:04.641Z,1636562224.641 [IBIT](DEBUG): Construct Initiated Built In Test.
2021-11-10T16:37:04.654Z,1636562224.654 [IBIT] Loaded
2021-11-10T16:37:04.655Z,1636562224.655 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2021-11-10T16:37:04.661Z,1636562224.661 [CBIT](DEBUG): Construct Continuous Built In Test.
2021-11-10T16:37:04.761Z,1636562224.761 [CBIT] Loaded
2021-11-10T16:37:04.762Z,1636562224.762 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2021-11-10T16:37:04.762Z,1636562224.762 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2021-11-10T16:37:04.763Z,1636562224.763 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2021-11-10T16:37:04.918Z,1636562224.918 [BuoyancyServo] Loaded
2021-11-10T16:37:04.919Z,1636562224.919 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2021-11-10T16:37:04.939Z,1636562224.939 [ElevatorServo] Loaded
2021-11-10T16:37:04.939Z,1636562224.939 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2021-11-10T16:37:04.958Z,1636562224.958 [MassServo] Loaded
2021-11-10T16:37:04.958Z,1636562224.958 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2021-11-10T16:37:04.977Z,1636562224.977 [RudderServo] Loaded
2021-11-10T16:37:04.977Z,1636562224.977 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2021-11-10T16:37:04.996Z,1636562224.996 [ThrusterServo] Loaded
2021-11-10T16:37:04.996Z,1636562224.996 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2021-11-10T16:37:04.996Z,1636562224.996 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2021-11-10T16:37:04.997Z,1636562224.997 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2021-11-10T16:37:05.013Z,1636562225.013 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2021-11-10T16:37:05.014Z,1636562225.014 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2021-11-10T16:37:05.234Z,1636562225.234 [CTD_Seabird] Loaded
2021-11-10T16:37:05.234Z,1636562225.234 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2021-11-10T16:37:05.235Z,1636562225.235 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B0D4E0
2021-11-10T16:37:05.236Z,1636562225.236 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 927
2021-11-10T16:37:05.256Z,1636562225.256 [PAR_Licor] Loaded
2021-11-10T16:37:05.256Z,1636562225.256 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2021-11-10T16:37:05.289Z,1636562225.289 [WetLabsBB2FL] Loaded
2021-11-10T16:37:05.289Z,1636562225.289 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2021-11-10T16:37:05.290Z,1636562225.290 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B3D4E0
2021-11-10T16:37:05.290Z,1636562225.290 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 928
2021-11-10T16:37:05.310Z,1636562225.310 [WetLabsUBAT] Loaded
2021-11-10T16:37:05.311Z,1636562225.311 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread.
2021-11-10T16:37:05.312Z,1636562225.312 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40B6D4E0
2021-11-10T16:37:05.312Z,1636562225.312 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 929
2021-11-10T16:37:05.313Z,1636562225.313 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2021-11-10T16:37:05.319Z,1636562225.319 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2021-11-10T16:37:05.322Z,1636562225.322 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2021-11-10T16:37:05.333Z,1636562225.333 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2021-11-10T16:37:05.334Z,1636562225.334 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B9D4E0
2021-11-10T16:37:05.334Z,1636562225.334 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 930
2021-11-10T16:37:05.339Z,1636562225.339 [Supervisor](INFO): Main Thread ID is 831
2021-11-10T16:37:05.339Z,1636562225.339 [Supervisor](DEBUG): Running supervisor.
2021-11-10T16:37:05.339Z,1636562225.339 [CommandExec ThreadHandler](INFO): Handler Thread ID is 931
2021-11-10T16:37:05.340Z,1636562225.340 [CommandExec](INFO): Initializing the command executive.
2021-11-10T16:37:05.341Z,1636562225.341 [CommandLine ThreadHandler](INFO): Handler Thread ID is 932
2021-11-10T16:37:05.343Z,1636562225.343 [controlThread ThreadHandler](INFO): Handler Thread ID is 933
2021-11-10T16:37:05.344Z,1636562225.344 [controlThread](DEBUG): Initializing ControlThread
2021-11-10T16:37:05.345Z,1636562225.345 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2021-11-10T16:37:05.347Z,1636562225.347 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2021-11-10T16:37:05.347Z,1636562225.347 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2021-11-10T16:37:05.348Z,1636562225.348 [LoopControl](DEBUG): Initialize LoopControlComponent.
2021-11-10T16:37:05.348Z,1636562225.348 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2021-11-10T16:37:05.349Z,1636562225.349 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2021-11-10T16:37:05.349Z,1636562225.349 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2021-11-10T16:37:05.349Z,1636562225.349 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2021-11-10T16:37:05.350Z,1636562225.350 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2021-11-10T16:37:05.350Z,1636562225.350 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2021-11-10T16:37:05.356Z,1636562225.356 [NavChart](DEBUG): Initialize NavChart Navigation.
2021-11-10T16:37:05.356Z,1636562225.356 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2021-11-10T16:37:05.357Z,1636562225.357 [SBIT](INFO): Initialize SBIT Component.
2021-11-10T16:37:05.357Z,1636562225.357 [SBIT](IMPORTANT): git: 2021-11-09A
2021-11-10T16:37:05.358Z,1636562225.358 [SBIT](INFO): git hash: 0f0d2425d2b814912a05763138617759beb4d0e7
2021-11-10T16:37:05.358Z,1636562225.358 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2021-11-10T16:37:05.359Z,1636562225.359 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2021-11-10T16:37:05.360Z,1636562225.360 [SBIT](INFO): Beginning SBIT in 49.000000 seconds.
2021-11-10T16:37:05.361Z,1636562225.361 [IBIT](INFO): Initialize IBIT Component.
2021-11-10T16:37:05.362Z,1636562225.362 [CBIT](DEBUG): Initialize CBIT Component.
2021-11-10T16:37:05.363Z,1636562225.363 [logger ThreadHandler](INFO): Handler Thread ID is 934
2021-11-10T16:37:05.373Z,1636562225.373 [CBIT](DEBUG): Initialized mux pins.
2021-11-10T16:37:05.373Z,1636562225.373 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2021-11-10T16:37:05.373Z,1636562225.373 [CBIT](DEBUG): Initializing the watchdog timer.
2021-11-10T16:37:05.381Z,1636562225.381 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 935
2021-11-10T16:37:05.394Z,1636562225.394 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 936
2021-11-10T16:37:05.395Z,1636562225.395 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2021-11-10T16:37:05.397Z,1636562225.397 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2021-11-10T16:37:05.397Z,1636562225.397 [CBIT](DEBUG): Initializing heartbeat.
2021-11-10T16:37:05.405Z,1636562225.405 [Onboard ThreadHandler](INFO): Handler Thread ID is 937
2021-11-10T16:37:05.422Z,1636562225.422 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 938
2021-11-10T16:37:05.446Z,1636562225.446 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 939
2021-11-10T16:37:05.446Z,1636562225.446 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2021-11-10T16:37:05.450Z,1636562225.450 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 941
2021-11-10T16:37:05.453Z,1636562225.453 [WetLabsBB2FL](INFO): Powering up
2021-11-10T16:37:05.454Z,1636562225.454 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 943
2021-11-10T16:37:05.457Z,1636562225.457 [WetLabsUBAT](INFO): Powering up
2021-11-10T16:37:05.458Z,1636562225.458 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 945
2021-11-10T16:37:05.467Z,1636562225.467 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2021-11-10T16:37:05.468Z,1636562225.468 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2021-11-10T16:37:05.468Z,1636562225.468 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2021-11-10T16:37:05.468Z,1636562225.468 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2021-11-10T16:37:05.468Z,1636562225.468 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2021-11-10T16:37:05.468Z,1636562225.468 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2021-11-10T16:37:05.468Z,1636562225.468 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2021-11-10T16:37:05.469Z,1636562225.469 [CBIT](DEBUG): Deactivating GF circuits.
2021-11-10T16:37:05.469Z,1636562225.469 [CBIT](DEBUG): Deactivating emergency mode.
2021-11-10T16:37:05.469Z,1636562225.469 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2021-11-10T16:37:05.469Z,1636562225.469 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2021-11-10T16:37:05.470Z,1636562225.470 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2021-11-10T16:37:05.470Z,1636562225.470 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2021-11-10T16:37:05.470Z,1636562225.470 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2021-11-10T16:37:05.470Z,1636562225.470 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2021-11-10T16:37:05.470Z,1636562225.470 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2021-11-10T16:37:05.470Z,1636562225.470 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2021-11-10T16:37:05.471Z,1636562225.471 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2021-11-10T16:37:05.505Z,1636562225.505 [CBIT](DEBUG): Backplane powered.
2021-11-10T16:37:05.510Z,1636562225.510 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2021-11-10T16:37:05.546Z,1636562225.546 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2021-11-10T16:37:05.586Z,1636562225.586 [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>


2021-11-10T16:37:05.587Z,1636562225.587 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2021-11-10T16:37:05.657Z,1636562225.657 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2021-11-10T16:37:05.658Z,1636562225.658 [Default:A.Wait](DEBUG): Construct Wait.
2021-11-10T16:37:05.677Z,1636562225.677 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2021-11-10T16:37:05.719Z,1636562225.719 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2021-11-10T16:37:05.732Z,1636562225.732 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2021-11-10T16:37:05.766Z,1636562225.766 [Default:E.Execute](DEBUG): Construct Execute.
2021-11-10T16:37:05.770Z,1636562225.770 [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>


2021-11-10T16:37:05.781Z,1636562225.781 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,RDI_Pathfinder,DAT,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,LcmUniversalReporter,Reporter,LogSplitter,
2021-11-10T16:37:05.789Z,1636562225.789 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2021-11-10T16:37:05.829Z,1636562225.829 [Radio_Surface](INFO): Powering up
2021-11-10T16:37:05.923Z,1636562225.923 [Power24vConverter](INFO): Powering up.
2021-11-10T16:37:05.934Z,1636562225.934 [DAT](INFO): Powering up
2021-11-10T16:37:05.934Z,1636562225.934 [DAT](DEBUG): Initializing DAT.
2021-11-10T16:37:06.004Z,1636562226.004 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2021-11-10T16:37:06.013Z,1636562226.013 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2021-11-10T16:37:06.014Z,1636562226.014 [ElevatorServo](DEBUG): Initializing EZServoServo.
2021-11-10T16:37:06.021Z,1636562226.021 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2021-11-10T16:37:06.022Z,1636562226.022 [MassServo](DEBUG): Initializing EZServoServo.
2021-11-10T16:37:06.029Z,1636562226.029 [MassServo](DEBUG): Initializing MassServo.
2021-11-10T16:37:06.030Z,1636562226.030 [RudderServo](DEBUG): Initializing EZServoServo.
2021-11-10T16:37:06.037Z,1636562226.037 [RudderServo](DEBUG): Initializing RudderServo.
2021-11-10T16:37:06.038Z,1636562226.038 [ThrusterServo](DEBUG): Initializing EZServoServo.
2021-11-10T16:37:06.045Z,1636562226.045 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2021-11-10T16:37:06.047Z,1636562226.047 [CommandExec](FAULT): Scheduling is paused
2021-11-10T16:37:06.048Z,1636562226.048 [CBIT](INFO): Critical error at 20211110T163705
2021-11-10T16:37:06.048Z,1636562226.048 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2021-11-10T16:37:06.937Z,1636562226.937 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2021-11-10T16:37:06.937Z,1636562226.937 [RudderServo](FAULT): Rudder failed to initialize
2021-11-10T16:37:06.937Z,1636562226.937 [RudderServo] Communications Fault, FailCount= 1
2021-11-10T16:37:06.937Z,1636562226.937 [RudderServo](ERROR): Communications Fault
2021-11-10T16:37:07.037Z,1636562227.037 [CBIT](ERROR): Communications Fault in component: RudderServo
2021-11-10T16:37:07.223Z,1636562227.223 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2021-11-10T16:37:07.223Z,1636562227.223 [RudderServo](INFO): Powering down
2021-11-10T16:37:07.878Z,1636562227.878 [RudderServo](DEBUG): Initializing EZServoServo.
2021-11-10T16:37:07.998Z,1636562227.998 [RudderServo](DEBUG): Initializing RudderServo.
2021-11-10T16:37:08.002Z,1636562228.002 [CBIT](INFO): Clearing failed state for component RudderServo
2021-11-10T16:37:08.003Z,1636562228.003 [RudderServo] No Fault, FailCount= 1
2021-11-10T16:37:08.065Z,1636562228.065 [WetLabsBB2FL](INFO): Powering down
2021-11-10T16:37:14.761Z,1636562234.761 [WetLabsUBAT](INFO): Powering down
2021-11-10T16:37:28.059Z,1636562248.059 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2021-11-10T16:37:28.059Z,1636562248.059 [RDI_Pathfinder] Communications Fault, FailCount= 1
2021-11-10T16:37:28.059Z,1636562248.059 [RDI_Pathfinder](ERROR): Communications Fault
2021-11-10T16:37:28.121Z,1636562248.121 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2021-11-10T16:37:28.542Z,1636562248.542 [RDI_Pathfinder](INFO): Powering down
2021-11-10T16:37:29.356Z,1636562249.356 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2021-11-10T16:37:29.356Z,1636562249.356 [RDI_Pathfinder] No Fault, FailCount= 1
2021-11-10T16:37:33.715Z,1636562253.715 [NAL9602](INFO): Powering up NAL9602
2021-11-10T16:37:44.623Z,1636562264.623 [NAL9602](INFO): NAL9602 initialized
2021-11-10T16:37:51.896Z,1636562271.896 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2021-11-10T16:37:51.896Z,1636562271.896 [RDI_Pathfinder] Communications Fault, FailCount= 2
2021-11-10T16:37:51.896Z,1636562271.896 [RDI_Pathfinder](ERROR): Communications Fault
2021-11-10T16:37:51.961Z,1636562271.961 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2021-11-10T16:37:52.365Z,1636562272.365 [RDI_Pathfinder](INFO): Powering down
2021-11-10T16:37:53.152Z,1636562273.152 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2021-11-10T16:37:53.152Z,1636562273.152 [RDI_Pathfinder] No Fault, FailCount= 2
2021-11-10T16:37:55.166Z,1636562275.166 [SBIT](IMPORTANT): Beginning Startup BIT
2021-11-10T16:37:55.174Z,1636562275.174 [CBIT](IMPORTANT): Beginning ground fault scan
2021-11-10T16:38:01.997Z,1636562281.997 [NAL9602](INFO): SBD MO Status=0, MOMSN=17313, MT Status=0, MTMSN=0
2021-11-10T16:38:01.997Z,1636562281.997 [NAL9602](INFO): No messages in MT queue
2021-11-10T16:38:06.058Z,1636562286.058 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-11-10T16:38:06.058Z,1636562286.058 [DAT] Communications Fault, FailCount= 1
2021-11-10T16:38:06.058Z,1636562286.058 [DAT](ERROR): Communications Fault
2021-11-10T16:38:06.114Z,1636562286.114 [CBIT](ERROR): Communications Fault in component: DAT
2021-11-10T16:38:06.122Z,1636562286.122 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.010579
CHAN A1 (24V): -0.111300
CHAN A2 (12V): -0.007592
CHAN A3 (5V): -0.001910
CHAN B0 (3.3V): 0.000005
CHAN B1 (3.15aV): -0.000328
CHAN B2 (3.15bV): 0.000545
CHAN B3 (GND): 0.001936
OPEN: -0.003458
Full Scale: +/- 1 mA
2021-11-10T16:38:06.468Z,1636562286.468 [DAT](INFO): Powering down
2021-11-10T16:38:07.299Z,1636562287.299 [CBIT](INFO): Clearing failed state for component DAT
2021-11-10T16:38:07.299Z,1636562287.299 [DAT] No Fault, FailCount= 1
2021-11-10T16:38:09.682Z,1636562289.682 [DAT](INFO): Powering up
2021-11-10T16:38:09.682Z,1636562289.682 [DAT](DEBUG): Initializing DAT.
2021-11-10T16:38:15.732Z,1636562295.732 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2021-11-10T16:38:15.732Z,1636562295.732 [RDI_Pathfinder] Communications Fault, FailCount= 3
2021-11-10T16:38:15.732Z,1636562295.732 [RDI_Pathfinder](ERROR): Communications Fault
2021-11-10T16:38:15.902Z,1636562295.902 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2021-11-10T16:38:15.902Z,1636562295.902 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder
2021-11-10T16:38:16.213Z,1636562296.213 [RDI_Pathfinder](INFO): Powering down
2021-11-10T16:38:16.463Z,1636562296.463 [CBIT](INFO): Critical error at 20211110T163815
2021-11-10T16:38:25.960Z,1636562305.960 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163824.00,A,3648.64641,N,12151.56762,W,19.283,270.79,101121,,,A*40
2021-11-10T16:38:25.964Z,1636562305.964 [NAL9602](INFO): GPS fix at 20211110T163824: (36.810774, -121.859460)
2021-11-10T16:38:35.231Z,1636562315.231 [SBIT](FAULT): Rudder: EXPECTED:-15.000000 ACTUAL:-14.764685
2021-11-10T16:38:35.231Z,1636562315.231 [SBIT](FAULT): Control surface position failure.
2021-11-10T16:38:49.309Z,1636562329.309 [SBIT](CRITICAL): SBIT FAILED
2021-11-10T16:38:49.309Z,1636562329.309 [SBIT](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2021-11-10T16:38:49.310Z,1636562329.310 [CBIT](INFO): Critical error at 20211110T163849
2021-11-10T16:38:49.685Z,1636562329.685 [MissionManager](IMPORTANT): Started mission Startup
2021-11-10T16:38:49.685Z,1636562329.685 [Startup] Running Loop=1
2021-11-10T16:38:49.685Z,1636562329.685 [Startup](DEBUG): Aggregate::initialize Startup
2021-11-10T16:38:49.685Z,1636562329.685 [Startup:A.GoToSurface] Running Loop=1
2021-11-10T16:38:49.685Z,1636562329.685 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-11-10T16:38:49.686Z,1636562329.686 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-11-10T16:38:49.686Z,1636562329.686 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-11-10T16:38:49.687Z,1636562329.687 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-11-10T16:38:49.687Z,1636562329.687 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-11-10T16:38:49.688Z,1636562329.688 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-11-10T16:38:49.689Z,1636562329.689 [Startup:StartupSatComms] Running Loop=1
2021-11-10T16:38:49.689Z,1636562329.689 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2021-11-10T16:38:49.689Z,1636562329.689 [Startup:StartupSatComms:A] Running Loop=1
2021-11-10T16:38:50.094Z,1636562330.094 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2021-11-10T16:38:51.700Z,1636562331.700 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163850.00,A,3648.65156,N,12151.74276,W,21.596,276.92,101121,,,A*48
2021-11-10T16:38:51.703Z,1636562331.703 [NAL9602](INFO): GPS fix at 20211110T163850: (36.810859, -121.862379)
2021-11-10T16:38:51.714Z,1636562331.714 [Startup:StartupSatComms:A] Stopped
2021-11-10T16:38:51.714Z,1636562331.714 [Startup:StartupSatComms:B] Running Loop=1
2021-11-10T16:38:52.139Z,1636562332.139 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2021-11-10T16:39:09.920Z,1636562349.920 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-11-10T16:39:09.920Z,1636562349.920 [DAT] Communications Fault, FailCount= 2
2021-11-10T16:39:09.920Z,1636562349.920 [DAT](ERROR): Communications Fault
2021-11-10T16:39:09.940Z,1636562349.940 [CBIT](ERROR): Communications Fault in component: DAT
2021-11-10T16:39:10.324Z,1636562350.324 [DAT](INFO): Powering down
2021-11-10T16:39:11.147Z,1636562351.147 [CBIT](INFO): Clearing failed state for component DAT
2021-11-10T16:39:11.147Z,1636562351.147 [DAT] No Fault, FailCount= 2
2021-11-10T16:39:11.540Z,1636562351.540 [NAL9602](INFO): SBD MO Status=1, MOMSN=17314, MT Status=0, MTMSN=0
2021-11-10T16:39:11.805Z,1636562351.805 [NAL9602](INFO): Sent 18 bytes from file Logs/20211110T004555/Courier0307.lzma
2021-11-10T16:39:11.805Z,1636562351.805 [NAL9602](INFO): Packets left to send: 0
2021-11-10T16:39:14.757Z,1636562354.757 [DAT](INFO): Powering up
2021-11-10T16:39:14.758Z,1636562354.758 [DAT](DEBUG): Initializing DAT.
2021-11-10T16:39:22.118Z,1636562362.118 [NAL9602](INFO): SBD MO Status=1, MOMSN=17315, MT Status=0, MTMSN=0
2021-11-10T16:39:22.169Z,1636562362.169 [NAL9602](INFO): Sent 332 bytes from file Logs/20211110T163654/Courier0000.lzma
2021-11-10T16:39:22.169Z,1636562362.169 [NAL9602](INFO): Packets left to send: 1
2021-11-10T16:39:27.853Z,1636562367.853 [NAL9602](INFO): SBD MO Status=1, MOMSN=17316, MT Status=0, MTMSN=0
2021-11-10T16:39:27.903Z,1636562367.903 [NAL9602](INFO): Sent 67 bytes from file Logs/20211110T163654/Courier0000.lzma
2021-11-10T16:39:27.904Z,1636562367.904 [NAL9602](INFO): Packets left to send: 0
2021-11-10T16:39:38.072Z,1636562378.072 [NAL9602](INFO): SBD MO Status=1, MOMSN=17317, MT Status=0, MTMSN=0
2021-11-10T16:39:38.121Z,1636562378.121 [NAL9602](INFO): Sent 141 bytes from file Logs/20211110T004555/Express0305.lzma
2021-11-10T16:39:38.122Z,1636562378.122 [NAL9602](INFO): Packets left to send: 0
2021-11-10T16:39:45.824Z,1636562385.824 [NAL9602](INFO): SBD MO Status=1, MOMSN=17318, MT Status=0, MTMSN=0
2021-11-10T16:39:45.873Z,1636562385.873 [NAL9602](INFO): Sent 18 bytes from file Logs/20211110T004555/Express0308.lzma
2021-11-10T16:39:45.873Z,1636562385.873 [NAL9602](INFO): Packets left to send: 0
2021-11-10T16:39:52.045Z,1636562392.045 [Startup:StartupSatComms:B](INFO): Timed out from 2021-11-10T16:38:51.7Z
2021-11-10T16:39:52.045Z,1636562392.045 [Startup:StartupSatComms:B] Stopped
2021-11-10T16:39:52.046Z,1636562392.046 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2021-11-10T16:39:52.046Z,1636562392.046 [Startup:StartupSatComms] Stopped
2021-11-10T16:39:52.046Z,1636562392.046 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2021-11-10T16:39:52.047Z,1636562392.047 [Startup](INFO): Completed Startup
2021-11-10T16:39:52.047Z,1636562392.047 [MissionManager](INFO): Startup is completed.
2021-11-10T16:39:52.047Z,1636562392.047 [MissionManager](INFO): Uninitializing Mission Startup
2021-11-10T16:39:52.047Z,1636562392.047 [Startup] Stopped
2021-11-10T16:39:52.047Z,1636562392.047 [Startup](DEBUG): Aggregate::uninitialize Startup
2021-11-10T16:39:52.047Z,1636562392.047 [Startup:A.GoToSurface] Stopped
2021-11-10T16:39:52.047Z,1636562392.047 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-11-10T16:39:52.457Z,1636562392.457 [MissionManager](IMPORTANT): Started mission Default
2021-11-10T16:39:52.458Z,1636562392.458 [Default] Running Loop=1
2021-11-10T16:39:52.458Z,1636562392.458 [Default](DEBUG): Aggregate::initialize Default
2021-11-10T16:39:52.458Z,1636562392.458 [Default:B.GoToSurface] Running Loop=1
2021-11-10T16:39:52.458Z,1636562392.458 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-11-10T16:39:52.458Z,1636562392.458 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-11-10T16:39:52.459Z,1636562392.459 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-11-10T16:39:52.459Z,1636562392.459 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-11-10T16:39:52.459Z,1636562392.459 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-11-10T16:39:52.460Z,1636562392.460 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-11-10T16:39:52.460Z,1636562392.460 [Default:A.Wait] Running Loop=1
2021-11-10T16:39:52.460Z,1636562392.460 [Default:A.Wait](DEBUG): Initialize Wait Component.
2021-11-10T16:39:56.480Z,1636562396.480 [NAL9602](INFO): SBD MO Status=1, MOMSN=17319, MT Status=0, MTMSN=0
2021-11-10T16:39:56.537Z,1636562396.537 [NAL9602](INFO): Sent 332 bytes from file Logs/20211110T163654/Express0001.lzma
2021-11-10T16:39:56.537Z,1636562396.537 [NAL9602](INFO): Packets left to send: 2
2021-11-10T16:40:05.777Z,1636562405.777 [Default:A.Wait](INFO): Done Waiting.
2021-11-10T16:40:05.777Z,1636562405.777 [Default:A.Wait] Stopped
2021-11-10T16:40:05.777Z,1636562405.777 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2021-11-10T16:40:06.183Z,1636562406.183 [Default:CheckIn] Running Loop=1
2021-11-10T16:40:06.183Z,1636562406.183 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-11-10T16:40:06.183Z,1636562406.183 [Default:CheckIn:Read_GPS] Running Loop=1
2021-11-10T16:40:06.593Z,1636562406.593 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2021-11-10T16:40:08.200Z,1636562408.200 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164006.00,A,3648.72985,N,12152.28001,W,20.877,277.63,101121,,,A*40
2021-11-10T16:40:08.202Z,1636562408.202 [NAL9602](INFO): GPS fix at 20211110T164006: (36.812164, -121.871334)
2021-11-10T16:40:08.213Z,1636562408.213 [Default:CheckIn:Read_GPS] Stopped
2021-11-10T16:40:08.214Z,1636562408.214 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-11-10T16:40:08.647Z,1636562408.647 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2021-11-10T16:40:15.067Z,1636562415.067 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-11-10T16:40:15.067Z,1636562415.067 [DAT] Communications Fault, FailCount= 3
2021-11-10T16:40:15.067Z,1636562415.067 [DAT](ERROR): Communications Fault
2021-11-10T16:40:15.087Z,1636562415.087 [CBIT](ERROR): Communications Fault in component: DAT
2021-11-10T16:40:15.617Z,1636562415.617 [DAT](INFO): Powering down
2021-11-10T16:40:16.332Z,1636562416.332 [CBIT](INFO): Clearing failed state for component DAT
2021-11-10T16:40:16.332Z,1636562416.332 [DAT] No Fault, FailCount= 3
2021-11-10T16:40:19.917Z,1636562419.917 [DAT](INFO): Powering up
2021-11-10T16:40:19.917Z,1636562419.917 [DAT](DEBUG): Initializing DAT.
2021-11-10T16:40:27.879Z,1636562427.879 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004548
2021-11-10T16:40:31.304Z,1636562431.304 [NAL9602](INFO): SBD MO Status=1, MOMSN=17320, MT Status=0, MTMSN=0
2021-11-10T16:40:31.353Z,1636562431.353 [NAL9602](INFO): Sent 72 bytes from file Logs/20211110T163654/Courier0004.lzma
2021-11-10T16:40:31.353Z,1636562431.353 [NAL9602](INFO): Packets left to send: 0
2021-11-10T16:40:31.421Z,1636562431.421 [Default:CheckIn:Read_Iridium] Stopped
2021-11-10T16:40:31.421Z,1636562431.421 [Default:CheckIn:C.Wait] Running Loop=1
2021-11-10T16:40:31.422Z,1636562431.422 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-11-10T16:40:35.355Z,1636562435.355 [DataOverHttps](IMPORTANT): Could not stat file Logs/20211110T163654/Courier0004.lzma
2021-11-10T16:40:35.355Z,1636562435.355 [DataOverHttps](FAULT): Could not open file Logs/20211110T163654/Courier0004.lzma
2021-11-10T16:40:44.343Z,1636562444.343 [DataOverHttps](IMPORTANT): Could not stat file Logs/20211110T163654/Courier0004.lzma
2021-11-10T16:40:44.343Z,1636562444.343 [DataOverHttps](FAULT): Could not open file Logs/20211110T163654/Courier0004.lzma
2021-11-10T16:40:56.753Z,1636562456.753 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2021-11-10T16:41:02.010Z,1636562462.010 [NAL9602](INFO): Not Powering down - fast GPS
2021-11-10T16:41:15.770Z,1636562475.770 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2021-11-10T16:41:15.770Z,1636562475.770 [RDI_Pathfinder] No Fault, FailCount= 3
2021-11-10T16:41:20.191Z,1636562480.191 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-11-10T16:41:20.191Z,1636562480.191 [DAT] Communications Fault, FailCount= 4
2021-11-10T16:41:20.191Z,1636562480.191 [DAT](ERROR): Communications Fault
2021-11-10T16:41:20.210Z,1636562480.210 [CBIT](ERROR): Communications Fault in component: DAT
2021-11-10T16:41:20.589Z,1636562480.589 [DAT](INFO): Powering down
2021-11-10T16:41:21.446Z,1636562481.446 [CBIT](INFO): Clearing failed state for component DAT
2021-11-10T16:41:21.446Z,1636562481.446 [DAT] No Fault, FailCount= 4
2021-11-10T16:41:23.839Z,1636562483.839 [DAT](INFO): Powering up
2021-11-10T16:41:23.839Z,1636562483.839 [DAT](DEBUG): Initializing DAT.
2021-11-10T16:41:28.674Z,1636562488.674 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout.
2021-11-10T16:41:28.674Z,1636562488.674 [RDI_Pathfinder] Data Fault, FailCount= 1
2021-11-10T16:41:28.674Z,1636562488.674 [RDI_Pathfinder](ERROR): Data Fault
2021-11-10T16:41:28.695Z,1636562488.695 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder
2021-11-10T16:41:29.145Z,1636562489.145 [RDI_Pathfinder](INFO): Powering down
2021-11-10T16:41:29.903Z,1636562489.903 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2021-11-10T16:41:29.903Z,1636562489.903 [RDI_Pathfinder] No Fault, FailCount= 1
2021-11-10T16:41:42.573Z,1636562502.573 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-11-10T16:41:45.585Z,1636562505.585 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.239380
2021-11-10T16:41:52.510Z,1636562512.510 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2021-11-10T16:41:52.510Z,1636562512.510 [RDI_Pathfinder] Communications Fault, FailCount= 2
2021-11-10T16:41:52.511Z,1636562512.511 [RDI_Pathfinder](ERROR): Communications Fault
2021-11-10T16:41:52.531Z,1636562512.531 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2021-11-10T16:41:52.989Z,1636562512.989 [RDI_Pathfinder](INFO): Powering down
2021-11-10T16:41:53.738Z,1636562513.738 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2021-11-10T16:41:53.738Z,1636562513.738 [RDI_Pathfinder] No Fault, FailCount= 2
2021-11-10T16:42:16.413Z,1636562536.413 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2021-11-10T16:42:16.413Z,1636562536.413 [RDI_Pathfinder] Communications Fault, FailCount= 3
2021-11-10T16:42:16.413Z,1636562536.413 [RDI_Pathfinder](ERROR): Communications Fault
2021-11-10T16:42:16.435Z,1636562536.435 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2021-11-10T16:42:16.435Z,1636562536.435 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder
2021-11-10T16:42:16.893Z,1636562536.893 [RDI_Pathfinder](INFO): Powering down
2021-11-10T16:42:16.912Z,1636562536.912 [CBIT](INFO): Critical error at 20211110T164216
2021-11-10T16:42:17.713Z,1636562537.713 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-11-10T16:42:24.015Z,1636562544.015 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-11-10T16:42:24.015Z,1636562544.015 [DAT] Communications Fault, FailCount= 5
2021-11-10T16:42:24.015Z,1636562544.015 [DAT](ERROR): Communications Fault
2021-11-10T16:42:24.067Z,1636562544.067 [CBIT](ERROR): Communications Fault in component: DAT
2021-11-10T16:42:24.418Z,1636562544.418 [DAT](INFO): Powering down
2021-11-10T16:42:25.255Z,1636562545.255 [CBIT](INFO): Clearing failed state for component DAT
2021-11-10T16:42:25.255Z,1636562545.255 [DAT] No Fault, FailCount= 5
2021-11-10T16:42:27.651Z,1636562547.651 [DAT](INFO): Powering up
2021-11-10T16:42:27.651Z,1636562547.651 [DAT](DEBUG): Initializing DAT.
2021-11-10T16:42:52.881Z,1636562572.881 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-11-10T16:43:27.847Z,1636562607.847 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-11-10T16:43:27.847Z,1636562607.847 [DAT] Communications Fault, FailCount= 6
2021-11-10T16:43:27.847Z,1636562607.847 [DAT](ERROR): Communications Fault
2021-11-10T16:43:27.885Z,1636562607.885 [CBIT](ERROR): Communications Fault in component: DAT
2021-11-10T16:43:28.022Z,1636562608.022 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-11-10T16:43:28.259Z,1636562608.259 [DAT](INFO): Powering down
2021-11-10T16:43:29.098Z,1636562609.098 [CBIT](INFO): Clearing failed state for component DAT
2021-11-10T16:43:29.098Z,1636562609.098 [DAT] No Fault, FailCount= 6
2021-11-10T16:43:31.543Z,1636562611.543 [DAT](INFO): Powering up
2021-11-10T16:43:31.544Z,1636562611.544 [DAT](DEBUG): Initializing DAT.
2021-11-10T16:44:03.170Z,1636562643.170 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-11-10T16:44:31.687Z,1636562671.687 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-11-10T16:44:31.687Z,1636562671.687 [DAT] Communications Fault, FailCount= 7
2021-11-10T16:44:31.687Z,1636562671.687 [DAT](ERROR): Communications Fault
2021-11-10T16:44:31.754Z,1636562671.754 [CBIT](ERROR): Communications Fault in component: DAT
2021-11-10T16:44:32.144Z,1636562672.144 [DAT](INFO): Powering down
2021-11-10T16:44:32.982Z,1636562672.982 [CBIT](INFO): Clearing failed state for component DAT
2021-11-10T16:44:32.982Z,1636562672.982 [DAT] No Fault, FailCount= 7
2021-11-10T16:44:35.318Z,1636562675.318 [DAT](INFO): Powering up
2021-11-10T16:44:35.318Z,1636562675.318 [DAT](DEBUG): Initializing DAT.
2021-11-10T16:44:38.318Z,1636562678.318 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-11-10T16:44:50.865Z,1636562690.865 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003162
2021-11-10T16:45:10.873Z,1636562710.873 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2021-11-10T16:45:10.877Z,1636562710.877 [BPC1](INFO): Received data from all battery sticks.
2021-11-10T16:45:16.545Z,1636562716.545 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2021-11-10T16:45:16.545Z,1636562716.545 [RDI_Pathfinder] No Fault, FailCount= 3
2021-11-10T16:45:29.456Z,1636562729.456 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout.
2021-11-10T16:45:29.456Z,1636562729.456 [RDI_Pathfinder] Data Fault, FailCount= 1
2021-11-10T16:45:29.456Z,1636562729.456 [RDI_Pathfinder](ERROR): Data Fault
2021-11-10T16:45:29.480Z,1636562729.480 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder
2021-11-10T16:45:29.929Z,1636562729.929 [RDI_Pathfinder](INFO): Powering down
2021-11-10T16:45:30.686Z,1636562730.686 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2021-11-10T16:45:30.686Z,1636562730.686 [RDI_Pathfinder] No Fault, FailCount= 1
2021-11-10T16:45:31.888Z,1636562731.888 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-11-10T16:45:31.888Z,1636562731.888 [Default:CheckIn:C.Wait] Stopped
2021-11-10T16:45:31.888Z,1636562731.888 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-11-10T16:45:31.889Z,1636562731.889 [Default:CheckIn:D] Running Loop=1
2021-11-10T16:45:32.297Z,1636562732.297 [Default:CheckIn:D] Stopped
2021-11-10T16:45:32.297Z,1636562732.297 [Default:CheckIn:E] Running Loop=1
2021-11-10T16:45:32.695Z,1636562732.695 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 5.663979 min 
2021-11-10T16:45:32.696Z,1636562732.696 [Default:CheckIn:E] Stopped
2021-11-10T16:45:32.696Z,1636562732.696 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-11-10T16:45:32.696Z,1636562732.696 [Default:CheckIn] Stopped
2021-11-10T16:45:32.696Z,1636562732.696 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-11-10T16:45:32.696Z,1636562732.696 [Default:CheckIn](INFO): Running loop #2
2021-11-10T16:45:32.696Z,1636562732.696 [Default:CheckIn] Running Loop=2
2021-11-10T16:45:32.696Z,1636562732.696 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-11-10T16:45:32.697Z,1636562732.697 [Default:CheckIn:Read_GPS] Running Loop=1
2021-11-10T16:45:34.704Z,1636562734.704 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164534.00,A,3648.80987,N,12154.51371,W,21.110,276.51,101121,,,D*4B
2021-11-10T16:45:34.707Z,1636562734.707 [NAL9602](INFO): GPS fix at 20211110T164534: (36.813498, -121.908562)
2021-11-10T16:45:34.718Z,1636562734.718 [Default:CheckIn:Read_GPS] Stopped
2021-11-10T16:45:34.718Z,1636562734.718 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-11-10T16:45:35.513Z,1636562735.513 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-11-10T16:45:35.513Z,1636562735.513 [DAT] Communications Fault, FailCount= 8
2021-11-10T16:45:35.513Z,1636562735.513 [DAT](ERROR): Communications Fault
2021-11-10T16:45:35.597Z,1636562735.597 [CBIT](ERROR): Communications Fault in component: DAT
2021-11-10T16:45:35.598Z,1636562735.598 [CBIT](FAULT): Communications Fault in component: DAT
2021-11-10T16:45:35.915Z,1636562735.915 [DAT](INFO): Powering down
2021-11-10T16:45:42.261Z,1636562742.261 [DataOverHttps](INFO): Sending 254 bytes from file Logs/20211110T163654/Courier0007.lzma
2021-11-10T16:45:43.263Z,1636562743.263 [DataOverHttps](INFO): Moved sent file to Logs/20211110T163654/Courier0007.lzma.bak
2021-11-10T16:45:43.263Z,1636562743.263 [DataOverHttps](INFO): SBD MOMSN=16230077
2021-11-10T16:45:48.843Z,1636562748.843 [NAL9602](INFO): SBD MO Status=0, MOMSN=17321, MT Status=0, MTMSN=0
2021-11-10T16:45:48.844Z,1636562748.844 [NAL9602](INFO): No messages in MT queue
2021-11-10T16:45:53.286Z,1636562753.286 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2021-11-10T16:45:53.286Z,1636562753.286 [RDI_Pathfinder] Communications Fault, FailCount= 2
2021-11-10T16:45:53.286Z,1636562753.286 [RDI_Pathfinder](ERROR): Communications Fault
2021-11-10T16:45:53.320Z,1636562753.320 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2021-11-10T16:45:53.757Z,1636562753.757 [RDI_Pathfinder](INFO): Powering down
2021-11-10T16:45:54.520Z,1636562754.520 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2021-11-10T16:45:54.520Z,1636562754.520 [RDI_Pathfinder] No Fault, FailCount= 2
2021-11-10T16:45:59.774Z,1636562759.774 [DataOverHttps](INFO): Sending 823 bytes from file Logs/20211110T163654/Express0001.lzma
2021-11-10T16:46:00.774Z,1636562760.774 [DataOverHttps](INFO): Moved sent file to Logs/20211110T163654/Express0001.lzma.bak
2021-11-10T16:46:00.775Z,1636562760.775 [DataOverHttps](INFO): SBD MOMSN=16230083
2021-11-10T16:46:17.170Z,1636562777.170 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2021-11-10T16:46:17.171Z,1636562777.171 [RDI_Pathfinder] Communications Fault, FailCount= 3
2021-11-10T16:46:17.171Z,1636562777.171 [RDI_Pathfinder](ERROR): Communications Fault
2021-11-10T16:46:17.277Z,1636562777.277 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2021-11-10T16:46:17.278Z,1636562777.278 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder
2021-11-10T16:46:17.653Z,1636562777.653 [RDI_Pathfinder](INFO): Powering down
2021-11-10T16:46:17.713Z,1636562777.713 [CBIT](INFO): Critical error at 20211110T164617
2021-11-10T16:46:19.590Z,1636562779.590 [NAL9602](INFO): Not Powering down - fast GPS
2021-11-10T16:46:20.693Z,1636562780.693 [DataOverHttps](INFO): Sending 292 bytes from file Logs/20211110T163654/Express0005.lzma
2021-11-10T16:46:21.694Z,1636562781.694 [DataOverHttps](INFO): Moved sent file to Logs/20211110T163654/Express0005.lzma.bak
2021-11-10T16:46:21.695Z,1636562781.695 [DataOverHttps](INFO): SBD MOMSN=16230107
2021-11-10T16:46:37.921Z,1636562797.921 [DataOverHttps](INFO): Sending 413 bytes from file Logs/20211110T163654/Express0008.lzma
2021-11-10T16:46:38.923Z,1636562798.923 [DataOverHttps](INFO): Moved sent file to Logs/20211110T163654/Express0008.lzma.bak
2021-11-10T16:46:38.923Z,1636562798.923 [DataOverHttps](INFO): SBD MOMSN=16230114
2021-11-10T16:46:40.222Z,1636562800.222 [Default:CheckIn:Read_Iridium] Stopped
2021-11-10T16:46:40.222Z,1636562800.222 [Default:CheckIn:C.Wait] Running Loop=1
2021-11-10T16:46:40.222Z,1636562800.222 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-11-10T16:49:11.299Z,1636562951.299 [Power24vConverter](INFO): Powering down.
2021-11-10T16:49:17.392Z,1636562957.392 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2021-11-10T16:49:17.392Z,1636562957.392 [RDI_Pathfinder] No Fault, FailCount= 3
2021-11-10T16:49:18.170Z,1636562958.170 [Power24vConverter](INFO): Powering up.
2021-11-10T16:49:30.286Z,1636562970.286 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout.
2021-11-10T16:49:30.286Z,1636562970.286 [RDI_Pathfinder] Data Fault, FailCount= 1
2021-11-10T16:49:30.286Z,1636562970.286 [RDI_Pathfinder](ERROR): Data Fault
2021-11-10T16:49:30.322Z,1636562970.322 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder
2021-11-10T16:49:30.757Z,1636562970.757 [RDI_Pathfinder](INFO): Powering down
2021-11-10T16:49:31.534Z,1636562971.534 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2021-11-10T16:49:31.534Z,1636562971.534 [RDI_Pathfinder] No Fault, FailCount= 1
2021-11-10T16:49:32.641Z,1636562972.641 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2021-11-10T16:49:34.649Z,1636562974.649 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.563282
2021-11-10T16:49:54.126Z,1636562994.126 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2021-11-10T16:49:54.126Z,1636562994.126 [RDI_Pathfinder] Communications Fault, FailCount= 2
2021-11-10T16:49:54.126Z,1636562994.126 [RDI_Pathfinder](ERROR): Communications Fault
2021-11-10T16:49:54.163Z,1636562994.163 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2021-11-10T16:49:54.601Z,1636562994.601 [RDI_Pathfinder](INFO): Powering down
2021-11-10T16:49:55.372Z,1636562995.372 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2021-11-10T16:49:55.372Z,1636562995.372 [RDI_Pathfinder] No Fault, FailCount= 2
2021-11-10T16:50:07.789Z,1636563007.789 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-11-10T16:50:17.963Z,1636563017.963 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2021-11-10T16:50:17.963Z,1636563017.963 [RDI_Pathfinder] Communications Fault, FailCount= 3
2021-11-10T16:50:17.964Z,1636563017.964 [RDI_Pathfinder](ERROR): Communications Fault
2021-11-10T16:50:17.987Z,1636563017.987 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2021-11-10T16:50:17.987Z,1636563017.987 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder
2021-11-10T16:50:18.453Z,1636563018.453 [RDI_Pathfinder](INFO): Powering down
2021-11-10T16:50:18.488Z,1636563018.488 [CBIT](INFO): Critical error at 20211110T165017
2021-11-10T16:50:35.764Z,1636563035.764 [CBIT](INFO): Clearing failed state for component DAT
2021-11-10T16:50:35.765Z,1636563035.765 [DAT] No Fault, FailCount= 8
2021-11-10T16:50:36.142Z,1636563036.142 [DAT](INFO): Powering up
2021-11-10T16:50:36.143Z,1636563036.143 [DAT](DEBUG): Initializing DAT.
2021-11-10T16:50:42.929Z,1636563042.929 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-11-10T16:51:18.069Z,1636563078.069 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-11-10T16:51:36.334Z,1636563096.334 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-11-10T16:51:36.334Z,1636563096.334 [DAT] Communications Fault, FailCount= 1
2021-11-10T16:51:36.334Z,1636563096.334 [DAT](ERROR): Communications Fault
2021-11-10T16:51:36.370Z,1636563096.370 [CBIT](ERROR): Communications Fault in component: DAT
2021-11-10T16:51:36.739Z,1636563096.739 [DAT](INFO): Powering down
2021-11-10T16:51:37.575Z,1636563097.575 [CBIT](INFO): Clearing failed state for component DAT
2021-11-10T16:51:37.576Z,1636563097.576 [DAT] No Fault, FailCount= 1
2021-11-10T16:51:39.970Z,1636563099.970 [DAT](INFO): Powering up
2021-11-10T16:51:39.970Z,1636563099.970 [DAT](DEBUG): Initializing DAT.
2021-11-10T16:51:40.815Z,1636563100.815 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-11-10T16:51:40.815Z,1636563100.815 [Default:CheckIn:C.Wait] Stopped
2021-11-10T16:51:40.815Z,1636563100.815 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-11-10T16:51:40.816Z,1636563100.816 [Default:CheckIn:D] Running Loop=1
2021-11-10T16:51:41.192Z,1636563101.192 [Default:CheckIn:D] Stopped
2021-11-10T16:51:41.193Z,1636563101.193 [Default:CheckIn:E] Running Loop=1
2021-11-10T16:51:41.610Z,1636563101.610 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.812244 min 
2021-11-10T16:51:41.610Z,1636563101.610 [Default:CheckIn:E] Stopped
2021-11-10T16:51:41.610Z,1636563101.610 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-11-10T16:51:41.610Z,1636563101.610 [Default:CheckIn] Stopped
2021-11-10T16:51:41.610Z,1636563101.610 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-11-10T16:51:41.610Z,1636563101.610 [Default:CheckIn](INFO): Running loop #3
2021-11-10T16:51:41.611Z,1636563101.611 [Default:CheckIn] Running Loop=3
2021-11-10T16:51:41.611Z,1636563101.611 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-11-10T16:51:41.611Z,1636563101.611 [Default:CheckIn:Read_GPS] Running Loop=1
2021-11-10T16:51:43.608Z,1636563103.608 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165143.00,A,3648.94182,N,12157.38483,W,23.890,269.99,101121,,,D*49
2021-11-10T16:51:43.610Z,1636563103.610 [NAL9602](INFO): GPS fix at 20211110T165143: (36.815697, -121.956414)
2021-11-10T16:51:43.644Z,1636563103.644 [Default:CheckIn:Read_GPS] Stopped
2021-11-10T16:51:43.644Z,1636563103.644 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-11-10T16:51:53.209Z,1636563113.209 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-11-10T16:52:00.233Z,1636563120.233 [NAL9602](INFO): SBD MO Status=1, MOMSN=17322, MT Status=0, MTMSN=0
2021-11-10T16:52:00.293Z,1636563120.293 [NAL9602](INFO): Sent 135 bytes from file Logs/20211110T163654/Courier0010.lzma
2021-11-10T16:52:00.293Z,1636563120.293 [NAL9602](INFO): Packets left to send: 0
2021-11-10T16:52:12.024Z,1636563132.024 [NAL9602](INFO): SBD MO Status=1, MOMSN=17323, MT Status=0, MTMSN=0
2021-11-10T16:52:12.073Z,1636563132.073 [NAL9602](INFO): Sent 288 bytes from file Logs/20211110T163654/Express0011.lzma
2021-11-10T16:52:12.073Z,1636563132.073 [NAL9602](INFO): Packets left to send: 0
2021-11-10T16:52:28.365Z,1636563148.365 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-11-10T16:52:30.203Z,1636563150.203 [NAL9602](INFO): SBD MO Status=2, MOMSN=17324, MT Status=2, MTMSN=0
2021-11-10T16:52:30.204Z,1636563150.204 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-11-10T16:52:40.306Z,1636563160.306 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-11-10T16:52:40.306Z,1636563160.306 [DAT] Communications Fault, FailCount= 2
2021-11-10T16:52:40.306Z,1636563160.306 [DAT](ERROR): Communications Fault
2021-11-10T16:52:40.353Z,1636563160.353 [CBIT](ERROR): Communications Fault in component: DAT
2021-11-10T16:52:40.711Z,1636563160.711 [DAT](INFO): Powering down
2021-11-10T16:52:41.549Z,1636563161.549 [CBIT](INFO): Clearing failed state for component DAT
2021-11-10T16:52:41.549Z,1636563161.549 [DAT] No Fault, FailCount= 2
2021-11-10T16:52:43.939Z,1636563163.939 [DAT](INFO): Powering up
2021-11-10T16:52:43.939Z,1636563163.939 [DAT](DEBUG): Initializing DAT.
2021-11-10T16:52:54.451Z,1636563174.451 [NAL9602](INFO): SBD MO Status=0, MOMSN=17324, MT Status=0, MTMSN=0
2021-11-10T16:52:54.538Z,1636563174.538 [Default:CheckIn:Read_Iridium] Stopped
2021-11-10T16:52:54.538Z,1636563174.538 [Default:CheckIn:C.Wait] Running Loop=1
2021-11-10T16:52:54.538Z,1636563174.538 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-11-10T16:53:03.505Z,1636563183.505 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-11-10T16:53:18.302Z,1636563198.302 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2021-11-10T16:53:18.303Z,1636563198.303 [RDI_Pathfinder] No Fault, FailCount= 3
2021-11-10T16:53:25.147Z,1636563205.147 [NAL9602](INFO): Not Powering down - fast GPS
2021-11-10T16:53:31.230Z,1636563211.230 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout.
2021-11-10T16:53:31.230Z,1636563211.230 [RDI_Pathfinder] Data Fault, FailCount= 1
2021-11-10T16:53:31.230Z,1636563211.230 [RDI_Pathfinder](ERROR): Data Fault
2021-11-10T16:53:31.279Z,1636563211.279 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder
2021-11-10T16:53:31.689Z,1636563211.689 [RDI_Pathfinder](INFO): Powering down
2021-11-10T16:53:32.453Z,1636563212.453 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2021-11-10T16:53:32.453Z,1636563212.453 [RDI_Pathfinder] No Fault, FailCount= 1
2021-11-10T16:53:38.645Z,1636563218.645 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-11-10T16:53:44.137Z,1636563224.137 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-11-10T16:53:44.137Z,1636563224.137 [DAT] Communications Fault, FailCount= 3
2021-11-10T16:53:44.137Z,1636563224.137 [DAT](ERROR): Communications Fault
2021-11-10T16:53:44.157Z,1636563224.157 [CBIT](ERROR): Communications Fault in component: DAT
2021-11-10T16:53:44.542Z,1636563224.542 [DAT](INFO): Powering down
2021-11-10T16:53:45.369Z,1636563225.369 [CBIT](INFO): Clearing failed state for component DAT
2021-11-10T16:53:45.369Z,1636563225.369 [DAT] No Fault, FailCount= 3
2021-11-10T16:53:47.770Z,1636563227.770 [DAT](INFO): Powering up
2021-11-10T16:53:47.770Z,1636563227.770 [DAT](DEBUG): Initializing DAT.
2021-11-10T16:53:55.044Z,1636563235.044 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2021-11-10T16:53:55.045Z,1636563235.045 [RDI_Pathfinder] Communications Fault, FailCount= 2
2021-11-10T16:53:55.045Z,1636563235.045 [RDI_Pathfinder](ERROR): Communications Fault
2021-11-10T16:53:55.065Z,1636563235.065 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2021-11-10T16:53:55.521Z,1636563235.521 [RDI_Pathfinder](INFO): Powering down
2021-11-10T16:53:56.319Z,1636563236.319 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2021-11-10T16:53:56.319Z,1636563236.319 [RDI_Pathfinder] No Fault, FailCount= 2
2021-11-10T16:54:13.785Z,1636563253.785 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-11-10T16:54:18.882Z,1636563258.882 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2021-11-10T16:54:18.882Z,1636563258.882 [RDI_Pathfinder] Communications Fault, FailCount= 3
2021-11-10T16:54:18.882Z,1636563258.882 [RDI_Pathfinder](ERROR): Communications Fault
2021-11-10T16:54:18.927Z,1636563258.927 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2021-11-10T16:54:18.927Z,1636563258.927 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder
2021-11-10T16:54:19.353Z,1636563259.353 [RDI_Pathfinder](INFO): Powering down
2021-11-10T16:54:19.435Z,1636563259.435 [CBIT](INFO): Critical error at 20211110T165418
2021-11-10T16:54:47.967Z,1636563287.967 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-11-10T16:54:47.967Z,1636563287.967 [DAT] Communications Fault, FailCount= 4
2021-11-10T16:54:47.967Z,1636563287.967 [DAT](ERROR): Communications Fault
2021-11-10T16:54:48.016Z,1636563288.016 [CBIT](ERROR): Communications Fault in component: DAT
2021-11-10T16:54:48.371Z,1636563288.371 [DAT](INFO): Powering down
2021-11-10T16:54:48.925Z,1636563288.925 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-11-10T16:54:49.214Z,1636563289.214 [CBIT](INFO): Clearing failed state for component DAT
2021-11-10T16:54:49.214Z,1636563289.214 [DAT] No Fault, FailCount= 4
2021-11-10T16:54:51.603Z,1636563291.603 [DAT](INFO): Powering up
2021-11-10T16:54:51.603Z,1636563291.603 [DAT](DEBUG): Initializing DAT.
2021-11-10T16:55:24.085Z,1636563324.085 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-11-10T16:55:51.807Z,1636563351.807 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-11-10T16:55:51.807Z,1636563351.807 [DAT] Communications Fault, FailCount= 5
2021-11-10T16:55:51.807Z,1636563351.807 [DAT](ERROR): Communications Fault
2021-11-10T16:55:51.827Z,1636563351.827 [CBIT](ERROR): Communications Fault in component: DAT
2021-11-10T16:55:52.203Z,1636563352.203 [DAT](INFO): Powering down
2021-11-10T16:55:53.037Z,1636563353.037 [CBIT](INFO): Clearing failed state for component DAT
2021-11-10T16:55:53.037Z,1636563353.037 [DAT] No Fault, FailCount= 5
2021-11-10T16:55:55.434Z,1636563355.434 [DAT](INFO): Powering up
2021-11-10T16:55:55.435Z,1636563355.435 [DAT](DEBUG): Initializing DAT.
2021-11-10T16:55:59.225Z,1636563359.225 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-11-10T16:56:34.365Z,1636563394.365 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-11-10T16:56:55.633Z,1636563415.633 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-11-10T16:56:55.633Z,1636563415.633 [DAT] Communications Fault, FailCount= 6
2021-11-10T16:56:55.633Z,1636563415.633 [DAT](ERROR): Communications Fault
2021-11-10T16:56:55.653Z,1636563415.653 [CBIT](ERROR): Communications Fault in component: DAT
2021-11-10T16:56:56.038Z,1636563416.038 [DAT](INFO): Powering down
2021-11-10T16:56:56.861Z,1636563416.861 [CBIT](INFO): Clearing failed state for component DAT
2021-11-10T16:56:56.861Z,1636563416.861 [DAT] No Fault, FailCount= 6
2021-11-10T16:56:59.266Z,1636563419.266 [DAT](INFO): Powering up
2021-11-10T16:56:59.266Z,1636563419.266 [DAT](DEBUG): Initializing DAT.
2021-11-10T16:57:09.505Z,1636563429.505 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-11-10T16:57:19.084Z,1636563439.084 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2021-11-10T16:57:19.084Z,1636563439.084 [RDI_Pathfinder] No Fault, FailCount= 3
2021-11-10T16:57:31.990Z,1636563451.990 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout.
2021-11-10T16:57:31.990Z,1636563451.990 [RDI_Pathfinder] Data Fault, FailCount= 1
2021-11-10T16:57:31.991Z,1636563451.991 [RDI_Pathfinder](ERROR): Data Fault
2021-11-10T16:57:32.037Z,1636563452.037 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder
2021-11-10T16:57:32.465Z,1636563452.465 [RDI_Pathfinder](INFO): Powering down
2021-11-10T16:57:33.224Z,1636563453.224 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2021-11-10T16:57:33.224Z,1636563453.224 [RDI_Pathfinder] No Fault, FailCount= 1
2021-11-10T16:57:44.645Z,1636563464.645 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-11-10T16:57:55.041Z,1636563475.041 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-11-10T16:57:55.041Z,1636563475.041 [Default:CheckIn:C.Wait] Stopped
2021-11-10T16:57:55.042Z,1636563475.042 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-11-10T16:57:55.042Z,1636563475.042 [Default:CheckIn:D] Running Loop=1
2021-11-10T16:57:55.438Z,1636563475.438 [Default:CheckIn:D] Stopped
2021-11-10T16:57:55.438Z,1636563475.438 [Default:CheckIn:E] Running Loop=1
2021-11-10T16:57:55.830Z,1636563475.830 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2021-11-10T16:57:55.830Z,1636563475.830 [RDI_Pathfinder] Communications Fault, FailCount= 2
2021-11-10T16:57:55.831Z,1636563475.831 [RDI_Pathfinder](ERROR): Communications Fault
2021-11-10T16:57:55.842Z,1636563475.842 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 18.049668 min 
2021-11-10T16:57:55.842Z,1636563475.842 [Default:CheckIn:E] Stopped
2021-11-10T16:57:55.842Z,1636563475.842 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-11-10T16:57:55.842Z,1636563475.842 [Default:CheckIn] Stopped
2021-11-10T16:57:55.842Z,1636563475.842 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-11-10T16:57:55.843Z,1636563475.843 [Default:CheckIn](INFO): Running loop #4
2021-11-10T16:57:55.843Z,1636563475.843 [Default:CheckIn] Running Loop=4
2021-11-10T16:57:55.843Z,1636563475.843 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-11-10T16:57:55.843Z,1636563475.843 [Default:CheckIn:Read_GPS] Running Loop=1
2021-11-10T16:57:55.853Z,1636563475.853 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2021-11-10T16:57:56.305Z,1636563476.305 [RDI_Pathfinder](INFO): Powering down
2021-11-10T16:57:57.093Z,1636563477.093 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2021-11-10T16:57:57.094Z,1636563477.094 [RDI_Pathfinder] No Fault, FailCount= 2
2021-11-10T16:57:57.849Z,1636563477.849 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165757.00,A,3648.99094,N,12159.94077,W,19.011,277.80,101121,,,D*49
2021-11-10T16:57:57.851Z,1636563477.851 [NAL9602](INFO): GPS fix at 20211110T165757: (36.816516, -121.999013)
2021-11-10T16:57:57.864Z,1636563477.864 [Default:CheckIn:Read_GPS] Stopped
2021-11-10T16:57:57.864Z,1636563477.864 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-11-10T16:57:59.484Z,1636563479.484 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-11-10T16:57:59.484Z,1636563479.484 [DAT] Communications Fault, FailCount= 7
2021-11-10T16:57:59.484Z,1636563479.484 [DAT](ERROR): Communications Fault
2021-11-10T16:57:59.504Z,1636563479.504 [CBIT](ERROR): Communications Fault in component: DAT
2021-11-10T16:57:59.897Z,1636563479.897 [DAT](INFO): Powering down
2021-11-10T16:58:00.698Z,1636563480.698 [CBIT](INFO): Clearing failed state for component DAT
2021-11-10T16:58:00.698Z,1636563480.698 [DAT] No Fault, FailCount= 7
2021-11-10T16:58:03.100Z,1636563483.100 [DAT](INFO): Powering up
2021-11-10T16:58:03.100Z,1636563483.100 [DAT](DEBUG): Initializing DAT.
2021-11-10T16:58:13.259Z,1636563493.259 [NAL9602](INFO): SBD MO Status=1, MOMSN=17325, MT Status=0, MTMSN=0
2021-11-10T16:58:13.317Z,1636563493.317 [NAL9602](INFO): Sent 129 bytes from file Logs/20211110T163654/Courier0013.lzma
2021-11-10T16:58:13.317Z,1636563493.317 [NAL9602](INFO): Packets left to send: 0
2021-11-10T16:58:19.785Z,1636563499.785 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-11-10T16:58:19.795Z,1636563499.795 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2021-11-10T16:58:19.795Z,1636563499.795 [RDI_Pathfinder] Communications Fault, FailCount= 3
2021-11-10T16:58:19.795Z,1636563499.795 [RDI_Pathfinder](ERROR): Communications Fault
2021-11-10T16:58:19.845Z,1636563499.845 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2021-11-10T16:58:19.846Z,1636563499.846 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder
2021-11-10T16:58:20.269Z,1636563500.269 [RDI_Pathfinder](INFO): Powering down
2021-11-10T16:58:20.300Z,1636563500.300 [CBIT](INFO): Critical error at 20211110T165819
2021-11-10T16:58:23.843Z,1636563503.843 [NAL9602](INFO): SBD MO Status=1, MOMSN=17326, MT Status=0, MTMSN=0
2021-11-10T16:58:23.893Z,1636563503.893 [NAL9602](INFO): Sent 274 bytes from file Logs/20211110T163654/Express0014.lzma
2021-11-10T16:58:23.893Z,1636563503.893 [NAL9602](INFO): Packets left to send: 0
2021-11-10T16:58:34.339Z,1636563514.339 [NAL9602](INFO): SBD MO Status=0, MOMSN=17327, MT Status=0, MTMSN=0
2021-11-10T16:58:34.427Z,1636563514.427 [Default:CheckIn:Read_Iridium] Stopped
2021-11-10T16:58:34.427Z,1636563514.427 [Default:CheckIn:C.Wait] Running Loop=1
2021-11-10T16:58:34.427Z,1636563514.427 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-11-10T16:58:54.929Z,1636563534.929 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-11-10T16:59:03.427Z,1636563543.427 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-11-10T16:59:03.427Z,1636563543.427 [DAT] Communications Fault, FailCount= 8
2021-11-10T16:59:03.427Z,1636563543.427 [DAT](ERROR): Communications Fault
2021-11-10T16:59:03.471Z,1636563543.471 [CBIT](ERROR): Communications Fault in component: DAT
2021-11-10T16:59:03.472Z,1636563543.472 [CBIT](FAULT): Communications Fault in component: DAT
2021-11-10T16:59:03.828Z,1636563543.828 [DAT](INFO): Powering down
2021-11-10T16:59:05.038Z,1636563545.038 [NAL9602](INFO): Not Powering down - fast GPS
2021-11-10T16:59:30.085Z,1636563570.085 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-11-10T16:59:31.710Z,1636563571.710 [Power24vConverter](INFO): Powering down.
2021-11-10T17:00:05.225Z,1636563605.225 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-11-10T17:00:40.377Z,1636563640.377 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-11-10T17:01:15.533Z,1636563675.533 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-11-10T17:01:19.996Z,1636563679.996 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2021-11-10T17:01:19.997Z,1636563679.997 [RDI_Pathfinder] No Fault, FailCount= 3
2021-11-10T17:01:20.814Z,1636563680.814 [Power24vConverter](INFO): Powering up.
2021-11-10T17:01:32.906Z,1636563692.906 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout.
2021-11-10T17:01:32.906Z,1636563692.906 [RDI_Pathfinder] Data Fault, FailCount= 1
2021-11-10T17:01:32.906Z,1636563692.906 [RDI_Pathfinder](ERROR): Data Fault
2021-11-10T17:01:32.927Z,1636563692.927 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder
2021-11-10T17:01:33.393Z,1636563693.393 [RDI_Pathfinder](INFO): Powering down
2021-11-10T17:01:34.134Z,1636563694.134 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2021-11-10T17:01:34.134Z,1636563694.134 [RDI_Pathfinder] No Fault, FailCount= 1
2021-11-10T17:01:50.673Z,1636563710.673 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-11-10T17:01:56.743Z,1636563716.743 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2021-11-10T17:01:56.743Z,1636563716.743 [RDI_Pathfinder] Communications Fault, FailCount= 2
2021-11-10T17:01:56.743Z,1636563716.743 [RDI_Pathfinder](ERROR): Communications Fault
2021-11-10T17:01:56.763Z,1636563716.763 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2021-11-10T17:01:57.221Z,1636563717.221 [RDI_Pathfinder](INFO): Powering down
2021-11-10T17:01:57.997Z,1636563717.997 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2021-11-10T17:01:57.997Z,1636563717.997 [RDI_Pathfinder] No Fault, FailCount= 2
2021-11-10T17:02:20.575Z,1636563740.575 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2021-11-10T17:02:20.575Z,1636563740.575 [RDI_Pathfinder] Communications Fault, FailCount= 3
2021-11-10T17:02:20.575Z,1636563740.575 [RDI_Pathfinder](ERROR): Communications Fault
2021-11-10T17:02:20.616Z,1636563740.616 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2021-11-10T17:02:20.616Z,1636563740.616 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder
2021-11-10T17:02:21.053Z,1636563741.053 [RDI_Pathfinder](INFO): Powering down
2021-11-10T17:02:21.082Z,1636563741.082 [CBIT](INFO): Critical error at 20211110T170220
2021-11-10T17:02:25.837Z,1636563745.837 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-11-10T17:03:00.977Z,1636563780.977 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-11-10T17:03:34.941Z,1636563814.941 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-11-10T17:03:34.942Z,1636563814.942 [Default:CheckIn:C.Wait] Stopped
2021-11-10T17:03:34.942Z,1636563814.942 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-11-10T17:03:34.942Z,1636563814.942 [Default:CheckIn:D] Running Loop=1
2021-11-10T17:03:35.330Z,1636563815.330 [Default:CheckIn:D] Stopped
2021-11-10T17:03:35.330Z,1636563815.330 [Default:CheckIn:E] Running Loop=1
2021-11-10T17:03:35.730Z,1636563815.730 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 23.714539 min 
2021-11-10T17:03:35.730Z,1636563815.730 [Default:CheckIn:E] Stopped
2021-11-10T17:03:35.730Z,1636563815.730 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-11-10T17:03:35.730Z,1636563815.730 [Default:CheckIn] Stopped
2021-11-10T17:03:35.731Z,1636563815.731 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-11-10T17:03:35.731Z,1636563815.731 [Default:CheckIn](INFO): Running loop #5
2021-11-10T17:03:35.731Z,1636563815.731 [Default:CheckIn] Running Loop=5
2021-11-10T17:03:35.731Z,1636563815.731 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-11-10T17:03:35.731Z,1636563815.731 [Default:CheckIn:Read_GPS] Running Loop=1
2021-11-10T17:03:36.117Z,1636563816.117 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-11-10T17:03:37.756Z,1636563817.756 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170337.00,A,3649.13446,N,12202.04473,W,18.525,270.83,101121,,,D*44
2021-11-10T17:03:37.758Z,1636563817.758 [NAL9602](INFO): GPS fix at 20211110T170337: (36.818908, -122.034079)
2021-11-10T17:03:37.769Z,1636563817.769 [Default:CheckIn:Read_GPS] Stopped
2021-11-10T17:03:37.769Z,1636563817.769 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-11-10T17:03:48.601Z,1636563828.601 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002742
2021-11-10T17:03:55.972Z,1636563835.972 [DataOverHttps](INFO): Sending 135 bytes from file Logs/20211110T163654/Courier0016.lzma
2021-11-10T17:03:56.974Z,1636563836.974 [DataOverHttps](INFO): Moved sent file to Logs/20211110T163654/Courier0016.lzma.bak
2021-11-10T17:03:56.975Z,1636563836.975 [DataOverHttps](INFO): SBD MOMSN=16230222
2021-11-10T17:04:03.636Z,1636563843.636 [CBIT](INFO): Clearing failed state for component DAT
2021-11-10T17:04:03.637Z,1636563843.637 [DAT] No Fault, FailCount= 8
2021-11-10T17:04:04.002Z,1636563844.002 [DAT](INFO): Powering up
2021-11-10T17:04:04.003Z,1636563844.003 [DAT](DEBUG): Initializing DAT.
2021-11-10T17:04:18.721Z,1636563858.721 [DataOverHttps](INFO): Sending 283 bytes from file Logs/20211110T163654/Express0017.lzma
2021-11-10T17:04:19.723Z,1636563859.723 [DataOverHttps](INFO): Moved sent file to Logs/20211110T163654/Express0017.lzma.bak
2021-11-10T17:04:19.723Z,1636563859.723 [DataOverHttps](INFO): SBD MOMSN=16230227
2021-11-10T17:04:21.443Z,1636563861.443 [Default:CheckIn:Read_Iridium] Stopped
2021-11-10T17:04:21.443Z,1636563861.443 [Default:CheckIn:C.Wait] Running Loop=1
2021-11-10T17:04:21.443Z,1636563861.443 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-11-10T17:04:26.286Z,1636563866.286 [NAL9602](INFO): Not Powering down - fast GPS
2021-11-10T17:05:04.258Z,1636563904.258 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-11-10T17:05:04.258Z,1636563904.258 [DAT] Communications Fault, FailCount= 1
2021-11-10T17:05:04.258Z,1636563904.258 [DAT](ERROR): Communications Fault
2021-11-10T17:05:04.279Z,1636563904.279 [CBIT](ERROR): Communications Fault in component: DAT
2021-11-10T17:05:04.668Z,1636563904.668 [DAT](INFO): Powering down
2021-11-10T17:05:05.523Z,1636563905.523 [CBIT](INFO): Clearing failed state for component DAT
2021-11-10T17:05:05.523Z,1636563905.523 [DAT] No Fault, FailCount= 1
2021-11-10T17:05:07.900Z,1636563907.900 [DAT](INFO): Powering up
2021-11-10T17:05:07.900Z,1636563907.900 [DAT](DEBUG): Initializing DAT.
2021-11-10T17:05:20.856Z,1636563920.856 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2021-11-10T17:05:20.856Z,1636563920.856 [RDI_Pathfinder] No Fault, FailCount= 3
2021-11-10T17:05:33.756Z,1636563933.756 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout.
2021-11-10T17:05:33.756Z,1636563933.756 [RDI_Pathfinder] Data Fault, FailCount= 1
2021-11-10T17:05:33.756Z,1636563933.756 [RDI_Pathfinder](ERROR): Data Fault
2021-11-10T17:05:33.784Z,1636563933.784 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder
2021-11-10T17:05:34.225Z,1636563934.225 [RDI_Pathfinder](INFO): Powering down
2021-11-10T17:05:35.007Z,1636563935.007 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2021-11-10T17:05:35.007Z,1636563935.007 [RDI_Pathfinder] No Fault, FailCount= 1
2021-11-10T17:05:57.594Z,1636563957.594 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2021-11-10T17:05:57.594Z,1636563957.594 [RDI_Pathfinder] Communications Fault, FailCount= 2
2021-11-10T17:05:57.594Z,1636563957.594 [RDI_Pathfinder](ERROR): Communications Fault
2021-11-10T17:05:57.660Z,1636563957.660 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2021-11-10T17:05:58.057Z,1636563958.057 [RDI_Pathfinder](INFO): Powering down
2021-11-10T17:05:58.818Z,1636563958.818 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2021-11-10T17:05:58.818Z,1636563958.818 [RDI_Pathfinder] No Fault, FailCount= 2
2021-11-10T17:06:08.090Z,1636563968.090 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-11-10T17:06:08.090Z,1636563968.090 [DAT] Communications Fault, FailCount= 2
2021-11-10T17:06:08.090Z,1636563968.090 [DAT](ERROR): Communications Fault
2021-11-10T17:06:08.138Z,1636563968.138 [CBIT](ERROR): Communications Fault in component: DAT
2021-11-10T17:06:08.498Z,1636563968.498 [DAT](INFO): Powering down
2021-11-10T17:06:09.336Z,1636563969.336 [CBIT](INFO): Clearing failed state for component DAT
2021-11-10T17:06:09.336Z,1636563969.336 [DAT] No Fault, FailCount= 2
2021-11-10T17:06:11.727Z,1636563971.727 [DAT](INFO): Powering up
2021-11-10T17:06:11.727Z,1636563971.727 [DAT](DEBUG): Initializing DAT.
2021-11-10T17:06:21.423Z,1636563981.423 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2021-11-10T17:06:21.423Z,1636563981.423 [RDI_Pathfinder] Communications Fault, FailCount= 3
2021-11-10T17:06:21.423Z,1636563981.423 [RDI_Pathfinder](ERROR): Communications Fault
2021-11-10T17:06:21.503Z,1636563981.503 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2021-11-10T17:06:21.503Z,1636563981.503 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder
2021-11-10T17:06:21.893Z,1636563981.893 [RDI_Pathfinder](INFO): Powering down
2021-11-10T17:06:21.926Z,1636563981.926 [CBIT](INFO): Critical error at 20211110T170621
2021-11-10T17:07:11.931Z,1636564031.931 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-11-10T17:07:11.931Z,1636564031.931 [DAT] Communications Fault, FailCount= 3
2021-11-10T17:07:11.931Z,1636564031.931 [DAT](ERROR): Communications Fault
2021-11-10T17:07:11.951Z,1636564031.951 [CBIT](ERROR): Communications Fault in component: DAT
2021-11-10T17:07:12.326Z,1636564032.326 [DAT](INFO): Powering down
2021-11-10T17:07:13.158Z,1636564033.158 [CBIT](INFO): Clearing failed state for component DAT
2021-11-10T17:07:13.158Z,1636564033.158 [DAT] No Fault, FailCount= 3
2021-11-10T17:07:15.559Z,1636564035.559 [DAT](INFO): Powering up
2021-11-10T17:07:15.559Z,1636564035.559 [DAT](DEBUG): Initializing DAT.
2021-11-10T17:08:15.762Z,1636564095.762 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-11-10T17:08:15.762Z,1636564095.762 [DAT] Communications Fault, FailCount= 4
2021-11-10T17:08:15.763Z,1636564095.763 [DAT](ERROR): Communications Fault
2021-11-10T17:08:15.798Z,1636564095.798 [CBIT](ERROR): Communications Fault in component: DAT
2021-11-10T17:08:16.170Z,1636564096.170 [DAT](INFO): Powering down
2021-11-10T17:08:16.996Z,1636564096.996 [CBIT](INFO): Clearing failed state for component DAT
2021-11-10T17:08:16.996Z,1636564096.996 [DAT] No Fault, FailCount= 4
2021-11-10T17:08:19.406Z,1636564099.406 [DAT](INFO): Powering up
2021-11-10T17:08:19.406Z,1636564099.406 [DAT](DEBUG): Initializing DAT.
2021-11-10T17:09:19.597Z,1636564159.597 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-11-10T17:09:19.597Z,1636564159.597 [DAT] Communications Fault, FailCount= 5
2021-11-10T17:09:19.597Z,1636564159.597 [DAT](ERROR): Communications Fault
2021-11-10T17:09:19.617Z,1636564159.617 [CBIT](ERROR): Communications Fault in component: DAT
2021-11-10T17:09:19.998Z,1636564159.998 [DAT](INFO): Powering down
2021-11-10T17:09:20.825Z,1636564160.825 [CBIT](INFO): Clearing failed state for component DAT
2021-11-10T17:09:20.825Z,1636564160.825 [DAT] No Fault, FailCount= 5
2021-11-10T17:09:21.647Z,1636564161.647 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2021-11-10T17:09:21.647Z,1636564161.647 [RDI_Pathfinder] No Fault, FailCount= 3
2021-11-10T17:09:22.055Z,1636564162.055 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-11-10T17:09:22.055Z,1636564162.055 [Default:CheckIn:C.Wait] Stopped
2021-11-10T17:09:22.057Z,1636564162.057 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-11-10T17:09:22.057Z,1636564162.057 [Default:CheckIn:D] Running Loop=1
2021-11-10T17:09:22.439Z,1636564162.439 [Default:CheckIn:D] Stopped
2021-11-10T17:09:22.439Z,1636564162.439 [Default:CheckIn:E] Running Loop=1
2021-11-10T17:09:22.844Z,1636564162.844 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 29.499691 min 
2021-11-10T17:09:22.844Z,1636564162.844 [Default:CheckIn:E] Stopped
2021-11-10T17:09:22.844Z,1636564162.844 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-11-10T17:09:22.844Z,1636564162.844 [Default:CheckIn] Stopped
2021-11-10T17:09:22.844Z,1636564162.844 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-11-10T17:09:22.845Z,1636564162.845 [Default:CheckIn](INFO): Running loop #6
2021-11-10T17:09:22.845Z,1636564162.845 [Default:CheckIn] Running Loop=6
2021-11-10T17:09:22.845Z,1636564162.845 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-11-10T17:09:22.845Z,1636564162.845 [Default:CheckIn:Read_GPS] Running Loop=1
2021-11-10T17:09:23.230Z,1636564163.230 [DAT](INFO): Powering up
2021-11-10T17:09:23.230Z,1636564163.230 [DAT](DEBUG): Initializing DAT.
2021-11-10T17:09:24.848Z,1636564164.848 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170924.00,A,3649.20111,N,12204.30801,W,19.322,264.33,101121,,,D*4D
2021-11-10T17:09:24.859Z,1636564164.859 [NAL9602](INFO): GPS fix at 20211110T170924: (36.820019, -122.071800)
2021-11-10T17:09:24.871Z,1636564164.871 [Default:CheckIn:Read_GPS] Stopped
2021-11-10T17:09:24.871Z,1636564164.871 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-11-10T17:09:32.401Z,1636564172.401 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20211110T163654/Courier0019.lzma
2021-11-10T17:09:33.403Z,1636564173.403 [DataOverHttps](INFO): Moved sent file to Logs/20211110T163654/Courier0019.lzma.bak
2021-11-10T17:09:33.403Z,1636564173.403 [DataOverHttps](INFO): SBD MOMSN=16230247
2021-11-10T17:09:34.547Z,1636564174.547 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout.
2021-11-10T17:09:34.547Z,1636564174.547 [RDI_Pathfinder] Data Fault, FailCount= 1
2021-11-10T17:09:34.547Z,1636564174.547 [RDI_Pathfinder](ERROR): Data Fault
2021-11-10T17:09:34.584Z,1636564174.584 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder
2021-11-10T17:09:35.013Z,1636564175.013 [RDI_Pathfinder](INFO): Powering down
2021-11-10T17:09:35.779Z,1636564175.779 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2021-11-10T17:09:35.779Z,1636564175.779 [RDI_Pathfinder] No Fault, FailCount= 1
2021-11-10T17:09:49.161Z,1636564189.161 [DataOverHttps](INFO): Sending 265 bytes from file Logs/20211110T163654/Express0020.lzma
2021-11-10T17:09:50.163Z,1636564190.163 [DataOverHttps](INFO): Moved sent file to Logs/20211110T163654/Express0020.lzma.bak
2021-11-10T17:09:50.163Z,1636564190.163 [DataOverHttps](INFO): SBD MOMSN=16230251
2021-11-10T17:09:51.512Z,1636564191.512 [NAL9602](INFO): SBD MO Status=0, MOMSN=17328, MT Status=0, MTMSN=0
2021-11-10T17:09:51.512Z,1636564191.512 [NAL9602](INFO): No messages in MT queue
2021-11-10T17:09:51.550Z,1636564191.550 [Default:CheckIn:Read_Iridium] Stopped
2021-11-10T17:09:51.550Z,1636564191.550 [Default:CheckIn:C.Wait] Running Loop=1
2021-11-10T17:09:51.550Z,1636564191.550 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-11-10T17:09:58.379Z,1636564198.379 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2021-11-10T17:09:58.379Z,1636564198.379 [RDI_Pathfinder] Communications Fault, FailCount= 2
2021-11-10T17:09:58.379Z,1636564198.379 [RDI_Pathfinder](ERROR): Communications Fault
2021-11-10T17:09:58.399Z,1636564198.399 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2021-11-10T17:09:58.857Z,1636564198.857 [RDI_Pathfinder](INFO): Powering down
2021-11-10T17:09:59.640Z,1636564199.640 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2021-11-10T17:09:59.640Z,1636564199.640 [RDI_Pathfinder] No Fault, FailCount= 2
2021-11-10T17:10:22.234Z,1636564222.234 [NAL9602](INFO): Not Powering down - fast GPS
2021-11-10T17:10:22.237Z,1636564222.237 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2021-11-10T17:10:22.237Z,1636564222.237 [RDI_Pathfinder] Communications Fault, FailCount= 3
2021-11-10T17:10:22.237Z,1636564222.237 [RDI_Pathfinder](ERROR): Communications Fault
2021-11-10T17:10:22.307Z,1636564222.307 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2021-11-10T17:10:22.308Z,1636564222.308 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder
2021-11-10T17:10:22.713Z,1636564222.713 [RDI_Pathfinder](INFO): Powering down
2021-11-10T17:10:22.738Z,1636564222.738 [CBIT](INFO): Critical error at 20211110T171022
2021-11-10T17:10:23.453Z,1636564223.453 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-11-10T17:10:23.454Z,1636564223.454 [DAT] Communications Fault, FailCount= 6
2021-11-10T17:10:23.454Z,1636564223.454 [DAT](ERROR): Communications Fault
2021-11-10T17:10:23.479Z,1636564223.479 [CBIT](ERROR): Communications Fault in component: DAT
2021-11-10T17:10:23.850Z,1636564223.850 [DAT](INFO): Powering down
2021-11-10T17:10:24.690Z,1636564224.690 [CBIT](INFO): Clearing failed state for component DAT
2021-11-10T17:10:24.690Z,1636564224.690 [DAT] No Fault, FailCount= 6
2021-11-10T17:10:27.086Z,1636564227.086 [DAT](INFO): Powering up
2021-11-10T17:10:27.086Z,1636564227.086 [DAT](DEBUG): Initializing DAT.
2021-11-10T17:11:27.283Z,1636564287.283 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-11-10T17:11:27.283Z,1636564287.283 [DAT] Communications Fault, FailCount= 7
2021-11-10T17:11:27.283Z,1636564287.283 [DAT](ERROR): Communications Fault
2021-11-10T17:11:27.303Z,1636564287.303 [CBIT](ERROR): Communications Fault in component: DAT
2021-11-10T17:11:27.682Z,1636564287.682 [DAT](INFO): Powering down
2021-11-10T17:11:28.510Z,1636564288.510 [CBIT](INFO): Clearing failed state for component DAT
2021-11-10T17:11:28.510Z,1636564288.510 [DAT] No Fault, FailCount= 7
2021-11-10T17:11:30.915Z,1636564290.915 [DAT](INFO): Powering up
2021-11-10T17:11:30.915Z,1636564290.915 [DAT](DEBUG): Initializing DAT.
2021-11-10T17:12:31.110Z,1636564351.110 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-11-10T17:12:31.110Z,1636564351.110 [DAT] Communications Fault, FailCount= 8
2021-11-10T17:12:31.110Z,1636564351.110 [DAT](ERROR): Communications Fault
2021-11-10T17:12:31.163Z,1636564351.163 [CBIT](ERROR): Communications Fault in component: DAT
2021-11-10T17:12:31.163Z,1636564351.163 [CBIT](FAULT): Communications Fault in component: DAT
2021-11-10T17:12:31.520Z,1636564351.520 [DAT](INFO): Powering down
2021-11-10T17:12:32.348Z,1636564352.348 [Power24vConverter](INFO): Powering down.
2021-11-10T17:13:22.445Z,1636564402.445 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2021-11-10T17:13:22.445Z,1636564402.445 [RDI_Pathfinder] No Fault, FailCount= 3
2021-11-10T17:13:23.232Z,1636564403.232 [Power24vConverter](INFO): Powering up.
2021-11-10T17:13:35.347Z,1636564415.347 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout.
2021-11-10T17:13:35.347Z,1636564415.347 [RDI_Pathfinder] Data Fault, FailCount= 1
2021-11-10T17:13:35.347Z,1636564415.347 [RDI_Pathfinder](ERROR): Data Fault
2021-11-10T17:13:35.367Z,1636564415.367 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder
2021-11-10T17:13:35.825Z,1636564415.825 [RDI_Pathfinder](INFO): Powering down
2021-11-10T17:13:36.609Z,1636564416.609 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2021-11-10T17:13:36.609Z,1636564416.609 [RDI_Pathfinder] No Fault, FailCount= 1
2021-11-10T17:13:59.188Z,1636564439.188 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2021-11-10T17:13:59.188Z,1636564439.188 [RDI_Pathfinder] Communications Fault, FailCount= 2
2021-11-10T17:13:59.188Z,1636564439.188 [RDI_Pathfinder](ERROR): Communications Fault
2021-11-10T17:13:59.209Z,1636564439.209 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2021-11-10T17:13:59.653Z,1636564439.653 [RDI_Pathfinder](INFO): Powering down
2021-11-10T17:14:00.419Z,1636564440.419 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2021-11-10T17:14:00.419Z,1636564440.419 [RDI_Pathfinder] No Fault, FailCount= 2
2021-11-10T17:14:23.019Z,1636564463.019 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2021-11-10T17:14:23.019Z,1636564463.019 [RDI_Pathfinder] Communications Fault, FailCount= 3
2021-11-10T17:14:23.019Z,1636564463.019 [RDI_Pathfinder](ERROR): Communications Fault
2021-11-10T17:14:23.040Z,1636564463.040 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2021-11-10T17:14:23.040Z,1636564463.040 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder
2021-11-10T17:14:23.497Z,1636564463.497 [RDI_Pathfinder](INFO): Powering down
2021-11-10T17:14:23.540Z,1636564463.540 [CBIT](INFO): Critical error at 20211110T171423
2021-11-10T17:14:52.116Z,1636564492.116 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-11-10T17:14:52.116Z,1636564492.116 [Default:CheckIn:C.Wait] Stopped
2021-11-10T17:14:52.116Z,1636564492.116 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-11-10T17:14:52.116Z,1636564492.116 [Default:CheckIn:D] Running Loop=1
2021-11-10T17:14:52.536Z,1636564492.536 [Default:CheckIn:D] Stopped
2021-11-10T17:14:52.536Z,1636564492.536 [Default:CheckIn:E] Running Loop=1
2021-11-10T17:14:52.936Z,1636564492.936 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 35.001298 min 
2021-11-10T17:14:52.936Z,1636564492.936 [Default:CheckIn:E] Stopped
2021-11-10T17:14:52.936Z,1636564492.936 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-11-10T17:14:52.936Z,1636564492.936 [Default:CheckIn] Stopped
2021-11-10T17:14:52.936Z,1636564492.936 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-11-10T17:14:52.936Z,1636564492.936 [Default:CheckIn](INFO): Running loop #7
2021-11-10T17:14:52.937Z,1636564492.937 [Default:CheckIn] Running Loop=7
2021-11-10T17:14:52.937Z,1636564492.937 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-11-10T17:14:52.937Z,1636564492.937 [Default:CheckIn:Read_GPS] Running Loop=1
2021-11-10T17:14:54.940Z,1636564494.940 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171454.00,A,3649.30970,N,12206.54664,W,21.227,278.83,101121,,,D*4C
2021-11-10T17:14:54.942Z,1636564494.942 [NAL9602](INFO): GPS fix at 20211110T171454: (36.821828, -122.109111)
2021-11-10T17:14:54.977Z,1636564494.977 [Default:CheckIn:Read_GPS] Stopped
2021-11-10T17:14:54.977Z,1636564494.977 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-11-10T17:15:02.645Z,1636564502.645 [DataOverHttps](INFO): Sending 134 bytes from file Logs/20211110T163654/Courier0022.lzma
2021-11-10T17:15:03.646Z,1636564503.646 [DataOverHttps](INFO): Moved sent file to Logs/20211110T163654/Courier0022.lzma.bak
2021-11-10T17:15:03.647Z,1636564503.647 [DataOverHttps](INFO): SBD MOMSN=16230269
2021-11-10T17:15:19.987Z,1636564519.987 [NAL9602](INFO): SBD MO Status=2, MOMSN=17329, MT Status=2, MTMSN=0
2021-11-10T17:15:19.988Z,1636564519.988 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-11-10T17:15:20.401Z,1636564520.401 [DataOverHttps](INFO): Sending 294 bytes from file Logs/20211110T163654/Express0023.lzma
2021-11-10T17:15:21.403Z,1636564521.403 [DataOverHttps](INFO): Moved sent file to Logs/20211110T163654/Express0023.lzma.bak
2021-11-10T17:15:21.403Z,1636564521.403 [DataOverHttps](INFO): SBD MOMSN=16230274
2021-11-10T17:15:22.841Z,1636564522.841 [Default:CheckIn:Read_Iridium] Stopped
2021-11-10T17:15:22.841Z,1636564522.841 [Default:CheckIn:C.Wait] Running Loop=1
2021-11-10T17:15:22.841Z,1636564522.841 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-11-10T17:15:54.327Z,1636564554.327 [NAL9602](INFO): SBD MO Status=2, MOMSN=17329, MT Status=2, MTMSN=0
2021-11-10T17:15:54.328Z,1636564554.328 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-11-10T17:16:02.807Z,1636564562.807 [NAL9602](INFO): SBD MO Status=0, MOMSN=17329, MT Status=0, MTMSN=0
2021-11-10T17:16:02.808Z,1636564562.808 [NAL9602](INFO): No messages in MT queue
2021-11-10T17:16:33.524Z,1636564593.524 [NAL9602](INFO): Not Powering down - fast GPS
2021-11-10T17:17:23.221Z,1636564643.221 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2021-11-10T17:17:23.221Z,1636564643.221 [RDI_Pathfinder] No Fault, FailCount= 3
2021-11-10T17:17:31.304Z,1636564651.304 [CBIT](INFO): Clearing failed state for component DAT
2021-11-10T17:17:31.304Z,1636564651.304 [DAT] No Fault, FailCount= 8
2021-11-10T17:17:31.696Z,1636564651.696 [DAT](INFO): Powering up
2021-11-10T17:17:31.696Z,1636564651.696 [DAT](DEBUG): Initializing DAT.
2021-11-10T17:17:36.135Z,1636564656.135 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout.
2021-11-10T17:17:36.135Z,1636564656.135 [RDI_Pathfinder] Data Fault, FailCount= 1
2021-11-10T17:17:36.135Z,1636564656.135 [RDI_Pathfinder](ERROR): Data Fault
2021-11-10T17:17:36.156Z,1636564656.156 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder
2021-11-10T17:17:36.613Z,1636564656.613 [RDI_Pathfinder](INFO): Powering down
2021-11-10T17:17:37.392Z,1636564657.392 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2021-11-10T17:17:37.392Z,1636564657.392 [RDI_Pathfinder] No Fault, FailCount= 1
2021-11-10T17:17:59.971Z,1636564679.971 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2021-11-10T17:17:59.971Z,1636564679.971 [RDI_Pathfinder] Communications Fault, FailCount= 2
2021-11-10T17:17:59.971Z,1636564679.971 [RDI_Pathfinder](ERROR): Communications Fault
2021-11-10T17:18:00.008Z,1636564680.008 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2021-11-10T17:18:00.441Z,1636564680.441 [RDI_Pathfinder](INFO): Powering down
2021-11-10T17:18:01.214Z,1636564681.214 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2021-11-10T17:18:01.214Z,1636564681.214 [RDI_Pathfinder] No Fault, FailCount= 2
2021-11-10T17:18:23.802Z,1636564703.802 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2021-11-10T17:18:23.802Z,1636564703.802 [RDI_Pathfinder] Communications Fault, FailCount= 3
2021-11-10T17:18:23.802Z,1636564703.802 [RDI_Pathfinder](ERROR): Communications Fault
2021-11-10T17:18:23.846Z,1636564703.846 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2021-11-10T17:18:23.846Z,1636564703.846 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder
2021-11-10T17:18:24.289Z,1636564704.289 [RDI_Pathfinder](INFO): Powering down
2021-11-10T17:18:24.341Z,1636564704.341 [CBIT](INFO): Critical error at 20211110T171823
2021-11-10T17:18:31.886Z,1636564711.886 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-11-10T17:18:31.887Z,1636564711.887 [DAT] Communications Fault, FailCount= 1
2021-11-10T17:18:31.887Z,1636564711.887 [DAT](ERROR): Communications Fault
2021-11-10T17:18:31.907Z,1636564711.907 [CBIT](ERROR): Communications Fault in component: DAT
2021-11-10T17:18:32.288Z,1636564712.288 [DAT](INFO): Powering down
2021-11-10T17:18:33.144Z,1636564713.144 [CBIT](INFO): Clearing failed state for component DAT
2021-11-10T17:18:33.144Z,1636564713.144 [DAT] No Fault, FailCount= 1
2021-11-10T17:18:35.526Z,1636564715.526 [DAT](INFO): Powering up
2021-11-10T17:18:35.526Z,1636564715.526 [DAT](DEBUG): Initializing DAT.
2021-11-10T17:19:35.727Z,1636564775.727 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-11-10T17:19:35.727Z,1636564775.727 [DAT] Communications Fault, FailCount= 2
2021-11-10T17:19:35.727Z,1636564775.727 [DAT](ERROR): Communications Fault
2021-11-10T17:19:35.787Z,1636564775.787 [CBIT](ERROR): Communications Fault in component: DAT
2021-11-10T17:19:36.123Z,1636564776.123 [DAT](INFO): Powering down
2021-11-10T17:19:36.962Z,1636564776.962 [CBIT](INFO): Clearing failed state for component DAT
2021-11-10T17:19:36.962Z,1636564776.962 [DAT] No Fault, FailCount= 2
2021-11-10T17:19:39.352Z,1636564779.352 [DAT](INFO): Powering up
2021-11-10T17:19:39.352Z,1636564779.352 [DAT](DEBUG): Initializing DAT.
2021-11-10T17:20:23.405Z,1636564823.405 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-11-10T17:20:23.405Z,1636564823.405 [Default:CheckIn:C.Wait] Stopped
2021-11-10T17:20:23.405Z,1636564823.405 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-11-10T17:20:23.405Z,1636564823.405 [Default:CheckIn:D] Running Loop=1
2021-11-10T17:20:23.813Z,1636564823.813 [Default:CheckIn:D] Stopped
2021-11-10T17:20:23.813Z,1636564823.813 [Default:CheckIn:E] Running Loop=1
2021-11-10T17:20:24.216Z,1636564824.216 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 40.522587 min 
2021-11-10T17:20:24.216Z,1636564824.216 [Default:CheckIn:E] Stopped
2021-11-10T17:20:24.221Z,1636564824.221 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-11-10T17:20:24.221Z,1636564824.221 [Default:CheckIn] Stopped
2021-11-10T17:20:24.221Z,1636564824.221 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-11-10T17:20:24.221Z,1636564824.221 [Default:CheckIn](INFO): Running loop #8
2021-11-10T17:20:24.221Z,1636564824.221 [Default:CheckIn] Running Loop=8
2021-11-10T17:20:24.221Z,1636564824.221 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-11-10T17:20:24.221Z,1636564824.221 [Default:CheckIn:Read_GPS] Running Loop=1
2021-11-10T17:20:26.224Z,1636564826.224 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172025.00,A,3649.33528,N,12208.37085,W,16.114,283.07,101121,,,D*42
2021-11-10T17:20:26.226Z,1636564826.226 [NAL9602](INFO): GPS fix at 20211110T172025: (36.822255, -122.139514)
2021-11-10T17:20:26.261Z,1636564826.261 [Default:CheckIn:Read_GPS] Stopped
2021-11-10T17:20:26.261Z,1636564826.261 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-11-10T17:20:33.528Z,1636564833.528 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20211110T163654/Courier0025.lzma
2021-11-10T17:20:34.531Z,1636564834.531 [DataOverHttps](INFO): Moved sent file to Logs/20211110T163654/Courier0025.lzma.bak
2021-11-10T17:20:34.531Z,1636564834.531 [DataOverHttps](INFO): SBD MOMSN=16230296
2021-11-10T17:20:38.027Z,1636564838.027 [NAL9602](INFO): SBD MO Status=0, MOMSN=17330, MT Status=0, MTMSN=0
2021-11-10T17:20:38.028Z,1636564838.028 [NAL9602](INFO): No messages in MT queue
2021-11-10T17:20:39.643Z,1636564839.643 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-11-10T17:20:39.643Z,1636564839.643 [DAT] Communications Fault, FailCount= 3
2021-11-10T17:20:39.643Z,1636564839.643 [DAT](ERROR): Communications Fault
2021-11-10T17:20:39.728Z,1636564839.728 [CBIT](ERROR): Communications Fault in component: DAT
2021-11-10T17:20:40.046Z,1636564840.046 [DAT](INFO): Powering down
2021-11-10T17:20:40.926Z,1636564840.926 [CBIT](INFO): Clearing failed state for component DAT
2021-11-10T17:20:40.926Z,1636564840.926 [DAT] No Fault, FailCount= 3
2021-11-10T17:20:43.278Z,1636564843.278 [DAT](INFO): Powering up
2021-11-10T17:20:43.278Z,1636564843.278 [DAT](DEBUG): Initializing DAT.
2021-11-10T17:20:50.581Z,1636564850.581 [DataOverHttps](INFO): Sending 247 bytes from file Logs/20211110T163654/Express0026.lzma
2021-11-10T17:20:51.582Z,1636564851.582 [DataOverHttps](INFO): Moved sent file to Logs/20211110T163654/Express0026.lzma.bak
2021-11-10T17:20:51.583Z,1636564851.583 [DataOverHttps](INFO): SBD MOMSN=16230300
2021-11-10T17:20:52.985Z,1636564852.985 [Default:CheckIn:Read_Iridium] Stopped
2021-11-10T17:20:52.985Z,1636564852.985 [Default:CheckIn:C.Wait] Running Loop=1
2021-11-10T17:20:52.985Z,1636564852.985 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-11-10T17:21:08.729Z,1636564868.729 [NAL9602](INFO): Not Powering down - fast GPS
2021-11-10T17:21:24.106Z,1636564884.106 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2021-11-10T17:21:24.106Z,1636564884.106 [RDI_Pathfinder] No Fault, FailCount= 3
2021-11-10T17:21:37.010Z,1636564897.010 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout.
2021-11-10T17:21:37.010Z,1636564897.010 [RDI_Pathfinder] Data Fault, FailCount= 1
2021-11-10T17:21:37.010Z,1636564897.010 [RDI_Pathfinder](ERROR): Data Fault
2021-11-10T17:21:37.056Z,1636564897.056 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder
2021-11-10T17:21:37.485Z,1636564897.485 [RDI_Pathfinder](INFO): Powering down
2021-11-10T17:21:38.247Z,1636564898.247 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2021-11-10T17:21:38.248Z,1636564898.248 [RDI_Pathfinder] No Fault, FailCount= 1
2021-11-10T17:21:43.479Z,1636564903.479 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-11-10T17:21:43.479Z,1636564903.479 [DAT] Communications Fault, FailCount= 4
2021-11-10T17:21:43.479Z,1636564903.479 [DAT](ERROR): Communications Fault
2021-11-10T17:21:43.524Z,1636564903.524 [CBIT](ERROR): Communications Fault in component: DAT
2021-11-10T17:21:43.879Z,1636564903.879 [DAT](INFO): Powering down
2021-11-10T17:21:44.713Z,1636564904.713 [CBIT](INFO): Clearing failed state for component DAT
2021-11-10T17:21:44.714Z,1636564904.714 [DAT] No Fault, FailCount= 4
2021-11-10T17:21:47.115Z,1636564907.115 [DAT](INFO): Powering up
2021-11-10T17:21:47.115Z,1636564907.115 [DAT](DEBUG): Initializing DAT.
2021-11-10T17:22:00.846Z,1636564920.846 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2021-11-10T17:22:00.846Z,1636564920.846 [RDI_Pathfinder] Communications Fault, FailCount= 2
2021-11-10T17:22:00.846Z,1636564920.846 [RDI_Pathfinder](ERROR): Communications Fault
2021-11-10T17:22:00.867Z,1636564920.867 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2021-11-10T17:22:01.321Z,1636564921.321 [RDI_Pathfinder](INFO): Powering down
2021-11-10T17:22:02.117Z,1636564922.117 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2021-11-10T17:22:02.117Z,1636564922.117 [RDI_Pathfinder] No Fault, FailCount= 2
2021-11-10T17:22:24.750Z,1636564944.750 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2021-11-10T17:22:24.751Z,1636564944.751 [RDI_Pathfinder] Communications Fault, FailCount= 3
2021-11-10T17:22:24.751Z,1636564944.751 [RDI_Pathfinder](ERROR): Communications Fault
2021-11-10T17:22:24.803Z,1636564944.803 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2021-11-10T17:22:24.803Z,1636564944.803 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder
2021-11-10T17:22:25.217Z,1636564945.217 [RDI_Pathfinder](INFO): Powering down
2021-11-10T17:22:25.266Z,1636564945.266 [CBIT](INFO): Critical error at 20211110T172224
2021-11-10T17:22:47.371Z,1636564967.371 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-11-10T17:22:47.371Z,1636564967.371 [DAT] Communications Fault, FailCount= 5
2021-11-10T17:22:47.371Z,1636564967.371 [DAT](ERROR): Communications Fault
2021-11-10T17:22:47.392Z,1636564967.392 [CBIT](ERROR): Communications Fault in component: DAT
2021-11-10T17:22:47.774Z,1636564967.774 [DAT](INFO): Powering down
2021-11-10T17:22:48.614Z,1636564968.614 [CBIT](INFO): Clearing failed state for component DAT
2021-11-10T17:22:48.614Z,1636564968.614 [DAT] No Fault, FailCount= 5
2021-11-10T17:22:51.010Z,1636564971.010 [DAT](INFO): Powering up
2021-11-10T17:22:51.010Z,1636564971.010 [DAT](DEBUG): Initializing DAT.
2021-11-10T17:23:51.219Z,1636565031.219 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-11-10T17:23:51.219Z,1636565031.219 [DAT] Communications Fault, FailCount= 6
2021-11-10T17:23:51.219Z,1636565031.219 [DAT](ERROR): Communications Fault
2021-11-10T17:23:51.260Z,1636565031.260 [CBIT](ERROR): Communications Fault in component: DAT
2021-11-10T17:23:51.626Z,1636565031.626 [DAT](INFO): Powering down
2021-11-10T17:23:52.431Z,1636565032.431 [Power24vConverter](INFO): Powering down.
2021-11-10T17:23:52.506Z,1636565032.506 [CBIT](INFO): Clearing failed state for component DAT
2021-11-10T17:23:52.506Z,1636565032.506 [DAT] No Fault, FailCount= 6
2021-11-10T17:23:53.238Z,1636565033.238 [Power24vConverter](INFO): Powering up.
2021-11-10T17:23:54.866Z,1636565034.866 [DAT](INFO): Powering up
2021-11-10T17:23:54.866Z,1636565034.866 [DAT](DEBUG): Initializing DAT.
2021-11-10T17:24:55.054Z,1636565095.054 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-11-10T17:24:55.055Z,1636565095.055 [DAT] Communications Fault, FailCount= 7
2021-11-10T17:24:55.055Z,1636565095.055 [DAT](ERROR): Communications Fault
2021-11-10T17:24:55.109Z,1636565095.109 [CBIT](ERROR): Communications Fault in component: DAT
2021-11-10T17:24:55.454Z,1636565095.454 [DAT](INFO): Powering down
2021-11-10T17:24:56.300Z,1636565096.300 [CBIT](INFO): Clearing failed state for component DAT
2021-11-10T17:24:56.301Z,1636565096.301 [DAT] No Fault, FailCount= 7
2021-11-10T17:24:58.694Z,1636565098.694 [DAT](INFO): Powering up
2021-11-10T17:24:58.694Z,1636565098.694 [DAT](DEBUG): Initializing DAT.
2021-11-10T17:25:24.969Z,1636565124.969 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2021-11-10T17:25:24.969Z,1636565124.969 [RDI_Pathfinder] No Fault, FailCount= 3
2021-11-10T17:25:37.885Z,1636565137.885 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout.
2021-11-10T17:25:37.885Z,1636565137.885 [RDI_Pathfinder] Data Fault, FailCount= 1
2021-11-10T17:25:37.885Z,1636565137.885 [RDI_Pathfinder](ERROR): Data Fault
2021-11-10T17:25:37.937Z,1636565137.937 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder
2021-11-10T17:25:38.357Z,1636565138.357 [RDI_Pathfinder](INFO): Powering down
2021-11-10T17:25:39.154Z,1636565139.154 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2021-11-10T17:25:39.154Z,1636565139.154 [RDI_Pathfinder] No Fault, FailCount= 1
2021-11-10T17:25:53.649Z,1636565153.649 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-11-10T17:25:53.649Z,1636565153.649 [Default:CheckIn:C.Wait] Stopped
2021-11-10T17:25:53.649Z,1636565153.649 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-11-10T17:25:53.649Z,1636565153.649 [Default:CheckIn:D] Running Loop=1
2021-11-10T17:25:54.049Z,1636565154.049 [Default:CheckIn:D] Stopped
2021-11-10T17:25:54.049Z,1636565154.049 [Default:CheckIn:E] Running Loop=1
2021-11-10T17:25:54.449Z,1636565154.449 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 46.026530 min 
2021-11-10T17:25:54.449Z,1636565154.449 [Default:CheckIn:E] Stopped
2021-11-10T17:25:54.449Z,1636565154.449 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-11-10T17:25:54.449Z,1636565154.449 [Default:CheckIn] Stopped
2021-11-10T17:25:54.449Z,1636565154.449 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-11-10T17:25:54.450Z,1636565154.450 [Default:CheckIn](INFO): Running loop #9
2021-11-10T17:25:54.450Z,1636565154.450 [Default:CheckIn] Running Loop=9
2021-11-10T17:25:54.450Z,1636565154.450 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-11-10T17:25:54.450Z,1636565154.450 [Default:CheckIn:Read_GPS] Running Loop=1
2021-11-10T17:25:56.462Z,1636565156.462 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172556.00,A,3649.13012,N,12209.35519,W,1.069,134.77,101121,,,D*7B
2021-11-10T17:25:56.464Z,1636565156.464 [NAL9602](INFO): GPS fix at 20211110T172556: (36.818835, -122.155920)
2021-11-10T17:25:56.476Z,1636565156.476 [Default:CheckIn:Read_GPS] Stopped
2021-11-10T17:25:56.476Z,1636565156.476 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-11-10T17:25:58.950Z,1636565158.950 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-11-10T17:25:58.950Z,1636565158.950 [DAT] Communications Fault, FailCount= 8
2021-11-10T17:25:58.950Z,1636565158.950 [DAT](ERROR): Communications Fault
2021-11-10T17:25:58.997Z,1636565158.997 [CBIT](ERROR): Communications Fault in component: DAT
2021-11-10T17:25:58.998Z,1636565158.998 [CBIT](FAULT): Communications Fault in component: DAT
2021-11-10T17:25:59.340Z,1636565159.340 [DAT](INFO): Powering down
2021-11-10T17:26:01.763Z,1636565161.763 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2021-11-10T17:26:01.763Z,1636565161.763 [RDI_Pathfinder] Communications Fault, FailCount= 2
2021-11-10T17:26:01.763Z,1636565161.763 [RDI_Pathfinder](ERROR): Communications Fault
2021-11-10T17:26:01.845Z,1636565161.845 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2021-11-10T17:26:02.237Z,1636565162.237 [RDI_Pathfinder](INFO): Powering down
2021-11-10T17:26:02.998Z,1636565162.998 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2021-11-10T17:26:02.998Z,1636565162.998 [RDI_Pathfinder] No Fault, FailCount= 2
2021-11-10T17:26:03.553Z,1636565163.553 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20211110T163654/Courier0028.lzma
2021-11-10T17:26:04.554Z,1636565164.554 [DataOverHttps](INFO): Moved sent file to Logs/20211110T163654/Courier0028.lzma.bak
2021-11-10T17:26:04.555Z,1636565164.555 [DataOverHttps](INFO): SBD MOMSN=16230312
2021-11-10T17:26:21.637Z,1636565181.637 [DataOverHttps](INFO): Sending 274 bytes from file Logs/20211110T163654/Express0029.lzma
2021-11-10T17:26:22.639Z,1636565182.639 [DataOverHttps](INFO): Moved sent file to Logs/20211110T163654/Express0029.lzma.bak
2021-11-10T17:26:22.639Z,1636565182.639 [DataOverHttps](INFO): SBD MOMSN=16230316
2021-11-10T17:26:24.023Z,1636565184.023 [Default:CheckIn:Read_Iridium] Stopped
2021-11-10T17:26:24.023Z,1636565184.023 [Default:CheckIn:C.Wait] Running Loop=1
2021-11-10T17:26:24.023Z,1636565184.023 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-11-10T17:26:25.603Z,1636565185.603 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2021-11-10T17:26:25.603Z,1636565185.603 [RDI_Pathfinder] Communications Fault, FailCount= 3
2021-11-10T17:26:25.603Z,1636565185.603 [RDI_Pathfinder](ERROR): Communications Fault
2021-11-10T17:26:25.707Z,1636565185.707 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2021-11-10T17:26:25.708Z,1636565185.708 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder
2021-11-10T17:26:26.102Z,1636565186.102 [RDI_Pathfinder](INFO): Powering down
2021-11-10T17:26:26.179Z,1636565186.179 [CBIT](INFO): Critical error at 20211110T172625
2021-11-10T17:26:43.411Z,1636565203.411 [NAL9602](INFO): SBD MO Status=0, MOMSN=17331, MT Status=0, MTMSN=0
2021-11-10T17:26:43.412Z,1636565203.412 [NAL9602](INFO): No messages in MT queue
2021-11-10T17:27:14.111Z,1636565234.111 [NAL9602](INFO): Not Powering down - fast GPS
2021-11-10T17:29:25.883Z,1636565365.883 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2021-11-10T17:29:25.883Z,1636565365.883 [RDI_Pathfinder] No Fault, FailCount= 3
2021-11-10T17:29:38.746Z,1636565378.746 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout.
2021-11-10T17:29:38.746Z,1636565378.746 [RDI_Pathfinder] Data Fault, FailCount= 1
2021-11-10T17:29:38.746Z,1636565378.746 [RDI_Pathfinder](ERROR): Data Fault
2021-11-10T17:29:38.792Z,1636565378.792 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder
2021-11-10T17:29:39.213Z,1636565379.213 [RDI_Pathfinder](INFO): Powering down
2021-11-10T17:29:39.983Z,1636565379.983 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2021-11-10T17:29:39.983Z,1636565379.983 [RDI_Pathfinder] No Fault, FailCount= 1
2021-11-10T17:30:02.574Z,1636565402.574 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2021-11-10T17:30:02.575Z,1636565402.575 [RDI_Pathfinder] Communications Fault, FailCount= 2
2021-11-10T17:30:02.575Z,1636565402.575 [RDI_Pathfinder](ERROR): Communications Fault
2021-11-10T17:30:02.636Z,1636565402.636 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2021-11-10T17:30:03.049Z,1636565403.049 [RDI_Pathfinder](INFO): Powering down
2021-11-10T17:30:03.815Z,1636565403.815 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2021-11-10T17:30:03.815Z,1636565403.815 [RDI_Pathfinder] No Fault, FailCount= 2
2021-11-10T17:30:26.411Z,1636565426.411 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2021-11-10T17:30:26.411Z,1636565426.411 [RDI_Pathfinder] Communications Fault, FailCount= 3
2021-11-10T17:30:26.411Z,1636565426.411 [RDI_Pathfinder](ERROR): Communications Fault
2021-11-10T17:30:26.431Z,1636565426.431 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2021-11-10T17:30:26.431Z,1636565426.431 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder
2021-11-10T17:30:26.889Z,1636565426.889 [RDI_Pathfinder](INFO): Powering down
2021-11-10T17:30:26.907Z,1636565426.907 [CBIT](INFO): Critical error at 20211110T173026
2021-11-10T17:30:40.186Z,1636565440.186 [DataOverHttps](IMPORTANT): SBD MTMSN=20211110T173039
2021-11-10T17:30:47.537Z,1636565447.537 [DataOverHttps](INFO): Received command: configSet RDI_Pathfinder.loadAtStartup 0 bool persist
2021-11-10T17:30:47.749Z,1636565447.749 [CommandExec](IMPORTANT): got command configSet RDI_Pathfinder.loadAtStartup 0.000000 bool persist
2021-11-10T17:30:47.749Z,1636565447.749 [CommandExec](IMPORTANT): configSet RDI_Pathfinder.loadAtStartup requires a restart to take effect.
2021-11-10T17:30:47.750Z,1636565447.750 [CommandExec](FAULT): Not allowing to overwrite existing Data/persisted.cfg after unclean shutdown.
2021-11-10T17:30:59.169Z,1636565459.169 [CBIT](INFO): Clearing failed state for component DAT
2021-11-10T17:30:59.170Z,1636565459.170 [DAT] No Fault, FailCount= 8
2021-11-10T17:30:59.543Z,1636565459.543 [DAT](INFO): Powering up
2021-11-10T17:30:59.543Z,1636565459.543 [DAT](DEBUG): Initializing DAT.
2021-11-10T17:31:24.621Z,1636565484.621 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-11-10T17:31:24.621Z,1636565484.621 [Default:CheckIn:C.Wait] Stopped
2021-11-10T17:31:24.621Z,1636565484.621 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-11-10T17:31:24.621Z,1636565484.621 [Default:CheckIn:D] Running Loop=1
2021-11-10T17:31:25.047Z,1636565485.047 [Default:CheckIn:D] Stopped
2021-11-10T17:31:25.047Z,1636565485.047 [Default:CheckIn:E] Running Loop=1
2021-11-10T17:31:25.404Z,1636565485.404 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 51.543152 min 
2021-11-10T17:31:25.404Z,1636565485.404 [Default:CheckIn:E] Stopped
2021-11-10T17:31:25.404Z,1636565485.404 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-11-10T17:31:25.404Z,1636565485.404 [Default:CheckIn] Stopped
2021-11-10T17:31:25.404Z,1636565485.404 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-11-10T17:31:25.405Z,1636565485.405 [Default:CheckIn](INFO): Running loop #10
2021-11-10T17:31:25.405Z,1636565485.405 [Default:CheckIn] Running Loop=10
2021-11-10T17:31:25.405Z,1636565485.405 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-11-10T17:31:25.405Z,1636565485.405 [Default:CheckIn:Read_GPS] Running Loop=1
2021-11-10T17:31:27.424Z,1636565487.424 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173127.00,A,3649.09569,N,12209.34462,W,1.536,124.60,101121,,,D*7E
2021-11-10T17:31:27.426Z,1636565487.426 [NAL9602](INFO): GPS fix at 20211110T173127: (36.818261, -122.155744)
2021-11-10T17:31:27.438Z,1636565487.438 [Default:CheckIn:Read_GPS] Stopped
2021-11-10T17:31:27.438Z,1636565487.438 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-11-10T17:31:28.507Z,1636565488.507 [DataOverHttps](IMPORTANT): SBD MTMSN=20211110T173127
2021-11-10T17:31:35.569Z,1636565495.569 [DataOverHttps](INFO): Sending 135 bytes from file Logs/20211110T163654/Courier0031.lzma
2021-11-10T17:31:35.572Z,1636565495.572 [DataOverHttps](INFO): Received command: restart app
2021-11-10T17:31:35.594Z,1636565495.594 [CommandExec](IMPORTANT): got command restart application
2021-11-10T17:31:36.597Z,1636565496.597 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2021-11-10T17:31:36.597Z,1636565496.597 [CommandExec](INFO): Uninitializing the command executive.
2021-11-10T17:31:36.597Z,1636565496.597 [CommandExec](INFO): Uninitializing the command scheduler.
2021-11-10T17:31:36.597Z,1636565496.597 [CommandExec ThreadHandler](INFO): Thread cancelled.
2021-11-10T17:31:36.661Z,1636565496.661 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2021-11-10T17:31:36.661Z,1636565496.661 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2021-11-10T17:31:36.661Z,1636565496.661 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-11-10T17:31:36.663Z,1636565496.663 [NavChartDb](INFO): Join timeout helper Thread ID is 1126
2021-11-10T17:31:36.849Z,1636565496.849 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2021-11-10T17:31:36.849Z,1636565496.849 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-11-10T17:31:36.861Z,1636565496.861 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler
2021-11-10T17:31:36.861Z,1636565496.861 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2021-11-10T17:31:36.861Z,1636565496.861 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 1127
2021-11-10T17:31:37.241Z,1636565497.241 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread.
2021-11-10T17:31:37.241Z,1636565497.241 [WetLabsUBAT](INFO): Powering down
2021-11-10T17:31:37.242Z,1636565497.242 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2021-11-10T17:31:37.261Z,1636565497.261 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2021-11-10T17:31:37.261Z,1636565497.261 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-11-10T17:31:37.261Z,1636565497.261 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1128
2021-11-10T17:31:37.653Z,1636565497.653 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2021-11-10T17:31:37.653Z,1636565497.653 [WetLabsBB2FL](INFO): Powering down
2021-11-10T17:31:37.654Z,1636565497.654 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-11-10T17:31:37.665Z,1636565497.665 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2021-11-10T17:31:37.665Z,1636565497.665 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-11-10T17:31:37.665Z,1636565497.665 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1129
2021-11-10T17:31:38.365Z,1636565498.365 [CTD_Seabird](INFO): Powering down
2021-11-10T17:31:38.377Z,1636565498.377 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2021-11-10T17:31:38.377Z,1636565498.377 [CTD_Seabird](INFO): Powering down
2021-11-10T17:31:38.389Z,1636565498.389 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-11-10T17:31:38.409Z,1636565498.409 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2021-11-10T17:31:38.409Z,1636565498.409 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-11-10T17:31:38.409Z,1636565498.409 [Radio_Surface](INFO): Join timeout helper Thread ID is 1130
2021-11-10T17:31:38.445Z,1636565498.445 [Radio_Surface](INFO): Powering down
2021-11-10T17:31:38.446Z,1636565498.446 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2021-11-10T17:31:38.446Z,1636565498.446 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-11-10T17:31:38.450Z,1636565498.450 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2021-11-10T17:31:38.450Z,1636565498.450 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-11-10T17:31:38.450Z,1636565498.450 [Onboard](INFO): Join timeout helper Thread ID is 1131
2021-11-10T17:31:38.578Z,1636565498.578 [DataOverHttps](INFO): Moved sent file to Logs/20211110T163654/Courier0031.lzma.bak
2021-11-10T17:31:38.579Z,1636565498.579 [DataOverHttps](INFO): SBD MOMSN=16230337
2021-11-10T17:31:39.686Z,1636565499.686 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2021-11-10T17:31:41.972Z,1636565501.972 [NAL9602](INFO): SBD MO Status=0, MOMSN=17332, MT Status=0, MTMSN=0
2021-11-10T17:31:41.972Z,1636565501.972 [NAL9602](INFO): No messages in MT queue
2021-11-10T17:31:43.273Z,1636565503.273 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2021-11-10T17:31:43.273Z,1636565503.273 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-11-10T17:31:43.289Z,1636565503.289 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2021-11-10T17:31:43.289Z,1636565503.289 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-11-10T17:31:43.290Z,1636565503.290 [DataOverHttps](INFO): Join timeout helper Thread ID is 1132
2021-11-10T17:31:43.609Z,1636565503.609 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2021-11-10T17:31:43.609Z,1636565503.609 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-11-10T17:31:43.621Z,1636565503.621 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2021-11-10T17:31:43.621Z,1636565503.621 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2021-11-10T17:31:43.622Z,1636565503.622 [BackseatComponent](INFO): Join timeout helper Thread ID is 1133
2021-11-10T17:31:43.729Z,1636565503.729 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2021-11-10T17:31:43.729Z,1636565503.729 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2021-11-10T17:31:43.749Z,1636565503.749 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2021-11-10T17:31:43.750Z,1636565503.750 [logger ThreadHandler](INFO): Thread cancelled.
2021-11-10T17:31:43.750Z,1636565503.750 [logger](INFO): Join timeout helper Thread ID is 1134
2021-11-10T17:31:43.785Z,1636565503.785 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2021-11-10T17:31:43.785Z,1636565503.785 [logger ThreadHandler](INFO): Thread cancelled.
2021-11-10T17:31:43.789Z,1636565503.789 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2021-11-10T17:31:43.789Z,1636565503.789 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-11-10T17:31:43.790Z,1636565503.790 [CommandLine](INFO): Join timeout helper Thread ID is 1135
2021-11-10T17:31:43.881Z,1636565503.881 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2021-11-10T17:31:43.881Z,1636565503.881 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-11-10T17:31:43.889Z,1636565503.889 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2021-11-10T17:31:43.889Z,1636565503.889 [CommandExec ThreadHandler](INFO): Thread cancelled.
2021-11-10T17:31:43.890Z,1636565503.890 [CommandExec](INFO): Join timeout helper Thread ID is 1136
2021-11-10T17:31:43.891Z,1636565503.891 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2021-11-10T17:31:43.891Z,1636565503.891 [controlThread ThreadHandler](INFO): Thread cancelled.
2021-11-10T17:31:43.892Z,1636565503.892 [controlThread](INFO): Join timeout helper Thread ID is 1137
2021-11-10T17:31:43.965Z,1636565503.965 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2021-11-10T17:31:43.965Z,1636565503.965 [controlThread](DEBUG): Uninitializing ControlThread
2021-11-10T17:31:43.966Z,1636565503.966 [AHRS_M2](INFO): Powering down
2021-11-10T17:31:44.038Z,1636565504.038 [NAL9602](INFO): Powering down
2021-11-10T17:31:44.039Z,1636565504.039 [RDI_Pathfinder](INFO): Powering down
2021-11-10T17:31:44.040Z,1636565504.040 [DAT](INFO): Powering down
2021-11-10T17:31:44.114Z,1636565504.114 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2021-11-10T17:31:44.115Z,1636565504.115 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2021-11-10T17:31:44.115Z,1636565504.115 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2021-11-10T17:31:44.116Z,1636565504.116 [MissionManager](INFO): Uninitializing Mission Default
2021-11-10T17:31:44.116Z,1636565504.116 [Default] Stopped
2021-11-10T17:31:44.116Z,1636565504.116 [Default](DEBUG): Aggregate::uninitialize Default
2021-11-10T17:31:44.116Z,1636565504.116 [Default:B.GoToSurface] Stopped
2021-11-10T17:31:44.116Z,1636565504.116 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-11-10T17:31:44.116Z,1636565504.116 [Default:CheckIn] Stopped
2021-11-10T17:31:44.116Z,1636565504.116 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-11-10T17:31:44.116Z,1636565504.116 [Default:CheckIn:Read_Iridium] Stopped
2021-11-10T17:31:44.119Z,1636565504.119 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2021-11-10T17:31:44.120Z,1636565504.120 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2021-11-10T17:31:44.120Z,1636565504.120 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2021-11-10T17:31:44.120Z,1636565504.120 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2021-11-10T17:31:44.121Z,1636565504.121 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2021-11-10T17:31:44.121Z,1636565504.121 [BuoyancyServo](INFO): Powering down
2021-11-10T17:31:44.133Z,1636565504.133 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2021-11-10T17:31:44.133Z,1636565504.133 [ElevatorServo](INFO): Powering down
2021-11-10T17:31:44.134Z,1636565504.134 [MassServo](DEBUG): Uninitialize Mass Servo.
2021-11-10T17:31:44.134Z,1636565504.134 [MassServo](INFO): Powering down
2021-11-10T17:31:44.135Z,1636565504.135 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2021-11-10T17:31:44.135Z,1636565504.135 [RudderServo](INFO): Powering down
2021-11-10T17:31:44.136Z,1636565504.136 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2021-11-10T17:31:44.136Z,1636565504.136 [ThrusterServo](INFO): Powering down
2021-11-10T17:31:44.137Z,1636565504.137 [SBIT](DEBUG): Uninitialize SBIT Component.
2021-11-10T17:31:44.137Z,1636565504.137 [IBIT](DEBUG): Uninitialize IBIT Component.
2021-11-10T17:31:44.138Z,1636565504.138 [CBIT](DEBUG): Uninitialize CBIT Component.
2021-11-10T17:31:44.138Z,1636565504.138 [CBIT](DEBUG): Powering off loads.
2021-11-10T17:31:44.149Z,1636565504.149 [CBIT](DEBUG): Disabling WDT.
2021-11-10T17:31:44.161Z,1636565504.161 [CBIT](DEBUG): Opening all GF detection circuits.
2021-11-10T17:31:44.162Z,1636565504.162 [controlThread ThreadHandler](INFO): Thread cancelled.
2021-11-10T17:31:44.216Z,1636565504.216 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-11-10T17:31:44.219Z,1636565504.219 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-11-10T17:31:44.225Z,1636565504.225 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-11-10T17:31:44.270Z,1636565504.270 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2021-11-10T17:31:44.325Z,1636565504.325 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2021-11-10T17:31:44.327Z,1636565504.327 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-11-10T17:31:44.330Z,1636565504.330 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-11-10T17:31:44.338Z,1636565504.338 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-11-10T17:31:44.422Z,1636565504.422 [logger ThreadHandler](INFO): Thread cancelled.