2021-04-12T04:20:58.788Z,1618201258.788 [Supervisor](DEBUG): Initializing supervisor.
2021-04-12T04:20:58.792Z,1618201258.792 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2021-04-12T04:20:58.793Z,1618201258.793 [SyncHandler](INFO): Protected caller Thread ID is 1739
2021-04-12T04:20:58.793Z,1618201258.793 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2021-04-12T04:20:58.794Z,1618201258.794 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2021-04-12T04:20:58.795Z,1618201258.795 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1740
2021-04-12T04:20:58.799Z,1618201258.799 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2021-04-12T04:20:58.819Z,1618201258.819 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2021-04-12T04:20:58.820Z,1618201258.820 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2021-04-12T04:20:58.820Z,1618201258.820 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1741
2021-04-12T04:20:58.823Z,1618201258.823 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2021-04-12T04:20:58.824Z,1618201258.824 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2021-04-12T04:20:58.824Z,1618201258.824 [logger ThreadHandler](INFO): Protected caller Thread ID is 1742
2021-04-12T04:20:58.828Z,1618201258.828 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2021-04-12T04:20:58.828Z,1618201258.828 [Supervisor](INFO): Looking for Config files in directory: Config/
2021-04-12T04:20:58.830Z,1618201258.830 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2021-04-12T04:20:59.185Z,1618201259.185 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2021-04-12T04:20:59.186Z,1618201259.186 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2021-04-12T04:20:59.826Z,1618201259.826 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2021-04-12T04:20:59.826Z,1618201259.826 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2021-04-12T04:20:59.927Z,1618201259.927 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2021-04-12T04:20:59.928Z,1618201259.928 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2021-04-12T04:21:00.470Z,1618201260.470 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2021-04-12T04:21:00.470Z,1618201260.470 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2021-04-12T04:21:00.567Z,1618201260.567 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2021-04-12T04:21:00.568Z,1618201260.568 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2021-04-12T04:21:00.648Z,1618201260.648 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2021-04-12T04:21:01.021Z,1618201261.021 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2021-04-12T04:21:01.022Z,1618201261.022 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2021-04-12T04:21:01.173Z,1618201261.173 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2021-04-12T04:21:01.174Z,1618201261.174 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2021-04-12T04:21:01.301Z,1618201261.301 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2021-04-12T04:21:01.302Z,1618201261.302 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2021-04-12T04:21:01.543Z,1618201261.543 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2021-04-12T04:21:01.543Z,1618201261.543 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2021-04-12T04:21:02.003Z,1618201262.003 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2021-04-12T04:21:02.004Z,1618201262.004 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2021-04-12T04:21:02.454Z,1618201262.454 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2021-04-12T04:21:02.455Z,1618201262.455 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2021-04-12T04:21:02.534Z,1618201262.534 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2021-04-12T04:21:02.736Z,1618201262.736 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2021-04-12T04:21:02.737Z,1618201262.737 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2021-04-12T04:21:02.951Z,1618201262.951 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2021-04-12T04:21:02.952Z,1618201262.952 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2021-04-12T04:21:03.207Z,1618201263.207 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2021-04-12T04:21:03.209Z,1618201263.209 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/
2021-04-12T04:21:03.210Z,1618201263.210 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Control.cfg
2021-04-12T04:21:03.307Z,1618201263.307 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Sensor.cfg
2021-04-12T04:21:03.455Z,1618201263.455 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Simulator.cfg
2021-04-12T04:21:03.541Z,1618201263.541 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/secure.cfg
2021-04-12T04:21:03.624Z,1618201263.624 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Servo.cfg
2021-04-12T04:21:03.732Z,1618201263.732 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/vehicle.cfg
2021-04-12T04:21:03.963Z,1618201263.963 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Battery.cfg
2021-04-12T04:21:04.416Z,1618201264.416 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2021-04-12T04:21:04.416Z,1618201264.416 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Science.cfg
2021-04-12T04:21:04.744Z,1618201264.744 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/logger.cfg
2021-04-12T04:21:04.845Z,1618201264.845 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/BIT.cfg
2021-04-12T04:21:04.952Z,1618201264.952 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Navigation.cfg
2021-04-12T04:21:05.048Z,1618201265.048 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/root/
2021-04-12T04:21:05.049Z,1618201265.049 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2021-04-12T04:21:05.059Z,1618201265.059 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2021-04-12T04:21:05.145Z,1618201265.145 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2021-04-12T04:21:05.146Z,1618201265.146 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2021-04-12T04:21:05.157Z,1618201265.157 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2021-04-12T04:21:05.157Z,1618201265.157 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2021-04-12T04:21:05.199Z,1618201265.199 [DepthRateCalculator] Loaded
2021-04-12T04:21:05.200Z,1618201265.200 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2021-04-12T04:21:05.205Z,1618201265.205 [PitchRateCalculator] Loaded
2021-04-12T04:21:05.205Z,1618201265.205 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2021-04-12T04:21:05.216Z,1618201265.216 [SpeedCalculator] Loaded
2021-04-12T04:21:05.216Z,1618201265.216 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2021-04-12T04:21:05.229Z,1618201265.229 [TempGradientCalculator] Loaded
2021-04-12T04:21:05.230Z,1618201265.230 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2021-04-12T04:21:05.235Z,1618201265.235 [YawRateCalculator] Loaded
2021-04-12T04:21:05.235Z,1618201265.235 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2021-04-12T04:21:05.256Z,1618201265.256 [ElevatorOffsetCalculator] Loaded
2021-04-12T04:21:05.256Z,1618201265.256 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2021-04-12T04:21:05.256Z,1618201265.256 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2021-04-12T04:21:05.257Z,1618201265.257 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2021-04-12T04:21:05.309Z,1618201265.309 [VerticalControl](DEBUG): Construct VerticalControl.
2021-04-12T04:21:05.367Z,1618201265.367 [VerticalControl] Loaded
2021-04-12T04:21:05.367Z,1618201265.367 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2021-04-12T04:21:05.370Z,1618201265.370 [HorizontalControl](DEBUG): Construct HorizontalControl.
2021-04-12T04:21:05.408Z,1618201265.408 [HorizontalControl] Loaded
2021-04-12T04:21:05.409Z,1618201265.409 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2021-04-12T04:21:05.411Z,1618201265.411 [SpeedControl](DEBUG): Construct SpeedControl.
2021-04-12T04:21:05.414Z,1618201265.414 [SpeedControl] Loaded
2021-04-12T04:21:05.414Z,1618201265.414 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2021-04-12T04:21:05.417Z,1618201265.417 [LoopControl](DEBUG): Construct LoopControl.
2021-04-12T04:21:05.417Z,1618201265.417 [LoopControl] Loaded
2021-04-12T04:21:05.417Z,1618201265.417 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2021-04-12T04:21:05.418Z,1618201265.418 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2021-04-12T04:21:05.418Z,1618201265.418 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2021-04-12T04:21:05.499Z,1618201265.499 [DeadReckonUsingMultipleVelocitySources] Loaded
2021-04-12T04:21:05.500Z,1618201265.500 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2021-04-12T04:21:05.513Z,1618201265.513 [NavChart] Loaded
2021-04-12T04:21:05.513Z,1618201265.513 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2021-04-12T04:21:05.519Z,1618201265.519 [UniversalFixResidualReporter] Loaded
2021-04-12T04:21:05.519Z,1618201265.519 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2021-04-12T04:21:05.520Z,1618201265.520 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2021-04-12T04:21:05.520Z,1618201265.520 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2021-04-12T04:21:05.620Z,1618201265.620 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2021-04-12T04:21:05.620Z,1618201265.620 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2021-04-12T04:21:05.953Z,1618201265.953 [AHRS_M2] Loaded
2021-04-12T04:21:05.953Z,1618201265.953 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2021-04-12T04:21:05.983Z,1618201265.983 [BackseatComponent] Loaded
2021-04-12T04:21:05.983Z,1618201265.983 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2021-04-12T04:21:05.984Z,1618201265.984 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408674E0
2021-04-12T04:21:05.985Z,1618201265.985 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1825
2021-04-12T04:21:05.987Z,1618201265.987 [LcmUniversalReporter] Loaded
2021-04-12T04:21:05.988Z,1618201265.988 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2021-04-12T04:21:06.774Z,1618201266.774 [BPC1] Loaded
2021-04-12T04:21:06.774Z,1618201266.774 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2021-04-12T04:21:06.847Z,1618201266.847 [DataOverHttps] Loaded
2021-04-12T04:21:06.847Z,1618201266.847 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2021-04-12T04:21:06.848Z,1618201266.848 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408974E0
2021-04-12T04:21:06.848Z,1618201266.848 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1826
2021-04-12T04:21:06.868Z,1618201266.868 [Depth_Keller] Loaded
2021-04-12T04:21:06.869Z,1618201266.869 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2021-04-12T04:21:06.873Z,1618201266.873 [DropWeight] Loaded
2021-04-12T04:21:06.873Z,1618201266.873 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2021-04-12T04:21:06.908Z,1618201266.908 [DVL_micro] Loaded
2021-04-12T04:21:06.909Z,1618201266.909 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2021-04-12T04:21:06.968Z,1618201266.968 [NAL9602] Loaded
2021-04-12T04:21:06.969Z,1618201266.969 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2021-04-12T04:21:06.998Z,1618201266.998 [Onboard] Loaded
2021-04-12T04:21:06.998Z,1618201266.998 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2021-04-12T04:21:06.999Z,1618201266.999 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408C74E0
2021-04-12T04:21:06.999Z,1618201266.999 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1827
2021-04-12T04:21:07.013Z,1618201267.013 [Radio_Surface] Loaded
2021-04-12T04:21:07.013Z,1618201267.013 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2021-04-12T04:21:07.014Z,1618201267.014 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408F74E0
2021-04-12T04:21:07.014Z,1618201267.014 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1828
2021-04-12T04:21:07.096Z,1618201267.096 [DAT] Loaded
2021-04-12T04:21:07.097Z,1618201267.097 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2021-04-12T04:21:07.097Z,1618201267.097 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2021-04-12T04:21:07.098Z,1618201267.098 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2021-04-12T04:21:07.210Z,1618201267.210 [BuoyancyServo] Loaded
2021-04-12T04:21:07.210Z,1618201267.210 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2021-04-12T04:21:07.230Z,1618201267.230 [ElevatorServo] Loaded
2021-04-12T04:21:07.230Z,1618201267.230 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2021-04-12T04:21:07.249Z,1618201267.249 [MassServo] Loaded
2021-04-12T04:21:07.250Z,1618201267.250 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2021-04-12T04:21:07.268Z,1618201267.268 [RudderServo] Loaded
2021-04-12T04:21:07.268Z,1618201267.268 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2021-04-12T04:21:07.286Z,1618201267.286 [ThrusterServo] Loaded
2021-04-12T04:21:07.286Z,1618201267.286 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2021-04-12T04:21:07.287Z,1618201267.287 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2021-04-12T04:21:07.287Z,1618201267.287 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2021-04-12T04:21:07.341Z,1618201267.341 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2021-04-12T04:21:07.342Z,1618201267.342 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2021-04-12T04:21:07.370Z,1618201267.370 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2021-04-12T04:21:07.370Z,1618201267.370 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2021-04-12T04:21:07.681Z,1618201267.681 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2021-04-12T04:21:07.682Z,1618201267.682 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2021-04-12T04:21:07.852Z,1618201267.852 [CTD_Seabird] Loaded
2021-04-12T04:21:07.853Z,1618201267.853 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2021-04-12T04:21:07.854Z,1618201267.854 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A7F4E0
2021-04-12T04:21:07.854Z,1618201267.854 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1829
2021-04-12T04:21:07.887Z,1618201267.887 [ESPComponent] Loaded
2021-04-12T04:21:07.887Z,1618201267.887 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2021-04-12T04:21:07.907Z,1618201267.907 [PAR_Licor] Loaded
2021-04-12T04:21:07.907Z,1618201267.907 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2021-04-12T04:21:07.939Z,1618201267.939 [WetLabsBB2FL] Loaded
2021-04-12T04:21:07.940Z,1618201267.940 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2021-04-12T04:21:07.941Z,1618201267.941 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AAF4E0
2021-04-12T04:21:07.941Z,1618201267.941 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1830
2021-04-12T04:21:07.942Z,1618201267.942 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2021-04-12T04:21:07.942Z,1618201267.942 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2021-04-12T04:21:08.085Z,1618201268.085 [SBIT](DEBUG): Construct Startup Built In Test.
2021-04-12T04:21:08.094Z,1618201268.094 [SBIT] Loaded
2021-04-12T04:21:08.094Z,1618201268.094 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2021-04-12T04:21:08.097Z,1618201268.097 [IBIT](DEBUG): Construct Initiated Built In Test.
2021-04-12T04:21:08.110Z,1618201268.110 [IBIT] Loaded
2021-04-12T04:21:08.111Z,1618201268.111 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2021-04-12T04:21:08.117Z,1618201268.117 [CBIT](DEBUG): Construct Continuous Built In Test.
2021-04-12T04:21:08.220Z,1618201268.220 [CBIT] Loaded
2021-04-12T04:21:08.220Z,1618201268.220 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2021-04-12T04:21:08.221Z,1618201268.221 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2021-04-12T04:21:08.227Z,1618201268.227 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2021-04-12T04:21:08.230Z,1618201268.230 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2021-04-12T04:21:08.240Z,1618201268.240 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2021-04-12T04:21:08.242Z,1618201268.242 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B1B4E0
2021-04-12T04:21:08.242Z,1618201268.242 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1831
2021-04-12T04:21:08.247Z,1618201268.247 [Supervisor](INFO): Main Thread ID is 1738
2021-04-12T04:21:08.247Z,1618201268.247 [Supervisor](DEBUG): Running supervisor.
2021-04-12T04:21:08.248Z,1618201268.248 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1832
2021-04-12T04:21:08.250Z,1618201268.250 [controlThread ThreadHandler](INFO): Handler Thread ID is 1833
2021-04-12T04:21:08.251Z,1618201268.251 [controlThread](DEBUG): Initializing ControlThread
2021-04-12T04:21:08.251Z,1618201268.251 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2021-04-12T04:21:08.252Z,1618201268.252 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2021-04-12T04:21:08.252Z,1618201268.252 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2021-04-12T04:21:08.253Z,1618201268.253 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2021-04-12T04:21:08.253Z,1618201268.253 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2021-04-12T04:21:08.254Z,1618201268.254 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2021-04-12T04:21:08.254Z,1618201268.254 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2021-04-12T04:21:08.256Z,1618201268.256 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2021-04-12T04:21:08.257Z,1618201268.257 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2021-04-12T04:21:08.257Z,1618201268.257 [LoopControl](DEBUG): Initialize LoopControlComponent.
2021-04-12T04:21:08.258Z,1618201268.258 [NavChart](DEBUG): Initialize NavChart Navigation.
2021-04-12T04:21:08.258Z,1618201268.258 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2021-04-12T04:21:08.267Z,1618201268.267 [SBIT](INFO): Initialize SBIT Component.
2021-04-12T04:21:08.268Z,1618201268.268 [SBIT](IMPORTANT): git: 2021-04-08-2-gfc5b6cfe3
2021-04-12T04:21:08.268Z,1618201268.268 [SBIT](INFO): git hash: fc5b6cfe3a0a617c2655bbe510b7fa07d5046659
2021-04-12T04:21:08.268Z,1618201268.268 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2021-04-12T04:21:08.268Z,1618201268.268 [SBIT](IMPORTANT): Kernel Version:#1 PREEMPT Tue Sep 24 11:12:27 PDT 2019
2021-04-12T04:21:08.270Z,1618201268.270 [SBIT](INFO): Beginning SBIT in 22.000000 seconds.
2021-04-12T04:21:08.270Z,1618201268.270 [IBIT](INFO): Initialize IBIT Component.
2021-04-12T04:21:08.271Z,1618201268.271 [CBIT](DEBUG): Initialize CBIT Component.
2021-04-12T04:21:08.272Z,1618201268.272 [logger ThreadHandler](INFO): Handler Thread ID is 1834
2021-04-12T04:21:08.283Z,1618201268.283 [CBIT](DEBUG): Initialized mux pins.
2021-04-12T04:21:08.283Z,1618201268.283 [CBIT](DEBUG): Initializing the watchdog timer.
2021-04-12T04:21:08.291Z,1618201268.291 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1835
2021-04-12T04:21:08.303Z,1618201268.303 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1836
2021-04-12T04:21:08.304Z,1618201268.304 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2021-04-12T04:21:08.307Z,1618201268.307 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2021-04-12T04:21:08.308Z,1618201268.308 [CBIT](FAULT): Found backtrace file:
1618201141.974721 [DataOverHttps]: SIGABRT: execution aborted (failed assertion, corrupted heap, or other problem?)
1618201141.974721 [DataOverHttps]: Backtrace:
./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x19f58c]
/lib/libc.so.6 [0x403566f8]


