2020-01-10T21:20:50.838Z,1578691250.838 [Supervisor](DEBUG): Initializing supervisor.
2020-01-10T21:20:50.841Z,1578691250.841 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-01-10T21:20:50.841Z,1578691250.841 [SyncHandler](INFO): Protected caller Thread ID is 7924
2020-01-10T21:20:50.842Z,1578691250.842 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-01-10T21:20:50.843Z,1578691250.843 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-01-10T21:20:50.843Z,1578691250.843 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 7925
2020-01-10T21:20:50.846Z,1578691250.846 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-01-10T21:20:50.859Z,1578691250.859 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-01-10T21:20:50.860Z,1578691250.860 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-01-10T21:20:50.860Z,1578691250.860 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 7926
2020-01-10T21:20:50.861Z,1578691250.861 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-01-10T21:20:50.862Z,1578691250.862 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-01-10T21:20:50.862Z,1578691250.862 [logger ThreadHandler](INFO): Protected caller Thread ID is 7927
2020-01-10T21:20:50.864Z,1578691250.864 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-01-10T21:20:50.864Z,1578691250.864 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-01-10T21:20:50.866Z,1578691250.866 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-01-10T21:20:51.291Z,1578691251.291 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-01-10T21:20:51.291Z,1578691251.291 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-01-10T21:20:51.386Z,1578691251.386 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-01-10T21:20:51.386Z,1578691251.386 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-01-10T21:20:51.700Z,1578691251.700 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-01-10T21:20:51.701Z,1578691251.701 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-01-10T21:20:51.836Z,1578691251.836 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-01-10T21:20:51.837Z,1578691251.837 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-01-10T21:20:52.021Z,1578691252.021 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-01-10T21:20:52.022Z,1578691252.022 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-01-10T21:20:52.464Z,1578691252.464 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-01-10T21:20:52.465Z,1578691252.465 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-01-10T21:20:52.667Z,1578691252.667 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-01-10T21:20:52.668Z,1578691252.668 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-01-10T21:20:52.809Z,1578691252.809 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-01-10T21:20:52.809Z,1578691252.809 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-01-10T21:20:52.990Z,1578691252.990 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-01-10T21:20:52.991Z,1578691252.991 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-01-10T21:20:53.084Z,1578691253.084 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-01-10T21:20:53.085Z,1578691253.085 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-01-10T21:20:53.378Z,1578691253.378 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-01-10T21:20:53.378Z,1578691253.378 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-01-10T21:20:53.456Z,1578691253.456 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-01-10T21:20:53.557Z,1578691253.557 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-01-10T21:20:53.558Z,1578691253.558 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-01-10T21:20:54.187Z,1578691254.187 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-01-10T21:20:54.187Z,1578691254.187 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-01-10T21:20:54.592Z,1578691254.592 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-01-10T21:20:54.594Z,1578691254.594 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2020-01-10T21:20:54.595Z,1578691254.595 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2020-01-10T21:20:54.866Z,1578691254.866 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2020-01-10T21:20:55.032Z,1578691255.032 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2020-01-10T21:20:55.267Z,1578691255.267 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2020-01-10T21:20:55.555Z,1578691255.555 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-01-10T21:20:55.556Z,1578691255.556 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2020-01-10T21:20:55.638Z,1578691255.638 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2020-01-10T21:20:55.729Z,1578691255.729 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2020-01-10T21:20:55.823Z,1578691255.823 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2020-01-10T21:20:55.905Z,1578691255.905 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2020-01-10T21:20:56.009Z,1578691256.009 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2020-01-10T21:20:56.185Z,1578691256.185 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2020-01-10T21:20:56.311Z,1578691256.311 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/
2020-01-10T21:20:56.311Z,1578691256.311 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-01-10T21:20:56.332Z,1578691256.332 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-01-10T21:20:56.676Z,1578691256.676 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-01-10T21:20:56.682Z,1578691256.682 [AHRS_M2](INFO): created writer for : platform_orientation
2020-01-10T21:20:56.683Z,1578691256.683 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-01-10T21:20:56.689Z,1578691256.689 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-01-10T21:20:56.689Z,1578691256.689 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-01-10T21:20:56.695Z,1578691256.695 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-01-10T21:20:56.695Z,1578691256.695 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-01-10T21:20:56.701Z,1578691256.701 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-01-10T21:20:56.776Z,1578691256.776 [AHRS_M2] Loaded
2020-01-10T21:20:56.776Z,1578691256.776 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-01-10T21:20:56.855Z,1578691256.855 [DataOverHttps] Loaded
2020-01-10T21:20:56.856Z,1578691256.856 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-01-10T21:20:56.857Z,1578691256.857 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4076F4E0
2020-01-10T21:20:56.857Z,1578691256.857 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 8006
2020-01-10T21:20:56.883Z,1578691256.883 [DDM] Loaded
2020-01-10T21:20:56.883Z,1578691256.883 [ComponentRegistry](DEBUG): SyncComponent "DDM" handled in the control thread.
2020-01-10T21:20:56.897Z,1578691256.897 [Depth_Keller] Loaded
2020-01-10T21:20:56.897Z,1578691256.897 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-01-10T21:20:56.902Z,1578691256.902 [DropWeight] Loaded
2020-01-10T21:20:56.902Z,1578691256.902 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2020-01-10T21:20:56.967Z,1578691256.967 [DUSBL_Hydroid] Loaded
2020-01-10T21:20:56.967Z,1578691256.967 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2020-01-10T21:20:57.016Z,1578691257.016 [Micromodem] Loaded
2020-01-10T21:20:57.016Z,1578691257.016 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2020-01-10T21:20:57.119Z,1578691257.119 [NAL9602] Loaded
2020-01-10T21:20:57.120Z,1578691257.120 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-01-10T21:20:57.159Z,1578691257.159 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/voltage_now
2020-01-10T21:20:57.160Z,1578691257.160 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/current_now
2020-01-10T21:20:57.160Z,1578691257.160 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_now
2020-01-10T21:20:57.161Z,1578691257.161 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_full
2020-01-10T21:20:57.161Z,1578691257.161 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/voltage_now
2020-01-10T21:20:57.162Z,1578691257.162 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/current_now
2020-01-10T21:20:57.163Z,1578691257.163 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_now
2020-01-10T21:20:57.163Z,1578691257.163 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_full
2020-01-10T21:20:57.163Z,1578691257.163 [Onboard] Loaded
2020-01-10T21:20:57.163Z,1578691257.163 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2020-01-10T21:20:57.170Z,1578691257.170 [PowerOnly] Loaded
2020-01-10T21:20:57.170Z,1578691257.170 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2020-01-10T21:20:57.177Z,1578691257.177 [Radio_Surface] Loaded
2020-01-10T21:20:57.177Z,1578691257.177 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-01-10T21:20:57.178Z,1578691257.178 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4079F4E0
2020-01-10T21:20:57.178Z,1578691257.178 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 8007
2020-01-10T21:20:57.224Z,1578691257.224 [RDI_Pathfinder] Loaded
2020-01-10T21:20:57.225Z,1578691257.225 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2020-01-10T21:20:58.805Z,1578691258.805 [BPC1] Loaded
2020-01-10T21:20:58.806Z,1578691258.806 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-01-10T21:20:58.806Z,1578691258.806 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-01-10T21:20:58.807Z,1578691258.807 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-01-10T21:20:58.820Z,1578691258.820 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-01-10T21:20:58.821Z,1578691258.821 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-01-10T21:20:58.929Z,1578691258.929 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-01-10T21:20:58.930Z,1578691258.930 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-01-10T21:20:58.951Z,1578691258.951 [NavChart] Loaded
2020-01-10T21:20:58.952Z,1578691258.952 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-01-10T21:20:58.956Z,1578691258.956 [UniversalFixResidualReporter] Loaded
2020-01-10T21:20:58.956Z,1578691258.956 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-01-10T21:20:58.956Z,1578691258.956 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-01-10T21:20:58.957Z,1578691258.957 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-01-10T21:20:59.068Z,1578691259.068 [BuoyancyServo] Loaded
2020-01-10T21:20:59.069Z,1578691259.069 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-01-10T21:20:59.084Z,1578691259.084 [ElevatorServo] Loaded
2020-01-10T21:20:59.085Z,1578691259.085 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-01-10T21:20:59.100Z,1578691259.100 [MassServo] Loaded
2020-01-10T21:20:59.100Z,1578691259.100 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-01-10T21:20:59.115Z,1578691259.115 [RudderServo] Loaded
2020-01-10T21:20:59.116Z,1578691259.116 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-01-10T21:20:59.131Z,1578691259.131 [ThrusterServo] Loaded
2020-01-10T21:20:59.131Z,1578691259.131 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-01-10T21:20:59.131Z,1578691259.131 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-01-10T21:20:59.132Z,1578691259.132 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-01-10T21:20:59.400Z,1578691259.400 [CTD_NeilBrown] Loaded
2020-01-10T21:20:59.400Z,1578691259.400 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2020-01-10T21:20:59.402Z,1578691259.402 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408F34E0
2020-01-10T21:20:59.402Z,1578691259.402 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 8008
2020-01-10T21:20:59.417Z,1578691259.417 [PAR_Licor] Loaded
2020-01-10T21:20:59.417Z,1578691259.417 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2020-01-10T21:20:59.463Z,1578691259.463 [WetLabsSeaOWL_UV_A] Loaded
2020-01-10T21:20:59.463Z,1578691259.463 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2020-01-10T21:20:59.464Z,1578691259.464 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 409234E0
2020-01-10T21:20:59.465Z,1578691259.465 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 8009
2020-01-10T21:20:59.465Z,1578691259.465 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-01-10T21:20:59.466Z,1578691259.466 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-01-10T21:20:59.791Z,1578691259.791 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-01-10T21:20:59.791Z,1578691259.791 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-01-10T21:20:59.833Z,1578691259.833 [DepthRateCalculator] Loaded
2020-01-10T21:20:59.833Z,1578691259.833 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-01-10T21:20:59.839Z,1578691259.839 [PitchRateCalculator] Loaded
2020-01-10T21:20:59.840Z,1578691259.840 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-01-10T21:20:59.852Z,1578691259.852 [SpeedCalculator] Loaded
2020-01-10T21:20:59.853Z,1578691259.853 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-01-10T21:20:59.874Z,1578691259.874 [TempGradientCalculator] Loaded
2020-01-10T21:20:59.875Z,1578691259.875 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-01-10T21:20:59.880Z,1578691259.880 [YawRateCalculator] Loaded
2020-01-10T21:20:59.881Z,1578691259.881 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-01-10T21:20:59.970Z,1578691259.970 [ElevatorOffsetCalculator] Loaded
2020-01-10T21:20:59.970Z,1578691259.970 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-01-10T21:20:59.971Z,1578691259.971 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-01-10T21:20:59.972Z,1578691259.972 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-01-10T21:21:00.287Z,1578691260.287 [SBIT](DEBUG): Construct Startup Built In Test.
2020-01-10T21:21:00.310Z,1578691260.310 [SBIT] Loaded
2020-01-10T21:21:00.311Z,1578691260.311 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-01-10T21:21:00.311Z,1578691260.311 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-01-10T21:21:00.324Z,1578691260.324 [IBIT] Loaded
2020-01-10T21:21:00.324Z,1578691260.324 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-01-10T21:21:00.327Z,1578691260.327 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-01-10T21:21:00.471Z,1578691260.471 [CBIT] Loaded
2020-01-10T21:21:00.471Z,1578691260.471 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-01-10T21:21:00.472Z,1578691260.472 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-01-10T21:21:00.472Z,1578691260.472 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-01-10T21:21:00.558Z,1578691260.558 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-01-10T21:21:00.558Z,1578691260.558 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-01-10T21:21:00.660Z,1578691260.660 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-01-10T21:21:00.661Z,1578691260.661 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-01-10T21:21:00.711Z,1578691260.711 [VerticalControl](DEBUG): Construct VerticalControl.
2020-01-10T21:21:00.797Z,1578691260.797 [VerticalControl] Loaded
2020-01-10T21:21:00.797Z,1578691260.797 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-01-10T21:21:00.798Z,1578691260.798 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-01-10T21:21:00.871Z,1578691260.871 [HorizontalControl] Loaded
2020-01-10T21:21:00.871Z,1578691260.871 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-01-10T21:21:00.872Z,1578691260.872 [SpeedControl](DEBUG): Construct SpeedControl.
2020-01-10T21:21:00.874Z,1578691260.874 [SpeedControl] Loaded
2020-01-10T21:21:00.874Z,1578691260.874 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-01-10T21:21:00.874Z,1578691260.874 [LoopControl](DEBUG): Construct LoopControl.
2020-01-10T21:21:00.875Z,1578691260.875 [LoopControl] Loaded
2020-01-10T21:21:00.875Z,1578691260.875 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-01-10T21:21:00.876Z,1578691260.876 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-01-10T21:21:00.876Z,1578691260.876 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-01-10T21:21:00.910Z,1578691260.910 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-01-10T21:21:00.913Z,1578691260.913 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-01-10T21:21:00.914Z,1578691260.914 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-01-10T21:21:00.921Z,1578691260.921 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-01-10T21:21:00.922Z,1578691260.922 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B024E0
2020-01-10T21:21:00.922Z,1578691260.922 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 8010
2020-01-10T21:21:00.927Z,1578691260.927 [Supervisor](INFO): Main Thread ID is 7655
2020-01-10T21:21:00.927Z,1578691260.927 [Supervisor](DEBUG): Running supervisor.
2020-01-10T21:21:00.928Z,1578691260.928 [CommandLine ThreadHandler](INFO): Handler Thread ID is 8011
2020-01-10T21:21:00.930Z,1578691260.930 [controlThread ThreadHandler](INFO): Handler Thread ID is 8012
2020-01-10T21:21:00.930Z,1578691260.930 [controlThread](DEBUG): Initializing ControlThread
2020-01-10T21:21:00.936Z,1578691260.936 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-01-10T21:21:00.936Z,1578691260.936 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-01-10T21:21:00.941Z,1578691260.941 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-01-10T21:21:00.941Z,1578691260.941 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-01-10T21:21:00.942Z,1578691260.942 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-01-10T21:21:00.942Z,1578691260.942 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-01-10T21:21:00.942Z,1578691260.942 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-01-10T21:21:00.943Z,1578691260.943 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-01-10T21:21:00.943Z,1578691260.943 [SBIT](INFO): Initialize SBIT Component.
2020-01-10T21:21:00.944Z,1578691260.944 [SBIT](IMPORTANT): git: 2019-12-03-17-g4812c94
2020-01-10T21:21:00.944Z,1578691260.944 [SBIT](INFO): git hash: 4812c9429720da7730f61311b178b42396aebb36
2020-01-10T21:21:00.944Z,1578691260.944 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-01-10T21:21:00.945Z,1578691260.945 [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-10T21:21:00.947Z,1578691260.947 [SBIT](INFO): Beginning SBIT in 39.000000 seconds.
2020-01-10T21:21:00.947Z,1578691260.947 [IBIT](INFO): Initialize IBIT Component.
2020-01-10T21:21:00.948Z,1578691260.948 [CBIT](DEBUG): Initialize CBIT Component.
2020-01-10T21:21:00.949Z,1578691260.949 [logger ThreadHandler](INFO): Handler Thread ID is 8013
2020-01-10T21:21:00.961Z,1578691260.961 [CBIT](DEBUG): Initialized mux pins.
2020-01-10T21:21:00.961Z,1578691260.961 [CBIT](DEBUG): Initializing the watchdog timer.
2020-01-10T21:21:00.969Z,1578691260.969 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 8014
2020-01-10T21:21:00.970Z,1578691260.970 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-01-10T21:21:00.981Z,1578691260.981 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 8015
2020-01-10T21:21:00.985Z,1578691260.985 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-01-10T21:21:00.985Z,1578691260.985 [CBIT](DEBUG): Initializing heartbeat.
2020-01-10T21:21:00.993Z,1578691260.993 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 8016
2020-01-10T21:21:00.994Z,1578691260.994 [CTD_NeilBrown](INFO): Powering down
2020-01-10T21:21:01.021Z,1578691261.021 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 8017
2020-01-10T21:21:01.022Z,1578691261.022 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-01-10T21:21:01.049Z,1578691261.049 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 8018
2020-01-10T21:21:01.052Z,1578691261.052 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-01-10T21:21:01.053Z,1578691261.053 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-01-10T21:21:01.053Z,1578691261.053 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-01-10T21:21:01.053Z,1578691261.053 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-01-10T21:21:01.053Z,1578691261.053 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-01-10T21:21:01.054Z,1578691261.054 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-01-10T21:21:01.054Z,1578691261.054 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-01-10T21:21:01.054Z,1578691261.054 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-01-10T21:21:01.054Z,1578691261.054 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-01-10T21:21:01.054Z,1578691261.054 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-01-10T21:21:01.055Z,1578691261.055 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-01-10T21:21:01.055Z,1578691261.055 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-01-10T21:21:01.055Z,1578691261.055 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-01-10T21:21:01.055Z,1578691261.055 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-01-10T21:21:01.055Z,1578691261.055 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-01-10T21:21:01.055Z,1578691261.055 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-01-10T21:21:01.057Z,1578691261.057 [CBIT](DEBUG): Deactivating GF circuits.
2020-01-10T21:21:01.057Z,1578691261.057 [CBIT](DEBUG): Deactivating emergency mode.
2020-01-10T21:21:01.093Z,1578691261.093 [CBIT](DEBUG): Backplane powered.
2020-01-10T21:21:01.093Z,1578691261.093 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-01-10T21:21:01.095Z,1578691261.095 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-01-10T21:21:01.095Z,1578691261.095 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-01-10T21:21:01.096Z,1578691261.096 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-01-10T21:21:01.097Z,1578691261.097 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-01-10T21:21:01.107Z,1578691261.107 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-01-10T21:21:01.145Z,1578691261.145 [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-10T21:21:01.146Z,1578691261.146 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-01-10T21:21:01.204Z,1578691261.204 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-01-10T21:21:01.221Z,1578691261.221 [Default:A.Wait](DEBUG): Construct Wait.
2020-01-10T21:21:01.223Z,1578691261.223 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-01-10T21:21:01.243Z,1578691261.243 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-01-10T21:21:01.269Z,1578691261.269 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-01-10T21:21:01.275Z,1578691261.275 [Default:E.Execute](DEBUG): Construct Execute.
2020-01-10T21:21:01.294Z,1578691261.294 [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-10T21:21:01.310Z,1578691261.310 [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-10T21:21:01.324Z,1578691261.324 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-01-10T21:21:01.373Z,1578691261.373 [DDM](INFO): Powering up
2020-01-10T21:21:01.373Z,1578691261.373 [DDM](DEBUG): Initializing DDM.
2020-01-10T21:21:01.385Z,1578691261.385 [Radio_Surface](INFO): Powering up
2020-01-10T21:21:01.398Z,1578691261.398 [Depth_Keller](ERROR): Pressure reading out of range: 957.962097 decibar
2020-01-10T21:21:01.398Z,1578691261.398 [DUSBL_Hydroid](INFO): Powering up
2020-01-10T21:21:01.399Z,1578691261.399 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2020-01-10T21:21:01.555Z,1578691261.555 [DepthRateCalculator](ERROR): Depth measurement is not active
2020-01-10T21:21:01.607Z,1578691261.607 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-01-10T21:21:01.620Z,1578691261.620 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-01-10T21:21:01.621Z,1578691261.621 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-01-10T21:21:01.629Z,1578691261.629 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-01-10T21:21:01.630Z,1578691261.630 [MassServo](DEBUG): Initializing EZServoServo.
2020-01-10T21:21:01.637Z,1578691261.637 [MassServo](DEBUG): Initializing MassServo.
2020-01-10T21:21:01.638Z,1578691261.638 [RudderServo](DEBUG): Initializing EZServoServo.
2020-01-10T21:21:01.645Z,1578691261.645 [RudderServo](DEBUG): Initializing RudderServo.
2020-01-10T21:21:01.646Z,1578691261.646 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-01-10T21:21:01.659Z,1578691261.659 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-01-10T21:21:01.828Z,1578691261.828 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2020-01-10T21:21:01.828Z,1578691261.828 [DropWeight] Hardware Fault, FailCount= 1
2020-01-10T21:21:01.828Z,1578691261.828 [DropWeight](ERROR): Hardware Fault
2020-01-10T21:21:01.846Z,1578691261.846 [Micromodem](INFO): Powering up
2020-01-10T21:21:01.846Z,1578691261.846 [Micromodem](DEBUG): Initializing Micromodem.
2020-01-10T21:21:01.934Z,1578691261.934 [CommandLine](FAULT): Scheduling is paused
2020-01-10T21:21:01.934Z,1578691261.934 [CBIT](INFO): Critical error at 20200110T212101
2020-01-10T21:21:01.934Z,1578691261.934 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2020-01-10T21:21:01.947Z,1578691261.947 [CBIT](ERROR): Hardware Fault in component: DropWeight
2020-01-10T21:21:01.949Z,1578691261.949 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2020-01-10T21:21:02.525Z,1578691262.525 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2020-01-10T21:21:02.525Z,1578691262.525 [RudderServo](FAULT): Rudder failed to initialize
2020-01-10T21:21:02.525Z,1578691262.525 [RudderServo] Communications Fault, FailCount= 1
2020-01-10T21:21:02.525Z,1578691262.525 [RudderServo](ERROR): Communications Fault
2020-01-10T21:21:02.634Z,1578691262.634 [CBIT](INFO): Critical error at 20200110T212101
2020-01-10T21:21:02.636Z,1578691262.636 [CBIT](ERROR): Communications Fault in component: RudderServo
2020-01-10T21:21:02.826Z,1578691262.826 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-01-10T21:21:02.826Z,1578691262.826 [RudderServo](INFO): Powering down
2020-01-10T21:21:03.539Z,1578691263.539 [RudderServo](DEBUG): Initializing EZServoServo.
2020-01-10T21:21:03.658Z,1578691263.658 [RudderServo](DEBUG): Initializing RudderServo.
2020-01-10T21:21:03.662Z,1578691263.662 [CBIT](INFO): Clearing failed state for component RudderServo
2020-01-10T21:21:03.662Z,1578691263.662 [RudderServo] No Fault, FailCount= 1
2020-01-10T21:21:06.725Z,1578691266.725 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2020-01-10T21:21:07.128Z,1578691267.128 [Micromodem](INFO): Nmea in: $CATMG,2020-01-10T21:21:06.677029Z,RTC,RTC*5B

2020-01-10T21:21:07.128Z,1578691267.128 [Micromodem](ERROR): CFG response from modem unexpected: $CATMG,2020-01-10T21:21:06.677029Z,RTC,RTC*5B

2020-01-10T21:21:07.531Z,1578691267.531 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31

2020-01-10T21:21:07.531Z,1578691267.531 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2020-01-10T21:21:07.935Z,1578691267.935 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33

2020-01-10T21:21:07.935Z,1578691267.935 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64

2020-01-10T21:21:08.339Z,1578691268.339 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66

2020-01-10T21:21:08.339Z,1578691268.339 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F

2020-01-10T21:21:08.747Z,1578691268.747 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D

2020-01-10T21:21:08.747Z,1578691268.747 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E

2020-01-10T21:21:09.151Z,1578691269.151 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C

2020-01-10T21:21:09.151Z,1578691269.151 [Micromodem](INFO): Nmea out: $CCCFG,FC0,10000*46

2020-01-10T21:21:09.551Z,1578691269.551 [Micromodem](INFO): Nmea in: $CACFG,FC0,10000*44

2020-01-10T21:21:09.551Z,1578691269.551 [Micromodem](INFO): Nmea out: $CCCFG,BW0,4000*63

2020-01-10T21:21:09.956Z,1578691269.956 [Micromodem](INFO): Nmea in: $CACFG,BW0,4000*61

2020-01-10T21:21:09.956Z,1578691269.956 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A

2020-01-10T21:21:10.335Z,1578691270.335 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38

2020-01-10T21:21:10.336Z,1578691270.336 [Micromodem](INFO): Nmea out: $CCCLK,2020,01,10,21,21,11*44

2020-01-10T21:21:10.759Z,1578691270.759 [Micromodem](INFO): Nmea in: $CACLK,2020,1,10,21,21,11*76

2020-01-10T21:21:11.139Z,1578691271.139 [Micromodem](INFO): Nmea in: $CATMS,0,2020-01-10T21:21:12Z*75

2020-01-10T21:21:11.140Z,1578691271.140 [Micromodem](INFO): Nmea in: $CATMG,2020-01-10T21:21:12.027137Z,USER_CMD,RTC*12

2020-01-10T21:21:11.950Z,1578691271.950 [DDM](INFO): Dynamic Docking Module:
2020-01-10T21:21:14.796Z,1578691274.796 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2020-01-10T21:21:19.214Z,1578691279.214 [DUSBL_Hydroid](INFO): DUSBL Version:O
2020-01-10T21:21:27.295Z,1578691287.295 [NAL9602](INFO): Powering up NAL9602
2020-01-10T21:21:38.207Z,1578691298.207 [NAL9602](INFO): NAL9602 initialized
2020-01-10T21:21:40.682Z,1578691300.682 [SBIT](IMPORTANT): Beginning Startup BIT
2020-01-10T21:21:40.711Z,1578691300.711 [CBIT](IMPORTANT): Beginning ground fault scan
2020-01-10T21:21:44.920Z,1578691304.920 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-10T21:21:44.940Z,1578691304.940 [RDI_Pathfinder](ERROR): Failed to parse:
:TS4343591,35.0, -0.1,   0.0,1448.9,  0
2020-01-10T21:21:51.528Z,1578691311.528 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.014927
CHAN A1 (24V): -0.029106
CHAN A2 (12V): -0.006915
CHAN A3 (5V): -0.002046
CHAN B0 (3.3V): 0.000537
CHAN B1 (3.15aV): 0.000192
CHAN B2 (3.15bV): -0.000254
CHAN B3 (GND): 0.002471
OPEN: 0.005261
Full Scale Calc: 4.765 mA, -1.589 mA
2020-01-10T21:21:53.884Z,1578691313.884 [SBIT](FAULT): Rudder: EXPECTED:15.000000 ACTUAL:15.008821
2020-01-10T21:22:12.102Z,1578691332.102 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-10T21:22:12.102Z,1578691332.102 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20011014350291,35.0, -0.1,   0.0,1448:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2020-01-10T21:22:20.711Z,1578691340.711 [SBIT](FAULT): Rudder: EXPECTED:-15.000000 ACTUAL:-14.975829
2020-01-10T21:22:20.711Z,1578691340.711 [SBIT](FAULT): Control surface position failure.
2020-01-10T21:22:27.166Z,1578691347.166 [Micromodem](INFO): Nmea in: $CACYC,1,12,1,1,0,2*6B

2020-01-10T21:22:27.167Z,1578691347.167 [Micromodem](INFO): Have incoming data
2020-01-10T21:22:27.573Z,1578691347.573 [Micromodem](INFO): Nmea in: $CARXD,12,1,0,1,858A1AB878DB70DBE89704525FDC95B5C5FE9F4147C09B083E4A8CE30F9EA23DB7C2EC1A3F0C8416B2D6BA469C63028C955B1EDAD054116D0BA2C541AD2A74E9*5A

2020-01-10T21:22:27.574Z,1578691347.574 [Micromodem](INFO): Frame:1
2020-01-10T21:22:27.574Z,1578691347.574 [Micromodem](INFO): Frame Size:-18
2020-01-10T21:22:27.574Z,1578691347.574 [Micromodem](INFO): Data End:-2
2020-01-10T21:22:27.575Z,1578691347.575 [Micromodem](INFO): Data At:16
2020-01-10T21:22:27.577Z,1578691347.577 [Micromodem](INFO): Nmea in: $CARXD,12,1,0,2,4EEE1310BA042F91D0BD31A76379D458F2E8C122*52

2020-01-10T21:22:27.578Z,1578691347.578 [Micromodem](INFO): Frame:2
2020-01-10T21:22:27.578Z,1578691347.578 [Micromodem](INFO): Frame Size:41
2020-01-10T21:22:27.578Z,1578691347.578 [Micromodem](INFO): Data End:57
2020-01-10T21:22:27.578Z,1578691347.578 [Micromodem](INFO): Data At:16
2020-01-10T21:22:27.578Z,1578691347.578 [Micromodem](INFO): Deserialize. FrameCount:2
2020-01-10T21:22:27.579Z,1578691347.579 [Micromodem](INFO): Outer loop. Size:-18
2020-01-10T21:22:27.579Z,1578691347.579 [Micromodem](INFO): Outer loop. Size:41
2020-01-10T21:22:27.580Z,1578691347.580 [Micromodem](CRITICAL): CRC Error 0x22C1E8F2 != computed 0x-4B65D0F9
2020-01-10T21:22:27.582Z,1578691347.582 [Micromodem](CRITICAL): Failed to parse uplink message:4EEE1310BA042F91D0BD31A76379D458F2E8C122
2020-01-10T21:22:27.779Z,1578691347.779 [CBIT](INFO): Critical error at 20200110T212227
2020-01-10T21:22:27.979Z,1578691347.979 [Micromodem](INFO): Nmea in: $CACST,6,0,20200110212225.078922,06,1840,32,0140,0150,59,02,02,00,03,1,012,001,0,3,2,0,150,26.6,8.00,00,-5.00,-01,-0.02,25,10000,4000*7D

2020-01-10T21:22:34.159Z,1578691354.159 [SBIT](FAULT): Rudder: EXPECTED:0.000000 ACTUAL:0.010997
2020-01-10T21:22:34.159Z,1578691354.159 [SBIT](FAULT): Control surface position failure.
2020-01-10T21:22:34.539Z,1578691354.539 [SBIT](CRITICAL): SBIT FAILED
2020-01-10T21:22:34.576Z,1578691354.576 [CBIT](INFO): Critical error at 20200110T212234
2020-01-10T21:22:34.609Z,1578691354.609 [CommandLine](IMPORTANT): got command configSet list
2020-01-10T21:22:34.610Z,1578691354.610 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-01-10T21:22:34.612Z,1578691354.612 [CommandLine](IMPORTANT): DDM.loadAtStartup=1 bool;

2020-01-10T21:22:34.613Z,1578691354.613 [CommandLine](IMPORTANT): DDM.verbosity=3 count;

2020-01-10T21:22:34.614Z,1578691354.614 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold=75 count;

2020-01-10T21:22:34.614Z,1578691354.614 [CommandLine](IMPORTANT): DUSBL_Hydroid.transmitLockout=40 millisecond;

2020-01-10T21:22:34.615Z,1578691354.615 [CommandLine](IMPORTANT): Express linearApproximation DDM.DDMMode 1.000000 count;

2020-01-10T21:22:34.615Z,1578691354.615 [CommandLine](IMPORTANT): Express all DDM.cablePresent;

2020-01-10T21:22:34.616Z,1578691354.616 [CommandLine](IMPORTANT): Express linearApproximation DDM.latchState 1.000000 count;

2020-01-10T21:22:34.616Z,1578691354.616 [CommandLine](IMPORTANT): Express linearApproximation DDM.whiskerState 1.000000 count;

2020-01-10T21:22:34.616Z,1578691354.616 [CommandLine](IMPORTANT): Express linearApproximation DUSBL_Hydroid.acoustic_contact_range 1.000000 meter;

2020-01-10T21:22:34.616Z,1578691354.616 [CommandLine](IMPORTANT): Express linearApproximation DUSBL_Hydroid.xAngle 2.000000 degree;

2020-01-10T21:22:34.616Z,1578691354.616 [CommandLine](IMPORTANT): Express linearApproximation RDI_Pathfinder.height_above_sea_floor 2.000000 meter;

2020-01-10T21:22:34.616Z,1578691354.616 [CommandLine](IMPORTANT): Express linearApproximation platform_orientation 5.000000 degree;

2020-01-10T21:22:34.616Z,1578691354.616 [CommandLine](IMPORTANT): Express linearApproximation platform_x_velocity_wrt_ground 0.100000 meter_per_second;

2020-01-10T21:22:34.616Z,1578691354.616 [CommandLine](IMPORTANT): Express linearApproximation platform_y_velocity_wrt_ground 0.100000 meter_per_second;

2020-01-10T21:22:34.617Z,1578691354.617 [CommandLine](IMPORTANT): HorizontalControl.kiHeading=0.002 reciprocal_second;

2020-01-10T21:22:34.618Z,1578691354.618 [CommandLine](IMPORTANT): HorizontalControl.kpHeading=0.8 none;

2020-01-10T21:22:34.618Z,1578691354.618 [CommandLine](IMPORTANT): HorizontalControl.rudDeadband=0.05 degree;

2020-01-10T21:22:34.618Z,1578691354.618 [CommandLine](IMPORTANT): Micromodem.destinationAddress=12 enum;

2020-01-10T21:22:34.618Z,1578691354.618 [CommandLine](IMPORTANT): Micromodem.surfaceThreshold=0 meter;

2020-01-10T21:22:34.618Z,1578691354.618 [CommandLine](IMPORTANT): PowerOnly.sampleTime=90 second;

2020-01-10T21:22:34.618Z,1578691354.618 [CommandLine](IMPORTANT): RudderServo.deviationAngle=0.0001 degree;

2020-01-10T21:22:34.618Z,1578691354.618 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=105 cubic_centimeter;

2020-01-10T21:22:34.618Z,1578691354.618 [CommandLine](IMPORTANT): VerticalControl.massDefault=-5 millimeter;

2020-01-10T21:22:34.879Z,1578691354.879 [MissionManager](IMPORTANT): Started mission Startup
2020-01-10T21:22:34.879Z,1578691354.879 [Startup] Running Loop=1
2020-01-10T21:22:34.880Z,1578691354.880 [Startup](DEBUG): Aggregate::initialize Startup
2020-01-10T21:22:34.880Z,1578691354.880 [Startup:A.GoToSurface] Running Loop=1
2020-01-10T21:22:34.880Z,1578691354.880 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-01-10T21:22:34.880Z,1578691354.880 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-01-10T21:22:34.881Z,1578691354.881 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-01-10T21:22:34.881Z,1578691354.881 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-01-10T21:22:34.882Z,1578691354.882 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-01-10T21:22:34.882Z,1578691354.882 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-01-10T21:22:34.883Z,1578691354.883 [Startup:StartupSatComms] Running Loop=1
2020-01-10T21:22:34.884Z,1578691354.884 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-01-10T21:22:34.884Z,1578691354.884 [Startup:StartupSatComms:A] Running Loop=1
2020-01-10T21:22:35.285Z,1578691355.285 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-01-10T21:23:30.147Z,1578691410.147 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005033
2020-01-10T21:23:35.096Z,1578691415.096 [Startup:StartupSatComms:A](INFO): Timed out from 2020-01-10T21:22:34.9Z
2020-01-10T21:23:35.096Z,1578691415.096 [Startup:StartupSatComms:A] Stopped
2020-01-10T21:23:35.096Z,1578691415.096 [Startup:StartupSatComms:B] Running Loop=1
2020-01-10T21:23:35.487Z,1578691415.487 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-01-10T21:23:36.659Z,1578691416.659 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 37E9185E040000005D000004006D0000000000000000151D80030C5669BF31555EAE1409A708CE24895934E535C987D20F3A1CC3A494F9F2A8A305A44F941D5C
2020-01-10T21:23:36.659Z,1578691416.659 [Micromodem](INFO): Outgoing frame #2, 64 bytes: 05F8E620FE1846B6E11FF4949F699EFB27078C58DA101DE567D6D7D8EC23C6F4C1BDDAB0C10210A5527440E389552E0FB4DE24FC16A90C34471ECB7E49D547BD
2020-01-10T21:23:36.660Z,1578691416.660 [Micromodem](INFO): Outgoing frame #3, 3 bytes: 8C7500
2020-01-10T21:23:36.660Z,1578691416.660 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69

2020-01-10T21:23:36.839Z,1578691416.839 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B

2020-01-10T21:23:37.207Z,1578691417.207 [Micromodem](INFO): Nmea in: $CADRQ,212337,1,12,0,64,1*72

2020-01-10T21:23:37.208Z,1578691417.208 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,37E9185E040000005D000004006D0000000000000000151D80030C5669BF31555EAE1409A708CE24895934E535C987D20F3A1CC3A494F9F2A8A305A44F941D5C*41

2020-01-10T21:23:37.624Z,1578691417.624 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-10T21:23:38.019Z,1578691418.019 [Micromodem](INFO): Nmea in: $CADRQ,212338,1,12,0,64,2*7E

2020-01-10T21:23:38.019Z,1578691418.019 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,05F8E620FE1846B6E11FF4949F699EFB27078C58DA101DE567D6D7D8EC23C6F4C1BDDAB0C10210A5527440E389552E0FB4DE24FC16A90C34471ECB7E49D547BD*32

2020-01-10T21:23:38.423Z,1578691418.423 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-10T21:23:38.827Z,1578691418.827 [Micromodem](INFO): Nmea in: $CADRQ,212339,1,12,0,64,3*7E

2020-01-10T21:23:38.827Z,1578691418.827 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,8C7500*32

2020-01-10T21:23:39.239Z,1578691419.239 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,3*7A

2020-01-10T21:23:39.627Z,1578691419.627 [Micromodem](INFO): Nmea in: $CATXP,131*41

2020-01-10T21:23:43.271Z,1578691423.271 [Micromodem](INFO): Nmea in: $CATXF,131*57

2020-01-10T21:23:43.672Z,1578691423.672 [Micromodem](INFO): Nmea in: $CAXST,6,20200110,212339.845100,3,0,200,4000,10000,1,1,12,1,3,3,3,131*73

2020-01-10T21:23:44.889Z,1578691424.889 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-10T21:23:45.287Z,1578691425.287 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-10T21:23:45.441Z,1578691425.441 [DataOverHttps](INFO): Sending 123 bytes from file Logs/20200110T211431/Courier0004.lzma
2020-01-10T21:23:45.695Z,1578691425.695 [Micromodem](INFO): Nmea in: $CAACK,12,1,3,1*7B

2020-01-10T21:23:45.696Z,1578691425.696 [Micromodem](INFO): Sent 123 bytes from file Logs/20200110T211431/Courier0004.lzma.parts
2020-01-10T21:23:45.696Z,1578691425.696 [Micromodem](INFO): Packets left to send: 0
2020-01-10T21:23:46.102Z,1578691426.102 [Micromodem](INFO): Nmea in: $CACST,6,0,20200110212344.337406,06,558,27,0099,0150,89,02,02,00,03,1,012,001,0,4,1,0,150,26.0,5.00,14,-2.00,-01,-0.04,27,10000,4000*48

2020-01-10T21:23:46.912Z,1578691426.912 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 08EA185E000001005D00000400640100000000000000151D80030C5669BF31555EAE1409A708CE2489EC102F971AAFC35C3D87A14EFD88D7E667BF45C1E3A334
2020-01-10T21:23:46.914Z,1578691426.914 [Micromodem](INFO): Outgoing frame #2, 64 bytes: DF3A8FFF9A9267E1E3FDEBF1882C89C1D12EDAE84F064394B4971F763859CD7E296BEC14B02C05A959B4A43566D1FAD4D3397C4FCC3502FA1A62A762F2667367
2020-01-10T21:23:46.916Z,1578691426.916 [Micromodem](INFO): Outgoing frame #3, 64 bytes: 3406953D3F17CF0B18053F5A7E7E81E05BEAF4EDF8D8A8A423D53A143BCEE49402CB4F9F6D048251BB91F577BC8BC80A6F02CC2C4693F8CA524146A3DEF47E74
2020-01-10T21:23:46.929Z,1578691426.929 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69

2020-01-10T21:23:47.463Z,1578691427.463 [DataOverHttps](FAULT): Server acknowledged file Logs/20200110T211431/Courier0004.lzma, notLogs/20200110T211800/Courier0000.lzma
2020-01-10T21:23:47.463Z,1578691427.463 [DataOverHttps](INFO): SBD MOMSN=12190840
2020-01-10T21:23:47.983Z,1578691427.983 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B

2020-01-10T21:23:48.387Z,1578691428.387 [Micromodem](INFO): Nmea in: $CADRQ,212347,1,12,0,64,1*75

2020-01-10T21:23:48.387Z,1578691428.387 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,08EA185E000001005D00000400640100000000000000151D80030C5669BF31555EAE1409A708CE2489EC102F971AAFC35C3D87A14EFD88D7E667BF45C1E3A334*4F

2020-01-10T21:23:48.791Z,1578691428.791 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-10T21:23:49.195Z,1578691429.195 [Micromodem](INFO): Nmea in: $CADRQ,212349,1,12,0,64,2*78

2020-01-10T21:23:49.195Z,1578691429.195 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,DF3A8FFF9A9267E1E3FDEBF1882C89C1D12EDAE84F064394B4971F763859CD7E296BEC14B02C05A959B4A43566D1FAD4D3397C4FCC3502FA1A62A762F2667367*3B

2020-01-10T21:23:49.599Z,1578691429.599 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-10T21:23:50.003Z,1578691430.003 [Micromodem](INFO): Nmea in: $CADRQ,212350,1,12,0,64,3*71

2020-01-10T21:23:50.003Z,1578691430.003 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,3406953D3F17CF0B18053F5A7E7E81E05BEAF4EDF8D8A8A423D53A143BCEE49402CB4F9F6D048251BB91F577BC8BC80A6F02CC2C4693F8CA524146A3DEF47E74*3D

2020-01-10T21:23:50.407Z,1578691430.407 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-10T21:23:50.806Z,1578691430.806 [Micromodem](INFO): Nmea in: $CATXP,192*48

2020-01-10T21:23:54.447Z,1578691434.447 [Micromodem](INFO): Nmea in: $CATXF,192*5E

2020-01-10T21:23:54.848Z,1578691434.848 [Micromodem](INFO): Nmea in: $CAXST,6,20200110,212351.089040,3,0,200,4000,10000,1,1,12,1,3,3,3,192*79

2020-01-10T21:23:56.067Z,1578691436.067 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-10T21:23:56.467Z,1578691436.467 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-10T21:23:56.879Z,1578691436.879 [Micromodem](INFO): Nmea in: $CAACK,12,1,3,1*7B

2020-01-10T21:23:56.880Z,1578691436.880 [Micromodem](INFO): Sent 184 bytes from file Logs/20200110T211800/Courier0000.lzma.parts
2020-01-10T21:23:56.880Z,1578691436.880 [Micromodem](INFO): Packets left to send: 1
2020-01-10T21:23:57.284Z,1578691437.284 [Micromodem](INFO): Nmea in: $CACST,6,0,20200110212355.644082,06,218,23,0116,0150,59,02,02,00,04,1,012,001,0,4,1,0,150,21.9,6.00,13,-3.00,-01,-0.04,27,10000,4000*41

2020-01-10T21:23:57.872Z,1578691437.872 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 08EA185E00000000233D96586C11DA391E400F7C8505B8664D61B484E49015F500197640350A7EB579E64AA607FE2962EC32C7DB68BCF9A279D8604474C3AEAF
2020-01-10T21:23:57.872Z,1578691437.872 [Micromodem](INFO): Outgoing frame #2, 40 bytes: 845E6BABEDAEC18450F60DABD3E08F55D9962709C9A36A2CC1D3D96BA59D2F9CE9A94BD74C21E70D
2020-01-10T21:23:57.873Z,1578691437.873 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,2*68

2020-01-10T21:23:58.811Z,1578691438.811 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,2*6A

2020-01-10T21:23:59.219Z,1578691439.219 [Micromodem](INFO): Nmea in: $CADRQ,212358,1,12,0,64,1*7B

2020-01-10T21:23:59.219Z,1578691439.219 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,08EA185E00000000233D96586C11DA391E400F7C8505B8664D61B484E49015F500197640350A7EB579E64AA607FE2962EC32C7DB68BCF9A279D8604474C3AEAF*4B

2020-01-10T21:23:59.615Z,1578691439.615 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-10T21:24:00.023Z,1578691440.023 [Micromodem](INFO): Nmea in: $CADRQ,212400,1,12,0,64,2*72

2020-01-10T21:24:00.023Z,1578691440.023 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,845E6BABEDAEC18450F60DABD3E08F55D9962709C9A36A2CC1D3D96BA59D2F9CE9A94BD74C21E70D*4D

2020-01-10T21:24:00.431Z,1578691440.431 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,40*4D

2020-01-10T21:24:00.840Z,1578691440.840 [Micromodem](INFO): Nmea in: $CATXP,104*47

2020-01-10T21:24:01.281Z,1578691441.281 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2020-01-10T21:24:01.281Z,1578691441.281 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-10T21:24:01.326Z,1578691441.326 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-10T21:24:01.727Z,1578691441.727 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-10T21:24:01.727Z,1578691441.727 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2020-01-10T21:24:02.910Z,1578691442.910 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-10T21:24:02.910Z,1578691442.910 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20011014365391,35.0, -0.1,   0.0,14
2020-01-10T21:24:03.263Z,1578691443.263 [Micromodem](INFO): Nmea in: $CATXF,104*51

2020-01-10T21:24:03.664Z,1578691443.664 [Micromodem](INFO): Nmea in: $CAXST,6,20200110,212401.072998,3,0,200,4000,10000,1,1,12,1,2,2,3,104*7C

2020-01-10T21:24:04.463Z,1578691444.463 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-10T21:24:04.874Z,1578691444.874 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-10T21:24:04.875Z,1578691444.875 [Micromodem](INFO): Sent 96 bytes from file Logs/20200110T211800/Courier0000.lzma.parts
2020-01-10T21:24:04.875Z,1578691444.875 [Micromodem](INFO): Packets left to send: 0
2020-01-10T21:24:05.282Z,1578691445.282 [Micromodem](INFO): Nmea in: $CACST,6,0,20200110212404.330383,06,544,27,0096,0150,59,02,02,00,04,1,012,001,0,4,1,0,150,26.3,6.00,16,-3.00,-01,-0.04,24,10000,4000*4E

2020-01-10T21:24:05.774Z,1578691445.774 [DataOverHttps](INFO): Sending 279 bytes from file Logs/20200110T212050/Courier0000.lzma
2020-01-10T21:24:06.076Z,1578691446.076 [Micromodem](INFO): Outgoing frame #1, 64 bytes: B2EA185E000001005D00000400640100000000000000151D80030C5669BF31555EAE1409A708CE2489EC102F971AAFC35C3D87A14EFD88D7E667BF45C1E3A334
2020-01-10T21:24:06.077Z,1578691446.077 [Micromodem](INFO): Outgoing frame #2, 64 bytes: DD5C77243E20893ACDD0F3023CD99B574FFDE4D16B2A2C1E653E25B20DB290BFE072F2D413A3546DB1C589445A7F7953944BC95954999E1DA55C1946A79434E7
2020-01-10T21:24:06.077Z,1578691446.077 [Micromodem](INFO): Outgoing frame #3, 64 bytes: CE17E66718E86EB3E41A5D2B1248E8BE891D74FFD65D36E950A4F8914B773AE24C60DF2D9BCC6237F2B26B5A293C1BEAE4D18E1E28A09CBB1C5F2F4D62387910
2020-01-10T21:24:06.078Z,1578691446.078 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69

2020-01-10T21:24:06.970Z,1578691446.970 [DataOverHttps](INFO): Moved sent file to Logs/20200110T212050/Courier0000.lzma.bak
2020-01-10T21:24:06.971Z,1578691446.971 [DataOverHttps](INFO): SBD MOMSN=12190844
2020-01-10T21:24:06.999Z,1578691446.999 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B

2020-01-10T21:24:07.407Z,1578691447.407 [Micromodem](INFO): Nmea in: $CADRQ,212407,1,12,0,64,1*76

2020-01-10T21:24:07.407Z,1578691447.407 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,B2EA185E000001005D00000400640100000000000000151D80030C5669BF31555EAE1409A708CE2489EC102F971AAFC35C3D87A14EFD88D7E667BF45C1E3A334*37

2020-01-10T21:24:07.817Z,1578691447.817 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-10T21:24:08.219Z,1578691448.219 [Micromodem](INFO): Nmea in: $CADRQ,212408,1,12,0,64,2*7A

2020-01-10T21:24:08.219Z,1578691448.219 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,DD5C77243E20893ACDD0F3023CD99B574FFDE4D16B2A2C1E653E25B20DB290BFE072F2D413A3546DB1C589445A7F7953944BC95954999E1DA55C1946A79434E7*4C

2020-01-10T21:24:08.619Z,1578691448.619 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-10T21:24:09.033Z,1578691449.033 [Micromodem](INFO): Nmea in: $CADRQ,212409,1,12,0,64,3*7A

2020-01-10T21:24:09.033Z,1578691449.033 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,CE17E66718E86EB3E41A5D2B1248E8BE891D74FFD65D36E950A4F8914B773AE24C60DF2D9BCC6237F2B26B5A293C1BEAE4D18E1E28A09CBB1C5F2F4D62387910*3D

2020-01-10T21:24:09.423Z,1578691449.423 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-10T21:24:09.832Z,1578691449.832 [Micromodem](INFO): Nmea in: $CATXP,192*48

2020-01-10T21:24:13.475Z,1578691453.475 [Micromodem](INFO): Nmea in: $CATXF,192*5E

2020-01-10T21:24:13.872Z,1578691453.872 [Micromodem](INFO): Nmea in: $CAXST,6,20200110,212410.114739,3,0,200,4000,10000,1,1,12,1,3,3,3,192*77

2020-01-10T21:24:14.680Z,1578691454.680 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-10T21:24:15.087Z,1578691455.087 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-10T21:24:15.483Z,1578691455.483 [Micromodem](INFO): Nmea in: $CAACK,12,1,3,1*7B

2020-01-10T21:24:15.484Z,1578691455.484 [Micromodem](INFO): Sent 184 bytes from file Logs/20200110T212050/Courier0000.lzma.parts
2020-01-10T21:24:15.484Z,1578691455.484 [Micromodem](INFO): Packets left to send: 1
2020-01-10T21:24:15.900Z,1578691455.900 [Micromodem](INFO): Nmea in: $CACST,6,0,20200110212414.670185,06,199,22,0104,0150,37,03,03,00,04,1,012,001,0,4,1,0,150,26.4,6.00,16,-3.00,-01,-0.03,19,10000,4000*47

2020-01-10T21:24:16.502Z,1578691456.502 [Startup:StartupSatComms:B] Stopped
2020-01-10T21:24:16.502Z,1578691456.502 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2020-01-10T21:24:16.502Z,1578691456.502 [Startup:StartupSatComms] Stopped
2020-01-10T21:24:16.502Z,1578691456.502 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-01-10T21:24:16.503Z,1578691456.503 [Startup](INFO): Completed Startup
2020-01-10T21:24:16.503Z,1578691456.503 [MissionManager](INFO): Startup is completed.
2020-01-10T21:24:16.503Z,1578691456.503 [MissionManager](INFO): Uninitializing Mission Startup
2020-01-10T21:24:16.503Z,1578691456.503 [Startup] Stopped
2020-01-10T21:24:16.503Z,1578691456.503 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-01-10T21:24:16.503Z,1578691456.503 [Startup:A.GoToSurface] Stopped
2020-01-10T21:24:16.503Z,1578691456.503 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-01-10T21:24:16.628Z,1578691456.628 [MissionManager](IMPORTANT): Started mission Default
2020-01-10T21:24:16.628Z,1578691456.628 [Default] Running Loop=1
2020-01-10T21:24:16.628Z,1578691456.628 [Default](DEBUG): Aggregate::initialize Default
2020-01-10T21:24:16.628Z,1578691456.628 [Default:B.GoToSurface] Running Loop=1
2020-01-10T21:24:16.628Z,1578691456.628 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-01-10T21:24:16.637Z,1578691456.637 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-01-10T21:24:16.637Z,1578691456.637 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-01-10T21:24:16.638Z,1578691456.638 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-01-10T21:24:16.638Z,1578691456.638 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-01-10T21:24:16.638Z,1578691456.638 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-01-10T21:24:16.639Z,1578691456.639 [Default:A.Wait] Running Loop=1
2020-01-10T21:24:16.639Z,1578691456.639 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-01-10T21:24:28.234Z,1578691468.234 [DataOverHttps](INFO): Sending 115 bytes from file Logs/20200109T215614/Express0005.lzma
2020-01-10T21:24:29.968Z,1578691469.968 [Default:A.Wait](INFO): Done Waiting.
2020-01-10T21:24:29.968Z,1578691469.968 [Default:A.Wait] Stopped
2020-01-10T21:24:29.968Z,1578691469.968 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-01-10T21:24:30.238Z,1578691470.238 [DataOverHttps](INFO): Moved sent file to Logs/20200109T215614/Express0005.lzma.bak
2020-01-10T21:24:30.238Z,1578691470.238 [DataOverHttps](INFO): SBD MOMSN=12190852
2020-01-10T21:24:30.340Z,1578691470.340 [Default:CheckIn] Running Loop=1
2020-01-10T21:24:30.340Z,1578691470.340 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-10T21:24:30.340Z,1578691470.340 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-10T21:24:30.750Z,1578691470.750 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-01-10T21:26:38.417Z,1578691598.417 [BPC1](ERROR): BPC1B: No match for serial number FF57 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2020-01-10T21:26:41.612Z,1578691601.612 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-01-10T21:27:02.242Z,1578691622.242 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2020-01-10T21:27:02.242Z,1578691622.242 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-10T21:27:02.270Z,1578691622.270 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-10T21:27:02.689Z,1578691622.689 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-10T21:27:02.689Z,1578691622.689 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2020-01-10T21:29:24.075Z,1578691764.075 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-01-10T21:29:24.078Z,1578691764.078 [BPC1](INFO): Received data from all battery sticks.
2020-01-10T21:29:30.525Z,1578691770.525 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-01-10T21:24:30.3Z
2020-01-10T21:29:30.525Z,1578691770.525 [Default:CheckIn:Read_GPS] Stopped
2020-01-10T21:29:30.525Z,1578691770.525 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-10T21:29:30.941Z,1578691770.941 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-01-10T21:29:31.936Z,1578691771.936 [Micromodem](INFO): Outgoing frame #1, 64 bytes: B2EA185E040001005D00000400540100000000000000151D80030C5669BF3148E2FA22188B6A3173E5573572661C0BCBDFFAB423BD32D9A51E8904B0A920CE9A
2020-01-10T21:29:31.937Z,1578691771.937 [Micromodem](INFO): Outgoing frame #2, 64 bytes: EF9F66414C9B9FD6761AD008C3E3DC4FCA2E4E25B6359B14B772C42BBF445E54CD5F36943A3BDD9E89309E9017DB79DD009A333A47CF42F3BE4E4AD4301D597F
2020-01-10T21:29:31.937Z,1578691771.937 [Micromodem](INFO): Outgoing frame #3, 64 bytes: FFA3EC7FA24353C087A3A1B3BD161F92F69707865251F3B93C9772C25CD67B52231335EF84B8E92D5FFA309E6B04FFDD1E5FBC9A8B1E7A1333FA62009A92D1A7
2020-01-10T21:29:31.938Z,1578691771.938 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69

2020-01-10T21:29:32.148Z,1578691772.148 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B

2020-01-10T21:29:32.531Z,1578691772.531 [Micromodem](INFO): Nmea in: $CADRQ,212932,1,12,0,64,1*7D

2020-01-10T21:29:32.531Z,1578691772.531 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,B2EA185E040001005D00000400540100000000000000151D80030C5669BF3148E2FA22188B6A3173E5573572661C0BCBDFFAB423BD32D9A51E8904B0A920CE9A*40

2020-01-10T21:29:32.949Z,1578691772.949 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-10T21:29:33.339Z,1578691773.339 [Micromodem](INFO): Nmea in: $CADRQ,212933,1,12,0,64,2*7F

2020-01-10T21:29:33.339Z,1578691773.339 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,EF9F66414C9B9FD6761AD008C3E3DC4FCA2E4E25B6359B14B772C42BBF445E54CD5F36943A3BDD9E89309E9017DB79DD009A333A47CF42F3BE4E4AD4301D597F*3D

2020-01-10T21:29:33.747Z,1578691773.747 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-10T21:29:34.159Z,1578691774.159 [Micromodem](INFO): Nmea in: $CADRQ,212934,1,12,0,64,3*79

2020-01-10T21:29:34.159Z,1578691774.159 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,FFA3EC7FA24353C087A3A1B3BD161F92F69707865251F3B93C9772C25CD67B52231335EF84B8E92D5FFA309E6B04FFDD1E5FBC9A8B1E7A1333FA62009A92D1A7*3E

2020-01-10T21:29:34.564Z,1578691774.564 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-10T21:29:34.955Z,1578691774.955 [Micromodem](INFO): Nmea in: $CATXP,192*48

2020-01-10T21:29:38.599Z,1578691778.599 [Micromodem](INFO): Nmea in: $CATXF,192*5E

2020-01-10T21:29:38.689Z,1578691778.689 [DataOverHttps](INFO): Sending 189 bytes from file Logs/20200110T212050/Courier0004.lzma
2020-01-10T21:29:38.992Z,1578691778.992 [Micromodem](INFO): Nmea in: $CAXST,6,20200110,212935.240758,3,0,200,4000,10000,1,1,12,1,3,3,3,192*78

2020-01-10T21:29:40.215Z,1578691780.215 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-10T21:29:40.626Z,1578691780.626 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-10T21:29:40.694Z,1578691780.694 [DataOverHttps](INFO): Moved sent file to Logs/20200110T212050/Courier0004.lzma.bak
2020-01-10T21:29:40.695Z,1578691780.695 [DataOverHttps](INFO): SBD MOMSN=12190868
2020-01-10T21:29:41.015Z,1578691781.015 [Micromodem](INFO): Nmea in: $CAACK,12,1,3,1*7B

2020-01-10T21:29:41.016Z,1578691781.016 [Micromodem](INFO): Sent 184 bytes from file Logs/20200110T212050/Courier0004.lzma.parts
2020-01-10T21:29:41.016Z,1578691781.016 [Micromodem](INFO): Packets left to send: 1
2020-01-10T21:29:41.426Z,1578691781.426 [Micromodem](INFO): Nmea in: $CACST,6,0,20200110212939.793872,06,571,27,0115,0150,59,01,01,00,03,1,012,001,0,4,1,0,150,17.7,5.00,14,-2.00,-01,-0.05,31,10000,4000*4D

2020-01-10T21:29:42.446Z,1578691782.446 [Default:CheckIn:Read_Iridium] Stopped
2020-01-10T21:29:42.446Z,1578691782.446 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-10T21:29:42.446Z,1578691782.446 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-10T21:30:03.124Z,1578691803.124 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2020-01-10T21:30:03.124Z,1578691803.124 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-10T21:30:03.154Z,1578691803.154 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-10T21:30:03.524Z,1578691803.524 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-10T21:30:03.524Z,1578691803.524 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2020-01-10T21:30:05.937Z,1578691805.937 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-01-10T21:30:05.937Z,1578691805.937 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-32768,
2020-01-10T21:30:22.955Z,1578691822.955 [RDI_Pathfinder](ERROR): Failed to parse:
:SA,   +0.00,   +0.00,  0.00
2020-01-10T21:30:25.727Z,1578691825.727 [DataOverHttps](INFO): Sending 822 bytes from file Logs/20200109T220527/Express0001.lzma
2020-01-10T21:30:27.730Z,1578691827.730 [DataOverHttps](INFO): Moved sent file to Logs/20200109T220527/Express0001.lzma.bak
2020-01-10T21:30:27.730Z,1578691827.730 [DataOverHttps](INFO): SBD MOMSN=12190871
2020-01-10T21:31:39.239Z,1578691899.239 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-01-10T21:31:39.239Z,1578691899.239 [NAL9602] Data Fault, FailCount= 1
2020-01-10T21:31:39.239Z,1578691899.239 [NAL9602](ERROR): Data Fault
2020-01-10T21:31:39.303Z,1578691899.303 [CBIT](ERROR): Data Fault in component: NAL9602
2020-01-10T21:31:39.649Z,1578691899.649 [NAL9602](INFO): Powering down
2020-01-10T21:31:40.498Z,1578691900.498 [CBIT](INFO): Clearing failed state for component NAL9602
2020-01-10T21:31:40.498Z,1578691900.498 [NAL9602] No Fault, FailCount= 1
2020-01-10T21:32:09.940Z,1578691929.940 [NAL9602](INFO): Powering up NAL9602
2020-01-10T21:32:20.851Z,1578691940.851 [NAL9602](INFO): NAL9602 initialized
2020-01-10T21:32:24.929Z,1578691944.929 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2020-01-10T21:32:33.663Z,1578691953.663 [CommandLine](IMPORTANT): got command restart application
2020-01-10T21:32:34.669Z,1578691954.669 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-10T21:32:34.669Z,1578691954.669 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-10T21:32:34.769Z,1578691954.769 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-01-10T21:32:34.769Z,1578691954.769 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-10T21:32:34.770Z,1578691954.770 [CommandLine](INFO): Join timeout helper Thread ID is 8047
2020-01-10T21:32:34.770Z,1578691954.770 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-01-10T21:32:34.770Z,1578691954.770 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-10T21:32:34.771Z,1578691954.771 [NavChartDb](INFO): Join timeout helper Thread ID is 8048
2020-01-10T21:32:35.097Z,1578691955.097 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-10T21:32:35.097Z,1578691955.097 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-10T21:32:35.117Z,1578691955.117 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2020-01-10T21:32:35.117Z,1578691955.117 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-10T21:32:35.117Z,1578691955.117 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 8049
2020-01-10T21:32:35.273Z,1578691955.273 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-10T21:32:35.273Z,1578691955.273 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-01-10T21:32:35.274Z,1578691955.274 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-10T21:32:35.281Z,1578691955.281 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2020-01-10T21:32:35.281Z,1578691955.281 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-10T21:32:35.281Z,1578691955.281 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 8050
2020-01-10T21:32:35.481Z,1578691955.481 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-10T21:32:35.482Z,1578691955.482 [CTD_NeilBrown](INFO): Powering down
2020-01-10T21:32:35.493Z,1578691955.493 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-10T21:32:35.497Z,1578691955.497 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-01-10T21:32:35.497Z,1578691955.497 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-10T21:32:35.497Z,1578691955.497 [Radio_Surface](INFO): Join timeout helper Thread ID is 8051
2020-01-10T21:32:35.529Z,1578691955.529 [Radio_Surface](INFO): Powering down
2020-01-10T21:32:35.532Z,1578691955.532 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-10T21:32:35.532Z,1578691955.532 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-10T21:32:35.562Z,1578691955.562 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-01-10T21:32:35.562Z,1578691955.562 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-10T21:32:35.562Z,1578691955.562 [DataOverHttps](INFO): Join timeout helper Thread ID is 8052
2020-01-10T21:32:35.989Z,1578691955.989 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-10T21:32:35.989Z,1578691955.989 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-10T21:32:36.002Z,1578691956.002 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-01-10T21:32:36.002Z,1578691956.002 [logger ThreadHandler](INFO): Thread cancelled.
2020-01-10T21:32:36.002Z,1578691956.002 [logger](INFO): Join timeout helper Thread ID is 8053
2020-01-10T21:32:36.005Z,1578691956.005 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-10T21:32:36.005Z,1578691956.005 [logger ThreadHandler](INFO): Thread cancelled.
2020-01-10T21:32:36.021Z,1578691956.021 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-01-10T21:32:36.022Z,1578691956.022 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-10T21:32:36.022Z,1578691956.022 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-01-10T21:32:36.022Z,1578691956.022 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-01-10T21:32:36.022Z,1578691956.022 [controlThread](INFO): Join timeout helper Thread ID is 8054
2020-01-10T21:32:36.185Z,1578691956.185 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-10T21:32:36.185Z,1578691956.185 [controlThread](DEBUG): Uninitializing ControlThread
2020-01-10T21:32:36.186Z,1578691956.186 [AHRS_M2](INFO): Powering down
2020-01-10T21:32:36.257Z,1578691956.257 [DDM](INFO): Powering down
2020-01-10T21:32:36.349Z,1578691956.349 [DUSBL_Hydroid](INFO): Powering down
2020-01-10T21:32:36.441Z,1578691956.441 [Micromodem](INFO): Powering down
2020-01-10T21:32:36.537Z,1578691956.537 [NAL9602](INFO): Powering down
2020-01-10T21:32:36.609Z,1578691956.609 [RDI_Pathfinder](INFO): Powering down
2020-01-10T21:32:36.610Z,1578691956.610 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-01-10T21:32:36.611Z,1578691956.611 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-01-10T21:32:36.612Z,1578691956.612 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-01-10T21:32:36.612Z,1578691956.612 [MissionManager](INFO): Uninitializing Mission Default
2020-01-10T21:32:36.612Z,1578691956.612 [Default] Stopped
2020-01-10T21:32:36.612Z,1578691956.612 [Default](DEBUG): Aggregate::uninitialize Default
2020-01-10T21:32:36.612Z,1578691956.612 [Default:B.GoToSurface] Stopped
2020-01-10T21:32:36.612Z,1578691956.612 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-01-10T21:32:36.613Z,1578691956.613 [Default:CheckIn] Stopped
2020-01-10T21:32:36.613Z,1578691956.613 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-10T21:32:36.613Z,1578691956.613 [Default:CheckIn:C.Wait] Stopped
2020-01-10T21:32:36.613Z,1578691956.613 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-10T21:32:36.615Z,1578691956.615 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-01-10T21:32:36.616Z,1578691956.616 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-01-10T21:32:36.616Z,1578691956.616 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-01-10T21:32:36.616Z,1578691956.616 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-01-10T21:32:36.616Z,1578691956.616 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-01-10T21:32:36.616Z,1578691956.616 [BuoyancyServo](INFO): Powering down
2020-01-10T21:32:36.629Z,1578691956.629 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-01-10T21:32:36.629Z,1578691956.629 [ElevatorServo](INFO): Powering down
2020-01-10T21:32:36.630Z,1578691956.630 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-01-10T21:32:36.630Z,1578691956.630 [MassServo](INFO): Powering down
2020-01-10T21:32:36.631Z,1578691956.631 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-01-10T21:32:36.631Z,1578691956.631 [RudderServo](INFO): Powering down
2020-01-10T21:32:36.631Z,1578691956.631 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-01-10T21:32:36.631Z,1578691956.631 [ThrusterServo](INFO): Powering down
2020-01-10T21:32:36.632Z,1578691956.632 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-01-10T21:32:36.633Z,1578691956.633 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-01-10T21:32:36.633Z,1578691956.633 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-01-10T21:32:36.633Z,1578691956.633 [CBIT](DEBUG): Powering off loads.
2020-01-10T21:32:36.645Z,1578691956.645 [CBIT](DEBUG): Disabling WDT.
2020-01-10T21:32:36.657Z,1578691956.657 [CBIT](DEBUG): Opening all GF detection circuits.
2020-01-10T21:32:36.658Z,1578691956.658 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-01-10T21:32:36.706Z,1578691956.706 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-10T21:32:36.717Z,1578691956.717 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-10T21:32:36.764Z,1578691956.764 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-10T21:32:36.767Z,1578691956.767 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-10T21:32:36.827Z,1578691956.827 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-10T21:32:36.889Z,1578691956.889 [logger ThreadHandler](INFO): Thread cancelled.