2020-01-13T16:58:02.459Z,1578934682.459 [Supervisor](DEBUG): Initializing supervisor.
2020-01-13T16:58:02.461Z,1578934682.461 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-01-13T16:58:02.462Z,1578934682.462 [SyncHandler](INFO): Protected caller Thread ID is 7659
2020-01-13T16:58:02.462Z,1578934682.462 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-01-13T16:58:02.463Z,1578934682.463 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-01-13T16:58:02.463Z,1578934682.463 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 7660
2020-01-13T16:58:02.466Z,1578934682.466 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-01-13T16:58:02.477Z,1578934682.477 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-01-13T16:58:02.478Z,1578934682.478 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-01-13T16:58:02.478Z,1578934682.478 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 7661
2020-01-13T16:58:02.479Z,1578934682.479 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-01-13T16:58:02.480Z,1578934682.480 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-01-13T16:58:02.480Z,1578934682.480 [logger ThreadHandler](INFO): Protected caller Thread ID is 7662
2020-01-13T16:58:02.482Z,1578934682.482 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-01-13T16:58:02.482Z,1578934682.482 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-01-13T16:58:02.484Z,1578934682.484 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-01-13T16:58:02.908Z,1578934682.908 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-01-13T16:58:02.909Z,1578934682.909 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-01-13T16:58:03.003Z,1578934683.003 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-01-13T16:58:03.003Z,1578934683.003 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-01-13T16:58:03.306Z,1578934683.306 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-01-13T16:58:03.306Z,1578934683.306 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-01-13T16:58:03.440Z,1578934683.440 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-01-13T16:58:03.440Z,1578934683.440 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-01-13T16:58:03.623Z,1578934683.623 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-01-13T16:58:03.624Z,1578934683.624 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-01-13T16:58:04.057Z,1578934684.057 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-01-13T16:58:04.058Z,1578934684.058 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-01-13T16:58:04.263Z,1578934684.263 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-01-13T16:58:04.264Z,1578934684.264 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-01-13T16:58:04.476Z,1578934684.476 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-01-13T16:58:04.477Z,1578934684.477 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-01-13T16:58:04.656Z,1578934684.656 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-01-13T16:58:04.656Z,1578934684.656 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-01-13T16:58:04.749Z,1578934684.749 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-01-13T16:58:04.750Z,1578934684.750 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-01-13T16:58:05.038Z,1578934685.038 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-01-13T16:58:05.039Z,1578934685.039 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-01-13T16:58:05.117Z,1578934685.117 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-01-13T16:58:05.217Z,1578934685.217 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-01-13T16:58:05.218Z,1578934685.218 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-01-13T16:58:05.834Z,1578934685.834 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-01-13T16:58:05.834Z,1578934685.834 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-01-13T16:58:06.231Z,1578934686.231 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-01-13T16:58:06.233Z,1578934686.233 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2020-01-13T16:58:06.234Z,1578934686.234 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2020-01-13T16:58:06.431Z,1578934686.431 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2020-01-13T16:58:06.532Z,1578934686.532 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2020-01-13T16:58:06.626Z,1578934686.626 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2020-01-13T16:58:06.847Z,1578934686.847 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-01-13T16:58:06.848Z,1578934686.848 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2020-01-13T16:58:06.929Z,1578934686.929 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2020-01-13T16:58:07.020Z,1578934687.020 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2020-01-13T16:58:07.118Z,1578934687.118 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2020-01-13T16:58:07.217Z,1578934687.217 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2020-01-13T16:58:07.560Z,1578934687.560 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2020-01-13T16:58:07.904Z,1578934687.904 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2020-01-13T16:58:08.207Z,1578934688.207 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/
2020-01-13T16:58:08.208Z,1578934688.208 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-01-13T16:58:08.228Z,1578934688.228 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-01-13T16:58:08.509Z,1578934688.509 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-01-13T16:58:08.514Z,1578934688.514 [AHRS_M2](INFO): created writer for : platform_orientation
2020-01-13T16:58:08.516Z,1578934688.516 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-01-13T16:58:08.521Z,1578934688.521 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-01-13T16:58:08.522Z,1578934688.522 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-01-13T16:58:08.526Z,1578934688.526 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-01-13T16:58:08.527Z,1578934688.527 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-01-13T16:58:08.532Z,1578934688.532 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-01-13T16:58:08.599Z,1578934688.599 [AHRS_M2] Loaded
2020-01-13T16:58:08.599Z,1578934688.599 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-01-13T16:58:08.676Z,1578934688.676 [DataOverHttps] Loaded
2020-01-13T16:58:08.676Z,1578934688.676 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-01-13T16:58:08.677Z,1578934688.677 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4076F4E0
2020-01-13T16:58:08.677Z,1578934688.677 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 7742
2020-01-13T16:58:08.700Z,1578934688.700 [DDM] Loaded
2020-01-13T16:58:08.700Z,1578934688.700 [ComponentRegistry](DEBUG): SyncComponent "DDM" handled in the control thread.
2020-01-13T16:58:08.713Z,1578934688.713 [Depth_Keller] Loaded
2020-01-13T16:58:08.713Z,1578934688.713 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-01-13T16:58:08.718Z,1578934688.718 [DropWeight] Loaded
2020-01-13T16:58:08.718Z,1578934688.718 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2020-01-13T16:58:08.776Z,1578934688.776 [DUSBL_Hydroid] Loaded
2020-01-13T16:58:08.777Z,1578934688.777 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2020-01-13T16:58:08.822Z,1578934688.822 [Micromodem] Loaded
2020-01-13T16:58:08.822Z,1578934688.822 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2020-01-13T16:58:08.915Z,1578934688.915 [NAL9602] Loaded
2020-01-13T16:58:08.915Z,1578934688.915 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-01-13T16:58:08.951Z,1578934688.951 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/voltage_now
2020-01-13T16:58:08.952Z,1578934688.952 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/current_now
2020-01-13T16:58:08.952Z,1578934688.952 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_now
2020-01-13T16:58:08.953Z,1578934688.953 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_full
2020-01-13T16:58:08.953Z,1578934688.953 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/voltage_now
2020-01-13T16:58:08.954Z,1578934688.954 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/current_now
2020-01-13T16:58:08.954Z,1578934688.954 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_now
2020-01-13T16:58:08.955Z,1578934688.955 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_full
2020-01-13T16:58:08.955Z,1578934688.955 [Onboard] Loaded
2020-01-13T16:58:08.955Z,1578934688.955 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2020-01-13T16:58:08.961Z,1578934688.961 [PowerOnly] Loaded
2020-01-13T16:58:08.961Z,1578934688.961 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2020-01-13T16:58:08.967Z,1578934688.967 [Radio_Surface] Loaded
2020-01-13T16:58:08.967Z,1578934688.967 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-01-13T16:58:08.968Z,1578934688.968 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4079F4E0
2020-01-13T16:58:08.969Z,1578934688.969 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 7743
2020-01-13T16:58:09.011Z,1578934689.011 [RDI_Pathfinder] Loaded
2020-01-13T16:58:09.011Z,1578934689.011 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2020-01-13T16:58:10.440Z,1578934690.440 [BPC1] Loaded
2020-01-13T16:58:10.440Z,1578934690.440 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-01-13T16:58:10.440Z,1578934690.440 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-01-13T16:58:10.441Z,1578934690.441 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-01-13T16:58:10.454Z,1578934690.454 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-01-13T16:58:10.454Z,1578934690.454 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-01-13T16:58:10.555Z,1578934690.555 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-01-13T16:58:10.556Z,1578934690.556 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-01-13T16:58:10.575Z,1578934690.575 [NavChart] Loaded
2020-01-13T16:58:10.575Z,1578934690.575 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-01-13T16:58:10.579Z,1578934690.579 [UniversalFixResidualReporter] Loaded
2020-01-13T16:58:10.579Z,1578934690.579 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-01-13T16:58:10.580Z,1578934690.580 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-01-13T16:58:10.580Z,1578934690.580 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-01-13T16:58:10.684Z,1578934690.684 [BuoyancyServo] Loaded
2020-01-13T16:58:10.685Z,1578934690.685 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-01-13T16:58:10.699Z,1578934690.699 [ElevatorServo] Loaded
2020-01-13T16:58:10.700Z,1578934690.700 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-01-13T16:58:10.714Z,1578934690.714 [MassServo] Loaded
2020-01-13T16:58:10.714Z,1578934690.714 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-01-13T16:58:10.729Z,1578934690.729 [RudderServo] Loaded
2020-01-13T16:58:10.729Z,1578934690.729 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-01-13T16:58:10.743Z,1578934690.743 [ThrusterServo] Loaded
2020-01-13T16:58:10.743Z,1578934690.743 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-01-13T16:58:10.744Z,1578934690.744 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-01-13T16:58:10.744Z,1578934690.744 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-01-13T16:58:10.995Z,1578934690.995 [CTD_NeilBrown] Loaded
2020-01-13T16:58:10.995Z,1578934690.995 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2020-01-13T16:58:10.996Z,1578934690.996 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408F34E0
2020-01-13T16:58:10.997Z,1578934690.997 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 7744
2020-01-13T16:58:11.010Z,1578934691.010 [PAR_Licor] Loaded
2020-01-13T16:58:11.011Z,1578934691.011 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2020-01-13T16:58:11.053Z,1578934691.053 [WetLabsSeaOWL_UV_A] Loaded
2020-01-13T16:58:11.053Z,1578934691.053 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2020-01-13T16:58:11.054Z,1578934691.054 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 409234E0
2020-01-13T16:58:11.055Z,1578934691.055 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 7745
2020-01-13T16:58:11.055Z,1578934691.055 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-01-13T16:58:11.056Z,1578934691.056 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-01-13T16:58:11.357Z,1578934691.357 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-01-13T16:58:11.357Z,1578934691.357 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-01-13T16:58:11.397Z,1578934691.397 [DepthRateCalculator] Loaded
2020-01-13T16:58:11.398Z,1578934691.398 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-01-13T16:58:11.403Z,1578934691.403 [PitchRateCalculator] Loaded
2020-01-13T16:58:11.403Z,1578934691.403 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-01-13T16:58:11.415Z,1578934691.415 [SpeedCalculator] Loaded
2020-01-13T16:58:11.415Z,1578934691.415 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-01-13T16:58:11.435Z,1578934691.435 [TempGradientCalculator] Loaded
2020-01-13T16:58:11.436Z,1578934691.436 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-01-13T16:58:11.441Z,1578934691.441 [YawRateCalculator] Loaded
2020-01-13T16:58:11.441Z,1578934691.441 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-01-13T16:58:11.480Z,1578934691.480 [ElevatorOffsetCalculator] Loaded
2020-01-13T16:58:11.481Z,1578934691.481 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-01-13T16:58:11.481Z,1578934691.481 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-01-13T16:58:11.482Z,1578934691.482 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-01-13T16:58:11.615Z,1578934691.615 [SBIT](DEBUG): Construct Startup Built In Test.
2020-01-13T16:58:11.635Z,1578934691.635 [SBIT] Loaded
2020-01-13T16:58:11.636Z,1578934691.636 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-01-13T16:58:11.636Z,1578934691.636 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-01-13T16:58:11.649Z,1578934691.649 [IBIT] Loaded
2020-01-13T16:58:11.649Z,1578934691.649 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-01-13T16:58:11.652Z,1578934691.652 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-01-13T16:58:11.785Z,1578934691.785 [CBIT] Loaded
2020-01-13T16:58:11.785Z,1578934691.785 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-01-13T16:58:11.786Z,1578934691.786 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-01-13T16:58:11.786Z,1578934691.786 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-01-13T16:58:11.866Z,1578934691.866 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-01-13T16:58:11.866Z,1578934691.866 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-01-13T16:58:11.962Z,1578934691.962 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-01-13T16:58:11.962Z,1578934691.962 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-01-13T16:58:12.010Z,1578934692.010 [VerticalControl](DEBUG): Construct VerticalControl.
2020-01-13T16:58:12.091Z,1578934692.091 [VerticalControl] Loaded
2020-01-13T16:58:12.091Z,1578934692.091 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-01-13T16:58:12.092Z,1578934692.092 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-01-13T16:58:12.158Z,1578934692.158 [HorizontalControl] Loaded
2020-01-13T16:58:12.158Z,1578934692.158 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-01-13T16:58:12.159Z,1578934692.159 [SpeedControl](DEBUG): Construct SpeedControl.
2020-01-13T16:58:12.161Z,1578934692.161 [SpeedControl] Loaded
2020-01-13T16:58:12.161Z,1578934692.161 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-01-13T16:58:12.162Z,1578934692.162 [LoopControl](DEBUG): Construct LoopControl.
2020-01-13T16:58:12.162Z,1578934692.162 [LoopControl] Loaded
2020-01-13T16:58:12.162Z,1578934692.162 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-01-13T16:58:12.163Z,1578934692.163 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-01-13T16:58:12.163Z,1578934692.163 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-01-13T16:58:12.195Z,1578934692.195 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-01-13T16:58:12.198Z,1578934692.198 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-01-13T16:58:12.199Z,1578934692.199 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-01-13T16:58:12.206Z,1578934692.206 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-01-13T16:58:12.207Z,1578934692.207 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B024E0
2020-01-13T16:58:12.207Z,1578934692.207 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 7746
2020-01-13T16:58:12.244Z,1578934692.244 [Supervisor](INFO): Main Thread ID is 7658
2020-01-13T16:58:12.244Z,1578934692.244 [Supervisor](DEBUG): Running supervisor.
2020-01-13T16:58:12.244Z,1578934692.244 [CommandLine ThreadHandler](INFO): Handler Thread ID is 7747
2020-01-13T16:58:12.245Z,1578934692.245 [controlThread ThreadHandler](INFO): Handler Thread ID is 7748
2020-01-13T16:58:12.246Z,1578934692.246 [controlThread](DEBUG): Initializing ControlThread
2020-01-13T16:58:12.252Z,1578934692.252 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-01-13T16:58:12.252Z,1578934692.252 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-01-13T16:58:12.257Z,1578934692.257 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-01-13T16:58:12.257Z,1578934692.257 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-01-13T16:58:12.258Z,1578934692.258 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-01-13T16:58:12.258Z,1578934692.258 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-01-13T16:58:12.259Z,1578934692.259 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-01-13T16:58:12.259Z,1578934692.259 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-01-13T16:58:12.260Z,1578934692.260 [SBIT](INFO): Initialize SBIT Component.
2020-01-13T16:58:12.260Z,1578934692.260 [SBIT](IMPORTANT): git: 2019-12-03-34-g1420f60
2020-01-13T16:58:12.260Z,1578934692.260 [SBIT](INFO): git hash: 1420f6075bfd9e2317cf67bc08ebc33a04e592a5
2020-01-13T16:58:12.261Z,1578934692.261 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-01-13T16:58:12.262Z,1578934692.262 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #2 PREEMPT Wed May 15 08:34:03 PDT 2019
2020-01-13T16:58:12.263Z,1578934692.263 [SBIT](INFO): Beginning SBIT in 39.000000 seconds.
2020-01-13T16:58:12.264Z,1578934692.264 [IBIT](INFO): Initialize IBIT Component.
2020-01-13T16:58:12.264Z,1578934692.264 [CBIT](DEBUG): Initialize CBIT Component.
2020-01-13T16:58:12.266Z,1578934692.266 [CommandLine ThreadHandler](INFO): Handler Thread ID is 7749
2020-01-13T16:58:12.269Z,1578934692.269 [logger ThreadHandler](INFO): Handler Thread ID is 7750
2020-01-13T16:58:12.285Z,1578934692.285 [CBIT](DEBUG): Initialized mux pins.
2020-01-13T16:58:12.285Z,1578934692.285 [CBIT](DEBUG): Initializing the watchdog timer.
2020-01-13T16:58:12.309Z,1578934692.309 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-01-13T16:58:12.309Z,1578934692.309 [CBIT](DEBUG): Initializing heartbeat.
2020-01-13T16:58:12.333Z,1578934692.333 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 7752
2020-01-13T16:58:12.335Z,1578934692.335 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 7753
2020-01-13T16:58:12.338Z,1578934692.338 [CTD_NeilBrown](INFO): Powering down
2020-01-13T16:58:12.353Z,1578934692.353 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 7754
2020-01-13T16:58:12.354Z,1578934692.354 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-01-13T16:58:12.372Z,1578934692.372 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 7751
2020-01-13T16:58:12.373Z,1578934692.373 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 7755
2020-01-13T16:58:12.377Z,1578934692.377 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-01-13T16:58:12.377Z,1578934692.377 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-01-13T16:58:12.377Z,1578934692.377 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-01-13T16:58:12.377Z,1578934692.377 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-01-13T16:58:12.377Z,1578934692.377 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-01-13T16:58:12.378Z,1578934692.378 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-01-13T16:58:12.378Z,1578934692.378 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-01-13T16:58:12.378Z,1578934692.378 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-01-13T16:58:12.378Z,1578934692.378 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-01-13T16:58:12.378Z,1578934692.378 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-01-13T16:58:12.378Z,1578934692.378 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-01-13T16:58:12.379Z,1578934692.379 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-01-13T16:58:12.379Z,1578934692.379 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-01-13T16:58:12.379Z,1578934692.379 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-01-13T16:58:12.379Z,1578934692.379 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-01-13T16:58:12.379Z,1578934692.379 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-01-13T16:58:12.381Z,1578934692.381 [CBIT](DEBUG): Deactivating GF circuits.
2020-01-13T16:58:12.381Z,1578934692.381 [CBIT](DEBUG): Deactivating emergency mode.
2020-01-13T16:58:12.417Z,1578934692.417 [CBIT](DEBUG): Backplane powered.
2020-01-13T16:58:12.417Z,1578934692.417 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-01-13T16:58:12.419Z,1578934692.419 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-01-13T16:58:12.419Z,1578934692.419 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-01-13T16:58:12.420Z,1578934692.420 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-01-13T16:58:12.421Z,1578934692.421 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-01-13T16:58:12.431Z,1578934692.431 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-01-13T16:58:12.465Z,1578934692.465 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-01-13T16:58:12.503Z,1578934692.503 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix/>
        </ReadDatum>

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

    </Aggregate>