2021-04-12T04:21:08.310Z,1618201268.310 [CBIT](FAULT): Renaming backtrace file to Data/backtrace9
2021-04-12T04:21:08.311Z,1618201268.311 [CBIT](DEBUG): Initializing heartbeat.
2021-04-12T04:21:08.320Z,1618201268.320 [Onboard ThreadHandler](INFO): Handler Thread ID is 1837
2021-04-12T04:21:08.337Z,1618201268.337 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1838
2021-04-12T04:21:08.355Z,1618201268.355 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1839
2021-04-12T04:21:08.356Z,1618201268.356 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2021-04-12T04:21:08.360Z,1618201268.360 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1841
2021-04-12T04:21:08.363Z,1618201268.363 [WetLabsBB2FL](INFO): Powering up
2021-04-12T04:21:08.364Z,1618201268.364 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1843
2021-04-12T04:21:08.367Z,1618201268.367 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2021-04-12T04:21:08.368Z,1618201268.368 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2021-04-12T04:21:08.368Z,1618201268.368 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2021-04-12T04:21:08.368Z,1618201268.368 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2021-04-12T04:21:08.368Z,1618201268.368 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2021-04-12T04:21:08.368Z,1618201268.368 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2021-04-12T04:21:08.369Z,1618201268.369 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2021-04-12T04:21:08.369Z,1618201268.369 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2021-04-12T04:21:08.369Z,1618201268.369 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2021-04-12T04:21:08.369Z,1618201268.369 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2021-04-12T04:21:08.369Z,1618201268.369 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2021-04-12T04:21:08.369Z,1618201268.369 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2021-04-12T04:21:08.369Z,1618201268.369 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2021-04-12T04:21:08.370Z,1618201268.370 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2021-04-12T04:21:08.370Z,1618201268.370 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2021-04-12T04:21:08.370Z,1618201268.370 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2021-04-12T04:21:08.383Z,1618201268.383 [CBIT](DEBUG): Deactivating GF circuits.
2021-04-12T04:21:08.383Z,1618201268.383 [CBIT](DEBUG): Deactivating emergency mode.
2021-04-12T04:21:08.419Z,1618201268.419 [CBIT](DEBUG): Backplane powered.
2021-04-12T04:21:08.420Z,1618201268.420 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2021-04-12T04:21:08.432Z,1618201268.432 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2021-04-12T04:21:08.455Z,1618201268.455 [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-04-12T04:21:08.456Z,1618201268.456 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2021-04-12T04:21:08.529Z,1618201268.529 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2021-04-12T04:21:08.530Z,1618201268.530 [Default:A.Wait](DEBUG): Construct Wait.
2021-04-12T04:21:08.533Z,1618201268.533 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2021-04-12T04:21:08.572Z,1618201268.572 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2021-04-12T04:21:08.575Z,1618201268.575 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2021-04-12T04:21:08.601Z,1618201268.601 [Default:E.Execute](DEBUG): Construct Execute.
2021-04-12T04:21:08.605Z,1618201268.605 [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-04-12T04:21:08.623Z,1618201268.623 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,DAT,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2021-04-12T04:21:08.633Z,1618201268.633 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2021-04-12T04:21:08.710Z,1618201268.710 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2021-04-12T04:21:08.710Z,1618201268.710 [DAT](INFO): Powering up
2021-04-12T04:21:08.715Z,1618201268.715 [DAT](DEBUG): Initializing DAT.
2021-04-12T04:21:08.743Z,1618201268.743 [Radio_Surface](INFO): Powering up
2021-04-12T04:21:08.747Z,1618201268.747 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2021-04-12T04:21:08.833Z,1618201268.833 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2021-04-12T04:21:08.843Z,1618201268.843 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2021-04-12T04:21:08.844Z,1618201268.844 [ElevatorServo](DEBUG): Initializing EZServoServo.
2021-04-12T04:21:08.855Z,1618201268.855 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2021-04-12T04:21:08.856Z,1618201268.856 [MassServo](DEBUG): Initializing EZServoServo.
2021-04-12T04:21:08.870Z,1618201268.870 [MassServo](DEBUG): Initializing MassServo.
2021-04-12T04:21:08.871Z,1618201268.871 [RudderServo](DEBUG): Initializing EZServoServo.
2021-04-12T04:21:08.883Z,1618201268.883 [RudderServo](DEBUG): Initializing RudderServo.
2021-04-12T04:21:08.884Z,1618201268.884 [ThrusterServo](DEBUG): Initializing EZServoServo.
2021-04-12T04:21:08.891Z,1618201268.891 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2021-04-12T04:21:09.767Z,1618201269.767 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2021-04-12T04:21:09.767Z,1618201269.767 [RudderServo](FAULT): Rudder failed to initialize
2021-04-12T04:21:09.767Z,1618201269.767 [RudderServo] Communications Fault, FailCount= 1
2021-04-12T04:21:09.767Z,1618201269.767 [RudderServo](ERROR): Communications Fault
2021-04-12T04:21:09.867Z,1618201269.867 [CBIT](ERROR): Communications Fault in component: RudderServo
2021-04-12T04:21:10.068Z,1618201270.068 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2021-04-12T04:21:10.068Z,1618201270.068 [RudderServo](INFO): Powering down
2021-04-12T04:21:10.698Z,1618201270.698 [RudderServo](DEBUG): Initializing EZServoServo.
2021-04-12T04:21:10.816Z,1618201270.816 [RudderServo](DEBUG): Initializing RudderServo.
2021-04-12T04:21:10.820Z,1618201270.820 [CBIT](INFO): Clearing failed state for component RudderServo
2021-04-12T04:21:10.820Z,1618201270.820 [RudderServo] No Fault, FailCount= 1
2021-04-12T04:21:10.975Z,1618201270.975 [WetLabsBB2FL](INFO): Powering down
2021-04-12T04:21:23.622Z,1618201283.622 [DVL_micro](ERROR): Failed to parse:
:WI,+00516,-02374,+00336,+00000,A
2021-04-12T04:21:30.912Z,1618201290.912 [SBIT](IMPORTANT): Beginning Startup BIT
2021-04-12T04:21:30.916Z,1618201290.916 [CBIT](IMPORTANT): Beginning ground fault scan
2021-04-12T04:21:37.365Z,1618201297.365 [NAL9602](INFO): Powering up NAL9602
2021-04-12T04:21:41.872Z,1618201301.872 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.003106
CHAN A1 (24V): -0.002232
CHAN A2 (12V): -0.000001
CHAN A3 (5V): -0.000189
CHAN B0 (3.3V): 0.001101
CHAN B1 (3.15aV): -0.000096
CHAN B2 (3.15bV): 0.001181
CHAN B3 (GND): 0.000755
OPEN: 0.003705
Full Scale Calc: 4.765 mA, -1.589 mA
2021-04-12T04:21:54.474Z,1618201314.474 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005264
2021-04-12T04:21:54.722Z,1618201314.722 [DAT](INFO): unknown deviceResponse_: Lowpower

2021-04-12T04:21:57.953Z,1618201317.953 [NAL9602](ERROR): NAL9602 initialization error.
2021-04-12T04:21:57.953Z,1618201317.953 [NAL9602] Communications Fault, FailCount= 1
2021-04-12T04:21:57.953Z,1618201317.953 [NAL9602](ERROR): Communications Fault
2021-04-12T04:21:58.032Z,1618201318.032 [CBIT](ERROR): Communications Fault in component: NAL9602
2021-04-12T04:21:58.357Z,1618201318.357 [NAL9602](INFO): Powering down
2021-04-12T04:21:59.272Z,1618201319.272 [CBIT](INFO): Clearing failed state for component NAL9602
2021-04-12T04:21:59.272Z,1618201319.272 [NAL9602] No Fault, FailCount= 1
2021-04-12T04:22:08.905Z,1618201328.905 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-04-12T04:22:08.905Z,1618201328.905 [DAT] Communications Fault, FailCount= 1
2021-04-12T04:22:08.905Z,1618201328.905 [DAT](ERROR): Communications Fault
2021-04-12T04:22:09.032Z,1618201329.032 [CBIT](ERROR): Communications Fault in component: DAT
2021-04-12T04:22:09.338Z,1618201329.338 [DAT](INFO): Powering down
2021-04-12T04:22:10.157Z,1618201330.157 [CBIT](INFO): Clearing failed state for component DAT
2021-04-12T04:22:10.157Z,1618201330.157 [DAT] No Fault, FailCount= 1
2021-04-12T04:22:12.542Z,1618201332.542 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2021-04-12T04:22:12.543Z,1618201332.543 [DAT](INFO): Powering up
2021-04-12T04:22:12.543Z,1618201332.543 [DAT](DEBUG): Initializing DAT.
2021-04-12T04:22:12.610Z,1618201332.610 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2021-04-12T04:22:12.949Z,1618201332.949 [DVL_micro](ERROR): only read 3 of 4 data items
2021-04-12T04:22:12.949Z,1618201332.949 [DVL_micro](ERROR): Failed to parse:
:BI,+0133,+00844,+00000,I
2021-04-12T04:22:20.290Z,1618201340.290 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:22:25.082Z,1618201345.082 [SBIT](IMPORTANT): SBIT PASSED
2021-04-12T04:22:25.082Z,1618201345.082 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2021-04-12T04:22:25.083Z,1618201345.083 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count;

2021-04-12T04:22:25.083Z,1618201345.083 [SBIT](IMPORTANT): BackseatComponent.loadAtStartup=1 bool;

2021-04-12T04:22:25.083Z,1618201345.083 [SBIT](IMPORTANT): BackseatComponent.simulateHardware=1 bool;

2021-04-12T04:22:25.083Z,1618201345.083 [SBIT](IMPORTANT): DAT.sbdAddress=-1 enum;

2021-04-12T04:22:25.083Z,1618201345.083 [SBIT](IMPORTANT): DAT.surfaceThreshold=-0.5 meter;

2021-04-12T04:22:25.083Z,1618201345.083 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=1 bool;

2021-04-12T04:22:25.084Z,1618201345.084 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=1 minute;

2021-04-12T04:22:25.084Z,1618201345.084 [SBIT](IMPORTANT): ESPComponent.simulateHardware=1 bool;

2021-04-12T04:22:25.084Z,1618201345.084 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=194.169302 cubic_centimeter;

2021-04-12T04:22:25.084Z,1618201345.084 [SBIT](IMPORTANT): VerticalControl.massDefault=-0.743969 millimeter;

2021-04-12T04:22:25.490Z,1618201345.490 [MissionManager](IMPORTANT): Started mission Startup
2021-04-12T04:22:25.490Z,1618201345.490 [Startup] Running Loop=1
2021-04-12T04:22:25.490Z,1618201345.490 [Startup](DEBUG): Aggregate::initialize Startup
2021-04-12T04:22:25.490Z,1618201345.490 [Startup:A.GoToSurface] Running Loop=1
2021-04-12T04:22:25.490Z,1618201345.490 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-04-12T04:22:25.491Z,1618201345.491 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-04-12T04:22:25.491Z,1618201345.491 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-04-12T04:22:25.492Z,1618201345.492 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-04-12T04:22:25.492Z,1618201345.492 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-04-12T04:22:25.493Z,1618201345.493 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-04-12T04:22:25.494Z,1618201345.494 [Startup:StartupSatComms] Running Loop=1
2021-04-12T04:22:25.494Z,1618201345.494 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2021-04-12T04:22:25.494Z,1618201345.494 [Startup:StartupSatComms:A] Running Loop=1
2021-04-12T04:22:25.900Z,1618201345.900 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2021-04-12T04:22:28.701Z,1618201348.701 [NAL9602](INFO): Powering up NAL9602
2021-04-12T04:22:31.000Z,1618201352.000 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:22:39.609Z,1618201359.609 [NAL9602](INFO): NAL9602 initialized
2021-04-12T04:22:44.115Z,1618201364.115 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:22:55.853Z,1618201375.853 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:23:03.486Z,1618201383.486 [DVL_micro](ERROR): Failed to parse:
:W,-01225,+0000,A
2021-04-12T04:23:12.755Z,1618201392.755 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-04-12T04:23:12.755Z,1618201392.755 [DAT] Communications Fault, FailCount= 2
2021-04-12T04:23:12.755Z,1618201392.755 [DAT](ERROR): Communications Fault
2021-04-12T04:23:12.778Z,1618201392.778 [CBIT](ERROR): Communications Fault in component: DAT
2021-04-12T04:23:13.141Z,1618201393.141 [DAT](INFO): Powering down
2021-04-12T04:23:13.979Z,1618201393.979 [CBIT](INFO): Clearing failed state for component DAT
2021-04-12T04:23:13.979Z,1618201393.979 [DAT] No Fault, FailCount= 2
2021-04-12T04:23:16.401Z,1618201396.401 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2021-04-12T04:23:16.401Z,1618201396.401 [DAT](INFO): Powering up
2021-04-12T04:23:16.401Z,1618201396.401 [DAT](DEBUG): Initializing DAT.
2021-04-12T04:23:16.435Z,1618201396.435 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2021-04-12T04:23:25.689Z,1618201405.689 [Startup:StartupSatComms:A](INFO): Timed out from 2021-04-12T04:22:25.5Z
2021-04-12T04:23:25.689Z,1618201405.689 [Startup:StartupSatComms:A] Stopped
2021-04-12T04:23:25.689Z,1618201405.689 [Startup:StartupSatComms:B] Running Loop=1
2021-04-12T04:23:26.081Z,1618201406.081 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2021-04-12T04:23:32.647Z,1618201412.647 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20210412T042058/Courier0000.lzma
2021-04-12T04:23:33.649Z,1618201413.649 [DataOverHttps](INFO): Moved sent file to Logs/20210412T042058/Courier0000.lzma.bak
2021-04-12T04:23:33.649Z,1618201413.649 [DataOverHttps](INFO): SBD MOMSN=15558626
2021-04-12T04:23:49.183Z,1618201429.183 [DataOverHttps](INFO): Sending 1152 bytes from file Logs/20210411T182147/Express0005.lzma
2021-04-12T04:23:50.181Z,1618201430.181 [DataOverHttps](INFO): Moved sent file to Logs/20210411T182147/Express0005.lzma.bak
2021-04-12T04:23:50.181Z,1618201430.181 [DataOverHttps](INFO): SBD MOMSN=15558629
2021-04-12T04:24:05.734Z,1618201445.734 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20210411T183045/Express0001.lzma
2021-04-12T04:24:06.737Z,1618201446.737 [DataOverHttps](INFO): Moved sent file to Logs/20210411T183045/Express0001.lzma.bak
2021-04-12T04:24:06.737Z,1618201446.737 [DataOverHttps](INFO): SBD MOMSN=15558725
2021-04-12T04:24:08.664Z,1618201448.664 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2021-04-12T04:24:08.664Z,1618201448.664 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-04-12T04:24:08.711Z,1618201448.711 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-04-12T04:24:08.946Z,1618201448.946 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-04-12T04:24:08.946Z,1618201448.946 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2021-04-12T04:24:16.569Z,1618201456.569 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-04-12T04:24:16.570Z,1618201456.570 [DAT] Communications Fault, FailCount= 3
2021-04-12T04:24:16.570Z,1618201456.570 [DAT](ERROR): Communications Fault
2021-04-12T04:24:16.586Z,1618201456.586 [CBIT](ERROR): Communications Fault in component: DAT
2021-04-12T04:24:16.979Z,1618201456.979 [DAT](INFO): Powering down
2021-04-12T04:24:17.797Z,1618201457.797 [CBIT](INFO): Clearing failed state for component DAT
2021-04-12T04:24:17.797Z,1618201457.797 [DAT] No Fault, FailCount= 3
2021-04-12T04:24:20.233Z,1618201460.233 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2021-04-12T04:24:20.233Z,1618201460.233 [DAT](INFO): Powering up
2021-04-12T04:24:20.233Z,1618201460.233 [DAT](DEBUG): Initializing DAT.
2021-04-12T04:24:20.327Z,1618201460.327 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2021-04-12T04:24:23.466Z,1618201463.466 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20210412T034655/Express0001.lzma
2021-04-12T04:24:24.468Z,1618201464.468 [DataOverHttps](INFO): Moved sent file to Logs/20210412T034655/Express0001.lzma.bak
2021-04-12T04:24:24.469Z,1618201464.469 [DataOverHttps](INFO): SBD MOMSN=15558727
2021-04-12T04:24:25.897Z,1618201465.897 [Startup:StartupSatComms:B](INFO): Timed out from 2021-04-12T04:23:25.7Z
2021-04-12T04:24:25.897Z,1618201465.897 [Startup:StartupSatComms:B] Stopped
2021-04-12T04:24:25.897Z,1618201465.897 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2021-04-12T04:24:25.897Z,1618201465.897 [Startup:StartupSatComms] Stopped
2021-04-12T04:24:25.897Z,1618201465.897 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2021-04-12T04:24:25.898Z,1618201465.898 [Startup](INFO): Completed Startup
2021-04-12T04:24:25.898Z,1618201465.898 [MissionManager](INFO): Startup is completed.
2021-04-12T04:24:25.898Z,1618201465.898 [MissionManager](INFO): Uninitializing Mission Startup
2021-04-12T04:24:25.898Z,1618201465.898 [Startup] Stopped
2021-04-12T04:24:25.903Z,1618201465.903 [Startup](DEBUG): Aggregate::uninitialize Startup
2021-04-12T04:24:25.903Z,1618201465.903 [Startup:A.GoToSurface] Stopped
2021-04-12T04:24:25.903Z,1618201465.903 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-04-12T04:24:26.300Z,1618201466.300 [MissionManager](IMPORTANT): Started mission Default
2021-04-12T04:24:26.301Z,1618201466.301 [Default] Running Loop=1
2021-04-12T04:24:26.301Z,1618201466.301 [Default](DEBUG): Aggregate::initialize Default
2021-04-12T04:24:26.301Z,1618201466.301 [Default:B.GoToSurface] Running Loop=1
2021-04-12T04:24:26.301Z,1618201466.301 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-04-12T04:24:26.301Z,1618201466.301 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-04-12T04:24:26.302Z,1618201466.302 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-04-12T04:24:26.302Z,1618201466.302 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-04-12T04:24:26.302Z,1618201466.302 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-04-12T04:24:26.335Z,1618201466.335 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-04-12T04:24:26.335Z,1618201466.335 [Default:A.Wait] Running Loop=1
2021-04-12T04:24:26.335Z,1618201466.335 [Default:A.Wait](DEBUG): Initialize Wait Component.
2021-04-12T04:24:39.617Z,1618201479.617 [Default:A.Wait](INFO): Done Waiting.
2021-04-12T04:24:39.617Z,1618201479.617 [Default:A.Wait] Stopped
2021-04-12T04:24:39.617Z,1618201479.617 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2021-04-12T04:24:40.010Z,1618201480.010 [Default:CheckIn] Running Loop=1
2021-04-12T04:24:40.010Z,1618201480.010 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-12T04:24:40.010Z,1618201480.010 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-12T04:24:40.422Z,1618201480.422 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2021-04-12T04:25:06.760Z,1618201506.760 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:25:08.056Z,1618201508.056 [DataOverHttps](INFO): Sending 437 bytes from file Logs/20210412T034815/Express0001.lzma
2021-04-12T04:25:09.056Z,1618201509.056 [DataOverHttps](INFO): Moved sent file to Logs/20210412T034815/Express0001.lzma.bak
2021-04-12T04:25:09.056Z,1618201509.056 [DataOverHttps](INFO): SBD MOMSN=15558729
2021-04-12T04:25:20.410Z,1618201520.410 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-04-12T04:25:20.410Z,1618201520.410 [DAT] Communications Fault, FailCount= 4
2021-04-12T04:25:20.410Z,1618201520.410 [DAT](ERROR): Communications Fault
2021-04-12T04:25:20.455Z,1618201520.455 [CBIT](ERROR): Communications Fault in component: DAT
2021-04-12T04:25:20.826Z,1618201520.826 [DAT](INFO): Powering down
2021-04-12T04:25:21.663Z,1618201521.663 [CBIT](INFO): Clearing failed state for component DAT
2021-04-12T04:25:21.664Z,1618201521.664 [DAT] No Fault, FailCount= 4
2021-04-12T04:25:24.053Z,1618201524.053 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2021-04-12T04:25:24.053Z,1618201524.053 [DAT](INFO): Powering up
2021-04-12T04:25:24.053Z,1618201524.053 [DAT](DEBUG): Initializing DAT.
2021-04-12T04:25:24.123Z,1618201524.123 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2021-04-12T04:25:49.948Z,1618201549.948 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:26:21.453Z,1618201581.453 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-12T04:26:24.247Z,1618201584.247 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-04-12T04:26:24.247Z,1618201584.247 [DAT] Communications Fault, FailCount= 5
2021-04-12T04:26:24.247Z,1618201584.247 [DAT](ERROR): Communications Fault
2021-04-12T04:26:24.294Z,1618201584.294 [CBIT](ERROR): Communications Fault in component: DAT
2021-04-12T04:26:24.663Z,1618201584.663 [DAT](INFO): Powering down
2021-04-12T04:26:25.482Z,1618201585.482 [CBIT](INFO): Clearing failed state for component DAT
2021-04-12T04:26:25.482Z,1618201585.482 [DAT] No Fault, FailCount= 5
2021-04-12T04:26:27.867Z,1618201587.867 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2021-04-12T04:26:27.867Z,1618201587.867 [DAT](INFO): Powering up
2021-04-12T04:26:27.867Z,1618201587.867 [DAT](DEBUG): Initializing DAT.
2021-04-12T04:26:27.935Z,1618201587.935 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2021-04-12T04:26:33.176Z,1618201593.176 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:27:00.641Z,1618201620.641 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:27:05.704Z,1618201625.704 [CommandLine](IMPORTANT): got command failComponent
2021-04-12T04:27:05.704Z,1618201625.704 [CommandLine](IMPORTANT): Failed components:
2021-04-12T04:27:05.705Z,1618201625.705 [CommandLine](IMPORTANT): No failed Components.
2021-04-12T04:27:09.488Z,1618201629.488 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2021-04-12T04:27:09.488Z,1618201629.488 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-04-12T04:27:09.498Z,1618201629.498 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-04-12T04:27:09.897Z,1618201629.897 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-04-12T04:27:09.897Z,1618201629.897 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2021-04-12T04:27:13.970Z,1618201633.970 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-12T04:27:22.959Z,1618201642.959 [CommandLine](IMPORTANT): got command show stack
2021-04-12T04:27:22.959Z,1618201642.959 [CommandLine](IMPORTANT): Behavior Stack:

2021-04-12T04:27:22.960Z,1618201642.960 [Default](IMPORTANT): Priority 0: Default:B.GoToSurface
2021-04-12T04:27:22.960Z,1618201642.960 [Default:CheckIn](IMPORTANT): Priority 1: Default:CheckIn:Read_GPS
2021-04-12T04:27:28.083Z,1618201648.083 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-04-12T04:27:28.083Z,1618201648.083 [DAT] Communications Fault, FailCount= 6
2021-04-12T04:27:28.083Z,1618201648.083 [DAT](ERROR): Communications Fault
2021-04-12T04:27:28.168Z,1618201648.168 [CBIT](ERROR): Communications Fault in component: DAT
2021-04-12T04:27:28.543Z,1618201648.543 [DAT](INFO): Powering down
2021-04-12T04:27:29.379Z,1618201649.379 [CBIT](INFO): Clearing failed state for component DAT
2021-04-12T04:27:29.379Z,1618201649.379 [DAT] No Fault, FailCount= 6
2021-04-12T04:27:30.428Z,1618201650.428 [CommandLine](IMPORTANT): got command show 
2021-04-12T04:27:30.429Z,1618201650.429 [CommandLine](FAULT): Incomplete syntax. Try: help show
2021-04-12T04:27:31.703Z,1618201651.703 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2021-04-12T04:27:31.703Z,1618201651.703 [DAT](INFO): Powering up
2021-04-12T04:27:31.704Z,1618201651.704 [DAT](DEBUG): Initializing DAT.
2021-04-12T04:27:31.735Z,1618201651.735 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2021-04-12T04:27:36.484Z,1618201656.484 [CommandLine](IMPORTANT): got command help show
2021-04-12T04:27:42.665Z,1618201662.665 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-12T04:27:42.668Z,1618201662.668 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2021-04-12T04:28:01.980Z,1618201681.980 [CommandLine](IMPORTANT): got command show state
2021-04-12T04:28:01.980Z,1618201681.980 [CommandLine](IMPORTANT): Mission State:

2021-04-12T04:28:01.980Z,1618201681.980 [CommandLine](INFO): Startup->null/0
2021-04-12T04:28:01.981Z,1618201681.981 [CommandLine](INFO): Startup:StartupSatComms->null/0
2021-04-12T04:28:01.981Z,1618201681.981 [CommandLine](INFO): Default->Default:CheckIn/0
2021-04-12T04:28:01.981Z,1618201681.981 [CommandLine](INFO): Default:CheckIn->Default:CheckIn:Read_GPS/0
2021-04-12T04:28:14.772Z,1618201694.772 [CommandLine](IMPORTANT): got command show stack
2021-04-12T04:28:14.773Z,1618201694.773 [CommandLine](IMPORTANT): Behavior Stack:

2021-04-12T04:28:14.773Z,1618201694.773 [Default](IMPORTANT): Priority 0: Default:B.GoToSurface
2021-04-12T04:28:14.773Z,1618201694.773 [Default:CheckIn](IMPORTANT): Priority 1: Default:CheckIn:Read_GPS
2021-04-12T04:28:31.904Z,1618201711.904 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-04-12T04:28:31.904Z,1618201711.904 [DAT] Communications Fault, FailCount= 7
2021-04-12T04:28:31.904Z,1618201711.904 [DAT](ERROR): Communications Fault
2021-04-12T04:28:31.969Z,1618201711.969 [CBIT](ERROR): Communications Fault in component: DAT
2021-04-12T04:28:32.297Z,1618201712.297 [DAT](INFO): Powering down
2021-04-12T04:28:33.151Z,1618201713.151 [CBIT](INFO): Clearing failed state for component DAT
2021-04-12T04:28:33.151Z,1618201713.151 [DAT] No Fault, FailCount= 7
2021-04-12T04:28:35.549Z,1618201715.549 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2021-04-12T04:28:35.549Z,1618201715.549 [DAT](INFO): Powering up
2021-04-12T04:28:35.549Z,1618201715.549 [DAT](DEBUG): Initializing DAT.
2021-04-12T04:28:35.647Z,1618201715.647 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2021-04-12T04:28:47.772Z,1618201727.772 [CommandLine](IMPORTANT): got command failComponent 
2021-04-12T04:28:47.773Z,1618201727.773 [CommandLine](IMPORTANT): Failed components:
2021-04-12T04:28:47.773Z,1618201727.773 [CommandLine](IMPORTANT): No failed Components.
2021-04-12T04:29:18.485Z,1618201758.485 [CommandLine](IMPORTANT): got command show variable shore
2021-04-12T04:29:18.488Z,1618201758.488 [CommandLine](IMPORTANT): distance_from_shore (unknown)
2021-04-12T04:29:18.543Z,1618201758.543 [CommandLine](IMPORTANT): Vehicle.sendDataToShore (bool)
2021-04-12T04:29:18.581Z,1618201758.581 [CommandLine](IMPORTANT): NavChart.distance_from_shore (meter)
2021-04-12T04:29:18.605Z,1618201758.605 [CommandLine](IMPORTANT): CBIT.shorePowerOn (bool)
2021-04-12T04:29:32.040Z,1618201772.040 [CommandLine](IMPORTANT): got command get CBIT.shorePowerOn
2021-04-12T04:29:32.041Z,1618201772.041 [CommandLine](IMPORTANT): CBIT.shorePowerOn 1 bool
2021-04-12T04:29:35.776Z,1618201775.776 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:29:35.791Z,1618201775.791 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-04-12T04:29:35.791Z,1618201775.791 [DAT] Communications Fault, FailCount= 8
2021-04-12T04:29:35.791Z,1618201775.791 [DAT](ERROR): Communications Fault
2021-04-12T04:29:35.846Z,1618201775.846 [CBIT](ERROR): Communications Fault in component: DAT
2021-04-12T04:29:35.851Z,1618201775.851 [CBIT](FAULT): Communications Fault in component: DAT
2021-04-12T04:29:36.163Z,1618201776.163 [DAT](INFO): Powering down
2021-04-12T04:29:38.961Z,1618201778.961 [BPC1](ERROR): BPC1B: No match for serial number FFFF in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-04-12T04:29:40.180Z,1618201780.180 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-04-12T04:24:40.0Z
2021-04-12T04:29:40.180Z,1618201780.180 [Default:CheckIn:Read_GPS] Stopped
2021-04-12T04:29:40.181Z,1618201780.181 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-12T04:29:40.591Z,1618201780.591 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2021-04-12T04:29:47.022Z,1618201787.022 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20210412T042058/Courier0004.lzma
2021-04-12T04:29:48.024Z,1618201788.024 [DataOverHttps](INFO): Moved sent file to Logs/20210412T042058/Courier0004.lzma.bak
2021-04-12T04:29:48.025Z,1618201788.025 [DataOverHttps](INFO): SBD MOMSN=15558738
2021-04-12T04:30:03.831Z,1618201803.831 [CommandLine](IMPORTANT): got command show state
2021-04-12T04:30:03.831Z,1618201803.831 [CommandLine](IMPORTANT): Mission State:

2021-04-12T04:30:03.832Z,1618201803.832 [CommandLine](INFO): Startup->null/0
2021-04-12T04:30:03.832Z,1618201803.832 [CommandLine](INFO): Startup:StartupSatComms->null/0
2021-04-12T04:30:03.832Z,1618201803.832 [CommandLine](INFO): Default->Default:CheckIn/0
2021-04-12T04:30:03.833Z,1618201803.833 [CommandLine](INFO): Default:CheckIn->Default:CheckIn:Read_Iridium/0
2021-04-12T04:30:03.862Z,1618201803.862 [DataOverHttps](INFO): Sending 961 bytes from file Logs/20210412T034901/Express0001.lzma
2021-04-12T04:30:04.861Z,1618201804.861 [DataOverHttps](INFO): Moved sent file to Logs/20210412T034901/Express0001.lzma.bak
2021-04-12T04:30:04.861Z,1618201804.861 [DataOverHttps](INFO): SBD MOMSN=15558740
2021-04-12T04:30:10.506Z,1618201810.506 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2021-04-12T04:30:10.506Z,1618201810.506 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-04-12T04:30:10.528Z,1618201810.528 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-04-12T04:30:10.925Z,1618201810.925 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-04-12T04:30:10.925Z,1618201810.925 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2021-04-12T04:30:13.308Z,1618201813.308 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2021-04-12T04:30:13.331Z,1618201813.331 [BPC1](ERROR): BPC1A failed to parse battery stick message.
2021-04-12T04:30:20.446Z,1618201820.446 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20210412T034901/Express0005.lzma
2021-04-12T04:30:21.449Z,1618201821.449 [DataOverHttps](INFO): Moved sent file to Logs/20210412T034901/Express0005.lzma.bak
2021-04-12T04:30:21.449Z,1618201821.449 [DataOverHttps](INFO): SBD MOMSN=15558765
2021-04-12T04:30:22.594Z,1618201822.594 [BPC1](ERROR): BPC1B failed to parse battery stick message.
2021-04-12T04:30:24.202Z,1618201824.202 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2021-04-12T04:30:24.208Z,1618201824.208 [BPC1](ERROR): BPC1A failed to parse battery stick message.
2021-04-12T04:30:36.059Z,1618201836.059 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:30:37.234Z,1618201837.234 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20210412T035748/Express0001.lzma
2021-04-12T04:30:38.236Z,1618201838.236 [DataOverHttps](INFO): Moved sent file to Logs/20210412T035748/Express0001.lzma.bak
2021-04-12T04:30:38.237Z,1618201838.237 [DataOverHttps](INFO): SBD MOMSN=15558767
2021-04-12T04:30:44.018Z,1618201844.018 [DVL_micro](ERROR): only read 3 of 4 data items
2021-04-12T04:30:44.019Z,1618201844.019 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+99999.99,+9999.99
2021-04-12T04:30:48.112Z,1618201848.112 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:30:50.163Z,1618201850.163 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-12T04:30:53.757Z,1618201853.757 [DataOverHttps](INFO): Sending 928 bytes from file Logs/20210412T040013/Express0001.lzma
2021-04-12T04:30:54.756Z,1618201854.756 [DataOverHttps](INFO): Moved sent file to Logs/20210412T040013/Express0001.lzma.bak
2021-04-12T04:30:54.757Z,1618201854.757 [DataOverHttps](INFO): SBD MOMSN=15558769
2021-04-12T04:30:59.873Z,1618201859.873 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:31:10.260Z,1618201870.260 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2021-04-12T04:31:10.262Z,1618201870.262 [BPC1](ERROR): BPC1A failed to parse battery stick message.
2021-04-12T04:31:10.358Z,1618201870.358 [DataOverHttps](INFO): Sending 117 bytes from file Logs/20210412T040013/Express0005.lzma
2021-04-12T04:31:11.491Z,1618201871.491 [DataOverHttps](INFO): Moved sent file to Logs/20210412T040013/Express0005.lzma.bak
2021-04-12T04:31:11.491Z,1618201871.491 [DataOverHttps](INFO): SBD MOMSN=15558793
2021-04-12T04:31:17.989Z,1618201877.989 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-12T04:31:22.074Z,1618201882.074 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:31:25.949Z,1618201885.949 [DataOverHttps](INFO): Sending 1001 bytes from file Logs/20210412T040606/Express0001.lzma
2021-04-12T04:31:26.949Z,1618201886.949 [DataOverHttps](INFO): Moved sent file to Logs/20210412T040606/Express0001.lzma.bak
2021-04-12T04:31:26.949Z,1618201886.949 [DataOverHttps](INFO): SBD MOMSN=15558797
2021-04-12T04:31:34.160Z,1618201894.160 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:31:42.449Z,1618201902.449 [DataOverHttps](INFO): Sending 1152 bytes from file Logs/20210412T040606/Express0005.lzma
2021-04-12T04:31:43.448Z,1618201903.448 [DataOverHttps](INFO): Moved sent file to Logs/20210412T040606/Express0005.lzma.bak
2021-04-12T04:31:43.449Z,1618201903.449 [DataOverHttps](INFO): SBD MOMSN=15558825
2021-04-12T04:31:45.880Z,1618201905.880 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:31:58.040Z,1618201918.040 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:31:59.141Z,1618201919.141 [DataOverHttps](INFO): Sending 1212 bytes from file Logs/20210412T042058/Express0001.lzma
2021-04-12T04:32:00.133Z,1618201920.133 [DataOverHttps](INFO): Moved sent file to Logs/20210412T042058/Express0001.lzma.bak
2021-04-12T04:32:00.133Z,1618201920.133 [DataOverHttps](INFO): SBD MOMSN=15558924
2021-04-12T04:32:09.943Z,1618201929.943 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:32:15.016Z,1618201935.016 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-12T04:32:21.545Z,1618201941.545 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:32:23.101Z,1618201943.101 [DataOverHttps](INFO): Sending 729 bytes from file Logs/20210412T042058/Express0005.lzma
2021-04-12T04:32:24.100Z,1618201944.100 [DataOverHttps](INFO): Moved sent file to Logs/20210412T042058/Express0005.lzma.bak
2021-04-12T04:32:24.101Z,1618201944.101 [DataOverHttps](INFO): SBD MOMSN=15558954
2021-04-12T04:32:26.705Z,1618201946.705 [Default:CheckIn:Read_Iridium] Stopped
2021-04-12T04:32:26.705Z,1618201946.705 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-12T04:32:26.705Z,1618201946.705 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-12T04:32:33.228Z,1618201953.228 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:32:42.837Z,1618201962.837 [NAL9602](FAULT): GPS failed to acquire within timeout.
2021-04-12T04:32:42.837Z,1618201962.837 [NAL9602] Data Fault, FailCount= 2
2021-04-12T04:32:42.837Z,1618201962.837 [NAL9602](ERROR): Data Fault
2021-04-12T04:32:42.894Z,1618201962.894 [CBIT](ERROR): Data Fault in component: NAL9602
2021-04-12T04:32:43.241Z,1618201963.241 [NAL9602](INFO): Powering down
2021-04-12T04:32:44.101Z,1618201964.101 [CBIT](INFO): Clearing failed state for component NAL9602
2021-04-12T04:32:44.101Z,1618201964.101 [NAL9602] No Fault, FailCount= 2
2021-04-12T04:33:11.553Z,1618201991.553 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2021-04-12T04:33:11.553Z,1618201991.553 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-04-12T04:33:11.591Z,1618201991.591 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-04-12T04:33:11.968Z,1618201991.968 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-04-12T04:33:11.968Z,1618201991.968 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2021-04-12T04:33:13.561Z,1618201993.561 [NAL9602](INFO): Powering up NAL9602
2021-04-12T04:33:19.202Z,1618201999.202 [DVL_micro](ERROR): only read 3 of 4 data items
2021-04-12T04:33:19.202Z,1618201999.202 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.999,+9999.99,+9999.99
2021-04-12T04:33:24.461Z,1618202004.461 [NAL9602](INFO): NAL9602 initialized
2021-04-12T04:33:41.894Z,1618202021.894 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-12T04:33:58.844Z,1618202038.844 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:34:26.321Z,1618202066.321 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:34:35.978Z,1618202075.978 [CBIT](INFO): Clearing failed state for component DAT
2021-04-12T04:34:35.978Z,1618202075.978 [DAT] No Fault, FailCount= 8
2021-04-12T04:34:36.368Z,1618202076.368 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2021-04-12T04:34:36.368Z,1618202076.368 [DAT](INFO): Powering up
2021-04-12T04:34:36.368Z,1618202076.368 [DAT](DEBUG): Initializing DAT.
2021-04-12T04:34:36.443Z,1618202076.443 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2021-04-12T04:34:50.517Z,1618202090.517 [DAT](INFO): commRate: 800
2021-04-12T04:34:52.540Z,1618202092.540 [DAT](INFO): entering command mode
2021-04-12T04:34:52.982Z,1618202092.982 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:34:53.371Z,1618202093.371 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:34:53.754Z,1618202093.754 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:34:54.158Z,1618202094.158 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:34:54.563Z,1618202094.563 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:34:55.023Z,1618202095.023 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:34:55.367Z,1618202095.367 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:34:55.783Z,1618202095.783 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:34:56.178Z,1618202096.178 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:34:56.566Z,1618202096.566 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:34:56.965Z,1618202096.965 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:34:57.387Z,1618202097.387 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:34:57.791Z,1618202097.791 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:34:58.203Z,1618202098.203 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:34:58.581Z,1618202098.581 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:34:58.986Z,1618202098.986 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:34:59.393Z,1618202099.393 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:34:59.795Z,1618202099.795 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:35:00.198Z,1618202100.198 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:35:00.615Z,1618202100.615 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:35:01.035Z,1618202101.035 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:35:01.427Z,1618202101.427 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:35:01.817Z,1618202101.817 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:35:02.218Z,1618202102.218 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:35:02.634Z,1618202102.634 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:35:03.029Z,1618202103.029 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:35:03.467Z,1618202103.467 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:35:03.848Z,1618202103.848 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:35:04.255Z,1618202104.255 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:35:04.645Z,1618202104.645 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:35:05.050Z,1618202105.050 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:35:05.453Z,1618202105.453 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:35:05.903Z,1618202105.903 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-12T04:35:05.935Z,1618202105.935 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:35:06.283Z,1618202106.283 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:35:06.697Z,1618202106.697 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:35:07.087Z,1618202107.087 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:35:07.491Z,1618202107.491 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:35:07.877Z,1618202107.877 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:35:07.877Z,1618202107.877 [DAT](FAULT): failed to enter command mode
2021-04-12T04:35:08.328Z,1618202108.328 [DAT](INFO): entering command mode
2021-04-12T04:35:08.688Z,1618202108.688 [DAT](INFO): setting verbose to 3
2021-04-12T04:35:09.146Z,1618202109.146 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:35:09.151Z,1618202109.151 [DAT](INFO): set verbose to 3
2021-04-12T04:35:09.152Z,1618202109.152 [DAT](INFO): setting DatVerbose to 27440
2021-04-12T04:35:09.528Z,1618202109.528 [DAT](INFO): set DatVerbose to 27440
2021-04-12T04:35:09.529Z,1618202109.529 [DAT](INFO): setting transmit power to 8
2021-04-12T04:35:09.934Z,1618202109.934 [DAT](INFO): set transmit power to 8
2021-04-12T04:35:09.934Z,1618202109.934 [DAT](INFO): setting local address to 8
2021-04-12T04:35:10.317Z,1618202110.317 [DAT](INFO): set local address to 8
2021-04-12T04:36:06.916Z,1618202166.916 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:36:12.548Z,1618202172.548 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2021-04-12T04:36:12.548Z,1618202172.548 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-04-12T04:36:12.583Z,1618202172.583 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-04-12T04:36:12.969Z,1618202172.969 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-04-12T04:36:12.969Z,1618202172.969 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2021-04-12T04:36:19.436Z,1618202179.436 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:37:02.262Z,1618202222.262 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:37:04.678Z,1618202224.678 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-12T04:37:27.273Z,1618202247.273 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-12T04:37:27.273Z,1618202247.273 [Default:CheckIn:C.Wait] Stopped
2021-04-12T04:37:27.273Z,1618202247.273 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-12T04:37:27.273Z,1618202247.273 [Default:CheckIn:D] Running Loop=1
2021-04-12T04:37:27.693Z,1618202247.693 [Default:CheckIn:D] Stopped
2021-04-12T04:37:27.693Z,1618202247.693 [Default:CheckIn:E] Running Loop=1
2021-04-12T04:37:28.105Z,1618202248.105 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 13.023211 min 
2021-04-12T04:37:28.106Z,1618202248.106 [Default:CheckIn:E] Stopped
2021-04-12T04:37:28.106Z,1618202248.106 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-12T04:37:28.106Z,1618202248.106 [Default:CheckIn] Stopped
2021-04-12T04:37:28.106Z,1618202248.106 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-12T04:37:28.106Z,1618202248.106 [Default:CheckIn](INFO): Running loop #2
2021-04-12T04:37:28.106Z,1618202248.106 [Default:CheckIn] Running Loop=2
2021-04-12T04:37:28.106Z,1618202248.106 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-12T04:37:28.106Z,1618202248.106 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-12T04:38:29.481Z,1618202309.481 [DVL_micro](ERROR): Failed to parse:
:WI,+0004,+00894,+00000,A
2021-04-12T04:39:13.521Z,1618202353.521 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2021-04-12T04:39:13.521Z,1618202353.521 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-04-12T04:39:13.532Z,1618202353.532 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-04-12T04:39:13.941Z,1618202353.941 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-04-12T04:39:13.942Z,1618202353.942 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2021-04-12T04:39:54.311Z,1618202394.311 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2021-04-12T04:39:54.315Z,1618202394.315 [BPC1](ERROR): BPC1A failed to parse battery stick message.
2021-04-12T04:40:05.684Z,1618202405.684 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:40:17.420Z,1618202417.420 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:40:29.526Z,1618202429.526 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:40:41.242Z,1618202441.242 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:40:50.923Z,1618202450.923 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:41:02.655Z,1618202462.655 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:41:04.363Z,1618202464.363 [CommandLine](IMPORTANT): got command run ./Missions/esp_send_test.xml
2021-04-12T04:41:04.363Z,1618202464.363 [MissionManager](INFO): Loading Mission: ./Missions/esp_send_test.xml
2021-04-12T04:41:04.487Z,1618202464.487 [MissionManager](INFO): DefineArg esp_send_test.MissionTimeout = 4.000000 h
2021-04-12T04:41:04.490Z,1618202464.490 [MissionManager](INFO): DefineArg esp_send_test.ESPCartridgeType_1 = -4.000000 count
2021-04-12T04:41:04.493Z,1618202464.493 [MissionManager](INFO): DefineArg esp_send_test.ESPCartridgeType_2 = -15.000000 count
2021-04-12T04:41:04.532Z,1618202464.532 [MissionManager](INFO): DefineArg esp_send_test.ESPCartridgeType_3 = nan count
2021-04-12T04:41:04.535Z,1618202464.535 [MissionManager](INFO): DefineArg esp_send_test.ESPCartridgeType_4 = nan count
2021-04-12T04:41:04.538Z,1618202464.538 [MissionManager](INFO): DefineOutput esp_send_test.ESPCartridgeType = nan count
2021-04-12T04:41:04.541Z,1618202464.541 [MissionManager](INFO): DefineOutput esp_send_test.CntSamples = 1.000000 count
2021-04-12T04:41:04.541Z,1618202464.541 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml
2021-04-12T04:41:04.620Z,1618202464.620 [DVL_micro](ERROR): only read 3 of 4 data items
2021-04-12T04:41:04.620Z,1618202464.620 [DVL_micro](ERROR): Failed to parse:
:BI,+01289,-01642,+0000,I
2021-04-12T04:41:04.859Z,1618202464.859 [MissionManager](INFO): DefineArg esp_send_test:Science.PeakDetectChlActive = 0 bool
2021-04-12T04:41:04.862Z,1618202464.862 [MissionManager](INFO): DefineArg esp_send_test:Science.TimeWindowPeakReport = nan min
2021-04-12T04:41:04.865Z,1618202464.865 [MissionManager](INFO): DefineArg esp_send_test:Science.HighestChlPeakReportActive = 0 bool
2021-04-12T04:41:04.867Z,1618202464.867 [MissionManager](INFO): DefineArg esp_send_test:Science.PatchTracking = 0 bool
2021-04-12T04:41:04.870Z,1618202464.870 [MissionManager](INFO): DefineArg esp_send_test:Science.FilterWidthHorizontal = 3.000000 count
2021-04-12T04:41:04.873Z,1618202464.873 [MissionManager](INFO): DefineArg esp_send_test:Science.NumProfilesSlidingwindow = 100.000000 count
2021-04-12T04:41:04.876Z,1618202464.876 [MissionManager](INFO): DefineArg esp_send_test:Science.OffPeakFractionHorizontal = 80.000000 %
2021-04-12T04:41:04.879Z,1618202464.879 [MissionManager](INFO): DefineArg esp_send_test:Science.PeakDetectNO3Active = 0 bool
2021-04-12T04:41:04.881Z,1618202464.881 [MissionManager](INFO): DefineArg esp_send_test:Science.PeakDetectOilActive = 0 bool
2021-04-12T04:41:04.884Z,1618202464.884 [MissionManager](INFO): DefineArg esp_send_test:Science.PeakDetectFDOMActive = 0 bool
2021-04-12T04:41:04.887Z,1618202464.887 [MissionManager](INFO): DefineArg esp_send_test:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2021-04-12T04:41:04.890Z,1618202464.890 [MissionManager](INFO): DefineArg esp_send_test:Science.EnabledAanderaaO2 = 0.000000 bool
2021-04-12T04:41:04.893Z,1618202464.893 [MissionManager](INFO): DefineArg esp_send_test:Science.EnabledNeilBrown = 0.000000 bool
2021-04-12T04:41:04.897Z,1618202464.897 [MissionManager](INFO): DefineArg esp_send_test:Science.EnabledSeabird = 1.000000 bool
2021-04-12T04:41:04.900Z,1618202464.900 [MissionManager](INFO): DefineArg esp_send_test:Science.EnabledWetLabsBB2FL = 1.000000 bool
2021-04-12T04:41:04.904Z,1618202464.904 [MissionManager](INFO): DefineArg esp_send_test:Science.EnabledWetLabsSeaOWL_UV_A = 0.000000 bool
2021-04-12T04:41:04.940Z,1618202464.940 [MissionManager](INFO): DefineArg esp_send_test:Science.EnabledWetLabsUBAT = 0.000000 bool
2021-04-12T04:41:04.947Z,1618202464.947 [MissionManager](INFO): DefineArg esp_send_test:Science.LowPassWindowLength = 20.000000 count
2021-04-12T04:41:04.950Z,1618202464.950 [MissionManager](INFO): DefineArg esp_send_test:Science.MedianFilterLen = 5.000000 count
2021-04-12T04:41:04.957Z,1618202464.957 [MissionManager](INFO): DefineArg esp_send_test:Science.PeakShallowBound = nan m
2021-04-12T04:41:04.969Z,1618202464.969 [MissionManager](INFO): DefineArg esp_send_test:Science.PeakDeepBound = nan m
2021-04-12T04:41:04.980Z,1618202464.980 [MissionManager](INFO): DefineOutput esp_send_test:Science.PeakChl = 0.000000 ug/l
2021-04-12T04:41:05.027Z,1618202465.027 [MissionManager](INFO): DefineOutput esp_send_test:Science.PeakChlDepth = 0.000000 m
2021-04-12T04:41:05.030Z,1618202465.030 [MissionManager](INFO): DefineOutput esp_send_test:Science.PeakChlTemperature = 0.000000 degC
2021-04-12T04:41:05.053Z,1618202465.053 [MissionManager](INFO): DefineOutput esp_send_test:Science.PeakChlLatitude = nan arcdeg
2021-04-12T04:41:05.060Z,1618202465.060 [MissionManager](INFO): DefineOutput esp_send_test:Science.PeakChlLongitude = nan arcdeg
2021-04-12T04:41:05.064Z,1618202465.064 [MissionManager](INFO): DefineOutput esp_send_test:Science.PeakNO3 = 0.000000 umol/l
2021-04-12T04:41:05.083Z,1618202465.083 [MissionManager](INFO): DefineOutput esp_send_test:Science.PeakNO3Depth = 0.000000 m
2021-04-12T04:41:05.086Z,1618202465.086 [MissionManager](INFO): DefineOutput esp_send_test:Science.PeakNO3Latitude = nan arcdeg
2021-04-12T04:41:05.093Z,1618202465.093 [MissionManager](INFO): DefineOutput esp_send_test:Science.PeakNO3Longitude = nan arcdeg
2021-04-12T04:41:05.105Z,1618202465.105 [MissionManager](INFO): DefineOutput esp_send_test:Science.PatchChl = 0.000000 m
2021-04-12T04:41:05.112Z,1618202465.112 [MissionManager](INFO): DefineOutput esp_send_test:Science.PatchChlDepth = 0.000000 m
2021-04-12T04:41:05.119Z,1618202465.119 [MissionManager](INFO): DefineOutput esp_send_test:Science.PatchChlLatitude = nan arcdeg
2021-04-12T04:41:05.122Z,1618202465.122 [MissionManager](INFO): DefineOutput esp_send_test:Science.PatchChlLongitude = nan arcdeg
2021-04-12T04:41:05.137Z,1618202465.137 [MissionManager](INFO): DefineOutput esp_send_test:Science.PatchChlDistance = nan m
2021-04-12T04:41:05.144Z,1618202465.144 [MissionManager](INFO): DefineOutput esp_send_test:Science.PeakOil = 0.000000 kg/m3
2021-04-12T04:41:05.153Z,1618202465.153 [MissionManager](INFO): DefineOutput esp_send_test:Science.PeakOilDepth = 0.000000 m
2021-04-12T04:41:05.175Z,1618202465.175 [MissionManager](INFO): DefineOutput esp_send_test:Science.PeakOilLatitude = nan arcdeg
2021-04-12T04:41:05.178Z,1618202465.178 [MissionManager](INFO): DefineOutput esp_send_test:Science.PeakOilLongitude = nan arcdeg
2021-04-12T04:41:05.185Z,1618202465.185 [MissionManager](INFO): DefineOutput esp_send_test:Science.PeakFDOM = 0.000000 ppb
2021-04-12T04:41:05.207Z,1618202465.207 [MissionManager](INFO): DefineOutput esp_send_test:Science.PeakFDOMDepth = 0.000000 m
2021-04-12T04:41:05.210Z,1618202465.210 [MissionManager](INFO): DefineOutput esp_send_test:Science.PeakFDOMTemperature = nan degC
2021-04-12T04:41:05.226Z,1618202465.226 [MissionManager](INFO): DefineOutput esp_send_test:Science.PeakFDOMLatitude = nan arcdeg
2021-04-12T04:41:05.229Z,1618202465.229 [MissionManager](INFO): DefineOutput esp_send_test:Science.PeakFDOMLongitude = nan arcdeg
2021-04-12T04:41:05.240Z,1618202465.240 [MissionManager](INFO): DefineOutput esp_send_test:Science.PatchFDOMDistance = nan m
2021-04-12T04:41:05.328Z,1618202465.328 [esp_send_test:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-04-12T04:41:05.368Z,1618202465.368 [esp_send_test:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2021-04-12T04:41:05.428Z,1618202465.428 [esp_send_test:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-04-12T04:41:05.466Z,1618202465.466 [esp_send_test:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-04-12T04:41:05.505Z,1618202465.505 [esp_send_test:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-04-12T04:41:05.548Z,1618202465.548 [esp_send_test:B.AbortSample](DEBUG): Construct.
2021-04-12T04:41:05.648Z,1618202465.648 [esp_send_test:SampleESP:sample:B.ESPCartridgeSelect](DEBUG): Construct.
2021-04-12T04:41:05.695Z,1618202465.695 [esp_send_test:SampleESP:sample:E.Wait](DEBUG): Construct Wait.
2021-04-12T04:41:05.871Z,1618202465.871 [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:Control="Tethys/Control"
         xmlns:Sample="Tethys/Sample"
         xmlns:Science="Tethys/Science"
         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/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                             Tethys/Sample http://okeanids.mbari.org/tethys/Xml/Sample.xsd
                             Tethys/Science http://okeanids.mbari.org/tethys/Xml/Science.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="esp_send_test">

    <Description>
        This mission takes ESP samples at the designated depth.
    </Description>

    <DefineArg Name="MissionTimeout"><Description>
        Maximum duration of mission.
    </Description><Units:hour/><Value>4</Value></DefineArg>

    <DefineArg Name="ESPCartridgeType_1"><Description>
        Specifies ESP cartridge type code for sample# 1. Set to NaN to skip this
        sample.
    </Description><Units:count/><Value>-4</Value></DefineArg>

    <DefineArg Name="ESPCartridgeType_2"><Description>
        Specifies ESP cartridge type code for sample# 2. Set to NaN to skip this
        sample.
    </Description><Units:count/><Value>-15</Value></DefineArg>

    <DefineArg Name="ESPCartridgeType_3"><Description>
        Specifies ESP cartridge type code for sample# 3. Set to NaN to skip this
        sample.
    </Description><Units:count/><Value>NaN</Value></DefineArg>

    <DefineArg Name="ESPCartridgeType_4"><Description>
        Specifies ESP cartridge type code for sample# 4. Set to NaN to skip this
        sample.
    </Description><Units:count/><Value>NaN</Value></DefineArg>

   

    <DefineOutput Name="ESPCartridgeType"><Description>
        Mission parameter (don't change). Specifies ESP cartridge type code.
    </Description><Units:count/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="CntSamples"><Description>
        Count of water samples (initilized to 1).
    </Description><Units:count/><Value>1</Value></DefineOutput>

<!-- Mission timeout -->

    <Timeout Duration="MissionTimeout"/>

    <Insert Filename="Insert/Science.xml"></Insert>

<!-- Safety nets and surfacing behaviors -->

    <Sample:AbortSample>
        <Parallel/>
    </Sample:AbortSample>

<!-- Callable aggregate -->

    <Aggregate Id="SendDataTo1Modem">

        <When>
            <Called/>
        </When>

        <Aggregate Id="SendDataToModem">

            <Sequence/>

            <Aggregate Id="SendDataCntSamples">

                <Sequence/>

                <Syslog Severity="Important"> CntSamples = <Arg Name="CntSamples"/><Units:count/></Syslog>

                <SendData Service="Direct" Destination="modem:6:_.LRAUVcntSamples">
                    <Arg Name="CntSamples"/>
                </SendData>

            </Aggregate>

            <Aggregate Id="SendDataESPSampleNumber">

                <Sequence/>

                <Break>
                    <IsNaN><Science:ESPComponent.sample_number/></IsNaN>
                </Break>

                <Syslog Severity="Important"> ESPComponent.sample_number = <Science:ESPComponent.sample_number/><Units:count/></Syslog>

                <SendData Service="Direct" Destination="modem:6:_.ESPsampleNumber">
                    <Science:ESPComponent.sample_number/><Units:count/>
                </SendData>

            </Aggregate>

            <Aggregate Id="SendDataESPCartridgeType">

                <Sequence/>

                <Break>
                    <IsNaN><Science:ESPComponent.cartridge/></IsNaN>
                </Break>

                <Syslog Severity="Important"> ESPComponent.cartridge = <Science:ESPComponent.cartridge/><Units:count/></Syslog>

                <SendData Service="Direct" Destination="modem:6:_.ESPcartridgeType">
                    <Science:ESPComponent.cartridge/><Units:count/>
                </SendData>

            </Aggregate>

            <Aggregate Id="SendDataESPSampleVolume">

                <Sequence/>

                <Break>
                    <IsNaN><Science:ESPComponent.sampleVolume/></IsNaN>
                </Break>

                <Syslog Severity="Important">Science:ESPComponent.sampleVolume = <Science:ESPComponent.sampleVolume/><Units:milliliter/></Syslog>

                <SendData Service="Direct" Destination="modem:6:_.ESPsampleVolume">
                    <Science:ESPComponent.sampleVolume/><Units:milliliter/>
                </SendData>

            </Aggregate>

            <Aggregate Id="SendDataSampleDepth">

                <Sequence/>

                <Break>
                    <IsNaN><Universal:depth/></IsNaN>
                </Break>

                <Syslog Severity="Important">Universal:depth = <Universal:depth/><Units:meter/></Syslog>

                <SendData Service="Direct" Destination="modem:6:_.sampleDepth">
                    <Universal:depth/><Units:meter/>
                </SendData>

            </Aggregate>

            <Aggregate Id="SendDataSampleTemp">

                <Sequence/>

                <Break>
                    <IsNaN><Universal:sea_water_temperature/></IsNaN>
                </Break>

                <Syslog Severity="Important">Universal:sea_water_temperature = <Universal:sea_water_temperature/><Units:celsius/></Syslog>

                <SendData Service="Direct" Destination="modem:6:_.sampleTemperature">
                    <Universal:sea_water_temperature/><Units:celsius/>
                </SendData>

            </Aggregate>

            <Aggregate Id="SendDataSampleChl">

                <Sequence/>

                <Break>
                    <IsNaN><Universal:mass_concentration_of_chlorophyll_in_sea_water/></IsNaN>
                </Break>

                <Syslog Severity="Important">Universal:mass_concentration_of_chlorophyll_in_sea_water = <Universal:mass_concentration_of_chlorophyll_in_sea_water/><Units:microgram_per_liter/></Syslog>

                <SendData Service="Direct" Destination="modem:6:_.sampleChlorophyll">
                    <Universal:mass_concentration_of_chlorophyll_in_sea_water/><Units:microgram_per_liter/>
                </SendData>

            </Aggregate>

        </Aggregate>

    </Aggregate>


    <Aggregate Id="SampleESP">

        <When>
            <Called/>
        </When>

        <Aggregate Id="sample">

            <Sequence/>

            <Syslog Severity="Important">Requesting ESP cartridge type:<Arg Name="ESPCartridgeType"/><Units:count/></Syslog>

            <Science:ESPCartridgeSelect>
                <Sequence/>
                <Timeout Duration="P1M"/>
                <Setting><Science:ESPCartridgeSelect.cartridgeType/><Arg Name="ESPCartridgeType"/></Setting>
            </Science:ESPCartridgeSelect>

            <ReadDatum Id="TriggerESP">
                <Science:ESPComponent.sampling/>
            </ReadDatum>

            <Syslog Severity="Important">ESP sampling at <Universal:depth/><Units:meter/> in <Universal:sea_water_temperature/><Units:celsius/> water with <Universal:mass_concentration_of_chlorophyll_in_sea_water/><Units:microgram_per_liter/>chlorophyll fluorescence.</Syslog>

            <Guidance:Wait>
                <Sequence/>
                <Setting><Guidance:Wait.duration/><Units:second/><Value>30</Value></Setting>
            </Guidance:Wait>

            <ReadDatum Id="WaitForESP">
                <Science:ESPComponent.sample_number/>
            </ReadDatum>

            <Call Id="ReportSample" RefId="SendDataTo1Modem"/>

            <Assign><Sequence/><Arg Name="CntSamples"/><Arg Name="CntSamples"/><Add><Units:count/><Value>1</Value></Add></Assign>

        </Aggregate>

    </Aggregate>

<!-- Start mission sequence -->

    <Aggregate Id="SampleInLab">

        <Description>
            Acquire ESP samples.
        </Description>

        <Sequence/>

        <Aggregate Id="GrabSamples">

            <Sequence/>

            <Aggregate Id="Sample1">

                <Sequence/>
                
                <Break>
                    <IsNaN><Arg Name="ESPCartridgeType_1"/></IsNaN>
                </Break>

                <Assign><Sequence/><Arg Name="ESPCartridgeType"/><Arg Name="ESPCartridgeType_1"/></Assign>

                <Call RefId="SampleESP"/>

            </Aggregate>

            <Aggregate Id="Sample2">

                <Sequence/>
                
                <Break>
                    <IsNaN><Arg Name="ESPCartridgeType_2"/></IsNaN>
                </Break>

                <Assign><Sequence/><Arg Name="ESPCartridgeType"/><Arg Name="ESPCartridgeType_2"/></Assign>

                <Call RefId="SampleESP"/>

            </Aggregate>

            <Aggregate Id="Sample3">
                
                <Sequence/>

                <Break>
                    <IsNaN><Arg Name="ESPCartridgeType_3"/></IsNaN>
                </Break>

                <Assign><Sequence/><Arg Name="ESPCartridgeType"/><Arg Name="ESPCartridgeType_3"/></Assign>

                <Call RefId="SampleESP"/>

            </Aggregate>

            <Aggregate Id="Sample4">

                <Sequence/>

                <Break>
                    <IsNaN><Arg Name="ESPCartridgeType_4"/></IsNaN>
                </Break>

                <Assign><Sequence/><Arg Name="ESPCartridgeType"/><Arg Name="ESPCartridgeType_4"/></Assign>

                <Call RefId="SampleESP"/>

            </Aggregate>

        </Aggregate>

    </Aggregate>

</Mission>


2021-04-12T04:41:05.874Z,1618202465.874 [CommandLine](IMPORTANT): Running ./Missions/esp_send_test.xml
2021-04-12T04:41:06.252Z,1618202466.252 [Default] Stopped
2021-04-12T04:41:06.252Z,1618202466.252 [Default](DEBUG): Aggregate::uninitialize Default
2021-04-12T04:41:06.252Z,1618202466.252 [Default:B.GoToSurface] Stopped
2021-04-12T04:41:06.252Z,1618202466.252 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-04-12T04:41:06.252Z,1618202466.252 [Default:CheckIn] Stopped
2021-04-12T04:41:06.253Z,1618202466.253 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-12T04:41:06.253Z,1618202466.253 [Default:CheckIn:Read_GPS] Stopped
2021-04-12T04:41:06.253Z,1618202466.253 [MissionManager](IMPORTANT): Started mission esp_send_test
2021-04-12T04:41:06.253Z,1618202466.253 [esp_send_test] Running Loop=1
2021-04-12T04:41:06.253Z,1618202466.253 [esp_send_test](DEBUG): Aggregate::initialize esp_send_test
2021-04-12T04:41:06.253Z,1618202466.253 [esp_send_test:Science] Running Loop=1
2021-04-12T04:41:06.253Z,1618202466.253 [esp_send_test:Science](DEBUG): Aggregate::initialize esp_send_test:Science
2021-04-12T04:41:06.253Z,1618202466.253 [esp_send_test:Science:A] Running Loop=1
2021-04-12T04:41:06.254Z,1618202466.254 [esp_send_test:Science:B] Running Loop=1
2021-04-12T04:41:06.254Z,1618202466.254 [esp_send_test:Science:C] Running Loop=1
2021-04-12T04:41:06.254Z,1618202466.254 [esp_send_test:Science:D] Running Loop=1
2021-04-12T04:41:06.254Z,1618202466.254 [esp_send_test:Science:E] Running Loop=1
2021-04-12T04:41:06.254Z,1618202466.254 [esp_send_test:Science:F] Running Loop=1
2021-04-12T04:41:06.254Z,1618202466.254 [esp_send_test:Science:Read_Oil] Running Loop=1
2021-04-12T04:41:06.254Z,1618202466.254 [esp_send_test:Science:PeakDetectChl] Running Loop=1
2021-04-12T04:41:06.254Z,1618202466.254 [esp_send_test:Science:PeakDetectChl](DEBUG): Aggregate::initialize esp_send_test:Science:PeakDetectChl
2021-04-12T04:41:06.254Z,1618202466.254 [esp_send_test:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2021-04-12T04:41:06.254Z,1618202466.254 [esp_send_test:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-04-12T04:41:06.267Z,1618202466.267 [esp_send_test:Science:HighestChlPeakReport] Running Loop=1
2021-04-12T04:41:06.267Z,1618202466.267 [esp_send_test:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize esp_send_test:Science:HighestChlPeakReport
2021-04-12T04:41:06.267Z,1618202466.267 [esp_send_test:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2021-04-12T04:41:06.267Z,1618202466.267 [esp_send_test:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2021-04-12T04:41:06.268Z,1618202466.268 [esp_send_test:Science:PeakDetectNO3] Running Loop=1
2021-04-12T04:41:06.268Z,1618202466.268 [esp_send_test:Science:PeakDetectNO3](DEBUG): Aggregate::initialize esp_send_test:Science:PeakDetectNO3
2021-04-12T04:41:06.268Z,1618202466.268 [esp_send_test:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2021-04-12T04:41:06.268Z,1618202466.268 [esp_send_test:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-04-12T04:41:06.270Z,1618202466.270 [esp_send_test:Science:PeakDetectOil] Running Loop=1
2021-04-12T04:41:06.270Z,1618202466.270 [esp_send_test:Science:PeakDetectOil](DEBUG): Aggregate::initialize esp_send_test:Science:PeakDetectOil
2021-04-12T04:41:06.270Z,1618202466.270 [esp_send_test:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2021-04-12T04:41:06.270Z,1618202466.270 [esp_send_test:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-04-12T04:41:06.276Z,1618202466.276 [esp_send_test:Science:PeakDetectFDOM] Running Loop=1
2021-04-12T04:41:06.276Z,1618202466.276 [esp_send_test:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize esp_send_test:Science:PeakDetectFDOM
2021-04-12T04:41:06.276Z,1618202466.276 [esp_send_test:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2021-04-12T04:41:06.276Z,1618202466.276 [esp_send_test:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-04-12T04:41:06.278Z,1618202466.278 [esp_send_test:Science:OceanCurrent] Running Loop=1
2021-04-12T04:41:06.278Z,1618202466.278 [esp_send_test:Science:OceanCurrent](DEBUG): Aggregate::initialize esp_send_test:Science:OceanCurrent
2021-04-12T04:41:06.278Z,1618202466.278 [esp_send_test:Science:OceanCurrent:A.] Running Loop=1
2021-04-12T04:41:06.283Z,1618202466.283 [esp_send_test:Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator.
2021-04-12T04:41:06.283Z,1618202466.283 [esp_send_test:B.AbortSample] Running Loop=1
2021-04-12T04:41:06.283Z,1618202466.283 [esp_send_test:B.AbortSample](INFO): Initializing AbortSample.
2021-04-12T04:41:06.284Z,1618202466.284 [esp_send_test:SampleInLab] Running Loop=1
2021-04-12T04:41:06.284Z,1618202466.284 [esp_send_test:SampleInLab](DEBUG): Aggregate::initialize esp_send_test:SampleInLab
2021-04-12T04:41:06.284Z,1618202466.284 [esp_send_test:SampleInLab:GrabSamples] Running Loop=1
2021-04-12T04:41:06.284Z,1618202466.284 [esp_send_test:SampleInLab:GrabSamples](DEBUG): Aggregate::initialize esp_send_test:SampleInLab:GrabSamples
2021-04-12T04:41:06.284Z,1618202466.284 [esp_send_test:SampleInLab:GrabSamples:Sample1] Running Loop=1
2021-04-12T04:41:06.284Z,1618202466.284 [esp_send_test:SampleInLab:GrabSamples:Sample1](DEBUG): Aggregate::initialize esp_send_test:SampleInLab:GrabSamples:Sample1
2021-04-12T04:41:06.284Z,1618202466.284 [esp_send_test:SampleInLab:GrabSamples:Sample1:A] Running Loop=1
2021-04-12T04:41:06.285Z,1618202466.285 [esp_send_test:SampleInLab:GrabSamples:Sample1:A] Stopped
2021-04-12T04:41:06.285Z,1618202466.285 [esp_send_test:SampleInLab:GrabSamples:Sample1:B] Running Loop=1
2021-04-12T04:41:06.285Z,1618202466.285 [esp_send_test:SampleInLab:GrabSamples:Sample1:B](DEBUG): Aggregate::initialize esp_send_test:SampleInLab:GrabSamples:Sample1:B
2021-04-12T04:41:06.285Z,1618202466.285 [esp_send_test:B.AbortSample] Running Loop=1
2021-04-12T04:41:06.285Z,1618202466.285 [esp_send_test:Science] Running Loop=1
2021-04-12T04:41:06.286Z,1618202466.286 [esp_send_test:Science:PeakDetectFDOM] Stopped
2021-04-12T04:41:06.286Z,1618202466.286 [esp_send_test:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize esp_send_test:Science:PeakDetectFDOM
2021-04-12T04:41:06.291Z,1618202466.291 [esp_send_test:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2021-04-12T04:41:06.291Z,1618202466.291 [esp_send_test:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-04-12T04:41:06.291Z,1618202466.291 [esp_send_test:Science:PeakDetectOil] Stopped
2021-04-12T04:41:06.291Z,1618202466.291 [esp_send_test:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize esp_send_test:Science:PeakDetectOil
2021-04-12T04:41:06.291Z,1618202466.291 [esp_send_test:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2021-04-12T04:41:06.291Z,1618202466.291 [esp_send_test:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-04-12T04:41:06.291Z,1618202466.291 [esp_send_test:Science:PeakDetectNO3] Stopped
2021-04-12T04:41:06.291Z,1618202466.291 [esp_send_test:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize esp_send_test:Science:PeakDetectNO3
2021-04-12T04:41:06.291Z,1618202466.291 [esp_send_test:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2021-04-12T04:41:06.291Z,1618202466.291 [esp_send_test:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-04-12T04:41:06.291Z,1618202466.291 [esp_send_test:Science:HighestChlPeakReport] Stopped
2021-04-12T04:41:06.292Z,1618202466.292 [esp_send_test:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize esp_send_test:Science:HighestChlPeakReport
2021-04-12T04:41:06.292Z,1618202466.292 [esp_send_test:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2021-04-12T04:41:06.292Z,1618202466.292 [esp_send_test:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2021-04-12T04:41:06.292Z,1618202466.292 [esp_send_test:Science:PeakDetectChl] Stopped
2021-04-12T04:41:06.292Z,1618202466.292 [esp_send_test:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize esp_send_test:Science:PeakDetectChl
2021-04-12T04:41:06.292Z,1618202466.292 [esp_send_test:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2021-04-12T04:41:06.292Z,1618202466.292 [esp_send_test:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-04-12T04:41:06.292Z,1618202466.292 [esp_send_test:Science:OceanCurrent:A.] Running Loop=1
2021-04-12T04:41:06.292Z,1618202466.292 [esp_send_test:Science:Read_Oil] Stopped
2021-04-12T04:41:06.292Z,1618202466.292 [esp_send_test:Science:F] Stopped
2021-04-12T04:41:06.292Z,1618202466.292 [esp_send_test:Science:E] Running Loop=1
2021-04-12T04:41:06.293Z,1618202466.293 [esp_send_test:Science:E](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2021-04-12T04:41:06.293Z,1618202466.293 [esp_send_test:Science:D] Stopped
2021-04-12T04:41:06.293Z,1618202466.293 [esp_send_test:Science:C] Running Loop=1
2021-04-12T04:41:06.294Z,1618202466.294 [esp_send_test:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature
2021-04-12T04:41:06.295Z,1618202466.295 [esp_send_test:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity
2021-04-12T04:41:06.295Z,1618202466.295 [esp_send_test:Science:B] Running Loop=1
2021-04-12T04:41:06.296Z,1618202466.296 [esp_send_test:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2021-04-12T04:41:06.297Z,1618202466.297 [esp_send_test:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2021-04-12T04:41:06.297Z,1618202466.297 [esp_send_test:Science:A] Stopped
2021-04-12T04:41:06.652Z,1618202466.652 [esp_send_test:SampleESP] Running Loop=1
2021-04-12T04:41:06.653Z,1618202466.653 [esp_send_test:SampleESP](DEBUG): Aggregate::initialize esp_send_test:SampleESP
2021-04-12T04:41:06.653Z,1618202466.653 [esp_send_test:SampleESP:sample] Running Loop=1
2021-04-12T04:41:06.653Z,1618202466.653 [esp_send_test:SampleESP:sample](DEBUG): Aggregate::initialize esp_send_test:SampleESP:sample
2021-04-12T04:41:06.653Z,1618202466.653 [esp_send_test:SampleESP:sample:A] Running Loop=1
2021-04-12T04:41:06.654Z,1618202466.654 [esp_send_test:SampleESP:sample:A](IMPORTANT): Requesting ESP cartridge type: -4.000000 count 
2021-04-12T04:41:06.654Z,1618202466.654 [esp_send_test:SampleESP:sample:A] Stopped
2021-04-12T04:41:06.654Z,1618202466.654 [esp_send_test:SampleESP:sample:B.ESPCartridgeSelect] Running Loop=1
2021-04-12T04:41:06.654Z,1618202466.654 [esp_send_test:SampleESP:sample:B.ESPCartridgeSelect](INFO): Initializing ESPCartridgeSelect.
2021-04-12T04:41:06.764Z,1618202466.764 [WetLabsBB2FL](INFO): Powering up
2021-04-12T04:41:07.074Z,1618202467.074 [esp_send_test:SampleESP:sample:B.ESPCartridgeSelect](INFO): Issuing request for ESP cartridge type: -4.
2021-04-12T04:41:07.074Z,1618202467.074 [esp_send_test:SampleESP:sample:B.ESPCartridgeSelect] Stopped
2021-04-12T04:41:07.074Z,1618202467.074 [esp_send_test:SampleESP:sample:B.ESPCartridgeSelect](INFO): Uninitializing ESPCartridgeSelect.
2021-04-12T04:41:07.074Z,1618202467.074 [esp_send_test:SampleESP:sample:TriggerESP] Running Loop=1
2021-04-12T04:41:07.446Z,1618202467.446 [esp_send_test:SampleESP:sample:TriggerESP](DEBUG): Initialize ReadDataComponent to sense ESPComponent.sampling
2021-04-12T04:41:09.068Z,1618202469.068 [ESPComponent](DEBUG): Writing samplingActive=1, sampleNumber=0
2021-04-12T04:41:09.094Z,1618202469.094 [esp_send_test:SampleESP:sample:TriggerESP] Stopped
2021-04-12T04:41:09.094Z,1618202469.094 [esp_send_test:SampleESP:sample:D] Running Loop=1
2021-04-12T04:41:09.484Z,1618202469.484 [ESPComponent](DEBUG): Writing samplingActive=0, sampleNumber=1
2021-04-12T04:41:09.522Z,1618202469.522 [esp_send_test:SampleESP:sample:D](ERROR): data element is not active.
2021-04-12T04:41:09.522Z,1618202469.522 [esp_send_test:SampleESP:sample:D](ERROR): data element is not active.
2021-04-12T04:41:09.527Z,1618202469.527 [esp_send_test:SampleESP:sample:D](IMPORTANT): ESP sampling at 0.215774 m in nan degC water with nan ug/l chlorophyll fluorescence.
2021-04-12T04:41:09.527Z,1618202469.527 [esp_send_test:SampleESP:sample:D] Stopped
2021-04-12T04:41:09.527Z,1618202469.527 [esp_send_test:SampleESP:sample:E.Wait] Running Loop=1
2021-04-12T04:41:09.527Z,1618202469.527 [esp_send_test:SampleESP:sample:E.Wait](DEBUG): Initialize Wait Component.
2021-04-12T04:41:10.280Z,1618202470.280 [CTD_Seabird](ERROR): Failed to parse device response: <!--startlogging-->
2021-04-12T04:41:14.404Z,1618202474.404 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:41:24.858Z,1618202484.858 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-12T04:41:26.088Z,1618202486.088 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:41:37.800Z,1618202497.800 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:41:40.201Z,1618202500.201 [esp_send_test:SampleESP:sample:E.Wait](INFO): Done Waiting.
2021-04-12T04:41:40.202Z,1618202500.202 [esp_send_test:SampleESP:sample:E.Wait] Stopped
2021-04-12T04:41:40.202Z,1618202500.202 [esp_send_test:SampleESP:sample:E.Wait](DEBUG): Uninitialize Wait Component.
2021-04-12T04:41:40.202Z,1618202500.202 [esp_send_test:SampleESP:sample:WaitForESP] Running Loop=1
2021-04-12T04:41:40.578Z,1618202500.578 [esp_send_test:SampleESP:sample:WaitForESP](DEBUG): Initialize ReadDataComponent to sense ESPComponent.sample_number
2021-04-12T04:41:41.403Z,1618202501.403 [CTD_Seabird](FAULT): Failed to acquire real or simulated CTD data within timeout.
2021-04-12T04:41:41.403Z,1618202501.403 [CTD_Seabird] Communications Fault, FailCount= 1
2021-04-12T04:41:41.403Z,1618202501.404 [CTD_Seabird](ERROR): Communications Fault
2021-04-12T04:41:41.405Z,1618202501.405 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2021-04-12T04:41:41.807Z,1618202501.807 [CTD_Seabird](INFO): Powering down
2021-04-12T04:41:42.747Z,1618202502.747 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2021-04-12T04:41:43.018Z,1618202503.018 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2021-04-12T04:41:43.018Z,1618202503.018 [CTD_Seabird] No Fault, FailCount= 1
2021-04-12T04:41:49.529Z,1618202509.529 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:41:50.199Z,1618202510.199 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2021-04-12T04:42:01.238Z,1618202521.238 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:42:08.101Z,1618202528.101 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-12T04:42:09.275Z,1618202529.275 [CTD_Seabird](FAULT): Failed to acquire real or simulated CTD data within timeout.
2021-04-12T04:42:09.276Z,1618202529.276 [CTD_Seabird] Communications Fault, FailCount= 1
2021-04-12T04:42:09.276Z,1618202529.276 [CTD_Seabird](ERROR): Communications Fault
2021-04-12T04:42:09.290Z,1618202529.290 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2021-04-12T04:42:09.679Z,1618202529.679 [CTD_Seabird](INFO): Powering down
2021-04-12T04:42:10.619Z,1618202530.619 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2021-04-12T04:42:10.909Z,1618202530.909 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2021-04-12T04:42:10.909Z,1618202530.909 [CTD_Seabird] No Fault, FailCount= 1
2021-04-12T04:42:13.358Z,1618202533.358 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:42:14.535Z,1618202534.535 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2021-04-12T04:42:14.535Z,1618202534.535 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-04-12T04:42:14.546Z,1618202534.546 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-04-12T04:42:14.957Z,1618202534.957 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-04-12T04:42:14.957Z,1618202534.957 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2021-04-12T04:42:18.071Z,1618202538.071 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2021-04-12T04:42:21.018Z,1618202541.018 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-12T04:42:33.547Z,1618202553.547 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-12T04:42:37.143Z,1618202557.143 [CTD_Seabird](FAULT): Failed to acquire real or simulated CTD data within timeout.
2021-04-12T04:42:37.143Z,1618202557.143 [CTD_Seabird] Communications Fault, FailCount= 1
2021-04-12T04:42:37.143Z,1618202557.143 [CTD_Seabird](ERROR): Communications Fault
2021-04-12T04:42:37.190Z,1618202557.190 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2021-04-12T04:42:37.548Z,1618202557.548 [CTD_Seabird](INFO): Powering down
2021-04-12T04:42:38.483Z,1618202558.483 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2021-04-12T04:42:38.819Z,1618202558.819 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2021-04-12T04:42:38.819Z,1618202558.819 [CTD_Seabird] No Fault, FailCount= 1
2021-04-12T04:42:45.935Z,1618202565.935 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2021-04-12T04:42:47.282Z,1618202567.282 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-12T04:43:00.205Z,1618202580.205 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-12T04:43:05.007Z,1618202585.007 [CTD_Seabird](FAULT): Failed to acquire real or simulated CTD data within timeout.
2021-04-12T04:43:05.007Z,1618202585.007 [CTD_Seabird] Communications Fault, FailCount= 1
2021-04-12T04:43:05.008Z,1618202585.008 [CTD_Seabird](ERROR): Communications Fault
2021-04-12T04:43:05.061Z,1618202585.061 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2021-04-12T04:43:05.412Z,1618202585.412 [CTD_Seabird](INFO): Powering down
2021-04-12T04:43:06.351Z,1618202586.351 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2021-04-12T04:43:06.656Z,1618202586.656 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2021-04-12T04:43:06.656Z,1618202586.656 [CTD_Seabird] No Fault, FailCount= 1
2021-04-12T04:43:11.117Z,1618202591.117 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:43:19.709Z,1618202599.709 [CommandLine](IMPORTANT): got command show stack
2021-04-12T04:43:19.709Z,1618202599.709 [CommandLine](IMPORTANT): Behavior Stack:

2021-04-12T04:43:19.711Z,1618202599.711 [esp_send_test:Science](IMPORTANT): Priority 0: esp_send_test:Science:B
2021-04-12T04:43:19.711Z,1618202599.711 [esp_send_test:Science](IMPORTANT): Priority 1: esp_send_test:Science:C
2021-04-12T04:43:19.711Z,1618202599.711 [esp_send_test:Science](IMPORTANT): Priority 2: esp_send_test:Science:E
2021-04-12T04:43:19.711Z,1618202599.711 [esp_send_test:Science:OceanCurrent](IMPORTANT): Priority 3: esp_send_test:Science:OceanCurrent:A.
2021-04-12T04:43:19.712Z,1618202599.712 [esp_send_test](IMPORTANT): Priority 4: esp_send_test:B.AbortSample
2021-04-12T04:43:19.712Z,1618202599.712 [esp_send_test:SampleESP:sample](IMPORTANT): Priority 5: esp_send_test:SampleESP:sample:WaitForESP
2021-04-12T04:43:23.634Z,1618202603.634 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:43:26.422Z,1618202606.422 [NAL9602](FAULT): GPS failed to acquire within timeout.
2021-04-12T04:43:26.422Z,1618202606.422 [NAL9602] Data Fault, FailCount= 3
2021-04-12T04:43:26.422Z,1618202606.422 [NAL9602](ERROR): Data Fault
2021-04-12T04:43:26.444Z,1618202606.444 [CBIT](ERROR): Data Fault in component: NAL9602
2021-04-12T04:43:26.832Z,1618202606.832 [NAL9602](INFO): Powering down
2021-04-12T04:43:27.221Z,1618202607.221 [NAL9602](FAULT): LCB fault:  Software Overcurrent.
2021-04-12T04:43:27.221Z,1618202607.221 [NAL9602] Hardware Fault, FailCount= 3
2021-04-12T04:43:27.221Z,1618202607.221 [NAL9602](ERROR): Hardware Fault
2021-04-12T04:43:27.642Z,1618202607.642 [CBIT](INFO): Clearing failed state for component NAL9602
2021-04-12T04:43:27.642Z,1618202607.642 [NAL9602] No Fault, FailCount= 3
2021-04-12T04:43:33.879Z,1618202613.879 [CTD_Seabird](FAULT): Failed to acquire real or simulated CTD data within timeout.
2021-04-12T04:43:33.879Z,1618202613.879 [CTD_Seabird] Communications Fault, FailCount= 1
2021-04-12T04:43:33.879Z,1618202613.879 [CTD_Seabird](ERROR): Communications Fault
2021-04-12T04:43:34.107Z,1618202614.107 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2021-04-12T04:43:34.284Z,1618202614.284 [CTD_Seabird](INFO): Powering down
2021-04-12T04:43:35.219Z,1618202615.219 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2021-04-12T04:43:35.319Z,1618202615.319 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2021-04-12T04:43:35.319Z,1618202615.319 [CTD_Seabird] No Fault, FailCount= 1
2021-04-12T04:43:39.749Z,1618202619.749 [DVL_micro](ERROR): Failed to parse:
:WI,+00887,-01684,+00517,+00000,A
2021-04-12T04:43:57.117Z,1618202637.117 [NAL9602](INFO): Powering up NAL9602
2021-04-12T04:43:57.989Z,1618202637.989 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-12T04:44:02.755Z,1618202642.755 [CTD_Seabird](FAULT): Failed to acquire real or simulated CTD data within timeout.
2021-04-12T04:44:02.755Z,1618202642.755 [CTD_Seabird] Communications Fault, FailCount= 1
2021-04-12T04:44:02.755Z,1618202642.755 [CTD_Seabird](ERROR): Communications Fault
2021-04-12T04:44:02.849Z,1618202642.849 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2021-04-12T04:44:03.160Z,1618202643.160 [CTD_Seabird](INFO): Powering down
2021-04-12T04:44:04.095Z,1618202644.095 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2021-04-12T04:44:04.419Z,1618202644.419 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2021-04-12T04:44:04.419Z,1618202644.419 [CTD_Seabird] No Fault, FailCount= 1
2021-04-12T04:44:08.041Z,1618202648.041 [NAL9602](INFO): NAL9602 initialized
2021-04-12T04:44:11.547Z,1618202651.547 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2021-04-12T04:44:20.564Z,1618202660.564 [DVL_micro](ERROR): only read 0 of 4 data items
2021-04-12T04:44:20.564Z,1618202660.564 [DVL_micro](ERROR): Failed to parse:
:BI-01853,+00527,+00000,I
2021-04-12T04:44:30.627Z,1618202670.627 [CTD_Seabird](FAULT): Failed to acquire real or simulated CTD data within timeout.
2021-04-12T04:44:30.627Z,1618202670.627 [CTD_Seabird] Communications Fault, FailCount= 1
2021-04-12T04:44:30.627Z,1618202670.627 [CTD_Seabird](ERROR): Communications Fault
2021-04-12T04:44:30.714Z,1618202670.714 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2021-04-12T04:44:31.032Z,1618202671.032 [CTD_Seabird](INFO): Powering down
2021-04-12T04:44:31.967Z,1618202671.967 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2021-04-12T04:44:32.306Z,1618202672.306 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2021-04-12T04:44:32.306Z,1618202672.306 [CTD_Seabird] No Fault, FailCount= 1
2021-04-12T04:44:39.422Z,1618202679.422 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2021-04-12T04:44:53.273Z,1618202693.273 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-04-12T04:44:58.492Z,1618202698.492 [CTD_Seabird](FAULT): Failed to acquire real or simulated CTD data within timeout.
2021-04-12T04:44:58.492Z,1618202698.492 [CTD_Seabird] Communications Fault, FailCount= 1
2021-04-12T04:44:58.492Z,1618202698.492 [CTD_Seabird](ERROR): Communications Fault
2021-04-12T04:44:58.618Z,1618202698.618 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2021-04-12T04:44:58.896Z,1618202698.896 [CTD_Seabird](INFO): Powering down
2021-04-12T04:44:59.831Z,1618202699.831 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2021-04-12T04:45:00.190Z,1618202700.190 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2021-04-12T04:45:00.190Z,1618202700.190 [CTD_Seabird] No Fault, FailCount= 1
2021-04-12T04:45:07.283Z,1618202707.283 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2021-04-12T04:45:15.520Z,1618202715.520 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2021-04-12T04:45:15.520Z,1618202715.520 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-04-12T04:45:15.531Z,1618202715.531 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-04-12T04:45:15.934Z,1618202715.934 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-04-12T04:45:15.934Z,1618202715.934 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2021-04-12T04:45:19.936Z,1618202719.936 [BPC1](ERROR): BPC1A: No match for serial number 0000 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2021-04-12T04:45:27.359Z,1618202727.359 [CTD_Seabird](FAULT): Failed to acquire real or simulated CTD data within timeout.
2021-04-12T04:45:27.360Z,1618202727.360 [CTD_Seabird] Communications Fault, FailCount= 1
2021-04-12T04:45:27.360Z,1618202727.360 [CTD_Seabird](ERROR): Communications Fault
2021-04-12T04:45:27.647Z,1618202727.647 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2021-04-12T04:45:27.764Z,1618202727.764 [CTD_Seabird](INFO): Powering down
2021-04-12T04:45:28.699Z,1618202728.699 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2021-04-12T04:45:28.871Z,1618202728.871 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2021-04-12T04:45:28.871Z,1618202728.871 [CTD_Seabird] No Fault, FailCount= 1
2021-04-12T04:45:36.151Z,1618202736.151 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2021-04-12T04:45:55.224Z,1618202755.224 [CTD_Seabird](FAULT): Failed to acquire real or simulated CTD data within timeout.
2021-04-12T04:45:55.224Z,1618202755.224 [CTD_Seabird] Communications Fault, FailCount= 1
2021-04-12T04:45:55.224Z,1618202755.224 [CTD_Seabird](ERROR): Communications Fault
2021-04-12T04:45:55.527Z,1618202755.527 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2021-04-12T04:45:55.628Z,1618202755.628 [CTD_Seabird](INFO): Powering down
2021-04-12T04:45:56.563Z,1618202756.563 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2021-04-12T04:45:56.723Z,1618202756.723 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2021-04-12T04:45:56.723Z,1618202756.723 [CTD_Seabird] No Fault, FailCount= 1
2021-04-12T04:46:04.031Z,1618202764.031 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2021-04-12T04:46:23.103Z,1618202783.103 [CTD_Seabird](FAULT): Failed to acquire real or simulated CTD data within timeout.
2021-04-12T04:46:23.104Z,1618202783.104 [CTD_Seabird] Communications Fault, FailCount= 1
2021-04-12T04:46:23.104Z,1618202783.104 [CTD_Seabird](ERROR): Communications Fault
2021-04-12T04:46:23.396Z,1618202783.396 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2021-04-12T04:46:23.508Z,1618202783.508 [CTD_Seabird](INFO): Powering down
2021-04-12T04:46:24.443Z,1618202784.443 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2021-04-12T04:46:24.642Z,1618202784.642 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2021-04-12T04:46:24.642Z,1618202784.642 [CTD_Seabird] No Fault, FailCount= 1
2021-04-12T04:46:31.907Z,1618202791.907 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2021-04-12T04:46:46.888Z,1618202806.888 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:46:50.971Z,1618202810.971 [CTD_Seabird](FAULT): Failed to acquire real or simulated CTD data within timeout.
2021-04-12T04:46:50.972Z,1618202810.972 [CTD_Seabird] Communications Fault, FailCount= 1
2021-04-12T04:46:50.972Z,1618202810.972 [CTD_Seabird](ERROR): Communications Fault
2021-04-12T04:46:51.279Z,1618202811.279 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2021-04-12T04:46:51.376Z,1618202811.376 [CTD_Seabird](INFO): Powering down
2021-04-12T04:46:52.311Z,1618202812.311 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2021-04-12T04:46:52.479Z,1618202812.479 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2021-04-12T04:46:52.479Z,1618202812.479 [CTD_Seabird] No Fault, FailCount= 1
2021-04-12T04:47:19.839Z,1618202839.839 [CTD_Seabird](FAULT): Failed to acquire real or simulated CTD data within timeout.
2021-04-12T04:47:19.840Z,1618202839.840 [CTD_Seabird] Communications Fault, FailCount= 1
2021-04-12T04:47:19.840Z,1618202839.840 [CTD_Seabird](ERROR): Communications Fault
2021-04-12T04:47:19.947Z,1618202839.947 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2021-04-12T04:47:20.244Z,1618202840.244 [CTD_Seabird](INFO): Powering down
2021-04-12T04:47:21.183Z,1618202841.183 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2021-04-12T04:47:21.206Z,1618202841.206 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2021-04-12T04:47:21.206Z,1618202841.206 [CTD_Seabird] No Fault, FailCount= 1
2021-04-12T04:47:30.202Z,1618202850.202 [CommandLine](IMPORTANT): got command show stack
2021-04-12T04:47:30.202Z,1618202850.202 [CommandLine](IMPORTANT): Behavior Stack:

2021-04-12T04:47:30.204Z,1618202850.204 [esp_send_test:Science](IMPORTANT): Priority 0: esp_send_test:Science:B
2021-04-12T04:47:30.204Z,1618202850.204 [esp_send_test:Science](IMPORTANT): Priority 1: esp_send_test:Science:C
2021-04-12T04:47:30.204Z,1618202850.204 [esp_send_test:Science](IMPORTANT): Priority 2: esp_send_test:Science:E
2021-04-12T04:47:30.204Z,1618202850.204 [esp_send_test:Science:OceanCurrent](IMPORTANT): Priority 3: esp_send_test:Science:OceanCurrent:A.
2021-04-12T04:47:30.205Z,1618202850.205 [esp_send_test](IMPORTANT): Priority 4: esp_send_test:B.AbortSample
2021-04-12T04:47:30.205Z,1618202850.205 [esp_send_test:SampleESP:sample](IMPORTANT): Priority 5: esp_send_test:SampleESP:sample:WaitForESP
2021-04-12T04:47:48.712Z,1618202868.712 [CTD_Seabird](FAULT): Failed to acquire real or simulated CTD data within timeout.
2021-04-12T04:47:48.712Z,1618202868.712 [CTD_Seabird] Communications Fault, FailCount= 1
2021-04-12T04:47:48.712Z,1618202868.712 [CTD_Seabird](ERROR): Communications Fault
2021-04-12T04:47:49.060Z,1618202869.060 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2021-04-12T04:47:49.116Z,1618202869.116 [CTD_Seabird](INFO): Powering down
2021-04-12T04:47:50.051Z,1618202870.051 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2021-04-12T04:47:50.256Z,1618202870.256 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2021-04-12T04:47:50.256Z,1618202870.256 [CTD_Seabird] No Fault, FailCount= 1
2021-04-12T04:47:57.503Z,1618202877.503 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2021-04-12T04:48:16.512Z,1618202896.512 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2021-04-12T04:48:16.512Z,1618202896.512 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-04-12T04:48:16.549Z,1618202896.549 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-04-12T04:48:16.576Z,1618202896.576 [CTD_Seabird](FAULT): Failed to acquire real or simulated CTD data within timeout.
2021-04-12T04:48:16.576Z,1618202896.576 [CTD_Seabird] Communications Fault, FailCount= 1
2021-04-12T04:48:16.576Z,1618202896.576 [CTD_Seabird](ERROR): Communications Fault
2021-04-12T04:48:16.922Z,1618202896.922 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-04-12T04:48:16.922Z,1618202896.922 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2021-04-12T04:48:16.923Z,1618202896.923 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2021-04-12T04:48:16.980Z,1618202896.980 [CTD_Seabird](INFO): Powering down
2021-04-12T04:48:17.919Z,1618202897.919 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2021-04-12T04:48:18.119Z,1618202898.119 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2021-04-12T04:48:18.119Z,1618202898.119 [CTD_Seabird] No Fault, FailCount= 1
2021-04-12T04:48:21.380Z,1618202901.380 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:48:25.371Z,1618202905.371 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2021-04-12T04:48:44.443Z,1618202924.443 [CTD_Seabird](FAULT): Failed to acquire real or simulated CTD data within timeout.
2021-04-12T04:48:44.444Z,1618202924.444 [CTD_Seabird] Communications Fault, FailCount= 1
2021-04-12T04:48:44.444Z,1618202924.444 [CTD_Seabird](ERROR): Communications Fault
2021-04-12T04:48:44.831Z,1618202924.831 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2021-04-12T04:48:44.848Z,1618202924.848 [CTD_Seabird](INFO): Powering down
2021-04-12T04:48:45.787Z,1618202925.787 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2021-04-12T04:48:46.015Z,1618202926.015 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2021-04-12T04:48:46.015Z,1618202926.015 [CTD_Seabird] No Fault, FailCount= 1
2021-04-12T04:48:53.239Z,1618202933.239 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2021-04-12T04:49:12.311Z,1618202952.311 [CTD_Seabird](FAULT): Failed to acquire real or simulated CTD data within timeout.
2021-04-12T04:49:12.312Z,1618202952.312 [CTD_Seabird] Communications Fault, FailCount= 1
2021-04-12T04:49:12.312Z,1618202952.312 [CTD_Seabird](ERROR): Communications Fault
2021-04-12T04:49:12.690Z,1618202952.690 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2021-04-12T04:49:12.716Z,1618202952.716 [CTD_Seabird](INFO): Powering down
2021-04-12T04:49:13.655Z,1618202953.655 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2021-04-12T04:49:13.875Z,1618202953.875 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2021-04-12T04:49:13.875Z,1618202953.875 [CTD_Seabird] No Fault, FailCount= 1
2021-04-12T04:49:17.912Z,1618202957.912 [DVL_micro](ERROR): only read 3 of 4 data items
2021-04-12T04:49:17.912Z,1618202957.912 [DVL_micro](ERROR): Failed to parse:
:BI,+01373,-0665,+00000,I
2021-04-12T04:49:21.107Z,1618202961.107 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2021-04-12T04:49:38.494Z,1618202978.494 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2021-04-12T04:49:38.498Z,1618202978.498 [BPC1](ERROR): BPC1A failed to parse battery stick message.
2021-04-12T04:49:40.182Z,1618202980.182 [CTD_Seabird](FAULT): Failed to acquire real or simulated CTD data within timeout.
2021-04-12T04:49:40.182Z,1618202980.182 [CTD_Seabird] Communications Fault, FailCount= 1
2021-04-12T04:49:40.183Z,1618202980.183 [CTD_Seabird](ERROR): Communications Fault
2021-04-12T04:49:40.545Z,1618202980.545 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2021-04-12T04:49:40.588Z,1618202980.588 [CTD_Seabird](INFO): Powering down
2021-04-12T04:49:41.527Z,1618202981.527 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2021-04-12T04:49:41.783Z,1618202981.783 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2021-04-12T04:49:41.784Z,1618202981.784 [CTD_Seabird] No Fault, FailCount= 1
2021-04-12T04:49:48.979Z,1618202988.979 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2021-04-12T04:49:50.280Z,1618202990.280 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:50:01.135Z,1618203001.135 [DVL_micro](ERROR): only read 3 of 4 data items
2021-04-12T04:50:01.135Z,1618203001.135 [DVL_micro](ERROR): Failed to parse:
:RD,+99999.99,+9999.99,+9999.99
2021-04-12T04:50:01.997Z,1618203001.997 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:50:08.051Z,1618203008.051 [CTD_Seabird](FAULT): Failed to acquire real or simulated CTD data within timeout.
2021-04-12T04:50:08.052Z,1618203008.052 [CTD_Seabird] Communications Fault, FailCount= 1
2021-04-12T04:50:08.052Z,1618203008.052 [CTD_Seabird](ERROR): Communications Fault
2021-04-12T04:50:08.427Z,1618203008.427 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2021-04-12T04:50:08.456Z,1618203008.456 [CTD_Seabird](INFO): Powering down
2021-04-12T04:50:09.395Z,1618203009.395 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2021-04-12T04:50:09.662Z,1618203009.662 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2021-04-12T04:50:09.662Z,1618203009.662 [CTD_Seabird] No Fault, FailCount= 1
2021-04-12T04:50:12.430Z,1618203012.430 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2021-04-12T04:50:12.430Z,1618203012.430 [BPC1](ERROR): BPC1A failed to parse battery stick message.
2021-04-12T04:50:24.220Z,1618203024.220 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:50:35.919Z,1618203035.919 [CTD_Seabird](FAULT): Failed to acquire real or simulated CTD data within timeout.
2021-04-12T04:50:35.919Z,1618203035.919 [CTD_Seabird] Communications Fault, FailCount= 1
2021-04-12T04:50:35.919Z,1618203035.919 [CTD_Seabird](ERROR): Communications Fault
2021-04-12T04:50:36.324Z,1618203036.324 [CTD_Seabird](INFO): Powering down
2021-04-12T04:50:36.353Z,1618203036.353 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:50:36.413Z,1618203036.413 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2021-04-12T04:50:37.271Z,1618203037.271 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2021-04-12T04:50:37.511Z,1618203037.511 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2021-04-12T04:50:37.511Z,1618203037.511 [CTD_Seabird] No Fault, FailCount= 1
2021-04-12T04:50:46.023Z,1618203046.023 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-12T04:50:48.057Z,1618203048.057 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:50:52.032Z,1618203052.032 [DVL_micro](ERROR): only read 3 of 4 data items
2021-04-12T04:50:52.033Z,1618203052.033 [DVL_micro](ERROR): Failed to parse:
:BI,+0138,+00689,+00000,I
2021-04-12T04:51:00.179Z,1618203060.179 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:51:04.799Z,1618203064.799 [CTD_Seabird](FAULT): Failed to acquire real or simulated CTD data within timeout.
2021-04-12T04:51:04.799Z,1618203064.799 [CTD_Seabird] Communications Fault, FailCount= 1
2021-04-12T04:51:04.799Z,1618203064.799 [CTD_Seabird](ERROR): Communications Fault
2021-04-12T04:51:04.990Z,1618203064.990 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2021-04-12T04:51:05.204Z,1618203065.204 [CTD_Seabird](INFO): Powering down
2021-04-12T04:51:06.139Z,1618203066.139 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2021-04-12T04:51:06.214Z,1618203066.214 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2021-04-12T04:51:06.214Z,1618203066.214 [CTD_Seabird] No Fault, FailCount= 1
2021-04-12T04:51:11.901Z,1618203071.901 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:51:17.516Z,1618203077.516 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2021-04-12T04:51:17.516Z,1618203077.516 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-04-12T04:51:17.543Z,1618203077.543 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-04-12T04:51:17.934Z,1618203077.934 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-04-12T04:51:17.934Z,1618203077.934 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2021-04-12T04:51:24.009Z,1618203084.009 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:51:33.667Z,1618203093.667 [CTD_Seabird](FAULT): Failed to acquire real or simulated CTD data within timeout.
2021-04-12T04:51:33.667Z,1618203093.667 [CTD_Seabird] Communications Fault, FailCount= 1
2021-04-12T04:51:33.667Z,1618203093.667 [CTD_Seabird](ERROR): Communications Fault
2021-04-12T04:51:33.747Z,1618203093.747 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2021-04-12T04:51:34.072Z,1618203094.072 [CTD_Seabird](INFO): Powering down
2021-04-12T04:51:35.007Z,1618203095.007 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2021-04-12T04:51:35.291Z,1618203095.291 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2021-04-12T04:51:35.291Z,1618203095.291 [CTD_Seabird] No Fault, FailCount= 1
2021-04-12T04:51:35.732Z,1618203095.732 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:51:41.405Z,1618203101.405 [CommandLine](IMPORTANT): got command get ESPComponent.simulateHardware
2021-04-12T04:51:41.406Z,1618203101.406 [CommandLine](IMPORTANT): ESPComponent.simulateHardware 1 bool
2021-04-12T04:51:42.460Z,1618203102.460 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2021-04-12T04:51:47.440Z,1618203107.440 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:51:52.329Z,1618203112.329 [CommandLine](IMPORTANT): got command stop
2021-04-12T04:51:52.330Z,1618203112.330 [CommandLine](IMPORTANT): Scheduling is paused
2021-04-12T04:51:52.330Z,1618203112.330 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop
2021-04-12T04:51:52.639Z,1618203112.639 [MissionManager](INFO): MissionManager is completed.
2021-04-12T04:51:52.639Z,1618203112.639 [MissionManager](INFO): Uninitializing Mission esp_send_test
2021-04-12T04:51:52.639Z,1618203112.639 [esp_send_test] Stopped
2021-04-12T04:51:52.639Z,1618203112.639 [esp_send_test](DEBUG): Aggregate::uninitialize esp_send_test
2021-04-12T04:51:52.639Z,1618203112.639 [esp_send_test:Science] Stopped
2021-04-12T04:51:52.639Z,1618203112.639 [esp_send_test:Science](DEBUG): Aggregate::uninitialize esp_send_test:Science
2021-04-12T04:51:52.639Z,1618203112.639 [esp_send_test:Science:B] Stopped
2021-04-12T04:51:52.639Z,1618203112.639 [esp_send_test:Science:C] Stopped
2021-04-12T04:51:52.639Z,1618203112.639 [esp_send_test:Science:E] Stopped
2021-04-12T04:51:52.640Z,1618203112.640 [esp_send_test:Science:OceanCurrent] Stopped
2021-04-12T04:51:52.640Z,1618203112.640 [esp_send_test:Science:OceanCurrent](DEBUG): Aggregate::uninitialize esp_send_test:Science:OceanCurrent
2021-04-12T04:51:52.640Z,1618203112.640 [esp_send_test:Science:OceanCurrent:A.] Stopped
2021-04-12T04:51:52.640Z,1618203112.640 [esp_send_test:B.AbortSample] Stopped
2021-04-12T04:51:52.640Z,1618203112.640 [esp_send_test:B.AbortSample](INFO): Uninitializing AbortSample.
2021-04-12T04:51:52.640Z,1618203112.640 [esp_send_test:SampleESP] Stopped
2021-04-12T04:51:52.640Z,1618203112.640 [esp_send_test:SampleESP](DEBUG): Aggregate::uninitialize esp_send_test:SampleESP
2021-04-12T04:51:52.640Z,1618203112.640 [esp_send_test:SampleESP:sample] Stopped
2021-04-12T04:51:52.640Z,1618203112.640 [esp_send_test:SampleESP:sample](DEBUG): Aggregate::uninitialize esp_send_test:SampleESP:sample
2021-04-12T04:51:52.640Z,1618203112.640 [esp_send_test:SampleESP:sample:WaitForESP] Stopped
2021-04-12T04:51:52.640Z,1618203112.640 [esp_send_test:SampleInLab] Stopped
2021-04-12T04:51:52.641Z,1618203112.641 [esp_send_test:SampleInLab](DEBUG): Aggregate::uninitialize esp_send_test:SampleInLab
2021-04-12T04:51:52.641Z,1618203112.641 [esp_send_test:SampleInLab:GrabSamples] Stopped
2021-04-12T04:51:52.641Z,1618203112.641 [esp_send_test:SampleInLab:GrabSamples](DEBUG): Aggregate::uninitialize esp_send_test:SampleInLab:GrabSamples
2021-04-12T04:51:52.641Z,1618203112.641 [esp_send_test:SampleInLab:GrabSamples:Sample1] Stopped
2021-04-12T04:51:52.641Z,1618203112.641 [esp_send_test:SampleInLab:GrabSamples:Sample1](DEBUG): Aggregate::uninitialize esp_send_test:SampleInLab:GrabSamples:Sample1
2021-04-12T04:51:52.641Z,1618203112.641 [esp_send_test:SampleInLab:GrabSamples:Sample1:B] Stopped
2021-04-12T04:51:52.641Z,1618203112.641 [esp_send_test:SampleInLab:GrabSamples:Sample1:B](DEBUG): Aggregate::uninitialize esp_send_test:SampleInLab:GrabSamples:Sample1:B
2021-04-12T04:51:53.058Z,1618203113.058 [MissionManager](IMPORTANT): Started mission Default
2021-04-12T04:51:53.059Z,1618203113.059 [Default] Running Loop=1
2021-04-12T04:51:53.059Z,1618203113.059 [Default](DEBUG): Aggregate::initialize Default
2021-04-12T04:51:53.059Z,1618203113.059 [Default:B.GoToSurface] Running Loop=1
2021-04-12T04:51:53.059Z,1618203113.059 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-04-12T04:51:53.059Z,1618203113.059 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-04-12T04:51:53.060Z,1618203113.060 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-04-12T04:51:53.060Z,1618203113.060 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-04-12T04:51:53.060Z,1618203113.060 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-04-12T04:51:53.060Z,1618203113.060 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-04-12T04:51:53.061Z,1618203113.061 [Default:A.Wait] Running Loop=1
2021-04-12T04:51:53.061Z,1618203113.061 [Default:A.Wait](DEBUG): Initialize Wait Component.
2021-04-12T04:51:53.163Z,1618203113.163 [WetLabsBB2FL](INFO): Powering down
2021-04-12T04:51:59.164Z,1618203119.164 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:52:06.073Z,1618203126.073 [Default:A.Wait](INFO): Done Waiting.
2021-04-12T04:52:06.073Z,1618203126.073 [Default:A.Wait] Stopped
2021-04-12T04:52:06.073Z,1618203126.073 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2021-04-12T04:52:06.434Z,1618203126.434 [Default:CheckIn] Running Loop=1
2021-04-12T04:52:06.434Z,1618203126.434 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-12T04:52:06.434Z,1618203126.434 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-12T04:52:09.200Z,1618203129.200 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 5.
2021-04-12T04:52:09.203Z,1618203129.203 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2021-04-12T04:54:09.597Z,1618203249.597 [NAL9602](FAULT): GPS failed to acquire within timeout.
2021-04-12T04:54:09.597Z,1618203249.597 [NAL9602] Data Fault, FailCount= 4
2021-04-12T04:54:09.597Z,1618203249.597 [NAL9602](ERROR): Data Fault
2021-04-12T04:54:09.632Z,1618203249.632 [CBIT](ERROR): Data Fault in component: NAL9602
2021-04-12T04:54:09.997Z,1618203249.997 [NAL9602](INFO): Powering down
2021-04-12T04:54:10.827Z,1618203250.827 [CBIT](INFO): Clearing failed state for component NAL9602
2021-04-12T04:54:10.827Z,1618203250.827 [NAL9602] No Fault, FailCount= 4
2021-04-12T04:54:18.492Z,1618203258.492 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2021-04-12T04:54:18.492Z,1618203258.492 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-04-12T04:54:18.502Z,1618203258.502 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-04-12T04:54:18.905Z,1618203258.905 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-04-12T04:54:18.905Z,1618203258.905 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2021-04-12T04:54:40.297Z,1618203280.297 [NAL9602](INFO): Powering up NAL9602
2021-04-12T04:54:51.214Z,1618203291.214 [NAL9602](INFO): NAL9602 initialized
2021-04-12T04:54:58.886Z,1618203298.886 [DVL_micro](ERROR): only read 3 of 4 data items
2021-04-12T04:54:58.887Z,1618203298.887 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.99,+99999.99
2021-04-12T04:56:27.781Z,1618203387.781 [DVL_micro](ERROR): Failed to parse:
:SA,-01.84,+00.90,063.3
2021-04-12T04:57:06.577Z,1618203426.577 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-04-12T04:52:06.4Z
2021-04-12T04:57:06.578Z,1618203426.578 [Default:CheckIn:Read_GPS] Stopped
2021-04-12T04:57:06.578Z,1618203426.578 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-12T04:57:13.537Z,1618203433.537 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20210412T042058/Courier0007.lzma
2021-04-12T04:57:14.536Z,1618203434.536 [DataOverHttps](INFO): Moved sent file to Logs/20210412T042058/Courier0007.lzma.bak
2021-04-12T04:57:14.537Z,1618203434.537 [DataOverHttps](INFO): SBD MOMSN=15559067
2021-04-12T04:57:19.503Z,1618203439.503 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2021-04-12T04:57:19.503Z,1618203439.503 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-04-12T04:57:19.521Z,1618203439.521 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-04-12T04:57:19.927Z,1618203439.927 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-04-12T04:57:19.927Z,1618203439.927 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2021-04-12T04:57:30.101Z,1618203450.101 [DataOverHttps](INFO): Sending 1175 bytes from file Logs/20210412T042058/Express0008.lzma
2021-04-12T04:57:31.101Z,1618203451.101 [DataOverHttps](INFO): Moved sent file to Logs/20210412T042058/Express0008.lzma.bak
2021-04-12T04:57:31.101Z,1618203451.101 [DataOverHttps](INFO): SBD MOMSN=15559069
2021-04-12T04:57:33.260Z,1618203453.260 [Default:CheckIn:Read_Iridium] Stopped
2021-04-12T04:57:33.260Z,1618203453.260 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-12T04:57:33.260Z,1618203453.260 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-12T05:00:20.487Z,1618203620.487 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2021-04-12T05:00:20.487Z,1618203620.487 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-04-12T05:00:20.498Z,1618203620.498 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-04-12T05:00:20.911Z,1618203620.911 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-04-12T05:00:20.911Z,1618203620.911 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2021-04-12T05:02:33.817Z,1618203753.817 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-12T05:02:33.817Z,1618203753.817 [Default:CheckIn:C.Wait] Stopped
2021-04-12T05:02:33.817Z,1618203753.817 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-12T05:02:33.817Z,1618203753.817 [Default:CheckIn:D] Running Loop=1
2021-04-12T05:02:34.223Z,1618203754.223 [Default:CheckIn:D] Stopped
2021-04-12T05:02:34.223Z,1618203754.223 [Default:CheckIn:E] Running Loop=1
2021-04-12T05:02:34.616Z,1618203754.616 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.686068 min 
2021-04-12T05:02:34.617Z,1618203754.617 [Default:CheckIn:E] Stopped
2021-04-12T05:02:34.617Z,1618203754.617 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-12T05:02:34.617Z,1618203754.617 [Default:CheckIn] Stopped
2021-04-12T05:02:34.617Z,1618203754.617 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-12T05:02:34.617Z,1618203754.617 [Default:CheckIn](INFO): Running loop #2
2021-04-12T05:02:34.617Z,1618203754.617 [Default:CheckIn] Running Loop=2
2021-04-12T05:02:34.617Z,1618203754.617 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-12T05:02:34.617Z,1618203754.617 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-12T05:03:21.490Z,1618203801.490 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2021-04-12T05:03:21.490Z,1618203801.490 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-04-12T05:03:21.500Z,1618203801.500 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-04-12T05:03:21.892Z,1618203801.892 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-04-12T05:03:21.892Z,1618203801.892 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2021-04-12T05:04:13.172Z,1618203853.172 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2021-04-12T05:04:13.173Z,1618203853.173 [DVL_micro](ERROR): Failed to parse:
:TS,000000000.0,+21.4,0000.0,1489.0,0001336,+00340,+0132,A
2021-04-12T05:04:54.785Z,1618203894.785 [NAL9602](FAULT): GPS failed to acquire within timeout.
2021-04-12T05:04:54.785Z,1618203894.785 [NAL9602] Data Fault, FailCount= 5
2021-04-12T05:04:54.785Z,1618203894.785 [NAL9602](ERROR): Data Fault
2021-04-12T05:04:54.873Z,1618203894.873 [CBIT](ERROR): Data Fault in component: NAL9602
2021-04-12T05:04:54.874Z,1618203894.874 [CBIT](CRITICAL): Data Fault in component: NAL9602
2021-04-12T05:04:55.185Z,1618203895.185 [NAL9602](INFO): Powering down
2021-04-12T05:04:55.211Z,1618203895.211 [CommandLine](FAULT): Scheduling is paused
2021-04-12T05:04:55.212Z,1618203895.212 [CBIT](INFO): Critical error at 20210412T050454
2021-04-12T05:06:22.491Z,1618203982.491 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2021-04-12T05:06:22.491Z,1618203982.491 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-04-12T05:06:22.502Z,1618203982.502 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-04-12T05:06:22.916Z,1618203982.916 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-04-12T05:06:22.916Z,1618203982.916 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2021-04-12T05:06:45.923Z,1618204005.923 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2021-04-12T05:06:45.923Z,1618204005.923 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,34,0000.0,1489.0,000
2021-04-12T05:06:54.821Z,1618204014.821 [CBIT](INFO): Clearing failed state for component NAL9602
2021-04-12T05:06:54.821Z,1618204014.821 [NAL9602] No Fault, FailCount= 5
2021-04-12T05:06:55.209Z,1618204015.209 [NAL9602](INFO): Powering up NAL9602
2021-04-12T05:07:06.126Z,1618204026.126 [NAL9602](INFO): NAL9602 initialized
2021-04-12T05:07:34.809Z,1618204054.809 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-04-12T05:02:34.6Z
2021-04-12T05:07:34.809Z,1618204054.809 [Default:CheckIn:Read_GPS] Stopped
2021-04-12T05:07:34.809Z,1618204054.809 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-12T05:07:42.362Z,1618204062.362 [DataOverHttps](INFO): Sending 105 bytes from file Logs/20210412T042058/Courier0010.lzma
2021-04-12T05:07:43.364Z,1618204063.364 [DataOverHttps](INFO): Moved sent file to Logs/20210412T042058/Courier0010.lzma.bak
2021-04-12T05:07:43.365Z,1618204063.365 [DataOverHttps](INFO): SBD MOMSN=15559128
2021-04-12T05:07:57.855Z,1618204077.855 [DataOverHttps](INFO): Sending 173 bytes from file Logs/20210412T042058/Express0011.lzma
2021-04-12T05:07:58.857Z,1618204078.857 [DataOverHttps](INFO): Moved sent file to Logs/20210412T042058/Express0011.lzma.bak
2021-04-12T05:07:58.857Z,1618204078.857 [DataOverHttps](INFO): SBD MOMSN=15559131
2021-04-12T05:08:01.081Z,1618204081.081 [Default:CheckIn:Read_Iridium] Stopped
2021-04-12T05:08:01.081Z,1618204081.081 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-12T05:08:01.081Z,1618204081.081 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-12T05:09:23.523Z,1618204163.523 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2021-04-12T05:09:23.523Z,1618204163.523 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-04-12T05:09:23.533Z,1618204163.533 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-04-12T05:09:23.928Z,1618204163.928 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-04-12T05:09:23.928Z,1618204163.928 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2021-04-12T05:11:50.937Z,1618204310.937 [DVL_micro](ERROR): Failed to parse:
:BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00
2021-04-12T05:12:24.472Z,1618204344.472 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17
2021-04-12T05:12:24.472Z,1618204344.472 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-04-12T05:12:24.482Z,1618204344.482 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-04-12T05:12:24.913Z,1618204344.913 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-04-12T05:12:24.913Z,1618204344.913 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17
2021-04-12T05:13:01.665Z,1618204381.665 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-12T05:13:01.665Z,1618204381.665 [Default:CheckIn:C.Wait] Stopped
2021-04-12T05:13:01.665Z,1618204381.665 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-12T05:13:01.666Z,1618204381.666 [Default:CheckIn:D] Running Loop=1
2021-04-12T05:13:02.054Z,1618204382.054 [Default:CheckIn:D] Stopped
2021-04-12T05:13:02.054Z,1618204382.054 [Default:CheckIn:E] Running Loop=1
2021-04-12T05:13:02.469Z,1618204382.469 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.149921 min 
2021-04-12T05:13:02.469Z,1618204382.469 [Default:CheckIn:E] Stopped
2021-04-12T05:13:02.469Z,1618204382.469 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-12T05:13:02.469Z,1618204382.469 [Default:CheckIn] Stopped
2021-04-12T05:13:02.469Z,1618204382.469 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-12T05:13:02.470Z,1618204382.470 [Default:CheckIn](INFO): Running loop #3
2021-04-12T05:13:02.470Z,1618204382.470 [Default:CheckIn] Running Loop=3
2021-04-12T05:13:02.470Z,1618204382.470 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-12T05:13:02.470Z,1618204382.470 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-12T05:15:25.488Z,1618204525.488 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18
2021-04-12T05:15:25.488Z,1618204525.488 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-04-12T05:15:25.499Z,1618204525.499 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-04-12T05:15:25.881Z,1618204525.881 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-04-12T05:15:25.881Z,1618204525.881 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18
2021-04-12T05:17:08.481Z,1618204628.481 [NAL9602](FAULT): GPS failed to acquire within timeout.
2021-04-12T05:17:08.481Z,1618204628.481 [NAL9602] Data Fault, FailCount= 1
2021-04-12T05:17:08.481Z,1618204628.481 [NAL9602](ERROR): Data Fault
2021-04-12T05:17:08.498Z,1618204628.498 [CBIT](ERROR): Data Fault in component: NAL9602
2021-04-12T05:17:08.894Z,1618204628.894 [NAL9602](INFO): Powering down
2021-04-12T05:17:09.707Z,1618204629.707 [CBIT](INFO): Clearing failed state for component NAL9602
2021-04-12T05:17:09.707Z,1618204629.707 [NAL9602] No Fault, FailCount= 1
2021-04-12T05:17:39.181Z,1618204659.181 [NAL9602](INFO): Powering up NAL9602
2021-04-12T05:17:50.093Z,1618204670.093 [NAL9602](INFO): NAL9602 initialized
2021-04-12T05:18:02.643Z,1618204682.643 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-04-12T05:13:02.5Z
2021-04-12T05:18:02.643Z,1618204682.643 [Default:CheckIn:Read_GPS] Stopped
2021-04-12T05:18:02.643Z,1618204682.643 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-12T05:18:09.394Z,1618204689.394 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20210412T042058/Courier0013.lzma
2021-04-12T05:18:10.396Z,1618204690.396 [DataOverHttps](INFO): Moved sent file to Logs/20210412T042058/Courier0013.lzma.bak
2021-04-12T05:18:10.397Z,1618204690.397 [DataOverHttps](INFO): SBD MOMSN=15559136
2021-04-12T05:18:25.935Z,1618204705.935 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20210412T042058/Express0014.lzma
2021-04-12T05:18:26.476Z,1618204706.476 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19
2021-04-12T05:18:26.476Z,1618204706.476 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-04-12T05:18:26.486Z,1618204706.486 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-04-12T05:18:26.897Z,1618204706.897 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-04-12T05:18:26.897Z,1618204706.897 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19
2021-04-12T05:18:26.937Z,1618204706.937 [DataOverHttps](INFO): Moved sent file to Logs/20210412T042058/Express0014.lzma.bak
2021-04-12T05:18:26.937Z,1618204706.937 [DataOverHttps](INFO): SBD MOMSN=15559138
2021-04-12T05:18:28.905Z,1618204708.905 [Default:CheckIn:Read_Iridium] Stopped
2021-04-12T05:18:28.905Z,1618204708.905 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-12T05:18:28.905Z,1618204708.905 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-12T05:20:47.862Z,1618204847.862 [DVL_micro](ERROR): only read 3 of 4 data items
2021-04-12T05:20:47.863Z,1618204847.863 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.99,+9999.999
2021-04-12T05:21:27.483Z,1618204887.483 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 20
2021-04-12T05:21:27.483Z,1618204887.483 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-04-12T05:21:27.493Z,1618204887.493 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-04-12T05:21:27.917Z,1618204887.917 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-04-12T05:21:27.917Z,1618204887.917 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 20
2021-04-12T05:22:04.248Z,1618204924.248 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2021-04-12T05:22:04.248Z,1618204924.248 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+21.4,0000.0000
2021-04-12T05:23:29.533Z,1618205009.533 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-12T05:23:29.533Z,1618205009.533 [Default:CheckIn:C.Wait] Stopped
2021-04-12T05:23:29.534Z,1618205009.534 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-12T05:23:29.534Z,1618205009.534 [Default:CheckIn:D] Running Loop=1
2021-04-12T05:23:29.929Z,1618205009.929 [Default:CheckIn:D] Stopped
2021-04-12T05:23:29.929Z,1618205009.929 [Default:CheckIn:E] Running Loop=1
2021-04-12T05:23:30.329Z,1618205010.329 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.614510 min 
2021-04-12T05:23:30.329Z,1618205010.329 [Default:CheckIn:E] Stopped
2021-04-12T05:23:30.330Z,1618205010.330 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-12T05:23:30.330Z,1618205010.330 [Default:CheckIn] Stopped
2021-04-12T05:23:30.330Z,1618205010.330 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-12T05:23:30.330Z,1618205010.330 [Default:CheckIn](INFO): Running loop #4
2021-04-12T05:23:30.330Z,1618205010.330 [Default:CheckIn] Running Loop=4
2021-04-12T05:23:30.330Z,1618205010.330 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-12T05:23:30.330Z,1618205010.330 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-12T05:24:28.504Z,1618205068.504 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 21
2021-04-12T05:24:28.504Z,1618205068.504 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-04-12T05:24:28.535Z,1618205068.535 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-04-12T05:24:28.921Z,1618205068.921 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-04-12T05:24:28.921Z,1618205068.921 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 21
2021-04-12T05:24:31.734Z,1618205071.734 [DVL_micro](ERROR): only read 0 of 4 data items
2021-04-12T05:24:31.734Z,1618205071.734 [DVL_micro](ERROR): Failed to parse:
:RD9,+9999..99,+9999.99
2021-04-12T05:27:29.527Z,1618205249.527 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 22
2021-04-12T05:27:29.527Z,1618205249.527 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-04-12T05:27:29.538Z,1618205249.538 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-04-12T05:27:29.953Z,1618205249.953 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-04-12T05:27:29.953Z,1618205249.953 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 22
2021-04-12T05:27:53.325Z,1618205273.325 [NAL9602](FAULT): GPS failed to acquire within timeout.
2021-04-12T05:27:53.325Z,1618205273.325 [NAL9602] Data Fault, FailCount= 2
2021-04-12T05:27:53.325Z,1618205273.325 [NAL9602](ERROR): Data Fault
2021-04-12T05:27:53.373Z,1618205273.373 [CBIT](ERROR): Data Fault in component: NAL9602
2021-04-12T05:27:53.725Z,1618205273.725 [NAL9602](INFO): Powering down
2021-04-12T05:27:54.556Z,1618205274.556 [CBIT](INFO): Clearing failed state for component NAL9602
2021-04-12T05:27:54.556Z,1618205274.556 [NAL9602] No Fault, FailCount= 2
2021-04-12T05:28:24.025Z,1618205304.025 [NAL9602](INFO): Powering up NAL9602
2021-04-12T05:28:30.496Z,1618205310.496 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-04-12T05:23:30.3Z
2021-04-12T05:28:30.496Z,1618205310.496 [Default:CheckIn:Read_GPS] Stopped
2021-04-12T05:28:30.497Z,1618205310.497 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-12T05:28:34.933Z,1618205314.933 [NAL9602](INFO): NAL9602 initialized
2021-04-12T05:28:36.454Z,1618205316.454 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20210412T042058/Courier0016.lzma
2021-04-12T05:28:37.457Z,1618205317.457 [DataOverHttps](INFO): Moved sent file to Logs/20210412T042058/Courier0016.lzma.bak
2021-04-12T05:28:37.457Z,1618205317.457 [DataOverHttps](INFO): SBD MOMSN=15559142
2021-04-12T05:28:52.975Z,1618205332.975 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20210412T042058/Express0017.lzma
2021-04-12T05:28:53.977Z,1618205333.977 [DataOverHttps](INFO): Moved sent file to Logs/20210412T042058/Express0017.lzma.bak
2021-04-12T05:28:53.977Z,1618205333.977 [DataOverHttps](INFO): SBD MOMSN=15559144
2021-04-12T05:28:55.963Z,1618205335.963 [Default:CheckIn:Read_Iridium] Stopped
2021-04-12T05:28:55.963Z,1618205335.963 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-12T05:28:55.963Z,1618205335.963 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-12T05:29:39.593Z,1618205379.593 [DVL_micro](ERROR): only read 3 of 4 data items
2021-04-12T05:29:39.593Z,1618205379.593 [DVL_micro](ERROR): Failed to parse:
:BI,+00936,-00057,+00934,+0:BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.09999.99,+9999.99,+9999.99,
2021-04-12T05:30:30.531Z,1618205430.531 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 23
2021-04-12T05:30:30.531Z,1618205430.531 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-04-12T05:30:30.541Z,1618205430.541 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-04-12T05:30:30.933Z,1618205430.933 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-04-12T05:30:30.934Z,1618205430.934 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 23
2021-04-12T05:32:09.479Z,1618205529.479 [DVL_micro](ERROR): Failed to parse:
:WI,+01841,-00966,+00908,+00000,A
2021-04-12T05:33:31.480Z,1618205611.480 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 24
2021-04-12T05:33:31.480Z,1618205611.480 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-04-12T05:33:31.490Z,1618205611.490 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-04-12T05:33:31.897Z,1618205611.897 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-04-12T05:33:31.897Z,1618205611.897 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 24
2021-04-12T05:33:56.534Z,1618205636.534 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-12T05:33:56.534Z,1618205636.534 [Default:CheckIn:C.Wait] Stopped
2021-04-12T05:33:56.535Z,1618205636.535 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-12T05:33:56.535Z,1618205636.535 [Default:CheckIn:D] Running Loop=1
2021-04-12T05:33:56.940Z,1618205636.940 [Default:CheckIn:D] Stopped
2021-04-12T05:33:56.940Z,1618205636.940 [Default:CheckIn:E] Running Loop=1
2021-04-12T05:33:57.361Z,1618205637.361 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.064681 min 
2021-04-12T05:33:57.361Z,1618205637.361 [Default:CheckIn:E] Stopped
2021-04-12T05:33:57.361Z,1618205637.361 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-12T05:33:57.361Z,1618205637.361 [Default:CheckIn] Stopped
2021-04-12T05:33:57.362Z,1618205637.362 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-12T05:33:57.362Z,1618205637.362 [Default:CheckIn](INFO): Running loop #5
2021-04-12T05:33:57.362Z,1618205637.362 [Default:CheckIn] Running Loop=5
2021-04-12T05:33:57.362Z,1618205637.362 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-12T05:33:57.362Z,1618205637.362 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-12T05:34:37.329Z,1618205677.329 [DVL_micro](ERROR): Failed to parse:
:1,-01544,+01259,+00000,I
2021-04-12T05:36:32.472Z,1618205792.472 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 25
2021-04-12T05:36:32.472Z,1618205792.472 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-04-12T05:36:32.482Z,1618205792.482 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-04-12T05:36:32.888Z,1618205792.888 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-04-12T05:36:32.888Z,1618205792.888 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 25
2021-04-12T05:37:07.210Z,1618205827.210 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2021-04-12T05:37:07.211Z,1618205827.211 [DVL_micro](ERROR): Failed to parse::TS,00000000000000,35.0,+21.4,489.0,000
2021-04-12T05:38:36.498Z,1618205916.498 [NAL9602](FAULT): GPS failed to acquire within timeout.
2021-04-12T05:38:36.498Z,1618205916.498 [NAL9602] Data Fault, FailCount= 3
2021-04-12T05:38:36.498Z,1618205916.498 [NAL9602](ERROR): Data Fault
2021-04-12T05:38:36.535Z,1618205916.535 [CBIT](ERROR): Data Fault in component: NAL9602
2021-04-12T05:38:36.893Z,1618205916.893 [NAL9602](INFO): Powering down
2021-04-12T05:38:37.729Z,1618205917.729 [CBIT](INFO): Clearing failed state for component NAL9602
2021-04-12T05:38:37.729Z,1618205917.729 [NAL9602] No Fault, FailCount= 3
2021-04-12T05:38:57.509Z,1618205937.509 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-04-12T05:33:57.4Z
2021-04-12T05:38:57.509Z,1618205937.509 [Default:CheckIn:Read_GPS] Stopped
2021-04-12T05:38:57.509Z,1618205937.509 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-12T05:39:03.446Z,1618205943.446 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20210412T042058/Courier0019.lzma
2021-04-12T05:39:04.449Z,1618205944.449 [DataOverHttps](INFO): Moved sent file to Logs/20210412T042058/Courier0019.lzma.bak
2021-04-12T05:39:04.449Z,1618205944.449 [DataOverHttps](INFO): SBD MOMSN=15559148
2021-04-12T05:39:07.197Z,1618205947.197 [NAL9602](INFO): Powering up NAL9602
2021-04-12T05:39:18.101Z,1618205958.101 [NAL9602](INFO): NAL9602 initialized
2021-04-12T05:39:20.026Z,1618205960.026 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20210412T042058/Express0020.lzma
2021-04-12T05:39:21.029Z,1618205961.029 [DataOverHttps](INFO): Moved sent file to Logs/20210412T042058/Express0020.lzma.bak
2021-04-12T05:39:21.029Z,1618205961.029 [DataOverHttps](INFO): SBD MOMSN=15559150
2021-04-12T05:39:22.971Z,1618205962.971 [Default:CheckIn:Read_Iridium] Stopped
2021-04-12T05:39:22.971Z,1618205962.971 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-12T05:39:22.971Z,1618205962.971 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-12T05:39:33.464Z,1618205973.464 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 26
2021-04-12T05:39:33.464Z,1618205973.464 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-04-12T05:39:33.474Z,1618205973.474 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-04-12T05:39:33.882Z,1618205973.882 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-04-12T05:39:33.882Z,1618205973.882 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 26
2021-04-12T05:39:37.501Z,1618205977.501 [DVL_micro](ERROR): Failed to parse:
:BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00
2021-04-12T05:42:34.476Z,1618206154.476 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 27
2021-04-12T05:42:34.476Z,1618206154.476 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-04-12T05:42:34.510Z,1618206154.510 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-04-12T05:42:34.874Z,1618206154.874 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-04-12T05:42:34.874Z,1618206154.874 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 27
2021-04-12T05:44:23.537Z,1618206263.537 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-12T05:44:23.537Z,1618206263.537 [Default:CheckIn:C.Wait] Stopped
2021-04-12T05:44:23.537Z,1618206263.537 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-12T05:44:23.537Z,1618206263.537 [Default:CheckIn:D] Running Loop=1
2021-04-12T05:44:23.945Z,1618206263.945 [Default:CheckIn:D] Stopped
2021-04-12T05:44:23.945Z,1618206263.945 [Default:CheckIn:E] Running Loop=1
2021-04-12T05:44:24.350Z,1618206264.350 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.514766 min 
2021-04-12T05:44:24.350Z,1618206264.350 [Default:CheckIn:E] Stopped
2021-04-12T05:44:24.350Z,1618206264.350 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-12T05:44:24.350Z,1618206264.350 [Default:CheckIn] Stopped
2021-04-12T05:44:24.350Z,1618206264.350 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-12T05:44:24.351Z,1618206264.351 [Default:CheckIn](INFO): Running loop #6
2021-04-12T05:44:24.351Z,1618206264.351 [Default:CheckIn] Running Loop=6
2021-04-12T05:44:24.351Z,1618206264.351 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-12T05:44:24.351Z,1618206264.351 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-12T05:45:35.461Z,1618206335.461 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 28
2021-04-12T05:45:35.461Z,1618206335.461 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-04-12T05:45:35.487Z,1618206335.487 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-04-12T05:45:35.877Z,1618206335.877 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-04-12T05:45:35.877Z,1618206335.877 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 28
2021-04-12T05:47:12.811Z,1618206432.811 [DVL_micro](ERROR): Failed to parse:
:WI,+00891,-00021,+00827,+00000,A0891,-0027,+00000,I
2021-04-12T05:48:36.456Z,1618206516.456 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 29
2021-04-12T05:48:36.456Z,1618206516.456 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-04-12T05:48:36.466Z,1618206516.466 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-04-12T05:48:36.902Z,1618206516.902 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-04-12T05:48:36.902Z,1618206516.902 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 29
2021-04-12T05:49:19.265Z,1618206559.265 [NAL9602](FAULT): GPS failed to acquire within timeout.
2021-04-12T05:49:19.265Z,1618206559.265 [NAL9602] Data Fault, FailCount= 4
2021-04-12T05:49:19.265Z,1618206559.265 [NAL9602](ERROR): Data Fault
2021-04-12T05:49:19.311Z,1618206559.311 [CBIT](ERROR): Data Fault in component: NAL9602
2021-04-12T05:49:19.665Z,1618206559.665 [NAL9602](INFO): Powering down
2021-04-12T05:49:20.498Z,1618206560.498 [CBIT](INFO): Clearing failed state for component NAL9602
2021-04-12T05:49:20.498Z,1618206560.498 [NAL9602] No Fault, FailCount= 4
2021-04-12T05:49:24.525Z,1618206564.525 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-04-12T05:44:24.4Z
2021-04-12T05:49:24.525Z,1618206564.525 [Default:CheckIn:Read_GPS] Stopped
2021-04-12T05:49:24.525Z,1618206564.525 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-12T05:49:30.326Z,1618206570.326 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20210412T042058/Courier0022.lzma
2021-04-12T05:49:31.329Z,1618206571.329 [DataOverHttps](INFO): Moved sent file to Logs/20210412T042058/Courier0022.lzma.bak
2021-04-12T05:49:31.329Z,1618206571.329 [DataOverHttps](INFO): SBD MOMSN=15559154
2021-04-12T05:49:43.098Z,1618206583.098 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2021-04-12T05:49:43.099Z,1618206583.099 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35,0000.0,10
2021-04-12T05:49:46.751Z,1618206586.751 [DataOverHttps](INFO): Sending 148 bytes from file Logs/20210412T042058/Express0023.lzma
2021-04-12T05:49:47.752Z,1618206587.752 [DataOverHttps](INFO): Moved sent file to Logs/20210412T042058/Express0023.lzma.bak
2021-04-12T05:49:47.753Z,1618206587.753 [DataOverHttps](INFO): SBD MOMSN=15559156
2021-04-12T05:49:49.965Z,1618206589.965 [NAL9602](INFO): Powering up NAL9602
2021-04-12T05:49:50.015Z,1618206590.015 [Default:CheckIn:Read_Iridium] Stopped
2021-04-12T05:49:50.016Z,1618206590.016 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-12T05:49:50.016Z,1618206590.016 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-12T05:50:00.893Z,1618206600.893 [NAL9602](INFO): NAL9602 initialized
2021-04-12T05:50:12.406Z,1618206612.406 [CBIT](CRITICAL): Run-time exception #7
2021-04-12T05:50:12.406Z,1618206612.406 [CBIT] Software Fault, FailCount= 1
2021-04-12T05:50:12.406Z,1618206612.406 [CBIT](ERROR): Software Fault
2021-04-12T05:50:12.409Z,1618206612.409 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2021-04-12T05:50:12.409Z,1618206612.409 [SyncHandler](INFO): Protected caller Thread ID is 2088
2021-04-12T05:50:12.773Z,1618206612.773 [CBIT](DEBUG): Uninitialize CBIT Component.
2021-04-12T05:50:12.773Z,1618206612.773 [