2021-06-01T15:59:37.226Z,1622563177.226 [Supervisor](DEBUG): Initializing supervisor.
2021-06-01T15:59:37.231Z,1622563177.231 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2021-06-01T15:59:37.231Z,1622563177.231 [SyncHandler](INFO): Protected caller Thread ID is 1118
2021-06-01T15:59:37.232Z,1622563177.232 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2021-06-01T15:59:37.233Z,1622563177.233 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2021-06-01T15:59:37.233Z,1622563177.233 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1119
2021-06-01T15:59:37.237Z,1622563177.237 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2021-06-01T15:59:37.257Z,1622563177.257 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2021-06-01T15:59:37.258Z,1622563177.258 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2021-06-01T15:59:37.259Z,1622563177.259 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1120
2021-06-01T15:59:37.261Z,1622563177.261 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2021-06-01T15:59:37.262Z,1622563177.262 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2021-06-01T15:59:37.262Z,1622563177.262 [logger ThreadHandler](INFO): Protected caller Thread ID is 1121
2021-06-01T15:59:37.266Z,1622563177.266 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2021-06-01T15:59:37.267Z,1622563177.267 [Supervisor](INFO): Looking for Config files in directory: Config/
2021-06-01T15:59:37.268Z,1622563177.268 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2021-06-01T15:59:37.497Z,1622563177.497 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2021-06-01T15:59:37.497Z,1622563177.497 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2021-06-01T15:59:37.578Z,1622563177.578 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2021-06-01T15:59:38.083Z,1622563178.083 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2021-06-01T15:59:38.083Z,1622563178.083 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2021-06-01T15:59:38.417Z,1622563178.417 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2021-06-01T15:59:38.418Z,1622563178.418 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2021-06-01T15:59:38.516Z,1622563178.516 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2021-06-01T15:59:38.517Z,1622563178.517 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2021-06-01T15:59:38.890Z,1622563178.890 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2021-06-01T15:59:38.891Z,1622563178.891 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2021-06-01T15:59:39.101Z,1622563179.101 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2021-06-01T15:59:39.102Z,1622563179.102 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2021-06-01T15:59:39.737Z,1622563179.737 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2021-06-01T15:59:39.737Z,1622563179.737 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2021-06-01T15:59:39.865Z,1622563179.865 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2021-06-01T15:59:39.865Z,1622563179.865 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2021-06-01T15:59:39.945Z,1622563179.945 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2021-06-01T15:59:40.615Z,1622563180.615 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2021-06-01T15:59:40.616Z,1622563180.616 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2021-06-01T15:59:40.983Z,1622563180.983 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2021-06-01T15:59:40.984Z,1622563180.984 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2021-06-01T15:59:41.178Z,1622563181.178 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2021-06-01T15:59:41.178Z,1622563181.178 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2021-06-01T15:59:41.282Z,1622563181.282 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2021-06-01T15:59:41.282Z,1622563181.282 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2021-06-01T15:59:41.435Z,1622563181.435 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2021-06-01T15:59:41.436Z,1622563181.436 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2021-06-01T15:59:41.671Z,1622563181.671 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2021-06-01T15:59:41.673Z,1622563181.673 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2021-06-01T15:59:41.674Z,1622563181.674 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2021-06-01T15:59:41.766Z,1622563181.766 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2021-06-01T15:59:41.853Z,1622563181.853 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2021-06-01T15:59:41.956Z,1622563181.956 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2021-06-01T15:59:42.040Z,1622563182.040 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2021-06-01T15:59:42.137Z,1622563182.137 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2021-06-01T15:59:42.240Z,1622563182.240 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2021-06-01T15:59:42.429Z,1622563182.429 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2021-06-01T15:59:42.506Z,1622563182.506 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2021-06-01T15:59:42.656Z,1622563182.656 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2021-06-01T15:59:42.796Z,1622563182.796 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2021-06-01T15:59:42.916Z,1622563182.916 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2021-06-01T15:59:43.182Z,1622563183.182 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2021-06-01T15:59:43.184Z,1622563183.184 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2021-06-01T15:59:43.184Z,1622563183.184 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2021-06-01T15:59:43.189Z,1622563183.189 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2021-06-01T15:59:43.244Z,1622563183.244 [VerticalControl](DEBUG): Construct VerticalControl.
2021-06-01T15:59:43.308Z,1622563183.308 [VerticalControl] Loaded
2021-06-01T15:59:43.308Z,1622563183.308 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2021-06-01T15:59:43.311Z,1622563183.311 [HorizontalControl](DEBUG): Construct HorizontalControl.
2021-06-01T15:59:43.351Z,1622563183.351 [HorizontalControl] Loaded
2021-06-01T15:59:43.352Z,1622563183.352 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2021-06-01T15:59:43.354Z,1622563183.354 [SpeedControl](DEBUG): Construct SpeedControl.
2021-06-01T15:59:43.357Z,1622563183.357 [SpeedControl] Loaded
2021-06-01T15:59:43.358Z,1622563183.358 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2021-06-01T15:59:43.360Z,1622563183.360 [LoopControl](DEBUG): Construct LoopControl.
2021-06-01T15:59:43.361Z,1622563183.361 [LoopControl] Loaded
2021-06-01T15:59:43.361Z,1622563183.361 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2021-06-01T15:59:43.361Z,1622563183.361 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2021-06-01T15:59:43.362Z,1622563183.362 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2021-06-01T15:59:43.373Z,1622563183.373 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2021-06-01T15:59:43.374Z,1622563183.374 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2021-06-01T15:59:43.481Z,1622563183.481 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2021-06-01T15:59:43.482Z,1622563183.482 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2021-06-01T15:59:43.631Z,1622563183.631 [BuoyancyServo] Loaded
2021-06-01T15:59:43.631Z,1622563183.631 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2021-06-01T15:59:43.651Z,1622563183.651 [ElevatorServo] Loaded
2021-06-01T15:59:43.651Z,1622563183.651 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2021-06-01T15:59:43.670Z,1622563183.670 [MassServo] Loaded
2021-06-01T15:59:43.670Z,1622563183.670 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2021-06-01T15:59:43.688Z,1622563183.688 [RudderServo] Loaded
2021-06-01T15:59:43.689Z,1622563183.689 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2021-06-01T15:59:43.707Z,1622563183.707 [ThrusterServo] Loaded
2021-06-01T15:59:43.707Z,1622563183.707 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2021-06-01T15:59:43.708Z,1622563183.708 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2021-06-01T15:59:43.708Z,1622563183.708 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2021-06-01T15:59:43.791Z,1622563183.791 [DeadReckonUsingMultipleVelocitySources] Loaded
2021-06-01T15:59:43.791Z,1622563183.791 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2021-06-01T15:59:43.805Z,1622563183.805 [NavChart] Loaded
2021-06-01T15:59:43.806Z,1622563183.806 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2021-06-01T15:59:43.812Z,1622563183.812 [UniversalFixResidualReporter] Loaded
2021-06-01T15:59:43.812Z,1622563183.812 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2021-06-01T15:59:43.812Z,1622563183.812 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2021-06-01T15:59:43.813Z,1622563183.813 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2021-06-01T15:59:43.870Z,1622563183.870 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2021-06-01T15:59:43.870Z,1622563183.870 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2021-06-01T15:59:44.246Z,1622563184.246 [AHRS_M2] Loaded
2021-06-01T15:59:44.247Z,1622563184.247 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2021-06-01T15:59:45.612Z,1622563185.612 [BPC1] Loaded
2021-06-01T15:59:45.613Z,1622563185.613 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2021-06-01T15:59:45.686Z,1622563185.686 [DataOverHttps] Loaded
2021-06-01T15:59:45.686Z,1622563185.686 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2021-06-01T15:59:45.688Z,1622563185.688 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408844E0
2021-06-01T15:59:45.688Z,1622563185.688 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1206
2021-06-01T15:59:45.708Z,1622563185.708 [Depth_Keller] Loaded
2021-06-01T15:59:45.709Z,1622563185.709 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2021-06-01T15:59:45.713Z,1622563185.713 [DropWeight] Loaded
2021-06-01T15:59:45.713Z,1622563185.713 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2021-06-01T15:59:45.773Z,1622563185.773 [NAL9602] Loaded
2021-06-01T15:59:45.773Z,1622563185.773 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2021-06-01T15:59:45.803Z,1622563185.803 [Onboard] Loaded
2021-06-01T15:59:45.803Z,1622563185.803 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2021-06-01T15:59:45.804Z,1622563185.804 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408B44E0
2021-06-01T15:59:45.805Z,1622563185.805 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1207
2021-06-01T15:59:45.818Z,1622563185.818 [Power24vConverter] Loaded
2021-06-01T15:59:45.818Z,1622563185.818 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2021-06-01T15:59:45.831Z,1622563185.831 [Radio_Surface] Loaded
2021-06-01T15:59:45.831Z,1622563185.831 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2021-06-01T15:59:45.832Z,1622563185.832 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408E44E0
2021-06-01T15:59:45.832Z,1622563185.832 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1208
2021-06-01T15:59:45.854Z,1622563185.854 [AMEcho] Loaded
2021-06-01T15:59:45.854Z,1622563185.854 [ComponentRegistry](DEBUG): SyncComponent "AMEcho" handled in the control thread.
2021-06-01T15:59:45.936Z,1622563185.936 [DAT] Loaded
2021-06-01T15:59:45.937Z,1622563185.937 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2021-06-01T15:59:45.937Z,1622563185.937 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2021-06-01T15:59:45.938Z,1622563185.938 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2021-06-01T15:59:45.981Z,1622563185.981 [DepthRateCalculator] Loaded
2021-06-01T15:59:45.981Z,1622563185.981 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2021-06-01T15:59:45.986Z,1622563185.986 [PitchRateCalculator] Loaded
2021-06-01T15:59:45.986Z,1622563185.986 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2021-06-01T15:59:45.996Z,1622563185.996 [SpeedCalculator] Loaded
2021-06-01T15:59:45.996Z,1622563185.996 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2021-06-01T15:59:46.010Z,1622563186.010 [TempGradientCalculator] Loaded
2021-06-01T15:59:46.011Z,1622563186.011 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2021-06-01T15:59:46.015Z,1622563186.015 [YawRateCalculator] Loaded
2021-06-01T15:59:46.015Z,1622563186.015 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2021-06-01T15:59:46.033Z,1622563186.033 [ElevatorOffsetCalculator] Loaded
2021-06-01T15:59:46.034Z,1622563186.034 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2021-06-01T15:59:46.034Z,1622563186.034 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2021-06-01T15:59:46.035Z,1622563186.035 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2021-06-01T15:59:46.214Z,1622563186.214 [CTD_Seabird] Loaded
2021-06-01T15:59:46.214Z,1622563186.214 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2021-06-01T15:59:46.216Z,1622563186.216 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409974E0
2021-06-01T15:59:46.216Z,1622563186.216 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1209
2021-06-01T15:59:46.236Z,1622563186.236 [PAR_Licor] Loaded
2021-06-01T15:59:46.237Z,1622563186.237 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2021-06-01T15:59:46.269Z,1622563186.269 [WetLabsBB2FL] Loaded
2021-06-01T15:59:46.269Z,1622563186.269 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2021-06-01T15:59:46.270Z,1622563186.270 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409C74E0
2021-06-01T15:59:46.271Z,1622563186.271 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1210
2021-06-01T15:59:46.272Z,1622563186.272 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2021-06-01T15:59:46.272Z,1622563186.272 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2021-06-01T15:59:46.300Z,1622563186.300 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2021-06-01T15:59:46.301Z,1622563186.301 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2021-06-01T15:59:46.633Z,1622563186.633 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2021-06-01T15:59:46.634Z,1622563186.634 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2021-06-01T15:59:46.788Z,1622563186.788 [SBIT](DEBUG): Construct Startup Built In Test.
2021-06-01T15:59:46.798Z,1622563186.798 [SBIT] Loaded
2021-06-01T15:59:46.798Z,1622563186.798 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2021-06-01T15:59:46.801Z,1622563186.801 [IBIT](DEBUG): Construct Initiated Built In Test.
2021-06-01T15:59:46.814Z,1622563186.814 [IBIT] Loaded
2021-06-01T15:59:46.815Z,1622563186.815 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2021-06-01T15:59:46.821Z,1622563186.821 [CBIT](DEBUG): Construct Continuous Built In Test.
2021-06-01T15:59:46.925Z,1622563186.925 [CBIT] Loaded
2021-06-01T15:59:46.926Z,1622563186.926 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2021-06-01T15:59:46.926Z,1622563186.926 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2021-06-01T15:59:46.927Z,1622563186.927 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2021-06-01T15:59:47.027Z,1622563187.027 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2021-06-01T15:59:47.033Z,1622563187.033 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2021-06-01T15:59:47.036Z,1622563187.036 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2021-06-01T15:59:47.047Z,1622563187.047 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2021-06-01T15:59:47.048Z,1622563187.048 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AF94E0
2021-06-01T15:59:47.049Z,1622563187.049 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1211
2021-06-01T15:59:47.053Z,1622563187.053 [Supervisor](INFO): Main Thread ID is 822
2021-06-01T15:59:47.053Z,1622563187.053 [Supervisor](DEBUG): Running supervisor.
2021-06-01T15:59:47.054Z,1622563187.054 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1212
2021-06-01T15:59:47.057Z,1622563187.057 [controlThread ThreadHandler](INFO): Handler Thread ID is 1213
2021-06-01T15:59:47.058Z,1622563187.058 [controlThread](DEBUG): Initializing ControlThread
2021-06-01T15:59:47.059Z,1622563187.059 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2021-06-01T15:59:47.061Z,1622563187.061 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2021-06-01T15:59:47.061Z,1622563187.061 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2021-06-01T15:59:47.062Z,1622563187.062 [LoopControl](DEBUG): Initialize LoopControlComponent.
2021-06-01T15:59:47.064Z,1622563187.064 [NavChart](DEBUG): Initialize NavChart Navigation.
2021-06-01T15:59:47.064Z,1622563187.064 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2021-06-01T15:59:47.069Z,1622563187.069 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2021-06-01T15:59:47.070Z,1622563187.070 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2021-06-01T15:59:47.070Z,1622563187.070 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2021-06-01T15:59:47.070Z,1622563187.070 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2021-06-01T15:59:47.071Z,1622563187.071 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2021-06-01T15:59:47.072Z,1622563187.072 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2021-06-01T15:59:47.075Z,1622563187.075 [SBIT](INFO): Initialize SBIT Component.
2021-06-01T15:59:47.076Z,1622563187.076 [SBIT](IMPORTANT): git: 2021-06-01
2021-06-01T15:59:47.076Z,1622563187.076 [SBIT](INFO): git hash: cf38c2cb22e5f1a07f3566d8bf81cf524e6bff3d
2021-06-01T15:59:47.077Z,1622563187.077 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2021-06-01T15:59:47.078Z,1622563187.078 [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 Nov 13 12:38:10 PST 2019
2021-06-01T15:59:47.079Z,1622563187.079 [SBIT](INFO): Beginning SBIT in 57.000000 seconds.
2021-06-01T15:59:47.080Z,1622563187.080 [IBIT](INFO): Initialize IBIT Component.
2021-06-01T15:59:47.081Z,1622563187.081 [CBIT](DEBUG): Initialize CBIT Component.
2021-06-01T15:59:47.081Z,1622563187.081 [logger ThreadHandler](INFO): Handler Thread ID is 1214
2021-06-01T15:59:47.091Z,1622563187.091 [CBIT](DEBUG): Initialized mux pins.
2021-06-01T15:59:47.091Z,1622563187.091 [CBIT](DEBUG): Initializing the watchdog timer.
2021-06-01T15:59:47.100Z,1622563187.100 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1215
2021-06-01T15:59:47.101Z,1622563187.101 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2021-06-01T15:59:47.112Z,1622563187.112 [Onboard ThreadHandler](INFO): Handler Thread ID is 1216
2021-06-01T15:59:47.116Z,1622563187.116 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2021-06-01T15:59:47.116Z,1622563187.116 [CBIT](DEBUG): Initializing heartbeat.
2021-06-01T15:59:47.132Z,1622563187.132 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1217
2021-06-01T15:59:47.148Z,1622563187.148 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1218
2021-06-01T15:59:47.148Z,1622563187.148 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2021-06-01T15:59:47.153Z,1622563187.153 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1220
2021-06-01T15:59:47.156Z,1622563187.156 [WetLabsBB2FL](INFO): Powering up
2021-06-01T15:59:47.157Z,1622563187.157 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1222
2021-06-01T15:59:47.160Z,1622563187.160 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2021-06-01T15:59:47.160Z,1622563187.160 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2021-06-01T15:59:47.161Z,1622563187.161 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2021-06-01T15:59:47.161Z,1622563187.161 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2021-06-01T15:59:47.161Z,1622563187.161 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2021-06-01T15:59:47.161Z,1622563187.161 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2021-06-01T15:59:47.161Z,1622563187.161 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2021-06-01T15:59:47.162Z,1622563187.162 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2021-06-01T15:59:47.162Z,1622563187.162 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2021-06-01T15:59:47.162Z,1622563187.162 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2021-06-01T15:59:47.162Z,1622563187.162 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2021-06-01T15:59:47.162Z,1622563187.162 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2021-06-01T15:59:47.163Z,1622563187.163 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2021-06-01T15:59:47.163Z,1622563187.163 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2021-06-01T15:59:47.163Z,1622563187.163 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2021-06-01T15:59:47.164Z,1622563187.164 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2021-06-01T15:59:47.187Z,1622563187.187 [CBIT](DEBUG): Deactivating GF circuits.
2021-06-01T15:59:47.187Z,1622563187.187 [CBIT](DEBUG): Deactivating emergency mode.
2021-06-01T15:59:47.223Z,1622563187.223 [CBIT](DEBUG): Backplane powered.
2021-06-01T15:59:47.224Z,1622563187.224 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2021-06-01T15:59:47.237Z,1622563187.237 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2021-06-01T15:59:47.253Z,1622563187.253 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2021-06-01T15:59:47.254Z,1622563187.254 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2021-06-01T15:59:47.332Z,1622563187.332 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2021-06-01T15:59:47.333Z,1622563187.333 [Default:A.Wait](DEBUG): Construct Wait.
2021-06-01T15:59:47.344Z,1622563187.344 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2021-06-01T15:59:47.395Z,1622563187.395 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2021-06-01T15:59:47.398Z,1622563187.398 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2021-06-01T15:59:47.420Z,1622563187.420 [Default:E.Execute](DEBUG): Construct Execute.
2021-06-01T15:59:47.424Z,1622563187.424 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2021-06-01T15:59:47.435Z,1622563187.435 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,AMEcho,DAT,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2021-06-01T15:59:47.443Z,1622563187.443 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2021-06-01T15:59:47.528Z,1622563187.528 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar
2021-06-01T15:59:47.529Z,1622563187.529 [Power24vConverter](INFO): Powering up.
2021-06-01T15:59:47.530Z,1622563187.530 [AMEcho](INFO): Powering up.
2021-06-01T15:59:47.539Z,1622563187.539 [Radio_Surface](INFO): Powering up
2021-06-01T15:59:47.548Z,1622563187.548 [DAT](INFO): Powering up
2021-06-01T15:59:47.548Z,1622563187.548 [DAT](DEBUG): Initializing DAT.
2021-06-01T15:59:47.589Z,1622563187.589 [DepthRateCalculator](ERROR): Depth measurement is not active
2021-06-01T15:59:47.634Z,1622563187.634 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2021-06-01T15:59:47.643Z,1622563187.643 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2021-06-01T15:59:47.645Z,1622563187.645 [ElevatorServo](DEBUG): Initializing EZServoServo.
2021-06-01T15:59:47.651Z,1622563187.651 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2021-06-01T15:59:47.652Z,1622563187.652 [MassServo](DEBUG): Initializing EZServoServo.
2021-06-01T15:59:47.659Z,1622563187.659 [MassServo](DEBUG): Initializing MassServo.
2021-06-01T15:59:47.660Z,1622563187.660 [RudderServo](DEBUG): Initializing EZServoServo.
2021-06-01T15:59:47.668Z,1622563187.668 [RudderServo](DEBUG): Initializing RudderServo.
2021-06-01T15:59:47.668Z,1622563187.668 [ThrusterServo](DEBUG): Initializing EZServoServo.
2021-06-01T15:59:47.675Z,1622563187.675 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2021-06-01T15:59:48.031Z,1622563188.031 [AMEcho](INFO): Powering down.
2021-06-01T15:59:48.575Z,1622563188.575 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2021-06-01T15:59:48.575Z,1622563188.575 [RudderServo](FAULT): Rudder failed to initialize
2021-06-01T15:59:48.575Z,1622563188.575 [RudderServo] Communications Fault, FailCount= 1
2021-06-01T15:59:48.576Z,1622563188.576 [RudderServo](ERROR): Communications Fault
2021-06-01T15:59:48.679Z,1622563188.679 [CBIT](ERROR): Communications Fault in component: RudderServo
2021-06-01T15:59:48.689Z,1622563188.689 [AMEcho](IMPORTANT): Setting 'depthThreshold' to 20.000000
2021-06-01T15:59:48.844Z,1622563188.844 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2021-06-01T15:59:48.844Z,1622563188.844 [RudderServo](INFO): Powering down
2021-06-01T15:59:49.263Z,1622563189.263 [WetLabsBB2FL](INFO): Powering down
2021-06-01T15:59:49.516Z,1622563189.516 [RudderServo](DEBUG): Initializing EZServoServo.
2021-06-01T15:59:49.636Z,1622563189.636 [RudderServo](DEBUG): Initializing RudderServo.
2021-06-01T15:59:49.640Z,1622563189.640 [CBIT](INFO): Clearing failed state for component RudderServo
2021-06-01T15:59:49.640Z,1622563189.640 [RudderServo] No Fault, FailCount= 1
2021-06-01T15:59:54.180Z,1622563194.180 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2021-06-01T16:00:16.165Z,1622563216.165 [NAL9602](INFO): Powering up NAL9602
2021-06-01T16:00:27.073Z,1622563227.073 [NAL9602](INFO): NAL9602 initialized
2021-06-01T16:00:44.867Z,1622563244.867 [SBIT](IMPORTANT): Beginning Startup BIT
2021-06-01T16:00:44.872Z,1622563244.872 [CBIT](IMPORTANT): Beginning ground fault scan
2021-06-01T16:00:47.678Z,1622563247.678 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-06-01T16:00:47.678Z,1622563247.678 [DAT] Communications Fault, FailCount= 1
2021-06-01T16:00:47.678Z,1622563247.678 [DAT](ERROR): Communications Fault
2021-06-01T16:00:47.780Z,1622563247.780 [CBIT](ERROR): Communications Fault in component: DAT
2021-06-01T16:00:48.118Z,1622563248.118 [DAT](INFO): Powering down
2021-06-01T16:00:48.980Z,1622563248.980 [CBIT](INFO): Clearing failed state for component DAT
2021-06-01T16:00:48.980Z,1622563248.980 [DAT] No Fault, FailCount= 1
2021-06-01T16:00:51.332Z,1622563251.332 [DAT](INFO): Powering up
2021-06-01T16:00:51.332Z,1622563251.332 [DAT](DEBUG): Initializing DAT.
2021-06-01T16:00:53.350Z,1622563253.350 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-06-01T16:00:55.782Z,1622563255.782 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.015528
CHAN A1 (24V): 0.000271
CHAN A2 (12V): -0.002015
CHAN A3 (5V): 4.753929
CHAN B0 (3.3V): 0.001606
CHAN B1 (3.15aV): 4.767929
CHAN B2 (3.15bV): 0.000284
CHAN B3 (GND): 0.000285
OPEN: -0.000517
Full Scale Calc: 4.765 mA, -1.589 mA
2021-06-01T16:01:39.035Z,1622563299.035 [SBIT](IMPORTANT): SBIT PASSED
2021-06-01T16:01:39.036Z,1622563299.036 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2021-06-01T16:01:39.036Z,1622563299.036 [SBIT](IMPORTANT): DAT.verbosity=3 count;

2021-06-01T16:01:39.037Z,1622563299.037 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=282 cubic_centimeter;

2021-06-01T16:01:39.037Z,1622563299.037 [SBIT](IMPORTANT): VerticalControl.massDefault=9.25 millimeter;

2021-06-01T16:01:39.422Z,1622563299.422 [MissionManager](IMPORTANT): Started mission Startup
2021-06-01T16:01:39.422Z,1622563299.422 [Startup] Running Loop=1
2021-06-01T16:01:39.422Z,1622563299.422 [Startup](DEBUG): Aggregate::initialize Startup
2021-06-01T16:01:39.422Z,1622563299.422 [Startup:A.GoToSurface] Running Loop=1
2021-06-01T16:01:39.422Z,1622563299.422 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-06-01T16:01:39.423Z,1622563299.423 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-06-01T16:01:39.424Z,1622563299.424 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-06-01T16:01:39.424Z,1622563299.424 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-06-01T16:01:39.424Z,1622563299.424 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-06-01T16:01:39.425Z,1622563299.425 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-06-01T16:01:39.426Z,1622563299.426 [Startup:StartupSatComms] Running Loop=1
2021-06-01T16:01:39.426Z,1622563299.426 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2021-06-01T16:01:39.427Z,1622563299.427 [Startup:StartupSatComms:A] Running Loop=1
2021-06-01T16:01:39.821Z,1622563299.821 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2021-06-01T16:01:40.201Z,1622563300.201 [AMEcho](INFO): Powering up.
2021-06-01T16:01:51.514Z,1622563311.514 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-06-01T16:01:51.514Z,1622563311.514 [DAT] Communications Fault, FailCount= 2
2021-06-01T16:01:51.514Z,1622563311.514 [DAT](ERROR): Communications Fault
2021-06-01T16:01:51.567Z,1622563311.567 [CBIT](ERROR): Communications Fault in component: DAT
2021-06-01T16:01:51.919Z,1622563311.919 [DAT](INFO): Powering down
2021-06-01T16:01:52.744Z,1622563312.744 [CBIT](INFO): Clearing failed state for component DAT
2021-06-01T16:01:52.744Z,1622563312.744 [DAT] No Fault, FailCount= 2
2021-06-01T16:01:55.150Z,1622563315.150 [DAT](INFO): Powering up
2021-06-01T16:01:55.150Z,1622563315.150 [DAT](DEBUG): Initializing DAT.
2021-06-01T16:02:21.863Z,1622563341.863 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-06-01T16:02:30.021Z,1622563350.021 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004545
2021-06-01T16:02:32.773Z,1622563352.773 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-06-01T16:02:39.602Z,1622563359.602 [Startup:StartupSatComms:A](INFO): Timed out from 2021-06-01T16:01:39.4Z
2021-06-01T16:02:39.603Z,1622563359.603 [Startup:StartupSatComms:A] Stopped
2021-06-01T16:02:39.603Z,1622563359.603 [Startup:StartupSatComms:B] Running Loop=1
2021-06-01T16:02:40.028Z,1622563360.028 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2021-06-01T16:02:46.343Z,1622563366.343 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20210601T145301/Courier0022.lzma
2021-06-01T16:02:47.345Z,1622563367.345 [DataOverHttps](INFO): Moved sent file to Logs/20210601T145301/Courier0022.lzma.bak
2021-06-01T16:02:47.345Z,1622563367.345 [DataOverHttps](INFO): SBD MOMSN=15681234
2021-06-01T16:02:47.382Z,1622563367.382 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2021-06-01T16:02:47.382Z,1622563367.382 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T16:02:47.394Z,1622563367.394 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T16:02:47.763Z,1622563367.763 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T16:02:47.764Z,1622563367.764 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2021-06-01T16:02:55.422Z,1622563375.422 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-06-01T16:02:55.423Z,1622563375.423 [DAT] Communications Fault, FailCount= 3
2021-06-01T16:02:55.423Z,1622563375.423 [DAT](ERROR): Communications Fault
2021-06-01T16:02:55.469Z,1622563375.469 [CBIT](ERROR): Communications Fault in component: DAT
2021-06-01T16:02:55.827Z,1622563375.827 [DAT](INFO): Powering down
2021-06-01T16:02:56.700Z,1622563376.700 [CBIT](INFO): Clearing failed state for component DAT
2021-06-01T16:02:56.700Z,1622563376.700 [DAT] No Fault, FailCount= 3
2021-06-01T16:02:59.059Z,1622563379.059 [DAT](INFO): Powering up
2021-06-01T16:02:59.059Z,1622563379.059 [DAT](DEBUG): Initializing DAT.
2021-06-01T16:03:02.072Z,1622563382.072 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-06-01T16:03:05.867Z,1622563385.867 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20210601T155937/Courier0000.lzma
2021-06-01T16:03:06.869Z,1622563386.869 [DataOverHttps](INFO): Moved sent file to Logs/20210601T155937/Courier0000.lzma.bak
2021-06-01T16:03:06.869Z,1622563386.869 [DataOverHttps](INFO): SBD MOMSN=15681236
2021-06-01T16:03:20.630Z,1622563400.630 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-06-01T16:03:24.163Z,1622563404.163 [DataOverHttps](INFO): Sending 185 bytes from file Logs/20210601T145301/Express0023.lzma
2021-06-01T16:03:25.165Z,1622563405.165 [DataOverHttps](INFO): Moved sent file to Logs/20210601T145301/Express0023.lzma.bak
2021-06-01T16:03:25.165Z,1622563405.165 [DataOverHttps](INFO): SBD MOMSN=15681239
2021-06-01T16:03:39.961Z,1622563419.961 [Startup:StartupSatComms:B](INFO): Timed out from 2021-06-01T16:02:39.6Z
2021-06-01T16:03:39.961Z,1622563419.961 [Startup:StartupSatComms:B] Stopped
2021-06-01T16:03:39.961Z,1622563419.961 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2021-06-01T16:03:39.962Z,1622563419.962 [Startup:StartupSatComms] Stopped
2021-06-01T16:03:39.962Z,1622563419.962 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2021-06-01T16:03:39.963Z,1622563419.963 [Startup](INFO): Completed Startup
2021-06-01T16:03:39.963Z,1622563419.963 [MissionManager](INFO): Startup is completed.
2021-06-01T16:03:39.963Z,1622563419.963 [MissionManager](INFO): Uninitializing Mission Startup
2021-06-01T16:03:39.963Z,1622563419.963 [Startup] Stopped
2021-06-01T16:03:39.963Z,1622563419.963 [Startup](DEBUG): Aggregate::uninitialize Startup
2021-06-01T16:03:39.963Z,1622563419.963 [Startup:A.GoToSurface] Stopped
2021-06-01T16:03:39.963Z,1622563419.963 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-06-01T16:03:40.361Z,1622563420.361 [MissionManager](IMPORTANT): Started mission Default
2021-06-01T16:03:40.361Z,1622563420.361 [Default] Running Loop=1
2021-06-01T16:03:40.361Z,1622563420.361 [Default](DEBUG): Aggregate::initialize Default
2021-06-01T16:03:40.361Z,1622563420.361 [Default:B.GoToSurface] Running Loop=1
2021-06-01T16:03:40.362Z,1622563420.362 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-06-01T16:03:40.362Z,1622563420.362 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-06-01T16:03:40.362Z,1622563420.362 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-06-01T16:03:40.363Z,1622563420.363 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-06-01T16:03:40.367Z,1622563420.367 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-06-01T16:03:40.367Z,1622563420.367 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-06-01T16:03:40.368Z,1622563420.368 [Default:A.Wait] Running Loop=1
2021-06-01T16:03:40.368Z,1622563420.368 [Default:A.Wait](DEBUG): Initialize Wait Component.
2021-06-01T16:03:40.899Z,1622563420.899 [AMEcho](INFO): Powering down.
2021-06-01T16:03:41.949Z,1622563421.949 [AMEcho](INFO): Powering up.
2021-06-01T16:03:42.300Z,1622563422.300 [DataOverHttps](INFO): Sending 804 bytes from file Logs/20210601T155937/Express0001.lzma
2021-06-01T16:03:43.301Z,1622563423.301 [DataOverHttps](INFO): Moved sent file to Logs/20210601T155937/Express0001.lzma.bak
2021-06-01T16:03:43.302Z,1622563423.302 [DataOverHttps](INFO): SBD MOMSN=15681245
2021-06-01T16:03:53.691Z,1622563433.691 [Default:A.Wait](INFO): Done Waiting.
2021-06-01T16:03:53.691Z,1622563433.691 [Default:A.Wait] Stopped
2021-06-01T16:03:53.691Z,1622563433.691 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2021-06-01T16:03:54.084Z,1622563434.084 [AMEcho](ERROR): No EchoSounder communication! Re-initializing
2021-06-01T16:03:54.084Z,1622563434.084 [AMEcho] Communications Fault, FailCount= 1
2021-06-01T16:03:54.084Z,1622563434.084 [AMEcho](ERROR): Communications Fault
2021-06-01T16:03:54.093Z,1622563434.093 [Default:CheckIn] Running Loop=1
2021-06-01T16:03:54.093Z,1622563434.093 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-06-01T16:03:54.093Z,1622563434.093 [Default:CheckIn:Read_GPS] Running Loop=1
2021-06-01T16:03:54.103Z,1622563434.103 [CBIT](ERROR): Communications Fault in component: AMEcho
2021-06-01T16:03:54.651Z,1622563434.651 [AMEcho](INFO): Powering down.
2021-06-01T16:03:54.667Z,1622563434.667 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2021-06-01T16:03:55.299Z,1622563435.299 [CBIT](INFO): Clearing failed state for component AMEcho
2021-06-01T16:03:55.299Z,1622563435.299 [AMEcho] No Fault, FailCount= 1
2021-06-01T16:03:55.694Z,1622563435.694 [AMEcho](INFO): Powering up.
2021-06-01T16:03:59.337Z,1622563439.337 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-06-01T16:03:59.337Z,1622563439.337 [DAT] Communications Fault, FailCount= 4
2021-06-01T16:03:59.337Z,1622563439.337 [DAT](ERROR): Communications Fault
2021-06-01T16:03:59.388Z,1622563439.388 [CBIT](ERROR): Communications Fault in component: DAT
2021-06-01T16:03:59.736Z,1622563439.736 [DAT](INFO): Powering down
2021-06-01T16:04:00.580Z,1622563440.580 [CBIT](INFO): Clearing failed state for component DAT
2021-06-01T16:04:00.580Z,1622563440.580 [DAT] No Fault, FailCount= 4
2021-06-01T16:04:02.961Z,1622563442.961 [DAT](INFO): Powering up
2021-06-01T16:04:02.961Z,1622563442.961 [DAT](DEBUG): Initializing DAT.
2021-06-01T16:04:07.802Z,1622563447.802 [AMEcho](ERROR): No EchoSounder communication! Re-initializing
2021-06-01T16:04:07.802Z,1622563447.802 [AMEcho] Communications Fault, FailCount= 2
2021-06-01T16:04:07.802Z,1622563447.802 [AMEcho](ERROR): Communications Fault
2021-06-01T16:04:07.857Z,1622563447.857 [CBIT](ERROR): Communications Fault in component: AMEcho
2021-06-01T16:04:08.372Z,1622563448.372 [AMEcho](INFO): Powering down.
2021-06-01T16:04:09.048Z,1622563449.048 [CBIT](INFO): Clearing failed state for component AMEcho
2021-06-01T16:04:09.048Z,1622563449.048 [AMEcho] No Fault, FailCount= 2
2021-06-01T16:04:09.432Z,1622563449.432 [AMEcho](INFO): Powering up.
2021-06-01T16:04:21.546Z,1622563461.546 [AMEcho](ERROR): No EchoSounder communication! Re-initializing
2021-06-01T16:04:21.546Z,1622563461.546 [AMEcho] Communications Fault, FailCount= 3
2021-06-01T16:04:21.546Z,1622563461.546 [AMEcho](ERROR): Communications Fault
2021-06-01T16:04:21.565Z,1622563461.565 [CBIT](ERROR): Communications Fault in component: AMEcho
2021-06-01T16:04:21.565Z,1622563461.565 [CBIT](CRITICAL): Communications Fault in component: AMEcho
2021-06-01T16:04:22.107Z,1622563462.107 [AMEcho](INFO): Powering down.
2021-06-01T16:04:22.129Z,1622563462.129 [CommandLine](FAULT): Scheduling is paused
2021-06-01T16:04:22.130Z,1622563462.130 [CBIT](INFO): Critical error at 20210601T160421
2021-06-01T16:04:22.130Z,1622563462.130 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2021-06-01T16:04:42.205Z,1622563482.205 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-06-01T16:05:03.172Z,1622563503.172 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-06-01T16:05:03.172Z,1622563503.172 [DAT] Communications Fault, FailCount= 5
2021-06-01T16:05:03.172Z,1622563503.172 [DAT](ERROR): Communications Fault
2021-06-01T16:05:03.196Z,1622563503.196 [CBIT](ERROR): Communications Fault in component: DAT
2021-06-01T16:05:03.557Z,1622563503.557 [DAT](INFO): Powering down
2021-06-01T16:05:04.388Z,1622563504.388 [CBIT](INFO): Clearing failed state for component DAT
2021-06-01T16:05:04.388Z,1622563504.388 [DAT] No Fault, FailCount= 5
2021-06-01T16:05:06.790Z,1622563506.790 [DAT](INFO): Powering up
2021-06-01T16:05:06.790Z,1622563506.790 [DAT](DEBUG): Initializing DAT.
2021-06-01T16:05:30.629Z,1622563530.629 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2021-06-01T16:05:48.429Z,1622563548.429 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2021-06-01T16:05:48.429Z,1622563548.429 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T16:05:48.473Z,1622563548.473 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T16:05:48.852Z,1622563548.852 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T16:05:48.852Z,1622563548.852 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2021-06-01T16:06:06.992Z,1622563566.992 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-06-01T16:06:06.993Z,1622563566.993 [DAT] Communications Fault, FailCount= 6
2021-06-01T16:06:06.993Z,1622563566.993 [DAT](ERROR): Communications Fault
2021-06-01T16:06:07.045Z,1622563567.045 [CBIT](ERROR): Communications Fault in component: DAT
2021-06-01T16:06:07.398Z,1622563567.398 [DAT](INFO): Powering down
2021-06-01T16:06:08.226Z,1622563568.226 [CBIT](INFO): Clearing failed state for component DAT
2021-06-01T16:06:08.226Z,1622563568.226 [DAT] No Fault, FailCount= 6
2021-06-01T16:06:10.623Z,1622563570.623 [DAT](INFO): Powering up
2021-06-01T16:06:10.623Z,1622563570.623 [DAT](DEBUG): Initializing DAT.
2021-06-01T16:07:10.822Z,1622563630.822 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-06-01T16:07:10.822Z,1622563630.822 [DAT] Communications Fault, FailCount= 7
2021-06-01T16:07:10.822Z,1622563630.822 [DAT](ERROR): Communications Fault
2021-06-01T16:07:10.840Z,1622563630.840 [CBIT](ERROR): Communications Fault in component: DAT
2021-06-01T16:07:11.222Z,1622563631.222 [DAT](INFO): Powering down
2021-06-01T16:07:12.066Z,1622563632.066 [CBIT](INFO): Clearing failed state for component DAT
2021-06-01T16:07:12.066Z,1622563632.066 [DAT] No Fault, FailCount= 7
2021-06-01T16:07:14.464Z,1622563634.464 [DAT](INFO): Powering up
2021-06-01T16:07:14.464Z,1622563634.464 [DAT](DEBUG): Initializing DAT.
2021-06-01T16:07:21.772Z,1622563641.772 [CBIT](INFO): Clearing failed state for component AMEcho
2021-06-01T16:07:21.772Z,1622563641.772 [AMEcho] No Fault, FailCount= 3
2021-06-01T16:07:22.133Z,1622563642.133 [AMEcho](INFO): Powering up.
2021-06-01T16:07:33.875Z,1622563653.875 [AMEcho](INFO): Failure count cleared after critical for AMEcho
2021-06-01T16:07:39.909Z,1622563659.909 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2021-06-01T16:07:39.913Z,1622563659.913 [BPC1](INFO): Received data from all battery sticks.
2021-06-01T16:08:14.669Z,1622563694.669 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-06-01T16:08:14.670Z,1622563694.670 [DAT] Communications Fault, FailCount= 8
2021-06-01T16:08:14.670Z,1622563694.670 [DAT](ERROR): Communications Fault
2021-06-01T16:08:14.716Z,1622563694.716 [CBIT](ERROR): Communications Fault in component: DAT
2021-06-01T16:08:14.717Z,1622563694.717 [CBIT](FAULT): Communications Fault in component: DAT
2021-06-01T16:08:15.072Z,1622563695.072 [DAT](INFO): Powering down
2021-06-01T16:08:49.404Z,1622563729.404 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2021-06-01T16:08:49.404Z,1622563729.404 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T16:08:49.432Z,1622563729.432 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T16:08:49.851Z,1622563729.851 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T16:08:49.851Z,1622563729.851 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2021-06-01T16:08:54.278Z,1622563734.278 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-06-01T16:03:54.1Z
2021-06-01T16:08:54.278Z,1622563734.278 [Default:CheckIn:Read_GPS] Stopped
2021-06-01T16:08:54.279Z,1622563734.279 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-06-01T16:08:54.665Z,1622563734.665 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2021-06-01T16:09:02.323Z,1622563742.323 [DataOverHttps](INFO): Sending 228 bytes from file Logs/20210601T155937/Courier0004.lzma
2021-06-01T16:09:03.325Z,1622563743.325 [DataOverHttps](INFO): Moved sent file to Logs/20210601T155937/Courier0004.lzma.bak
2021-06-01T16:09:03.325Z,1622563743.325 [DataOverHttps](INFO): SBD MOMSN=15681263
2021-06-01T16:09:20.299Z,1622563760.299 [DataOverHttps](INFO): Sending 337 bytes from file Logs/20210601T155937/Express0005.lzma
2021-06-01T16:09:21.301Z,1622563761.301 [DataOverHttps](INFO): Moved sent file to Logs/20210601T155937/Express0005.lzma.bak
2021-06-01T16:09:21.301Z,1622563761.301 [DataOverHttps](INFO): SBD MOMSN=15681266
2021-06-01T16:09:23.809Z,1622563763.809 [Default:CheckIn:Read_Iridium] Stopped
2021-06-01T16:09:23.809Z,1622563763.809 [Default:CheckIn:C.Wait] Running Loop=1
2021-06-01T16:09:23.810Z,1622563763.810 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-06-01T16:10:28.005Z,1622563828.005 [NAL9602](FAULT): GPS failed to acquire within timeout.
2021-06-01T16:10:28.005Z,1622563828.005 [NAL9602] Data Fault, FailCount= 1
2021-06-01T16:10:28.005Z,1622563828.005 [NAL9602](ERROR): Data Fault
2021-06-01T16:10:28.089Z,1622563828.089 [CBIT](ERROR): Data Fault in component: NAL9602
2021-06-01T16:10:28.409Z,1622563828.409 [NAL9602](INFO): Powering down
2021-06-01T16:10:28.813Z,1622563828.813 [NAL9602](FAULT): LCB fault:  Software Overcurrent.
2021-06-01T16:10:28.813Z,1622563828.813 [NAL9602] Hardware Fault, FailCount= 1
2021-06-01T16:10:28.813Z,1622563828.813 [NAL9602](ERROR): Hardware Fault
2021-06-01T16:10:29.281Z,1622563829.281 [CBIT](INFO): Clearing failed state for component NAL9602
2021-06-01T16:10:29.281Z,1622563829.281 [NAL9602] No Fault, FailCount= 1
2021-06-01T16:10:58.713Z,1622563858.713 [NAL9602](INFO): Powering up NAL9602
2021-06-01T16:11:09.621Z,1622563869.621 [NAL9602](INFO): NAL9602 initialized
2021-06-01T16:11:50.446Z,1622563910.446 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2021-06-01T16:11:50.446Z,1622563910.446 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T16:11:50.457Z,1622563910.457 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T16:11:50.871Z,1622563910.871 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T16:11:50.871Z,1622563910.871 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2021-06-01T16:13:14.908Z,1622563994.908 [CBIT](INFO): Clearing failed state for component DAT
2021-06-01T16:13:14.908Z,1622563994.908 [DAT] No Fault, FailCount= 8
2021-06-01T16:13:15.279Z,1622563995.279 [DAT](INFO): Powering up
2021-06-01T16:13:15.279Z,1622563995.279 [DAT](DEBUG): Initializing DAT.
2021-06-01T16:13:26.608Z,1622564006.608 [DAT](INFO): DAT read: 

2021-06-01T16:13:26.610Z,1622564006.610 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2021-06-01T16:13:28.237Z,1622564008.237 [DAT](INFO): DAT read: MF Frequency Band

2021-06-01T16:13:28.238Z,1622564008.238 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.14.2

2021-06-01T16:13:28.239Z,1622564008.239 [DAT](INFO): DAT read: Jun  1 2021 16:12:57 

2021-06-01T16:13:29.427Z,1622564009.427 [DAT](INFO): DAT read: Features enabled [Bearing]

2021-06-01T16:13:29.429Z,1622564009.429 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP

2021-06-01T16:13:29.429Z,1622564009.429 [DAT](INFO): commRate: 800
2021-06-01T16:13:31.454Z,1622564011.454 [DAT](INFO): entering command mode
2021-06-01T16:13:31.857Z,1622564011.857 [DAT](INFO): DAT read: 

2021-06-01T16:13:31.858Z,1622564011.858 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T16:13:32.246Z,1622564012.246 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T16:13:32.653Z,1622564012.653 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T16:13:33.055Z,1622564013.055 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T16:13:33.459Z,1622564013.459 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T16:13:33.863Z,1622564013.863 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T16:13:34.281Z,1622564014.281 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T16:13:34.685Z,1622564014.685 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T16:13:35.085Z,1622564015.085 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T16:13:35.479Z,1622564015.479 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T16:13:35.885Z,1622564015.885 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T16:13:36.283Z,1622564016.283 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T16:13:36.692Z,1622564016.692 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T16:13:37.105Z,1622564017.105 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T16:13:37.517Z,1622564017.517 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T16:13:37.922Z,1622564017.922 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T16:13:38.302Z,1622564018.302 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T16:13:38.707Z,1622564018.707 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T16:13:39.117Z,1622564019.117 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T16:13:39.515Z,1622564019.515 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T16:13:39.919Z,1622564019.919 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T16:13:40.337Z,1622564020.337 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T16:13:40.745Z,1622564020.745 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T16:13:41.145Z,1622564021.145 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T16:13:41.539Z,1622564021.539 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T16:13:41.943Z,1622564021.943 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T16:13:42.351Z,1622564022.351 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T16:13:42.751Z,1622564022.751 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T16:13:43.165Z,1622564023.165 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T16:13:43.569Z,1622564023.569 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T16:13:43.977Z,1622564023.977 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T16:13:44.362Z,1622564024.362 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T16:13:44.771Z,1622564024.771 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T16:13:45.172Z,1622564025.172 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T16:13:45.580Z,1622564025.580 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T16:13:45.998Z,1622564025.998 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T16:13:46.401Z,1622564026.401 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T16:13:46.805Z,1622564026.805 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T16:13:46.805Z,1622564026.805 [DAT](FAULT): failed to enter command mode
2021-06-01T16:13:47.200Z,1622564027.200 [DAT](INFO): entering command mode
2021-06-01T16:13:47.599Z,1622564027.599 [DAT](INFO): DAT read: user:1>

2021-06-01T16:13:47.615Z,1622564027.615 [DAT](INFO): DAT read: Command '+++' not found

2021-06-01T16:13:47.616Z,1622564027.616 [DAT](INFO): DAT read: Error

2021-06-01T16:13:47.616Z,1622564027.616 [DAT](INFO): setting verbose to 3
2021-06-01T16:13:48.013Z,1622564028.013 [DAT](INFO): DAT read: user:2>

2021-06-01T16:13:48.015Z,1622564028.015 [DAT](INFO): DAT read: Verbose         | 3           

2021-06-01T16:13:48.015Z,1622564028.015 [DAT](INFO): set verbose to 3
2021-06-01T16:13:48.015Z,1622564028.015 [DAT](INFO): setting DatVerbose to 27440
2021-06-01T16:13:48.421Z,1622564028.421 [DAT](INFO): DAT read: user:3>

2021-06-01T16:13:48.422Z,1622564028.422 [DAT](INFO): DAT read: DatVerbose      | 27440       

2021-06-01T16:13:48.422Z,1622564028.422 [DAT](INFO): set DatVerbose to 27440
2021-06-01T16:13:48.423Z,1622564028.423 [DAT](INFO): setting transmit power to 8
2021-06-01T16:13:48.826Z,1622564028.826 [DAT](INFO): DAT read: user:4>

2021-06-01T16:13:48.827Z,1622564028.827 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2021-06-01T16:13:48.827Z,1622564028.827 [DAT](INFO): set transmit power to 8
2021-06-01T16:13:48.827Z,1622564028.827 [DAT](INFO): setting local address to 7
2021-06-01T16:13:49.221Z,1622564029.221 [DAT](INFO): DAT read: user:5>

2021-06-01T16:13:49.222Z,1622564029.222 [DAT](INFO): DAT read: LocalAddr       | 7           

2021-06-01T16:13:49.223Z,1622564029.223 [DAT](INFO): set local address to 7
2021-06-01T16:14:24.382Z,1622564064.382 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-06-01T16:14:24.382Z,1622564064.382 [Default:CheckIn:C.Wait] Stopped
2021-06-01T16:14:24.382Z,1622564064.382 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-06-01T16:14:24.382Z,1622564064.382 [Default:CheckIn:D] Running Loop=1
2021-06-01T16:14:24.775Z,1622564064.775 [Default:CheckIn:D] Stopped
2021-06-01T16:14:24.775Z,1622564064.775 [Default:CheckIn:E] Running Loop=1
2021-06-01T16:14:25.179Z,1622564065.179 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.740233 min 
2021-06-01T16:14:25.179Z,1622564065.179 [Default:CheckIn:E] Stopped
2021-06-01T16:14:25.180Z,1622564065.180 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-06-01T16:14:25.180Z,1622564065.180 [Default:CheckIn] Stopped
2021-06-01T16:14:25.180Z,1622564065.180 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-06-01T16:14:25.180Z,1622564065.180 [Default:CheckIn](INFO): Running loop #2
2021-06-01T16:14:25.180Z,1622564065.180 [Default:CheckIn] Running Loop=2
2021-06-01T16:14:25.180Z,1622564065.180 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-06-01T16:14:25.180Z,1622564065.180 [Default:CheckIn:Read_GPS] Running Loop=1
2021-06-01T16:14:51.471Z,1622564091.471 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2021-06-01T16:14:51.471Z,1622564091.471 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T16:14:51.482Z,1622564091.482 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T16:14:51.874Z,1622564091.874 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T16:14:51.874Z,1622564091.874 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2021-06-01T16:16:49.000Z,1622564209.000 [DAT](INFO): DAT read: user:6>Lowpower

2021-06-01T16:16:49.000Z,1622564209.000 [DAT](DEBUG): Re-entering command mode due to deviceResponse_: user:6>Lowpower

2021-06-01T16:17:52.436Z,1622564272.436 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2021-06-01T16:17:52.436Z,1622564272.436 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T16:17:52.448Z,1622564272.448 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T16:17:52.852Z,1622564272.852 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T16:17:52.852Z,1622564272.852 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2021-06-01T16:19:25.360Z,1622564365.360 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-06-01T16:14:25.2Z
2021-06-01T16:19:25.360Z,1622564365.360 [Default:CheckIn:Read_GPS] Stopped
2021-06-01T16:19:25.360Z,1622564365.360 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-06-01T16:19:32.171Z,1622564372.171 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20210601T155937/Courier0007.lzma
2021-06-01T16:19:33.175Z,1622564373.175 [DataOverHttps](INFO): Moved sent file to Logs/20210601T155937/Courier0007.lzma.bak
2021-06-01T16:19:33.175Z,1622564373.175 [DataOverHttps](INFO): SBD MOMSN=15681279
2021-06-01T16:19:50.195Z,1622564390.195 [DataOverHttps](INFO): Sending 235 bytes from file Logs/20210601T155937/Express0008.lzma
2021-06-01T16:19:51.197Z,1622564391.197 [DataOverHttps](INFO): Moved sent file to Logs/20210601T155937/Express0008.lzma.bak
2021-06-01T16:19:51.197Z,1622564391.197 [DataOverHttps](INFO): SBD MOMSN=15681281
2021-06-01T16:19:54.058Z,1622564394.058 [Default:CheckIn:Read_Iridium] Stopped
2021-06-01T16:19:54.058Z,1622564394.058 [Default:CheckIn:C.Wait] Running Loop=1
2021-06-01T16:19:54.058Z,1622564394.058 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-06-01T16:20:53.440Z,1622564453.440 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2021-06-01T16:20:53.440Z,1622564453.440 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T16:20:53.479Z,1622564453.479 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T16:20:53.878Z,1622564453.878 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T16:20:53.878Z,1622564453.878 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2021-06-01T16:21:10.789Z,1622564470.789 [NAL9602](FAULT): GPS failed to acquire within timeout.
2021-06-01T16:21:10.789Z,1622564470.789 [NAL9602] Data Fault, FailCount= 2
2021-06-01T16:21:10.789Z,1622564470.789 [NAL9602](ERROR): Data Fault
2021-06-01T16:21:10.809Z,1622564470.809 [CBIT](ERROR): Data Fault in component: NAL9602
2021-06-01T16:21:11.199Z,1622564471.199 [NAL9602](INFO): Powering down
2021-06-01T16:21:12.019Z,1622564472.019 [CBIT](INFO): Clearing failed state for component NAL9602
2021-06-01T16:21:12.019Z,1622564472.019 [NAL9602] No Fault, FailCount= 2
2021-06-01T16:21:41.493Z,1622564501.493 [NAL9602](INFO): Powering up NAL9602
2021-06-01T16:21:52.408Z,1622564512.408 [NAL9602](INFO): NAL9602 initialized
2021-06-01T16:23:54.421Z,1622564634.421 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2021-06-01T16:23:54.421Z,1622564634.421 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T16:23:54.432Z,1622564634.432 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T16:23:54.847Z,1622564634.847 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T16:23:54.847Z,1622564634.847 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2021-06-01T16:24:54.632Z,1622564694.632 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-06-01T16:24:54.633Z,1622564694.633 [Default:CheckIn:C.Wait] Stopped
2021-06-01T16:24:54.633Z,1622564694.633 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-06-01T16:24:54.633Z,1622564694.633 [Default:CheckIn:D] Running Loop=1
2021-06-01T16:24:55.022Z,1622564695.022 [Default:CheckIn:D] Stopped
2021-06-01T16:24:55.022Z,1622564695.022 [Default:CheckIn:E] Running Loop=1
2021-06-01T16:24:55.423Z,1622564695.423 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.244344 min 
2021-06-01T16:24:55.423Z,1622564695.423 [Default:CheckIn:E] Stopped
2021-06-01T16:24:55.423Z,1622564695.423 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-06-01T16:24:55.423Z,1622564695.423 [Default:CheckIn] Stopped
2021-06-01T16:24:55.423Z,1622564695.423 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-06-01T16:24:55.423Z,1622564695.423 [Default:CheckIn](INFO): Running loop #3
2021-06-01T16:24:55.424Z,1622564695.424 [Default:CheckIn] Running Loop=3
2021-06-01T16:24:55.424Z,1622564695.424 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-06-01T16:24:55.424Z,1622564695.424 [Default:CheckIn:Read_GPS] Running Loop=1
2021-06-01T16:26:55.413Z,1622564815.413 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2021-06-01T16:26:55.414Z,1622564815.414 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T16:26:55.425Z,1622564815.425 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T16:26:55.839Z,1622564815.839 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T16:26:55.840Z,1622564815.840 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2021-06-01T16:29:55.603Z,1622564995.603 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-06-01T16:24:55.4Z
2021-06-01T16:29:55.603Z,1622564995.603 [Default:CheckIn:Read_GPS] Stopped
2021-06-01T16:29:55.603Z,1622564995.603 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-06-01T16:29:56.421Z,1622564996.421 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2021-06-01T16:29:56.421Z,1622564996.421 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T16:29:56.525Z,1622564996.525 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T16:29:56.839Z,1622564996.839 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T16:29:56.839Z,1622564996.839 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2021-06-01T16:30:03.235Z,1622565003.235 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20210601T155937/Courier0010.lzma
2021-06-01T16:30:04.237Z,1622565004.237 [DataOverHttps](INFO): Moved sent file to Logs/20210601T155937/Courier0010.lzma.bak
2021-06-01T16:30:04.237Z,1622565004.237 [DataOverHttps](INFO): SBD MOMSN=15681289
2021-06-01T16:30:22.843Z,1622565022.843 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20210601T155937/Express0011.lzma
2021-06-01T16:30:23.845Z,1622565023.845 [DataOverHttps](INFO): Moved sent file to Logs/20210601T155937/Express0011.lzma.bak
2021-06-01T16:30:23.845Z,1622565023.845 [DataOverHttps](INFO): SBD MOMSN=15681291
2021-06-01T16:30:27.605Z,1622565027.605 [Default:CheckIn:Read_Iridium] Stopped
2021-06-01T16:30:27.605Z,1622565027.605 [Default:CheckIn:C.Wait] Running Loop=1
2021-06-01T16:30:27.605Z,1622565027.605 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-06-01T16:31:56.033Z,1622565116.033 [NAL9602](FAULT): GPS failed to acquire within timeout.
2021-06-01T16:31:56.033Z,1622565116.033 [NAL9602] Data Fault, FailCount= 3
2021-06-01T16:31:56.033Z,1622565116.033 [NAL9602](ERROR): Data Fault
2021-06-01T16:31:56.054Z,1622565116.054 [CBIT](ERROR): Data Fault in component: NAL9602
2021-06-01T16:31:56.441Z,1622565116.441 [NAL9602](INFO): Powering down
2021-06-01T16:31:57.284Z,1622565117.284 [CBIT](INFO): Clearing failed state for component NAL9602
2021-06-01T16:31:57.284Z,1622565117.284 [NAL9602] No Fault, FailCount= 3
2021-06-01T16:32:26.741Z,1622565146.741 [NAL9602](INFO): Powering up NAL9602
2021-06-01T16:32:37.649Z,1622565157.649 [NAL9602](INFO): NAL9602 initialized
2021-06-01T16:32:57.451Z,1622565177.451 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2021-06-01T16:32:57.451Z,1622565177.451 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T16:32:57.486Z,1622565177.486 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T16:32:57.888Z,1622565177.888 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T16:32:57.888Z,1622565177.888 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2021-06-01T16:35:28.160Z,1622565328.160 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-06-01T16:35:28.160Z,1622565328.160 [Default:CheckIn:C.Wait] Stopped
2021-06-01T16:35:28.160Z,1622565328.160 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-06-01T16:35:28.160Z,1622565328.160 [Default:CheckIn:D] Running Loop=1
2021-06-01T16:35:28.554Z,1622565328.554 [Default:CheckIn:D] Stopped
2021-06-01T16:35:28.554Z,1622565328.554 [Default:CheckIn:E] Running Loop=1
2021-06-01T16:35:28.951Z,1622565328.951 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.803215 min 
2021-06-01T16:35:28.951Z,1622565328.951 [Default:CheckIn:E] Stopped
2021-06-01T16:35:28.951Z,1622565328.951 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-06-01T16:35:28.951Z,1622565328.951 [Default:CheckIn] Stopped
2021-06-01T16:35:28.951Z,1622565328.951 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-06-01T16:35:28.952Z,1622565328.952 [Default:CheckIn](INFO): Running loop #4
2021-06-01T16:35:28.952Z,1622565328.952 [Default:CheckIn] Running Loop=4
2021-06-01T16:35:28.952Z,1622565328.952 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-06-01T16:35:28.952Z,1622565328.952 [Default:CheckIn:Read_GPS] Running Loop=1
2021-06-01T16:35:58.476Z,1622565358.476 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2021-06-01T16:35:58.476Z,1622565358.476 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T16:35:58.488Z,1622565358.488 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T16:35:58.895Z,1622565358.895 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T16:35:58.896Z,1622565358.896 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2021-06-01T16:38:59.438Z,1622565539.438 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2021-06-01T16:38:59.438Z,1622565539.438 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T16:38:59.449Z,1622565539.449 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T16:38:59.855Z,1622565539.855 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T16:38:59.855Z,1622565539.855 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2021-06-01T16:40:29.183Z,1622565629.183 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-06-01T16:35:28.0Z
2021-06-01T16:40:29.183Z,1622565629.183 [Default:CheckIn:Read_GPS] Stopped
2021-06-01T16:40:29.183Z,1622565629.183 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-06-01T16:40:40.059Z,1622565640.059 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20210601T155937/Courier0013.lzma
2021-06-01T16:40:41.061Z,1622565641.061 [DataOverHttps](INFO): Moved sent file to Logs/20210601T155937/Courier0013.lzma.bak
2021-06-01T16:40:41.061Z,1622565641.061 [DataOverHttps](INFO): SBD MOMSN=15681373
2021-06-01T16:41:01.019Z,1622565661.019 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20210601T155937/Express0014.lzma
2021-06-01T16:41:03.007Z,1622565663.007 [DataOverHttps](INFO): Moved sent file to Logs/20210601T155937/Express0014.lzma.bak
2021-06-01T16:41:03.008Z,1622565663.008 [DataOverHttps](INFO): SBD MOMSN=15681375
2021-06-01T16:41:04.726Z,1622565664.726 [Default:CheckIn:Read_Iridium] Stopped
2021-06-01T16:41:04.726Z,1622565664.726 [Default:CheckIn:C.Wait] Running Loop=1
2021-06-01T16:41:04.726Z,1622565664.726 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-06-01T16:42:00.489Z,1622565720.489 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2021-06-01T16:42:00.489Z,1622565720.489 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T16:42:00.524Z,1622565720.524 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T16:42:00.923Z,1622565720.923 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T16:42:00.923Z,1622565720.923 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2021-06-01T16:42:40.059Z,1622565760.059 [NAL9602](FAULT): GPS failed to acquire within timeout.
2021-06-01T16:42:40.059Z,1622565760.059 [NAL9602] Data Fault, FailCount= 4
2021-06-01T16:42:40.059Z,1622565760.059 [NAL9602](ERROR): Data Fault
2021-06-01T16:42:40.079Z,1622565760.079 [CBIT](ERROR): Data Fault in component: NAL9602
2021-06-01T16:42:40.457Z,1622565760.457 [NAL9602](INFO): Powering down
2021-06-01T16:42:41.287Z,1622565761.287 [CBIT](INFO): Clearing failed state for component NAL9602
2021-06-01T16:42:41.287Z,1622565761.287 [NAL9602] No Fault, FailCount= 4
2021-06-01T16:43:10.757Z,1622565790.757 [NAL9602](INFO): Powering up NAL9602
2021-06-01T16:43:21.669Z,1622565801.669 [NAL9602](INFO): NAL9602 initialized
2021-06-01T16:45:01.500Z,1622565901.500 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2021-06-01T16:45:01.500Z,1622565901.500 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T16:45:01.512Z,1622565901.512 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T16:45:01.917Z,1622565901.917 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T16:45:01.917Z,1622565901.917 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2021-06-01T16:46:05.298Z,1622565965.298 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-06-01T16:46:05.298Z,1622565965.298 [Default:CheckIn:C.Wait] Stopped
2021-06-01T16:46:05.298Z,1622565965.298 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-06-01T16:46:05.298Z,1622565965.298 [Default:CheckIn:D] Running Loop=1
2021-06-01T16:46:05.703Z,1622565965.703 [Default:CheckIn:D] Stopped
2021-06-01T16:46:05.703Z,1622565965.703 [Default:CheckIn:E] Running Loop=1
2021-06-01T16:46:06.110Z,1622565966.110 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.422355 min 
2021-06-01T16:46:06.110Z,1622565966.110 [Default:CheckIn:E] Stopped
2021-06-01T16:46:06.111Z,1622565966.111 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-06-01T16:46:06.111Z,1622565966.111 [Default:CheckIn] Stopped
2021-06-01T16:46:06.111Z,1622565966.111 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-06-01T16:46:06.111Z,1622565966.111 [Default:CheckIn](INFO): Running loop #5
2021-06-01T16:46:06.111Z,1622565966.111 [Default:CheckIn] Running Loop=5
2021-06-01T16:46:06.111Z,1622565966.111 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-06-01T16:46:06.112Z,1622565966.112 [Default:CheckIn:Read_GPS] Running Loop=1
2021-06-01T16:48:02.477Z,1622566082.477 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2021-06-01T16:48:02.477Z,1622566082.477 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T16:48:02.509Z,1622566082.509 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T16:48:02.886Z,1622566082.886 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T16:48:02.886Z,1622566082.886 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2021-06-01T16:51:03.526Z,1622566263.526 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17
2021-06-01T16:51:03.526Z,1622566263.526 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T16:51:03.537Z,1622566263.537 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T16:51:03.943Z,1622566263.943 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T16:51:03.943Z,1622566263.943 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17
2021-06-01T16:51:06.340Z,1622566266.340 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-06-01T16:46:06.1Z
2021-06-01T16:51:06.341Z,1622566266.341 [Default:CheckIn:Read_GPS] Stopped
2021-06-01T16:51:06.341Z,1622566266.341 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-06-01T16:51:16.950Z,1622566276.950 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20210601T155937/Courier0016.lzma
2021-06-01T16:51:17.953Z,1622566277.953 [DataOverHttps](INFO): Moved sent file to Logs/20210601T155937/Courier0016.lzma.bak
2021-06-01T16:51:17.953Z,1622566277.953 [DataOverHttps](INFO): SBD MOMSN=15681431
2021-06-01T16:51:35.943Z,1622566295.943 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20210601T155937/Express0017.lzma
2021-06-01T16:51:36.945Z,1622566296.945 [DataOverHttps](INFO): Moved sent file to Logs/20210601T155937/Express0017.lzma.bak
2021-06-01T16:51:36.945Z,1622566296.945 [DataOverHttps](INFO): SBD MOMSN=15681433
2021-06-01T16:51:39.100Z,1622566299.100 [CommandLine](IMPORTANT): got command strobe off
2021-06-01T16:51:39.100Z,1622566299.100 [CommandLine](IMPORTANT): Deactivating strobe
2021-06-01T16:51:39.541Z,1622566299.541 [Default:CheckIn:Read_Iridium] Stopped
2021-06-01T16:51:39.541Z,1622566299.541 [Default:CheckIn:C.Wait] Running Loop=1
2021-06-01T16:51:39.541Z,1622566299.541 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-06-01T16:53:22.553Z,1622566402.553 [NAL9602](FAULT): GPS failed to acquire within timeout.
2021-06-01T16:53:22.553Z,1622566402.553 [NAL9602] Data Fault, FailCount= 5
2021-06-01T16:53:22.553Z,1622566402.553 [NAL9602](ERROR): Data Fault
2021-06-01T16:53:22.584Z,1622566402.584 [CBIT](ERROR): Data Fault in component: NAL9602
2021-06-01T16:53:22.585Z,1622566402.585 [CBIT](CRITICAL): Data Fault in component: NAL9602
2021-06-01T16:53:22.949Z,1622566402.949 [NAL9602](INFO): Powering down
2021-06-01T16:53:23.010Z,1622566403.010 [CBIT](INFO): Critical error at 20210601T165322
2021-06-01T16:54:04.586Z,1622566444.586 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18
2021-06-01T16:54:04.586Z,1622566444.586 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T16:54:04.597Z,1622566444.597 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T16:54:04.987Z,1622566444.987 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T16:54:05.004Z,1622566445.004 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18
2021-06-01T16:55:22.560Z,1622566522.560 [CBIT](INFO): Clearing failed state for component NAL9602
2021-06-01T16:55:22.560Z,1622566522.560 [NAL9602] No Fault, FailCount= 5
2021-06-01T16:55:22.937Z,1622566522.937 [NAL9602](INFO): Powering up NAL9602
2021-06-01T16:55:33.853Z,1622566533.853 [NAL9602](INFO): NAL9602 initialized
2021-06-01T16:56:40.115Z,1622566600.115 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-06-01T16:56:40.115Z,1622566600.115 [Default:CheckIn:C.Wait] Stopped
2021-06-01T16:56:40.115Z,1622566600.115 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-06-01T16:56:40.115Z,1622566600.115 [Default:CheckIn:D] Running Loop=1
2021-06-01T16:56:40.530Z,1622566600.530 [Default:CheckIn:D] Stopped
2021-06-01T16:56:40.530Z,1622566600.530 [Default:CheckIn:E] Running Loop=1
2021-06-01T16:56:40.935Z,1622566600.935 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 53.002812 min 
2021-06-01T16:56:40.935Z,1622566600.935 [Default:CheckIn:E] Stopped
2021-06-01T16:56:40.935Z,1622566600.935 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-06-01T16:56:40.935Z,1622566600.935 [Default:CheckIn] Stopped
2021-06-01T16:56:40.936Z,1622566600.936 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-06-01T16:56:40.936Z,1622566600.936 [Default:CheckIn](INFO): Running loop #6
2021-06-01T16:56:40.936Z,1622566600.936 [Default:CheckIn] Running Loop=6
2021-06-01T16:56:40.936Z,1622566600.936 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-06-01T16:56:40.936Z,1622566600.936 [Default:CheckIn:Read_GPS] Running Loop=1
2021-06-01T16:57:05.585Z,1622566625.585 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19
2021-06-01T16:57:05.585Z,1622566625.585 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T16:57:05.596Z,1622566625.596 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T16:57:05.993Z,1622566625.993 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T16:57:05.993Z,1622566625.993 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19
2021-06-01T17:00:06.573Z,1622566806.573 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 20
2021-06-01T17:00:06.573Z,1622566806.573 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T17:00:06.604Z,1622566806.604 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T17:00:06.604Z,1622566806.604 [CBIT](FAULT): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T17:01:41.094Z,1622566901.094 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-06-01T16:56:40.9Z
2021-06-01T17:01:41.094Z,1622566901.094 [Default:CheckIn:Read_GPS] Stopped
2021-06-01T17:01:41.094Z,1622566901.094 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-06-01T17:01:48.067Z,1622566908.067 [DataOverHttps](INFO): Sending 82 bytes from file Logs/20210601T155937/Courier0019.lzma
2021-06-01T17:01:49.069Z,1622566909.069 [DataOverHttps](INFO): Moved sent file to Logs/20210601T155937/Courier0019.lzma.bak
2021-06-01T17:01:49.069Z,1622566909.069 [DataOverHttps](INFO): SBD MOMSN=15681438
2021-06-01T17:02:06.043Z,1622566926.043 [DataOverHttps](INFO): Sending 254 bytes from file Logs/20210601T155937/Express0020.lzma
2021-06-01T17:02:07.045Z,1622566927.045 [DataOverHttps](INFO): Moved sent file to Logs/20210601T155937/Express0020.lzma.bak
2021-06-01T17:02:07.045Z,1622566927.045 [DataOverHttps](INFO): SBD MOMSN=15681441
2021-06-01T17:02:09.800Z,1622566929.800 [Default:CheckIn:Read_Iridium] Stopped
2021-06-01T17:02:09.800Z,1622566929.800 [Default:CheckIn:C.Wait] Running Loop=1
2021-06-01T17:02:09.801Z,1622566929.801 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-06-01T17:05:36.213Z,1622567136.213 [NAL9602](FAULT): GPS failed to acquire within timeout.
2021-06-01T17:05:36.213Z,1622567136.213 [NAL9602] Data Fault, FailCount= 1
2021-06-01T17:05:36.213Z,1622567136.213 [NAL9602](ERROR): Data Fault
2021-06-01T17:05:36.233Z,1622567136.233 [CBIT](ERROR): Data Fault in component: NAL9602
2021-06-01T17:05:36.617Z,1622567136.617 [NAL9602](INFO): Powering down
2021-06-01T17:05:37.439Z,1622567137.439 [CBIT](INFO): Clearing failed state for component NAL9602
2021-06-01T17:05:37.439Z,1622567137.439 [NAL9602] No Fault, FailCount= 1
2021-06-01T17:06:06.913Z,1622567166.913 [NAL9602](INFO): Powering up NAL9602
2021-06-01T17:06:17.825Z,1622567177.825 [NAL9602](INFO): NAL9602 initialized
2021-06-01T17:07:10.373Z,1622567230.373 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-06-01T17:07:10.373Z,1622567230.373 [Default:CheckIn:C.Wait] Stopped
2021-06-01T17:07:10.373Z,1622567230.373 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-06-01T17:07:10.373Z,1622567230.373 [Default:CheckIn:D] Running Loop=1
2021-06-01T17:07:10.772Z,1622567230.772 [Default:CheckIn:D] Stopped
2021-06-01T17:07:10.772Z,1622567230.772 [Default:CheckIn:E] Running Loop=1
2021-06-01T17:07:11.181Z,1622567231.181 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 63.506836 min 
2021-06-01T17:07:11.182Z,1622567231.182 [Default:CheckIn:E] Stopped
2021-06-01T17:07:11.182Z,1622567231.182 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-06-01T17:07:11.182Z,1622567231.182 [Default:CheckIn] Stopped
2021-06-01T17:07:11.182Z,1622567231.182 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-06-01T17:07:11.182Z,1622567231.182 [Default:CheckIn](INFO): Running loop #7
2021-06-01T17:07:11.182Z,1622567231.182 [Default:CheckIn] Running Loop=7
2021-06-01T17:07:11.182Z,1622567231.182 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-06-01T17:07:11.182Z,1622567231.182 [Default:CheckIn:Read_GPS] Running Loop=1
2021-06-01T17:10:00.073Z,1622567400.073 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-06-01T17:11:11.193Z,1622567471.193 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-06-01T17:12:11.376Z,1622567531.376 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-06-01T17:07:11.2Z
2021-06-01T17:12:11.377Z,1622567531.377 [Default:CheckIn:Read_GPS] Stopped
2021-06-01T17:12:11.377Z,1622567531.377 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-06-01T17:12:18.995Z,1622567538.995 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20210601T155937/Courier0022.lzma
2021-06-01T17:12:19.997Z,1622567539.997 [DataOverHttps](INFO): Moved sent file to Logs/20210601T155937/Courier0022.lzma.bak
2021-06-01T17:12:19.997Z,1622567539.997 [DataOverHttps](INFO): SBD MOMSN=15681448
2021-06-01T17:12:32.449Z,1622567552.449 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-06-01T17:12:37.079Z,1622567557.079 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20210601T155937/Express0023.lzma
2021-06-01T17:12:38.081Z,1622567558.081 [DataOverHttps](INFO): Moved sent file to Logs/20210601T155937/Express0023.lzma.bak
2021-06-01T17:12:38.081Z,1622567558.081 [DataOverHttps](INFO): SBD MOMSN=15681450
2021-06-01T17:12:40.851Z,1622567560.851 [Default:CheckIn:Read_Iridium] Stopped
2021-06-01T17:12:40.851Z,1622567560.851 [Default:CheckIn:C.Wait] Running Loop=1
2021-06-01T17:12:40.855Z,1622567560.855 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-06-01T17:14:07.345Z,1622567647.345 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-06-01T17:14:20.274Z,1622567660.274 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-06-01T17:14:25.970Z,1622567665.970 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-06-01T17:15:06.746Z,1622567706.746 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T17:15:06.746Z,1622567706.746 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 20
2021-06-01T17:15:41.825Z,1622567741.825 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2021-06-01T17:15:41.828Z,1622567741.828 [BPC1](INFO): Received data from all battery sticks.
2021-06-01T17:16:20.205Z,1622567780.205 [NAL9602](FAULT): GPS failed to acquire within timeout.
2021-06-01T17:16:20.205Z,1622567780.205 [NAL9602] Data Fault, FailCount= 2
2021-06-01T17:16:20.205Z,1622567780.205 [NAL9602](ERROR): Data Fault
2021-06-01T17:16:20.225Z,1622567780.225 [CBIT](ERROR): Data Fault in component: NAL9602
2021-06-01T17:16:20.611Z,1622567780.611 [NAL9602](INFO): Powering down
2021-06-01T17:16:21.435Z,1622567781.435 [CBIT](INFO): Clearing failed state for component NAL9602
2021-06-01T17:16:21.455Z,1622567781.455 [NAL9602] No Fault, FailCount= 2
2021-06-01T17:16:50.905Z,1622567810.905 [NAL9602](INFO): Powering up NAL9602
2021-06-01T17:17:01.817Z,1622567821.817 [NAL9602](INFO): NAL9602 initialized
2021-06-01T17:17:41.438Z,1622567861.438 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-06-01T17:17:41.438Z,1622567861.438 [Default:CheckIn:C.Wait] Stopped
2021-06-01T17:17:41.438Z,1622567861.438 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-06-01T17:17:41.438Z,1622567861.438 [Default:CheckIn:D] Running Loop=1
2021-06-01T17:17:41.849Z,1622567861.849 [Default:CheckIn:D] Stopped
2021-06-01T17:17:41.849Z,1622567861.849 [Default:CheckIn:E] Running Loop=1
2021-06-01T17:17:42.242Z,1622567862.242 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 74.024788 min 
2021-06-01T17:17:42.243Z,1622567862.243 [Default:CheckIn:E] Stopped
2021-06-01T17:17:42.243Z,1622567862.243 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-06-01T17:17:42.243Z,1622567862.243 [Default:CheckIn] Stopped
2021-06-01T17:17:42.243Z,1622567862.243 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-06-01T17:17:42.243Z,1622567862.243 [Default:CheckIn](INFO): Running loop #8
2021-06-01T17:17:42.243Z,1622567862.243 [Default:CheckIn] Running Loop=8
2021-06-01T17:17:42.244Z,1622567862.244 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-06-01T17:17:42.244Z,1622567862.244 [Default:CheckIn:Read_GPS] Running Loop=1
2021-06-01T17:18:07.293Z,1622567887.293 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2021-06-01T17:18:07.293Z,1622567887.293 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T17:18:07.333Z,1622567887.333 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T17:18:07.690Z,1622567887.690 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T17:18:07.691Z,1622567887.691 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2021-06-01T17:21:08.298Z,1622568068.298 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2021-06-01T17:21:08.298Z,1622568068.298 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T17:21:08.309Z,1622568068.309 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T17:21:08.683Z,1622568068.683 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T17:21:08.683Z,1622568068.683 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2021-06-01T17:22:42.400Z,1622568162.400 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-06-01T17:17:42.2Z
2021-06-01T17:22:42.400Z,1622568162.400 [Default:CheckIn:Read_GPS] Stopped
2021-06-01T17:22:42.400Z,1622568162.400 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-06-01T17:22:48.936Z,1622568168.936 [DataOverHttps](INFO): Sending 52 bytes from file Logs/20210601T155937/Courier0025.lzma
2021-06-01T17:22:49.905Z,1622568169.905 [DataOverHttps](INFO): Moved sent file to Logs/20210601T155937/Courier0025.lzma.bak
2021-06-01T17:22:49.905Z,1622568169.905 [DataOverHttps](INFO): SBD MOMSN=15681457
2021-06-01T17:23:06.951Z,1622568186.951 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20210601T155937/Express0026.lzma
2021-06-01T17:23:07.953Z,1622568187.953 [DataOverHttps](INFO): Moved sent file to Logs/20210601T155937/Express0026.lzma.bak
2021-06-01T17:23:07.954Z,1622568187.954 [DataOverHttps](INFO): SBD MOMSN=15681459
2021-06-01T17:23:10.694Z,1622568190.694 [Default:CheckIn:Read_Iridium] Stopped
2021-06-01T17:23:10.694Z,1622568190.694 [Default:CheckIn:C.Wait] Running Loop=1
2021-06-01T17:23:10.694Z,1622568190.694 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-06-01T17:24:09.257Z,1622568249.257 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2021-06-01T17:24:09.257Z,1622568249.257 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T17:24:09.289Z,1622568249.289 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T17:24:09.673Z,1622568249.673 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T17:24:09.673Z,1622568249.673 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2021-06-01T17:27:04.181Z,1622568424.181 [NAL9602](FAULT): GPS failed to acquire within timeout.
2021-06-01T17:27:04.181Z,1622568424.181 [NAL9602] Data Fault, FailCount= 3
2021-06-01T17:27:04.181Z,1622568424.181 [NAL9602](ERROR): Data Fault
2021-06-01T17:27:04.226Z,1622568424.226 [CBIT](ERROR): Data Fault in component: NAL9602
2021-06-01T17:27:04.581Z,1622568424.581 [NAL9602](INFO): Powering down
2021-06-01T17:27:05.420Z,1622568425.420 [CBIT](INFO): Clearing failed state for component NAL9602
2021-06-01T17:27:05.421Z,1622568425.421 [NAL9602] No Fault, FailCount= 3
2021-06-01T17:27:10.258Z,1622568430.258 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2021-06-01T17:27:10.258Z,1622568430.258 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T17:27:10.269Z,1622568430.269 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T17:27:10.657Z,1622568430.657 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T17:27:10.657Z,1622568430.657 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2021-06-01T17:27:34.881Z,1622568454.881 [NAL9602](INFO): Powering up NAL9602
2021-06-01T17:27:45.797Z,1622568465.797 [NAL9602](INFO): NAL9602 initialized
2021-06-01T17:28:11.275Z,1622568491.275 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-06-01T17:28:11.276Z,1622568491.276 [Default:CheckIn:C.Wait] Stopped
2021-06-01T17:28:11.276Z,1622568491.276 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-06-01T17:28:11.276Z,1622568491.276 [Default:CheckIn:D] Running Loop=1
2021-06-01T17:28:11.662Z,1622568491.662 [Default:CheckIn:D] Stopped
2021-06-01T17:28:11.662Z,1622568491.662 [Default:CheckIn:E] Running Loop=1
2021-06-01T17:28:12.070Z,1622568492.070 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 84.521680 min 
2021-06-01T17:28:12.070Z,1622568492.070 [Default:CheckIn:E] Stopped
2021-06-01T17:28:12.071Z,1622568492.071 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-06-01T17:28:12.071Z,1622568492.071 [Default:CheckIn] Stopped
2021-06-01T17:28:12.071Z,1622568492.071 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-06-01T17:28:12.071Z,1622568492.071 [Default:CheckIn](INFO): Running loop #9
2021-06-01T17:28:12.071Z,1622568492.071 [Default:CheckIn] Running Loop=9
2021-06-01T17:28:12.072Z,1622568492.072 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-06-01T17:28:12.072Z,1622568492.072 [Default:CheckIn:Read_GPS] Running Loop=1
2021-06-01T17:30:11.260Z,1622568611.260 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2021-06-01T17:30:11.260Z,1622568611.260 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T17:30:11.271Z,1622568611.271 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T17:30:11.667Z,1622568611.667 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T17:30:11.667Z,1622568611.667 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2021-06-01T17:33:12.234Z,1622568792.234 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2021-06-01T17:33:12.234Z,1622568792.234 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T17:33:12.236Z,1622568792.236 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-06-01T17:28:12.1Z
2021-06-01T17:33:12.236Z,1622568792.236 [Default:CheckIn:Read_GPS] Stopped
2021-06-01T17:33:12.236Z,1622568792.236 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-06-01T17:33:12.276Z,1622568792.276 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T17:33:12.648Z,1622568792.648 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T17:33:12.648Z,1622568792.648 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2021-06-01T17:33:19.009Z,1622568799.009 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20210601T155937/Courier0028.lzma
2021-06-01T17:33:20.009Z,1622568800.009 [DataOverHttps](INFO): Moved sent file to Logs/20210601T155937/Courier0028.lzma.bak
2021-06-01T17:33:20.010Z,1622568800.010 [DataOverHttps](INFO): SBD MOMSN=15681463
2021-06-01T17:33:37.320Z,1622568817.320 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20210601T155937/Express0029.lzma
2021-06-01T17:33:38.113Z,1622568818.113 [DataOverHttps](INFO): Moved sent file to Logs/20210601T155937/Express0029.lzma.bak
2021-06-01T17:33:38.113Z,1622568818.113 [DataOverHttps](INFO): SBD MOMSN=15681465
2021-06-01T17:33:40.939Z,1622568820.939 [Default:CheckIn:Read_Iridium] Stopped
2021-06-01T17:33:40.939Z,1622568820.939 [Default:CheckIn:C.Wait] Running Loop=1
2021-06-01T17:33:40.939Z,1622568820.939 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-06-01T17:36:13.233Z,1622568973.233 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2021-06-01T17:36:13.233Z,1622568973.233 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T17:36:13.244Z,1622568973.244 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T17:36:13.660Z,1622568973.660 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T17:36:13.660Z,1622568973.660 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2021-06-01T17:37:46.945Z,1622569066.945 [NAL9602](FAULT): GPS failed to acquire within timeout.
2021-06-01T17:37:46.945Z,1622569066.945 [NAL9602] Data Fault, FailCount= 4
2021-06-01T17:37:46.945Z,1622569066.945 [NAL9602](ERROR): Data Fault
2021-06-01T17:37:46.964Z,1622569066.964 [CBIT](ERROR): Data Fault in component: NAL9602
2021-06-01T17:37:47.361Z,1622569067.361 [NAL9602](INFO): Powering down
2021-06-01T17:37:48.171Z,1622569068.171 [CBIT](INFO): Clearing failed state for component NAL9602
2021-06-01T17:37:48.172Z,1622569068.172 [NAL9602] No Fault, FailCount= 4
2021-06-01T17:38:17.651Z,1622569097.651 [NAL9602](INFO): Powering up NAL9602
2021-06-01T17:38:28.557Z,1622569108.557 [NAL9602](INFO): NAL9602 initialized
2021-06-01T17:38:41.512Z,1622569121.512 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-06-01T17:38:41.512Z,1622569121.512 [Default:CheckIn:C.Wait] Stopped
2021-06-01T17:38:41.512Z,1622569121.512 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-06-01T17:38:41.512Z,1622569121.512 [Default:CheckIn:D] Running Loop=1
2021-06-01T17:38:41.922Z,1622569121.922 [Default:CheckIn:D] Stopped
2021-06-01T17:38:41.922Z,1622569121.922 [Default:CheckIn:E] Running Loop=1
2021-06-01T17:38:42.306Z,1622569122.306 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 95.026017 min 
2021-06-01T17:38:42.306Z,1622569122.306 [Default:CheckIn:E] Stopped
2021-06-01T17:38:42.307Z,1622569122.307 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-06-01T17:38:42.307Z,1622569122.307 [Default:CheckIn] Stopped
2021-06-01T17:38:42.307Z,1622569122.307 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-06-01T17:38:42.307Z,1622569122.307 [Default:CheckIn](INFO): Running loop #10
2021-06-01T17:38:42.307Z,1622569122.307 [Default:CheckIn] Running Loop=10
2021-06-01T17:38:42.307Z,1622569122.307 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-06-01T17:38:42.308Z,1622569122.308 [Default:CheckIn:Read_GPS] Running Loop=1
2021-06-01T17:39:14.231Z,1622569154.231 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2021-06-01T17:39:14.232Z,1622569154.232 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T17:39:14.263Z,1622569154.263 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T17:39:14.651Z,1622569154.651 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T17:39:14.651Z,1622569154.651 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2021-06-01T17:42:15.220Z,1622569335.220 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2021-06-01T17:42:15.220Z,1622569335.220 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T17:42:15.232Z,1622569335.232 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T17:42:15.644Z,1622569335.644 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T17:42:15.644Z,1622569335.644 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2021-06-01T17:43:42.475Z,1622569422.475 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-06-01T17:38:42.3Z
2021-06-01T17:43:42.475Z,1622569422.475 [Default:CheckIn:Read_GPS] Stopped
2021-06-01T17:43:42.475Z,1622569422.475 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-06-01T17:43:49.455Z,1622569429.455 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20210601T155937/Courier0031.lzma
2021-06-01T17:43:50.457Z,1622569430.457 [DataOverHttps](INFO): Moved sent file to Logs/20210601T155937/Courier0031.lzma.bak
2021-06-01T17:43:50.457Z,1622569430.457 [DataOverHttps](INFO): SBD MOMSN=15681469
2021-06-01T17:44:08.447Z,1622569448.447 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20210601T155937/Express0032.lzma
2021-06-01T17:44:09.449Z,1622569449.449 [DataOverHttps](INFO): Moved sent file to Logs/20210601T155937/Express0032.lzma.bak
2021-06-01T17:44:09.449Z,1622569449.449 [DataOverHttps](INFO): SBD MOMSN=15681471
2021-06-01T17:44:11.997Z,1622569451.997 [Default:CheckIn:Read_Iridium] Stopped
2021-06-01T17:44:11.997Z,1622569451.997 [Default:CheckIn:C.Wait] Running Loop=1
2021-06-01T17:44:11.997Z,1622569451.997 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-06-01T17:45:16.210Z,1622569516.210 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2021-06-01T17:45:16.210Z,1622569516.210 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T17:45:16.221Z,1622569516.221 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T17:45:16.637Z,1622569516.637 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T17:45:16.638Z,1622569516.638 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2021-06-01T17:48:17.205Z,1622569697.205 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2021-06-01T17:48:17.205Z,1622569697.205 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T17:48:17.216Z,1622569697.216 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T17:48:17.630Z,1622569697.630 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T17:48:17.630Z,1622569697.630 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2021-06-01T17:48:30.925Z,1622569710.925 [NAL9602](FAULT): GPS failed to acquire within timeout.
2021-06-01T17:48:30.925Z,1622569710.925 [NAL9602] Data Fault, FailCount= 5
2021-06-01T17:48:30.925Z,1622569710.925 [NAL9602](ERROR): Data Fault
2021-06-01T17:48:30.968Z,1622569710.968 [CBIT](ERROR): Data Fault in component: NAL9602
2021-06-01T17:48:30.969Z,1622569710.969 [CBIT](CRITICAL): Data Fault in component: NAL9602
2021-06-01T17:48:31.325Z,1622569711.325 [NAL9602](INFO): Powering down
2021-06-01T17:48:31.350Z,1622569711.350 [CBIT](INFO): Critical error at 20210601T174830
2021-06-01T17:49:12.553Z,1622569752.553 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-06-01T17:49:12.553Z,1622569752.553 [Default:CheckIn:C.Wait] Stopped
2021-06-01T17:49:12.553Z,1622569752.553 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-06-01T17:49:12.554Z,1622569752.554 [Default:CheckIn:D] Running Loop=1
2021-06-01T17:49:12.963Z,1622569752.963 [Default:CheckIn:D] Stopped
2021-06-01T17:49:12.963Z,1622569752.963 [Default:CheckIn:E] Running Loop=1
2021-06-01T17:49:13.354Z,1622569753.354 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 105.543359 min 
2021-06-01T17:49:13.354Z,1622569753.354 [Default:CheckIn:E] Stopped
2021-06-01T17:49:13.355Z,1622569753.355 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-06-01T17:49:13.355Z,1622569753.355 [Default:CheckIn] Stopped
2021-06-01T17:49:13.355Z,1622569753.355 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-06-01T17:49:13.355Z,1622569753.355 [Default:CheckIn](INFO): Running loop #11
2021-06-01T17:49:13.355Z,1622569753.355 [Default:CheckIn] Running Loop=11
2021-06-01T17:49:13.355Z,1622569753.355 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-06-01T17:49:13.356Z,1622569753.356 [Default:CheckIn:Read_GPS] Running Loop=1
2021-06-01T17:50:31.066Z,1622569831.066 [CBIT](INFO): Clearing failed state for component NAL9602
2021-06-01T17:50:31.066Z,1622569831.066 [NAL9602] No Fault, FailCount= 5
2021-06-01T17:50:31.389Z,1622569831.389 [NAL9602](INFO): Powering up NAL9602
2021-06-01T17:50:42.293Z,1622569842.293 [NAL9602](INFO): NAL9602 initialized
2021-06-01T17:51:18.274Z,1622569878.274 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2021-06-01T17:51:18.274Z,1622569878.274 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T17:51:18.286Z,1622569878.286 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T17:51:18.699Z,1622569878.699 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T17:51:18.699Z,1622569878.699 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2021-06-01T17:54:13.602Z,1622570053.602 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-06-01T17:49:13.4Z
2021-06-01T17:54:13.602Z,1622570053.602 [Default:CheckIn:Read_GPS] Stopped
2021-06-01T17:54:13.602Z,1622570053.602 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-06-01T17:54:19.257Z,1622570059.257 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2021-06-01T17:54:19.257Z,1622570059.257 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T17:54:19.301Z,1622570059.301 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T17:54:19.702Z,1622570059.702 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T17:54:19.702Z,1622570059.702 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2021-06-01T17:54:21.227Z,1622570061.227 [DataOverHttps](INFO): Sending 82 bytes from file Logs/20210601T155937/Courier0034.lzma
2021-06-01T17:54:22.229Z,1622570062.229 [DataOverHttps](INFO): Moved sent file to Logs/20210601T155937/Courier0034.lzma.bak
2021-06-01T17:54:22.229Z,1622570062.229 [DataOverHttps](INFO): SBD MOMSN=15681547
2021-06-01T17:54:39.595Z,1622570079.595 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20210601T155937/Express0035.lzma
2021-06-01T17:54:40.597Z,1622570080.597 [DataOverHttps](INFO): Moved sent file to Logs/20210601T155937/Express0035.lzma.bak
2021-06-01T17:54:40.597Z,1622570080.597 [DataOverHttps](INFO): SBD MOMSN=15681550
2021-06-01T17:54:43.117Z,1622570083.117 [Default:CheckIn:Read_Iridium] Stopped
2021-06-01T17:54:43.117Z,1622570083.117 [Default:CheckIn:C.Wait] Running Loop=1
2021-06-01T17:54:43.118Z,1622570083.118 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-06-01T17:57:20.260Z,1622570240.260 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2021-06-01T17:57:20.260Z,1622570240.260 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T17:57:20.272Z,1622570240.272 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T17:57:20.690Z,1622570240.690 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T17:57:20.690Z,1622570240.690 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2021-06-01T17:59:43.667Z,1622570383.667 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-06-01T17:59:43.667Z,1622570383.667 [Default:CheckIn:C.Wait] Stopped
2021-06-01T17:59:43.667Z,1622570383.667 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-06-01T17:59:43.667Z,1622570383.667 [Default:CheckIn:D] Running Loop=1
2021-06-01T17:59:44.086Z,1622570384.086 [Default:CheckIn:D] Stopped
2021-06-01T17:59:44.086Z,1622570384.086 [Default:CheckIn:E] Running Loop=1
2021-06-01T17:59:44.498Z,1622570384.498 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 116.062077 min 
2021-06-01T17:59:44.498Z,1622570384.498 [Default:CheckIn:E] Stopped
2021-06-01T17:59:44.499Z,1622570384.499 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-06-01T17:59:44.499Z,1622570384.499 [Default:CheckIn] Stopped
2021-06-01T17:59:44.499Z,1622570384.499 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-06-01T17:59:44.499Z,1622570384.499 [Default:CheckIn](INFO): Running loop #12
2021-06-01T17:59:44.499Z,1622570384.499 [Default:CheckIn] Running Loop=12
2021-06-01T17:59:44.499Z,1622570384.499 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-06-01T17:59:44.500Z,1622570384.500 [Default:CheckIn:Read_GPS] Running Loop=1
2021-06-01T18:00:21.268Z,1622570421.268 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2021-06-01T18:00:21.268Z,1622570421.268 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T18:00:21.287Z,1622570421.287 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T18:00:21.695Z,1622570421.695 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T18:00:21.695Z,1622570421.695 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2021-06-01T18:00:43.461Z,1622570443.461 [NAL9602](FAULT): GPS failed to acquire within timeout.
2021-06-01T18:00:43.461Z,1622570443.461 [NAL9602] Data Fault, FailCount= 1
2021-06-01T18:00:43.461Z,1622570443.461 [NAL9602](ERROR): Data Fault
2021-06-01T18:00:43.552Z,1622570443.552 [CBIT](ERROR): Data Fault in component: NAL9602
2021-06-01T18:00:43.873Z,1622570443.873 [NAL9602](INFO): Powering down
2021-06-01T18:00:44.700Z,1622570444.700 [CBIT](INFO): Clearing failed state for component NAL9602
2021-06-01T18:00:44.700Z,1622570444.700 [NAL9602] No Fault, FailCount= 1
2021-06-01T18:01:14.172Z,1622570474.172 [NAL9602](INFO): Powering up NAL9602
2021-06-01T18:01:25.073Z,1622570485.073 [NAL9602](INFO): NAL9602 initialized
2021-06-01T18:03:22.264Z,1622570602.264 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2021-06-01T18:03:22.264Z,1622570602.264 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T18:03:22.275Z,1622570602.275 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T18:03:22.679Z,1622570602.679 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T18:03:22.679Z,1622570602.679 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2021-06-01T18:04:44.660Z,1622570684.660 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-06-01T17:59:44.5Z
2021-06-01T18:04:44.660Z,1622570684.660 [Default:CheckIn:Read_GPS] Stopped
2021-06-01T18:04:44.660Z,1622570684.660 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-06-01T18:04:51.895Z,1622570691.895 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20210601T155937/Courier0037.lzma
2021-06-01T18:04:52.897Z,1622570692.897 [DataOverHttps](INFO): Moved sent file to Logs/20210601T155937/Courier0037.lzma.bak
2021-06-01T18:04:52.897Z,1622570692.897 [DataOverHttps](INFO): SBD MOMSN=15681561
2021-06-01T18:05:09.959Z,1622570709.959 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20210601T155937/Express0038.lzma
2021-06-01T18:05:10.961Z,1622570710.961 [DataOverHttps](INFO): Moved sent file to Logs/20210601T155937/Express0038.lzma.bak
2021-06-01T18:05:10.961Z,1622570710.961 [DataOverHttps](INFO): SBD MOMSN=15681563
2021-06-01T18:05:13.767Z,1622570713.767 [Default:CheckIn:Read_Iridium] Stopped
2021-06-01T18:05:13.767Z,1622570713.767 [Default:CheckIn:C.Wait] Running Loop=1
2021-06-01T18:05:13.771Z,1622570713.771 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-06-01T18:06:23.234Z,1622570783.234 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17
2021-06-01T18:06:23.234Z,1622570783.234 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T18:06:23.245Z,1622570783.245 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T18:06:23.654Z,1622570783.654 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T18:06:23.655Z,1622570783.655 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17
2021-06-01T18:09:24.238Z,1622570964.238 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18
2021-06-01T18:09:24.238Z,1622570964.238 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T18:09:24.250Z,1622570964.250 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T18:09:24.659Z,1622570964.659 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T18:09:24.659Z,1622570964.659 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18
2021-06-01T18:10:14.322Z,1622571014.322 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-06-01T18:10:14.322Z,1622571014.322 [Default:CheckIn:C.Wait] Stopped
2021-06-01T18:10:14.322Z,1622571014.322 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-06-01T18:10:14.322Z,1622571014.322 [Default:CheckIn:D] Running Loop=1
2021-06-01T18:10:14.754Z,1622571014.754 [Default:CheckIn:D] Stopped
2021-06-01T18:10:14.754Z,1622571014.754 [Default:CheckIn:E] Running Loop=1
2021-06-01T18:10:15.171Z,1622571015.171 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 126.573210 min 
2021-06-01T18:10:15.189Z,1622571015.189 [Default:CheckIn:E] Stopped
2021-06-01T18:10:15.189Z,1622571015.189 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-06-01T18:10:15.189Z,1622571015.189 [Default:CheckIn] Stopped
2021-06-01T18:10:15.189Z,1622571015.189 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-06-01T18:10:15.190Z,1622571015.190 [Default:CheckIn](INFO): Running loop #13
2021-06-01T18:10:15.190Z,1622571015.190 [Default:CheckIn] Running Loop=13
2021-06-01T18:10:15.190Z,1622571015.190 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-06-01T18:10:15.190Z,1622571015.190 [Default:CheckIn:Read_GPS] Running Loop=1
2021-06-01T18:11:27.445Z,1622571087.445 [NAL9602](FAULT): GPS failed to acquire within timeout.
2021-06-01T18:11:27.445Z,1622571087.445 [NAL9602] Data Fault, FailCount= 2
2021-06-01T18:11:27.445Z,1622571087.445 [NAL9602](ERROR): Data Fault
2021-06-01T18:11:27.494Z,1622571087.494 [CBIT](ERROR): Data Fault in component: NAL9602
2021-06-01T18:11:27.849Z,1622571087.849 [NAL9602](INFO): Powering down
2021-06-01T18:11:28.739Z,1622571088.739 [CBIT](INFO): Clearing failed state for component NAL9602
2021-06-01T18:11:28.739Z,1622571088.739 [NAL9602] No Fault, FailCount= 2
2021-06-01T18:11:58.145Z,1622571118.145 [NAL9602](INFO): Powering up NAL9602
2021-06-01T18:12:09.053Z,1622571129.053 [NAL9602](INFO): NAL9602 initialized
2021-06-01T18:12:25.245Z,1622571145.245 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19
2021-06-01T18:12:25.245Z,1622571145.245 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T18:12:25.276Z,1622571145.276 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T18:12:25.646Z,1622571145.646 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T18:12:25.646Z,1622571145.646 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19
2021-06-01T18:15:15.307Z,1622571315.307 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-06-01T18:10:15.2Z
2021-06-01T18:15:15.307Z,1622571315.307 [Default:CheckIn:Read_GPS] Stopped
2021-06-01T18:15:15.307Z,1622571315.307 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-06-01T18:15:22.727Z,1622571322.727 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20210601T155937/Courier0040.lzma
2021-06-01T18:15:23.729Z,1622571323.729 [DataOverHttps](INFO): Moved sent file to Logs/20210601T155937/Courier0040.lzma.bak
2021-06-01T18:15:23.729Z,1622571323.729 [DataOverHttps](INFO): SBD MOMSN=15681574
2021-06-01T18:15:26.240Z,1622571326.240 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 20
2021-06-01T18:15:26.240Z,1622571326.240 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T18:15:26.251Z,1622571326.251 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T18:15:26.252Z,1622571326.252 [CBIT](FAULT): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T18:15:40.748Z,1622571340.748 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20210601T155937/Express0041.lzma
2021-06-01T18:15:41.745Z,1622571341.745 [DataOverHttps](INFO): Moved sent file to Logs/20210601T155937/Express0041.lzma.bak
2021-06-01T18:15:41.745Z,1622571341.745 [DataOverHttps](INFO): SBD MOMSN=15681576
2021-06-01T18:15:44.428Z,1622571344.428 [Default:CheckIn:Read_Iridium] Stopped
2021-06-01T18:15:44.428Z,1622571344.428 [Default:CheckIn:C.Wait] Running Loop=1
2021-06-01T18:15:44.428Z,1622571344.428 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-06-01T18:17:36.764Z,1622571456.764 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-06-01T18:18:13.921Z,1622571493.921 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-06-01T18:19:53.718Z,1622571593.718 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-06-01T18:20:45.013Z,1622571645.013 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-06-01T18:20:45.013Z,1622571645.013 [Default:CheckIn:C.Wait] Stopped
2021-06-01T18:20:45.013Z,1622571645.013 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-06-01T18:20:45.013Z,1622571645.013 [Default:CheckIn:D] Running Loop=1
2021-06-01T18:20:45.421Z,1622571645.421 [Default:CheckIn:D] Stopped
2021-06-01T18:20:45.421Z,1622571645.421 [Default:CheckIn:E] Running Loop=1
2021-06-01T18:20:45.814Z,1622571645.814 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 137.084326 min 
2021-06-01T18:20:45.814Z,1622571645.814 [Default:CheckIn:E] Stopped
2021-06-01T18:20:45.814Z,1622571645.814 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-06-01T18:20:45.814Z,1622571645.814 [Default:CheckIn] Stopped
2021-06-01T18:20:45.815Z,1622571645.815 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-06-01T18:20:45.815Z,1622571645.815 [Default:CheckIn](INFO): Running loop #14
2021-06-01T18:20:45.815Z,1622571645.815 [Default:CheckIn] Running Loop=14
2021-06-01T18:20:45.815Z,1622571645.815 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-06-01T18:20:45.815Z,1622571645.815 [Default:CheckIn:Read_GPS] Running Loop=1
2021-06-01T18:21:44.363Z,1622571704.363 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2021-06-01T18:22:11.496Z,1622571731.496 [NAL9602](FAULT): GPS failed to acquire within timeout.
2021-06-01T18:22:11.496Z,1622571731.496 [NAL9602] Data Fault, FailCount= 3
2021-06-01T18:22:11.496Z,1622571731.496 [NAL9602](ERROR): Data Fault
2021-06-01T18:22:11.536Z,1622571731.536 [CBIT](ERROR): Data Fault in component: NAL9602
2021-06-01T18:22:11.849Z,1622571731.849 [NAL9602](INFO): Powering down
2021-06-01T18:22:12.685Z,1622571732.685 [CBIT](INFO): Clearing failed state for component NAL9602
2021-06-01T18:22:12.686Z,1622571732.686 [NAL9602] No Fault, FailCount= 3
2021-06-01T18:22:42.141Z,1622571762.141 [NAL9602](INFO): Powering up NAL9602
2021-06-01T18:22:44.618Z,1622571764.618 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-06-01T18:22:53.057Z,1622571773.057 [NAL9602](INFO): NAL9602 initialized
2021-06-01T18:23:05.634Z,1622571785.634 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-06-01T18:23:39.520Z,1622571819.520 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2021-06-01T18:23:39.523Z,1622571819.523 [BPC1](INFO): Received data from all battery sticks.
2021-06-01T18:25:45.970Z,1622571945.970 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-06-01T18:20:45.8Z
2021-06-01T18:25:45.970Z,1622571945.970 [Default:CheckIn:Read_GPS] Stopped
2021-06-01T18:25:45.970Z,1622571945.970 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-06-01T18:25:52.787Z,1622571952.787 [DataOverHttps](INFO): Sending 50 bytes from file Logs/20210601T155937/Courier0043.lzma
2021-06-01T18:25:53.790Z,1622571953.790 [DataOverHttps](INFO): Moved sent file to Logs/20210601T155937/Courier0043.lzma.bak
2021-06-01T18:25:53.790Z,1622571953.790 [DataOverHttps](INFO): SBD MOMSN=15681586
2021-06-01T18:26:11.543Z,1622571971.543 [DataOverHttps](INFO): Sending 214 bytes from file Logs/20210601T155937/Express0044.lzma
2021-06-01T18:26:12.545Z,1622571972.545 [DataOverHttps](INFO): Moved sent file to Logs/20210601T155937/Express0044.lzma.bak
2021-06-01T18:26:12.545Z,1622571972.545 [DataOverHttps](INFO): SBD MOMSN=15681588
2021-06-01T18:26:15.124Z,1622571975.124 [Default:CheckIn:Read_Iridium] Stopped
2021-06-01T18:26:15.124Z,1622571975.124 [Default:CheckIn:C.Wait] Running Loop=1
2021-06-01T18:26:15.124Z,1622571975.124 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-06-01T18:30:26.544Z,1622572226.544 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T18:30:26.545Z,1622572226.545 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 20
2021-06-01T18:31:15.678Z,1622572275.678 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-06-01T18:31:15.678Z,1622572275.678 [Default:CheckIn:C.Wait] Stopped
2021-06-01T18:31:15.679Z,1622572275.679 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-06-01T18:31:15.679Z,1622572275.679 [Default:CheckIn:D] Running Loop=1
2021-06-01T18:31:16.078Z,1622572276.078 [Default:CheckIn:D] Stopped
2021-06-01T18:31:16.078Z,1622572276.078 [Default:CheckIn:E] Running Loop=1
2021-06-01T18:31:16.491Z,1622572276.491 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 147.595280 min 
2021-06-01T18:31:16.491Z,1622572276.491 [Default:CheckIn:E] Stopped
2021-06-01T18:31:16.491Z,1622572276.491 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-06-01T18:31:16.491Z,1622572276.491 [Default:CheckIn] Stopped
2021-06-01T18:31:16.492Z,1622572276.492 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-06-01T18:31:16.492Z,1622572276.492 [Default:CheckIn](INFO): Running loop #15
2021-06-01T18:31:16.492Z,1622572276.492 [Default:CheckIn] Running Loop=15
2021-06-01T18:31:16.492Z,1622572276.492 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-06-01T18:31:16.492Z,1622572276.492 [Default:CheckIn:Read_GPS] Running Loop=1
2021-06-01T18:32:55.461Z,1622572375.461 [NAL9602](FAULT): GPS failed to acquire within timeout.
2021-06-01T18:32:55.461Z,1622572375.461 [NAL9602] Data Fault, FailCount= 4
2021-06-01T18:32:55.461Z,1622572375.461 [NAL9602](ERROR): Data Fault
2021-06-01T18:32:55.481Z,1622572375.481 [CBIT](ERROR): Data Fault in component: NAL9602
2021-06-01T18:32:55.868Z,1622572375.868 [NAL9602](INFO): Powering down
2021-06-01T18:32:56.708Z,1622572376.708 [CBIT](INFO): Clearing failed state for component NAL9602
2021-06-01T18:32:56.708Z,1622572376.708 [NAL9602] No Fault, FailCount= 4
2021-06-01T18:33:26.165Z,1622572406.165 [NAL9602](INFO): Powering up NAL9602
2021-06-01T18:33:26.977Z,1622572406.977 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2021-06-01T18:33:26.977Z,1622572406.977 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T18:33:26.988Z,1622572406.988 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T18:33:27.402Z,1622572407.402 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T18:33:27.403Z,1622572407.403 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2021-06-01T18:33:37.073Z,1622572417.073 [NAL9602](INFO): NAL9602 initialized
2021-06-01T18:36:16.725Z,1622572576.725 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-06-01T18:31:16.5Z
2021-06-01T18:36:16.725Z,1622572576.725 [Default:CheckIn:Read_GPS] Stopped
2021-06-01T18:36:16.725Z,1622572576.725 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-06-01T18:36:28.149Z,1622572588.149 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2021-06-01T18:36:28.149Z,1622572588.149 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T18:36:28.166Z,1622572588.166 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T18:36:28.243Z,1622572588.243 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20210601T155937/Courier0046.lzma
2021-06-01T18:36:28.588Z,1622572588.588 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T18:36:28.588Z,1622572588.588 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2021-06-01T18:36:29.245Z,1622572589.245 [DataOverHttps](INFO): Moved sent file to Logs/20210601T155937/Courier0046.lzma.bak
2021-06-01T18:36:29.245Z,1622572589.245 [DataOverHttps](INFO): SBD MOMSN=15681599
2021-06-01T18:36:51.103Z,1622572611.103 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20210601T155937/Express0047.lzma
2021-06-01T18:36:52.105Z,1622572612.105 [DataOverHttps](INFO): Moved sent file to Logs/20210601T155937/Express0047.lzma.bak
2021-06-01T18:36:52.105Z,1622572612.105 [DataOverHttps](INFO): SBD MOMSN=15681601
2021-06-01T18:36:54.890Z,1622572614.890 [Default:CheckIn:Read_Iridium] Stopped
2021-06-01T18:36:54.890Z,1622572614.890 [Default:CheckIn:C.Wait] Running Loop=1
2021-06-01T18:36:54.890Z,1622572614.890 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-06-01T18:39:29.209Z,1622572769.209 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2021-06-01T18:39:29.209Z,1622572769.209 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T18:39:29.245Z,1622572769.245 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T18:39:29.627Z,1622572769.627 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T18:39:29.627Z,1622572769.627 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2021-06-01T18:41:55.439Z,1622572915.439 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-06-01T18:41:55.439Z,1622572915.439 [Default:CheckIn:C.Wait] Stopped
2021-06-01T18:41:55.439Z,1622572915.439 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-06-01T18:41:55.439Z,1622572915.439 [Default:CheckIn:D] Running Loop=1
2021-06-01T18:41:55.869Z,1622572915.869 [Default:CheckIn:D] Stopped
2021-06-01T18:41:55.869Z,1622572915.869 [Default:CheckIn:E] Running Loop=1
2021-06-01T18:41:56.264Z,1622572916.264 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 158.258464 min 
2021-06-01T18:41:56.264Z,1622572916.264 [Default:CheckIn:E] Stopped
2021-06-01T18:41:56.264Z,1622572916.264 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-06-01T18:41:56.265Z,1622572916.265 [Default:CheckIn] Stopped
2021-06-01T18:41:56.265Z,1622572916.265 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-06-01T18:41:56.265Z,1622572916.265 [Default:CheckIn](INFO): Running loop #16
2021-06-01T18:41:56.265Z,1622572916.265 [Default:CheckIn] Running Loop=16
2021-06-01T18:41:56.265Z,1622572916.265 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-06-01T18:41:56.265Z,1622572916.265 [Default:CheckIn:Read_GPS] Running Loop=1
2021-06-01T18:42:30.201Z,1622572950.201 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2021-06-01T18:42:30.201Z,1622572950.201 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T18:42:30.223Z,1622572950.223 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T18:42:30.644Z,1622572950.644 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T18:42:30.644Z,1622572950.644 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2021-06-01T18:43:38.049Z,1622573018.049 [NAL9602](FAULT): GPS failed to acquire within timeout.
2021-06-01T18:43:38.050Z,1622573018.050 [NAL9602] Data Fault, FailCount= 5
2021-06-01T18:43:38.050Z,1622573018.050 [NAL9602](ERROR): Data Fault
2021-06-01T18:43:38.069Z,1622573018.069 [CBIT](ERROR): Data Fault in component: NAL9602
2021-06-01T18:43:38.070Z,1622573018.070 [CBIT](CRITICAL): Data Fault in component: NAL9602
2021-06-01T18:43:38.456Z,1622573018.456 [NAL9602](INFO): Powering down
2021-06-01T18:43:38.475Z,1622573018.475 [CBIT](INFO): Critical error at 20210601T184338
2021-06-01T18:45:31.185Z,1622573131.185 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2021-06-01T18:45:31.185Z,1622573131.185 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T18:45:31.196Z,1622573131.196 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T18:45:31.613Z,1622573131.613 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T18:45:31.614Z,1622573131.614 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2021-06-01T18:45:38.053Z,1622573138.053 [CBIT](INFO): Clearing failed state for component NAL9602
2021-06-01T18:45:38.053Z,1622573138.053 [NAL9602] No Fault, FailCount= 5
2021-06-01T18:45:38.437Z,1622573138.437 [NAL9602](INFO): Powering up NAL9602
2021-06-01T18:45:49.349Z,1622573149.349 [NAL9602](INFO): NAL9602 initialized
2021-06-01T18:46:56.453Z,1622573216.453 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-06-01T18:41:56.3Z
2021-06-01T18:46:56.453Z,1622573216.453 [Default:CheckIn:Read_GPS] Stopped
2021-06-01T18:46:56.454Z,1622573216.454 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-06-01T18:47:03.655Z,1622573223.655 [DataOverHttps](INFO): Sending 82 bytes from file Logs/20210601T155937/Courier0049.lzma
2021-06-01T18:47:04.657Z,1622573224.657 [DataOverHttps](INFO): Moved sent file to Logs/20210601T155937/Courier0049.lzma.bak
2021-06-01T18:47:04.657Z,1622573224.657 [DataOverHttps](INFO): SBD MOMSN=15681614
2021-06-01T18:47:21.711Z,1622573241.711 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20210601T155937/Express0050.lzma
2021-06-01T18:47:22.737Z,1622573242.737 [DataOverHttps](INFO): Moved sent file to Logs/20210601T155937/Express0050.lzma.bak
2021-06-01T18:47:22.737Z,1622573242.737 [DataOverHttps](INFO): SBD MOMSN=15681617
2021-06-01T18:47:25.545Z,1622573245.545 [Default:CheckIn:Read_Iridium] Stopped
2021-06-01T18:47:25.546Z,1622573245.546 [Default:CheckIn:C.Wait] Running Loop=1
2021-06-01T18:47:25.546Z,1622573245.546 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-06-01T18:48:32.188Z,1622573312.188 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2021-06-01T18:48:32.188Z,1622573312.188 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T18:48:32.199Z,1622573312.199 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T18:48:32.599Z,1622573312.599 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T18:48:32.600Z,1622573312.600 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2021-06-01T18:48:35.775Z,1622573315.775 [CommandLine](IMPORTANT): got command failComponent
2021-06-01T18:48:35.775Z,1622573315.775 [CommandLine](IMPORTANT): Failed components:
2021-06-01T18:48:35.776Z,1622573315.776 [CommandLine](IMPORTANT): No failed Components.
2021-06-01T18:48:46.840Z,1622573326.840 [CommandLine](IMPORTANT): got command get DropWeight.loadAtStartup
2021-06-01T18:48:46.840Z,1622573326.840 [CommandLine](IMPORTANT): DropWeight.loadAtStartup 1 bool
2021-06-01T18:48:48.599Z,1622573328.599 [CommandLine](IMPORTANT): got command failComponent
2021-06-01T18:48:48.599Z,1622573328.599 [CommandLine](IMPORTANT): Failed components:
2021-06-01T18:48:48.599Z,1622573328.599 [CommandLine](IMPORTANT): No failed Components.
2021-06-01T18:49:23.908Z,1622573363.908 [CommandLine](IMPORTANT): got command report
2021-06-01T18:49:28.231Z,1622573368.231 [CommandLine](IMPORTANT): got command report mod platform_buoyancy_position
2021-06-01T18:49:28.368Z,1622573368.368 [Reporter](INFO): platform_buoyancy_position 945.864868 cc
2021-06-01T18:51:33.173Z,1622573493.173 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2021-06-01T18:51:33.173Z,1622573493.173 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T18:51:33.184Z,1622573493.184 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T18:51:33.591Z,1622573493.591 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T18:51:33.591Z,1622573493.591 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2021-06-01T18:52:26.102Z,1622573546.102 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-06-01T18:52:26.102Z,1622573546.102 [Default:CheckIn:C.Wait] Stopped
2021-06-01T18:52:26.102Z,1622573546.102 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-06-01T18:52:26.102Z,1622573546.102 [Default:CheckIn:D] Running Loop=1
2021-06-01T18:52:26.564Z,1622573546.564 [Default:CheckIn:D] Stopped
2021-06-01T18:52:26.564Z,1622573546.564 [Default:CheckIn:E] Running Loop=1
2021-06-01T18:52:26.898Z,1622573546.898 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 168.770036 min 
2021-06-01T18:52:26.899Z,1622573546.899 [Default:CheckIn:E] Stopped
2021-06-01T18:52:26.899Z,1622573546.899 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-06-01T18:52:26.919Z,1622573546.919 [Default:CheckIn] Stopped
2021-06-01T18:52:26.919Z,1622573546.919 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-06-01T18:52:26.919Z,1622573546.919 [Default:CheckIn](INFO): Running loop #17
2021-06-01T18:52:26.920Z,1622573546.920 [Default:CheckIn] Running Loop=17
2021-06-01T18:52:26.920Z,1622573546.920 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-06-01T18:52:26.920Z,1622573546.920 [Default:CheckIn:Read_GPS] Running Loop=1
2021-06-01T18:52:55.979Z,1622573575.979 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185255.00,A,3648.16139,N,12147.27741,W,0.136,193.63,010621,,,A*7F
2021-06-01T18:52:55.982Z,1622573575.982 [NAL9602](INFO): GPS fix at 20210601T185255: (36.802690, -121.787957)
2021-06-01T18:52:55.995Z,1622573575.995 [Default:CheckIn:Read_GPS] Stopped
2021-06-01T18:52:55.995Z,1622573575.995 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-06-01T18:52:56.419Z,1622573576.419 [DeadReckonUsingMultipleVelocitySources](INFO): Failure count cleared after critical for DeadReckonUsingMultipleVelocitySources
2021-06-01T18:53:06.015Z,1622573586.015 [DataOverHttps](INFO): Sending 222 bytes from file Logs/20210601T155937/Courier0052.lzma
2021-06-01T18:53:07.017Z,1622573587.017 [DataOverHttps](INFO): Moved sent file to Logs/20210601T155937/Courier0052.lzma.bak
2021-06-01T18:53:07.018Z,1622573587.018 [DataOverHttps](INFO): SBD MOMSN=15681622
2021-06-01T18:53:25.067Z,1622573605.067 [DataOverHttps](INFO): Sending 407 bytes from file Logs/20210601T155937/Express0053.lzma
2021-06-01T18:53:26.069Z,1622573606.069 [DataOverHttps](INFO): Moved sent file to Logs/20210601T155937/Express0053.lzma.bak
2021-06-01T18:53:26.069Z,1622573606.069 [DataOverHttps](INFO): SBD MOMSN=15681625
2021-06-01T18:53:28.336Z,1622573608.336 [NAL9602](INFO): Not Powering down - fast GPS
2021-06-01T18:53:28.795Z,1622573608.795 [Default:CheckIn:Read_Iridium] Stopped
2021-06-01T18:53:28.796Z,1622573608.796 [Default:CheckIn:C.Wait] Running Loop=1
2021-06-01T18:53:28.796Z,1622573608.796 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-06-01T18:54:47.331Z,1622573687.331 [CommandLine](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 500.000000 cubic_centimeter
2021-06-01T18:54:47.355Z,1622573687.355 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.buoyancyAction" handled in the control thread.
2021-06-01T18:54:47.587Z,1622573687.587 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,AMEcho,DAT,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.buoyancyAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2021-06-01T18:54:53.694Z,1622573693.694 [Reporter](INFO): platform_buoyancy_position 945.730591 cc
2021-06-01T18:54:54.042Z,1622573694.042 [Reporter](INFO): platform_buoyancy_position 945.864868 cc
2021-06-01T18:54:54.454Z,1622573694.454 [Reporter](INFO): platform_buoyancy_position 943.716064 cc
2021-06-01T18:54:54.869Z,1622573694.869 [Reporter](INFO): platform_buoyancy_position 940.761475 cc
2021-06-01T18:54:55.274Z,1622573695.274 [Reporter](INFO): platform_buoyancy_position 937.269653 cc
2021-06-01T18:54:55.661Z,1622573695.661 [Reporter](INFO): platform_buoyancy_position 930.823242 cc
2021-06-01T18:54:56.080Z,1622573696.080 [Reporter](INFO): platform_buoyancy_position 926.525635 cc
2021-06-01T18:54:56.496Z,1622573696.496 [Reporter](INFO): platform_buoyancy_position 922.093750 cc
2021-06-01T18:54:56.882Z,1622573696.882 [Reporter](INFO): platform_buoyancy_position 915.647339 cc
2021-06-01T18:54:57.270Z,1622573697.270 [Reporter](INFO): platform_buoyancy_position 911.484070 cc
2021-06-01T18:54:57.686Z,1622573697.686 [Reporter](INFO): platform_buoyancy_position 905.037659 cc
2021-06-01T18:54:58.101Z,1622573698.101 [Reporter](INFO): platform_buoyancy_position 900.605774 cc
2021-06-01T18:54:58.514Z,1622573698.514 [Reporter](INFO): platform_buoyancy_position 894.293640 cc
2021-06-01T18:54:58.886Z,1622573698.886 [Reporter](INFO): platform_buoyancy_position 889.861755 cc
2021-06-01T18:54:59.308Z,1622573699.308 [Reporter](INFO): platform_buoyancy_position 885.026917 cc
2021-06-01T18:54:59.722Z,1622573699.722 [Reporter](INFO): platform_buoyancy_position 879.117737 cc
2021-06-01T18:55:00.101Z,1622573700.101 [Reporter](INFO): platform_buoyancy_position 874.820129 cc
2021-06-01T18:55:00.514Z,1622573700.514 [Reporter](INFO): platform_buoyancy_position 868.508057 cc
2021-06-01T18:55:00.926Z,1622573700.926 [Reporter](INFO): platform_buoyancy_position 864.076172 cc
2021-06-01T18:55:01.321Z,1622573701.321 [Reporter](INFO): platform_buoyancy_position 859.778564 cc
2021-06-01T18:55:01.714Z,1622573701.714 [Reporter](INFO): platform_buoyancy_position 853.466431 cc
2021-06-01T18:55:02.130Z,1622573702.130 [Reporter](INFO): platform_buoyancy_position 848.900269 cc
2021-06-01T18:55:02.546Z,1622573702.546 [Reporter](INFO): platform_buoyancy_position 842.588135 cc
2021-06-01T18:55:02.945Z,1622573702.945 [Reporter](INFO): platform_buoyancy_position 836.678955 cc
2021-06-01T18:55:03.334Z,1622573703.334 [Reporter](INFO): platform_buoyancy_position 831.978455 cc
2021-06-01T18:55:03.750Z,1622573703.750 [Reporter](INFO): platform_buoyancy_position 827.412231 cc
2021-06-01T18:55:04.161Z,1622573704.161 [Reporter](INFO): platform_buoyancy_position 821.234436 cc
2021-06-01T18:55:04.557Z,1622573704.557 [Reporter](INFO): platform_buoyancy_position 816.399658 cc
2021-06-01T18:55:04.950Z,1622573704.950 [Reporter](INFO): platform_buoyancy_position 810.356140 cc
2021-06-01T18:55:05.362Z,1622573705.362 [Reporter](INFO): platform_buoyancy_position 806.058533 cc
2021-06-01T18:55:05.784Z,1622573705.784 [Reporter](INFO): platform_buoyancy_position 799.612122 cc
2021-06-01T18:55:06.154Z,1622573706.154 [Reporter](INFO): platform_buoyancy_position 795.314514 cc
2021-06-01T18:55:06.574Z,1622573706.574 [Reporter](INFO): platform_buoyancy_position 788.868164 cc
2021-06-01T18:55:06.989Z,1622573706.989 [Reporter](INFO): platform_buoyancy_position 784.570557 cc
2021-06-01T18:55:07.382Z,1622573707.382 [Reporter](INFO): platform_buoyancy_position 778.124146 cc
2021-06-01T18:55:07.781Z,1622573707.781 [Reporter](INFO): platform_buoyancy_position 773.960815 cc
2021-06-01T18:55:08.205Z,1622573708.205 [Reporter](INFO): platform_buoyancy_position 767.514465 cc
2021-06-01T18:55:08.601Z,1622573708.601 [Reporter](INFO): platform_buoyancy_position 763.082520 cc
2021-06-01T18:55:08.990Z,1622573708.990 [Reporter](INFO): platform_buoyancy_position 756.770447 cc
2021-06-01T18:55:09.406Z,1622573709.406 [Reporter](INFO): platform_buoyancy_position 752.472839 cc
2021-06-01T18:55:09.818Z,1622573709.818 [Reporter](INFO): platform_buoyancy_position 747.503723 cc
2021-06-01T18:55:10.218Z,1622573710.218 [Reporter](INFO): platform_buoyancy_position 741.728821 cc
2021-06-01T18:55:10.606Z,1622573710.606 [Reporter](INFO): platform_buoyancy_position 737.296936 cc
2021-06-01T18:55:11.025Z,1622573711.025 [Reporter](INFO): platform_buoyancy_position 730.984863 cc
2021-06-01T18:55:11.445Z,1622573711.445 [Reporter](INFO): platform_buoyancy_position 726.418640 cc
2021-06-01T18:55:11.876Z,1622573711.876 [Reporter](INFO): platform_buoyancy_position 720.240845 cc
2021-06-01T18:55:12.270Z,1622573712.270 [Reporter](INFO): platform_buoyancy_position 713.794434 cc
2021-06-01T18:55:12.650Z,1622573712.650 [Reporter](INFO): platform_buoyancy_position 709.362549 cc
2021-06-01T18:55:13.077Z,1622573713.077 [Reporter](INFO): platform_buoyancy_position 704.662048 cc
2021-06-01T18:55:13.478Z,1622573713.478 [Reporter](INFO): platform_buoyancy_position 698.752808 cc
2021-06-01T18:55:13.853Z,1622573713.853 [Reporter](INFO): platform_buoyancy_position 694.455261 cc
2021-06-01T18:55:14.312Z,1622573714.312 [Reporter](INFO): platform_buoyancy_position 687.874512 cc
2021-06-01T18:55:14.685Z,1622573714.685 [Reporter](INFO): platform_buoyancy_position 683.576904 cc
2021-06-01T18:55:15.061Z,1622573715.061 [Reporter](INFO): platform_buoyancy_position 677.264832 cc
2021-06-01T18:55:15.478Z,1622573715.478 [Reporter](INFO): platform_buoyancy_position 672.967224 cc
2021-06-01T18:55:15.870Z,1622573715.870 [Reporter](INFO): platform_buoyancy_position 667.863831 cc
2021-06-01T18:55:16.258Z,1622573716.258 [Reporter](INFO): platform_buoyancy_position 662.088928 cc
2021-06-01T18:55:16.686Z,1622573716.686 [Reporter](INFO): platform_buoyancy_position 657.522705 cc
2021-06-01T18:55:17.145Z,1622573717.145 [Reporter](INFO): platform_buoyancy_position 651.344910 cc
2021-06-01T18:55:17.490Z,1622573717.490 [Reporter](INFO): platform_buoyancy_position 646.241516 cc
2021-06-01T18:55:17.901Z,1622573717.901 [Reporter](INFO): platform_buoyancy_position 640.735229 cc
2021-06-01T18:55:18.289Z,1622573718.289 [Reporter](INFO): platform_buoyancy_position 636.169006 cc
2021-06-01T18:55:18.684Z,1622573718.684 [Reporter](INFO): platform_buoyancy_position 629.991211 cc
2021-06-01T18:55:19.100Z,1622573719.100 [Reporter](INFO): platform_buoyancy_position 625.559326 cc
2021-06-01T18:55:19.520Z,1622573719.520 [Reporter](INFO): platform_buoyancy_position 619.247253 cc
2021-06-01T18:55:19.909Z,1622573719.909 [Reporter](INFO): platform_buoyancy_position 614.815308 cc
2021-06-01T18:55:20.300Z,1622573720.300 [Reporter](INFO): platform_buoyancy_position 608.503235 cc
2021-06-01T18:55:20.720Z,1622573720.720 [Reporter](INFO): platform_buoyancy_position 604.071350 cc
2021-06-01T18:55:21.133Z,1622573721.133 [Reporter](INFO): platform_buoyancy_position 597.759216 cc
2021-06-01T18:55:21.543Z,1622573721.543 [Reporter](INFO): platform_buoyancy_position 593.327332 cc
2021-06-01T18:55:21.914Z,1622573721.914 [Reporter](INFO): platform_buoyancy_position 589.029724 cc
2021-06-01T18:55:22.338Z,1622573722.338 [Reporter](INFO): platform_buoyancy_position 582.583313 cc
2021-06-01T18:55:22.763Z,1622573722.763 [Reporter](INFO): platform_buoyancy_position 578.151428 cc
2021-06-01T18:55:23.122Z,1622573723.122 [Reporter](INFO): platform_buoyancy_position 571.973633 cc
2021-06-01T18:55:23.550Z,1622573723.550 [Reporter](INFO): platform_buoyancy_position 567.541748 cc
2021-06-01T18:55:23.993Z,1622573723.993 [Reporter](INFO): platform_buoyancy_position 561.095337 cc
2021-06-01T18:55:24.334Z,1622573724.334 [Reporter](INFO): platform_buoyancy_position 556.797729 cc
2021-06-01T18:55:24.761Z,1622573724.761 [Reporter](INFO): platform_buoyancy_position 550.485596 cc
2021-06-01T18:55:25.176Z,1622573725.176 [Reporter](INFO): platform_buoyancy_position 545.919434 cc
2021-06-01T18:55:25.575Z,1622573725.575 [Reporter](INFO): platform_buoyancy_position 539.741638 cc
2021-06-01T18:55:25.970Z,1622573725.970 [Reporter](INFO): platform_buoyancy_position 535.444031 cc
2021-06-01T18:55:26.379Z,1622573726.379 [Reporter](INFO): platform_buoyancy_position 528.997620 cc
2021-06-01T18:55:26.781Z,1622573726.781 [Reporter](INFO): platform_buoyancy_position 524.565735 cc
2021-06-01T18:55:27.173Z,1622573727.173 [Reporter](INFO): platform_buoyancy_position 518.253601 cc
2021-06-01T18:55:27.593Z,1622573727.593 [Reporter](INFO): platform_buoyancy_position 513.955994 cc
2021-06-01T18:55:28.033Z,1622573728.033 [Reporter](INFO): platform_buoyancy_position 508.449738 cc
2021-06-01T18:55:41.734Z,1622573741.734 [CommandLine](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 200.000000 cubic_centimeter
2021-06-01T18:55:43.808Z,1622573743.808 [Reporter](INFO): platform_buoyancy_position 501.331879 cc
2021-06-01T18:55:44.174Z,1622573744.174 [Reporter](INFO): platform_buoyancy_position 501.063232 cc
2021-06-01T18:55:44.561Z,1622573744.561 [Reporter](INFO): platform_buoyancy_position 498.780121 cc
2021-06-01T18:55:44.969Z,1622573744.969 [Reporter](INFO): platform_buoyancy_position 496.497040 cc
2021-06-01T18:55:45.382Z,1622573745.382 [Reporter](INFO): platform_buoyancy_position 491.527893 cc
2021-06-01T18:55:45.790Z,1622573745.790 [Reporter](INFO): platform_buoyancy_position 486.021637 cc
2021-06-01T18:55:46.177Z,1622573746.177 [Reporter](INFO): platform_buoyancy_position 481.724030 cc
2021-06-01T18:55:46.585Z,1622573746.585 [Reporter](INFO): platform_buoyancy_position 477.292145 cc
2021-06-01T18:55:46.970Z,1622573746.970 [Reporter](INFO): platform_buoyancy_position 471.248627 cc
2021-06-01T18:55:47.382Z,1622573747.382 [Reporter](INFO): platform_buoyancy_position 466.682465 cc
2021-06-01T18:55:47.796Z,1622573747.796 [Reporter](INFO): platform_buoyancy_position 461.847626 cc
2021-06-01T18:55:48.177Z,1622573748.177 [Reporter](INFO): platform_buoyancy_position 455.804169 cc
2021-06-01T18:55:48.590Z,1622573748.590 [Reporter](INFO): platform_buoyancy_position 451.506561 cc
2021-06-01T18:55:48.997Z,1622573748.997 [Reporter](INFO): platform_buoyancy_position 447.208954 cc
2021-06-01T18:55:49.413Z,1622573749.413 [Reporter](INFO): platform_buoyancy_position 440.762543 cc
2021-06-01T18:55:49.790Z,1622573749.790 [Reporter](INFO): platform_buoyancy_position 434.584747 cc
2021-06-01T18:55:50.212Z,1622573750.212 [Reporter](INFO): platform_buoyancy_position 430.018524 cc
2021-06-01T18:55:50.625Z,1622573750.625 [Reporter](INFO): platform_buoyancy_position 424.915131 cc
2021-06-01T18:55:51.021Z,1622573751.021 [Reporter](INFO): platform_buoyancy_position 419.408844 cc
2021-06-01T18:55:51.414Z,1622573751.414 [Reporter](INFO): platform_buoyancy_position 414.708344 cc
2021-06-01T18:55:51.828Z,1622573751.828 [Reporter](INFO): platform_buoyancy_position 408.664856 cc
2021-06-01T18:55:52.240Z,1622573752.240 [Reporter](INFO): platform_buoyancy_position 403.695740 cc
2021-06-01T18:55:52.643Z,1622573752.643 [Reporter](INFO): platform_buoyancy_position 397.786530 cc
2021-06-01T18:55:53.028Z,1622573753.028 [Reporter](INFO): platform_buoyancy_position 393.354645 cc
2021-06-01T18:55:53.445Z,1622573753.445 [Reporter](INFO): platform_buoyancy_position 387.042542 cc
2021-06-01T18:55:53.853Z,1622573753.853 [Reporter](INFO): platform_buoyancy_position 382.744934 cc
2021-06-01T18:55:54.230Z,1622573754.230 [Reporter](INFO): platform_buoyancy_position 376.432831 cc
2021-06-01T18:55:54.642Z,1622573754.642 [Reporter](INFO): platform_buoyancy_position 371.866638 cc
2021-06-01T18:55:55.062Z,1622573755.062 [Reporter](INFO): platform_buoyancy_position 365.688843 cc
2021-06-01T18:55:55.463Z,1622573755.463 [Reporter](INFO): platform_buoyancy_position 361.256927 cc
2021-06-01T18:55:55.846Z,1622573755.846 [Reporter](INFO): platform_buoyancy_position 354.810547 cc
2021-06-01T18:55:56.274Z,1622573756.274 [Reporter](INFO): platform_buoyancy_position 350.512939 cc
2021-06-01T18:55:56.788Z,1622573756.788 [Reporter](INFO): platform_buoyancy_position 344.066528 cc
2021-06-01T18:55:57.090Z,1622573757.090 [Reporter](INFO): platform_buoyancy_position 339.903229 cc
2021-06-01T18:55:57.470Z,1622573757.470 [Reporter](INFO): platform_buoyancy_position 334.531250 cc
2021-06-01T18:55:57.899Z,1622573757.899 [Reporter](INFO): platform_buoyancy_position 329.024933 cc
2021-06-01T18:55:58.293Z,1622573758.293 [Reporter](INFO): platform_buoyancy_position 323.652924 cc
2021-06-01T18:55:58.694Z,1622573758.694 [Reporter](INFO): platform_buoyancy_position 318.280945 cc
2021-06-01T18:55:59.105Z,1622573759.105 [Reporter](INFO): platform_buoyancy_position 313.983337 cc
2021-06-01T18:55:59.509Z,1622573759.509 [Reporter](INFO): platform_buoyancy_position 309.685730 cc
2021-06-01T18:55:59.916Z,1622573759.916 [Reporter](INFO): platform_buoyancy_position 303.239319 cc
2021-06-01T18:56:00.301Z,1622573760.301 [Reporter](INFO): platform_buoyancy_position 297.061523 cc
2021-06-01T18:56:00.698Z,1622573760.698 [Reporter](INFO): platform_buoyancy_position 292.495331 cc
2021-06-01T18:56:01.122Z,1622573761.122 [Reporter](INFO): platform_buoyancy_position 286.183228 cc
2021-06-01T18:56:01.544Z,1622573761.544 [Reporter](INFO): platform_buoyancy_position 281.885620 cc
2021-06-01T18:56:01.910Z,1622573761.910 [Reporter](INFO): platform_buoyancy_position 275.439240 cc
2021-06-01T18:56:02.330Z,1622573762.330 [Reporter](INFO): platform_buoyancy_position 271.141632 cc
2021-06-01T18:56:02.756Z,1622573762.756 [Reporter](INFO): platform_buoyancy_position 264.695221 cc
2021-06-01T18:56:03.125Z,1622573763.125 [Reporter](INFO): platform_buoyancy_position 260.397644 cc
2021-06-01T18:56:03.544Z,1622573763.544 [Reporter](INFO): platform_buoyancy_position 254.219803 cc
2021-06-01T18:56:03.961Z,1622573763.961 [Reporter](INFO): platform_buoyancy_position 249.519302 cc
2021-06-01T18:56:04.338Z,1622573764.338 [Reporter](INFO): platform_buoyancy_position 244.818832 cc
2021-06-01T18:56:04.742Z,1622573764.742 [Reporter](INFO): platform_buoyancy_position 238.909637 cc
2021-06-01T18:56:05.169Z,1622573765.169 [Reporter](INFO): platform_buoyancy_position 233.403320 cc
2021-06-01T18:56:05.559Z,1622573765.559 [Reporter](INFO): platform_buoyancy_position 228.031342 cc
2021-06-01T18:56:05.994Z,1622573765.994 [Reporter](INFO): platform_buoyancy_position 221.719238 cc
2021-06-01T18:56:06.362Z,1622573766.362 [Reporter](INFO): platform_buoyancy_position 217.421631 cc
2021-06-01T18:56:06.774Z,1622573766.774 [Reporter](INFO): platform_buoyancy_position 212.989716 cc
2021-06-01T18:56:07.213Z,1622573767.213 [Reporter](INFO): platform_buoyancy_position 208.020630 cc
2021-06-01T18:56:30.863Z,1622573790.863 [CommandLine](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 100.000000 cubic_centimeter
2021-06-01T18:56:33.102Z,1622573793.102 [Reporter](INFO): platform_buoyancy_position 200.231232 cc
2021-06-01T18:56:33.562Z,1622573793.562 [Reporter](INFO): platform_buoyancy_position 200.096924 cc
2021-06-01T18:56:33.941Z,1622573793.941 [Reporter](INFO): platform_buoyancy_position 198.082428 cc
2021-06-01T18:56:34.333Z,1622573794.333 [Reporter](INFO): platform_buoyancy_position 193.784821 cc
2021-06-01T18:56:34.766Z,1622573794.766 [Reporter](INFO): platform_buoyancy_position 189.487213 cc
2021-06-01T18:56:35.108Z,1622573795.108 [Reporter](INFO): platform_buoyancy_position 185.189636 cc
2021-06-01T18:56:35.530Z,1622573795.530 [Reporter](INFO): platform_buoyancy_position 180.892029 cc
2021-06-01T18:56:35.920Z,1622573795.920 [Reporter](INFO): platform_buoyancy_position 176.460114 cc
2021-06-01T18:56:36.302Z,1622573796.302 [Reporter](INFO): platform_buoyancy_position 170.013733 cc
2021-06-01T18:56:36.737Z,1622573796.737 [Reporter](INFO): platform_buoyancy_position 165.716125 cc
2021-06-01T18:56:37.140Z,1622573797.140 [Reporter](INFO): platform_buoyancy_position 159.404022 cc
2021-06-01T18:56:37.539Z,1622573797.539 [Reporter](INFO): platform_buoyancy_position 155.106415 cc
2021-06-01T18:56:37.939Z,1622573797.939 [Reporter](INFO): platform_buoyancy_position 148.525726 cc
2021-06-01T18:56:38.340Z,1622573798.340 [Reporter](INFO): platform_buoyancy_position 144.228119 cc
2021-06-01T18:56:38.750Z,1622573798.750 [Reporter](INFO): platform_buoyancy_position 137.916016 cc
2021-06-01T18:56:39.140Z,1622573799.140 [Reporter](INFO): platform_buoyancy_position 133.484100 cc
2021-06-01T18:56:39.534Z,1622573799.534 [Reporter](INFO): platform_buoyancy_position 128.112122 cc
2021-06-01T18:56:39.960Z,1622573799.960 [Reporter](INFO): platform_buoyancy_position 122.740120 cc
2021-06-01T18:56:40.373Z,1622573800.373 [Reporter](INFO): platform_buoyancy_position 116.428017 cc
2021-06-01T18:56:40.749Z,1622573800.749 [Reporter](INFO): platform_buoyancy_position 111.996117 cc
2021-06-01T18:56:41.198Z,1622573801.198 [Reporter](INFO): platform_buoyancy_position 107.832817 cc
2021-06-01T18:57:42.949Z,1622573862.949 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction -1.000000 centimeter
2021-06-01T18:57:42.953Z,1622573862.953 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.massPositionAction" handled in the control thread.
2021-06-01T18:57:43.358Z,1622573863.358 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,AMEcho,DAT,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.buoyancyAction,Maintain_VerticalControl.massPositionAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2021-06-01T18:57:49.477Z,1622573869.477 [CommandLine](IMPORTANT): got command show variable limitAFt
2021-06-01T18:57:49.511Z,1622573869.511 [CommandLine](IMPORTANT): VerticalControl.massPositionLimitAft (millimeter)
2021-06-01T18:57:55.964Z,1622573875.964 [CommandLine](IMPORTANT): got command get VerticalControl.massPositionLimitAft
2021-06-01T18:57:55.964Z,1622573875.964 [CommandLine](IMPORTANT): VerticalControl.massPositionLimitAft -35.000000 mm
2021-06-01T18:58:29.481Z,1622573909.481 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-06-01T18:58:29.481Z,1622573909.481 [Default:CheckIn:C.Wait] Stopped
2021-06-01T18:58:29.481Z,1622573909.481 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-06-01T18:58:29.481Z,1622573909.481 [Default:CheckIn:D] Running Loop=1
2021-06-01T18:58:29.868Z,1622573909.868 [Default:CheckIn:D] Stopped
2021-06-01T18:58:29.869Z,1622573909.869 [Default:CheckIn:E] Running Loop=1
2021-06-01T18:58:30.273Z,1622573910.273 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 174.825114 min 
2021-06-01T18:58:30.273Z,1622573910.273 [Default:CheckIn:E] Stopped
2021-06-01T18:58:30.273Z,1622573910.273 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-06-01T18:58:30.273Z,1622573910.273 [Default:CheckIn] Stopped
2021-06-01T18:58:30.273Z,1622573910.273 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-06-01T18:58:30.274Z,1622573910.274 [Default:CheckIn](INFO): Running loop #18
2021-06-01T18:58:30.274Z,1622573910.274 [Default:CheckIn] Running Loop=18
2021-06-01T18:58:30.274Z,1622573910.274 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-06-01T18:58:30.274Z,1622573910.274 [Default:CheckIn:Read_GPS] Running Loop=1
2021-06-01T18:58:32.270Z,1622573912.270 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185831.00,A,3648.16156,N,12147.28024,W,0.136,193.63,010621,,,A*75
2021-06-01T18:58:32.273Z,1622573912.273 [NAL9602](INFO): GPS fix at 20210601T185831: (36.802693, -121.788004)
2021-06-01T18:58:32.290Z,1622573912.290 [Default:CheckIn:Read_GPS] Stopped
2021-06-01T18:58:32.290Z,1622573912.290 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-06-01T18:58:35.536Z,1622573915.536 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction -0.500000 centimeter
2021-06-01T18:58:45.843Z,1622573925.843 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210601T155937/Courier0055.lzma
2021-06-01T18:58:46.526Z,1622573926.526 [CommandLine](IMPORTANT): got command get platform_pitch_angle degree
2021-06-01T18:58:46.526Z,1622573926.526 [CommandLine](IMPORTANT): platform_pitch_angle 2.281598 arcdeg
2021-06-01T18:58:46.845Z,1622573926.845 [DataOverHttps](INFO): Moved sent file to Logs/20210601T155937/Courier0055.lzma.bak
2021-06-01T18:58:46.846Z,1622573926.846 [DataOverHttps](INFO): SBD MOMSN=15681645
2021-06-01T18:58:51.764Z,1622573931.764 [CommandLine](IMPORTANT): got command get platform_pitch_angle degree
2021-06-01T18:58:51.765Z,1622573931.765 [CommandLine](IMPORTANT): platform_pitch_angle -1.559450 arcdeg
2021-06-01T18:59:04.588Z,1622573944.588 [DataOverHttps](INFO): Sending 321 bytes from file Logs/20210601T155937/Express0056.lzma
2021-06-01T18:59:05.589Z,1622573945.589 [DataOverHttps](INFO): Moved sent file to Logs/20210601T155937/Express0056.lzma.bak
2021-06-01T18:59:05.589Z,1622573945.589 [DataOverHttps](INFO): SBD MOMSN=15681648
2021-06-01T18:59:08.912Z,1622573948.912 [Default:CheckIn:Read_Iridium] Stopped
2021-06-01T18:59:08.912Z,1622573948.912 [Default:CheckIn:C.Wait] Running Loop=1
2021-06-01T18:59:08.912Z,1622573948.912 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-06-01T18:59:26.226Z,1622573966.226 [CommandLine](IMPORTANT): got command show variable limitLo
2021-06-01T18:59:26.308Z,1622573966.308 [CommandLine](IMPORTANT): VerticalControl.buoyancyLimitLoCC (cubic_centimeter)
2021-06-01T18:59:26.327Z,1622573966.327 [CommandLine](IMPORTANT): BuoyancyServo.limitLo (count)
2021-06-01T18:59:26.344Z,1622573966.344 [CommandLine](IMPORTANT): DockingServo.limitLo (count)
2021-06-01T18:59:26.346Z,1622573966.346 [CommandLine](IMPORTANT): ElevatorServo.limitLo (count)
2021-06-01T18:59:26.356Z,1622573966.356 [CommandLine](IMPORTANT): MassServo.limitLo (count)
2021-06-01T18:59:26.357Z,1622573966.357 [CommandLine](IMPORTANT): RudderServo.limitLo (count)
2021-06-01T18:59:34.526Z,1622573974.526 [CommandLine](IMPORTANT): got command get VerticalControl.buoyancyLimitLoCC
2021-06-01T18:59:34.527Z,1622573974.527 [CommandLine](IMPORTANT): VerticalControl.buoyancyLimitLoCC 30.000000 cc
2021-06-01T18:59:47.952Z,1622573987.952 [CommandLine](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 40.000000 cubic_centimeter
2021-06-01T18:59:53.485Z,1622573993.485 [Reporter](INFO): platform_buoyancy_position 96.954506 cc
2021-06-01T18:59:53.833Z,1622573993.833 [Reporter](INFO): platform_buoyancy_position 97.088814 cc
2021-06-01T18:59:54.653Z,1622573994.653 [Reporter](INFO): platform_buoyancy_position 96.954506 cc
2021-06-01T18:59:55.026Z,1622573995.026 [Reporter](INFO): platform_buoyancy_position 94.940010 cc
2021-06-01T18:59:55.446Z,1622573995.446 [Reporter](INFO): platform_buoyancy_position 94.805717 cc
2021-06-01T18:59:55.917Z,1622573995.917 [Reporter](INFO): platform_buoyancy_position 92.791206 cc
2021-06-01T18:59:56.263Z,1622573996.263 [Reporter](INFO): platform_buoyancy_position 92.656914 cc
2021-06-01T18:59:56.646Z,1622573996.646 [Reporter](INFO): platform_buoyancy_position 90.508110 cc
2021-06-01T18:59:57.064Z,1622573997.064 [Reporter](INFO): platform_buoyancy_position 88.493614 cc
2021-06-01T18:59:57.890Z,1622573997.890 [Reporter](INFO): platform_buoyancy_position 86.344810 cc
2021-06-01T18:59:58.264Z,1622573998.264 [Reporter](INFO): platform_buoyancy_position 86.076210 cc
2021-06-01T18:59:58.674Z,1622573998.674 [Reporter](INFO): platform_buoyancy_position 84.061714 cc
2021-06-01T18:59:59.090Z,1622573999.090 [Reporter](INFO): platform_buoyancy_position 81.912910 cc
2021-06-01T18:59:59.878Z,1622573999.878 [Reporter](INFO): platform_buoyancy_position 79.898415 cc
2021-06-01T19:00:00.294Z,1622574000.294 [Reporter](INFO): platform_buoyancy_position 77.615303 cc
2021-06-01T19:00:01.102Z,1622574001.102 [Reporter](INFO): platform_buoyancy_position 75.735115 cc
2021-06-01T19:00:01.549Z,1622574001.549 [Reporter](INFO): platform_buoyancy_position 75.466515 cc
2021-06-01T19:00:01.914Z,1622574001.914 [Reporter](INFO): platform_buoyancy_position 73.452003 cc
2021-06-01T19:00:02.333Z,1622574002.333 [Reporter](INFO): platform_buoyancy_position 71.303215 cc
2021-06-01T19:00:03.120Z,1622574003.120 [Reporter](INFO): platform_buoyancy_position 69.154411 cc
2021-06-01T19:00:03.540Z,1622574003.540 [Reporter](INFO): platform_buoyancy_position 67.005608 cc
2021-06-01T19:00:03.910Z,1622574003.910 [Reporter](INFO): platform_buoyancy_position 66.871315 cc
2021-06-01T19:00:04.338Z,1622574004.338 [Reporter](INFO): platform_buoyancy_position 64.856804 cc
2021-06-01T19:00:04.746Z,1622574004.746 [Reporter](INFO): platform_buoyancy_position 64.722511 cc
2021-06-01T19:00:05.152Z,1622574005.152 [Reporter](INFO): platform_buoyancy_position 62.708015 cc
2021-06-01T19:00:05.543Z,1622574005.543 [Reporter](INFO): platform_buoyancy_position 60.559212 cc
2021-06-01T19:00:05.954Z,1622574005.954 [Reporter](INFO): platform_buoyancy_position 60.424904 cc
2021-06-01T19:00:06.374Z,1622574006.374 [Reporter](INFO): platform_buoyancy_position 58.410408 cc
2021-06-01T19:00:06.793Z,1622574006.793 [Reporter](INFO): platform_buoyancy_position 57.470314 cc
2021-06-01T19:00:07.181Z,1622574007.181 [Reporter](INFO): platform_buoyancy_position 56.261604 cc
2021-06-01T19:00:07.603Z,1622574007.603 [Reporter](INFO): platform_buoyancy_position 54.112801 cc
2021-06-01T19:00:07.977Z,1622574007.977 [Reporter](INFO): platform_buoyancy_position 53.978508 cc
2021-06-01T19:00:08.384Z,1622574008.384 [Reporter](INFO): platform_buoyancy_position 51.829704 cc
2021-06-01T19:00:08.769Z,1622574008.769 [Reporter](INFO): platform_buoyancy_position 51.561104 cc
2021-06-01T19:00:09.194Z,1622574009.194 [Reporter](INFO): platform_buoyancy_position 49.680901 cc
2021-06-01T19:01:53.050Z,1622574113.050 [NAL9602](INFO): SBD MO Status=2, MOMSN=27558, MT Status=2, MTMSN=0
2021-06-01T19:01:53.050Z,1622574113.050 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-06-01T19:02:10.426Z,1622574130.426 [NAL9602](INFO): SBD MO Status=2, MOMSN=27558, MT Status=2, MTMSN=0
2021-06-01T19:02:10.426Z,1622574130.426 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-06-01T19:03:41.802Z,1622574221.802 [NAL9602](INFO): SBD MO Status=0, MOMSN=27558, MT Status=0, MTMSN=0
2021-06-01T19:03:41.802Z,1622574221.802 [NAL9602](INFO): No messages in MT queue
2021-06-01T19:03:41.802Z,1622574221.802 [NAL9602](INFO): Failure count cleared after critical for NAL9602
2021-06-01T19:04:09.686Z,1622574249.686 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-06-01T19:04:09.686Z,1622574249.686 [Default:CheckIn:C.Wait] Stopped
2021-06-01T19:04:09.686Z,1622574249.686 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-06-01T19:04:09.686Z,1622574249.686 [Default:CheckIn:D] Running Loop=1
2021-06-01T19:04:10.097Z,1622574250.097 [Default:CheckIn:D] Stopped
2021-06-01T19:04:10.097Z,1622574250.097 [Default:CheckIn:E] Running Loop=1
2021-06-01T19:04:10.513Z,1622574250.513 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 180.495589 min 
2021-06-01T19:04:10.513Z,1622574250.513 [Default:CheckIn:E] Stopped
2021-06-01T19:04:10.513Z,1622574250.513 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-06-01T19:04:10.513Z,1622574250.513 [Default:CheckIn] Stopped
2021-06-01T19:04:10.514Z,1622574250.514 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-06-01T19:04:10.514Z,1622574250.514 [Default:CheckIn](INFO): Running loop #19
2021-06-01T19:04:10.514Z,1622574250.514 [Default:CheckIn] Running Loop=19
2021-06-01T19:04:10.514Z,1622574250.514 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-06-01T19:04:10.514Z,1622574250.514 [Default:CheckIn:Read_GPS] Running Loop=1
2021-06-01T19:04:12.504Z,1622574252.504 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190411.00,A,3648.16740,N,12147.27925,W,0.078,193.63,010621,,,A*72
2021-06-01T19:04:12.506Z,1622574252.506 [NAL9602](INFO): GPS fix at 20210601T190411: (36.802790, -121.787987)
2021-06-01T19:04:12.519Z,1622574252.519 [Default:CheckIn:Read_GPS] Stopped
2021-06-01T19:04:12.519Z,1622574252.519 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-06-01T19:04:15.110Z,1622574255.110 [CommandLine](IMPORTANT): got command report clear
2021-06-01T19:04:18.901Z,1622574258.901 [CommandLine](IMPORTANT): got command maintain clear
2021-06-01T19:04:19.096Z,1622574259.096 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,AMEcho,DAT,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2021-06-01T19:04:23.415Z,1622574263.415 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210601T155937/Courier0058.lzma
2021-06-01T19:04:24.417Z,1622574264.417 [DataOverHttps](INFO): Moved sent file to Logs/20210601T155937/Courier0058.lzma.bak
2021-06-01T19:04:24.418Z,1622574264.418 [DataOverHttps](INFO): SBD MOMSN=15681662
2021-06-01T19:04:44.156Z,1622574284.156 [NAL9602](INFO): Not Powering down - fast GPS
2021-06-01T19:04:47.287Z,1622574287.287 [DataOverHttps](INFO): Sending 443 bytes from file Logs/20210601T155937/Express0059.lzma
2021-06-01T19:04:48.289Z,1622574288.289 [DataOverHttps](INFO): Moved sent file to Logs/20210601T155937/Express0059.lzma.bak
2021-06-01T19:04:48.289Z,1622574288.289 [DataOverHttps](INFO): SBD MOMSN=15681665
2021-06-01T19:04:51.052Z,1622574291.052 [Default:CheckIn:Read_Iridium] Stopped
2021-06-01T19:04:51.053Z,1622574291.053 [Default:CheckIn:C.Wait] Running Loop=1
2021-06-01T19:04:51.053Z,1622574291.053 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-06-01T19:09:51.666Z,1622574591.666 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-06-01T19:09:51.666Z,1622574591.666 [Default:CheckIn:C.Wait] Stopped
2021-06-01T19:09:51.666Z,1622574591.666 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-06-01T19:09:51.666Z,1622574591.666 [Default:CheckIn:D] Running Loop=1
2021-06-01T19:09:52.024Z,1622574592.024 [Default:CheckIn:D] Stopped
2021-06-01T19:09:52.024Z,1622574592.024 [Default:CheckIn:E] Running Loop=1
2021-06-01T19:09:52.430Z,1622574592.430 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 186.194368 min 
2021-06-01T19:09:52.430Z,1622574592.430 [Default:CheckIn:E] Stopped
2021-06-01T19:09:52.430Z,1622574592.430 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-06-01T19:09:52.430Z,1622574592.430 [Default:CheckIn] Stopped
2021-06-01T19:09:52.430Z,1622574592.430 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-06-01T19:09:52.430Z,1622574592.430 [Default:CheckIn](INFO): Running loop #20
2021-06-01T19:09:52.431Z,1622574592.431 [Default:CheckIn] Running Loop=20
2021-06-01T19:09:52.431Z,1622574592.431 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-06-01T19:09:52.431Z,1622574592.431 [Default:CheckIn:Read_GPS] Running Loop=1
2021-06-01T19:09:54.438Z,1622574594.438 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190953.00,A,3648.14420,N,12147.22796,W,0.019,65.93,010621,,,A*4D
2021-06-01T19:09:54.450Z,1622574594.450 [NAL9602](INFO): GPS fix at 20210601T190953: (36.802403, -121.787133)
2021-06-01T19:09:54.463Z,1622574594.463 [Default:CheckIn:Read_GPS] Stopped
2021-06-01T19:09:54.463Z,1622574594.463 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-06-01T19:10:03.067Z,1622574603.067 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210601T155937/Courier0061.lzma
2021-06-01T19:10:04.069Z,1622574604.069 [DataOverHttps](INFO): Moved sent file to Logs/20210601T155937/Courier0061.lzma.bak
2021-06-01T19:10:04.069Z,1622574604.069 [DataOverHttps](INFO): SBD MOMSN=15681689
2021-06-01T19:10:13.018Z,1622574613.018 [NAL9602](INFO): SBD MO Status=0, MOMSN=27559, MT Status=0, MTMSN=0
2021-06-01T19:10:13.018Z,1622574613.018 [NAL9602](INFO): No messages in MT queue
2021-06-01T19:10:24.775Z,1622574624.775 [DataOverHttps](INFO): Sending 188 bytes from file Logs/20210601T155937/Express0062.lzma
2021-06-01T19:10:25.777Z,1622574625.777 [DataOverHttps](INFO): Moved sent file to Logs/20210601T155937/Express0062.lzma.bak
2021-06-01T19:10:25.778Z,1622574625.778 [DataOverHttps](INFO): SBD MOMSN=15681692
2021-06-01T19:10:30.964Z,1622574630.964 [Default:CheckIn:Read_Iridium] Stopped
2021-06-01T19:10:30.964Z,1622574630.964 [Default:CheckIn:C.Wait] Running Loop=1
2021-06-01T19:10:30.964Z,1622574630.964 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-06-01T19:10:43.500Z,1622574643.500 [NAL9602](INFO): Not Powering down - fast GPS
2021-06-01T19:15:31.696Z,1622574931.696 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-06-01T19:15:31.696Z,1622574931.696 [Default:CheckIn:C.Wait] Stopped
2021-06-01T19:15:31.696Z,1622574931.696 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-06-01T19:15:31.696Z,1622574931.696 [Default:CheckIn:D] Running Loop=1
2021-06-01T19:15:32.100Z,1622574932.100 [Default:CheckIn:D] Stopped
2021-06-01T19:15:32.100Z,1622574932.100 [Default:CheckIn:E] Running Loop=1
2021-06-01T19:15:32.509Z,1622574932.509 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 191.862321 min 
2021-06-01T19:15:32.509Z,1622574932.509 [Default:CheckIn:E] Stopped
2021-06-01T19:15:32.509Z,1622574932.509 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-06-01T19:15:32.509Z,1622574932.509 [Default:CheckIn] Stopped
2021-06-01T19:15:32.509Z,1622574932.509 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-06-01T19:15:32.510Z,1622574932.510 [Default:CheckIn](INFO): Running loop #21
2021-06-01T19:15:32.510Z,1622574932.510 [Default:CheckIn] Running Loop=21
2021-06-01T19:15:32.510Z,1622574932.510 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-06-01T19:15:32.510Z,1622574932.510 [Default:CheckIn:Read_GPS] Running Loop=1
2021-06-01T19:15:34.506Z,1622574934.506 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191533.00,A,3648.15154,N,12147.21950,W,0.078,24.73,010621,,,A*4A
2021-06-01T19:15:34.509Z,1622574934.509 [NAL9602](INFO): GPS fix at 20210601T191533: (36.802526, -121.786992)
2021-06-01T19:15:34.522Z,1622574934.522 [Default:CheckIn:Read_GPS] Stopped
2021-06-01T19:15:34.522Z,1622574934.522 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-06-01T19:15:43.115Z,1622574943.115 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210601T155937/Courier0064.lzma
2021-06-01T19:15:44.117Z,1622574944.117 [DataOverHttps](INFO): Moved sent file to Logs/20210601T155937/Courier0064.lzma.bak
2021-06-01T19:15:44.117Z,1622574944.117 [DataOverHttps](INFO): SBD MOMSN=15681698
2021-06-01T19:16:01.559Z,1622574961.559 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20210601T155937/Express0065.lzma
2021-06-01T19:16:02.561Z,1622574962.561 [DataOverHttps](INFO): Moved sent file to Logs/20210601T155937/Express0065.lzma.bak
2021-06-01T19:16:02.561Z,1622574962.561 [DataOverHttps](INFO): SBD MOMSN=15681703
2021-06-01T19:16:05.268Z,1622574965.268 [Default:CheckIn:Read_Iridium] Stopped
2021-06-01T19:16:05.268Z,1622574965.268 [Default:CheckIn:C.Wait] Running Loop=1
2021-06-01T19:16:05.268Z,1622574965.268 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-06-01T19:16:24.757Z,1622574984.757 [DataOverHttps](IMPORTANT): SBD MTMSN=20210601T191623
2021-06-01T19:16:32.387Z,1622574992.387 [NAL9602](INFO): SBD MO Status=2, MOMSN=27560, MT Status=2, MTMSN=0
2021-06-01T19:16:32.387Z,1622574992.387 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-06-01T19:16:35.324Z,1622574995.324 [DataOverHttps](INFO): Received command:ibit
2021-06-01T19:16:35.340Z,1622574995.340 [CommandLine](IMPORTANT): got command ibit
2021-06-01T19:16:35.661Z,1622574995.661 [IBIT](IMPORTANT): Beginning Initiated BIT
2021-06-01T19:16:35.661Z,1622574995.661 [IBIT](IMPORTANT): Beginning control surface checks.
2021-06-01T19:16:35.672Z,1622574995.672 [CBIT](IMPORTANT): Beginning ground fault scan
2021-06-01T19:16:46.565Z,1622575006.565 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.002167
CHAN A1 (24V): 0.000506
CHAN A2 (12V): -0.002248
CHAN A3 (5V): -0.001444
CHAN B0 (3.3V): 0.000016
CHAN B1 (3.15aV): -0.000219
CHAN B2 (3.15bV): 0.000061
CHAN B3 (GND): -0.000254
OPEN: -0.000407
Full Scale Calc: 4.765 mA, -1.589 mA
2021-06-01T19:17:02.698Z,1622575022.698 [NAL9602](INFO): SBD MO Status=2, MOMSN=27560, MT Status=2, MTMSN=0
2021-06-01T19:17:02.698Z,1622575022.698 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-06-01T19:17:22.119Z,1622575042.119 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 1
Latitude: 36.802525 Longitude: -121.786995
2021-06-01T19:17:22.543Z,1622575042.543 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 14.669000
2021-06-01T19:17:22.543Z,1622575042.543 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2021-06-01T19:17:22.544Z,1622575042.544 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2021-06-01T19:17:22.946Z,1622575042.946 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2021-06-01T19:17:22.946Z,1622575042.946 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2021-06-01T19:17:22.946Z,1622575042.946 [IBIT](IMPORTANT): Pressure:9.894383 PSI
2021-06-01T19:17:22.947Z,1622575042.947 [IBIT](IMPORTANT): Humidity:6.342327 %
2021-06-01T19:17:23.325Z,1622575043.325 [IBIT](IMPORTANT): Vehicle Pitch:2.084037 degrees
2021-06-01T19:17:23.325Z,1622575043.325 [IBIT](IMPORTANT): Vehicle  Roll:8.696117 degrees
2021-06-01T19:17:23.325Z,1622575043.325 [IBIT](IMPORTANT): Vehicle  Heading:205.437805 degrees
2021-06-01T19:17:23.744Z,1622575043.744 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2021-06-01T19:17:23.744Z,1622575043.744 [IBIT](IMPORTANT): buoyancyNeutral: 282.000000 cc
2021-06-01T19:17:23.744Z,1622575043.744 [IBIT](IMPORTANT): massDefault: 0.925000 cm
2021-06-01T19:17:23.745Z,1622575043.745 [IBIT](IMPORTANT): stopDepth: 205.000000 m
2021-06-01T19:17:23.745Z,1622575043.745 [IBIT](IMPORTANT): abortDepth: 250.000000 m
2021-06-01T19:17:23.745Z,1622575043.745 [IBIT](IMPORTANT): IBIT PASSED
2021-06-01T19:18:02.497Z,1622575082.497 [NAL9602](INFO): SBD MO Status=0, MOMSN=27560, MT Status=0, MTMSN=0
2021-06-01T19:18:02.497Z,1622575082.497 [NAL9602](INFO): No messages in MT queue
2021-06-01T19:18:33.248Z,1622575113.248 [NAL9602](INFO): Not Powering down - fast GPS
2021-06-01T19:21:05.985Z,1622575265.985 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-06-01T19:21:05.985Z,1622575265.985 [Default:CheckIn:C.Wait] Stopped
2021-06-01T19:21:05.985Z,1622575265.985 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-06-01T19:21:05.985Z,1622575265.985 [Default:CheckIn:D] Running Loop=1
2021-06-01T19:21:06.405Z,1622575266.405 [Default:CheckIn:D] Stopped
2021-06-01T19:21:06.405Z,1622575266.405 [Default:CheckIn:E] Running Loop=1
2021-06-01T19:21:06.789Z,1622575266.789 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 197.434049 min 
2021-06-01T19:21:06.789Z,1622575266.789 [Default:CheckIn:E] Stopped
2021-06-01T19:21:06.789Z,1622575266.789 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-06-01T19:21:06.789Z,1622575266.789 [Default:CheckIn] Stopped
2021-06-01T19:21:06.789Z,1622575266.789 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-06-01T19:21:06.790Z,1622575266.790 [Default:CheckIn](INFO): Running loop #22
2021-06-01T19:21:06.790Z,1622575266.790 [Default:CheckIn] Running Loop=22
2021-06-01T19:21:06.790Z,1622575266.790 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-06-01T19:21:06.790Z,1622575266.790 [Default:CheckIn:Read_GPS] Running Loop=1
2021-06-01T19:21:08.802Z,1622575268.802 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192108.00,A,3648.19445,N,12147.15875,W,4.918,24.01,010621,,,D*46
2021-06-01T19:21:08.805Z,1622575268.805 [NAL9602](INFO): GPS fix at 20210601T192108: (36.803241, -121.785979)
2021-06-01T19:21:08.818Z,1622575268.818 [Default:CheckIn:Read_GPS] Stopped
2021-06-01T19:21:08.818Z,1622575268.818 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-06-01T19:21:17.627Z,1622575277.627 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20210601T155937/Courier0067.lzma
2021-06-01T19:21:18.629Z,1622575278.629 [DataOverHttps](INFO): Moved sent file to Logs/20210601T155937/Courier0067.lzma.bak
2021-06-01T19:21:18.629Z,1622575278.629 [DataOverHttps](INFO): SBD MOMSN=15681715
2021-06-01T19:21:22.534Z,1622575282.534 [NAL9602](INFO): SBD MO Status=0, MOMSN=27561, MT Status=0, MTMSN=0
2021-06-01T19:21:22.534Z,1622575282.534 [NAL9602](INFO): No messages in MT queue
2021-06-01T19:21:38.604Z,1622575298.604 [DataOverHttps](INFO): Sending 829 bytes from file Logs/20210601T155937/Express0068.lzma
2021-06-01T19:21:39.605Z,1622575299.605 [DataOverHttps](INFO): Moved sent file to Logs/20210601T155937/Express0068.lzma.bak
2021-06-01T19:21:39.605Z,1622575299.605 [DataOverHttps](INFO): SBD MOMSN=15681718
2021-06-01T19:21:53.232Z,1622575313.232 [NAL9602](INFO): Not Powering down - fast GPS
2021-06-01T19:21:57.159Z,1622575317.159 [DataOverHttps](INFO): Sending 53 bytes from file Logs/20210601T155937/Express0071.lzma
2021-06-01T19:21:58.161Z,1622575318.161 [DataOverHttps](INFO): Moved sent file to Logs/20210601T155937/Express0071.lzma.bak
2021-06-01T19:21:58.161Z,1622575318.161 [DataOverHttps](INFO): SBD MOMSN=15681744
2021-06-01T19:22:00.948Z,1622575320.948 [Default:CheckIn:Read_Iridium] Stopped
2021-06-01T19:22:00.948Z,1622575320.948 [Default:CheckIn:C.Wait] Running Loop=1
2021-06-01T19:22:00.948Z,1622575320.948 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-06-01T19:26:13.497Z,1622575573.497 [DataOverHttps](IMPORTANT): SBD MTMSN=20210601T192612
2021-06-01T19:26:24.523Z,1622575584.523 [DataOverHttps](INFO): Received command:maintain clear
2021-06-01T19:26:24.584Z,1622575584.584 [CommandLine](IMPORTANT): got command maintain clear
2021-06-01T19:27:01.569Z,1622575621.569 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-06-01T19:27:01.569Z,1622575621.569 [Default:CheckIn:C.Wait] Stopped
2021-06-01T19:27:01.569Z,1622575621.569 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-06-01T19:27:01.569Z,1622575621.569 [Default:CheckIn:D] Running Loop=1
2021-06-01T19:27:02.030Z,1622575622.030 [Default:CheckIn:D] Stopped
2021-06-01T19:27:02.031Z,1622575622.031 [Default:CheckIn:E] Running Loop=1
2021-06-01T19:27:02.465Z,1622575622.465 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 203.361149 min 
2021-06-01T19:27:02.465Z,1622575622.465 [Default:CheckIn:E] Stopped
2021-06-01T19:27:02.466Z,1622575622.466 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-06-01T19:27:02.466Z,1622575622.466 [Default:CheckIn] Stopped
2021-06-01T19:27:02.466Z,1622575622.466 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-06-01T19:27:02.466Z,1622575622.466 [Default:CheckIn](INFO): Running loop #23
2021-06-01T19:27:02.466Z,1622575622.466 [Default:CheckIn] Running Loop=23
2021-06-01T19:27:02.466Z,1622575622.466 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-06-01T19:27:02.467Z,1622575622.467 [Default:CheckIn:Read_GPS] Running Loop=1
2021-06-01T19:27:04.395Z,1622575624.395 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192703.00,A,3648.35906,N,12147.45806,W,14.559,226.16,010621,,,D*40
2021-06-01T19:27:04.401Z,1622575624.401 [NAL9602](INFO): GPS fix at 20210601T192703: (36.805984, -121.790968)
2021-06-01T19:27:04.425Z,1622575624.425 [Default:CheckIn:Read_GPS] Stopped
2021-06-01T19:27:04.426Z,1622575624.426 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-06-01T19:27:13.291Z,1622575633.291 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210601T155937/Courier0073.lzma
2021-06-01T19:27:14.293Z,1622575634.293 [DataOverHttps](INFO): Moved sent file to Logs/20210601T155937/Courier0073.lzma.bak
2021-06-01T19:27:14.293Z,1622575634.293 [DataOverHttps](INFO): SBD MOMSN=15681755
2021-06-01T19:27:32.363Z,1622575652.363 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20210601T155937/Express0074.lzma
2021-06-01T19:27:33.365Z,1622575653.365 [DataOverHttps](INFO): Moved sent file to Logs/20210601T155937/Express0074.lzma.bak
2021-06-01T19:27:33.365Z,1622575653.365 [DataOverHttps](INFO): SBD MOMSN=15681758
2021-06-01T19:27:51.763Z,1622575671.763 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20210601T155937/Express0077.lzma
2021-06-01T19:27:52.765Z,1622575672.765 [DataOverHttps](INFO): Moved sent file to Logs/20210601T155937/Express0077.lzma.bak
2021-06-01T19:27:52.765Z,1622575672.765 [DataOverHttps](INFO): SBD MOMSN=15681763
2021-06-01T19:27:55.721Z,1622575675.721 [Default:CheckIn:Read_Iridium] Stopped
2021-06-01T19:27:55.721Z,1622575675.721 [Default:CheckIn:C.Wait] Running Loop=1
2021-06-01T19:27:55.721Z,1622575675.721 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-06-01T19:28:41.355Z,1622575721.355 [NAL9602](INFO): SBD MO Status=0, MOMSN=27562, MT Status=0, MTMSN=0
2021-06-01T19:28:41.356Z,1622575721.356 [NAL9602](INFO): No messages in MT queue
2021-06-01T19:29:12.056Z,1622575752.056 [NAL9602](INFO): Not Powering down - fast GPS
2021-06-01T19:31:11.234Z,1622575871.234 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2021-06-01T19:31:11.253Z,1622575871.253 [BPC1](INFO): Received data from all battery sticks.
2021-06-01T19:32:56.700Z,1622575976.700 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-06-01T19:32:56.700Z,1622575976.700 [Default:CheckIn:C.Wait] Stopped
2021-06-01T19:32:56.700Z,1622575976.700 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-06-01T19:32:56.700Z,1622575976.700 [Default:CheckIn:D] Running Loop=1
2021-06-01T19:32:57.116Z,1622575977.116 [Default:CheckIn:D] Stopped
2021-06-01T19:32:57.116Z,1622575977.116 [Default:CheckIn:E] Running Loop=1
2021-06-01T19:32:57.506Z,1622575977.506 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 209.279248 min 
2021-06-01T19:32:57.507Z,1622575977.507 [Default:CheckIn:E] Stopped
2021-06-01T19:32:57.507Z,1622575977.507 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-06-01T19:32:57.507Z,1622575977.507 [Default:CheckIn] Stopped
2021-06-01T19:32:57.507Z,1622575977.507 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-06-01T19:32:57.507Z,1622575977.507 [Default:CheckIn](INFO): Running loop #24
2021-06-01T19:32:57.508Z,1622575977.508 [Default:CheckIn] Running Loop=24
2021-06-01T19:32:57.508Z,1622575977.508 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-06-01T19:32:57.508Z,1622575977.508 [Default:CheckIn:Read_GPS] Running Loop=1
2021-06-01T19:32:59.512Z,1622575979.512 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193258.00,A,3647.90741,N,12149.23464,W,1.905,261.28,010621,,,A*7B
2021-06-01T19:32:59.514Z,1622575979.514 [NAL9602](INFO): GPS fix at 20210601T193258: (36.798457, -121.820577)
2021-06-01T19:32:59.528Z,1622575979.528 [Default:CheckIn:Read_GPS] Stopped
2021-06-01T19:32:59.528Z,1622575979.528 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-06-01T19:33:08.023Z,1622575988.023 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20210601T155937/Courier0079.lzma
2021-06-01T19:33:09.025Z,1622575989.025 [DataOverHttps](INFO): Moved sent file to Logs/20210601T155937/Courier0079.lzma.bak
2021-06-01T19:33:09.025Z,1622575989.025 [DataOverHttps](INFO): SBD MOMSN=15681766
2021-06-01T19:33:26.527Z,1622576006.527 [DataOverHttps](INFO): Sending 146 bytes from file Logs/20210601T155937/Express0080.lzma
2021-06-01T19:33:27.529Z,1622576007.529 [DataOverHttps](INFO): Moved sent file to Logs/20210601T155937/Express0080.lzma.bak
2021-06-01T19:33:27.530Z,1622576007.530 [DataOverHttps](INFO): SBD MOMSN=15681769
2021-06-01T19:33:30.256Z,1622576010.256 [Default:CheckIn:Read_Iridium] Stopped
2021-06-01T19:33:30.256Z,1622576010.256 [Default:CheckIn:C.Wait] Running Loop=1
2021-06-01T19:33:30.256Z,1622576010.256 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-06-01T19:33:35.090Z,1622576015.090 [NAL9602](INFO): SBD MO Status=2, MOMSN=27563, MT Status=2, MTMSN=0
2021-06-01T19:33:35.090Z,1622576015.090 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-06-01T19:34:16.698Z,1622576056.698 [NAL9602](INFO): SBD MO Status=0, MOMSN=27563, MT Status=0, MTMSN=0
2021-06-01T19:34:16.698Z,1622576056.698 [NAL9602](INFO): No messages in MT queue
2021-06-01T19:34:47.404Z,1622576087.404 [NAL9602](INFO): Not Powering down - fast GPS
2021-06-01T19:36:21.417Z,1622576181.417 [DataOverHttps](IMPORTANT): SBD MTMSN=20210601T193620