</Mission>


2020-01-13T16:58:12.504Z,1578934692.504 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-01-13T16:58:12.579Z,1578934692.579 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-01-13T16:58:12.580Z,1578934692.580 [Default:A.Wait](DEBUG): Construct Wait.
2020-01-13T16:58:12.581Z,1578934692.581 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-01-13T16:58:12.625Z,1578934692.625 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-01-13T16:58:12.627Z,1578934692.627 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-01-13T16:58:12.649Z,1578934692.649 [Default:E.Execute](DEBUG): Construct Execute.
2020-01-13T16:58:12.652Z,1578934692.652 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn on</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

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

</Mission>


2020-01-13T16:58:12.656Z,1578934692.656 [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-13T16:58:12.670Z,1578934692.670 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-01-13T16:58:12.709Z,1578934692.709 [DDM](INFO): Powering up
2020-01-13T16:58:12.709Z,1578934692.709 [DDM](DEBUG): Initializing DDM.
2020-01-13T16:58:12.735Z,1578934692.735 [DUSBL_Hydroid](INFO): Powering up
2020-01-13T16:58:12.735Z,1578934692.735 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2020-01-13T16:58:12.737Z,1578934692.737 [Radio_Surface](INFO): Powering up
2020-01-13T16:58:12.934Z,1578934692.934 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-01-13T16:58:12.941Z,1578934692.941 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-01-13T16:58:12.942Z,1578934692.942 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-01-13T16:58:12.950Z,1578934692.950 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-01-13T16:58:12.950Z,1578934692.950 [MassServo](DEBUG): Initializing EZServoServo.
2020-01-13T16:58:12.957Z,1578934692.957 [MassServo](DEBUG): Initializing MassServo.
2020-01-13T16:58:12.958Z,1578934692.958 [RudderServo](DEBUG): Initializing EZServoServo.
2020-01-13T16:58:12.965Z,1578934692.965 [RudderServo](DEBUG): Initializing RudderServo.
2020-01-13T16:58:12.966Z,1578934692.966 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-01-13T16:58:12.973Z,1578934692.973 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-01-13T16:58:13.086Z,1578934693.086 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2020-01-13T16:58:13.086Z,1578934693.086 [DropWeight] Hardware Fault, FailCount= 1
2020-01-13T16:58:13.086Z,1578934693.086 [DropWeight](ERROR): Hardware Fault
2020-01-13T16:58:13.088Z,1578934693.088 [Micromodem](INFO): Powering up
2020-01-13T16:58:13.088Z,1578934693.088 [Micromodem](DEBUG): Initializing Micromodem.
2020-01-13T16:58:13.168Z,1578934693.168 [CommandLine](FAULT): Scheduling is paused
2020-01-13T16:58:13.168Z,1578934693.168 [CBIT](INFO): Critical error at 20200113T165813
2020-01-13T16:58:13.177Z,1578934693.177 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2020-01-13T16:58:13.179Z,1578934693.179 [CBIT](ERROR): Hardware Fault in component: DropWeight
2020-01-13T16:58:13.180Z,1578934693.180 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2020-01-13T16:58:13.869Z,1578934693.869 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2020-01-13T16:58:13.869Z,1578934693.869 [RudderServo](FAULT): Rudder failed to initialize
2020-01-13T16:58:13.869Z,1578934693.869 [RudderServo] Communications Fault, FailCount= 1
2020-01-13T16:58:13.869Z,1578934693.869 [RudderServo](ERROR): Communications Fault
2020-01-13T16:58:13.978Z,1578934693.978 [CBIT](INFO): Critical error at 20200113T165813
2020-01-13T16:58:13.981Z,1578934693.981 [CBIT](ERROR): Communications Fault in component: RudderServo
2020-01-13T16:58:14.186Z,1578934694.186 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-01-13T16:58:14.186Z,1578934694.186 [RudderServo](INFO): Powering down
2020-01-13T16:58:14.851Z,1578934694.851 [RudderServo](DEBUG): Initializing EZServoServo.
2020-01-13T16:58:14.970Z,1578934694.970 [RudderServo](DEBUG): Initializing RudderServo.
2020-01-13T16:58:14.974Z,1578934694.974 [CBIT](INFO): Clearing failed state for component RudderServo
2020-01-13T16:58:14.974Z,1578934694.974 [RudderServo] No Fault, FailCount= 1
2020-01-13T16:58:18.069Z,1578934698.069 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2020-01-13T16:58:18.476Z,1578934698.476 [Micromodem](INFO): Nmea in: $CATMG,2020-01-13T16:58:17.860207Z,RTC,RTC*54

2020-01-13T16:58:18.476Z,1578934698.476 [Micromodem](ERROR): CFG response from modem unexpected: $CATMG,2020-01-13T16:58:17.860207Z,RTC,RTC*54

2020-01-13T16:58:18.875Z,1578934698.875 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31

2020-01-13T16:58:18.875Z,1578934698.875 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2020-01-13T16:58:19.279Z,1578934699.279 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33

2020-01-13T16:58:19.279Z,1578934699.279 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64

2020-01-13T16:58:19.679Z,1578934699.679 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66

2020-01-13T16:58:19.679Z,1578934699.679 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F

2020-01-13T16:58:20.087Z,1578934700.087 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D

2020-01-13T16:58:20.087Z,1578934700.087 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E

2020-01-13T16:58:20.491Z,1578934700.491 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C

2020-01-13T16:58:20.491Z,1578934700.491 [Micromodem](INFO): Nmea out: $CCCFG,FC0,10000*46

2020-01-13T16:58:20.894Z,1578934700.894 [Micromodem](INFO): Nmea in: $CACFG,FC0,10000*44

2020-01-13T16:58:20.895Z,1578934700.895 [Micromodem](INFO): Nmea out: $CCCFG,BW0,4000*63

2020-01-13T16:58:21.307Z,1578934701.307 [Micromodem](INFO): Nmea in: $CACFG,BW0,4000*61

2020-01-13T16:58:21.307Z,1578934701.307 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A

2020-01-13T16:58:21.679Z,1578934701.679 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38

2020-01-13T16:58:21.680Z,1578934701.680 [Micromodem](INFO): Nmea out: $CCCLK,2020,01,13,16,58,22*4D

2020-01-13T16:58:22.113Z,1578934702.113 [Micromodem](INFO): Nmea in: $CACLK,2020,1,13,16,58,22*7F

2020-01-13T16:58:22.483Z,1578934702.483 [Micromodem](INFO): Nmea in: $CATMS,0,2020-01-13T16:58:23Z*7E

2020-01-13T16:58:22.485Z,1578934702.485 [Micromodem](INFO): Nmea in: $CATMG,2020-01-13T16:58:23.026273Z,USER_CMD,RTC*1B

2020-01-13T16:58:23.311Z,1578934703.311 [DDM](INFO): Dynamic Docking Module:Ï€!C REMUS Capture Rev 1.1
2020-01-13T16:58:26.137Z,1578934706.137 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2020-01-13T16:58:27.803Z,1578934707.803 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004747
2020-01-13T16:58:30.554Z,1578934710.554 [DUSBL_Hydroid](INFO): DUSBL Version:O
2020-01-13T16:58:39.055Z,1578934719.055 [NAL9602](INFO): Powering up NAL9602
2020-01-13T16:58:49.959Z,1578934729.959 [NAL9602](INFO): NAL9602 initialized
2020-01-13T16:58:52.089Z,1578934732.089 [SBIT](IMPORTANT): Beginning Startup BIT
2020-01-13T16:58:52.121Z,1578934732.121 [CBIT](IMPORTANT): Beginning ground fault scan
2020-01-13T16:59:03.224Z,1578934743.224 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.003192
CHAN A1 (24V): -0.028943
CHAN A2 (12V): -0.007177
CHAN A3 (5V): -0.002137
CHAN B0 (3.3V): 0.000432
CHAN B1 (3.15aV): -0.000132
CHAN B2 (3.15bV): -0.000078
CHAN B3 (GND): 0.002127
OPEN: 0.005374
Full Scale Calc: 4.765 mA, -1.589 mA
2020-01-13T16:59:05.147Z,1578934745.147 [SBIT](FAULT): Rudder: EXPECTED:15.000000 ACTUAL:14.997823
2020-01-13T16:59:45.407Z,1578934785.407 [SBIT](FAULT): Rudder: EXPECTED:0.000000 ACTUAL:0.013197
2020-01-13T16:59:45.407Z,1578934785.407 [SBIT](FAULT): Control surface position failure.
2020-01-13T16:59:45.810Z,1578934785.810 [SBIT](CRITICAL): SBIT FAILED
2020-01-13T16:59:45.840Z,1578934785.840 [CommandLine](IMPORTANT): got command configSet list
2020-01-13T16:59:45.840Z,1578934785.840 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-01-13T16:59:45.841Z,1578934785.841 [CommandLine](IMPORTANT): DDM.loadAtStartup=1 bool;

2020-01-13T16:59:45.841Z,1578934785.841 [CommandLine](IMPORTANT): DDM.verbosity=3 count;

2020-01-13T16:59:45.841Z,1578934785.841 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold=75 count;

2020-01-13T16:59:45.841Z,1578934785.841 [CommandLine](IMPORTANT): DUSBL_Hydroid.transmitLockout=40 millisecond;

2020-01-13T16:59:45.842Z,1578934785.842 [CommandLine](IMPORTANT): Express linearApproximation DDM.DDMMode 1.000000 count;

2020-01-13T16:59:45.842Z,1578934785.842 [CommandLine](IMPORTANT): Express all DDM.cablePresent;

2020-01-13T16:59:45.842Z,1578934785.842 [CommandLine](IMPORTANT): Express linearApproximation DDM.latchState 1.000000 count;

2020-01-13T16:59:45.842Z,1578934785.842 [CommandLine](IMPORTANT): Express linearApproximation DDM.whiskerState 1.000000 count;

2020-01-13T16:59:45.842Z,1578934785.842 [CommandLine](IMPORTANT): Express linearApproximation DUSBL_Hydroid.acoustic_contact_range 1.000000 meter;

2020-01-13T16:59:45.842Z,1578934785.842 [CommandLine](IMPORTANT): Express linearApproximation DUSBL_Hydroid.xAngle 2.000000 degree;

2020-01-13T16:59:45.842Z,1578934785.842 [CommandLine](IMPORTANT): Express linearApproximation RDI_Pathfinder.height_above_sea_floor 2.000000 meter;

2020-01-13T16:59:45.842Z,1578934785.842 [CommandLine](IMPORTANT): Express linearApproximation platform_orientation 5.000000 degree;

2020-01-13T16:59:45.843Z,1578934785.843 [CommandLine](IMPORTANT): Express linearApproximation platform_x_velocity_wrt_ground 0.100000 meter_per_second;

2020-01-13T16:59:45.843Z,1578934785.843 [CommandLine](IMPORTANT): Express linearApproximation platform_y_velocity_wrt_ground 0.100000 meter_per_second;

2020-01-13T16:59:45.843Z,1578934785.843 [CommandLine](IMPORTANT): HorizontalControl.kiHeading=0.002 reciprocal_second;

2020-01-13T16:59:45.843Z,1578934785.843 [CommandLine](IMPORTANT): HorizontalControl.kpHeading=0.8 none;

2020-01-13T16:59:45.843Z,1578934785.843 [CommandLine](IMPORTANT): HorizontalControl.rudDeadband=0.05 degree;

2020-01-13T16:59:45.843Z,1578934785.843 [CommandLine](IMPORTANT): Micromodem.destinationAddress=12 enum;

2020-01-13T16:59:45.843Z,1578934785.843 [CommandLine](IMPORTANT): Micromodem.surfaceThreshold=0 meter;

2020-01-13T16:59:45.843Z,1578934785.843 [CommandLine](IMPORTANT): PowerOnly.sampleTime=90 second;

2020-01-13T16:59:45.843Z,1578934785.843 [CommandLine](IMPORTANT): RudderServo.deviationAngle=0.0001 degree;

2020-01-13T16:59:45.844Z,1578934785.844 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=105 cubic_centimeter;

2020-01-13T16:59:45.844Z,1578934785.844 [CommandLine](IMPORTANT): VerticalControl.massDefault=-5 millimeter;

2020-01-13T16:59:45.849Z,1578934785.849 [CBIT](INFO): Critical error at 20200113T165945
2020-01-13T16:59:46.171Z,1578934786.171 [MissionManager](IMPORTANT): Started mission Startup
2020-01-13T16:59:46.172Z,1578934786.172 [Startup] Running Loop=1
2020-01-13T16:59:46.172Z,1578934786.172 [Startup](DEBUG): Aggregate::initialize Startup
2020-01-13T16:59:46.172Z,1578934786.172 [Startup:A.GoToSurface] Running Loop=1
2020-01-13T16:59:46.172Z,1578934786.172 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-01-13T16:59:46.172Z,1578934786.172 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-01-13T16:59:46.181Z,1578934786.181 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-01-13T16:59:46.181Z,1578934786.181 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-01-13T16:59:46.182Z,1578934786.182 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-01-13T16:59:46.182Z,1578934786.182 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-01-13T16:59:46.184Z,1578934786.184 [Startup:StartupSatComms] Running Loop=1
2020-01-13T16:59:46.184Z,1578934786.184 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-01-13T16:59:46.184Z,1578934786.184 [Startup:StartupSatComms:A] Running Loop=1
2020-01-13T16:59:46.677Z,1578934786.677 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-01-13T17:00:46.326Z,1578934846.326 [Startup:StartupSatComms:A](INFO): Timed out from 2020-01-13T16:59:46.2Z
2020-01-13T17:00:46.326Z,1578934846.326 [Startup:StartupSatComms:A] Stopped
2020-01-13T17:00:46.326Z,1578934846.326 [Startup:StartupSatComms:B] Running Loop=1
2020-01-13T17:00:46.743Z,1578934846.743 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-01-13T17:00:48.178Z,1578934848.178 [Micromodem](INFO): Outgoing frame #1, 64 bytes: C917195E0D0000005D00000400320000000000000000151D80030C5669C010E4BA364E1BCEC95AFD389D2032C40ABD4E02BA605916A56966D0B8E476014BA181
2020-01-13T17:00:48.178Z,1578934848.178 [Micromodem](INFO): Outgoing frame #2, 3 bytes: B9DC60
2020-01-13T17:00:48.178Z,1578934848.178 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,2*68

2020-01-13T17:00:48.703Z,1578934848.703 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,2*6A

2020-01-13T17:00:49.115Z,1578934849.115 [Micromodem](INFO): Nmea in: $CADRQ,170049,1,12,0,64,1*7F

2020-01-13T17:00:49.115Z,1578934849.115 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,C917195E0D0000005D00000400320000000000000000151D80030C5669C010E4BA364E1BCEC95AFD389D2032C40ABD4E02BA605916A56966D0B8E476014BA181*44

2020-01-13T17:00:49.511Z,1578934849.511 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-13T17:00:49.932Z,1578934849.932 [Micromodem](INFO): Nmea in: $CADRQ,170050,1,12,0,64,2*74

2020-01-13T17:00:49.934Z,1578934849.934 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,B9DC60*31

2020-01-13T17:00:50.327Z,1578934850.327 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,3*7A

2020-01-13T17:00:50.731Z,1578934850.731 [Micromodem](INFO): Nmea in: $CATXP,67*73

2020-01-13T17:00:53.155Z,1578934853.155 [Micromodem](INFO): Nmea in: $CATXF,67*65

2020-01-13T17:00:53.552Z,1578934853.552 [Micromodem](INFO): Nmea in: $CAXST,6,20200113,170050.853404,3,0,200,4000,10000,1,1,12,1,2,2,3,67*4F

2020-01-13T17:00:53.900Z,1578934853.900 [DataOverHttps](INFO): Sending 59 bytes from file Logs/20200111T003313/Courier0013.lzma
2020-01-13T17:00:54.366Z,1578934854.366 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-13T17:00:54.763Z,1578934854.763 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-13T17:00:54.764Z,1578934854.764 [Micromodem](INFO): Sent 59 bytes from file Logs/20200111T003313/Courier0013.lzma.parts
2020-01-13T17:00:54.764Z,1578934854.764 [Micromodem](INFO): Packets left to send: 0
2020-01-13T17:00:55.179Z,1578934855.179 [Micromodem](INFO): Nmea in: $CACST,6,0,20200113170054.077150,06,864,29,0130,0150,59,01,01,00,03,1,012,001,0,4,1,0,150,15.8,8.00,22,-5.00,-01,-0.06,33,10000,4000*4C

2020-01-13T17:00:55.909Z,1578934855.909 [DataOverHttps](FAULT): Server acknowledged file Logs/20200111T003313/Courier0013.lzma, notLogs/20200113T165802/Courier0000.lzma
2020-01-13T17:00:55.910Z,1578934855.910 [DataOverHttps](INFO): SBD MOMSN=12191083
2020-01-13T17:00:56.133Z,1578934856.133 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 9AA11C5E000001005D00000400670100000000000000151D80030C5669BF3148E2FA22188B6A388EEB0BC45C9E58EC1F659FAAC29EC6B6829A7CC0606A7B8EA6
2020-01-13T17:00:56.133Z,1578934856.133 [Micromodem](INFO): Outgoing frame #2, 64 bytes: AA422B2385EC73C0FABC6B0C6D70893EB78F88D46452A4A3C7E9D8907F12032C01CF700820374B1C8E5D82357819DDFA5B46F5DFA93FEDD106818297EE367B80
2020-01-13T17:00:56.134Z,1578934856.134 [Micromodem](INFO): Outgoing frame #3, 64 bytes: 2559E106C6D71B167702284BCD8EB35BEF2546B4650E1907BAF28A8E47A590DF5EBADB8B21341D81B7A8784EB9B1CF06CCCBCE5B5D7E1CDFDA1A2BDD1B8F253B
2020-01-13T17:00:56.134Z,1578934856.134 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69

2020-01-13T17:00:57.115Z,1578934857.115 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B

2020-01-13T17:00:57.515Z,1578934857.515 [Micromodem](INFO): Nmea in: $CADRQ,170057,1,12,0,64,1*70

2020-01-13T17:00:57.515Z,1578934857.515 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,9AA11C5E000001005D00000400670100000000000000151D80030C5669BF3148E2FA22188B6A388EEB0BC45C9E58EC1F659FAAC29EC6B6829A7CC0606A7B8EA6*47

2020-01-13T17:00:57.934Z,1578934857.934 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-13T17:00:58.319Z,1578934858.319 [Micromodem](INFO): Nmea in: $CADRQ,170058,1,12,0,64,2*7C

2020-01-13T17:00:58.319Z,1578934858.319 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,AA422B2385EC73C0FABC6B0C6D70893EB78F88D46452A4A3C7E9D8907F12032C01CF700820374B1C8E5D82357819DDFA5B46F5DFA93FEDD106818297EE367B80*40

2020-01-13T17:00:58.727Z,1578934858.727 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-13T17:00:59.133Z,1578934859.133 [Micromodem](INFO): Nmea in: $CADRQ,170059,1,12,0,64,3*7C

2020-01-13T17:00:59.133Z,1578934859.133 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,2559E106C6D71B167702284BCD8EB35BEF2546B4650E1907BAF28A8E47A590DF5EBADB8B21341D81B7A8784EB9B1CF06CCCBCE5B5D7E1CDFDA1A2BDD1B8F253B*37

2020-01-13T17:00:59.535Z,1578934859.535 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-13T17:00:59.938Z,1578934859.938 [Micromodem](INFO): Nmea in: $CATXP,192*48

2020-01-13T17:01:03.575Z,1578934863.575 [Micromodem](INFO): Nmea in: $CATXF,192*5E

2020-01-13T17:01:03.986Z,1578934863.986 [Micromodem](INFO): Nmea in: $CAXST,6,20200113,170100.129706,3,0,200,4000,10000,1,1,12,1,3,3,3,192*75

2020-01-13T17:01:04.796Z,1578934864.796 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-13T17:01:05.192Z,1578934865.192 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-13T17:01:05.601Z,1578934865.601 [Micromodem](INFO): Nmea in: $CAACK,12,1,3,1*7B

2020-01-13T17:01:05.602Z,1578934865.602 [Micromodem](INFO): Sent 184 bytes from file Logs/20200113T165802/Courier0000.lzma.parts
2020-01-13T17:01:05.602Z,1578934865.602 [Micromodem](INFO): Packets left to send: 1
2020-01-13T17:01:06.002Z,1578934866.002 [Micromodem](INFO): Nmea in: $CACST,6,0,20200113170104.683190,06,563,27,0096,0150,89,02,02,00,03,1,012,001,0,4,1,0,150,24.6,6.00,14,-3.00,-01,-0.07,28,10000,4000*47

2020-01-13T17:01:06.559Z,1578934866.559 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 9AA11C5E000000002A3FCCB34F6D62E9B8AD95E2DDDE03FDBA217D811C470D09A699C1927D1166147CB71C6887DE791DD4CC8D3916394CA26AC34165EDF1BBC9
2020-01-13T17:01:06.559Z,1578934866.559 [Micromodem](INFO): Outgoing frame #2, 24 bytes: 77813EAFA4747DB7A8AB325974B4631BD3067A8A35238D00
2020-01-13T17:01:06.560Z,1578934866.560 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,2*68

2020-01-13T17:01:07.503Z,1578934867.503 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,2*6A

2020-01-13T17:01:07.944Z,1578934867.944 [Micromodem](INFO): Nmea in: $CADRQ,170107,1,12,0,64,1*74

2020-01-13T17:01:07.944Z,1578934867.944 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,9AA11C5E000000002A3FCCB34F6D62E9B8AD95E2DDDE03FDBA217D811C470D09A699C1927D1166147CB71C6887DE791DD4CC8D3916394CA26AC34165EDF1BBC9*4E

2020-01-13T17:01:08.311Z,1578934868.311 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-13T17:01:08.721Z,1578934868.721 [Micromodem](INFO): Nmea in: $CADRQ,170108,1,12,0,64,2*78

2020-01-13T17:01:08.721Z,1578934868.721 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,77813EAFA4747DB7A8AB325974B4631BD3067A8A35238D00*32

2020-01-13T17:01:09.119Z,1578934869.119 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,24*4F

2020-01-13T17:01:09.519Z,1578934869.519 [Micromodem](INFO): Nmea in: $CATXP,88*72

2020-01-13T17:01:11.966Z,1578934871.966 [Micromodem](INFO): Nmea in: $CATXF,88*64

2020-01-13T17:01:12.360Z,1578934872.360 [Micromodem](INFO): Nmea in: $CAXST,6,20200113,170109.663643,3,0,200,4000,10000,1,1,12,1,2,2,3,88*4F

2020-01-13T17:01:12.425Z,1578934872.425 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2020-01-13T17:01:12.425Z,1578934872.425 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-13T17:01:12.443Z,1578934872.443 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-13T17:01:12.810Z,1578934872.810 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-13T17:01:12.810Z,1578934872.810 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2020-01-13T17:01:13.160Z,1578934873.160 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-13T17:01:13.575Z,1578934873.575 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-13T17:01:13.576Z,1578934873.576 [Micromodem](INFO): Sent 80 bytes from file Logs/20200113T165802/Courier0000.lzma.parts
2020-01-13T17:01:13.576Z,1578934873.576 [Micromodem](INFO): Packets left to send: 0
2020-01-13T17:01:13.967Z,1578934873.967 [Micromodem](INFO): Nmea in: $CACST,6,0,20200113170112.908121,06,528,27,0094,0150,59,02,02,00,04,1,012,001,0,4,1,0,150,23.5,7.00,16,-4.00,-01,-0.06,24,10000,4000*4C

2020-01-13T17:01:15.148Z,1578934875.148 [Startup:StartupSatComms:B] Stopped
2020-01-13T17:01:15.148Z,1578934875.148 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2020-01-13T17:01:15.148Z,1578934875.148 [Startup:StartupSatComms] Stopped
2020-01-13T17:01:15.148Z,1578934875.148 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-01-13T17:01:15.153Z,1578934875.153 [Startup](INFO): Completed Startup
2020-01-13T17:01:15.154Z,1578934875.154 [MissionManager](INFO): Startup is completed.
2020-01-13T17:01:15.154Z,1578934875.154 [MissionManager](INFO): Uninitializing Mission Startup
2020-01-13T17:01:15.154Z,1578934875.154 [Startup] Stopped
2020-01-13T17:01:15.154Z,1578934875.154 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-01-13T17:01:15.154Z,1578934875.154 [Startup:A.GoToSurface] Stopped
2020-01-13T17:01:15.154Z,1578934875.154 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-01-13T17:01:15.378Z,1578934875.378 [MissionManager](IMPORTANT): Started mission Default
2020-01-13T17:01:15.378Z,1578934875.378 [Default] Running Loop=1
2020-01-13T17:01:15.378Z,1578934875.378 [Default](DEBUG): Aggregate::initialize Default
2020-01-13T17:01:15.378Z,1578934875.378 [Default:B.GoToSurface] Running Loop=1
2020-01-13T17:01:15.378Z,1578934875.378 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-01-13T17:01:15.378Z,1578934875.378 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-01-13T17:01:15.379Z,1578934875.379 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-01-13T17:01:15.379Z,1578934875.379 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-01-13T17:01:15.379Z,1578934875.379 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-01-13T17:01:15.379Z,1578934875.379 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-01-13T17:01:15.380Z,1578934875.380 [Default:A.Wait] Running Loop=1
2020-01-13T17:01:15.380Z,1578934875.380 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-01-13T17:01:16.516Z,1578934876.516 [DataOverHttps](INFO): Sending 127 bytes from file Logs/20200110T012202/Express0005.lzma
2020-01-13T17:01:18.526Z,1578934878.526 [DataOverHttps](INFO): Moved sent file to Logs/20200110T012202/Express0005.lzma.bak
2020-01-13T17:01:18.527Z,1578934878.527 [DataOverHttps](INFO): SBD MOMSN=12191085
2020-01-13T17:01:28.547Z,1578934888.547 [Default:A.Wait](INFO): Done Waiting.
2020-01-13T17:01:28.547Z,1578934888.547 [Default:A.Wait] Stopped
2020-01-13T17:01:28.547Z,1578934888.547 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-01-13T17:01:28.967Z,1578934888.967 [Default:CheckIn] Running Loop=1
2020-01-13T17:01:28.967Z,1578934888.967 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-13T17:01:28.967Z,1578934888.967 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-13T17:01:29.347Z,1578934889.347 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-01-13T17:02:24.356Z,1578934944.356 [RDI_Pathfinder](ERROR): Failed to parse:
:SA,   +0.00,   +0.00,  0.00
2020-01-13T17:02:46.939Z,1578934966.939 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-13T17:02:46.939Z,1578934966.939 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-01-13T17:03:12.373Z,1578934992.373 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-13T17:03:12.373Z,1578934992.373 [RDI_Pathfinder](ERROR): Failed to parse:
:TS0160602,35.0, -0.1,   0.0,1448.9,  0
2020-01-13T17:03:17.279Z,1578934997.279 [CommandLine](IMPORTANT): got command show variable range
2020-01-13T17:03:17.375Z,1578934997.375 [CommandLine](IMPORTANT): acoustic_contact_range (unknown)
2020-01-13T17:03:17.458Z,1578934997.458 [CommandLine](IMPORTANT): BR_Ping1D.minRange (meter)
2020-01-13T17:03:17.458Z,1578934997.458 [CommandLine](IMPORTANT): BR_Ping1D.maxRange (meter)
2020-01-13T17:03:17.491Z,1578934997.491 [CommandLine](IMPORTANT): DUSBL_Hydroid.acoustic_contact_range (meter)
2020-01-13T17:03:17.492Z,1578934997.492 [CommandLine](IMPORTANT): Micromodem.range_request (count)
2020-01-13T17:03:17.493Z,1578934997.493 [CommandLine](IMPORTANT): Micromodem.range (meter)
2020-01-13T17:03:17.496Z,1578934997.496 [CommandLine](IMPORTANT): RDI_Pathfinder.Beam1Range (meter)
2020-01-13T17:03:17.497Z,1578934997.497 [CommandLine](IMPORTANT): RDI_Pathfinder.Beam2Range (meter)
2020-01-13T17:03:17.497Z,1578934997.497 [CommandLine](IMPORTANT): RDI_Pathfinder.Beam3Range (meter)
2020-01-13T17:03:17.498Z,1578934997.498 [CommandLine](IMPORTANT): RDI_Pathfinder.Beam4Range (meter)
2020-01-13T17:03:27.517Z,1578935007.517 [CommandLine](IMPORTANT): got command report touch DUSBL_Hydroid.acoustic_contact_range
2020-01-13T17:03:33.963Z,1578935013.963 [CommandLine](IMPORTANT): got command show variable xangle
2020-01-13T17:03:33.998Z,1578935013.998 [CommandLine](IMPORTANT): DUSBL_Hydroid.xAngle (angular_degree)
2020-01-13T17:03:39.755Z,1578935019.755 [CommandLine](IMPORTANT): got command report touch DUSBL_Hydroid.xAngle
2020-01-13T17:03:43.470Z,1578935023.470 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-13T17:03:43.470Z,1578935023.470 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20011310163702,35.0, -0.1,   0.0, 0
2020-01-13T17:03:44.611Z,1578935024.611 [CommandLine](IMPORTANT): got command show variable DUSBL
2020-01-13T17:03:44.786Z,1578935024.786 [CommandLine](IMPORTANT): DUSBL_Hydroid.loadAtStartup (bool)
2020-01-13T17:03:44.787Z,1578935024.787 [CommandLine](IMPORTANT): DUSBL_Hydroid.simulateHardware (bool)
2020-01-13T17:03:44.787Z,1578935024.787 [CommandLine](IMPORTANT): DUSBL_Hydroid.defaultTurnAroundTime (second)
2020-01-13T17:03:44.787Z,1578935024.787 [CommandLine](IMPORTANT): DUSBL_Hydroid.defaultSoundSpeed (meter_per_second)
2020-01-13T17:03:44.788Z,1578935024.788 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold (count)
2020-01-13T17:03:44.788Z,1578935024.788 [CommandLine](IMPORTANT): DUSBL_Hydroid.recieveTimeout (millisecond)
2020-01-13T17:03:44.788Z,1578935024.788 [CommandLine](IMPORTANT): DUSBL_Hydroid.transmitLockout (millisecond)
2020-01-13T17:03:44.809Z,1578935024.809 [CommandLine](IMPORTANT): DUSBL_Hydroid.transponderCode (enum)
2020-01-13T17:03:44.809Z,1578935024.809 [CommandLine](IMPORTANT): DUSBL_Hydroid.numberOfPingsRequested (count)
2020-01-13T17:03:44.810Z,1578935024.810 [CommandLine](IMPORTANT): DUSBL_Hydroid.verbosity (enum)
2020-01-13T17:03:44.810Z,1578935024.810 [CommandLine](IMPORTANT): DUSBL_Hydroid.xCenter (angular_degree)
2020-01-13T17:03:44.810Z,1578935024.810 [CommandLine](IMPORTANT): DUSBL_Hydroid.yCenter (angular_degree)
2020-01-13T17:03:44.824Z,1578935024.824 [CommandLine](IMPORTANT): Micromodem.dusblPingCode (none)
2020-01-13T17:03:44.864Z,1578935024.864 [CommandLine](IMPORTANT): DUSBL_Hydroid.loadControl (none)
2020-01-13T17:03:44.873Z,1578935024.873 [CommandLine](IMPORTANT): DUSBL_Hydroid.uart (none)
2020-01-13T17:03:44.873Z,1578935024.873 [CommandLine](IMPORTANT): DUSBL_Hydroid.baud (bit_per_second)
2020-01-13T17:03:44.886Z,1578935024.886 [CommandLine](IMPORTANT): Micromodem.dusblPingCodeRequested (enum)
2020-01-13T17:03:44.887Z,1578935024.887 [CommandLine](IMPORTANT): DUSBL_Hydroid.xAngle (angular_degree)
2020-01-13T17:03:44.887Z,1578935024.887 [CommandLine](IMPORTANT): DUSBL_Hydroid.yAngle (angular_degree)
2020-01-13T17:03:44.887Z,1578935024.887 [CommandLine](IMPORTANT): DUSBL_Hydroid.travelTime (microsecond)
2020-01-13T17:03:44.888Z,1578935024.888 [CommandLine](IMPORTANT): DUSBL_Hydroid.latency (microsecond)
2020-01-13T17:03:44.888Z,1578935024.888 [CommandLine](IMPORTANT): DUSBL_Hydroid.gain (ratio)
2020-01-13T17:03:44.888Z,1578935024.888 [CommandLine](IMPORTANT): DUSBL_Hydroid.inBandSignalToNoise (ratio)
2020-01-13T17:03:44.888Z,1578935024.888 [CommandLine](IMPORTANT): DUSBL_Hydroid.outBandSignalToNoise (ratio)
2020-01-13T17:03:44.897Z,1578935024.897 [CommandLine](IMPORTANT): DUSBL_Hydroid.acoustic_contact_range (meter)
2020-01-13T17:03:44.897Z,1578935024.897 [CommandLine](IMPORTANT): DUSBL_Hydroid.acoustic_contact_direction_vehicle_frame (none)
2020-01-13T17:03:44.898Z,1578935024.898 [CommandLine](IMPORTANT): DUSBL_Hydroid.acoustic_contact_address (enum)
2020-01-13T17:03:44.898Z,1578935024.898 [CommandLine](IMPORTANT): DUSBL_Hydroid.acoustic_receive_time (epoch_second)
2020-01-13T17:03:53.547Z,1578935033.547 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-01-13T17:03:58.863Z,1578935038.863 [CommandLine](IMPORTANT): got command get DUSBL_Hydroid.verbosity
2020-01-13T17:03:58.863Z,1578935038.863 [CommandLine](IMPORTANT): DUSBL_Hydroid.verbosity 0 enum
2020-01-13T17:04:06.146Z,1578935046.146 [CommandLine](IMPORTANT): got command run ./Missions/Maintenance/DUSBL.xml
2020-01-13T17:04:06.146Z,1578935046.146 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/DUSBL.xml
2020-01-13T17:04:06.204Z,1578935046.204 [MissionManager](INFO): DefineArg DUSBL.MissionTimeout = 90.000000 min
2020-01-13T17:04:06.207Z,1578935046.207 [MissionManager](INFO): DefineArg DUSBL.NumberOfRequests = 10.000000 count
2020-01-13T17:04:06.211Z,1578935046.211 [MissionManager](INFO): DefineArg DUSBL.TransponderCode = 2.000000 count
2020-01-13T17:04:06.215Z,1578935046.215 [MissionManager](INFO): DefineArg DUSBL.NumberOfPings = 1.000000 count
2020-01-13T17:04:06.218Z,1578935046.218 [MissionManager](INFO): DefineArg DUSBL.EnabledDUSBL = DUSBL_Hydroid.loadAtStartup
2020-01-13T17:04:06.219Z,1578935046.219 [DUSBL:A.Pitch](DEBUG): Construct.
2020-01-13T17:04:06.226Z,1578935046.226 [DUSBL:B.SetSpeed](DEBUG): Construct.
2020-01-13T17:04:06.254Z,1578935046.254 [DUSBL:RequestRepeater:C.Wait](DEBUG): Construct Wait.
2020-01-13T17:04:06.266Z,1578935046.266 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<!-- ====================
     Generated by TethysL
     ==================== -->
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Control="Tethys/Control"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd"
       Id="DUSBL">

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

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

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

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

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

    <Timeout Duration="MissionTimeout"/>

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

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

    <ReadData>
        <When>
            <Arg Name="EnabledDUSBL"/>
        </When>
            <Universal:acoustic_contact_range/>
            <Universal:acoustic_contact_direction_vehicle_frame/>
    </ReadData>
    
      <Aggregate Id="RequestRepeater">

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


2020-01-13T17:04:06.268Z,1578935046.268 [CommandLine](IMPORTANT): Running ./Missions/Maintenance/DUSBL.xml
2020-01-13T17:04:06.503Z,1578935046.503 [Default] Stopped
2020-01-13T17:04:06.503Z,1578935046.503 [Default](DEBUG): Aggregate::uninitialize Default
2020-01-13T17:04:06.503Z,1578935046.503 [Default:B.GoToSurface] Stopped
2020-01-13T17:04:06.503Z,1578935046.503 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-01-13T17:04:06.503Z,1578935046.503 [Default:CheckIn] Stopped
2020-01-13T17:04:06.503Z,1578935046.503 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-13T17:04:06.503Z,1578935046.503 [Default:CheckIn:Read_GPS] Stopped
2020-01-13T17:04:06.503Z,1578935046.503 [MissionManager](IMPORTANT): Started mission DUSBL
2020-01-13T17:04:06.503Z,1578935046.503 [DUSBL] Running Loop=1
2020-01-13T17:04:06.504Z,1578935046.504 [DUSBL](DEBUG): Aggregate::initialize DUSBL
2020-01-13T17:04:06.504Z,1578935046.504 [DUSBL:A.Pitch] Running Loop=1
2020-01-13T17:04:06.504Z,1578935046.504 [DUSBL:A.Pitch](DEBUG): Initialize.
2020-01-13T17:04:06.504Z,1578935046.504 [DUSBL:B.SetSpeed] Running Loop=1
2020-01-13T17:04:06.504Z,1578935046.504 [DUSBL:B.SetSpeed](DEBUG): Initialize.
2020-01-13T17:04:06.504Z,1578935046.504 [DUSBL:C] Running Loop=1
2020-01-13T17:04:06.504Z,1578935046.504 [DUSBL:RequestRepeater] Running Loop=1
2020-01-13T17:04:06.504Z,1578935046.504 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2020-01-13T17:04:06.521Z,1578935046.521 [DUSBL:RequestRepeater:A] Running Loop=1
2020-01-13T17:04:06.521Z,1578935046.521 [DUSBL:RequestRepeater:B] Running Loop=1
2020-01-13T17:04:06.521Z,1578935046.521 [DUSBL:RequestRepeater:C.Wait] Running Loop=1
2020-01-13T17:04:06.521Z,1578935046.521 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component.
2020-01-13T17:04:06.521Z,1578935046.521 [DUSBL:RequestRepeater:B] Running Loop=1
2020-01-13T17:04:06.522Z,1578935046.522 [DUSBL:RequestRepeater:A] Running Loop=1
2020-01-13T17:04:06.523Z,1578935046.523 [DUSBL:C](DEBUG): Initialize ReadDataComponent to sense acoustic_contact_range
2020-01-13T17:04:06.523Z,1578935046.523 [DUSBL:C](DEBUG): Initialize ReadDataComponent to sense acoustic_contact_direction_vehicle_frame
2020-01-13T17:04:06.523Z,1578935046.523 [DUSBL:B.SetSpeed] Running Loop=1
2020-01-13T17:04:06.523Z,1578935046.523 [DUSBL:A.Pitch] Running Loop=1
2020-01-13T17:04:08.500Z,1578935048.500 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-13T17:04:08.946Z,1578935048.946 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-13T17:04:09.723Z,1578935049.723 [Micromodem](INFO): Nmea in: $SNTTA,,,,,170409.46*57

2020-01-13T17:04:12.138Z,1578935052.138 [DUSBL_Hydroid](ERROR): No response from remote modem.
2020-01-13T17:04:12.953Z,1578935052.953 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-13T17:04:13.357Z,1578935053.357 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-13T17:04:13.445Z,1578935053.445 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2020-01-13T17:04:13.445Z,1578935053.445 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-13T17:04:13.455Z,1578935053.455 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-13T17:04:13.815Z,1578935053.815 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-13T17:04:13.815Z,1578935053.815 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2020-01-13T17:04:14.169Z,1578935054.169 [Micromodem](INFO): Nmea in: $SNTTA,,,,,170413.91*56

2020-01-13T17:04:16.586Z,1578935056.586 [DUSBL_Hydroid](ERROR): No response from remote modem.
2020-01-13T17:04:17.396Z,1578935057.396 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-13T17:04:17.803Z,1578935057.803 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-13T17:04:18.606Z,1578935058.606 [Micromodem](INFO): Nmea in: $SNTTA,,,,,170418.36*50

2020-01-13T17:04:21.038Z,1578935061.038 [DUSBL_Hydroid](ERROR): No response from remote modem.
2020-01-13T17:04:21.835Z,1578935061.835 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-13T17:04:22.249Z,1578935062.249 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-13T17:04:23.051Z,1578935063.051 [Micromodem](INFO): Nmea in: $SNTTA,,,,,170422.79*52

2020-01-13T17:04:23.105Z,1578935063.105 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-13T17:04:23.105Z,1578935063.105 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20011310171702,35.   0.0,1448.9,  0
2020-01-13T17:04:25.474Z,1578935065.474 [DUSBL_Hydroid](ERROR): No response from remote modem.
2020-01-13T17:04:26.279Z,1578935066.279 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-13T17:04:26.689Z,1578935066.689 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-13T17:04:27.496Z,1578935067.496 [Micromodem](INFO): Nmea in: $SNTTA,,,,,170427.24*5F

2020-01-13T17:04:28.442Z,1578935068.442 [CommandLine](IMPORTANT): got command stop
2020-01-13T17:04:28.442Z,1578935068.442 [CommandLine](IMPORTANT): Scheduling is paused
2020-01-13T17:04:28.443Z,1578935068.443 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop
2020-01-13T17:04:28.813Z,1578935068.813 [MissionManager](INFO): MissionManager is completed.
2020-01-13T17:04:28.813Z,1578935068.813 [MissionManager](INFO): Uninitializing Mission DUSBL
2020-01-13T17:04:28.814Z,1578935068.814 [DUSBL] Stopped
2020-01-13T17:04:28.814Z,1578935068.814 [DUSBL](DEBUG): Aggregate::uninitialize DUSBL
2020-01-13T17:04:28.814Z,1578935068.814 [DUSBL:A.Pitch] Stopped
2020-01-13T17:04:28.814Z,1578935068.814 [DUSBL:B.SetSpeed] Stopped
2020-01-13T17:04:28.814Z,1578935068.814 [DUSBL:B.SetSpeed](DEBUG): Uninitialize.
2020-01-13T17:04:28.814Z,1578935068.814 [DUSBL:C] Stopped
2020-01-13T17:04:28.814Z,1578935068.814 [DUSBL:RequestRepeater] Stopped
2020-01-13T17:04:28.814Z,1578935068.814 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2020-01-13T17:04:28.814Z,1578935068.814 [DUSBL:RequestRepeater:A] Stopped
2020-01-13T17:04:28.814Z,1578935068.814 [DUSBL:RequestRepeater:B] Stopped
2020-01-13T17:04:28.814Z,1578935068.814 [DUSBL:RequestRepeater:C.Wait] Stopped
2020-01-13T17:04:28.814Z,1578935068.814 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-13T17:04:29.181Z,1578935069.181 [MissionManager](IMPORTANT): Started mission Default
2020-01-13T17:04:29.182Z,1578935069.182 [Default] Running Loop=1
2020-01-13T17:04:29.182Z,1578935069.182 [Default](DEBUG): Aggregate::initialize Default
2020-01-13T17:04:29.182Z,1578935069.182 [Default:B.GoToSurface] Running Loop=1
2020-01-13T17:04:29.182Z,1578935069.182 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-01-13T17:04:29.182Z,1578935069.182 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-01-13T17:04:29.182Z,1578935069.182 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-01-13T17:04:29.183Z,1578935069.183 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-01-13T17:04:29.183Z,1578935069.183 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-01-13T17:04:29.183Z,1578935069.183 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-01-13T17:04:29.183Z,1578935069.183 [Default:A.Wait] Running Loop=1
2020-01-13T17:04:29.184Z,1578935069.184 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-01-13T17:04:32.383Z,1578935072.383 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-13T17:04:32.383Z,1578935072.383 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20011310172, -0.1,   0.0,1448.9,  0
2020-01-13T17:04:42.478Z,1578935082.478 [Default:A.Wait](INFO): Done Waiting.
2020-01-13T17:04:42.478Z,1578935082.478 [Default:A.Wait] Stopped
2020-01-13T17:04:42.478Z,1578935082.478 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-01-13T17:04:42.899Z,1578935082.899 [Default:CheckIn] Running Loop=1
2020-01-13T17:04:42.899Z,1578935082.899 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-13T17:04:42.900Z,1578935082.900 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-13T17:04:43.253Z,1578935083.253 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170442.00,A,3648.16516,N,12147.28485,W,0.136,0.00,130120,,,A*73
2020-01-13T17:04:43.256Z,1578935083.256 [NAL9602](INFO): GPS fix at 20200113T170442: (36.802753, -121.788081)
2020-01-13T17:04:43.399Z,1578935083.399 [Default:CheckIn:Read_GPS] Stopped
2020-01-13T17:04:43.399Z,1578935083.399 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-13T17:04:43.759Z,1578935083.759 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-01-13T17:04:44.719Z,1578935084.719 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 9AA11C5E040001005D00000400180300000000000000151D80030C5669BF3148E2FA22188B6A3173E6960F887FF8A99E669AC91D474B0DB47F940EC581E0D567
2020-01-13T17:04:44.720Z,1578935084.720 [Micromodem](INFO): Outgoing frame #2, 64 bytes: 266CC0E6F1A517638CB0C0B8BE372A0B00F6D61E05F93094AA77DEFC753FE61F094712E1114D8E0AD7B6106F0F7B016211E5CA62E332602D1D41ACBF7CDB6A31
2020-01-13T17:04:44.720Z,1578935084.720 [Micromodem](INFO): Outgoing frame #3, 64 bytes: 6612EED8A8416BB29191C1CCB1D608CF1AC7D5382E0295D324E3FB9C1C2302F2C3889792149C6D6DF406A218E47F75290FD65E23824F901508F051383D0A8ED3
2020-01-13T17:04:44.721Z,1578935084.721 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69

2020-01-13T17:04:45.001Z,1578935085.001 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B

2020-01-13T17:04:45.363Z,1578935085.363 [Micromodem](INFO): Nmea in: $CADRQ,170445,1,12,0,64,1*77

2020-01-13T17:04:45.363Z,1578935085.363 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,9AA11C5E040001005D00000400180300000000000000151D80030C5669BF3148E2FA22188B6A3173E6960F887FF8A99E669AC91D474B0DB47F940EC581E0D567*34

2020-01-13T17:04:45.766Z,1578935085.766 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-13T17:04:46.167Z,1578935086.167 [Micromodem](INFO): Nmea in: $CADRQ,170446,1,12,0,64,2*77

2020-01-13T17:04:46.167Z,1578935086.167 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,266CC0E6F1A517638CB0C0B8BE372A0B00F6D61E05F93094AA77DEFC753FE61F094712E1114D8E0AD7B6106F0F7B016211E5CA62E332602D1D41ACBF7CDB6A31*3C

2020-01-13T17:04:46.570Z,1578935086.570 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-13T17:04:46.987Z,1578935086.987 [Micromodem](INFO): Nmea in: $CADRQ,170447,1,12,0,64,3*77

2020-01-13T17:04:46.987Z,1578935086.987 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,6612EED8A8416BB29191C1CCB1D608CF1AC7D5382E0295D324E3FB9C1C2302F2C3889792149C6D6DF406A218E47F75290FD65E23824F901508F051383D0A8ED3*39

2020-01-13T17:04:47.378Z,1578935087.378 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-13T17:04:47.794Z,1578935087.794 [Micromodem](INFO): Nmea in: $CATXP,192*48

2020-01-13T17:04:51.427Z,1578935091.427 [Micromodem](INFO): Nmea in: $CATXF,192*5E

2020-01-13T17:04:51.824Z,1578935091.824 [Micromodem](INFO): Nmea in: $CAXST,6,20200113,170447.983589,3,0,200,4000,10000,1,1,12,1,3,3,3,192*7E

2020-01-13T17:04:53.035Z,1578935093.035 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-13T17:04:53.439Z,1578935093.439 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-13T17:04:53.843Z,1578935093.843 [Micromodem](INFO): Nmea in: $CAACK,12,1,3,1*7B

2020-01-13T17:04:53.843Z,1578935093.843 [Micromodem](INFO): Sent 184 bytes from file Logs/20200113T165802/Courier0004.lzma.parts
2020-01-13T17:04:53.844Z,1578935093.844 [Micromodem](INFO): Packets left to send: 1
2020-01-13T17:04:54.287Z,1578935094.287 [Micromodem](INFO): Nmea in: $CACST,6,0,20200113170452.539603,06,594,27,0128,0150,59,02,02,00,04,1,012,001,0,4,1,0,150,-7.8,6.00,14,-3.00,-01,-0.07,45,10000,4000*51

2020-01-13T17:04:54.866Z,1578935094.866 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 9AA11C5E04000000F62E8CD8314AF937CD3E0227693AC2C8DC780FF862C74FEBF64902CA5ACCD1CC6B568C8167815421FB2C9C26C44CE60287EC4FE7F3A75034
2020-01-13T17:04:54.866Z,1578935094.866 [Micromodem](INFO): Outgoing frame #2, 30 bytes: BE3780B5B21EF1C8A75D3A6A0D0123257EACC2554373EC7A309263408400
2020-01-13T17:04:54.867Z,1578935094.867 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,2*68

2020-01-13T17:04:55.321Z,1578935095.321 [DataOverHttps](INFO): Sending 270 bytes from file Logs/20200113T165802/Courier0004.lzma
2020-01-13T17:04:55.818Z,1578935095.818 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,2*6A

2020-01-13T17:04:56.215Z,1578935096.215 [Micromodem](INFO): Nmea in: $CADRQ,170455,1,12,0,64,1*76

2020-01-13T17:04:56.215Z,1578935096.215 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,9AA11C5E04000000F62E8CD8314AF937CD3E0227693AC2C8DC780FF862C74FEBF64902CA5ACCD1CC6B568C8167815421FB2C9C26C44CE60287EC4FE7F3A75034*4C

2020-01-13T17:04:56.624Z,1578935096.624 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-13T17:04:57.021Z,1578935097.021 [Micromodem](INFO): Nmea in: $CADRQ,170457,1,12,0,64,2*77

2020-01-13T17:04:57.021Z,1578935097.021 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,BE3780B5B21EF1C8A75D3A6A0D0123257EACC2554373EC7A309263408400*39

2020-01-13T17:04:57.326Z,1578935097.326 [DataOverHttps](INFO): Moved sent file to Logs/20200113T165802/Courier0004.lzma.bak
2020-01-13T17:04:57.326Z,1578935097.326 [DataOverHttps](INFO): SBD MOMSN=12191089
2020-01-13T17:04:57.430Z,1578935097.430 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,30*4A

2020-01-13T17:04:57.834Z,1578935097.834 [Micromodem](INFO): Nmea in: $CATXP,94*7F

2020-01-13T17:05:00.254Z,1578935100.254 [Micromodem](INFO): Nmea in: $CATXF,94*69

2020-01-13T17:05:00.659Z,1578935100.659 [Micromodem](INFO): Nmea in: $CAXST,6,20200113,170457.968194,3,0,200,4000,10000,1,1,12,1,2,2,3,94*45

2020-01-13T17:05:01.475Z,1578935101.475 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-13T17:05:01.871Z,1578935101.871 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-13T17:05:01.871Z,1578935101.871 [Micromodem](INFO): Sent 86 bytes from file Logs/20200113T165802/Courier0004.lzma.parts
2020-01-13T17:05:01.872Z,1578935101.872 [Micromodem](INFO): Packets left to send: 0
2020-01-13T17:05:02.278Z,1578935102.278 [Micromodem](INFO): Nmea in: $CACST,6,0,20200113170501.215458,06,175,22,0108,0150,59,01,01,00,03,1,012,001,0,4,1,0,150,17.2,5.00,11,-2.00,-01,-0.07,28,10000,4000*42

2020-01-13T17:05:03.675Z,1578935103.675 [Default:CheckIn:Read_Iridium] Stopped
2020-01-13T17:05:03.675Z,1578935103.675 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-13T17:05:03.675Z,1578935103.675 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-13T17:05:15.427Z,1578935115.427 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-13T17:05:17.152Z,1578935117.152 [DataOverHttps](INFO): Sending 1411 bytes from file Logs/20200110T012716/Express0001.lzma
2020-01-13T17:05:19.174Z,1578935119.174 [DataOverHttps](INFO): Moved sent file to Logs/20200110T012716/Express0001.lzma.bak
2020-01-13T17:05:19.174Z,1578935119.174 [DataOverHttps](INFO): SBD MOMSN=12191092
2020-01-13T17:05:36.244Z,1578935136.244 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-01-13T17:05:36.252Z,1578935136.252 [BPC1](INFO): Received data from all battery sticks.
2020-01-13T17:06:26.315Z,1578935186.315 [RDI_Pathfinder](ERROR): only read 0 of 4 data items
2020-01-13T17:06:26.316Z,1578935186.316 [RDI_Pathfinder](ERROR): Failed to parse:
:RA   0.00,   0.00,   0.00,   0.00
2020-01-13T17:10:04.094Z,1578935404.094 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-13T17:10:04.094Z,1578935404.094 [Default:CheckIn:C.Wait] Stopped
2020-01-13T17:10:04.095Z,1578935404.095 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-13T17:10:04.095Z,1578935404.095 [Default:CheckIn:D] Running Loop=1
2020-01-13T17:10:04.501Z,1578935404.501 [Default:CheckIn:D] Stopped
2020-01-13T17:10:04.502Z,1578935404.502 [Default:CheckIn:E] Running Loop=1
2020-01-13T17:10:04.898Z,1578935404.898 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 5.588662 min 
2020-01-13T17:10:04.898Z,1578935404.898 [Default:CheckIn:E] Stopped
2020-01-13T17:10:04.898Z,1578935404.898 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-13T17:10:04.898Z,1578935404.898 [Default:CheckIn] Stopped
2020-01-13T17:10:04.898Z,1578935404.898 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-13T17:10:04.898Z,1578935404.898 [Default:CheckIn](INFO): Running loop #2
2020-01-13T17:10:04.898Z,1578935404.898 [Default:CheckIn] Running Loop=2
2020-01-13T17:10:04.898Z,1578935404.898 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-13T17:10:04.898Z,1578935404.898 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-13T17:10:06.890Z,1578935406.890 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171005.00,A,3648.16909,N,12147.29765,W,0.428,0.00,130120,,,A*71
2020-01-13T17:10:06.896Z,1578935406.896 [NAL9602](INFO): GPS fix at 20200113T171005: (36.802818, -121.788294)
2020-01-13T17:10:06.976Z,1578935406.976 [Default:CheckIn:Read_GPS] Stopped
2020-01-13T17:10:06.976Z,1578935406.976 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-13T17:10:08.948Z,1578935408.948 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 9AA11C5E070001005D00000400AD0100000000000000151D80030C5669BF3148E2FA22188B6A3173E5573572661C0BCBDFFAB423BD32D9A51E8904B0A920CE9A
2020-01-13T17:10:08.949Z,1578935408.949 [Micromodem](INFO): Outgoing frame #2, 64 bytes: EF9F66414C9B9FD6761AD008C3E3DC4FCA2E4E25B6359B14B772C42BBF445E54CD5F36943A3BDD9E89309E9017DB79DD009A333A47CF42F3BE4E4AD4301D597F
2020-01-13T17:10:08.949Z,1578935408.949 [Micromodem](INFO): Outgoing frame #3, 64 bytes: FFA3EC7FA24353C087A3A1B3BD161F92F69707865251F3B93C9772C25CD67B52231335EF84B8E92D5FFA309E6A3CA3D471ADAED835A8CC621FCC9AF57AA69DA7
2020-01-13T17:10:08.949Z,1578935408.949 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69

2020-01-13T17:10:09.134Z,1578935409.134 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B

2020-01-13T17:10:09.533Z,1578935409.533 [Micromodem](INFO): Nmea in: $CADRQ,171009,1,12,0,64,1*7A

2020-01-13T17:10:09.534Z,1578935409.534 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,9AA11C5E070001005D00000400AD0100000000000000151D80030C5669BF3148E2FA22188B6A3173E5573572661C0BCBDFFAB423BD32D9A51E8904B0A920CE9A*40

2020-01-13T17:10:09.936Z,1578935409.936 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-13T17:10:10.335Z,1578935410.335 [Micromodem](INFO): Nmea in: $CADRQ,171010,1,12,0,64,2*71

2020-01-13T17:10:10.335Z,1578935410.335 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,EF9F66414C9B9FD6761AD008C3E3DC4FCA2E4E25B6359B14B772C42BBF445E54CD5F36943A3BDD9E89309E9017DB79DD009A333A47CF42F3BE4E4AD4301D597F*3D

2020-01-13T17:10:10.777Z,1578935410.777 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-13T17:10:11.147Z,1578935411.147 [Micromodem](INFO): Nmea in: $CADRQ,171011,1,12,0,64,3*71

2020-01-13T17:10:11.148Z,1578935411.148 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,FFA3EC7FA24353C087A3A1B3BD161F92F69707865251F3B93C9772C25CD67B52231335EF84B8E92D5FFA309E6A3CA3D471ADAED835A8CC621FCC9AF57AA69DA7*4A

2020-01-13T17:10:11.542Z,1578935411.542 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-13T17:10:11.953Z,1578935411.953 [Micromodem](INFO): Nmea in: $CATXP,192*48

2020-01-13T17:10:15.594Z,1578935415.594 [Micromodem](INFO): Nmea in: $CATXF,192*5E

2020-01-13T17:10:15.992Z,1578935415.992 [Micromodem](INFO): Nmea in: $CAXST,6,20200113,171012.149447,3,0,200,4000,10000,1,1,12,1,3,3,3,192*76

2020-01-13T17:10:16.025Z,1578935416.025 [DataOverHttps](INFO): Sending 245 bytes from file Logs/20200113T165802/Courier0007.lzma
2020-01-13T17:10:17.203Z,1578935417.203 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-13T17:10:17.607Z,1578935417.607 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-13T17:10:18.008Z,1578935418.008 [Micromodem](INFO): Nmea in: $CAACK,12,1,3,1*7B

2020-01-13T17:10:18.008Z,1578935418.008 [Micromodem](INFO): Sent 184 bytes from file Logs/20200113T165802/Courier0007.lzma.parts
2020-01-13T17:10:18.009Z,1578935418.009 [Micromodem](INFO): Packets left to send: 1
2020-01-13T17:10:18.030Z,1578935418.030 [DataOverHttps](INFO): Moved sent file to Logs/20200113T165802/Courier0007.lzma.bak
2020-01-13T17:10:18.030Z,1578935418.030 [DataOverHttps](INFO): SBD MOMSN=12191133
2020-01-13T17:10:18.426Z,1578935418.426 [Micromodem](INFO): Nmea in: $CACST,6,0,20200113171016.702627,06,454,26,0093,0150,89,02,02,00,03,1,012,001,0,4,1,0,150,14.8,5.00,12,-2.00,-01,-0.06,36,10000,4000*41

2020-01-13T17:10:19.106Z,1578935419.106 [Default:CheckIn:Read_Iridium] Stopped
2020-01-13T17:10:19.106Z,1578935419.106 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-13T17:10:19.106Z,1578935419.106 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-13T17:10:37.327Z,1578935437.327 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2020-01-13T17:10:37.405Z,1578935437.405 [NAL9602](FAULT): received: 
+CSQ:0

OK
2020-01-13T17:10:37.405Z,1578935437.405 [NAL9602] Data Fault, FailCount= 1
2020-01-13T17:10:37.405Z,1578935437.405 [NAL9602](ERROR): Data Fault
2020-01-13T17:10:37.501Z,1578935437.501 [CBIT](ERROR): Data Fault in component: NAL9602
2020-01-13T17:10:37.730Z,1578935437.730 [NAL9602](INFO): Powering down
2020-01-13T17:10:38.597Z,1578935438.597 [CBIT](INFO): Clearing failed state for component NAL9602
2020-01-13T17:10:38.598Z,1578935438.598 [NAL9602] No Fault, FailCount= 1
2020-01-13T17:11:02.454Z,1578935462.454 [DataOverHttps](INFO): Sending 236 bytes from file Logs/20200110T012716/Express0005.lzma
2020-01-13T17:11:04.458Z,1578935464.458 [DataOverHttps](INFO): Moved sent file to Logs/20200110T012716/Express0005.lzma.bak
2020-01-13T17:11:04.458Z,1578935464.458 [DataOverHttps](INFO): SBD MOMSN=12191136
2020-01-13T17:11:08.031Z,1578935468.031 [NAL9602](INFO): Powering up NAL9602
2020-01-13T17:11:18.943Z,1578935478.943 [NAL9602](INFO): NAL9602 initialized
2020-01-13T17:11:35.948Z,1578935495.948 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-01-13T17:11:35.957Z,1578935495.957 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-2768,-32768,V
2020-01-13T17:11:50.052Z,1578935510.052 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-13T17:12:15.947Z,1578935535.947 [RDI_Pathfinder](ERROR): Failed to parse:
68,-32768,-32768,V
2020-01-13T17:13:13.310Z,1578935593.310 [CBIT](INFO): Clearing failed state for component DropWeight
2020-01-13T17:13:13.310Z,1578935593.310 [DropWeight] No Fault, FailCount= 1
2020-01-13T17:15:19.353Z,1578935719.353 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-13T17:15:19.353Z,1578935719.353 [Default:CheckIn:C.Wait] Stopped
2020-01-13T17:15:19.353Z,1578935719.353 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-13T17:15:19.353Z,1578935719.353 [Default:CheckIn:D] Running Loop=1
2020-01-13T17:15:19.757Z,1578935719.757 [Default:CheckIn:D] Stopped
2020-01-13T17:15:19.757Z,1578935719.757 [Default:CheckIn:E] Running Loop=1
2020-01-13T17:15:20.169Z,1578935720.169 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.842922 min 
2020-01-13T17:15:20.170Z,1578935720.170 [Default:CheckIn:E] Stopped
2020-01-13T17:15:20.170Z,1578935720.170 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-13T17:15:20.170Z,1578935720.170 [Default:CheckIn] Stopped
2020-01-13T17:15:20.170Z,1578935720.170 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-13T17:15:20.170Z,1578935720.170 [Default:CheckIn](INFO): Running loop #3
2020-01-13T17:15:20.170Z,1578935720.170 [Default:CheckIn] Running Loop=3
2020-01-13T17:15:20.170Z,1578935720.170 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-13T17:15:20.170Z,1578935720.170 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-13T17:15:24.583Z,1578935724.583 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-01-13T17:16:13.104Z,1578935773.104 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-01-13T17:16:13.104Z,1578935773.104 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +     +0.00,        +0.00,   0.00,1066.95
2020-01-13T17:18:53.099Z,1578935933.099 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-13T17:18:53.100Z,1578935933.100 [RDI_Pathfinder](ERROR): Failed to parse:
:TS-32768,-32768,V
2020-01-13T17:18:58.296Z,1578935938.296 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171857.00,A,3648.16848,N,12147.27606,W,2.119,100.45,130120,,,A*75
2020-01-13T17:18:58.299Z,1578935938.299 [NAL9602](INFO): GPS fix at 20200113T171857: (36.802808, -121.787934)
2020-01-13T17:18:58.323Z,1578935938.323 [Default:CheckIn:Read_GPS] Stopped
2020-01-13T17:18:58.324Z,1578935938.324 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-13T17:18:59.932Z,1578935939.932 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 9AA11C5E0A0000005D00000400610000000000000000151D80030C5669C08C34EE6EA62AB180B479A8D0F3AD05D02568BB8D9223179CA9F5522C31773AFAB5A6
2020-01-13T17:18:59.932Z,1578935939.932 [Micromodem](INFO): Outgoing frame #2, 28 bytes: 4DE0ED18B811B551AF9CA30FA6A56297C33FA6E02C1423554232F600
2020-01-13T17:18:59.932Z,1578935939.932 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,2*68

2020-01-13T17:19:00.173Z,1578935940.173 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,2*6A

2020-01-13T17:19:00.539Z,1578935940.539 [Micromodem](INFO): Nmea in: $CADRQ,171900,1,12,0,64,1*7A

2020-01-13T17:19:00.539Z,1578935940.539 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,9AA11C5E0A0000005D00000400610000000000000000151D80030C5669C08C34EE6EA62AB180B479A8D0F3AD05D02568BB8D9223179CA9F5522C31773AFAB5A6*39

2020-01-13T17:19:00.958Z,1578935940.958 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-13T17:19:01.347Z,1578935941.347 [Micromodem](INFO): Nmea in: $CADRQ,171901,1,12,0,64,2*78

2020-01-13T17:19:01.348Z,1578935941.348 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,4DE0ED18B811B551AF9CA30FA6A56297C33FA6E02C1423554232F600*3A

2020-01-13T17:19:01.755Z,1578935941.755 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,28*43

2020-01-13T17:19:02.158Z,1578935942.158 [Micromodem](INFO): Nmea in: $CATXP,92*79

2020-01-13T17:19:04.583Z,1578935944.583 [Micromodem](INFO): Nmea in: $CATXF,92*6F

2020-01-13T17:19:04.984Z,1578935944.984 [Micromodem](INFO): Nmea in: $CAXST,6,20200113,171902.299770,3,0,200,4000,10000,1,1,12,1,2,2,3,92*46

2020-01-13T17:19:05.795Z,1578935945.795 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-13T17:19:06.199Z,1578935946.199 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-13T17:19:06.200Z,1578935946.200 [Micromodem](INFO): Sent 84 bytes from file Logs/20200113T165802/Courier0010.lzma.parts
2020-01-13T17:19:06.200Z,1578935946.200 [Micromodem](INFO): Packets left to send: 0
2020-01-13T17:19:06.602Z,1578935946.602 [Micromodem](INFO): Nmea in: $CACST,6,0,20200113171905.547901,06,576,27,0106,0150,59,01,01,00,03,1,012,001,0,4,1,0,150,24.7,5.00,15,-2.00,-01,-0.08,23,10000,4000*43

2020-01-13T17:19:07.810Z,1578935947.810 [Default:CheckIn:Read_Iridium] Stopped
2020-01-13T17:19:07.810Z,1578935947.810 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-13T17:19:07.810Z,1578935947.810 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-13T17:19:08.873Z,1578935948.873 [DataOverHttps](INFO): Sending 1435 bytes from file Logs/20200110T013425/Express0001.lzma
2020-01-13T17:19:10.894Z,1578935950.894 [DataOverHttps](INFO): Moved sent file to Logs/20200110T013425/Express0001.lzma.bak
2020-01-13T17:19:10.894Z,1578935950.894 [DataOverHttps](INFO): SBD MOMSN=12191141
2020-01-13T17:19:12.356Z,1578935952.356 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-13T17:19:12.356Z,1578935952.356 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20011310320602,35.0, -0.1,   0.0,1448.9A   0.00,   0.00
2020-01-13T17:19:30.498Z,1578935970.498 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-13T17:22:08.917Z,1578936128.917 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-13T17:22:08.917Z,1578936128.917 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20011310350302,35.0, -0.1,   0.0,10
2020-01-13T17:23:29.278Z,1578936209.278 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2020-01-13T17:24:05.634Z,1578936245.634 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-13T17:24:05.634Z,1578936245.634 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20011310365902,35.0, -0.1,448.9,  0
2020-01-13T17:24:08.076Z,1578936248.076 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-13T17:24:08.076Z,1578936248.076 [Default:CheckIn:C.Wait] Stopped
2020-01-13T17:24:08.076Z,1578936248.076 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-13T17:24:08.077Z,1578936248.077 [Default:CheckIn:D] Running Loop=1
2020-01-13T17:24:08.481Z,1578936248.481 [Default:CheckIn:D] Stopped
2020-01-13T17:24:08.481Z,1578936248.481 [Default:CheckIn:E] Running Loop=1
2020-01-13T17:24:08.917Z,1578936248.917 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 19.654991 min 
2020-01-13T17:24:08.917Z,1578936248.917 [Default:CheckIn:E] Stopped
2020-01-13T17:24:08.917Z,1578936248.917 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-13T17:24:08.917Z,1578936248.917 [Default:CheckIn] Stopped
2020-01-13T17:24:08.917Z,1578936248.917 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-13T17:24:08.917Z,1578936248.917 [Default:CheckIn](INFO): Running loop #4
2020-01-13T17:24:08.917Z,1578936248.917 [Default:CheckIn] Running Loop=4
2020-01-13T17:24:08.918Z,1578936248.918 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-13T17:24:08.918Z,1578936248.918 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-13T17:24:10.880Z,1578936250.880 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172410.00,A,3648.16268,N,12147.23782,W,1.108,0.00,130120,,,A*7B
2020-01-13T17:24:10.882Z,1578936250.882 [NAL9602](INFO): GPS fix at 20200113T172410: (36.802711, -121.787297)
2020-01-13T17:24:10.958Z,1578936250.958 [Default:CheckIn:Read_GPS] Stopped
2020-01-13T17:24:10.958Z,1578936250.958 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-13T17:24:12.787Z,1578936252.787 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 9AA11C5E0D0000005D00000400700000000000000000151D80030C5669C010E4BA60DD17BEC358771124919000E20465EFE7EB5637469812D005FD73BE663E28
2020-01-13T17:24:12.788Z,1578936252.788 [Micromodem](INFO): Outgoing frame #2, 38 bytes: 5DB84611632D6AFEBB9B765BFD3E83B37E29A46AD5D0C17AC51548A4576F28811E462A230600
2020-01-13T17:24:12.788Z,1578936252.788 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,2*68

2020-01-13T17:24:12.994Z,1578936252.994 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,2*6A

2020-01-13T17:24:13.343Z,1578936253.343 [Micromodem](INFO): Nmea in: $CADRQ,172413,1,12,0,64,1*76

2020-01-13T17:24:13.343Z,1578936253.343 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,9AA11C5E0D0000005D00000400700000000000000000151D80030C5669C010E4BA60DD17BEC358771124919000E20465EFE7EB5637469812D005FD73BE663E28*32

2020-01-13T17:24:13.751Z,1578936253.751 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-13T17:24:14.162Z,1578936254.162 [Micromodem](INFO): Nmea in: $CADRQ,172414,1,12,0,64,2*72

2020-01-13T17:24:14.162Z,1578936254.162 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,5DB84611632D6AFEBB9B765BFD3E83B37E29A46AD5D0C17AC51548A4576F28811E462A230600*44

2020-01-13T17:24:14.587Z,1578936254.587 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,38*42

2020-01-13T17:24:14.967Z,1578936254.967 [Micromodem](INFO): Nmea in: $CATXP,102*41

2020-01-13T17:24:17.387Z,1578936257.387 [Micromodem](INFO): Nmea in: $CATXF,102*57

2020-01-13T17:24:18.601Z,1578936258.601 [Micromodem](INFO): Nmea in: $CAXST,6,20200113,172415.119713,3,0,200,4000,10000,1,1,12,1,2,2,3,102*78

2020-01-13T17:24:19.006Z,1578936259.006 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-13T17:24:19.403Z,1578936259.403 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-13T17:24:19.404Z,1578936259.404 [Micromodem](INFO): Sent 94 bytes from file Logs/20200113T165802/Courier0013.lzma.parts
2020-01-13T17:24:19.404Z,1578936259.404 [Micromodem](INFO): Packets left to send: 0
2020-01-13T17:24:19.814Z,1578936259.814 [Micromodem](INFO): Nmea in: $CACST,6,0,20200113172418.376114,06,197,22,0098,0150,59,01,01,00,03,1,012,001,0,4,1,0,150,26.4,6.00,15,-3.00,-01,-0.06,22,10000,4000*4D

2020-01-13T17:24:21.001Z,1578936261.001 [Default:CheckIn:Read_Iridium] Stopped
2020-01-13T17:24:21.001Z,1578936261.001 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-13T17:24:21.001Z,1578936261.001 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-13T17:24:21.588Z,1578936261.588 [DataOverHttps](INFO): Sending 240 bytes from file Logs/20200110T013425/Express0005.lzma
2020-01-13T17:24:23.598Z,1578936263.598 [DataOverHttps](INFO): Moved sent file to Logs/20200110T013425/Express0005.lzma.bak
2020-01-13T17:24:23.598Z,1578936263.598 [DataOverHttps](INFO): SBD MOMSN=12191185
2020-01-13T17:28:26.294Z,1578936506.294 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2020-01-13T17:28:36.372Z,1578936516.372 [NAL9602](INFO): SBD MO Status=2, MOMSN=10158, MT Status=2, MTMSN=0
2020-01-13T17:28:36.372Z,1578936516.372 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-01-13T17:29:13.147Z,1578936553.147 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-01-13T17:29:21.251Z,1578936561.251 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-13T17:29:21.251Z,1578936561.251 [Default:CheckIn:C.Wait] Stopped
2020-01-13T17:29:21.251Z,1578936561.251 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-13T17:29:21.251Z,1578936561.251 [Default:CheckIn:D] Running Loop=1
2020-01-13T17:29:21.650Z,1578936561.650 [Default:CheckIn:D] Stopped
2020-01-13T17:29:21.650Z,1578936561.650 [Default:CheckIn:E] Running Loop=1
2020-01-13T17:29:22.070Z,1578936562.070 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 24.874479 min 
2020-01-13T17:29:22.070Z,1578936562.070 [Default:CheckIn:E] Stopped
2020-01-13T17:29:22.070Z,1578936562.070 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-13T17:29:22.070Z,1578936562.070 [Default:CheckIn] Stopped
2020-01-13T17:29:22.070Z,1578936562.070 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-13T17:29:22.070Z,1578936562.070 [Default:CheckIn](INFO): Running loop #5
2020-01-13T17:29:22.070Z,1578936562.070 [Default:CheckIn] Running Loop=5
2020-01-13T17:29:22.070Z,1578936562.070 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-13T17:29:22.070Z,1578936562.070 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-13T17:29:24.039Z,1578936564.039 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172923.00,A,3648.16355,N,12147.28830,W,0.700,96.18,130120,,,A*4D
2020-01-13T17:29:24.055Z,1578936564.055 [NAL9602](INFO): GPS fix at 20200113T172923: (36.802726, -121.788138)
2020-01-13T17:29:24.079Z,1578936564.079 [Default:CheckIn:Read_GPS] Stopped
2020-01-13T17:29:24.079Z,1578936564.079 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-13T17:29:25.885Z,1578936565.885 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 9AA11C5E100000005D00000400700000000000000000151D80030C5669C010E4BA4A656052EBEE4DFF04D35799FA53A498D05FD90973EFA6173ACD3BFF4BB491
2020-01-13T17:29:25.886Z,1578936565.886 [Micromodem](INFO): Outgoing frame #2, 38 bytes: E3D4759AB3BB24AE1F4655A0534AD6C70F42EB8FA78326B950ACFC7F1695A0CFC5DA4584EB00
2020-01-13T17:29:25.886Z,1578936565.886 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,2*68

2020-01-13T17:29:26.097Z,1578936566.097 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,2*6A

2020-01-13T17:29:26.467Z,1578936566.467 [Micromodem](INFO): Nmea in: $CADRQ,172926,1,12,0,64,1*7D

2020-01-13T17:29:26.467Z,1578936566.467 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,9AA11C5E100000005D00000400700000000000000000151D80030C5669C010E4BA4A656052EBEE4DFF04D35799FA53A498D05FD90973EFA6173ACD3BFF4BB491*3A

2020-01-13T17:29:26.882Z,1578936566.882 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-13T17:29:27.279Z,1578936567.279 [Micromodem](INFO): Nmea in: $CADRQ,172927,1,12,0,64,2*7F

2020-01-13T17:29:27.279Z,1578936567.279 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,E3D4759AB3BB24AE1F4655A0534AD6C70F42EB8FA78326B950ACFC7F1695A0CFC5DA4584EB00*4B

2020-01-13T17:29:27.683Z,1578936567.683 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,38*42

2020-01-13T17:29:28.086Z,1578936568.086 [Micromodem](INFO): Nmea in: $CATXP,102*41

2020-01-13T17:29:30.507Z,1578936570.507 [Micromodem](INFO): Nmea in: $CATXF,102*57

2020-01-13T17:29:31.728Z,1578936571.728 [Micromodem](INFO): Nmea in: $CAXST,6,20200113,172928.239976,3,0,200,4000,10000,1,1,12,1,2,2,3,102*77

2020-01-13T17:29:33.388Z,1578936573.388 [DataOverHttps](INFO): Sending 94 bytes from file Logs/20200113T165802/Courier0016.lzma
2020-01-13T17:29:35.394Z,1578936575.394 [DataOverHttps](INFO): Moved sent file to Logs/20200113T165802/Courier0016.lzma.bak
2020-01-13T17:29:35.395Z,1578936575.395 [DataOverHttps](INFO): SBD MOMSN=12191188
2020-01-13T17:29:54.454Z,1578936594.454 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20200110T013425/Express0008.lzma
2020-01-13T17:29:56.458Z,1578936596.458 [DataOverHttps](INFO): Moved sent file to Logs/20200110T013425/Express0008.lzma.bak
2020-01-13T17:29:56.458Z,1578936596.458 [DataOverHttps](INFO): SBD MOMSN=12191191
2020-01-13T17:29:56.771Z,1578936596.771 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-13T17:30:07.298Z,1578936607.298 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-13T17:30:07.298Z,1578936607.298 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-01-13T17:30:16.672Z,1578936616.672 [DataOverHttps](INFO): Sending 1385 bytes from file Logs/20200110T014708/Express0001.lzma
2020-01-13T17:30:18.674Z,1578936618.674 [DataOverHttps](INFO): Moved sent file to Logs/20200110T014708/Express0001.lzma.bak
2020-01-13T17:30:18.674Z,1578936618.674 [DataOverHttps](INFO): SBD MOMSN=12191194
2020-01-13T17:30:31.111Z,1578936631.111 [Micromodem](ERROR): Buffer fill timout failure.
2020-01-13T17:30:32.290Z,1578936632.290 [Default:CheckIn:Read_Iridium] Stopped
2020-01-13T17:30:32.290Z,1578936632.290 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-13T17:30:32.290Z,1578936632.290 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-13T17:30:37.691Z,1578936637.691 [DataOverHttps](INFO): Sending 1415 bytes from file Logs/20200110T015429/Express0001.lzma
2020-01-13T17:30:39.670Z,1578936639.670 [DataOverHttps](INFO): Moved sent file to Logs/20200110T015429/Express0001.lzma.bak
2020-01-13T17:30:39.670Z,1578936639.670 [DataOverHttps](INFO): SBD MOMSN=12191235
2020-01-13T17:30:48.937Z,1578936648.937 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-01-13T17:30:48.937Z,1578936648.937 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +0.00,        +0.00,     ,   0.00,1942.85
2020-01-13T17:32:02.761Z,1578936722.761 [CommandLine](IMPORTANT): got command failComponent
2020-01-13T17:32:02.762Z,1578936722.762 [CommandLine](IMPORTANT): Failed components:
2020-01-13T17:32:02.762Z,1578936722.762 [CommandLine](IMPORTANT): No failed Components.
2020-01-13T17:32:10.133Z,1578936730.133 [RDI_Pathfinder](ERROR): Failed to parse:
:B  +0.00,        +0.00,        +0.00,   0.00,2022.84
2020-01-13T17:32:14.747Z,1578936734.747 [CommandLine](IMPORTANT): got command run ./Missions/Maintenance/DUSBL.xml
2020-01-13T17:32:14.747Z,1578936734.747 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/DUSBL.xml
2020-01-13T17:32:14.794Z,1578936734.794 [MissionManager](INFO): DefineArg DUSBL.MissionTimeout = 90.000000 min
2020-01-13T17:32:14.796Z,1578936734.796 [MissionManager](INFO): DefineArg DUSBL.NumberOfRequests = 10.000000 count
2020-01-13T17:32:14.798Z,1578936734.798 [MissionManager](INFO): DefineArg DUSBL.TransponderCode = 2.000000 count
2020-01-13T17:32:14.800Z,1578936734.800 [MissionManager](INFO): DefineArg DUSBL.NumberOfPings = 1.000000 count
2020-01-13T17:32:14.802Z,1578936734.802 [MissionManager](INFO): DefineArg DUSBL.EnabledDUSBL = DUSBL_Hydroid.loadAtStartup
2020-01-13T17:32:14.803Z,1578936734.803 [DUSBL:A.Pitch](DEBUG): Construct.
2020-01-13T17:32:14.807Z,1578936734.807 [DUSBL:B.SetSpeed](DEBUG): Construct.
2020-01-13T17:32:14.815Z,1578936734.815 [DUSBL:RequestRepeater:C.Wait](DEBUG): Construct Wait.
2020-01-13T17:32:14.843Z,1578936734.843 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<!-- ====================
     Generated by TethysL
     ==================== -->
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Control="Tethys/Control"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd"
       Id="DUSBL">

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

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

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

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

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

    <Timeout Duration="MissionTimeout"/>

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

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

    <ReadData>
        <When>
            <Arg Name="EnabledDUSBL"/>
        </When>
            <Universal:acoustic_contact_range/>
            <Universal:acoustic_contact_direction_vehicle_frame/>
    </ReadData>
    
      <Aggregate Id="RequestRepeater">

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


2020-01-13T17:32:14.844Z,1578936734.844 [CommandLine](IMPORTANT): Running ./Missions/Maintenance/DUSBL.xml
2020-01-13T17:32:14.965Z,1578936734.965 [Default] Stopped
2020-01-13T17:32:14.965Z,1578936734.965 [Default](DEBUG): Aggregate::uninitialize Default
2020-01-13T17:32:14.965Z,1578936734.965 [Default:B.GoToSurface] Stopped
2020-01-13T17:32:14.965Z,1578936734.965 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-01-13T17:32:14.965Z,1578936734.965 [Default:CheckIn] Stopped
2020-01-13T17:32:14.966Z,1578936734.966 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-13T17:32:14.966Z,1578936734.966 [Default:CheckIn:C.Wait] Stopped
2020-01-13T17:32:14.966Z,1578936734.966 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-13T17:32:14.966Z,1578936734.966 [MissionManager](IMPORTANT): Started mission DUSBL
2020-01-13T17:32:14.966Z,1578936734.966 [DUSBL] Running Loop=1
2020-01-13T17:32:14.966Z,1578936734.966 [DUSBL](DEBUG): Aggregate::initialize DUSBL
2020-01-13T17:32:14.966Z,1578936734.966 [DUSBL:A.Pitch] Running Loop=1
2020-01-13T17:32:14.966Z,1578936734.966 [DUSBL:A.Pitch](DEBUG): Initialize.
2020-01-13T17:32:14.966Z,1578936734.966 [DUSBL:B.SetSpeed] Running Loop=1
2020-01-13T17:32:14.967Z,1578936734.967 [DUSBL:B.SetSpeed](DEBUG): Initialize.
2020-01-13T17:32:14.967Z,1578936734.967 [DUSBL:C] Running Loop=1
2020-01-13T17:32:14.967Z,1578936734.967 [DUSBL:RequestRepeater] Running Loop=1
2020-01-13T17:32:14.967Z,1578936734.967 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2020-01-13T17:32:14.967Z,1578936734.967 [DUSBL:RequestRepeater:A] Running Loop=1
2020-01-13T17:32:14.968Z,1578936734.968 [DUSBL:RequestRepeater:B] Running Loop=1
2020-01-13T17:32:14.968Z,1578936734.968 [DUSBL:RequestRepeater:C.Wait] Running Loop=1
2020-01-13T17:32:14.968Z,1578936734.968 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component.
2020-01-13T17:32:14.968Z,1578936734.968 [DUSBL:RequestRepeater:B] Running Loop=1
2020-01-13T17:32:14.968Z,1578936734.968 [DUSBL:RequestRepeater:A] Running Loop=1
2020-01-13T17:32:14.969Z,1578936734.969 [DUSBL:C](DEBUG): Initialize ReadDataComponent to sense acoustic_contact_range
2020-01-13T17:32:14.970Z,1578936734.970 [DUSBL:C](DEBUG): Initialize ReadDataComponent to sense acoustic_contact_direction_vehicle_frame
2020-01-13T17:32:14.970Z,1578936734.970 [DUSBL:B.SetSpeed] Running Loop=1
2020-01-13T17:32:14.970Z,1578936734.970 [DUSBL:A.Pitch] Running Loop=1
2020-01-13T17:32:16.951Z,1578936736.951 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-13T17:32:17.360Z,1578936737.360 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-13T17:32:18.195Z,1578936738.195 [Micromodem](INFO): Nmea in: $SNTTA,,,,,173217.91*57

2020-01-13T17:32:20.602Z,1578936740.602 [DUSBL_Hydroid](ERROR): No response from remote modem.
2020-01-13T17:32:21.411Z,1578936741.411 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-13T17:32:21.856Z,1578936741.856 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-13T17:32:22.634Z,1578936742.634 [Micromodem](INFO): Nmea in: $SNTTA,,,,,173222.37*5D

2020-01-13T17:32:25.046Z,1578936745.046 [DUSBL_Hydroid](ERROR): No response from remote modem.
2020-01-13T17:32:25.855Z,1578936745.855 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-13T17:32:26.273Z,1578936746.273 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-13T17:32:27.086Z,1578936747.086 [Micromodem](INFO): Nmea in: $SNTTA,,,,,173226.81*54

2020-01-13T17:32:29.490Z,1578936749.490 [DUSBL_Hydroid](ERROR): No response from remote modem.
2020-01-13T17:32:30.299Z,1578936750.299 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-13T17:32:30.705Z,1578936750.705 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-13T17:32:31.519Z,1578936751.519 [Micromodem](INFO): Nmea in: $SNTTA,,,,,173231.26*5F

2020-01-13T17:32:33.934Z,1578936753.934 [DUSBL_Hydroid](ERROR): No response from remote modem.
2020-01-13T17:32:34.743Z,1578936754.743 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-13T17:32:35.158Z,1578936755.158 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-13T17:32:35.960Z,1578936755.960 [Micromodem](INFO): Nmea in: $SNTTA,,,,,173235.70*58

2020-01-13T17:32:38.382Z,1578936758.382 [DUSBL_Hydroid](ERROR): No response from remote modem.
2020-01-13T17:32:39.187Z,1578936759.187 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-13T17:32:39.603Z,1578936759.603 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-13T17:32:40.404Z,1578936760.404 [Micromodem](INFO): Nmea in: $SNTTA,,,,,173240.14*58

2020-01-13T17:32:42.820Z,1578936762.820 [DUSBL_Hydroid](ERROR): No response from remote modem.
2020-01-13T17:32:43.635Z,1578936763.635 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-13T17:32:44.035Z,1578936764.035 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-13T17:32:44.863Z,1578936764.863 [Micromodem](INFO): Nmea in: $SNTTA,,,,,173244.60*5F

2020-01-13T17:32:47.270Z,1578936767.270 [DUSBL_Hydroid](ERROR): No response from remote modem.
2020-01-13T17:32:48.075Z,1578936768.075 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-13T17:32:48.493Z,1578936768.493 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-13T17:32:49.294Z,1578936769.294 [Micromodem](INFO): Nmea in: $SNTTA,,,,,173249.04*50

2020-01-13T17:32:51.714Z,1578936771.714 [DUSBL_Hydroid](ERROR): No response from remote modem.
2020-01-13T17:32:52.530Z,1578936772.530 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-13T17:32:52.929Z,1578936772.929 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-13T17:32:53.744Z,1578936773.744 [Micromodem](INFO): Nmea in: $SNTTA,,,,,173253.49*52

2020-01-13T17:32:56.155Z,1578936776.155 [DUSBL_Hydroid](ERROR): No response from remote modem.
2020-01-13T17:32:56.967Z,1578936776.967 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-13T17:32:57.380Z,1578936777.380 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-13T17:32:58.181Z,1578936778.181 [Micromodem](INFO): Nmea in: $SNTTA,,,,,173257.93*51

2020-01-13T17:33:00.602Z,1578936780.602 [DUSBL_Hydroid](ERROR): No response from remote modem.
2020-01-13T17:33:01.408Z,1578936781.408 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-13T17:33:01.817Z,1578936781.817 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-13T17:33:02.631Z,1578936782.631 [Micromodem](INFO): Nmea in: $SNTTA,,,,,173302.37*5E

2020-01-13T17:33:05.042Z,1578936785.042 [DUSBL_Hydroid](ERROR): No response from remote modem.
2020-01-13T17:33:05.851Z,1578936785.851 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-13T17:33:06.257Z,1578936786.257 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-13T17:33:06.287Z,1578936786.287 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-13T17:33:06.287Z,1578936786.287 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20011310460002,35.0  0.0,1448.9,  0
2020-01-13T17:33:07.075Z,1578936787.075 [Micromodem](INFO): Nmea in: $SNTTA,,,,,173306.81*57

2020-01-13T17:33:09.494Z,1578936789.494 [DUSBL_Hydroid](ERROR): No response from remote modem.
2020-01-13T17:33:10.299Z,1578936790.299 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-13T17:33:10.708Z,1578936790.708 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-13T17:33:11.514Z,1578936791.514 [Micromodem](INFO): Nmea in: $SNTTA,0.239449,,,,173311.26*43

2020-01-13T17:33:13.930Z,1578936793.930 [DUSBL_Hydroid](ERROR): No response from remote modem.
2020-01-13T17:33:14.743Z,1578936794.743 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-13T17:33:15.149Z,1578936795.149 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-13T17:33:15.958Z,1578936795.958 [Micromodem](INFO): Nmea in: $SNTTA,,,0.359000,,173315.70*4A

2020-01-13T17:33:18.378Z,1578936798.378 [DUSBL_Hydroid](ERROR): No response from remote modem.
2020-01-13T17:33:19.204Z,1578936799.204 [Micromodem](INFO): Nmea out: $CCPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*3E

2020-01-13T17:33:19.596Z,1578936799.596 [Micromodem](INFO): Nmea in: $SNPGT,0,14500,28,6F8F7A90,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,2000,4000,0*23

2020-01-13T17:33:19.817Z,1578936799.817 [CommandLine](IMPORTANT): got command stop
2020-01-13T17:33:19.818Z,1578936799.818 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop
2020-01-13T17:33:20.037Z,1578936800.037 [MissionManager](INFO): MissionManager is completed.
2020-01-13T17:33:20.038Z,1578936800.038 [MissionManager](INFO): Uninitializing Mission DUSBL
2020-01-13T17:33:20.038Z,1578936800.038 [DUSBL] Stopped
2020-01-13T17:33:20.038Z,1578936800.038 [DUSBL](DEBUG): Aggregate::uninitialize DUSBL
2020-01-13T17:33:20.038Z,1578936800.038 [DUSBL:A.Pitch] Stopped
2020-01-13T17:33:20.038Z,1578936800.038 [DUSBL:B.SetSpeed] Stopped
2020-01-13T17:33:20.038Z,1578936800.038 [DUSBL:B.SetSpeed](DEBUG): Uninitialize.
2020-01-13T17:33:20.038Z,1578936800.038 [DUSBL:C] Stopped
2020-01-13T17:33:20.038Z,1578936800.038 [DUSBL:RequestRepeater] Stopped
2020-01-13T17:33:20.038Z,1578936800.038 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2020-01-13T17:33:20.038Z,1578936800.038 [DUSBL:RequestRepeater:A] Stopped
2020-01-13T17:33:20.038Z,1578936800.038 [DUSBL:RequestRepeater:B] Stopped
2020-01-13T17:33:20.038Z,1578936800.038 [DUSBL:RequestRepeater:C.Wait] Stopped
2020-01-13T17:33:20.038Z,1578936800.038 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-13T17:33:20.420Z,1578936800.420 [Micromodem](INFO): Nmea in: $SNTTA,,,,,173320.16*5D

2020-01-13T17:33:20.446Z,1578936800.446 [MissionManager](IMPORTANT): Started mission Default
2020-01-13T17:33:20.447Z,1578936800.447 [Default] Running Loop=1
2020-01-13T17:33:20.447Z,1578936800.447 [Default](DEBUG): Aggregate::initialize Default
2020-01-13T17:33:20.447Z,1578936800.447 [Default:B.GoToSurface] Running Loop=1
2020-01-13T17:33:20.447Z,1578936800.447 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-01-13T17:33:20.447Z,1578936800.447 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-01-13T17:33:20.447Z,1578936800.447 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-01-13T17:33:20.448Z,1578936800.448 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-01-13T17:33:20.448Z,1578936800.448 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-01-13T17:33:20.448Z,1578936800.448 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-01-13T17:33:20.449Z,1578936800.449 [Default:A.Wait] Running Loop=1
2020-01-13T17:33:20.449Z,1578936800.449 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-01-13T17:33:21.713Z,1578936801.713 [CommandLine](IMPORTANT): got command quit
2020-01-13T17:33:22.717Z,1578936802.717 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-13T17:33:22.718Z,1578936802.718 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-13T17:33:22.718Z,1578936802.718 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-13T17:33:22.719Z,1578936802.719 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-13T17:33:22.805Z,1578936802.805 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-01-13T17:33:22.805Z,1578936802.805 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-13T17:33:22.806Z,1578936802.806 [CommandLine](INFO): Join timeout helper Thread ID is 7811
2020-01-13T17:33:22.820Z,1578936802.820 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-01-13T17:33:22.820Z,1578936802.820 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-13T17:33:22.821Z,1578936802.821 [NavChartDb](INFO): Join timeout helper Thread ID is 7812
2020-01-13T17:33:23.005Z,1578936803.005 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-13T17:33:23.005Z,1578936803.005 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-13T17:33:23.026Z,1578936803.026 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2020-01-13T17:33:23.026Z,1578936803.026 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-13T17:33:23.027Z,1578936803.027 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 7813
2020-01-13T17:33:23.046Z,1578936803.046 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-13T17:33:23.046Z,1578936803.046 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-01-13T17:33:23.048Z,1578936803.048 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-13T17:33:23.065Z,1578936803.065 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2020-01-13T17:33:23.065Z,1578936803.065 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-13T17:33:23.066Z,1578936803.066 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 7814
2020-01-13T17:33:23.133Z,1578936803.133 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-13T17:33:23.133Z,1578936803.133 [CTD_NeilBrown](INFO): Powering down
2020-01-13T17:33:23.145Z,1578936803.145 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-13T17:33:23.153Z,1578936803.153 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-01-13T17:33:23.153Z,1578936803.153 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-13T17:33:23.153Z,1578936803.153 [Radio_Surface](INFO): Join timeout helper Thread ID is 7815
2020-01-13T17:33:23.385Z,1578936803.385 [Radio_Surface](INFO): Powering down
2020-01-13T17:33:23.386Z,1578936803.386 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-13T17:33:23.386Z,1578936803.386 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-13T17:33:23.402Z,1578936803.402 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-01-13T17:33:23.402Z,1578936803.402 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-13T17:33:23.402Z,1578936803.402 [DataOverHttps](INFO): Join timeout helper Thread ID is 7816
2020-01-13T17:33:23.501Z,1578936803.501 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-13T17:33:23.501Z,1578936803.501 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-13T17:33:23.505Z,1578936803.505 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-01-13T17:33:23.506Z,1578936803.506 [logger ThreadHandler](INFO): Thread cancelled.
2020-01-13T17:33:23.506Z,1578936803.506 [logger](INFO): Join timeout helper Thread ID is 7817
2020-01-13T17:33:23.541Z,1578936803.541 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-13T17:33:23.541Z,1578936803.541 [logger ThreadHandler](INFO): Thread cancelled.
2020-01-13T17:33:23.545Z,1578936803.545 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-01-13T17:33:23.546Z,1578936803.546 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-13T17:33:23.546Z,1578936803.546 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-01-13T17:33:23.546Z,1578936803.546 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-01-13T17:33:23.546Z,1578936803.546 [controlThread](INFO): Join timeout helper Thread ID is 7818
2020-01-13T17:33:23.609Z,1578936803.609 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-13T17:33:23.609Z,1578936803.609 [controlThread](DEBUG): Uninitializing ControlThread
2020-01-13T17:33:23.610Z,1578936803.610 [AHRS_M2](INFO): Powering down
2020-01-13T17:33:23.681Z,1578936803.681 [DDM](INFO): Powering down
2020-01-13T17:33:23.774Z,1578936803.774 [DUSBL_Hydroid](INFO): Powering down
2020-01-13T17:33:23.845Z,1578936803.845 [Micromodem](INFO): Powering down
2020-01-13T17:33:23.941Z,1578936803.941 [NAL9602](INFO): Powering down
2020-01-13T17:33:24.013Z,1578936804.013 [RDI_Pathfinder](INFO): Powering down
2020-01-13T17:33:24.014Z,1578936804.014 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-01-13T17:33:24.015Z,1578936804.015 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-01-13T17:33:24.016Z,1578936804.016 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-01-13T17:33:24.016Z,1578936804.016 [MissionManager](INFO): Uninitializing Mission Default
2020-01-13T17:33:24.016Z,1578936804.016 [Default] Stopped
2020-01-13T17:33:24.016Z,1578936804.016 [Default](DEBUG): Aggregate::uninitialize Default
2020-01-13T17:33:24.016Z,1578936804.016 [Default:A.Wait] Stopped
2020-01-13T17:33:24.016Z,1578936804.016 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-01-13T17:33:24.017Z,1578936804.017 [Default:B.GoToSurface] Stopped
2020-01-13T17:33:24.017Z,1578936804.017 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-01-13T17:33:24.019Z,1578936804.019 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-01-13T17:33:24.019Z,1578936804.019 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-01-13T17:33:24.019Z,1578936804.019 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-01-13T17:33:24.020Z,1578936804.020 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-01-13T17:33:24.020Z,1578936804.020 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-01-13T17:33:24.020Z,1578936804.020 [BuoyancyServo](INFO): Powering down
2020-01-13T17:33:24.033Z,1578936804.033 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-01-13T17:33:24.033Z,1578936804.033 [ElevatorServo](INFO): Powering down
2020-01-13T17:33:24.034Z,1578936804.034 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-01-13T17:33:24.034Z,1578936804.034 [MassServo](INFO): Powering down
2020-01-13T17:33:24.035Z,1578936804.035 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-01-13T17:33:24.035Z,1578936804.035 [RudderServo](INFO): Powering down
2020-01-13T17:33:24.035Z,1578936804.035 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-01-13T17:33:24.035Z,1578936804.035 [ThrusterServo](INFO): Powering down
2020-01-13T17:33:24.036Z,1578936804.036 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-01-13T17:33:24.037Z,1578936804.037 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-01-13T17:33:24.037Z,1578936804.037 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-01-13T17:33:24.037Z,1578936804.037 [CBIT](DEBUG): Powering off loads.
2020-01-13T17:33:24.049Z,1578936804.049 [CBIT](DEBUG): Disabling WDT.
2020-01-13T17:33:24.061Z,1578936804.061 [CBIT](DEBUG): Opening all GF detection circuits.
2020-01-13T17:33:24.061Z,1578936804.061 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-01-13T17:33:24.105Z,1578936804.105 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-13T17:33:24.115Z,1578936804.115 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-13T17:33:24.157Z,1578936804.157 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-13T17:33:24.160Z,1578936804.160 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-13T17:33:24.216Z,1578936804.216 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-13T17:33:24.287Z,1578936804.287 [logger ThreadHandler](INFO): Thread cancelled.