2020-01-23T22:58:24.089Z,1579820304.089 [Supervisor](DEBUG): Initializing supervisor.
2020-01-23T22:58:24.092Z,1579820304.092 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-01-23T22:58:24.093Z,1579820304.093 [SyncHandler](INFO): Protected caller Thread ID is 1174
2020-01-23T22:58:24.094Z,1579820304.094 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-01-23T22:58:24.095Z,1579820304.095 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-01-23T22:58:24.095Z,1579820304.095 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1175
2020-01-23T22:58:24.098Z,1579820304.098 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-01-23T22:58:24.111Z,1579820304.111 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-01-23T22:58:24.112Z,1579820304.112 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-01-23T22:58:24.112Z,1579820304.112 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1176
2020-01-23T22:58:24.113Z,1579820304.113 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-01-23T22:58:24.114Z,1579820304.114 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-01-23T22:58:24.114Z,1579820304.114 [logger ThreadHandler](INFO): Protected caller Thread ID is 1177
2020-01-23T22:58:24.116Z,1579820304.116 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-01-23T22:58:24.116Z,1579820304.116 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-01-23T22:58:24.118Z,1579820304.118 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-01-23T22:58:24.576Z,1579820304.576 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-01-23T22:58:24.577Z,1579820304.577 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-01-23T22:58:24.676Z,1579820304.676 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-01-23T22:58:24.676Z,1579820304.676 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-01-23T22:58:25.008Z,1579820305.008 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-01-23T22:58:25.008Z,1579820305.008 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-01-23T22:58:25.150Z,1579820305.150 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-01-23T22:58:25.150Z,1579820305.150 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-01-23T22:58:25.349Z,1579820305.349 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-01-23T22:58:25.349Z,1579820305.349 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-01-23T22:58:25.816Z,1579820305.816 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-01-23T22:58:25.816Z,1579820305.816 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-01-23T22:58:26.033Z,1579820306.033 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-01-23T22:58:26.034Z,1579820306.034 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-01-23T22:58:26.181Z,1579820306.181 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-01-23T22:58:26.182Z,1579820306.182 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-01-23T22:58:26.383Z,1579820306.383 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-01-23T22:58:26.384Z,1579820306.384 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-01-23T22:58:26.481Z,1579820306.481 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-01-23T22:58:26.481Z,1579820306.481 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-01-23T22:58:26.795Z,1579820306.795 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-01-23T22:58:26.795Z,1579820306.795 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-01-23T22:58:26.876Z,1579820306.876 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-01-23T22:58:26.980Z,1579820306.980 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-01-23T22:58:26.980Z,1579820306.980 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-01-23T22:58:27.650Z,1579820307.650 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-01-23T22:58:27.650Z,1579820307.650 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-01-23T22:58:28.333Z,1579820308.333 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-01-23T22:58:28.335Z,1579820308.335 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2020-01-23T22:58:28.336Z,1579820308.336 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2020-01-23T22:58:28.631Z,1579820308.631 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2020-01-23T22:58:28.733Z,1579820308.733 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2020-01-23T22:58:28.835Z,1579820308.835 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2020-01-23T22:58:29.067Z,1579820309.067 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-01-23T22:58:29.068Z,1579820309.068 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2020-01-23T22:58:29.153Z,1579820309.153 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2020-01-23T22:58:29.247Z,1579820309.247 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2020-01-23T22:58:29.346Z,1579820309.346 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2020-01-23T22:58:29.429Z,1579820309.429 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2020-01-23T22:58:29.540Z,1579820309.540 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/workSite.cfg
2020-01-23T22:58:29.634Z,1579820309.634 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2020-01-23T22:58:29.826Z,1579820309.826 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2020-01-23T22:58:29.961Z,1579820309.961 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/
2020-01-23T22:58:29.961Z,1579820309.961 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-01-23T22:58:29.967Z,1579820309.967 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-01-23T22:58:30.294Z,1579820310.294 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-01-23T22:58:30.300Z,1579820310.300 [AHRS_M2](INFO): created writer for : platform_orientation
2020-01-23T22:58:30.301Z,1579820310.301 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-01-23T22:58:30.306Z,1579820310.306 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-01-23T22:58:30.307Z,1579820310.307 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-01-23T22:58:30.312Z,1579820310.312 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-01-23T22:58:30.313Z,1579820310.313 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-01-23T22:58:30.318Z,1579820310.318 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-01-23T22:58:30.389Z,1579820310.389 [AHRS_M2] Loaded
2020-01-23T22:58:30.389Z,1579820310.389 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-01-23T22:58:30.468Z,1579820310.468 [DataOverHttps] Loaded
2020-01-23T22:58:30.469Z,1579820310.469 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-01-23T22:58:30.470Z,1579820310.470 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 407684E0
2020-01-23T22:58:30.470Z,1579820310.470 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1259
2020-01-23T22:58:30.494Z,1579820310.494 [DDM] Loaded
2020-01-23T22:58:30.494Z,1579820310.494 [ComponentRegistry](DEBUG): SyncComponent "DDM" handled in the control thread.
2020-01-23T22:58:30.507Z,1579820310.507 [Depth_Keller] Loaded
2020-01-23T22:58:30.508Z,1579820310.508 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-01-23T22:58:30.512Z,1579820310.512 [DropWeight] Loaded
2020-01-23T22:58:30.513Z,1579820310.513 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2020-01-23T22:58:30.574Z,1579820310.574 [DUSBL_Hydroid] Loaded
2020-01-23T22:58:30.574Z,1579820310.574 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2020-01-23T22:58:30.621Z,1579820310.621 [Micromodem] Loaded
2020-01-23T22:58:30.621Z,1579820310.621 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2020-01-23T22:58:30.719Z,1579820310.718 [NAL9602] Loaded
2020-01-23T22:58:30.719Z,1579820310.719 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-01-23T22:58:30.758Z,1579820310.758 [Onboard] Loaded
2020-01-23T22:58:30.759Z,1579820310.759 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2020-01-23T22:58:30.764Z,1579820310.764 [PowerOnly] Loaded
2020-01-23T22:58:30.765Z,1579820310.765 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2020-01-23T22:58:30.771Z,1579820310.771 [Radio_Surface] Loaded
2020-01-23T22:58:30.771Z,1579820310.771 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-01-23T22:58:30.772Z,1579820310.772 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407984E0
2020-01-23T22:58:30.773Z,1579820310.773 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1260
2020-01-23T22:58:30.816Z,1579820310.816 [RDI_Pathfinder] Loaded
2020-01-23T22:58:30.817Z,1579820310.817 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2020-01-23T22:58:32.290Z,1579820312.290 [BPC1] Loaded
2020-01-23T22:58:32.290Z,1579820312.290 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-01-23T22:58:32.290Z,1579820312.290 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-01-23T22:58:32.291Z,1579820312.291 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-01-23T22:58:32.304Z,1579820312.304 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-01-23T22:58:32.304Z,1579820312.304 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-01-23T22:58:32.420Z,1579820312.420 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-01-23T22:58:32.420Z,1579820312.420 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-01-23T22:58:32.441Z,1579820312.441 [NavChart] Loaded
2020-01-23T22:58:32.441Z,1579820312.441 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-01-23T22:58:32.446Z,1579820312.446 [UniversalFixResidualReporter] Loaded
2020-01-23T22:58:32.446Z,1579820312.446 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-01-23T22:58:32.447Z,1579820312.447 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-01-23T22:58:32.448Z,1579820312.448 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-01-23T22:58:32.556Z,1579820312.556 [BuoyancyServo] Loaded
2020-01-23T22:58:32.556Z,1579820312.556 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-01-23T22:58:32.571Z,1579820312.571 [ElevatorServo] Loaded
2020-01-23T22:58:32.572Z,1579820312.572 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-01-23T22:58:32.586Z,1579820312.586 [MassServo] Loaded
2020-01-23T22:58:32.587Z,1579820312.587 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-01-23T22:58:32.602Z,1579820312.602 [RudderServo] Loaded
2020-01-23T22:58:32.602Z,1579820312.602 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-01-23T22:58:32.617Z,1579820312.617 [ThrusterServo] Loaded
2020-01-23T22:58:32.617Z,1579820312.617 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-01-23T22:58:32.617Z,1579820312.617 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-01-23T22:58:32.618Z,1579820312.618 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-01-23T22:58:32.875Z,1579820312.875 [CTD_NeilBrown] Loaded
2020-01-23T22:58:32.876Z,1579820312.876 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2020-01-23T22:58:32.877Z,1579820312.877 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408EC4E0
2020-01-23T22:58:32.877Z,1579820312.877 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 1261
2020-01-23T22:58:32.892Z,1579820312.892 [PAR_Licor] Loaded
2020-01-23T22:58:32.892Z,1579820312.892 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2020-01-23T22:58:32.936Z,1579820312.936 [WetLabsSeaOWL_UV_A] Loaded
2020-01-23T22:58:32.936Z,1579820312.936 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2020-01-23T22:58:32.937Z,1579820312.937 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 4091C4E0
2020-01-23T22:58:32.937Z,1579820312.937 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 1262
2020-01-23T22:58:32.938Z,1579820312.938 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-01-23T22:58:32.938Z,1579820312.938 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-01-23T22:58:33.279Z,1579820313.279 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-01-23T22:58:33.279Z,1579820313.279 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-01-23T22:58:33.320Z,1579820313.320 [DepthRateCalculator] Loaded
2020-01-23T22:58:33.320Z,1579820313.320 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-01-23T22:58:33.326Z,1579820313.326 [PitchRateCalculator] Loaded
2020-01-23T22:58:33.326Z,1579820313.326 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-01-23T22:58:33.339Z,1579820313.339 [SpeedCalculator] Loaded
2020-01-23T22:58:33.339Z,1579820313.339 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-01-23T22:58:33.360Z,1579820313.360 [TempGradientCalculator] Loaded
2020-01-23T22:58:33.360Z,1579820313.360 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-01-23T22:58:33.366Z,1579820313.366 [YawRateCalculator] Loaded
2020-01-23T22:58:33.366Z,1579820313.366 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-01-23T22:58:33.408Z,1579820313.408 [ElevatorOffsetCalculator] Loaded
2020-01-23T22:58:33.408Z,1579820313.408 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-01-23T22:58:33.408Z,1579820313.408 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-01-23T22:58:33.409Z,1579820313.409 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-01-23T22:58:33.903Z,1579820313.903 [SBIT](DEBUG): Construct Startup Built In Test.
2020-01-23T22:58:33.925Z,1579820313.925 [SBIT] Loaded
2020-01-23T22:58:33.925Z,1579820313.925 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-01-23T22:58:33.926Z,1579820313.926 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-01-23T22:58:33.939Z,1579820313.939 [IBIT] Loaded
2020-01-23T22:58:33.939Z,1579820313.939 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-01-23T22:58:33.942Z,1579820313.942 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-01-23T22:58:34.087Z,1579820314.087 [CBIT] Loaded
2020-01-23T22:58:34.087Z,1579820314.087 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-01-23T22:58:34.087Z,1579820314.087 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-01-23T22:58:34.088Z,1579820314.088 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-01-23T22:58:34.170Z,1579820314.170 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-01-23T22:58:34.170Z,1579820314.170 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-01-23T22:58:34.270Z,1579820314.270 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-01-23T22:58:34.271Z,1579820314.271 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-01-23T22:58:34.320Z,1579820314.320 [VerticalControl](DEBUG): Construct VerticalControl.
2020-01-23T22:58:34.406Z,1579820314.406 [VerticalControl] Loaded
2020-01-23T22:58:34.406Z,1579820314.406 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-01-23T22:58:34.407Z,1579820314.407 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-01-23T22:58:34.476Z,1579820314.476 [HorizontalControl] Loaded
2020-01-23T22:58:34.477Z,1579820314.477 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-01-23T22:58:34.477Z,1579820314.477 [SpeedControl](DEBUG): Construct SpeedControl.
2020-01-23T22:58:34.479Z,1579820314.479 [SpeedControl] Loaded
2020-01-23T22:58:34.479Z,1579820314.479 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-01-23T22:58:34.480Z,1579820314.480 [LoopControl](DEBUG): Construct LoopControl.
2020-01-23T22:58:34.481Z,1579820314.481 [LoopControl] Loaded
2020-01-23T22:58:34.481Z,1579820314.481 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-01-23T22:58:34.481Z,1579820314.481 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-01-23T22:58:34.482Z,1579820314.482 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-01-23T22:58:34.514Z,1579820314.514 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-01-23T22:58:34.518Z,1579820314.518 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-01-23T22:58:34.519Z,1579820314.519 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-01-23T22:58:34.525Z,1579820314.525 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-01-23T22:58:34.526Z,1579820314.526 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AFC4E0
2020-01-23T22:58:34.527Z,1579820314.527 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1263
2020-01-23T22:58:34.531Z,1579820314.531 [Supervisor](INFO): Main Thread ID is 815
2020-01-23T22:58:34.531Z,1579820314.531 [Supervisor](DEBUG): Running supervisor.
2020-01-23T22:58:34.532Z,1579820314.532 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1264
2020-01-23T22:58:34.534Z,1579820314.534 [controlThread ThreadHandler](INFO): Handler Thread ID is 1265
2020-01-23T22:58:34.535Z,1579820314.535 [controlThread](DEBUG): Initializing ControlThread
2020-01-23T22:58:34.542Z,1579820314.542 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-01-23T22:58:34.542Z,1579820314.542 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-01-23T22:58:34.547Z,1579820314.547 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-01-23T22:58:34.548Z,1579820314.548 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-01-23T22:58:34.548Z,1579820314.548 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-01-23T22:58:34.548Z,1579820314.548 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-01-23T22:58:34.549Z,1579820314.549 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-01-23T22:58:34.549Z,1579820314.549 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-01-23T22:58:34.549Z,1579820314.549 [SBIT](INFO): Initialize SBIT Component.
2020-01-23T22:58:34.550Z,1579820314.550 [SBIT](IMPORTANT): git: 2019-12-03-78-gb92fbb9
2020-01-23T22:58:34.550Z,1579820314.550 [SBIT](INFO): git hash: b92fbb99b4c8d1793352820b784fbd968d0634e4
2020-01-23T22:58:34.550Z,1579820314.550 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-01-23T22:58:34.551Z,1579820314.551 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Nov 13 12:38:10 PST 2019
2020-01-23T22:58:34.553Z,1579820314.553 [SBIT](INFO): Beginning SBIT in 39.000000 seconds.
2020-01-23T22:58:34.553Z,1579820314.553 [IBIT](INFO): Initialize IBIT Component.
2020-01-23T22:58:34.554Z,1579820314.554 [CBIT](DEBUG): Initialize CBIT Component.
2020-01-23T22:58:34.555Z,1579820314.555 [logger ThreadHandler](INFO): Handler Thread ID is 1266
2020-01-23T22:58:34.567Z,1579820314.567 [CBIT](DEBUG): Initialized mux pins.
2020-01-23T22:58:34.567Z,1579820314.567 [CBIT](DEBUG): Initializing the watchdog timer.
2020-01-23T22:58:34.575Z,1579820314.575 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1267
2020-01-23T22:58:34.577Z,1579820314.577 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-01-23T22:58:34.587Z,1579820314.587 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1268
2020-01-23T22:58:34.591Z,1579820314.591 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-01-23T22:58:34.591Z,1579820314.591 [CBIT](DEBUG): Initializing heartbeat.
2020-01-23T22:58:34.599Z,1579820314.599 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 1269
2020-01-23T22:58:34.600Z,1579820314.600 [CTD_NeilBrown](INFO): Powering down
2020-01-23T22:58:34.627Z,1579820314.627 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 1270
2020-01-23T22:58:34.628Z,1579820314.628 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-01-23T22:58:34.651Z,1579820314.651 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1271
2020-01-23T22:58:34.654Z,1579820314.654 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-01-23T22:58:34.654Z,1579820314.654 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-01-23T22:58:34.655Z,1579820314.655 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-01-23T22:58:34.655Z,1579820314.655 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-01-23T22:58:34.655Z,1579820314.655 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-01-23T22:58:34.655Z,1579820314.655 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-01-23T22:58:34.655Z,1579820314.655 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-01-23T22:58:34.655Z,1579820314.655 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-01-23T22:58:34.656Z,1579820314.656 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-01-23T22:58:34.656Z,1579820314.656 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-01-23T22:58:34.656Z,1579820314.656 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-01-23T22:58:34.656Z,1579820314.656 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-01-23T22:58:34.656Z,1579820314.656 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-01-23T22:58:34.656Z,1579820314.656 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-01-23T22:58:34.657Z,1579820314.657 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-01-23T22:58:34.657Z,1579820314.657 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-01-23T22:58:34.663Z,1579820314.663 [CBIT](DEBUG): Deactivating GF circuits.
2020-01-23T22:58:34.663Z,1579820314.663 [CBIT](DEBUG): Deactivating emergency mode.
2020-01-23T22:58:34.699Z,1579820314.699 [CBIT](DEBUG): Backplane powered.
2020-01-23T22:58:34.699Z,1579820314.699 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-01-23T22:58:34.701Z,1579820314.701 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-01-23T22:58:34.702Z,1579820314.702 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-01-23T22:58:34.702Z,1579820314.702 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-01-23T22:58:34.703Z,1579820314.703 [MissionManager](INFO): Loading Mission: Missions/underIce/StartupUnder.xml
2020-01-23T22:58:34.709Z,1579820314.709 [MissionManager](ERROR): Top node has no child nodes: Mission
2020-01-23T22:58:34.709Z,1579820314.709 [MissionManager](ERROR): Could not load startup mission.
2020-01-23T22:58:34.709Z,1579820314.709 [MissionManager](INFO): Loading Mission: Missions/underIce/DefaultUnder.xml
2020-01-23T22:58:34.939Z,1579820314.939 [MissionManager](INFO): DefineArg DefaultUnder.uModemCommsInterval = 5.000000 min
2020-01-23T22:58:34.942Z,1579820314.942 [MissionManager](INFO): DefineArg DefaultUnder.TransponderCode = 2.000000 count
2020-01-23T22:58:34.954Z,1579820314.954 [MissionManager](INFO): DefineArg DefaultUnder.AcousticUpdatePeriod = 60.000000 s
2020-01-23T22:58:34.957Z,1579820314.957 [MissionManager](INFO): DefineArg DefaultUnder.DockingAcousticUpdatePeriod = 2.500000 s
2020-01-23T22:58:34.986Z,1579820314.986 [MissionManager](INFO): DefineArg DefaultUnder.NumberOfPings = 1.000000 count
2020-01-23T22:58:34.991Z,1579820314.991 [Radio_Surface](INFO): Powering up
2020-01-23T22:58:34.997Z,1579820314.997 [MissionManager](INFO): DefineOutput DefaultUnder.TrackingUpdatePeriod = 60.000000 n/a
2020-01-23T22:58:35.031Z,1579820315.031 [MissionManager](INFO): DefineOutput DefaultUnder.Surface = -1.000000 count
2020-01-23T22:58:35.035Z,1579820315.035 [MissionManager](INFO): DefineOutput DefaultUnder.Docked = 0.000000 count
2020-01-23T22:58:35.054Z,1579820315.054 [MissionManager](INFO): DefineOutput DefaultUnder.Home = 1.000000 count
2020-01-23T22:58:35.077Z,1579820315.077 [MissionManager](INFO): DefineOutput DefaultUnder.DefaultMode = 0.000000 n/a
2020-01-23T22:58:35.081Z,1579820315.081 [MissionManager](INFO): DefineOutput DefaultUnder.CaptureMode = -1.000000 count
2020-01-23T22:58:35.100Z,1579820315.100 [MissionManager](INFO): DefineOutput DefaultUnder.ElapsedSinceDefaultStarted = 0.000000 min
2020-01-23T22:58:35.124Z,1579820315.124 [DefaultUnder:Notify:A:A:A.Wait](DEBUG): Construct Wait.
2020-01-23T22:58:35.130Z,1579820315.130 [DefaultUnder:Notify:A:C.Execute](DEBUG): Construct Execute.
2020-01-23T22:58:35.364Z,1579820315.364 [MissionManager](INFO): Inserting Stack: Missions/Insert/LineCapture.xml
2020-01-23T22:58:35.875Z,1579820315.875 [MissionManager](INFO): DefineArg DefaultUnder:LineCapture.DockDepth = 6.000000 _
2020-01-23T22:58:35.891Z,1579820315.891 [MissionManager](INFO): DefineArg DefaultUnder:LineCapture.DockLat = 0.642341 _
2020-01-23T22:58:35.894Z,1579820315.894 [MissionManager](INFO): DefineArg DefaultUnder:LineCapture.DockLon = -2.126200 _
2020-01-23T22:58:35.898Z,1579820315.898 [MissionManager](INFO): DefineArg DefaultUnder:LineCapture.DetachRange = 8.000000 m
2020-01-23T22:58:35.924Z,1579820315.924 [MissionManager](INFO): DefineArg DefaultUnder:LineCapture.DetachTimeout = 30.000000 min
2020-01-23T22:58:35.928Z,1579820315.928 [MissionManager](INFO): DefineArg DefaultUnder:LineCapture.MidcourseDepth = 15.000000 m
2020-01-23T22:58:35.943Z,1579820315.943 [MissionManager](INFO): DefineArg DefaultUnder:LineCapture.MidcourseSpeed = 1.000000 m/s
2020-01-23T22:58:35.946Z,1579820315.946 [MissionManager](INFO): DefineArg DefaultUnder:LineCapture.MidcourseTimeout = 12.000000 h
2020-01-23T22:58:35.949Z,1579820315.949 [MissionManager](INFO): DefineArg DefaultUnder:LineCapture.TerminalRange = 650.000000 m
2020-01-23T22:58:35.980Z,1579820315.980 [MissionManager](INFO): DefineArg DefaultUnder:LineCapture.ArmRange = 40.000000 m
2020-01-23T22:58:35.984Z,1579820315.984 [MissionManager](INFO): DefineArg DefaultUnder:LineCapture.DockRange = 2.000000 m
2020-01-23T22:58:35.995Z,1579820315.995 [MissionManager](INFO): DefineArg DefaultUnder:LineCapture.DockTimeout = 60.000000 s
2020-01-23T22:58:35.998Z,1579820315.998 [MissionManager](INFO): DefineArg DefaultUnder:LineCapture.RolloutDistance = 250.000000 m
2020-01-23T22:58:36.001Z,1579820316.001 [MissionManager](INFO): DefineArg DefaultUnder:LineCapture.UpdateNav = 1 bool
2020-01-23T22:58:36.024Z,1579820316.024 [MissionManager](INFO): DefineArg DefaultUnder:LineCapture.AcousticTimeout = 10.000000 min
2020-01-23T22:58:36.029Z,1579820316.029 [MissionManager](INFO): DefineArg DefaultUnder:LineCapture.TerminalSpeed = 0.800000 m/s
2020-01-23T22:58:36.032Z,1579820316.032 [MissionManager](INFO): DefineArg DefaultUnder:LineCapture.SearchTimeout = 60.000000 min
2020-01-23T22:58:36.059Z,1579820316.059 [MissionManager](INFO): DefineArg DefaultUnder:LineCapture.CircleRadius = 300.000000 m
2020-01-23T22:58:36.063Z,1579820316.063 [MissionManager](INFO): DefineArg DefaultUnder:LineCapture.CircleMaxError = 100.000000 m
2020-01-23T22:58:36.066Z,1579820316.066 [MissionManager](INFO): DefineArg DefaultUnder:LineCapture.CircleTurnToPort = 0 bool
2020-01-23T22:58:36.077Z,1579820316.077 [MissionManager](INFO): DefineArg DefaultUnder:LineCapture.DepthDeadband = 0.010000 m
2020-01-23T22:58:36.081Z,1579820316.081 [MissionManager](INFO): DefineArg DefaultUnder:LineCapture.RudderDeadband = 0.001000 arcdeg
2020-01-23T22:58:36.112Z,1579820316.112 [MissionManager](INFO): DefineArg DefaultUnder:LineCapture.MinAltitude = 5.000000 m
2020-01-23T22:58:36.115Z,1579820316.115 [MissionManager](INFO): DefineArg DefaultUnder:LineCapture.MaxDepth = 205.000000 m
2020-01-23T22:58:36.135Z,1579820316.135 [MissionManager](INFO): DefineArg DefaultUnder:LineCapture.MinOffshore = 500.000000 m
2020-01-23T22:58:36.138Z,1579820316.138 [MissionManager](INFO): DefineOutput DefaultUnder:LineCapture.Depth = 15.000000 n/a
2020-01-23T22:58:36.142Z,1579820316.142 [MissionManager](INFO): DefineOutput DefaultUnder:LineCapture.Speed = 1.000000 n/a
2020-01-23T22:58:36.165Z,1579820316.165 [MissionManager](INFO): DefineOutput DefaultUnder:LineCapture.DoneTimeout = -1.000000 count
2020-01-23T22:58:36.168Z,1579820316.168 [MissionManager](INFO): DefineOutput DefaultUnder:LineCapture.Done = 0.000000 count
2020-01-23T22:58:36.188Z,1579820316.188 [MissionManager](INFO): DefineOutput DefaultUnder:LineCapture.DetachFromDock = 1.000000 count
2020-01-23T22:58:36.191Z,1579820316.191 [MissionManager](INFO): DefineOutput DefaultUnder:LineCapture.Midcourse = 2.000000 count
2020-01-23T22:58:36.195Z,1579820316.195 [MissionManager](INFO): DefineOutput DefaultUnder:LineCapture.Terminal = 3.000000 count
2020-01-23T22:58:36.214Z,1579820316.214 [MissionManager](INFO): DefineOutput DefaultUnder:LineCapture.GuidanceMode = 1.000000 n/a
2020-01-23T22:58:36.217Z,1579820316.217 [MissionManager](INFO): DefineArg DefaultUnder:LineCapture.ElapsedRange = 0.000000 min
2020-01-23T22:58:36.244Z,1579820316.244 [MissionManager](INFO): DefineArg DefaultUnder:LineCapture.Range = nan m
2020-01-23T22:58:36.246Z,1579820316.246 [MissionManager](INFO): Inserting Stack: Missions/Insert/StandardEnvelopes.xml
2020-01-23T22:58:36.294Z,1579820316.294 [MissionManager](INFO): DefineArg DefaultUnder:LineCapture:StandardEnvelopes.MinAltitude = 5.000000 m
2020-01-23T22:58:36.298Z,1579820316.298 [MissionManager](INFO): DefineArg DefaultUnder:LineCapture:StandardEnvelopes.MaxDepth = 200.000000 m
2020-01-23T22:58:36.313Z,1579820316.313 [MissionManager](INFO): DefineArg DefaultUnder:LineCapture:StandardEnvelopes.MinOffshore = 2000.000000 m
2020-01-23T22:58:36.314Z,1579820316.314 [DefaultUnder:LineCapture:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2020-01-23T22:58:36.329Z,1579820316.329 [DefaultUnder:LineCapture:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2020-01-23T22:58:36.361Z,1579820316.361 [DefaultUnder:LineCapture:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2020-01-23T22:58:36.413Z,1579820316.413 [DefaultUnder:LineCapture:H.Mass](DEBUG): Construct.
2020-01-23T22:58:36.445Z,1579820316.445 [DefaultUnder:LineCapture:DiveCmd:Guided:dive:B.Buoyancy](DEBUG): Construct Buoyancy.
2020-01-23T22:58:36.448Z,1579820316.448 [DefaultUnder:LineCapture:DiveCmd:Guided:dive:C.SetSpeed](DEBUG): Construct.
2020-01-23T22:58:36.467Z,1579820316.467 [DefaultUnder:LineCapture:DiveCmd:Guided:dive:D.Pitch](DEBUG): Construct.
2020-01-23T22:58:36.474Z,1579820316.474 [DefaultUnder:LineCapture:DiveCmd:DriftAwayFromDock:dive:A.SetSpeed](DEBUG): Construct.
2020-01-23T22:58:36.486Z,1579820316.486 [DefaultUnder:LineCapture:DiveCmd:DriftAwayFromDock:dive:B.Pitch](DEBUG): Construct.
2020-01-23T22:58:36.530Z,1579820316.530 [DefaultUnder:LineCapture:HomingSequence:DetachFromDock:guide:A.Undock](DEBUG): Construct.
2020-01-23T22:58:36.581Z,1579820316.581 [DefaultUnder:LineCapture:HomingSequence:MidcourseGuidance:guide:E.Waypoint](DEBUG): Construct Waypoint.
2020-01-23T22:58:36.616Z,1579820316.616 [DefaultUnder:LineCapture:HomingSequence:MidcourseGuidance:guide:G.Circle](DEBUG): Construct.
2020-01-23T22:58:36.717Z,1579820316.717 [DefaultUnder:LineCapture:HomingSequence:TerminalGuidance:guide:UpdateNav:B.SetNav](DEBUG): Construct SetNav.
2020-01-23T22:58:36.720Z,1579820316.720 [DefaultUnder:LineCapture:HomingSequence:TerminalGuidance:guide:H.LineCapture](DEBUG): Construct.
2020-01-23T22:58:36.759Z,1579820316.759 [DefaultUnder:LineCapture:K.Wait](DEBUG): Construct Wait.
2020-01-23T22:58:36.762Z,1579820316.762 [DefaultUnder:E.Wait](DEBUG): Construct Wait.
2020-01-23T22:58:36.788Z,1579820316.788 [DefaultUnder:Default:Docked:run:B.Docked](DEBUG): Construct.
2020-01-23T22:58:36.866Z,1579820316.866 [DefaultUnder:Default:Surface:run:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-01-23T22:58:36.908Z,1579820316.908 [DefaultUnder:Default:Surface:run:SurfaceComms:Read_Iridium_uModem:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-01-23T22:58:36.911Z,1579820316.911 [DefaultUnder:Default:Surface:run:SurfaceComms:C.Wait](DEBUG): Construct Wait.
2020-01-23T22:58:36.975Z,1579820316.975 [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="DefaultUnder">


    <DefineArg Name="uModemCommsInterval"><Description>
        How often to trigger uModem communications
    </Description><Units:minute/><Value>5</Value></DefineArg>

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

    <DefineArg Name="AcousticUpdatePeriod"><Description>
        How long to wait between acoustic queries while the mission
        is in an idle state (on the dock or surface).
    </Description><Units:second/><Value>60</Value></DefineArg>

    <DefineArg Name="DockingAcousticUpdatePeriod"><Description>
        How long to wait between acoustic queries while the mission
        is actively docking (line capture)
    </Description><Units:second/><Value>2.5</Value></DefineArg>

    <DefineArg Name="NumberOfPings"><Description>
        Number of pings requested each time.
    </Description><Units:count/><Value>1</Value></DefineArg>

    <!-- Mission Variables (don't change!) -->

    <DefineOutput Name="TrackingUpdatePeriod"><Description>
        How long to wait between acoustic queries.
    </Description><Arg Name="AcousticUpdatePeriod"/></DefineOutput>

    <DefineOutput Name="Surface"><Description>
        Mission variable (don't change).
        The mission will run in this mode to go to the surface after homing attempts have failed.
    </Description><Units:count/><Value>-1</Value></DefineOutput>

    <DefineOutput Name="Docked"><Description>
        Mission variable (don't change).
        The mission will run in this mode when the vehicle is docked.
    </Description><Units:count/><Value>0</Value></DefineOutput>

    <DefineOutput Name="Home"><Description>
        Mission variable (don't change).
        The mission will run in this mode when homing to the dock.
    </Description><Units:count/><Value>1</Value></DefineOutput>

    <DefineOutput Name="DefaultMode"><Description>
        Mission variable (don't change).
        The mission sets this variable to switch between default modes.
    </Description><Arg Name="Docked"/></DefineOutput>

    <DefineOutput Name="CaptureMode"><Description>
        Mission variable (don't change).
        The mission sets this variable to reflect the termination status of the LineCapture insert.
    </Description><Units:count/><Value>-1</Value></DefineOutput>

    <DefineOutput Name="ElapsedSinceDefaultStarted"><Units:minute/><Value>0</Value></DefineOutput>

    <!-- TODO: Safety nets and surfacing behaviors? -->

    <!-- Communication directives -->

    <Aggregate Id="uModemComms">
        <While>
            <Arg Name="DefaultMode"/><Eq><Arg Name="Docked"/></Eq>
        </While>

        <Aggregate Id="CheckIn">

            <When>
                <Elapsed><Universal:platform_communications/></Elapsed>
                <Gt><Arg Name="uModemCommsInterval"/></Gt>
            </When>

            <ReadDatum>
                <Timeout Duration="P10M"/>
                <Universal:platform_communications/>
            </ReadDatum>

        </Aggregate>
    </Aggregate>

    <!-- Notify directives. -->

    <Aggregate Id="Notify">
        <Parallel/>

        <Aggregate>
            <Sequence/>

            <Aggregate>
                <Sequence Repeat="288"/>

                <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.</Syslog>

            <Guidance:Execute>
                <Sequence/>
                <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
            </Guidance:Execute>
        </Aggregate>
    </Aggregate>

   <!-- Add track acoustic contact directives. -->

    <Estimation:TrackAcousticContact>
        <Parallel/>
        <Setting><Estimation:TrackAcousticContact.contactLabelSetting/><Arg Name="TransponderCode"/></Setting>
        <Setting><Estimation:TrackAcousticContact.numberOfSamplesSetting/><Arg Name="NumberOfPings"/></Setting>
        <Setting><Estimation:TrackAcousticContact.updatePeriodSetting/><Arg Name="TrackingUpdatePeriod"/></Setting>
    </Estimation:TrackAcousticContact>

    <!-- Add LineCapture directives. -->

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

    <!-- Default mission sequence. -->

    <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>

    <Aggregate Id="Default">

        <Sequence/>

        <Aggregate Id="Docked">

            <While>
                <Arg Name="DefaultMode"/><Eq><Arg Name="Docked"/></Eq>
            </While>

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

                <Assign><Sequence/><Arg Name="TrackingUpdatePeriod"/><Arg Name="AcousticUpdatePeriod"/></Assign>

                <Guidance:Docked>
                    <Sequence/>
                </Guidance:Docked>

                <Assign><Sequence/><Arg Name="DefaultMode"/><Arg Name="Home"/></Assign>

            </Aggregate>
        </Aggregate>


        <Aggregate Id="Home">

            <While>
                <Arg Name="DefaultMode"/><Eq><Arg Name="Home"/></Eq>
            </While>

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

                <Assign><Sequence/><Arg Name="TrackingUpdatePeriod"/><Arg Name="DockingAcousticUpdatePeriod"/></Assign>

                <Assign><Sequence/><Arg Name="CaptureMode"/><Arg Name="Surface"/></Assign>

                <Call RefId="LineCapture"/>

                <Assign><Sequence/><Arg Name="CaptureMode"/><Arg Name="LineCapture:GuidanceMode"/></Assign>

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

                    <Break>
                        <Not><Arg Name="CaptureMode"/><Eq><Arg Name="Docked"/></Eq></Not>
                    </Break>

                    <Assign><Sequence/><Arg Name="DefaultMode"/><Arg Name="Docked"/></Assign>
                </Aggregate>

                <Assign><Sequence/><Arg Name="DefaultMode"/><Arg Name="Surface"/></Assign>

            </Aggregate>
        </Aggregate>


        <Aggregate Id="Surface">

            <While>
                <Arg Name="DefaultMode"/><Eq><Arg Name="Surface"/></Eq>
            </While>

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

                <Assign><Sequence/><Arg Name="TrackingUpdatePeriod"/><Arg Name="AcousticUpdatePeriod"/></Assign>

                <Guidance:GoToSurface>
                    <Progression/>
                </Guidance:GoToSurface>

                <Aggregate Id="SurfaceComms">

                    <Sequence Repeat="288"/>

                    <ReadDatum Id="Read_GPS">
                        <Timeout Duration="P5M"/>
                        <Universal:time_fix/>
                    </ReadDatum>

                    <ReadDatum Id="Read_Iridium_uModem">
                        <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>

                </Aggregate>
            </Aggregate>

        </Aggregate>

    </Aggregate>

</Mission>


2020-01-23T22:58:36.980Z,1579820316.980 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,DDM,Depth_Keller,DropWeight,DUSBL_Hydroid,Micromodem,NAL9602,Onboard,PowerOnly,RDI_Pathfinder,BPC1,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2020-01-23T22:58:36.991Z,1579820316.991 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-01-23T22:58:37.031Z,1579820317.031 [DDM](INFO): Powering up
2020-01-23T22:58:37.031Z,1579820317.031 [DDM](DEBUG): Initializing DDM.
2020-01-23T22:58:37.056Z,1579820317.056 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar
2020-01-23T22:58:37.057Z,1579820317.057 [DUSBL_Hydroid](INFO): Powering up
2020-01-23T22:58:37.057Z,1579820317.057 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2020-01-23T22:58:37.229Z,1579820317.229 [DepthRateCalculator](ERROR): Depth measurement is not active
2020-01-23T22:58:37.252Z,1579820317.252 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-01-23T22:58:37.259Z,1579820317.259 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-01-23T22:58:37.260Z,1579820317.260 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-01-23T22:58:37.267Z,1579820317.267 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-01-23T22:58:37.268Z,1579820317.268 [MassServo](DEBUG): Initializing EZServoServo.
2020-01-23T22:58:37.275Z,1579820317.275 [MassServo](DEBUG): Initializing MassServo.
2020-01-23T22:58:37.276Z,1579820317.276 [RudderServo](DEBUG): Initializing EZServoServo.
2020-01-23T22:58:37.283Z,1579820317.283 [RudderServo](DEBUG): Initializing RudderServo.
2020-01-23T22:58:37.284Z,1579820317.284 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-01-23T22:58:37.291Z,1579820317.291 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-01-23T22:58:37.514Z,1579820317.514 [Micromodem](INFO): Powering up
2020-01-23T22:58:37.519Z,1579820317.519 [Micromodem](DEBUG): Initializing Micromodem.
2020-01-23T22:58:38.167Z,1579820318.167 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2020-01-23T22:58:38.167Z,1579820318.167 [RudderServo](FAULT): Rudder failed to initialize
2020-01-23T22:58:38.167Z,1579820318.167 [RudderServo] Communications Fault, FailCount= 1
2020-01-23T22:58:38.167Z,1579820318.167 [RudderServo](ERROR): Communications Fault
2020-01-23T22:58:38.278Z,1579820318.278 [CBIT](ERROR): Communications Fault in component: RudderServo
2020-01-23T22:58:38.452Z,1579820318.452 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-01-23T22:58:38.452Z,1579820318.452 [RudderServo](INFO): Powering down
2020-01-23T22:58:39.157Z,1579820319.157 [RudderServo](DEBUG): Initializing EZServoServo.
2020-01-23T22:58:39.276Z,1579820319.276 [RudderServo](DEBUG): Initializing RudderServo.
2020-01-23T22:58:39.280Z,1579820319.280 [CBIT](INFO): Clearing failed state for component RudderServo
2020-01-23T22:58:39.280Z,1579820319.280 [RudderServo] No Fault, FailCount= 1
2020-01-23T22:58:42.371Z,1579820322.371 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2020-01-23T22:58:42.770Z,1579820322.770 [Micromodem](INFO): Nmea in: $CATMG,2020-01-23T22:58:42.748514Z,RTC,RTC*50

2020-01-23T22:58:42.770Z,1579820322.770 [Micromodem](ERROR): CFG response from modem unexpected: $CATMG,2020-01-23T22:58:42.748514Z,RTC,RTC*50

2020-01-23T22:58:43.174Z,1579820323.174 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31

2020-01-23T22:58:43.174Z,1579820323.174 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2020-01-23T22:58:43.582Z,1579820323.582 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33

2020-01-23T22:58:43.582Z,1579820323.582 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64

2020-01-23T22:58:43.985Z,1579820323.985 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66

2020-01-23T22:58:43.986Z,1579820323.986 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F

2020-01-23T22:58:44.389Z,1579820324.389 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D

2020-01-23T22:58:44.390Z,1579820324.390 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E

2020-01-23T22:58:44.793Z,1579820324.793 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C

2020-01-23T22:58:44.793Z,1579820324.793 [Micromodem](INFO): Nmea out: $CCCFG,FC0,10000*46

2020-01-23T22:58:45.193Z,1579820325.193 [Micromodem](INFO): Nmea in: $CACFG,FC0,10000*44

2020-01-23T22:58:45.193Z,1579820325.193 [Micromodem](INFO): Nmea out: $CCCFG,BW0,4000*63

2020-01-23T22:58:45.619Z,1579820325.619 [Micromodem](INFO): Nmea in: $CACFG,BW0,4000*61

2020-01-23T22:58:45.620Z,1579820325.620 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A

2020-01-23T22:58:45.981Z,1579820325.981 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38

2020-01-23T22:58:45.982Z,1579820325.982 [Micromodem](INFO): Nmea out: $CCCLK,2020,01,23,22,58,46*4B

2020-01-23T22:58:46.389Z,1579820326.389 [Micromodem](INFO): Nmea in: $CACLK,2020,1,23,22,58,46*79

2020-01-23T22:58:46.786Z,1579820326.786 [Micromodem](INFO): Nmea in: $CATMS,0,2020-01-23T22:58:47Z*78

2020-01-23T22:58:46.787Z,1579820326.787 [Micromodem](INFO): Nmea in: $CATMG,2020-01-23T22:58:47.028709Z,USER_CMD,RTC*1B

2020-01-23T22:58:47.596Z,1579820327.596 [DDM](INFO): Dynamic Docking Module:!C REMUS Capture Rev 1.1
2020-01-23T22:58:50.438Z,1579820330.438 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2020-01-23T22:58:53.665Z,1579820333.665 [RDI_Pathfinder](ERROR): Failed to parse:.00,   0.00,   0.00,   0.00,   0.00
2020-01-23T22:58:55.261Z,1579820335.261 [DUSBL_Hydroid](INFO): DUSBL Version:O
2020-01-23T22:59:00.918Z,1579820340.918 [NAL9602](INFO): Powering up NAL9602
2020-01-23T22:59:11.830Z,1579820351.830 [NAL9602](INFO): NAL9602 initialized
2020-01-23T22:59:14.369Z,1579820354.369 [SBIT](IMPORTANT): Beginning Startup BIT
2020-01-23T22:59:14.373Z,1579820354.373 [CBIT](IMPORTANT): Beginning ground fault scan
2020-01-23T22:59:25.500Z,1579820365.500 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.007729
CHAN A1 (24V): -0.028532
CHAN A2 (12V): -0.007062
CHAN A3 (5V): -0.001597
CHAN B0 (3.3V): 0.000767
CHAN B1 (3.15aV): 0.000304
CHAN B2 (3.15bV): 0.000195
CHAN B3 (GND): 0.002548
OPEN: 0.005394
Full Scale Calc: 4.765 mA, -1.589 mA
2020-01-23T22:59:40.781Z,1579820380.781 [CommandLine](IMPORTANT): got command strobe off
2020-01-23T22:59:40.781Z,1579820380.781 [CommandLine](IMPORTANT): Deactivating strobe
2020-01-23T23:00:07.697Z,1579820407.697 [SBIT](IMPORTANT): SBIT PASSED
2020-01-23T23:00:07.697Z,1579820407.697 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-01-23T23:00:07.698Z,1579820407.698 [SBIT](IMPORTANT): DUSBL_Hydroid.detectionThreshold=30 count;

2020-01-23T23:00:07.698Z,1579820407.698 [SBIT](IMPORTANT): Micromodem.surfaceThreshold=0 meter;

2020-01-23T23:00:07.698Z,1579820407.698 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=105 cubic_centimeter;

2020-01-23T23:00:07.698Z,1579820407.698 [SBIT](IMPORTANT): VerticalControl.massDefault=-5 millimeter;

2020-01-23T23:00:08.065Z,1579820408.065 [MissionManager](IMPORTANT): Started mission DefaultUnder
2020-01-23T23:00:08.065Z,1579820408.065 [DefaultUnder] Running Loop=1
2020-01-23T23:00:08.065Z,1579820408.065 [DefaultUnder](DEBUG): Aggregate::initialize DefaultUnder
2020-01-23T23:00:08.065Z,1579820408.065 [DefaultUnder:uModemComms] Running Loop=1
2020-01-23T23:00:08.066Z,1579820408.066 [DefaultUnder:uModemComms](DEBUG): Aggregate::initialize DefaultUnder:uModemComms
2020-01-23T23:00:08.066Z,1579820408.066 [DefaultUnder:Notify] Running Loop=1
2020-01-23T23:00:08.066Z,1579820408.066 [DefaultUnder:Notify](DEBUG): Aggregate::initialize DefaultUnder:Notify
2020-01-23T23:00:08.066Z,1579820408.066 [DefaultUnder:Notify:A] Running Loop=1
2020-01-23T23:00:08.066Z,1579820408.066 [DefaultUnder:Notify:A](DEBUG): Aggregate::initialize DefaultUnder:Notify:A
2020-01-23T23:00:08.066Z,1579820408.066 [DefaultUnder:Notify:A:A] Running Loop=1
2020-01-23T23:00:08.066Z,1579820408.066 [DefaultUnder:Notify:A:A](DEBUG): Aggregate::initialize DefaultUnder:Notify:A:A
2020-01-23T23:00:08.066Z,1579820408.066 [DefaultUnder:Notify:A:A:A.Wait] Running Loop=1
2020-01-23T23:00:08.066Z,1579820408.066 [DefaultUnder:Notify:A:A:A.Wait](DEBUG): Initialize Wait Component.
2020-01-23T23:00:08.067Z,1579820408.067 [DefaultUnder:C.] Running Loop=1
2020-01-23T23:00:08.083Z,1579820408.083 [DefaultUnder:C.](INFO): Initializing TrackAcousticContact.
2020-01-23T23:00:08.083Z,1579820408.083 [DefaultUnder:E.Wait] Running Loop=1
2020-01-23T23:00:08.083Z,1579820408.083 [DefaultUnder:E.Wait](DEBUG): Initialize Wait Component.
2020-01-23T23:00:08.084Z,1579820408.084 [DefaultUnder:C.] Running Loop=1
2020-01-23T23:00:08.085Z,1579820408.085 [DefaultUnder:Notify] Running Loop=1
2020-01-23T23:00:08.085Z,1579820408.085 [DefaultUnder:uModemComms] Running Loop=1
2020-01-23T23:00:08.085Z,1579820408.085 [DefaultUnder:uModemComms:CheckIn] Running Loop=1
2020-01-23T23:00:08.085Z,1579820408.085 [DefaultUnder:uModemComms:CheckIn](DEBUG): Aggregate::initialize DefaultUnder:uModemComms:CheckIn
2020-01-23T23:00:08.085Z,1579820408.085 [DefaultUnder:uModemComms:CheckIn:A] Running Loop=1
2020-01-23T23:00:08.086Z,1579820408.086 [DefaultUnder:uModemComms:CheckIn:A](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-01-23T23:00:08.456Z,1579820408.456 [Micromodem](INFO): Outgoing frame #1, 47 bytes: C0202A5E160000005D00000400140000000000000000151D80030C5669BF92DB1A8E2AA33DA52FC12367A631F7C000
2020-01-23T23:00:08.457Z,1579820408.457 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,1*6B

2020-01-23T23:00:08.849Z,1579820408.849 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,1*69

2020-01-23T23:00:09.246Z,1579820409.246 [Micromodem](INFO): Nmea in: $CADRQ,230008,1,12,0,64,1*7D

2020-01-23T23:00:09.246Z,1579820409.246 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,C0202A5E160000005D00000400140000000000000000151D80030C5669BF92DB1A8E2AA33DA52FC12367A631F7C000*3C

2020-01-23T23:00:09.658Z,1579820409.658 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,47*4A

2020-01-23T23:00:10.057Z,1579820410.057 [Micromodem](INFO): Nmea in: $CATXP,47*71

2020-01-23T23:00:11.293Z,1579820411.293 [Micromodem](INFO): Nmea in: $CATXF,47*67

2020-01-23T23:00:11.689Z,1579820411.689 [Micromodem](INFO): Nmea in: $CAXST,6,20200123,230009.689350,3,0,200,4000,10000,1,1,12,1,1,1,3,47*4A

2020-01-23T23:00:13.298Z,1579820413.298 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-23T23:00:13.298Z,1579820413.298 [Micromodem](INFO): Sent 39 bytes from file Logs/20200123T224000/Courier0022.lzma.parts
2020-01-23T23:00:13.298Z,1579820413.298 [Micromodem](INFO): Packets left to send: 0
2020-01-23T23:00:13.697Z,1579820413.697 [Micromodem](INFO): Nmea in: $CACST,6,0,20200123230012.409726,06,1843,32,0106,0150,22,02,02,00,03,1,012,001,0,4,1,0,150,35.5,10.00,23,-7.00,-01,-0.01,13,10000,4000*47

2020-01-23T23:00:13.707Z,1579820413.707 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 10252A5E000000005D00000400AB0000000000000000151D80030C5669BF3148E2FA22188B6A3173E51B1C3DE1295C413B9EA27D0D0958A55575A4074CBAA7A5
2020-01-23T23:00:13.708Z,1579820413.708 [Micromodem](INFO): Outgoing frame #2, 64 bytes: 3F6AE7AF0D07E622820F45259E052A3980439491B5FE44BA5725AF9F520D380EF650D1224C2DD44756ABC8376EA4F1C2928328840EB8F148A67DBA8E3F744407
2020-01-23T23:00:13.708Z,1579820413.708 [Micromodem](INFO): Outgoing frame #3, 31 bytes: 8E4C4CBB77E7035800BF8B4B5F220FBBB0D546E72D7B80CB11106042A08100
2020-01-23T23:00:13.709Z,1579820413.709 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69

2020-01-23T23:00:14.909Z,1579820414.909 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B

2020-01-23T23:00:15.318Z,1579820415.318 [Micromodem](INFO): Nmea in: $CADRQ,230014,1,12,0,64,1*70

2020-01-23T23:00:15.318Z,1579820415.318 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,10252A5E000000005D00000400AB0000000000000000151D80030C5669BF3148E2FA22188B6A3173E51B1C3DE1295C413B9EA27D0D0958A55575A4074CBAA7A5*40

2020-01-23T23:00:15.718Z,1579820415.718 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-23T23:00:16.121Z,1579820416.121 [Micromodem](INFO): Nmea in: $CADRQ,230015,1,12,0,64,2*72

2020-01-23T23:00:16.122Z,1579820416.122 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,3F6AE7AF0D07E622820F45259E052A3980439491B5FE44BA5725AF9F520D380EF650D1224C2DD44756ABC8376EA4F1C2928328840EB8F148A67DBA8E3F744407*3F

2020-01-23T23:00:16.521Z,1579820416.521 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-23T23:00:16.933Z,1579820416.933 [Micromodem](INFO): Nmea in: $CADRQ,230016,1,12,0,64,3*70

2020-01-23T23:00:16.934Z,1579820416.934 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,8E4C4CBB77E7035800BF8B4B5F220FBBB0D546E72D7B80CB11106042A08100*47

2020-01-23T23:00:17.333Z,1579820417.333 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,31*4B

2020-01-23T23:00:17.738Z,1579820417.738 [Micromodem](INFO): Nmea in: $CATXP,159*4F

2020-01-23T23:00:21.366Z,1579820421.366 [Micromodem](INFO): Nmea in: $CATXF,159*59

2020-01-23T23:00:21.429Z,1579820421.429 [DefaultUnder:E.Wait](INFO): Done Waiting.
2020-01-23T23:00:21.429Z,1579820421.429 [DefaultUnder:E.Wait] Stopped
2020-01-23T23:00:21.429Z,1579820421.429 [DefaultUnder:E.Wait](DEBUG): Uninitialize Wait Component.
2020-01-23T23:00:21.429Z,1579820421.429 [DefaultUnder:Default] Running Loop=1
2020-01-23T23:00:21.429Z,1579820421.429 [DefaultUnder:Default](DEBUG): Aggregate::initialize DefaultUnder:Default
2020-01-23T23:00:21.429Z,1579820421.429 [DefaultUnder:Default:Docked] Running Loop=1
2020-01-23T23:00:21.429Z,1579820421.429 [DefaultUnder:Default:Docked](DEBUG): Aggregate::initialize DefaultUnder:Default:Docked
2020-01-23T23:00:21.430Z,1579820421.430 [DefaultUnder:Default:Docked:run] Running Loop=1
2020-01-23T23:00:21.430Z,1579820421.430 [DefaultUnder:Default:Docked:run](DEBUG): Aggregate::initialize DefaultUnder:Default:Docked:run
2020-01-23T23:00:21.430Z,1579820421.430 [DefaultUnder:Default:Docked:run:A] Running Loop=1
2020-01-23T23:00:21.430Z,1579820421.430 [DefaultUnder:Default:Home] Running Loop=1
2020-01-23T23:00:21.430Z,1579820421.430 [DefaultUnder:Default:Home](DEBUG): Aggregate::initialize DefaultUnder:Default:Home
2020-01-23T23:00:21.430Z,1579820421.430 [DefaultUnder:Default:Home:run] Running Loop=1
2020-01-23T23:00:21.430Z,1579820421.430 [DefaultUnder:Default:Home:run](DEBUG): Aggregate::initialize DefaultUnder:Default:Home:run
2020-01-23T23:00:21.430Z,1579820421.430 [DefaultUnder:Default:Home:run:A] Running Loop=1
2020-01-23T23:00:21.430Z,1579820421.430 [DefaultUnder:Default:Surface] Running Loop=1
2020-01-23T23:00:21.430Z,1579820421.430 [DefaultUnder:Default:Surface](DEBUG): Aggregate::initialize DefaultUnder:Default:Surface
2020-01-23T23:00:21.430Z,1579820421.430 [DefaultUnder:Default:Surface:run] Running Loop=1
2020-01-23T23:00:21.431Z,1579820421.431 [DefaultUnder:Default:Surface:run](DEBUG): Aggregate::initialize DefaultUnder:Default:Surface:run
2020-01-23T23:00:21.431Z,1579820421.431 [DefaultUnder:Default:Surface:run:B.GoToSurface] Running Loop=1
2020-01-23T23:00:21.431Z,1579820421.431 [DefaultUnder:Default:Surface:run:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-01-23T23:00:21.431Z,1579820421.431 [DefaultUnder:Default:Surface:run:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-01-23T23:00:21.432Z,1579820421.432 [DefaultUnder:Default:Surface:run:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-01-23T23:00:21.432Z,1579820421.432 [DefaultUnder:Default:Surface:run:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-01-23T23:00:21.433Z,1579820421.433 [DefaultUnder:Default:Surface:run:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-01-23T23:00:21.433Z,1579820421.433 [DefaultUnder:Default:Surface:run:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-01-23T23:00:21.433Z,1579820421.433 [DefaultUnder:Default:Surface:run:A] Running Loop=1
2020-01-23T23:00:21.774Z,1579820421.774 [Micromodem](INFO): Nmea in: $CAXST,6,20200123,230017.386378,3,0,200,4000,10000,1,1,12,1,3,3,3,159*7B

2020-01-23T23:00:21.820Z,1579820421.820 [DefaultUnder:Default:Surface] Stopped
2020-01-23T23:00:21.820Z,1579820421.820 [DefaultUnder:Default:Surface](DEBUG): Aggregate::uninitialize DefaultUnder:Default:Surface
2020-01-23T23:00:21.820Z,1579820421.820 [DefaultUnder:Default:Surface:run] Stopped
2020-01-23T23:00:21.820Z,1579820421.820 [DefaultUnder:Default:Surface:run](DEBUG): Aggregate::uninitialize DefaultUnder:Default:Surface:run
2020-01-23T23:00:21.820Z,1579820421.820 [DefaultUnder:Default:Surface:run:A] Stopped
2020-01-23T23:00:21.820Z,1579820421.820 [DefaultUnder:Default:Surface:run:B.GoToSurface] Stopped
2020-01-23T23:00:21.820Z,1579820421.820 [DefaultUnder:Default:Surface:run:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-01-23T23:00:21.820Z,1579820421.820 [DefaultUnder:Default:Home] Stopped
2020-01-23T23:00:21.820Z,1579820421.820 [DefaultUnder:Default:Home](DEBUG): Aggregate::uninitialize DefaultUnder:Default:Home
2020-01-23T23:00:21.820Z,1579820421.820 [DefaultUnder:Default:Home:run] Stopped
2020-01-23T23:00:21.821Z,1579820421.821 [DefaultUnder:Default:Home:run](DEBUG): Aggregate::uninitialize DefaultUnder:Default:Home:run
2020-01-23T23:00:21.821Z,1579820421.821 [DefaultUnder:Default:Home:run:A] Stopped
2020-01-23T23:00:21.821Z,1579820421.821 [DefaultUnder:Default:Docked] Running Loop=1
2020-01-23T23:00:21.821Z,1579820421.821 [DefaultUnder:Default:Docked:run:A] Stopped
2020-01-23T23:00:21.821Z,1579820421.821 [DefaultUnder:Default:Docked:run:B.Docked] Running Loop=1
2020-01-23T23:00:21.821Z,1579820421.821 [DefaultUnder:Default:Docked:run:B.Docked](DEBUG): Initialize.
2020-01-23T23:00:21.822Z,1579820421.822 [DefaultUnder:Default:Docked:run:B.Docked](INFO): Depth unspecified. Maintaining workSite beacon depth of 6.000000 m.
2020-01-23T23:00:24.641Z,1579820424.641 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-23T23:00:25.042Z,1579820425.042 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-23T23:00:25.442Z,1579820425.442 [Micromodem](INFO): Nmea in: $CAACK,12,1,3,1*7B

2020-01-23T23:00:25.443Z,1579820425.443 [Micromodem](INFO): Sent 151 bytes from file Logs/20200123T225824/Courier0000.lzma.parts
2020-01-23T23:00:25.443Z,1579820425.443 [Micromodem](INFO): Packets left to send: 0
2020-01-23T23:00:25.853Z,1579820425.853 [Micromodem](INFO): Nmea in: $CACST,6,0,20200123230023.749552,06,2121,33,0113,0150,14,02,02,00,02,1,012,001,0,4,1,0,150,38.5,8.00,20,-5.00,-01,0.00,13,10000,4000*55

2020-01-23T23:00:25.870Z,1579820425.870 [Micromodem](INFO): Outgoing frame #1, 64 bytes: C0202A5E170000005D00000400760000000000000000151D80030C5669BF5A33BA1CC828F8D109D8375066203A90EDF003F66C42CE0C52AA598E2DC220AF94E6
2020-01-23T23:00:25.870Z,1579820425.870 [Micromodem](INFO): Outgoing frame #2, 53 bytes: B922C2FF585F446230CF8E0A17C3E9C556452B1168B303E7ED48A4C240A4DB5D875B0253448FFE07C5E6C02E896B0DFC95C56B7C95
2020-01-23T23:00:25.871Z,1579820425.871 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,2*68

2020-01-23T23:00:27.057Z,1579820427.057 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,2*6A

2020-01-23T23:00:27.469Z,1579820427.469 [Micromodem](INFO): Nmea in: $CADRQ,230026,1,12,0,64,1*71

2020-01-23T23:00:27.470Z,1579820427.470 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,C0202A5E170000005D00000400760000000000000000151D80030C5669BF5A33BA1CC828F8D109D8375066203A90EDF003F66C42CE0C52AA598E2DC220AF94E6*30

2020-01-23T23:00:27.865Z,1579820427.865 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-23T23:00:28.268Z,1579820428.268 [Micromodem](INFO): Nmea in: $CADRQ,230027,1,12,0,64,2*73

2020-01-23T23:00:28.269Z,1579820428.269 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,B922C2FF585F446230CF8E0A17C3E9C556452B1168B303E7ED48A4C240A4DB5D875B0253448FFE07C5E6C02E896B0DFC95C56B7C95*4B

2020-01-23T23:00:28.682Z,1579820428.682 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,53*4F

2020-01-23T23:00:29.076Z,1579820429.076 [Micromodem](INFO): Nmea in: $CATXP,117*45

2020-01-23T23:00:31.501Z,1579820431.501 [Micromodem](INFO): Nmea in: $CATXF,117*53

2020-01-23T23:00:31.906Z,1579820431.906 [Micromodem](INFO): Nmea in: $CAXST,6,20200123,230028.732223,3,0,200,4000,10000,1,1,12,1,2,2,3,117*79

2020-01-23T23:00:33.897Z,1579820433.897 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-23T23:00:34.325Z,1579820434.325 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-23T23:00:34.326Z,1579820434.326 [Micromodem](INFO): Sent 109 bytes from file Logs/20200123T224000/Express0023.lzma.parts
2020-01-23T23:00:34.326Z,1579820434.326 [Micromodem](INFO): Packets left to send: 0
2020-01-23T23:00:34.710Z,1579820434.710 [Micromodem](INFO): Nmea in: $CACST,6,0,20200123230033.283794,06,2005,33,0111,0150,29,01,01,00,03,1,012,001,0,4,1,0,150,15.7,9.00,21,-6.00,-01,-0.01,35,10000,4000*73

2020-01-23T23:00:34.728Z,1579820434.728 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 10252A5E010004005D00000400010500000000000000151D80030C5669BF31555EAE1409A708D50837CD96632A3B471986A383D0F4DFCC64A6879B643ABEF98C
2020-01-23T23:00:34.729Z,1579820434.729 [Micromodem](INFO): Outgoing frame #2, 64 bytes: 24CF1A89C4071952A7F3B7FB5A92546CE1859DFE26CC70FEB5A19E94E90EB0A216473C69D183AB11A2E891D6BA983B72FFEC7CA3B281A4359277E2DBD882D479
2020-01-23T23:00:34.729Z,1579820434.729 [Micromodem](INFO): Outgoing frame #3, 64 bytes: 64B71691D7D4BB05F4BD5CB4CC46B061E11253BE9BAAA3A6D4A232FA4B34DFCA7F242849B04E2B96697E5597568AC3AEB840FD69E540152F07FE95EBFC476873
2020-01-23T23:00:34.729Z,1579820434.729 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69

2020-01-23T23:00:35.945Z,1579820435.945 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B

2020-01-23T23:00:36.354Z,1579820436.354 [Micromodem](INFO): Nmea in: $CADRQ,230035,1,12,0,64,1*73

2020-01-23T23:00:36.354Z,1579820436.354 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,10252A5E010004005D00000400010500000000000000151D80030C5669BF31555EAE1409A708D50837CD96632A3B471986A383D0F4DFCC64A6879B643ABEF98C*47

2020-01-23T23:00:36.749Z,1579820436.749 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-23T23:00:37.153Z,1579820437.153 [Micromodem](INFO): Nmea in: $CADRQ,230036,1,12,0,64,2*73

2020-01-23T23:00:37.154Z,1579820437.154 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,24CF1A89C4071952A7F3B7FB5A92546CE1859DFE26CC70FEB5A19E94E90EB0A216473C69D183AB11A2E891D6BA983B72FFEC7CA3B281A4359277E2DBD882D479*3A

2020-01-23T23:00:37.557Z,1579820437.557 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-23T23:00:37.961Z,1579820437.961 [Micromodem](INFO): Nmea in: $CADRQ,230037,1,12,0,64,3*73

2020-01-23T23:00:37.962Z,1579820437.962 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,64B71691D7D4BB05F4BD5CB4CC46B061E11253BE9BAAA3A6D4A232FA4B34DFCA7F242849B04E2B96697E5597568AC3AEB840FD69E540152F07FE95EBFC476873*4F

2020-01-23T23:00:38.365Z,1579820438.365 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-23T23:00:38.769Z,1579820438.769 [Micromodem](INFO): Nmea in: $CATXP,192*48

2020-01-23T23:00:42.405Z,1579820442.405 [Micromodem](INFO): Nmea in: $CATXF,192*5E

2020-01-23T23:00:42.817Z,1579820442.817 [Micromodem](INFO): Nmea in: $CAXST,6,20200123,230038.446555,3,0,200,4000,10000,1,1,12,1,3,3,3,192*73

2020-01-23T23:00:43.653Z,1579820443.653 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-23T23:00:43.653Z,1579820443.653 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20012316115208,35.0, -0.1,  8.9,  0
2020-01-23T23:00:45.710Z,1579820445.710 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-23T23:00:46.048Z,1579820446.048 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-23T23:00:46.446Z,1579820446.446 [Micromodem](INFO): Nmea in: $CAACK,12,1,3,1*7B

2020-01-23T23:00:46.446Z,1579820446.446 [Micromodem](INFO): Sent 184 bytes from file Logs/20200123T225824/Express0001.lzma.parts
2020-01-23T23:00:46.447Z,1579820446.447 [Micromodem](INFO): Packets left to send: 4
2020-01-23T23:00:46.853Z,1579820446.853 [Micromodem](INFO): Nmea in: $CACST,6,0,20200123230044.845219,06,2059,33,0125,0150,29,01,01,00,03,1,012,001,0,4,1,0,150,32.7,9.00,21,-6.00,-01,-0.03,16,10000,4000*7B

2020-01-23T23:00:46.870Z,1579820446.870 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 10252A5E01000300263F3AC4A911B8CCB019ABACFB62418A26125E2E0C18A148BAA4C9DC212B45D493F2D4C3D9AB36BD7385FF544EB501AA1D40775FD05A7085
2020-01-23T23:00:46.871Z,1579820446.871 [Micromodem](INFO): Outgoing frame #2, 64 bytes: C51CEF7507E9B9AABD01E11DA8EED5B8DA976F8D65A46BAFFFD55EE25B522409E432645460DA984ED4A5FB83A28D94B951CEA9B2AA1EE1ABBA30DB300067D624
2020-01-23T23:00:46.872Z,1579820446.872 [Micromodem](INFO): Outgoing frame #3, 64 bytes: E0D487E3F3530BBF94F0DCC88FA0208C8B31F471CB2CF6023B63F95BDCB83DDD03E2A9677C91E45F3846B08046003CCB69EFF6F6CD4BDE29EEF24DE13D7E32DF
2020-01-23T23:00:46.872Z,1579820446.872 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69

2020-01-23T23:00:48.065Z,1579820448.065 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B

2020-01-23T23:00:48.468Z,1579820448.468 [Micromodem](INFO): Nmea in: $CADRQ,230047,1,12,0,64,1*76

2020-01-23T23:00:48.469Z,1579820448.469 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,10252A5E01000300263F3AC4A911B8CCB019ABACFB62418A26125E2E0C18A148BAA4C9DC212B45D493F2D4C3D9AB36BD7385FF544EB501AA1D40775FD05A7085*36

2020-01-23T23:00:48.865Z,1579820448.865 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-23T23:00:49.273Z,1579820449.273 [Micromodem](INFO): Nmea in: $CADRQ,230048,1,12,0,64,2*7A

2020-01-23T23:00:49.274Z,1579820449.274 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,C51CEF7507E9B9AABD01E11DA8EED5B8DA976F8D65A46BAFFFD55EE25B522409E432645460DA984ED4A5FB83A28D94B951CEA9B2AA1EE1ABBA30DB300067D624*3A

2020-01-23T23:00:49.681Z,1579820449.681 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-23T23:00:50.081Z,1579820450.081 [Micromodem](INFO): Nmea in: $CADRQ,230049,1,12,0,64,3*7A

2020-01-23T23:00:50.082Z,1579820450.082 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,E0D487E3F3530BBF94F0DCC88FA0208C8B31F471CB2CF6023B63F95BDCB83DDD03E2A9677C91E45F3846B08046003CCB69EFF6F6CD4BDE29EEF24DE13D7E32DF*49

2020-01-23T23:00:50.489Z,1579820450.489 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-23T23:00:50.889Z,1579820450.889 [Micromodem](INFO): Nmea in: $CATXP,192*48

2020-01-23T23:00:54.521Z,1579820454.521 [Micromodem](INFO): Nmea in: $CATXF,192*5E

2020-01-23T23:00:54.899Z,1579820454.899 [Micromodem](INFO): Nmea in: $CAXST,6,20200123,230050.566865,3,0,200,4000,10000,1,1,12,1,3,3,3,192*70

2020-01-23T23:00:57.742Z,1579820457.742 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-23T23:00:58.146Z,1579820458.146 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-23T23:00:58.537Z,1579820458.537 [Micromodem](INFO): Nmea in: $CAACK,12,1,3,1*7B

2020-01-23T23:00:58.538Z,1579820458.538 [Micromodem](INFO): Sent 184 bytes from file Logs/20200123T225824/Express0001.lzma.parts
2020-01-23T23:00:58.538Z,1579820458.538 [Micromodem](INFO): Packets left to send: 3
2020-01-23T23:00:58.953Z,1579820458.953 [Micromodem](INFO): Nmea in: $CACST,6,0,20200123230056.958760,06,1940,32,0107,0150,07,01,01,00,03,1,012,001,0,4,1,0,150,30.7,7.00,19,-4.00,-01,0.00,18,10000,4000*54

2020-01-23T23:00:59.015Z,1579820459.015 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 10252A5E01000200ADA97E09041598F0FDD1C72E3979E36CA1D9D368B1B4B5ED3CDE8BB26D7E3E0F4C2A09BB0840720345435ABAB465A4EF916FD37BFEB03ED5
2020-01-23T23:00:59.016Z,1579820459.016 [Micromodem](INFO): Outgoing frame #2, 64 bytes: 335B546F9132F31EF28BEA6304C853D5705285F19C44170CA054956F56774C51EE3B07AB5FF8590EADE79CEFA0E50A871E7A64BD1796B07D13CDD3D96A491CEC
2020-01-23T23:00:59.016Z,1579820459.016 [Micromodem](INFO): Outgoing frame #3, 64 bytes: 3152E5CFFEDA68978FB4F9F61AFEC4D65642EFB40CAAE8E8DB1A859377907B49BB8CA2A5C6E8EADD91D5D4168D91A3E0A818F169D89BC83BF44919DEECF28CF2
2020-01-23T23:00:59.017Z,1579820459.017 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69

2020-01-23T23:01:00.153Z,1579820460.153 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B

2020-01-23T23:01:00.557Z,1579820460.557 [Micromodem](INFO): Nmea in: $CADRQ,230059,1,12,0,64,1*79

2020-01-23T23:01:00.558Z,1579820460.558 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,10252A5E01000200ADA97E09041598F0FDD1C72E3979E36CA1D9D368B1B4B5ED3CDE8BB26D7E3E0F4C2A09BB0840720345435ABAB465A4EF916FD37BFEB03ED5*4A

2020-01-23T23:01:00.965Z,1579820460.965 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-23T23:01:01.365Z,1579820461.365 [Micromodem](INFO): Nmea in: $CADRQ,230100,1,12,0,64,2*77

2020-01-23T23:01:01.366Z,1579820461.366 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,335B546F9132F31EF28BEA6304C853D5705285F19C44170CA054956F56774C51EE3B07AB5FF8590EADE79CEFA0E50A871E7A64BD1796B07D13CDD3D96A491CEC*34

2020-01-23T23:01:01.781Z,1579820461.781 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-23T23:01:02.177Z,1579820462.177 [Micromodem](INFO): Nmea in: $CADRQ,230101,1,12,0,64,3*77

2020-01-23T23:01:02.178Z,1579820462.178 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,3152E5CFFEDA68978FB4F9F61AFEC4D65642EFB40CAAE8E8DB1A859377907B49BB8CA2A5C6E8EADD91D5D4168D91A3E0A818F169D89BC83BF44919DEECF28CF2*4C

2020-01-23T23:01:02.589Z,1579820462.589 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-23T23:01:02.993Z,1579820462.993 [Micromodem](INFO): Nmea in: $CATXP,192*48

2020-01-23T23:01:04.742Z,1579820464.742 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004968
2020-01-23T23:01:06.621Z,1579820466.621 [Micromodem](INFO): Nmea in: $CATXF,192*5E

2020-01-23T23:01:07.026Z,1579820467.026 [Micromodem](INFO): Nmea in: $CAXST,6,20200123,230102.668875,3,0,200,4000,10000,1,1,12,1,3,3,3,192*7A

2020-01-23T23:01:08.299Z,1579820468.299 [DefaultUnder:C.](INFO): *** querying acoustic contact ***
2020-01-23T23:01:09.050Z,1579820469.050 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2020-01-23T23:01:09.451Z,1579820469.451 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2020-01-23T23:01:10.270Z,1579820470.270 [Micromodem](INFO): Nmea in: $SNTTA,,,,,230109.49*5A

2020-01-23T23:01:11.746Z,1579820471.746 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-01-23T23:01:12.682Z,1579820472.682 [DUSBL_Hydroid](ERROR): No response from remote modem.
2020-01-23T23:01:12.900Z,1579820472.900 [DataOverHttps](INFO): Sending 750 bytes from file Logs/20200123T225824/Express0001.lzma
2020-01-23T23:01:14.904Z,1579820474.904 [DataOverHttps](INFO): Moved sent file to Logs/20200123T225824/Express0001.lzma.bak
2020-01-23T23:01:14.905Z,1579820474.905 [DataOverHttps](INFO): SBD MOMSN=12201436
2020-01-23T23:01:15.970Z,1579820475.970 [DefaultUnder:uModemComms:CheckIn:A] Stopped
2020-01-23T23:01:15.970Z,1579820475.970 [DefaultUnder:uModemComms:CheckIn](INFO): Completed DefaultUnder:uModemComms:CheckIn
2020-01-23T23:01:15.970Z,1579820475.970 [DefaultUnder:uModemComms:CheckIn] Stopped
2020-01-23T23:01:15.971Z,1579820475.971 [DefaultUnder:uModemComms:CheckIn](DEBUG): Aggregate::uninitialize DefaultUnder:uModemComms:CheckIn
2020-01-23T23:01:34.589Z,1579820494.589 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2020-01-23T23:01:34.589Z,1579820494.589 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-23T23:01:34.632Z,1579820494.632 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-23T23:01:35.045Z,1579820495.045 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-23T23:01:35.045Z,1579820495.045 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2020-01-23T23:02:03.627Z,1579820523.627 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-23T23:02:03.628Z,1579820523.628 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20012316131208,35.0, -0.1,448.9,  0
2020-01-23T23:02:08.543Z,1579820528.543 [DefaultUnder:C.](INFO): *** querying acoustic contact ***
2020-01-23T23:02:09.293Z,1579820529.293 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2020-01-23T23:02:09.704Z,1579820529.704 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2020-01-23T23:02:10.527Z,1579820530.527 [Micromodem](INFO): Nmea in: $SNTTA,0.000230,,,,230209.73*4F

2020-01-23T23:02:45.010Z,1579820565.010 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-01-23T23:03:01.504Z,1579820581.504 [RDI_Pathfinder](ERROR): Failed to parse:
:SA,   +0.00,   +0.00,  0.00
2020-01-23T23:03:08.200Z,1579820588.200 [CommandLine](IMPORTANT): got command configSet Radio_Surface.loadAtStartup 0.000000 bool persist
2020-01-23T23:03:08.200Z,1579820588.200 [CommandLine](IMPORTANT): configSet Radio_Surface.loadAtStartup requires a restart to take effect.
2020-01-23T23:03:10.425Z,1579820590.425 [DefaultUnder:C.](INFO): *** querying acoustic contact ***
2020-01-23T23:03:11.171Z,1579820591.171 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2020-01-23T23:03:11.567Z,1579820591.567 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2020-01-23T23:03:12.392Z,1579820592.392 [Micromodem](INFO): Nmea in: $SNTTA,0.000223,,,,230311.61*46

2020-01-23T23:03:13.816Z,1579820593.816 [CommandLine](IMPORTANT): got command ! echo i > /dev/loadB6
2020-01-23T23:03:22.180Z,1579820602.180 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-01-23T23:04:09.616Z,1579820649.616 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-23T23:04:12.197Z,1579820652.197 [DefaultUnder:C.](INFO): *** querying acoustic contact ***
2020-01-23T23:04:12.643Z,1579820652.643 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.263493
2020-01-23T23:04:12.978Z,1579820652.978 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2020-01-23T23:04:13.075Z,1579820653.075 [DefaultUnder:uModemComms:CheckIn] Running Loop=1
2020-01-23T23:04:13.075Z,1579820653.075 [DefaultUnder:uModemComms:CheckIn](DEBUG): Aggregate::initialize DefaultUnder:uModemComms:CheckIn
2020-01-23T23:04:13.075Z,1579820653.075 [DefaultUnder:uModemComms:CheckIn:A] Running Loop=1
2020-01-23T23:04:13.383Z,1579820653.383 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2020-01-23T23:04:13.402Z,1579820653.402 [Micromodem](ERROR): Buffer fill timout failure.
2020-01-23T23:04:13.793Z,1579820653.793 [Micromodem](INFO): Outgoing frame #1, 47 bytes: 10252A5E040000005D00000400140000000000000000151D80030C5669BF92DB1A559F166D9E0849B461D0E598C000
2020-01-23T23:04:13.793Z,1579820653.793 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,1*6B

2020-01-23T23:04:14.204Z,1579820654.204 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,1*69

2020-01-23T23:04:14.282Z,1579820654.282 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-23T23:04:14.282Z,1579820654.282 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-01-23T23:04:14.604Z,1579820654.604 [Micromodem](INFO): Nmea in: $CADRQ,230414,1,12,0,64,1*74

2020-01-23T23:04:14.605Z,1579820654.605 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,10252A5E040000005D00000400140000000000000000151D80030C5669BF92DB1A559F166D9E0849B461D0E598C000*32

2020-01-23T23:04:15.004Z,1579820655.004 [Micromodem](INFO): Nmea in: $SNTTA,0.000225,,,,230413.41*47

2020-01-23T23:04:15.004Z,1579820655.004 [Micromodem](ERROR): TXD response from modem unexpected: $SNTTA,0.000225,,,,230413.41*47

2020-01-23T23:04:15.417Z,1579820655.417 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-01-23T23:04:16.248Z,1579820656.248 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,47*4A

2020-01-23T23:04:16.637Z,1579820656.637 [Micromodem](INFO): Nmea in: $CATXP,47*71

2020-01-23T23:04:17.041Z,1579820657.041 [Micromodem](INFO): Nmea in: $CATXF,47*67

2020-01-23T23:04:17.453Z,1579820657.453 [Micromodem](INFO): Nmea in: $CAXST,6,20200123,230415.051485,3,0,200,4000,10000,1,1,12,1,1,1,3,47*4F

2020-01-23T23:04:18.670Z,1579820658.670 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-23T23:04:18.671Z,1579820658.671 [Micromodem](INFO): Sent 39 bytes from file Logs/20200123T225824/Courier0004.lzma.parts
2020-01-23T23:04:18.671Z,1579820658.671 [Micromodem](INFO): Packets left to send: 0
2020-01-23T23:04:19.065Z,1579820659.065 [Micromodem](INFO): Nmea in: $CACST,6,0,20200123230417.767189,06,1400,31,0103,0150,29,01,01,00,03,1,012,001,0,4,1,0,150,27.1,9.00,20,-6.00,-01,-0.04,21,10000,4000*71

2020-01-23T23:04:19.084Z,1579820659.084 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 10252A5E050000005D00000400E30000000000000000151D80030C5669BF5A33BA5873B7D0E66810D41C4A678F1633988971B2843BE3C2AEBAECCF475A529B01
2020-01-23T23:04:19.084Z,1579820659.084 [Micromodem](INFO): Outgoing frame #2, 64 bytes: 1FD3F536185470DE249BAF7A8CCBD1DD46556D11B3EB193C35646A4BABE357394F0935F146634C5CCC0EBDD8CEDB8D71B8C09D4ACE412323029AFF23F138B09A
2020-01-23T23:04:19.085Z,1579820659.085 [Micromodem](INFO): Outgoing frame #3, 56 bytes: 67EA6034A7BF1ED5A07CC001A4C019DB10BCF7899AB79358FAEA8354415FA093CEE5ECABF6392483FF464133EB3F04F8FD03D33FDE18D700
2020-01-23T23:04:19.085Z,1579820659.085 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69

2020-01-23T23:04:20.286Z,1579820660.286 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B

2020-01-23T23:04:20.678Z,1579820660.678 [Micromodem](INFO): Nmea in: $CADRQ,230419,1,12,0,64,1*79

2020-01-23T23:04:20.678Z,1579820660.678 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,10252A5E050000005D00000400E30000000000000000151D80030C5669BF5A33BA5873B7D0E66810D41C4A678F1633988971B2843BE3C2AEBAECCF475A529B01*47

2020-01-23T23:04:21.081Z,1579820661.081 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-23T23:04:21.489Z,1579820661.489 [Micromodem](INFO): Nmea in: $CADRQ,230421,1,12,0,64,2*71

2020-01-23T23:04:21.490Z,1579820661.490 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,1FD3F536185470DE249BAF7A8CCBD1DD46556D11B3EB193C35646A4BABE357394F0935F146634C5CCC0EBDD8CEDB8D71B8C09D4ACE412323029AFF23F138B09A*40

2020-01-23T23:04:21.889Z,1579820661.889 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-23T23:04:22.310Z,1579820662.310 [Micromodem](INFO): Nmea in: $CADRQ,230421,1,12,0,64,3*70

2020-01-23T23:04:22.310Z,1579820662.310 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,67EA6034A7BF1ED5A07CC001A4C019DB10BCF7899AB79358FAEA8354415FA093CEE5ECABF6392483FF464133EB3F04F8FD03D33FDE18D700*4A

2020-01-23T23:04:22.697Z,1579820662.697 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,56*4A

2020-01-23T23:04:23.105Z,1579820663.105 [Micromodem](INFO): Nmea in: $CATXP,184*4F

2020-01-23T23:04:26.713Z,1579820666.713 [Micromodem](INFO): Nmea in: $CATXF,184*59

2020-01-23T23:04:27.142Z,1579820667.142 [Micromodem](INFO): Nmea in: $CAXST,6,20200123,230422.791534,3,0,200,4000,10000,1,1,12,1,3,3,3,184*75

2020-01-23T23:04:29.974Z,1579820669.974 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-23T23:04:30.373Z,1579820670.373 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-23T23:04:30.786Z,1579820670.786 [Micromodem](INFO): Nmea in: $CAACK,12,1,3,1*7B

2020-01-23T23:04:30.786Z,1579820670.786 [Micromodem](INFO): Sent 176 bytes from file Logs/20200123T225824/Express0005.lzma.parts
2020-01-23T23:04:30.786Z,1579820670.786 [Micromodem](INFO): Packets left to send: 0
2020-01-23T23:04:31.189Z,1579820671.189 [Micromodem](INFO): Nmea in: $CACST,6,0,20200123230429.175611,06,2449,33,0125,0150,37,02,02,00,03,1,012,001,0,4,1,0,150,29.2,8.00,21,-5.00,-01,0.00,25,10000,4000*5B

2020-01-23T23:04:31.288Z,1579820671.288 [DefaultUnder:uModemComms:CheckIn:A] Stopped
2020-01-23T23:04:31.288Z,1579820671.288 [DefaultUnder:uModemComms:CheckIn](INFO): Completed DefaultUnder:uModemComms:CheckIn
2020-01-23T23:04:31.288Z,1579820671.288 [DefaultUnder:uModemComms:CheckIn] Stopped
2020-01-23T23:04:31.288Z,1579820671.288 [DefaultUnder:uModemComms:CheckIn](DEBUG): Aggregate::uninitialize DefaultUnder:uModemComms:CheckIn
2020-01-23T23:04:35.765Z,1579820675.765 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-01-23T23:04:35.770Z,1579820675.770 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2020-01-23T23:04:35.771Z,1579820675.771 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-23T23:04:35.782Z,1579820675.782 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-23T23:04:36.125Z,1579820676.125 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-23T23:04:36.125Z,1579820676.125 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2020-01-23T23:04:44.771Z,1579820684.771 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-23T23:05:08.370Z,1579820708.370 [DefaultUnder:Notify:A:A:A.Wait](INFO): Done Waiting.
2020-01-23T23:05:08.370Z,1579820708.370 [DefaultUnder:Notify:A:A:A.Wait] Stopped
2020-01-23T23:05:08.370Z,1579820708.370 [DefaultUnder:Notify:A:A:A.Wait](DEBUG): Uninitialize Wait Component.
2020-01-23T23:05:08.370Z,1579820708.370 [DefaultUnder:Notify:A:A:B] Running Loop=1
2020-01-23T23:05:08.777Z,1579820708.777 [DefaultUnder:Notify:A:A:B] Stopped
2020-01-23T23:05:08.777Z,1579820708.777 [DefaultUnder:Notify:A:A:C] Running Loop=1
2020-01-23T23:05:09.194Z,1579820709.194 [DefaultUnder:Notify:A:A:C](IMPORTANT): Default mission has been running for 5.011859 min 
2020-01-23T23:05:09.195Z,1579820709.195 [DefaultUnder:Notify:A:A:C] Stopped
2020-01-23T23:05:09.195Z,1579820709.195 [DefaultUnder:Notify:A:A](INFO): Completed DefaultUnder:Notify:A:A
2020-01-23T23:05:09.195Z,1579820709.195 [DefaultUnder:Notify:A:A] Stopped
2020-01-23T23:05:09.195Z,1579820709.195 [DefaultUnder:Notify:A:A](DEBUG): Aggregate::uninitialize DefaultUnder:Notify:A:A
2020-01-23T23:05:09.195Z,1579820709.195 [DefaultUnder:Notify:A:A](INFO): Running loop #2
2020-01-23T23:05:09.195Z,1579820709.195 [DefaultUnder:Notify:A:A] Running Loop=2
2020-01-23T23:05:09.195Z,1579820709.195 [DefaultUnder:Notify:A:A](DEBUG): Aggregate::initialize DefaultUnder:Notify:A:A
2020-01-23T23:05:09.195Z,1579820709.195 [DefaultUnder:Notify:A:A:A.Wait] Running Loop=1
2020-01-23T23:05:09.196Z,1579820709.196 [DefaultUnder:Notify:A:A:A.Wait](DEBUG): Initialize Wait Component.
2020-01-23T23:05:13.651Z,1579820713.651 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-23T23:05:13.651Z,1579820713.651 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20012316162208,35.0, -0.1,   0.0,1448.9,,  0.00,   0.00,   0.00,   0.00,   0.00
2020-01-23T23:05:14.093Z,1579820714.093 [DefaultUnder:C.](INFO): *** querying acoustic contact ***
2020-01-23T23:05:14.839Z,1579820714.839 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2020-01-23T23:05:15.253Z,1579820715.253 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2020-01-23T23:05:16.064Z,1579820716.064 [Micromodem](INFO): Nmea in: $SNTTA,0.000243,,,,230515.28*4F

2020-01-23T23:05:19.911Z,1579820719.911 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-23T23:05:28.684Z,1579820728.684 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-01-23T23:05:55.051Z,1579820755.051 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-23T23:06:09.085Z,1579820769.085 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-01-23T23:06:15.886Z,1579820775.886 [DefaultUnder:C.](INFO): *** querying acoustic contact ***
2020-01-23T23:06:16.651Z,1579820776.651 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2020-01-23T23:06:17.052Z,1579820777.052 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2020-01-23T23:06:17.876Z,1579820777.876 [Micromodem](INFO): Nmea in: $SNTTA,0.000209,,,,230617.09*43

2020-01-23T23:06:22.368Z,1579820782.368 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-01-23T23:06:22.387Z,1579820782.387 [BPC1](INFO): Received data from all battery sticks.
2020-01-23T23:06:30.192Z,1579820790.192 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-23T23:07:05.331Z,1579820825.331 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-23T23:07:17.706Z,1579820837.706 [DefaultUnder:C.](INFO): *** querying acoustic contact ***
2020-01-23T23:07:18.464Z,1579820838.464 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2020-01-23T23:07:18.863Z,1579820838.863 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2020-01-23T23:07:19.696Z,1579820839.696 [Micromodem](INFO): Nmea in: $SNTTA,0.000226,,,,230718.90*40

2020-01-23T23:07:36.717Z,1579820856.717 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2020-01-23T23:07:36.717Z,1579820856.717 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-23T23:07:36.728Z,1579820856.728 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-23T23:07:37.129Z,1579820857.129 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-23T23:07:37.129Z,1579820857.129 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2020-01-23T23:07:40.471Z,1579820860.471 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-23T23:08:15.633Z,1579820895.633 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-23T23:08:19.570Z,1579820899.570 [DefaultUnder:C.](INFO): *** querying acoustic contact ***
2020-01-23T23:08:20.334Z,1579820900.334 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2020-01-23T23:08:20.739Z,1579820900.739 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2020-01-23T23:08:21.558Z,1579820901.558 [Micromodem](INFO): Nmea in: $SNTTA,0.000206,,,,230820.78*40

2020-01-23T23:08:47.473Z,1579820927.473 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-01-23T23:08:47.473Z,1579820927.473 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +0.00,     ,        +0.00,   0.00,596.98
2020-01-23T23:08:50.771Z,1579820930.771 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-23T23:09:12.859Z,1579820952.859 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-01-23T23:09:12.860Z,1579820952.860 [NAL9602] Data Fault, FailCount= 1
2020-01-23T23:09:12.860Z,1579820952.860 [NAL9602](ERROR): Data Fault
2020-01-23T23:09:12.892Z,1579820952.892 [CBIT](ERROR): Data Fault in component: NAL9602
2020-01-23T23:09:13.278Z,1579820953.278 [NAL9602](INFO): Powering down
2020-01-23T23:09:14.097Z,1579820954.097 [CBIT](INFO): Clearing failed state for component NAL9602
2020-01-23T23:09:14.097Z,1579820954.097 [NAL9602] No Fault, FailCount= 1
2020-01-23T23:09:21.374Z,1579820961.374 [DefaultUnder:C.](INFO): *** querying acoustic contact ***
2020-01-23T23:09:22.146Z,1579820962.146 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2020-01-23T23:09:22.555Z,1579820962.555 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2020-01-23T23:09:23.368Z,1579820963.368 [Micromodem](INFO): Nmea in: $SNTTA,0.000214,,,,230922.59*43

2020-01-23T23:09:25.911Z,1579820965.911 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-23T23:09:31.503Z,1579820971.503 [DefaultUnder:uModemComms:CheckIn] Running Loop=1
2020-01-23T23:09:31.503Z,1579820971.503 [DefaultUnder:uModemComms:CheckIn](DEBUG): Aggregate::initialize DefaultUnder:uModemComms:CheckIn
2020-01-23T23:09:31.503Z,1579820971.503 [DefaultUnder:uModemComms:CheckIn:A] Running Loop=1
2020-01-23T23:09:31.884Z,1579820971.884 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 10252A5E070000005D00000400BD0000000000000000151D80030C5669BF3148E2FA22188B6A3173E5573572661C0BCBDFFAB423BD32D9A51E8904B0A920CE9A
2020-01-23T23:09:31.884Z,1579820971.884 [Micromodem](INFO): Outgoing frame #2, 64 bytes: EF9F66414C9B9FD6761AD008C3E3DC4FCA2E4E25B6359B14B772C4B6AB8CB62EC77F4B11CE3F413145360DD3796D0EF36AE23762292A1EE74F3C2A63268A8647
2020-01-23T23:09:31.885Z,1579820971.885 [Micromodem](INFO): Outgoing frame #3, 48 bytes: ABFD082D0517C40D06268F3340D9FB48DA86FACAE084FFA97FAFF58B8FDCACD3B93AEF97E18C6A3768152E74EDB212DE
2020-01-23T23:09:31.885Z,1579820971.885 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69

2020-01-23T23:09:32.281Z,1579820972.281 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B

2020-01-23T23:09:32.678Z,1579820972.678 [Micromodem](INFO): Nmea in: $CADRQ,230932,1,12,0,64,1*7D

2020-01-23T23:09:32.678Z,1579820972.678 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,10252A5E070000005D00000400BD0000000000000000151D80030C5669BF3148E2FA22188B6A3173E5573572661C0BCBDFFAB423BD32D9A51E8904B0A920CE9A*4C

2020-01-23T23:09:33.081Z,1579820973.081 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-23T23:09:33.489Z,1579820973.489 [Micromodem](INFO): Nmea in: $CADRQ,230933,1,12,0,64,2*7F

2020-01-23T23:09:33.490Z,1579820973.490 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,EF9F66414C9B9FD6761AD008C3E3DC4FCA2E4E25B6359B14B772C4B6AB8CB62EC77F4B11CE3F413145360DD3796D0EF36AE23762292A1EE74F3C2A63268A8647*4C

2020-01-23T23:09:33.893Z,1579820973.893 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-23T23:09:34.313Z,1579820974.313 [Micromodem](INFO): Nmea in: $CADRQ,230933,1,12,0,64,3*7E

2020-01-23T23:09:34.314Z,1579820974.314 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,ABFD082D0517C40D06268F3340D9FB48DA86FACAE084FFA97FAFF58B8FDCACD3B93AEF97E18C6A3768152E74EDB212DE*30

2020-01-23T23:09:34.697Z,1579820974.697 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,48*45

2020-01-23T23:09:35.101Z,1579820975.101 [Micromodem](INFO): Nmea in: $CATXP,176*42

2020-01-23T23:09:38.745Z,1579820978.745 [Micromodem](INFO): Nmea in: $CATXF,176*54

2020-01-23T23:09:39.143Z,1579820979.143 [Micromodem](INFO): Nmea in: $CAXST,6,20200123,230934.784129,3,0,200,4000,10000,1,1,12,1,3,3,3,176*7E

2020-01-23T23:09:41.973Z,1579820981.973 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-23T23:09:42.374Z,1579820982.374 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-23T23:09:42.777Z,1579820982.777 [Micromodem](INFO): Nmea in: $CAACK,12,1,3,1*7B

2020-01-23T23:09:42.778Z,1579820982.778 [Micromodem](INFO): Sent 168 bytes from file Logs/20200123T225824/Courier0007.lzma.parts
2020-01-23T23:09:42.778Z,1579820982.778 [Micromodem](INFO): Packets left to send: 0
2020-01-23T23:09:43.189Z,1579820983.189 [Micromodem](INFO): Nmea in: $CACST,6,0,20200123230941.151176,06,1717,32,0097,0150,07,01,01,00,03,1,012,001,0,4,1,0,150,35.6,8.00,22,-5.00,-01,0.00,12,10000,4000*57

2020-01-23T23:09:43.208Z,1579820983.208 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 10252A5E080001005D00000400250100000000000000151D80030C5669BF31555EAE1409A708CE2489B1CBBC019D68DCD8D7B9ED0B0627A8DD3DB4DE10BB4E16
2020-01-23T23:09:43.208Z,1579820983.208 [Micromodem](INFO): Outgoing frame #2, 64 bytes: 3D43F22C2EF0F039CA3BF8D5126AA2AB7041547B9EA2F008D641F0C519CD941A995BD463A0DD43789D6F92A4B2579FDC29D395D2A4716816360CF3F4043FD135
2020-01-23T23:09:43.208Z,1579820983.208 [Micromodem](INFO): Outgoing frame #3, 64 bytes: 8A5423087C38E4736EDCB87EBCDD444D3E5E6BF49255DF4F824452BB8F3CAB551198326401C2E0D358718EB5236E846253DDA41780D50321757E565340C6D580
2020-01-23T23:09:43.209Z,1579820983.209 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69

2020-01-23T23:09:43.584Z,1579820983.584 [NAL9602](INFO): Powering up NAL9602
2020-01-23T23:09:44.397Z,1579820984.397 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B

2020-01-23T23:09:44.797Z,1579820984.797 [Micromodem](INFO): Nmea in: $CADRQ,230943,1,12,0,64,1*7B

2020-01-23T23:09:44.798Z,1579820984.798 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,10252A5E080001005D00000400250100000000000000151D80030C5669BF31555EAE1409A708CE2489B1CBBC019D68DCD8D7B9ED0B0627A8DD3DB4DE10BB4E16*3C

2020-01-23T23:09:45.204Z,1579820985.204 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-23T23:09:45.605Z,1579820985.605 [Micromodem](INFO): Nmea in: $CADRQ,230945,1,12,0,64,2*7E

2020-01-23T23:09:45.606Z,1579820985.606 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,3D43F22C2EF0F039CA3BF8D5126AA2AB7041547B9EA2F008D641F0C519CD941A995BD463A0DD43789D6F92A4B2579FDC29D395D2A4716816360CF3F4043FD135*34

2020-01-23T23:09:45.986Z,1579820985.986 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-23T23:09:46.417Z,1579820986.417 [Micromodem](INFO): Nmea in: $CADRQ,230946,1,12,0,64,3*7C

2020-01-23T23:09:46.418Z,1579820986.418 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,8A5423087C38E4736EDCB87EBCDD444D3E5E6BF49255DF4F824452BB8F3CAB551198326401C2E0D358718EB5236E846253DDA41780D50321757E565340C6D580*3C

2020-01-23T23:09:46.794Z,1579820986.794 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-23T23:09:47.193Z,1579820987.193 [Micromodem](INFO): Nmea in: $CATXP,192*48

2020-01-23T23:09:50.857Z,1579820990.857 [Micromodem](INFO): Nmea in: $CATXF,192*5E

2020-01-23T23:09:51.278Z,1579820991.278 [Micromodem](INFO): Nmea in: $CAXST,6,20200123,230946.903820,3,0,200,4000,10000,1,1,12,1,3,3,3,192*70

2020-01-23T23:09:54.096Z,1579820994.096 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-23T23:09:54.494Z,1579820994.494 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-23T23:09:54.495Z,1579820994.495 [NAL9602](INFO): NAL9602 initialized
2020-01-23T23:09:54.894Z,1579820994.894 [Micromodem](INFO): Nmea in: $CAACK,12,1,3,1*7B

2020-01-23T23:09:54.894Z,1579820994.894 [Micromodem](INFO): Sent 184 bytes from file Logs/20200123T225824/Express0008.lzma.parts
2020-01-23T23:09:54.894Z,1579820994.894 [Micromodem](INFO): Packets left to send: 1
2020-01-23T23:09:55.310Z,1579820995.310 [Micromodem](INFO): Nmea in: $CACST,6,0,20200123230953.313363,06,2118,33,0115,0150,07,01,01,00,03,1,012,001,0,4,1,0,150,9.3,8.00,23,-5.00,-01,0.00,38,10000,4000*65

2020-01-23T23:09:55.329Z,1579820995.329 [Micromodem](INFO): Outgoing frame #1, 63 bytes: 10252A5E0800000066903E53F4B9422EB902C1E791DD1902BC543B5018F487B895022E32683DDAD8B8CAAC5B32F599E726D3D8D462CD7E79C8B59491A8E300
2020-01-23T23:09:55.329Z,1579820995.329 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,1*6B

2020-01-23T23:09:56.517Z,1579820996.517 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,1*69

2020-01-23T23:09:56.893Z,1579820996.893 [Micromodem](INFO): Nmea in: $CADRQ,230955,1,12,0,64,1*7C

2020-01-23T23:09:56.894Z,1579820996.894 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,10252A5E0800000066903E53F4B9422EB902C1E791DD1902BC543B5018F487B895022E32683DDAD8B8CAAC5B32F599E726D3D8D462CD7E79C8B59491A8E300*35

2020-01-23T23:09:57.290Z,1579820997.290 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,63*4C

2020-01-23T23:09:57.697Z,1579820997.697 [Micromodem](INFO): Nmea in: $CATXP,63*77

2020-01-23T23:09:58.913Z,1579820998.913 [Micromodem](INFO): Nmea in: $CATXF,63*61

2020-01-23T23:09:59.318Z,1579820999.318 [Micromodem](INFO): Nmea in: $CAXST,6,20200123,230957.356039,3,0,200,4000,10000,1,1,12,1,1,1,3,63*45

2020-01-23T23:10:00.930Z,1579821000.930 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-23T23:10:00.930Z,1579821000.930 [Micromodem](INFO): Sent 55 bytes from file Logs/20200123T225824/Express0008.lzma.parts
2020-01-23T23:10:00.930Z,1579821000.930 [Micromodem](INFO): Packets left to send: 0
2020-01-23T23:10:01.056Z,1579821001.056 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-23T23:10:01.347Z,1579821001.347 [Micromodem](INFO): Nmea in: $CACST,6,0,20200123231000.092858,06,1727,32,0117,0150,59,02,02,00,03,1,012,001,0,4,1,0,150,32.8,9.00,20,-6.00,-01,-0.02,20,10000,4000*77

2020-01-23T23:10:01.427Z,1579821001.427 [DefaultUnder:uModemComms:CheckIn:A] Stopped
2020-01-23T23:10:01.427Z,1579821001.427 [DefaultUnder:uModemComms:CheckIn](INFO): Completed DefaultUnder:uModemComms:CheckIn
2020-01-23T23:10:01.427Z,1579821001.427 [DefaultUnder:uModemComms:CheckIn] Stopped
2020-01-23T23:10:01.427Z,1579821001.427 [DefaultUnder:uModemComms:CheckIn](DEBUG): Aggregate::uninitialize DefaultUnder:uModemComms:CheckIn
2020-01-23T23:10:09.922Z,1579821009.922 [DefaultUnder:Notify:A:A:A.Wait](INFO): Done Waiting.
2020-01-23T23:10:09.922Z,1579821009.922 [DefaultUnder:Notify:A:A:A.Wait] Stopped
2020-01-23T23:10:09.923Z,1579821009.923 [DefaultUnder:Notify:A:A:A.Wait](DEBUG): Uninitialize Wait Component.
2020-01-23T23:10:09.923Z,1579821009.923 [DefaultUnder:Notify:A:A:B] Running Loop=1
2020-01-23T23:10:10.345Z,1579821010.345 [DefaultUnder:Notify:A:A:B] Stopped
2020-01-23T23:10:10.345Z,1579821010.345 [DefaultUnder:Notify:A:A:C] Running Loop=1
2020-01-23T23:10:10.747Z,1579821010.747 [DefaultUnder:Notify:A:A:C](IMPORTANT): Default mission has been running for 10.037994 min 
2020-01-23T23:10:10.747Z,1579821010.747 [DefaultUnder:Notify:A:A:C] Stopped
2020-01-23T23:10:10.747Z,1579821010.747 [DefaultUnder:Notify:A:A](INFO): Completed DefaultUnder:Notify:A:A
2020-01-23T23:10:10.747Z,1579821010.747 [DefaultUnder:Notify:A:A] Stopped
2020-01-23T23:10:10.747Z,1579821010.747 [DefaultUnder:Notify:A:A](DEBUG): Aggregate::uninitialize DefaultUnder:Notify:A:A
2020-01-23T23:10:10.748Z,1579821010.748 [DefaultUnder:Notify:A:A](INFO): Running loop #3
2020-01-23T23:10:10.748Z,1579821010.748 [DefaultUnder:Notify:A:A] Running Loop=3
2020-01-23T23:10:10.748Z,1579821010.748 [DefaultUnder:Notify:A:A](DEBUG): Aggregate::initialize DefaultUnder:Notify:A:A
2020-01-23T23:10:10.748Z,1579821010.748 [DefaultUnder:Notify:A:A:A.Wait] Running Loop=1
2020-01-23T23:10:10.748Z,1579821010.748 [DefaultUnder:Notify:A:A:A.Wait](DEBUG): Initialize Wait Component.
2020-01-23T23:10:23.269Z,1579821023.269 [DefaultUnder:C.](INFO): *** querying acoustic contact ***
2020-01-23T23:10:24.023Z,1579821024.023 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2020-01-23T23:10:24.423Z,1579821024.423 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2020-01-23T23:10:25.245Z,1579821025.245 [Micromodem](INFO): Nmea in: $SNTTA,0.000208,,,,231024.47*4F

2020-01-23T23:10:36.195Z,1579821036.195 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-23T23:10:37.795Z,1579821037.795 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2020-01-23T23:10:37.795Z,1579821037.795 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-23T23:10:37.807Z,1579821037.807 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-23T23:10:38.230Z,1579821038.230 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-23T23:10:38.230Z,1579821038.230 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2020-01-23T23:10:42.279Z,1579821042.279 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-23T23:10:42.279Z,1579821042.279 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20012316215108,35.0, -0.1,448.9,  0
2020-01-23T23:11:11.335Z,1579821071.335 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-23T23:11:25.073Z,1579821085.073 [DefaultUnder:C.](INFO): *** querying acoustic contact ***
2020-01-23T23:11:25.830Z,1579821085.830 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2020-01-23T23:11:26.231Z,1579821086.231 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2020-01-23T23:11:27.056Z,1579821087.056 [Micromodem](INFO): Nmea in: $SNTTA,0.000214,,,,231126.27*47

2020-01-23T23:11:40.035Z,1579821100.035 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-23T23:11:40.035Z,1579821100.035 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,200123162248080.1,   0.0,1448.9,  0
2020-01-23T23:11:43.297Z,1579821103.297 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-23T23:11:43.297Z,1579821103.297 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-01-23T23:11:46.476Z,1579821106.476 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-23T23:12:04.633Z,1579821124.633 [RDI_Pathfinder](ERROR): Failed to parse:
:R,   0.00,   0.00,   0.00,   0.00
2020-01-23T23:12:21.615Z,1579821141.615 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-23T23:12:26.878Z,1579821146.878 [DefaultUnder:C.](INFO): *** querying acoustic contact ***
2020-01-23T23:12:27.642Z,1579821147.642 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2020-01-23T23:12:28.047Z,1579821148.047 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2020-01-23T23:12:28.872Z,1579821148.872 [Micromodem](INFO): Nmea in: $SNTTA,0.000205,,,,231228.08*47

2020-01-23T23:12:34.653Z,1579821154.653 [RDI_Pathfinder](ERROR): Failed to parse:
:R,   0.00,   0.00,   0.00,   0.00
2020-01-23T23:12:56.755Z,1579821176.755 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-23T23:13:28.798Z,1579821208.798 [DefaultUnder:C.](INFO): *** querying acoustic contact ***
2020-01-23T23:13:29.546Z,1579821209.546 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2020-01-23T23:13:29.956Z,1579821209.956 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2020-01-23T23:13:30.776Z,1579821210.776 [Micromodem](INFO): Nmea in: $SNTTA,0.000209,,,,231329.99*43

2020-01-23T23:13:31.895Z,1579821211.895 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-23T23:13:38.910Z,1579821218.910 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2020-01-23T23:13:38.910Z,1579821218.910 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-23T23:13:38.922Z,1579821218.922 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-23T23:13:39.330Z,1579821219.330 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-23T23:13:39.330Z,1579821219.330 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2020-01-23T23:14:07.043Z,1579821247.043 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-23T23:14:30.608Z,1579821270.608 [DefaultUnder:C.](INFO): *** querying acoustic contact ***
2020-01-23T23:14:31.362Z,1579821271.362 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2020-01-23T23:14:31.772Z,1579821271.772 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2020-01-23T23:14:32.584Z,1579821272.584 [Micromodem](INFO): Nmea in: $SNTTA,0.000209,,,,231431.80*45

2020-01-23T23:14:42.183Z,1579821282.183 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-23T23:15:01.404Z,1579821301.404 [DefaultUnder:uModemComms:CheckIn] Running Loop=1
2020-01-23T23:15:01.404Z,1579821301.404 [DefaultUnder:uModemComms:CheckIn](DEBUG): Aggregate::initialize DefaultUnder:uModemComms:CheckIn
2020-01-23T23:15:01.405Z,1579821301.405 [DefaultUnder:uModemComms:CheckIn:A] Running Loop=1
2020-01-23T23:15:01.784Z,1579821301.784 [Micromodem](INFO): Outgoing frame #1, 47 bytes: 10252A5E0A0000005D00000400140000000000000000151D80030C5669BF92DB1A867A95CA399623077ABE2C8E4000
2020-01-23T23:15:01.784Z,1579821301.784 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,1*6B

2020-01-23T23:15:02.169Z,1579821302.169 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,1*69

2020-01-23T23:15:02.574Z,1579821302.574 [Micromodem](INFO): Nmea in: $CADRQ,231502,1,12,0,64,1*73

2020-01-23T23:15:02.574Z,1579821302.574 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,10252A5E0A0000005D00000400140000000000000000151D80030C5669BF92DB1A867A95CA399623077ABE2C8E4000*3F

2020-01-23T23:15:02.984Z,1579821302.984 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,47*4A

2020-01-23T23:15:03.381Z,1579821303.381 [Micromodem](INFO): Nmea in: $CATXP,47*71

2020-01-23T23:15:04.605Z,1579821304.605 [Micromodem](INFO): Nmea in: $CATXF,47*67

2020-01-23T23:15:05.810Z,1579821305.810 [Micromodem](INFO): Nmea in: $CAXST,6,20200123,231503.019199,3,0,200,4000,10000,1,1,12,1,1,1,3,47*4C

2020-01-23T23:15:06.590Z,1579821306.590 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-23T23:15:06.590Z,1579821306.590 [Micromodem](INFO): Sent 39 bytes from file Logs/20200123T225824/Courier0010.lzma.parts
2020-01-23T23:15:06.590Z,1579821306.590 [Micromodem](INFO): Packets left to send: 0
2020-01-23T23:15:06.997Z,1579821306.997 [Micromodem](INFO): Nmea in: $CACST,6,0,20200123231505.738055,06,1657,32,0101,0150,29,01,01,00,03,1,012,001,0,4,1,0,150,34.5,10.00,24,-7.00,-01,-0.01,15,10000,4000*40

2020-01-23T23:15:07.027Z,1579821307.027 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 10252A5E0B0000005D00000400510000000000000000151D80030C5669C055D3E2241520F31412FBAAF1F81770196647004AF3B8BAF1336021ABAE4A105837B9
2020-01-23T23:15:07.028Z,1579821307.028 [Micromodem](INFO): Outgoing frame #2, 42 bytes: 109F9FCF9C89020B7C588D3CD732B54B94CC1BC19FFAB6FFC9947767D153AB6C739EB863B7E63028A000
2020-01-23T23:15:07.028Z,1579821307.028 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,2*68

2020-01-23T23:15:08.205Z,1579821308.205 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,2*6A

2020-01-23T23:15:08.625Z,1579821308.625 [Micromodem](INFO): Nmea in: $CADRQ,231507,1,12,0,64,1*76

2020-01-23T23:15:08.626Z,1579821308.626 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,10252A5E0B0000005D00000400510000000000000000151D80030C5669C055D3E2241520F31412FBAAF1F81770196647004AF3B8BAF1336021ABAE4A105837B9*45

2020-01-23T23:15:09.013Z,1579821309.013 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-23T23:15:09.409Z,1579821309.409 [Micromodem](INFO): Nmea in: $CADRQ,231509,1,12,0,64,2*7B

2020-01-23T23:15:09.410Z,1579821309.410 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,109F9FCF9C89020B7C588D3CD732B54B94CC1BC19FFAB6FFC9947767D153AB6C739EB863B7E63028A000*48

2020-01-23T23:15:09.824Z,1579821309.824 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,42*4F

2020-01-23T23:15:10.225Z,1579821310.225 [Micromodem](INFO): Nmea in: $CATXP,106*45

2020-01-23T23:15:11.493Z,1579821311.493 [DefaultUnder:Notify:A:A:A.Wait](INFO): Done Waiting.
2020-01-23T23:15:11.493Z,1579821311.493 [DefaultUnder:Notify:A:A:A.Wait] Stopped
2020-01-23T23:15:11.493Z,1579821311.493 [DefaultUnder:Notify:A:A:A.Wait](DEBUG): Uninitialize Wait Component.
2020-01-23T23:15:11.493Z,1579821311.493 [DefaultUnder:Notify:A:A:B] Running Loop=1
2020-01-23T23:15:11.878Z,1579821311.878 [DefaultUnder:Notify:A:A:B] Stopped
2020-01-23T23:15:11.878Z,1579821311.878 [DefaultUnder:Notify:A:A:C] Running Loop=1
2020-01-23T23:15:12.291Z,1579821312.291 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-01-23T23:15:12.292Z,1579821312.292 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-3276,-32768,V
2020-01-23T23:15:12.320Z,1579821312.320 [DefaultUnder:Notify:A:A:C](IMPORTANT): Default mission has been running for 15.063551 min 
2020-01-23T23:15:12.321Z,1579821312.321 [DefaultUnder:Notify:A:A:C] Stopped
2020-01-23T23:15:12.321Z,1579821312.321 [DefaultUnder:Notify:A:A](INFO): Completed DefaultUnder:Notify:A:A
2020-01-23T23:15:12.321Z,1579821312.321 [DefaultUnder:Notify:A:A] Stopped
2020-01-23T23:15:12.321Z,1579821312.321 [DefaultUnder:Notify:A:A](DEBUG): Aggregate::uninitialize DefaultUnder:Notify:A:A
2020-01-23T23:15:12.321Z,1579821312.321 [DefaultUnder:Notify:A:A](INFO): Running loop #4
2020-01-23T23:15:12.321Z,1579821312.321 [DefaultUnder:Notify:A:A] Running Loop=4
2020-01-23T23:15:12.321Z,1579821312.321 [DefaultUnder:Notify:A:A](DEBUG): Aggregate::initialize DefaultUnder:Notify:A:A
2020-01-23T23:15:12.321Z,1579821312.321 [DefaultUnder:Notify:A:A:A.Wait] Running Loop=1
2020-01-23T23:15:12.321Z,1579821312.321 [DefaultUnder:Notify:A:A:A.Wait](DEBUG): Initialize Wait Component.
2020-01-23T23:15:12.641Z,1579821312.641 [Micromodem](INFO): Nmea in: $CATXF,106*53

2020-01-23T23:15:13.056Z,1579821313.056 [Micromodem](INFO): Nmea in: $CAXST,6,20200123,231509.858754,3,0,200,4000,10000,1,1,12,1,2,2,3,106*78

2020-01-23T23:15:15.074Z,1579821315.074 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-23T23:15:15.482Z,1579821315.482 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-23T23:15:15.483Z,1579821315.483 [Micromodem](INFO): Sent 98 bytes from file Logs/20200123T225824/Express0011.lzma.parts
2020-01-23T23:15:15.484Z,1579821315.484 [Micromodem](INFO): Packets left to send: 0
2020-01-23T23:15:15.887Z,1579821315.887 [Micromodem](INFO): Nmea in: $CACST,6,0,20200123231514.396280,06,1657,32,0112,0150,07,01,01,00,03,1,012,001,0,4,1,0,150,33.6,10.00,24,-7.00,-01,-0.02,12,10000,4000*44

2020-01-23T23:15:15.954Z,1579821315.954 [DefaultUnder:uModemComms:CheckIn:A] Stopped
2020-01-23T23:15:15.954Z,1579821315.954 [DefaultUnder:uModemComms:CheckIn](INFO): Completed DefaultUnder:uModemComms:CheckIn
2020-01-23T23:15:15.954Z,1579821315.954 [DefaultUnder:uModemComms:CheckIn] Stopped
2020-01-23T23:15:15.954Z,1579821315.954 [DefaultUnder:uModemComms:CheckIn](DEBUG): Aggregate::uninitialize DefaultUnder:uModemComms:CheckIn
2020-01-23T23:15:16.716Z,1579821316.716 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-23T23:15:16.716Z,1579821316.716 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   I,-32768,-32768,-32768,-32768,V
2020-01-23T23:15:17.323Z,1579821317.323 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-23T23:15:31.663Z,1579821331.663 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-01-23T23:15:31.663Z,1579821331.663 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-2768,-32768,V
2020-01-23T23:15:32.501Z,1579821332.501 [DefaultUnder:C.](INFO): *** querying acoustic contact ***
2020-01-23T23:15:33.308Z,1579821333.308 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2020-01-23T23:15:33.659Z,1579821333.659 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2020-01-23T23:15:34.882Z,1579821334.882 [Micromodem](INFO): Nmea in: $SNTTA,0.000194,,,,231533.79*47

2020-01-23T23:15:52.463Z,1579821352.463 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-23T23:16:27.603Z,1579821387.603 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-23T23:16:34.312Z,1579821394.312 [DefaultUnder:C.](INFO): *** querying acoustic contact ***
2020-01-23T23:16:35.058Z,1579821395.058 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2020-01-23T23:16:35.471Z,1579821395.471 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2020-01-23T23:16:36.296Z,1579821396.296 [Micromodem](INFO): Nmea in: $SNTTA,0.000221,,,,231635.50*44

2020-01-23T23:16:39.987Z,1579821399.987 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2020-01-23T23:16:39.987Z,1579821399.987 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-23T23:16:39.998Z,1579821399.998 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-23T23:16:40.391Z,1579821400.391 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-23T23:16:40.411Z,1579821400.411 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2020-01-23T23:17:02.759Z,1579821422.759 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-23T23:17:12.284Z,1579821432.284 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-01-23T23:17:12.284Z,1579821432.284 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-3278,-32768,V
2020-01-23T23:17:36.166Z,1579821456.166 [DefaultUnder:C.](INFO): *** querying acoustic contact ***
2020-01-23T23:17:36.930Z,1579821456.930 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2020-01-23T23:17:37.344Z,1579821457.344 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2020-01-23T23:17:37.903Z,1579821457.903 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-23T23:17:38.156Z,1579821458.156 [Micromodem](INFO): Nmea in: $SNTTA,0.000202,,,,231737.37*47

2020-01-23T23:18:03.644Z,1579821483.644 [RDI_Pathfinder](ERROR): Failed to parse:.00,   0.00,   0.00,   0.00,   0.00
2020-01-23T23:18:13.048Z,1579821493.048 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-23T23:18:37.965Z,1579821517.965 [DefaultUnder:C.](INFO): *** querying acoustic contact ***
2020-01-23T23:18:38.747Z,1579821518.747 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2020-01-23T23:18:39.152Z,1579821519.152 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2020-01-23T23:18:39.972Z,1579821519.972 [Micromodem](INFO): Nmea in: $SNTTA,0.000210,,,,231839.19*49

2020-01-23T23:18:48.187Z,1579821528.187 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-23T23:19:23.327Z,1579821563.327 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-23T23:19:39.802Z,1579821579.802 [DefaultUnder:C.](INFO): *** querying acoustic contact ***
2020-01-23T23:19:40.550Z,1579821580.550 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2020-01-23T23:19:40.963Z,1579821580.963 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2020-01-23T23:19:41.023Z,1579821581.023 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2020-01-23T23:19:41.023Z,1579821581.023 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-23T23:19:41.035Z,1579821581.035 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-23T23:19:41.440Z,1579821581.440 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-23T23:19:41.440Z,1579821581.440 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2020-01-23T23:19:41.780Z,1579821581.780 [Micromodem](INFO): Nmea in: $SNTTA,0.000230,,,,231940.99*4C

2020-01-23T23:19:57.202Z,1579821597.202 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-01-23T23:19:57.202Z,1579821597.202 [NAL9602] Data Fault, FailCount= 2
2020-01-23T23:19:57.202Z,1579821597.202 [NAL9602](ERROR): Data Fault
2020-01-23T23:19:57.303Z,1579821597.303 [CBIT](ERROR): Data Fault in component: NAL9602
2020-01-23T23:19:57.605Z,1579821597.605 [NAL9602](INFO): Powering down
2020-01-23T23:19:58.460Z,1579821598.460 [CBIT](INFO): Clearing failed state for component NAL9602
2020-01-23T23:19:58.460Z,1579821598.460 [NAL9602] No Fault, FailCount= 2
2020-01-23T23:19:58.491Z,1579821598.491 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-23T23:20:12.990Z,1579821612.990 [DefaultUnder:Notify:A:A:A.Wait](INFO): Done Waiting.
2020-01-23T23:20:12.990Z,1579821612.990 [DefaultUnder:Notify:A:A:A.Wait] Stopped
2020-01-23T23:20:12.990Z,1579821612.990 [DefaultUnder:Notify:A:A:A.Wait](DEBUG): Uninitialize Wait Component.
2020-01-23T23:20:12.990Z,1579821612.990 [DefaultUnder:Notify:A:A:B] Running Loop=1
2020-01-23T23:20:13.398Z,1579821613.398 [DefaultUnder:Notify:A:A:B] Stopped
2020-01-23T23:20:13.398Z,1579821613.398 [DefaultUnder:Notify:A:A:C] Running Loop=1
2020-01-23T23:20:13.805Z,1579821613.805 [DefaultUnder:Notify:A:A:C](IMPORTANT): Default mission has been running for 20.088881 min 
2020-01-23T23:20:13.805Z,1579821613.805 [DefaultUnder:Notify:A:A:C] Stopped
2020-01-23T23:20:13.806Z,1579821613.806 [DefaultUnder:Notify:A:A](INFO): Completed DefaultUnder:Notify:A:A
2020-01-23T23:20:13.806Z,1579821613.806 [DefaultUnder:Notify:A:A] Stopped
2020-01-23T23:20:13.806Z,1579821613.806 [DefaultUnder:Notify:A:A](DEBUG): Aggregate::uninitialize DefaultUnder:Notify:A:A
2020-01-23T23:20:13.806Z,1579821613.806 [DefaultUnder:Notify:A:A](INFO): Running loop #5
2020-01-23T23:20:13.806Z,1579821613.806 [DefaultUnder:Notify:A:A] Running Loop=5
2020-01-23T23:20:13.806Z,1579821613.806 [DefaultUnder:Notify:A:A](DEBUG): Aggregate::initialize DefaultUnder:Notify:A:A
2020-01-23T23:20:13.806Z,1579821613.806 [DefaultUnder:Notify:A:A:A.Wait] Running Loop=1
2020-01-23T23:20:13.806Z,1579821613.806 [DefaultUnder:Notify:A:A:A.Wait](DEBUG): Initialize Wait Component.
2020-01-23T23:20:16.180Z,1579821616.180 [DefaultUnder:uModemComms:CheckIn] Running Loop=1
2020-01-23T23:20:16.181Z,1579821616.181 [DefaultUnder:uModemComms:CheckIn](DEBUG): Aggregate::initialize DefaultUnder:uModemComms:CheckIn
2020-01-23T23:20:16.181Z,1579821616.181 [DefaultUnder:uModemComms:CheckIn:A] Running Loop=1
2020-01-23T23:20:16.577Z,1579821616.577 [Micromodem](INFO): Outgoing frame #1, 47 bytes: 10252A5E0D0000005D00000400140000000000000000151D80030C5669BF92DB1A4DB4C2030A247597758DE2620000
2020-01-23T23:20:16.577Z,1579821616.577 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,1*6B

2020-01-23T23:20:16.961Z,1579821616.961 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,1*69

2020-01-23T23:20:17.366Z,1579821617.366 [Micromodem](INFO): Nmea in: $CADRQ,232016,1,12,0,64,1*70

2020-01-23T23:20:17.366Z,1579821617.366 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,10252A5E0D0000005D00000400140000000000000000151D80030C5669BF92DB1A4DB4C2030A247597758DE2620000*34

2020-01-23T23:20:17.777Z,1579821617.777 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,47*4A

2020-01-23T23:20:18.181Z,1579821618.181 [Micromodem](INFO): Nmea in: $CATXP,47*71

2020-01-23T23:20:18.723Z,1579821618.723 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout
2020-01-23T23:20:18.723Z,1579821618.723 [RDI_Pathfinder] Communications Fault, FailCount= 1
2020-01-23T23:20:18.723Z,1579821618.723 [RDI_Pathfinder](ERROR): Communications Fault
2020-01-23T23:20:18.723Z,1579821618.723 [RDI_Pathfinder](ERROR): Failed to parse:
2020-01-23T23:20:18.777Z,1579821618.777 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-01-23T23:20:19.067Z,1579821619.067 [RDI_Pathfinder](INFO): Powering down
2020-01-23T23:20:19.386Z,1579821619.386 [Micromodem](INFO): Nmea in: $CATXF,47*67

2020-01-23T23:20:19.802Z,1579821619.802 [Micromodem](INFO): Nmea in: $CAXST,6,20200123,232017.813340,3,0,200,4000,10000,1,1,12,1,1,1,3,47*4B

2020-01-23T23:20:19.834Z,1579821619.834 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-01-23T23:20:19.834Z,1579821619.834 [RDI_Pathfinder] No Fault, FailCount= 1
2020-01-23T23:20:21.416Z,1579821621.416 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-23T23:20:21.417Z,1579821621.417 [Micromodem](INFO): Sent 39 bytes from file Logs/20200123T225824/Courier0013.lzma.parts
2020-01-23T23:20:21.417Z,1579821621.417 [Micromodem](INFO): Packets left to send: 0
2020-01-23T23:20:21.813Z,1579821621.813 [Micromodem](INFO): Nmea in: $CACST,6,0,20200123232020.529602,06,1859,32,0131,0150,29,02,02,00,02,1,012,001,0,4,1,0,150,34.1,10.00,26,-7.00,-01,-0.02,15,10000,4000*40

2020-01-23T23:20:21.846Z,1579821621.846 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 10252A5E0E0000005D00000400BD0000000000000000151D80030C5669C055D3E1FE250B6AC83349B610E7F8D61E5851675A38655AF8A72394F3E3597DCA4B1D
2020-01-23T23:20:21.852Z,1579821621.852 [Micromodem](INFO): Outgoing frame #2, 64 bytes: 6044480858C46A1D687DB821DD0FE9E4670E540588F40C8BF91D647AA8E2503F8E6BDF07A9246FB9FAB6318C89693A53D773EEA6D39D1E8D6C9F1819911C8E51
2020-01-23T23:20:21.852Z,1579821621.852 [Micromodem](INFO): Outgoing frame #3, 30 bytes: A930601F0D011CEB14B5A13865126E50E4B41FF7E0DEC1183BE4A17BC7A6
2020-01-23T23:20:21.853Z,1579821621.853 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69

2020-01-23T23:20:23.030Z,1579821623.030 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B

2020-01-23T23:20:23.429Z,1579821623.429 [Micromodem](INFO): Nmea in: $CADRQ,232022,1,12,0,64,1*77

2020-01-23T23:20:23.430Z,1579821623.430 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,10252A5E0E0000005D00000400BD0000000000000000151D80030C5669C055D3E1FE250B6AC83349B610E7F8D61E5851675A38655AF8A72394F3E3597DCA4B1D*4C

2020-01-23T23:20:23.833Z,1579821623.833 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-23T23:20:24.237Z,1579821624.237 [Micromodem](INFO): Nmea in: $CADRQ,232023,1,12,0,64,2*75

2020-01-23T23:20:24.238Z,1579821624.238 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,6044480858C46A1D687DB821DD0FE9E4670E540588F40C8BF91D647AA8E2503F8E6BDF07A9246FB9FAB6318C89693A53D773EEA6D39D1E8D6C9F1819911C8E51*46

2020-01-23T23:20:24.646Z,1579821624.646 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-23T23:20:25.050Z,1579821625.050 [Micromodem](INFO): Nmea in: $CADRQ,232024,1,12,0,64,3*73

2020-01-23T23:20:25.050Z,1579821625.050 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,A930601F0D011CEB14B5A13865126E50E4B41FF7E0DEC1183BE4A17BC7A6*36

2020-01-23T23:20:25.449Z,1579821625.449 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,30*4A

2020-01-23T23:20:25.857Z,1579821625.857 [Micromodem](INFO): Nmea in: $CATXP,158*4E

2020-01-23T23:20:27.880Z,1579821627.880 [NAL9602](INFO): Powering up NAL9602
2020-01-23T23:20:29.494Z,1579821629.494 [Micromodem](INFO): Nmea in: $CATXF,158*58

2020-01-23T23:20:29.894Z,1579821629.894 [Micromodem](INFO): Nmea in: $CAXST,6,20200123,232025.499516,3,0,200,4000,10000,1,1,12,1,3,3,3,158*7E

2020-01-23T23:20:32.727Z,1579821632.727 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-23T23:20:33.125Z,1579821633.125 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-23T23:20:33.155Z,1579821633.155 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2020-01-23T23:20:33.534Z,1579821633.534 [Micromodem](INFO): Nmea in: $CAACK,12,1,3,1*7B

2020-01-23T23:20:33.535Z,1579821633.535 [Micromodem](INFO): Sent 150 bytes from file Logs/20200123T225824/Express0014.lzma.parts
2020-01-23T23:20:33.535Z,1579821633.535 [Micromodem](INFO): Packets left to send: 0
2020-01-23T23:20:33.631Z,1579821633.631 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-23T23:20:33.937Z,1579821633.937 [Micromodem](INFO): Nmea in: $CACST,6,0,20200123232031.864366,06,2151,33,0117,0150,37,02,02,00,04,1,012,001,0,4,1,0,150,34.2,8.00,22,-5.00,-01,-0.01,16,10000,4000*71

2020-01-23T23:20:33.997Z,1579821633.997 [DefaultUnder:uModemComms:CheckIn:A] Stopped
2020-01-23T23:20:33.998Z,1579821633.998 [DefaultUnder:uModemComms:CheckIn](INFO): Completed DefaultUnder:uModemComms:CheckIn
2020-01-23T23:20:33.998Z,1579821633.998 [DefaultUnder:uModemComms:CheckIn] Stopped
2020-01-23T23:20:33.998Z,1579821633.998 [DefaultUnder:uModemComms:CheckIn](DEBUG): Aggregate::uninitialize DefaultUnder:uModemComms:CheckIn
2020-01-23T23:20:38.786Z,1579821638.786 [NAL9602](INFO): NAL9602 initialized
2020-01-23T23:20:41.653Z,1579821641.653 [DefaultUnder:C.](INFO): *** querying acoustic contact ***
2020-01-23T23:20:42.436Z,1579821642.436 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2020-01-23T23:20:42.819Z,1579821642.819 [Micromodem](INFO): Nmea in: $CREV,232042,AUV,2.0.32731*E
$ARE,232042,COPROC,0.10.0.46*45

2020-01-23T23:20:42.819Z,1579821642.819 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CREV,232042,AUV,2.0.32731*E
$ARE,232042,COPROC,0.10.0.46*45
 expected 5F
2020-01-23T23:20:42.820Z,1579821642.820 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2020-01-23T23:20:42.848Z,1579821642.848 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2020-01-23T23:20:43.228Z,1579821643.228 [Micromodem](INFO): Nmea in: $CAERR,232043,NI  ,29,Busy completing a previous request*7F

2020-01-23T23:20:43.645Z,1579821643.645 [Micromodem](INFO): Nmea in: $SNTTA,0.000209,,,,232042.87*41

2020-01-23T23:21:08.771Z,1579821668.771 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-23T23:21:43.479Z,1579821703.479 [DefaultUnder:C.](INFO): *** querying acoustic contact ***
2020-01-23T23:21:43.915Z,1579821703.915 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-23T23:21:44.235Z,1579821704.235 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2020-01-23T23:21:44.631Z,1579821704.631 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2020-01-23T23:21:45.460Z,1579821705.460 [Micromodem](INFO): Nmea in: $SNTTA,0.000216,,,,232144.68*49

2020-01-23T23:22:19.055Z,1579821739.055 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-23T23:22:42.075Z,1579821762.075 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2020-01-23T23:22:42.075Z,1579821762.075 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-23T23:22:42.086Z,1579821762.086 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-23T23:22:42.504Z,1579821762.504 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-23T23:22:42.504Z,1579821762.504 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2020-01-23T23:22:45.322Z,1579821765.322 [DefaultUnder:C.](INFO): *** querying acoustic contact ***
2020-01-23T23:22:46.102Z,1579821766.102 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2020-01-23T23:22:46.499Z,1579821766.499 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2020-01-23T23:22:47.312Z,1579821767.312 [Micromodem](INFO): Nmea in: $SNTTA,0.000202,,,,232246.54*42

2020-01-23T23:22:54.203Z,1579821774.203 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-23T23:23:29.343Z,1579821809.343 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-23T23:23:47.156Z,1579821827.156 [DefaultUnder:C.](INFO): *** querying acoustic contact ***
2020-01-23T23:23:47.894Z,1579821827.894 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2020-01-23T23:23:48.307Z,1579821828.307 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2020-01-23T23:23:49.143Z,1579821829.143 [Micromodem](INFO): Nmea in: $SNTTA,0.000213,,,,232348.34*4B

2020-01-23T23:24:04.483Z,1579821844.483 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-23T23:24:39.625Z,1579821879.625 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-23T23:24:48.953Z,1579821888.953 [DefaultUnder:C.](INFO): *** querying acoustic contact ***
2020-01-23T23:24:49.710Z,1579821889.710 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2020-01-23T23:24:50.115Z,1579821890.115 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2020-01-23T23:24:50.944Z,1579821890.944 [Micromodem](INFO): Nmea in: $SNTTA,0.000211,,,,232450.15*44

2020-01-23T23:25:14.462Z,1579821914.462 [DefaultUnder:Notify:A:A:A.Wait](INFO): Done Waiting.
2020-01-23T23:25:14.462Z,1579821914.462 [DefaultUnder:Notify:A:A:A.Wait] Stopped
2020-01-23T23:25:14.462Z,1579821914.462 [DefaultUnder:Notify:A:A:A.Wait](DEBUG): Uninitialize Wait Component.
2020-01-23T23:25:14.463Z,1579821914.463 [DefaultUnder:Notify:A:A:B] Running Loop=1
2020-01-23T23:25:14.767Z,1579821914.767 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-23T23:25:14.866Z,1579821914.866 [DefaultUnder:Notify:A:A:B] Stopped
2020-01-23T23:25:14.866Z,1579821914.866 [DefaultUnder:Notify:A:A:C] Running Loop=1
2020-01-23T23:25:15.384Z,1579821915.384 [DefaultUnder:Notify:A:A:C](IMPORTANT): Default mission has been running for 25.113348 min 
2020-01-23T23:25:15.384Z,1579821915.384 [DefaultUnder:Notify:A:A:C] Stopped
2020-01-23T23:25:15.384Z,1579821915.384 [DefaultUnder:Notify:A:A](INFO): Completed DefaultUnder:Notify:A:A
2020-01-23T23:25:15.384Z,1579821915.384 [DefaultUnder:Notify:A:A] Stopped
2020-01-23T23:25:15.384Z,1579821915.384 [DefaultUnder:Notify:A:A](DEBUG): Aggregate::uninitialize DefaultUnder:Notify:A:A
2020-01-23T23:25:15.385Z,1579821915.385 [DefaultUnder:Notify:A:A](INFO): Running loop #6
2020-01-23T23:25:15.385Z,1579821915.385 [DefaultUnder:Notify:A:A] Running Loop=6
2020-01-23T23:25:15.385Z,1579821915.385 [DefaultUnder:Notify:A:A](DEBUG): Aggregate::initialize DefaultUnder:Notify:A:A
2020-01-23T23:25:15.385Z,1579821915.385 [DefaultUnder:Notify:A:A:A.Wait] Running Loop=1
2020-01-23T23:25:15.385Z,1579821915.385 [DefaultUnder:Notify:A:A:A.Wait](DEBUG): Initialize Wait Component.
2020-01-23T23:25:33.452Z,1579821933.452 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2020-01-23T23:25:34.260Z,1579821934.260 [DefaultUnder:uModemComms:CheckIn] Running Loop=1
2020-01-23T23:25:34.260Z,1579821934.260 [DefaultUnder:uModemComms:CheckIn](DEBUG): Aggregate::initialize DefaultUnder:uModemComms:CheckIn
2020-01-23T23:25:34.260Z,1579821934.260 [DefaultUnder:uModemComms:CheckIn:A] Running Loop=1
2020-01-23T23:25:34.632Z,1579821934.632 [Micromodem](INFO): Outgoing frame #1, 47 bytes: 10252A5E100000005D00000400140000000000000000151D80030C5669BF92DB1A84D5CBC6A301158A3C8E9CE6E000
2020-01-23T23:25:34.633Z,1579821934.633 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,1*6B

2020-01-23T23:25:35.029Z,1579821935.029 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,1*69

2020-01-23T23:25:35.440Z,1579821935.440 [Micromodem](INFO): Nmea in: $CADRQ,232534,1,12,0,64,1*75

2020-01-23T23:25:35.440Z,1579821935.440 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,10252A5E100000005D00000400140000000000000000151D80030C5669BF92DB1A84D5CBC6A301158A3C8E9CE6E000*35

2020-01-23T23:25:35.845Z,1579821935.845 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,47*4A

2020-01-23T23:25:36.237Z,1579821936.237 [Micromodem](INFO): Nmea in: $CATXP,47*71

2020-01-23T23:25:37.453Z,1579821937.453 [Micromodem](INFO): Nmea in: $CATXF,47*67

2020-01-23T23:25:37.867Z,1579821937.867 [Micromodem](INFO): Nmea in: $CAXST,6,20200123,232535.890006,3,0,200,4000,10000,1,1,12,1,1,1,3,47*44

2020-01-23T23:25:39.473Z,1579821939.473 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-23T23:25:39.474Z,1579821939.474 [Micromodem](INFO): Sent 39 bytes from file Logs/20200123T225824/Courier0016.lzma.parts
2020-01-23T23:25:39.474Z,1579821939.474 [Micromodem](INFO): Packets left to send: 0
2020-01-23T23:25:39.881Z,1579821939.881 [Micromodem](INFO): Nmea in: $CACST,6,0,20200123232538.607765,06,1985,32,0156,0150,29,02,02,00,02,1,012,001,0,4,1,0,150,24.7,10.00,23,-7.00,-01,-0.05,22,10000,4000*43

2020-01-23T23:25:39.901Z,1579821939.901 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 10252A5E110000005D00000400510000000000000000151D80030C5669C055D3E20D32418C3790849D57F4ABFC64D28E6A65081F09990FCE1AA4C8466215EAC9
2020-01-23T23:25:39.902Z,1579821939.902 [Micromodem](INFO): Outgoing frame #2, 41 bytes: A323B3A1D0AAB01B4BDD88E0D810BCC8EA9E7A7BC2D19899B668E5C9CA77875C6DC0E3055DBBEFE3E1
2020-01-23T23:25:39.902Z,1579821939.902 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,2*68

2020-01-23T23:25:41.089Z,1579821941.089 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,2*6A

2020-01-23T23:25:41.493Z,1579821941.493 [Micromodem](INFO): Nmea in: $CADRQ,232540,1,12,0,64,1*76

2020-01-23T23:25:41.494Z,1579821941.494 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,10252A5E110000005D00000400510000000000000000151D80030C5669C055D3E20D32418C3790849D57F4ABFC64D28E6A65081F09990FCE1AA4C8466215EAC9*31

2020-01-23T23:25:41.897Z,1579821941.897 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-23T23:25:42.297Z,1579821942.297 [Micromodem](INFO): Nmea in: $CADRQ,232541,1,12,0,64,2*74

2020-01-23T23:25:42.298Z,1579821942.298 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,A323B3A1D0AAB01B4BDD88E0D810BCC8EA9E7A7BC2D19899B668E5C9CA77875C6DC0E3055DBBEFE3E1*3F

2020-01-23T23:25:42.707Z,1579821942.707 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,41*4C

2020-01-23T23:25:43.109Z,1579821943.109 [Micromodem](INFO): Nmea in: $CATXP,105*46

2020-01-23T23:25:43.154Z,1579821943.154 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2020-01-23T23:25:43.154Z,1579821943.154 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-23T23:25:43.174Z,1579821943.174 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-23T23:25:43.550Z,1579821943.550 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-23T23:25:43.550Z,1579821943.550 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2020-01-23T23:25:45.533Z,1579821945.533 [Micromodem](INFO): Nmea in: $CATXF,105*50

2020-01-23T23:25:45.934Z,1579821945.934 [Micromodem](INFO): Nmea in: $CAXST,6,20200123,232542.748166,3,0,200,4000,10000,1,1,12,1,2,2,3,105*7E

2020-01-23T23:25:47.966Z,1579821947.966 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-23T23:25:48.358Z,1579821948.358 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-23T23:25:48.358Z,1579821948.358 [Micromodem](INFO): Sent 97 bytes from file Logs/20200123T225824/Express0017.lzma.parts
2020-01-23T23:25:48.358Z,1579821948.358 [Micromodem](INFO): Packets left to send: 0
2020-01-23T23:25:48.765Z,1579821948.765 [Micromodem](INFO): Nmea in: $CACST,6,0,20200123232547.299787,06,1762,32,0101,0150,29,02,02,00,02,1,012,001,0,4,1,0,150,34.5,8.00,21,-5.00,-01,0.01,17,10000,4000*54

2020-01-23T23:25:48.825Z,1579821948.825 [DefaultUnder:uModemComms:CheckIn:A] Stopped
2020-01-23T23:25:48.825Z,1579821948.825 [DefaultUnder:uModemComms:CheckIn](INFO): Completed DefaultUnder:uModemComms:CheckIn
2020-01-23T23:25:48.825Z,1579821948.825 [DefaultUnder:uModemComms:CheckIn] Stopped
2020-01-23T23:25:48.826Z,1579821948.826 [DefaultUnder:uModemComms:CheckIn](DEBUG): Aggregate::uninitialize DefaultUnder:uModemComms:CheckIn
2020-01-23T23:25:49.907Z,1579821949.907 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-23T23:25:50.826Z,1579821950.826 [DefaultUnder:C.](INFO): *** querying acoustic contact ***
2020-01-23T23:25:51.594Z,1579821951.594 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2020-01-23T23:25:51.995Z,1579821951.995 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2020-01-23T23:25:52.822Z,1579821952.822 [Micromodem](INFO): Nmea in: $SNTTA,0.000204,,,,232552.03*44

2020-01-23T23:26:13.468Z,1579821973.468 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2020-01-23T23:26:25.052Z,1579821985.052 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-23T23:26:38.108Z,1579821998.108 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-01-23T23:26:38.108Z,1579821998.108 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +0.00,     ,        +0.00,   0.00,364.99
2020-01-23T23:26:52.633Z,1579822012.633 [DefaultUnder:C.](INFO): *** querying acoustic contact ***
2020-01-23T23:26:53.402Z,1579822013.402 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2020-01-23T23:26:53.812Z,1579822013.812 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2020-01-23T23:26:54.632Z,1579822014.632 [Micromodem](INFO): Nmea in: $SNTTA,0.000213,,,,232653.84*4F

2020-01-23T23:27:00.191Z,1579822020.191 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-23T23:27:35.339Z,1579822055.339 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-23T23:27:54.502Z,1579822074.502 [DefaultUnder:C.](INFO): *** querying acoustic contact ***
2020-01-23T23:27:55.258Z,1579822075.258 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2020-01-23T23:27:55.663Z,1579822075.663 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2020-01-23T23:27:56.487Z,1579822076.487 [Micromodem](INFO): Nmea in: $SNTTA,0.000193,,,,232755.70*48

2020-01-23T23:28:10.487Z,1579822090.487 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-01-23T23:28:16.197Z,1579822096.197 [CommandLine](IMPORTANT): got command get depth
2020-01-23T23:28:16.197Z,1579822096.197 [CommandLine](IMPORTANT): depth 0.168807 m
2020-01-23T23:28:21.525Z,1579822101.525 [CommandLine](IMPORTANT): got command quit
2020-01-23T23:28:22.531Z,1579822102.531 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2020-01-23T23:28:22.531Z,1579822102.531 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-23T23:28:22.532Z,1579822102.532 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-23T23:28:22.619Z,1579822102.619 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-01-23T23:28:22.619Z,1579822102.619 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-23T23:28:22.620Z,1579822102.620 [CommandLine](INFO): Join timeout helper Thread ID is 1314
2020-01-23T23:28:22.620Z,1579822102.620 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-01-23T23:28:22.620Z,1579822102.620 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-23T23:28:22.621Z,1579822102.621 [NavChartDb](INFO): Join timeout helper Thread ID is 1315
2020-01-23T23:28:22.779Z,1579822102.779 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-23T23:28:22.779Z,1579822102.779 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-23T23:28:22.787Z,1579822102.787 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2020-01-23T23:28:22.787Z,1579822102.787 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-23T23:28:22.787Z,1579822102.787 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 1316
2020-01-23T23:28:22.827Z,1579822102.827 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-23T23:28:22.827Z,1579822102.827 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-01-23T23:28:22.828Z,1579822102.828 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-23T23:28:22.831Z,1579822102.831 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2020-01-23T23:28:22.831Z,1579822102.831 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-23T23:28:22.832Z,1579822102.832 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 1317
2020-01-23T23:28:22.875Z,1579822102.875 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-23T23:28:22.875Z,1579822102.875 [CTD_NeilBrown](INFO): Powering down
2020-01-23T23:28:22.887Z,1579822102.887 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-23T23:28:22.903Z,1579822102.903 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-01-23T23:28:22.903Z,1579822102.903 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-23T23:28:22.903Z,1579822102.903 [Radio_Surface](INFO): Join timeout helper Thread ID is 1318
2020-01-23T23:28:23.263Z,1579822103.263 [Radio_Surface](INFO): Powering down
2020-01-23T23:28:23.264Z,1579822103.264 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-23T23:28:23.264Z,1579822103.264 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-23T23:28:23.272Z,1579822103.272 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-01-23T23:28:23.272Z,1579822103.272 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-23T23:28:23.272Z,1579822103.272 [DataOverHttps](INFO): Join timeout helper Thread ID is 1319
2020-01-23T23:28:23.540Z,1579822103.540 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-23T23:28:23.540Z,1579822103.540 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-23T23:28:23.553Z,1579822103.553 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-01-23T23:28:23.553Z,1579822103.553 [logger ThreadHandler](INFO): Thread cancelled.
2020-01-23T23:28:23.553Z,1579822103.553 [logger](INFO): Join timeout helper Thread ID is 1320
2020-01-23T23:28:23.595Z,1579822103.595 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-23T23:28:23.595Z,1579822103.595 [logger ThreadHandler](INFO): Thread cancelled.
2020-01-23T23:28:23.597Z,1579822103.597 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-01-23T23:28:23.597Z,1579822103.597 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-23T23:28:23.597Z,1579822103.597 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-01-23T23:28:23.597Z,1579822103.597 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-01-23T23:28:23.597Z,1579822103.597 [controlThread](INFO): Join timeout helper Thread ID is 1321
2020-01-23T23:28:23.612Z,1579822103.612 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-23T23:28:23.612Z,1579822103.612 [controlThread](DEBUG): Uninitializing ControlThread
2020-01-23T23:28:23.612Z,1579822103.612 [AHRS_M2](INFO): Powering down
2020-01-23T23:28:23.683Z,1579822103.683 [DDM](INFO): Powering down
2020-01-23T23:28:23.776Z,1579822103.776 [DUSBL_Hydroid](INFO): Powering down
2020-01-23T23:28:23.847Z,1579822103.847 [Micromodem](INFO): Powering down
2020-01-23T23:28:23.943Z,1579822103.943 [NAL9602](INFO): Powering down
2020-01-23T23:28:24.015Z,1579822104.015 [RDI_Pathfinder](INFO): Powering down
2020-01-23T23:28:24.017Z,1579822104.017 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-01-23T23:28:24.017Z,1579822104.017 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-01-23T23:28:24.018Z,1579822104.018 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-01-23T23:28:24.018Z,1579822104.018 [MissionManager](INFO): Uninitializing Mission DefaultUnder
2020-01-23T23:28:24.018Z,1579822104.018 [DefaultUnder] Stopped
2020-01-23T23:28:24.019Z,1579822104.019 [DefaultUnder](DEBUG): Aggregate::uninitialize DefaultUnder
2020-01-23T23:28:24.019Z,1579822104.019 [DefaultUnder:uModemComms] Stopped
2020-01-23T23:28:24.019Z,1579822104.019 [DefaultUnder:uModemComms](DEBUG): Aggregate::uninitialize DefaultUnder:uModemComms
2020-01-23T23:28:24.019Z,1579822104.019 [DefaultUnder:Notify] Stopped
2020-01-23T23:28:24.019Z,1579822104.019 [DefaultUnder:Notify](DEBUG): Aggregate::uninitialize DefaultUnder:Notify
2020-01-23T23:28:24.019Z,1579822104.019 [DefaultUnder:Notify:A] Stopped
2020-01-23T23:28:24.019Z,1579822104.019 [DefaultUnder:Notify:A](DEBUG): Aggregate::uninitialize DefaultUnder:Notify:A
2020-01-23T23:28:24.019Z,1579822104.019 [DefaultUnder:Notify:A:A] Stopped
2020-01-23T23:28:24.019Z,1579822104.019 [DefaultUnder:Notify:A:A](DEBUG): Aggregate::uninitialize DefaultUnder:Notify:A:A
2020-01-23T23:28:24.019Z,1579822104.019 [DefaultUnder:Notify:A:A:A.Wait] Stopped
2020-01-23T23:28:24.019Z,1579822104.019 [DefaultUnder:Notify:A:A:A.Wait](DEBUG): Uninitialize Wait Component.
2020-01-23T23:28:24.020Z,1579822104.020 [DefaultUnder:C.] Stopped
2020-01-23T23:28:24.020Z,1579822104.020 [DefaultUnder:Default] Stopped
2020-01-23T23:28:24.020Z,1579822104.020 [DefaultUnder:Default](DEBUG): Aggregate::uninitialize DefaultUnder:Default
2020-01-23T23:28:24.020Z,1579822104.020 [DefaultUnder:Default:Docked] Stopped
2020-01-23T23:28:24.020Z,1579822104.020 [DefaultUnder:Default:Docked](DEBUG): Aggregate::uninitialize DefaultUnder:Default:Docked
2020-01-23T23:28:24.020Z,1579822104.020 [DefaultUnder:Default:Docked:run] Stopped
2020-01-23T23:28:24.020Z,1579822104.020 [DefaultUnder:Default:Docked:run](DEBUG): Aggregate::uninitialize DefaultUnder:Default:Docked:run
2020-01-23T23:28:24.020Z,1579822104.020 [DefaultUnder:Default:Docked:run:B.Docked] Stopped
2020-01-23T23:28:24.049Z,1579822104.049 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-01-23T23:28:24.049Z,1579822104.049 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-01-23T23:28:24.049Z,1579822104.049 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-01-23T23:28:24.049Z,1579822104.049 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-01-23T23:28:24.050Z,1579822104.050 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-01-23T23:28:24.050Z,1579822104.050 [BuoyancyServo](INFO): Powering down
2020-01-23T23:28:24.063Z,1579822104.063 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-01-23T23:28:24.063Z,1579822104.063 [ElevatorServo](INFO): Powering down
2020-01-23T23:28:24.064Z,1579822104.064 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-01-23T23:28:24.064Z,1579822104.064 [MassServo](INFO): Powering down
2020-01-23T23:28:24.065Z,1579822104.065 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-01-23T23:28:24.065Z,1579822104.065 [RudderServo](INFO): Powering down
2020-01-23T23:28:24.066Z,1579822104.066 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-01-23T23:28:24.066Z,1579822104.066 [ThrusterServo](INFO): Powering down
2020-01-23T23:28:24.066Z,1579822104.066 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-01-23T23:28:24.067Z,1579822104.067 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-01-23T23:28:24.067Z,1579822104.067 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-01-23T23:28:24.067Z,1579822104.067 [CBIT](DEBUG): Powering off loads.
2020-01-23T23:28:24.079Z,1579822104.079 [CBIT](DEBUG): Disabling WDT.
2020-01-23T23:28:24.091Z,1579822104.091 [CBIT](DEBUG): Opening all GF detection circuits.
2020-01-23T23:28:24.092Z,1579822104.092 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-01-23T23:28:24.151Z,1579822104.151 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-23T23:28:24.162Z,1579822104.162 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-23T23:28:24.205Z,1579822104.205 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-23T23:28:24.208Z,1579822104.208 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-23T23:28:24.270Z,1579822104.270 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-23T23:28:24.333Z,1579822104.333 [logger ThreadHandler](INFO): Thread cancelled.