2019-03-13T22:27:10.855Z,1552516030.855 [Supervisor](DEBUG): Initializing supervisor.
2019-03-13T22:27:10.858Z,1552516030.858 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-03-13T22:27:10.859Z,1552516030.859 [SyncHandler](INFO): Protected caller Thread ID is 9963
2019-03-13T22:27:10.860Z,1552516030.860 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-03-13T22:27:10.861Z,1552516030.861 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-03-13T22:27:10.861Z,1552516030.861 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 9964
2019-03-13T22:27:10.865Z,1552516030.865 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-03-13T22:27:10.877Z,1552516030.877 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-03-13T22:27:10.878Z,1552516030.878 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-03-13T22:27:10.878Z,1552516030.878 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 9965
2019-03-13T22:27:10.879Z,1552516030.879 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-03-13T22:27:10.880Z,1552516030.880 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-03-13T22:27:10.880Z,1552516030.880 [logger ThreadHandler](INFO): Protected caller Thread ID is 9966
2019-03-13T22:27:10.882Z,1552516030.882 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-03-13T22:27:10.883Z,1552516030.883 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-03-13T22:27:10.884Z,1552516030.884 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-03-13T22:27:11.419Z,1552516031.419 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-03-13T22:27:11.419Z,1552516031.419 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-03-13T22:27:11.522Z,1552516031.522 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-03-13T22:27:11.523Z,1552516031.523 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-03-13T22:27:11.891Z,1552516031.891 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-03-13T22:27:11.892Z,1552516031.892 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-03-13T22:27:12.046Z,1552516032.046 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-03-13T22:27:12.047Z,1552516032.047 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-03-13T22:27:12.259Z,1552516032.259 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-03-13T22:27:12.259Z,1552516032.259 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-03-13T22:27:12.770Z,1552516032.770 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-03-13T22:27:12.771Z,1552516032.771 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-03-13T22:27:13.003Z,1552516033.003 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-03-13T22:27:13.004Z,1552516033.004 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-03-13T22:27:13.163Z,1552516033.163 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-03-13T22:27:13.164Z,1552516033.164 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-03-13T22:27:13.386Z,1552516033.386 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-03-13T22:27:13.387Z,1552516033.387 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-03-13T22:27:13.489Z,1552516033.489 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-03-13T22:27:13.490Z,1552516033.490 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-03-13T22:27:13.834Z,1552516033.834 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-03-13T22:27:13.835Z,1552516033.835 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-03-13T22:27:13.920Z,1552516033.920 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-03-13T22:27:14.031Z,1552516034.031 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-03-13T22:27:14.031Z,1552516034.031 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-03-13T22:27:14.587Z,1552516034.587 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-03-13T22:27:14.588Z,1552516034.588 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-03-13T22:27:15.360Z,1552516035.360 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-03-13T22:27:15.362Z,1552516035.362 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2019-03-13T22:27:15.363Z,1552516035.363 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2019-03-13T22:27:15.596Z,1552516035.596 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2019-03-13T22:27:15.703Z,1552516035.703 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2019-03-13T22:27:15.810Z,1552516035.810 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2019-03-13T22:27:16.051Z,1552516036.051 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-03-13T22:27:16.052Z,1552516036.052 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2019-03-13T22:27:16.142Z,1552516036.142 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2019-03-13T22:27:16.242Z,1552516036.242 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2019-03-13T22:27:16.347Z,1552516036.347 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2019-03-13T22:27:16.434Z,1552516036.434 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2019-03-13T22:27:16.551Z,1552516036.551 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2019-03-13T22:27:16.745Z,1552516036.745 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2019-03-13T22:27:16.890Z,1552516036.890 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/
2019-03-13T22:27:16.891Z,1552516036.891 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-03-13T22:27:16.894Z,1552516036.894 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-03-13T22:27:17.264Z,1552516037.264 [DataOverHttps] Loaded
2019-03-13T22:27:17.264Z,1552516037.264 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-03-13T22:27:17.265Z,1552516037.265 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4074D4E0
2019-03-13T22:27:17.266Z,1552516037.266 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 345
2019-03-13T22:27:17.305Z,1552516037.305 [Depth_Keller] Loaded
2019-03-13T22:27:17.305Z,1552516037.305 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-03-13T22:27:17.310Z,1552516037.310 [DropWeight] Loaded
2019-03-13T22:27:17.311Z,1552516037.311 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-03-13T22:27:17.361Z,1552516037.361 [DUSBL_Hydroid] Loaded
2019-03-13T22:27:17.361Z,1552516037.361 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2019-03-13T22:27:17.404Z,1552516037.404 [Micromodem] Loaded
2019-03-13T22:27:17.404Z,1552516037.404 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2019-03-13T22:27:17.505Z,1552516037.505 [NAL9602] Loaded
2019-03-13T22:27:17.505Z,1552516037.505 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-03-13T22:27:17.521Z,1552516037.521 [Onboard] Loaded
2019-03-13T22:27:17.521Z,1552516037.521 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-03-13T22:27:17.528Z,1552516037.528 [Radio_Surface] Loaded
2019-03-13T22:27:17.528Z,1552516037.528 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-03-13T22:27:17.530Z,1552516037.530 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4077D4E0
2019-03-13T22:27:17.530Z,1552516037.530 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 346
2019-03-13T22:27:17.593Z,1552516037.593 [RDI_Pathfinder] Loaded
2019-03-13T22:27:17.593Z,1552516037.593 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2019-03-13T22:27:17.616Z,1552516037.616 [RDI_PathfinderUp] Loaded
2019-03-13T22:27:17.616Z,1552516037.616 [ComponentRegistry](DEBUG): SyncComponent "RDI_PathfinderUp" handled in the control thread.
2019-03-13T22:27:19.484Z,1552516039.484 [BPC1] Loaded
2019-03-13T22:27:19.485Z,1552516039.485 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-03-13T22:27:19.485Z,1552516039.485 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-03-13T22:27:19.486Z,1552516039.486 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-03-13T22:27:19.507Z,1552516039.507 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-03-13T22:27:19.507Z,1552516039.507 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-03-13T22:27:19.680Z,1552516039.680 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-03-13T22:27:19.681Z,1552516039.681 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-03-13T22:27:19.772Z,1552516039.772 [DeadReckonUsingSpeedCalculator] Loaded
2019-03-13T22:27:19.772Z,1552516039.772 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-03-13T22:27:19.854Z,1552516039.854 [DeadReckonWithRespectToSeafloor] Loaded
2019-03-13T22:27:19.857Z,1552516039.857 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2019-03-13T22:27:19.901Z,1552516039.901 [NavChart] Loaded
2019-03-13T22:27:19.902Z,1552516039.902 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-03-13T22:27:19.930Z,1552516039.930 [UniversalFixResidualReporter] Loaded
2019-03-13T22:27:19.930Z,1552516039.930 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-03-13T22:27:19.930Z,1552516039.930 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-03-13T22:27:19.931Z,1552516039.931 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-03-13T22:27:20.243Z,1552516040.243 [BuoyancyServo] Loaded
2019-03-13T22:27:20.243Z,1552516040.243 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-03-13T22:27:20.259Z,1552516040.259 [ElevatorServo] Loaded
2019-03-13T22:27:20.259Z,1552516040.259 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-03-13T22:27:20.274Z,1552516040.274 [MassServo] Loaded
2019-03-13T22:27:20.274Z,1552516040.274 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-03-13T22:27:20.290Z,1552516040.290 [RudderServo] Loaded
2019-03-13T22:27:20.291Z,1552516040.291 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-03-13T22:27:20.305Z,1552516040.305 [ThrusterServo] Loaded
2019-03-13T22:27:20.305Z,1552516040.305 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-03-13T22:27:20.306Z,1552516040.306 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-03-13T22:27:20.307Z,1552516040.307 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-03-13T22:27:20.506Z,1552516040.506 [Aanderaa_O2] Loaded
2019-03-13T22:27:20.507Z,1552516040.507 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-03-13T22:27:20.595Z,1552516040.595 [CTD_NeilBrown] Loaded
2019-03-13T22:27:20.596Z,1552516040.596 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-03-13T22:27:20.597Z,1552516040.597 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408C24E0
2019-03-13T22:27:20.598Z,1552516040.598 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 347
2019-03-13T22:27:20.644Z,1552516040.644 [WetLabsSeaOWL_UV_A] Loaded
2019-03-13T22:27:20.644Z,1552516040.644 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2019-03-13T22:27:20.645Z,1552516040.645 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 408F24E0
2019-03-13T22:27:20.645Z,1552516040.645 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 348
2019-03-13T22:27:20.646Z,1552516040.646 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-03-13T22:27:20.647Z,1552516040.647 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-03-13T22:27:20.928Z,1552516040.928 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-03-13T22:27:20.928Z,1552516040.928 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-03-13T22:27:20.980Z,1552516040.980 [DepthRateCalculator] Loaded
2019-03-13T22:27:20.981Z,1552516040.981 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-03-13T22:27:20.987Z,1552516040.987 [PitchRateCalculator] Loaded
2019-03-13T22:27:20.987Z,1552516040.987 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-03-13T22:27:21.000Z,1552516041.000 [SpeedCalculator] Loaded
2019-03-13T22:27:21.001Z,1552516041.001 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-03-13T22:27:21.022Z,1552516041.022 [TempGradientCalculator] Loaded
2019-03-13T22:27:21.022Z,1552516041.022 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-03-13T22:27:21.028Z,1552516041.028 [YawRateCalculator] Loaded
2019-03-13T22:27:21.029Z,1552516041.029 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-03-13T22:27:21.071Z,1552516041.071 [ElevatorOffsetCalculator] Loaded
2019-03-13T22:27:21.071Z,1552516041.071 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-03-13T22:27:21.071Z,1552516041.071 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-03-13T22:27:21.072Z,1552516041.072 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-03-13T22:27:21.231Z,1552516041.231 [SBIT](DEBUG): Construct Startup Built In Test.
2019-03-13T22:27:21.254Z,1552516041.254 [SBIT] Loaded
2019-03-13T22:27:21.254Z,1552516041.254 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-03-13T22:27:21.255Z,1552516041.255 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-03-13T22:27:21.267Z,1552516041.267 [IBIT] Loaded
2019-03-13T22:27:21.267Z,1552516041.267 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-03-13T22:27:21.270Z,1552516041.270 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-03-13T22:27:21.412Z,1552516041.412 [CBIT] Loaded
2019-03-13T22:27:21.413Z,1552516041.413 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-03-13T22:27:21.413Z,1552516041.413 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-03-13T22:27:21.414Z,1552516041.414 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-03-13T22:27:21.511Z,1552516041.511 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-03-13T22:27:21.511Z,1552516041.511 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-03-13T22:27:21.637Z,1552516041.637 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-03-13T22:27:21.638Z,1552516041.638 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-03-13T22:27:21.723Z,1552516041.723 [VerticalControl](DEBUG): Construct VerticalControl.
2019-03-13T22:27:21.809Z,1552516041.809 [VerticalControl] Loaded
2019-03-13T22:27:21.809Z,1552516041.809 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-03-13T22:27:21.810Z,1552516041.810 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-03-13T22:27:21.870Z,1552516041.870 [HorizontalControl] Loaded
2019-03-13T22:27:21.870Z,1552516041.870 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-03-13T22:27:21.871Z,1552516041.871 [SpeedControl](DEBUG): Construct SpeedControl.
2019-03-13T22:27:21.872Z,1552516041.872 [SpeedControl] Loaded
2019-03-13T22:27:21.872Z,1552516041.872 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-03-13T22:27:21.873Z,1552516041.873 [LoopControl](DEBUG): Construct LoopControl.
2019-03-13T22:27:21.874Z,1552516041.874 [LoopControl] Loaded
2019-03-13T22:27:21.874Z,1552516041.874 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-03-13T22:27:21.875Z,1552516041.875 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-03-13T22:27:21.875Z,1552516041.875 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-03-13T22:27:21.914Z,1552516041.914 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-03-13T22:27:21.917Z,1552516041.917 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-03-13T22:27:21.918Z,1552516041.918 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-03-13T22:27:21.925Z,1552516041.925 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-03-13T22:27:21.926Z,1552516041.926 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AB04E0
2019-03-13T22:27:21.926Z,1552516041.926 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 349
2019-03-13T22:27:21.931Z,1552516041.931 [Supervisor](INFO): Main Thread ID is 4239
2019-03-13T22:27:21.931Z,1552516041.931 [Supervisor](DEBUG): Running supervisor.
2019-03-13T22:27:21.932Z,1552516041.932 [CommandLine ThreadHandler](INFO): Handler Thread ID is 350
2019-03-13T22:27:21.935Z,1552516041.935 [controlThread ThreadHandler](INFO): Handler Thread ID is 351
2019-03-13T22:27:21.935Z,1552516041.935 [controlThread](DEBUG): Initializing ControlThread
2019-03-13T22:27:21.940Z,1552516041.940 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-13T22:27:21.941Z,1552516041.941 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-03-13T22:27:21.941Z,1552516041.941 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-03-13T22:27:21.943Z,1552516041.943 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-03-13T22:27:21.943Z,1552516041.943 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-03-13T22:27:21.943Z,1552516041.943 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-03-13T22:27:21.943Z,1552516041.943 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-03-13T22:27:21.944Z,1552516041.944 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-03-13T22:27:21.944Z,1552516041.944 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-03-13T22:27:21.945Z,1552516041.945 [SBIT](INFO): Initialize SBIT Component.
2019-03-13T22:27:21.946Z,1552516041.946 [SBIT](IMPORTANT): git: 2019-03-04-36-g743def3
2019-03-13T22:27:21.946Z,1552516041.946 [SBIT](INFO): git hash: 743def31acf12ef8e20e672f0fefb031590b97fa
2019-03-13T22:27:21.946Z,1552516041.946 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-03-13T22:27:21.946Z,1552516041.946 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2019-03-13T22:27:21.947Z,1552516041.947 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2019-03-13T22:27:21.948Z,1552516041.948 [IBIT](INFO): Initialize IBIT Component.
2019-03-13T22:27:21.949Z,1552516041.949 [CBIT](DEBUG): Initialize CBIT Component.
2019-03-13T22:27:21.950Z,1552516041.950 [logger ThreadHandler](INFO): Handler Thread ID is 352
2019-03-13T22:27:21.961Z,1552516041.961 [CBIT](DEBUG): Initialized mux pins.
2019-03-13T22:27:21.961Z,1552516041.961 [CBIT](DEBUG): Initializing the watchdog timer.
2019-03-13T22:27:21.969Z,1552516041.969 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 353
2019-03-13T22:27:21.970Z,1552516041.970 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-03-13T22:27:21.981Z,1552516041.981 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 354
2019-03-13T22:27:21.985Z,1552516041.985 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-03-13T22:27:21.985Z,1552516041.985 [CBIT](DEBUG): Initializing heartbeat.
2019-03-13T22:27:21.993Z,1552516041.993 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 355
2019-03-13T22:27:21.994Z,1552516041.994 [CTD_NeilBrown](INFO): Powering down
2019-03-13T22:27:22.021Z,1552516042.021 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 356
2019-03-13T22:27:22.022Z,1552516042.022 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-03-13T22:27:22.050Z,1552516042.050 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 357
2019-03-13T22:27:22.057Z,1552516042.057 [CBIT](DEBUG): Deactivating GF circuits.
2019-03-13T22:27:22.057Z,1552516042.057 [CBIT](DEBUG): Deactivating emergency mode.
2019-03-13T22:27:22.057Z,1552516042.057 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-03-13T22:27:22.057Z,1552516042.057 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-03-13T22:27:22.058Z,1552516042.058 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-03-13T22:27:22.058Z,1552516042.058 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-03-13T22:27:22.058Z,1552516042.058 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-03-13T22:27:22.058Z,1552516042.058 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-03-13T22:27:22.058Z,1552516042.058 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-03-13T22:27:22.059Z,1552516042.059 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-03-13T22:27:22.059Z,1552516042.059 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-03-13T22:27:22.059Z,1552516042.059 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-03-13T22:27:22.059Z,1552516042.059 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-03-13T22:27:22.059Z,1552516042.059 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-03-13T22:27:22.060Z,1552516042.060 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-03-13T22:27:22.060Z,1552516042.060 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-03-13T22:27:22.060Z,1552516042.060 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-03-13T22:27:22.060Z,1552516042.060 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-03-13T22:27:22.093Z,1552516042.093 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-03-13T22:27:22.095Z,1552516042.095 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-03-13T22:27:22.095Z,1552516042.095 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-03-13T22:27:22.096Z,1552516042.096 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-03-13T22:27:22.097Z,1552516042.097 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-03-13T22:27:22.125Z,1552516042.125 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-03-13T22:27:22.165Z,1552516042.165 [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>


2019-03-13T22:27:22.166Z,1552516042.166 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-03-13T22:27:22.218Z,1552516042.218 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-03-13T22:27:22.219Z,1552516042.219 [Default:A.Wait](DEBUG): Construct Wait.
2019-03-13T22:27:22.233Z,1552516042.233 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-03-13T22:27:22.260Z,1552516042.260 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-03-13T22:27:22.262Z,1552516042.262 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-03-13T22:27:22.283Z,1552516042.283 [Default:E.Execute](DEBUG): Construct Execute.
2019-03-13T22:27:22.287Z,1552516042.287 [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>


2019-03-13T22:27:22.302Z,1552516042.302 [controlThread](DEBUG): Component order: CycleStarter,Depth_Keller,DropWeight,DUSBL_Hydroid,Micromodem,NAL9602,Onboard,RDI_Pathfinder,RDI_PathfinderUp,BPC1,Aanderaa_O2,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-03-13T22:27:22.342Z,1552516042.342 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar
2019-03-13T22:27:22.342Z,1552516042.342 [DUSBL_Hydroid](INFO): Powering up
2019-03-13T22:27:22.343Z,1552516042.343 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-03-13T22:27:22.357Z,1552516042.357 [Micromodem](INFO): Start
2019-03-13T22:27:22.385Z,1552516042.385 [Radio_Surface](INFO): Powering up
2019-03-13T22:27:22.421Z,1552516042.421 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-03-13T22:27:22.485Z,1552516042.485 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-03-13T22:27:22.497Z,1552516042.497 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-03-13T22:27:22.498Z,1552516042.498 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-03-13T22:27:22.505Z,1552516042.505 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-03-13T22:27:22.506Z,1552516042.506 [MassServo](DEBUG): Initializing EZServoServo.
2019-03-13T22:27:22.513Z,1552516042.513 [MassServo](DEBUG): Initializing MassServo.
2019-03-13T22:27:22.514Z,1552516042.514 [RudderServo](DEBUG): Initializing EZServoServo.
2019-03-13T22:27:22.521Z,1552516042.521 [RudderServo](DEBUG): Initializing RudderServo.
2019-03-13T22:27:22.522Z,1552516042.522 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-03-13T22:27:22.529Z,1552516042.529 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-03-13T22:27:22.726Z,1552516042.726 [Micromodem](INFO): Starting
2019-03-13T22:27:22.727Z,1552516042.727 [Micromodem](INFO): Powering up
2019-03-13T22:27:22.727Z,1552516042.727 [Micromodem](DEBUG): Initializing Micromodem.
2019-03-13T22:27:22.833Z,1552516042.833 [RDI_PathfinderUp](INFO): Powering down
2019-03-13T22:27:22.909Z,1552516042.909 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-03-13T22:27:23.118Z,1552516043.118 [Micromodem](INFO): Starting
2019-03-13T22:27:23.137Z,1552516043.137 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-03-13T22:27:23.449Z,1552516043.449 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-03-13T22:27:23.449Z,1552516043.449 [RudderServo](FAULT): Rudder failed to initialize
2019-03-13T22:27:23.449Z,1552516043.449 [RudderServo] Communications Fault, FailCount= 1
2019-03-13T22:27:23.449Z,1552516043.449 [RudderServo](ERROR): Communications Fault
2019-03-13T22:27:23.560Z,1552516043.560 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-03-13T22:27:23.571Z,1552516043.571 [Micromodem](INFO): Starting
2019-03-13T22:27:23.750Z,1552516043.750 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-03-13T22:27:23.750Z,1552516043.750 [RudderServo](INFO): Powering down
2019-03-13T22:27:23.974Z,1552516043.974 [Micromodem](INFO): Starting
2019-03-13T22:27:24.378Z,1552516044.378 [Micromodem](INFO): Starting
2019-03-13T22:27:24.418Z,1552516044.418 [RudderServo](DEBUG): Initializing EZServoServo.
2019-03-13T22:27:24.538Z,1552516044.538 [RudderServo](DEBUG): Initializing RudderServo.
2019-03-13T22:27:24.542Z,1552516044.542 [CBIT](INFO): Clearing failed state for component RudderServo
2019-03-13T22:27:24.542Z,1552516044.542 [RudderServo] No Fault, FailCount= 1
2019-03-13T22:27:24.782Z,1552516044.782 [Micromodem](INFO): Starting
2019-03-13T22:27:25.186Z,1552516045.186 [Micromodem](INFO): Starting
2019-03-13T22:27:25.590Z,1552516045.590 [Micromodem](INFO): Starting
2019-03-13T22:27:25.994Z,1552516045.994 [Micromodem](INFO): Starting
2019-03-13T22:27:26.398Z,1552516046.398 [Micromodem](INFO): Starting
2019-03-13T22:27:26.802Z,1552516046.802 [Micromodem](INFO): Starting
2019-03-13T22:27:27.206Z,1552516047.206 [Micromodem](INFO): Starting
2019-03-13T22:27:27.610Z,1552516047.610 [Micromodem](INFO): Starting
2019-03-13T22:27:27.611Z,1552516047.611 [Micromodem](INFO): Starting
2019-03-13T22:27:27.611Z,1552516047.611 [Micromodem](DEBUG): Nmea buf: $CCCFG,CST,1*37

2019-03-13T22:27:27.611Z,1552516047.611 [Micromodem](IMPORTANT): Nmea buf: $CCCFG,CST,1*37

2019-03-13T22:27:27.611Z,1552516047.611 [Micromodem](INFO): Nmea out: $CCCFG,CST,1*37

2019-03-13T22:27:27.612Z,1552516047.612 [Micromodem](DEBUG): Nmea buf: $CCCFG,SRC,1*31

2019-03-13T22:27:27.612Z,1552516047.612 [Micromodem](IMPORTANT): Nmea buf: $CCCFG,SRC,1*31

2019-03-13T22:27:27.612Z,1552516047.612 [Micromodem](DEBUG): Nmea buf: $CCCFG,REV,1*32

2019-03-13T22:27:27.612Z,1552516047.612 [Micromodem](IMPORTANT): Nmea buf: $CCCFG,REV,1*32

2019-03-13T22:27:27.621Z,1552516047.621 [Micromodem](DEBUG): Nmea buf: $CCCFG,RXP,1*29

2019-03-13T22:27:27.621Z,1552516047.621 [Micromodem](IMPORTANT): Nmea buf: $CCCFG,RXP,1*29

2019-03-13T22:27:28.014Z,1552516048.014 [Micromodem](INFO): Starting
2019-03-13T22:27:28.419Z,1552516048.419 [Micromodem](INFO): Starting
2019-03-13T22:27:28.822Z,1552516048.822 [Micromodem](INFO): Starting
2019-03-13T22:27:29.226Z,1552516049.226 [Micromodem](INFO): Starting
2019-03-13T22:27:29.631Z,1552516049.631 [Micromodem](INFO): Starting
2019-03-13T22:27:30.034Z,1552516050.034 [Micromodem](INFO): Starting
2019-03-13T22:27:30.438Z,1552516050.438 [Micromodem](INFO): Starting
2019-03-13T22:27:30.842Z,1552516050.842 [Micromodem](INFO): Starting
2019-03-13T22:27:31.246Z,1552516051.246 [Micromodem](INFO): Starting
2019-03-13T22:27:31.650Z,1552516051.650 [Micromodem](INFO): Starting
2019-03-13T22:27:32.054Z,1552516052.054 [Micromodem](INFO): Starting
2019-03-13T22:27:32.458Z,1552516052.458 [Micromodem](INFO): Starting
2019-03-13T22:27:32.475Z,1552516052.475 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-03-13T22:27:32.475Z,1552516052.475 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-03-13T22:27:32.475Z,1552516052.475 [Aanderaa_O2](ERROR): Communications Fault
2019-03-13T22:27:32.491Z,1552516052.491 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-03-13T22:27:32.862Z,1552516052.862 [Micromodem](INFO): Starting
2019-03-13T22:27:32.997Z,1552516052.997 [Aanderaa_O2](INFO): Powering down
2019-03-13T22:27:33.266Z,1552516053.266 [Micromodem](INFO): Starting
2019-03-13T22:27:33.670Z,1552516053.670 [Micromodem](INFO): Starting
2019-03-13T22:27:33.718Z,1552516053.718 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-03-13T22:27:33.718Z,1552516053.718 [Aanderaa_O2] No Fault, FailCount= 1
2019-03-13T22:27:34.074Z,1552516054.074 [Micromodem](INFO): Starting
2019-03-13T22:27:34.478Z,1552516054.478 [Micromodem](INFO): Starting
2019-03-13T22:27:34.883Z,1552516054.883 [Micromodem](INFO): Starting
2019-03-13T22:27:35.286Z,1552516055.286 [Micromodem](INFO): Starting
2019-03-13T22:27:35.690Z,1552516055.690 [Micromodem](INFO): Starting
2019-03-13T22:27:35.717Z,1552516055.717 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2019-03-13T22:27:36.105Z,1552516056.105 [Micromodem](INFO): Starting
2019-03-13T22:27:36.510Z,1552516056.510 [Micromodem](INFO): Starting
2019-03-13T22:27:36.902Z,1552516056.902 [Micromodem](INFO): Starting
2019-03-13T22:27:37.306Z,1552516057.306 [Micromodem](INFO): Starting
2019-03-13T22:27:37.710Z,1552516057.710 [Micromodem](INFO): Starting
2019-03-13T22:27:38.114Z,1552516058.114 [Micromodem](INFO): Starting
2019-03-13T22:27:38.526Z,1552516058.526 [Micromodem](INFO): Starting
2019-03-13T22:27:38.922Z,1552516058.922 [Micromodem](INFO): Starting
2019-03-13T22:27:38.923Z,1552516058.923 [Micromodem](ERROR): Nmea resend: $CCCFG,CST,1*37

2019-03-13T22:27:39.326Z,1552516059.326 [Micromodem](INFO): Starting
2019-03-13T22:27:39.731Z,1552516059.731 [Micromodem](INFO): Starting
2019-03-13T22:27:40.135Z,1552516060.135 [Micromodem](INFO): Starting
2019-03-13T22:27:40.540Z,1552516060.540 [Micromodem](INFO): Starting
2019-03-13T22:27:40.950Z,1552516060.950 [Micromodem](INFO): Starting
2019-03-13T22:27:41.347Z,1552516061.347 [Micromodem](INFO): Starting
2019-03-13T22:27:41.752Z,1552516061.752 [Micromodem](INFO): Starting
2019-03-13T22:27:42.156Z,1552516062.156 [Micromodem](INFO): Starting
2019-03-13T22:27:42.399Z,1552516062.399 [CommandLine](IMPORTANT): got command show variable DUSBL
2019-03-13T22:27:42.559Z,1552516062.559 [DUSBL_Hydroid](FAULT): failed to initialize; deviceResponse_ loaded: , available: 0
2019-03-13T22:27:42.559Z,1552516062.559 [DUSBL_Hydroid] Communications Fault, FailCount= 1
2019-03-13T22:27:42.559Z,1552516062.559 [DUSBL_Hydroid](ERROR): Communications Fault
2019-03-13T22:27:42.559Z,1552516062.559 [Micromodem](INFO): Starting
2019-03-13T22:27:42.610Z,1552516062.610 [CommandLine](IMPORTANT): DUSBL_Hydroid.loadAtStartup (bool)
2019-03-13T22:27:42.611Z,1552516062.611 [CommandLine](IMPORTANT): DUSBL_Hydroid.simulateHardware (bool)
2019-03-13T22:27:42.611Z,1552516062.611 [CommandLine](IMPORTANT): DUSBL_Hydroid.defaultTurnAroundTime (meter_per_second)
2019-03-13T22:27:42.611Z,1552516062.611 [CommandLine](IMPORTANT): DUSBL_Hydroid.defaultSoundSpeed (meter_per_second)
2019-03-13T22:27:42.612Z,1552516062.612 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold (count)
2019-03-13T22:27:42.612Z,1552516062.612 [CommandLine](IMPORTANT): DUSBL_Hydroid.recieveTimeout (millisecond)
2019-03-13T22:27:42.617Z,1552516062.617 [CommandLine](IMPORTANT): DUSBL_Hydroid.transmitLockout (millisecond)
2019-03-13T22:27:42.618Z,1552516062.618 [CommandLine](IMPORTANT): DUSBL_Hydroid.transponderCode (enum)
2019-03-13T22:27:42.618Z,1552516062.618 [CommandLine](IMPORTANT): DUSBL_Hydroid.numberOfPingsRequested (count)
2019-03-13T22:27:42.618Z,1552516062.618 [CommandLine](IMPORTANT): DUSBL_Hydroid.verbosity (enum)
2019-03-13T22:27:42.619Z,1552516062.619 [CommandLine](IMPORTANT): DUSBL_Hydroid.xCenter (angular_degree)
2019-03-13T22:27:42.619Z,1552516062.619 [CommandLine](IMPORTANT): DUSBL_Hydroid.yCenter (angular_degree)
2019-03-13T22:27:42.633Z,1552516062.633 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid
2019-03-13T22:27:42.676Z,1552516062.676 [CommandLine](IMPORTANT): DUSBL_Hydroid.loadControl (none)
2019-03-13T22:27:42.676Z,1552516062.676 [CommandLine](IMPORTANT): DUSBL_Hydroid.uart (none)
2019-03-13T22:27:42.676Z,1552516062.676 [CommandLine](IMPORTANT): DUSBL_Hydroid.baud (bit_per_second)
2019-03-13T22:27:42.688Z,1552516062.688 [CommandLine](IMPORTANT): Micromodem.dusblPingCode (enum)
2019-03-13T22:27:42.709Z,1552516062.709 [CommandLine](IMPORTANT): DUSBL_Hydroid.xAngle (angular_degree)
2019-03-13T22:27:42.710Z,1552516062.710 [CommandLine](IMPORTANT): DUSBL_Hydroid.yAngle (angular_degree)
2019-03-13T22:27:42.710Z,1552516062.710 [CommandLine](IMPORTANT): DUSBL_Hydroid.travelTime (microsecond)
2019-03-13T22:27:42.710Z,1552516062.710 [CommandLine](IMPORTANT): DUSBL_Hydroid.latency (microsecond)
2019-03-13T22:27:42.711Z,1552516062.711 [CommandLine](IMPORTANT): DUSBL_Hydroid.gain (ratio)
2019-03-13T22:27:42.711Z,1552516062.711 [CommandLine](IMPORTANT): DUSBL_Hydroid.inBandSignalToNoise (ratio)
2019-03-13T22:27:42.711Z,1552516062.711 [CommandLine](IMPORTANT): DUSBL_Hydroid.outBandSignalToNoise (ratio)
2019-03-13T22:27:42.712Z,1552516062.712 [CommandLine](IMPORTANT): DUSBL_Hydroid.range (meter)
2019-03-13T22:27:42.712Z,1552516062.712 [CommandLine](IMPORTANT): DUSBL_Hydroid.direction (none)
2019-03-13T22:27:42.962Z,1552516062.962 [DUSBL_Hydroid](INFO): Powering down
2019-03-13T22:27:43.013Z,1552516063.013 [Micromodem](INFO): Starting
2019-03-13T22:27:43.366Z,1552516063.366 [Micromodem](INFO): Starting
2019-03-13T22:27:43.770Z,1552516063.770 [Micromodem](INFO): Starting
2019-03-13T22:27:43.857Z,1552516063.857 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid
2019-03-13T22:27:43.857Z,1552516063.857 [DUSBL_Hydroid] No Fault, FailCount= 1
2019-03-13T22:27:44.174Z,1552516064.174 [Micromodem](INFO): Starting
2019-03-13T22:27:44.191Z,1552516064.191 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-03-13T22:27:44.191Z,1552516064.191 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-03-13T22:27:44.191Z,1552516064.191 [Aanderaa_O2](ERROR): Communications Fault
2019-03-13T22:27:44.221Z,1552516064.221 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-03-13T22:27:44.578Z,1552516064.578 [Micromodem](INFO): Starting
2019-03-13T22:27:44.729Z,1552516064.729 [Aanderaa_O2](INFO): Powering down
2019-03-13T22:27:44.982Z,1552516064.982 [Micromodem](INFO): Starting
2019-03-13T22:27:45.386Z,1552516065.386 [Micromodem](INFO): Starting
2019-03-13T22:27:45.418Z,1552516065.418 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-03-13T22:27:45.418Z,1552516065.418 [Aanderaa_O2] No Fault, FailCount= 2
2019-03-13T22:27:45.790Z,1552516065.790 [Micromodem](INFO): Starting
2019-03-13T22:27:46.194Z,1552516066.194 [DUSBL_Hydroid](INFO): Powering up
2019-03-13T22:27:46.194Z,1552516066.194 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-03-13T22:27:46.209Z,1552516066.209 [Micromodem](INFO): Starting
2019-03-13T22:27:46.599Z,1552516066.599 [Micromodem](INFO): Starting
2019-03-13T22:27:47.002Z,1552516067.002 [Micromodem](INFO): Starting
2019-03-13T22:27:47.415Z,1552516067.415 [Micromodem](INFO): Starting
2019-03-13T22:27:47.810Z,1552516067.810 [Micromodem](INFO): Starting
2019-03-13T22:27:47.811Z,1552516067.811 [NAL9602](INFO): Powering up NAL9602
2019-03-13T22:27:48.216Z,1552516068.216 [Micromodem](INFO): Starting
2019-03-13T22:27:48.618Z,1552516068.618 [Micromodem](INFO): Starting
2019-03-13T22:27:49.022Z,1552516069.022 [Micromodem](INFO): Starting
2019-03-13T22:27:49.023Z,1552516069.023 [Micromodem](IMPORTANT): CACFG stsring$CACFG,CST,1*35

2019-03-13T22:27:49.023Z,1552516069.023 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2019-03-13T22:27:49.426Z,1552516069.426 [Micromodem](INFO): Starting
2019-03-13T22:27:49.830Z,1552516069.830 [Micromodem](INFO): Starting
2019-03-13T22:27:50.231Z,1552516070.231 [Micromodem](INFO): Starting
2019-03-13T22:27:50.639Z,1552516070.639 [Micromodem](INFO): Starting
2019-03-13T22:27:50.680Z,1552516070.680 [SBIT](IMPORTANT): Beginning Startup BIT
2019-03-13T22:27:50.688Z,1552516070.688 [CBIT](IMPORTANT): Beginning ground fault scan
2019-03-13T22:27:51.042Z,1552516071.042 [Micromodem](INFO): Starting
2019-03-13T22:27:51.446Z,1552516071.446 [Micromodem](INFO): Starting
2019-03-13T22:27:51.850Z,1552516071.850 [Micromodem](INFO): Starting
2019-03-13T22:27:52.254Z,1552516072.254 [Micromodem](INFO): Starting
2019-03-13T22:27:52.658Z,1552516072.658 [Micromodem](INFO): Starting
2019-03-13T22:27:53.062Z,1552516073.062 [Micromodem](INFO): Starting
2019-03-13T22:27:53.695Z,1552516073.695 [Micromodem](INFO): Starting
2019-03-13T22:27:54.086Z,1552516074.086 [Micromodem](INFO): Starting
2019-03-13T22:27:54.516Z,1552516074.516 [Micromodem](INFO): Starting
2019-03-13T22:27:54.894Z,1552516074.894 [Micromodem](INFO): Starting
2019-03-13T22:27:55.298Z,1552516075.298 [Micromodem](INFO): Starting
2019-03-13T22:27:55.702Z,1552516075.702 [Micromodem](INFO): Starting
2019-03-13T22:27:56.110Z,1552516076.110 [Micromodem](INFO): Starting
2019-03-13T22:27:56.127Z,1552516076.127 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-03-13T22:27:56.127Z,1552516076.127 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-03-13T22:27:56.127Z,1552516076.127 [Aanderaa_O2](ERROR): Communications Fault
2019-03-13T22:27:56.210Z,1552516076.210 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-03-13T22:27:56.211Z,1552516076.211 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-03-13T22:27:56.518Z,1552516076.518 [Micromodem](INFO): Starting
2019-03-13T22:27:56.689Z,1552516076.689 [Aanderaa_O2](INFO): Powering down
2019-03-13T22:27:56.923Z,1552516076.923 [Micromodem](INFO): Starting
2019-03-13T22:27:57.318Z,1552516077.318 [Micromodem](INFO): Starting
2019-03-13T22:27:57.722Z,1552516077.722 [Micromodem](INFO): Starting
2019-03-13T22:27:58.126Z,1552516078.126 [Micromodem](INFO): Starting
2019-03-13T22:27:58.531Z,1552516078.531 [Micromodem](INFO): Starting
2019-03-13T22:27:58.934Z,1552516078.934 [Micromodem](INFO): Starting
2019-03-13T22:27:58.935Z,1552516078.935 [Micromodem](IMPORTANT): CACFG stsring$CACFG,SRC,1*33

2019-03-13T22:27:58.935Z,1552516078.935 [Micromodem](INFO): Nmea out: $CCCFG,REV,1*32

2019-03-13T22:27:58.936Z,1552516078.936 [NAL9602](INFO): NAL9602 initialized
2019-03-13T22:27:59.338Z,1552516079.338 [Micromodem](INFO): Starting
2019-03-13T22:27:59.742Z,1552516079.742 [Micromodem](INFO): Starting
2019-03-13T22:27:59.757Z,1552516079.757 [NAL9602](DEBUG): Fix Requested
2019-03-13T22:28:00.146Z,1552516080.146 [Micromodem](INFO): Starting
2019-03-13T22:28:00.550Z,1552516080.550 [Micromodem](INFO): Starting
2019-03-13T22:28:00.954Z,1552516080.954 [Micromodem](INFO): Starting
2019-03-13T22:28:01.358Z,1552516081.358 [Micromodem](INFO): Starting
2019-03-13T22:28:01.434Z,1552516081.434 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.009462
CHAN A1 (24V): -0.027470
CHAN A2 (12V): -0.007065
CHAN A3 (5V): -0.001615
CHAN B0 (3.3V): 0.000079
CHAN B1 (3.15aV): 0.000439
CHAN B2 (3.15bV): 0.000220
CHAN B3 (GND): 0.002245
OPEN: 0.006733
Full Scale Calc: 4.765 mA, -1.589 mA
2019-03-13T22:28:01.770Z,1552516081.770 [Micromodem](INFO): Starting
2019-03-13T22:28:02.183Z,1552516082.183 [Micromodem](INFO): Starting
2019-03-13T22:28:02.570Z,1552516082.570 [Micromodem](INFO): Starting
2019-03-13T22:28:02.975Z,1552516082.975 [Micromodem](INFO): Starting
2019-03-13T22:28:03.380Z,1552516083.380 [Micromodem](INFO): Starting
2019-03-13T22:28:03.784Z,1552516083.784 [Micromodem](INFO): Starting
2019-03-13T22:28:04.188Z,1552516084.188 [Micromodem](INFO): Starting
2019-03-13T22:28:04.590Z,1552516084.590 [Micromodem](INFO): Starting
2019-03-13T22:28:04.996Z,1552516084.996 [Micromodem](INFO): Starting
2019-03-13T22:28:05.400Z,1552516085.400 [Micromodem](INFO): Starting
2019-03-13T22:28:05.804Z,1552516085.804 [Micromodem](INFO): Starting
2019-03-13T22:28:06.206Z,1552516086.206 [DUSBL_Hydroid](FAULT): failed to initialize; deviceResponse_ loaded: , available: 0
2019-03-13T22:28:06.207Z,1552516086.207 [DUSBL_Hydroid] Communications Fault, FailCount= 2
2019-03-13T22:28:06.207Z,1552516086.207 [DUSBL_Hydroid](ERROR): Communications Fault
2019-03-13T22:28:06.207Z,1552516086.207 [Micromodem](INFO): Starting
2019-03-13T22:28:06.706Z,1552516086.706 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid
2019-03-13T22:28:06.714Z,1552516086.714 [DUSBL_Hydroid](INFO): Powering down
2019-03-13T22:28:06.715Z,1552516086.715 [Micromodem](INFO): Starting
2019-03-13T22:28:07.118Z,1552516087.118 [Micromodem](INFO): Starting
2019-03-13T22:28:07.534Z,1552516087.534 [Micromodem](INFO): Starting
2019-03-13T22:28:07.702Z,1552516087.702 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid
2019-03-13T22:28:07.702Z,1552516087.702 [DUSBL_Hydroid] No Fault, FailCount= 2
2019-03-13T22:28:07.926Z,1552516087.926 [Micromodem](INFO): Starting
2019-03-13T22:28:08.330Z,1552516088.330 [Micromodem](INFO): Starting
2019-03-13T22:28:08.739Z,1552516088.739 [Micromodem](INFO): Starting
2019-03-13T22:28:08.740Z,1552516088.740 [Micromodem](IMPORTANT): CACFG stsring$CACFG,REV,1*30

2019-03-13T22:28:08.740Z,1552516088.740 [Micromodem](INFO): Nmea out: $CCCFG,RXP,1*29

2019-03-13T22:28:09.138Z,1552516089.138 [Micromodem](INFO): Starting
2019-03-13T22:28:09.542Z,1552516089.542 [Micromodem](INFO): Starting
2019-03-13T22:28:09.543Z,1552516089.543 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAEV,22289,COPROC,0.10.0.46*43

2019-03-13T22:28:09.946Z,1552516089.946 [DUSBL_Hydroid](INFO): Powering up
2019-03-13T22:28:09.946Z,1552516089.946 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-03-13T22:28:09.961Z,1552516089.961 [Micromodem](INFO): Starting
2019-03-13T22:28:10.350Z,1552516090.350 [Micromodem](INFO): Starting
2019-03-13T22:28:10.754Z,1552516090.754 [Micromodem](INFO): Starting
2019-03-13T22:28:11.158Z,1552516091.158 [Micromodem](INFO): Starting
2019-03-13T22:28:11.562Z,1552516091.562 [Micromodem](INFO): Starting
2019-03-13T22:28:11.966Z,1552516091.966 [Micromodem](INFO): Starting
2019-03-13T22:28:12.372Z,1552516092.372 [Micromodem](INFO): Starting
2019-03-13T22:28:12.775Z,1552516092.775 [Micromodem](INFO): Starting
2019-03-13T22:28:13.179Z,1552516093.179 [Micromodem](INFO): Starting
2019-03-13T22:28:13.582Z,1552516093.582 [Micromodem](INFO): Starting
2019-03-13T22:28:13.986Z,1552516093.986 [Micromodem](INFO): Starting
2019-03-13T22:28:14.391Z,1552516094.391 [Micromodem](INFO): Starting
2019-03-13T22:28:14.802Z,1552516094.802 [Micromodem](INFO): Starting
2019-03-13T22:28:15.198Z,1552516095.198 [Micromodem](INFO): Starting
2019-03-13T22:28:15.602Z,1552516095.602 [Micromodem](INFO): Starting
2019-03-13T22:28:16.006Z,1552516096.006 [Micromodem](INFO): Starting
2019-03-13T22:28:16.410Z,1552516096.410 [Micromodem](INFO): Starting
2019-03-13T22:28:16.814Z,1552516096.814 [Micromodem](INFO): Starting
2019-03-13T22:28:17.218Z,1552516097.218 [Micromodem](INFO): Starting
2019-03-13T22:28:17.622Z,1552516097.622 [Micromodem](INFO): Starting
2019-03-13T22:28:18.026Z,1552516098.026 [Micromodem](INFO): Starting
2019-03-13T22:28:18.430Z,1552516098.430 [Micromodem](INFO): Starting
2019-03-13T22:28:18.834Z,1552516098.834 [Micromodem](INFO): Starting
2019-03-13T22:28:18.835Z,1552516098.835 [Micromodem](IMPORTANT): CACFG stsring$CACFG,RXP,1*2B

2019-03-13T22:28:18.836Z,1552516098.836 [Micromodem](DEBUG): Nmea buf: $CCTMS,2019-03-13T22:28:19Z,0*7D

2019-03-13T22:28:18.836Z,1552516098.836 [Micromodem](IMPORTANT): Nmea buf: $CCTMS,2019-03-13T22:28:19Z,0*7D

2019-03-13T22:28:18.836Z,1552516098.836 [Micromodem](INFO): Nmea out: $CCTMS,2019-03-13T22:28:19Z,0*7D

2019-03-13T22:28:18.838Z,1552516098.838 [CommandLine](IMPORTANT): got command failComponent
2019-03-13T22:28:18.838Z,1552516098.838 [CommandLine](IMPORTANT): Failed components:
2019-03-13T22:28:18.838Z,1552516098.838 [CommandLine](IMPORTANT): Aanderaa_O2: Communications Fault
2019-03-13T22:28:19.239Z,1552516099.239 [Micromodem](INFO): Starting
2019-03-13T22:28:19.643Z,1552516099.643 [Micromodem](INFO): Starting
2019-03-13T22:28:20.043Z,1552516100.043 [Micromodem](INFO): Starting
2019-03-13T22:28:20.450Z,1552516100.450 [Micromodem](INFO): Starting
2019-03-13T22:28:30.146Z,1552516110.146 [DUSBL_Hydroid](FAULT): failed to initialize; deviceResponse_ loaded: , available: 0
2019-03-13T22:28:30.146Z,1552516110.146 [DUSBL_Hydroid] Communications Fault, FailCount= 3
2019-03-13T22:28:30.146Z,1552516110.146 [DUSBL_Hydroid](ERROR): Communications Fault
2019-03-13T22:28:30.228Z,1552516110.228 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid
2019-03-13T22:28:30.554Z,1552516110.554 [DUSBL_Hydroid](INFO): Powering down
2019-03-13T22:28:31.394Z,1552516111.394 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid
2019-03-13T22:28:31.394Z,1552516111.394 [DUSBL_Hydroid] No Fault, FailCount= 3
2019-03-13T22:28:33.870Z,1552516113.870 [DUSBL_Hydroid](INFO): Powering up
2019-03-13T22:28:33.870Z,1552516113.870 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-03-13T22:28:35.540Z,1552516115.540 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2019-03-13T22:28:35.540Z,1552516115.540 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,       -0.25,       -0.21 +0.13,   9.54,  0.00
2019-03-13T22:28:36.523Z,1552516116.523 [CommandLine](IMPORTANT): got command configSet Aanderaa_O2.loadAtStartup 0.000000 bool persist
2019-03-13T22:28:36.524Z,1552516116.524 [CommandLine](IMPORTANT): configSet Aanderaa_O2.loadAtStartup requires a restart to take effect.
2019-03-13T22:28:44.406Z,1552516124.406 [SBIT](IMPORTANT): SBIT PASSED
2019-03-13T22:28:44.454Z,1552516124.454 [CommandLine](IMPORTANT): got command configSet list
2019-03-13T22:28:44.454Z,1552516124.454 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-03-13T22:28:44.455Z,1552516124.455 [CommandLine](IMPORTANT): AHRS_M2.loadAtStartup=0 bool;

2019-03-13T22:28:44.455Z,1552516124.455 [CommandLine](IMPORTANT): Aanderaa_O2.loadAtStartup=0 bool;

2019-03-13T22:28:44.824Z,1552516124.824 [MissionManager](IMPORTANT): Started mission Startup
2019-03-13T22:28:44.824Z,1552516124.824 [Startup] Running Loop=1
2019-03-13T22:28:44.824Z,1552516124.824 [Startup](DEBUG): Aggregate::initialize Startup
2019-03-13T22:28:44.824Z,1552516124.824 [Startup:A.GoToSurface] Running Loop=1
2019-03-13T22:28:44.824Z,1552516124.824 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-03-13T22:28:44.826Z,1552516124.826 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-03-13T22:28:44.826Z,1552516124.826 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-03-13T22:28:44.827Z,1552516124.827 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-03-13T22:28:44.827Z,1552516124.827 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-03-13T22:28:44.827Z,1552516124.827 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-03-13T22:28:44.829Z,1552516124.829 [Startup:StartupSatComms] Running Loop=1
2019-03-13T22:28:44.829Z,1552516124.829 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-03-13T22:28:44.829Z,1552516124.829 [Startup:StartupSatComms:A] Running Loop=1
2019-03-13T22:28:45.231Z,1552516125.231 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-03-13T22:28:47.709Z,1552516127.709 [CommandLine](IMPORTANT): got command failComponent
2019-03-13T22:28:47.710Z,1552516127.710 [CommandLine](IMPORTANT): Failed components:
2019-03-13T22:28:47.710Z,1552516127.710 [CommandLine](IMPORTANT): Aanderaa_O2: Communications Fault
2019-03-13T22:28:53.018Z,1552516133.018 [CommandLine](IMPORTANT): got command show variable DUSBL
2019-03-13T22:28:53.055Z,1552516133.055 [CommandLine](IMPORTANT): DUSBL_Hydroid.loadAtStartup (bool)
2019-03-13T22:28:53.056Z,1552516133.056 [CommandLine](IMPORTANT): DUSBL_Hydroid.simulateHardware (bool)
2019-03-13T22:28:53.056Z,1552516133.056 [CommandLine](IMPORTANT): DUSBL_Hydroid.defaultTurnAroundTime (meter_per_second)
2019-03-13T22:28:53.056Z,1552516133.056 [CommandLine](IMPORTANT): DUSBL_Hydroid.defaultSoundSpeed (meter_per_second)
2019-03-13T22:28:53.057Z,1552516133.057 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold (count)
2019-03-13T22:28:53.057Z,1552516133.057 [CommandLine](IMPORTANT): DUSBL_Hydroid.recieveTimeout (millisecond)
2019-03-13T22:28:53.058Z,1552516133.058 [CommandLine](IMPORTANT): DUSBL_Hydroid.transmitLockout (millisecond)
2019-03-13T22:28:53.058Z,1552516133.058 [CommandLine](IMPORTANT): DUSBL_Hydroid.transponderCode (enum)
2019-03-13T22:28:53.058Z,1552516133.058 [CommandLine](IMPORTANT): DUSBL_Hydroid.numberOfPingsRequested (count)
2019-03-13T22:28:53.059Z,1552516133.059 [CommandLine](IMPORTANT): DUSBL_Hydroid.verbosity (enum)
2019-03-13T22:28:53.059Z,1552516133.059 [CommandLine](IMPORTANT): DUSBL_Hydroid.xCenter (angular_degree)
2019-03-13T22:28:53.059Z,1552516133.059 [CommandLine](IMPORTANT): DUSBL_Hydroid.yCenter (angular_degree)
2019-03-13T22:28:53.095Z,1552516133.095 [CommandLine](IMPORTANT): DUSBL_Hydroid.loadControl (none)
2019-03-13T22:28:53.096Z,1552516133.096 [CommandLine](IMPORTANT): DUSBL_Hydroid.uart (none)
2019-03-13T22:28:53.096Z,1552516133.096 [CommandLine](IMPORTANT): DUSBL_Hydroid.baud (bit_per_second)
2019-03-13T22:28:53.103Z,1552516133.103 [CommandLine](IMPORTANT): Micromodem.dusblPingCode (enum)
2019-03-13T22:28:53.104Z,1552516133.104 [CommandLine](IMPORTANT): DUSBL_Hydroid.xAngle (angular_degree)
2019-03-13T22:28:53.104Z,1552516133.104 [CommandLine](IMPORTANT): DUSBL_Hydroid.yAngle (angular_degree)
2019-03-13T22:28:53.109Z,1552516133.109 [CommandLine](IMPORTANT): DUSBL_Hydroid.travelTime (microsecond)
2019-03-13T22:28:53.109Z,1552516133.109 [CommandLine](IMPORTANT): DUSBL_Hydroid.latency (microsecond)
2019-03-13T22:28:53.110Z,1552516133.110 [CommandLine](IMPORTANT): DUSBL_Hydroid.gain (ratio)
2019-03-13T22:28:53.110Z,1552516133.110 [CommandLine](IMPORTANT): DUSBL_Hydroid.inBandSignalToNoise (ratio)
2019-03-13T22:28:53.110Z,1552516133.110 [CommandLine](IMPORTANT): DUSBL_Hydroid.outBandSignalToNoise (ratio)
2019-03-13T22:28:53.111Z,1552516133.111 [CommandLine](IMPORTANT): DUSBL_Hydroid.range (meter)
2019-03-13T22:28:53.111Z,1552516133.111 [CommandLine](IMPORTANT): DUSBL_Hydroid.direction (none)
2019-03-13T22:28:53.160Z,1552516133.160 [CommandLine](IMPORTANT): DUSBL_Hydroid.component_voltage (volt)
2019-03-13T22:28:53.161Z,1552516133.161 [CommandLine](IMPORTANT): DUSBL_Hydroid.component_avgVoltage (volt)
2019-03-13T22:28:53.161Z,1552516133.161 [CommandLine](IMPORTANT): DUSBL_Hydroid.component_current (milliampere)
2019-03-13T22:28:53.162Z,1552516133.162 [CommandLine](IMPORTANT): DUSBL_Hydroid.component_avgCurrent (milliampere)
2019-03-13T22:28:54.071Z,1552516134.071 [DUSBL_Hydroid](FAULT): failed to initialize; deviceResponse_ loaded: , available: 0
2019-03-13T22:28:54.071Z,1552516134.071 [DUSBL_Hydroid] Communications Fault, FailCount= 4
2019-03-13T22:28:54.071Z,1552516134.071 [DUSBL_Hydroid](ERROR): Communications Fault
2019-03-13T22:28:54.142Z,1552516134.142 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid
2019-03-13T22:28:54.475Z,1552516134.475 [DUSBL_Hydroid](INFO): Powering down
2019-03-13T22:28:55.319Z,1552516135.319 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid
2019-03-13T22:28:55.319Z,1552516135.319 [DUSBL_Hydroid] No Fault, FailCount= 4
2019-03-13T22:28:57.706Z,1552516137.706 [DUSBL_Hydroid](INFO): Powering up
2019-03-13T22:28:57.706Z,1552516137.706 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-03-13T22:29:04.059Z,1552516144.059 [CommandLine](IMPORTANT): got command report touch DUSBL_Hydroid.range
2019-03-13T22:29:11.554Z,1552516151.554 [CommandLine](IMPORTANT): got command report touch DUSBL_Hydroid.xAngle
2019-03-13T22:29:15.586Z,1552516155.586 [CommandLine](IMPORTANT): got command failComponent
2019-03-13T22:29:15.586Z,1552516155.586 [CommandLine](IMPORTANT): Failed components:
2019-03-13T22:29:15.586Z,1552516155.586 [CommandLine](IMPORTANT): Aanderaa_O2: Communications Fault
2019-03-13T22:29:17.907Z,1552516157.907 [DUSBL_Hydroid](FAULT): failed to initialize; deviceResponse_ loaded: , available: 0
2019-03-13T22:29:17.907Z,1552516157.907 [DUSBL_Hydroid] Communications Fault, FailCount= 5
2019-03-13T22:29:17.907Z,1552516157.907 [DUSBL_Hydroid](ERROR): Communications Fault
2019-03-13T22:29:17.967Z,1552516157.967 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid
2019-03-13T22:29:18.310Z,1552516158.310 [DUSBL_Hydroid](INFO): Powering down
2019-03-13T22:29:19.155Z,1552516159.155 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid
2019-03-13T22:29:19.155Z,1552516159.155 [DUSBL_Hydroid] No Fault, FailCount= 5
2019-03-13T22:29:21.546Z,1552516161.546 [DUSBL_Hydroid](INFO): Powering up
2019-03-13T22:29:21.546Z,1552516161.546 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-03-13T22:29:21.972Z,1552516161.972 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-13T22:29:21.972Z,1552516161.972 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-03-13T22:29:21.972Z,1552516161.972 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-13T22:29:21.974Z,1552516161.974 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-13T22:29:21.974Z,1552516161.974 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-03-13T22:29:21.974Z,1552516161.974 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-13T22:29:21.975Z,1552516161.975 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-13T22:29:21.975Z,1552516161.975 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2019-03-13T22:29:21.975Z,1552516161.975 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-13T22:29:22.026Z,1552516162.026 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-13T22:29:22.026Z,1552516162.026 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-13T22:29:22.027Z,1552516162.027 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-13T22:29:22.386Z,1552516162.386 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-13T22:29:22.386Z,1552516162.386 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-03-13T22:29:22.386Z,1552516162.386 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-13T22:29:22.386Z,1552516162.386 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-03-13T22:29:22.387Z,1552516162.387 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-13T22:29:22.387Z,1552516162.387 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2019-03-13T22:29:22.858Z,1552516162.858 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-13T22:29:37.233Z,1552516177.233 [CommandLine](IMPORTANT): got command failComponent
2019-03-13T22:29:37.234Z,1552516177.234 [CommandLine](IMPORTANT): Failed components:
2019-03-13T22:29:37.234Z,1552516177.234 [CommandLine](IMPORTANT): Aanderaa_O2: Communications Fault
2019-03-13T22:29:41.746Z,1552516181.746 [DUSBL_Hydroid](FAULT): failed to initialize; deviceResponse_ loaded: , available: 0
2019-03-13T22:29:41.746Z,1552516181.746 [DUSBL_Hydroid] Communications Fault, FailCount= 6
2019-03-13T22:29:41.747Z,1552516181.747 [DUSBL_Hydroid](ERROR): Communications Fault
2019-03-13T22:29:41.817Z,1552516181.817 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid
2019-03-13T22:29:42.150Z,1552516182.150 [DUSBL_Hydroid](INFO): Powering down
2019-03-13T22:29:43.012Z,1552516183.012 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid
2019-03-13T22:29:43.012Z,1552516183.012 [DUSBL_Hydroid] No Fault, FailCount= 6
2019-03-13T22:29:45.014Z,1552516185.014 [Startup:StartupSatComms:A](INFO): Timed out from 2019-03-13T22:28:44.8Z
2019-03-13T22:29:45.014Z,1552516185.014 [Startup:StartupSatComms:A] Stopped
2019-03-13T22:29:45.014Z,1552516185.014 [Startup:StartupSatComms:B] Running Loop=1
2019-03-13T22:29:45.382Z,1552516185.382 [DUSBL_Hydroid](INFO): Powering up
2019-03-13T22:29:45.382Z,1552516185.382 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-03-13T22:29:45.441Z,1552516185.441 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-03-13T22:29:49.619Z,1552516189.619 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004469
2019-03-13T22:29:59.528Z,1552516199.528 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190312T001146/Courier0804.lzma
2019-03-13T22:30:00.335Z,1552516200.335 [DataOverHttps](INFO): Moved sent file to Logs/20190312T001146/Courier0804.lzma.bak
2019-03-13T22:30:00.335Z,1552516200.335 [DataOverHttps](INFO): SBD MOMSN=10135408
2019-03-13T22:30:05.456Z,1552516205.456 [DUSBL_Hydroid](FAULT): failed to initialize; deviceResponse_ loaded: , available: 0
2019-03-13T22:30:05.456Z,1552516205.456 [DUSBL_Hydroid] Communications Fault, FailCount= 7
2019-03-13T22:30:05.456Z,1552516205.456 [DUSBL_Hydroid](ERROR): Communications Fault
2019-03-13T22:30:05.718Z,1552516205.718 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid
2019-03-13T22:30:05.838Z,1552516205.838 [DUSBL_Hydroid](INFO): Powering down
2019-03-13T22:30:06.824Z,1552516206.824 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid
2019-03-13T22:30:06.824Z,1552516206.824 [DUSBL_Hydroid] No Fault, FailCount= 7
2019-03-13T22:30:09.098Z,1552516209.098 [DUSBL_Hydroid](INFO): Powering up
2019-03-13T22:30:09.098Z,1552516209.098 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-03-13T22:30:20.548Z,1552516220.548 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20190312T001146/Express0805.lzma
2019-03-13T22:30:21.355Z,1552516221.355 [DataOverHttps](INFO): Moved sent file to Logs/20190312T001146/Express0805.lzma.bak
2019-03-13T22:30:21.355Z,1552516221.355 [DataOverHttps](INFO): SBD MOMSN=10135410
2019-03-13T22:30:26.546Z,1552516226.546 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-03-13T22:30:26.546Z,1552516226.546 [Aanderaa_O2] No Fault, FailCount= 3
2019-03-13T22:30:29.298Z,1552516229.298 [DUSBL_Hydroid](FAULT): failed to initialize; deviceResponse_ loaded: , available: 0
2019-03-13T22:30:29.298Z,1552516229.298 [DUSBL_Hydroid] Communications Fault, FailCount= 8
2019-03-13T22:30:29.298Z,1552516229.298 [DUSBL_Hydroid](ERROR): Communications Fault
2019-03-13T22:30:29.367Z,1552516229.367 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid
2019-03-13T22:30:29.368Z,1552516229.368 [CBIT](CRITICAL): Communications Fault in component: DUSBL_Hydroid
2019-03-13T22:30:29.702Z,1552516229.702 [DUSBL_Hydroid](INFO): Powering down
2019-03-13T22:30:29.918Z,1552516229.918 [CommandLine](FAULT): Scheduling is paused
2019-03-13T22:30:29.919Z,1552516229.919 [CBIT](INFO): Critical error at 20190313T223029
2019-03-13T22:30:29.919Z,1552516229.919 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-03-13T22:30:34.914Z,1552516234.914 [CommandLine](IMPORTANT): got command 
2019-03-13T22:30:34.915Z,1552516234.915 [CommandLine](FAULT): Incomplete syntax. Try: help
2019-03-13T22:30:35.894Z,1552516235.894 [CommandLine](IMPORTANT): got command failComponent
2019-03-13T22:30:35.895Z,1552516235.895 [CommandLine](IMPORTANT): Failed components:
2019-03-13T22:30:35.895Z,1552516235.895 [CommandLine](IMPORTANT): DUSBL_Hydroid: Communications Fault
2019-03-13T22:30:36.997Z,1552516236.997 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-03-13T22:30:36.997Z,1552516236.997 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-03-13T22:30:36.997Z,1552516236.997 [Aanderaa_O2](ERROR): Communications Fault
2019-03-13T22:30:37.024Z,1552516237.024 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-03-13T22:30:37.533Z,1552516237.533 [Aanderaa_O2](INFO): Powering down
2019-03-13T22:30:38.227Z,1552516238.227 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-03-13T22:30:38.228Z,1552516238.228 [Aanderaa_O2] No Fault, FailCount= 1
2019-03-13T22:30:38.633Z,1552516238.633 [DataOverHttps](INFO): Sending 1806 bytes from file Logs/20190313T222710/Express0001.lzma
2019-03-13T22:30:39.434Z,1552516239.434 [DataOverHttps](INFO): Moved sent file to Logs/20190313T222710/Express0001.lzma.bak
2019-03-13T22:30:39.435Z,1552516239.435 [DataOverHttps](INFO): SBD MOMSN=10135414
2019-03-13T22:30:40.262Z,1552516240.262 [Startup:StartupSatComms:B] Stopped
2019-03-13T22:30:40.262Z,1552516240.262 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-03-13T22:30:40.263Z,1552516240.263 [Startup:StartupSatComms] Stopped
2019-03-13T22:30:40.263Z,1552516240.263 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-03-13T22:30:40.263Z,1552516240.263 [Startup](INFO): Completed Startup
2019-03-13T22:30:40.264Z,1552516240.264 [MissionManager](INFO): Startup is completed.
2019-03-13T22:30:40.264Z,1552516240.264 [MissionManager](INFO): Uninitializing Mission Startup
2019-03-13T22:30:40.264Z,1552516240.264 [Startup] Stopped
2019-03-13T22:30:40.264Z,1552516240.264 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-03-13T22:30:40.264Z,1552516240.264 [Startup:A.GoToSurface] Stopped
2019-03-13T22:30:40.264Z,1552516240.264 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-03-13T22:30:40.661Z,1552516240.661 [MissionManager](IMPORTANT): Started mission Default
2019-03-13T22:30:40.661Z,1552516240.661 [Default] Running Loop=1
2019-03-13T22:30:40.661Z,1552516240.661 [Default](DEBUG): Aggregate::initialize Default
2019-03-13T22:30:40.661Z,1552516240.661 [Default:B.GoToSurface] Running Loop=1
2019-03-13T22:30:40.661Z,1552516240.661 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-03-13T22:30:40.662Z,1552516240.662 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-03-13T22:30:40.662Z,1552516240.662 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-03-13T22:30:40.662Z,1552516240.662 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-03-13T22:30:40.663Z,1552516240.663 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-03-13T22:30:40.663Z,1552516240.663 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-03-13T22:30:40.663Z,1552516240.663 [Default:A.Wait] Running Loop=1
2019-03-13T22:30:40.663Z,1552516240.663 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-03-13T22:30:40.998Z,1552516240.998 [CommandLine](IMPORTANT): got command failComponent none DUSBL_Hydroid
2019-03-13T22:30:40.998Z,1552516240.998 [DUSBL_Hydroid] No Fault, FailCount= 8
2019-03-13T22:30:40.998Z,1552516240.998 [CommandLine](IMPORTANT): DUSBL_Hydroid failureMode is No Fault
2019-03-13T22:30:41.018Z,1552516241.018 [DUSBL_Hydroid](INFO): Powering up
2019-03-13T22:30:41.019Z,1552516241.019 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-03-13T22:30:47.902Z,1552516247.902 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2019-03-13T22:30:47.902Z,1552516247.902 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,  94.92,  97.92,   0.00
2019-03-13T22:30:48.731Z,1552516248.731 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-03-13T22:30:48.731Z,1552516248.731 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-03-13T22:30:48.731Z,1552516248.731 [Aanderaa_O2](ERROR): Communications Fault
2019-03-13T22:30:48.755Z,1552516248.755 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-03-13T22:30:49.245Z,1552516249.245 [Aanderaa_O2](INFO): Powering down
2019-03-13T22:30:49.935Z,1552516249.935 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-03-13T22:30:49.935Z,1552516249.935 [Aanderaa_O2] No Fault, FailCount= 2
2019-03-13T22:30:53.969Z,1552516253.969 [Default:A.Wait](INFO): Done Waiting.
2019-03-13T22:30:53.969Z,1552516253.969 [Default:A.Wait] Stopped
2019-03-13T22:30:53.969Z,1552516253.969 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-03-13T22:30:54.374Z,1552516254.374 [Default:CheckIn] Running Loop=1
2019-03-13T22:30:54.375Z,1552516254.375 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-03-13T22:30:54.375Z,1552516254.375 [Default:CheckIn:Read_GPS] Running Loop=1
2019-03-13T22:30:54.783Z,1552516254.783 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-03-13T22:30:59.195Z,1552516259.195 [DUSBL_Hydroid](INFO): DUSBL Version:O
2019-03-13T22:31:00.428Z,1552516260.428 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-03-13T22:31:00.428Z,1552516260.428 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-03-13T22:31:00.428Z,1552516260.428 [Aanderaa_O2](ERROR): Communications Fault
2019-03-13T22:31:00.443Z,1552516260.443 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-03-13T22:31:00.444Z,1552516260.444 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-03-13T22:31:00.953Z,1552516260.953 [Aanderaa_O2](INFO): Powering down
2019-03-13T22:31:06.606Z,1552516266.606 [CommandLine](IMPORTANT): got command report
2019-03-13T22:31:06.606Z,1552516266.606 [Reporter](INFO): DUSBL_Hydroid.range reporting when touched
2019-03-13T22:31:06.606Z,1552516266.606 [Reporter](INFO): DUSBL_Hydroid.xAngle reporting when touched
2019-03-13T22:31:15.615Z,1552516275.615 [CommandLine](IMPORTANT): got command run ./Missions/Maintenance/DUSBL.xml
2019-03-13T22:31:15.615Z,1552516275.615 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/DUSBL.xml
2019-03-13T22:31:15.643Z,1552516275.643 [MissionManager](INFO): DefineArg DUSBL.MissionTimeout = 90.000000 min
2019-03-13T22:31:15.647Z,1552516275.647 [MissionManager](INFO): DefineArg DUSBL.NumberOfRequests = 10.000000 count
2019-03-13T22:31:15.667Z,1552516275.667 [MissionManager](INFO): DefineArg DUSBL.TransponderCode = 2.000000 count
2019-03-13T22:31:15.671Z,1552516275.671 [MissionManager](INFO): DefineArg DUSBL.NumberOfPings = 1.000000 count
2019-03-13T22:31:15.683Z,1552516275.683 [MissionManager](INFO): DefineArg DUSBL.EnabledDUSBL = 1.000000 bool
2019-03-13T22:31:15.684Z,1552516275.684 [DUSBL:A.Pitch](DEBUG): Construct.
2019-03-13T22:31:15.702Z,1552516275.702 [DUSBL:B.SetSpeed](DEBUG): Construct.
2019-03-13T22:31:15.720Z,1552516275.720 [DUSBL:RequestRepeater:C.Wait](DEBUG): Construct Wait.
2019-03-13T22:31:15.732Z,1552516275.732 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<!-- ====================
     Generated by TethysL
     ==================== -->
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Control="Tethys/Control"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd"
       Id="DUSBL">

    <Description>
        This mission is requests a DUSBL arming.
    </Description>

    <DefineArg Name="MissionTimeout"><Description>
        How long to let the mission run.
    </Description><Units:minute/><Value>90</Value></DefineArg>

    <DefineArg Name="NumberOfRequests"><Description>
        Number of pings to requst.
    </Description><Units:count/><Value>10</Value></DefineArg>

    <DefineArg Name="TransponderCode"><Description>
        Transponder Address.
    </Description><Units:count/><Value>2</Value></DefineArg>   

    <DefineArg Name="NumberOfPings"><Description>
        Number of pings requested each time.
    </Description><Units:count/><Value>1</Value></DefineArg>      
    
    <DefineArg Name="EnabledDUSBL"><Description>
        Automatically set to true if the DUSBL is enabled. Set to false to disable
        reading from the DUSBL.
    </Description><Science:DUSBL_Hydroid.loadAtStartup/></DefineArg>    

    <Timeout Duration="MissionTimeout"/>

    <Guidance:Pitch>
        <Parallel/>
        <Setting><Guidance:Pitch.massPosition/><Control:VerticalControl.massDefault/></Setting>
    </Guidance:Pitch>

    <Guidance:SetSpeed>
        <Parallel/>
        <Setting><Guidance:SetSpeed.speed/><Units:meter_per_second/><Value>0</Value></Setting>
    </Guidance:SetSpeed>

    <ReadData>
        <While>
            <Arg Name="EnabledDUSBL"/>
        </While>
        <Sensor:DUSBL_Hydroid.range/>
        <Sensor:DUSBL_Hydroid.direction/>
    </ReadData>
    
      <Aggregate Id="RequestRepeater">

        <Sequence Repeat="NumberOfRequests"/>
        <Assign><Parallel/><Sensor:DUSBL_Hydroid.transponderCode/><Arg Name="TransponderCode"/></Assign>
        <Assign><Parallel/><Sensor:DUSBL_Hydroid.numberOfPingsRequested/><Arg Name="NumberOfPings"/></Assign>
          <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:second/><Value>10</Value></Setting>
          </Guidance:Wait>
      </Aggregate>    
</Mission>


2019-03-13T22:31:15.738Z,1552516275.738 [CommandLine](IMPORTANT): Running ./Missions/Maintenance/DUSBL.xml
2019-03-13T22:31:15.797Z,1552516275.797 [Default] Stopped
2019-03-13T22:31:15.798Z,1552516275.798 [Default](DEBUG): Aggregate::uninitialize Default
2019-03-13T22:31:15.798Z,1552516275.798 [Default:B.GoToSurface] Stopped
2019-03-13T22:31:15.798Z,1552516275.798 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-03-13T22:31:15.798Z,1552516275.798 [Default:CheckIn] Stopped
2019-03-13T22:31:15.798Z,1552516275.798 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-03-13T22:31:15.798Z,1552516275.798 [Default:CheckIn:Read_GPS] Stopped
2019-03-13T22:31:15.798Z,1552516275.798 [MissionManager](IMPORTANT): Started mission DUSBL
2019-03-13T22:31:15.798Z,1552516275.798 [DUSBL] Running Loop=1
2019-03-13T22:31:15.799Z,1552516275.799 [DUSBL](DEBUG): Aggregate::initialize DUSBL
2019-03-13T22:31:15.799Z,1552516275.799 [DUSBL:A.Pitch] Running Loop=1
2019-03-13T22:31:15.799Z,1552516275.799 [DUSBL:A.Pitch](DEBUG): Initialize.
2019-03-13T22:31:15.799Z,1552516275.799 [DUSBL:B.SetSpeed] Running Loop=1
2019-03-13T22:31:15.799Z,1552516275.799 [DUSBL:B.SetSpeed](DEBUG): Initialize.
2019-03-13T22:31:15.799Z,1552516275.799 [DUSBL:C] Running Loop=1
2019-03-13T22:31:15.799Z,1552516275.799 [DUSBL:RequestRepeater] Running Loop=1
2019-03-13T22:31:15.800Z,1552516275.800 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-03-13T22:31:15.800Z,1552516275.800 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-13T22:31:15.800Z,1552516275.800 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-13T22:31:15.800Z,1552516275.800 [DUSBL:RequestRepeater:C.Wait] Running Loop=1
2019-03-13T22:31:15.800Z,1552516275.800 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component.
2019-03-13T22:31:15.800Z,1552516275.800 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-13T22:31:15.800Z,1552516275.800 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-13T22:31:15.805Z,1552516275.805 [DUSBL:C] Running Loop=1
2019-03-13T22:31:15.806Z,1552516275.806 [DUSBL:C](DEBUG): Initialize ReadDataComponent to sense DUSBL_Hydroid.range
2019-03-13T22:31:15.806Z,1552516275.806 [DUSBL:C](DEBUG): Initialize ReadDataComponent to sense DUSBL_Hydroid.direction
2019-03-13T22:31:15.806Z,1552516275.806 [DUSBL:B.SetSpeed] Running Loop=1
2019-03-13T22:31:15.807Z,1552516275.807 [DUSBL:A.Pitch] Running Loop=1
2019-03-13T22:31:17.376Z,1552516277.376 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-13T22:31:17.380Z,1552516277.380 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-13T22:31:17.381Z,1552516277.381 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,6F8F7A90,54,24000,0,0,0,0,1000,0*22

2019-03-13T22:31:17.381Z,1552516277.381 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,6F8F7A90,54,24000,0,0,0,0,1000,0*22

2019-03-13T22:31:17.381Z,1552516277.381 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,6F8F7A90,54,24000,0,0,0,0,1000,0*22

2019-03-13T22:31:21.415Z,1552516281.415 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-13T22:31:21.418Z,1552516281.418 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-13T22:31:21.420Z,1552516281.420 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,6F8F7A90,54,24000,0,0,0,0,1000,0*22

2019-03-13T22:31:21.420Z,1552516281.420 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,6F8F7A90,54,24000,0,0,0,0,1000,0*22

2019-03-13T22:31:21.420Z,1552516281.420 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,6F8F7A90,54,24000,0,0,0,0,1000,0*22

2019-03-13T22:31:21.897Z,1552516281.897 [Reporter](INFO): DUSBL_Hydroid.range -74949.000000 m
2019-03-13T22:31:21.898Z,1552516281.898 [Reporter](INFO): DUSBL_Hydroid.xAngle -14.700000 arcdeg
2019-03-13T22:31:23.166Z,1552516283.166 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-13T22:31:23.166Z,1552516283.166 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-03-13T22:31:23.166Z,1552516283.166 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-13T22:31:23.167Z,1552516283.167 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-13T22:31:23.167Z,1552516283.167 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2019-03-13T22:31:23.167Z,1552516283.167 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-13T22:31:23.197Z,1552516283.197 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-13T22:31:23.197Z,1552516283.197 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2019-03-13T22:31:23.197Z,1552516283.197 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-13T22:31:23.267Z,1552516283.267 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-13T22:31:23.267Z,1552516283.267 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-13T22:31:23.267Z,1552516283.267 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-13T22:31:23.543Z,1552516283.543 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-13T22:31:23.543Z,1552516283.543 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-03-13T22:31:23.543Z,1552516283.543 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-13T22:31:23.543Z,1552516283.543 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2019-03-13T22:31:23.544Z,1552516283.544 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-13T22:31:23.544Z,1552516283.544 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2019-03-13T22:31:24.159Z,1552516284.159 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-13T22:31:25.466Z,1552516285.466 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-13T22:31:25.468Z,1552516285.468 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-13T22:31:25.470Z,1552516285.470 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,6F8F7A90,54,24000,0,0,0,0,1000,0*22

2019-03-13T22:31:25.471Z,1552516285.471 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,6F8F7A90,54,24000,0,0,0,0,1000,0*22

2019-03-13T22:31:25.471Z,1552516285.471 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,6F8F7A90,54,24000,0,0,0,0,1000,0*22

2019-03-13T22:31:25.909Z,1552516285.909 [DUSBL:RequestRepeater:C.Wait](INFO): Done Waiting.
2019-03-13T22:31:25.909Z,1552516285.909 [DUSBL:RequestRepeater:C.Wait] Stopped
2019-03-13T22:31:25.909Z,1552516285.909 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-13T22:31:25.910Z,1552516285.910 [DUSBL:RequestRepeater](INFO): Completed DUSBL:RequestRepeater
2019-03-13T22:31:25.910Z,1552516285.910 [DUSBL:RequestRepeater] Stopped
2019-03-13T22:31:25.910Z,1552516285.910 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-03-13T22:31:25.910Z,1552516285.910 [DUSBL:RequestRepeater:A] Stopped
2019-03-13T22:31:25.910Z,1552516285.910 [DUSBL:RequestRepeater:B] Stopped
2019-03-13T22:31:25.910Z,1552516285.910 [DUSBL:RequestRepeater](INFO): Running loop #2
2019-03-13T22:31:25.910Z,1552516285.910 [DUSBL:RequestRepeater] Running Loop=2
2019-03-13T22:31:25.911Z,1552516285.911 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-03-13T22:31:25.911Z,1552516285.911 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-13T22:31:25.911Z,1552516285.911 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-13T22:31:25.911Z,1552516285.911 [DUSBL:RequestRepeater:C.Wait] Running Loop=1
2019-03-13T22:31:25.911Z,1552516285.911 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component.
2019-03-13T22:31:29.500Z,1552516289.500 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-13T22:31:29.505Z,1552516289.505 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-13T22:31:29.506Z,1552516289.506 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,6F8F7A90,54,24000,0,0,0,0,1000,0*22

2019-03-13T22:31:29.507Z,1552516289.507 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,6F8F7A90,54,24000,0,0,0,0,1000,0*22

2019-03-13T22:31:29.507Z,1552516289.507 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,6F8F7A90,54,24000,0,0,0,0,1000,0*22

2019-03-13T22:31:29.950Z,1552516289.950 [Reporter](INFO): DUSBL_Hydroid.range -74810.460938 m
2019-03-13T22:31:29.950Z,1552516289.950 [Reporter](INFO): DUSBL_Hydroid.xAngle -4.750000 arcdeg
2019-03-13T22:31:33.539Z,1552516293.539 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-13T22:31:33.541Z,1552516293.541 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-13T22:31:33.543Z,1552516293.543 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,6F8F7A90,54,24000,0,0,0,0,1000,0*22

2019-03-13T22:31:33.544Z,1552516293.544 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,6F8F7A90,54,24000,0,0,0,0,1000,0*22

2019-03-13T22:31:33.544Z,1552516293.544 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,6F8F7A90,54,24000,0,0,0,0,1000,0*22

2019-03-13T22:31:36.452Z,1552516296.452 [DUSBL:RequestRepeater:C.Wait](INFO): Done Waiting.
2019-03-13T22:31:36.452Z,1552516296.452 [DUSBL:RequestRepeater:C.Wait] Stopped
2019-03-13T22:31:36.452Z,1552516296.452 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-13T22:31:36.453Z,1552516296.453 [DUSBL:RequestRepeater](INFO): Completed DUSBL:RequestRepeater
2019-03-13T22:31:36.453Z,1552516296.453 [DUSBL:RequestRepeater] Stopped
2019-03-13T22:31:36.453Z,1552516296.453 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-03-13T22:31:36.453Z,1552516296.453 [DUSBL:RequestRepeater:A] Stopped
2019-03-13T22:31:36.454Z,1552516296.454 [DUSBL:RequestRepeater:B] Stopped
2019-03-13T22:31:36.454Z,1552516296.454 [DUSBL:RequestRepeater](INFO): Running loop #3
2019-03-13T22:31:36.454Z,1552516296.454 [DUSBL:RequestRepeater] Running Loop=3
2019-03-13T22:31:36.454Z,1552516296.454 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-03-13T22:31:36.454Z,1552516296.454 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-13T22:31:36.454Z,1552516296.454 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-13T22:31:36.454Z,1552516296.454 [DUSBL:RequestRepeater:C.Wait] Running Loop=1
2019-03-13T22:31:36.454Z,1552516296.454 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component.
2019-03-13T22:31:37.580Z,1552516297.580 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-13T22:31:37.582Z,1552516297.582 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-13T22:31:37.584Z,1552516297.584 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,6F8F7A90,54,24000,0,0,0,0,1000,0*22

2019-03-13T22:31:37.584Z,1552516297.584 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,6F8F7A90,54,24000,0,0,0,0,1000,0*22

2019-03-13T22:31:37.584Z,1552516297.584 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,6F8F7A90,54,24000,0,0,0,0,1000,0*22

2019-03-13T22:31:38.790Z,1552516298.790 [NAL9602](INFO): SBD MO Status=0, MOMSN=2263, MT Status=0, MTMSN=0
2019-03-13T22:31:38.790Z,1552516298.790 [NAL9602](INFO): No messages in MT queue
2019-03-13T22:31:39.609Z,1552516299.609 [NAL9602](DEBUG): Fix Requested
2019-03-13T22:31:41.615Z,1552516301.615 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-13T22:31:41.620Z,1552516301.620 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-13T22:31:41.620Z,1552516301.620 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,6F8F7A90,54,24000,0,0,0,0,1000,0*22

2019-03-13T22:31:41.620Z,1552516301.620 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,6F8F7A90,54,24000,0,0,0,0,1000,0*22

2019-03-13T22:31:41.620Z,1552516301.620 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,6F8F7A90,54,24000,0,0,0,0,1000,0*22

2019-03-13T22:31:42.037Z,1552516302.037 [NAL9602](DEBUG): Fix Requested
2019-03-13T22:31:42.073Z,1552516302.073 [Reporter](INFO): DUSBL_Hydroid.range -74810.460938 m
2019-03-13T22:31:42.074Z,1552516302.074 [Reporter](INFO): DUSBL_Hydroid.xAngle -10.650000 arcdeg
2019-03-13T22:31:45.265Z,1552516305.265 [NAL9602](DEBUG): Fix Requested
2019-03-13T22:31:45.659Z,1552516305.659 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-13T22:31:45.662Z,1552516305.662 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-13T22:31:45.664Z,1552516305.664 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,6F8F7A90,54,24000,0,0,0,0,1000,0*22

2019-03-13T22:31:45.664Z,1552516305.664 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,6F8F7A90,54,24000,0,0,0,0,1000,0*22

2019-03-13T22:31:45.664Z,1552516305.664 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,6F8F7A90,54,24000,0,0,0,0,1000,0*22

2019-03-13T22:31:46.120Z,1552516306.120 [Reporter](INFO): DUSBL_Hydroid.range -74804.343750 m
2019-03-13T22:31:46.121Z,1552516306.121 [Reporter](INFO): DUSBL_Hydroid.xAngle -26.300000 arcdeg
2019-03-13T22:31:46.894Z,1552516306.894 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-03-13T22:31:46.894Z,1552516306.894 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,19031315354114,35.0, -0.11448.9,  0
2019-03-13T22:31:46.902Z,1552516306.902 [DUSBL:RequestRepeater:C.Wait](INFO): Done Waiting.
2019-03-13T22:31:46.902Z,1552516306.902 [DUSBL:RequestRepeater:C.Wait] Stopped
2019-03-13T22:31:46.902Z,1552516306.902 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-13T22:31:46.903Z,1552516306.903 [DUSBL:RequestRepeater](INFO): Completed DUSBL:RequestRepeater
2019-03-13T22:31:46.903Z,1552516306.903 [DUSBL:RequestRepeater] Stopped
2019-03-13T22:31:46.903Z,1552516306.903 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-03-13T22:31:46.903Z,1552516306.903 [DUSBL:RequestRepeater:A] Stopped
2019-03-13T22:31:46.903Z,1552516306.903 [DUSBL:RequestRepeater:B] Stopped
2019-03-13T22:31:46.904Z,1552516306.904 [DUSBL:RequestRepeater](INFO): Running loop #4
2019-03-13T22:31:46.904Z,1552516306.904 [DUSBL:RequestRepeater] Running Loop=4
2019-03-13T22:31:46.904Z,1552516306.904 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-03-13T22:31:46.904Z,1552516306.904 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-13T22:31:46.904Z,1552516306.904 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-13T22:31:46.904Z,1552516306.904 [DUSBL:RequestRepeater:C.Wait] Running Loop=1
2019-03-13T22:31:46.904Z,1552516306.904 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component.
2019-03-13T22:31:48.093Z,1552516308.093 [NAL9602](DEBUG): Fix Requested
2019-03-13T22:31:48.582Z,1552516308.582 [CommandLine](IMPORTANT): got command stop
2019-03-13T22:31:48.582Z,1552516308.582 [CommandLine](IMPORTANT): Scheduling is paused
2019-03-13T22:31:48.582Z,1552516308.582 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop
2019-03-13T22:31:48.931Z,1552516308.931 [MissionManager](INFO): MissionManager is completed.
2019-03-13T22:31:48.931Z,1552516308.931 [MissionManager](INFO): Uninitializing Mission DUSBL
2019-03-13T22:31:48.931Z,1552516308.931 [DUSBL] Stopped
2019-03-13T22:31:48.931Z,1552516308.931 [DUSBL](DEBUG): Aggregate::uninitialize DUSBL
2019-03-13T22:31:48.931Z,1552516308.931 [DUSBL:A.Pitch] Stopped
2019-03-13T22:31:48.931Z,1552516308.931 [DUSBL:B.SetSpeed] Stopped
2019-03-13T22:31:48.932Z,1552516308.932 [DUSBL:B.SetSpeed](DEBUG): Uninitialize.
2019-03-13T22:31:48.932Z,1552516308.932 [DUSBL:C] Stopped
2019-03-13T22:31:48.932Z,1552516308.932 [DUSBL:RequestRepeater] Stopped
2019-03-13T22:31:48.932Z,1552516308.932 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-03-13T22:31:48.932Z,1552516308.932 [DUSBL:RequestRepeater:A] Stopped
2019-03-13T22:31:48.932Z,1552516308.932 [DUSBL:RequestRepeater:B] Stopped
2019-03-13T22:31:48.932Z,1552516308.932 [DUSBL:RequestRepeater:C.Wait] Stopped
2019-03-13T22:31:48.932Z,1552516308.932 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-13T22:31:49.322Z,1552516309.322 [MissionManager](IMPORTANT): Started mission Default
2019-03-13T22:31:49.322Z,1552516309.322 [Default] Running Loop=1
2019-03-13T22:31:49.322Z,1552516309.322 [Default](DEBUG): Aggregate::initialize Default
2019-03-13T22:31:49.322Z,1552516309.322 [Default:B.GoToSurface] Running Loop=1
2019-03-13T22:31:49.322Z,1552516309.322 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-03-13T22:31:49.323Z,1552516309.323 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-03-13T22:31:49.323Z,1552516309.323 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-03-13T22:31:49.323Z,1552516309.323 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-03-13T22:31:49.324Z,1552516309.324 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-03-13T22:31:49.324Z,1552516309.324 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-03-13T22:31:49.324Z,1552516309.324 [Default:A.Wait] Running Loop=1
2019-03-13T22:31:49.325Z,1552516309.325 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-03-13T22:31:50.925Z,1552516310.925 [NAL9602](DEBUG): Fix Requested
2019-03-13T22:31:54.157Z,1552516314.157 [NAL9602](DEBUG): Fix Requested
2019-03-13T22:31:56.983Z,1552516316.983 [NAL9602](DEBUG): Fix Requested
2019-03-13T22:32:00.213Z,1552516320.213 [NAL9602](DEBUG): Fix Requested
2019-03-13T22:32:02.659Z,1552516322.659 [Default:A.Wait](INFO): Done Waiting.
2019-03-13T22:32:02.659Z,1552516322.659 [Default:A.Wait] Stopped
2019-03-13T22:32:02.659Z,1552516322.659 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-03-13T22:32:03.045Z,1552516323.045 [NAL9602](DEBUG): Fix Requested
2019-03-13T22:32:03.132Z,1552516323.132 [Default:CheckIn] Running Loop=1
2019-03-13T22:32:03.132Z,1552516323.132 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-03-13T22:32:03.132Z,1552516323.132 [Default:CheckIn:Read_GPS] Running Loop=1
2019-03-13T22:32:06.275Z,1552516326.275 [NAL9602](DEBUG): Fix Requested
2019-03-13T22:32:06.668Z,1552516326.668 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222740.00,A,3648.15821,N,12147.28644,W,2.819,0.00,130319,,,A*7D
2019-03-13T22:32:06.672Z,1552516326.672 [NAL9602](INFO): GPS fix at 20190313T222740: (36.802637, -121.788107)
2019-03-13T22:32:06.752Z,1552516326.752 [Default:CheckIn:Read_GPS] Stopped
2019-03-13T22:32:06.752Z,1552516326.752 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-03-13T22:32:07.118Z,1552516327.118 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-03-13T22:32:14.177Z,1552516334.177 [DataOverHttps](INFO): Sending 279 bytes from file Logs/20190313T222710/Courier0004.lzma
2019-03-13T22:32:14.982Z,1552516334.982 [DataOverHttps](INFO): Moved sent file to Logs/20190313T222710/Courier0004.lzma.bak
2019-03-13T22:32:14.982Z,1552516334.982 [DataOverHttps](INFO): SBD MOMSN=10135525
2019-03-13T22:32:27.146Z,1552516347.146 [DataOverHttps](INFO): Sending 746 bytes from file Logs/20190313T222710/Express0005.lzma
2019-03-13T22:32:27.950Z,1552516347.950 [DataOverHttps](INFO): Moved sent file to Logs/20190313T222710/Express0005.lzma.bak
2019-03-13T22:32:27.950Z,1552516347.950 [DataOverHttps](INFO): SBD MOMSN=10135529
2019-03-13T22:32:29.016Z,1552516349.016 [Default:CheckIn:Read_Iridium] Stopped
2019-03-13T22:32:29.016Z,1552516349.016 [Default:CheckIn:C.Wait] Running Loop=1
2019-03-13T22:32:29.016Z,1552516349.016 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-03-13T22:32:39.074Z,1552516359.074 [NAL9602](INFO): Powering down
2019-03-13T22:33:24.372Z,1552516404.372 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-13T22:33:24.372Z,1552516404.372 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-03-13T22:33:24.372Z,1552516404.372 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-13T22:33:24.374Z,1552516404.374 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-13T22:33:24.374Z,1552516404.374 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-03-13T22:33:24.374Z,1552516404.374 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-13T22:33:24.376Z,1552516404.376 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-13T22:33:24.376Z,1552516404.376 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2019-03-13T22:33:24.376Z,1552516404.376 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-13T22:33:24.387Z,1552516404.387 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-13T22:33:24.388Z,1552516404.388 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-13T22:33:24.388Z,1552516404.388 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-13T22:33:24.836Z,1552516404.836 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-13T22:33:24.836Z,1552516404.836 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-03-13T22:33:24.836Z,1552516404.836 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-13T22:33:24.837Z,1552516404.837 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-03-13T22:33:24.837Z,1552516404.837 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-13T22:33:24.837Z,1552516404.837 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2019-03-13T22:33:25.176Z,1552516405.176 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-13T22:33:30.430Z,1552516410.430 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-03-13T22:33:30.430Z,1552516410.430 [Aanderaa_O2] No Fault, FailCount= 3
2019-03-13T22:33:39.278Z,1552516419.278 [Micromodem](FAULT): LCB 2 fault: 
2019-03-13T22:33:39.278Z,1552516419.278 [Micromodem] Hardware Fault, FailCount= 1
2019-03-13T22:33:39.278Z,1552516419.278 [Micromodem](ERROR): Hardware Fault
2019-03-13T22:33:39.279Z,1552516419.279 [Micromodem](INFO): Stop
2019-03-13T22:33:39.279Z,1552516419.279 [Micromodem](INFO): uninitialize
2019-03-13T22:33:39.279Z,1552516419.279 [Micromodem](INFO): Powering down
2019-03-13T22:33:39.320Z,1552516419.320 [CBIT](ERROR): Hardware Fault in component: Micromodem
2019-03-13T22:33:39.678Z,1552516419.678 [Micromodem](INFO): Stop
2019-03-13T22:33:39.678Z,1552516419.678 [Micromodem](INFO): uninitialize
2019-03-13T22:33:39.678Z,1552516419.678 [Micromodem](INFO): Powering down
2019-03-13T22:33:40.082Z,1552516420.082 [Micromodem](INFO): Stopping
2019-03-13T22:33:40.082Z,1552516420.082 [Micromodem](FAULT): LCB 2 fault:  Software Overcurrent.
2019-03-13T22:33:40.524Z,1552516420.524 [Micromodem](INFO): Stopped
2019-03-13T22:33:40.565Z,1552516420.565 [CBIT](INFO): Clearing failed state for component Micromodem
2019-03-13T22:33:40.566Z,1552516420.566 [Micromodem] No Fault, FailCount= 1
2019-03-13T22:33:40.890Z,1552516420.890 [Micromodem](INFO): Start
2019-03-13T22:33:40.906Z,1552516420.906 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-03-13T22:33:40.906Z,1552516420.906 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-03-13T22:33:40.906Z,1552516420.906 [Aanderaa_O2](ERROR): Communications Fault
2019-03-13T22:33:40.940Z,1552516420.940 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-03-13T22:33:41.294Z,1552516421.294 [Micromodem](INFO): Start
2019-03-13T22:33:41.429Z,1552516421.429 [Aanderaa_O2](INFO): Powering down
2019-03-13T22:33:41.698Z,1552516421.698 [Micromodem](INFO): Start
2019-03-13T22:33:42.102Z,1552516422.102 [Micromodem](INFO): Start
2019-03-13T22:33:42.141Z,1552516422.141 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-03-13T22:33:42.141Z,1552516422.141 [Aanderaa_O2] No Fault, FailCount= 1
2019-03-13T22:33:42.520Z,1552516422.520 [Micromodem](INFO): Start
2019-03-13T22:33:42.910Z,1552516422.910 [Micromodem](INFO): Start
2019-03-13T22:33:43.318Z,1552516423.318 [Micromodem](INFO): Starting
2019-03-13T22:33:43.318Z,1552516423.318 [Micromodem](INFO): Powering up
2019-03-13T22:33:43.318Z,1552516423.318 [Micromodem](DEBUG): Initializing Micromodem.
2019-03-13T22:33:43.718Z,1552516423.718 [Micromodem](INFO): Starting
2019-03-13T22:33:44.126Z,1552516424.126 [Micromodem](INFO): Starting
2019-03-13T22:33:44.527Z,1552516424.527 [Micromodem](INFO): Starting
2019-03-13T22:33:44.930Z,1552516424.930 [Micromodem](INFO): Starting
2019-03-13T22:33:45.338Z,1552516425.338 [Micromodem](INFO): Starting
2019-03-13T22:33:45.738Z,1552516425.738 [Micromodem](INFO): Starting
2019-03-13T22:33:46.142Z,1552516426.142 [Micromodem](INFO): Starting
2019-03-13T22:33:46.546Z,1552516426.546 [Micromodem](INFO): Starting
2019-03-13T22:33:46.951Z,1552516426.951 [Micromodem](INFO): Starting
2019-03-13T22:33:47.354Z,1552516427.354 [Micromodem](INFO): Starting
2019-03-13T22:33:47.758Z,1552516427.758 [Micromodem](INFO): Starting
2019-03-13T22:33:48.162Z,1552516428.162 [Micromodem](INFO): Starting
2019-03-13T22:33:48.162Z,1552516428.162 [Micromodem](INFO): Starting
2019-03-13T22:33:48.162Z,1552516428.162 [Micromodem](DEBUG): Nmea buf: $CCCFG,CST,1*37

2019-03-13T22:33:48.162Z,1552516428.162 [Micromodem](IMPORTANT): Nmea buf: $CCCFG,CST,1*37

2019-03-13T22:33:48.163Z,1552516428.163 [Micromodem](INFO): Nmea out: $CCCFG,CST,1*37

2019-03-13T22:33:48.172Z,1552516428.172 [Micromodem](DEBUG): Nmea buf: $CCCFG,SRC,1*31

2019-03-13T22:33:48.172Z,1552516428.172 [Micromodem](IMPORTANT): Nmea buf: $CCCFG,SRC,1*31

2019-03-13T22:33:48.172Z,1552516428.172 [Micromodem](DEBUG): Nmea buf: $CCCFG,REV,1*32

2019-03-13T22:33:48.172Z,1552516428.172 [Micromodem](IMPORTANT): Nmea buf: $CCCFG,REV,1*32

2019-03-13T22:33:48.172Z,1552516428.172 [Micromodem](DEBUG): Nmea buf: $CCCFG,RXP,1*29

2019-03-13T22:33:48.172Z,1552516428.172 [Micromodem](IMPORTANT): Nmea buf: $CCCFG,RXP,1*29

2019-03-13T22:33:48.588Z,1552516428.588 [Micromodem](INFO): Starting
2019-03-13T22:33:48.970Z,1552516428.970 [Micromodem](INFO): Starting
2019-03-13T22:33:49.374Z,1552516429.374 [Micromodem](INFO): Starting
2019-03-13T22:33:49.778Z,1552516429.778 [Micromodem](INFO): Starting
2019-03-13T22:33:50.186Z,1552516430.186 [Micromodem](INFO): Starting
2019-03-13T22:33:50.603Z,1552516430.603 [Micromodem](INFO): Starting
2019-03-13T22:33:50.990Z,1552516430.990 [Micromodem](INFO): Starting
2019-03-13T22:33:51.398Z,1552516431.398 [Micromodem](INFO): Starting
2019-03-13T22:33:51.803Z,1552516431.803 [Micromodem](INFO): Starting
2019-03-13T22:33:52.202Z,1552516432.202 [Micromodem](INFO): Starting
2019-03-13T22:33:52.606Z,1552516432.606 [Micromodem](INFO): Starting
2019-03-13T22:33:52.705Z,1552516432.705 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-03-13T22:33:52.705Z,1552516432.705 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-03-13T22:33:52.705Z,1552516432.705 [Aanderaa_O2](ERROR): Communications Fault
2019-03-13T22:33:52.722Z,1552516432.722 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-03-13T22:33:53.010Z,1552516433.010 [Micromodem](INFO): Starting
2019-03-13T22:33:53.153Z,1552516433.153 [Aanderaa_O2](INFO): Powering down
2019-03-13T22:33:53.414Z,1552516433.414 [Micromodem](INFO): Starting
2019-03-13T22:33:53.822Z,1552516433.822 [Micromodem](INFO): Starting
2019-03-13T22:33:53.914Z,1552516433.914 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-03-13T22:33:53.914Z,1552516433.914 [Aanderaa_O2] No Fault, FailCount= 2
2019-03-13T22:33:54.226Z,1552516434.226 [Micromodem](INFO): Starting
2019-03-13T22:33:54.630Z,1552516434.630 [Micromodem](INFO): Starting
2019-03-13T22:33:55.032Z,1552516435.032 [Micromodem](INFO): Starting
2019-03-13T22:33:55.434Z,1552516435.434 [Micromodem](INFO): Starting
2019-03-13T22:33:55.842Z,1552516435.842 [Micromodem](INFO): Starting
2019-03-13T22:33:56.246Z,1552516436.246 [Micromodem](INFO): Starting
2019-03-13T22:33:56.646Z,1552516436.646 [Micromodem](INFO): Starting
2019-03-13T22:33:57.050Z,1552516437.050 [Micromodem](INFO): Starting
2019-03-13T22:33:57.458Z,1552516437.458 [Micromodem](INFO): Starting
2019-03-13T22:33:57.862Z,1552516437.862 [Micromodem](INFO): Starting
2019-03-13T22:33:58.274Z,1552516438.274 [Micromodem](INFO): Starting
2019-03-13T22:33:58.671Z,1552516438.671 [Micromodem](INFO): Starting
2019-03-13T22:33:59.070Z,1552516439.070 [Micromodem](INFO): Starting
2019-03-13T22:33:59.474Z,1552516439.474 [Micromodem](INFO): Starting
2019-03-13T22:33:59.474Z,1552516439.474 [Micromodem](ERROR): Nmea resend: $CCCFG,CST,1*37

2019-03-13T22:33:59.882Z,1552516439.882 [Micromodem](INFO): Starting
2019-03-13T22:34:00.286Z,1552516440.286 [Micromodem](INFO): Starting
2019-03-13T22:34:00.686Z,1552516440.686 [Micromodem](INFO): Starting
2019-03-13T22:34:01.090Z,1552516441.090 [Micromodem](INFO): Starting
2019-03-13T22:34:01.520Z,1552516441.520 [Micromodem](INFO): Starting
2019-03-13T22:34:01.900Z,1552516441.900 [Micromodem](INFO): Starting
2019-03-13T22:34:02.302Z,1552516442.302 [Micromodem](INFO): Starting
2019-03-13T22:34:02.710Z,1552516442.710 [Micromodem](INFO): Starting
2019-03-13T22:34:03.110Z,1552516443.110 [Micromodem](INFO): Starting
2019-03-13T22:34:03.520Z,1552516443.520 [Micromodem](INFO): Starting
2019-03-13T22:34:03.922Z,1552516443.922 [Micromodem](INFO): Starting
2019-03-13T22:34:04.322Z,1552516444.322 [Micromodem](INFO): Starting
2019-03-13T22:34:04.365Z,1552516444.365 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-03-13T22:34:04.365Z,1552516444.365 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-03-13T22:34:04.365Z,1552516444.365 [Aanderaa_O2](ERROR): Communications Fault
2019-03-13T22:34:04.382Z,1552516444.382 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-03-13T22:34:04.383Z,1552516444.383 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-03-13T22:34:04.730Z,1552516444.730 [Micromodem](INFO): Starting
2019-03-13T22:34:04.889Z,1552516444.889 [Aanderaa_O2](INFO): Powering down
2019-03-13T22:34:05.131Z,1552516445.131 [Micromodem](INFO): Starting
2019-03-13T22:34:05.534Z,1552516445.534 [Micromodem](INFO): Starting
2019-03-13T22:34:05.942Z,1552516445.942 [Micromodem](INFO): Starting
2019-03-13T22:34:06.342Z,1552516446.342 [Micromodem](INFO): Starting
2019-03-13T22:34:06.746Z,1552516446.746 [Micromodem](INFO): Starting
2019-03-13T22:34:07.150Z,1552516447.150 [Micromodem](INFO): Starting
2019-03-13T22:34:07.550Z,1552516447.550 [Micromodem](INFO): Starting
2019-03-13T22:34:07.958Z,1552516447.958 [Micromodem](INFO): Starting
2019-03-13T22:34:08.362Z,1552516448.362 [Micromodem](INFO): Starting
2019-03-13T22:34:08.363Z,1552516448.363 [Micromodem](IMPORTANT): CACFG stsring$CACFG,CST,1*35

2019-03-13T22:34:08.363Z,1552516448.363 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2019-03-13T22:34:08.766Z,1552516448.766 [Micromodem](INFO): Starting
2019-03-13T22:34:09.170Z,1552516449.170 [Micromodem](INFO): Starting
2019-03-13T22:34:09.578Z,1552516449.578 [Micromodem](INFO): Starting
2019-03-13T22:34:09.975Z,1552516449.975 [Micromodem](INFO): Starting
2019-03-13T22:34:10.386Z,1552516450.386 [Micromodem](INFO): Starting
2019-03-13T22:34:10.786Z,1552516450.786 [Micromodem](INFO): Starting
2019-03-13T22:34:11.190Z,1552516451.190 [Micromodem](INFO): Starting
2019-03-13T22:34:11.594Z,1552516451.594 [Micromodem](INFO): Starting
2019-03-13T22:34:11.999Z,1552516451.999 [Micromodem](INFO): Starting
2019-03-13T22:34:12.402Z,1552516452.402 [Micromodem](INFO): Starting
2019-03-13T22:34:12.806Z,1552516452.806 [Micromodem](INFO): Starting
2019-03-13T22:34:13.214Z,1552516453.214 [Micromodem](INFO): Starting
2019-03-13T22:34:13.614Z,1552516453.614 [Micromodem](INFO): Starting
2019-03-13T22:34:14.018Z,1552516454.018 [Micromodem](INFO): Starting
2019-03-13T22:34:14.422Z,1552516454.422 [Micromodem](INFO): Starting
2019-03-13T22:34:14.826Z,1552516454.826 [Micromodem](INFO): Starting
2019-03-13T22:34:15.230Z,1552516455.230 [Micromodem](INFO): Starting
2019-03-13T22:34:15.634Z,1552516455.634 [Micromodem](INFO): Starting
2019-03-13T22:34:16.038Z,1552516456.038 [Micromodem](INFO): Starting
2019-03-13T22:34:16.442Z,1552516456.442 [Micromodem](INFO): Starting
2019-03-13T22:34:16.846Z,1552516456.846 [Micromodem](INFO): Starting
2019-03-13T22:34:17.254Z,1552516457.254 [Micromodem](INFO): Starting
2019-03-13T22:34:17.654Z,1552516457.654 [Micromodem](INFO): Starting
2019-03-13T22:34:18.058Z,1552516458.058 [Micromodem](INFO): Starting
2019-03-13T22:34:18.464Z,1552516458.464 [Micromodem](INFO): Starting
2019-03-13T22:34:18.465Z,1552516458.465 [Micromodem](IMPORTANT): CACFG stsring$CACFG,SRC,1*33

2019-03-13T22:34:18.465Z,1552516458.465 [Micromodem](INFO): Nmea out: $CCCFG,REV,1*32

2019-03-13T22:34:18.872Z,1552516458.872 [Micromodem](INFO): Starting
2019-03-13T22:34:18.890Z,1552516458.890 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-03-13T22:34:18.890Z,1552516458.890 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,19031315381314,35.0, -0.1,   0.0,14
2019-03-13T22:34:19.274Z,1552516459.274 [Micromodem](INFO): Starting
2019-03-13T22:34:19.678Z,1552516459.678 [Micromodem](INFO): Starting
2019-03-13T22:34:20.082Z,1552516460.082 [Micromodem](INFO): Starting
2019-03-13T22:34:20.488Z,1552516460.488 [Micromodem](INFO): Starting
2019-03-13T22:34:20.890Z,1552516460.890 [Micromodem](INFO): Starting
2019-03-13T22:34:21.294Z,1552516461.294 [Micromodem](INFO): Starting
2019-03-13T22:34:21.702Z,1552516461.702 [Micromodem](INFO): Starting
2019-03-13T22:34:22.098Z,1552516462.098 [Micromodem](INFO): Starting
2019-03-13T22:34:22.512Z,1552516462.512 [Micromodem](INFO): Starting
2019-03-13T22:34:22.906Z,1552516462.906 [Micromodem](INFO): Starting
2019-03-13T22:34:23.310Z,1552516463.310 [Micromodem](INFO): Starting
2019-03-13T22:34:23.714Z,1552516463.714 [Micromodem](INFO): Starting
2019-03-13T22:34:24.118Z,1552516464.118 [Micromodem](INFO): Starting
2019-03-13T22:34:24.526Z,1552516464.526 [Micromodem](INFO): Starting
2019-03-13T22:34:24.926Z,1552516464.926 [Micromodem](INFO): Starting
2019-03-13T22:34:25.334Z,1552516465.334 [Micromodem](INFO): Starting
2019-03-13T22:34:25.738Z,1552516465.738 [Micromodem](INFO): Starting
2019-03-13T22:34:26.138Z,1552516466.138 [Micromodem](INFO): Starting
2019-03-13T22:34:26.546Z,1552516466.546 [Micromodem](INFO): Starting
2019-03-13T22:34:26.946Z,1552516466.946 [Micromodem](INFO): Starting
2019-03-13T22:34:27.350Z,1552516467.350 [Micromodem](INFO): Starting
2019-03-13T22:34:27.754Z,1552516467.754 [Micromodem](INFO): Starting
2019-03-13T22:34:28.158Z,1552516468.158 [Micromodem](INFO): Starting
2019-03-13T22:34:28.567Z,1552516468.567 [Micromodem](INFO): Starting
2019-03-13T22:34:28.568Z,1552516468.568 [Micromodem](IMPORTANT): CACFG stsring$CACFG,REV,1*30

2019-03-13T22:34:28.568Z,1552516468.568 [Micromodem](INFO): Nmea out: $CCCFG,RXP,1*29

2019-03-13T22:34:28.970Z,1552516468.970 [Micromodem](INFO): Starting
2019-03-13T22:34:29.374Z,1552516469.374 [Micromodem](INFO): Starting
2019-03-13T22:34:29.774Z,1552516469.774 [Micromodem](INFO): Starting
2019-03-13T22:34:30.178Z,1552516470.178 [Micromodem](INFO): Starting
2019-03-13T22:34:30.586Z,1552516470.586 [Micromodem](INFO): Starting
2019-03-13T22:34:30.986Z,1552516470.986 [Micromodem](INFO): Starting
2019-03-13T22:34:31.390Z,1552516471.390 [Micromodem](INFO): Starting
2019-03-13T22:34:31.800Z,1552516471.800 [Micromodem](INFO): Starting
2019-03-13T22:34:32.202Z,1552516472.202 [Micromodem](INFO): Starting
2019-03-13T22:34:32.602Z,1552516472.602 [Micromodem](INFO): Starting
2019-03-13T22:34:33.006Z,1552516473.006 [Micromodem](INFO): Starting
2019-03-13T22:34:33.414Z,1552516473.414 [Micromodem](INFO): Starting
2019-03-13T22:34:33.818Z,1552516473.818 [Micromodem](INFO): Starting
2019-03-13T22:34:34.218Z,1552516474.218 [Micromodem](INFO): Starting
2019-03-13T22:34:34.622Z,1552516474.622 [Micromodem](INFO): Starting
2019-03-13T22:34:35.030Z,1552516475.030 [Micromodem](INFO): Starting
2019-03-13T22:34:35.430Z,1552516475.430 [Micromodem](INFO): Starting
2019-03-13T22:34:35.834Z,1552516475.834 [Micromodem](INFO): Starting
2019-03-13T22:34:36.242Z,1552516476.242 [Micromodem](INFO): Starting
2019-03-13T22:34:36.642Z,1552516476.642 [Micromodem](INFO): Starting
2019-03-13T22:34:37.046Z,1552516477.046 [Micromodem](INFO): Starting
2019-03-13T22:34:37.450Z,1552516477.450 [Micromodem](INFO): Starting
2019-03-13T22:34:37.850Z,1552516477.850 [Micromodem](INFO): Starting
2019-03-13T22:34:38.258Z,1552516478.258 [Micromodem](INFO): Starting
2019-03-13T22:34:38.259Z,1552516478.259 [Micromodem](IMPORTANT): CACFG stsring$CACFG,RXP,1*2B

2019-03-13T22:34:38.260Z,1552516478.260 [Micromodem](DEBUG): Nmea buf: $CCTMS,2019-03-13T22:34:39Z,0*72

2019-03-13T22:34:38.260Z,1552516478.260 [Micromodem](IMPORTANT): Nmea buf: $CCTMS,2019-03-13T22:34:39Z,0*72

2019-03-13T22:34:38.260Z,1552516478.260 [Micromodem](INFO): Nmea out: $CCTMS,2019-03-13T22:34:39Z,0*72

2019-03-13T22:34:38.670Z,1552516478.670 [Micromodem](INFO): Starting
2019-03-13T22:34:39.066Z,1552516479.066 [Micromodem](INFO): Starting
2019-03-13T22:34:39.470Z,1552516479.470 [Micromodem](INFO): Starting
2019-03-13T22:34:39.874Z,1552516479.874 [Micromodem](INFO): Starting
2019-03-13T22:34:40.278Z,1552516480.278 [Micromodem](INFO): Starting
2019-03-13T22:34:46.377Z,1552516486.377 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2019-03-13T22:35:25.566Z,1552516525.566 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-13T22:35:25.566Z,1552516525.566 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-03-13T22:35:25.566Z,1552516525.566 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-13T22:35:25.568Z,1552516525.568 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-13T22:35:25.569Z,1552516525.569 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2019-03-13T22:35:25.569Z,1552516525.569 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-13T22:35:25.571Z,1552516525.571 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-13T22:35:25.571Z,1552516525.571 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2019-03-13T22:35:25.571Z,1552516525.571 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-13T22:35:25.582Z,1552516525.582 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-13T22:35:25.582Z,1552516525.582 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-13T22:35:25.582Z,1552516525.582 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-13T22:35:25.977Z,1552516525.977 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-13T22:35:25.977Z,1552516525.977 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-03-13T22:35:25.978Z,1552516525.978 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-13T22:35:25.978Z,1552516525.978 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2019-03-13T22:35:25.978Z,1552516525.978 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-13T22:35:25.978Z,1552516525.978 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2019-03-13T22:35:26.372Z,1552516526.372 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-13T22:36:34.668Z,1552516594.668 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-03-13T22:36:34.668Z,1552516594.668 [Aanderaa_O2] No Fault, FailCount= 3
2019-03-13T22:36:45.142Z,1552516605.142 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-03-13T22:36:45.142Z,1552516605.142 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-03-13T22:36:45.142Z,1552516605.142 [Aanderaa_O2](ERROR): Communications Fault
2019-03-13T22:36:45.198Z,1552516605.198 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-03-13T22:36:45.673Z,1552516605.673 [Aanderaa_O2](INFO): Powering down
2019-03-13T22:36:46.374Z,1552516606.374 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-03-13T22:36:46.374Z,1552516606.374 [Aanderaa_O2] No Fault, FailCount= 1
2019-03-13T22:36:56.867Z,1552516616.867 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-03-13T22:36:56.867Z,1552516616.867 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-03-13T22:36:56.867Z,1552516616.867 [Aanderaa_O2](ERROR): Communications Fault
2019-03-13T22:36:56.888Z,1552516616.888 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-03-13T22:36:57.381Z,1552516617.381 [Aanderaa_O2](INFO): Powering down
2019-03-13T22:36:58.096Z,1552516618.096 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-03-13T22:36:58.096Z,1552516618.096 [Aanderaa_O2] No Fault, FailCount= 2
2019-03-13T22:37:08.588Z,1552516628.588 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-03-13T22:37:08.588Z,1552516628.588 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-03-13T22:37:08.588Z,1552516628.588 [Aanderaa_O2](ERROR): Communications Fault
2019-03-13T22:37:08.606Z,1552516628.606 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-03-13T22:37:08.606Z,1552516628.606 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-03-13T22:37:09.101Z,1552516629.101 [Aanderaa_O2](INFO): Powering down
2019-03-13T22:37:26.767Z,1552516646.767 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-13T22:37:26.767Z,1552516646.767 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-03-13T22:37:26.767Z,1552516646.767 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-13T22:37:26.770Z,1552516646.770 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-13T22:37:26.770Z,1552516646.770 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2019-03-13T22:37:26.770Z,1552516646.770 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-13T22:37:26.771Z,1552516646.771 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-13T22:37:26.772Z,1552516646.772 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2019-03-13T22:37:26.772Z,1552516646.772 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-13T22:37:26.783Z,1552516646.783 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-13T22:37:26.783Z,1552516646.783 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-13T22:37:26.783Z,1552516646.783 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-13T22:37:27.177Z,1552516647.177 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-13T22:37:27.177Z,1552516647.177 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-03-13T22:37:27.178Z,1552516647.178 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-13T22:37:27.178Z,1552516647.178 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2019-03-13T22:37:27.178Z,1552516647.178 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-13T22:37:27.178Z,1552516647.178 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3
2019-03-13T22:37:27.575Z,1552516647.575 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-13T22:37:29.603Z,1552516649.603 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-03-13T22:37:29.603Z,1552516649.603 [Default:CheckIn:C.Wait] Stopped
2019-03-13T22:37:29.603Z,1552516649.603 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-13T22:37:29.603Z,1552516649.603 [Default:CheckIn:D] Running Loop=1
2019-03-13T22:37:29.990Z,1552516649.990 [Default:CheckIn:D] Stopped
2019-03-13T22:37:29.990Z,1552516649.990 [Default:CheckIn:E] Running Loop=1
2019-03-13T22:37:30.404Z,1552516650.404 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 5.677791 min 
2019-03-13T22:37:30.404Z,1552516650.404 [Default:CheckIn:E] Stopped
2019-03-13T22:37:30.404Z,1552516650.404 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-03-13T22:37:30.404Z,1552516650.404 [Default:CheckIn] Stopped
2019-03-13T22:37:30.405Z,1552516650.405 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-03-13T22:37:30.405Z,1552516650.405 [Default:CheckIn](INFO): Running loop #2
2019-03-13T22:37:30.405Z,1552516650.405 [Default:CheckIn] Running Loop=2
2019-03-13T22:37:30.405Z,1552516650.405 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-03-13T22:37:30.405Z,1552516650.405 [Default:CheckIn:Read_GPS] Running Loop=1
2019-03-13T22:37:31.179Z,1552516651.179 [NAL9602](INFO): Powering up
2019-03-13T22:37:42.091Z,1552516662.091 [NAL9602](INFO): NAL9602 initialized
2019-03-13T22:37:42.913Z,1552516662.913 [NAL9602](DEBUG): Fix Requested
2019-03-13T22:39:27.610Z,1552516767.610 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-13T22:39:27.610Z,1552516767.610 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2019-03-13T22:39:27.611Z,1552516767.611 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-13T22:39:27.613Z,1552516767.613 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-13T22:39:27.613Z,1552516767.613 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2019-03-13T22:39:27.613Z,1552516767.613 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-13T22:39:27.615Z,1552516767.615 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-13T22:39:27.615Z,1552516767.615 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 4
2019-03-13T22:39:27.615Z,1552516767.615 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-13T22:39:27.626Z,1552516767.626 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-13T22:39:27.626Z,1552516767.626 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-13T22:39:27.627Z,1552516767.627 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-13T22:39:28.001Z,1552516768.001 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-13T22:39:28.002Z,1552516768.002 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2019-03-13T22:39:28.002Z,1552516768.002 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-13T22:39:28.002Z,1552516768.002 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2019-03-13T22:39:28.003Z,1552516768.003 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-13T22:39:28.003Z,1552516768.003 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 4
2019-03-13T22:39:28.402Z,1552516768.402 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-13T22:39:38.955Z,1552516778.955 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-03-13T22:39:38.955Z,1552516778.955 [Aanderaa_O2] No Fault, FailCount= 3
2019-03-13T22:39:49.417Z,1552516789.417 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-03-13T22:39:49.417Z,1552516789.417 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-03-13T22:39:49.417Z,1552516789.417 [Aanderaa_O2](ERROR): Communications Fault
2019-03-13T22:39:49.452Z,1552516789.452 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-03-13T22:39:49.937Z,1552516789.937 [Aanderaa_O2](INFO): Powering down
2019-03-13T22:39:50.661Z,1552516790.661 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-03-13T22:39:50.662Z,1552516790.662 [Aanderaa_O2] No Fault, FailCount= 1
2019-03-13T22:40:01.110Z,1552516801.110 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-03-13T22:40:01.110Z,1552516801.110 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-03-13T22:40:01.111Z,1552516801.111 [Aanderaa_O2](ERROR): Communications Fault
2019-03-13T22:40:01.128Z,1552516801.128 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-03-13T22:40:01.661Z,1552516801.661 [Aanderaa_O2](INFO): Powering down
2019-03-13T22:40:02.349Z,1552516802.349 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-03-13T22:40:02.350Z,1552516802.350 [Aanderaa_O2] No Fault, FailCount= 2
2019-03-13T22:40:12.839Z,1552516812.839 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-03-13T22:40:12.839Z,1552516812.839 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-03-13T22:40:12.840Z,1552516812.840 [Aanderaa_O2](ERROR): Communications Fault
2019-03-13T22:40:12.861Z,1552516812.861 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-03-13T22:40:12.862Z,1552516812.862 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-03-13T22:40:13.345Z,1552516813.345 [Aanderaa_O2](INFO): Powering down
2019-03-13T22:41:08.973Z,1552516868.973 [NAL9602](INFO): SBD MO Status=0, MOMSN=2264, MT Status=0, MTMSN=0
2019-03-13T22:41:08.973Z,1552516868.973 [NAL9602](INFO): No messages in MT queue
2019-03-13T22:41:09.789Z,1552516869.789 [NAL9602](DEBUG): Fix Requested
2019-03-13T22:41:10.187Z,1552516870.187 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,223643.00,A,3648.16184,N,12147.28463,W,0.428,0.00,130319,,,A*70
2019-03-13T22:41:10.189Z,1552516870.189 [NAL9602](INFO): GPS fix at 20190313T223643: (36.802697, -121.788077)
2019-03-13T22:41:10.222Z,1552516870.222 [Default:CheckIn:Read_GPS] Stopped
2019-03-13T22:41:10.223Z,1552516870.223 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-03-13T22:41:15.673Z,1552516875.673 [DataOverHttps](INFO): Sending 224 bytes from file Logs/20190313T222710/Courier0007.lzma
2019-03-13T22:41:16.481Z,1552516876.481 [DataOverHttps](INFO): Moved sent file to Logs/20190313T222710/Courier0007.lzma.bak
2019-03-13T22:41:16.482Z,1552516876.482 [DataOverHttps](INFO): SBD MOMSN=10135582
2019-03-13T22:41:27.754Z,1552516887.754 [DataOverHttps](INFO): Sending 595 bytes from file Logs/20190313T222710/Express0008.lzma
2019-03-13T22:41:28.558Z,1552516888.558 [DataOverHttps](INFO): Moved sent file to Logs/20190313T222710/Express0008.lzma.bak
2019-03-13T22:41:28.558Z,1552516888.558 [DataOverHttps](INFO): SBD MOMSN=10135585
2019-03-13T22:41:28.792Z,1552516888.792 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-13T22:41:28.792Z,1552516888.792 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-03-13T22:41:28.792Z,1552516888.792 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-13T22:41:28.794Z,1552516888.794 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-13T22:41:28.794Z,1552516888.794 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-03-13T22:41:28.794Z,1552516888.794 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-13T22:41:28.796Z,1552516888.796 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-13T22:41:28.796Z,1552516888.796 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2019-03-13T22:41:28.796Z,1552516888.796 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-13T22:41:28.807Z,1552516888.807 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-13T22:41:28.808Z,1552516888.808 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-13T22:41:28.808Z,1552516888.808 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-13T22:41:29.226Z,1552516889.226 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-13T22:41:29.226Z,1552516889.226 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-03-13T22:41:29.227Z,1552516889.227 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-13T22:41:29.227Z,1552516889.227 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-03-13T22:41:29.227Z,1552516889.227 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-13T22:41:29.227Z,1552516889.227 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2019-03-13T22:41:29.620Z,1552516889.620 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-13T22:41:29.622Z,1552516889.622 [Default:CheckIn:Read_Iridium] Stopped
2019-03-13T22:41:29.622Z,1552516889.622 [Default:CheckIn:C.Wait] Running Loop=1
2019-03-13T22:41:29.622Z,1552516889.622 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-03-13T22:41:42.903Z,1552516902.903 [NAL9602](INFO): Powering down
2019-03-13T22:42:05.544Z,1552516925.544 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,  94.92,  93.93,  96.92,   0.00
2019-03-13T22:42:26.530Z,1552516946.530 [CommandLine](IMPORTANT): got command show variable detectionTh
2019-03-13T22:42:26.658Z,1552516946.658 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold (count)
2019-03-13T22:42:35.802Z,1552516955.802 [CommandLine](IMPORTANT): got command configSet DUSBL_Hydroid.detectionThreshold 75.000000 count persist
2019-03-13T22:42:43.137Z,1552516963.137 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-03-13T22:42:43.137Z,1552516963.137 [Aanderaa_O2] No Fault, FailCount= 3
2019-03-13T22:42:53.632Z,1552516973.632 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-03-13T22:42:53.632Z,1552516973.632 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-03-13T22:42:53.632Z,1552516973.632 [Aanderaa_O2](ERROR): Communications Fault
2019-03-13T22:42:53.649Z,1552516973.649 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-03-13T22:42:54.141Z,1552516974.141 [Aanderaa_O2](INFO): Powering down
2019-03-13T22:42:54.856Z,1552516974.856 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-03-13T22:42:54.856Z,1552516974.856 [Aanderaa_O2] No Fault, FailCount= 1
2019-03-13T22:43:05.335Z,1552516985.335 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-03-13T22:43:05.335Z,1552516985.335 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-03-13T22:43:05.335Z,1552516985.335 [Aanderaa_O2](ERROR): Communications Fault
2019-03-13T22:43:05.356Z,1552516985.356 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-03-13T22:43:05.877Z,1552516985.877 [Aanderaa_O2](INFO): Powering down
2019-03-13T22:43:06.596Z,1552516986.596 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-03-13T22:43:06.596Z,1552516986.596 [Aanderaa_O2] No Fault, FailCount= 2
2019-03-13T22:43:17.054Z,1552516997.054 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-03-13T22:43:17.054Z,1552516997.054 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-03-13T22:43:17.054Z,1552516997.054 [Aanderaa_O2](ERROR): Communications Fault
2019-03-13T22:43:17.077Z,1552516997.077 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-03-13T22:43:17.077Z,1552516997.077 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-03-13T22:43:17.573Z,1552516997.573 [Aanderaa_O2](INFO): Powering down
2019-03-13T22:43:29.981Z,1552517009.981 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-13T22:43:29.982Z,1552517009.982 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-03-13T22:43:29.982Z,1552517009.982 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-13T22:43:29.984Z,1552517009.984 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-13T22:43:29.984Z,1552517009.984 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2019-03-13T22:43:29.984Z,1552517009.984 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-13T22:43:29.986Z,1552517009.986 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-13T22:43:29.986Z,1552517009.986 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2019-03-13T22:43:29.986Z,1552517009.986 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-13T22:43:30.018Z,1552517010.018 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-13T22:43:30.018Z,1552517010.018 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-13T22:43:30.018Z,1552517010.018 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-13T22:43:30.402Z,1552517010.402 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-13T22:43:30.402Z,1552517010.402 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-03-13T22:43:30.402Z,1552517010.402 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-13T22:43:30.402Z,1552517010.402 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2019-03-13T22:43:30.403Z,1552517010.403 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-13T22:43:30.403Z,1552517010.403 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2019-03-13T22:43:30.799Z,1552517010.799 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-13T22:45:31.192Z,1552517131.192 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-13T22:45:31.192Z,1552517131.192 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-03-13T22:45:31.192Z,1552517131.192 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-13T22:45:31.194Z,1552517131.194 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-13T22:45:31.194Z,1552517131.194 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2019-03-13T22:45:31.194Z,1552517131.194 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-13T22:45:31.196Z,1552517131.196 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-13T22:45:31.196Z,1552517131.196 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2019-03-13T22:45:31.196Z,1552517131.196 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-13T22:45:31.208Z,1552517131.208 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-13T22:45:31.208Z,1552517131.208 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-13T22:45:31.208Z,1552517131.208 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-13T22:45:31.628Z,1552517131.628 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-13T22:45:31.633Z,1552517131.633 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-03-13T22:45:31.634Z,1552517131.634 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-13T22:45:31.634Z,1552517131.634 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2019-03-13T22:45:31.634Z,1552517131.634 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-13T22:45:31.634Z,1552517131.634 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3
2019-03-13T22:45:31.996Z,1552517131.996 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-13T22:45:47.359Z,1552517147.359 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-03-13T22:45:47.359Z,1552517147.359 [Aanderaa_O2] No Fault, FailCount= 3
2019-03-13T22:45:57.852Z,1552517157.852 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-03-13T22:45:57.852Z,1552517157.852 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-03-13T22:45:57.852Z,1552517157.852 [Aanderaa_O2](ERROR): Communications Fault
2019-03-13T22:45:57.869Z,1552517157.869 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-03-13T22:45:58.365Z,1552517158.365 [Aanderaa_O2](INFO): Powering down
2019-03-13T22:45:59.085Z,1552517159.085 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-03-13T22:45:59.085Z,1552517159.085 [Aanderaa_O2] No Fault, FailCount= 1
2019-03-13T22:46:09.573Z,1552517169.573 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-03-13T22:46:09.573Z,1552517169.573 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-03-13T22:46:09.573Z,1552517169.573 [Aanderaa_O2](ERROR): Communications Fault
2019-03-13T22:46:09.590Z,1552517169.590 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-03-13T22:46:10.085Z,1552517170.085 [Aanderaa_O2](INFO): Powering down
2019-03-13T22:46:10.823Z,1552517170.823 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-03-13T22:46:10.824Z,1552517170.824 [Aanderaa_O2] No Fault, FailCount= 2
2019-03-13T22:46:21.274Z,1552517181.274 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-03-13T22:46:21.274Z,1552517181.274 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-03-13T22:46:21.274Z,1552517181.274 [Aanderaa_O2](ERROR): Communications Fault
2019-03-13T22:46:21.297Z,1552517181.297 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-03-13T22:46:21.297Z,1552517181.297 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-03-13T22:46:21.801Z,1552517181.801 [Aanderaa_O2](INFO): Powering down
2019-03-13T22:46:30.175Z,1552517190.175 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-03-13T22:46:30.175Z,1552517190.175 [Default:CheckIn:C.Wait] Stopped
2019-03-13T22:46:30.176Z,1552517190.176 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-13T22:46:30.176Z,1552517190.176 [Default:CheckIn:D] Running Loop=1
2019-03-13T22:46:30.583Z,1552517190.583 [Default:CheckIn:D] Stopped
2019-03-13T22:46:30.583Z,1552517190.583 [Default:CheckIn:E] Running Loop=1
2019-03-13T22:46:30.988Z,1552517190.988 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 14.687684 min 
2019-03-13T22:46:30.988Z,1552517190.988 [Default:CheckIn:E] Stopped
2019-03-13T22:46:30.988Z,1552517190.988 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-03-13T22:46:30.988Z,1552517190.988 [Default:CheckIn] Stopped
2019-03-13T22:46:30.988Z,1552517190.988 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-03-13T22:46:30.989Z,1552517190.989 [Default:CheckIn](INFO): Running loop #3
2019-03-13T22:46:30.989Z,1552517190.989 [Default:CheckIn] Running Loop=3
2019-03-13T22:46:30.989Z,1552517190.989 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-03-13T22:46:30.989Z,1552517190.989 [Default:CheckIn:Read_GPS] Running Loop=1
2019-03-13T22:46:31.759Z,1552517191.759 [NAL9602](INFO): Powering up
2019-03-13T22:46:42.671Z,1552517202.671 [NAL9602](INFO): NAL9602 initialized
2019-03-13T22:46:43.493Z,1552517203.493 [NAL9602](DEBUG): Fix Requested
2019-03-13T22:47:32.388Z,1552517252.388 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-13T22:47:32.388Z,1552517252.388 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2019-03-13T22:47:32.388Z,1552517252.388 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-13T22:47:32.390Z,1552517252.390 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-13T22:47:32.390Z,1552517252.390 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2019-03-13T22:47:32.390Z,1552517252.390 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-13T22:47:32.392Z,1552517252.392 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-13T22:47:32.392Z,1552517252.392 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 4
2019-03-13T22:47:32.392Z,1552517252.392 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-13T22:47:32.403Z,1552517252.403 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-13T22:47:32.403Z,1552517252.403 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-13T22:47:32.403Z,1552517252.403 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-13T22:47:32.805Z,1552517252.805 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-13T22:47:32.805Z,1552517252.805 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2019-03-13T22:47:32.806Z,1552517252.806 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-13T22:47:32.806Z,1552517252.806 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2019-03-13T22:47:32.806Z,1552517252.806 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-13T22:47:32.806Z,1552517252.806 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 4
2019-03-13T22:47:33.204Z,1552517253.204 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-13T22:47:50.030Z,1552517270.030 [CommandLine](IMPORTANT): got command restart application
2019-03-13T22:47:51.041Z,1552517271.041 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-13T22:47:51.041Z,1552517271.041 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-03-13T22:47:51.049Z,1552517271.049 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-03-13T22:47:51.049Z,1552517271.049 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-03-13T22:47:51.050Z,1552517271.050 [CommandLine](INFO): Join timeout helper Thread ID is 459
2019-03-13T22:47:51.050Z,1552517271.050 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-03-13T22:47:51.050Z,1552517271.050 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-03-13T22:47:51.051Z,1552517271.051 [NavChartDb](INFO): Join timeout helper Thread ID is 460
2019-03-13T22:47:51.317Z,1552517271.317 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-13T22:47:51.317Z,1552517271.317 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-03-13T22:47:51.333Z,1552517271.333 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2019-03-13T22:47:51.333Z,1552517271.333 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-03-13T22:47:51.333Z,1552517271.333 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 461
2019-03-13T22:47:51.625Z,1552517271.625 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-13T22:47:51.625Z,1552517271.625 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-03-13T22:47:51.626Z,1552517271.626 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-03-13T22:47:51.641Z,1552517271.641 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-03-13T22:47:51.641Z,1552517271.641 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-03-13T22:47:51.641Z,1552517271.641 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 462
2019-03-13T22:47:51.697Z,1552517271.697 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-13T22:47:51.697Z,1552517271.697 [CTD_NeilBrown](INFO): Powering down
2019-03-13T22:47:51.709Z,1552517271.709 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-03-13T22:47:51.721Z,1552517271.721 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-03-13T22:47:51.721Z,1552517271.721 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-03-13T22:47:51.721Z,1552517271.721 [Radio_Surface](INFO): Join timeout helper Thread ID is 463
2019-03-13T22:47:51.957Z,1552517271.957 [Radio_Surface](INFO): Powering down
2019-03-13T22:47:51.958Z,1552517271.958 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-13T22:47:51.958Z,1552517271.958 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-03-13T22:47:51.978Z,1552517271.978 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-03-13T22:47:51.978Z,1552517271.978 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-03-13T22:47:51.978Z,1552517271.978 [DataOverHttps](INFO): Join timeout helper Thread ID is 464
2019-03-13T22:47:52.277Z,1552517272.277 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-13T22:47:52.277Z,1552517272.277 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-03-13T22:47:52.282Z,1552517272.282 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-03-13T22:47:52.282Z,1552517272.282 [logger ThreadHandler](INFO): Thread cancelled.
2019-03-13T22:47:52.282Z,1552517272.282 [logger](INFO): Join timeout helper Thread ID is 465
2019-03-13T22:47:52.329Z,1552517272.329 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-13T22:47:52.329Z,1552517272.329 [logger ThreadHandler](INFO): Thread cancelled.
2019-03-13T22:47:52.342Z,1552517272.342 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-03-13T22:47:52.342Z,1552517272.342 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-03-13T22:47:52.342Z,1552517272.342 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-03-13T22:47:52.342Z,1552517272.342 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-03-13T22:47:52.342Z,1552517272.342 [controlThread](INFO): Join timeout helper Thread ID is 466
2019-03-13T22:47:52.553Z,1552517272.553 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-13T22:47:52.553Z,1552517272.553 [controlThread](DEBUG): Uninitializing ControlThread
2019-03-13T22:47:52.554Z,1552517272.554 [DUSBL_Hydroid](INFO): Powering down
2019-03-13T22:47:52.625Z,1552517272.625 [Micromodem](INFO): uninitialize
2019-03-13T22:47:52.625Z,1552517272.625 [Micromodem](INFO): Powering down
2019-03-13T22:47:52.721Z,1552517272.721 [NAL9602](INFO): Powering down
2019-03-13T22:47:52.793Z,1552517272.793 [RDI_Pathfinder](INFO): Powering down
2019-03-13T22:47:52.794Z,1552517272.794 [RDI_PathfinderUp](INFO): Powering down
2019-03-13T22:47:52.795Z,1552517272.795 [Aanderaa_O2](INFO): Powering down
2019-03-13T22:47:52.796Z,1552517272.796 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-03-13T22:47:52.797Z,1552517272.797 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-03-13T22:47:52.798Z,1552517272.798 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-03-13T22:47:52.798Z,1552517272.798 [MissionManager](INFO): Uninitializing Mission Default
2019-03-13T22:47:52.798Z,1552517272.798 [Default] Stopped
2019-03-13T22:47:52.798Z,1552517272.798 [Default](DEBUG): Aggregate::uninitialize Default
2019-03-13T22:47:52.798Z,1552517272.798 [Default:B.GoToSurface] Stopped
2019-03-13T22:47:52.799Z,1552517272.799 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-03-13T22:47:52.799Z,1552517272.799 [Default:CheckIn] Stopped
2019-03-13T22:47:52.799Z,1552517272.799 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-03-13T22:47:52.799Z,1552517272.799 [Default:CheckIn:Read_GPS] Stopped
2019-03-13T22:47:52.801Z,1552517272.801 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-03-13T22:47:52.802Z,1552517272.802 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-03-13T22:47:52.802Z,1552517272.802 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-03-13T22:47:52.802Z,1552517272.802 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-03-13T22:47:52.802Z,1552517272.802 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-03-13T22:47:52.802Z,1552517272.802 [BuoyancyServo](INFO): Powering down
2019-03-13T22:47:52.817Z,1552517272.817 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-03-13T22:47:52.817Z,1552517272.817 [ElevatorServo](INFO): Powering down
2019-03-13T22:47:52.818Z,1552517272.818 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-03-13T22:47:52.818Z,1552517272.818 [MassServo](INFO): Powering down
2019-03-13T22:47:52.819Z,1552517272.819 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-03-13T22:47:52.819Z,1552517272.819 [RudderServo](INFO): Powering down
2019-03-13T22:47:52.820Z,1552517272.820 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-03-13T22:47:52.820Z,1552517272.820 [ThrusterServo](INFO): Powering down
2019-03-13T22:47:52.820Z,1552517272.820 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-03-13T22:47:52.821Z,1552517272.821 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-03-13T22:47:52.821Z,1552517272.821 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-03-13T22:47:52.821Z,1552517272.821 [CBIT](DEBUG): Powering off loads.
2019-03-13T22:47:52.833Z,1552517272.833 [CBIT](DEBUG): Disabling WDT.
2019-03-13T22:47:52.845Z,1552517272.845 [CBIT](DEBUG): Opening all GF detection circuits.
2019-03-13T22:47:52.846Z,1552517272.846 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-03-13T22:47:52.910Z,1552517272.910 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-03-13T22:47:52.919Z,1552517272.919 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-03-13T22:47:52.963Z,1552517272.963 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-03-13T22:47:52.965Z,1552517272.965 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-03-13T22:47:53.019Z,1552517273.019 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-03-13T22:47:53.090Z,1552517273.090 [logger ThreadHandler](INFO): Thread cancelled.