2022-04-11T19:49:58.812Z,1649706598.812 [Supervisor](DEBUG): Initializing supervisor.
2022-04-11T19:49:58.817Z,1649706598.817 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2022-04-11T19:49:58.817Z,1649706598.817 [SyncHandler](INFO): Protected caller Thread ID is 3901
2022-04-11T19:49:58.818Z,1649706598.818 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2022-04-11T19:49:58.819Z,1649706598.819 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2022-04-11T19:49:58.819Z,1649706598.819 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 3902
2022-04-11T19:49:58.823Z,1649706598.823 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2022-04-11T19:49:58.842Z,1649706598.842 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2022-04-11T19:49:58.843Z,1649706598.843 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2022-04-11T19:49:58.843Z,1649706598.843 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 3903
2022-04-11T19:49:58.848Z,1649706598.848 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2022-04-11T19:49:58.849Z,1649706598.849 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2022-04-11T19:49:58.849Z,1649706598.849 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 3904
2022-04-11T19:49:58.851Z,1649706598.851 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2022-04-11T19:49:58.852Z,1649706598.852 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2022-04-11T19:49:58.853Z,1649706598.853 [logger ThreadHandler](INFO): Protected caller Thread ID is 3905
2022-04-11T19:49:58.857Z,1649706598.857 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2022-04-11T19:49:58.857Z,1649706598.857 [Supervisor](INFO): Looking for Config files in directory: Config/
2022-04-11T19:49:58.859Z,1649706598.859 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2022-04-11T19:49:59.084Z,1649706599.084 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2022-04-11T19:49:59.084Z,1649706599.084 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2022-04-11T19:49:59.163Z,1649706599.163 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2022-04-11T19:49:59.671Z,1649706599.671 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2022-04-11T19:49:59.671Z,1649706599.671 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2022-04-11T19:49:59.989Z,1649706599.989 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2022-04-11T19:49:59.990Z,1649706599.990 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2022-04-11T19:50:00.084Z,1649706600.084 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2022-04-11T19:50:00.084Z,1649706600.084 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2022-04-11T19:50:00.441Z,1649706600.441 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2022-04-11T19:50:00.441Z,1649706600.441 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2022-04-11T19:50:00.641Z,1649706600.641 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2022-04-11T19:50:00.642Z,1649706600.642 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2022-04-11T19:50:01.094Z,1649706601.094 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2022-04-11T19:50:01.094Z,1649706601.094 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2022-04-11T19:50:01.219Z,1649706601.219 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2022-04-11T19:50:01.220Z,1649706601.220 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2022-04-11T19:50:01.297Z,1649706601.297 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2022-04-11T19:50:01.956Z,1649706601.956 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2022-04-11T19:50:01.957Z,1649706601.957 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2022-04-11T19:50:02.311Z,1649706602.311 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2022-04-11T19:50:02.311Z,1649706602.311 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2022-04-11T19:50:02.494Z,1649706602.494 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2022-04-11T19:50:02.494Z,1649706602.494 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2022-04-11T19:50:02.603Z,1649706602.603 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2022-04-11T19:50:02.603Z,1649706602.603 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2022-04-11T19:50:02.751Z,1649706602.751 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2022-04-11T19:50:02.751Z,1649706602.751 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2022-04-11T19:50:02.973Z,1649706602.973 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2022-04-11T19:50:02.975Z,1649706602.975 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2022-04-11T19:50:02.976Z,1649706602.976 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2022-04-11T19:50:03.064Z,1649706603.064 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2022-04-11T19:50:03.147Z,1649706603.147 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2022-04-11T19:50:03.245Z,1649706603.245 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2022-04-11T19:50:03.325Z,1649706603.325 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2022-04-11T19:50:03.418Z,1649706603.418 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2022-04-11T19:50:03.555Z,1649706603.555 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2022-04-11T19:50:03.972Z,1649706603.972 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2022-04-11T19:50:04.106Z,1649706604.106 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2022-04-11T19:50:04.256Z,1649706604.256 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2022-04-11T19:50:04.476Z,1649706604.476 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2022-04-11T19:50:04.588Z,1649706604.588 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2022-04-11T19:50:04.851Z,1649706604.851 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2022-04-11T19:50:04.852Z,1649706604.852 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2022-04-11T19:50:04.876Z,1649706604.876 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2022-04-11T19:50:04.979Z,1649706604.979 [VerticalControl](DEBUG): Construct VerticalControl.
2022-04-11T19:50:05.116Z,1649706605.116 [VerticalControl] Loaded
2022-04-11T19:50:05.116Z,1649706605.116 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2022-04-11T19:50:05.119Z,1649706605.119 [HorizontalControl](DEBUG): Construct HorizontalControl.
2022-04-11T19:50:05.215Z,1649706605.215 [HorizontalControl] Loaded
2022-04-11T19:50:05.216Z,1649706605.216 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2022-04-11T19:50:05.218Z,1649706605.218 [SpeedControl](DEBUG): Construct SpeedControl.
2022-04-11T19:50:05.221Z,1649706605.221 [SpeedControl] Loaded
2022-04-11T19:50:05.222Z,1649706605.222 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2022-04-11T19:50:05.237Z,1649706605.237 [LoopControl](DEBUG): Construct LoopControl.
2022-04-11T19:50:05.237Z,1649706605.237 [LoopControl] Loaded
2022-04-11T19:50:05.237Z,1649706605.237 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2022-04-11T19:50:05.238Z,1649706605.238 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2022-04-11T19:50:05.238Z,1649706605.238 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2022-04-11T19:50:05.261Z,1649706605.261 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2022-04-11T19:50:05.262Z,1649706605.262 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2022-04-11T19:50:05.481Z,1649706605.481 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2022-04-11T19:50:05.482Z,1649706605.482 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2022-04-11T19:50:05.683Z,1649706605.683 [BuoyancyServo] Loaded
2022-04-11T19:50:05.683Z,1649706605.683 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2022-04-11T19:50:05.719Z,1649706605.719 [ElevatorServo] Loaded
2022-04-11T19:50:05.719Z,1649706605.719 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2022-04-11T19:50:05.747Z,1649706605.747 [MassServo] Loaded
2022-04-11T19:50:05.747Z,1649706605.747 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2022-04-11T19:50:05.773Z,1649706605.773 [RudderServo] Loaded
2022-04-11T19:50:05.774Z,1649706605.774 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2022-04-11T19:50:05.800Z,1649706605.800 [ThrusterServo] Loaded
2022-04-11T19:50:05.800Z,1649706605.800 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2022-04-11T19:50:05.801Z,1649706605.801 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2022-04-11T19:50:05.801Z,1649706605.801 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2022-04-11T19:50:05.935Z,1649706605.935 [DeadReckonUsingMultipleVelocitySources] Loaded
2022-04-11T19:50:05.935Z,1649706605.935 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2022-04-11T19:50:05.949Z,1649706605.949 [NavChart] Loaded
2022-04-11T19:50:05.949Z,1649706605.949 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2022-04-11T19:50:05.963Z,1649706605.963 [UniversalFixResidualReporter] Loaded
2022-04-11T19:50:05.963Z,1649706605.963 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2022-04-11T19:50:05.964Z,1649706605.964 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2022-04-11T19:50:05.964Z,1649706605.964 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2022-04-11T19:50:06.060Z,1649706606.060 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2022-04-11T19:50:06.060Z,1649706606.060 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2022-04-11T19:50:06.679Z,1649706606.679 [AHRS_M2] Loaded
2022-04-11T19:50:06.679Z,1649706606.679 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2022-04-11T19:50:07.955Z,1649706607.955 [BPC1] Loaded
2022-04-11T19:50:07.955Z,1649706607.955 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2022-04-11T19:50:08.071Z,1649706608.071 [DataOverHttps] Loaded
2022-04-11T19:50:08.071Z,1649706608.071 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2022-04-11T19:50:08.072Z,1649706608.072 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408C64E0
2022-04-11T19:50:08.073Z,1649706608.073 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 3993
2022-04-11T19:50:08.102Z,1649706608.102 [Depth_Keller] Loaded
2022-04-11T19:50:08.102Z,1649706608.102 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2022-04-11T19:50:08.107Z,1649706608.107 [DropWeight] Loaded
2022-04-11T19:50:08.107Z,1649706608.107 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2022-04-11T19:50:08.202Z,1649706608.202 [NAL9602] Loaded
2022-04-11T19:50:08.202Z,1649706608.202 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2022-04-11T19:50:08.249Z,1649706608.249 [Onboard] Loaded
2022-04-11T19:50:08.249Z,1649706608.249 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2022-04-11T19:50:08.255Z,1649706608.255 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408F64E0
2022-04-11T19:50:08.255Z,1649706608.255 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 3994
2022-04-11T19:50:08.268Z,1649706608.268 [Power24vConverter] Loaded
2022-04-11T19:50:08.269Z,1649706608.269 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2022-04-11T19:50:08.290Z,1649706608.290 [Radio_Surface] Loaded
2022-04-11T19:50:08.291Z,1649706608.291 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2022-04-11T19:50:08.292Z,1649706608.292 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409264E0
2022-04-11T19:50:08.292Z,1649706608.292 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 3995
2022-04-11T19:50:08.293Z,1649706608.293 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2022-04-11T19:50:08.293Z,1649706608.293 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2022-04-11T19:50:08.362Z,1649706608.362 [DepthRateCalculator] Loaded
2022-04-11T19:50:08.363Z,1649706608.363 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2022-04-11T19:50:08.368Z,1649706608.368 [PitchRateCalculator] Loaded
2022-04-11T19:50:08.368Z,1649706608.368 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2022-04-11T19:50:08.377Z,1649706608.377 [SpeedCalculator] Loaded
2022-04-11T19:50:08.378Z,1649706608.378 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2022-04-11T19:50:08.400Z,1649706608.400 [TempGradientCalculator] Loaded
2022-04-11T19:50:08.400Z,1649706608.400 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2022-04-11T19:50:08.413Z,1649706608.413 [YawRateCalculator] Loaded
2022-04-11T19:50:08.413Z,1649706608.413 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2022-04-11T19:50:08.449Z,1649706608.449 [ElevatorOffsetCalculator] Loaded
2022-04-11T19:50:08.449Z,1649706608.449 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2022-04-11T19:50:08.449Z,1649706608.449 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2022-04-11T19:50:08.450Z,1649706608.450 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2022-04-11T19:50:08.658Z,1649706608.658 [CANONSampler] Loaded
2022-04-11T19:50:08.658Z,1649706608.658 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2022-04-11T19:50:08.751Z,1649706608.751 [CTD_Seabird] Loaded
2022-04-11T19:50:08.752Z,1649706608.752 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2022-04-11T19:50:08.759Z,1649706608.759 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409DB4E0
2022-04-11T19:50:08.759Z,1649706608.759 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 3996
2022-04-11T19:50:08.788Z,1649706608.788 [PAR_Licor] Loaded
2022-04-11T19:50:08.788Z,1649706608.788 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2022-04-11T19:50:08.838Z,1649706608.838 [WetLabsBB2FL] Loaded
2022-04-11T19:50:08.838Z,1649706608.838 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2022-04-11T19:50:08.840Z,1649706608.840 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A0B4E0
2022-04-11T19:50:08.840Z,1649706608.840 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 3997
2022-04-11T19:50:08.841Z,1649706608.841 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2022-04-11T19:50:08.841Z,1649706608.841 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2022-04-11T19:50:08.956Z,1649706608.956 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2022-04-11T19:50:08.957Z,1649706608.957 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2022-04-11T19:50:09.536Z,1649706609.536 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2022-04-11T19:50:09.537Z,1649706609.537 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2022-04-11T19:50:09.776Z,1649706609.776 [SBIT](DEBUG): Construct Startup Built In Test.
2022-04-11T19:50:09.793Z,1649706609.793 [SBIT] Loaded
2022-04-11T19:50:09.793Z,1649706609.793 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2022-04-11T19:50:09.796Z,1649706609.796 [IBIT](DEBUG): Construct Initiated Built In Test.
2022-04-11T19:50:09.818Z,1649706609.818 [IBIT] Loaded
2022-04-11T19:50:09.818Z,1649706609.818 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2022-04-11T19:50:09.824Z,1649706609.824 [CBIT](DEBUG): Construct Continuous Built In Test.
2022-04-11T19:50:09.988Z,1649706609.988 [CBIT] Loaded
2022-04-11T19:50:09.988Z,1649706609.988 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2022-04-11T19:50:09.989Z,1649706609.989 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2022-04-11T19:50:09.989Z,1649706609.989 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2022-04-11T19:50:10.141Z,1649706610.141 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2022-04-11T19:50:10.155Z,1649706610.155 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2022-04-11T19:50:10.158Z,1649706610.158 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2022-04-11T19:50:10.177Z,1649706610.177 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2022-04-11T19:50:10.179Z,1649706610.179 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B414E0
2022-04-11T19:50:10.179Z,1649706610.179 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 3998
2022-04-11T19:50:10.184Z,1649706610.184 [Supervisor](INFO): Main Thread ID is 3900
2022-04-11T19:50:10.184Z,1649706610.184 [Supervisor](DEBUG): Running supervisor.
2022-04-11T19:50:10.184Z,1649706610.184 [CommandExec ThreadHandler](INFO): Handler Thread ID is 3999
2022-04-11T19:50:10.185Z,1649706610.185 [CommandExec](INFO): Initializing the command executive.
2022-04-11T19:50:10.187Z,1649706610.187 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4000
2022-04-11T19:50:10.189Z,1649706610.189 [controlThread ThreadHandler](INFO): Handler Thread ID is 4001
2022-04-11T19:50:10.189Z,1649706610.189 [controlThread](DEBUG): Initializing ControlThread
2022-04-11T19:50:10.190Z,1649706610.190 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2022-04-11T19:50:10.200Z,1649706610.200 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2022-04-11T19:50:10.201Z,1649706610.201 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2022-04-11T19:50:10.201Z,1649706610.201 [LoopControl](DEBUG): Initialize LoopControlComponent.
2022-04-11T19:50:10.203Z,1649706610.203 [NavChart](DEBUG): Initialize NavChart Navigation.
2022-04-11T19:50:10.203Z,1649706610.203 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2022-04-11T19:50:10.208Z,1649706610.208 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2022-04-11T19:50:10.209Z,1649706610.209 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2022-04-11T19:50:10.209Z,1649706610.209 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2022-04-11T19:50:10.209Z,1649706610.209 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2022-04-11T19:50:10.210Z,1649706610.210 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2022-04-11T19:50:10.210Z,1649706610.210 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2022-04-11T19:50:10.223Z,1649706610.223 [SBIT](INFO): Initialize SBIT Component.
2022-04-11T19:50:10.223Z,1649706610.223 [SBIT](IMPORTANT): git: 2022-01-05
2022-04-11T19:50:10.224Z,1649706610.224 [SBIT](INFO): git hash: 4648efb7c0362dd4a6331fa887af857d1a6abffc
2022-04-11T19:50:10.224Z,1649706610.224 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2022-04-11T19:50:10.225Z,1649706610.225 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2022-04-11T19:50:10.227Z,1649706610.227 [SBIT](INFO): Beginning SBIT in 68.000000 seconds.
2022-04-11T19:50:10.227Z,1649706610.227 [IBIT](INFO): Initialize IBIT Component.
2022-04-11T19:50:10.228Z,1649706610.228 [CBIT](DEBUG): Initialize CBIT Component.
2022-04-11T19:50:10.229Z,1649706610.229 [logger ThreadHandler](INFO): Handler Thread ID is 4002
2022-04-11T19:50:10.246Z,1649706610.246 [CBIT](DEBUG): Initialized mux pins.
2022-04-11T19:50:10.247Z,1649706610.247 [CBIT](DEBUG): Initializing the watchdog timer.
2022-04-11T19:50:10.255Z,1649706610.255 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 4003
2022-04-11T19:50:10.256Z,1649706610.256 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2022-04-11T19:50:10.270Z,1649706610.270 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2022-04-11T19:50:10.271Z,1649706610.271 [CBIT](DEBUG): Initializing heartbeat.
2022-04-11T19:50:10.272Z,1649706610.272 [Onboard ThreadHandler](INFO): Handler Thread ID is 4004
2022-04-11T19:50:10.296Z,1649706610.296 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4005
2022-04-11T19:50:10.319Z,1649706610.319 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 4006
2022-04-11T19:50:10.320Z,1649706610.320 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-04-11T19:50:10.324Z,1649706610.324 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 4008
2022-04-11T19:50:10.327Z,1649706610.327 [WetLabsBB2FL](INFO): Powering up
2022-04-11T19:50:10.328Z,1649706610.328 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4010
2022-04-11T19:50:10.332Z,1649706610.332 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2022-04-11T19:50:10.332Z,1649706610.332 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2022-04-11T19:50:10.332Z,1649706610.332 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2022-04-11T19:50:10.332Z,1649706610.332 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2022-04-11T19:50:10.333Z,1649706610.333 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2022-04-11T19:50:10.333Z,1649706610.333 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2022-04-11T19:50:10.333Z,1649706610.333 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2022-04-11T19:50:10.333Z,1649706610.333 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2022-04-11T19:50:10.333Z,1649706610.333 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2022-04-11T19:50:10.333Z,1649706610.333 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2022-04-11T19:50:10.334Z,1649706610.334 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2022-04-11T19:50:10.334Z,1649706610.334 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2022-04-11T19:50:10.343Z,1649706610.343 [CBIT](DEBUG): Deactivating GF circuits.
2022-04-11T19:50:10.343Z,1649706610.343 [CBIT](DEBUG): Deactivating emergency mode.
2022-04-11T19:50:10.344Z,1649706610.344 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2022-04-11T19:50:10.344Z,1649706610.344 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2022-04-11T19:50:10.344Z,1649706610.344 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2022-04-11T19:50:10.344Z,1649706610.344 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2022-04-11T19:50:10.378Z,1649706610.378 [CBIT](DEBUG): Backplane powered.
2022-04-11T19:50:10.379Z,1649706610.379 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2022-04-11T19:50:10.401Z,1649706610.401 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2022-04-11T19:50:10.446Z,1649706610.446 [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>


2022-04-11T19:50:10.447Z,1649706610.447 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2022-04-11T19:50:10.583Z,1649706610.583 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2022-04-11T19:50:10.591Z,1649706610.591 [Default:A.Wait](DEBUG): Construct Wait.
2022-04-11T19:50:10.593Z,1649706610.593 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2022-04-11T19:50:10.641Z,1649706610.641 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2022-04-11T19:50:10.644Z,1649706610.644 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2022-04-11T19:50:10.683Z,1649706610.683 [Default:E.Execute](DEBUG): Construct Execute.
2022-04-11T19:50:10.703Z,1649706610.703 [Radio_Surface](INFO): Powering up
2022-04-11T19:50:10.707Z,1649706610.707 [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>


2022-04-11T19:50:10.712Z,1649706610.712 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2022-04-11T19:50:10.755Z,1649706610.755 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2022-04-11T19:50:10.877Z,1649706610.877 [Power24vConverter](INFO): Powering up.
2022-04-11T19:50:10.878Z,1649706610.878 [CANONSampler](INFO): Powering down
2022-04-11T19:50:10.934Z,1649706610.934 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2022-04-11T19:50:10.967Z,1649706610.967 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2022-04-11T19:50:10.968Z,1649706610.968 [ElevatorServo](DEBUG): Initializing EZServoServo.
2022-04-11T19:50:10.979Z,1649706610.979 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2022-04-11T19:50:10.980Z,1649706610.980 [MassServo](DEBUG): Initializing EZServoServo.
2022-04-11T19:50:10.995Z,1649706610.995 [MassServo](DEBUG): Initializing MassServo.
2022-04-11T19:50:10.996Z,1649706610.996 [RudderServo](DEBUG): Initializing EZServoServo.
2022-04-11T19:50:11.003Z,1649706611.003 [RudderServo](DEBUG): Initializing RudderServo.
2022-04-11T19:50:11.004Z,1649706611.004 [ThrusterServo](DEBUG): Initializing EZServoServo.
2022-04-11T19:50:11.019Z,1649706611.019 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2022-04-11T19:50:11.866Z,1649706611.866 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2022-04-11T19:50:11.867Z,1649706611.867 [RudderServo](FAULT): Rudder failed to initialize
2022-04-11T19:50:11.867Z,1649706611.867 [RudderServo] Communications Fault, FailCount= 1
2022-04-11T19:50:11.867Z,1649706611.867 [RudderServo](ERROR): Communications Fault
2022-04-11T19:50:11.966Z,1649706611.966 [CBIT](ERROR): Communications Fault in component: RudderServo
2022-04-11T19:50:12.144Z,1649706612.144 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-04-11T19:50:12.144Z,1649706612.144 [RudderServo](INFO): Powering down
2022-04-11T19:50:12.437Z,1649706612.437 [RudderServo](FAULT): LCB fault:  Current Limiter Activated.
2022-04-11T19:50:12.437Z,1649706612.437 [RudderServo] Hardware Fault, FailCount= 1
2022-04-11T19:50:12.437Z,1649706612.437 [RudderServo](ERROR): Hardware Fault
2022-04-11T19:50:12.438Z,1649706612.438 [WetLabsBB2FL](INFO): Powering down
2022-04-11T19:50:12.869Z,1649706612.869 [RudderServo](DEBUG): Initializing EZServoServo.
2022-04-11T19:50:12.983Z,1649706612.983 [RudderServo](DEBUG): Initializing RudderServo.
2022-04-11T19:50:12.988Z,1649706612.988 [CBIT](INFO): Clearing failed state for component RudderServo
2022-04-11T19:50:12.988Z,1649706612.988 [RudderServo] No Fault, FailCount= 1
2022-04-11T19:50:17.379Z,1649706617.379 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2022-04-11T19:50:28.181Z,1649706628.181 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004866
2022-04-11T19:50:38.316Z,1649706638.316 [NAL9602](INFO): Powering up NAL9602
2022-04-11T19:50:49.224Z,1649706649.224 [NAL9602](INFO): NAL9602 initialized
2022-04-11T19:51:18.309Z,1649706678.309 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2022-04-11T19:51:18.815Z,1649706678.815 [SBIT](IMPORTANT): Beginning Startup BIT
2022-04-11T19:51:18.848Z,1649706678.848 [CBIT](IMPORTANT): Beginning ground fault scan
2022-04-11T19:51:29.676Z,1649706689.676 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2022-04-11T19:51:30.087Z,1649706690.087 [CBIT](IMPORTANT): Beginning ground fault scan
2022-04-11T19:51:41.060Z,1649706701.060 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2022-04-11T19:51:41.455Z,1649706701.455 [CBIT](IMPORTANT): Beginning ground fault scan
2022-04-11T19:51:52.340Z,1649706712.340 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2022-04-11T19:51:52.340Z,1649706712.340 [CBIT](IMPORTANT): Ground fault scan returned out of bounds value in succession 3 times. Will re-run scan on normal schedule.
2022-04-11T19:52:12.493Z,1649706732.493 [SBIT](IMPORTANT): SBIT PASSED
2022-04-11T19:52:12.494Z,1649706732.494 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2022-04-11T19:52:12.520Z,1649706732.520 [SBIT](IMPORTANT): AMEcho.loadAtStartup=0 bool;

2022-04-11T19:52:12.521Z,1649706732.521 [SBIT](IMPORTANT): CANONSampler.loadAtStartup=1 bool;

2022-04-11T19:52:12.521Z,1649706732.521 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;

2022-04-11T19:52:12.521Z,1649706732.521 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=0 bool;

2022-04-11T19:52:12.521Z,1649706732.521 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=234 cubic_centimeter;

2022-04-11T19:52:12.521Z,1649706732.521 [SBIT](IMPORTANT): VerticalControl.massDefault=-12 millimeter;

2022-04-11T19:52:12.885Z,1649706732.885 [MissionManager](IMPORTANT): Started mission Startup
2022-04-11T19:52:12.885Z,1649706732.885 [Startup] Running Loop=1
2022-04-11T19:52:12.886Z,1649706732.886 [Startup](DEBUG): Aggregate::initialize Startup
2022-04-11T19:52:12.886Z,1649706732.886 [Startup:A.GoToSurface] Running Loop=1
2022-04-11T19:52:12.886Z,1649706732.886 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-04-11T19:52:12.887Z,1649706732.887 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-04-11T19:52:12.887Z,1649706732.887 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-04-11T19:52:12.887Z,1649706732.887 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-04-11T19:52:12.888Z,1649706732.888 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-04-11T19:52:12.888Z,1649706732.888 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-04-11T19:52:12.890Z,1649706732.890 [Startup:StartupSatComms] Running Loop=1
2022-04-11T19:52:12.890Z,1649706732.890 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2022-04-11T19:52:12.890Z,1649706732.890 [Startup:StartupSatComms:A] Running Loop=1
2022-04-11T19:52:13.270Z,1649706733.270 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2022-04-11T19:53:10.236Z,1649706790.236 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2022-04-11T19:53:10.236Z,1649706790.236 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-11T19:53:10.247Z,1649706790.247 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-11T19:53:10.655Z,1649706790.655 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-11T19:53:10.655Z,1649706790.655 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2022-04-11T19:53:13.061Z,1649706793.061 [Startup:StartupSatComms:A](INFO): Timed out from 2022-04-11T19:52:12.9Z
2022-04-11T19:53:13.061Z,1649706793.061 [Startup:StartupSatComms:A] Stopped
2022-04-11T19:53:13.061Z,1649706793.061 [Startup:StartupSatComms:B] Running Loop=1
2022-04-11T19:53:13.471Z,1649706793.471 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-04-11T19:53:20.058Z,1649706800.058 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220408T220447/Courier0007.lzma
2022-04-11T19:53:21.060Z,1649706801.060 [DataOverHttps](INFO): Moved sent file to Logs/20220408T220447/Courier0007.lzma.bak
2022-04-11T19:53:21.060Z,1649706801.060 [DataOverHttps](INFO): SBD MOMSN=16640939
2022-04-11T19:53:36.790Z,1649706816.790 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20220411T194958/Courier0000.lzma
2022-04-11T19:53:37.792Z,1649706817.792 [DataOverHttps](INFO): Moved sent file to Logs/20220411T194958/Courier0000.lzma.bak
2022-04-11T19:53:37.792Z,1649706817.792 [DataOverHttps](INFO): SBD MOMSN=16640941
2022-04-11T19:53:53.326Z,1649706833.326 [DataOverHttps](INFO): Sending 198 bytes from file Logs/20220408T220447/Express0008.lzma
2022-04-11T19:53:54.329Z,1649706834.329 [DataOverHttps](INFO): Moved sent file to Logs/20220408T220447/Express0008.lzma.bak
2022-04-11T19:53:54.329Z,1649706834.329 [DataOverHttps](INFO): SBD MOMSN=16640944
2022-04-11T19:54:09.259Z,1649706849.259 [DataOverHttps](INFO): Sending 720 bytes from file Logs/20220411T194958/Express0001.lzma
2022-04-11T19:54:10.244Z,1649706850.244 [DataOverHttps](INFO): Moved sent file to Logs/20220411T194958/Express0001.lzma.bak
2022-04-11T19:54:10.244Z,1649706850.244 [DataOverHttps](INFO): SBD MOMSN=16640951
2022-04-11T19:54:11.657Z,1649706851.657 [Startup:StartupSatComms:B] Stopped
2022-04-11T19:54:11.657Z,1649706851.657 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2022-04-11T19:54:11.657Z,1649706851.657 [Startup:StartupSatComms] Stopped
2022-04-11T19:54:11.657Z,1649706851.657 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2022-04-11T19:54:11.658Z,1649706851.658 [Startup](INFO): Completed Startup
2022-04-11T19:54:11.659Z,1649706851.659 [MissionManager](INFO): Startup is completed.
2022-04-11T19:54:11.659Z,1649706851.659 [MissionManager](INFO): Uninitializing Mission Startup
2022-04-11T19:54:11.659Z,1649706851.659 [Startup] Stopped
2022-04-11T19:54:11.659Z,1649706851.659 [Startup](DEBUG): Aggregate::uninitialize Startup
2022-04-11T19:54:11.659Z,1649706851.659 [Startup:A.GoToSurface] Stopped
2022-04-11T19:54:11.659Z,1649706851.659 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-04-11T19:54:12.069Z,1649706852.069 [MissionManager](IMPORTANT): Started mission Default
2022-04-11T19:54:12.069Z,1649706852.069 [Default] Running Loop=1
2022-04-11T19:54:12.069Z,1649706852.069 [Default](DEBUG): Aggregate::initialize Default
2022-04-11T19:54:12.069Z,1649706852.069 [Default:B.GoToSurface] Running Loop=1
2022-04-11T19:54:12.069Z,1649706852.069 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-04-11T19:54:12.069Z,1649706852.069 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-04-11T19:54:12.070Z,1649706852.070 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-04-11T19:54:12.070Z,1649706852.070 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-04-11T19:54:12.071Z,1649706852.071 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-04-11T19:54:12.071Z,1649706852.071 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-04-11T19:54:12.071Z,1649706852.071 [Default:A.Wait] Running Loop=1
2022-04-11T19:54:12.071Z,1649706852.071 [Default:A.Wait](DEBUG): Initialize Wait Component.
2022-04-11T19:54:25.384Z,1649706865.384 [Default:A.Wait](INFO): Done Waiting.
2022-04-11T19:54:25.384Z,1649706865.384 [Default:A.Wait] Stopped
2022-04-11T19:54:25.384Z,1649706865.384 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2022-04-11T19:54:25.852Z,1649706865.852 [Default:CheckIn] Running Loop=1
2022-04-11T19:54:25.852Z,1649706865.852 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-11T19:54:25.852Z,1649706865.852 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-11T19:54:26.253Z,1649706866.253 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2022-04-11T19:55:52.633Z,1649706952.633 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-04-11T19:56:11.247Z,1649706971.247 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2022-04-11T19:56:11.247Z,1649706971.247 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-11T19:56:11.259Z,1649706971.259 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-11T19:56:11.666Z,1649706971.666 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-11T19:56:11.666Z,1649706971.666 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2022-04-11T19:58:14.841Z,1649707094.841 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2022-04-11T19:58:14.844Z,1649707094.844 [BPC1](INFO): Received data from all battery sticks.
2022-04-11T19:59:12.216Z,1649707152.216 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2022-04-11T19:59:12.216Z,1649707152.216 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-11T19:59:12.244Z,1649707152.244 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-11T19:59:12.631Z,1649707152.631 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-11T19:59:12.631Z,1649707152.631 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2022-04-11T19:59:25.957Z,1649707165.957 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-04-11T19:54:25.9Z
2022-04-11T19:59:25.958Z,1649707165.958 [Default:CheckIn:Read_GPS] Stopped
2022-04-11T19:59:25.958Z,1649707165.958 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-11T19:59:26.369Z,1649707166.369 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-04-11T19:59:32.910Z,1649707172.910 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20220411T194958/Courier0004.lzma
2022-04-11T19:59:33.912Z,1649707173.912 [DataOverHttps](INFO): Moved sent file to Logs/20220411T194958/Courier0004.lzma.bak
2022-04-11T19:59:33.912Z,1649707173.912 [DataOverHttps](INFO): SBD MOMSN=16640988
2022-04-11T19:59:49.334Z,1649707189.334 [DataOverHttps](INFO): Sending 188 bytes from file Logs/20220411T194958/Express0005.lzma
2022-04-11T19:59:50.336Z,1649707190.336 [DataOverHttps](INFO): Moved sent file to Logs/20220411T194958/Express0005.lzma.bak
2022-04-11T19:59:50.336Z,1649707190.336 [DataOverHttps](INFO): SBD MOMSN=16640990
2022-04-11T19:59:51.817Z,1649707191.817 [Default:CheckIn:Read_Iridium] Stopped
2022-04-11T19:59:51.817Z,1649707191.817 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-11T19:59:51.817Z,1649707191.817 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-11T20:00:11.591Z,1649707211.591 [Power24vConverter](INFO): Powering down.
2022-04-11T20:00:50.785Z,1649707250.785 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-04-11T20:00:50.785Z,1649707250.785 [NAL9602] Data Fault, FailCount= 1
2022-04-11T20:00:50.785Z,1649707250.785 [NAL9602](ERROR): Data Fault
2022-04-11T20:00:50.848Z,1649707250.848 [CBIT](ERROR): Data Fault in component: NAL9602
2022-04-11T20:00:51.184Z,1649707251.184 [NAL9602](INFO): Powering down
2022-04-11T20:00:52.014Z,1649707252.014 [CBIT](INFO): Clearing failed state for component NAL9602
2022-04-11T20:00:52.014Z,1649707252.014 [NAL9602] No Fault, FailCount= 1
2022-04-11T20:01:21.484Z,1649707281.484 [NAL9602](INFO): Powering up NAL9602
2022-04-11T20:01:32.396Z,1649707292.396 [NAL9602](INFO): NAL9602 initialized
2022-04-11T20:02:13.212Z,1649707333.212 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2022-04-11T20:02:13.212Z,1649707333.212 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-11T20:02:13.240Z,1649707333.240 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-11T20:02:13.622Z,1649707333.622 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-11T20:02:13.622Z,1649707333.622 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2022-04-11T20:04:52.403Z,1649707492.403 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-11T20:04:52.404Z,1649707492.404 [Default:CheckIn:C.Wait] Stopped
2022-04-11T20:04:52.404Z,1649707492.404 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-11T20:04:52.404Z,1649707492.404 [Default:CheckIn:D] Running Loop=1
2022-04-11T20:04:52.782Z,1649707492.782 [Default:CheckIn:D] Stopped
2022-04-11T20:04:52.783Z,1649707492.783 [Default:CheckIn:E] Running Loop=1
2022-04-11T20:04:53.231Z,1649707493.231 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.678557 min 
2022-04-11T20:04:53.231Z,1649707493.231 [Default:CheckIn:E] Stopped
2022-04-11T20:04:53.231Z,1649707493.231 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-11T20:04:53.231Z,1649707493.231 [Default:CheckIn] Stopped
2022-04-11T20:04:53.231Z,1649707493.231 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-11T20:04:53.231Z,1649707493.231 [Default:CheckIn](INFO): Running loop #2
2022-04-11T20:04:53.231Z,1649707493.231 [Default:CheckIn] Running Loop=2
2022-04-11T20:04:53.232Z,1649707493.232 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-11T20:04:53.232Z,1649707493.232 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-11T20:05:14.197Z,1649707514.197 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2022-04-11T20:05:14.197Z,1649707514.197 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-11T20:05:14.208Z,1649707514.208 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-11T20:05:14.631Z,1649707514.631 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-11T20:05:14.631Z,1649707514.631 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2022-04-11T20:08:15.188Z,1649707695.188 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2022-04-11T20:08:15.188Z,1649707695.188 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-11T20:08:15.199Z,1649707695.199 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-11T20:08:15.623Z,1649707695.623 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-11T20:08:15.623Z,1649707695.623 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2022-04-11T20:09:53.369Z,1649707793.369 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-04-11T20:04:53.2Z
2022-04-11T20:09:53.369Z,1649707793.369 [Default:CheckIn:Read_GPS] Stopped
2022-04-11T20:09:53.369Z,1649707793.369 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-11T20:10:00.698Z,1649707800.698 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220411T194958/Courier0007.lzma
2022-04-11T20:10:01.700Z,1649707801.700 [DataOverHttps](INFO): Moved sent file to Logs/20220411T194958/Courier0007.lzma.bak
2022-04-11T20:10:01.700Z,1649707801.700 [DataOverHttps](INFO): SBD MOMSN=16641071
2022-04-11T20:10:21.834Z,1649707821.834 [DataOverHttps](INFO): Sending 181 bytes from file Logs/20220411T194958/Express0008.lzma
2022-04-11T20:10:22.844Z,1649707822.844 [DataOverHttps](INFO): Moved sent file to Logs/20220411T194958/Express0008.lzma.bak
2022-04-11T20:10:22.844Z,1649707822.844 [DataOverHttps](INFO): SBD MOMSN=16641076
2022-04-11T20:10:24.134Z,1649707824.134 [Default:CheckIn:Read_Iridium] Stopped
2022-04-11T20:10:24.134Z,1649707824.134 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-11T20:10:24.134Z,1649707824.134 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-11T20:11:16.233Z,1649707876.233 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2022-04-11T20:11:16.233Z,1649707876.233 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-11T20:11:16.244Z,1649707876.244 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-11T20:11:16.662Z,1649707876.662 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-11T20:11:16.662Z,1649707876.662 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2022-04-11T20:11:34.396Z,1649707894.396 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-04-11T20:11:34.396Z,1649707894.396 [NAL9602] Data Fault, FailCount= 2
2022-04-11T20:11:34.397Z,1649707894.397 [NAL9602](ERROR): Data Fault
2022-04-11T20:11:34.436Z,1649707894.436 [CBIT](ERROR): Data Fault in component: NAL9602
2022-04-11T20:11:34.796Z,1649707894.796 [NAL9602](INFO): Powering down
2022-04-11T20:11:35.625Z,1649707895.625 [CBIT](INFO): Clearing failed state for component NAL9602
2022-04-11T20:11:35.626Z,1649707895.626 [NAL9602] No Fault, FailCount= 2
2022-04-11T20:12:05.103Z,1649707925.103 [NAL9602](INFO): Powering up NAL9602
2022-04-11T20:12:16.008Z,1649707936.008 [NAL9602](INFO): NAL9602 initialized
2022-04-11T20:14:17.236Z,1649708057.236 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2022-04-11T20:14:17.236Z,1649708057.236 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-11T20:14:17.247Z,1649708057.247 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-11T20:14:17.635Z,1649708057.635 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-11T20:14:17.635Z,1649708057.635 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2022-04-11T20:15:24.706Z,1649708124.706 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-11T20:15:24.706Z,1649708124.706 [Default:CheckIn:C.Wait] Stopped
2022-04-11T20:15:24.706Z,1649708124.706 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-11T20:15:24.706Z,1649708124.706 [Default:CheckIn:D] Running Loop=1
2022-04-11T20:15:25.094Z,1649708125.094 [Default:CheckIn:D] Stopped
2022-04-11T20:15:25.095Z,1649708125.095 [Default:CheckIn:E] Running Loop=1
2022-04-11T20:15:25.510Z,1649708125.510 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.217090 min 
2022-04-11T20:15:25.510Z,1649708125.510 [Default:CheckIn:E] Stopped
2022-04-11T20:15:25.510Z,1649708125.510 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-11T20:15:25.510Z,1649708125.510 [Default:CheckIn] Stopped
2022-04-11T20:15:25.510Z,1649708125.510 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-11T20:15:25.510Z,1649708125.510 [Default:CheckIn](INFO): Running loop #3
2022-04-11T20:15:25.510Z,1649708125.510 [Default:CheckIn] Running Loop=3
2022-04-11T20:15:25.511Z,1649708125.511 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-11T20:15:25.511Z,1649708125.511 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-11T20:17:18.227Z,1649708238.227 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2022-04-11T20:17:18.227Z,1649708238.227 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-11T20:17:18.239Z,1649708238.239 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-11T20:17:18.651Z,1649708238.651 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-11T20:17:18.651Z,1649708238.651 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2022-04-11T20:20:19.229Z,1649708419.229 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2022-04-11T20:20:19.229Z,1649708419.229 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-11T20:20:19.241Z,1649708419.241 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-11T20:20:19.642Z,1649708419.642 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-11T20:20:19.642Z,1649708419.642 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2022-04-11T20:20:25.691Z,1649708425.691 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-04-11T20:15:25.5Z
2022-04-11T20:20:25.691Z,1649708425.691 [Default:CheckIn:Read_GPS] Stopped
2022-04-11T20:20:25.691Z,1649708425.691 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-11T20:20:32.966Z,1649708432.966 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220411T194958/Courier0010.lzma
2022-04-11T20:20:33.968Z,1649708433.968 [DataOverHttps](INFO): Moved sent file to Logs/20220411T194958/Courier0010.lzma.bak
2022-04-11T20:20:33.968Z,1649708433.968 [DataOverHttps](INFO): SBD MOMSN=16641083
2022-04-11T20:20:49.438Z,1649708449.438 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20220411T194958/Express0011.lzma
2022-04-11T20:20:50.441Z,1649708450.441 [DataOverHttps](INFO): Moved sent file to Logs/20220411T194958/Express0011.lzma.bak
2022-04-11T20:20:50.441Z,1649708450.441 [DataOverHttps](INFO): SBD MOMSN=16641085
2022-04-11T20:20:51.953Z,1649708451.953 [Default:CheckIn:Read_Iridium] Stopped
2022-04-11T20:20:51.953Z,1649708451.953 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-11T20:20:51.953Z,1649708451.953 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-11T20:22:17.569Z,1649708537.569 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-04-11T20:22:17.569Z,1649708537.569 [NAL9602] Data Fault, FailCount= 3
2022-04-11T20:22:17.569Z,1649708537.569 [NAL9602](ERROR): Data Fault
2022-04-11T20:22:17.587Z,1649708537.587 [CBIT](ERROR): Data Fault in component: NAL9602
2022-04-11T20:22:17.973Z,1649708537.973 [NAL9602](INFO): Powering down
2022-04-11T20:22:18.808Z,1649708538.808 [CBIT](INFO): Clearing failed state for component NAL9602
2022-04-11T20:22:18.808Z,1649708538.808 [NAL9602] No Fault, FailCount= 3
2022-04-11T20:22:48.276Z,1649708568.276 [NAL9602](INFO): Powering up NAL9602
2022-04-11T20:22:59.184Z,1649708579.184 [NAL9602](INFO): NAL9602 initialized
2022-04-11T20:23:20.216Z,1649708600.216 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2022-04-11T20:23:20.216Z,1649708600.216 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-11T20:23:20.257Z,1649708600.257 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-11T20:23:20.631Z,1649708600.631 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-11T20:23:20.631Z,1649708600.631 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2022-04-11T20:25:52.537Z,1649708752.537 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-11T20:25:52.538Z,1649708752.538 [Default:CheckIn:C.Wait] Stopped
2022-04-11T20:25:52.538Z,1649708752.538 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-11T20:25:52.538Z,1649708752.538 [Default:CheckIn:D] Running Loop=1
2022-04-11T20:25:52.941Z,1649708752.941 [Default:CheckIn:D] Stopped
2022-04-11T20:25:52.941Z,1649708752.941 [Default:CheckIn:E] Running Loop=1
2022-04-11T20:25:53.347Z,1649708753.347 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.681201 min 
2022-04-11T20:25:53.347Z,1649708753.347 [Default:CheckIn:E] Stopped
2022-04-11T20:25:53.347Z,1649708753.347 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-11T20:25:53.347Z,1649708753.347 [Default:CheckIn] Stopped
2022-04-11T20:25:53.348Z,1649708753.348 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-11T20:25:53.348Z,1649708753.348 [Default:CheckIn](INFO): Running loop #4
2022-04-11T20:25:53.348Z,1649708753.348 [Default:CheckIn] Running Loop=4
2022-04-11T20:25:53.348Z,1649708753.348 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-11T20:25:53.348Z,1649708753.348 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-11T20:26:21.232Z,1649708781.232 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2022-04-11T20:26:21.232Z,1649708781.232 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-11T20:26:21.244Z,1649708781.244 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-11T20:26:21.658Z,1649708781.658 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-11T20:26:21.659Z,1649708781.659 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2022-04-11T20:29:22.226Z,1649708962.226 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2022-04-11T20:29:22.226Z,1649708962.226 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-11T20:29:22.237Z,1649708962.237 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-11T20:29:22.651Z,1649708962.651 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-11T20:29:22.651Z,1649708962.651 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2022-04-11T20:30:53.516Z,1649709053.516 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-04-11T20:25:53.3Z
2022-04-11T20:30:53.517Z,1649709053.517 [Default:CheckIn:Read_GPS] Stopped
2022-04-11T20:30:53.517Z,1649709053.517 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-11T20:31:00.798Z,1649709060.798 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220411T194958/Courier0013.lzma
2022-04-11T20:31:01.800Z,1649709061.800 [DataOverHttps](INFO): Moved sent file to Logs/20220411T194958/Courier0013.lzma.bak
2022-04-11T20:31:01.800Z,1649709061.800 [DataOverHttps](INFO): SBD MOMSN=16641100
2022-04-11T20:31:17.346Z,1649709077.346 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20220411T194958/Express0014.lzma
2022-04-11T20:31:18.348Z,1649709078.348 [DataOverHttps](INFO): Moved sent file to Logs/20220411T194958/Express0014.lzma.bak
2022-04-11T20:31:18.348Z,1649709078.348 [DataOverHttps](INFO): SBD MOMSN=16641102
2022-04-11T20:31:19.792Z,1649709079.792 [Default:CheckIn:Read_Iridium] Stopped
2022-04-11T20:31:19.792Z,1649709079.792 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-11T20:31:19.793Z,1649709079.793 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-11T20:32:23.228Z,1649709143.228 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2022-04-11T20:32:23.228Z,1649709143.228 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-11T20:32:23.251Z,1649709143.251 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-11T20:32:23.655Z,1649709143.655 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-11T20:32:23.655Z,1649709143.655 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2022-04-11T20:33:01.980Z,1649709181.980 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-04-11T20:33:01.980Z,1649709181.980 [NAL9602] Data Fault, FailCount= 4
2022-04-11T20:33:01.980Z,1649709181.980 [NAL9602](ERROR): Data Fault
2022-04-11T20:33:02.026Z,1649709182.026 [CBIT](ERROR): Data Fault in component: NAL9602
2022-04-11T20:33:02.378Z,1649709182.378 [NAL9602](INFO): Powering down
2022-04-11T20:33:03.231Z,1649709183.231 [CBIT](INFO): Clearing failed state for component NAL9602
2022-04-11T20:33:03.231Z,1649709183.231 [NAL9602] No Fault, FailCount= 4
2022-04-11T20:33:32.684Z,1649709212.684 [NAL9602](INFO): Powering up NAL9602
2022-04-11T20:33:43.592Z,1649709223.592 [NAL9602](INFO): NAL9602 initialized
2022-04-11T20:35:24.263Z,1649709324.263 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2022-04-11T20:35:24.263Z,1649709324.263 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-11T20:35:24.284Z,1649709324.284 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-11T20:35:24.655Z,1649709324.655 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-11T20:35:24.655Z,1649709324.655 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2022-04-11T20:36:20.361Z,1649709380.361 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-11T20:36:20.362Z,1649709380.362 [Default:CheckIn:C.Wait] Stopped
2022-04-11T20:36:20.362Z,1649709380.362 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-11T20:36:20.362Z,1649709380.362 [Default:CheckIn:D] Running Loop=1
2022-04-11T20:36:20.773Z,1649709380.773 [Default:CheckIn:D] Stopped
2022-04-11T20:36:20.773Z,1649709380.773 [Default:CheckIn:E] Running Loop=1
2022-04-11T20:36:21.193Z,1649709381.193 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.145064 min 
2022-04-11T20:36:21.193Z,1649709381.193 [Default:CheckIn:E] Stopped
2022-04-11T20:36:21.193Z,1649709381.193 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-11T20:36:21.193Z,1649709381.193 [Default:CheckIn] Stopped
2022-04-11T20:36:21.193Z,1649709381.193 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-11T20:36:21.193Z,1649709381.193 [Default:CheckIn](INFO): Running loop #5
2022-04-11T20:36:21.194Z,1649709381.194 [Default:CheckIn] Running Loop=5
2022-04-11T20:36:21.194Z,1649709381.194 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-11T20:36:21.194Z,1649709381.194 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-11T20:38:25.208Z,1649709505.208 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2022-04-11T20:38:25.208Z,1649709505.208 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-11T20:38:25.245Z,1649709505.245 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-11T20:38:25.606Z,1649709505.606 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-11T20:38:25.606Z,1649709505.606 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2022-04-11T20:41:21.349Z,1649709681.349 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-04-11T20:36:21.2Z
2022-04-11T20:41:21.350Z,1649709681.350 [Default:CheckIn:Read_GPS] Stopped
2022-04-11T20:41:21.350Z,1649709681.350 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-11T20:41:26.223Z,1649709686.223 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17
2022-04-11T20:41:26.223Z,1649709686.223 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-11T20:41:26.247Z,1649709686.247 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-11T20:41:26.639Z,1649709686.639 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-11T20:41:26.639Z,1649709686.639 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17
2022-04-11T20:41:28.938Z,1649709688.938 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220411T194958/Courier0016.lzma
2022-04-11T20:41:29.940Z,1649709689.940 [DataOverHttps](INFO): Moved sent file to Logs/20220411T194958/Courier0016.lzma.bak
2022-04-11T20:41:29.940Z,1649709689.940 [DataOverHttps](INFO): SBD MOMSN=16641106
2022-04-11T20:41:45.430Z,1649709705.430 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20220411T194958/Express0017.lzma
2022-04-11T20:41:46.432Z,1649709706.432 [DataOverHttps](INFO): Moved sent file to Logs/20220411T194958/Express0017.lzma.bak
2022-04-11T20:41:46.432Z,1649709706.432 [DataOverHttps](INFO): SBD MOMSN=16641108
2022-04-11T20:41:47.602Z,1649709707.602 [Default:CheckIn:Read_Iridium] Stopped
2022-04-11T20:41:47.602Z,1649709707.602 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-11T20:41:47.603Z,1649709707.603 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-11T20:43:45.160Z,1649709825.160 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-04-11T20:43:45.160Z,1649709825.160 [NAL9602] Data Fault, FailCount= 5
2022-04-11T20:43:45.160Z,1649709825.160 [NAL9602](ERROR): Data Fault
2022-04-11T20:43:45.179Z,1649709825.179 [CBIT](ERROR): Data Fault in component: NAL9602
2022-04-11T20:43:45.179Z,1649709825.179 [CBIT](CRITICAL): Data Fault in component: NAL9602
2022-04-11T20:43:45.560Z,1649709825.560 [NAL9602](INFO): Powering down
2022-04-11T20:43:45.597Z,1649709825.597 [CommandExec](FAULT): Scheduling is paused
2022-04-11T20:43:45.598Z,1649709825.598 [CBIT](INFO): Critical error at 20220411T204345
2022-04-11T20:43:45.598Z,1649709825.598 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2022-04-11T20:44:27.182Z,1649709867.182 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18
2022-04-11T20:44:27.182Z,1649709867.182 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-11T20:44:27.193Z,1649709867.193 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-11T20:44:27.598Z,1649709867.598 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-11T20:44:27.598Z,1649709867.598 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18
2022-04-11T20:45:45.170Z,1649709945.170 [CBIT](INFO): Clearing failed state for component NAL9602
2022-04-11T20:45:45.170Z,1649709945.170 [NAL9602] No Fault, FailCount= 5
2022-04-11T20:45:45.548Z,1649709945.548 [NAL9602](INFO): Powering up NAL9602
2022-04-11T20:45:56.460Z,1649709956.460 [NAL9602](INFO): NAL9602 initialized
2022-04-11T20:46:48.193Z,1649710008.193 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-11T20:46:48.193Z,1649710008.193 [Default:CheckIn:C.Wait] Stopped
2022-04-11T20:46:48.194Z,1649710008.194 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-11T20:46:48.194Z,1649710008.194 [Default:CheckIn:D] Running Loop=1
2022-04-11T20:46:48.585Z,1649710008.585 [Default:CheckIn:D] Stopped
2022-04-11T20:46:48.585Z,1649710008.585 [Default:CheckIn:E] Running Loop=1
2022-04-11T20:46:49.026Z,1649710009.026 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.608602 min 
2022-04-11T20:46:49.026Z,1649710009.026 [Default:CheckIn:E] Stopped
2022-04-11T20:46:49.026Z,1649710009.026 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-11T20:46:49.026Z,1649710009.026 [Default:CheckIn] Stopped
2022-04-11T20:46:49.026Z,1649710009.026 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-11T20:46:49.027Z,1649710009.027 [Default:CheckIn](INFO): Running loop #6
2022-04-11T20:46:49.027Z,1649710009.027 [Default:CheckIn] Running Loop=6
2022-04-11T20:46:49.027Z,1649710009.027 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-11T20:46:49.027Z,1649710009.027 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-11T20:47:28.215Z,1649710048.215 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19
2022-04-11T20:47:28.215Z,1649710048.215 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-11T20:47:28.227Z,1649710048.227 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-11T20:47:28.593Z,1649710048.593 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-11T20:47:28.593Z,1649710048.593 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19
2022-04-11T20:50:29.192Z,1649710229.192 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 20
2022-04-11T20:50:29.192Z,1649710229.192 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-11T20:50:29.203Z,1649710229.203 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-11T20:50:29.203Z,1649710229.203 [CBIT](FAULT): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-11T20:51:49.172Z,1649710309.172 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-04-11T20:46:49.0Z
2022-04-11T20:51:49.172Z,1649710309.172 [Default:CheckIn:Read_GPS] Stopped
2022-04-11T20:51:49.172Z,1649710309.172 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-11T20:51:56.494Z,1649710316.494 [DataOverHttps](INFO): Sending 105 bytes from file Logs/20220411T194958/Courier0019.lzma
2022-04-11T20:51:57.496Z,1649710317.496 [DataOverHttps](INFO): Moved sent file to Logs/20220411T194958/Courier0019.lzma.bak
2022-04-11T20:51:57.496Z,1649710317.496 [DataOverHttps](INFO): SBD MOMSN=16641112
2022-04-11T20:52:12.974Z,1649710332.974 [DataOverHttps](INFO): Sending 263 bytes from file Logs/20220411T194958/Express0020.lzma
2022-04-11T20:52:13.976Z,1649710333.976 [DataOverHttps](INFO): Moved sent file to Logs/20220411T194958/Express0020.lzma.bak
2022-04-11T20:52:13.976Z,1649710333.976 [DataOverHttps](INFO): SBD MOMSN=16641115
2022-04-11T20:52:15.452Z,1649710335.452 [Default:CheckIn:Read_Iridium] Stopped
2022-04-11T20:52:15.452Z,1649710335.452 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-11T20:52:15.452Z,1649710335.452 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-11T20:55:57.644Z,1649710557.644 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-04-11T20:55:57.644Z,1649710557.644 [NAL9602] Data Fault, FailCount= 1
2022-04-11T20:55:57.644Z,1649710557.644 [NAL9602](ERROR): Data Fault
2022-04-11T20:55:57.691Z,1649710557.691 [CBIT](ERROR): Data Fault in component: NAL9602
2022-04-11T20:55:58.048Z,1649710558.048 [NAL9602](INFO): Powering down
2022-04-11T20:55:58.892Z,1649710558.892 [CBIT](INFO): Clearing failed state for component NAL9602
2022-04-11T20:55:58.892Z,1649710558.892 [NAL9602] No Fault, FailCount= 1
2022-04-11T20:56:28.346Z,1649710588.346 [NAL9602](INFO): Powering up NAL9602
2022-04-11T20:56:39.264Z,1649710599.264 [NAL9602](INFO): NAL9602 initialized
2022-04-11T20:57:16.047Z,1649710636.047 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-11T20:57:16.048Z,1649710636.048 [Default:CheckIn:C.Wait] Stopped
2022-04-11T20:57:16.048Z,1649710636.048 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-11T20:57:16.048Z,1649710636.048 [Default:CheckIn:D] Running Loop=1
2022-04-11T20:57:16.435Z,1649710636.435 [Default:CheckIn:D] Stopped
2022-04-11T20:57:16.435Z,1649710636.435 [Default:CheckIn:E] Running Loop=1
2022-04-11T20:57:16.836Z,1649710636.836 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 63.072770 min 
2022-04-11T20:57:16.836Z,1649710636.836 [Default:CheckIn:E] Stopped
2022-04-11T20:57:16.836Z,1649710636.836 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-11T20:57:16.836Z,1649710636.836 [Default:CheckIn] Stopped
2022-04-11T20:57:16.836Z,1649710636.836 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-11T20:57:16.836Z,1649710636.836 [Default:CheckIn](INFO): Running loop #7
2022-04-11T20:57:16.836Z,1649710636.836 [Default:CheckIn] Running Loop=7
2022-04-11T20:57:16.837Z,1649710636.837 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-11T20:57:16.837Z,1649710636.837 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-11T21:02:17.018Z,1649710937.018 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-04-11T20:57:16.8Z
2022-04-11T21:02:17.018Z,1649710937.018 [Default:CheckIn:Read_GPS] Stopped
2022-04-11T21:02:17.018Z,1649710937.018 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-11T21:02:23.502Z,1649710943.502 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220411T194958/Courier0022.lzma
2022-04-11T21:02:24.504Z,1649710944.504 [DataOverHttps](INFO): Moved sent file to Logs/20220411T194958/Courier0022.lzma.bak
2022-04-11T21:02:24.504Z,1649710944.504 [DataOverHttps](INFO): SBD MOMSN=16641121
2022-04-11T21:02:40.118Z,1649710960.118 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20220411T194958/Express0023.lzma
2022-04-11T21:02:41.120Z,1649710961.120 [DataOverHttps](INFO): Moved sent file to Logs/20220411T194958/Express0023.lzma.bak
2022-04-11T21:02:41.120Z,1649710961.120 [DataOverHttps](INFO): SBD MOMSN=16641123
2022-04-11T21:02:42.481Z,1649710962.481 [Default:CheckIn:Read_Iridium] Stopped
2022-04-11T21:02:42.481Z,1649710962.481 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-11T21:02:42.481Z,1649710962.481 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-11T21:05:29.339Z,1649711129.339 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-11T21:05:29.339Z,1649711129.339 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 20
2022-04-11T21:05:38.603Z,1649711138.603 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2022-04-11T21:05:38.606Z,1649711138.606 [BPC1](INFO): Received data from all battery sticks.
2022-04-11T21:06:40.410Z,1649711200.410 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-04-11T21:06:40.411Z,1649711200.411 [NAL9602] Data Fault, FailCount= 2
2022-04-11T21:06:40.411Z,1649711200.411 [NAL9602](ERROR): Data Fault
2022-04-11T21:06:40.428Z,1649711200.428 [CBIT](ERROR): Data Fault in component: NAL9602
2022-04-11T21:06:40.816Z,1649711200.816 [NAL9602](INFO): Powering down
2022-04-11T21:06:41.651Z,1649711201.651 [CBIT](INFO): Clearing failed state for component NAL9602
2022-04-11T21:06:41.651Z,1649711201.651 [NAL9602] No Fault, FailCount= 2
2022-04-11T21:07:11.120Z,1649711231.120 [NAL9602](INFO): Powering up NAL9602
2022-04-11T21:07:22.028Z,1649711242.028 [NAL9602](INFO): NAL9602 initialized
2022-04-11T21:07:43.044Z,1649711263.044 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-11T21:07:43.044Z,1649711263.044 [Default:CheckIn:C.Wait] Stopped
2022-04-11T21:07:43.044Z,1649711263.044 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-11T21:07:43.044Z,1649711263.044 [Default:CheckIn:D] Running Loop=1
2022-04-11T21:07:43.443Z,1649711263.443 [Default:CheckIn:D] Stopped
2022-04-11T21:07:43.443Z,1649711263.443 [Default:CheckIn:E] Running Loop=1
2022-04-11T21:07:43.859Z,1649711263.859 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 73.522892 min 
2022-04-11T21:07:43.859Z,1649711263.859 [Default:CheckIn:E] Stopped
2022-04-11T21:07:43.859Z,1649711263.859 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-11T21:07:43.859Z,1649711263.859 [Default:CheckIn] Stopped
2022-04-11T21:07:43.859Z,1649711263.859 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-11T21:07:43.859Z,1649711263.859 [Default:CheckIn](INFO): Running loop #8
2022-04-11T21:07:43.859Z,1649711263.859 [Default:CheckIn] Running Loop=8
2022-04-11T21:07:43.860Z,1649711263.860 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-11T21:07:43.860Z,1649711263.860 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-11T21:08:29.908Z,1649711309.908 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2022-04-11T21:08:29.908Z,1649711309.908 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-11T21:08:29.944Z,1649711309.944 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-11T21:08:30.317Z,1649711310.317 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-11T21:08:30.317Z,1649711310.317 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2022-04-11T21:11:31.004Z,1649711491.004 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2022-04-11T21:11:31.004Z,1649711491.004 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-11T21:11:31.015Z,1649711491.015 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-11T21:11:31.421Z,1649711491.421 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-11T21:11:31.422Z,1649711491.422 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2022-04-11T21:12:44.134Z,1649711564.134 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-04-11T21:07:43.9Z
2022-04-11T21:12:44.134Z,1649711564.134 [Default:CheckIn:Read_GPS] Stopped
2022-04-11T21:12:44.135Z,1649711564.135 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-11T21:12:51.734Z,1649711571.734 [DataOverHttps](INFO): Sending 51 bytes from file Logs/20220411T194958/Courier0025.lzma
2022-04-11T21:12:52.736Z,1649711572.736 [DataOverHttps](INFO): Moved sent file to Logs/20220411T194958/Courier0025.lzma.bak
2022-04-11T21:12:52.736Z,1649711572.736 [DataOverHttps](INFO): SBD MOMSN=16641127
2022-04-11T21:13:08.514Z,1649711588.514 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20220411T194958/Express0026.lzma
2022-04-11T21:13:09.516Z,1649711589.516 [DataOverHttps](INFO): Moved sent file to Logs/20220411T194958/Express0026.lzma.bak
2022-04-11T21:13:09.516Z,1649711589.516 [DataOverHttps](INFO): SBD MOMSN=16641129
2022-04-11T21:13:10.796Z,1649711590.796 [Default:CheckIn:Read_Iridium] Stopped
2022-04-11T21:13:10.796Z,1649711590.796 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-11T21:13:10.796Z,1649711590.796 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-11T21:14:32.007Z,1649711672.007 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2022-04-11T21:14:32.007Z,1649711672.007 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-11T21:14:32.018Z,1649711672.018 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-11T21:14:32.433Z,1649711672.433 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-11T21:14:32.433Z,1649711672.433 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2022-04-11T21:17:24.888Z,1649711844.888 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-04-11T21:17:24.888Z,1649711844.888 [NAL9602] Data Fault, FailCount= 3
2022-04-11T21:17:24.888Z,1649711844.888 [NAL9602](ERROR): Data Fault
2022-04-11T21:17:24.906Z,1649711844.906 [CBIT](ERROR): Data Fault in component: NAL9602
2022-04-11T21:17:25.296Z,1649711845.296 [NAL9602](INFO): Powering down
2022-04-11T21:17:25.692Z,1649711845.692 [NAL9602](FAULT): LCB fault:  Software Overcurrent.
2022-04-11T21:17:25.692Z,1649711845.692 [NAL9602] Hardware Fault, FailCount= 3
2022-04-11T21:17:25.692Z,1649711845.692 [NAL9602](ERROR): Hardware Fault
2022-04-11T21:17:26.117Z,1649711846.117 [CBIT](INFO): Clearing failed state for component NAL9602
2022-04-11T21:17:26.117Z,1649711846.117 [NAL9602] No Fault, FailCount= 3
2022-04-11T21:17:32.971Z,1649711852.971 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2022-04-11T21:17:32.971Z,1649711852.971 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-11T21:17:32.983Z,1649711852.983 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-11T21:17:33.395Z,1649711853.395 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-11T21:17:33.395Z,1649711853.395 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2022-04-11T21:17:55.587Z,1649711875.587 [NAL9602](INFO): Powering up NAL9602
2022-04-11T21:18:06.501Z,1649711886.501 [NAL9602](INFO): NAL9602 initialized
2022-04-11T21:18:11.356Z,1649711891.356 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-11T21:18:11.356Z,1649711891.356 [Default:CheckIn:C.Wait] Stopped
2022-04-11T21:18:11.356Z,1649711891.356 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-11T21:18:11.356Z,1649711891.356 [Default:CheckIn:D] Running Loop=1
2022-04-11T21:18:11.760Z,1649711891.760 [Default:CheckIn:D] Stopped
2022-04-11T21:18:11.760Z,1649711891.760 [Default:CheckIn:E] Running Loop=1
2022-04-11T21:18:12.171Z,1649711892.171 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 83.994857 min 
2022-04-11T21:18:12.171Z,1649711892.171 [Default:CheckIn:E] Stopped
2022-04-11T21:18:12.171Z,1649711892.171 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-11T21:18:12.171Z,1649711892.171 [Default:CheckIn] Stopped
2022-04-11T21:18:12.171Z,1649711892.171 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-11T21:18:12.172Z,1649711892.172 [Default:CheckIn](INFO): Running loop #9
2022-04-11T21:18:12.172Z,1649711892.172 [Default:CheckIn] Running Loop=9
2022-04-11T21:18:12.172Z,1649711892.172 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-11T21:18:12.172Z,1649711892.172 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-11T21:20:33.970Z,1649712033.970 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2022-04-11T21:20:33.970Z,1649712033.970 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-11T21:20:33.982Z,1649712033.982 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-11T21:20:34.398Z,1649712034.398 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-11T21:20:34.398Z,1649712034.398 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2022-04-11T21:23:12.341Z,1649712192.341 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-04-11T21:18:12.2Z
2022-04-11T21:23:12.341Z,1649712192.341 [Default:CheckIn:Read_GPS] Stopped
2022-04-11T21:23:12.342Z,1649712192.342 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-11T21:23:19.882Z,1649712199.882 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220411T194958/Courier0028.lzma
2022-04-11T21:23:20.884Z,1649712200.884 [DataOverHttps](INFO): Moved sent file to Logs/20220411T194958/Courier0028.lzma.bak
2022-04-11T21:23:20.884Z,1649712200.884 [DataOverHttps](INFO): SBD MOMSN=16641168
2022-04-11T21:23:34.963Z,1649712214.963 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2022-04-11T21:23:34.963Z,1649712214.963 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-11T21:23:34.988Z,1649712214.988 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-11T21:23:35.407Z,1649712215.407 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-11T21:23:35.407Z,1649712215.407 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2022-04-11T21:23:36.442Z,1649712216.442 [DataOverHttps](INFO): Sending 181 bytes from file Logs/20220411T194958/Express0029.lzma
2022-04-11T21:23:37.444Z,1649712217.444 [DataOverHttps](INFO): Moved sent file to Logs/20220411T194958/Express0029.lzma.bak
2022-04-11T21:23:37.444Z,1649712217.444 [DataOverHttps](INFO): SBD MOMSN=16641170
2022-04-11T21:23:38.593Z,1649712218.593 [Default:CheckIn:Read_Iridium] Stopped
2022-04-11T21:23:38.593Z,1649712218.593 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-11T21:23:38.593Z,1649712218.593 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-11T21:26:35.951Z,1649712395.951 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2022-04-11T21:26:35.951Z,1649712395.951 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-11T21:26:35.979Z,1649712395.979 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-11T21:26:36.403Z,1649712396.403 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-11T21:26:36.403Z,1649712396.403 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2022-04-11T21:28:08.052Z,1649712488.052 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-04-11T21:28:08.052Z,1649712488.052 [NAL9602] Data Fault, FailCount= 4
2022-04-11T21:28:08.053Z,1649712488.053 [NAL9602](ERROR): Data Fault
2022-04-11T21:28:08.072Z,1649712488.072 [CBIT](ERROR): Data Fault in component: NAL9602
2022-04-11T21:28:08.463Z,1649712488.463 [NAL9602](INFO): Powering down
2022-04-11T21:28:08.858Z,1649712488.858 [NAL9602](FAULT): LCB fault:  Software Overcurrent.
2022-04-11T21:28:08.858Z,1649712488.858 [NAL9602] Hardware Fault, FailCount= 4
2022-04-11T21:28:08.858Z,1649712488.858 [NAL9602](ERROR): Hardware Fault
2022-04-11T21:28:09.285Z,1649712489.285 [CBIT](INFO): Clearing failed state for component NAL9602
2022-04-11T21:28:09.285Z,1649712489.285 [NAL9602] No Fault, FailCount= 4
2022-04-11T21:28:38.756Z,1649712518.756 [NAL9602](INFO): Powering up NAL9602
2022-04-11T21:28:39.171Z,1649712519.171 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-11T21:28:39.171Z,1649712519.171 [Default:CheckIn:C.Wait] Stopped
2022-04-11T21:28:39.171Z,1649712519.171 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-11T21:28:39.172Z,1649712519.172 [Default:CheckIn:D] Running Loop=1
2022-04-11T21:28:39.576Z,1649712519.576 [Default:CheckIn:D] Stopped
2022-04-11T21:28:39.576Z,1649712519.576 [Default:CheckIn:E] Running Loop=1
2022-04-11T21:28:39.989Z,1649712519.989 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 94.458447 min 
2022-04-11T21:28:39.989Z,1649712519.989 [Default:CheckIn:E] Stopped
2022-04-11T21:28:39.989Z,1649712519.989 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-11T21:28:39.990Z,1649712519.990 [Default:CheckIn] Stopped
2022-04-11T21:28:39.990Z,1649712519.990 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-11T21:28:39.990Z,1649712519.990 [Default:CheckIn](INFO): Running loop #10
2022-04-11T21:28:39.990Z,1649712519.990 [Default:CheckIn] Running Loop=10
2022-04-11T21:28:39.990Z,1649712519.990 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-11T21:28:39.990Z,1649712519.990 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-11T21:28:49.664Z,1649712529.664 [NAL9602](INFO): NAL9602 initialized
2022-04-11T21:29:36.960Z,1649712576.960 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2022-04-11T21:29:36.960Z,1649712576.960 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-11T21:29:36.971Z,1649712576.971 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-11T21:29:37.386Z,1649712577.386 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-11T21:29:37.387Z,1649712577.387 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2022-04-11T21:32:37.952Z,1649712757.952 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2022-04-11T21:32:37.952Z,1649712757.952 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-11T21:32:37.963Z,1649712757.963 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-11T21:32:38.371Z,1649712758.371 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-11T21:32:38.371Z,1649712758.371 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2022-04-11T21:33:40.167Z,1649712820.167 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-04-11T21:28:39.0Z
2022-04-11T21:33:40.167Z,1649712820.167 [Default:CheckIn:Read_GPS] Stopped
2022-04-11T21:33:40.167Z,1649712820.167 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-11T21:33:46.666Z,1649712826.666 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220411T194958/Courier0031.lzma
2022-04-11T21:33:47.668Z,1649712827.668 [DataOverHttps](INFO): Moved sent file to Logs/20220411T194958/Courier0031.lzma.bak
2022-04-11T21:33:47.669Z,1649712827.669 [DataOverHttps](INFO): SBD MOMSN=16641222
2022-04-11T21:34:03.111Z,1649712843.111 [DataOverHttps](INFO): Sending 182 bytes from file Logs/20220411T194958/Express0032.lzma
2022-04-11T21:34:04.112Z,1649712844.112 [DataOverHttps](INFO): Moved sent file to Logs/20220411T194958/Express0032.lzma.bak
2022-04-11T21:34:04.112Z,1649712844.112 [DataOverHttps](INFO): SBD MOMSN=16641224
2022-04-11T21:34:05.632Z,1649712845.632 [Default:CheckIn:Read_Iridium] Stopped
2022-04-11T21:34:05.632Z,1649712845.632 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-11T21:34:05.632Z,1649712845.632 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-11T21:35:38.959Z,1649712938.959 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2022-04-11T21:35:38.959Z,1649712938.959 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-11T21:35:38.995Z,1649712938.995 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-11T21:35:39.412Z,1649712939.412 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-11T21:35:39.412Z,1649712939.412 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2022-04-11T21:38:39.947Z,1649713119.947 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2022-04-11T21:38:39.947Z,1649713119.947 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-11T21:38:39.958Z,1649713119.958 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-11T21:38:40.375Z,1649713120.375 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-11T21:38:40.375Z,1649713120.375 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2022-04-11T21:38:51.223Z,1649713131.223 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-04-11T21:38:51.223Z,1649713131.223 [NAL9602] Data Fault, FailCount= 5
2022-04-11T21:38:51.223Z,1649713131.223 [NAL9602](ERROR): Data Fault
2022-04-11T21:38:51.241Z,1649713131.241 [CBIT](ERROR): Data Fault in component: NAL9602
2022-04-11T21:38:51.241Z,1649713131.241 [CBIT](CRITICAL): Data Fault in component: NAL9602
2022-04-11T21:38:51.629Z,1649713131.629 [NAL9602](INFO): Powering down
2022-04-11T21:38:51.653Z,1649713131.653 [CBIT](INFO): Critical error at 20220411T213851
2022-04-11T21:39:06.195Z,1649713146.195 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-11T21:39:06.195Z,1649713146.195 [Default:CheckIn:C.Wait] Stopped
2022-04-11T21:39:06.196Z,1649713146.196 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-11T21:39:06.196Z,1649713146.196 [Default:CheckIn:D] Running Loop=1
2022-04-11T21:39:06.588Z,1649713146.588 [Default:CheckIn:D] Stopped
2022-04-11T21:39:06.589Z,1649713146.589 [Default:CheckIn:E] Running Loop=1
2022-04-11T21:39:06.988Z,1649713146.988 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 104.908659 min 
2022-04-11T21:39:06.988Z,1649713146.988 [Default:CheckIn:E] Stopped
2022-04-11T21:39:06.989Z,1649713146.989 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-11T21:39:06.989Z,1649713146.989 [Default:CheckIn] Stopped
2022-04-11T21:39:06.989Z,1649713146.989 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-11T21:39:06.989Z,1649713146.989 [Default:CheckIn](INFO): Running loop #11
2022-04-11T21:39:06.989Z,1649713146.989 [Default:CheckIn] Running Loop=11
2022-04-11T21:39:06.989Z,1649713146.989 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-11T21:39:06.989Z,1649713146.989 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-11T21:40:51.248Z,1649713251.248 [CBIT](INFO): Clearing failed state for component NAL9602
2022-04-11T21:40:51.248Z,1649713251.248 [NAL9602] No Fault, FailCount= 5
2022-04-11T21:40:51.628Z,1649713251.628 [NAL9602](INFO): Powering up NAL9602
2022-04-11T21:41:02.537Z,1649713262.537 [NAL9602](INFO): NAL9602 initialized
2022-04-11T21:41:40.940Z,1649713300.940 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2022-04-11T21:41:40.940Z,1649713300.940 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-11T21:41:40.951Z,1649713300.951 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-11T21:41:41.351Z,1649713301.351 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-11T21:41:41.351Z,1649713301.351 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2022-04-11T21:44:07.182Z,1649713447.182 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-04-11T21:39:06.0Z
2022-04-11T21:44:07.182Z,1649713447.182 [Default:CheckIn:Read_GPS] Stopped
2022-04-11T21:44:07.182Z,1649713447.182 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-11T21:44:14.438Z,1649713454.438 [DataOverHttps](INFO): Sending 82 bytes from file Logs/20220411T194958/Courier0034.lzma
2022-04-11T21:44:15.440Z,1649713455.440 [DataOverHttps](INFO): Moved sent file to Logs/20220411T194958/Courier0034.lzma.bak
2022-04-11T21:44:15.440Z,1649713455.440 [DataOverHttps](INFO): SBD MOMSN=16641230
2022-04-11T21:44:31.166Z,1649713471.166 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20220411T194958/Express0035.lzma
2022-04-11T21:44:32.168Z,1649713472.168 [DataOverHttps](INFO): Moved sent file to Logs/20220411T194958/Express0035.lzma.bak
2022-04-11T21:44:32.168Z,1649713472.168 [DataOverHttps](INFO): SBD MOMSN=16641233
2022-04-11T21:44:33.447Z,1649713473.447 [Default:CheckIn:Read_Iridium] Stopped
2022-04-11T21:44:33.447Z,1649713473.447 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-11T21:44:33.447Z,1649713473.447 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-11T21:44:41.930Z,1649713481.930 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2022-04-11T21:44:41.930Z,1649713481.930 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-11T21:44:41.941Z,1649713481.941 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-11T21:44:42.353Z,1649713482.353 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-11T21:44:42.353Z,1649713482.353 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2022-04-11T21:47:42.912Z,1649713662.912 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2022-04-11T21:47:42.912Z,1649713662.912 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-11T21:47:42.924Z,1649713662.924 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-11T21:47:43.344Z,1649713663.344 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-11T21:47:43.344Z,1649713663.344 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2022-04-11T21:49:34.008Z,1649713774.008 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-11T21:49:34.008Z,1649713774.008 [Default:CheckIn:C.Wait] Stopped
2022-04-11T21:49:34.008Z,1649713774.008 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-11T21:49:34.008Z,1649713774.008 [Default:CheckIn:D] Running Loop=1
2022-04-11T21:49:34.429Z,1649713774.429 [Default:CheckIn:D] Stopped
2022-04-11T21:49:34.429Z,1649713774.429 [Default:CheckIn:E] Running Loop=1
2022-04-11T21:49:34.834Z,1649713774.834 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 115.372673 min 
2022-04-11T21:49:34.834Z,1649713774.834 [Default:CheckIn:E] Stopped
2022-04-11T21:49:34.835Z,1649713774.835 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-11T21:49:34.835Z,1649713774.835 [Default:CheckIn] Stopped
2022-04-11T21:49:34.835Z,1649713774.835 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-11T21:49:34.835Z,1649713774.835 [Default:CheckIn](INFO): Running loop #12
2022-04-11T21:49:34.835Z,1649713774.835 [Default:CheckIn] Running Loop=12
2022-04-11T21:49:34.835Z,1649713774.835 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-11T21:49:34.835Z,1649713774.835 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-11T21:50:06.980Z,1649713806.980 [CommandExec](IMPORTANT): got command restart
2022-04-11T21:50:06.980Z,1649713806.980 [CommandExec](FAULT): Incomplete syntax. Try: help restart
2022-04-11T21:50:43.948Z,1649713843.948 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2022-04-11T21:50:43.948Z,1649713843.948 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-11T21:50:43.996Z,1649713843.996 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-11T21:50:44.401Z,1649713844.401 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-11T21:50:44.402Z,1649713844.402 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2022-04-11T21:51:04.140Z,1649713864.140 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-04-11T21:51:04.140Z,1649713864.140 [NAL9602] Data Fault, FailCount= 1
2022-04-11T21:51:04.140Z,1649713864.140 [NAL9602](ERROR): Data Fault
2022-04-11T21:51:04.196Z,1649713864.196 [CBIT](ERROR): Data Fault in component: NAL9602
2022-04-11T21:51:04.544Z,1649713864.544 [NAL9602](INFO): Powering down
2022-04-11T21:51:05.379Z,1649713865.379 [CBIT](INFO): Clearing failed state for component NAL9602
2022-04-11T21:51:05.379Z,1649713865.379 [NAL9602] No Fault, FailCount= 1
2022-04-11T21:51:34.842Z,1649713894.842 [NAL9602](INFO): Powering up NAL9602
2022-04-11T21:51:45.756Z,1649713905.756 [NAL9602](INFO): NAL9602 initialized
2022-04-11T21:53:44.965Z,1649714024.965 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2022-04-11T21:53:44.965Z,1649714024.965 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-11T21:53:44.976Z,1649714024.976 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-11T21:53:45.382Z,1649714025.382 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-11T21:53:45.382Z,1649714025.382 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2022-04-11T21:54:35.062Z,1649714075.062 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-04-11T21:49:34.8Z
2022-04-11T21:54:35.062Z,1649714075.062 [Default:CheckIn:Read_GPS] Stopped
2022-04-11T21:54:35.062Z,1649714075.062 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-11T21:54:42.090Z,1649714082.090 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220411T194958/Courier0037.lzma
2022-04-11T21:54:43.092Z,1649714083.092 [DataOverHttps](INFO): Moved sent file to Logs/20220411T194958/Courier0037.lzma.bak
2022-04-11T21:54:43.092Z,1649714083.092 [DataOverHttps](INFO): SBD MOMSN=16641237
2022-04-11T21:55:01.302Z,1649714101.302 [DataOverHttps](INFO): Sending 201 bytes from file Logs/20220411T194958/Express0038.lzma
2022-04-11T21:55:02.304Z,1649714102.304 [DataOverHttps](INFO): Moved sent file to Logs/20220411T194958/Express0038.lzma.bak
2022-04-11T21:55:02.304Z,1649714102.304 [DataOverHttps](INFO): SBD MOMSN=16641239
2022-04-11T21:55:03.805Z,1649714103.805 [Default:CheckIn:Read_Iridium] Stopped
2022-04-11T21:55:03.805Z,1649714103.805 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-11T21:55:03.805Z,1649714103.805 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-11T21:56:46.041Z,1649714206.041 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17
2022-04-11T21:56:46.041Z,1649714206.041 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-11T21:56:46.052Z,1649714206.052 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-11T21:56:46.489Z,1649714206.489 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-11T21:56:46.489Z,1649714206.489 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17
2022-04-11T21:58:12.704Z,1649714292.704 [DataOverHttps](IMPORTANT): SBD MTMSN=20220411T215811
2022-04-11T21:58:22.171Z,1649714302.171 [DataOverHttps](INFO): Received command: restart app
2022-04-11T21:58:22.216Z,1649714302.216 [CommandExec](IMPORTANT): got command restart application
2022-04-11T21:58:23.219Z,1649714303.219 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2022-04-11T21:58:23.219Z,1649714303.219 [CommandExec](INFO): Uninitializing the command executive.
2022-04-11T21:58:23.219Z,1649714303.219 [CommandExec](INFO): Uninitializing the command scheduler.
2022-04-11T21:58:23.219Z,1649714303.219 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-04-11T21:58:23.394Z,1649714303.394 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2022-04-11T21:58:23.394Z,1649714303.394 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2022-04-11T21:58:23.395Z,1649714303.395 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-04-11T21:58:23.395Z,1649714303.395 [NavChartDb](INFO): Join timeout helper Thread ID is 4123
2022-04-11T21:58:23.575Z,1649714303.575 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2022-04-11T21:58:23.575Z,1649714303.575 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-04-11T21:58:23.582Z,1649714303.582 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2022-04-11T21:58:23.583Z,1649714303.583 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-04-11T21:58:23.583Z,1649714303.583 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 4124
2022-04-11T21:58:23.799Z,1649714303.799 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2022-04-11T21:58:23.799Z,1649714303.799 [WetLabsBB2FL](INFO): Powering down
2022-04-11T21:58:23.800Z,1649714303.800 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-04-11T21:58:23.803Z,1649714303.803 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2022-04-11T21:58:23.803Z,1649714303.803 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-04-11T21:58:23.803Z,1649714303.803 [CTD_Seabird](INFO): Join timeout helper Thread ID is 4125
2022-04-11T21:58:24.615Z,1649714304.615 [CTD_Seabird](INFO): Powering down
2022-04-11T21:58:24.627Z,1649714304.627 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2022-04-11T21:58:24.632Z,1649714304.632 [CTD_Seabird](INFO): Powering down
2022-04-11T21:58:24.646Z,1649714304.646 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-04-11T21:58:24.661Z,1649714304.661 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2022-04-11T21:58:24.661Z,1649714304.661 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-04-11T21:58:24.662Z,1649714304.662 [Radio_Surface](INFO): Join timeout helper Thread ID is 4126
2022-04-11T21:58:24.763Z,1649714304.763 [Radio_Surface](INFO): Powering down
2022-04-11T21:58:24.763Z,1649714304.763 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2022-04-11T21:58:24.764Z,1649714304.764 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-04-11T21:58:24.778Z,1649714304.778 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2022-04-11T21:58:24.778Z,1649714304.778 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-04-11T21:58:24.779Z,1649714304.779 [Onboard](INFO): Join timeout helper Thread ID is 4127
2022-04-11T21:58:25.179Z,1649714305.179 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2022-04-11T21:58:26.734Z,1649714306.734 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2022-04-11T21:58:26.735Z,1649714306.735 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-04-11T21:58:26.752Z,1649714306.752 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2022-04-11T21:58:26.752Z,1649714306.752 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-04-11T21:58:26.752Z,1649714306.752 [DataOverHttps](INFO): Join timeout helper Thread ID is 4128
2022-04-11T21:58:27.187Z,1649714307.187 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2022-04-11T21:58:27.187Z,1649714307.187 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-04-11T21:58:27.195Z,1649714307.195 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2022-04-11T21:58:27.195Z,1649714307.195 [logger ThreadHandler](INFO): Thread cancelled.
2022-04-11T21:58:27.196Z,1649714307.196 [logger](INFO): Join timeout helper Thread ID is 4129
2022-04-11T21:58:27.222Z,1649714307.222 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2022-04-11T21:58:27.223Z,1649714307.223 [logger ThreadHandler](INFO): Thread cancelled.
2022-04-11T21:58:27.235Z,1649714307.235 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2022-04-11T21:58:27.235Z,1649714307.235 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-04-11T21:58:27.236Z,1649714307.236 [CommandLine](INFO): Join timeout helper Thread ID is 4130
2022-04-11T21:58:27.266Z,1649714307.266 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2022-04-11T21:58:27.267Z,1649714307.267 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-04-11T21:58:27.275Z,1649714307.275 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2022-04-11T21:58:27.275Z,1649714307.275 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-04-11T21:58:27.276Z,1649714307.276 [CommandExec](INFO): Join timeout helper Thread ID is 4131
2022-04-11T21:58:27.277Z,1649714307.277 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2022-04-11T21:58:27.277Z,1649714307.277 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-04-11T21:58:27.277Z,1649714307.277 [controlThread](INFO): Join timeout helper Thread ID is 4132
2022-04-11T21:58:27.455Z,1649714307.455 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2022-04-11T21:58:27.455Z,1649714307.455 [controlThread](DEBUG): Uninitializing ControlThread
2022-04-11T21:58:27.455Z,1649714307.455 [AHRS_M2](INFO): Powering down
2022-04-11T21:58:27.527Z,1649714307.527 [NAL9602](INFO): Powering down
2022-04-11T21:58:27.530Z,1649714307.530 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2022-04-11T21:58:27.531Z,1649714307.531 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2022-04-11T21:58:27.532Z,1649714307.532 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2022-04-11T21:58:27.532Z,1649714307.532 [MissionManager](INFO): Uninitializing Mission Default
2022-04-11T21:58:27.532Z,1649714307.532 [Default] Stopped
2022-04-11T21:58:27.532Z,1649714307.532 [Default](DEBUG): Aggregate::uninitialize Default
2022-04-11T21:58:27.532Z,1649714307.532 [Default:B.GoToSurface] Stopped
2022-04-11T21:58:27.532Z,1649714307.532 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-04-11T21:58:27.533Z,1649714307.533 [Default:CheckIn] Stopped
2022-04-11T21:58:27.533Z,1649714307.533 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-11T21:58:27.533Z,1649714307.533 [Default:CheckIn:C.Wait] Stopped
2022-04-11T21:58:27.533Z,1649714307.533 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-11T21:58:27.536Z,1649714307.536 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2022-04-11T21:58:27.537Z,1649714307.537 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2022-04-11T21:58:27.537Z,1649714307.537 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2022-04-11T21:58:27.537Z,1649714307.537 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2022-04-11T21:58:27.538Z,1649714307.538 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2022-04-11T21:58:27.538Z,1649714307.538 [BuoyancyServo](INFO): Powering down
2022-04-11T21:58:27.550Z,1649714307.550 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2022-04-11T21:58:27.551Z,1649714307.551 [ElevatorServo](INFO): Powering down
2022-04-11T21:58:27.552Z,1649714307.552 [MassServo](DEBUG): Uninitialize Mass Servo.
2022-04-11T21:58:27.552Z,1649714307.552 [MassServo](INFO): Powering down
2022-04-11T21:58:27.553Z,1649714307.553 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-04-11T21:58:27.553Z,1649714307.553 [RudderServo](INFO): Powering down
2022-04-11T21:58:27.554Z,1649714307.554 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2022-04-11T21:58:27.554Z,1649714307.554 [ThrusterServo](INFO): Powering down
2022-04-11T21:58:27.555Z,1649714307.555 [SBIT](DEBUG): Uninitialize SBIT Component.
2022-04-11T21:58:27.555Z,1649714307.555 [IBIT](DEBUG): Uninitialize IBIT Component.
2022-04-11T21:58:27.555Z,1649714307.555 [CBIT](DEBUG): Uninitialize CBIT Component.
2022-04-11T21:58:27.556Z,1649714307.556 [CBIT](DEBUG): Powering off loads.
2022-04-11T21:58:27.566Z,1649714307.566 [CBIT](DEBUG): Disabling WDT.
2022-04-11T21:58:27.578Z,1649714307.578 [CBIT](DEBUG): Opening all GF detection circuits.
2022-04-11T21:58:27.579Z,1649714307.579 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-04-11T21:58:27.630Z,1649714307.630 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-04-11T21:58:27.633Z,1649714307.633 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-04-11T21:58:27.639Z,1649714307.639 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-04-11T21:58:27.710Z,1649714307.710 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-04-11T21:58:27.714Z,1649714307.714 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-04-11T21:58:27.754Z,1649714307.754 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-04-11T21:58:27.819Z,1649714307.819 [logger ThreadHandler](INFO): Thread cancelled.