2019-05-21T14:11:34.763Z,1558447894.763 [Supervisor](DEBUG): Initializing supervisor.
2019-05-21T14:11:34.767Z,1558447894.767 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-05-21T14:11:34.768Z,1558447894.768 [SyncHandler](INFO): Protected caller Thread ID is 8223
2019-05-21T14:11:34.768Z,1558447894.768 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-05-21T14:11:34.769Z,1558447894.769 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-05-21T14:11:34.770Z,1558447894.770 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 8224
2019-05-21T14:11:34.773Z,1558447894.773 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-05-21T14:11:34.785Z,1558447894.785 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-05-21T14:11:34.786Z,1558447894.786 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-05-21T14:11:34.786Z,1558447894.786 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 8225
2019-05-21T14:11:34.787Z,1558447894.787 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-05-21T14:11:34.788Z,1558447894.788 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-05-21T14:11:34.789Z,1558447894.789 [logger ThreadHandler](INFO): Protected caller Thread ID is 8226
2019-05-21T14:11:34.791Z,1558447894.791 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-05-21T14:11:34.791Z,1558447894.791 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-05-21T14:11:34.793Z,1558447894.793 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-05-21T14:11:35.334Z,1558447895.334 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-05-21T14:11:35.335Z,1558447895.335 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-05-21T14:11:35.464Z,1558447895.464 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-05-21T14:11:35.465Z,1558447895.465 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-05-21T14:11:35.843Z,1558447895.843 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-05-21T14:11:35.843Z,1558447895.843 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-05-21T14:11:35.990Z,1558447895.990 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-05-21T14:11:35.990Z,1558447895.990 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-05-21T14:11:36.228Z,1558447896.228 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-05-21T14:11:36.229Z,1558447896.229 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-05-21T14:11:36.734Z,1558447896.734 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-05-21T14:11:36.735Z,1558447896.735 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-05-21T14:11:36.986Z,1558447896.986 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-05-21T14:11:36.987Z,1558447896.987 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-05-21T14:11:37.135Z,1558447897.135 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-05-21T14:11:37.136Z,1558447897.136 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-05-21T14:11:37.376Z,1558447897.376 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-05-21T14:11:37.376Z,1558447897.376 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-05-21T14:11:37.473Z,1558447897.473 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-05-21T14:11:37.474Z,1558447897.474 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-05-21T14:11:37.841Z,1558447897.841 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-05-21T14:11:37.842Z,1558447897.842 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-05-21T14:11:37.923Z,1558447897.923 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-05-21T14:11:38.065Z,1558447898.065 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-05-21T14:11:38.066Z,1558447898.066 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-05-21T14:11:39.158Z,1558447899.158 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-05-21T14:11:39.159Z,1558447899.159 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-05-21T14:11:39.594Z,1558447899.594 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-05-21T14:11:39.596Z,1558447899.596 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2019-05-21T14:11:39.597Z,1558447899.597 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2019-05-21T14:11:39.848Z,1558447899.848 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2019-05-21T14:11:39.955Z,1558447899.955 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2019-05-21T14:11:40.056Z,1558447900.056 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2019-05-21T14:11:40.323Z,1558447900.323 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-05-21T14:11:40.324Z,1558447900.324 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2019-05-21T14:11:40.410Z,1558447900.410 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2019-05-21T14:11:40.505Z,1558447900.505 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2019-05-21T14:11:40.640Z,1558447900.640 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2019-05-21T14:11:40.723Z,1558447900.723 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2019-05-21T14:11:40.834Z,1558447900.834 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2019-05-21T14:11:41.056Z,1558447901.056 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2019-05-21T14:11:41.191Z,1558447901.191 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/
2019-05-21T14:11:41.191Z,1558447901.191 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-05-21T14:11:41.207Z,1558447901.207 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-05-21T14:11:42.015Z,1558447902.015 [AHRS_M2] Loaded
2019-05-21T14:11:42.015Z,1558447902.015 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2019-05-21T14:11:42.111Z,1558447902.111 [DataOverHttps] Loaded
2019-05-21T14:11:42.111Z,1558447902.111 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-05-21T14:11:42.112Z,1558447902.112 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 407604E0
2019-05-21T14:11:42.112Z,1558447902.112 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 8345
2019-05-21T14:11:42.126Z,1558447902.126 [Depth_Keller] Loaded
2019-05-21T14:11:42.126Z,1558447902.126 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-05-21T14:11:42.130Z,1558447902.130 [DropWeight] Loaded
2019-05-21T14:11:42.131Z,1558447902.131 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-05-21T14:11:42.188Z,1558447902.188 [DUSBL_Hydroid] Loaded
2019-05-21T14:11:42.188Z,1558447902.188 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2019-05-21T14:11:42.236Z,1558447902.236 [Micromodem] Loaded
2019-05-21T14:11:42.236Z,1558447902.236 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2019-05-21T14:11:42.377Z,1558447902.377 [NAL9602] Loaded
2019-05-21T14:11:42.377Z,1558447902.377 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-05-21T14:11:42.393Z,1558447902.393 [Onboard] Loaded
2019-05-21T14:11:42.393Z,1558447902.393 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-05-21T14:11:42.399Z,1558447902.399 [PowerOnly] Loaded
2019-05-21T14:11:42.399Z,1558447902.399 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2019-05-21T14:11:42.406Z,1558447902.406 [Radio_Surface] Loaded
2019-05-21T14:11:42.406Z,1558447902.406 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-05-21T14:11:42.407Z,1558447902.407 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407904E0
2019-05-21T14:11:42.407Z,1558447902.407 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 8348
2019-05-21T14:11:42.451Z,1558447902.451 [RDI_Pathfinder] Loaded
2019-05-21T14:11:42.452Z,1558447902.452 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2019-05-21T14:11:44.136Z,1558447904.136 [BPC1] Loaded
2019-05-21T14:11:44.136Z,1558447904.136 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-05-21T14:11:44.137Z,1558447904.137 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-05-21T14:11:44.137Z,1558447904.137 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-05-21T14:11:44.267Z,1558447904.267 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-05-21T14:11:44.267Z,1558447904.267 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-05-21T14:11:44.735Z,1558447904.735 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-05-21T14:11:44.735Z,1558447904.735 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-05-21T14:11:44.776Z,1558447904.776 [NavChart] Loaded
2019-05-21T14:11:44.776Z,1558447904.776 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-05-21T14:11:44.788Z,1558447904.788 [UniversalFixResidualReporter] Loaded
2019-05-21T14:11:44.788Z,1558447904.788 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-05-21T14:11:44.789Z,1558447904.789 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-05-21T14:11:44.790Z,1558447904.790 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-05-21T14:11:44.923Z,1558447904.923 [BuoyancyServo] Loaded
2019-05-21T14:11:44.923Z,1558447904.923 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-05-21T14:11:44.938Z,1558447904.938 [ElevatorServo] Loaded
2019-05-21T14:11:44.939Z,1558447904.939 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-05-21T14:11:44.954Z,1558447904.954 [MassServo] Loaded
2019-05-21T14:11:44.954Z,1558447904.954 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-05-21T14:11:44.969Z,1558447904.969 [RudderServo] Loaded
2019-05-21T14:11:44.970Z,1558447904.970 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-05-21T14:11:44.984Z,1558447904.984 [ThrusterServo] Loaded
2019-05-21T14:11:44.985Z,1558447904.985 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-05-21T14:11:44.985Z,1558447904.985 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-05-21T14:11:44.986Z,1558447904.986 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-05-21T14:11:45.354Z,1558447905.354 [CTD_NeilBrown] Loaded
2019-05-21T14:11:45.354Z,1558447905.354 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-05-21T14:11:45.355Z,1558447905.355 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408D24E0
2019-05-21T14:11:45.355Z,1558447905.355 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 8365
2019-05-21T14:11:45.400Z,1558447905.400 [WetLabsSeaOWL_UV_A] Loaded
2019-05-21T14:11:45.400Z,1558447905.400 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2019-05-21T14:11:45.401Z,1558447905.401 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 409024E0
2019-05-21T14:11:45.402Z,1558447905.402 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 8366
2019-05-21T14:11:45.402Z,1558447905.402 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-05-21T14:11:45.403Z,1558447905.403 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-05-21T14:11:45.783Z,1558447905.783 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-05-21T14:11:45.783Z,1558447905.783 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-05-21T14:11:45.875Z,1558447905.875 [DepthRateCalculator] Loaded
2019-05-21T14:11:45.875Z,1558447905.875 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-05-21T14:11:45.881Z,1558447905.881 [PitchRateCalculator] Loaded
2019-05-21T14:11:45.881Z,1558447905.881 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-05-21T14:11:45.893Z,1558447905.893 [SpeedCalculator] Loaded
2019-05-21T14:11:45.894Z,1558447905.894 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-05-21T14:11:45.915Z,1558447905.915 [TempGradientCalculator] Loaded
2019-05-21T14:11:45.915Z,1558447905.915 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-05-21T14:11:45.920Z,1558447905.920 [YawRateCalculator] Loaded
2019-05-21T14:11:45.921Z,1558447905.921 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-05-21T14:11:45.961Z,1558447905.961 [ElevatorOffsetCalculator] Loaded
2019-05-21T14:11:45.961Z,1558447905.961 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-05-21T14:11:45.962Z,1558447905.962 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-05-21T14:11:45.962Z,1558447905.962 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-05-21T14:11:46.126Z,1558447906.126 [SBIT](DEBUG): Construct Startup Built In Test.
2019-05-21T14:11:46.148Z,1558447906.148 [SBIT] Loaded
2019-05-21T14:11:46.148Z,1558447906.148 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-05-21T14:11:46.149Z,1558447906.149 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-05-21T14:11:46.161Z,1558447906.161 [IBIT] Loaded
2019-05-21T14:11:46.161Z,1558447906.161 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-05-21T14:11:46.164Z,1558447906.164 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-05-21T14:11:46.339Z,1558447906.339 [CBIT] Loaded
2019-05-21T14:11:46.340Z,1558447906.340 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-05-21T14:11:46.340Z,1558447906.340 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-05-21T14:11:46.341Z,1558447906.341 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-05-21T14:11:46.455Z,1558447906.455 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-05-21T14:11:46.456Z,1558447906.455 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-05-21T14:11:46.619Z,1558447906.619 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-05-21T14:11:46.619Z,1558447906.619 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-05-21T14:11:46.705Z,1558447906.705 [VerticalControl](DEBUG): Construct VerticalControl.
2019-05-21T14:11:46.789Z,1558447906.789 [VerticalControl] Loaded
2019-05-21T14:11:46.789Z,1558447906.789 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-05-21T14:11:46.790Z,1558447906.790 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-05-21T14:11:46.847Z,1558447906.847 [HorizontalControl] Loaded
2019-05-21T14:11:46.847Z,1558447906.847 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-05-21T14:11:46.848Z,1558447906.848 [SpeedControl](DEBUG): Construct SpeedControl.
2019-05-21T14:11:46.850Z,1558447906.850 [SpeedControl] Loaded
2019-05-21T14:11:46.850Z,1558447906.850 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-05-21T14:11:46.851Z,1558447906.851 [LoopControl](DEBUG): Construct LoopControl.
2019-05-21T14:11:46.851Z,1558447906.851 [LoopControl] Loaded
2019-05-21T14:11:46.851Z,1558447906.851 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-05-21T14:11:46.852Z,1558447906.852 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-05-21T14:11:46.853Z,1558447906.853 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-05-21T14:11:46.933Z,1558447906.933 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-05-21T14:11:46.937Z,1558447906.937 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-05-21T14:11:46.938Z,1558447906.938 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-05-21T14:11:46.945Z,1558447906.945 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-05-21T14:11:46.946Z,1558447906.946 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AD94E0
2019-05-21T14:11:46.946Z,1558447906.946 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 8377
2019-05-21T14:11:46.951Z,1558447906.951 [Supervisor](INFO): Main Thread ID is 8212
2019-05-21T14:11:46.951Z,1558447906.951 [Supervisor](DEBUG): Running supervisor.
2019-05-21T14:11:46.952Z,1558447906.952 [CommandLine ThreadHandler](INFO): Handler Thread ID is 8378
2019-05-21T14:11:46.954Z,1558447906.954 [controlThread ThreadHandler](INFO): Handler Thread ID is 8379
2019-05-21T14:11:46.955Z,1558447906.955 [controlThread](DEBUG): Initializing ControlThread
2019-05-21T14:11:46.960Z,1558447906.960 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-05-21T14:11:46.960Z,1558447906.960 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-05-21T14:11:46.962Z,1558447906.962 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-05-21T14:11:46.962Z,1558447906.962 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-05-21T14:11:46.962Z,1558447906.962 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-05-21T14:11:46.962Z,1558447906.962 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-05-21T14:11:46.963Z,1558447906.963 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-05-21T14:11:46.963Z,1558447906.963 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-05-21T14:11:46.964Z,1558447906.964 [SBIT](INFO): Initialize SBIT Component.
2019-05-21T14:11:46.964Z,1558447906.964 [SBIT](IMPORTANT): git: 2019-05-01-9-g5be6d91
2019-05-21T14:11:46.964Z,1558447906.964 [SBIT](INFO): git hash: 5be6d91bfd1d3ef858080230e47d423d1f938b96
2019-05-21T14:11:46.965Z,1558447906.965 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-05-21T14:11:46.966Z,1558447906.966 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
2019-05-21T14:11:46.967Z,1558447906.967 [SBIT](INFO): Beginning SBIT in 52.000000 seconds.
2019-05-21T14:11:46.968Z,1558447906.968 [IBIT](INFO): Initialize IBIT Component.
2019-05-21T14:11:46.969Z,1558447906.969 [CBIT](DEBUG): Initialize CBIT Component.
2019-05-21T14:11:46.970Z,1558447906.970 [logger ThreadHandler](INFO): Handler Thread ID is 8380
2019-05-21T14:11:46.981Z,1558447906.981 [CBIT](DEBUG): Initialized mux pins.
2019-05-21T14:11:46.981Z,1558447906.981 [CBIT](DEBUG): Initializing the watchdog timer.
2019-05-21T14:11:46.989Z,1558447906.989 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 8381
2019-05-21T14:11:46.990Z,1558447906.990 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-05-21T14:11:47.001Z,1558447907.001 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 8382
2019-05-21T14:11:47.005Z,1558447907.005 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-05-21T14:11:47.005Z,1558447907.005 [CBIT](DEBUG): Initializing heartbeat.
2019-05-21T14:11:47.013Z,1558447907.013 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 8383
2019-05-21T14:11:47.014Z,1558447907.014 [CTD_NeilBrown](INFO): Powering down
2019-05-21T14:11:47.049Z,1558447907.049 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 8384
2019-05-21T14:11:47.050Z,1558447907.050 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-05-21T14:11:47.077Z,1558447907.077 [CBIT](DEBUG): Deactivating GF circuits.
2019-05-21T14:11:47.077Z,1558447907.077 [CBIT](DEBUG): Deactivating emergency mode.
2019-05-21T14:11:47.078Z,1558447907.078 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 8385
2019-05-21T14:11:47.085Z,1558447907.085 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-05-21T14:11:47.085Z,1558447907.085 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-05-21T14:11:47.085Z,1558447907.085 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-05-21T14:11:47.085Z,1558447907.085 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-05-21T14:11:47.085Z,1558447907.085 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-05-21T14:11:47.086Z,1558447907.086 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-05-21T14:11:47.086Z,1558447907.086 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-05-21T14:11:47.086Z,1558447907.086 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-05-21T14:11:47.086Z,1558447907.086 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-05-21T14:11:47.086Z,1558447907.086 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-05-21T14:11:47.087Z,1558447907.087 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-05-21T14:11:47.087Z,1558447907.087 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-05-21T14:11:47.087Z,1558447907.087 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-05-21T14:11:47.087Z,1558447907.087 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-05-21T14:11:47.087Z,1558447907.087 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-05-21T14:11:47.088Z,1558447907.088 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-05-21T14:11:47.113Z,1558447907.113 [CBIT](DEBUG): Backplane powered.
2019-05-21T14:11:47.113Z,1558447907.113 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-05-21T14:11:47.115Z,1558447907.115 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-05-21T14:11:47.116Z,1558447907.116 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-05-21T14:11:47.116Z,1558447907.116 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-05-21T14:11:47.117Z,1558447907.117 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-05-21T14:11:47.134Z,1558447907.134 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-05-21T14:11:47.170Z,1558447907.170 [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-05-21T14:11:47.171Z,1558447907.171 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-05-21T14:11:47.266Z,1558447907.266 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-05-21T14:11:47.267Z,1558447907.267 [Default:A.Wait](DEBUG): Construct Wait.
2019-05-21T14:11:47.285Z,1558447907.285 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-05-21T14:11:47.308Z,1558447907.308 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-05-21T14:11:47.341Z,1558447907.341 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-05-21T14:11:47.363Z,1558447907.363 [Default:E.Execute](DEBUG): Construct Execute.
2019-05-21T14:11:47.367Z,1558447907.367 [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-05-21T14:11:47.371Z,1558447907.371 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,DUSBL_Hydroid,Micromodem,NAL9602,Onboard,PowerOnly,RDI_Pathfinder,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-05-21T14:11:47.389Z,1558447907.389 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-05-21T14:11:47.405Z,1558447907.405 [Radio_Surface](INFO): Powering up
2019-05-21T14:11:47.571Z,1558447907.571 [DUSBL_Hydroid](INFO): Powering up
2019-05-21T14:11:47.572Z,1558447907.572 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-05-21T14:11:47.593Z,1558447907.593 [Micromodem](INFO): Start
2019-05-21T14:11:47.697Z,1558447907.697 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-05-21T14:11:47.721Z,1558447907.721 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-05-21T14:11:47.722Z,1558447907.722 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-05-21T14:11:47.729Z,1558447907.729 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-05-21T14:11:47.730Z,1558447907.730 [MassServo](DEBUG): Initializing EZServoServo.
2019-05-21T14:11:47.737Z,1558447907.737 [MassServo](DEBUG): Initializing MassServo.
2019-05-21T14:11:47.738Z,1558447907.738 [RudderServo](DEBUG): Initializing EZServoServo.
2019-05-21T14:11:47.745Z,1558447907.745 [RudderServo](DEBUG): Initializing RudderServo.
2019-05-21T14:11:47.746Z,1558447907.746 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-05-21T14:11:47.760Z,1558447907.760 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-05-21T14:11:47.794Z,1558447907.794 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar
2019-05-21T14:11:47.796Z,1558447907.796 [Micromodem](INFO): Starting
2019-05-21T14:11:47.796Z,1558447907.796 [Micromodem](INFO): Powering up
2019-05-21T14:11:47.829Z,1558447907.829 [Micromodem](DEBUG): Initializing Micromodem.
2019-05-21T14:11:48.187Z,1558447908.187 [Micromodem](INFO): Starting
2019-05-21T14:11:48.590Z,1558447908.590 [Micromodem](INFO): Starting
2019-05-21T14:11:48.994Z,1558447908.994 [Micromodem](INFO): Starting
2019-05-21T14:11:49.398Z,1558447909.398 [Micromodem](INFO): Starting
2019-05-21T14:11:49.802Z,1558447909.802 [Micromodem](INFO): Starting
2019-05-21T14:11:50.206Z,1558447910.206 [Micromodem](INFO): Starting
2019-05-21T14:11:50.679Z,1558447910.679 [Micromodem](INFO): Starting
2019-05-21T14:11:51.038Z,1558447911.038 [Micromodem](INFO): Starting
2019-05-21T14:11:51.454Z,1558447911.454 [Micromodem](INFO): Starting
2019-05-21T14:11:51.946Z,1558447911.946 [Micromodem](INFO): Starting
2019-05-21T14:11:52.258Z,1558447912.258 [Micromodem](INFO): Starting
2019-05-21T14:11:52.662Z,1558447912.662 [Micromodem](INFO): Starting
2019-05-21T14:11:52.663Z,1558447912.663 [Micromodem](INFO): Starting
2019-05-21T14:11:52.665Z,1558447912.665 [Micromodem](DEBUG): Nmea buf: $CCCFG,ALL,0*33

2019-05-21T14:11:52.665Z,1558447912.665 [Micromodem](DEBUG): Nmea buf: $CCCFG,SRC,1*31

2019-05-21T14:11:52.665Z,1558447912.665 [Micromodem](DEBUG): Nmea buf: $CCCFG,nav.dt.txtrig_gpio4,1*64

2019-05-21T14:11:52.666Z,1558447912.666 [Micromodem](DEBUG): Nmea buf: $CCCFG,BND,1*3B

2019-05-21T14:11:53.066Z,1558447913.066 [Micromodem](INFO): Starting
2019-05-21T14:11:53.470Z,1558447913.470 [Micromodem](INFO): Starting
2019-05-21T14:11:53.862Z,1558447913.862 [Micromodem](INFO): Starting
2019-05-21T14:11:54.278Z,1558447914.278 [Micromodem](INFO): Starting
2019-05-21T14:11:54.699Z,1558447914.699 [Micromodem](INFO): Starting
2019-05-21T14:11:55.086Z,1558447915.086 [Micromodem](INFO): Starting
2019-05-21T14:11:55.478Z,1558447915.478 [Micromodem](INFO): Starting
2019-05-21T14:11:55.875Z,1558447915.875 [Micromodem](INFO): Starting
2019-05-21T14:11:56.274Z,1558447916.274 [Micromodem](INFO): Starting
2019-05-21T14:11:56.682Z,1558447916.682 [Micromodem](INFO): Starting
2019-05-21T14:11:57.074Z,1558447917.074 [Micromodem](INFO): Starting
2019-05-21T14:11:57.478Z,1558447917.478 [Micromodem](INFO): Starting
2019-05-21T14:11:57.892Z,1558447917.892 [Micromodem](INFO): Starting
2019-05-21T14:11:58.283Z,1558447918.283 [Micromodem](INFO): Starting
2019-05-21T14:11:58.686Z,1558447918.686 [Micromodem](INFO): Starting
2019-05-21T14:11:59.106Z,1558447919.106 [Micromodem](INFO): Starting
2019-05-21T14:11:59.507Z,1558447919.507 [Micromodem](INFO): Starting
2019-05-21T14:11:59.910Z,1558447919.910 [Micromodem](INFO): Starting
2019-05-21T14:12:00.318Z,1558447920.318 [Micromodem](INFO): Starting
2019-05-21T14:12:00.743Z,1558447920.743 [Micromodem](INFO): Starting
2019-05-21T14:12:01.122Z,1558447921.122 [Micromodem](INFO): Starting
2019-05-21T14:12:01.148Z,1558447921.148 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2019-05-21T14:12:01.526Z,1558447921.526 [Micromodem](INFO): Starting
2019-05-21T14:12:01.934Z,1558447921.934 [Micromodem](INFO): Starting
2019-05-21T14:12:02.339Z,1558447922.339 [Micromodem](INFO): Starting
2019-05-21T14:12:02.746Z,1558447922.746 [Micromodem](INFO): Starting
2019-05-21T14:12:03.142Z,1558447923.142 [Micromodem](INFO): Starting
2019-05-21T14:12:03.570Z,1558447923.570 [Micromodem](INFO): Starting
2019-05-21T14:12:03.970Z,1558447923.970 [Micromodem](INFO): Starting
2019-05-21T14:12:04.403Z,1558447924.403 [Micromodem](INFO): Starting
2019-05-21T14:12:04.816Z,1558447924.816 [Micromodem](INFO): Starting
2019-05-21T14:12:05.183Z,1558447925.182 [Micromodem](INFO): Starting
2019-05-21T14:12:05.586Z,1558447925.586 [DUSBL_Hydroid](INFO): DUSBL Version:O
2019-05-21T14:12:05.601Z,1558447925.601 [Micromodem](INFO): Starting
2019-05-21T14:12:05.990Z,1558447925.990 [Micromodem](INFO): Starting
2019-05-21T14:12:06.398Z,1558447926.398 [Micromodem](INFO): Starting
2019-05-21T14:12:06.799Z,1558447926.799 [Micromodem](INFO): Starting
2019-05-21T14:12:07.202Z,1558447927.202 [Micromodem](INFO): Starting
2019-05-21T14:12:07.610Z,1558447927.610 [Micromodem](INFO): Starting
2019-05-21T14:12:08.010Z,1558447928.010 [Micromodem](INFO): Starting
2019-05-21T14:12:08.386Z,1558447928.386 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004490
2019-05-21T14:12:08.414Z,1558447928.414 [Micromodem](INFO): Starting
2019-05-21T14:12:08.822Z,1558447928.822 [Micromodem](INFO): Starting
2019-05-21T14:12:09.226Z,1558447929.226 [Micromodem](INFO): Starting
2019-05-21T14:12:09.626Z,1558447929.626 [Micromodem](INFO): Starting
2019-05-21T14:12:10.034Z,1558447930.034 [Micromodem](INFO): Starting
2019-05-21T14:12:10.434Z,1558447930.434 [Micromodem](INFO): Starting
2019-05-21T14:12:10.842Z,1558447930.842 [Micromodem](INFO): Starting
2019-05-21T14:12:11.242Z,1558447931.242 [Micromodem](INFO): Starting
2019-05-21T14:12:11.686Z,1558447931.686 [Micromodem](INFO): Starting
2019-05-21T14:12:12.054Z,1558447932.054 [Micromodem](INFO): Starting
2019-05-21T14:12:12.458Z,1558447932.458 [Micromodem](INFO): Starting
2019-05-21T14:12:12.460Z,1558447932.460 [NAL9602](INFO): Powering up NAL9602
2019-05-21T14:12:12.862Z,1558447932.862 [Micromodem](INFO): Starting
2019-05-21T14:12:13.298Z,1558447933.298 [Micromodem](INFO): Starting
2019-05-21T14:12:13.666Z,1558447933.666 [Micromodem](INFO): Starting
2019-05-21T14:12:14.106Z,1558447934.106 [Micromodem](INFO): Starting
2019-05-21T14:12:14.474Z,1558447934.474 [Micromodem](INFO): Starting
2019-05-21T14:12:14.882Z,1558447934.882 [Micromodem](INFO): Starting
2019-05-21T14:12:15.282Z,1558447935.282 [Micromodem](INFO): Starting
2019-05-21T14:12:15.686Z,1558447935.686 [Micromodem](INFO): Starting
2019-05-21T14:12:16.102Z,1558447936.102 [Micromodem](INFO): Starting
2019-05-21T14:12:16.499Z,1558447936.499 [Micromodem](INFO): Starting
2019-05-21T14:12:16.898Z,1558447936.898 [Micromodem](INFO): Starting
2019-05-21T14:12:17.306Z,1558447937.306 [Micromodem](INFO): Starting
2019-05-21T14:12:17.706Z,1558447937.706 [Micromodem](INFO): Starting
2019-05-21T14:12:18.110Z,1558447938.110 [Micromodem](INFO): Starting
2019-05-21T14:12:18.514Z,1558447938.514 [Micromodem](INFO): Starting
2019-05-21T14:12:18.922Z,1558447938.922 [Micromodem](INFO): Starting
2019-05-21T14:12:19.322Z,1558447939.322 [Micromodem](INFO): Starting
2019-05-21T14:12:19.726Z,1558447939.726 [Micromodem](INFO): Starting
2019-05-21T14:12:20.145Z,1558447940.145 [Micromodem](INFO): Starting
2019-05-21T14:12:20.534Z,1558447940.534 [Micromodem](INFO): Starting
2019-05-21T14:12:20.950Z,1558447940.950 [Micromodem](INFO): Starting
2019-05-21T14:12:21.374Z,1558447941.374 [Micromodem](INFO): Starting
2019-05-21T14:12:21.802Z,1558447941.802 [Micromodem](INFO): Starting
2019-05-21T14:12:22.154Z,1558447942.154 [Micromodem](INFO): Starting
2019-05-21T14:12:22.591Z,1558447942.591 [Micromodem](INFO): Starting
2019-05-21T14:12:22.958Z,1558447942.958 [Micromodem](INFO): Starting
2019-05-21T14:12:23.366Z,1558447943.366 [Micromodem](INFO): Starting
2019-05-21T14:12:23.367Z,1558447943.367 [NAL9602](INFO): NAL9602 initialized
2019-05-21T14:12:23.786Z,1558447943.786 [Micromodem](INFO): Starting
2019-05-21T14:12:24.174Z,1558447944.174 [Micromodem](INFO): Starting
2019-05-21T14:12:24.189Z,1558447944.189 [NAL9602](DEBUG): Fix Requested
2019-05-21T14:12:24.578Z,1558447944.578 [Micromodem](INFO): Starting
2019-05-21T14:12:24.991Z,1558447944.991 [Micromodem](INFO): Starting
2019-05-21T14:12:25.382Z,1558447945.382 [Micromodem](INFO): Starting
2019-05-21T14:12:25.813Z,1558447945.813 [Micromodem](INFO): Starting
2019-05-21T14:12:26.190Z,1558447946.190 [Micromodem](INFO): Starting
2019-05-21T14:12:26.626Z,1558447946.626 [Micromodem](INFO): Starting
2019-05-21T14:12:27.034Z,1558447947.034 [Micromodem](INFO): Starting
2019-05-21T14:12:27.434Z,1558447947.434 [Micromodem](INFO): Starting
2019-05-21T14:12:27.834Z,1558447947.834 [Micromodem](INFO): Starting
2019-05-21T14:12:28.238Z,1558447948.238 [Micromodem](INFO): Starting
2019-05-21T14:12:28.646Z,1558447948.646 [Micromodem](INFO): Starting
2019-05-21T14:12:29.046Z,1558447949.046 [Micromodem](INFO): Starting
2019-05-21T14:12:29.450Z,1558447949.450 [Micromodem](INFO): Starting
2019-05-21T14:12:29.870Z,1558447949.870 [Micromodem](INFO): Starting
2019-05-21T14:12:30.258Z,1558447950.258 [Micromodem](INFO): Starting
2019-05-21T14:12:30.662Z,1558447950.662 [Micromodem](INFO): Starting
2019-05-21T14:12:31.070Z,1558447951.070 [Micromodem](INFO): Starting
2019-05-21T14:12:31.478Z,1558447951.478 [Micromodem](INFO): Starting
2019-05-21T14:12:31.898Z,1558447951.898 [Micromodem](INFO): Starting
2019-05-21T14:12:32.338Z,1558447952.338 [Micromodem](INFO): Starting
2019-05-21T14:12:32.734Z,1558447952.734 [Micromodem](INFO): Starting
2019-05-21T14:12:33.126Z,1558447953.126 [Micromodem](INFO): Starting
2019-05-21T14:12:33.526Z,1558447953.526 [Micromodem](INFO): Starting
2019-05-21T14:12:33.930Z,1558447953.930 [Micromodem](INFO): Starting
2019-05-21T14:12:34.334Z,1558447954.334 [Micromodem](INFO): Starting
2019-05-21T14:12:34.750Z,1558447954.750 [Micromodem](INFO): Starting
2019-05-21T14:12:35.142Z,1558447955.142 [Micromodem](INFO): Starting
2019-05-21T14:12:35.546Z,1558447955.546 [Micromodem](INFO): Starting
2019-05-21T14:12:35.958Z,1558447955.958 [Micromodem](INFO): Starting
2019-05-21T14:12:36.366Z,1558447956.366 [Micromodem](INFO): Starting
2019-05-21T14:12:36.758Z,1558447956.758 [Micromodem](INFO): Starting
2019-05-21T14:12:37.162Z,1558447957.162 [Micromodem](INFO): Starting
2019-05-21T14:12:37.566Z,1558447957.566 [Micromodem](INFO): Starting
2019-05-21T14:12:37.975Z,1558447957.975 [Micromodem](INFO): Starting
2019-05-21T14:12:38.374Z,1558447958.374 [Micromodem](INFO): Starting
2019-05-21T14:12:38.778Z,1558447958.778 [Micromodem](INFO): Starting
2019-05-21T14:12:39.182Z,1558447959.182 [Micromodem](INFO): Starting
2019-05-21T14:12:39.598Z,1558447959.598 [Micromodem](INFO): Starting
2019-05-21T14:12:39.659Z,1558447959.659 [SBIT](IMPORTANT): Beginning Startup BIT
2019-05-21T14:12:39.676Z,1558447959.676 [CBIT](IMPORTANT): Beginning ground fault scan
2019-05-21T14:12:39.990Z,1558447959.990 [Micromodem](INFO): Starting
2019-05-21T14:12:40.406Z,1558447960.406 [Micromodem](INFO): Starting
2019-05-21T14:12:40.810Z,1558447960.810 [Micromodem](INFO): Starting
2019-05-21T14:12:41.230Z,1558447961.230 [Micromodem](INFO): Starting
2019-05-21T14:12:41.650Z,1558447961.650 [Micromodem](INFO): Starting
2019-05-21T14:12:42.030Z,1558447962.030 [Micromodem](INFO): Starting
2019-05-21T14:12:42.034Z,1558447962.034 [NAL9602](INFO): SBD MO Status=2, MOMSN=4821, MT Status=2, MTMSN=0
2019-05-21T14:12:42.034Z,1558447962.034 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-05-21T14:12:42.770Z,1558447962.770 [Micromodem](INFO): Starting
2019-05-21T14:12:43.138Z,1558447963.138 [Micromodem](INFO): Starting
2019-05-21T14:12:43.542Z,1558447963.542 [Micromodem](INFO): Starting
2019-05-21T14:12:43.954Z,1558447963.954 [Micromodem](INFO): Starting
2019-05-21T14:12:44.365Z,1558447964.365 [Micromodem](INFO): Starting
2019-05-21T14:12:44.794Z,1558447964.794 [Micromodem](INFO): Starting
2019-05-21T14:12:45.158Z,1558447965.158 [Micromodem](INFO): Starting
2019-05-21T14:12:45.570Z,1558447965.570 [Micromodem](INFO): Starting
2019-05-21T14:12:46.011Z,1558447966.011 [Micromodem](INFO): Starting
2019-05-21T14:12:46.390Z,1558447966.390 [Micromodem](INFO): Starting
2019-05-21T14:12:46.810Z,1558447966.810 [Micromodem](INFO): Starting
2019-05-21T14:12:47.218Z,1558447967.218 [Micromodem](INFO): Starting
2019-05-21T14:12:47.590Z,1558447967.590 [Micromodem](INFO): Starting
2019-05-21T14:12:47.986Z,1558447967.986 [Micromodem](INFO): Starting
2019-05-21T14:12:48.398Z,1558447968.398 [Micromodem](INFO): Starting
2019-05-21T14:12:48.798Z,1558447968.798 [Micromodem](INFO): Starting
2019-05-21T14:12:49.202Z,1558447969.202 [Micromodem](INFO): Starting
2019-05-21T14:12:49.602Z,1558447969.602 [Micromodem](INFO): Starting
2019-05-21T14:12:50.006Z,1558447970.006 [Micromodem](INFO): Starting
2019-05-21T14:12:50.414Z,1558447970.414 [Micromodem](INFO): Starting
2019-05-21T14:12:50.830Z,1558447970.830 [Micromodem](INFO): Starting
2019-05-21T14:12:51.022Z,1558447971.022 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.011404
CHAN A1 (24V): -0.028446
CHAN A2 (12V): -0.007520
CHAN A3 (5V): -0.002413
CHAN B0 (3.3V): 0.000004
CHAN B1 (3.15aV): -0.000298
CHAN B2 (3.15bV): 0.000343
CHAN B3 (GND): 0.001912
OPEN: 0.005047
Full Scale Calc: 4.765 mA, -1.589 mA
2019-05-21T14:12:51.218Z,1558447971.218 [Micromodem](INFO): Starting
2019-05-21T14:12:51.626Z,1558447971.626 [Micromodem](INFO): Starting
2019-05-21T14:12:52.026Z,1558447972.026 [Micromodem](INFO): Starting
2019-05-21T14:12:52.430Z,1558447972.430 [Micromodem](INFO): Starting
2019-05-21T14:12:52.834Z,1558447972.834 [Micromodem](INFO): Starting
2019-05-21T14:12:52.954Z,1558447972.954 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2019-05-21T14:12:53.238Z,1558447973.238 [Micromodem](INFO): Starting
2019-05-21T14:12:53.646Z,1558447973.646 [Micromodem](INFO): Starting
2019-05-21T14:12:54.050Z,1558447974.050 [Micromodem](INFO): Starting
2019-05-21T14:12:54.454Z,1558447974.454 [Micromodem](INFO): Starting
2019-05-21T14:12:54.854Z,1558447974.854 [Micromodem](INFO): Starting
2019-05-21T14:12:55.457Z,1558447975.457 [Micromodem](INFO): Starting
2019-05-21T14:12:55.854Z,1558447975.854 [Micromodem](INFO): Starting
2019-05-21T14:12:56.262Z,1558447976.262 [Micromodem](INFO): Starting
2019-05-21T14:12:56.662Z,1558447976.662 [Micromodem](INFO): Starting
2019-05-21T14:12:57.070Z,1558447977.070 [Micromodem](INFO): Starting
2019-05-21T14:12:57.474Z,1558447977.474 [Micromodem](INFO): Starting
2019-05-21T14:12:57.874Z,1558447977.874 [Micromodem](INFO): Starting
2019-05-21T14:12:58.278Z,1558447978.278 [Micromodem](INFO): Starting
2019-05-21T14:12:58.686Z,1558447978.686 [Micromodem](INFO): Starting
2019-05-21T14:12:59.090Z,1558447979.090 [Micromodem](INFO): Starting
2019-05-21T14:12:59.490Z,1558447979.490 [Micromodem](INFO): Starting
2019-05-21T14:12:59.524Z,1558447979.524 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2019-05-21T14:12:59.898Z,1558447979.898 [Micromodem](INFO): Starting
2019-05-21T14:13:00.302Z,1558447980.302 [Micromodem](INFO): Starting
2019-05-21T14:13:00.702Z,1558447980.702 [Micromodem](INFO): Starting
2019-05-21T14:13:01.110Z,1558447981.110 [Micromodem](INFO): Starting
2019-05-21T14:13:01.510Z,1558447981.510 [Micromodem](INFO): Starting
2019-05-21T14:13:01.914Z,1558447981.914 [Micromodem](INFO): Starting
2019-05-21T14:13:02.322Z,1558447982.322 [Micromodem](INFO): Starting
2019-05-21T14:13:02.732Z,1558447982.732 [Micromodem](INFO): Starting
2019-05-21T14:13:03.126Z,1558447983.126 [Micromodem](INFO): Starting
2019-05-21T14:13:03.534Z,1558447983.534 [Micromodem](INFO): Starting
2019-05-21T14:13:03.934Z,1558447983.934 [Micromodem](INFO): Starting
2019-05-21T14:13:04.342Z,1558447984.342 [Micromodem](INFO): Starting
2019-05-21T14:13:04.748Z,1558447984.748 [Micromodem](INFO): Starting
2019-05-21T14:13:05.146Z,1558447985.146 [Micromodem](INFO): Starting
2019-05-21T14:13:05.554Z,1558447985.554 [Micromodem](INFO): Starting
2019-05-21T14:13:05.970Z,1558447985.970 [Micromodem](INFO): Starting
2019-05-21T14:13:06.358Z,1558447986.358 [Micromodem](INFO): Starting
2019-05-21T14:13:06.782Z,1558447986.782 [Micromodem](INFO): Starting
2019-05-21T14:13:07.172Z,1558447987.172 [Micromodem](INFO): Starting
2019-05-21T14:13:07.574Z,1558447987.574 [Micromodem](INFO): Starting
2019-05-21T14:13:07.986Z,1558447987.986 [Micromodem](INFO): Starting
2019-05-21T14:13:08.390Z,1558447988.390 [Micromodem](INFO): Starting
2019-05-21T14:13:08.782Z,1558447988.782 [Micromodem](INFO): Starting
2019-05-21T14:13:09.197Z,1558447989.197 [Micromodem](INFO): Starting
2019-05-21T14:13:09.614Z,1558447989.614 [Micromodem](INFO): Starting
2019-05-21T14:13:10.030Z,1558447990.030 [Micromodem](INFO): Starting
2019-05-21T14:13:10.402Z,1558447990.402 [Micromodem](INFO): Starting
2019-05-21T14:13:10.814Z,1558447990.814 [Micromodem](INFO): Starting
2019-05-21T14:13:11.227Z,1558447991.227 [Micromodem](INFO): Starting
2019-05-21T14:13:11.233Z,1558447991.233 [NAL9602](INFO): SBD MO Status=0, MOMSN=4821, MT Status=0, MTMSN=0
2019-05-21T14:13:11.234Z,1558447991.234 [NAL9602](INFO): No messages in MT queue
2019-05-21T14:13:11.650Z,1558447991.650 [Micromodem](INFO): Starting
2019-05-21T14:13:12.042Z,1558447992.042 [Micromodem](INFO): Starting
2019-05-21T14:13:12.057Z,1558447992.057 [NAL9602](DEBUG): Fix Requested
2019-05-21T14:13:12.438Z,1558447992.438 [Micromodem](INFO): Starting
2019-05-21T14:13:12.842Z,1558447992.842 [Micromodem](INFO): Starting
2019-05-21T14:13:13.263Z,1558447993.263 [Micromodem](INFO): Starting
2019-05-21T14:13:13.650Z,1558447993.650 [Micromodem](INFO): Starting
2019-05-21T14:13:14.062Z,1558447994.062 [Micromodem](INFO): Starting
2019-05-21T14:13:14.478Z,1558447994.478 [Micromodem](INFO): Starting
2019-05-21T14:13:14.493Z,1558447994.493 [NAL9602](DEBUG): Fix Requested
2019-05-21T14:13:14.862Z,1558447994.862 [Micromodem](INFO): Starting
2019-05-21T14:13:15.266Z,1558447995.266 [Micromodem](INFO): Starting
2019-05-21T14:13:15.678Z,1558447995.678 [Micromodem](INFO): Starting
2019-05-21T14:13:16.074Z,1558447996.074 [Micromodem](INFO): Starting
2019-05-21T14:13:16.486Z,1558447996.486 [Micromodem](INFO): Starting
2019-05-21T14:13:16.882Z,1558447996.882 [Micromodem](INFO): Starting
2019-05-21T14:13:17.286Z,1558447997.286 [Micromodem](INFO): Starting
2019-05-21T14:13:17.699Z,1558447997.699 [Micromodem](INFO): Starting
2019-05-21T14:13:17.713Z,1558447997.713 [NAL9602](DEBUG): Fix Requested
2019-05-21T14:13:18.130Z,1558447998.130 [Micromodem](INFO): Starting
2019-05-21T14:13:18.502Z,1558447998.502 [Micromodem](INFO): Starting
2019-05-21T14:13:18.906Z,1558447998.906 [Micromodem](INFO): Starting
2019-05-21T14:13:19.310Z,1558447999.310 [Micromodem](INFO): Starting
2019-05-21T14:13:19.714Z,1558447999.714 [Micromodem](INFO): Starting
2019-05-21T14:13:20.173Z,1558448000.173 [Micromodem](INFO): Starting
2019-05-21T14:13:20.535Z,1558448000.535 [Micromodem](INFO): Starting
2019-05-21T14:13:20.553Z,1558448000.553 [NAL9602](DEBUG): Fix Requested
2019-05-21T14:13:20.953Z,1558448000.953 [Micromodem](INFO): Starting
2019-05-21T14:13:21.330Z,1558448001.330 [Micromodem](INFO): Starting
2019-05-21T14:13:21.759Z,1558448001.759 [Micromodem](INFO): Starting
2019-05-21T14:13:21.800Z,1558448001.800 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2019-05-21T14:13:22.299Z,1558448002.299 [Micromodem](INFO): Starting
2019-05-21T14:13:22.718Z,1558448002.718 [Micromodem](INFO): Starting
2019-05-21T14:13:23.110Z,1558448003.110 [Micromodem](INFO): Starting
2019-05-21T14:13:23.517Z,1558448003.517 [Micromodem](INFO): Starting
2019-05-21T14:13:23.529Z,1558448003.529 [NAL9602](DEBUG): Fix Requested
2019-05-21T14:13:23.918Z,1558448003.918 [Micromodem](INFO): Starting
2019-05-21T14:13:24.314Z,1558448004.314 [Micromodem](INFO): Starting
2019-05-21T14:13:24.722Z,1558448004.722 [Micromodem](INFO): Starting
2019-05-21T14:13:25.162Z,1558448005.162 [Micromodem](INFO): Starting
2019-05-21T14:13:25.574Z,1558448005.574 [Micromodem](INFO): Starting
2019-05-21T14:13:25.967Z,1558448005.967 [Micromodem](INFO): Starting
2019-05-21T14:13:26.370Z,1558448006.370 [Micromodem](INFO): Starting
2019-05-21T14:13:26.774Z,1558448006.774 [Micromodem](INFO): Starting
2019-05-21T14:13:26.789Z,1558448006.789 [NAL9602](DEBUG): Fix Requested
2019-05-21T14:13:27.178Z,1558448007.178 [Micromodem](INFO): Starting
2019-05-21T14:13:27.582Z,1558448007.582 [Micromodem](INFO): Starting
2019-05-21T14:13:27.986Z,1558448007.986 [Micromodem](INFO): Starting
2019-05-21T14:13:28.405Z,1558448008.405 [Micromodem](INFO): Starting
2019-05-21T14:13:28.810Z,1558448008.810 [Micromodem](INFO): Starting
2019-05-21T14:13:29.215Z,1558448009.215 [Micromodem](INFO): Starting
2019-05-21T14:13:29.602Z,1558448009.602 [Micromodem](INFO): Starting
2019-05-21T14:13:29.617Z,1558448009.617 [NAL9602](DEBUG): Fix Requested
2019-05-21T14:13:30.006Z,1558448010.006 [Micromodem](INFO): Starting
2019-05-21T14:13:30.414Z,1558448010.414 [Micromodem](INFO): Starting
2019-05-21T14:13:30.818Z,1558448010.818 [Micromodem](INFO): Starting
2019-05-21T14:13:31.218Z,1558448011.218 [Micromodem](INFO): Starting
2019-05-21T14:13:31.626Z,1558448011.626 [Micromodem](INFO): Starting
2019-05-21T14:13:32.035Z,1558448012.035 [Micromodem](INFO): Starting
2019-05-21T14:13:32.465Z,1558448012.465 [Micromodem](INFO): Starting
2019-05-21T14:13:32.477Z,1558448012.477 [NAL9602](DEBUG): Fix Requested
2019-05-21T14:13:32.838Z,1558448012.838 [Micromodem](INFO): Starting
2019-05-21T14:13:33.238Z,1558448013.238 [Micromodem](INFO): Starting
2019-05-21T14:13:33.282Z,1558448013.282 [SBIT](IMPORTANT): SBIT PASSED
2019-05-21T14:13:33.350Z,1558448013.350 [CommandLine](IMPORTANT): got command configSet list
2019-05-21T14:13:33.351Z,1558448013.351 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-05-21T14:13:33.352Z,1558448013.352 [CommandLine](IMPORTANT): BPC1.batterySamplingInterval=3 hour;

2019-05-21T14:13:33.352Z,1558448013.352 [CommandLine](IMPORTANT): BPC1.loadAtStartup=1 bool;

2019-05-21T14:13:33.352Z,1558448013.352 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold=75 count;

2019-05-21T14:13:33.352Z,1558448013.352 [CommandLine](IMPORTANT): Express linearApproximation DUSBL_Hydroid.range 2.000000 meter;

2019-05-21T14:13:33.352Z,1558448013.352 [CommandLine](IMPORTANT): Express linearApproximation DUSBL_Hydroid.xAngle 2.000000 angular_degree;

2019-05-21T14:13:33.352Z,1558448013.352 [CommandLine](IMPORTANT): Express linearApproximation RDI_Pathfinder.BottomVelocityFlag 0.000000 count;

2019-05-21T14:13:33.352Z,1558448013.352 [CommandLine](IMPORTANT): Express linearApproximation RDI_Pathfinder.height_above_sea_floor 2.000000 meter;

2019-05-21T14:13:33.352Z,1558448013.352 [CommandLine](IMPORTANT): Express none TrackAcousticContact.contact_latitude;

2019-05-21T14:13:33.353Z,1558448013.353 [CommandLine](IMPORTANT): Express none TrackAcousticContact.contact_longitude;

2019-05-21T14:13:33.353Z,1558448013.353 [CommandLine](IMPORTANT): Express linearApproximation acoustic_contact_range 1.000000 meter;

2019-05-21T14:13:33.353Z,1558448013.353 [CommandLine](IMPORTANT): Express linearApproximation mass_concentration_of_chlorophyll_in_sea_water 5.000000 microgram_per_liter;

2019-05-21T14:13:33.353Z,1558448013.353 [CommandLine](IMPORTANT): Express none mass_concentration_of_oxygen_in_sea_water;

2019-05-21T14:13:33.353Z,1558448013.353 [CommandLine](IMPORTANT): NAL9602.fastGPSFix=1 bool;

2019-05-21T14:13:33.353Z,1558448013.353 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=525 cubic_centimeter;

2019-05-21T14:13:33.353Z,1558448013.353 [CommandLine](IMPORTANT): VerticalControl.massDefault=-8 millimeter;

2019-05-21T14:13:33.642Z,1558448013.642 [Micromodem](INFO): Starting
2019-05-21T14:13:33.670Z,1558448013.670 [MissionManager](IMPORTANT): Started mission Startup
2019-05-21T14:13:33.670Z,1558448013.670 [Startup] Running Loop=1
2019-05-21T14:13:33.670Z,1558448013.670 [Startup](DEBUG): Aggregate::initialize Startup
2019-05-21T14:13:33.670Z,1558448013.670 [Startup:A.GoToSurface] Running Loop=1
2019-05-21T14:13:33.670Z,1558448013.670 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-05-21T14:13:33.671Z,1558448013.671 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-05-21T14:13:33.671Z,1558448013.671 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-05-21T14:13:33.672Z,1558448013.672 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-05-21T14:13:33.672Z,1558448013.672 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-05-21T14:13:33.673Z,1558448013.673 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-05-21T14:13:33.674Z,1558448013.674 [Startup:StartupSatComms] Running Loop=1
2019-05-21T14:13:33.674Z,1558448013.674 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-05-21T14:13:33.674Z,1558448013.674 [Startup:StartupSatComms:A] Running Loop=1
2019-05-21T14:13:34.059Z,1558448014.059 [Micromodem](INFO): Starting
2019-05-21T14:13:34.094Z,1558448014.094 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-05-21T14:13:34.450Z,1558448014.450 [Micromodem](INFO): Starting
2019-05-21T14:13:34.465Z,1558448014.465 [NAL9602](DEBUG): Fix Requested
2019-05-21T14:13:34.854Z,1558448014.854 [Micromodem](INFO): Starting
2019-05-21T14:13:35.258Z,1558448015.258 [Micromodem](INFO): Starting
2019-05-21T14:13:35.662Z,1558448015.662 [Micromodem](INFO): Starting
2019-05-21T14:13:36.066Z,1558448016.066 [Micromodem](INFO): Starting
2019-05-21T14:13:36.486Z,1558448016.486 [Micromodem](INFO): Starting
2019-05-21T14:13:36.878Z,1558448016.878 [Micromodem](INFO): Starting
2019-05-21T14:13:36.893Z,1558448016.893 [NAL9602](DEBUG): Fix Requested
2019-05-21T14:13:37.294Z,1558448017.294 [Micromodem](INFO): Starting
2019-05-21T14:13:37.734Z,1558448017.734 [Micromodem](INFO): Starting
2019-05-21T14:13:38.086Z,1558448018.086 [Micromodem](INFO): Starting
2019-05-21T14:13:38.490Z,1558448018.490 [Micromodem](INFO): Starting
2019-05-21T14:13:38.894Z,1558448018.894 [Micromodem](INFO): Starting
2019-05-21T14:13:39.021Z,1558448019.021 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-05-21T14:13:39.298Z,1558448019.298 [Micromodem](INFO): Starting
2019-05-21T14:13:39.702Z,1558448019.702 [Micromodem](INFO): Starting
2019-05-21T14:13:39.717Z,1558448019.717 [NAL9602](DEBUG): Fix Requested
2019-05-21T14:13:40.133Z,1558448020.133 [Micromodem](INFO): Starting
2019-05-21T14:13:40.510Z,1558448020.510 [Micromodem](INFO): Starting
2019-05-21T14:13:40.914Z,1558448020.914 [Micromodem](INFO): Starting
2019-05-21T14:13:41.334Z,1558448021.334 [Micromodem](INFO): Starting
2019-05-21T14:13:41.732Z,1558448021.732 [Micromodem](INFO): Starting
2019-05-21T14:13:42.134Z,1558448022.134 [Micromodem](INFO): Starting
2019-05-21T14:13:42.538Z,1558448022.538 [Micromodem](INFO): Starting
2019-05-21T14:13:42.934Z,1558448022.934 [Micromodem](INFO): Starting
2019-05-21T14:13:42.949Z,1558448022.949 [NAL9602](DEBUG): Fix Requested
2019-05-21T14:13:43.338Z,1558448023.338 [Micromodem](INFO): Starting
2019-05-21T14:13:43.771Z,1558448023.771 [Micromodem](INFO): Starting
2019-05-21T14:13:44.146Z,1558448024.146 [Micromodem](INFO): Starting
2019-05-21T14:13:44.566Z,1558448024.566 [Micromodem](INFO): Starting
2019-05-21T14:13:44.958Z,1558448024.958 [Micromodem](INFO): Starting
2019-05-21T14:13:45.358Z,1558448025.358 [Micromodem](INFO): Starting
2019-05-21T14:13:45.766Z,1558448025.766 [Micromodem](INFO): Starting
2019-05-21T14:13:45.781Z,1558448025.781 [NAL9602](DEBUG): Fix Requested
2019-05-21T14:13:46.191Z,1558448026.191 [Micromodem](INFO): Starting
2019-05-21T14:13:46.570Z,1558448026.570 [Micromodem](INFO): Starting
2019-05-21T14:13:46.974Z,1558448026.974 [Micromodem](INFO): Starting
2019-05-21T14:13:47.378Z,1558448027.378 [Micromodem](INFO): Starting
2019-05-21T14:13:47.782Z,1558448027.782 [Micromodem](INFO): Starting
2019-05-21T14:13:47.783Z,1558448027.783 [Micromodem](FAULT): failed to initialize; deviceResponse_ loaded: $CAREV,141341,AUV,2.0.27690*17
, available: $CAREV,141341,COPROC,0.10.0.46*42

2019-05-21T14:13:47.783Z,1558448027.783 [Micromodem] Communications Fault, FailCount= 1
2019-05-21T14:13:47.783Z,1558448027.783 [Micromodem](ERROR): Communications Fault
2019-05-21T14:13:47.819Z,1558448027.819 [CBIT](ERROR): Communications Fault in component: Micromodem
2019-05-21T14:13:48.186Z,1558448028.186 [Micromodem](INFO): Stop
2019-05-21T14:13:48.186Z,1558448028.186 [Micromodem](INFO): uninitialize
2019-05-21T14:13:48.186Z,1558448028.186 [Micromodem](INFO): Powering down
2019-05-21T14:13:48.598Z,1558448028.598 [Micromodem](INFO): Stopping
2019-05-21T14:13:48.994Z,1558448028.994 [Micromodem](INFO): Stopped
2019-05-21T14:13:49.009Z,1558448029.009 [NAL9602](DEBUG): Fix Requested
2019-05-21T14:13:49.071Z,1558448029.071 [CBIT](INFO): Clearing failed state for component Micromodem
2019-05-21T14:13:49.071Z,1558448029.071 [Micromodem] No Fault, FailCount= 1
2019-05-21T14:13:49.398Z,1558448029.398 [Micromodem](INFO): Start
2019-05-21T14:13:49.802Z,1558448029.802 [Micromodem](INFO): Start
2019-05-21T14:13:50.223Z,1558448030.223 [Micromodem](INFO): Start
2019-05-21T14:13:50.610Z,1558448030.610 [Micromodem](INFO): Start
2019-05-21T14:13:51.046Z,1558448031.046 [Micromodem](INFO): Start
2019-05-21T14:13:51.434Z,1558448031.434 [Micromodem](INFO): Start
2019-05-21T14:13:51.838Z,1558448031.838 [Micromodem](INFO): Starting
2019-05-21T14:13:51.838Z,1558448031.838 [Micromodem](INFO): Powering up
2019-05-21T14:13:51.839Z,1558448031.839 [Micromodem](DEBUG): Initializing Micromodem.
2019-05-21T14:13:51.865Z,1558448031.865 [NAL9602](DEBUG): Fix Requested
2019-05-21T14:13:52.242Z,1558448032.242 [Micromodem](INFO): Starting
2019-05-21T14:13:52.646Z,1558448032.646 [Micromodem](INFO): Starting
2019-05-21T14:13:53.050Z,1558448033.050 [Micromodem](INFO): Starting
2019-05-21T14:13:53.454Z,1558448033.454 [Micromodem](INFO): Starting
2019-05-21T14:13:53.858Z,1558448033.858 [Micromodem](INFO): Starting
2019-05-21T14:13:54.282Z,1558448034.282 [Micromodem](INFO): Starting
2019-05-21T14:13:54.670Z,1558448034.670 [Micromodem](INFO): Starting
2019-05-21T14:13:54.685Z,1558448034.685 [NAL9602](DEBUG): Fix Requested
2019-05-21T14:13:55.077Z,1558448035.077 [Micromodem](INFO): Starting
2019-05-21T14:13:55.474Z,1558448035.474 [Micromodem](INFO): Starting
2019-05-21T14:13:55.886Z,1558448035.886 [Micromodem](INFO): Starting
2019-05-21T14:13:56.282Z,1558448036.282 [Micromodem](INFO): Starting
2019-05-21T14:13:56.694Z,1558448036.694 [Micromodem](INFO): Starting
2019-05-21T14:13:56.695Z,1558448036.695 [Micromodem](INFO): Starting
2019-05-21T14:13:56.695Z,1558448036.695 [Micromodem](DEBUG): Nmea buf: $CCCFG,ALL,0*33

2019-05-21T14:13:56.696Z,1558448036.696 [Micromodem](DEBUG): Nmea buf: $CCCFG,SRC,1*31

2019-05-21T14:13:56.698Z,1558448036.698 [Micromodem](DEBUG): Nmea buf: $CCCFG,nav.dt.txtrig_gpio4,1*64

2019-05-21T14:13:56.699Z,1558448036.699 [Micromodem](DEBUG): Nmea buf: $CCCFG,BND,1*3B

2019-05-21T14:13:57.090Z,1558448037.090 [Micromodem](INFO): Starting
2019-05-21T14:13:57.503Z,1558448037.503 [Micromodem](INFO): Starting
2019-05-21T14:13:57.521Z,1558448037.521 [NAL9602](DEBUG): Fix Requested
2019-05-21T14:13:57.943Z,1558448037.943 [Micromodem](INFO): Starting
2019-05-21T14:13:58.310Z,1558448038.310 [Micromodem](INFO): Starting
2019-05-21T14:13:58.719Z,1558448038.719 [Micromodem](INFO): Starting
2019-05-21T14:13:59.160Z,1558448039.160 [Micromodem](INFO): Starting
2019-05-21T14:13:59.538Z,1558448039.538 [Micromodem](INFO): Starting
2019-05-21T14:13:59.926Z,1558448039.926 [Micromodem](INFO): Starting
2019-05-21T14:14:00.322Z,1558448040.322 [Micromodem](INFO): Starting
2019-05-21T14:14:00.734Z,1558448040.734 [Micromodem](INFO): Starting
2019-05-21T14:14:00.749Z,1558448040.749 [NAL9602](DEBUG): Fix Requested
2019-05-21T14:14:01.130Z,1558448041.130 [Micromodem](INFO): Starting
2019-05-21T14:14:01.534Z,1558448041.534 [Micromodem](INFO): Starting
2019-05-21T14:14:01.938Z,1558448041.938 [Micromodem](INFO): Starting
2019-05-21T14:14:02.350Z,1558448042.350 [Micromodem](INFO): Starting
2019-05-21T14:14:02.760Z,1558448042.760 [Micromodem](INFO): Starting
2019-05-21T14:14:03.170Z,1558448043.170 [Micromodem](INFO): Starting
2019-05-21T14:14:03.579Z,1558448043.579 [Micromodem](INFO): Starting
2019-05-21T14:14:03.593Z,1558448043.593 [NAL9602](DEBUG): Fix Requested
2019-05-21T14:14:03.979Z,1558448043.979 [Micromodem](INFO): Starting
2019-05-21T14:14:04.378Z,1558448044.378 [Micromodem](INFO): Starting
2019-05-21T14:14:04.790Z,1558448044.790 [Micromodem](INFO): Starting
2019-05-21T14:14:05.182Z,1558448045.182 [Micromodem](INFO): Starting
2019-05-21T14:14:05.602Z,1558448045.602 [Micromodem](INFO): Starting
2019-05-21T14:14:06.003Z,1558448046.003 [Micromodem](INFO): Starting
2019-05-21T14:14:06.433Z,1558448046.433 [Micromodem](INFO): Starting
2019-05-21T14:14:06.806Z,1558448046.806 [Micromodem](INFO): Starting
2019-05-21T14:14:06.822Z,1558448046.822 [NAL9602](DEBUG): Fix Requested
2019-05-21T14:14:07.219Z,1558448047.219 [Micromodem](INFO): Starting
2019-05-21T14:14:07.606Z,1558448047.606 [Micromodem](INFO): Starting
2019-05-21T14:14:08.010Z,1558448048.010 [Micromodem](INFO): Starting
2019-05-21T14:14:08.011Z,1558448048.011 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2019-05-21T14:14:08.414Z,1558448048.414 [Micromodem](INFO): Starting
2019-05-21T14:14:08.833Z,1558448048.833 [Micromodem](INFO): Starting
2019-05-21T14:14:09.222Z,1558448049.222 [Micromodem](INFO): Starting
2019-05-21T14:14:09.626Z,1558448049.626 [Micromodem](INFO): Starting
2019-05-21T14:14:09.641Z,1558448049.641 [NAL9602](DEBUG): Fix Requested
2019-05-21T14:14:10.030Z,1558448050.030 [Micromodem](INFO): Starting
2019-05-21T14:14:10.434Z,1558448050.434 [Micromodem](INFO): Starting
2019-05-21T14:14:10.838Z,1558448050.838 [Micromodem](INFO): Starting
2019-05-21T14:14:11.258Z,1558448051.258 [Micromodem](INFO): Starting
2019-05-21T14:14:11.658Z,1558448051.658 [Micromodem](INFO): Starting
2019-05-21T14:14:12.074Z,1558448052.074 [Micromodem](INFO): Starting
2019-05-21T14:14:12.483Z,1558448052.483 [Micromodem](INFO): Starting
2019-05-21T14:14:12.870Z,1558448052.870 [Micromodem](INFO): Starting
2019-05-21T14:14:12.885Z,1558448052.885 [NAL9602](DEBUG): Fix Requested
2019-05-21T14:14:13.270Z,1558448053.270 [Micromodem](INFO): Starting
2019-05-21T14:14:13.674Z,1558448053.674 [Micromodem](INFO): Starting
2019-05-21T14:14:14.086Z,1558448054.086 [Micromodem](INFO): Starting
2019-05-21T14:14:14.518Z,1558448054.518 [Micromodem](INFO): Starting
2019-05-21T14:14:14.890Z,1558448054.890 [Micromodem](INFO): Starting
2019-05-21T14:14:15.290Z,1558448055.290 [Micromodem](INFO): Starting
2019-05-21T14:14:15.694Z,1558448055.694 [Micromodem](INFO): Starting
2019-05-21T14:14:15.709Z,1558448055.709 [NAL9602](DEBUG): Fix Requested
2019-05-21T14:14:16.113Z,1558448056.113 [Micromodem](INFO): Starting
2019-05-21T14:14:16.511Z,1558448056.511 [Micromodem](INFO): Starting
2019-05-21T14:14:16.915Z,1558448056.915 [Micromodem](INFO): Starting
2019-05-21T14:14:17.310Z,1558448057.310 [Micromodem](INFO): Starting
2019-05-21T14:14:17.723Z,1558448057.723 [Micromodem](INFO): Starting
2019-05-21T14:14:18.126Z,1558448058.126 [Micromodem](INFO): Starting
2019-05-21T14:14:18.522Z,1558448058.522 [Micromodem](INFO): Starting
2019-05-21T14:14:18.946Z,1558448058.946 [Micromodem](INFO): Starting
2019-05-21T14:14:18.961Z,1558448058.961 [NAL9602](DEBUG): Fix Requested
2019-05-21T14:14:19.330Z,1558448059.330 [Micromodem](INFO): Starting
2019-05-21T14:14:19.747Z,1558448059.747 [Micromodem](INFO): Starting
2019-05-21T14:14:20.190Z,1558448060.190 [Micromodem](INFO): Starting
2019-05-21T14:14:20.264Z,1558448060.264 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-05-21T14:14:20.546Z,1558448060.546 [Micromodem](INFO): Starting
2019-05-21T14:14:20.946Z,1558448060.946 [Micromodem](INFO): Starting
2019-05-21T14:14:21.350Z,1558448061.350 [Micromodem](INFO): Starting
2019-05-21T14:14:21.785Z,1558448061.785 [Micromodem](INFO): Starting
2019-05-21T14:14:21.801Z,1558448061.801 [NAL9602](DEBUG): Fix Requested
2019-05-21T14:14:22.158Z,1558448062.158 [Micromodem](INFO): Starting
2019-05-21T14:14:22.562Z,1558448062.562 [Micromodem](INFO): Starting
2019-05-21T14:14:22.974Z,1558448062.974 [Micromodem](INFO): Starting
2019-05-21T14:14:23.390Z,1558448063.390 [Micromodem](INFO): Starting
2019-05-21T14:14:23.795Z,1558448063.795 [Micromodem](INFO): Starting
2019-05-21T14:14:24.206Z,1558448064.206 [Micromodem](INFO): Starting
2019-05-21T14:14:24.582Z,1558448064.582 [Micromodem](INFO): Starting
2019-05-21T14:14:24.998Z,1558448064.998 [Micromodem](INFO): Starting
2019-05-21T14:14:25.013Z,1558448065.013 [NAL9602](DEBUG): Fix Requested
2019-05-21T14:14:25.390Z,1558448065.390 [Micromodem](INFO): Starting
2019-05-21T14:14:25.811Z,1558448065.811 [Micromodem](INFO): Starting
2019-05-21T14:14:26.204Z,1558448066.204 [Micromodem](INFO): Starting
2019-05-21T14:14:26.606Z,1558448066.606 [Micromodem](INFO): Starting
2019-05-21T14:14:27.006Z,1558448067.006 [Micromodem](INFO): Starting
2019-05-21T14:14:27.450Z,1558448067.450 [Micromodem](INFO): Starting
2019-05-21T14:14:27.814Z,1558448067.814 [Micromodem](INFO): Starting
2019-05-21T14:14:27.829Z,1558448067.829 [NAL9602](DEBUG): Fix Requested
2019-05-21T14:14:28.218Z,1558448068.218 [Micromodem](INFO): Starting
2019-05-21T14:14:28.622Z,1558448068.622 [Micromodem](INFO): Starting
2019-05-21T14:14:29.026Z,1558448069.026 [Micromodem](INFO): Starting
2019-05-21T14:14:29.438Z,1558448069.438 [Micromodem](INFO): Starting
2019-05-21T14:14:29.838Z,1558448069.838 [Micromodem](INFO): Starting
2019-05-21T14:14:30.243Z,1558448070.243 [Micromodem](INFO): Starting
2019-05-21T14:14:30.646Z,1558448070.646 [Micromodem](INFO): Starting
2019-05-21T14:14:30.665Z,1558448070.665 [NAL9602](DEBUG): Fix Requested
2019-05-21T14:14:31.050Z,1558448071.050 [Micromodem](INFO): Starting
2019-05-21T14:14:31.454Z,1558448071.454 [Micromodem](INFO): Starting
2019-05-21T14:14:31.882Z,1558448071.882 [Micromodem](INFO): Starting
2019-05-21T14:14:32.283Z,1558448072.283 [Micromodem](INFO): Starting
2019-05-21T14:14:32.662Z,1558448072.662 [Micromodem](INFO): Starting
2019-05-21T14:14:33.074Z,1558448073.074 [Micromodem](INFO): Starting
2019-05-21T14:14:33.474Z,1558448073.474 [Micromodem](INFO): Starting
2019-05-21T14:14:33.874Z,1558448073.874 [Micromodem](INFO): Starting
2019-05-21T14:14:33.889Z,1558448073.889 [NAL9602](DEBUG): Fix Requested
2019-05-21T14:14:34.003Z,1558448074.003 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-05-21T14:14:34.010Z,1558448074.010 [Startup:StartupSatComms:A](INFO): Timed out from 2019-05-21T14:13:33.7Z
2019-05-21T14:14:34.010Z,1558448074.010 [Startup:StartupSatComms:A] Stopped
2019-05-21T14:14:34.010Z,1558448074.010 [Startup:StartupSatComms:B] Running Loop=1
2019-05-21T14:14:34.282Z,1558448074.282 [Micromodem](INFO): Starting
2019-05-21T14:14:34.338Z,1558448074.338 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-05-21T14:14:34.738Z,1558448074.738 [Micromodem](INFO): Starting
2019-05-21T14:14:35.126Z,1558448075.126 [Micromodem](INFO): Starting
2019-05-21T14:14:35.530Z,1558448075.530 [Micromodem](INFO): Starting
2019-05-21T14:14:35.938Z,1558448075.938 [Micromodem](INFO): Starting
2019-05-21T14:14:36.342Z,1558448076.342 [Micromodem](INFO): Starting
2019-05-21T14:14:36.742Z,1558448076.742 [Micromodem](INFO): Starting
2019-05-21T14:14:36.757Z,1558448076.757 [NAL9602](DEBUG): Fix Requested
2019-05-21T14:14:37.150Z,1558448077.150 [Micromodem](INFO): Starting
2019-05-21T14:14:37.554Z,1558448077.554 [Micromodem](INFO): Starting
2019-05-21T14:14:37.971Z,1558448077.971 [Micromodem](INFO): Starting
2019-05-21T14:14:38.362Z,1558448078.362 [Micromodem](INFO): Starting
2019-05-21T14:14:38.766Z,1558448078.766 [Micromodem](INFO): Starting
2019-05-21T14:14:39.187Z,1558448079.187 [Micromodem](INFO): Starting
2019-05-21T14:14:39.578Z,1558448079.578 [Micromodem](INFO): Starting
2019-05-21T14:14:39.593Z,1558448079.593 [NAL9602](DEBUG): Fix Requested
2019-05-21T14:14:39.974Z,1558448079.974 [Micromodem](INFO): Starting
2019-05-21T14:14:40.378Z,1558448080.378 [Micromodem](INFO): Starting
2019-05-21T14:14:40.801Z,1558448080.801 [Micromodem](INFO): Starting
2019-05-21T14:14:41.186Z,1558448081.186 [Micromodem](INFO): Starting
2019-05-21T14:14:41.598Z,1558448081.598 [Micromodem](INFO): Starting
2019-05-21T14:14:41.994Z,1558448081.994 [Micromodem](INFO): Starting
2019-05-21T14:14:42.398Z,1558448082.398 [Micromodem](INFO): Starting
2019-05-21T14:14:42.830Z,1558448082.830 [Micromodem](INFO): Starting
2019-05-21T14:14:42.845Z,1558448082.845 [NAL9602](DEBUG): Fix Requested
2019-05-21T14:14:43.210Z,1558448083.210 [Micromodem](INFO): Starting
2019-05-21T14:14:43.630Z,1558448083.630 [Micromodem](INFO): Starting
2019-05-21T14:14:44.014Z,1558448084.014 [Micromodem](INFO): Starting
2019-05-21T14:14:44.418Z,1558448084.418 [Micromodem](INFO): Starting
2019-05-21T14:14:44.822Z,1558448084.822 [Micromodem](INFO): Starting
2019-05-21T14:14:45.240Z,1558448085.240 [Micromodem](INFO): Starting
2019-05-21T14:14:45.675Z,1558448085.675 [Micromodem](INFO): Starting
2019-05-21T14:14:45.689Z,1558448085.689 [NAL9602](DEBUG): Fix Requested
2019-05-21T14:14:46.046Z,1558448086.046 [Micromodem](INFO): Starting
2019-05-21T14:14:46.446Z,1558448086.446 [Micromodem](INFO): Starting
2019-05-21T14:14:46.862Z,1558448086.862 [Micromodem](INFO): Starting
2019-05-21T14:14:47.246Z,1558448087.246 [Micromodem](INFO): Starting
2019-05-21T14:14:47.269Z,1558448087.269 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-05-21T14:14:47.269Z,1558448087.269 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-21T14:14:47.303Z,1558448087.303 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-21T14:14:47.650Z,1558448087.650 [Micromodem](INFO): Starting
2019-05-21T14:14:47.682Z,1558448087.682 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-21T14:14:47.682Z,1558448087.682 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-05-21T14:14:48.054Z,1558448088.054 [Micromodem](INFO): Starting
2019-05-21T14:14:48.458Z,1558448088.458 [Micromodem](INFO): Starting
2019-05-21T14:14:48.862Z,1558448088.862 [Micromodem](INFO): Starting
2019-05-21T14:14:48.877Z,1558448088.877 [NAL9602](DEBUG): Fix Requested
2019-05-21T14:14:49.290Z,1558448089.290 [Micromodem](INFO): Starting
2019-05-21T14:14:49.653Z,1558448089.653 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190520T161244/Courier0579.lzma
2019-05-21T14:14:49.678Z,1558448089.678 [Micromodem](INFO): Starting
2019-05-21T14:14:50.074Z,1558448090.074 [Micromodem](INFO): Starting
2019-05-21T14:14:50.435Z,1558448090.435 [DataOverHttps](INFO): Moved sent file to Logs/20190520T161244/Courier0579.lzma.bak
2019-05-21T14:14:50.435Z,1558448090.435 [DataOverHttps](INFO): SBD MOMSN=11130287
2019-05-21T14:14:50.482Z,1558448090.482 [Micromodem](INFO): Starting
2019-05-21T14:14:50.882Z,1558448090.882 [Micromodem](INFO): Starting
2019-05-21T14:14:51.286Z,1558448091.286 [Micromodem](INFO): Starting
2019-05-21T14:14:51.690Z,1558448091.690 [Micromodem](INFO): Starting
2019-05-21T14:14:51.705Z,1558448091.705 [NAL9602](DEBUG): Fix Requested
2019-05-21T14:14:52.094Z,1558448092.094 [Micromodem](INFO): Starting
2019-05-21T14:14:52.498Z,1558448092.498 [Micromodem](INFO): Starting
2019-05-21T14:14:52.914Z,1558448092.914 [Micromodem](INFO): Starting
2019-05-21T14:14:53.326Z,1558448093.326 [Micromodem](INFO): Starting
2019-05-21T14:14:53.749Z,1558448093.749 [Micromodem](INFO): Starting
2019-05-21T14:14:54.118Z,1558448094.118 [Micromodem](INFO): Starting
2019-05-21T14:14:54.518Z,1558448094.518 [Micromodem](INFO): Starting
2019-05-21T14:14:54.533Z,1558448094.533 [NAL9602](DEBUG): Fix Requested
2019-05-21T14:14:54.922Z,1558448094.922 [Micromodem](INFO): Starting
2019-05-21T14:14:55.326Z,1558448095.326 [Micromodem](INFO): Starting
2019-05-21T14:14:55.730Z,1558448095.730 [Micromodem](INFO): Starting
2019-05-21T14:14:56.142Z,1558448096.142 [Micromodem](INFO): Starting
2019-05-21T14:14:56.563Z,1558448096.563 [Micromodem](INFO): Starting
2019-05-21T14:14:56.942Z,1558448096.942 [Micromodem](INFO): Starting
2019-05-21T14:14:57.346Z,1558448097.346 [Micromodem](INFO): Starting
2019-05-21T14:14:57.758Z,1558448097.758 [Micromodem](INFO): Starting
2019-05-21T14:14:57.773Z,1558448097.773 [NAL9602](DEBUG): Fix Requested
2019-05-21T14:14:58.154Z,1558448098.154 [Micromodem](INFO): Starting
2019-05-21T14:14:58.587Z,1558448098.587 [Micromodem](INFO): Starting
2019-05-21T14:14:58.962Z,1558448098.962 [Micromodem](INFO): Starting
2019-05-21T14:14:59.366Z,1558448099.366 [Micromodem](INFO): Starting
2019-05-21T14:14:59.785Z,1558448099.785 [Micromodem](INFO): Starting
2019-05-21T14:15:00.182Z,1558448100.182 [Micromodem](INFO): Starting
2019-05-21T14:15:00.578Z,1558448100.578 [Micromodem](INFO): Starting
2019-05-21T14:15:00.593Z,1558448100.593 [NAL9602](DEBUG): Fix Requested
2019-05-21T14:15:00.982Z,1558448100.982 [Micromodem](INFO): Starting
2019-05-21T14:15:01.386Z,1558448101.386 [Micromodem](INFO): Starting
2019-05-21T14:15:01.822Z,1558448101.822 [Micromodem](INFO): Starting
2019-05-21T14:15:02.226Z,1558448102.226 [Micromodem](INFO): Starting
2019-05-21T14:15:02.606Z,1558448102.606 [Micromodem](INFO): Starting
2019-05-21T14:15:03.014Z,1558448103.014 [Micromodem](INFO): Starting
2019-05-21T14:15:03.406Z,1558448103.406 [Micromodem](INFO): Starting
2019-05-21T14:15:03.810Z,1558448103.810 [Micromodem](INFO): Starting
2019-05-21T14:15:03.825Z,1558448103.825 [NAL9602](DEBUG): Fix Requested
2019-05-21T14:15:04.214Z,1558448104.214 [Micromodem](INFO): Starting
2019-05-21T14:15:04.618Z,1558448104.618 [Micromodem](INFO): Starting
2019-05-21T14:15:05.026Z,1558448105.026 [Micromodem](INFO): Starting
2019-05-21T14:15:05.426Z,1558448105.426 [Micromodem](INFO): Starting
2019-05-21T14:15:05.834Z,1558448105.834 [Micromodem](INFO): Starting
2019-05-21T14:15:06.234Z,1558448106.234 [Micromodem](INFO): Starting
2019-05-21T14:15:06.678Z,1558448106.678 [Micromodem](INFO): Starting
2019-05-21T14:15:06.693Z,1558448106.693 [NAL9602](DEBUG): Fix Requested
2019-05-21T14:15:07.042Z,1558448107.042 [Micromodem](INFO): Starting
2019-05-21T14:15:07.450Z,1558448107.450 [Micromodem](INFO): Starting
2019-05-21T14:15:07.878Z,1558448107.878 [Micromodem](INFO): Starting
2019-05-21T14:15:08.254Z,1558448108.254 [Micromodem](INFO): Starting
2019-05-21T14:15:08.658Z,1558448108.658 [Micromodem](INFO): Starting
2019-05-21T14:15:09.062Z,1558448109.062 [Micromodem](INFO): Starting
2019-05-21T14:15:09.466Z,1558448109.466 [Micromodem](INFO): Starting
2019-05-21T14:15:09.870Z,1558448109.870 [Micromodem](INFO): Starting
2019-05-21T14:15:09.889Z,1558448109.889 [NAL9602](DEBUG): Fix Requested
2019-05-21T14:15:10.294Z,1558448110.294 [Micromodem](INFO): Starting
2019-05-21T14:15:10.678Z,1558448110.678 [Micromodem](INFO): Starting
2019-05-21T14:15:11.086Z,1558448111.086 [Micromodem](INFO): Starting
2019-05-21T14:15:11.486Z,1558448111.486 [Micromodem](INFO): Starting
2019-05-21T14:15:11.890Z,1558448111.890 [Micromodem](INFO): Starting
2019-05-21T14:15:12.303Z,1558448112.303 [Micromodem](INFO): Starting
2019-05-21T14:15:12.699Z,1558448112.699 [Micromodem](INFO): Starting
2019-05-21T14:15:12.730Z,1558448112.730 [NAL9602](DEBUG): Fix Requested
2019-05-21T14:15:13.102Z,1558448113.102 [Micromodem](INFO): Starting
2019-05-21T14:15:13.510Z,1558448113.510 [Micromodem](INFO): Starting
2019-05-21T14:15:13.910Z,1558448113.910 [Micromodem](INFO): Starting
2019-05-21T14:15:14.314Z,1558448114.314 [Micromodem](INFO): Starting
2019-05-21T14:15:14.722Z,1558448114.722 [Micromodem](INFO): Starting
2019-05-21T14:15:15.122Z,1558448115.122 [Micromodem](INFO): Starting
2019-05-21T14:15:15.533Z,1558448115.533 [Micromodem](INFO): Starting
2019-05-21T14:15:15.946Z,1558448115.946 [Micromodem](INFO): Starting
2019-05-21T14:15:15.961Z,1558448115.961 [NAL9602](DEBUG): Fix Requested
2019-05-21T14:15:16.338Z,1558448116.338 [Micromodem](INFO): Starting
2019-05-21T14:15:16.738Z,1558448116.738 [Micromodem](INFO): Starting
2019-05-21T14:15:17.147Z,1558448117.147 [Micromodem](INFO): Starting
2019-05-21T14:15:17.546Z,1558448117.546 [Micromodem](INFO): Starting
2019-05-21T14:15:17.996Z,1558448117.996 [Micromodem](INFO): Starting
2019-05-21T14:15:18.406Z,1558448118.406 [Micromodem](INFO): Starting
2019-05-21T14:15:18.799Z,1558448118.799 [Micromodem](INFO): Starting
2019-05-21T14:15:18.813Z,1558448118.813 [NAL9602](DEBUG): Fix Requested
2019-05-21T14:15:19.186Z,1558448119.186 [Micromodem](INFO): Starting
2019-05-21T14:15:19.606Z,1558448119.606 [Micromodem](INFO): Starting
2019-05-21T14:15:19.998Z,1558448119.998 [Micromodem](INFO): Starting
2019-05-21T14:15:20.398Z,1558448120.398 [Micromodem](INFO): Starting
2019-05-21T14:15:20.834Z,1558448120.834 [Micromodem](INFO): Starting
2019-05-21T14:15:21.219Z,1558448121.219 [Micromodem](INFO): Starting
2019-05-21T14:15:21.606Z,1558448121.606 [Micromodem](INFO): Starting
2019-05-21T14:15:21.621Z,1558448121.621 [NAL9602](DEBUG): Fix Requested
2019-05-21T14:15:22.010Z,1558448122.010 [Micromodem](INFO): Starting
2019-05-21T14:15:22.414Z,1558448122.414 [Micromodem](INFO): Starting
2019-05-21T14:15:22.842Z,1558448122.842 [Micromodem](INFO): Starting
2019-05-21T14:15:23.270Z,1558448123.270 [Micromodem](INFO): Starting
2019-05-21T14:15:23.630Z,1558448123.630 [Micromodem](INFO): Starting
2019-05-21T14:15:24.030Z,1558448124.030 [Micromodem](INFO): Starting
2019-05-21T14:15:24.103Z,1558448124.103 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2019-05-21T14:15:24.434Z,1558448124.434 [Micromodem](INFO): Starting
2019-05-21T14:15:24.838Z,1558448124.838 [Micromodem](INFO): Starting
2019-05-21T14:15:24.853Z,1558448124.853 [NAL9602](DEBUG): Fix Requested
2019-05-21T14:15:25.242Z,1558448125.242 [Micromodem](INFO): Starting
2019-05-21T14:15:25.658Z,1558448125.658 [Micromodem](INFO): Starting
2019-05-21T14:15:26.061Z,1558448126.061 [Micromodem](INFO): Starting
2019-05-21T14:15:26.454Z,1558448126.454 [Micromodem](INFO): Starting
2019-05-21T14:15:26.901Z,1558448126.901 [Micromodem](INFO): Starting
2019-05-21T14:15:27.262Z,1558448127.262 [Micromodem](INFO): Starting
2019-05-21T14:15:27.666Z,1558448127.666 [Micromodem](INFO): Starting
2019-05-21T14:15:27.681Z,1558448127.681 [NAL9602](DEBUG): Fix Requested
2019-05-21T14:15:28.070Z,1558448128.070 [Micromodem](INFO): Starting
2019-05-21T14:15:28.474Z,1558448128.474 [Micromodem](INFO): Starting
2019-05-21T14:15:28.889Z,1558448128.889 [Micromodem](INFO): Starting
2019-05-21T14:15:29.286Z,1558448129.286 [Micromodem](INFO): Starting
2019-05-21T14:15:29.734Z,1558448129.734 [Micromodem](INFO): Starting
2019-05-21T14:15:30.143Z,1558448130.143 [Micromodem](INFO): Starting
2019-05-21T14:15:30.522Z,1558448130.522 [Micromodem](INFO): Starting
2019-05-21T14:15:30.926Z,1558448130.926 [Micromodem](INFO): Starting
2019-05-21T14:15:30.941Z,1558448130.941 [NAL9602](DEBUG): Fix Requested
2019-05-21T14:15:31.330Z,1558448131.330 [Micromodem](INFO): Starting
2019-05-21T14:15:31.738Z,1558448131.738 [Micromodem](INFO): Starting
2019-05-21T14:15:32.138Z,1558448132.138 [Micromodem](INFO): Starting
2019-05-21T14:15:32.559Z,1558448132.559 [Micromodem](INFO): Starting
2019-05-21T14:15:32.954Z,1558448132.954 [Micromodem](INFO): Starting
2019-05-21T14:15:33.377Z,1558448133.377 [Micromodem](INFO): Starting
2019-05-21T14:15:33.774Z,1558448133.774 [Micromodem](INFO): Starting
2019-05-21T14:15:33.789Z,1558448133.789 [NAL9602](DEBUG): Fix Requested
2019-05-21T14:15:34.166Z,1558448134.166 [Micromodem](INFO): Starting
2019-05-21T14:15:34.226Z,1558448134.226 [Startup:StartupSatComms:B](INFO): Timed out from 2019-05-21T14:14:34.0Z
2019-05-21T14:15:34.226Z,1558448134.226 [Startup:StartupSatComms:B] Stopped
2019-05-21T14:15:34.226Z,1558448134.226 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-05-21T14:15:34.226Z,1558448134.226 [Startup:StartupSatComms] Stopped
2019-05-21T14:15:34.227Z,1558448134.227 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-05-21T14:15:34.227Z,1558448134.227 [Startup](INFO): Completed Startup
2019-05-21T14:15:34.227Z,1558448134.227 [MissionManager](INFO): Startup is completed.
2019-05-21T14:15:34.228Z,1558448134.228 [MissionManager](INFO): Uninitializing Mission Startup
2019-05-21T14:15:34.228Z,1558448134.228 [Startup] Stopped
2019-05-21T14:15:34.228Z,1558448134.228 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-05-21T14:15:34.228Z,1558448134.228 [Startup:A.GoToSurface] Stopped
2019-05-21T14:15:34.228Z,1558448134.228 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-05-21T14:15:34.566Z,1558448134.566 [Micromodem](INFO): Starting
2019-05-21T14:15:34.602Z,1558448134.602 [MissionManager](IMPORTANT): Started mission Default
2019-05-21T14:15:34.602Z,1558448134.602 [Default] Running Loop=1
2019-05-21T14:15:34.602Z,1558448134.602 [Default](DEBUG): Aggregate::initialize Default
2019-05-21T14:15:34.602Z,1558448134.602 [Default:B.GoToSurface] Running Loop=1
2019-05-21T14:15:34.603Z,1558448134.603 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-05-21T14:15:34.603Z,1558448134.603 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-05-21T14:15:34.603Z,1558448134.603 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-05-21T14:15:34.603Z,1558448134.603 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-05-21T14:15:34.604Z,1558448134.604 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-05-21T14:15:34.604Z,1558448134.604 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-05-21T14:15:34.604Z,1558448134.604 [Default:A.Wait] Running Loop=1
2019-05-21T14:15:34.605Z,1558448134.605 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-05-21T14:15:34.966Z,1558448134.966 [Micromodem](INFO): Starting
2019-05-21T14:15:35.370Z,1558448135.370 [Micromodem](INFO): Starting
2019-05-21T14:15:35.798Z,1558448135.798 [Micromodem](INFO): Starting
2019-05-21T14:15:36.186Z,1558448136.186 [Micromodem](INFO): Starting
2019-05-21T14:15:36.602Z,1558448136.602 [Micromodem](INFO): Starting
2019-05-21T14:15:36.617Z,1558448136.617 [NAL9602](DEBUG): Fix Requested
2019-05-21T14:15:37.003Z,1558448137.003 [Micromodem](INFO): Starting
2019-05-21T14:15:37.394Z,1558448137.394 [Micromodem](INFO): Starting
2019-05-21T14:15:37.798Z,1558448137.798 [Micromodem](INFO): Starting
2019-05-21T14:15:38.199Z,1558448138.199 [Micromodem](INFO): Starting
2019-05-21T14:15:38.602Z,1558448138.602 [Micromodem](INFO): Starting
2019-05-21T14:15:39.010Z,1558448139.010 [Micromodem](INFO): Starting
2019-05-21T14:15:39.495Z,1558448139.495 [Micromodem](INFO): Starting
2019-05-21T14:15:39.826Z,1558448139.826 [Micromodem](INFO): Starting
2019-05-21T14:15:39.853Z,1558448139.853 [NAL9602](DEBUG): Fix Requested
2019-05-21T14:15:40.238Z,1558448140.238 [Micromodem](INFO): Starting
2019-05-21T14:15:40.630Z,1558448140.630 [Micromodem](INFO): Starting
2019-05-21T14:15:41.030Z,1558448141.030 [Micromodem](INFO): Starting
2019-05-21T14:15:41.438Z,1558448141.438 [Micromodem](INFO): Starting
2019-05-21T14:15:41.838Z,1558448141.838 [Micromodem](INFO): Starting
2019-05-21T14:15:42.242Z,1558448142.242 [Micromodem](INFO): Starting
2019-05-21T14:15:42.650Z,1558448142.650 [Micromodem](INFO): Starting
2019-05-21T14:15:42.665Z,1558448142.665 [NAL9602](DEBUG): Fix Requested
2019-05-21T14:15:43.050Z,1558448143.050 [Micromodem](INFO): Starting
2019-05-21T14:15:43.458Z,1558448143.458 [Micromodem](INFO): Starting
2019-05-21T14:15:43.858Z,1558448143.858 [Micromodem](INFO): Starting
2019-05-21T14:15:44.262Z,1558448144.262 [Micromodem](INFO): Starting
2019-05-21T14:15:44.673Z,1558448144.673 [Micromodem](INFO): Starting
2019-05-21T14:15:45.074Z,1558448145.074 [Micromodem](INFO): Starting
2019-05-21T14:15:45.474Z,1558448145.474 [Micromodem](INFO): Starting
2019-05-21T14:15:45.878Z,1558448145.878 [Micromodem](INFO): Starting
2019-05-21T14:15:45.893Z,1558448145.893 [NAL9602](DEBUG): Fix Requested
2019-05-21T14:15:46.286Z,1558448146.286 [Micromodem](INFO): Starting
2019-05-21T14:15:46.686Z,1558448146.686 [Micromodem](INFO): Starting
2019-05-21T14:15:47.090Z,1558448147.090 [Micromodem](INFO): Starting
2019-05-21T14:15:47.498Z,1558448147.498 [Micromodem](INFO): Starting
2019-05-21T14:15:47.902Z,1558448147.902 [Micromodem](INFO): Starting
2019-05-21T14:15:48.083Z,1558448148.083 [Default:A.Wait](INFO): Done Waiting.
2019-05-21T14:15:48.083Z,1558448148.083 [Default:A.Wait] Stopped
2019-05-21T14:15:48.083Z,1558448148.083 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-05-21T14:15:48.302Z,1558448148.302 [Micromodem](INFO): Starting
2019-05-21T14:15:48.343Z,1558448148.343 [Default:CheckIn] Running Loop=1
2019-05-21T14:15:48.343Z,1558448148.343 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-05-21T14:15:48.343Z,1558448148.343 [Default:CheckIn:Read_GPS] Running Loop=1
2019-05-21T14:15:48.710Z,1558448148.710 [Micromodem](INFO): Starting
2019-05-21T14:15:48.733Z,1558448148.733 [NAL9602](DEBUG): Fix Requested
2019-05-21T14:15:48.770Z,1558448148.770 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-05-21T14:15:49.134Z,1558448149.134 [Micromodem](INFO): Starting
2019-05-21T14:15:49.514Z,1558448149.514 [Micromodem](INFO): Starting
2019-05-21T14:15:49.922Z,1558448149.922 [Micromodem](INFO): Starting
2019-05-21T14:15:50.338Z,1558448150.338 [Micromodem](INFO): Starting
2019-05-21T14:15:50.727Z,1558448150.727 [Micromodem](INFO): Starting
2019-05-21T14:15:51.134Z,1558448151.134 [Micromodem](INFO): Starting
2019-05-21T14:15:51.551Z,1558448151.551 [Micromodem](INFO): Starting
2019-05-21T14:15:51.552Z,1558448151.552 [Micromodem](FAULT): failed to initialize; deviceResponse_ loaded: $CAREV,141547,AUV,2.0.27690*17
, available: $CAREV,141547,COPROC,0.10.0.46*42

2019-05-21T14:15:51.552Z,1558448151.552 [Micromodem] Communications Fault, FailCount= 2
2019-05-21T14:15:51.552Z,1558448151.552 [Micromodem](ERROR): Communications Fault
2019-05-21T14:15:51.717Z,1558448151.717 [CBIT](ERROR): Communications Fault in component: Micromodem
2019-05-21T14:15:51.938Z,1558448151.938 [Micromodem](INFO): Stop
2019-05-21T14:15:51.938Z,1558448151.938 [Micromodem](INFO): uninitialize
2019-05-21T14:15:51.938Z,1558448151.938 [Micromodem](INFO): Powering down
2019-05-21T14:15:52.081Z,1558448152.081 [NAL9602](DEBUG): Fix Requested
2019-05-21T14:15:52.394Z,1558448152.394 [Micromodem](INFO): Stopping
2019-05-21T14:15:52.772Z,1558448152.772 [Micromodem](INFO): Stopped
2019-05-21T14:15:52.994Z,1558448152.994 [CBIT](INFO): Clearing failed state for component Micromodem
2019-05-21T14:15:52.994Z,1558448152.994 [Micromodem] No Fault, FailCount= 2
2019-05-21T14:15:53.158Z,1558448153.158 [Micromodem](INFO): Start
2019-05-21T14:15:53.603Z,1558448153.603 [Micromodem](INFO): Start
2019-05-21T14:15:53.958Z,1558448153.958 [Micromodem](INFO): Start
2019-05-21T14:15:54.362Z,1558448154.362 [Micromodem](INFO): Start
2019-05-21T14:15:54.781Z,1558448154.781 [Micromodem](INFO): Start
2019-05-21T14:15:54.861Z,1558448154.861 [NAL9602](DEBUG): Fix Requested
2019-05-21T14:15:55.176Z,1558448155.176 [Micromodem](INFO): Start
2019-05-21T14:15:55.574Z,1558448155.574 [Micromodem](INFO): Starting
2019-05-21T14:15:55.574Z,1558448155.574 [Micromodem](INFO): Powering up
2019-05-21T14:15:55.575Z,1558448155.575 [Micromodem](DEBUG): Initializing Micromodem.
2019-05-21T14:15:55.982Z,1558448155.982 [Micromodem](INFO): Starting
2019-05-21T14:15:56.386Z,1558448156.386 [Micromodem](INFO): Starting
2019-05-21T14:15:56.790Z,1558448156.790 [Micromodem](INFO): Starting
2019-05-21T14:15:57.194Z,1558448157.194 [Micromodem](INFO): Starting
2019-05-21T14:15:57.598Z,1558448157.598 [Micromodem](INFO): Starting
2019-05-21T14:15:57.613Z,1558448157.613 [NAL9602](DEBUG): Fix Requested
2019-05-21T14:15:58.002Z,1558448158.002 [Micromodem](INFO): Starting
2019-05-21T14:15:58.414Z,1558448158.414 [Micromodem](INFO): Starting
2019-05-21T14:15:58.815Z,1558448158.815 [Micromodem](INFO): Starting
2019-05-21T14:15:59.230Z,1558448159.230 [Micromodem](INFO): Starting
2019-05-21T14:15:59.614Z,1558448159.614 [Micromodem](INFO): Starting
2019-05-21T14:16:00.022Z,1558448160.022 [Micromodem](INFO): Starting
2019-05-21T14:16:00.422Z,1558448160.422 [Micromodem](INFO): Starting
2019-05-21T14:16:00.422Z,1558448160.422 [Micromodem](INFO): Starting
2019-05-21T14:16:00.423Z,1558448160.423 [Micromodem](DEBUG): Nmea buf: $CCCFG,ALL,0*33

2019-05-21T14:16:00.423Z,1558448160.423 [Micromodem](DEBUG): Nmea buf: $CCCFG,SRC,1*31

2019-05-21T14:16:00.423Z,1558448160.423 [Micromodem](DEBUG): Nmea buf: $CCCFG,nav.dt.txtrig_gpio4,1*64

2019-05-21T14:16:00.424Z,1558448160.424 [Micromodem](DEBUG): Nmea buf: $CCCFG,BND,1*3B

2019-05-21T14:16:00.830Z,1558448160.830 [Micromodem](INFO): Starting
2019-05-21T14:16:00.846Z,1558448160.846 [NAL9602](DEBUG): Fix Requested
2019-05-21T14:16:01.230Z,1558448161.230 [Micromodem](INFO): Starting
2019-05-21T14:16:01.634Z,1558448161.634 [Micromodem](INFO): Starting
2019-05-21T14:16:02.046Z,1558448162.046 [Micromodem](INFO): Starting
2019-05-21T14:16:02.442Z,1558448162.442 [Micromodem](INFO): Starting
2019-05-21T14:16:02.866Z,1558448162.866 [Micromodem](INFO): Starting
2019-05-21T14:16:03.270Z,1558448163.270 [Micromodem](INFO): Starting
2019-05-21T14:16:03.671Z,1558448163.671 [Micromodem](INFO): Starting
2019-05-21T14:16:03.685Z,1558448163.685 [NAL9602](DEBUG): Fix Requested
2019-05-21T14:16:04.066Z,1558448164.066 [Micromodem](INFO): Starting
2019-05-21T14:16:04.334Z,1558448164.334 [CommandLine](IMPORTANT): got command quit
2019-05-21T14:16:04.474Z,1558448164.474 [Micromodem](INFO): Starting
2019-05-21T14:16:04.895Z,1558448164.895 [Micromodem](INFO): Starting
2019-05-21T14:16:05.278Z,1558448165.278 [Micromodem](INFO): Starting
2019-05-21T14:16:05.337Z,1558448165.337 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2019-05-21T14:16:05.337Z,1558448165.337 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-21T14:16:05.337Z,1558448165.337 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-05-21T14:16:05.381Z,1558448165.381 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-05-21T14:16:05.381Z,1558448165.381 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-05-21T14:16:05.382Z,1558448165.382 [CommandLine](INFO): Join timeout helper Thread ID is 9770
2019-05-21T14:16:05.382Z,1558448165.382 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-05-21T14:16:05.382Z,1558448165.382 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-05-21T14:16:05.383Z,1558448165.383 [NavChartDb](INFO): Join timeout helper Thread ID is 9771
2019-05-21T14:16:05.429Z,1558448165.429 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-21T14:16:05.429Z,1558448165.429 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-05-21T14:16:05.433Z,1558448165.433 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2019-05-21T14:16:05.433Z,1558448165.433 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-05-21T14:16:05.433Z,1558448165.433 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 9772
2019-05-21T14:16:05.686Z,1558448165.686 [Micromodem](INFO): Starting
2019-05-21T14:16:05.785Z,1558448165.785 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-21T14:16:05.786Z,1558448165.786 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-05-21T14:16:05.789Z,1558448165.789 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-05-21T14:16:05.790Z,1558448165.790 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-05-21T14:16:05.791Z,1558448165.791 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-05-21T14:16:05.792Z,1558448165.792 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 9775
2019-05-21T14:16:05.838Z,1558448165.838 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-21T14:16:05.838Z,1558448165.838 [CTD_NeilBrown](INFO): Powering down
2019-05-21T14:16:05.853Z,1558448165.853 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-05-21T14:16:05.869Z,1558448165.869 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-05-21T14:16:05.869Z,1558448165.869 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-05-21T14:16:05.869Z,1558448165.869 [Radio_Surface](INFO): Join timeout helper Thread ID is 9776
2019-05-21T14:16:06.086Z,1558448166.086 [Micromodem](INFO): Starting
2019-05-21T14:16:06.237Z,1558448166.237 [Radio_Surface](INFO): Powering down
2019-05-21T14:16:06.238Z,1558448166.238 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-21T14:16:06.238Z,1558448166.238 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-05-21T14:16:06.242Z,1558448166.242 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-05-21T14:16:06.242Z,1558448166.242 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-05-21T14:16:06.242Z,1558448166.242 [DataOverHttps](INFO): Join timeout helper Thread ID is 9779
2019-05-21T14:16:06.243Z,1558448166.243 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-21T14:16:06.243Z,1558448166.243 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-05-21T14:16:06.244Z,1558448166.244 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-05-21T14:16:06.244Z,1558448166.244 [logger ThreadHandler](INFO): Thread cancelled.
2019-05-21T14:16:06.261Z,1558448166.261 [logger](INFO): Join timeout helper Thread ID is 9781
2019-05-21T14:16:06.333Z,1558448166.333 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-21T14:16:06.333Z,1558448166.333 [logger ThreadHandler](INFO): Thread cancelled.
2019-05-21T14:16:06.338Z,1558448166.338 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-05-21T14:16:06.338Z,1558448166.338 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-05-21T14:16:06.338Z,1558448166.338 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-05-21T14:16:06.338Z,1558448166.338 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-05-21T14:16:06.338Z,1558448166.338 [controlThread](INFO): Join timeout helper Thread ID is 9783
2019-05-21T14:16:06.481Z,1558448166.481 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-21T14:16:06.481Z,1558448166.481 [controlThread](DEBUG): Uninitializing ControlThread
2019-05-21T14:16:06.482Z,1558448166.482 [AHRS_M2](INFO): Powering down
2019-05-21T14:16:06.553Z,1558448166.553 [DUSBL_Hydroid](INFO): Powering down
2019-05-21T14:16:06.645Z,1558448166.645 [Micromodem](INFO): uninitialize
2019-05-21T14:16:06.645Z,1558448166.645 [Micromodem](INFO): Powering down
2019-05-21T14:16:06.741Z,1558448166.741 [NAL9602](INFO): Powering down
2019-05-21T14:16:06.813Z,1558448166.813 [RDI_Pathfinder](INFO): Powering down
2019-05-21T14:16:06.815Z,1558448166.815 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-05-21T14:16:06.815Z,1558448166.815 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-05-21T14:16:06.816Z,1558448166.816 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-05-21T14:16:06.816Z,1558448166.816 [MissionManager](INFO): Uninitializing Mission Default
2019-05-21T14:16:06.816Z,1558448166.816 [Default] Stopped
2019-05-21T14:16:06.817Z,1558448166.817 [Default](DEBUG): Aggregate::uninitialize Default
2019-05-21T14:16:06.817Z,1558448166.817 [Default:B.GoToSurface] Stopped
2019-05-21T14:16:06.817Z,1558448166.817 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-05-21T14:16:06.817Z,1558448166.817 [Default:CheckIn] Stopped
2019-05-21T14:16:06.817Z,1558448166.817 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-05-21T14:16:06.817Z,1558448166.817 [Default:CheckIn:Read_GPS] Stopped
2019-05-21T14:16:06.820Z,1558448166.820 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-05-21T14:16:06.820Z,1558448166.820 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-05-21T14:16:06.820Z,1558448166.820 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-05-21T14:16:06.820Z,1558448166.820 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-05-21T14:16:06.821Z,1558448166.821 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-05-21T14:16:06.821Z,1558448166.821 [BuoyancyServo](INFO): Powering down
2019-05-21T14:16:06.833Z,1558448166.833 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-05-21T14:16:06.833Z,1558448166.833 [ElevatorServo](INFO): Powering down
2019-05-21T14:16:06.834Z,1558448166.834 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-05-21T14:16:06.834Z,1558448166.834 [MassServo](INFO): Powering down
2019-05-21T14:16:06.835Z,1558448166.835 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-05-21T14:16:06.835Z,1558448166.835 [RudderServo](INFO): Powering down
2019-05-21T14:16:06.836Z,1558448166.836 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-05-21T14:16:06.836Z,1558448166.836 [ThrusterServo](INFO): Powering down
2019-05-21T14:16:06.836Z,1558448166.836 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-05-21T14:16:06.837Z,1558448166.837 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-05-21T14:16:06.837Z,1558448166.837 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-05-21T14:16:06.837Z,1558448166.837 [CBIT](DEBUG): Powering off loads.
2019-05-21T14:16:06.849Z,1558448166.849 [CBIT](DEBUG): Disabling WDT.
2019-05-21T14:16:06.861Z,1558448166.861 [CBIT](DEBUG): Opening all GF detection circuits.
2019-05-21T14:16:06.862Z,1558448166.862 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-05-21T14:16:06.937Z,1558448166.937 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-05-21T14:16:06.946Z,1558448166.946 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-05-21T14:16:07.002Z,1558448167.002 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-05-21T14:16:07.004Z,1558448167.004 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-05-21T14:16:07.059Z,1558448167.059 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-05-21T14:16:07.115Z,1558448167.115 [logger ThreadHandler](INFO): Thread cancelled.