2019-02-08T00:20:13.050Z,1549585213.050 [Supervisor](DEBUG): Initializing supervisor.
2019-02-08T00:20:13.053Z,1549585213.053 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-02-08T00:20:13.053Z,1549585213.053 [SyncHandler](INFO): Protected caller Thread ID is 1096
2019-02-08T00:20:13.054Z,1549585213.054 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-02-08T00:20:13.055Z,1549585213.055 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-02-08T00:20:13.055Z,1549585213.055 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1097
2019-02-08T00:20:13.058Z,1549585213.058 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-02-08T00:20:13.070Z,1549585213.070 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-02-08T00:20:13.071Z,1549585213.071 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-02-08T00:20:13.071Z,1549585213.071 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1098
2019-02-08T00:20:13.072Z,1549585213.072 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-02-08T00:20:13.073Z,1549585213.073 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-02-08T00:20:13.073Z,1549585213.073 [logger ThreadHandler](INFO): Protected caller Thread ID is 1099
2019-02-08T00:20:13.075Z,1549585213.075 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-02-08T00:20:13.075Z,1549585213.075 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-02-08T00:20:13.077Z,1549585213.077 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-02-08T00:20:13.432Z,1549585213.432 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-02-08T00:20:13.433Z,1549585213.433 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-02-08T00:20:13.888Z,1549585213.888 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-02-08T00:20:13.889Z,1549585213.889 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-02-08T00:20:14.220Z,1549585214.220 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-02-08T00:20:14.221Z,1549585214.221 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-02-08T00:20:14.416Z,1549585214.416 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-02-08T00:20:14.417Z,1549585214.417 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-02-08T00:20:14.868Z,1549585214.868 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-02-08T00:20:14.869Z,1549585214.869 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-02-08T00:20:15.353Z,1549585215.353 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-02-08T00:20:15.354Z,1549585215.354 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-02-08T00:20:15.458Z,1549585215.458 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-02-08T00:20:15.459Z,1549585215.459 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-02-08T00:20:15.656Z,1549585215.656 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-02-08T00:20:15.657Z,1549585215.657 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-02-08T00:20:15.803Z,1549585215.803 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-02-08T00:20:15.804Z,1549585215.804 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-02-08T00:20:15.884Z,1549585215.884 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-02-08T00:20:16.104Z,1549585216.104 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-02-08T00:20:16.105Z,1549585216.105 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-02-08T00:20:16.433Z,1549585216.433 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-02-08T00:20:16.434Z,1549585216.434 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-02-08T00:20:16.532Z,1549585216.532 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-02-08T00:20:16.533Z,1549585216.533 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-02-08T00:20:16.682Z,1549585216.682 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-02-08T00:20:16.682Z,1549585216.682 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-02-08T00:20:17.006Z,1549585217.006 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-02-08T00:20:17.008Z,1549585217.008 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2019-02-08T00:20:17.009Z,1549585217.009 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2019-02-08T00:20:17.145Z,1549585217.145 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2019-02-08T00:20:17.378Z,1549585217.378 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-02-08T00:20:17.378Z,1549585217.378 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2019-02-08T00:20:17.549Z,1549585217.549 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2019-02-08T00:20:17.650Z,1549585217.650 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2019-02-08T00:20:17.749Z,1549585217.749 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2019-02-08T00:20:17.947Z,1549585217.947 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2019-02-08T00:20:18.033Z,1549585218.033 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2019-02-08T00:20:18.132Z,1549585218.132 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2019-02-08T00:20:18.226Z,1549585218.226 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2019-02-08T00:20:18.308Z,1549585218.308 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2019-02-08T00:20:18.418Z,1549585218.418 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/
2019-02-08T00:20:18.418Z,1549585218.418 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-02-08T00:20:18.423Z,1549585218.423 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-02-08T00:20:18.560Z,1549585218.560 [SBIT](DEBUG): Construct Startup Built In Test.
2019-02-08T00:20:18.591Z,1549585218.591 [SBIT] Loaded
2019-02-08T00:20:18.591Z,1549585218.591 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-02-08T00:20:18.592Z,1549585218.592 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-02-08T00:20:18.620Z,1549585218.620 [IBIT] Loaded
2019-02-08T00:20:18.621Z,1549585218.621 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-02-08T00:20:18.624Z,1549585218.624 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-02-08T00:20:19.146Z,1549585219.146 [CBIT] Loaded
2019-02-08T00:20:19.147Z,1549585219.147 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-02-08T00:20:19.147Z,1549585219.147 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-02-08T00:20:19.148Z,1549585219.148 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-02-08T00:20:19.417Z,1549585219.417 [BuoyancyServo] Loaded
2019-02-08T00:20:19.417Z,1549585219.417 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-02-08T00:20:19.432Z,1549585219.432 [ElevatorServo] Loaded
2019-02-08T00:20:19.432Z,1549585219.432 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-02-08T00:20:19.446Z,1549585219.446 [MassServo] Loaded
2019-02-08T00:20:19.447Z,1549585219.447 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-02-08T00:20:19.498Z,1549585219.498 [RudderServo] Loaded
2019-02-08T00:20:19.498Z,1549585219.498 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-02-08T00:20:19.552Z,1549585219.552 [ThrusterServo] Loaded
2019-02-08T00:20:19.552Z,1549585219.552 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-02-08T00:20:19.552Z,1549585219.552 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-02-08T00:20:19.571Z,1549585219.571 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-02-08T00:20:19.810Z,1549585219.810 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-02-08T00:20:19.811Z,1549585219.811 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-02-08T00:20:19.870Z,1549585219.870 [DeadReckonUsingSpeedCalculator] Loaded
2019-02-08T00:20:19.871Z,1549585219.871 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-02-08T00:20:19.930Z,1549585219.930 [DeadReckonWithRespectToSeafloor] Loaded
2019-02-08T00:20:19.931Z,1549585219.931 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2019-02-08T00:20:19.947Z,1549585219.947 [NavChart] Loaded
2019-02-08T00:20:19.947Z,1549585219.947 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-02-08T00:20:19.951Z,1549585219.951 [UniversalFixResidualReporter] Loaded
2019-02-08T00:20:19.952Z,1549585219.952 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-02-08T00:20:19.952Z,1549585219.952 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-02-08T00:20:19.953Z,1549585219.953 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-02-08T00:20:19.966Z,1549585219.966 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-02-08T00:20:19.966Z,1549585219.966 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-02-08T00:20:20.204Z,1549585220.204 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-02-08T00:20:20.205Z,1549585220.205 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-02-08T00:20:20.544Z,1549585220.544 [AHRS_M2] Loaded
2019-02-08T00:20:20.544Z,1549585220.544 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2019-02-08T00:20:20.611Z,1549585220.611 [DataOverHttps] Loaded
2019-02-08T00:20:20.612Z,1549585220.612 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2019-02-08T00:20:20.625Z,1549585220.625 [Depth_Keller] Loaded
2019-02-08T00:20:20.625Z,1549585220.625 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-02-08T00:20:20.630Z,1549585220.630 [DropWeight] Loaded
2019-02-08T00:20:20.631Z,1549585220.631 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-02-08T00:20:20.683Z,1549585220.683 [DUSBL_Hydroid] Loaded
2019-02-08T00:20:20.683Z,1549585220.683 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2019-02-08T00:20:20.774Z,1549585220.774 [NAL9602] Loaded
2019-02-08T00:20:20.774Z,1549585220.774 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-02-08T00:20:20.780Z,1549585220.780 [Onboard] Loaded
2019-02-08T00:20:20.780Z,1549585220.780 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-02-08T00:20:20.787Z,1549585220.787 [Radio_Surface] Loaded
2019-02-08T00:20:20.787Z,1549585220.787 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-02-08T00:20:20.788Z,1549585220.788 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408494E0
2019-02-08T00:20:20.789Z,1549585220.789 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1178
2019-02-08T00:20:20.817Z,1549585220.817 [RDI_Pathfinder] Loaded
2019-02-08T00:20:20.818Z,1549585220.818 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2019-02-08T00:20:20.840Z,1549585220.840 [RDI_PathfinderUp] Loaded
2019-02-08T00:20:20.840Z,1549585220.840 [ComponentRegistry](DEBUG): SyncComponent "RDI_PathfinderUp" handled in the control thread.
2019-02-08T00:20:23.055Z,1549585223.055 [BPC1] Loaded
2019-02-08T00:20:23.055Z,1549585223.055 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-02-08T00:20:23.056Z,1549585223.056 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-02-08T00:20:23.056Z,1549585223.056 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-02-08T00:20:23.126Z,1549585223.126 [VerticalControl](DEBUG): Construct VerticalControl.
2019-02-08T00:20:23.214Z,1549585223.214 [VerticalControl] Loaded
2019-02-08T00:20:23.214Z,1549585223.214 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-02-08T00:20:23.215Z,1549585223.215 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-02-08T00:20:23.273Z,1549585223.273 [HorizontalControl] Loaded
2019-02-08T00:20:23.273Z,1549585223.273 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-02-08T00:20:23.274Z,1549585223.274 [SpeedControl](DEBUG): Construct SpeedControl.
2019-02-08T00:20:23.276Z,1549585223.276 [SpeedControl] Loaded
2019-02-08T00:20:23.276Z,1549585223.276 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-02-08T00:20:23.277Z,1549585223.277 [LoopControl](DEBUG): Construct LoopControl.
2019-02-08T00:20:23.278Z,1549585223.278 [LoopControl] Loaded
2019-02-08T00:20:23.278Z,1549585223.278 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-02-08T00:20:23.278Z,1549585223.278 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-02-08T00:20:23.279Z,1549585223.279 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-02-08T00:20:23.380Z,1549585223.380 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-02-08T00:20:23.381Z,1549585223.381 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-02-08T00:20:23.561Z,1549585223.561 [Aanderaa_O2] Loaded
2019-02-08T00:20:23.562Z,1549585223.562 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-02-08T00:20:23.647Z,1549585223.647 [CTD_NeilBrown] Loaded
2019-02-08T00:20:23.647Z,1549585223.647 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-02-08T00:20:23.648Z,1549585223.648 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4095A4E0
2019-02-08T00:20:23.648Z,1549585223.648 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 1179
2019-02-08T00:20:23.695Z,1549585223.695 [WetLabsSeaOWL_UV_A] Loaded
2019-02-08T00:20:23.695Z,1549585223.695 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2019-02-08T00:20:23.696Z,1549585223.696 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 4098A4E0
2019-02-08T00:20:23.696Z,1549585223.696 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 1180
2019-02-08T00:20:23.697Z,1549585223.697 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-02-08T00:20:23.698Z,1549585223.698 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-02-08T00:20:23.762Z,1549585223.762 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-02-08T00:20:23.762Z,1549585223.762 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-02-08T00:20:23.803Z,1549585223.803 [DepthRateCalculator] Loaded
2019-02-08T00:20:23.803Z,1549585223.803 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-02-08T00:20:23.808Z,1549585223.808 [PitchRateCalculator] Loaded
2019-02-08T00:20:23.808Z,1549585223.808 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-02-08T00:20:23.821Z,1549585223.821 [SpeedCalculator] Loaded
2019-02-08T00:20:23.821Z,1549585223.821 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-02-08T00:20:23.843Z,1549585223.843 [TempGradientCalculator] Loaded
2019-02-08T00:20:23.843Z,1549585223.843 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-02-08T00:20:23.848Z,1549585223.848 [YawRateCalculator] Loaded
2019-02-08T00:20:23.848Z,1549585223.848 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-02-08T00:20:23.879Z,1549585223.879 [ElevatorOffsetCalculator] Loaded
2019-02-08T00:20:23.879Z,1549585223.879 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-02-08T00:20:23.879Z,1549585223.879 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-02-08T00:20:23.880Z,1549585223.880 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-02-08T00:20:23.907Z,1549585223.907 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-02-08T00:20:23.910Z,1549585223.910 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-02-08T00:20:23.911Z,1549585223.911 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-02-08T00:20:23.918Z,1549585223.918 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-02-08T00:20:23.919Z,1549585223.919 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A464E0
2019-02-08T00:20:23.919Z,1549585223.919 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1181
2019-02-08T00:20:23.923Z,1549585223.923 [Supervisor](INFO): Main Thread ID is 796
2019-02-08T00:20:23.924Z,1549585223.924 [Supervisor](DEBUG): Running supervisor.
2019-02-08T00:20:23.924Z,1549585223.924 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1182
2019-02-08T00:20:23.927Z,1549585223.927 [controlThread ThreadHandler](INFO): Handler Thread ID is 1183
2019-02-08T00:20:23.927Z,1549585223.927 [controlThread](DEBUG): Initializing ControlThread
2019-02-08T00:20:23.928Z,1549585223.928 [SBIT](INFO): Initialize SBIT Component.
2019-02-08T00:20:23.928Z,1549585223.928 [SBIT](IMPORTANT): git: 2019-01-14A-54-g0d094c9
2019-02-08T00:20:23.929Z,1549585223.929 [SBIT](INFO): git hash: 0d094c9fe207dedbc4f0c5a93b42d412b4ef2ca6
2019-02-08T00:20:23.929Z,1549585223.929 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-02-08T00:20:23.929Z,1549585223.929 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2019-02-08T00:20:23.930Z,1549585223.930 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2019-02-08T00:20:23.931Z,1549585223.931 [IBIT](INFO): Initialize IBIT Component.
2019-02-08T00:20:23.932Z,1549585223.932 [CBIT](DEBUG): Initialize CBIT Component.
2019-02-08T00:20:23.932Z,1549585223.932 [logger ThreadHandler](INFO): Handler Thread ID is 1184
2019-02-08T00:20:23.945Z,1549585223.945 [CBIT](DEBUG): Initialized mux pins.
2019-02-08T00:20:23.945Z,1549585223.945 [CBIT](DEBUG): Initializing the watchdog timer.
2019-02-08T00:20:23.953Z,1549585223.953 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1185
2019-02-08T00:20:23.959Z,1549585223.959 [Radio_Surface](INFO): Powering up
2019-02-08T00:20:23.965Z,1549585223.965 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 1186
2019-02-08T00:20:23.966Z,1549585223.966 [CTD_NeilBrown](INFO): Powering down
2019-02-08T00:20:23.969Z,1549585223.969 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-02-08T00:20:23.969Z,1549585223.969 [CBIT](DEBUG): Initializing heartbeat.
2019-02-08T00:20:24.001Z,1549585224.001 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 1187
2019-02-08T00:20:24.002Z,1549585224.002 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-02-08T00:20:24.037Z,1549585224.037 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1188
2019-02-08T00:20:24.040Z,1549585224.040 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-02-08T00:20:24.040Z,1549585224.040 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-02-08T00:20:24.041Z,1549585224.041 [CBIT](DEBUG): Deactivating GF circuits.
2019-02-08T00:20:24.041Z,1549585224.041 [CBIT](DEBUG): Deactivating emergency mode.
2019-02-08T00:20:24.041Z,1549585224.041 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-02-08T00:20:24.041Z,1549585224.041 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-02-08T00:20:24.041Z,1549585224.041 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-02-08T00:20:24.042Z,1549585224.042 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-02-08T00:20:24.042Z,1549585224.042 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-02-08T00:20:24.042Z,1549585224.042 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-02-08T00:20:24.042Z,1549585224.042 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-02-08T00:20:24.042Z,1549585224.042 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-02-08T00:20:24.042Z,1549585224.042 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-02-08T00:20:24.043Z,1549585224.043 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-02-08T00:20:24.043Z,1549585224.043 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-02-08T00:20:24.043Z,1549585224.043 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-02-08T00:20:24.043Z,1549585224.043 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-02-08T00:20:24.043Z,1549585224.043 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-02-08T00:20:24.078Z,1549585224.078 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-08T00:20:24.079Z,1549585224.079 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-02-08T00:20:24.079Z,1549585224.079 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-02-08T00:20:24.084Z,1549585224.084 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-02-08T00:20:24.086Z,1549585224.086 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-02-08T00:20:24.086Z,1549585224.086 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-02-08T00:20:24.087Z,1549585224.087 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-02-08T00:20:24.087Z,1549585224.087 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-02-08T00:20:24.088Z,1549585224.088 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-02-08T00:20:24.088Z,1549585224.088 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-02-08T00:20:24.089Z,1549585224.089 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-02-08T00:20:24.089Z,1549585224.089 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-02-08T00:20:24.090Z,1549585224.090 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-02-08T00:20:24.091Z,1549585224.091 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-02-08T00:20:24.118Z,1549585224.118 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-02-08T00:20:24.149Z,1549585224.149 [MissionManager](DEBUG):
2019-02-08T00:20:24.150Z,1549585224.150 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-02-08T00:20:24.229Z,1549585224.229 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-02-08T00:20:24.231Z,1549585224.231 [Default:A.Wait](DEBUG): Construct Wait.
2019-02-08T00:20:24.232Z,1549585224.232 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-02-08T00:20:24.273Z,1549585224.273 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-02-08T00:20:24.287Z,1549585224.287 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-02-08T00:20:24.313Z,1549585224.313 [Default:E.Execute](DEBUG): Construct Execute.
2019-02-08T00:20:24.316Z,1549585224.316 [MissionManager](DEBUG):
0
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2019-02-08T00:20:24.321Z,1549585224.321 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,DataOverHttps,Depth_Keller,DropWeight,DUSBL_Hydroid,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-02-08T00:20:24.344Z,1549585224.344 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-02-08T00:20:24.433Z,1549585224.433 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-02-08T00:20:24.466Z,1549585224.466 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar
2019-02-08T00:20:24.490Z,1549585224.490 [DUSBL_Hydroid](INFO): Start
2019-02-08T00:20:24.490Z,1549585224.490 [DUSBL_Hydroid](INFO): Powering up
2019-02-08T00:20:24.490Z,1549585224.490 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-02-08T00:20:24.637Z,1549585224.637 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-02-08T00:20:24.865Z,1549585224.865 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-02-08T00:20:24.873Z,1549585224.873 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-02-08T00:20:24.895Z,1549585224.895 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-02-08T00:20:24.901Z,1549585224.901 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-02-08T00:20:24.919Z,1549585224.919 [MassServo](DEBUG): Initializing EZServoServo.
2019-02-08T00:20:24.925Z,1549585224.925 [MassServo](DEBUG): Initializing MassServo.
2019-02-08T00:20:24.939Z,1549585224.939 [RudderServo](DEBUG): Initializing EZServoServo.
2019-02-08T00:20:24.945Z,1549585224.945 [RudderServo](DEBUG): Initializing RudderServo.
2019-02-08T00:20:24.966Z,1549585224.966 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-02-08T00:20:24.973Z,1549585224.973 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-02-08T00:20:25.199Z,1549585225.199 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:25.297Z,1549585225.297 [RDI_PathfinderUp](INFO): Powering down
2019-02-08T00:20:25.459Z,1549585225.459 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:25.813Z,1549585225.813 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2019-02-08T00:20:25.813Z,1549585225.813 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2019-02-08T00:20:25.813Z,1549585225.813 [BuoyancyServo] Communications Fault, FailCount= 1
2019-02-08T00:20:25.813Z,1549585225.813 [BuoyancyServo](ERROR): Communications Fault
2019-02-08T00:20:26.013Z,1549585226.013 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2019-02-08T00:20:26.022Z,1549585226.022 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:26.426Z,1549585226.426 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:26.826Z,1549585226.826 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:27.226Z,1549585227.226 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:27.698Z,1549585227.698 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:27.833Z,1549585227.833 [Aanderaa_O2](INFO): Powering down
2019-02-08T00:20:28.050Z,1549585228.050 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:28.126Z,1549585228.126 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2019-02-08T00:20:28.126Z,1549585228.126 [BuoyancyServo] No Fault, FailCount= 1
2019-02-08T00:20:28.450Z,1549585228.450 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:28.479Z,1549585228.479 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-02-08T00:20:28.598Z,1549585228.598 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-02-08T00:20:28.914Z,1549585228.914 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:29.330Z,1549585229.330 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:29.722Z,1549585229.722 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:30.142Z,1549585230.142 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:30.530Z,1549585230.530 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:30.918Z,1549585230.918 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:31.330Z,1549585231.330 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:31.730Z,1549585231.730 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:32.130Z,1549585232.130 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:32.519Z,1549585232.519 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:32.923Z,1549585232.923 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:33.307Z,1549585233.307 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:33.709Z,1549585233.709 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:34.098Z,1549585234.098 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:34.511Z,1549585234.511 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:34.910Z,1549585234.910 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:35.298Z,1549585235.298 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:35.698Z,1549585235.698 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:36.098Z,1549585236.098 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:36.498Z,1549585236.498 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:36.910Z,1549585236.910 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:37.298Z,1549585237.298 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:37.710Z,1549585237.710 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:37.727Z,1549585237.727 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2019-02-08T00:20:38.098Z,1549585238.098 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:38.510Z,1549585238.510 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:38.901Z,1549585238.901 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:39.302Z,1549585239.302 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:39.707Z,1549585239.707 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:40.106Z,1549585240.106 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:40.502Z,1549585240.502 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:40.901Z,1549585240.901 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:41.301Z,1549585241.301 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:41.726Z,1549585241.726 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:42.106Z,1549585242.106 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:42.500Z,1549585242.500 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:42.899Z,1549585242.899 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:43.302Z,1549585243.302 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:43.706Z,1549585243.706 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:44.106Z,1549585244.106 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:44.502Z,1549585244.502 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:44.503Z,1549585244.503 [DUSBL_Hydroid](FAULT): failed to initialize; deviceResponse_ loaded: !U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
, available: 0
2019-02-08T00:20:44.503Z,1549585244.503 [DUSBL_Hydroid] Communications Fault, FailCount= 1
2019-02-08T00:20:44.503Z,1549585244.503 [DUSBL_Hydroid](ERROR): Communications Fault
2019-02-08T00:20:44.550Z,1549585244.550 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid
2019-02-08T00:20:44.906Z,1549585244.906 [DUSBL_Hydroid](INFO): Stop
2019-02-08T00:20:44.906Z,1549585244.906 [DUSBL_Hydroid](INFO): uninitialize
2019-02-08T00:20:44.906Z,1549585244.906 [DUSBL_Hydroid](INFO): Powering down
2019-02-08T00:20:45.302Z,1549585245.302 [DUSBL_Hydroid](INFO): Stopping
2019-02-08T00:20:45.702Z,1549585245.702 [DUSBL_Hydroid](INFO): Stopped
2019-02-08T00:20:45.762Z,1549585245.762 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid
2019-02-08T00:20:45.762Z,1549585245.762 [DUSBL_Hydroid] No Fault, FailCount= 1
2019-02-08T00:20:46.102Z,1549585246.102 [DUSBL_Hydroid](INFO): Start
2019-02-08T00:20:46.502Z,1549585246.502 [DUSBL_Hydroid](INFO): Start
2019-02-08T00:20:46.902Z,1549585246.902 [DUSBL_Hydroid](INFO): Start
2019-02-08T00:20:47.302Z,1549585247.302 [DUSBL_Hydroid](INFO): Start
2019-02-08T00:20:47.702Z,1549585247.702 [DUSBL_Hydroid](INFO): Start
2019-02-08T00:20:48.102Z,1549585248.102 [DUSBL_Hydroid](INFO): Start
2019-02-08T00:20:48.103Z,1549585248.103 [DUSBL_Hydroid](INFO): Powering up
2019-02-08T00:20:48.103Z,1549585248.103 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-02-08T00:20:48.502Z,1549585248.502 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:48.902Z,1549585248.902 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:49.302Z,1549585249.302 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:49.707Z,1549585249.707 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:50.102Z,1549585250.102 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:50.498Z,1549585250.498 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:50.898Z,1549585250.898 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:50.899Z,1549585250.899 [NAL9602](INFO): Powering up NAL9602
2019-02-08T00:20:51.298Z,1549585251.298 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:51.702Z,1549585251.702 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:52.098Z,1549585252.098 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:52.498Z,1549585252.498 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:52.531Z,1549585252.531 [SBIT](IMPORTANT): Beginning Startup BIT
2019-02-08T00:20:52.540Z,1549585252.540 [CBIT](IMPORTANT): Beginning ground fault scan
2019-02-08T00:20:52.910Z,1549585252.910 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:53.306Z,1549585253.306 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:53.706Z,1549585253.706 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:54.098Z,1549585254.098 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:54.498Z,1549585254.498 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:55.002Z,1549585255.002 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:55.402Z,1549585255.402 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:55.802Z,1549585255.802 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:56.210Z,1549585256.210 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:56.602Z,1549585256.602 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:57.002Z,1549585257.002 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:57.402Z,1549585257.402 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:57.819Z,1549585257.819 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:58.202Z,1549585258.202 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:58.602Z,1549585258.602 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:59.002Z,1549585259.002 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:59.398Z,1549585259.398 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:20:59.802Z,1549585259.802 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:00.202Z,1549585260.202 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:00.602Z,1549585260.602 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:01.002Z,1549585261.002 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:01.402Z,1549585261.402 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:01.819Z,1549585261.819 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:01.820Z,1549585261.820 [NAL9602](INFO): NAL9602 initialized
2019-02-08T00:21:02.202Z,1549585262.202 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:02.610Z,1549585262.610 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:02.632Z,1549585262.632 [NAL9602](DEBUG): Fix Requested
2019-02-08T00:21:03.010Z,1549585263.010 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:03.402Z,1549585263.402 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:03.562Z,1549585263.562 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.009738
CHAN A1 (24V): -0.196254
CHAN A2 (12V): -0.005634
CHAN A3 (5V): -0.002318
CHAN B0 (3.3V): 0.000126
CHAN B1 (3.15aV): -0.000067
CHAN B2 (3.15bV): -0.000010
CHAN B3 (GND): 0.001129
OPEN: 0.009313
Full Scale Calc: 4.765 mA, -1.589 mA
2019-02-08T00:21:03.802Z,1549585263.802 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:04.219Z,1549585264.219 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:04.602Z,1549585264.602 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:05.002Z,1549585265.002 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:05.402Z,1549585265.402 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:05.802Z,1549585265.802 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:06.210Z,1549585266.210 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:06.598Z,1549585266.598 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:07.002Z,1549585267.002 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:07.418Z,1549585267.418 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:07.802Z,1549585267.802 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:08.431Z,1549585268.431 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:08.431Z,1549585268.431 [DUSBL_Hydroid](FAULT): failed to initialize; deviceResponse_ loaded: !U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
, available: 0
2019-02-08T00:21:08.431Z,1549585268.431 [DUSBL_Hydroid] Communications Fault, FailCount= 2
2019-02-08T00:21:08.431Z,1549585268.431 [DUSBL_Hydroid](ERROR): Communications Fault
2019-02-08T00:21:08.606Z,1549585268.606 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid
2019-02-08T00:21:08.834Z,1549585268.834 [DUSBL_Hydroid](INFO): Stop
2019-02-08T00:21:08.834Z,1549585268.834 [DUSBL_Hydroid](INFO): uninitialize
2019-02-08T00:21:08.834Z,1549585268.834 [DUSBL_Hydroid](INFO): Powering down
2019-02-08T00:21:09.226Z,1549585269.226 [DUSBL_Hydroid](INFO): Stopping
2019-02-08T00:21:09.626Z,1549585269.626 [DUSBL_Hydroid](INFO): Stopped
2019-02-08T00:21:09.794Z,1549585269.794 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid
2019-02-08T00:21:09.794Z,1549585269.794 [DUSBL_Hydroid] No Fault, FailCount= 2
2019-02-08T00:21:10.034Z,1549585270.034 [DUSBL_Hydroid](INFO): Start
2019-02-08T00:21:10.426Z,1549585270.426 [DUSBL_Hydroid](INFO): Start
2019-02-08T00:21:10.839Z,1549585270.839 [DUSBL_Hydroid](INFO): Start
2019-02-08T00:21:11.243Z,1549585271.243 [DUSBL_Hydroid](INFO): Start
2019-02-08T00:21:11.636Z,1549585271.636 [DUSBL_Hydroid](INFO): Start
2019-02-08T00:21:12.026Z,1549585272.026 [DUSBL_Hydroid](INFO): Start
2019-02-08T00:21:12.026Z,1549585272.026 [DUSBL_Hydroid](INFO): Powering up
2019-02-08T00:21:12.026Z,1549585272.026 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-02-08T00:21:12.453Z,1549585272.453 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:12.826Z,1549585272.826 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:13.226Z,1549585273.226 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:13.637Z,1549585273.637 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:14.026Z,1549585274.026 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:14.422Z,1549585274.422 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:14.826Z,1549585274.826 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:15.234Z,1549585275.234 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:15.626Z,1549585275.626 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:16.026Z,1549585276.026 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:16.442Z,1549585276.442 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:16.839Z,1549585276.839 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:17.234Z,1549585277.234 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:17.626Z,1549585277.626 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:18.034Z,1549585278.034 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:18.434Z,1549585278.434 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:18.834Z,1549585278.834 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:19.234Z,1549585279.234 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:19.653Z,1549585279.653 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:20.026Z,1549585280.026 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:20.426Z,1549585280.426 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:20.826Z,1549585280.826 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:21.238Z,1549585281.238 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:21.627Z,1549585281.627 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:22.035Z,1549585282.035 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:22.426Z,1549585282.426 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:22.834Z,1549585282.834 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:23.243Z,1549585283.243 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:23.626Z,1549585283.626 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:24.034Z,1549585284.034 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:24.441Z,1549585284.441 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:24.835Z,1549585284.835 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:25.226Z,1549585285.226 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:25.630Z,1549585285.630 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:26.026Z,1549585286.026 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:26.439Z,1549585286.439 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:26.830Z,1549585286.830 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:27.238Z,1549585287.238 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:27.631Z,1549585287.631 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:28.026Z,1549585288.026 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:28.438Z,1549585288.438 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:28.838Z,1549585288.838 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:28.841Z,1549585288.841 [NAL9602](INFO): SBD MO Status=2, MOMSN=2138, MT Status=2, MTMSN=0
2019-02-08T00:21:28.841Z,1549585288.841 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-02-08T00:21:29.226Z,1549585289.226 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:29.626Z,1549585289.626 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:30.026Z,1549585290.026 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:30.427Z,1549585290.427 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:30.828Z,1549585290.828 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:31.231Z,1549585291.231 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:31.638Z,1549585291.638 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:32.031Z,1549585292.031 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:32.032Z,1549585292.032 [DUSBL_Hydroid](FAULT): failed to initialize; deviceResponse_ loaded: !U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
, available: 0
2019-02-08T00:21:32.032Z,1549585292.032 [DUSBL_Hydroid] Communications Fault, FailCount= 3
2019-02-08T00:21:32.032Z,1549585292.032 [DUSBL_Hydroid](ERROR): Communications Fault
2019-02-08T00:21:32.163Z,1549585292.163 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid
2019-02-08T00:21:32.430Z,1549585292.430 [DUSBL_Hydroid](INFO): Stop
2019-02-08T00:21:32.430Z,1549585292.430 [DUSBL_Hydroid](INFO): uninitialize
2019-02-08T00:21:32.430Z,1549585292.430 [DUSBL_Hydroid](INFO): Powering down
2019-02-08T00:21:32.830Z,1549585292.830 [DUSBL_Hydroid](INFO): Stopping
2019-02-08T00:21:33.237Z,1549585293.237 [DUSBL_Hydroid](INFO): Stopped
2019-02-08T00:21:33.301Z,1549585293.301 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid
2019-02-08T00:21:33.302Z,1549585293.302 [DUSBL_Hydroid] No Fault, FailCount= 3
2019-02-08T00:21:33.641Z,1549585293.641 [DUSBL_Hydroid](INFO): Start
2019-02-08T00:21:34.034Z,1549585294.034 [DUSBL_Hydroid](INFO): Start
2019-02-08T00:21:34.428Z,1549585294.428 [DUSBL_Hydroid](INFO): Start
2019-02-08T00:21:34.830Z,1549585294.830 [DUSBL_Hydroid](INFO): Start
2019-02-08T00:21:35.370Z,1549585295.370 [DUSBL_Hydroid](INFO): Start
2019-02-08T00:21:35.770Z,1549585295.770 [DUSBL_Hydroid](INFO): Start
2019-02-08T00:21:35.770Z,1549585295.770 [DUSBL_Hydroid](INFO): Powering up
2019-02-08T00:21:35.771Z,1549585295.771 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-02-08T00:21:36.166Z,1549585296.166 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:36.570Z,1549585296.570 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:36.970Z,1549585296.970 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:37.362Z,1549585297.362 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:37.762Z,1549585297.762 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:38.163Z,1549585298.163 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:38.562Z,1549585298.562 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:38.962Z,1549585298.962 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:39.362Z,1549585299.362 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:39.766Z,1549585299.766 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:40.166Z,1549585300.166 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:40.571Z,1549585300.571 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:40.970Z,1549585300.970 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:41.366Z,1549585301.366 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:41.776Z,1549585301.776 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:42.176Z,1549585302.176 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:42.575Z,1549585302.575 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:42.962Z,1549585302.962 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:43.366Z,1549585303.366 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:43.762Z,1549585303.762 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:44.162Z,1549585304.162 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:44.562Z,1549585304.562 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:44.966Z,1549585304.966 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:45.371Z,1549585305.371 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:45.762Z,1549585305.762 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:46.162Z,1549585306.162 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:46.570Z,1549585306.570 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:46.635Z,1549585306.635 [SBIT](IMPORTANT): SBIT PASSED
2019-02-08T00:21:46.705Z,1549585306.705 [CommandLine](IMPORTANT): got command configSet list
2019-02-08T00:21:46.705Z,1549585306.705 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-02-08T00:21:46.707Z,1549585306.707 [CommandLine](IMPORTANT): AHRS_M2.readMagnetics=1 bool;
2019-02-08T00:21:46.707Z,1549585306.707 [CommandLine](IMPORTANT): NAL9602.requestGGA=1 bool;
2019-02-08T00:21:46.962Z,1549585306.962 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:47.022Z,1549585307.022 [MissionManager](IMPORTANT): Started mission Startup
2019-02-08T00:21:47.022Z,1549585307.022 [Startup] Running Loop=1
2019-02-08T00:21:47.022Z,1549585307.022 [Startup](DEBUG): Aggregate::initialize Startup
2019-02-08T00:21:47.022Z,1549585307.022 [Startup:A.GoToSurface] Running Loop=1
2019-02-08T00:21:47.022Z,1549585307.022 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-02-08T00:21:47.023Z,1549585307.023 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-02-08T00:21:47.023Z,1549585307.023 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-02-08T00:21:47.024Z,1549585307.024 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-02-08T00:21:47.024Z,1549585307.024 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-02-08T00:21:47.024Z,1549585307.024 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-02-08T00:21:47.030Z,1549585307.030 [Startup:StartupSatComms] Running Loop=1
2019-02-08T00:21:47.030Z,1549585307.030 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-02-08T00:21:47.030Z,1549585307.030 [Startup:StartupSatComms:A] Running Loop=1
2019-02-08T00:21:47.363Z,1549585307.363 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:47.414Z,1549585307.414 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-02-08T00:21:47.766Z,1549585307.766 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:48.166Z,1549585308.166 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:48.562Z,1549585308.562 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:48.962Z,1549585308.962 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:49.362Z,1549585309.362 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:49.766Z,1549585309.766 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:50.162Z,1549585310.162 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:50.562Z,1549585310.562 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:50.974Z,1549585310.974 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:51.362Z,1549585311.362 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:51.762Z,1549585311.762 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:52.166Z,1549585312.166 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:52.566Z,1549585312.566 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:52.962Z,1549585312.962 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:53.362Z,1549585313.362 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:53.770Z,1549585313.770 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:54.166Z,1549585314.166 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:54.570Z,1549585314.570 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:54.966Z,1549585314.966 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:55.366Z,1549585315.366 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:55.766Z,1549585315.766 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:56.166Z,1549585316.166 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:21:56.167Z,1549585316.167 [DUSBL_Hydroid](FAULT): failed to initialize; deviceResponse_ loaded: !U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
, available: 0
2019-02-08T00:21:56.167Z,1549585316.167 [DUSBL_Hydroid] Communications Fault, FailCount= 4
2019-02-08T00:21:56.167Z,1549585316.167 [DUSBL_Hydroid](ERROR): Communications Fault
2019-02-08T00:21:56.212Z,1549585316.212 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid
2019-02-08T00:21:56.566Z,1549585316.566 [DUSBL_Hydroid](INFO): Stop
2019-02-08T00:21:56.566Z,1549585316.566 [DUSBL_Hydroid](INFO): uninitialize
2019-02-08T00:21:56.566Z,1549585316.566 [DUSBL_Hydroid](INFO): Powering down
2019-02-08T00:21:56.970Z,1549585316.970 [DUSBL_Hydroid](INFO): Stopping
2019-02-08T00:21:57.371Z,1549585317.371 [DUSBL_Hydroid](INFO): Stopped
2019-02-08T00:21:57.434Z,1549585317.434 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid
2019-02-08T00:21:57.434Z,1549585317.434 [DUSBL_Hydroid] No Fault, FailCount= 4
2019-02-08T00:21:57.762Z,1549585317.762 [DUSBL_Hydroid](INFO): Start
2019-02-08T00:21:58.163Z,1549585318.163 [DUSBL_Hydroid](INFO): Start
2019-02-08T00:21:58.562Z,1549585318.562 [DUSBL_Hydroid](INFO): Start
2019-02-08T00:21:58.962Z,1549585318.962 [DUSBL_Hydroid](INFO): Start
2019-02-08T00:21:59.359Z,1549585319.359 [DUSBL_Hydroid](INFO): Start
2019-02-08T00:21:59.766Z,1549585319.766 [DUSBL_Hydroid](INFO): Start
2019-02-08T00:21:59.766Z,1549585319.766 [DUSBL_Hydroid](INFO): Powering up
2019-02-08T00:21:59.767Z,1549585319.767 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-02-08T00:22:00.162Z,1549585320.162 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:00.562Z,1549585320.562 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:00.966Z,1549585320.966 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:01.362Z,1549585321.362 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:01.766Z,1549585321.766 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:02.162Z,1549585322.162 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:02.562Z,1549585322.562 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:02.962Z,1549585322.962 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:03.362Z,1549585323.362 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:03.762Z,1549585323.762 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:04.162Z,1549585324.162 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:04.562Z,1549585324.562 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:04.962Z,1549585324.962 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:05.362Z,1549585325.362 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:05.766Z,1549585325.766 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:06.162Z,1549585326.162 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:06.562Z,1549585326.562 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:06.966Z,1549585326.966 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:07.362Z,1549585327.362 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:07.763Z,1549585327.763 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:08.162Z,1549585328.162 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:08.566Z,1549585328.566 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:08.963Z,1549585328.963 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:09.379Z,1549585329.379 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:09.776Z,1549585329.776 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:10.166Z,1549585330.166 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:10.562Z,1549585330.562 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:10.962Z,1549585330.962 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:11.366Z,1549585331.366 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:11.766Z,1549585331.766 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:12.166Z,1549585332.166 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:12.566Z,1549585332.566 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:12.966Z,1549585332.966 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:13.362Z,1549585333.362 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:13.762Z,1549585333.762 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:14.166Z,1549585334.166 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:14.562Z,1549585334.562 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:14.962Z,1549585334.962 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:15.367Z,1549585335.367 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:15.762Z,1549585335.762 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:16.159Z,1549585336.159 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:16.562Z,1549585336.562 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:16.971Z,1549585336.971 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:17.366Z,1549585337.366 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:17.762Z,1549585337.762 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:18.162Z,1549585338.162 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:18.562Z,1549585338.562 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:18.962Z,1549585338.962 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:19.362Z,1549585339.362 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:19.762Z,1549585339.762 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:20.162Z,1549585340.162 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:20.163Z,1549585340.163 [DUSBL_Hydroid](FAULT): failed to initialize; deviceResponse_ loaded: !U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
, available: 0
2019-02-08T00:22:20.163Z,1549585340.163 [DUSBL_Hydroid] Communications Fault, FailCount= 5
2019-02-08T00:22:20.163Z,1549585340.163 [DUSBL_Hydroid](ERROR): Communications Fault
2019-02-08T00:22:20.228Z,1549585340.228 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid
2019-02-08T00:22:20.562Z,1549585340.562 [DUSBL_Hydroid](INFO): Stop
2019-02-08T00:22:20.562Z,1549585340.562 [DUSBL_Hydroid](INFO): uninitialize
2019-02-08T00:22:20.562Z,1549585340.562 [DUSBL_Hydroid](INFO): Powering down
2019-02-08T00:22:20.962Z,1549585340.962 [DUSBL_Hydroid](INFO): Stopping
2019-02-08T00:22:21.366Z,1549585341.366 [DUSBL_Hydroid](INFO): Stopped
2019-02-08T00:22:21.423Z,1549585341.423 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid
2019-02-08T00:22:21.423Z,1549585341.423 [DUSBL_Hydroid] No Fault, FailCount= 5
2019-02-08T00:22:21.774Z,1549585341.774 [DUSBL_Hydroid](INFO): Start
2019-02-08T00:22:22.162Z,1549585342.162 [DUSBL_Hydroid](INFO): Start
2019-02-08T00:22:22.562Z,1549585342.562 [DUSBL_Hydroid](INFO): Start
2019-02-08T00:22:22.962Z,1549585342.962 [DUSBL_Hydroid](INFO): Start
2019-02-08T00:22:23.362Z,1549585343.362 [DUSBL_Hydroid](INFO): Start
2019-02-08T00:22:23.762Z,1549585343.762 [DUSBL_Hydroid](INFO): Start
2019-02-08T00:22:23.762Z,1549585343.762 [DUSBL_Hydroid](INFO): Powering up
2019-02-08T00:22:23.763Z,1549585343.763 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-02-08T00:22:24.172Z,1549585344.172 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:24.562Z,1549585344.562 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:24.966Z,1549585344.966 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:25.366Z,1549585345.366 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:25.762Z,1549585345.762 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:26.162Z,1549585346.162 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:26.562Z,1549585346.562 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:26.962Z,1549585346.962 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:27.366Z,1549585347.366 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:27.762Z,1549585347.762 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:28.162Z,1549585348.162 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:28.562Z,1549585348.562 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:28.962Z,1549585348.962 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:29.370Z,1549585349.370 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:29.762Z,1549585349.762 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:30.162Z,1549585350.162 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:30.562Z,1549585350.562 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:30.966Z,1549585350.966 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:31.362Z,1549585351.362 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:31.762Z,1549585351.762 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:32.162Z,1549585352.162 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:32.562Z,1549585352.562 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:32.966Z,1549585352.966 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:33.374Z,1549585353.374 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:33.762Z,1549585353.762 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:34.162Z,1549585354.162 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:34.567Z,1549585354.567 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:34.962Z,1549585354.962 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:35.366Z,1549585355.366 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:35.766Z,1549585355.766 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:36.162Z,1549585356.162 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:36.562Z,1549585356.562 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:36.966Z,1549585356.966 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:37.363Z,1549585357.363 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:37.762Z,1549585357.762 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:38.162Z,1549585358.162 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:38.562Z,1549585358.562 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:38.959Z,1549585358.959 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:39.362Z,1549585359.362 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:39.766Z,1549585359.766 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:40.162Z,1549585360.162 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:40.562Z,1549585360.562 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:40.972Z,1549585360.972 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:41.370Z,1549585361.370 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:41.758Z,1549585361.758 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:42.162Z,1549585362.162 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:42.562Z,1549585362.562 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:42.962Z,1549585362.962 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:43.362Z,1549585363.362 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:43.762Z,1549585363.762 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:44.163Z,1549585364.163 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:44.163Z,1549585364.163 [DUSBL_Hydroid](FAULT): failed to initialize; deviceResponse_ loaded: !U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
, available: 0
2019-02-08T00:22:44.163Z,1549585364.163 [DUSBL_Hydroid] Communications Fault, FailCount= 6
2019-02-08T00:22:44.164Z,1549585364.164 [DUSBL_Hydroid](ERROR): Communications Fault
2019-02-08T00:22:44.252Z,1549585364.252 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid
2019-02-08T00:22:44.566Z,1549585364.566 [DUSBL_Hydroid](INFO): Stop
2019-02-08T00:22:44.566Z,1549585364.566 [DUSBL_Hydroid](INFO): uninitialize
2019-02-08T00:22:44.566Z,1549585364.566 [DUSBL_Hydroid](INFO): Powering down
2019-02-08T00:22:44.970Z,1549585364.970 [DUSBL_Hydroid](INFO): Stopping
2019-02-08T00:22:45.362Z,1549585365.362 [DUSBL_Hydroid](INFO): Stopped
2019-02-08T00:22:45.424Z,1549585365.424 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid
2019-02-08T00:22:45.424Z,1549585365.424 [DUSBL_Hydroid] No Fault, FailCount= 6
2019-02-08T00:22:45.786Z,1549585365.786 [DUSBL_Hydroid](INFO): Start
2019-02-08T00:22:49.068Z,1549585369.068 [DUSBL_Hydroid](INFO): Start
2019-02-08T00:22:49.068Z,1549585369.068 [DUSBL_Hydroid](INFO): Powering up
2019-02-08T00:22:49.068Z,1549585369.068 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-02-08T00:22:49.163Z,1549585369.163 [Startup:StartupSatComms:A](INFO): Timed out from 2019-02-08T00:21:47.0Z
2019-02-08T00:22:49.163Z,1549585369.163 [Startup:StartupSatComms:A] Stopped
2019-02-08T00:22:49.163Z,1549585369.163 [Startup:StartupSatComms:B] Running Loop=1
2019-02-08T00:22:49.235Z,1549585369.235 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:49.282Z,1549585369.282 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-02-08T00:22:49.618Z,1549585369.618 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:50.010Z,1549585370.010 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004798
2019-02-08T00:22:50.014Z,1549585370.014 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:50.422Z,1549585370.422 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:50.810Z,1549585370.810 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:54.026Z,1549585374.026 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:54.239Z,1549585374.239 [DataOverHttps](INFO): Sending 113 bytes from file Logs/20190208T001445/Courier0004.lzma
2019-02-08T00:22:54.294Z,1549585374.294 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:54.656Z,1549585374.656 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:55.034Z,1549585375.034 [DataOverHttps](INFO): Moved sent file to Logs/20190208T001445/Courier0004.lzma.bak
2019-02-08T00:22:55.034Z,1549585375.034 [DataOverHttps](INFO): SBD MOMSN=9641522
2019-02-08T00:22:55.042Z,1549585375.042 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:55.458Z,1549585375.458 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:55.838Z,1549585375.838 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:59.061Z,1549585379.061 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:59.221Z,1549585379.221 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:59.590Z,1549585379.590 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:22:59.974Z,1549585379.974 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:23:00.370Z,1549585380.370 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:23:00.770Z,1549585380.770 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:23:04.003Z,1549585384.003 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:23:04.124Z,1549585384.124 [DataOverHttps](INFO): Sending 295 bytes from file Logs/20190208T001445/Express0005.lzma
2019-02-08T00:23:04.142Z,1549585384.142 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:23:04.508Z,1549585384.508 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:23:04.889Z,1549585384.889 [DataOverHttps](INFO): Moved sent file to Logs/20190208T001445/Express0005.lzma.bak
2019-02-08T00:23:04.890Z,1549585384.890 [DataOverHttps](INFO): SBD MOMSN=9641526
2019-02-08T00:23:04.910Z,1549585384.910 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:23:05.324Z,1549585385.324 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:23:05.703Z,1549585385.703 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:23:08.916Z,1549585388.916 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:23:09.211Z,1549585389.211 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:23:09.212Z,1549585389.212 [DUSBL_Hydroid](FAULT): failed to initialize; deviceResponse_ loaded: !U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
, available: 0
2019-02-08T00:23:09.212Z,1549585389.212 [DUSBL_Hydroid] Communications Fault, FailCount= 7
2019-02-08T00:23:09.212Z,1549585389.212 [DUSBL_Hydroid](ERROR): Communications Fault
2019-02-08T00:23:09.312Z,1549585389.312 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid
2019-02-08T00:23:09.554Z,1549585389.554 [DUSBL_Hydroid](INFO): Stop
2019-02-08T00:23:09.554Z,1549585389.554 [DUSBL_Hydroid](INFO): uninitialize
2019-02-08T00:23:09.554Z,1549585389.554 [DUSBL_Hydroid](INFO): Powering down
2019-02-08T00:23:09.954Z,1549585389.954 [DUSBL_Hydroid](INFO): Stopping
2019-02-08T00:23:10.350Z,1549585390.350 [DUSBL_Hydroid](INFO): Stopped
2019-02-08T00:23:10.410Z,1549585390.410 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid
2019-02-08T00:23:10.410Z,1549585390.410 [DUSBL_Hydroid] No Fault, FailCount= 7
2019-02-08T00:23:10.754Z,1549585390.754 [DUSBL_Hydroid](INFO): Start
2019-02-08T00:23:13.939Z,1549585393.939 [DUSBL_Hydroid](INFO): Start
2019-02-08T00:23:13.939Z,1549585393.939 [DUSBL_Hydroid](INFO): Powering up
2019-02-08T00:23:13.940Z,1549585393.940 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-02-08T00:23:14.086Z,1549585394.086 [DataOverHttps](INFO): Sending 808 bytes from file Logs/20190208T002013/Express0001.lzma
2019-02-08T00:23:14.096Z,1549585394.096 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:23:14.466Z,1549585394.466 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:23:14.861Z,1549585394.861 [DataOverHttps](INFO): Moved sent file to Logs/20190208T002013/Express0001.lzma.bak
2019-02-08T00:23:14.861Z,1549585394.861 [DataOverHttps](INFO): SBD MOMSN=9641539
2019-02-08T00:23:14.870Z,1549585394.870 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:23:15.282Z,1549585395.282 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:23:15.325Z,1549585395.325 [Startup:StartupSatComms:B] Stopped
2019-02-08T00:23:15.326Z,1549585395.326 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-02-08T00:23:15.326Z,1549585395.326 [Startup:StartupSatComms] Stopped
2019-02-08T00:23:15.326Z,1549585395.326 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-02-08T00:23:15.327Z,1549585395.327 [Startup](INFO): Completed Startup
2019-02-08T00:23:15.327Z,1549585395.327 [MissionManager](INFO): Startup is completed.
2019-02-08T00:23:15.327Z,1549585395.327 [MissionManager](INFO): Uninitializing Mission Startup
2019-02-08T00:23:15.327Z,1549585395.327 [Startup] Stopped
2019-02-08T00:23:15.327Z,1549585395.327 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-02-08T00:23:15.327Z,1549585395.327 [Startup:A.GoToSurface] Stopped
2019-02-08T00:23:15.327Z,1549585395.327 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-02-08T00:23:15.685Z,1549585395.685 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:23:15.743Z,1549585395.743 [MissionManager](IMPORTANT): Started mission Default
2019-02-08T00:23:15.743Z,1549585395.743 [Default] Running Loop=1
2019-02-08T00:23:15.744Z,1549585395.744 [Default](DEBUG): Aggregate::initialize Default
2019-02-08T00:23:15.744Z,1549585395.744 [Default:B.GoToSurface] Running Loop=1
2019-02-08T00:23:15.744Z,1549585395.744 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-02-08T00:23:15.744Z,1549585395.744 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-02-08T00:23:15.744Z,1549585395.744 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-02-08T00:23:15.757Z,1549585395.757 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-02-08T00:23:15.757Z,1549585395.757 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-02-08T00:23:15.758Z,1549585395.758 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-02-08T00:23:15.758Z,1549585395.758 [Default:A.Wait] Running Loop=1
2019-02-08T00:23:15.758Z,1549585395.758 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-02-08T00:23:18.886Z,1549585398.886 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:23:19.038Z,1549585399.038 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:23:19.418Z,1549585399.418 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:23:19.818Z,1549585399.818 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:23:20.210Z,1549585400.210 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:23:20.611Z,1549585400.611 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:23:21.010Z,1549585401.010 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:23:21.410Z,1549585401.410 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:23:21.810Z,1549585401.810 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:23:22.210Z,1549585402.210 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:23:22.610Z,1549585402.610 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:23:23.010Z,1549585403.010 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:23:23.410Z,1549585403.410 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:23:23.810Z,1549585403.810 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:23:24.210Z,1549585404.210 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:23:24.258Z,1549585404.258 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-02-08T00:23:24.258Z,1549585404.258 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-02-08T00:23:24.259Z,1549585404.259 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-02-08T00:23:24.259Z,1549585404.259 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-02-08T00:23:24.265Z,1549585404.265 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2019-02-08T00:23:24.265Z,1549585404.265 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-08T00:23:24.291Z,1549585404.291 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-02-08T00:23:24.292Z,1549585404.292 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-02-08T00:23:24.292Z,1549585404.292 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-08T00:23:24.610Z,1549585404.610 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:23:24.703Z,1549585404.703 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-02-08T00:23:24.703Z,1549585404.703 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-02-08T00:23:24.703Z,1549585404.703 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-02-08T00:23:24.704Z,1549585404.704 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-02-08T00:23:24.704Z,1549585404.704 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-08T00:23:24.704Z,1549585404.704 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2019-02-08T00:23:25.018Z,1549585405.018 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:23:25.063Z,1549585405.063 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-08T00:23:25.410Z,1549585405.410 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:23:25.810Z,1549585405.810 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:23:26.210Z,1549585406.210 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:23:26.611Z,1549585406.611 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:23:27.010Z,1549585407.010 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:23:27.410Z,1549585407.410 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:23:27.810Z,1549585407.810 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:23:28.210Z,1549585408.210 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:23:28.610Z,1549585408.610 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:23:29.010Z,1549585409.010 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:23:29.073Z,1549585409.073 [Default:A.Wait](INFO): Done Waiting.
2019-02-08T00:23:29.073Z,1549585409.073 [Default:A.Wait] Stopped
2019-02-08T00:23:29.073Z,1549585409.073 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-02-08T00:23:29.407Z,1549585409.407 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:23:29.451Z,1549585409.451 [Default:CheckIn] Running Loop=1
2019-02-08T00:23:29.451Z,1549585409.451 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-02-08T00:23:29.452Z,1549585409.452 [Default:CheckIn:Read_GPS] Running Loop=1
2019-02-08T00:23:29.810Z,1549585409.810 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:23:29.875Z,1549585409.875 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-02-08T00:23:30.210Z,1549585410.210 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:23:30.610Z,1549585410.610 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:23:31.018Z,1549585411.018 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:23:31.410Z,1549585411.410 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:23:31.810Z,1549585411.810 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:23:32.210Z,1549585412.210 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:23:32.610Z,1549585412.610 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:23:33.014Z,1549585413.014 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:23:33.418Z,1549585413.418 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:23:33.814Z,1549585413.814 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:23:34.210Z,1549585414.210 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:23:34.211Z,1549585414.211 [DUSBL_Hydroid](FAULT): failed to initialize; deviceResponse_ loaded: !U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
, available: 0
2019-02-08T00:23:34.211Z,1549585414.211 [DUSBL_Hydroid] Communications Fault, FailCount= 8
2019-02-08T00:23:34.211Z,1549585414.211 [DUSBL_Hydroid](ERROR): Communications Fault
2019-02-08T00:23:34.290Z,1549585414.290 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid
2019-02-08T00:23:34.291Z,1549585414.291 [CBIT](CRITICAL): Communications Fault in component: DUSBL_Hydroid
2019-02-08T00:23:34.614Z,1549585414.614 [DUSBL_Hydroid](INFO): Stop
2019-02-08T00:23:34.614Z,1549585414.614 [DUSBL_Hydroid](INFO): uninitialize
2019-02-08T00:23:34.614Z,1549585414.614 [DUSBL_Hydroid](INFO): Powering down
2019-02-08T00:23:34.733Z,1549585414.733 [CommandLine](FAULT): Scheduling is paused
2019-02-08T00:23:34.733Z,1549585414.733 [CBIT](INFO): Critical error at 20190208T002334
2019-02-08T00:23:34.734Z,1549585414.734 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-02-08T00:23:35.010Z,1549585415.010 [DUSBL_Hydroid](INFO): Stopping
2019-02-08T00:23:35.418Z,1549585415.418 [DUSBL_Hydroid](INFO): Stopped
2019-02-08T00:26:05.162Z,1549585565.162 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-02-08T00:26:05.977Z,1549585565.977 [NAL9602](DEBUG): Fix Requested
2019-02-08T00:26:06.402Z,1549585566.402 [NAL9602](DEBUG): Parsing GGA sentence: $GPGGA,002517.00,3648.16225,N,12147.28496,W,1,4,3.14,18.5,M,-30.7,M,,*63
2019-02-08T00:26:06.417Z,1549585566.417 [NAL9602](INFO): GPS fix at 20190208T002517: (36.802704, -121.788083)
2019-02-08T00:26:06.760Z,1549585566.760 [Default:CheckIn:Read_GPS] Stopped
2019-02-08T00:26:06.760Z,1549585566.760 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-02-08T00:26:07.151Z,1549585567.151 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-02-08T00:26:24.272Z,1549585584.272 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2019-02-08T00:26:24.272Z,1549585584.272 [RDI_Pathfinder] Communications Fault, FailCount= 1
2019-02-08T00:26:24.272Z,1549585584.272 [RDI_Pathfinder](ERROR): Communications Fault
2019-02-08T00:26:24.386Z,1549585584.386 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2019-02-08T00:26:24.477Z,1549585584.477 [DataOverHttps](INFO): Sending 279 bytes from file Logs/20190208T002013/Courier0004.lzma
2019-02-08T00:26:24.621Z,1549585584.621 [RDI_Pathfinder](INFO): Powering down
2019-02-08T00:26:25.199Z,1549585585.199 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2019-02-08T00:26:25.199Z,1549585585.199 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-08T00:26:25.262Z,1549585585.262 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-08T00:26:25.468Z,1549585585.468 [DataOverHttps](INFO): Moved sent file to Logs/20190208T002013/Courier0004.lzma.bak
2019-02-08T00:26:25.468Z,1549585585.468 [DataOverHttps](INFO): SBD MOMSN=9641562
2019-02-08T00:26:25.589Z,1549585585.589 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-08T00:26:25.589Z,1549585585.589 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2019-02-08T00:26:25.590Z,1549585585.590 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2019-02-08T00:26:25.590Z,1549585585.590 [RDI_Pathfinder] No Fault, FailCount= 1
2019-02-08T00:26:27.737Z,1549585587.737 [CommandLine](IMPORTANT): got command restart application
2019-02-08T00:26:28.747Z,1549585588.747 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-08T00:26:28.747Z,1549585588.747 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-02-08T00:26:28.917Z,1549585588.917 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2019-02-08T00:26:28.917Z,1549585588.917 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-02-08T00:26:28.917Z,1549585588.917 [CommandLine](INFO): Join timeout helper Thread ID is 1228
2019-02-08T00:26:28.929Z,1549585588.929 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-02-08T00:26:28.929Z,1549585588.929 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-02-08T00:26:28.929Z,1549585588.929 [NavChartDb](INFO): Join timeout helper Thread ID is 1229
2019-02-08T00:26:28.977Z,1549585588.977 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-08T00:26:28.977Z,1549585588.977 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-02-08T00:26:28.989Z,1549585588.989 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2019-02-08T00:26:28.989Z,1549585588.989 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-02-08T00:26:28.989Z,1549585588.989 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 1230
2019-02-08T00:26:29.345Z,1549585589.345 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-08T00:26:29.345Z,1549585589.345 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-02-08T00:26:29.346Z,1549585589.346 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-02-08T00:26:29.361Z,1549585589.361 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-02-08T00:26:29.361Z,1549585589.361 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-02-08T00:26:29.361Z,1549585589.361 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 1231
2019-02-08T00:26:29.469Z,1549585589.469 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-08T00:26:29.469Z,1549585589.469 [CTD_NeilBrown](INFO): Powering down
2019-02-08T00:26:29.481Z,1549585589.481 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-02-08T00:26:29.493Z,1549585589.493 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-02-08T00:26:29.493Z,1549585589.493 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-02-08T00:26:29.493Z,1549585589.493 [Radio_Surface](INFO): Join timeout helper Thread ID is 1232
2019-02-08T00:26:29.721Z,1549585589.721 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-08T00:26:29.721Z,1549585589.721 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-02-08T00:26:29.726Z,1549585589.726 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-02-08T00:26:29.726Z,1549585589.726 [logger ThreadHandler](INFO): Thread cancelled.
2019-02-08T00:26:29.726Z,1549585589.726 [logger](INFO): Join timeout helper Thread ID is 1233
2019-02-08T00:26:29.761Z,1549585589.761 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-08T00:26:29.761Z,1549585589.761 [logger ThreadHandler](INFO): Thread cancelled.
2019-02-08T00:26:29.777Z,1549585589.777 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-02-08T00:26:29.778Z,1549585589.778 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-02-08T00:26:29.778Z,1549585589.778 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-02-08T00:26:29.778Z,1549585589.778 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-02-08T00:26:29.778Z,1549585589.778 [controlThread](INFO): Join timeout helper Thread ID is 1234
2019-02-08T00:26:29.861Z,1549585589.861 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-08T00:26:29.861Z,1549585589.861 [controlThread](DEBUG): Uninitializing ControlThread
2019-02-08T00:26:29.862Z,1549585589.862 [AHRS_M2](INFO): Powering down
2019-02-08T00:26:29.936Z,1549585589.936 [DUSBL_Hydroid](INFO): uninitialize
2019-02-08T00:26:29.936Z,1549585589.936 [DUSBL_Hydroid](INFO): Powering down
2019-02-08T00:26:29.937Z,1549585589.937 [NAL9602](INFO): Powering down
2019-02-08T00:26:30.009Z,1549585590.009 [RDI_Pathfinder](INFO): Powering down
2019-02-08T00:26:30.010Z,1549585590.010 [RDI_PathfinderUp](INFO): Powering down
2019-02-08T00:26:30.011Z,1549585590.011 [Aanderaa_O2](INFO): Powering down
2019-02-08T00:26:30.012Z,1549585590.012 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-02-08T00:26:30.013Z,1549585590.013 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-02-08T00:26:30.014Z,1549585590.014 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-02-08T00:26:30.015Z,1549585590.015 [MissionManager](INFO): Uninitializing Mission Default
2019-02-08T00:26:30.015Z,1549585590.015 [Default] Stopped
2019-02-08T00:26:30.015Z,1549585590.015 [Default](DEBUG): Aggregate::uninitialize Default
2019-02-08T00:26:30.015Z,1549585590.015 [Default:B.GoToSurface] Stopped
2019-02-08T00:26:30.015Z,1549585590.015 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-02-08T00:26:30.015Z,1549585590.015 [Default:CheckIn] Stopped
2019-02-08T00:26:30.015Z,1549585590.015 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-02-08T00:26:30.015Z,1549585590.015 [Default:CheckIn:Read_Iridium] Stopped
2019-02-08T00:26:30.018Z,1549585590.018 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-02-08T00:26:30.018Z,1549585590.018 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-02-08T00:26:30.018Z,1549585590.018 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-02-08T00:26:30.019Z,1549585590.019 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-02-08T00:26:30.019Z,1549585590.019 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-02-08T00:26:30.019Z,1549585590.019 [BuoyancyServo](INFO): Powering down
2019-02-08T00:26:30.033Z,1549585590.033 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-02-08T00:26:30.033Z,1549585590.033 [ElevatorServo](INFO): Powering down
2019-02-08T00:26:30.034Z,1549585590.034 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-02-08T00:26:30.034Z,1549585590.034 [MassServo](INFO): Powering down
2019-02-08T00:26:30.035Z,1549585590.035 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-02-08T00:26:30.035Z,1549585590.035 [RudderServo](INFO): Powering down
2019-02-08T00:26:30.036Z,1549585590.036 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-02-08T00:26:30.036Z,1549585590.036 [ThrusterServo](INFO): Powering down
2019-02-08T00:26:30.037Z,1549585590.037 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-02-08T00:26:30.037Z,1549585590.037 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-02-08T00:26:30.037Z,1549585590.037 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-02-08T00:26:30.037Z,1549585590.037 [CBIT](DEBUG): Powering off loads.
2019-02-08T00:26:30.049Z,1549585590.049 [CBIT](DEBUG): Disabling WDT.
2019-02-08T00:26:30.061Z,1549585590.061 [CBIT](DEBUG): Opening all GF detection circuits.
2019-02-08T00:26:30.062Z,1549585590.062 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-02-08T00:26:30.161Z,1549585590.161 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-02-08T00:26:30.210Z,1549585590.210 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-02-08T00:26:30.212Z,1549585590.212 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-02-08T00:26:30.237Z,1549585590.237 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-02-08T00:26:30.299Z,1549585590.299 [logger ThreadHandler](INFO): Thread cancelled.