2021-04-13T21:08:04.082Z,1618348084.082 [Supervisor](DEBUG): Initializing supervisor.
2021-04-13T21:08:04.087Z,1618348084.087 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2021-04-13T21:08:04.087Z,1618348084.087 [SyncHandler](INFO): Protected caller Thread ID is 9684
2021-04-13T21:08:04.088Z,1618348084.088 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2021-04-13T21:08:04.089Z,1618348084.089 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2021-04-13T21:08:04.089Z,1618348084.089 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 9685
2021-04-13T21:08:04.093Z,1618348084.093 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2021-04-13T21:08:04.112Z,1618348084.112 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2021-04-13T21:08:04.113Z,1618348084.113 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2021-04-13T21:08:04.114Z,1618348084.114 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 9686
2021-04-13T21:08:04.116Z,1618348084.116 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2021-04-13T21:08:04.117Z,1618348084.117 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2021-04-13T21:08:04.117Z,1618348084.117 [logger ThreadHandler](INFO): Protected caller Thread ID is 9687
2021-04-13T21:08:04.121Z,1618348084.121 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2021-04-13T21:08:04.121Z,1618348084.121 [Supervisor](INFO): Looking for Config files in directory: Config/
2021-04-13T21:08:04.125Z,1618348084.125 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2021-04-13T21:08:04.565Z,1618348084.565 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2021-04-13T21:08:04.567Z,1618348084.567 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2021-04-13T21:08:05.247Z,1618348085.247 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2021-04-13T21:08:05.249Z,1618348085.249 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2021-04-13T21:08:05.355Z,1618348085.355 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2021-04-13T21:08:05.355Z,1618348085.355 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2021-04-13T21:08:05.922Z,1618348085.922 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2021-04-13T21:08:05.923Z,1618348085.923 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2021-04-13T21:08:06.024Z,1618348086.024 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2021-04-13T21:08:06.025Z,1618348086.025 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2021-04-13T21:08:06.109Z,1618348086.109 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2021-04-13T21:08:06.502Z,1618348086.502 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2021-04-13T21:08:06.504Z,1618348086.504 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2021-04-13T21:08:06.663Z,1618348086.663 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2021-04-13T21:08:06.664Z,1618348086.664 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2021-04-13T21:08:06.798Z,1618348086.798 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2021-04-13T21:08:06.800Z,1618348086.800 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2021-04-13T21:08:07.054Z,1618348087.054 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2021-04-13T21:08:07.055Z,1618348087.055 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2021-04-13T21:08:07.544Z,1618348087.544 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2021-04-13T21:08:07.546Z,1618348087.546 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2021-04-13T21:08:07.941Z,1618348087.941 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2021-04-13T21:08:07.942Z,1618348087.942 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2021-04-13T21:08:08.025Z,1618348088.025 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2021-04-13T21:08:08.291Z,1618348088.291 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2021-04-13T21:08:08.382Z,1618348088.382 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2021-04-13T21:08:08.876Z,1618348088.876 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2021-04-13T21:08:08.880Z,1618348088.880 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2021-04-13T21:08:09.149Z,1618348089.149 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2021-04-13T21:08:09.151Z,1618348089.151 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/
2021-04-13T21:08:09.152Z,1618348089.152 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Control.cfg
2021-04-13T21:08:09.256Z,1618348089.256 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Sensor.cfg
2021-04-13T21:08:09.415Z,1618348089.415 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Simulator.cfg
2021-04-13T21:08:09.506Z,1618348089.506 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/secure.cfg
2021-04-13T21:08:09.593Z,1618348089.593 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Servo.cfg
2021-04-13T21:08:09.708Z,1618348089.708 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/vehicle.cfg
2021-04-13T21:08:09.913Z,1618348089.913 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Battery.cfg
2021-04-13T21:08:10.187Z,1618348090.187 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2021-04-13T21:08:10.189Z,1618348090.189 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Science.cfg
2021-04-13T21:08:10.333Z,1618348090.333 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/logger.cfg
2021-04-13T21:08:10.434Z,1618348090.434 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/BIT.cfg
2021-04-13T21:08:10.545Z,1618348090.545 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Navigation.cfg
2021-04-13T21:08:10.644Z,1618348090.644 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/root/
2021-04-13T21:08:10.645Z,1618348090.645 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2021-04-13T21:08:10.652Z,1618348090.652 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2021-04-13T21:08:10.744Z,1618348090.744 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2021-04-13T21:08:10.745Z,1618348090.745 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2021-04-13T21:08:10.756Z,1618348090.756 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2021-04-13T21:08:10.757Z,1618348090.757 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2021-04-13T21:08:10.800Z,1618348090.800 [DepthRateCalculator] Loaded
2021-04-13T21:08:10.800Z,1618348090.800 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2021-04-13T21:08:10.806Z,1618348090.806 [PitchRateCalculator] Loaded
2021-04-13T21:08:10.806Z,1618348090.806 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2021-04-13T21:08:10.817Z,1618348090.817 [SpeedCalculator] Loaded
2021-04-13T21:08:10.817Z,1618348090.817 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2021-04-13T21:08:10.830Z,1618348090.830 [TempGradientCalculator] Loaded
2021-04-13T21:08:10.831Z,1618348090.831 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2021-04-13T21:08:10.836Z,1618348090.836 [YawRateCalculator] Loaded
2021-04-13T21:08:10.836Z,1618348090.836 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2021-04-13T21:08:10.857Z,1618348090.857 [ElevatorOffsetCalculator] Loaded
2021-04-13T21:08:10.857Z,1618348090.857 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2021-04-13T21:08:10.858Z,1618348090.858 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2021-04-13T21:08:10.858Z,1618348090.858 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2021-04-13T21:08:10.913Z,1618348090.913 [VerticalControl](DEBUG): Construct VerticalControl.
2021-04-13T21:08:10.971Z,1618348090.971 [VerticalControl] Loaded
2021-04-13T21:08:10.972Z,1618348090.972 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2021-04-13T21:08:10.975Z,1618348090.975 [HorizontalControl](DEBUG): Construct HorizontalControl.
2021-04-13T21:08:11.013Z,1618348091.013 [HorizontalControl] Loaded
2021-04-13T21:08:11.014Z,1618348091.014 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2021-04-13T21:08:11.016Z,1618348091.016 [SpeedControl](DEBUG): Construct SpeedControl.
2021-04-13T21:08:11.019Z,1618348091.019 [SpeedControl] Loaded
2021-04-13T21:08:11.019Z,1618348091.019 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2021-04-13T21:08:11.022Z,1618348091.022 [LoopControl](DEBUG): Construct LoopControl.
2021-04-13T21:08:11.022Z,1618348091.022 [LoopControl] Loaded
2021-04-13T21:08:11.023Z,1618348091.023 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2021-04-13T21:08:11.023Z,1618348091.023 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2021-04-13T21:08:11.023Z,1618348091.023 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2021-04-13T21:08:11.106Z,1618348091.106 [DeadReckonUsingMultipleVelocitySources] Loaded
2021-04-13T21:08:11.106Z,1618348091.106 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2021-04-13T21:08:11.120Z,1618348091.120 [NavChart] Loaded
2021-04-13T21:08:11.120Z,1618348091.120 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2021-04-13T21:08:11.126Z,1618348091.126 [UniversalFixResidualReporter] Loaded
2021-04-13T21:08:11.127Z,1618348091.127 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2021-04-13T21:08:11.127Z,1618348091.127 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2021-04-13T21:08:11.128Z,1618348091.128 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2021-04-13T21:08:11.233Z,1618348091.233 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2021-04-13T21:08:11.234Z,1618348091.234 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2021-04-13T21:08:11.603Z,1618348091.603 [AHRS_M2] Loaded
2021-04-13T21:08:11.603Z,1618348091.603 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2021-04-13T21:08:11.633Z,1618348091.633 [BackseatComponent] Loaded
2021-04-13T21:08:11.633Z,1618348091.633 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2021-04-13T21:08:11.634Z,1618348091.634 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408674E0
2021-04-13T21:08:11.635Z,1618348091.635 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 9769
2021-04-13T21:08:11.637Z,1618348091.637 [LcmUniversalReporter] Loaded
2021-04-13T21:08:11.638Z,1618348091.638 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2021-04-13T21:08:12.431Z,1618348092.431 [BPC1] Loaded
2021-04-13T21:08:12.431Z,1618348092.431 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2021-04-13T21:08:12.502Z,1618348092.502 [DataOverHttps] Loaded
2021-04-13T21:08:12.502Z,1618348092.502 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2021-04-13T21:08:12.503Z,1618348092.503 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408974E0
2021-04-13T21:08:12.504Z,1618348092.504 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 9770
2021-04-13T21:08:12.524Z,1618348092.524 [Depth_Keller] Loaded
2021-04-13T21:08:12.524Z,1618348092.524 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2021-04-13T21:08:12.529Z,1618348092.529 [DropWeight] Loaded
2021-04-13T21:08:12.529Z,1618348092.529 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2021-04-13T21:08:12.564Z,1618348092.564 [DVL_micro] Loaded
2021-04-13T21:08:12.565Z,1618348092.565 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2021-04-13T21:08:12.624Z,1618348092.624 [NAL9602] Loaded
2021-04-13T21:08:12.624Z,1618348092.624 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2021-04-13T21:08:12.653Z,1618348092.653 [Onboard] Loaded
2021-04-13T21:08:12.654Z,1618348092.654 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2021-04-13T21:08:12.655Z,1618348092.655 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408C74E0
2021-04-13T21:08:12.655Z,1618348092.655 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 9771
2021-04-13T21:08:12.668Z,1618348092.668 [Radio_Surface] Loaded
2021-04-13T21:08:12.669Z,1618348092.669 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2021-04-13T21:08:12.670Z,1618348092.670 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408F74E0
2021-04-13T21:08:12.670Z,1618348092.670 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 9772
2021-04-13T21:08:12.753Z,1618348092.753 [DAT] Loaded
2021-04-13T21:08:12.753Z,1618348092.753 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2021-04-13T21:08:12.753Z,1618348092.753 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2021-04-13T21:08:12.754Z,1618348092.754 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2021-04-13T21:08:12.874Z,1618348092.874 [BuoyancyServo] Loaded
2021-04-13T21:08:12.874Z,1618348092.874 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2021-04-13T21:08:12.894Z,1618348092.894 [ElevatorServo] Loaded
2021-04-13T21:08:12.894Z,1618348092.894 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2021-04-13T21:08:12.913Z,1618348092.913 [MassServo] Loaded
2021-04-13T21:08:12.914Z,1618348092.914 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2021-04-13T21:08:12.932Z,1618348092.932 [RudderServo] Loaded
2021-04-13T21:08:12.932Z,1618348092.932 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2021-04-13T21:08:12.950Z,1618348092.950 [ThrusterServo] Loaded
2021-04-13T21:08:12.950Z,1618348092.950 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2021-04-13T21:08:12.951Z,1618348092.951 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2021-04-13T21:08:12.951Z,1618348092.951 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2021-04-13T21:08:13.013Z,1618348093.013 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2021-04-13T21:08:13.014Z,1618348093.014 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2021-04-13T21:08:13.042Z,1618348093.042 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2021-04-13T21:08:13.042Z,1618348093.042 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2021-04-13T21:08:13.373Z,1618348093.373 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2021-04-13T21:08:13.374Z,1618348093.374 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2021-04-13T21:08:13.994Z,1618348093.994 [CTD_Seabird] Loaded
2021-04-13T21:08:13.994Z,1618348093.994 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2021-04-13T21:08:13.995Z,1618348093.995 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A7F4E0
2021-04-13T21:08:13.996Z,1618348093.996 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 9773
2021-04-13T21:08:14.029Z,1618348094.029 [ESPComponent] Loaded
2021-04-13T21:08:14.029Z,1618348094.029 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2021-04-13T21:08:14.049Z,1618348094.049 [PAR_Licor] Loaded
2021-04-13T21:08:14.049Z,1618348094.049 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2021-04-13T21:08:14.087Z,1618348094.087 [WetLabsBB2FL] Loaded
2021-04-13T21:08:14.087Z,1618348094.087 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2021-04-13T21:08:14.088Z,1618348094.088 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AAF4E0
2021-04-13T21:08:14.088Z,1618348094.088 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 9774
2021-04-13T21:08:14.089Z,1618348094.089 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2021-04-13T21:08:14.090Z,1618348094.090 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2021-04-13T21:08:14.239Z,1618348094.239 [SBIT](DEBUG): Construct Startup Built In Test.
2021-04-13T21:08:14.249Z,1618348094.249 [SBIT] Loaded
2021-04-13T21:08:14.249Z,1618348094.249 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2021-04-13T21:08:14.252Z,1618348094.252 [IBIT](DEBUG): Construct Initiated Built In Test.
2021-04-13T21:08:14.265Z,1618348094.265 [IBIT] Loaded
2021-04-13T21:08:14.265Z,1618348094.265 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2021-04-13T21:08:14.271Z,1618348094.271 [CBIT](DEBUG): Construct Continuous Built In Test.
2021-04-13T21:08:14.376Z,1618348094.376 [CBIT] Loaded
2021-04-13T21:08:14.376Z,1618348094.376 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2021-04-13T21:08:14.377Z,1618348094.377 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2021-04-13T21:08:14.383Z,1618348094.383 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2021-04-13T21:08:14.386Z,1618348094.386 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2021-04-13T21:08:14.396Z,1618348094.396 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2021-04-13T21:08:14.397Z,1618348094.397 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B1B4E0
2021-04-13T21:08:14.398Z,1618348094.398 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 9775
2021-04-13T21:08:14.402Z,1618348094.402 [Supervisor](INFO): Main Thread ID is 815
2021-04-13T21:08:14.403Z,1618348094.403 [Supervisor](DEBUG): Running supervisor.
2021-04-13T21:08:14.403Z,1618348094.403 [CommandLine ThreadHandler](INFO): Handler Thread ID is 9776
2021-04-13T21:08:14.407Z,1618348094.407 [controlThread ThreadHandler](INFO): Handler Thread ID is 9777
2021-04-13T21:08:14.407Z,1618348094.407 [controlThread](DEBUG): Initializing ControlThread
2021-04-13T21:08:14.408Z,1618348094.408 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2021-04-13T21:08:14.408Z,1618348094.408 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2021-04-13T21:08:14.409Z,1618348094.409 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2021-04-13T21:08:14.409Z,1618348094.409 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2021-04-13T21:08:14.409Z,1618348094.409 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2021-04-13T21:08:14.410Z,1618348094.410 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2021-04-13T21:08:14.410Z,1618348094.410 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2021-04-13T21:08:14.412Z,1618348094.412 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2021-04-13T21:08:14.413Z,1618348094.413 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2021-04-13T21:08:14.413Z,1618348094.413 [LoopControl](DEBUG): Initialize LoopControlComponent.
2021-04-13T21:08:14.414Z,1618348094.414 [NavChart](DEBUG): Initialize NavChart Navigation.
2021-04-13T21:08:14.415Z,1618348094.415 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2021-04-13T21:08:14.422Z,1618348094.422 [SBIT](INFO): Initialize SBIT Component.
2021-04-13T21:08:14.423Z,1618348094.423 [SBIT](IMPORTANT): git: 2021-04-12
2021-04-13T21:08:14.423Z,1618348094.423 [SBIT](INFO): git hash: 4d37ca66b772b9605b4b6eba7ca05c94731fcf29
2021-04-13T21:08:14.423Z,1618348094.423 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2021-04-13T21:08:14.423Z,1618348094.423 [SBIT](IMPORTANT): Kernel Version:#1 PREEMPT Tue Sep 24 11:12:27 PDT 2019
2021-04-13T21:08:14.425Z,1618348094.425 [SBIT](INFO): Beginning SBIT in 24.000000 seconds.
2021-04-13T21:08:14.425Z,1618348094.425 [IBIT](INFO): Initialize IBIT Component.
2021-04-13T21:08:14.426Z,1618348094.426 [CBIT](DEBUG): Initialize CBIT Component.
2021-04-13T21:08:14.427Z,1618348094.427 [logger ThreadHandler](INFO): Handler Thread ID is 9778
2021-04-13T21:08:14.438Z,1618348094.438 [CBIT](DEBUG): Initialized mux pins.
2021-04-13T21:08:14.438Z,1618348094.438 [CBIT](DEBUG): Initializing the watchdog timer.
2021-04-13T21:08:14.446Z,1618348094.446 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 9779
2021-04-13T21:08:14.458Z,1618348094.458 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 9780
2021-04-13T21:08:14.459Z,1618348094.459 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2021-04-13T21:08:14.462Z,1618348094.462 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2021-04-13T21:08:14.462Z,1618348094.462 [CBIT](DEBUG): Initializing heartbeat.
2021-04-13T21:08:14.470Z,1618348094.470 [Onboard ThreadHandler](INFO): Handler Thread ID is 9781
2021-04-13T21:08:14.488Z,1618348094.488 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 9782
2021-04-13T21:08:14.506Z,1618348094.506 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 9783
2021-04-13T21:08:14.507Z,1618348094.507 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2021-04-13T21:08:14.511Z,1618348094.511 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 9785
2021-04-13T21:08:14.514Z,1618348094.514 [WetLabsBB2FL](INFO): Powering up
2021-04-13T21:08:14.515Z,1618348094.515 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 9787
2021-04-13T21:08:14.522Z,1618348094.522 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2021-04-13T21:08:14.522Z,1618348094.522 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2021-04-13T21:08:14.523Z,1618348094.523 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2021-04-13T21:08:14.523Z,1618348094.523 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2021-04-13T21:08:14.523Z,1618348094.523 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2021-04-13T21:08:14.523Z,1618348094.523 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2021-04-13T21:08:14.523Z,1618348094.523 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2021-04-13T21:08:14.523Z,1618348094.523 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2021-04-13T21:08:14.524Z,1618348094.524 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2021-04-13T21:08:14.524Z,1618348094.524 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2021-04-13T21:08:14.524Z,1618348094.524 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2021-04-13T21:08:14.524Z,1618348094.524 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2021-04-13T21:08:14.524Z,1618348094.524 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2021-04-13T21:08:14.524Z,1618348094.524 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2021-04-13T21:08:14.524Z,1618348094.524 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2021-04-13T21:08:14.525Z,1618348094.525 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2021-04-13T21:08:14.534Z,1618348094.534 [CBIT](DEBUG): Deactivating GF circuits.
2021-04-13T21:08:14.534Z,1618348094.534 [CBIT](DEBUG): Deactivating emergency mode.
2021-04-13T21:08:14.570Z,1618348094.570 [CBIT](DEBUG): Backplane powered.
2021-04-13T21:08:14.571Z,1618348094.571 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2021-04-13T21:08:14.586Z,1618348094.586 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2021-04-13T21:08:14.614Z,1618348094.614 [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-13T21:08:14.615Z,1618348094.615 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2021-04-13T21:08:14.694Z,1618348094.694 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2021-04-13T21:08:14.695Z,1618348094.695 [Default:A.Wait](DEBUG): Construct Wait.
2021-04-13T21:08:14.697Z,1618348094.697 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2021-04-13T21:08:14.737Z,1618348094.737 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2021-04-13T21:08:14.754Z,1618348094.754 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2021-04-13T21:08:14.760Z,1618348094.760 [Default:E.Execute](DEBUG): Construct Execute.
2021-04-13T21:08:14.779Z,1618348094.779 [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-13T21:08:14.784Z,1618348094.784 [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-13T21:08:14.798Z,1618348094.798 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2021-04-13T21:08:14.870Z,1618348094.870 [Depth_Keller](ERROR): Pressure reading out of range: 1829.799438 decibar
2021-04-13T21:08:14.881Z,1618348094.881 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2021-04-13T21:08:14.881Z,1618348094.881 [DAT](INFO): Powering up
2021-04-13T21:08:14.886Z,1618348094.886 [DAT](DEBUG): Initializing DAT.
2021-04-13T21:08:14.894Z,1618348094.894 [Radio_Surface](INFO): Powering up
2021-04-13T21:08:14.906Z,1618348094.906 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2021-04-13T21:08:14.974Z,1618348094.974 [DepthRateCalculator](ERROR): Depth measurement is not active
2021-04-13T21:08:15.017Z,1618348095.017 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2021-04-13T21:08:15.030Z,1618348095.030 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2021-04-13T21:08:15.031Z,1618348095.031 [ElevatorServo](DEBUG): Initializing EZServoServo.
2021-04-13T21:08:15.042Z,1618348095.042 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2021-04-13T21:08:15.043Z,1618348095.043 [MassServo](DEBUG): Initializing EZServoServo.
2021-04-13T21:08:15.059Z,1618348095.059 [MassServo](DEBUG): Initializing MassServo.
2021-04-13T21:08:15.060Z,1618348095.060 [RudderServo](DEBUG): Initializing EZServoServo.
2021-04-13T21:08:15.066Z,1618348095.066 [RudderServo](DEBUG): Initializing RudderServo.
2021-04-13T21:08:15.067Z,1618348095.067 [ThrusterServo](DEBUG): Initializing EZServoServo.
2021-04-13T21:08:15.074Z,1618348095.074 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2021-04-13T21:08:15.930Z,1618348095.930 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2021-04-13T21:08:15.930Z,1618348095.930 [RudderServo](FAULT): Rudder failed to initialize
2021-04-13T21:08:15.930Z,1618348095.930 [RudderServo] Communications Fault, FailCount= 1
2021-04-13T21:08:15.930Z,1618348095.930 [RudderServo](ERROR): Communications Fault
2021-04-13T21:08:16.030Z,1618348096.030 [CBIT](ERROR): Communications Fault in component: RudderServo
2021-04-13T21:08:16.183Z,1618348096.183 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2021-04-13T21:08:16.183Z,1618348096.183 [RudderServo](INFO): Powering down
2021-04-13T21:08:16.866Z,1618348096.866 [RudderServo](DEBUG): Initializing EZServoServo.
2021-04-13T21:08:16.987Z,1618348096.987 [RudderServo](DEBUG): Initializing RudderServo.
2021-04-13T21:08:16.991Z,1618348096.991 [CBIT](INFO): Clearing failed state for component RudderServo
2021-04-13T21:08:16.991Z,1618348096.991 [RudderServo] No Fault, FailCount= 1
2021-04-13T21:08:17.126Z,1618348097.126 [WetLabsBB2FL](INFO): Powering down
2021-04-13T21:08:21.539Z,1618348101.539 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2021-04-13T21:08:39.104Z,1618348119.104 [SBIT](IMPORTANT): Beginning Startup BIT
2021-04-13T21:08:39.112Z,1618348119.112 [CBIT](IMPORTANT): Beginning ground fault scan
2021-04-13T21:08:42.725Z,1618348122.725 [NAL9602](INFO): Powering up NAL9602
2021-04-13T21:08:50.054Z,1618348130.054 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.001494
CHAN A1 (24V): -0.000703
CHAN A2 (12V): 0.000546
CHAN A3 (5V): 0.001559
CHAN B0 (3.3V): -0.000107
CHAN B1 (3.15aV): 0.001090
CHAN B2 (3.15bV): 0.000388
CHAN B3 (GND): 0.000658
OPEN: 0.004929
Full Scale Calc: 4.765 mA, -1.589 mA
2021-04-13T21:08:53.612Z,1618348133.612 [NAL9602](INFO): NAL9602 initialized
2021-04-13T21:09:15.033Z,1618348155.033 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-04-13T21:09:15.033Z,1618348155.033 [DAT] Communications Fault, FailCount= 1
2021-04-13T21:09:15.033Z,1618348155.033 [DAT](ERROR): Communications Fault
2021-04-13T21:09:15.071Z,1618348155.071 [CBIT](ERROR): Communications Fault in component: DAT
2021-04-13T21:09:15.461Z,1618348155.461 [DAT](INFO): Powering down
2021-04-13T21:09:16.264Z,1618348156.264 [CBIT](INFO): Clearing failed state for component DAT
2021-04-13T21:09:16.264Z,1618348156.264 [DAT] No Fault, FailCount= 1
2021-04-13T21:09:18.679Z,1618348158.679 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2021-04-13T21:09:18.679Z,1618348158.679 [DAT](INFO): Powering up
2021-04-13T21:09:18.679Z,1618348158.679 [DAT](DEBUG): Initializing DAT.
2021-04-13T21:09:18.711Z,1618348158.711 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2021-04-13T21:09:19.884Z,1618348159.884 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:09:30.703Z,1618348170.703 [CommandLine](IMPORTANT): got command failComponent
2021-04-13T21:09:30.704Z,1618348170.704 [CommandLine](IMPORTANT): Failed components:
2021-04-13T21:09:30.704Z,1618348170.704 [CommandLine](IMPORTANT): No failed Components.
2021-04-13T21:09:31.653Z,1618348171.653 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:09:33.238Z,1618348173.238 [SBIT](IMPORTANT): SBIT PASSED
2021-04-13T21:09:33.239Z,1618348173.239 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2021-04-13T21:09:33.240Z,1618348173.240 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count;

2021-04-13T21:09:33.240Z,1618348173.240 [SBIT](IMPORTANT): BackseatComponent.loadAtStartup=1 bool;

2021-04-13T21:09:33.240Z,1618348173.240 [SBIT](IMPORTANT): BackseatComponent.simulateHardware=1 bool;

2021-04-13T21:09:33.240Z,1618348173.240 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=214.815570 cubic_centimeter;

2021-04-13T21:09:33.240Z,1618348173.240 [SBIT](IMPORTANT): VerticalControl.massDefault=-1.247810 millimeter;

2021-04-13T21:09:33.659Z,1618348173.659 [MissionManager](IMPORTANT): Started mission Startup
2021-04-13T21:09:33.660Z,1618348173.660 [Startup] Running Loop=1
2021-04-13T21:09:33.660Z,1618348173.660 [Startup](DEBUG): Aggregate::initialize Startup
2021-04-13T21:09:33.660Z,1618348173.660 [Startup:A.GoToSurface] Running Loop=1
2021-04-13T21:09:33.660Z,1618348173.660 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-04-13T21:09:33.661Z,1618348173.661 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-04-13T21:09:33.661Z,1618348173.661 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-04-13T21:09:33.662Z,1618348173.662 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-04-13T21:09:33.662Z,1618348173.662 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-04-13T21:09:33.662Z,1618348173.662 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-04-13T21:09:33.664Z,1618348173.664 [Startup:StartupSatComms] Running Loop=1
2021-04-13T21:09:33.664Z,1618348173.664 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2021-04-13T21:09:33.664Z,1618348173.664 [Startup:StartupSatComms:A] Running Loop=1
2021-04-13T21:09:34.073Z,1618348174.073 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2021-04-13T21:09:43.777Z,1618348183.777 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:09:45.752Z,1618348185.752 [CommandLine](IMPORTANT): got command burn on
2021-04-13T21:09:45.752Z,1618348185.752 [CommandLine](IMPORTANT): Activating dropweight wire
2021-04-13T21:09:55.495Z,1618348195.495 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:10:06.023Z,1618348206.023 [CommandLine](IMPORTANT): got command burn off
2021-04-13T21:10:06.024Z,1618348206.024 [CommandLine](IMPORTANT): Deactivating dropweight wire
2021-04-13T21:10:13.651Z,1618348213.651 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:10:14.826Z,1618348214.826 [DVL_micro](ERROR): Failed to parse:
00000000008.0,0000.0000
2021-04-13T21:10:18.865Z,1618348218.865 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-04-13T21:10:18.865Z,1618348218.865 [DAT] Communications Fault, FailCount= 2
2021-04-13T21:10:18.865Z,1618348218.865 [DAT](ERROR): Communications Fault
2021-04-13T21:10:18.905Z,1618348218.905 [CBIT](ERROR): Communications Fault in component: DAT
2021-04-13T21:10:19.325Z,1618348219.325 [DAT](INFO): Powering down
2021-04-13T21:10:20.129Z,1618348220.129 [CBIT](INFO): Clearing failed state for component DAT
2021-04-13T21:10:20.129Z,1618348220.129 [DAT] No Fault, FailCount= 2
2021-04-13T21:10:22.507Z,1618348222.507 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2021-04-13T21:10:22.507Z,1618348222.507 [DAT](INFO): Powering up
2021-04-13T21:10:22.508Z,1618348222.508 [DAT](DEBUG): Initializing DAT.
2021-04-13T21:10:22.554Z,1618348222.554 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2021-04-13T21:10:33.843Z,1618348233.843 [Startup:StartupSatComms:A](INFO): Timed out from 2021-04-13T21:09:33.7Z
2021-04-13T21:10:33.843Z,1618348233.843 [Startup:StartupSatComms:A] Stopped
2021-04-13T21:10:33.843Z,1618348233.843 [Startup:StartupSatComms:B] Running Loop=1
2021-04-13T21:10:34.236Z,1618348234.236 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2021-04-13T21:10:42.548Z,1618348242.548 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004996
2021-04-13T21:10:49.904Z,1618348249.904 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20210413T174526/Courier0094.lzma
2021-04-13T21:10:50.904Z,1618348250.904 [DataOverHttps](INFO): Moved sent file to Logs/20210413T174526/Courier0094.lzma.bak
2021-04-13T21:10:50.904Z,1618348250.904 [DataOverHttps](INFO): SBD MOMSN=15565600
2021-04-13T21:10:53.250Z,1618348253.250 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:11:06.297Z,1618348266.297 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20210413T210804/Courier0000.lzma
2021-04-13T21:11:07.300Z,1618348267.300 [DataOverHttps](INFO): Moved sent file to Logs/20210413T210804/Courier0000.lzma.bak
2021-04-13T21:11:07.300Z,1618348267.300 [DataOverHttps](INFO): SBD MOMSN=15565602
2021-04-13T21:11:13.089Z,1618348273.089 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-13T21:11:14.648Z,1618348274.648 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2021-04-13T21:11:14.648Z,1618348274.648 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-04-13T21:11:14.675Z,1618348274.675 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-04-13T21:11:15.052Z,1618348275.052 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-04-13T21:11:15.052Z,1618348275.052 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2021-04-13T21:11:22.696Z,1618348282.696 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-04-13T21:11:22.697Z,1618348282.697 [DAT] Communications Fault, FailCount= 3
2021-04-13T21:11:22.697Z,1618348282.697 [DAT](ERROR): Communications Fault
2021-04-13T21:11:22.713Z,1618348282.713 [CBIT](ERROR): Communications Fault in component: DAT
2021-04-13T21:11:23.002Z,1618348283.002 [DataOverHttps](INFO): Sending 185 bytes from file Logs/20210413T174526/Express0095.lzma
2021-04-13T21:11:23.092Z,1618348283.092 [DAT](INFO): Powering down
2021-04-13T21:11:23.912Z,1618348283.912 [DVL_micro](ERROR): only read 3 of 4 data items
2021-04-13T21:11:23.912Z,1618348283.912 [DVL_micro](ERROR): Failed to parse:
:BI,+01630,+00225,00000,I
2021-04-13T21:11:23.957Z,1618348283.957 [CBIT](INFO): Clearing failed state for component DAT
2021-04-13T21:11:23.959Z,1618348283.959 [DAT] No Fault, FailCount= 3
2021-04-13T21:11:24.004Z,1618348284.004 [DataOverHttps](INFO): Moved sent file to Logs/20210413T174526/Express0095.lzma.bak
2021-04-13T21:11:24.004Z,1618348284.004 [DataOverHttps](INFO): SBD MOMSN=15565605
2021-04-13T21:11:26.412Z,1618348286.412 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2021-04-13T21:11:26.412Z,1618348286.412 [DAT](INFO): Powering up
2021-04-13T21:11:26.412Z,1618348286.412 [DAT](DEBUG): Initializing DAT.
2021-04-13T21:11:26.418Z,1618348286.418 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2021-04-13T21:11:34.041Z,1618348294.041 [Startup:StartupSatComms:B](INFO): Timed out from 2021-04-13T21:10:33.8Z
2021-04-13T21:11:34.041Z,1618348294.041 [Startup:StartupSatComms:B] Stopped
2021-04-13T21:11:34.041Z,1618348294.041 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2021-04-13T21:11:34.041Z,1618348294.041 [Startup:StartupSatComms] Stopped
2021-04-13T21:11:34.041Z,1618348294.041 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2021-04-13T21:11:34.042Z,1618348294.042 [Startup](INFO): Completed Startup
2021-04-13T21:11:34.042Z,1618348294.042 [MissionManager](INFO): Startup is completed.
2021-04-13T21:11:34.042Z,1618348294.042 [MissionManager](INFO): Uninitializing Mission Startup
2021-04-13T21:11:34.042Z,1618348294.042 [Startup] Stopped
2021-04-13T21:11:34.042Z,1618348294.042 [Startup](DEBUG): Aggregate::uninitialize Startup
2021-04-13T21:11:34.043Z,1618348294.043 [Startup:A.GoToSurface] Stopped
2021-04-13T21:11:34.043Z,1618348294.043 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-04-13T21:11:34.412Z,1618348294.412 [MissionManager](IMPORTANT): Started mission Default
2021-04-13T21:11:34.412Z,1618348294.412 [Default] Running Loop=1
2021-04-13T21:11:34.412Z,1618348294.412 [Default](DEBUG): Aggregate::initialize Default
2021-04-13T21:11:34.412Z,1618348294.412 [Default:B.GoToSurface] Running Loop=1
2021-04-13T21:11:34.412Z,1618348294.412 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-04-13T21:11:34.413Z,1618348294.413 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-04-13T21:11:34.413Z,1618348294.413 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-04-13T21:11:34.413Z,1618348294.413 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-04-13T21:11:34.414Z,1618348294.414 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-04-13T21:11:34.414Z,1618348294.414 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-04-13T21:11:34.415Z,1618348294.415 [Default:A.Wait] Running Loop=1
2021-04-13T21:11:34.415Z,1618348294.415 [Default:A.Wait](DEBUG): Initialize Wait Component.
2021-04-13T21:11:41.632Z,1618348301.632 [DataOverHttps](INFO): Sending 1008 bytes from file Logs/20210413T210804/Express0001.lzma
2021-04-13T21:11:42.631Z,1618348302.631 [DataOverHttps](INFO): Moved sent file to Logs/20210413T210804/Express0001.lzma.bak
2021-04-13T21:11:42.632Z,1618348302.632 [DataOverHttps](INFO): SBD MOMSN=15565609
2021-04-13T21:11:45.767Z,1618348305.767 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:11:47.748Z,1618348307.748 [Default:A.Wait](INFO): Done Waiting.
2021-04-13T21:11:47.748Z,1618348307.748 [Default:A.Wait] Stopped
2021-04-13T21:11:47.748Z,1618348307.748 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2021-04-13T21:11:48.153Z,1618348308.153 [Default:CheckIn] Running Loop=1
2021-04-13T21:11:48.154Z,1618348308.154 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-13T21:11:48.154Z,1618348308.154 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-13T21:11:48.568Z,1618348308.568 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2021-04-13T21:12:24.550Z,1618348344.550 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:12:26.524Z,1618348346.524 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-04-13T21:12:26.524Z,1618348346.524 [DAT] Communications Fault, FailCount= 4
2021-04-13T21:12:26.524Z,1618348346.524 [DAT](ERROR): Communications Fault
2021-04-13T21:12:26.561Z,1618348346.561 [CBIT](ERROR): Communications Fault in component: DAT
2021-04-13T21:12:26.925Z,1618348346.925 [DAT](INFO): Powering down
2021-04-13T21:12:27.778Z,1618348347.778 [CBIT](INFO): Clearing failed state for component DAT
2021-04-13T21:12:27.778Z,1618348347.778 [DAT] No Fault, FailCount= 4
2021-04-13T21:12:30.173Z,1618348350.173 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2021-04-13T21:12:30.198Z,1618348350.198 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2021-04-13T21:12:30.198Z,1618348350.198 [DAT](INFO): Powering up
2021-04-13T21:12:30.199Z,1618348350.199 [DAT](DEBUG): Initializing DAT.
2021-04-13T21:12:36.286Z,1618348356.286 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:12:47.997Z,1618348367.997 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:13:00.127Z,1618348380.127 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:13:05.319Z,1618348385.319 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-13T21:13:11.835Z,1618348391.835 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:13:17.090Z,1618348397.090 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-13T21:13:24.020Z,1618348404.020 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:13:29.205Z,1618348409.205 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-13T21:13:30.356Z,1618348410.356 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-04-13T21:13:30.356Z,1618348410.356 [DAT] Communications Fault, FailCount= 5
2021-04-13T21:13:30.357Z,1618348410.357 [DAT](ERROR): Communications Fault
2021-04-13T21:13:30.373Z,1618348410.373 [CBIT](ERROR): Communications Fault in component: DAT
2021-04-13T21:13:30.778Z,1618348410.778 [DAT](INFO): Powering down
2021-04-13T21:13:31.619Z,1618348411.619 [CBIT](INFO): Clearing failed state for component DAT
2021-04-13T21:13:31.619Z,1618348411.619 [DAT] No Fault, FailCount= 5
2021-04-13T21:13:33.999Z,1618348413.999 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2021-04-13T21:13:33.000Z,1618348414.000 [DAT](INFO): Powering up
2021-04-13T21:13:33.000Z,1618348414.000 [DAT](DEBUG): Initializing DAT.
2021-04-13T21:13:34.087Z,1618348414.087 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2021-04-13T21:13:35.672Z,1618348415.672 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:13:40.932Z,1618348420.932 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-13T21:13:47.388Z,1618348427.388 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:13:53.043Z,1618348433.043 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-13T21:13:56.616Z,1618348436.616 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2021-04-13T21:13:59.155Z,1618348439.155 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:14:04.784Z,1618348444.784 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-13T21:14:11.227Z,1618348451.227 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:14:15.623Z,1618348455.623 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2021-04-13T21:14:15.623Z,1618348455.623 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-04-13T21:14:15.633Z,1618348455.633 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-04-13T21:14:16.039Z,1618348456.039 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-04-13T21:14:16.039Z,1618348456.039 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2021-04-13T21:14:16.878Z,1618348456.878 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-13T21:14:22.938Z,1618348462.938 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:14:28.602Z,1618348468.602 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-13T21:14:34.206Z,1618348474.206 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-04-13T21:14:34.206Z,1618348474.206 [DAT] Communications Fault, FailCount= 6
2021-04-13T21:14:34.206Z,1618348474.206 [DAT](ERROR): Communications Fault
2021-04-13T21:14:34.252Z,1618348474.252 [CBIT](ERROR): Communications Fault in component: DAT
2021-04-13T21:14:34.606Z,1618348474.606 [DAT](INFO): Powering down
2021-04-13T21:14:35.128Z,1618348475.128 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:14:35.449Z,1618348475.449 [CBIT](INFO): Clearing failed state for component DAT
2021-04-13T21:14:35.449Z,1618348475.449 [DAT] No Fault, FailCount= 6
2021-04-13T21:14:37.844Z,1618348477.844 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2021-04-13T21:14:37.844Z,1618348477.844 [DAT](INFO): Powering up
2021-04-13T21:14:37.844Z,1618348477.844 [DAT](DEBUG): Initializing DAT.
2021-04-13T21:14:37.902Z,1618348477.902 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2021-04-13T21:14:40.716Z,1618348480.716 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-13T21:14:46.776Z,1618348486.776 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:14:52.435Z,1618348492.435 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-13T21:14:58.492Z,1618348498.492 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:15:04.551Z,1618348504.551 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-13T21:15:10.246Z,1618348510.246 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:15:16.275Z,1618348516.275 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-13T21:15:21.926Z,1618348521.926 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:15:27.979Z,1618348527.979 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-13T21:15:34.042Z,1618348534.042 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:15:38.034Z,1618348538.034 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-04-13T21:15:38.035Z,1618348538.035 [DAT] Communications Fault, FailCount= 7
2021-04-13T21:15:38.035Z,1618348538.035 [DAT](ERROR): Communications Fault
2021-04-13T21:15:38.076Z,1618348538.076 [CBIT](ERROR): Communications Fault in component: DAT
2021-04-13T21:15:38.438Z,1618348538.438 [DAT](INFO): Powering down
2021-04-13T21:15:39.262Z,1618348539.262 [CBIT](INFO): Clearing failed state for component DAT
2021-04-13T21:15:39.262Z,1618348539.262 [DAT] No Fault, FailCount= 7
2021-04-13T21:15:41.692Z,1618348541.692 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2021-04-13T21:15:41.692Z,1618348541.692 [DAT](INFO): Powering up
2021-04-13T21:15:41.692Z,1618348541.692 [DAT](DEBUG): Initializing DAT.
2021-04-13T21:15:41.774Z,1618348541.774 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2021-04-13T21:15:45.819Z,1618348545.819 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:15:57.884Z,1618348557.884 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:16:09.188Z,1618348569.188 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:16:21.348Z,1618348581.348 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:16:33.031Z,1618348593.031 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:16:41.866Z,1618348601.866 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-04-13T21:16:41.866Z,1618348601.866 [DAT] Communications Fault, FailCount= 8
2021-04-13T21:16:41.867Z,1618348601.867 [DAT](ERROR): Communications Fault
2021-04-13T21:16:41.917Z,1618348601.917 [CBIT](ERROR): Communications Fault in component: DAT
2021-04-13T21:16:41.917Z,1618348601.917 [CBIT](FAULT): Communications Fault in component: DAT
2021-04-13T21:16:42.270Z,1618348602.270 [DAT](INFO): Powering down
2021-04-13T21:16:44.748Z,1618348604.748 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:16:48.327Z,1618348608.327 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-04-13T21:11:48.2Z
2021-04-13T21:16:48.327Z,1618348608.327 [Default:CheckIn:Read_GPS] Stopped
2021-04-13T21:16:48.327Z,1618348608.327 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-13T21:16:48.784Z,1618348608.784 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2021-04-13T21:16:55.277Z,1618348615.277 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20210413T210804/Courier0004.lzma
2021-04-13T21:16:56.279Z,1618348616.279 [DataOverHttps](INFO): Moved sent file to Logs/20210413T210804/Courier0004.lzma.bak
2021-04-13T21:16:56.280Z,1618348616.280 [DataOverHttps](INFO): SBD MOMSN=15565639
2021-04-13T21:16:56.461Z,1618348616.461 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:17:08.626Z,1618348628.626 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:17:11.907Z,1618348631.907 [DataOverHttps](INFO): Sending 356 bytes from file Logs/20210413T210804/Express0005.lzma
2021-04-13T21:17:12.907Z,1618348632.907 [DataOverHttps](INFO): Moved sent file to Logs/20210413T210804/Express0005.lzma.bak
2021-04-13T21:17:12.908Z,1618348632.908 [DataOverHttps](INFO): SBD MOMSN=15565641
2021-04-13T21:17:14.996Z,1618348634.996 [Default:CheckIn:Read_Iridium] Stopped
2021-04-13T21:17:14.996Z,1618348634.996 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-13T21:17:14.996Z,1618348634.996 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-13T21:17:16.635Z,1618348636.635 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2021-04-13T21:17:16.635Z,1618348636.635 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-04-13T21:17:16.662Z,1618348636.662 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-04-13T21:17:17.042Z,1618348637.042 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-04-13T21:17:17.042Z,1618348637.042 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2021-04-13T21:17:20.330Z,1618348640.330 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:17:23.517Z,1618348643.517 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-13T21:17:32.419Z,1618348652.419 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:17:44.134Z,1618348664.134 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:17:55.955Z,1618348675.955 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:18:07.970Z,1618348687.970 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:18:19.688Z,1618348699.688 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:18:31.807Z,1618348711.807 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:18:43.519Z,1618348723.519 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:18:55.237Z,1618348735.237 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:18:56.784Z,1618348736.784 [NAL9602](FAULT): GPS failed to acquire within timeout.
2021-04-13T21:18:56.784Z,1618348736.784 [NAL9602] Data Fault, FailCount= 1
2021-04-13T21:18:56.784Z,1618348736.784 [NAL9602](ERROR): Data Fault
2021-04-13T21:18:56.840Z,1618348736.840 [CBIT](ERROR): Data Fault in component: NAL9602
2021-04-13T21:18:57.192Z,1618348737.192 [NAL9602](INFO): Powering down
2021-04-13T21:18:58.028Z,1618348738.028 [CBIT](INFO): Clearing failed state for component NAL9602
2021-04-13T21:18:58.029Z,1618348738.029 [NAL9602] No Fault, FailCount= 1
2021-04-13T21:19:06.955Z,1618348746.955 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:19:18.669Z,1618348758.669 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:19:19.071Z,1618348759.071 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-13T21:19:27.492Z,1618348767.492 [NAL9602](INFO): Powering up NAL9602
2021-04-13T21:19:30.787Z,1618348770.787 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:19:32.798Z,1618348772.798 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-13T21:19:38.400Z,1618348778.400 [NAL9602](INFO): NAL9602 initialized
2021-04-13T21:19:42.504Z,1618348782.504 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:19:47.295Z,1618348787.295 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:20:17.622Z,1618348817.622 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2021-04-13T21:20:17.622Z,1618348817.622 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-04-13T21:20:17.633Z,1618348817.633 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-04-13T21:20:18.008Z,1618348818.008 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-04-13T21:20:18.008Z,1618348818.008 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2021-04-13T21:21:42.080Z,1618348902.080 [CBIT](INFO): Clearing failed state for component DAT
2021-04-13T21:21:42.080Z,1618348902.080 [DAT] No Fault, FailCount= 8
2021-04-13T21:21:42.456Z,1618348902.456 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2021-04-13T21:21:42.456Z,1618348902.456 [DAT](INFO): Powering up
2021-04-13T21:21:42.456Z,1618348902.456 [DAT](DEBUG): Initializing DAT.
2021-04-13T21:21:42.470Z,1618348902.470 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2021-04-13T21:21:56.580Z,1618348916.580 [DAT](INFO): commRate: 800
2021-04-13T21:21:58.595Z,1618348918.595 [DAT](INFO): entering command mode
2021-04-13T21:21:58.994Z,1618348918.994 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-13T21:21:59.397Z,1618348919.397 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-13T21:21:59.814Z,1618348919.814 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-13T21:22:00.234Z,1618348920.234 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-13T21:22:00.670Z,1618348920.670 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-13T21:22:01.020Z,1618348921.020 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-13T21:22:01.433Z,1618348921.433 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-13T21:22:01.838Z,1618348921.838 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-13T21:22:02.224Z,1618348922.224 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-13T21:22:02.642Z,1618348922.642 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-13T21:22:03.075Z,1618348923.075 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-13T21:22:03.106Z,1618348923.106 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-13T21:22:03.470Z,1618348923.470 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-13T21:22:03.861Z,1618348923.861 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-13T21:22:04.245Z,1618348924.245 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-13T21:22:04.653Z,1618348924.653 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-13T21:22:05.053Z,1618348925.053 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-13T21:22:05.479Z,1618348925.479 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-13T21:22:05.874Z,1618348925.874 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-13T21:22:06.278Z,1618348926.278 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-13T21:22:06.677Z,1618348926.677 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-13T21:22:07.072Z,1618348927.072 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-13T21:22:07.491Z,1618348927.491 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-13T21:22:07.927Z,1618348927.927 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-13T21:22:08.298Z,1618348928.298 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-13T21:22:08.702Z,1618348928.702 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-13T21:22:09.093Z,1618348929.093 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-13T21:22:09.514Z,1618348929.514 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-13T21:22:09.918Z,1618348929.918 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-13T21:22:10.326Z,1618348930.326 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-13T21:22:10.713Z,1618348930.713 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-13T21:22:11.113Z,1618348931.113 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-13T21:22:11.517Z,1618348931.517 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-13T21:22:11.927Z,1618348931.927 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-13T21:22:12.325Z,1618348932.325 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-13T21:22:12.742Z,1618348932.742 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-13T21:22:13.180Z,1618348933.180 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:22:13.206Z,1618348933.206 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-13T21:22:13.566Z,1618348933.566 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-13T21:22:13.961Z,1618348933.961 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-13T21:22:13.961Z,1618348933.961 [DAT](FAULT): failed to enter command mode
2021-04-13T21:22:14.345Z,1618348934.345 [DAT](INFO): entering command mode
2021-04-13T21:22:14.751Z,1618348934.751 [DAT](INFO): setting verbose to 3
2021-04-13T21:22:15.181Z,1618348935.181 [DAT](INFO): set verbose to 3
2021-04-13T21:22:15.181Z,1618348935.181 [DAT](INFO): setting DatVerbose to 27440
2021-04-13T21:22:15.584Z,1618348935.584 [DAT](INFO): set DatVerbose to 27440
2021-04-13T21:22:15.584Z,1618348935.584 [DAT](INFO): setting transmit power to 8
2021-04-13T21:22:15.600Z,1618348935.600 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-13T21:22:15.601Z,1618348935.601 [Default:CheckIn:C.Wait] Stopped
2021-04-13T21:22:15.601Z,1618348935.601 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-13T21:22:15.601Z,1618348935.601 [Default:CheckIn:D] Running Loop=1
2021-04-13T21:22:16.034Z,1618348936.034 [DAT](INFO): set transmit power to 8
2021-04-13T21:22:16.034Z,1618348936.034 [DAT](INFO): setting local address to 8
2021-04-13T21:22:16.052Z,1618348936.052 [Default:CheckIn:D] Stopped
2021-04-13T21:22:16.052Z,1618348936.052 [Default:CheckIn:E] Running Loop=1
2021-04-13T21:22:16.384Z,1618348936.384 [DAT](INFO): set local address to 8
2021-04-13T21:22:16.413Z,1618348936.413 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.694000 min 
2021-04-13T21:22:16.413Z,1618348936.413 [Default:CheckIn:E] Stopped
2021-04-13T21:22:16.413Z,1618348936.413 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-13T21:22:16.413Z,1618348936.413 [Default:CheckIn] Stopped
2021-04-13T21:22:16.413Z,1618348936.413 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-13T21:22:16.413Z,1618348936.413 [Default:CheckIn](INFO): Running loop #2
2021-04-13T21:22:16.413Z,1618348936.413 [Default:CheckIn] Running Loop=2
2021-04-13T21:22:16.413Z,1618348936.413 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-13T21:22:16.418Z,1618348936.418 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-13T21:22:24.909Z,1618348944.909 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:22:37.033Z,1618348957.033 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:22:48.343Z,1618348968.343 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:23:00.061Z,1618348980.061 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:23:01.619Z,1618348981.619 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-13T21:23:11.775Z,1618348991.775 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:23:13.388Z,1618348993.388 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-13T21:23:18.590Z,1618348998.590 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2021-04-13T21:23:18.591Z,1618348998.591 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-04-13T21:23:18.601Z,1618348998.601 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-04-13T21:23:19.021Z,1618348999.021 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-04-13T21:23:19.021Z,1618348999.021 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2021-04-13T21:23:23.083Z,1618349003.083 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:23:25.105Z,1618349005.105 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-13T21:23:35.210Z,1618349015.210 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:23:36.826Z,1618349016.826 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-13T21:23:46.927Z,1618349026.927 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:23:48.948Z,1618349028.948 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-13T21:23:58.639Z,1618349038.639 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:24:00.658Z,1618349040.658 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-13T21:24:10.383Z,1618349050.383 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:24:12.781Z,1618349052.781 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-13T21:24:22.076Z,1618349062.076 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:24:24.499Z,1618349064.499 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-13T21:24:34.195Z,1618349074.195 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:24:36.214Z,1618349076.214 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-13T21:24:45.907Z,1618349085.907 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:24:48.332Z,1618349088.332 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-13T21:24:58.031Z,1618349098.031 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:25:00.049Z,1618349100.049 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-13T21:25:09.745Z,1618349109.745 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:25:11.764Z,1618349111.764 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-13T21:25:21.052Z,1618349121.052 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:25:23.479Z,1618349123.479 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-13T21:25:33.175Z,1618349133.175 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:25:44.894Z,1618349144.894 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:25:57.014Z,1618349157.014 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:26:08.731Z,1618349168.731 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:26:17.958Z,1618349177.958 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2021-04-13T21:26:17.958Z,1618349177.958 [DVL_micro](ERROR): Failed to parse:
:TS,000000000,0000.0,1489.0,000
2021-04-13T21:26:19.579Z,1618349179.579 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2021-04-13T21:26:19.579Z,1618349179.579 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-04-13T21:26:19.618Z,1618349179.618 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-04-13T21:26:19.993Z,1618349179.993 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-04-13T21:26:19.993Z,1618349179.993 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2021-04-13T21:26:20.848Z,1618349180.848 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:26:32.566Z,1618349192.566 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:26:44.278Z,1618349204.278 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:26:56.004Z,1618349216.004 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:27:07.709Z,1618349227.709 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:27:16.565Z,1618349236.565 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-04-13T21:22:16.4Z
2021-04-13T21:27:16.565Z,1618349236.565 [Default:CheckIn:Read_GPS] Stopped
2021-04-13T21:27:16.565Z,1618349236.565 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-13T21:27:19.535Z,1618349239.535 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:27:23.524Z,1618349243.524 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20210413T210804/Courier0007.lzma
2021-04-13T21:27:24.524Z,1618349244.524 [DataOverHttps](INFO): Moved sent file to Logs/20210413T210804/Courier0007.lzma.bak
2021-04-13T21:27:24.524Z,1618349244.524 [DataOverHttps](INFO): SBD MOMSN=15565657
2021-04-13T21:27:31.248Z,1618349251.248 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:27:41.110Z,1618349261.110 [DataOverHttps](INFO): Sending 236 bytes from file Logs/20210413T210804/Express0008.lzma
2021-04-13T21:27:42.112Z,1618349262.112 [DataOverHttps](INFO): Moved sent file to Logs/20210413T210804/Express0008.lzma.bak
2021-04-13T21:27:42.112Z,1618349262.112 [DataOverHttps](INFO): SBD MOMSN=15565659
2021-04-13T21:27:43.368Z,1618349263.368 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:27:44.480Z,1618349264.480 [Default:CheckIn:Read_Iridium] Stopped
2021-04-13T21:27:44.480Z,1618349264.480 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-13T21:27:44.480Z,1618349264.480 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-13T21:27:55.030Z,1618349275.030 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:28:07.159Z,1618349287.159 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:28:18.865Z,1618349298.865 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:28:30.591Z,1618349310.591 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:28:42.300Z,1618349322.300 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:28:54.021Z,1618349334.021 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:29:00.873Z,1618349340.873 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-13T21:29:05.731Z,1618349345.731 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:29:17.855Z,1618349357.855 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:29:20.620Z,1618349360.620 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2021-04-13T21:29:20.620Z,1618349360.620 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-04-13T21:29:20.657Z,1618349360.657 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-04-13T21:29:21.036Z,1618349361.036 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-04-13T21:29:21.036Z,1618349361.036 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2021-04-13T21:29:25.535Z,1618349365.535 [CommandLine](IMPORTANT): got command failComponent
2021-04-13T21:29:25.535Z,1618349365.535 [CommandLine](IMPORTANT): Failed components:
2021-04-13T21:29:25.535Z,1618349365.535 [CommandLine](IMPORTANT): No failed Components.
2021-04-13T21:29:29.157Z,1618349369.157 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:29:39.604Z,1618349379.604 [NAL9602](FAULT): GPS failed to acquire within timeout.
2021-04-13T21:29:39.604Z,1618349379.604 [NAL9602] Data Fault, FailCount= 2
2021-04-13T21:29:39.604Z,1618349379.604 [NAL9602](ERROR): Data Fault
2021-04-13T21:29:39.682Z,1618349379.682 [CBIT](ERROR): Data Fault in component: NAL9602
2021-04-13T21:29:40.017Z,1618349380.017 [NAL9602](INFO): Powering down
2021-04-13T21:29:40.856Z,1618349380.856 [CBIT](INFO): Clearing failed state for component NAL9602
2021-04-13T21:29:40.856Z,1618349380.856 [NAL9602] No Fault, FailCount= 2
2021-04-13T21:30:10.308Z,1618349410.308 [NAL9602](INFO): Powering up NAL9602
2021-04-13T21:30:21.212Z,1618349421.212 [NAL9602](INFO): NAL9602 initialized
2021-04-13T21:32:00.671Z,1618349520.671 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:32:12.383Z,1618349532.383 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:32:21.639Z,1618349541.639 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2021-04-13T21:32:21.639Z,1618349541.639 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-04-13T21:32:21.649Z,1618349541.649 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-04-13T21:32:22.060Z,1618349542.060 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-04-13T21:32:22.060Z,1618349542.060 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2021-04-13T21:32:24.500Z,1618349544.500 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:32:36.223Z,1618349556.223 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:32:45.050Z,1618349565.050 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-13T21:32:45.050Z,1618349565.050 [Default:CheckIn:C.Wait] Stopped
2021-04-13T21:32:45.050Z,1618349565.050 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-13T21:32:45.050Z,1618349565.050 [Default:CheckIn:D] Running Loop=1
2021-04-13T21:32:45.463Z,1618349565.463 [Default:CheckIn:D] Stopped
2021-04-13T21:32:45.463Z,1618349565.463 [Default:CheckIn:E] Running Loop=1
2021-04-13T21:32:45.857Z,1618349565.857 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.184186 min 
2021-04-13T21:32:45.857Z,1618349565.857 [Default:CheckIn:E] Stopped
2021-04-13T21:32:45.857Z,1618349565.857 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-13T21:32:45.857Z,1618349565.857 [Default:CheckIn] Stopped
2021-04-13T21:32:45.857Z,1618349565.857 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-13T21:32:45.858Z,1618349565.858 [Default:CheckIn](INFO): Running loop #3
2021-04-13T21:32:45.858Z,1618349565.858 [Default:CheckIn] Running Loop=3
2021-04-13T21:32:45.858Z,1618349565.858 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-13T21:32:45.858Z,1618349565.858 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-13T21:32:48.335Z,1618349568.335 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:32:53.977Z,1618349573.977 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-13T21:33:00.055Z,1618349580.055 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:33:05.733Z,1618349585.733 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-13T21:33:12.171Z,1618349592.171 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:33:17.458Z,1618349597.458 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-13T21:33:23.894Z,1618349603.894 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:33:29.144Z,1618349609.144 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-13T21:33:35.598Z,1618349615.598 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:33:41.262Z,1618349621.262 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-13T21:33:47.323Z,1618349627.323 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:33:53.055Z,1618349633.055 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-13T21:33:59.057Z,1618349639.057 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:34:04.718Z,1618349644.718 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-13T21:34:11.188Z,1618349651.188 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:34:16.431Z,1618349656.431 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-13T21:34:22.894Z,1618349662.894 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:34:28.604Z,1618349668.604 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-13T21:34:34.614Z,1618349674.614 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:34:40.266Z,1618349680.266 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-13T21:34:46.732Z,1618349686.732 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:34:52.395Z,1618349692.395 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-13T21:34:58.455Z,1618349698.455 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-13T21:35:22.631Z,1618349722.631 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2021-04-13T21:35:22.631Z,1618349722.631 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-04-13T21:35:22.642Z,1618349722.642 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-04-13T21:35:23.048Z,1618349723.048 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-04-13T21:35:23.048Z,1618349723.048 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2021-04-13T21:37:46.113Z,1618349866.113 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-04-13T21:32:45.9Z
2021-04-13T21:37:46.113Z,1618349866.113 [Default:CheckIn:Read_GPS] Stopped
2021-04-13T21:37:46.113Z,1618349866.113 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-13T21:37:54.597Z,1618349874.597 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20210413T210804/Courier0010.lzma
2021-04-13T21:37:55.596Z,1618349875.596 [DataOverHttps](INFO): Moved sent file to Logs/20210413T210804/Courier0010.lzma.bak
2021-04-13T21:37:55.596Z,1618349875.596 [DataOverHttps](INFO): SBD MOMSN=15565665
2021-04-13T21:38:16.631Z,1618349896.631 [DataOverHttps](INFO): Sending 200 bytes from file Logs/20210413T210804/Express0011.lzma
2021-04-13T21:38:17.632Z,1618349897.632 [DataOverHttps](INFO): Moved sent file to Logs/20210413T210804/Express0011.lzma.bak
2021-04-13T21:38:17.632Z,1618349897.632 [DataOverHttps](INFO): SBD MOMSN=15565667
2021-04-13T21:38:20.630Z,1618349900.630 [Default:CheckIn:Read_Iridium] Stopped
2021-04-13T21:38:20.630Z,1618349900.630 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-13T21:38:20.630Z,1618349900.630 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-13T21:38:23.815Z,1618349903.815 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2021-04-13T21:38:23.815Z,1618349903.815 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-04-13T21:38:23.862Z,1618349903.862 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-04-13T21:38:24.286Z,1618349904.286 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-04-13T21:38:24.286Z,1618349904.286 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2021-04-13T21:39:15.097Z,1618349955.097 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5.
2021-04-13T21:39:15.100Z,1618349955.100 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2021-04-13T21:40:22.164Z,1618350022.164 [NAL9602](FAULT): GPS failed to acquire within timeout.
2021-04-13T21:40:22.164Z,1618350022.164 [NAL9602] Data Fault, FailCount= 3
2021-04-13T21:40:22.164Z,1618350022.164 [NAL9602](ERROR): Data Fault
2021-04-13T21:40:22.221Z,1618350022.221 [CBIT](ERROR): Data Fault in component: NAL9602
2021-04-13T21:40:22.568Z,1618350022.568 [NAL9602](INFO): Powering down
2021-04-13T21:40:23.410Z,1618350023.410 [CBIT](INFO): Clearing failed state for component NAL9602
2021-04-13T21:40:23.410Z,1618350023.410 [NAL9602] No Fault, FailCount= 3
2021-04-13T21:40:52.873Z,1618350052.873 [NAL9602](INFO): Powering up NAL9602
2021-04-13T21:41:03.780Z,1618350063.780 [NAL9602](INFO): NAL9602 initialized
2021-04-13T21:41:24.797Z,1618350084.797 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2021-04-13T21:41:24.797Z,1618350084.797 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-04-13T21:41:24.807Z,1618350084.807 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-04-13T21:41:25.217Z,1618350085.217 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-04-13T21:41:25.218Z,1618350085.218 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2021-04-13T21:41:50.769Z,1618350110.769 [CommandLine](IMPORTANT): got command failComponent
2021-04-13T21:41:50.769Z,1618350110.769 [CommandLine](IMPORTANT): Failed components:
2021-04-13T21:41:50.769Z,1618350110.769 [CommandLine](IMPORTANT): No failed Components.
2021-04-13T21:41:53.441Z,1618350113.441 [CommandLine](IMPORTANT): got command gfscan
2021-04-13T21:41:53.504Z,1618350113.504 [CBIT](IMPORTANT): Beginning ground fault scan
2021-04-13T21:42:04.817Z,1618350124.817 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214203.00,A,3648.17170,N,12147.28685,W,0.389,124.20,130421,,,A*71
2021-04-13T21:42:04.821Z,1618350124.821 [NAL9602](INFO): GPS fix at 20210413T214203: (36.802862, -121.788114)
2021-04-13T21:42:04.881Z,1618350124.881 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.003255
CHAN A1 (24V): -0.000928
CHAN A2 (12V): -0.000031
CHAN A3 (5V): 0.000204
CHAN B0 (3.3V): -0.000040
CHAN B1 (3.15aV): 0.000663
CHAN B2 (3.15bV): 0.000112
CHAN B3 (GND): 0.000800
OPEN: 0.007778
Full Scale Calc: 4.765 mA, -1.589 mA
2021-04-13T21:42:17.141Z,1618350137.141 [CommandLine](IMPORTANT): got command run ./Missions/Insert/Science.xml
2021-04-13T21:42:17.155Z,1618350137.155 [MissionManager](INFO): Loading Mission: ./Missions/Insert/Science.xml
2021-04-13T21:42:17.719Z,1618350137.719 [MissionManager](INFO): DefineArg Science.PeakDetectChlActive = 0 bool
2021-04-13T21:42:17.730Z,1618350137.730 [MissionManager](INFO): DefineArg Science.TimeWindowPeakReport = nan min
2021-04-13T21:42:17.733Z,1618350137.733 [MissionManager](INFO): DefineArg Science.HighestChlPeakReportActive = 0 bool
2021-04-13T21:42:17.744Z,1618350137.744 [MissionManager](INFO): DefineArg Science.PatchTracking = 0 bool
2021-04-13T21:42:17.787Z,1618350137.787 [MissionManager](INFO): DefineArg Science.FilterWidthHorizontal = 3.000000 count
2021-04-13T21:42:17.798Z,1618350137.798 [MissionManager](INFO): DefineArg Science.NumProfilesSlidingwindow = 100.000000 count
2021-04-13T21:42:17.858Z,1618350137.858 [MissionManager](INFO): DefineArg Science.OffPeakFractionHorizontal = 80.000000 %
2021-04-13T21:42:17.861Z,1618350137.861 [MissionManager](INFO): DefineArg Science.PeakDetectNO3Active = 0 bool
2021-04-13T21:42:17.900Z,1618350137.900 [MissionManager](INFO): DefineArg Science.PeakDetectOilActive = 0 bool
2021-04-13T21:42:17.908Z,1618350137.908 [MissionManager](INFO): DefineArg Science.PeakDetectFDOMActive = 0 bool
2021-04-13T21:42:17.943Z,1618350137.943 [MissionManager](INFO): DefineArg Science.UpwardDerivativeOfTemperatureActive = 0 bool
2021-04-13T21:42:17.946Z,1618350137.946 [MissionManager](INFO): DefineArg Science.EnabledAanderaaO2 = 0.000000 bool
2021-04-13T21:42:17.990Z,1618350137.990 [MissionManager](INFO): DefineArg Science.EnabledNeilBrown = 0.000000 bool
2021-04-13T21:42:17.994Z,1618350137.994 [MissionManager](INFO): DefineArg Science.EnabledSeabird = 1.000000 bool
2021-04-13T21:42:17.997Z,1618350137.997 [MissionManager](INFO): DefineArg Science.EnabledWetLabsBB2FL = 1.000000 bool
2021-04-13T21:42:18.021Z,1618350138.021 [MissionManager](INFO): DefineArg Science.EnabledWetLabsSeaOWL_UV_A = 0.000000 bool
2021-04-13T21:42:18.024Z,1618350138.024 [MissionManager](INFO): DefineArg Science.EnabledWetLabsUBAT = 0.000000 bool
2021-04-13T21:42:18.033Z,1618350138.033 [MissionManager](INFO): DefineArg Science.LowPassWindowLength = 20.000000 count
2021-04-13T21:42:18.036Z,1618350138.036 [MissionManager](INFO): DefineArg Science.MedianFilterLen = 5.000000 count
2021-04-13T21:42:18.067Z,1618350138.067 [MissionManager](INFO): DefineArg Science.PeakShallowBound = nan m
2021-04-13T21:42:18.070Z,1618350138.070 [MissionManager](INFO): DefineArg Science.PeakDeepBound = nan m
2021-04-13T21:42:18.073Z,1618350138.073 [MissionManager](INFO): DefineOutput Science.PeakChl = 0.000000 ug/l
2021-04-13T21:42:18.093Z,1618350138.093 [MissionManager](INFO): DefineOutput Science.PeakChlDepth = 0.000000 m
2021-04-13T21:42:18.096Z,1618350138.096 [MissionManager](INFO): DefineOutput Science.PeakChlTemperature = 0.000000 degC
2021-04-13T21:42:18.175Z,1618350138.175 [MissionManager](INFO): DefineOutput Science.PeakChlLatitude = nan arcdeg
2021-04-13T21:42:18.179Z,1618350138.179 [MissionManager](INFO): DefineOutput Science.PeakChlLongitude = nan arcdeg
2021-04-13T21:42:18.246Z,1618350138.246 [MissionManager](INFO): DefineOutput Science.PeakNO3 = 0.000000 umol/l
2021-04-13T21:42:18.249Z,1618350138.249 [MissionManager](INFO): DefineOutput Science.PeakNO3Depth = 0.000000 m
2021-04-13T21:42:18.269Z,1618350138.269 [MissionManager](INFO): DefineOutput Science.PeakNO3Latitude = nan arcdeg
2021-04-13T21:42:18.284Z,1618350138.284 [MissionManager](INFO): DefineOutput Science.PeakNO3Longitude = nan arcdeg
2021-04-13T21:42:18.296Z,1618350138.296 [MissionManager](INFO): DefineOutput Science.PatchChl = 0.000000 m
2021-04-13T21:42:18.320Z,1618350138.320 [MissionManager](INFO): DefineOutput Science.PatchChlDepth = 0.000000 m
2021-04-13T21:42:18.331Z,1618350138.331 [MissionManager](INFO): DefineOutput Science.PatchChlLatitude = nan arcdeg
2021-04-13T21:42:18.351Z,1618350138.351 [MissionManager](INFO): DefineOutput Science.PatchChlLongitude = nan arcdeg
2021-04-13T21:42:18.367Z,1618350138.367 [MissionManager](INFO): DefineOutput Science.PatchChlDistance = nan m
2021-04-13T21:42:18.390Z,1618350138.390 [MissionManager](INFO): DefineOutput Science.PeakOil = 0.000000 kg/m3
2021-04-13T21:42:18.402Z,1618350138.402 [MissionManager](INFO): DefineOutput Science.PeakOilDepth = 0.000000 m
2021-04-13T21:42:18.405Z,1618350138.405 [MissionManager](INFO): DefineOutput Science.PeakOilLatitude = nan arcdeg
2021-04-13T21:42:18.416Z,1618350138.416 [MissionManager](INFO): DefineOutput Science.PeakOilLongitude = nan arcdeg
2021-04-13T21:42:18.450Z,1618350138.450 [MissionManager](INFO): DefineOutput Science.PeakFDOM = 0.000000 ppb
2021-04-13T21:42:18.453Z,1618350138.453 [MissionManager](INFO): DefineOutput Science.PeakFDOMDepth = 0.000000 m
2021-04-13T21:42:18.477Z,1618350138.477 [MissionManager](INFO): DefineOutput Science.PeakFDOMTemperature = nan degC
2021-04-13T21:42:18.488Z,1618350138.488 [MissionManager](INFO): DefineOutput Science.PeakFDOMLatitude = nan arcdeg
2021-04-13T21:42:18.497Z,1618350138.497 [MissionManager](INFO): DefineOutput Science.PeakFDOMLongitude = nan arcdeg
2021-04-13T21:42:18.512Z,1618350138.512 [MissionManager](INFO): DefineOutput Science.PatchFDOMDistance = nan m
2021-04-13T21:42:18.747Z,1618350138.747 [Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-04-13T21:42:18.811Z,1618350138.811 [Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2021-04-13T21:42:18.856Z,1618350138.856 [Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-04-13T21:42:18.920Z,1618350138.920 [Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-04-13T21:42:18.993Z,1618350138.993 [Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-04-13T21:42:19.182Z,1618350139.182 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<!-- ====================
     Generated by TethysL
     ==================== -->
<Aggregate xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
           xmlns="Tethys"
           xmlns:Units="Tethys/Units"
           xmlns:Estimation="Tethys/Estimation"
           xmlns:Science="Tethys/Science"
           xmlns:Universal="Tethys/Universal"
           xmlns:Trigger="Tethys/Trigger"
           xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                               Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                               Tethys/Estimation http://okeanids.mbari.org/tethys/Xml/Estimation.xsd
                               Tethys/Science http://okeanids.mbari.org/tethys/Xml/Science.xsd
                               Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd
                               Tethys/Trigger http://okeanids.mbari.org/tethys/Xml/Trigger.xsd"
       Id="Science">

    <DefineArg Name="PeakDetectChlActive"><Description>
        Turns on peak detection of Cholorphyll.
    </Description><False/></DefineArg>

    <DefineArg Name="TimeWindowPeakReport"><Description>
        If greater than zero, report a peak every window. If NaN or zero, this
        variable is ignored.
    </Description><Units:minute/><Value>NaN</Value></DefineArg>

    <DefineArg Name="HighestChlPeakReportActive"><Description>
        Turns on reporting of the highest peak value on yo-yo profiles in a
        horizontal sliding window (of length numProfilesSlidingwindow)
    </Description><False/></DefineArg>

    <DefineArg Name="PatchTracking"><Description>
        If tracking a patch
    </Description><False/></DefineArg>

    <DefineArg Name="FilterWidthHorizontal"><Description>
        Width of boxcar filter applied to yoyo-wise chl peaks to pick out the
        highest peak.
    </Description><Units:count/><Value>3</Value></DefineArg>

    <DefineArg Name="NumProfilesSlidingwindow"><Description>
        Length of horizontal sliding window. The highest yoyo-wise chl peak
        (after low-pass filtering by a filter of length FilterWidthHorizontal)
        within this window.
    </Description><Units:count/><Value>100</Value></DefineArg>

    <DefineArg Name="OffPeakFractionHorizontal"><Description>
        When filtered horizontal value is this fraction of the peak, consider it
        outside the patch.
    </Description><Units:percent/><Value>80</Value></DefineArg>

    <DefineArg Name="PeakDetectNO3Active"><Description>
        Turns on peak detection of Nitrate (and turns on ISUS).
    </Description><False/></DefineArg>

    <DefineArg Name="PeakDetectOilActive"><Description>
        Turns on peak detection of Dissolved Oil.
    </Description><False/></DefineArg>

    <DefineArg Name="PeakDetectFDOMActive"><Description>
        Turns on peak detection of
        concentration_of_colored_dissolved_organic_matter_in_sea_water from
        FDOM sensor.
    </Description><False/></DefineArg>

    <DefineArg Name="UpwardDerivativeOfTemperatureActive"><Description>
        Turns on seawater temperature derivative.
    </Description><False/></DefineArg>

    <DefineArg Name="EnabledAanderaaO2"><Description>
        Automatically set to True if the Aanderaa O2 sensor is installed. Set to
        false to disable reading Aandera O2.
    </Description><Science:Aanderaa_O2.loadAtStartup/></DefineArg>

    <DefineArg Name="EnabledNeilBrown"><Description>
        Automatically set to true if the Neil Brown CTD is enabled. Set to false
        to disable reading from the Neil Brown.
    </Description><Science:CTD_NeilBrown.loadAtStartup/></DefineArg>

    <DefineArg Name="EnabledSeabird"><Description>
        Automatically set to true if the CTD is enabled. Set to false to disable
        reading from the CTD.
    </Description><Science:CTD_Seabird.loadAtStartup/></DefineArg>

    <DefineArg Name="EnabledWetLabsBB2FL"><Description>
        Automatically set to true if the the WetLabs BB2FL is enabled. Set to
        false to disable the WetLabs BB2FL.
    </Description><Science:WetLabsBB2FL.loadAtStartup/></DefineArg>

    <DefineArg Name="EnabledWetLabsSeaOWL_UV_A"><Description>
        Automatically set to true if the the WetLabs SeaOWL UV-A is enabled. Set
        to false to disable the WetLabs SeaOWL UV-A.
    </Description><Science:WetLabsSeaOWL_UV_A.loadAtStartup/></DefineArg>

    <DefineArg Name="EnabledWetLabsUBAT"><Description>
        Automatically set to true if the the WetLabs UBAT is enabled. Set to
        false to disable the WetLabs UBAT.
    </Description><Science:WetLabsUBAT.loadAtStartup/></DefineArg>

    <DefineArg Name="LowPassWindowLength"><Description>
        Low-pass window length (based on depth sensor sampling interval 0.4
        second) for low-pass filtering.
    </Description><Units:count/><Value>20</Value></DefineArg>

    <DefineArg Name="MedianFilterLen"><Description>
        Median filter length (only for chlorophyll fluorescence which tends to
        have spikes)
    </Description><Units:count/><Value>5</Value></DefineArg>

    <DefineArg Name="PeakShallowBound"><Description>
        Shallow depth bound for detecting any peak on each descent or ascent
        profile.
    </Description><Units:meter/><Value>NaN</Value></DefineArg>

    <DefineArg Name="PeakDeepBound"><Description>
        Deep depth bound for detecting any peak on each descent or ascent
        profile.
    </Description><Units:meter/><Value>NaN</Value></DefineArg>

    <DefineOutput Name="PeakChl"><Units:microgram_per_liter/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PeakChlDepth"><Units:meter/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PeakChlTemperature"><Units:celsius/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PeakChlLatitude"><Units:degree/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PeakChlLongitude"><Units:degree/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PeakNO3"><Units:micromole_per_liter/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PeakNO3Depth"><Units:meter/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PeakNO3Latitude"><Units:degree/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PeakNO3Longitude"><Units:degree/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PatchChl"><Units:meter/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PatchChlDepth"><Units:meter/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PatchChlLatitude"><Units:degree/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PatchChlLongitude"><Units:degree/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PatchChlDistance"><Units:meter/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PeakOil"><Units:kilogram_per_cubic_meter/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PeakOilDepth"><Units:meter/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PeakOilLatitude"><Units:degree/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PeakOilLongitude"><Units:degree/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PeakFDOM"><Units:part_per_billion/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PeakFDOMDepth"><Units:meter/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PeakFDOMTemperature"><Units:celsius/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PeakFDOMLatitude"><Units:degree/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PeakFDOMLongitude"><Units:degree/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PatchFDOMDistance"><Units:meter/><Value>NaN</Value></DefineOutput>

    <Parallel/>

    <ReadData Strategy="MinError">
        <While>
            <Arg Name="EnabledAanderaaO2"/>
        </While>
        <Universal:mass_concentration_of_oxygen_in_sea_water/>
    </ReadData>

    <ReadData Strategy="MinError">
        <While>
            <Arg Name="EnabledNeilBrown"/>
            <Or><Arg Name="EnabledSeabird"/></Or>
        </While>
        <Universal:sea_water_temperature/>
        <Universal:sea_water_salinity/>
    </ReadData>

    <ReadData Strategy="MinError">
        <While>
            <Arg Name="EnabledSeabird"/>
        </While>
        <Science:CTD_Seabird.sea_water_temperature/>
        <Science:CTD_Seabird.sea_water_salinity/>
    </ReadData>

    <ReadData Strategy="MinError">
        <While>
            <Arg Name="EnabledNeilBrown"/>
            <Or><Arg Name="EnabledSeabird"/></Or>
            <And><Arg Name="UpwardDerivativeOfTemperatureActive"/></And>
        </While>
        <Universal:upward_derivative_of_sea_water_temperature/>
    </ReadData>

    <ReadData Strategy="MinError">
        <While>
            <Arg Name="EnabledWetLabsBB2FL"/>
            <Or><Arg Name="PeakDetectChlActive"/></Or>
        </While>
        <Universal:mass_concentration_of_chlorophyll_in_sea_water/>
    </ReadData>

    <ReadData Strategy="MinError">
        <While>
            <Arg Name="EnabledWetLabsUBAT"/>
        </While>
        <Science:WetLabsUBAT.biolum_potential/>
    </ReadData>

    <ReadData Id="Read_Oil" Strategy="MinError">
        <While>
            <Arg Name="EnabledWetLabsSeaOWL_UV_A"/>
            <Or><Arg Name="PeakDetectOilActive"/></Or>
        </While>
        <Universal:mass_concentration_of_petroleum_hydrocarbons_in_sea_water/>
    </ReadData>

    <Aggregate Id="OceanCurrent">

        <Estimation:CurrentEstimator>
            <Parallel/>
        </Estimation:CurrentEstimator>

    </Aggregate>

    <Aggregate Id="PeakDetectChl">

        <While>
            <Arg Name="PeakDetectChlActive"/>
        </While>

        <Trigger:PeakDetectVsDepth>
            <Sequence/>
            <Setting><Trigger:PeakDetectVsDepth.detect/><Universal:mass_concentration_of_chlorophyll_in_sea_water/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.timeWindowPeakReport/><Arg Name="TimeWindowPeakReport"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.windowLength/><Arg Name="LowPassWindowLength"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.medianFilterLength/><Arg Name="MedianFilterLen"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.shallowBound/><Arg Name="PeakShallowBound"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.deepBound/><Arg Name="PeakDeepBound"/></Setting>
            <OutputArg Name="PeakChl"><Trigger:PeakDetectVsDepth.peakDetect/></OutputArg>
            <OutputArg Name="PeakChlDepth"><Trigger:PeakDetectVsDepth.peakDepth/></OutputArg>
            <OutputArg Name="PeakChlTemperature"><Trigger:PeakDetectVsDepth.peakTemperature/></OutputArg>
            <OutputArg Name="PeakChlLatitude"><Trigger:PeakDetectVsDepth.peakLatitude/></OutputArg>
            <OutputArg Name="PeakChlLongitude"><Trigger:PeakDetectVsDepth.peakLongitude/></OutputArg>
        </Trigger:PeakDetectVsDepth>

<!--debug
        <Syslog Severity="Important">PeakChl, PeakChlLatitude, PeakChlLongitude = <Arg Name="PeakChl"/><Units:microgram_per_liter/>, <Arg Name="PeakChlLatitude"/><Units:degree/>, <Arg Name="PeakChlLongitude"/><Units:degree/></Syslog>
-->

        <SendData Service="Express"><Arg Name="PeakChl"/><Arg Name="PeakChlDepth"/>
        </SendData>

    </Aggregate>

    <Aggregate Id="HighestChlPeakReport">

        <While>
            <Arg Name="HighestChlPeakReportActive"/>
        </While>

        <Trigger:PeakDetectHorizontal>
            <Sequence/>
            <Setting><Trigger:PeakDetectHorizontal.detectFrom/><Arg Name="PeakChl"/></Setting>
            <Setting><Trigger:PeakDetectHorizontal.depthFrom/><Arg Name="PeakChlDepth"/></Setting>
            <Setting><Trigger:PeakDetectHorizontal.latitudeFrom/><Arg Name="PeakChlLatitude"/></Setting>
            <Setting><Trigger:PeakDetectHorizontal.longitudeFrom/><Arg Name="PeakChlLongitude"/></Setting>
            <Setting><Trigger:PeakDetectHorizontal.patchTracking/><Arg Name="PatchTracking"/></Setting>
            <Setting><Trigger:PeakDetectHorizontal.filterWidth/><Arg Name="FilterWidthHorizontal"/></Setting>
            <Setting><Trigger:PeakDetectHorizontal.numProfilesSlidingwindow/><Arg Name="NumProfilesSlidingwindow"/></Setting>
            <Setting><Trigger:PeakDetectHorizontal.offPeakFraction/><Arg Name="OffPeakFractionHorizontal"/></Setting>
            <OutputArg Name="PatchChl"><Trigger:PeakDetectHorizontal.peakDetect/></OutputArg>
            <OutputArg Name="PatchChlDepth"><Trigger:PeakDetectHorizontal.peakDepth/></OutputArg>
            <OutputArg Name="PatchChlLatitude"><Trigger:PeakDetectHorizontal.peakLatitude/></OutputArg>
            <OutputArg Name="PatchChlLongitude"><Trigger:PeakDetectHorizontal.peakLongitude/></OutputArg>
            <OutputArg Name="PatchChlDistance"><Trigger:PeakDetectHorizontal.peakDistance/></OutputArg>
        </Trigger:PeakDetectHorizontal>

    </Aggregate>

    <Aggregate Id="PeakDetectNO3">

        <While>
            <Arg Name="PeakDetectNO3Active"/>
        </While>

        <Trigger:PeakDetectVsDepth>
            <Sequence/>
            <Setting><Trigger:PeakDetectVsDepth.detect/><Universal:mole_concentration_of_nitrate_in_sea_water/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.timeWindowPeakReport/><Arg Name="TimeWindowPeakReport"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.windowLength/><Arg Name="LowPassWindowLength"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.shallowBound/><Arg Name="PeakShallowBound"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.deepBound/><Arg Name="PeakDeepBound"/></Setting>
            <OutputArg Name="PeakNO3"><Trigger:PeakDetectVsDepth.peakDetect/></OutputArg>
            <OutputArg Name="PeakNO3Depth"><Trigger:PeakDetectVsDepth.peakDepth/></OutputArg>
            <OutputArg Name="PeakNO3Latitude"><Trigger:PeakDetectVsDepth.peakLatitude/></OutputArg>
            <OutputArg Name="PeakNO3Longitude"><Trigger:PeakDetectVsDepth.peakLongitude/></OutputArg>
        </Trigger:PeakDetectVsDepth>

        <SendData Service="Express"><Arg Name="PeakNO3"/><Arg Name="PeakNO3Depth"/>
        </SendData>

    </Aggregate>

    <Aggregate Id="PeakDetectOil">

        <While>
            <Arg Name="PeakDetectOilActive"/>
        </While>

        <Trigger:PeakDetectVsDepth>
            <Sequence/>
            <Setting><Trigger:PeakDetectVsDepth.detect/><Universal:mass_concentration_of_petroleum_hydrocarbons_in_sea_water/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.timeWindowPeakReport/><Arg Name="TimeWindowPeakReport"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.windowLength/><Arg Name="LowPassWindowLength"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.shallowBound/><Arg Name="PeakShallowBound"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.deepBound/><Arg Name="PeakDeepBound"/></Setting>
            <OutputArg Name="PeakOil"><Trigger:PeakDetectVsDepth.peakDetect/></OutputArg>
            <OutputArg Name="PeakOilDepth"><Trigger:PeakDetectVsDepth.peakDepth/></OutputArg>
            <OutputArg Name="PeakOilLatitude"><Trigger:PeakDetectVsDepth.peakLatitude/></OutputArg>
            <OutputArg Name="PeakOilLongitude"><Trigger:PeakDetectVsDepth.peakLongitude/></OutputArg>
        </Trigger:PeakDetectVsDepth>

        <SendData Service="Express"><Arg Name="PeakOil"/><Arg Name="PeakOilDepth"/>
        </SendData>

    </Aggregate>

    <Aggregate Id="PeakDetectFDOM">

        <While>
            <Arg Name="PeakDetectFDOMActive"/>
        </While>

        <Trigger:PeakDetectVsDepth>
            <Sequence/>
            <Setting><Trigger:PeakDetectVsDepth.detect/><Universal:concentration_of_colored_dissolved_organic_matter_in_sea_water/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.timeWindowPeakReport/><Arg Name="TimeWindowPeakReport"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.windowLength/><Arg Name="LowPassWindowLength"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.shallowBound/><Arg Name="PeakShallowBound"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.deepBound/><Arg Name="PeakDeepBound"/></Setting>
            <OutputArg Name="PeakFDOM"><Trigger:PeakDetectVsDepth.peakDetect/></OutputArg>
            <OutputArg Name="PeakFDOMDepth"><Trigger:PeakDetectVsDepth.peakDepth/></OutputArg>
            <OutputArg Name="PeakFDOMTemperature"><Trigger:PeakDetectVsDepth.peakTemperature/></OutputArg>
            <OutputArg Name="PeakFDOMLatitude"><Trigger:PeakDetectVsDepth.peakLatitude/></OutputArg>
            <OutputArg Name="PeakFDOMLongitude"><Trigger:PeakDetectVsDepth.peakLongitude/></OutputArg>
        </Trigger:PeakDetectVsDepth>

        <SendData Service="Express"><Arg Name="PeakFDOM"/><Arg Name="PeakFDOMDepth"/>
        </SendData>

    </Aggregate>

</Aggregate>


2021-04-13T21:42:19.185Z,1618350139.185 [CommandLine](IMPORTANT): Running ./Missions/Insert/Science.xml
2021-04-13T21:42:19.214Z,1618350139.214 [Default] Stopped
2021-04-13T21:42:19.214Z,1618350139.214 [Default](DEBUG): Aggregate::uninitialize Default
2021-04-13T21:42:19.214Z,1618350139.214 [Default:B.GoToSurface] Stopped
2021-04-13T21:42:19.214Z,1618350139.214 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-04-13T21:42:19.214Z,1618350139.214 [Default:CheckIn] Stopped
2021-04-13T21:42:19.214Z,1618350139.214 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-13T21:42:19.214Z,1618350139.214 [Default:CheckIn:C.Wait] Stopped
2021-04-13T21:42:19.215Z,1618350139.215 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-13T21:42:19.215Z,1618350139.215 [MissionManager](IMPORTANT): Started mission Science
2021-04-13T21:42:19.215Z,1618350139.215 [Science] Running Loop=1
2021-04-13T21:42:19.215Z,1618350139.215 [Science](DEBUG): Aggregate::initialize Science
2021-04-13T21:42:19.215Z,1618350139.215 [Science:A] Running Loop=1
2021-04-13T21:42:19.215Z,1618350139.215 [Science:B] Running Loop=1
2021-04-13T21:42:19.215Z,1618350139.215 [Science:C] Running Loop=1
2021-04-13T21:42:19.215Z,1618350139.215 [Science:D] Running Loop=1
2021-04-13T21:42:19.215Z,1618350139.215 [Science:E] Running Loop=1
2021-04-13T21:42:19.215Z,1618350139.215 [Science:F] Running Loop=1
2021-04-13T21:42:19.215Z,1618350139.215 [Science:Read_Oil] Running Loop=1
2021-04-13T21:42:19.216Z,1618350139.216 [Science:PeakDetectChl] Running Loop=1
2021-04-13T21:42:19.216Z,1618350139.216 [Science:PeakDetectChl](DEBUG): Aggregate::initialize Science:PeakDetectChl
2021-04-13T21:42:19.216Z,1618350139.216 [Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2021-04-13T21:42:19.216Z,1618350139.216 [Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-04-13T21:42:19.218Z,1618350139.218 [Science:HighestChlPeakReport] Running Loop=1
2021-04-13T21:42:19.218Z,1618350139.218 [Science:HighestChlPeakReport](DEBUG): Aggregate::initialize Science:HighestChlPeakReport
2021-04-13T21:42:19.218Z,1618350139.218 [Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2021-04-13T21:42:19.218Z,1618350139.218 [Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2021-04-13T21:42:19.219Z,1618350139.219 [Science:PeakDetectNO3] Running Loop=1
2021-04-13T21:42:19.219Z,1618350139.219 [Science:PeakDetectNO3](DEBUG): Aggregate::initialize Science:PeakDetectNO3
2021-04-13T21:42:19.219Z,1618350139.219 [Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2021-04-13T21:42:19.219Z,1618350139.219 [Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-04-13T21:42:19.221Z,1618350139.221 [Science:PeakDetectOil] Running Loop=1
2021-04-13T21:42:19.221Z,1618350139.221 [Science:PeakDetectOil](DEBUG): Aggregate::initialize Science:PeakDetectOil
2021-04-13T21:42:19.221Z,1618350139.221 [Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2021-04-13T21:42:19.221Z,1618350139.221 [Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-04-13T21:42:19.243Z,1618350139.243 [Science:PeakDetectFDOM] Running Loop=1
2021-04-13T21:42:19.243Z,1618350139.243 [Science:PeakDetectFDOM](DEBUG): Aggregate::initialize Science:PeakDetectFDOM
2021-04-13T21:42:19.243Z,1618350139.243 [Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2021-04-13T21:42:19.243Z,1618350139.243 [Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-04-13T21:42:19.245Z,1618350139.245 [Science:OceanCurrent] Running Loop=1
2021-04-13T21:42:19.246Z,1618350139.246 [Science:OceanCurrent](DEBUG): Aggregate::initialize Science:OceanCurrent
2021-04-13T21:42:19.246Z,1618350139.246 [Science:OceanCurrent:A.] Running Loop=1
2021-04-13T21:42:19.246Z,1618350139.246 [Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator.
2021-04-13T21:42:19.247Z,1618350139.247 [Science:PeakDetectFDOM] Stopped
2021-04-13T21:42:19.247Z,1618350139.247 [Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize Science:PeakDetectFDOM
2021-04-13T21:42:19.247Z,1618350139.247 [Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2021-04-13T21:42:19.247Z,1618350139.247 [Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-04-13T21:42:19.247Z,1618350139.247 [Science:PeakDetectOil] Stopped
2021-04-13T21:42:19.247Z,1618350139.247 [Science:PeakDetectOil](DEBUG): Aggregate::uninitialize Science:PeakDetectOil
2021-04-13T21:42:19.247Z,1618350139.247 [Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2021-04-13T21:42:19.247Z,1618350139.247 [Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-04-13T21:42:19.247Z,1618350139.247 [Science:PeakDetectNO3] Stopped
2021-04-13T21:42:19.248Z,1618350139.248 [Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize Science:PeakDetectNO3
2021-04-13T21:42:19.248Z,1618350139.248 [Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2021-04-13T21:42:19.248Z,1618350139.248 [Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-04-13T21:42:19.248Z,1618350139.248 [Science:HighestChlPeakReport] Stopped
2021-04-13T21:42:19.248Z,1618350139.248 [Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize Science:HighestChlPeakReport
2021-04-13T21:42:19.248Z,1618350139.248 [Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2021-04-13T21:42:19.248Z,1618350139.248 [Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2021-04-13T21:42:19.248Z,1618350139.248 [Science:PeakDetectChl] Stopped
2021-04-13T21:42:19.248Z,1618350139.248 [Science:PeakDetectChl](DEBUG): Aggregate::uninitialize Science:PeakDetectChl
2021-04-13T21:42:19.248Z,1618350139.248 [Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2021-04-13T21:42:19.248Z,1618350139.248 [Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-04-13T21:42:19.248Z,1618350139.248 [Science:OceanCurrent:A.] Running Loop=1
2021-04-13T21:42:19.279Z,1618350139.279 [Science:Read_Oil] Stopped
2021-04-13T21:42:19.279Z,1618350139.279 [Science:F] Stopped
2021-04-13T21:42:19.279Z,1618350139.279 [Science:E] Running Loop=1
2021-04-13T21:42:19.279Z,1618350139.279 [Science:E](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2021-04-13T21:42:19.279Z,1618350139.279 [Science:D] Stopped
2021-04-13T21:42:19.279Z,1618350139.279 [Science:C] Running Loop=1
2021-04-13T21:42:19.280Z,1618350139.280 [Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature
2021-04-13T21:42:19.281Z,1618350139.281 [Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity
2021-04-13T21:42:19.281Z,1618350139.281 [Science:B] Running Loop=1
2021-04-13T21:42:19.281Z,1618350139.281 [Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2021-04-13T21:42:19.282Z,1618350139.282 [Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2021-04-13T21:42:19.282Z,1618350139.282 [Science:A] Stopped
2021-04-13T21:42:19.446Z,1618350139.446 [WetLabsBB2FL](INFO): Powering up
2021-04-13T21:42:23.019Z,1618350143.019 [CTD_Seabird](ERROR): Failed to parse device response: <!--startlogging-->
2021-04-13T21:42:25.029Z,1618350145.029 [CTD_Seabird](ERROR): Salinity reading out of range: 15.623459 psu
2021-04-13T21:42:37.263Z,1618350157.263 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-13T21:42:51.680Z,1618350171.680 [CommandLine](IMPORTANT): got command gfscan
2021-04-13T21:42:51.853Z,1618350171.853 [CBIT](IMPORTANT): Beginning ground fault scan
2021-04-13T21:42:52.145Z,1618350172.145 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2021-04-13T21:42:53.148Z,1618350173.148 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2021-04-13T21:42:54.151Z,1618350174.151 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2021-04-13T21:42:55.156Z,1618350175.156 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2021-04-13T21:42:56.159Z,1618350176.159 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2021-04-13T21:42:57.168Z,1618350177.168 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2021-04-13T21:42:58.167Z,1618350178.167 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2021-04-13T21:42:59.172Z,1618350179.172 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2021-04-13T21:43:00.175Z,1618350180.175 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2021-04-13T21:43:01.184Z,1618350181.184 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2021-04-13T21:43:02.183Z,1618350182.183 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2021-04-13T21:43:02.757Z,1618350182.757 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.003357
CHAN A1 (24V): -0.001176
CHAN A2 (12V): -0.000411
CHAN A3 (5V): 0.000101
CHAN B0 (3.3V): 0.000207
CHAN B1 (3.15aV): -0.000150
CHAN B2 (3.15bV): 0.000230
CHAN B3 (GND): -0.000099
OPEN: 0.007910
Full Scale Calc: 4.765 mA, -1.589 mA
2021-04-13T21:43:21.881Z,1618350201.881 [CommandLine](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 250.000000 cubic_centimeter
2021-04-13T21:43:21.884Z,1618350201.884 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.buoyancyAction" handled in the control thread.
2021-04-13T21:43:22.154Z,1618350202.154 [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,Maintain_VerticalControl.buoyancyAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2021-04-13T21:43:32.321Z,1618350212.321 [CommandLine](IMPORTANT): got command report mod platform_buoyancy_position
2021-04-13T21:43:32.708Z,1618350212.708 [Reporter](INFO): platform_buoyancy_position 876.414551 cc
2021-04-13T21:43:33.084Z,1618350213.084 [Reporter](INFO): platform_buoyancy_position 871.579834 cc
2021-04-13T21:43:33.496Z,1618350213.496 [Reporter](INFO): platform_buoyancy_position 865.670532 cc
2021-04-13T21:43:33.876Z,1618350213.876 [Reporter](INFO): platform_buoyancy_position 861.238647 cc
2021-04-13T21:43:34.276Z,1618350214.276 [Reporter](INFO): platform_buoyancy_position 855.060913 cc
2021-04-13T21:43:34.696Z,1618350214.696 [Reporter](INFO): platform_buoyancy_position 850.629028 cc
2021-04-13T21:43:35.115Z,1618350215.115 [Reporter](INFO): platform_buoyancy_position 844.182617 cc
2021-04-13T21:43:35.508Z,1618350215.508 [Reporter](INFO): platform_buoyancy_position 839.885010 cc
2021-04-13T21:43:35.896Z,1618350215.896 [Reporter](INFO): platform_buoyancy_position 833.572876 cc
2021-04-13T21:43:36.356Z,1618350216.356 [Reporter](INFO): platform_buoyancy_position 827.126465 cc
2021-04-13T21:43:36.740Z,1618350216.740 [Reporter](INFO): platform_buoyancy_position 822.828857 cc
2021-04-13T21:43:37.116Z,1618350217.116 [Reporter](INFO): platform_buoyancy_position 818.396973 cc
2021-04-13T21:43:37.520Z,1618350217.520 [Reporter](INFO): platform_buoyancy_position 812.084900 cc
2021-04-13T21:43:37.940Z,1618350217.940 [Reporter](INFO): platform_buoyancy_position 807.787292 cc
2021-04-13T21:43:38.340Z,1618350218.340 [Reporter](INFO): platform_buoyancy_position 801.206543 cc
2021-04-13T21:43:38.768Z,1618350218.768 [Reporter](INFO): platform_buoyancy_position 796.774658 cc
2021-04-13T21:43:39.143Z,1618350219.143 [Reporter](INFO): platform_buoyancy_position 791.671265 cc
2021-04-13T21:43:39.552Z,1618350219.552 [Reporter](INFO): platform_buoyancy_position 786.164978 cc
2021-04-13T21:43:39.960Z,1618350219.960 [Reporter](INFO): platform_buoyancy_position 779.852844 cc
2021-04-13T21:43:40.338Z,1618350220.338 [Reporter](INFO): platform_buoyancy_position 775.420959 cc
2021-04-13T21:43:40.764Z,1618350220.764 [Reporter](INFO): platform_buoyancy_position 770.989075 cc
2021-04-13T21:43:41.172Z,1618350221.172 [Reporter](INFO): platform_buoyancy_position 764.676941 cc
2021-04-13T21:43:41.573Z,1618350221.573 [Reporter](INFO): platform_buoyancy_position 760.379395 cc
2021-04-13T21:43:41.955Z,1618350221.955 [Reporter](INFO): platform_buoyancy_position 755.544556 cc
2021-04-13T21:43:42.380Z,1618350222.380 [Reporter](INFO): platform_buoyancy_position 749.635376 cc
2021-04-13T21:43:42.788Z,1618350222.788 [Reporter](INFO): platform_buoyancy_position 743.323242 cc
2021-04-13T21:43:43.181Z,1618350223.181 [Reporter](INFO): platform_buoyancy_position 738.891357 cc
2021-04-13T21:43:43.572Z,1618350223.572 [Reporter](INFO): platform_buoyancy_position 734.593750 cc
2021-04-13T21:43:43.992Z,1618350223.992 [Reporter](INFO): platform_buoyancy_position 728.147339 cc
2021-04-13T21:43:44.408Z,1618350224.408 [Reporter](INFO): platform_buoyancy_position 723.446838 cc
2021-04-13T21:43:44.800Z,1618350224.800 [Reporter](INFO): platform_buoyancy_position 717.537659 cc
2021-04-13T21:43:45.187Z,1618350225.187 [Reporter](INFO): platform_buoyancy_position 712.837158 cc
2021-04-13T21:43:45.607Z,1618350225.607 [Reporter](INFO): platform_buoyancy_position 706.659363 cc
2021-04-13T21:43:46.020Z,1618350226.020 [Reporter](INFO): platform_buoyancy_position 702.361755 cc
2021-04-13T21:43:46.420Z,1618350226.420 [Reporter](INFO): platform_buoyancy_position 696.049683 cc
2021-04-13T21:43:46.812Z,1618350226.812 [Reporter](INFO): platform_buoyancy_position 690.946289 cc
2021-04-13T21:43:47.216Z,1618350227.216 [Reporter](INFO): platform_buoyancy_position 685.171387 cc
2021-04-13T21:43:47.636Z,1618350227.636 [Reporter](INFO): platform_buoyancy_position 680.202271 cc
2021-04-13T21:43:48.009Z,1618350228.009 [Reporter](INFO): platform_buoyancy_position 674.427368 cc
2021-04-13T21:43:48.432Z,1618350228.432 [Reporter](INFO): platform_buoyancy_position 670.129761 cc
2021-04-13T21:43:48.836Z,1618350228.836 [Reporter](INFO): platform_buoyancy_position 663.817688 cc
2021-04-13T21:43:49.248Z,1618350229.248 [Reporter](INFO): platform_buoyancy_position 659.520081 cc
2021-04-13T21:43:49.653Z,1618350229.653 [Reporter](INFO): platform_buoyancy_position 653.073669 cc
2021-04-13T21:43:50.032Z,1618350230.032 [Reporter](INFO): platform_buoyancy_position 648.776062 cc
2021-04-13T21:43:50.453Z,1618350230.453 [Reporter](INFO): platform_buoyancy_position 642.329651 cc
2021-04-13T21:43:50.880Z,1618350230.880 [Reporter](INFO): platform_buoyancy_position 637.897766 cc
2021-04-13T21:43:51.272Z,1618350231.272 [Reporter](INFO): platform_buoyancy_position 633.465881 cc
2021-04-13T21:43:51.656Z,1618350231.656 [Reporter](INFO): platform_buoyancy_position 627.153748 cc
2021-04-13T21:43:52.064Z,1618350232.064 [Reporter](INFO): platform_buoyancy_position 622.990479 cc
2021-04-13T21:43:52.488Z,1618350232.488 [Reporter](INFO): platform_buoyancy_position 616.544067 cc
2021-04-13T21:43:52.924Z,1618350232.924 [Reporter](INFO): platform_buoyancy_position 610.097656 cc
2021-04-13T21:43:53.292Z,1618350233.292 [Reporter](INFO): platform_buoyancy_position 605.800049 cc
2021-04-13T21:43:53.707Z,1618350233.707 [Reporter](INFO): platform_buoyancy_position 600.696655 cc
2021-04-13T21:43:54.118Z,1618350234.118 [Reporter](INFO): platform_buoyancy_position 594.921753 cc
2021-04-13T21:43:54.504Z,1618350234.504 [Reporter](INFO): platform_buoyancy_position 590.624146 cc
2021-04-13T21:43:54.920Z,1618350234.920 [Reporter](INFO): platform_buoyancy_position 584.177734 cc
2021-04-13T21:43:55.324Z,1618350235.324 [Reporter](INFO): platform_buoyancy_position 579.880127 cc
2021-04-13T21:43:55.708Z,1618350235.708 [Reporter](INFO): platform_buoyancy_position 573.702332 cc
2021-04-13T21:43:56.132Z,1618350236.132 [Reporter](INFO): platform_buoyancy_position 569.136169 cc
2021-04-13T21:43:56.536Z,1618350236.536 [Reporter](INFO): platform_buoyancy_position 562.824036 cc
2021-04-13T21:43:56.920Z,1618350236.920 [Reporter](INFO): platform_buoyancy_position 558.392151 cc
2021-04-13T21:43:57.320Z,1618350237.320 [Reporter](INFO): platform_buoyancy_position 554.094543 cc
2021-04-13T21:43:57.721Z,1618350237.721 [Reporter](INFO): platform_buoyancy_position 547.648132 cc
2021-04-13T21:43:58.138Z,1618350238.138 [Reporter](INFO): platform_buoyancy_position 542.544739 cc
2021-04-13T21:43:58.519Z,1618350238.519 [Reporter](INFO): platform_buoyancy_position 537.038452 cc
2021-04-13T21:43:58.951Z,1618350238.951 [Reporter](INFO): platform_buoyancy_position 532.606567 cc
2021-04-13T21:43:59.351Z,1618350239.351 [Reporter](INFO): platform_buoyancy_position 526.294434 cc
2021-04-13T21:43:59.742Z,1618350239.742 [Reporter](INFO): platform_buoyancy_position 521.862549 cc
2021-04-13T21:44:00.151Z,1618350240.151 [Reporter](INFO): platform_buoyancy_position 515.953369 cc
2021-04-13T21:44:00.570Z,1618350240.570 [Reporter](INFO): platform_buoyancy_position 511.387115 cc
2021-04-13T21:44:00.988Z,1618350240.988 [Reporter](INFO): platform_buoyancy_position 504.806458 cc
2021-04-13T21:44:01.351Z,1618350241.351 [Reporter](INFO): platform_buoyancy_position 500.508881 cc
2021-04-13T21:44:01.849Z,1618350241.849 [Reporter](INFO): platform_buoyancy_position 494.062439 cc
2021-04-13T21:44:02.228Z,1618350242.228 [Reporter](INFO): platform_buoyancy_position 489.630554 cc
2021-04-13T21:44:02.608Z,1618350242.608 [Reporter](INFO): platform_buoyancy_position 485.332947 cc
2021-04-13T21:44:02.996Z,1618350242.996 [Reporter](INFO): platform_buoyancy_position 479.020874 cc
2021-04-13T21:44:03.408Z,1618350243.408 [Reporter](INFO): platform_buoyancy_position 474.588928 cc
2021-04-13T21:44:03.803Z,1618350243.803 [Reporter](INFO): platform_buoyancy_position 468.142578 cc
2021-04-13T21:44:04.208Z,1618350244.208 [Reporter](INFO): platform_buoyancy_position 463.844971 cc
2021-04-13T21:44:04.633Z,1618350244.633 [Reporter](INFO): platform_buoyancy_position 457.532837 cc
2021-04-13T21:44:04.996Z,1618350244.996 [Reporter](INFO): platform_buoyancy_position 452.966675 cc
2021-04-13T21:44:05.436Z,1618350245.436 [Reporter](INFO): platform_buoyancy_position 446.788818 cc
2021-04-13T21:44:05.801Z,1618350245.801 [Reporter](INFO): platform_buoyancy_position 442.491272 cc
2021-04-13T21:44:06.221Z,1618350246.221 [Reporter](INFO): platform_buoyancy_position 436.179138 cc
2021-04-13T21:44:06.648Z,1618350246.648 [Reporter](INFO): platform_buoyancy_position 431.747253 cc
2021-04-13T21:44:07.012Z,1618350247.012 [Reporter](INFO): platform_buoyancy_position 425.300842 cc
2021-04-13T21:44:07.457Z,1618350247.457 [Reporter](INFO): platform_buoyancy_position 420.868958 cc
2021-04-13T21:44:07.842Z,1618350247.842 [Reporter](INFO): platform_buoyancy_position 414.556824 cc
2021-04-13T21:44:08.225Z,1618350248.225 [Reporter](INFO): platform_buoyancy_position 410.124939 cc
2021-04-13T21:44:08.652Z,1618350248.652 [Reporter](INFO): platform_buoyancy_position 403.678528 cc
2021-04-13T21:44:09.036Z,1618350249.036 [Reporter](INFO): platform_buoyancy_position 399.380920 cc
2021-04-13T21:44:09.524Z,1618350249.524 [Reporter](INFO): platform_buoyancy_position 392.934570 cc
2021-04-13T21:44:09.884Z,1618350249.884 [Reporter](INFO): platform_buoyancy_position 388.636963 cc
2021-04-13T21:44:10.241Z,1618350250.241 [Reporter](INFO): platform_buoyancy_position 384.070740 cc
2021-04-13T21:44:10.664Z,1618350250.664 [Reporter](INFO): platform_buoyancy_position 378.027222 cc
2021-04-13T21:44:11.079Z,1618350251.079 [Reporter](INFO): platform_buoyancy_position 373.058167 cc
2021-04-13T21:44:11.464Z,1618350251.464 [Reporter](INFO): platform_buoyancy_position 367.283264 cc
2021-04-13T21:44:11.884Z,1618350251.884 [Reporter](INFO): platform_buoyancy_position 362.045532 cc
2021-04-13T21:44:12.281Z,1618350252.281 [Reporter](INFO): platform_buoyancy_position 356.404968 cc
2021-04-13T21:44:12.689Z,1618350252.689 [Reporter](INFO): platform_buoyancy_position 352.241638 cc
2021-04-13T21:44:13.099Z,1618350253.099 [Reporter](INFO): platform_buoyancy_position 347.944031 cc
2021-04-13T21:44:13.520Z,1618350253.520 [Reporter](INFO): platform_buoyancy_position 341.497620 cc
2021-04-13T21:44:13.895Z,1618350253.895 [Reporter](INFO): platform_buoyancy_position 337.065735 cc
2021-04-13T21:44:14.311Z,1618350254.311 [Reporter](INFO): platform_buoyancy_position 330.619324 cc
2021-04-13T21:44:14.744Z,1618350254.744 [Reporter](INFO): platform_buoyancy_position 324.307251 cc
2021-04-13T21:44:15.100Z,1618350255.100 [Reporter](INFO): platform_buoyancy_position 320.009644 cc
2021-04-13T21:44:15.531Z,1618350255.531 [Reporter](INFO): platform_buoyancy_position 314.771912 cc
2021-04-13T21:44:15.916Z,1618350255.916 [Reporter](INFO): platform_buoyancy_position 309.265625 cc
2021-04-13T21:44:16.372Z,1618350256.372 [Reporter](INFO): platform_buoyancy_position 304.565125 cc
2021-04-13T21:44:16.736Z,1618350256.736 [Reporter](INFO): platform_buoyancy_position 298.521637 cc
2021-04-13T21:44:17.123Z,1618350257.123 [Reporter](INFO): platform_buoyancy_position 294.089722 cc
2021-04-13T21:44:17.540Z,1618350257.540 [Reporter](INFO): platform_buoyancy_position 287.777649 cc
2021-04-13T21:44:17.924Z,1618350257.924 [Reporter](INFO): platform_buoyancy_position 283.345734 cc
2021-04-13T21:44:18.344Z,1618350258.344 [Reporter](INFO): platform_buoyancy_position 277.033630 cc
2021-04-13T21:44:18.807Z,1618350258.807 [Reporter](INFO): platform_buoyancy_position 270.452942 cc
2021-04-13T21:44:19.181Z,1618350259.181 [Reporter](INFO): platform_buoyancy_position 266.155334 cc
2021-04-13T21:44:19.641Z,1618350259.641 [Reporter](INFO): platform_buoyancy_position 259.843231 cc
2021-04-13T21:44:38.884Z,1618350278.884 [CommandLine](IMPORTANT): got command maintain clear
2021-04-13T21:44:38.917Z,1618350278.917 [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-13T21:44:40.996Z,1618350280.996 [Reporter](INFO): platform_buoyancy_position 251.382324 cc
2021-04-13T21:44:41.387Z,1618350281.387 [Reporter](INFO): platform_buoyancy_position 253.262558 cc
2021-04-13T21:44:42.420Z,1618350282.420 [CommandLine](IMPORTANT): got command failComponent
2021-04-13T21:44:42.420Z,1618350282.420 [CommandLine](IMPORTANT): Failed components:
2021-04-13T21:44:42.420Z,1618350282.420 [CommandLine](IMPORTANT): No failed Components.
2021-04-13T21:44:42.628Z,1618350282.628 [Reporter](INFO): platform_buoyancy_position 259.843231 cc
2021-04-13T21:44:42.984Z,1618350282.984 [Reporter](INFO): platform_buoyancy_position 266.289642 cc
2021-04-13T21:44:43.396Z,1618350283.396 [Reporter](INFO): platform_buoyancy_position 270.587250 cc
2021-04-13T21:44:43.776Z,1618350283.776 [Reporter](INFO): platform_buoyancy_position 276.899323 cc
2021-04-13T21:44:44.180Z,1618350284.180 [Reporter](INFO): platform_buoyancy_position 281.734131 cc
2021-04-13T21:44:44.596Z,1618350284.596 [Reporter](INFO): platform_buoyancy_position 287.643341 cc
2021-04-13T21:44:45.008Z,1618350285.008 [Reporter](INFO): platform_buoyancy_position 294.089722 cc
2021-04-13T21:44:45.414Z,1618350285.414 [Reporter](INFO): platform_buoyancy_position 298.521637 cc
2021-04-13T21:44:45.796Z,1618350285.796 [Reporter](INFO): platform_buoyancy_position 302.819244 cc
2021-04-13T21:44:46.224Z,1618350286.224 [Reporter](INFO): platform_buoyancy_position 309.265625 cc
2021-04-13T21:44:46.629Z,1618350286.629 [Reporter](INFO): platform_buoyancy_position 315.577759 cc
2021-04-13T21:44:47.006Z,1618350287.006 [Reporter](INFO): platform_buoyancy_position 319.875366 cc
2021-04-13T21:44:47.235Z,1618350287.235 [CommandLine](IMPORTANT): got command configSet list
2021-04-13T21:44:47.236Z,1618350287.236 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2021-04-13T21:44:47.238Z,1618350287.238 [CommandLine](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count;

2021-04-13T21:44:47.238Z,1618350287.238 [CommandLine](IMPORTANT): BackseatComponent.loadAtStartup=1 bool;

2021-04-13T21:44:47.238Z,1618350287.238 [CommandLine](IMPORTANT): BackseatComponent.simulateHardware=1 bool;

2021-04-13T21:44:47.238Z,1618350287.238 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=214.815570 cubic_centimeter;

2021-04-13T21:44:47.239Z,1618350287.239 [CommandLine](IMPORTANT): VerticalControl.massDefault=-1.247810 millimeter;

2021-04-13T21:44:47.428Z,1618350287.428 [Reporter](INFO): platform_buoyancy_position 324.172913 cc
2021-04-13T21:44:47.816Z,1618350287.816 [Reporter](INFO): platform_buoyancy_position 330.082153 cc
2021-04-13T21:44:48.216Z,1618350288.216 [Reporter](INFO): platform_buoyancy_position 335.051270 cc
2021-04-13T21:44:48.635Z,1618350288.635 [Reporter](INFO): platform_buoyancy_position 341.363342 cc
2021-04-13T21:44:49.040Z,1618350289.040 [Reporter](INFO): platform_buoyancy_position 345.795227 cc
2021-04-13T21:44:49.444Z,1618350289.444 [Reporter](INFO): platform_buoyancy_position 349.958557 cc
2021-04-13T21:44:49.828Z,1618350289.828 [Reporter](INFO): platform_buoyancy_position 354.390442 cc
2021-04-13T21:44:50.248Z,1618350290.248 [Reporter](INFO): platform_buoyancy_position 360.836853 cc
2021-04-13T21:44:50.668Z,1618350290.668 [Reporter](INFO): platform_buoyancy_position 367.283264 cc
2021-04-13T21:44:51.068Z,1618350291.068 [Reporter](INFO): platform_buoyancy_position 371.580872 cc
2021-04-13T21:44:51.444Z,1618350291.444 [Reporter](INFO): platform_buoyancy_position 377.892944 cc
2021-04-13T21:44:51.864Z,1618350291.864 [Reporter](INFO): platform_buoyancy_position 382.459167 cc
2021-04-13T21:44:52.276Z,1618350292.276 [Reporter](INFO): platform_buoyancy_position 388.636963 cc
2021-04-13T21:44:52.708Z,1618350292.708 [Reporter](INFO): platform_buoyancy_position 393.068848 cc
2021-04-13T21:44:53.072Z,1618350293.072 [Reporter](INFO): platform_buoyancy_position 398.172241 cc
2021-04-13T21:44:53.488Z,1618350293.488 [Reporter](INFO): platform_buoyancy_position 403.678528 cc
2021-04-13T21:44:53.899Z,1618350293.899 [Reporter](INFO): platform_buoyancy_position 409.856323 cc
2021-04-13T21:44:54.273Z,1618350294.273 [Reporter](INFO): platform_buoyancy_position 414.556824 cc
2021-04-13T21:44:54.693Z,1618350294.693 [Reporter](INFO): platform_buoyancy_position 420.868958 cc
2021-04-13T21:44:55.102Z,1618350295.102 [Reporter](INFO): platform_buoyancy_position 425.300842 cc
2021-04-13T21:44:55.509Z,1618350295.509 [Reporter](INFO): platform_buoyancy_position 431.478638 cc
2021-04-13T21:44:55.895Z,1618350295.895 [Reporter](INFO): platform_buoyancy_position 435.910522 cc
2021-04-13T21:44:56.336Z,1618350296.336 [Reporter](INFO): platform_buoyancy_position 442.356934 cc
2021-04-13T21:44:56.733Z,1618350296.733 [Reporter](INFO): platform_buoyancy_position 446.654541 cc
2021-04-13T21:44:57.108Z,1618350297.108 [Reporter](INFO): platform_buoyancy_position 451.086426 cc
2021-04-13T21:44:57.524Z,1618350297.524 [Reporter](INFO): platform_buoyancy_position 457.398560 cc
2021-04-13T21:44:57.888Z,1618350297.888 [CommandLine](IMPORTANT): got command report clear
2021-04-13T21:45:38.664Z,1618350338.664 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2021-04-13T21:45:38.664Z,1618350338.664 [DVL_micro](ERROR): Failed to parse:
:TS,000,+16.7,0000.0,1489.0,000
2021-04-13T21:45:39.780Z,1618350339.780 [CommandLine](IMPORTANT): got command stop
2021-04-13T21:45:39.780Z,1618350339.780 [CommandLine](IMPORTANT): Scheduling is paused
2021-04-13T21:45:39.781Z,1618350339.781 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop
2021-04-13T21:45:39.923Z,1618350339.923 [MissionManager](INFO): MissionManager is completed.
2021-04-13T21:45:39.923Z,1618350339.923 [MissionManager](INFO): Uninitializing Mission Science
2021-04-13T21:45:39.923Z,1618350339.923 [Science] Stopped
2021-04-13T21:45:39.924Z,1618350339.924 [Science](DEBUG): Aggregate::uninitialize Science
2021-04-13T21:45:39.924Z,1618350339.924 [Science:B] Stopped
2021-04-13T21:45:39.924Z,1618350339.924 [Science:C] Stopped
2021-04-13T21:45:39.924Z,1618350339.924 [Science:E] Stopped
2021-04-13T21:45:39.924Z,1618350339.924 [Science:OceanCurrent] Stopped
2021-04-13T21:45:39.924Z,1618350339.924 [Science:OceanCurrent](DEBUG): Aggregate::uninitialize Science:OceanCurrent
2021-04-13T21:45:39.924Z,1618350339.924 [Science:OceanCurrent:A.] Stopped
2021-04-13T21:45:40.258Z,1618350340.258 [WetLabsBB2FL](INFO): Powering down
2021-04-13T21:45:40.338Z,1618350340.338 [MissionManager](IMPORTANT): Started mission Default
2021-04-13T21:45:40.339Z,1618350340.339 [Default] Running Loop=1
2021-04-13T21:45:40.340Z,1618350340.340 [Default](DEBUG): Aggregate::initialize Default
2021-04-13T21:45:40.340Z,1618350340.340 [Default:B.GoToSurface] Running Loop=1
2021-04-13T21:45:40.341Z,1618350340.341 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-04-13T21:45:40.342Z,1618350340.342 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-04-13T21:45:40.343Z,1618350340.343 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-04-13T21:45:40.345Z,1618350340.345 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-04-13T21:45:40.362Z,1618350340.362 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-04-13T21:45:40.362Z,1618350340.362 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-04-13T21:45:40.363Z,1618350340.363 [Default:A.Wait] Running Loop=1
2021-04-13T21:45:40.363Z,1618350340.363 [Default:A.Wait](DEBUG): Initialize Wait Component.
2021-04-13T21:45:40.703Z,1618350340.703 [DVL_micro](ERROR): only read 3 of 4 data items
2021-04-13T21:45:40.703Z,1618350340.703 [DVL_micro](ERROR): Failed to parse:
:BI,+00018,-00016,-00008,+00000+00000000.00,+00000000.00,+00000000.00,0009.70,000.00
2021-04-13T21:45:53.642Z,1618350353.642 [Default:A.Wait](INFO): Done Waiting.
2021-04-13T21:45:53.642Z,1618350353.642 [Default:A.Wait] Stopped
2021-04-13T21:45:53.643Z,1618350353.643 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2021-04-13T21:45:54.027Z,1618350354.027 [Default:CheckIn] Running Loop=1
2021-04-13T21:45:54.027Z,1618350354.027 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-13T21:45:54.027Z,1618350354.027 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-13T21:45:56.038Z,1618350356.038 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214555.00,A,3648.17034,N,12147.28072,W,0.078,113.29,130421,,,A*7A
2021-04-13T21:45:56.040Z,1618350356.040 [NAL9602](INFO): GPS fix at 20210413T214555: (36.802839, -121.788012)
2021-04-13T21:45:56.051Z,1618350356.051 [Default:CheckIn:Read_GPS] Stopped
2021-04-13T21:45:56.051Z,1618350356.051 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-13T21:46:04.966Z,1618350364.966 [DataOverHttps](INFO): Sending 286 bytes from file Logs/20210413T210804/Courier0013.lzma
2021-04-13T21:46:06.956Z,1618350366.956 [DataOverHttps](INFO): Moved sent file to Logs/20210413T210804/Courier0013.lzma.bak
2021-04-13T21:46:06.956Z,1618350366.956 [DataOverHttps](INFO): SBD MOMSN=15565675
2021-04-13T21:46:29.153Z,1618350389.153 [DataOverHttps](INFO): Sending 1175 bytes from file Logs/20210413T210804/Express0014.lzma
2021-04-13T21:46:30.151Z,1618350390.151 [DataOverHttps](INFO): Moved sent file to Logs/20210413T210804/Express0014.lzma.bak
2021-04-13T21:46:30.152Z,1618350390.152 [DataOverHttps](INFO): SBD MOMSN=15565678
2021-04-13T21:46:32.550Z,1618350392.550 [Default:CheckIn:Read_Iridium] Stopped
2021-04-13T21:46:32.550Z,1618350392.550 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-13T21:46:32.550Z,1618350392.550 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-13T21:48:25.382Z,1618350505.382 [CommandLine](IMPORTANT): got command burn off
2021-04-13T21:48:25.382Z,1618350505.382 [CommandLine](IMPORTANT): Deactivating dropweight wire
2021-04-13T21:48:31.716Z,1618350511.716 [DVL_micro](ERROR): only read 3 of 4 data items
2021-04-13T21:48:31.716Z,1618350511.716 [DVL_micro](ERROR): Failed to parse:
:BI,-01784,-00732,+00517,+000D,+00000000.00,+00000000.00,+00000000.00,900.00
2021-04-13T21:49:03.633Z,1618350543.633 [NAL9602](INFO): SBD MO Status=2, MOMSN=2006, MT Status=2, MTMSN=0
2021-04-13T21:49:03.633Z,1618350543.633 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-04-13T21:50:51.909Z,1618350651.909 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2021-04-13T21:50:51.909Z,1618350651.909 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35,0000.0,14
2021-04-13T21:50:58.372Z,1618350658.372 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2021-04-13T21:51:29.071Z,1618350689.071 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-13T21:51:33.126Z,1618350693.126 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-13T21:51:33.126Z,1618350693.126 [Default:CheckIn:C.Wait] Stopped
2021-04-13T21:51:33.126Z,1618350693.126 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-13T21:51:33.127Z,1618350693.127 [Default:CheckIn:D] Running Loop=1
2021-04-13T21:51:33.543Z,1618350693.543 [Default:CheckIn:D] Stopped
2021-04-13T21:51:33.543Z,1618350693.543 [Default:CheckIn:E] Running Loop=1
2021-04-13T21:51:33.927Z,1618350693.927 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 5.886730 min 
2021-04-13T21:51:33.927Z,1618350693.927 [Default:CheckIn:E] Stopped
2021-04-13T21:51:33.927Z,1618350693.927 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-13T21:51:33.927Z,1618350693.927 [Default:CheckIn] Stopped
2021-04-13T21:51:33.928Z,1618350693.928 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-13T21:51:33.928Z,1618350693.928 [Default:CheckIn](INFO): Running loop #2
2021-04-13T21:51:33.928Z,1618350693.928 [Default:CheckIn] Running Loop=2
2021-04-13T21:51:33.928Z,1618350693.928 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-13T21:51:33.928Z,1618350693.928 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-13T21:51:35.945Z,1618350695.945 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215135.00,A,3648.16756,N,12147.27969,W,0.078,113.29,130421,,,A*77
2021-04-13T21:51:35.961Z,1618350695.961 [NAL9602](INFO): GPS fix at 20210413T215135: (36.802793, -121.787995)
2021-04-13T21:51:35.972Z,1618350695.972 [Default:CheckIn:Read_GPS] Stopped
2021-04-13T21:51:35.972Z,1618350695.972 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-13T21:51:43.181Z,1618350703.181 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210413T210804/Courier0016.lzma
2021-04-13T21:51:44.183Z,1618350704.183 [DataOverHttps](INFO): Moved sent file to Logs/20210413T210804/Courier0016.lzma.bak
2021-04-13T21:51:44.184Z,1618350704.184 [DataOverHttps](INFO): SBD MOMSN=15565728
2021-04-13T21:52:01.852Z,1618350721.852 [DataOverHttps](INFO): Sending 256 bytes from file Logs/20210413T210804/Express0017.lzma
2021-04-13T21:52:02.070Z,1618350722.070 [DataOverHttps](INFO): Moved sent file to Logs/20210413T210804/Express0017.lzma.bak
2021-04-13T21:52:02.070Z,1618350722.070 [DataOverHttps](INFO): SBD MOMSN=15565731
2021-04-13T21:52:05.086Z,1618350725.086 [Default:CheckIn:Read_Iridium] Stopped
2021-04-13T21:52:05.086Z,1618350725.086 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-13T21:52:05.086Z,1618350725.086 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-13T21:52:06.739Z,1618350726.739 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2021-04-13T21:52:06.822Z,1618350726.822 [NAL9602](ERROR): received: 
+CSQ:1

OK06, 2, 0, 0, 0

OK
2021-04-13T21:52:39.121Z,1618350759.121 [NAL9602](INFO): SBD MO Status=0, MOMSN=2006, MT Status=0, MTMSN=0
2021-04-13T21:52:39.121Z,1618350759.121 [NAL9602](INFO): No messages in MT queue
2021-04-13T21:53:09.823Z,1618350789.823 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-13T21:57:05.901Z,1618351025.901 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-13T21:57:05.901Z,1618351025.901 [Default:CheckIn:C.Wait] Stopped
2021-04-13T21:57:05.902Z,1618351025.902 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-13T21:57:05.902Z,1618351025.902 [Default:CheckIn:D] Running Loop=1
2021-04-13T21:57:06.290Z,1618351026.290 [Default:CheckIn:D] Stopped
2021-04-13T21:57:06.290Z,1618351026.290 [Default:CheckIn:E] Running Loop=1
2021-04-13T21:57:06.707Z,1618351026.707 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.432447 min 
2021-04-13T21:57:06.707Z,1618351026.707 [Default:CheckIn:E] Stopped
2021-04-13T21:57:06.708Z,1618351026.708 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-13T21:57:06.708Z,1618351026.708 [Default:CheckIn] Stopped
2021-04-13T21:57:06.708Z,1618351026.708 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-13T21:57:06.708Z,1618351026.708 [Default:CheckIn](INFO): Running loop #3
2021-04-13T21:57:06.708Z,1618351026.708 [Default:CheckIn] Running Loop=3
2021-04-13T21:57:06.708Z,1618351026.708 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-13T21:57:06.708Z,1618351026.708 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-13T21:57:08.694Z,1618351028.694 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215708.00,A,3648.13913,N,12147.23006,W,0.097,98.16,130421,,,A*4E
2021-04-13T21:57:08.697Z,1618351028.697 [NAL9602](INFO): GPS fix at 20210413T215708: (36.802319, -121.787168)
2021-04-13T21:57:08.738Z,1618351028.738 [Default:CheckIn:Read_GPS] Stopped
2021-04-13T21:57:08.738Z,1618351028.738 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-13T21:57:16.717Z,1618351036.717 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20210413T210804/Courier0019.lzma
2021-04-13T21:57:17.720Z,1618351037.720 [DataOverHttps](INFO): Moved sent file to Logs/20210413T210804/Courier0019.lzma.bak
2021-04-13T21:57:17.720Z,1618351037.720 [DataOverHttps](INFO): SBD MOMSN=15565738
2021-04-13T21:57:24.049Z,1618351044.049 [NAL9602](INFO): SBD MO Status=0, MOMSN=2007, MT Status=0, MTMSN=0
2021-04-13T21:57:24.049Z,1618351044.049 [NAL9602](INFO): No messages in MT queue
2021-04-13T21:57:34.582Z,1618351054.582 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20210413T210804/Express0020.lzma
2021-04-13T21:57:35.583Z,1618351055.583 [DataOverHttps](INFO): Moved sent file to Logs/20210413T210804/Express0020.lzma.bak
2021-04-13T21:57:35.584Z,1618351055.584 [DataOverHttps](INFO): SBD MOMSN=15565741
2021-04-13T21:57:37.813Z,1618351057.813 [Default:CheckIn:Read_Iridium] Stopped
2021-04-13T21:57:37.813Z,1618351057.813 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-13T21:57:37.813Z,1618351057.813 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-13T21:57:54.749Z,1618351074.749 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-13T22:01:26.919Z,1618351286.919 [DataOverHttps](IMPORTANT): SBD MTMSN=20210413T220126
2021-04-13T22:01:34.246Z,1618351294.246 [DataOverHttps](INFO): Received command:Ibit
2021-04-13T22:01:34.253Z,1618351294.253 [CommandLine](IMPORTANT): got command ibit
2021-04-13T22:01:34.625Z,1618351294.625 [IBIT](IMPORTANT): Beginning Initiated BIT
2021-04-13T22:01:34.625Z,1618351294.625 [IBIT](IMPORTANT): Beginning control surface checks.
2021-04-13T22:01:34.629Z,1618351294.629 [CBIT](IMPORTANT): Beginning ground fault scan
2021-04-13T22:01:36.221Z,1618351296.221 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220135.00,A,3648.14065,N,12147.20946,W,0.039,48.89,130421,,,D*4B
2021-04-13T22:01:36.224Z,1618351296.224 [NAL9602](INFO): GPS fix at 20210413T220135: (36.802344, -121.786824)
2021-04-13T22:01:45.562Z,1618351305.562 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.026824
CHAN A1 (24V): 0.016210
CHAN A2 (12V): -0.011089
CHAN A3 (5V): -0.016860
CHAN B0 (3.3V): -0.010652
CHAN B1 (3.15aV): -0.005584
CHAN B2 (3.15bV): -0.003280
CHAN B3 (GND): -0.009942
OPEN: 0.004400
Full Scale Calc: 4.765 mA, -1.589 mA
2021-04-13T22:01:51.990Z,1618351311.990 [NAL9602](INFO): SBD MO Status=0, MOMSN=2008, MT Status=0, MTMSN=0
2021-04-13T22:01:51.990Z,1618351311.990 [NAL9602](INFO): No messages in MT queue
2021-04-13T22:01:53.187Z,1618351313.187 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220152.00,A,3648.14066,N,12147.21021,W,0.019,48.89,130421,,,D*42
2021-04-13T22:01:53.189Z,1618351313.189 [NAL9602](INFO): GPS fix at 20210413T220152: (36.802344, -121.786837)
2021-04-13T22:01:56.024Z,1618351316.024 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220155.00,A,3648.14061,N,12147.21036,W,0.019,48.89,130421,,,D*44
2021-04-13T22:01:56.027Z,1618351316.027 [NAL9602](INFO): GPS fix at 20210413T220155: (36.802343, -121.786839)
2021-04-13T22:01:58.850Z,1618351318.850 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220158.00,A,3648.14052,N,12147.21042,W,0.039,48.89,130421,,,D*48
2021-04-13T22:01:58.852Z,1618351318.852 [NAL9602](INFO): GPS fix at 20210413T220158: (36.802342, -121.786840)
2021-04-13T22:02:02.091Z,1618351322.091 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220201.00,A,3648.14047,N,12147.21035,W,0.019,48.89,130421,,,D*41
2021-04-13T22:02:02.093Z,1618351322.093 [NAL9602](INFO): GPS fix at 20210413T220201: (36.802341, -121.786839)
2021-04-13T22:02:04.905Z,1618351324.905 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220204.00,A,3648.14052,N,12147.21025,W,0.058,48.89,130421,,,D*44
2021-04-13T22:02:04.919Z,1618351324.919 [NAL9602](INFO): GPS fix at 20210413T220204: (36.802342, -121.786838)
2021-04-13T22:02:08.178Z,1618351328.178 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220207.00,A,3648.14063,N,12147.21007,W,0.058,48.89,130421,,,D*45
2021-04-13T22:02:08.180Z,1618351328.180 [NAL9602](INFO): GPS fix at 20210413T220207: (36.802344, -121.786834)
2021-04-13T22:02:11.045Z,1618351331.045 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220210.00,A,3648.14072,N,12147.20999,W,0.019,48.89,130421,,,D*49
2021-04-13T22:02:11.056Z,1618351331.056 [NAL9602](INFO): GPS fix at 20210413T220210: (36.802345, -121.786833)
2021-04-13T22:02:13.864Z,1618351333.864 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220213.00,A,3648.14069,N,12147.20997,W,0.039,48.89,130421,,,D*4C
2021-04-13T22:02:13.866Z,1618351333.866 [NAL9602](INFO): GPS fix at 20210413T220213: (36.802345, -121.786833)
2021-04-13T22:02:17.101Z,1618351337.101 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220216.00,A,3648.14061,N,12147.20982,W,0.019,48.89,130421,,,D*47
2021-04-13T22:02:17.104Z,1618351337.104 [NAL9602](INFO): GPS fix at 20210413T220216: (36.802343, -121.786830)
2021-04-13T22:02:17.512Z,1618351337.512 [DVL_micro](ERROR): Failed to parse:
55,-01486,+00003,+00000,I
2021-04-13T22:02:19.938Z,1618351339.938 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220219.00,A,3648.14054,N,12147.20966,W,0.039,48.89,130421,,,D*46
2021-04-13T22:02:19.941Z,1618351339.941 [NAL9602](INFO): GPS fix at 20210413T220219: (36.802342, -121.786828)
2021-04-13T22:02:20.753Z,1618351340.753 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 5
Latitude: 36.802341 Longitude: -121.786827
2021-04-13T22:02:21.165Z,1618351341.165 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.254000
2021-04-13T22:02:21.165Z,1618351341.165 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2021-04-13T22:02:21.165Z,1618351341.165 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2021-04-13T22:02:21.565Z,1618351341.565 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2021-04-13T22:02:21.565Z,1618351341.565 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2021-04-13T22:02:21.566Z,1618351341.566 [IBIT](IMPORTANT): Pressure:8.054051 PSI
2021-04-13T22:02:21.566Z,1618351341.566 [IBIT](IMPORTANT): Humidity:13.575876 %
2021-04-13T22:02:22.004Z,1618351342.004 [IBIT](IMPORTANT): Vehicle Pitch:-2.084545 degrees
2021-04-13T22:02:22.004Z,1618351342.004 [IBIT](IMPORTANT): Vehicle  Roll:0.271543 degrees
2021-04-13T22:02:22.004Z,1618351342.004 [IBIT](IMPORTANT): Vehicle  Heading:68.522552 degrees
2021-04-13T22:02:22.410Z,1618351342.410 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2021-04-13T22:02:22.410Z,1618351342.410 [IBIT](IMPORTANT): buoyancyNeutral: 214.815567 cc
2021-04-13T22:02:22.410Z,1618351342.410 [IBIT](IMPORTANT): massDefault: -0.124781 cm
2021-04-13T22:02:22.411Z,1618351342.411 [IBIT](IMPORTANT): stopDepth: 265.000000 m
2021-04-13T22:02:22.411Z,1618351342.411 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2021-04-13T22:02:22.411Z,1618351342.411 [IBIT](IMPORTANT): IBIT PASSED
2021-04-13T22:02:38.521Z,1618351358.521 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-13T22:02:38.522Z,1618351358.522 [Default:CheckIn:C.Wait] Stopped
2021-04-13T22:02:38.522Z,1618351358.522 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-13T22:02:38.522Z,1618351358.522 [Default:CheckIn:D] Running Loop=1
2021-04-13T22:02:38.930Z,1618351358.930 [Default:CheckIn:D] Stopped
2021-04-13T22:02:38.930Z,1618351358.930 [Default:CheckIn:E] Running Loop=1
2021-04-13T22:02:39.337Z,1618351359.337 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 16.976519 min 
2021-04-13T22:02:39.337Z,1618351359.337 [Default:CheckIn:E] Stopped
2021-04-13T22:02:39.337Z,1618351359.337 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-13T22:02:39.338Z,1618351359.338 [Default:CheckIn] Stopped
2021-04-13T22:02:39.338Z,1618351359.338 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-13T22:02:39.338Z,1618351359.338 [Default:CheckIn](INFO): Running loop #4
2021-04-13T22:02:39.338Z,1618351359.338 [Default:CheckIn] Running Loop=4
2021-04-13T22:02:39.338Z,1618351359.338 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-13T22:02:39.338Z,1618351359.338 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-13T22:02:41.337Z,1618351361.337 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220240.00,A,3648.14019,N,12147.20927,W,0.019,48.89,130421,,,D*44
2021-04-13T22:02:41.340Z,1618351361.340 [NAL9602](INFO): GPS fix at 20210413T220240: (36.802337, -121.786821)
2021-04-13T22:02:41.383Z,1618351361.383 [Default:CheckIn:Read_GPS] Stopped
2021-04-13T22:02:41.383Z,1618351361.383 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-13T22:02:45.699Z,1618351365.699 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20210413T210804/Courier0022.lzma
2021-04-13T22:02:45.912Z,1618351365.912 [DataOverHttps](INFO): Moved sent file to Logs/20210413T210804/Courier0022.lzma.bak
2021-04-13T22:02:45.912Z,1618351365.912 [DataOverHttps](INFO): SBD MOMSN=15565748
2021-04-13T22:03:02.085Z,1618351382.085 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20210413T210804/Courier0025.lzma
2021-04-13T22:03:03.094Z,1618351383.094 [DataOverHttps](INFO): Moved sent file to Logs/20210413T210804/Courier0025.lzma.bak
2021-04-13T22:03:03.095Z,1618351383.095 [DataOverHttps](INFO): SBD MOMSN=15565751
2021-04-13T22:03:13.347Z,1618351393.347 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-13T22:03:20.027Z,1618351400.027 [DataOverHttps](INFO): Sending 818 bytes from file Logs/20210413T210804/Express0023.lzma
2021-04-13T22:03:21.028Z,1618351401.028 [DataOverHttps](INFO): Moved sent file to Logs/20210413T210804/Express0023.lzma.bak
2021-04-13T22:03:21.028Z,1618351401.028 [DataOverHttps](INFO): SBD MOMSN=15565753
2021-04-13T22:03:37.965Z,1618351417.965 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20210413T210804/Express0026.lzma
2021-04-13T22:03:38.968Z,1618351418.968 [DataOverHttps](INFO): Moved sent file to Logs/20210413T210804/Express0026.lzma.bak
2021-04-13T22:03:38.968Z,1618351418.968 [DataOverHttps](INFO): SBD MOMSN=15565779
2021-04-13T22:03:41.267Z,1618351421.267 [Default:CheckIn:Read_Iridium] Stopped
2021-04-13T22:03:41.267Z,1618351421.267 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-13T22:03:41.267Z,1618351421.267 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-13T22:04:32.515Z,1618351472.515 [DVL_micro](ERROR): only read 3 of 4 data items
2021-04-13T22:04:32.515Z,1618351472.515 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+99999.99,+9999.99
2021-04-13T22:07:15.818Z,1618351635.818 [DVL_micro](ERROR): only read 3 of 4 data items
2021-04-13T22:07:15.818Z,1618351635.818 [DVL_micro](ERROR): Failed to parse:
:BI,-00703,-01617,+00107,+

2021-04-13T22:08:41.917Z,1618351721.917 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-13T22:08:41.918Z,1618351721.918 [Default:CheckIn:C.Wait] Stopped
2021-04-13T22:08:41.918Z,1618351721.918 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-13T22:08:41.918Z,1618351721.918 [Default:CheckIn:D] Running Loop=1
2021-04-13T22:08:42.316Z,1618351722.316 [Default:CheckIn:D] Stopped
2021-04-13T22:08:42.316Z,1618351722.316 [Default:CheckIn:E] Running Loop=1
2021-04-13T22:08:42.719Z,1618351722.719 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 23.032961 min 
2021-04-13T22:08:42.719Z,1618351722.719 [Default:CheckIn:E] Stopped
2021-04-13T22:08:42.719Z,1618351722.719 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-13T22:08:42.719Z,1618351722.719 [Default:CheckIn] Stopped
2021-04-13T22:08:42.720Z,1618351722.720 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-13T22:08:42.720Z,1618351722.720 [Default:CheckIn](INFO): Running loop #5
2021-04-13T22:08:42.720Z,1618351722.720 [Default:CheckIn] Running Loop=5
2021-04-13T22:08:42.720Z,1618351722.720 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-13T22:08:42.720Z,1618351722.720 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-13T22:08:44.740Z,1618351724.740 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220844.00,A,3648.13775,N,12147.20432,W,0.058,48.89,130421,,,D*4C
2021-04-13T22:08:44.743Z,1618351724.743 [NAL9602](INFO): GPS fix at 20210413T220844: (36.802296, -121.786739)
2021-04-13T22:08:44.754Z,1618351724.754 [Default:CheckIn:Read_GPS] Stopped
2021-04-13T22:08:44.754Z,1618351724.754 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-13T22:08:47.609Z,1618351727.609 [DVL_micro](ERROR): only read 0 of 4 data items
2021-04-13T22:08:47.609Z,1618351727.609 [DVL_micro](ERROR): Failed to parse:
:RD,++9999.99,+9999.99,+9999.99
2021-04-13T22:08:52.037Z,1618351732.037 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20210413T210804/Courier0028.lzma
2021-04-13T22:08:53.040Z,1618351733.040 [DataOverHttps](INFO): Moved sent file to Logs/20210413T210804/Courier0028.lzma.bak
2021-04-13T22:08:53.040Z,1618351733.040 [DataOverHttps](INFO): SBD MOMSN=15565781
2021-04-13T22:09:10.118Z,1618351750.118 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20210413T210804/Express0029.lzma
2021-04-13T22:09:11.120Z,1618351751.120 [DataOverHttps](INFO): Moved sent file to Logs/20210413T210804/Express0029.lzma.bak
2021-04-13T22:09:11.120Z,1618351751.120 [DataOverHttps](INFO): SBD MOMSN=15565784
2021-04-13T22:09:13.455Z,1618351753.455 [Default:CheckIn:Read_Iridium] Stopped
2021-04-13T22:09:13.455Z,1618351753.455 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-13T22:09:13.455Z,1618351753.455 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-13T22:10:05.561Z,1618351805.561 [NAL9602](INFO): SBD MO Status=0, MOMSN=2009, MT Status=0, MTMSN=0
2021-04-13T22:10:05.561Z,1618351805.561 [NAL9602](INFO): No messages in MT queue
2021-04-13T22:10:36.271Z,1618351836.271 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-13T22:14:14.155Z,1618352054.155 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-13T22:14:14.155Z,1618352054.155 [Default:CheckIn:C.Wait] Stopped
2021-04-13T22:14:14.155Z,1618352054.155 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-13T22:14:14.155Z,1618352054.155 [Default:CheckIn:D] Running Loop=1
2021-04-13T22:14:14.519Z,1618352054.519 [Default:CheckIn:D] Stopped
2021-04-13T22:14:14.519Z,1618352054.519 [Default:CheckIn:E] Running Loop=1
2021-04-13T22:14:14.925Z,1618352054.925 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 28.569670 min 
2021-04-13T22:14:14.930Z,1618352054.930 [Default:CheckIn:E] Stopped
2021-04-13T22:14:14.930Z,1618352054.930 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-13T22:14:14.930Z,1618352054.930 [Default:CheckIn] Stopped
2021-04-13T22:14:14.930Z,1618352054.930 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-13T22:14:14.930Z,1618352054.930 [Default:CheckIn](INFO): Running loop #6
2021-04-13T22:14:14.930Z,1618352054.930 [Default:CheckIn] Running Loop=6
2021-04-13T22:14:14.931Z,1618352054.931 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-13T22:14:14.931Z,1618352054.931 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-13T22:14:16.917Z,1618352056.917 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221416.00,A,3648.13739,N,12147.20447,W,0.039,48.89,130421,,,D*4B
2021-04-13T22:14:16.920Z,1618352056.920 [NAL9602](INFO): GPS fix at 20210413T221416: (36.802290, -121.786741)
2021-04-13T22:14:16.939Z,1618352056.939 [Default:CheckIn:Read_GPS] Stopped
2021-04-13T22:14:16.939Z,1618352056.939 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-13T22:14:28.909Z,1618352068.909 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20210413T210804/Courier0031.lzma
2021-04-13T22:14:29.912Z,1618352069.912 [DataOverHttps](INFO): Moved sent file to Logs/20210413T210804/Courier0031.lzma.bak
2021-04-13T22:14:29.912Z,1618352069.912 [DataOverHttps](INFO): SBD MOMSN=15565788
2021-04-13T22:14:29.989Z,1618352069.989 [NAL9602](INFO): SBD MO Status=0, MOMSN=2010, MT Status=0, MTMSN=0
2021-04-13T22:14:29.989Z,1618352069.989 [NAL9602](INFO): No messages in MT queue
2021-04-13T22:14:46.906Z,1618352086.906 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20210413T210804/Express0032.lzma
2021-04-13T22:14:47.908Z,1618352087.908 [DataOverHttps](INFO): Moved sent file to Logs/20210413T210804/Express0032.lzma.bak
2021-04-13T22:14:47.908Z,1618352087.908 [DataOverHttps](INFO): SBD MOMSN=15565791
2021-04-13T22:14:50.203Z,1618352090.203 [Default:CheckIn:Read_Iridium] Stopped
2021-04-13T22:14:50.203Z,1618352090.203 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-13T22:14:50.203Z,1618352090.203 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-13T22:15:00.669Z,1618352100.669 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-13T22:19:50.830Z,1618352390.830 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-13T22:19:50.830Z,1618352390.830 [Default:CheckIn:C.Wait] Stopped
2021-04-13T22:19:50.830Z,1618352390.830 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-13T22:19:50.830Z,1618352390.830 [Default:CheckIn:D] Running Loop=1
2021-04-13T22:19:51.233Z,1618352391.233 [Default:CheckIn:D] Stopped
2021-04-13T22:19:51.233Z,1618352391.233 [Default:CheckIn:E] Running Loop=1
2021-04-13T22:19:51.627Z,1618352391.627 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 34.181571 min 
2021-04-13T22:19:51.627Z,1618352391.627 [Default:CheckIn:E] Stopped
2021-04-13T22:19:51.627Z,1618352391.627 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-13T22:19:51.628Z,1618352391.628 [Default:CheckIn] Stopped
2021-04-13T22:19:51.628Z,1618352391.628 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-13T22:19:51.628Z,1618352391.628 [Default:CheckIn](INFO): Running loop #7
2021-04-13T22:19:51.628Z,1618352391.628 [Default:CheckIn] Running Loop=7
2021-04-13T22:19:51.628Z,1618352391.628 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-13T22:19:51.628Z,1618352391.628 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-13T22:19:53.649Z,1618352393.649 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221952.00,A,3648.14717,N,12147.19141,W,2.974,11.35,130421,,,D*4D
2021-04-13T22:19:53.652Z,1618352393.652 [NAL9602](INFO): GPS fix at 20210413T221952: (36.802453, -121.786524)
2021-04-13T22:19:53.662Z,1618352393.662 [Default:CheckIn:Read_GPS] Stopped
2021-04-13T22:19:53.662Z,1618352393.662 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-13T22:20:00.621Z,1618352400.621 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20210413T210804/Courier0034.lzma
2021-04-13T22:20:01.624Z,1618352401.624 [DataOverHttps](INFO): Moved sent file to Logs/20210413T210804/Courier0034.lzma.bak
2021-04-13T22:20:01.624Z,1618352401.624 [DataOverHttps](INFO): SBD MOMSN=15565795
2021-04-13T22:20:14.714Z,1618352414.714 [NAL9602](INFO): SBD MO Status=0, MOMSN=2011, MT Status=0, MTMSN=0
2021-04-13T22:20:14.715Z,1618352414.715 [NAL9602](INFO): No messages in MT queue
2021-04-13T22:20:25.498Z,1618352425.498 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20210413T210804/Express0035.lzma
2021-04-13T22:20:26.831Z,1618352426.831 [DataOverHttps](INFO): Moved sent file to Logs/20210413T210804/Express0035.lzma.bak
2021-04-13T22:20:26.831Z,1618352426.831 [DataOverHttps](INFO): SBD MOMSN=15565798
2021-04-13T22:20:28.961Z,1618352428.961 [Default:CheckIn:Read_Iridium] Stopped
2021-04-13T22:20:28.962Z,1618352428.962 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-13T22:20:28.962Z,1618352428.962 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-13T22:20:45.483Z,1618352445.483 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-13T22:25:29.590Z,1618352729.590 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-13T22:25:29.590Z,1618352729.590 [Default:CheckIn:C.Wait] Stopped
2021-04-13T22:25:29.590Z,1618352729.590 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-13T22:25:29.591Z,1618352729.591 [Default:CheckIn:D] Running Loop=1
2021-04-13T22:25:30.004Z,1618352730.004 [Default:CheckIn:D] Stopped
2021-04-13T22:25:30.004Z,1618352730.004 [Default:CheckIn:E] Running Loop=1
2021-04-13T22:25:30.386Z,1618352730.386 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 39.827759 min 
2021-04-13T22:25:30.386Z,1618352730.386 [Default:CheckIn:E] Stopped
2021-04-13T22:25:30.386Z,1618352730.386 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-13T22:25:30.386Z,1618352730.386 [Default:CheckIn] Stopped
2021-04-13T22:25:30.386Z,1618352730.386 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-13T22:25:30.387Z,1618352730.387 [Default:CheckIn](INFO): Running loop #8
2021-04-13T22:25:30.387Z,1618352730.387 [Default:CheckIn] Running Loop=8
2021-04-13T22:25:30.387Z,1618352730.387 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-13T22:25:30.387Z,1618352730.387 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-13T22:25:32.397Z,1618352732.397 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222531.00,A,3648.46406,N,12147.29589,W,8.222,236.37,130421,,,D*77
2021-04-13T22:25:32.400Z,1618352732.400 [NAL9602](INFO): GPS fix at 20210413T222531: (36.807734, -121.788265)
2021-04-13T22:25:32.411Z,1618352732.411 [Default:CheckIn:Read_GPS] Stopped
2021-04-13T22:25:32.412Z,1618352732.412 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-13T22:25:40.029Z,1618352740.029 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20210413T210804/Courier0037.lzma
2021-04-13T22:25:41.032Z,1618352741.032 [DataOverHttps](INFO): Moved sent file to Logs/20210413T210804/Courier0037.lzma.bak
2021-04-13T22:25:41.032Z,1618352741.032 [DataOverHttps](INFO): SBD MOMSN=15565802
2021-04-13T22:25:54.209Z,1618352754.209 [NAL9602](INFO): SBD MO Status=2, MOMSN=2012, MT Status=2, MTMSN=0
2021-04-13T22:25:54.209Z,1618352754.209 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-04-13T22:25:57.925Z,1618352757.925 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20210413T210804/Express0038.lzma
2021-04-13T22:25:58.928Z,1618352758.928 [DataOverHttps](INFO): Moved sent file to Logs/20210413T210804/Express0038.lzma.bak
2021-04-13T22:25:58.928Z,1618352758.928 [DataOverHttps](INFO): SBD MOMSN=15565806
2021-04-13T22:26:01.098Z,1618352761.098 [Default:CheckIn:Read_Iridium] Stopped
2021-04-13T22:26:01.098Z,1618352761.098 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-13T22:26:01.099Z,1618352761.099 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-13T22:26:20.993Z,1618352780.993 [NAL9602](INFO): SBD MO Status=0, MOMSN=2012, MT Status=0, MTMSN=0
2021-04-13T22:26:20.993Z,1618352780.993 [NAL9602](INFO): No messages in MT queue
2021-04-13T22:26:51.679Z,1618352811.679 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-13T22:26:58.553Z,1618352818.553 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.99,+9999.99,+9999.99
2021-04-13T22:31:01.813Z,1618353061.813 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-13T22:31:01.813Z,1618353061.813 [Default:CheckIn:C.Wait] Stopped
2021-04-13T22:31:01.813Z,1618353061.813 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-13T22:31:01.813Z,1618353061.813 [Default:CheckIn:D] Running Loop=1
2021-04-13T22:31:02.230Z,1618353062.230 [Default:CheckIn:D] Stopped
2021-04-13T22:31:02.230Z,1618353062.230 [Default:CheckIn:E] Running Loop=1
2021-04-13T22:31:02.645Z,1618353062.645 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 45.364779 min 
2021-04-13T22:31:02.645Z,1618353062.645 [Default:CheckIn:E] Stopped
2021-04-13T22:31:02.645Z,1618353062.645 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-13T22:31:02.645Z,1618353062.645 [Default:CheckIn] Stopped
2021-04-13T22:31:02.652Z,1618353062.652 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-13T22:31:02.652Z,1618353062.652 [Default:CheckIn](INFO): Running loop #9
2021-04-13T22:31:02.652Z,1618353062.652 [Default:CheckIn] Running Loop=9
2021-04-13T22:31:02.652Z,1618353062.652 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-13T22:31:02.652Z,1618353062.652 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-13T22:31:04.637Z,1618353064.637 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,223103.00,A,3648.19909,N,12148.07197,W,13.957,205.46,130421,,,A*43
2021-04-13T22:31:04.639Z,1618353064.639 [NAL9602](INFO): GPS fix at 20210413T223103: (36.803318, -121.801199)
2021-04-13T22:31:04.651Z,1618353064.651 [Default:CheckIn:Read_GPS] Stopped
2021-04-13T22:31:04.651Z,1618353064.651 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-13T22:31:12.525Z,1618353072.525 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210413T210804/Courier0040.lzma
2021-04-13T22:31:13.528Z,1618353073.528 [DataOverHttps](INFO): Moved sent file to Logs/20210413T210804/Courier0040.lzma.bak
2021-04-13T22:31:13.528Z,1618353073.528 [DataOverHttps](INFO): SBD MOMSN=15565811
2021-04-13T22:31:22.068Z,1618353082.068 [NAL9602](INFO): SBD MO Status=0, MOMSN=2013, MT Status=0, MTMSN=0
2021-04-13T22:31:22.068Z,1618353082.068 [NAL9602](INFO): No messages in MT queue
2021-04-13T22:31:30.849Z,1618353090.849 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20210413T210804/Express0041.lzma
2021-04-13T22:31:31.852Z,1618353091.852 [DataOverHttps](INFO): Moved sent file to Logs/20210413T210804/Express0041.lzma.bak
2021-04-13T22:31:31.852Z,1618353091.852 [DataOverHttps](INFO): SBD MOMSN=15565814
2021-04-13T22:31:34.157Z,1618353094.157 [Default:CheckIn:Read_Iridium] Stopped
2021-04-13T22:31:34.162Z,1618353094.162 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-13T22:31:34.162Z,1618353094.162 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-13T22:31:52.733Z,1618353112.733 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-13T22:35:29.343Z,1618353329.343 [DVL_micro](ERROR): only read 3 of 4 data items
2021-04-13T22:35:29.344Z,1618353329.344 [DVL_micro](ERROR): Failed to parse:
:BI,-00339,-01815,+0000,I
2021-04-13T22:36:34.795Z,1618353394.795 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-13T22:36:34.795Z,1618353394.795 [Default:CheckIn:C.Wait] Stopped
2021-04-13T22:36:34.795Z,1618353394.795 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-13T22:36:34.796Z,1618353394.796 [Default:CheckIn:D] Running Loop=1
2021-04-13T22:36:35.206Z,1618353395.206 [Default:CheckIn:D] Stopped
2021-04-13T22:36:35.206Z,1618353395.206 [Default:CheckIn:E] Running Loop=1
2021-04-13T22:36:35.623Z,1618353395.623 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 50.914457 min 
2021-04-13T22:36:35.623Z,1618353395.623 [Default:CheckIn:E] Stopped
2021-04-13T22:36:35.623Z,1618353395.623 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-13T22:36:35.623Z,1618353395.623 [Default:CheckIn] Stopped
2021-04-13T22:36:35.623Z,1618353395.623 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-13T22:36:35.623Z,1618353395.623 [Default:CheckIn](INFO): Running loop #10
2021-04-13T22:36:35.623Z,1618353395.623 [Default:CheckIn] Running Loop=10
2021-04-13T22:36:35.623Z,1618353395.623 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-13T22:36:35.624Z,1618353395.624 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-13T22:36:37.621Z,1618353397.621 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,223636.00,A,3647.55941,N,12148.82697,W,5.812,243.99,130421,,,A*74
2021-04-13T22:36:37.631Z,1618353397.631 [NAL9602](INFO): GPS fix at 20210413T223636: (36.792657, -121.813783)
2021-04-13T22:36:37.642Z,1618353397.642 [Default:CheckIn:Read_GPS] Stopped
2021-04-13T22:36:37.642Z,1618353397.642 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-13T22:36:45.417Z,1618353405.417 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20210413T210804/Courier0043.lzma
2021-04-13T22:36:46.420Z,1618353406.420 [DataOverHttps](INFO): Moved sent file to Logs/20210413T210804/Courier0043.lzma.bak
2021-04-13T22:36:46.420Z,1618353406.420 [DataOverHttps](INFO): SBD MOMSN=15565818
2021-04-13T22:36:57.017Z,1618353417.017 [NAL9602](INFO): SBD MO Status=0, MOMSN=2014, MT Status=0, MTMSN=0
2021-04-13T22:36:57.017Z,1618353417.017 [NAL9602](INFO): No messages in MT queue
2021-04-13T22:37:03.442Z,1618353423.442 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20210413T210804/Express0044.lzma
2021-04-13T22:37:04.444Z,1618353424.444 [DataOverHttps](INFO): Moved sent file to Logs/20210413T210804/Express0044.lzma.bak
2021-04-13T22:37:04.444Z,1618353424.444 [DataOverHttps](INFO): SBD MOMSN=15565821
2021-04-13T22:37:06.743Z,1618353426.743 [Default:CheckIn:Read_Iridium] Stopped
2021-04-13T22:37:06.744Z,1618353426.744 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-13T22:37:06.744Z,1618353426.744 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-13T22:37:27.708Z,1618353447.708 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-13T22:42:07.395Z,1618353727.395 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-13T22:42:07.395Z,1618353727.395 [Default:CheckIn:C.Wait] Stopped
2021-04-13T22:42:07.396Z,1618353727.396 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-13T22:42:07.396Z,1618353727.396 [Default:CheckIn:D] Running Loop=1
2021-04-13T22:42:07.797Z,1618353727.797 [Default:CheckIn:D] Stopped
2021-04-13T22:42:07.797Z,1618353727.797 [Default:CheckIn:E] Running Loop=1
2021-04-13T22:42:08.212Z,1618353728.212 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 56.457642 min 
2021-04-13T22:42:08.212Z,1618353728.212 [Default:CheckIn:E] Stopped
2021-04-13T22:42:08.212Z,1618353728.212 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-13T22:42:08.212Z,1618353728.212 [Default:CheckIn] Stopped
2021-04-13T22:42:08.212Z,1618353728.212 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-13T22:42:08.212Z,1618353728.212 [Default:CheckIn](INFO): Running loop #11
2021-04-13T22:42:08.212Z,1618353728.212 [Default:CheckIn] Running Loop=11
2021-04-13T22:42:08.213Z,1618353728.213 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-13T22:42:08.213Z,1618353728.213 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-13T22:42:10.222Z,1618353730.222 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224209.00,A,3647.46553,N,12149.17158,W,1.983,52.92,130421,,,A*4B
2021-04-13T22:42:10.224Z,1618353730.224 [NAL9602](INFO): GPS fix at 20210413T224209: (36.791092, -121.819526)
2021-04-13T22:42:10.243Z,1618353730.243 [Default:CheckIn:Read_GPS] Stopped
2021-04-13T22:42:10.243Z,1618353730.243 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-13T22:42:16.594Z,1618353736.594 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -27.00 mm (1 active estimators).
2021-04-13T22:42:20.599Z,1618353740.599 [Default:B.GoToSurface](ERROR): Altitude too low: 1.03
2021-04-13T22:42:20.988Z,1618353740.988 [Default:B.GoToSurface](ERROR): Altitude too low: 1.03
2021-04-13T22:42:34.007Z,1618353754.007 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=61.376423
2021-04-13T22:42:44.399Z,1618353764.399 [DAT](INFO): unknown deviceResponse_: Acoustic Wakeup

2021-04-13T22:42:44.848Z,1618353764.848 [DAT](INFO): commRate: 800
2021-04-13T22:42:45.302Z,1618353765.302 [DAT](INFO): unknown deviceResponse_: Lowpower

2021-04-13T22:42:45.617Z,1618353765.617 [NAL9602](INFO): SBD MO Status=0, MOMSN=2015, MT Status=0, MTMSN=0
2021-04-13T22:42:45.617Z,1618353765.617 [NAL9602](INFO): No messages in MT queue
2021-04-13T22:42:54.734Z,1618353774.734 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003021
2021-04-13T22:42:58.085Z,1618353778.085 [DVL_micro](ERROR): only read 2 of 4 data items
2021-04-13T22:42:58.085Z,1618353778.085 [DVL_micro](ERROR): Failed to parse:
:RD,+0000.12,+0000..32,+0000.35
2021-04-13T22:43:02.409Z,1618353782.409 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210413T210804/Courier0046.lzma
2021-04-13T22:43:03.412Z,1618353783.412 [DataOverHttps](INFO): Moved sent file to Logs/20210413T210804/Courier0046.lzma.bak
2021-04-13T22:43:03.412Z,1618353783.412 [DataOverHttps](INFO): SBD MOMSN=15565825
2021-04-13T22:43:03.729Z,1618353783.729 [NAL9602](INFO): SBD MO Status=1, MOMSN=2016, MT Status=0, MTMSN=0
2021-04-13T22:43:03.782Z,1618353783.782 [NAL9602](INFO): Sent 72 bytes from file Logs/20210413T210804/Courier0046.lzma
2021-04-13T22:43:03.782Z,1618353783.782 [NAL9602](INFO): Packets left to send: 0
2021-04-13T22:43:20.462Z,1618353800.462 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20210413T210804/Express0047.lzma
2021-04-13T22:43:21.464Z,1618353801.464 [DataOverHttps](INFO): Moved sent file to Logs/20210413T210804/Express0047.lzma.bak
2021-04-13T22:43:21.464Z,1618353801.464 [DataOverHttps](INFO): SBD MOMSN=15565828
2021-04-13T22:43:23.963Z,1618353803.963 [Default:CheckIn:Read_Iridium] Stopped
2021-04-13T22:43:23.963Z,1618353803.963 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-13T22:43:23.964Z,1618353803.964 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-13T22:43:31.435Z,1618353811.435 [DataOverHttps](IMPORTANT): SBD MTMSN=20210413T224330
2021-04-13T22:43:34.462Z,1618353814.462 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-13T22:43:40.049Z,1618353820.049 [DataOverHttps](INFO): Received command:restart logs