2021-06-01T14:53:01.807Z,1622559181.807 [Supervisor](DEBUG): Initializing supervisor.
2021-06-01T14:53:01.811Z,1622559181.811 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2021-06-01T14:53:01.812Z,1622559181.812 [SyncHandler](INFO): Protected caller Thread ID is 826
2021-06-01T14:53:01.812Z,1622559181.812 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2021-06-01T14:53:01.813Z,1622559181.813 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2021-06-01T14:53:01.814Z,1622559181.814 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 827
2021-06-01T14:53:01.818Z,1622559181.818 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2021-06-01T14:53:01.838Z,1622559181.838 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2021-06-01T14:53:01.839Z,1622559181.839 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2021-06-01T14:53:01.839Z,1622559181.839 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 828
2021-06-01T14:53:01.842Z,1622559181.842 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2021-06-01T14:53:01.843Z,1622559181.843 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2021-06-01T14:53:01.843Z,1622559181.843 [logger ThreadHandler](INFO): Protected caller Thread ID is 829
2021-06-01T14:53:01.847Z,1622559181.847 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2021-06-01T14:53:01.847Z,1622559181.847 [Supervisor](INFO): Looking for Config files in directory: Config/
2021-06-01T14:53:01.851Z,1622559181.851 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2021-06-01T14:53:02.130Z,1622559182.130 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2021-06-01T14:53:02.131Z,1622559182.131 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2021-06-01T14:53:02.214Z,1622559182.214 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2021-06-01T14:53:02.720Z,1622559182.720 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2021-06-01T14:53:02.720Z,1622559182.720 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2021-06-01T14:53:03.055Z,1622559183.055 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2021-06-01T14:53:03.057Z,1622559183.057 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2021-06-01T14:53:03.155Z,1622559183.155 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2021-06-01T14:53:03.157Z,1622559183.157 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2021-06-01T14:53:03.543Z,1622559183.543 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2021-06-01T14:53:03.544Z,1622559183.544 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2021-06-01T14:53:03.746Z,1622559183.746 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2021-06-01T14:53:03.747Z,1622559183.747 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2021-06-01T14:53:04.215Z,1622559184.215 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2021-06-01T14:53:04.216Z,1622559184.216 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2021-06-01T14:53:04.344Z,1622559184.344 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2021-06-01T14:53:04.345Z,1622559184.345 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2021-06-01T14:53:04.426Z,1622559184.426 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2021-06-01T14:53:05.100Z,1622559185.100 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2021-06-01T14:53:05.101Z,1622559185.101 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2021-06-01T14:53:05.473Z,1622559185.473 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2021-06-01T14:53:05.961Z,1622559185.961 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2021-06-01T14:53:06.232Z,1622559186.232 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2021-06-01T14:53:06.233Z,1622559186.233 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2021-06-01T14:53:06.337Z,1622559186.337 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2021-06-01T14:53:06.338Z,1622559186.338 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2021-06-01T14:53:06.491Z,1622559186.491 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2021-06-01T14:53:06.492Z,1622559186.492 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2021-06-01T14:53:06.729Z,1622559186.729 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2021-06-01T14:53:06.731Z,1622559186.731 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2021-06-01T14:53:06.735Z,1622559186.735 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2021-06-01T14:53:06.829Z,1622559186.829 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2021-06-01T14:53:06.915Z,1622559186.915 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2021-06-01T14:53:07.019Z,1622559187.019 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2021-06-01T14:53:07.104Z,1622559187.104 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2021-06-01T14:53:07.281Z,1622559187.281 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2021-06-01T14:53:07.389Z,1622559187.389 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2021-06-01T14:53:07.584Z,1622559187.584 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2021-06-01T14:53:07.661Z,1622559187.661 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2021-06-01T14:53:07.811Z,1622559187.811 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2021-06-01T14:53:07.951Z,1622559187.951 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2021-06-01T14:53:08.071Z,1622559188.071 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2021-06-01T14:53:08.338Z,1622559188.338 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2021-06-01T14:53:08.339Z,1622559188.339 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2021-06-01T14:53:08.350Z,1622559188.350 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2021-06-01T14:53:08.423Z,1622559188.423 [VerticalControl](DEBUG): Construct VerticalControl.
2021-06-01T14:53:08.487Z,1622559188.487 [VerticalControl] Loaded
2021-06-01T14:53:08.487Z,1622559188.487 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2021-06-01T14:53:08.489Z,1622559188.489 [HorizontalControl](DEBUG): Construct HorizontalControl.
2021-06-01T14:53:08.688Z,1622559188.688 [HorizontalControl] Loaded
2021-06-01T14:53:08.688Z,1622559188.688 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2021-06-01T14:53:08.691Z,1622559188.691 [SpeedControl](DEBUG): Construct SpeedControl.
2021-06-01T14:53:08.722Z,1622559188.722 [SpeedControl] Loaded
2021-06-01T14:53:08.722Z,1622559188.722 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2021-06-01T14:53:08.725Z,1622559188.725 [LoopControl](DEBUG): Construct LoopControl.
2021-06-01T14:53:08.726Z,1622559188.726 [LoopControl] Loaded
2021-06-01T14:53:08.726Z,1622559188.726 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2021-06-01T14:53:08.726Z,1622559188.726 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2021-06-01T14:53:08.798Z,1622559188.798 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2021-06-01T14:53:08.815Z,1622559188.815 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2021-06-01T14:53:08.815Z,1622559188.815 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2021-06-01T14:53:08.950Z,1622559188.950 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2021-06-01T14:53:08.951Z,1622559188.951 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2021-06-01T14:53:09.125Z,1622559189.125 [BuoyancyServo] Loaded
2021-06-01T14:53:09.125Z,1622559189.125 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2021-06-01T14:53:09.145Z,1622559189.145 [ElevatorServo] Loaded
2021-06-01T14:53:09.145Z,1622559189.145 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2021-06-01T14:53:09.163Z,1622559189.163 [MassServo] Loaded
2021-06-01T14:53:09.164Z,1622559189.164 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2021-06-01T14:53:09.182Z,1622559189.182 [RudderServo] Loaded
2021-06-01T14:53:09.183Z,1622559189.183 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2021-06-01T14:53:09.201Z,1622559189.201 [ThrusterServo] Loaded
2021-06-01T14:53:09.201Z,1622559189.201 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2021-06-01T14:53:09.201Z,1622559189.201 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2021-06-01T14:53:09.202Z,1622559189.202 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2021-06-01T14:53:09.303Z,1622559189.303 [DeadReckonUsingMultipleVelocitySources] Loaded
2021-06-01T14:53:09.303Z,1622559189.303 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2021-06-01T14:53:09.317Z,1622559189.317 [NavChart] Loaded
2021-06-01T14:53:09.317Z,1622559189.317 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2021-06-01T14:53:09.323Z,1622559189.323 [UniversalFixResidualReporter] Loaded
2021-06-01T14:53:09.323Z,1622559189.323 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2021-06-01T14:53:09.324Z,1622559189.324 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2021-06-01T14:53:09.325Z,1622559189.325 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2021-06-01T14:53:09.405Z,1622559189.405 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2021-06-01T14:53:09.405Z,1622559189.405 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2021-06-01T14:53:10.083Z,1622559190.083 [AHRS_M2] Loaded
2021-06-01T14:53:10.084Z,1622559190.084 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2021-06-01T14:53:11.181Z,1622559191.181 [BPC1] Loaded
2021-06-01T14:53:11.182Z,1622559191.182 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2021-06-01T14:53:11.471Z,1622559191.471 [DataOverHttps] Loaded
2021-06-01T14:53:11.471Z,1622559191.471 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2021-06-01T14:53:11.493Z,1622559191.493 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408844E0
2021-06-01T14:53:11.493Z,1622559191.493 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 916
2021-06-01T14:53:11.542Z,1622559191.542 [Depth_Keller] Loaded
2021-06-01T14:53:11.542Z,1622559191.542 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2021-06-01T14:53:11.681Z,1622559191.681 [NAL9602] Loaded
2021-06-01T14:53:11.681Z,1622559191.681 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2021-06-01T14:53:11.711Z,1622559191.711 [Onboard] Loaded
2021-06-01T14:53:11.711Z,1622559191.711 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2021-06-01T14:53:11.712Z,1622559191.712 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408B44E0
2021-06-01T14:53:11.713Z,1622559191.713 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 917
2021-06-01T14:53:11.726Z,1622559191.726 [Power24vConverter] Loaded
2021-06-01T14:53:11.726Z,1622559191.726 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2021-06-01T14:53:11.739Z,1622559191.739 [Radio_Surface] Loaded
2021-06-01T14:53:11.739Z,1622559191.739 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2021-06-01T14:53:11.740Z,1622559191.740 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408E44E0
2021-06-01T14:53:11.741Z,1622559191.741 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 918
2021-06-01T14:53:11.762Z,1622559191.762 [AMEcho] Loaded
2021-06-01T14:53:11.762Z,1622559191.762 [ComponentRegistry](DEBUG): SyncComponent "AMEcho" handled in the control thread.
2021-06-01T14:53:11.845Z,1622559191.845 [DAT] Loaded
2021-06-01T14:53:11.845Z,1622559191.845 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2021-06-01T14:53:11.845Z,1622559191.845 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2021-06-01T14:53:11.846Z,1622559191.846 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2021-06-01T14:53:11.905Z,1622559191.905 [DepthRateCalculator] Loaded
2021-06-01T14:53:11.906Z,1622559191.906 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2021-06-01T14:53:11.911Z,1622559191.911 [PitchRateCalculator] Loaded
2021-06-01T14:53:11.911Z,1622559191.911 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2021-06-01T14:53:11.920Z,1622559191.920 [SpeedCalculator] Loaded
2021-06-01T14:53:11.921Z,1622559191.921 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2021-06-01T14:53:11.935Z,1622559191.935 [TempGradientCalculator] Loaded
2021-06-01T14:53:11.935Z,1622559191.935 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2021-06-01T14:53:11.940Z,1622559191.940 [YawRateCalculator] Loaded
2021-06-01T14:53:11.940Z,1622559191.940 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2021-06-01T14:53:11.958Z,1622559191.958 [ElevatorOffsetCalculator] Loaded
2021-06-01T14:53:11.958Z,1622559191.958 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2021-06-01T14:53:11.959Z,1622559191.959 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2021-06-01T14:53:11.959Z,1622559191.959 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2021-06-01T14:53:12.186Z,1622559192.186 [CTD_Seabird] Loaded
2021-06-01T14:53:12.187Z,1622559192.187 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2021-06-01T14:53:12.188Z,1622559192.188 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409974E0
2021-06-01T14:53:12.188Z,1622559192.188 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 919
2021-06-01T14:53:12.208Z,1622559192.208 [PAR_Licor] Loaded
2021-06-01T14:53:12.209Z,1622559192.209 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2021-06-01T14:53:12.242Z,1622559192.242 [WetLabsBB2FL] Loaded
2021-06-01T14:53:12.242Z,1622559192.242 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2021-06-01T14:53:12.243Z,1622559192.243 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409C74E0
2021-06-01T14:53:12.244Z,1622559192.244 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 920
2021-06-01T14:53:12.244Z,1622559192.244 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2021-06-01T14:53:12.245Z,1622559192.245 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2021-06-01T14:53:12.288Z,1622559192.288 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2021-06-01T14:53:12.289Z,1622559192.289 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2021-06-01T14:53:12.689Z,1622559192.689 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2021-06-01T14:53:12.689Z,1622559192.689 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2021-06-01T14:53:12.871Z,1622559192.871 [SBIT](DEBUG): Construct Startup Built In Test.
2021-06-01T14:53:12.880Z,1622559192.880 [SBIT] Loaded
2021-06-01T14:53:12.881Z,1622559192.881 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2021-06-01T14:53:12.883Z,1622559192.883 [IBIT](DEBUG): Construct Initiated Built In Test.
2021-06-01T14:53:12.897Z,1622559192.897 [IBIT] Loaded
2021-06-01T14:53:12.897Z,1622559192.897 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2021-06-01T14:53:12.903Z,1622559192.903 [CBIT](DEBUG): Construct Continuous Built In Test.
2021-06-01T14:53:13.008Z,1622559193.008 [CBIT] Loaded
2021-06-01T14:53:13.008Z,1622559193.008 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2021-06-01T14:53:13.009Z,1622559193.009 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2021-06-01T14:53:13.009Z,1622559193.009 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2021-06-01T14:53:13.131Z,1622559193.131 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2021-06-01T14:53:13.137Z,1622559193.137 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2021-06-01T14:53:13.139Z,1622559193.139 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2021-06-01T14:53:13.150Z,1622559193.150 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2021-06-01T14:53:13.151Z,1622559193.151 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AF94E0
2021-06-01T14:53:13.152Z,1622559193.152 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 921
2021-06-01T14:53:13.156Z,1622559193.156 [Supervisor](INFO): Main Thread ID is 822
2021-06-01T14:53:13.156Z,1622559193.156 [Supervisor](DEBUG): Running supervisor.
2021-06-01T14:53:13.157Z,1622559193.157 [CommandLine ThreadHandler](INFO): Handler Thread ID is 922
2021-06-01T14:53:13.160Z,1622559193.160 [controlThread ThreadHandler](INFO): Handler Thread ID is 923
2021-06-01T14:53:13.160Z,1622559193.160 [controlThread](DEBUG): Initializing ControlThread
2021-06-01T14:53:13.161Z,1622559193.161 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2021-06-01T14:53:13.163Z,1622559193.163 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2021-06-01T14:53:13.164Z,1622559193.164 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2021-06-01T14:53:13.164Z,1622559193.164 [LoopControl](DEBUG): Initialize LoopControlComponent.
2021-06-01T14:53:13.166Z,1622559193.166 [NavChart](DEBUG): Initialize NavChart Navigation.
2021-06-01T14:53:13.167Z,1622559193.167 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2021-06-01T14:53:13.170Z,1622559193.170 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2021-06-01T14:53:13.171Z,1622559193.171 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2021-06-01T14:53:13.171Z,1622559193.171 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2021-06-01T14:53:13.171Z,1622559193.171 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2021-06-01T14:53:13.172Z,1622559193.172 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2021-06-01T14:53:13.172Z,1622559193.172 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2021-06-01T14:53:13.177Z,1622559193.177 [SBIT](INFO): Initialize SBIT Component.
2021-06-01T14:53:13.178Z,1622559193.178 [SBIT](IMPORTANT): git: 2021-06-01
2021-06-01T14:53:13.178Z,1622559193.178 [SBIT](INFO): git hash: cf38c2cb22e5f1a07f3566d8bf81cf524e6bff3d
2021-06-01T14:53:13.178Z,1622559193.178 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2021-06-01T14:53:13.179Z,1622559193.179 [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-01T14:53:13.180Z,1622559193.180 [SBIT](INFO): Beginning SBIT in 57.000000 seconds.
2021-06-01T14:53:13.181Z,1622559193.181 [IBIT](INFO): Initialize IBIT Component.
2021-06-01T14:53:13.182Z,1622559193.182 [CBIT](DEBUG): Initialize CBIT Component.
2021-06-01T14:53:13.183Z,1622559193.183 [logger ThreadHandler](INFO): Handler Thread ID is 924
2021-06-01T14:53:13.197Z,1622559193.197 [CBIT](DEBUG): Initialized mux pins.
2021-06-01T14:53:13.197Z,1622559193.197 [CBIT](DEBUG): Initializing the watchdog timer.
2021-06-01T14:53:13.201Z,1622559193.201 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 925
2021-06-01T14:53:13.202Z,1622559193.202 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2021-06-01T14:53:13.209Z,1622559193.209 [Onboard ThreadHandler](INFO): Handler Thread ID is 926
2021-06-01T14:53:13.221Z,1622559193.221 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2021-06-01T14:53:13.222Z,1622559193.222 [CBIT](DEBUG): Initializing heartbeat.
2021-06-01T14:53:13.230Z,1622559193.230 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 927
2021-06-01T14:53:13.245Z,1622559193.245 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 928
2021-06-01T14:53:13.246Z,1622559193.246 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2021-06-01T14:53:13.250Z,1622559193.250 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 930
2021-06-01T14:53:13.253Z,1622559193.253 [WetLabsBB2FL](INFO): Powering up
2021-06-01T14:53:13.254Z,1622559193.254 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 932
2021-06-01T14:53:13.263Z,1622559193.263 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2021-06-01T14:53:13.263Z,1622559193.263 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2021-06-01T14:53:13.263Z,1622559193.263 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2021-06-01T14:53:13.263Z,1622559193.263 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2021-06-01T14:53:13.264Z,1622559193.264 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2021-06-01T14:53:13.264Z,1622559193.264 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2021-06-01T14:53:13.264Z,1622559193.264 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2021-06-01T14:53:13.264Z,1622559193.264 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2021-06-01T14:53:13.264Z,1622559193.264 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2021-06-01T14:53:13.265Z,1622559193.265 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2021-06-01T14:53:13.265Z,1622559193.265 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2021-06-01T14:53:13.265Z,1622559193.265 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2021-06-01T14:53:13.266Z,1622559193.266 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2021-06-01T14:53:13.266Z,1622559193.266 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2021-06-01T14:53:13.266Z,1622559193.266 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2021-06-01T14:53:13.266Z,1622559193.266 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2021-06-01T14:53:13.293Z,1622559193.293 [CBIT](DEBUG): Deactivating GF circuits.
2021-06-01T14:53:13.293Z,1622559193.293 [CBIT](DEBUG): Deactivating emergency mode.
2021-06-01T14:53:13.329Z,1622559193.329 [CBIT](DEBUG): Backplane powered.
2021-06-01T14:53:13.330Z,1622559193.330 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2021-06-01T14:53:13.366Z,1622559193.366 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2021-06-01T14:53:13.398Z,1622559193.398 [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-01T14:53:13.399Z,1622559193.399 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2021-06-01T14:53:13.467Z,1622559193.467 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2021-06-01T14:53:13.468Z,1622559193.468 [Default:A.Wait](DEBUG): Construct Wait.
2021-06-01T14:53:13.486Z,1622559193.486 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2021-06-01T14:53:13.533Z,1622559193.533 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2021-06-01T14:53:13.536Z,1622559193.536 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2021-06-01T14:53:13.558Z,1622559193.558 [Default:E.Execute](DEBUG): Construct Execute.
2021-06-01T14:53:13.562Z,1622559193.562 [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-01T14:53:13.566Z,1622559193.566 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,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-01T14:53:13.593Z,1622559193.593 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2021-06-01T14:53:13.637Z,1622559193.637 [Radio_Surface](INFO): Powering up
2021-06-01T14:53:13.782Z,1622559193.782 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar
2021-06-01T14:53:13.782Z,1622559193.782 [Power24vConverter](INFO): Powering up.
2021-06-01T14:53:13.783Z,1622559193.783 [AMEcho](INFO): Powering up.
2021-06-01T14:53:13.801Z,1622559193.801 [DAT](INFO): Powering up
2021-06-01T14:53:13.801Z,1622559193.801 [DAT](DEBUG): Initializing DAT.
2021-06-01T14:53:13.907Z,1622559193.907 [DepthRateCalculator](ERROR): Depth measurement is not active
2021-06-01T14:53:13.947Z,1622559193.947 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2021-06-01T14:53:13.953Z,1622559193.953 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2021-06-01T14:53:13.954Z,1622559193.954 [ElevatorServo](DEBUG): Initializing EZServoServo.
2021-06-01T14:53:13.961Z,1622559193.961 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2021-06-01T14:53:13.963Z,1622559193.963 [MassServo](DEBUG): Initializing EZServoServo.
2021-06-01T14:53:13.969Z,1622559193.969 [MassServo](DEBUG): Initializing MassServo.
2021-06-01T14:53:13.970Z,1622559193.970 [RudderServo](DEBUG): Initializing EZServoServo.
2021-06-01T14:53:13.977Z,1622559193.977 [RudderServo](DEBUG): Initializing RudderServo.
2021-06-01T14:53:13.978Z,1622559193.978 [ThrusterServo](DEBUG): Initializing EZServoServo.
2021-06-01T14:53:13.985Z,1622559193.985 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2021-06-01T14:53:14.337Z,1622559194.337 [AMEcho](INFO): Powering down.
2021-06-01T14:53:14.801Z,1622559194.801 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2021-06-01T14:53:14.802Z,1622559194.802 [RudderServo](FAULT): Rudder failed to initialize
2021-06-01T14:53:14.802Z,1622559194.802 [RudderServo] Communications Fault, FailCount= 1
2021-06-01T14:53:14.802Z,1622559194.802 [RudderServo](ERROR): Communications Fault
2021-06-01T14:53:14.905Z,1622559194.905 [CBIT](ERROR): Communications Fault in component: RudderServo
2021-06-01T14:53:14.915Z,1622559194.915 [AMEcho](IMPORTANT): Setting 'depthThreshold' to 20.000000
2021-06-01T14:53:15.070Z,1622559195.070 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2021-06-01T14:53:15.070Z,1622559195.070 [RudderServo](INFO): Powering down
2021-06-01T14:53:15.361Z,1622559195.361 [WetLabsBB2FL](INFO): Powering down
2021-06-01T14:53:15.742Z,1622559195.742 [RudderServo](DEBUG): Initializing EZServoServo.
2021-06-01T14:53:15.862Z,1622559195.862 [RudderServo](DEBUG): Initializing RudderServo.
2021-06-01T14:53:15.866Z,1622559195.866 [CBIT](INFO): Clearing failed state for component RudderServo
2021-06-01T14:53:15.866Z,1622559195.866 [RudderServo] No Fault, FailCount= 1
2021-06-01T14:53:20.278Z,1622559200.278 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2021-06-01T14:53:41.986Z,1622559221.986 [NAL9602](INFO): Powering up NAL9602
2021-06-01T14:53:52.894Z,1622559232.894 [NAL9602](INFO): NAL9602 initialized
2021-06-01T14:54:10.697Z,1622559250.697 [SBIT](IMPORTANT): Beginning Startup BIT
2021-06-01T14:54:10.701Z,1622559250.701 [CBIT](IMPORTANT): Beginning ground fault scan
2021-06-01T14:54:13.911Z,1622559253.911 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-06-01T14:54:13.911Z,1622559253.911 [DAT] Communications Fault, FailCount= 1
2021-06-01T14:54:13.911Z,1622559253.911 [DAT](ERROR): Communications Fault
2021-06-01T14:54:14.054Z,1622559254.054 [CBIT](ERROR): Communications Fault in component: DAT
2021-06-01T14:54:14.343Z,1622559254.343 [DAT](INFO): Powering down
2021-06-01T14:54:14.750Z,1622559254.750 [DAT](FAULT): LCB fault:  Hardware Overcurrent Shutdown. Current Limiter Activated.
2021-06-01T14:54:14.750Z,1622559254.750 [DAT] Hardware Fault, FailCount= 1
2021-06-01T14:54:14.750Z,1622559254.750 [DAT](ERROR): Hardware Fault
2021-06-01T14:54:15.194Z,1622559255.194 [CBIT](INFO): Clearing failed state for component DAT
2021-06-01T14:54:15.194Z,1622559255.194 [DAT] No Fault, FailCount= 1
2021-06-01T14:54:17.563Z,1622559257.563 [DAT](INFO): Powering up
2021-06-01T14:54:17.563Z,1622559257.563 [DAT](DEBUG): Initializing DAT.
2021-06-01T14:54:21.603Z,1622559261.603 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.017492
CHAN A1 (24V): -0.161203
CHAN A2 (12V): -0.002625
CHAN A3 (5V): -0.001356
CHAN B0 (3.3V): 0.000022
CHAN B1 (3.15aV): -0.000131
CHAN B2 (3.15bV): -0.000199
CHAN B3 (GND): 0.000018
OPEN: -0.000351
Full Scale Calc: 4.765 mA, -1.589 mA
2021-06-01T14:54:53.550Z,1622559293.550 [CommandLine](IMPORTANT): got command failComponent
2021-06-01T14:54:53.551Z,1622559293.551 [CommandLine](IMPORTANT): Failed components:
2021-06-01T14:54:53.551Z,1622559293.551 [CommandLine](IMPORTANT): No failed Components.
2021-06-01T14:55:04.858Z,1622559304.858 [SBIT](IMPORTANT): SBIT PASSED
2021-06-01T14:55:04.858Z,1622559304.858 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2021-06-01T14:55:04.859Z,1622559304.859 [SBIT](IMPORTANT): DAT.verbosity=3 count;

2021-06-01T14:55:04.859Z,1622559304.859 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2021-06-01T14:55:04.859Z,1622559304.859 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=282 cubic_centimeter;

2021-06-01T14:55:04.859Z,1622559304.859 [SBIT](IMPORTANT): VerticalControl.massDefault=9.25 millimeter;

2021-06-01T14:55:05.251Z,1622559305.251 [MissionManager](IMPORTANT): Started mission Startup
2021-06-01T14:55:05.251Z,1622559305.251 [Startup] Running Loop=1
2021-06-01T14:55:05.251Z,1622559305.251 [Startup](DEBUG): Aggregate::initialize Startup
2021-06-01T14:55:05.252Z,1622559305.252 [Startup:A.GoToSurface] Running Loop=1
2021-06-01T14:55:05.252Z,1622559305.252 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-06-01T14:55:05.252Z,1622559305.252 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-06-01T14:55:05.253Z,1622559305.253 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-06-01T14:55:05.253Z,1622559305.253 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-06-01T14:55:05.254Z,1622559305.254 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-06-01T14:55:05.254Z,1622559305.254 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-06-01T14:55:05.256Z,1622559305.256 [Startup:StartupSatComms] Running Loop=1
2021-06-01T14:55:05.256Z,1622559305.256 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2021-06-01T14:55:05.256Z,1622559305.256 [Startup:StartupSatComms:A] Running Loop=1
2021-06-01T14:55:05.631Z,1622559305.631 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2021-06-01T14:55:06.026Z,1622559306.026 [AMEcho](INFO): Powering up.
2021-06-01T14:55:17.745Z,1622559317.745 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-06-01T14:55:17.745Z,1622559317.745 [DAT] Communications Fault, FailCount= 2
2021-06-01T14:55:17.745Z,1622559317.745 [DAT](ERROR): Communications Fault
2021-06-01T14:55:17.763Z,1622559317.763 [CBIT](ERROR): Communications Fault in component: DAT
2021-06-01T14:55:18.151Z,1622559318.151 [AMEcho](ERROR): No EchoSounder communication! Re-initializing
2021-06-01T14:55:18.151Z,1622559318.151 [AMEcho] Communications Fault, FailCount= 1
2021-06-01T14:55:18.151Z,1622559318.151 [AMEcho](ERROR): Communications Fault
2021-06-01T14:55:18.152Z,1622559318.152 [DAT](INFO): Powering down
2021-06-01T14:55:18.264Z,1622559318.264 [CBIT](ERROR): Communications Fault in component: AMEcho
2021-06-01T14:55:18.714Z,1622559318.714 [AMEcho](INFO): Powering down.
2021-06-01T14:55:18.968Z,1622559318.968 [CBIT](INFO): Clearing failed state for component DAT
2021-06-01T14:55:18.968Z,1622559318.968 [DAT] No Fault, FailCount= 2
2021-06-01T14:55:19.373Z,1622559319.373 [CBIT](INFO): Clearing failed state for component AMEcho
2021-06-01T14:55:19.373Z,1622559319.373 [AMEcho] No Fault, FailCount= 1
2021-06-01T14:55:19.773Z,1622559319.773 [AMEcho](INFO): Powering up.
2021-06-01T14:55:21.371Z,1622559321.371 [DAT](INFO): Powering up
2021-06-01T14:55:21.371Z,1622559321.371 [DAT](DEBUG): Initializing DAT.
2021-06-01T14:55:31.891Z,1622559331.891 [AMEcho](ERROR): No EchoSounder communication! Re-initializing
2021-06-01T14:55:31.891Z,1622559331.891 [AMEcho] Communications Fault, FailCount= 2
2021-06-01T14:55:31.891Z,1622559331.891 [AMEcho](ERROR): Communications Fault
2021-06-01T14:55:31.938Z,1622559331.938 [CBIT](ERROR): Communications Fault in component: AMEcho
2021-06-01T14:55:32.449Z,1622559332.449 [AMEcho](INFO): Powering down.
2021-06-01T14:55:33.118Z,1622559333.118 [CBIT](INFO): Clearing failed state for component AMEcho
2021-06-01T14:55:33.118Z,1622559333.118 [AMEcho] No Fault, FailCount= 2
2021-06-01T14:55:33.491Z,1622559333.491 [AMEcho](INFO): Powering up.
2021-06-01T14:55:45.631Z,1622559345.631 [AMEcho](ERROR): No EchoSounder communication! Re-initializing
2021-06-01T14:55:45.631Z,1622559345.631 [AMEcho] Communications Fault, FailCount= 3
2021-06-01T14:55:45.631Z,1622559345.631 [AMEcho](ERROR): Communications Fault
2021-06-01T14:55:45.666Z,1622559345.666 [CBIT](ERROR): Communications Fault in component: AMEcho
2021-06-01T14:55:45.667Z,1622559345.667 [CBIT](CRITICAL): Communications Fault in component: AMEcho
2021-06-01T14:55:46.201Z,1622559346.201 [AMEcho](INFO): Powering down.
2021-06-01T14:55:46.236Z,1622559346.236 [CommandLine](FAULT): Scheduling is paused
2021-06-01T14:55:46.241Z,1622559346.241 [CBIT](INFO): Critical error at 20210601T145545
2021-06-01T14:55:46.241Z,1622559346.241 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2021-06-01T14:55:56.279Z,1622559356.279 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004998
2021-06-01T14:49:45.878Z,1622558985.878 [CommandLine](IMPORTANT): got command failComponent
2021-06-01T14:49:45.878Z,1622558985.878 [CommandLine](IMPORTANT): Failed components:
2021-06-01T14:49:45.879Z,1622558985.879 [CommandLine](IMPORTANT): AMEcho: Communications Fault
2021-06-01T14:50:44.919Z,1622559044.919 [DAT](INFO): DAT read: 

2021-06-01T14:50:44.920Z,1622559044.920 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2021-06-01T14:50:46.547Z,1622559046.547 [DAT](INFO): DAT read: MF Frequency Band

2021-06-01T14:50:46.549Z,1622559046.549 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.14.2

2021-06-01T14:50:46.550Z,1622559046.550 [DAT](INFO): DAT read: Jun  1 2021 14:50:15 

2021-06-01T14:50:47.343Z,1622559047.343 [DAT](INFO): DAT read: Features enabled [Bearing]

2021-06-01T14:50:47.345Z,1622559047.345 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP

2021-06-01T14:50:47.345Z,1622559047.345 [DAT](INFO): commRate: 800
2021-06-01T14:50:49.381Z,1622559049.381 [DAT](INFO): entering command mode
2021-06-01T14:50:49.767Z,1622559049.767 [DAT](INFO): DAT read: 

2021-06-01T14:50:49.767Z,1622559049.767 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:50:50.175Z,1622559050.175 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:50:50.581Z,1622559050.581 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:50:51.044Z,1622559051.044 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:50:51.389Z,1622559051.389 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:50:51.799Z,1622559051.799 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:50:52.199Z,1622559052.199 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:50:52.599Z,1622559052.599 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:50:52.999Z,1622559052.999 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:50:53.421Z,1622559053.421 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:50:53.828Z,1622559053.828 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:50:54.229Z,1622559054.229 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:50:54.629Z,1622559054.629 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:50:55.027Z,1622559055.027 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:50:55.423Z,1622559055.423 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:50:55.831Z,1622559055.831 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:50:56.235Z,1622559056.235 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:50:56.641Z,1622559056.641 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:50:57.039Z,1622559057.039 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:50:57.447Z,1622559057.447 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:50:57.852Z,1622559057.852 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:50:58.258Z,1622559058.258 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:50:58.733Z,1622559058.733 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:50:59.097Z,1622559059.097 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:50:59.493Z,1622559059.493 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:50:59.897Z,1622559059.897 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:51:00.275Z,1622559060.275 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:51:00.678Z,1622559060.678 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:51:01.083Z,1622559061.083 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:51:01.483Z,1622559061.483 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:51:01.891Z,1622559061.891 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:51:02.291Z,1622559062.291 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:51:02.699Z,1622559062.699 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:51:03.105Z,1622559063.105 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:51:03.507Z,1622559063.507 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:51:03.921Z,1622559063.921 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:51:04.385Z,1622559064.385 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:51:04.385Z,1622559064.385 [DAT](FAULT): failed to enter command mode
2021-06-01T14:51:04.737Z,1622559064.737 [DAT](INFO): entering command mode
2021-06-01T14:51:05.153Z,1622559065.153 [DAT](INFO): DAT read: user:1>

2021-06-01T14:51:05.154Z,1622559065.154 [DAT](INFO): DAT read: Command '+++' not found

2021-06-01T14:51:05.155Z,1622559065.155 [DAT](INFO): DAT read: Error

2021-06-01T14:51:05.155Z,1622559065.155 [DAT](INFO): setting verbose to 3
2021-06-01T14:51:05.535Z,1622559065.535 [DAT](INFO): DAT read: user:2>

2021-06-01T14:51:05.537Z,1622559065.537 [DAT](INFO): DAT read: Verbose         | 3           

2021-06-01T14:51:05.537Z,1622559065.537 [DAT](INFO): set verbose to 3
2021-06-01T14:51:05.537Z,1622559065.537 [DAT](INFO): setting DatVerbose to 27440
2021-06-01T14:51:05.931Z,1622559065.931 [DAT](INFO): DAT read: user:3>

2021-06-01T14:51:05.932Z,1622559065.932 [DAT](INFO): DAT read: DatVerbose      | 27440       

2021-06-01T14:51:05.933Z,1622559065.933 [DAT](INFO): set DatVerbose to 27440
2021-06-01T14:51:05.933Z,1622559065.933 [DAT](INFO): setting transmit power to 8
2021-06-01T14:51:06.343Z,1622559066.343 [DAT](INFO): DAT read: user:4>

2021-06-01T14:51:06.344Z,1622559066.344 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2021-06-01T14:51:06.344Z,1622559066.344 [DAT](INFO): set transmit power to 8
2021-06-01T14:51:06.345Z,1622559066.345 [DAT](INFO): setting local address to 7
2021-06-01T14:51:06.739Z,1622559066.739 [DAT](INFO): DAT read: user:5>

2021-06-01T14:51:06.740Z,1622559066.740 [DAT](INFO): DAT read: LocalAddr       | 7           

2021-06-01T14:51:06.740Z,1622559066.740 [DAT](INFO): set local address to 7
2021-06-01T14:51:34.518Z,1622559094.518 [CommandLine](IMPORTANT): got command failComponent
2021-06-01T14:51:34.518Z,1622559094.518 [CommandLine](IMPORTANT): Failed components:
2021-06-01T14:51:34.519Z,1622559094.519 [CommandLine](IMPORTANT): AMEcho: Communications Fault
2021-06-01T14:52:42.050Z,1622559162.050 [CommandLine](IMPORTANT): got command failComponent
2021-06-01T14:52:42.050Z,1622559162.050 [CommandLine](IMPORTANT): Failed components:
2021-06-01T14:52:42.050Z,1622559162.050 [CommandLine](IMPORTANT): AMEcho: Communications Fault
2021-06-01T14:52:49.258Z,1622559169.258 [CommandLine](IMPORTANT): got command failComponent none AMEcho
2021-06-01T14:52:49.259Z,1622559169.259 [AMEcho] No Fault, FailCount= 3
2021-06-01T14:52:49.259Z,1622559169.259 [CommandLine](IMPORTANT): AMEcho failureMode is No Fault
2021-06-01T14:52:49.368Z,1622559169.368 [AMEcho](INFO): Powering up.
2021-06-01T14:52:55.014Z,1622559175.014 [CommandLine](IMPORTANT): got command failComponent
2021-06-01T14:52:55.014Z,1622559175.014 [CommandLine](IMPORTANT): Failed components:
2021-06-01T14:52:55.014Z,1622559175.014 [CommandLine](IMPORTANT): No failed Components.
2021-06-01T14:53:18.444Z,1622559198.444 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2021-06-01T14:53:18.447Z,1622559198.447 [BPC1](INFO): Received data from all battery sticks.
2021-06-01T14:54:06.517Z,1622559246.517 [DAT](INFO): DAT read: user:6>Lowpower

2021-06-01T14:54:06.517Z,1622559246.517 [DAT](DEBUG): Re-entering command mode due to deviceResponse_: user:6>Lowpower

2021-06-01T14:54:14.782Z,1622559254.782 [CommandLine](IMPORTANT): got command failComponent
2021-06-01T14:54:14.782Z,1622559254.782 [CommandLine](IMPORTANT): Failed components:
2021-06-01T14:54:14.782Z,1622559254.782 [CommandLine](IMPORTANT): No failed Components.
2021-06-01T14:56:05.307Z,1622559365.307 [Startup:StartupSatComms:A](INFO): Timed out from 2021-06-01T14:55:05.3Z
2021-06-01T14:56:05.307Z,1622559365.307 [Startup:StartupSatComms:A] Stopped
2021-06-01T14:56:05.308Z,1622559365.308 [Startup:StartupSatComms:B] Running Loop=1
2021-06-01T14:56:05.708Z,1622559365.708 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2021-06-01T14:56:10.289Z,1622559370.289 [CommandLine](IMPORTANT): got command failComponent
2021-06-01T14:56:10.289Z,1622559370.289 [CommandLine](IMPORTANT): Failed components:
2021-06-01T14:56:10.289Z,1622559370.289 [CommandLine](IMPORTANT): No failed Components.
2021-06-01T14:56:12.152Z,1622559372.152 [DataOverHttps](INFO): Sending 111 bytes from file Logs/20210601T143819/Courier0004.lzma
2021-06-01T14:56:13.154Z,1622559373.154 [DataOverHttps](INFO): Moved sent file to Logs/20210601T143819/Courier0004.lzma.bak
2021-06-01T14:56:13.154Z,1622559373.154 [DataOverHttps](INFO): SBD MOMSN=15681110
2021-06-01T14:56:13.458Z,1622559373.458 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2021-06-01T14:56:13.458Z,1622559373.458 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T14:56:13.471Z,1622559373.471 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T14:56:13.885Z,1622559373.885 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T14:56:13.885Z,1622559373.885 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2021-06-01T14:56:28.748Z,1622559388.748 [DataOverHttps](INFO): Sending 253 bytes from file Logs/20210601T145301/Courier0000.lzma
2021-06-01T14:56:29.750Z,1622559389.750 [DataOverHttps](INFO): Moved sent file to Logs/20210601T145301/Courier0000.lzma.bak
2021-06-01T14:56:29.750Z,1622559389.750 [DataOverHttps](INFO): SBD MOMSN=15681113
2021-06-01T14:56:46.809Z,1622559406.809 [DataOverHttps](INFO): Sending 346 bytes from file Logs/20210601T143819/Express0005.lzma
2021-06-01T14:56:47.810Z,1622559407.810 [DataOverHttps](INFO): Moved sent file to Logs/20210601T143819/Express0005.lzma.bak
2021-06-01T14:56:47.810Z,1622559407.810 [DataOverHttps](INFO): SBD MOMSN=15681117
2021-06-01T14:57:04.874Z,1622559424.874 [DataOverHttps](INFO): Sending 1122 bytes from file Logs/20210601T145301/Express0001.lzma
2021-06-01T14:57:05.552Z,1622559425.552 [Startup:StartupSatComms:B](INFO): Timed out from 2021-06-01T14:56:05.3Z
2021-06-01T14:57:05.552Z,1622559425.552 [Startup:StartupSatComms:B] Stopped
2021-06-01T14:57:05.552Z,1622559425.552 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2021-06-01T14:57:05.553Z,1622559425.553 [Startup:StartupSatComms] Stopped
2021-06-01T14:57:05.553Z,1622559425.553 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2021-06-01T14:57:05.554Z,1622559425.554 [Startup](INFO): Completed Startup
2021-06-01T14:57:05.554Z,1622559425.554 [MissionManager](INFO): Startup is completed.
2021-06-01T14:57:05.554Z,1622559425.554 [MissionManager](INFO): Uninitializing Mission Startup
2021-06-01T14:57:05.554Z,1622559425.554 [Startup] Stopped
2021-06-01T14:57:05.554Z,1622559425.554 [Startup](DEBUG): Aggregate::uninitialize Startup
2021-06-01T14:57:05.554Z,1622559425.554 [Startup:A.GoToSurface] Stopped
2021-06-01T14:57:05.554Z,1622559425.554 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-06-01T14:57:05.875Z,1622559425.875 [DataOverHttps](INFO): Moved sent file to Logs/20210601T145301/Express0001.lzma.bak
2021-06-01T14:57:05.875Z,1622559425.875 [DataOverHttps](INFO): SBD MOMSN=15681131
2021-06-01T14:57:05.971Z,1622559425.971 [MissionManager](IMPORTANT): Started mission Default
2021-06-01T14:57:05.971Z,1622559425.971 [Default] Running Loop=1
2021-06-01T14:57:05.972Z,1622559425.972 [Default](DEBUG): Aggregate::initialize Default
2021-06-01T14:57:05.972Z,1622559425.972 [Default:B.GoToSurface] Running Loop=1
2021-06-01T14:57:05.972Z,1622559425.972 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-06-01T14:57:05.976Z,1622559425.976 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-06-01T14:57:05.977Z,1622559425.977 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-06-01T14:57:05.977Z,1622559425.977 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-06-01T14:57:05.977Z,1622559425.977 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-06-01T14:57:05.978Z,1622559425.978 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-06-01T14:57:05.978Z,1622559425.978 [Default:A.Wait] Running Loop=1
2021-06-01T14:57:05.978Z,1622559425.978 [Default:A.Wait](DEBUG): Initialize Wait Component.
2021-06-01T14:57:06.536Z,1622559426.536 [AMEcho](INFO): Powering down.
2021-06-01T14:57:07.577Z,1622559427.577 [AMEcho](INFO): Powering up.
2021-06-01T14:57:19.285Z,1622559439.285 [Default:A.Wait](INFO): Done Waiting.
2021-06-01T14:57:19.286Z,1622559439.286 [Default:A.Wait] Stopped
2021-06-01T14:57:19.286Z,1622559439.286 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2021-06-01T14:57:19.689Z,1622559439.689 [AMEcho](ERROR): No EchoSounder communication! Re-initializing
2021-06-01T14:57:19.689Z,1622559439.689 [AMEcho] Communications Fault, FailCount= 1
2021-06-01T14:57:19.689Z,1622559439.689 [AMEcho](ERROR): Communications Fault
2021-06-01T14:57:19.699Z,1622559439.699 [Default:CheckIn] Running Loop=1
2021-06-01T14:57:19.699Z,1622559439.699 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-06-01T14:57:19.699Z,1622559439.699 [Default:CheckIn:Read_GPS] Running Loop=1
2021-06-01T14:57:19.708Z,1622559439.708 [CBIT](ERROR): Communications Fault in component: AMEcho
2021-06-01T14:57:20.256Z,1622559440.256 [AMEcho](INFO): Powering down.
2021-06-01T14:57:20.272Z,1622559440.272 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2021-06-01T14:57:20.917Z,1622559440.917 [CBIT](INFO): Clearing failed state for component AMEcho
2021-06-01T14:57:20.917Z,1622559440.917 [AMEcho] No Fault, FailCount= 1
2021-06-01T14:57:21.305Z,1622559441.305 [AMEcho](INFO): Powering up.
2021-06-01T14:57:33.433Z,1622559453.433 [AMEcho](ERROR): No EchoSounder communication! Re-initializing
2021-06-01T14:57:33.433Z,1622559453.433 [AMEcho] Communications Fault, FailCount= 2
2021-06-01T14:57:33.433Z,1622559453.433 [AMEcho](ERROR): Communications Fault
2021-06-01T14:57:33.490Z,1622559453.490 [CBIT](ERROR): Communications Fault in component: AMEcho
2021-06-01T14:57:34.004Z,1622559454.004 [AMEcho](INFO): Powering down.
2021-06-01T14:57:34.661Z,1622559454.661 [CBIT](INFO): Clearing failed state for component AMEcho
2021-06-01T14:57:34.661Z,1622559454.661 [AMEcho] No Fault, FailCount= 2
2021-06-01T14:57:35.038Z,1622559455.038 [AMEcho](INFO): Powering up.
2021-06-01T14:57:47.159Z,1622559467.159 [AMEcho](ERROR): No EchoSounder communication! Re-initializing
2021-06-01T14:57:47.159Z,1622559467.159 [AMEcho] Communications Fault, FailCount= 3
2021-06-01T14:57:47.159Z,1622559467.159 [AMEcho](ERROR): Communications Fault
2021-06-01T14:57:47.210Z,1622559467.210 [CBIT](ERROR): Communications Fault in component: AMEcho
2021-06-01T14:57:47.210Z,1622559467.210 [CBIT](CRITICAL): Communications Fault in component: AMEcho
2021-06-01T14:57:47.732Z,1622559467.732 [AMEcho](INFO): Powering down.
2021-06-01T14:57:47.749Z,1622559467.749 [CBIT](INFO): Critical error at 20210601T145747
2021-06-01T14:58:56.242Z,1622559536.242 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2021-06-01T14:59:14.426Z,1622559554.426 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2021-06-01T14:59:14.427Z,1622559554.427 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T14:59:14.438Z,1622559554.438 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T14:59:14.851Z,1622559554.851 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T14:59:14.852Z,1622559554.852 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2021-06-01T14:59:17.154Z,1622559557.154 [CommandLine](IMPORTANT): got command failComponent
2021-06-01T14:59:17.154Z,1622559557.154 [CommandLine](IMPORTANT): Failed components:
2021-06-01T14:59:17.154Z,1622559557.154 [CommandLine](IMPORTANT): AMEcho: Communications Fault
2021-06-01T15:00:47.381Z,1622559647.381 [CBIT](INFO): Clearing failed state for component AMEcho
2021-06-01T15:00:47.381Z,1622559647.381 [AMEcho] No Fault, FailCount= 3
2021-06-01T15:00:47.746Z,1622559647.746 [AMEcho](INFO): Powering up.
2021-06-01T15:00:59.464Z,1622559659.464 [AMEcho](INFO): Failure count cleared after critical for AMEcho
2021-06-01T15:01:12.934Z,1622559672.934 [CommandLine](IMPORTANT): got command report touch AMEcho.height_above_sea_floor
2021-06-01T15:01:13.661Z,1622559673.661 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:01:14.452Z,1622559674.452 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:01:15.685Z,1622559675.685 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:01:16.461Z,1622559676.461 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:01:17.667Z,1622559677.667 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:01:18.495Z,1622559678.495 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:01:19.295Z,1622559679.295 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:01:20.491Z,1622559680.491 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:01:21.310Z,1622559681.310 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:01:22.507Z,1622559682.507 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:01:23.335Z,1622559683.335 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:01:24.554Z,1622559684.554 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:01:25.369Z,1622559685.369 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:01:26.569Z,1622559686.569 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:01:27.397Z,1622559687.397 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:01:28.591Z,1622559688.591 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:01:29.383Z,1622559689.383 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:01:30.625Z,1622559690.625 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:01:31.405Z,1622559691.405 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:01:32.656Z,1622559692.656 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:01:33.432Z,1622559693.432 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:01:34.675Z,1622559694.675 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:01:35.466Z,1622559695.466 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:01:36.665Z,1622559696.665 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:01:37.498Z,1622559697.498 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:01:38.292Z,1622559698.292 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:01:39.484Z,1622559699.484 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:01:40.313Z,1622559700.313 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:01:41.653Z,1622559701.653 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:01:42.345Z,1622559702.345 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:01:43.565Z,1622559703.565 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:01:44.371Z,1622559704.371 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:01:45.555Z,1622559705.555 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:01:46.382Z,1622559706.382 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:01:47.574Z,1622559707.574 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:01:48.371Z,1622559708.371 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:01:49.611Z,1622559709.611 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:01:50.394Z,1622559710.394 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:01:51.652Z,1622559711.652 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:01:52.419Z,1622559712.419 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:01:53.638Z,1622559713.638 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:01:54.443Z,1622559714.443 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:01:55.647Z,1622559715.647 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:01:56.480Z,1622559716.480 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:01:57.675Z,1622559717.675 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:01:58.467Z,1622559718.467 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:01:59.299Z,1622559719.299 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:02:00.489Z,1622559720.489 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:02:01.293Z,1622559721.293 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:02:02.525Z,1622559722.525 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:02:03.353Z,1622559723.353 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:02:04.544Z,1622559724.544 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:02:05.354Z,1622559725.354 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:02:06.547Z,1622559726.547 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:02:07.371Z,1622559727.371 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:02:08.585Z,1622559728.585 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:02:09.401Z,1622559729.401 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:02:10.593Z,1622559730.593 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:02:11.423Z,1622559731.423 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:02:12.643Z,1622559732.643 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:02:13.451Z,1622559733.451 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:02:14.661Z,1622559734.661 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:02:15.429Z,1622559735.429 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2021-06-01T15:02:15.429Z,1622559735.429 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T15:02:15.469Z,1622559735.469 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T15:02:15.470Z,1622559735.470 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:02:15.858Z,1622559735.858 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T15:02:15.858Z,1622559735.858 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2021-06-01T15:02:16.647Z,1622559736.647 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:02:17.474Z,1622559737.474 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:02:18.671Z,1622559738.671 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:02:19.494Z,1622559739.494 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:02:19.867Z,1622559739.867 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-06-01T14:57:19.7Z
2021-06-01T15:02:19.868Z,1622559739.868 [Default:CheckIn:Read_GPS] Stopped
2021-06-01T15:02:19.868Z,1622559739.868 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-06-01T15:02:20.289Z,1622559740.289 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2021-06-01T15:02:20.301Z,1622559740.301 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:02:21.529Z,1622559741.529 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:02:22.320Z,1622559742.320 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:02:23.567Z,1622559743.567 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:02:24.372Z,1622559744.372 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:02:25.582Z,1622559745.582 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:02:26.343Z,1622559746.343 [DataOverHttps](INFO): Sending 89 bytes from file Logs/20210601T145301/Courier0004.lzma
2021-06-01T15:02:26.385Z,1622559746.385 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:02:27.383Z,1622559747.383 [DataOverHttps](INFO): Moved sent file to Logs/20210601T145301/Courier0004.lzma.bak
2021-06-01T15:02:27.383Z,1622559747.383 [DataOverHttps](INFO): SBD MOMSN=15681173
2021-06-01T15:02:27.582Z,1622559747.582 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:02:28.439Z,1622559748.439 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:02:29.603Z,1622559749.603 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:02:30.382Z,1622559750.382 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:02:31.618Z,1622559751.618 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:02:32.445Z,1622559752.445 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:02:33.651Z,1622559753.651 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:02:34.472Z,1622559754.472 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:02:35.676Z,1622559755.676 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:02:36.455Z,1622559756.455 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:02:37.660Z,1622559757.660 [Reporter](INFO): AMEcho.height_above_sea_floor 56.230000 m
2021-06-01T15:02:38.489Z,1622559758.489 [Reporter](INFO): AMEcho.height_above_sea_floor 50.380001 m
2021-06-01T15:02:39.681Z,1622559759.681 [Reporter](INFO): AMEcho.height_above_sea_floor 50.230000 m
2021-06-01T15:02:40.517Z,1622559760.517 [Reporter](INFO): AMEcho.height_above_sea_floor 51.130001 m
2021-06-01T15:02:41.348Z,1622559761.348 [Reporter](INFO): AMEcho.height_above_sea_floor 47.529999 m
2021-06-01T15:02:42.553Z,1622559762.553 [Reporter](INFO): AMEcho.height_above_sea_floor 49.029999 m
2021-06-01T15:02:43.335Z,1622559763.335 [Reporter](INFO): AMEcho.height_above_sea_floor 47.080002 m
2021-06-01T15:02:44.496Z,1622559764.496 [DataOverHttps](INFO): Sending 316 bytes from file Logs/20210601T145301/Express0005.lzma
2021-06-01T15:02:44.570Z,1622559764.570 [Reporter](INFO): AMEcho.height_above_sea_floor 47.230000 m
2021-06-01T15:02:45.354Z,1622559765.354 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:02:45.498Z,1622559765.498 [DataOverHttps](INFO): Moved sent file to Logs/20210601T145301/Express0005.lzma.bak
2021-06-01T15:02:45.498Z,1622559765.498 [DataOverHttps](INFO): SBD MOMSN=15681176
2021-06-01T15:02:46.597Z,1622559766.597 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:02:47.391Z,1622559767.391 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:02:48.167Z,1622559768.167 [Default:CheckIn:Read_Iridium] Stopped
2021-06-01T15:02:48.167Z,1622559768.167 [Default:CheckIn:C.Wait] Running Loop=1
2021-06-01T15:02:48.167Z,1622559768.167 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-06-01T15:02:48.595Z,1622559768.595 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:02:49.405Z,1622559769.405 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:02:50.681Z,1622559770.681 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:02:51.435Z,1622559771.435 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:02:52.657Z,1622559772.657 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:02:53.463Z,1622559773.463 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:02:54.638Z,1622559774.638 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:02:55.454Z,1622559775.454 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:02:56.651Z,1622559776.651 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:02:57.490Z,1622559777.490 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:02:58.687Z,1622559778.687 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:02:59.513Z,1622559779.513 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:03:00.318Z,1622559780.318 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:03:01.509Z,1622559781.509 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:03:02.330Z,1622559782.330 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:03:03.538Z,1622559783.538 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:03:04.333Z,1622559784.333 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:03:05.531Z,1622559785.531 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:03:06.358Z,1622559786.358 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:03:07.550Z,1622559787.550 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:03:08.374Z,1622559788.374 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:03:09.610Z,1622559789.610 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:03:10.405Z,1622559790.405 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T15:03:10.858Z,1622559790.858 [CommandLine](IMPORTANT): got command report clear
2021-06-01T15:03:55.202Z,1622559835.202 [NAL9602](FAULT): GPS failed to acquire within timeout.
2021-06-01T15:03:55.202Z,1622559835.202 [NAL9602] Data Fault, FailCount= 1
2021-06-01T15:03:55.203Z,1622559835.203 [NAL9602](ERROR): Data Fault
2021-06-01T15:03:55.259Z,1622559835.259 [CBIT](ERROR): Data Fault in component: NAL9602
2021-06-01T15:03:55.602Z,1622559835.602 [NAL9602](INFO): Powering down
2021-06-01T15:03:56.006Z,1622559836.006 [NAL9602](FAULT): LCB fault:  Software Overcurrent.
2021-06-01T15:03:56.006Z,1622559836.006 [NAL9602] Hardware Fault, FailCount= 1
2021-06-01T15:03:56.006Z,1622559836.006 [NAL9602](ERROR): Hardware Fault
2021-06-01T15:03:56.436Z,1622559836.436 [CBIT](INFO): Clearing failed state for component NAL9602
2021-06-01T15:03:56.436Z,1622559836.436 [NAL9602] No Fault, FailCount= 1
2021-06-01T15:04:25.910Z,1622559865.910 [NAL9602](INFO): Powering up NAL9602
2021-06-01T15:04:36.814Z,1622559876.814 [NAL9602](INFO): NAL9602 initialized
2021-06-01T15:05:16.412Z,1622559916.412 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2021-06-01T15:05:16.412Z,1622559916.412 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T15:05:16.446Z,1622559916.446 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T15:05:16.830Z,1622559916.830 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T15:05:16.830Z,1622559916.830 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2021-06-01T15:07:48.731Z,1622560068.731 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-06-01T15:07:48.731Z,1622560068.731 [Default:CheckIn:C.Wait] Stopped
2021-06-01T15:07:48.731Z,1622560068.731 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-06-01T15:07:48.731Z,1622560068.731 [Default:CheckIn:D] Running Loop=1
2021-06-01T15:07:49.141Z,1622560069.141 [Default:CheckIn:D] Stopped
2021-06-01T15:07:49.141Z,1622560069.141 [Default:CheckIn:E] Running Loop=1
2021-06-01T15:07:49.555Z,1622560069.555 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.719495 min 
2021-06-01T15:07:49.556Z,1622560069.556 [Default:CheckIn:E] Stopped
2021-06-01T15:07:49.556Z,1622560069.556 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-06-01T15:07:49.556Z,1622560069.556 [Default:CheckIn] Stopped
2021-06-01T15:07:49.556Z,1622560069.556 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-06-01T15:07:49.556Z,1622560069.556 [Default:CheckIn](INFO): Running loop #2
2021-06-01T15:07:49.557Z,1622560069.557 [Default:CheckIn] Running Loop=2
2021-06-01T15:07:49.557Z,1622560069.557 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-06-01T15:07:49.557Z,1622560069.557 [Default:CheckIn:Read_GPS] Running Loop=1
2021-06-01T15:08:17.408Z,1622560097.408 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2021-06-01T15:08:17.408Z,1622560097.408 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T15:08:17.428Z,1622560097.428 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T15:08:17.825Z,1622560097.825 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T15:08:17.825Z,1622560097.825 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2021-06-01T15:11:18.419Z,1622560278.419 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2021-06-01T15:11:18.419Z,1622560278.419 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T15:11:18.431Z,1622560278.431 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T15:11:18.840Z,1622560278.840 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T15:11:18.840Z,1622560278.840 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2021-06-01T15:12:49.740Z,1622560369.740 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-06-01T15:07:49.6Z
2021-06-01T15:12:49.741Z,1622560369.741 [Default:CheckIn:Read_GPS] Stopped
2021-06-01T15:12:49.741Z,1622560369.741 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-06-01T15:12:56.972Z,1622560376.972 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20210601T145301/Courier0007.lzma
2021-06-01T15:12:57.974Z,1622560377.974 [DataOverHttps](INFO): Moved sent file to Logs/20210601T145301/Courier0007.lzma.bak
2021-06-01T15:12:57.974Z,1622560377.974 [DataOverHttps](INFO): SBD MOMSN=15681187
2021-06-01T15:13:15.040Z,1622560395.040 [DataOverHttps](INFO): Sending 238 bytes from file Logs/20210601T145301/Express0008.lzma
2021-06-01T15:13:16.042Z,1622560396.042 [DataOverHttps](INFO): Moved sent file to Logs/20210601T145301/Express0008.lzma.bak
2021-06-01T15:13:16.042Z,1622560396.042 [DataOverHttps](INFO): SBD MOMSN=15681189
2021-06-01T15:13:18.830Z,1622560398.830 [Default:CheckIn:Read_Iridium] Stopped
2021-06-01T15:13:18.830Z,1622560398.830 [Default:CheckIn:C.Wait] Running Loop=1
2021-06-01T15:13:18.831Z,1622560398.831 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-06-01T15:14:19.428Z,1622560459.428 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2021-06-01T15:14:19.429Z,1622560459.429 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T15:14:19.440Z,1622560459.440 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T15:14:19.845Z,1622560459.845 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T15:14:19.845Z,1622560459.845 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2021-06-01T15:14:39.206Z,1622560479.206 [NAL9602](FAULT): GPS failed to acquire within timeout.
2021-06-01T15:14:39.206Z,1622560479.206 [NAL9602] Data Fault, FailCount= 2
2021-06-01T15:14:39.206Z,1622560479.206 [NAL9602](ERROR): Data Fault
2021-06-01T15:14:39.226Z,1622560479.226 [CBIT](ERROR): Data Fault in component: NAL9602
2021-06-01T15:14:39.606Z,1622560479.606 [NAL9602](INFO): Powering down
2021-06-01T15:14:40.431Z,1622560480.431 [CBIT](INFO): Clearing failed state for component NAL9602
2021-06-01T15:14:40.431Z,1622560480.431 [NAL9602] No Fault, FailCount= 2
2021-06-01T15:15:09.906Z,1622560509.906 [NAL9602](INFO): Powering up NAL9602
2021-06-01T15:15:20.814Z,1622560520.814 [NAL9602](INFO): NAL9602 initialized
2021-06-01T15:17:20.422Z,1622560640.422 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2021-06-01T15:17:20.422Z,1622560640.422 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T15:17:20.454Z,1622560640.454 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T15:17:20.866Z,1622560640.866 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T15:17:20.866Z,1622560640.866 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2021-06-01T15:18:19.396Z,1622560699.396 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-06-01T15:18:19.397Z,1622560699.397 [Default:CheckIn:C.Wait] Stopped
2021-06-01T15:18:19.397Z,1622560699.397 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-06-01T15:18:19.397Z,1622560699.397 [Default:CheckIn:D] Running Loop=1
2021-06-01T15:18:19.809Z,1622560699.809 [Default:CheckIn:D] Stopped
2021-06-01T15:18:19.810Z,1622560699.810 [Default:CheckIn:E] Running Loop=1
2021-06-01T15:18:20.228Z,1622560700.228 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.230634 min 
2021-06-01T15:18:20.228Z,1622560700.228 [Default:CheckIn:E] Stopped
2021-06-01T15:18:20.228Z,1622560700.228 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-06-01T15:18:20.228Z,1622560700.228 [Default:CheckIn] Stopped
2021-06-01T15:18:20.228Z,1622560700.228 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-06-01T15:18:20.229Z,1622560700.229 [Default:CheckIn](INFO): Running loop #3
2021-06-01T15:18:20.229Z,1622560700.229 [Default:CheckIn] Running Loop=3
2021-06-01T15:18:20.229Z,1622560700.229 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-06-01T15:18:20.229Z,1622560700.229 [Default:CheckIn:Read_GPS] Running Loop=1
2021-06-01T15:20:21.395Z,1622560821.395 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2021-06-01T15:20:21.395Z,1622560821.395 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T15:20:21.415Z,1622560821.415 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T15:20:21.826Z,1622560821.826 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T15:20:21.826Z,1622560821.826 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2021-06-01T15:23:20.370Z,1622561000.370 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-06-01T15:18:20.2Z
2021-06-01T15:23:20.370Z,1622561000.370 [Default:CheckIn:Read_GPS] Stopped
2021-06-01T15:23:20.370Z,1622561000.370 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-06-01T15:23:22.414Z,1622561002.414 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2021-06-01T15:23:22.414Z,1622561002.414 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T15:23:22.458Z,1622561002.458 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T15:23:22.810Z,1622561002.810 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T15:23:22.810Z,1622561002.810 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2021-06-01T15:23:27.292Z,1622561007.292 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20210601T145301/Courier0010.lzma
2021-06-01T15:23:28.294Z,1622561008.294 [DataOverHttps](INFO): Moved sent file to Logs/20210601T145301/Courier0010.lzma.bak
2021-06-01T15:23:28.294Z,1622561008.294 [DataOverHttps](INFO): SBD MOMSN=15681195
2021-06-01T15:23:45.344Z,1622561025.344 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20210601T145301/Express0011.lzma
2021-06-01T15:23:46.346Z,1622561026.346 [DataOverHttps](INFO): Moved sent file to Logs/20210601T145301/Express0011.lzma.bak
2021-06-01T15:23:46.346Z,1622561026.346 [DataOverHttps](INFO): SBD MOMSN=15681197
2021-06-01T15:23:49.066Z,1622561029.066 [Default:CheckIn:Read_Iridium] Stopped
2021-06-01T15:23:49.066Z,1622561029.066 [Default:CheckIn:C.Wait] Running Loop=1
2021-06-01T15:23:49.066Z,1622561029.066 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-06-01T15:25:23.186Z,1622561123.186 [NAL9602](FAULT): GPS failed to acquire within timeout.
2021-06-01T15:25:23.186Z,1622561123.186 [NAL9602] Data Fault, FailCount= 3
2021-06-01T15:25:23.186Z,1622561123.186 [NAL9602](ERROR): Data Fault
2021-06-01T15:25:23.205Z,1622561123.205 [CBIT](ERROR): Data Fault in component: NAL9602
2021-06-01T15:25:23.585Z,1622561123.585 [NAL9602](INFO): Powering down
2021-06-01T15:25:24.417Z,1622561124.417 [CBIT](INFO): Clearing failed state for component NAL9602
2021-06-01T15:25:24.417Z,1622561124.417 [NAL9602] No Fault, FailCount= 3
2021-06-01T15:25:53.890Z,1622561153.890 [NAL9602](INFO): Powering up NAL9602
2021-06-01T15:26:04.806Z,1622561164.806 [NAL9602](INFO): NAL9602 initialized
2021-06-01T15:26:23.410Z,1622561183.410 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2021-06-01T15:26:23.410Z,1622561183.410 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T15:26:23.422Z,1622561183.422 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T15:26:23.825Z,1622561183.825 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T15:26:23.825Z,1622561183.825 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2021-06-01T15:28:49.640Z,1622561329.640 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-06-01T15:28:49.640Z,1622561329.640 [Default:CheckIn:C.Wait] Stopped
2021-06-01T15:28:49.641Z,1622561329.641 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-06-01T15:28:49.641Z,1622561329.641 [Default:CheckIn:D] Running Loop=1
2021-06-01T15:28:50.040Z,1622561330.040 [Default:CheckIn:D] Stopped
2021-06-01T15:28:50.041Z,1622561330.041 [Default:CheckIn:E] Running Loop=1
2021-06-01T15:28:50.463Z,1622561330.463 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.734483 min 
2021-06-01T15:28:50.464Z,1622561330.464 [Default:CheckIn:E] Stopped
2021-06-01T15:28:50.464Z,1622561330.464 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-06-01T15:28:50.464Z,1622561330.464 [Default:CheckIn] Stopped
2021-06-01T15:28:50.464Z,1622561330.464 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-06-01T15:28:50.464Z,1622561330.464 [Default:CheckIn](INFO): Running loop #4
2021-06-01T15:28:50.465Z,1622561330.465 [Default:CheckIn] Running Loop=4
2021-06-01T15:28:50.465Z,1622561330.465 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-06-01T15:28:50.465Z,1622561330.465 [Default:CheckIn:Read_GPS] Running Loop=1
2021-06-01T15:29:24.427Z,1622561364.427 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2021-06-01T15:29:24.427Z,1622561364.427 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T15:29:24.455Z,1622561364.455 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T15:29:24.840Z,1622561364.840 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T15:29:24.840Z,1622561364.840 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2021-06-01T15:29:41.342Z,1622561381.342 [CommandLine](IMPORTANT): got command strobe off
2021-06-01T15:29:41.342Z,1622561381.342 [CommandLine](IMPORTANT): Deactivating strobe
2021-06-01T15:30:11.886Z,1622561411.886 [CommandLine](IMPORTANT): got command failComponent
2021-06-01T15:30:11.887Z,1622561411.887 [CommandLine](IMPORTANT): Failed components:
2021-06-01T15:30:11.887Z,1622561411.887 [CommandLine](IMPORTANT): No failed Components.
2021-06-01T15:31:59.724Z,1622561519.724 [CommandLine](IMPORTANT): got command burn on
2021-06-01T15:31:59.724Z,1622561519.724 [CommandLine](IMPORTANT): Activating dropweight wire
2021-06-01T15:32:09.788Z,1622561529.788 [CommandLine](IMPORTANT): got command burn off
2021-06-01T15:32:09.788Z,1622561529.788 [CommandLine](IMPORTANT): Deactivating dropweight wire
2021-06-01T15:32:25.417Z,1622561545.417 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2021-06-01T15:32:25.417Z,1622561545.417 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T15:32:25.428Z,1622561545.428 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T15:32:25.831Z,1622561545.831 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T15:32:25.831Z,1622561545.831 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2021-06-01T15:33:50.660Z,1622561630.660 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-06-01T15:28:50.5Z
2021-06-01T15:33:50.660Z,1622561630.660 [Default:CheckIn:Read_GPS] Stopped
2021-06-01T15:33:50.661Z,1622561630.661 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-06-01T15:33:57.472Z,1622561637.472 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20210601T145301/Courier0013.lzma
2021-06-01T15:33:58.474Z,1622561638.474 [DataOverHttps](INFO): Moved sent file to Logs/20210601T145301/Courier0013.lzma.bak
2021-06-01T15:33:58.474Z,1622561638.474 [DataOverHttps](INFO): SBD MOMSN=15681202
2021-06-01T15:34:14.577Z,1622561654.577 [DataOverHttps](INFO): Sending 280 bytes from file Logs/20210601T145301/Express0014.lzma
2021-06-01T15:34:15.580Z,1622561655.580 [DataOverHttps](INFO): Moved sent file to Logs/20210601T145301/Express0014.lzma.bak
2021-06-01T15:34:15.580Z,1622561655.580 [DataOverHttps](INFO): SBD MOMSN=15681204
2021-06-01T15:34:18.990Z,1622561658.990 [Default:CheckIn:Read_Iridium] Stopped
2021-06-01T15:34:18.990Z,1622561658.990 [Default:CheckIn:C.Wait] Running Loop=1
2021-06-01T15:34:18.990Z,1622561658.990 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-06-01T15:35:26.489Z,1622561726.489 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2021-06-01T15:35:26.490Z,1622561726.490 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T15:35:26.501Z,1622561726.501 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T15:35:26.921Z,1622561726.921 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T15:35:26.921Z,1622561726.921 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2021-06-01T15:36:05.663Z,1622561765.663 [NAL9602](FAULT): GPS failed to acquire within timeout.
2021-06-01T15:36:05.663Z,1622561765.663 [NAL9602] Data Fault, FailCount= 4
2021-06-01T15:36:05.663Z,1622561765.663 [NAL9602](ERROR): Data Fault
2021-06-01T15:36:05.683Z,1622561765.683 [CBIT](ERROR): Data Fault in component: NAL9602
2021-06-01T15:36:06.065Z,1622561766.065 [NAL9602](INFO): Powering down
2021-06-01T15:36:06.896Z,1622561766.896 [CBIT](INFO): Clearing failed state for component NAL9602
2021-06-01T15:36:06.896Z,1622561766.896 [NAL9602] No Fault, FailCount= 4
2021-06-01T15:36:36.370Z,1622561796.370 [NAL9602](INFO): Powering up NAL9602
2021-06-01T15:36:47.278Z,1622561807.278 [NAL9602](INFO): NAL9602 initialized
2021-06-01T15:38:27.493Z,1622561907.493 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2021-06-01T15:38:27.493Z,1622561907.493 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T15:38:27.515Z,1622561907.515 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T15:38:27.909Z,1622561907.909 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T15:38:27.909Z,1622561907.909 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2021-06-01T15:39:19.595Z,1622561959.595 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-06-01T15:39:19.595Z,1622561959.595 [Default:CheckIn:C.Wait] Stopped
2021-06-01T15:39:19.596Z,1622561959.596 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-06-01T15:39:19.596Z,1622561959.596 [Default:CheckIn:D] Running Loop=1
2021-06-01T15:39:20.013Z,1622561960.013 [Default:CheckIn:D] Stopped
2021-06-01T15:39:20.013Z,1622561960.013 [Default:CheckIn:E] Running Loop=1
2021-06-01T15:39:20.424Z,1622561960.424 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.234029 min 
2021-06-01T15:39:20.424Z,1622561960.424 [Default:CheckIn:E] Stopped
2021-06-01T15:39:20.425Z,1622561960.425 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-06-01T15:39:20.425Z,1622561960.425 [Default:CheckIn] Stopped
2021-06-01T15:39:20.425Z,1622561960.425 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-06-01T15:39:20.425Z,1622561960.425 [Default:CheckIn](INFO): Running loop #5
2021-06-01T15:39:20.425Z,1622561960.425 [Default:CheckIn] Running Loop=5
2021-06-01T15:39:20.425Z,1622561960.425 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-06-01T15:39:20.425Z,1622561960.425 [Default:CheckIn:Read_GPS] Running Loop=1
2021-06-01T15:41:28.494Z,1622562088.494 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2021-06-01T15:41:28.494Z,1622562088.494 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T15:41:28.505Z,1622562088.505 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T15:41:28.907Z,1622562088.907 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T15:41:28.907Z,1622562088.907 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2021-06-01T15:44:20.577Z,1622562260.577 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-06-01T15:39:20.4Z
2021-06-01T15:44:20.577Z,1622562260.577 [Default:CheckIn:Read_GPS] Stopped
2021-06-01T15:44:20.577Z,1622562260.577 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-06-01T15:44:29.384Z,1622562269.384 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20210601T145301/Courier0016.lzma
2021-06-01T15:44:29.470Z,1622562269.470 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17
2021-06-01T15:44:29.470Z,1622562269.470 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T15:44:29.481Z,1622562269.481 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T15:44:29.901Z,1622562269.901 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T15:44:29.901Z,1622562269.901 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17
2021-06-01T15:44:30.386Z,1622562270.386 [DataOverHttps](INFO): Moved sent file to Logs/20210601T145301/Courier0016.lzma.bak
2021-06-01T15:44:30.386Z,1622562270.386 [DataOverHttps](INFO): SBD MOMSN=15681218
2021-06-01T15:44:32.852Z,1622562272.852 [CommandLine](IMPORTANT): got command get platform_battery_charge
2021-06-01T15:44:32.853Z,1622562272.853 [CommandLine](FAULT): Element has no value
2021-06-01T15:44:47.776Z,1622562287.776 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20210601T145301/Express0017.lzma
2021-06-01T15:44:48.778Z,1622562288.778 [DataOverHttps](INFO): Moved sent file to Logs/20210601T145301/Express0017.lzma.bak
2021-06-01T15:44:48.778Z,1622562288.778 [DataOverHttps](INFO): SBD MOMSN=15681220
2021-06-01T15:44:51.306Z,1622562291.306 [Default:CheckIn:Read_Iridium] Stopped
2021-06-01T15:44:51.306Z,1622562291.306 [Default:CheckIn:C.Wait] Running Loop=1
2021-06-01T15:44:51.306Z,1622562291.306 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-06-01T15:46:49.642Z,1622562409.642 [NAL9602](FAULT): GPS failed to acquire within timeout.
2021-06-01T15:46:49.642Z,1622562409.642 [NAL9602] Data Fault, FailCount= 5
2021-06-01T15:46:49.642Z,1622562409.642 [NAL9602](ERROR): Data Fault
2021-06-01T15:46:49.663Z,1622562409.663 [CBIT](ERROR): Data Fault in component: NAL9602
2021-06-01T15:46:49.663Z,1622562409.663 [CBIT](CRITICAL): Data Fault in component: NAL9602
2021-06-01T15:46:50.050Z,1622562410.050 [NAL9602](INFO): Powering down
2021-06-01T15:46:50.089Z,1622562410.089 [CBIT](INFO): Critical error at 20210601T154649
2021-06-01T15:47:30.463Z,1622562450.463 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18
2021-06-01T15:47:30.463Z,1622562450.463 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T15:47:30.476Z,1622562450.476 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T15:47:30.890Z,1622562450.890 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T15:47:30.890Z,1622562450.890 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18
2021-06-01T15:48:49.663Z,1622562529.663 [CBIT](INFO): Clearing failed state for component NAL9602
2021-06-01T15:48:49.664Z,1622562529.664 [NAL9602] No Fault, FailCount= 5
2021-06-01T15:48:50.035Z,1622562530.035 [NAL9602](INFO): Powering up NAL9602
2021-06-01T15:49:00.946Z,1622562540.946 [NAL9602](INFO): NAL9602 initialized
2021-06-01T15:49:51.864Z,1622562591.864 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-06-01T15:49:51.865Z,1622562591.865 [Default:CheckIn:C.Wait] Stopped
2021-06-01T15:49:51.865Z,1622562591.865 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-06-01T15:49:51.865Z,1622562591.865 [Default:CheckIn:D] Running Loop=1
2021-06-01T15:49:52.271Z,1622562592.271 [Default:CheckIn:D] Stopped
2021-06-01T15:49:52.271Z,1622562592.271 [Default:CheckIn:E] Running Loop=1
2021-06-01T15:49:52.699Z,1622562592.699 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.771659 min 
2021-06-01T15:49:52.699Z,1622562592.699 [Default:CheckIn:E] Stopped
2021-06-01T15:49:52.700Z,1622562592.700 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-06-01T15:49:52.700Z,1622562592.700 [Default:CheckIn] Stopped
2021-06-01T15:49:52.700Z,1622562592.700 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-06-01T15:49:52.700Z,1622562592.700 [Default:CheckIn](INFO): Running loop #6
2021-06-01T15:49:52.700Z,1622562592.700 [Default:CheckIn] Running Loop=6
2021-06-01T15:49:52.700Z,1622562592.700 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-06-01T15:49:52.700Z,1622562592.700 [Default:CheckIn:Read_GPS] Running Loop=1
2021-06-01T15:50:31.475Z,1622562631.475 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19
2021-06-01T15:50:31.475Z,1622562631.475 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T15:50:31.536Z,1622562631.536 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T15:50:31.907Z,1622562631.907 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T15:50:31.907Z,1622562631.907 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19
2021-06-01T15:53:32.442Z,1622562812.442 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 20
2021-06-01T15:53:32.442Z,1622562812.442 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T15:53:32.487Z,1622562812.487 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T15:53:32.487Z,1622562812.487 [CBIT](FAULT): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T15:54:52.840Z,1622562892.840 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-06-01T15:49:52.7Z
2021-06-01T15:54:52.841Z,1622562892.841 [Default:CheckIn:Read_GPS] Stopped
2021-06-01T15:54:52.841Z,1622562892.841 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-06-01T15:54:59.748Z,1622562899.748 [DataOverHttps](INFO): Sending 81 bytes from file Logs/20210601T145301/Courier0019.lzma
2021-06-01T15:55:00.750Z,1622562900.750 [DataOverHttps](INFO): Moved sent file to Logs/20210601T145301/Courier0019.lzma.bak
2021-06-01T15:55:00.750Z,1622562900.750 [DataOverHttps](INFO): SBD MOMSN=15681224
2021-06-01T15:55:18.032Z,1622562918.032 [DataOverHttps](INFO): Sending 272 bytes from file Logs/20210601T145301/Express0020.lzma
2021-06-01T15:55:19.034Z,1622562919.034 [DataOverHttps](INFO): Moved sent file to Logs/20210601T145301/Express0020.lzma.bak
2021-06-01T15:55:19.034Z,1622562919.034 [DataOverHttps](INFO): SBD MOMSN=15681227
2021-06-01T15:55:21.979Z,1622562921.979 [Default:CheckIn:Read_Iridium] Stopped
2021-06-01T15:55:21.979Z,1622562921.979 [Default:CheckIn:C.Wait] Running Loop=1
2021-06-01T15:55:21.979Z,1622562921.979 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-06-01T15:55:22.817Z,1622562922.817 [CommandLine](IMPORTANT): got command get platform_battery_charge
2021-06-01T15:55:22.817Z,1622562922.817 [CommandLine](IMPORTANT): platform_battery_charge 131.496002 Ah
2021-06-01T15:56:14.934Z,1622562974.934 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-06-01T15:57:09.419Z,1622563029.419 [BPC1](ERROR): BPC1B failed to parse battery stick message.
2021-06-01T15:59:03.350Z,1622563143.350 [NAL9602](FAULT): GPS failed to acquire within timeout.
2021-06-01T15:59:03.350Z,1622563143.350 [NAL9602] Data Fault, FailCount= 1
2021-06-01T15:59:03.350Z,1622563143.350 [NAL9602](ERROR): Data Fault
2021-06-01T15:59:03.451Z,1622563143.451 [CBIT](ERROR): Data Fault in component: NAL9602
2021-06-01T15:59:03.749Z,1622563143.749 [NAL9602](INFO): Powering down
2021-06-01T15:59:04.649Z,1622563144.649 [CBIT](INFO): Clearing failed state for component NAL9602
2021-06-01T15:59:04.649Z,1622563144.649 [NAL9602] No Fault, FailCount= 1
2021-06-01T15:59:30.095Z,1622563170.095 [CommandLine](IMPORTANT): got command restart application
2021-06-01T15:59:31.099Z,1622563171.099 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2021-06-01T15:59:31.100Z,1622563171.100 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-06-01T15:59:31.235Z,1622563171.235 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2021-06-01T15:59:31.235Z,1622563171.235 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-06-01T15:59:31.236Z,1622563171.236 [CommandLine](INFO): Join timeout helper Thread ID is 1109
2021-06-01T15:59:31.243Z,1622563171.243 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2021-06-01T15:59:31.243Z,1622563171.243 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-06-01T15:59:31.244Z,1622563171.244 [NavChartDb](INFO): Join timeout helper Thread ID is 1110
2021-06-01T15:59:31.467Z,1622563171.467 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2021-06-01T15:59:31.468Z,1622563171.468 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-06-01T15:59:31.475Z,1622563171.475 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2021-06-01T15:59:31.475Z,1622563171.475 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-06-01T15:59:31.476Z,1622563171.476 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1111
2021-06-01T15:59:31.699Z,1622563171.699 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2021-06-01T15:59:31.700Z,1622563171.700 [WetLabsBB2FL](INFO): Powering down
2021-06-01T15:59:31.700Z,1622563171.700 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-06-01T15:59:31.711Z,1622563171.711 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2021-06-01T15:59:31.711Z,1622563171.711 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-06-01T15:59:31.712Z,1622563171.712 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1112
2021-06-01T15:59:31.731Z,1622563171.731 [CTD_Seabird](INFO): Powering down
2021-06-01T15:59:31.747Z,1622563171.747 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2021-06-01T15:59:31.747Z,1622563171.747 [CTD_Seabird](INFO): Powering down
2021-06-01T15:59:31.759Z,1622563171.759 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-06-01T15:59:31.775Z,1622563171.775 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2021-06-01T15:59:31.775Z,1622563171.775 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-06-01T15:59:31.776Z,1622563171.776 [Radio_Surface](INFO): Join timeout helper Thread ID is 1113
2021-06-01T15:59:31.823Z,1622563171.823 [Radio_Surface](INFO): Powering down
2021-06-01T15:59:31.824Z,1622563171.824 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2021-06-01T15:59:31.824Z,1622563171.824 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-06-01T15:59:31.840Z,1622563171.840 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2021-06-01T15:59:31.840Z,1622563171.840 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-06-01T15:59:31.841Z,1622563171.841 [Onboard](INFO): Join timeout helper Thread ID is 1114
2021-06-01T15:59:32.251Z,1622563172.251 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2021-06-01T15:59:34.025Z,1622563174.025 [NAL9602](INFO): Powering up NAL9602
2021-06-01T15:59:34.727Z,1622563174.727 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2021-06-01T15:59:34.728Z,1622563174.728 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-06-01T15:59:34.748Z,1622563174.748 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2021-06-01T15:59:34.748Z,1622563174.748 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-06-01T15:59:34.748Z,1622563174.748 [DataOverHttps](INFO): Join timeout helper Thread ID is 1115
2021-06-01T15:59:35.283Z,1622563175.283 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2021-06-01T15:59:35.284Z,1622563175.284 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-06-01T15:59:35.296Z,1622563175.296 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2021-06-01T15:59:35.296Z,1622563175.296 [logger ThreadHandler](INFO): Thread cancelled.
2021-06-01T15:59:35.297Z,1622563175.297 [logger](INFO): Join timeout helper Thread ID is 1116
2021-06-01T15:59:35.311Z,1622563175.311 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2021-06-01T15:59:35.312Z,1622563175.312 [logger ThreadHandler](INFO): Thread cancelled.
2021-06-01T15:59:35.324Z,1622563175.324 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2021-06-01T15:59:35.324Z,1622563175.324 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-06-01T15:59:35.324Z,1622563175.324 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2021-06-01T15:59:35.324Z,1622563175.324 [controlThread ThreadHandler](INFO): Thread cancelled.
2021-06-01T15:59:35.325Z,1622563175.325 [controlThread](INFO): Join timeout helper Thread ID is 1117
2021-06-01T15:59:35.627Z,1622563175.627 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2021-06-01T15:59:35.628Z,1622563175.628 [controlThread](DEBUG): Uninitializing ControlThread
2021-06-01T15:59:35.628Z,1622563175.628 [AHRS_M2](INFO): Powering down
2021-06-01T15:59:35.700Z,1622563175.700 [NAL9602](INFO): Powering down
2021-06-01T15:59:35.867Z,1622563175.867 [AMEcho](INFO): Powering down.
2021-06-01T15:59:35.868Z,1622563175.868 [DAT](INFO): Powering down
2021-06-01T15:59:35.940Z,1622563175.940 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2021-06-01T15:59:35.941Z,1622563175.941 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2021-06-01T15:59:35.941Z,1622563175.941 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2021-06-01T15:59:35.942Z,1622563175.942 [MissionManager](INFO): Uninitializing Mission Default
2021-06-01T15:59:35.942Z,1622563175.942 [Default] Stopped
2021-06-01T15:59:35.942Z,1622563175.942 [Default](DEBUG): Aggregate::uninitialize Default
2021-06-01T15:59:35.942Z,1622563175.942 [Default:B.GoToSurface] Stopped
2021-06-01T15:59:35.942Z,1622563175.942 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-06-01T15:59:35.942Z,1622563175.942 [Default:CheckIn] Stopped
2021-06-01T15:59:35.943Z,1622563175.943 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-06-01T15:59:35.943Z,1622563175.943 [Default:CheckIn:C.Wait] Stopped
2021-06-01T15:59:35.943Z,1622563175.943 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-06-01T15:59:35.946Z,1622563175.946 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2021-06-01T15:59:35.946Z,1622563175.946 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2021-06-01T15:59:35.947Z,1622563175.947 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2021-06-01T15:59:35.947Z,1622563175.947 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2021-06-01T15:59:35.947Z,1622563175.947 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2021-06-01T15:59:35.947Z,1622563175.947 [BuoyancyServo](INFO): Powering down
2021-06-01T15:59:35.959Z,1622563175.959 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2021-06-01T15:59:35.959Z,1622563175.959 [ElevatorServo](INFO): Powering down
2021-06-01T15:59:35.960Z,1622563175.960 [MassServo](DEBUG): Uninitialize Mass Servo.
2021-06-01T15:59:35.960Z,1622563175.960 [MassServo](INFO): Powering down
2021-06-01T15:59:35.961Z,1622563175.961 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2021-06-01T15:59:35.961Z,1622563175.961 [RudderServo](INFO): Powering down
2021-06-01T15:59:35.962Z,1622563175.962 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2021-06-01T15:59:35.962Z,1622563175.962 [ThrusterServo](INFO): Powering down
2021-06-01T15:59:35.964Z,1622563175.964 [SBIT](DEBUG): Uninitialize SBIT Component.
2021-06-01T15:59:35.964Z,1622563175.964 [IBIT](DEBUG): Uninitialize IBIT Component.
2021-06-01T15:59:35.964Z,1622563175.964 [CBIT](DEBUG): Uninitialize CBIT Component.
2021-06-01T15:59:35.964Z,1622563175.964 [CBIT](DEBUG): Powering off loads.
2021-06-01T15:59:35.975Z,1622563175.975 [CBIT](DEBUG): Disabling WDT.
2021-06-01T15:59:35.987Z,1622563175.987 [CBIT](DEBUG): Opening all GF detection circuits.
2021-06-01T15:59:35.988Z,1622563175.988 [controlThread ThreadHandler](INFO): Thread cancelled.
2021-06-01T15:59:36.043Z,1622563176.043 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-06-01T15:59:36.046Z,1622563176.046 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-06-01T15:59:36.051Z,1622563176.051 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-06-01T15:59:36.123Z,1622563176.123 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-06-01T15:59:36.127Z,1622563176.127 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-06-01T15:59:36.165Z,1622563176.165 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-06-01T15:59:36.230Z,1622563176.230 [logger ThreadHandler](INFO): Thread cancelled.