2020-01-10T02:12:38.047Z,1578622358.047 [Supervisor](DEBUG): Initializing supervisor.
2020-01-10T02:12:38.050Z,1578622358.050 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-01-10T02:12:38.050Z,1578622358.050 [SyncHandler](INFO): Protected caller Thread ID is 9059
2020-01-10T02:12:38.051Z,1578622358.051 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-01-10T02:12:38.052Z,1578622358.052 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-01-10T02:12:38.053Z,1578622358.053 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 9060
2020-01-10T02:12:38.056Z,1578622358.056 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-01-10T02:12:38.067Z,1578622358.067 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-01-10T02:12:38.068Z,1578622358.068 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-01-10T02:12:38.069Z,1578622358.069 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 9061
2020-01-10T02:12:38.070Z,1578622358.070 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-01-10T02:12:38.070Z,1578622358.070 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-01-10T02:12:38.071Z,1578622358.071 [logger ThreadHandler](INFO): Protected caller Thread ID is 9062
2020-01-10T02:12:38.073Z,1578622358.073 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-01-10T02:12:38.073Z,1578622358.073 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-01-10T02:12:38.075Z,1578622358.075 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-01-10T02:12:38.492Z,1578622358.492 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-01-10T02:12:38.493Z,1578622358.493 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-01-10T02:12:38.588Z,1578622358.588 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-01-10T02:12:38.588Z,1578622358.588 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-01-10T02:12:38.906Z,1578622358.906 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-01-10T02:12:38.907Z,1578622358.907 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-01-10T02:12:39.218Z,1578622359.218 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-01-10T02:12:39.219Z,1578622359.219 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-01-10T02:12:39.402Z,1578622359.402 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-01-10T02:12:39.403Z,1578622359.403 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-01-10T02:12:39.837Z,1578622359.837 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-01-10T02:12:39.838Z,1578622359.838 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-01-10T02:12:40.039Z,1578622360.039 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-01-10T02:12:40.040Z,1578622360.040 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-01-10T02:12:40.179Z,1578622360.179 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-01-10T02:12:40.180Z,1578622360.180 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-01-10T02:12:40.360Z,1578622360.360 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-01-10T02:12:40.360Z,1578622360.360 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-01-10T02:12:40.454Z,1578622360.454 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-01-10T02:12:40.454Z,1578622360.454 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-01-10T02:12:40.743Z,1578622360.743 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-01-10T02:12:40.744Z,1578622360.744 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-01-10T02:12:40.822Z,1578622360.822 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-01-10T02:12:40.922Z,1578622360.922 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-01-10T02:12:40.922Z,1578622360.922 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-01-10T02:12:41.542Z,1578622361.542 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-01-10T02:12:41.543Z,1578622361.543 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-01-10T02:12:41.943Z,1578622361.943 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-01-10T02:12:41.944Z,1578622361.944 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2020-01-10T02:12:41.945Z,1578622361.945 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2020-01-10T02:12:42.142Z,1578622362.142 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2020-01-10T02:12:42.238Z,1578622362.238 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2020-01-10T02:12:42.332Z,1578622362.332 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2020-01-10T02:12:42.558Z,1578622362.558 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-01-10T02:12:42.558Z,1578622362.558 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2020-01-10T02:12:42.640Z,1578622362.640 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2020-01-10T02:12:42.731Z,1578622362.731 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2020-01-10T02:12:42.824Z,1578622362.824 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2020-01-10T02:12:42.905Z,1578622362.905 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2020-01-10T02:12:43.008Z,1578622363.008 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2020-01-10T02:12:43.183Z,1578622363.183 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2020-01-10T02:12:43.308Z,1578622363.308 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/
2020-01-10T02:12:43.308Z,1578622363.308 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-01-10T02:12:43.331Z,1578622363.331 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-01-10T02:12:43.652Z,1578622363.652 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-01-10T02:12:43.657Z,1578622363.657 [AHRS_M2](INFO): created writer for : platform_orientation
2020-01-10T02:12:43.659Z,1578622363.659 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-01-10T02:12:43.664Z,1578622363.664 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-01-10T02:12:43.665Z,1578622363.665 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-01-10T02:12:43.670Z,1578622363.670 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-01-10T02:12:43.671Z,1578622363.671 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-01-10T02:12:43.676Z,1578622363.676 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-01-10T02:12:43.747Z,1578622363.747 [AHRS_M2] Loaded
2020-01-10T02:12:43.748Z,1578622363.748 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-01-10T02:12:43.828Z,1578622363.828 [DataOverHttps] Loaded
2020-01-10T02:12:43.828Z,1578622363.828 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-01-10T02:12:43.829Z,1578622363.829 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 407684E0
2020-01-10T02:12:43.829Z,1578622363.829 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 9141
2020-01-10T02:12:43.853Z,1578622363.853 [DDM] Loaded
2020-01-10T02:12:43.854Z,1578622363.854 [ComponentRegistry](DEBUG): SyncComponent "DDM" handled in the control thread.
2020-01-10T02:12:43.866Z,1578622363.866 [Depth_Keller] Loaded
2020-01-10T02:12:43.867Z,1578622363.867 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-01-10T02:12:43.872Z,1578622363.872 [DropWeight] Loaded
2020-01-10T02:12:43.872Z,1578622363.872 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2020-01-10T02:12:43.933Z,1578622363.933 [DUSBL_Hydroid] Loaded
2020-01-10T02:12:43.934Z,1578622363.934 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2020-01-10T02:12:43.980Z,1578622363.980 [Micromodem] Loaded
2020-01-10T02:12:43.980Z,1578622363.980 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2020-01-10T02:12:44.077Z,1578622364.077 [NAL9602] Loaded
2020-01-10T02:12:44.077Z,1578622364.077 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-01-10T02:12:44.114Z,1578622364.114 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/voltage_now
2020-01-10T02:12:44.115Z,1578622364.115 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/current_now
2020-01-10T02:12:44.115Z,1578622364.115 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_now
2020-01-10T02:12:44.116Z,1578622364.116 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_full
2020-01-10T02:12:44.116Z,1578622364.116 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/voltage_now
2020-01-10T02:12:44.117Z,1578622364.117 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/current_now
2020-01-10T02:12:44.117Z,1578622364.117 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_now
2020-01-10T02:12:44.118Z,1578622364.118 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_full
2020-01-10T02:12:44.118Z,1578622364.118 [Onboard] Loaded
2020-01-10T02:12:44.118Z,1578622364.118 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2020-01-10T02:12:44.124Z,1578622364.124 [PowerOnly] Loaded
2020-01-10T02:12:44.124Z,1578622364.124 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2020-01-10T02:12:44.131Z,1578622364.131 [Radio_Surface] Loaded
2020-01-10T02:12:44.131Z,1578622364.131 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-01-10T02:12:44.132Z,1578622364.132 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407984E0
2020-01-10T02:12:44.132Z,1578622364.132 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 9142
2020-01-10T02:12:44.175Z,1578622364.175 [RDI_Pathfinder] Loaded
2020-01-10T02:12:44.176Z,1578622364.176 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2020-01-10T02:12:45.681Z,1578622365.681 [BPC1] Loaded
2020-01-10T02:12:45.681Z,1578622365.681 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-01-10T02:12:45.682Z,1578622365.682 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-01-10T02:12:45.682Z,1578622365.682 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-01-10T02:12:45.728Z,1578622365.728 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-01-10T02:12:45.729Z,1578622365.729 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-01-10T02:12:45.846Z,1578622365.846 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-01-10T02:12:45.853Z,1578622365.853 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-01-10T02:12:45.997Z,1578622365.997 [NavChart] Loaded
2020-01-10T02:12:45.997Z,1578622365.997 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-01-10T02:12:46.001Z,1578622366.001 [UniversalFixResidualReporter] Loaded
2020-01-10T02:12:46.002Z,1578622366.002 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-01-10T02:12:46.002Z,1578622366.002 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-01-10T02:12:46.002Z,1578622366.002 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-01-10T02:12:46.243Z,1578622366.243 [BuoyancyServo] Loaded
2020-01-10T02:12:46.244Z,1578622366.244 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-01-10T02:12:46.283Z,1578622366.283 [ElevatorServo] Loaded
2020-01-10T02:12:46.284Z,1578622366.284 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-01-10T02:12:46.299Z,1578622366.299 [MassServo] Loaded
2020-01-10T02:12:46.299Z,1578622366.299 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-01-10T02:12:46.314Z,1578622366.314 [RudderServo] Loaded
2020-01-10T02:12:46.314Z,1578622366.314 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-01-10T02:12:46.338Z,1578622366.338 [ThrusterServo] Loaded
2020-01-10T02:12:46.338Z,1578622366.338 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-01-10T02:12:46.338Z,1578622366.338 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-01-10T02:12:46.339Z,1578622366.339 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-01-10T02:12:46.772Z,1578622366.772 [CTD_NeilBrown] Loaded
2020-01-10T02:12:46.773Z,1578622366.773 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2020-01-10T02:12:46.774Z,1578622366.774 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408EC4E0
2020-01-10T02:12:46.774Z,1578622366.774 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 9143
2020-01-10T02:12:46.788Z,1578622366.788 [PAR_Licor] Loaded
2020-01-10T02:12:46.788Z,1578622366.788 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2020-01-10T02:12:46.831Z,1578622366.831 [WetLabsSeaOWL_UV_A] Loaded
2020-01-10T02:12:46.832Z,1578622366.832 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2020-01-10T02:12:46.833Z,1578622366.833 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 4091C4E0
2020-01-10T02:12:46.833Z,1578622366.833 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 9144
2020-01-10T02:12:46.834Z,1578622366.834 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-01-10T02:12:46.834Z,1578622366.834 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-01-10T02:12:47.144Z,1578622367.144 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-01-10T02:12:47.144Z,1578622367.144 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-01-10T02:12:47.184Z,1578622367.184 [DepthRateCalculator] Loaded
2020-01-10T02:12:47.184Z,1578622367.184 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-01-10T02:12:47.190Z,1578622367.190 [PitchRateCalculator] Loaded
2020-01-10T02:12:47.190Z,1578622367.190 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-01-10T02:12:47.202Z,1578622367.202 [SpeedCalculator] Loaded
2020-01-10T02:12:47.203Z,1578622367.203 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-01-10T02:12:47.223Z,1578622367.223 [TempGradientCalculator] Loaded
2020-01-10T02:12:47.223Z,1578622367.223 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-01-10T02:12:47.229Z,1578622367.229 [YawRateCalculator] Loaded
2020-01-10T02:12:47.229Z,1578622367.229 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-01-10T02:12:47.269Z,1578622367.269 [ElevatorOffsetCalculator] Loaded
2020-01-10T02:12:47.269Z,1578622367.269 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-01-10T02:12:47.270Z,1578622367.270 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-01-10T02:12:47.270Z,1578622367.270 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-01-10T02:12:47.406Z,1578622367.406 [SBIT](DEBUG): Construct Startup Built In Test.
2020-01-10T02:12:47.427Z,1578622367.427 [SBIT] Loaded
2020-01-10T02:12:47.427Z,1578622367.427 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-01-10T02:12:47.428Z,1578622367.428 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-01-10T02:12:47.441Z,1578622367.441 [IBIT] Loaded
2020-01-10T02:12:47.441Z,1578622367.441 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-01-10T02:12:47.444Z,1578622367.444 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-01-10T02:12:47.582Z,1578622367.582 [CBIT] Loaded
2020-01-10T02:12:47.582Z,1578622367.582 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-01-10T02:12:47.583Z,1578622367.583 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-01-10T02:12:47.583Z,1578622367.583 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-01-10T02:12:47.665Z,1578622367.665 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-01-10T02:12:47.666Z,1578622367.666 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-01-10T02:12:47.763Z,1578622367.763 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-01-10T02:12:47.764Z,1578622367.764 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-01-10T02:12:47.812Z,1578622367.812 [VerticalControl](DEBUG): Construct VerticalControl.
2020-01-10T02:12:47.894Z,1578622367.894 [VerticalControl] Loaded
2020-01-10T02:12:47.895Z,1578622367.895 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-01-10T02:12:47.895Z,1578622367.895 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-01-10T02:12:47.965Z,1578622367.965 [HorizontalControl] Loaded
2020-01-10T02:12:47.966Z,1578622367.966 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-01-10T02:12:47.966Z,1578622367.966 [SpeedControl](DEBUG): Construct SpeedControl.
2020-01-10T02:12:47.968Z,1578622367.968 [SpeedControl] Loaded
2020-01-10T02:12:47.968Z,1578622367.968 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-01-10T02:12:47.969Z,1578622367.969 [LoopControl](DEBUG): Construct LoopControl.
2020-01-10T02:12:47.969Z,1578622367.969 [LoopControl] Loaded
2020-01-10T02:12:47.970Z,1578622367.970 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-01-10T02:12:47.970Z,1578622367.970 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-01-10T02:12:47.971Z,1578622367.971 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-01-10T02:12:48.003Z,1578622368.003 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-01-10T02:12:48.006Z,1578622368.006 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-01-10T02:12:48.007Z,1578622368.007 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-01-10T02:12:48.014Z,1578622368.014 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-01-10T02:12:48.015Z,1578622368.015 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AFB4E0
2020-01-10T02:12:48.015Z,1578622368.015 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 9145
2020-01-10T02:12:48.020Z,1578622368.020 [Supervisor](INFO): Main Thread ID is 8534
2020-01-10T02:12:48.020Z,1578622368.020 [Supervisor](DEBUG): Running supervisor.
2020-01-10T02:12:48.020Z,1578622368.020 [CommandLine ThreadHandler](INFO): Handler Thread ID is 9146
2020-01-10T02:12:48.023Z,1578622368.023 [controlThread ThreadHandler](INFO): Handler Thread ID is 9147
2020-01-10T02:12:48.023Z,1578622368.023 [controlThread](DEBUG): Initializing ControlThread
2020-01-10T02:12:48.032Z,1578622368.032 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-01-10T02:12:48.032Z,1578622368.032 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-01-10T02:12:48.037Z,1578622368.037 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-01-10T02:12:48.038Z,1578622368.038 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-01-10T02:12:48.038Z,1578622368.038 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-01-10T02:12:48.038Z,1578622368.038 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-01-10T02:12:48.039Z,1578622368.039 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-01-10T02:12:48.039Z,1578622368.039 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-01-10T02:12:48.040Z,1578622368.040 [SBIT](INFO): Initialize SBIT Component.
2020-01-10T02:12:48.040Z,1578622368.040 [SBIT](IMPORTANT): git: 2019-12-03-17-g4812c94
2020-01-10T02:12:48.040Z,1578622368.040 [SBIT](INFO): git hash: 4812c9429720da7730f61311b178b42396aebb36
2020-01-10T02:12:48.041Z,1578622368.041 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-01-10T02:12:48.042Z,1578622368.042 [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-10T02:12:48.043Z,1578622368.043 [SBIT](INFO): Beginning SBIT in 39.000000 seconds.
2020-01-10T02:12:48.044Z,1578622368.044 [IBIT](INFO): Initialize IBIT Component.
2020-01-10T02:12:48.045Z,1578622368.045 [CBIT](DEBUG): Initialize CBIT Component.
2020-01-10T02:12:48.046Z,1578622368.046 [logger ThreadHandler](INFO): Handler Thread ID is 9148
2020-01-10T02:12:48.057Z,1578622368.057 [CBIT](DEBUG): Initialized mux pins.
2020-01-10T02:12:48.057Z,1578622368.057 [CBIT](DEBUG): Initializing the watchdog timer.
2020-01-10T02:12:48.065Z,1578622368.065 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 9149
2020-01-10T02:12:48.066Z,1578622368.066 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-01-10T02:12:48.077Z,1578622368.077 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 9150
2020-01-10T02:12:48.081Z,1578622368.081 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-01-10T02:12:48.081Z,1578622368.081 [CBIT](DEBUG): Initializing heartbeat.
2020-01-10T02:12:48.089Z,1578622368.089 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 9151
2020-01-10T02:12:48.090Z,1578622368.090 [CTD_NeilBrown](INFO): Powering down
2020-01-10T02:12:48.117Z,1578622368.117 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 9152
2020-01-10T02:12:48.118Z,1578622368.118 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-01-10T02:12:48.145Z,1578622368.145 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 9153
2020-01-10T02:12:48.148Z,1578622368.148 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-01-10T02:12:48.148Z,1578622368.148 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-01-10T02:12:48.148Z,1578622368.148 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-01-10T02:12:48.149Z,1578622368.149 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-01-10T02:12:48.149Z,1578622368.149 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-01-10T02:12:48.149Z,1578622368.149 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-01-10T02:12:48.149Z,1578622368.149 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-01-10T02:12:48.149Z,1578622368.149 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-01-10T02:12:48.150Z,1578622368.150 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-01-10T02:12:48.150Z,1578622368.150 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-01-10T02:12:48.150Z,1578622368.150 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-01-10T02:12:48.150Z,1578622368.150 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-01-10T02:12:48.150Z,1578622368.150 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-01-10T02:12:48.151Z,1578622368.151 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-01-10T02:12:48.151Z,1578622368.151 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-01-10T02:12:48.151Z,1578622368.151 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-01-10T02:12:48.153Z,1578622368.153 [CBIT](DEBUG): Deactivating GF circuits.
2020-01-10T02:12:48.153Z,1578622368.153 [CBIT](DEBUG): Deactivating emergency mode.
2020-01-10T02:12:48.189Z,1578622368.189 [CBIT](DEBUG): Backplane powered.
2020-01-10T02:12:48.189Z,1578622368.189 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-01-10T02:12:48.191Z,1578622368.191 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-01-10T02:12:48.191Z,1578622368.191 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-01-10T02:12:48.192Z,1578622368.192 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-01-10T02:12:48.193Z,1578622368.193 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-01-10T02:12:48.203Z,1578622368.203 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-01-10T02:12:48.222Z,1578622368.222 [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-10T02:12:48.223Z,1578622368.223 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-01-10T02:12:48.296Z,1578622368.296 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-01-10T02:12:48.298Z,1578622368.298 [Default:A.Wait](DEBUG): Construct Wait.
2020-01-10T02:12:48.299Z,1578622368.299 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-01-10T02:12:48.347Z,1578622368.347 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-01-10T02:12:48.350Z,1578622368.350 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-01-10T02:12:48.371Z,1578622368.371 [Default:E.Execute](DEBUG): Construct Execute.
2020-01-10T02:12:48.375Z,1578622368.375 [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-10T02:12:48.395Z,1578622368.395 [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-10T02:12:48.413Z,1578622368.413 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-01-10T02:12:48.457Z,1578622368.457 [DDM](INFO): Powering up
2020-01-10T02:12:48.457Z,1578622368.457 [DDM](DEBUG): Initializing DDM.
2020-01-10T02:12:48.481Z,1578622368.481 [Radio_Surface](INFO): Powering up
2020-01-10T02:12:48.482Z,1578622368.482 [Depth_Keller](ERROR): Pressure reading out of range: 957.962097 decibar
2020-01-10T02:12:48.483Z,1578622368.483 [DUSBL_Hydroid](INFO): Powering up
2020-01-10T02:12:48.483Z,1578622368.483 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2020-01-10T02:12:48.655Z,1578622368.655 [DepthRateCalculator](ERROR): Depth measurement is not active
2020-01-10T02:12:48.687Z,1578622368.687 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-01-10T02:12:48.693Z,1578622368.693 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-01-10T02:12:48.694Z,1578622368.694 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-01-10T02:12:48.702Z,1578622368.702 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-01-10T02:12:48.702Z,1578622368.702 [MassServo](DEBUG): Initializing EZServoServo.
2020-01-10T02:12:48.709Z,1578622368.709 [MassServo](DEBUG): Initializing MassServo.
2020-01-10T02:12:48.710Z,1578622368.710 [RudderServo](DEBUG): Initializing EZServoServo.
2020-01-10T02:12:48.717Z,1578622368.717 [RudderServo](DEBUG): Initializing RudderServo.
2020-01-10T02:12:48.718Z,1578622368.718 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-01-10T02:12:48.725Z,1578622368.725 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-01-10T02:12:48.823Z,1578622368.823 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2020-01-10T02:12:48.823Z,1578622368.823 [DropWeight] Hardware Fault, FailCount= 1
2020-01-10T02:12:48.823Z,1578622368.823 [DropWeight](ERROR): Hardware Fault
2020-01-10T02:12:48.825Z,1578622368.825 [Micromodem](INFO): Powering up
2020-01-10T02:12:48.825Z,1578622368.825 [Micromodem](DEBUG): Initializing Micromodem.
2020-01-10T02:12:48.910Z,1578622368.910 [CommandLine](FAULT): Scheduling is paused
2020-01-10T02:12:48.910Z,1578622368.910 [CBIT](INFO): Critical error at 20200110T021248
2020-01-10T02:12:48.910Z,1578622368.910 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2020-01-10T02:12:48.913Z,1578622368.913 [CBIT](ERROR): Hardware Fault in component: DropWeight
2020-01-10T02:12:48.913Z,1578622368.913 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2020-01-10T02:12:49.565Z,1578622369.565 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2020-01-10T02:12:49.565Z,1578622369.565 [RudderServo](FAULT): Rudder failed to initialize
2020-01-10T02:12:49.565Z,1578622369.565 [RudderServo] Communications Fault, FailCount= 1
2020-01-10T02:12:49.565Z,1578622369.565 [RudderServo](ERROR): Communications Fault
2020-01-10T02:12:49.674Z,1578622369.674 [CBIT](INFO): Critical error at 20200110T021248
2020-01-10T02:12:49.677Z,1578622369.677 [CBIT](ERROR): Communications Fault in component: RudderServo
2020-01-10T02:12:49.878Z,1578622369.878 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-01-10T02:12:49.878Z,1578622369.878 [RudderServo](INFO): Powering down
2020-01-10T02:12:50.540Z,1578622370.540 [RudderServo](DEBUG): Initializing EZServoServo.
2020-01-10T02:12:50.658Z,1578622370.658 [RudderServo](DEBUG): Initializing RudderServo.
2020-01-10T02:12:50.662Z,1578622370.662 [CBIT](INFO): Clearing failed state for component RudderServo
2020-01-10T02:12:50.662Z,1578622370.662 [RudderServo] No Fault, FailCount= 1
2020-01-10T02:12:53.765Z,1578622373.765 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2020-01-10T02:12:54.171Z,1578622374.171 [Micromodem](INFO): Nmea in: $CATMG,2020-01-10T02:12:53.674435Z,RTC,RTC*50

2020-01-10T02:12:54.172Z,1578622374.172 [Micromodem](ERROR): CFG response from modem unexpected: $CATMG,2020-01-10T02:12:53.674435Z,RTC,RTC*50

2020-01-10T02:12:54.571Z,1578622374.571 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31

2020-01-10T02:12:54.571Z,1578622374.571 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2020-01-10T02:12:54.975Z,1578622374.975 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33

2020-01-10T02:12:54.975Z,1578622374.975 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64

2020-01-10T02:12:55.383Z,1578622375.383 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66

2020-01-10T02:12:55.383Z,1578622375.383 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F

2020-01-10T02:12:55.787Z,1578622375.787 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D

2020-01-10T02:12:55.787Z,1578622375.787 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E

2020-01-10T02:12:56.191Z,1578622376.191 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C

2020-01-10T02:12:56.191Z,1578622376.191 [Micromodem](INFO): Nmea out: $CCCFG,FC0,10000*46

2020-01-10T02:12:56.591Z,1578622376.591 [Micromodem](INFO): Nmea in: $CACFG,FC0,10000*44

2020-01-10T02:12:56.591Z,1578622376.591 [Micromodem](INFO): Nmea out: $CCCFG,BW0,4000*63

2020-01-10T02:12:57.004Z,1578622377.004 [Micromodem](INFO): Nmea in: $CACFG,BW0,4000*61

2020-01-10T02:12:57.013Z,1578622377.013 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A

2020-01-10T02:12:57.375Z,1578622377.375 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38

2020-01-10T02:12:57.375Z,1578622377.375 [Micromodem](INFO): Nmea out: $CCCLK,2020,01,10,02,12,58*48

2020-01-10T02:12:57.787Z,1578622377.787 [Micromodem](INFO): Nmea in: $CACLK,2020,1,10,2,12,58*4A

2020-01-10T02:12:58.179Z,1578622378.179 [Micromodem](INFO): Nmea in: $CATMS,0,2020-01-10T02:12:59Z*7B

2020-01-10T02:12:58.180Z,1578622378.180 [Micromodem](INFO): Nmea in: $CATMG,2020-01-10T02:12:59.026878Z,USER_CMD,RTC*1F

2020-01-10T02:12:58.989Z,1578622378.989 [DDM](INFO): Dynamic Docking Module:!C REMUS Capture Rev 1.1
2020-01-10T02:13:01.838Z,1578622381.838 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2020-01-10T02:13:06.662Z,1578622386.662 [DUSBL_Hydroid](INFO): DUSBL Version:O
2020-01-10T02:13:14.335Z,1578622394.335 [NAL9602](INFO): Powering up NAL9602
2020-01-10T02:13:18.376Z,1578622398.376 [Micromodem](INFO): Nmea in: $CACYC,1,12,1,1,0,2*6B

2020-01-10T02:13:18.376Z,1578622398.376 [Micromodem](INFO): Have incoming data
2020-01-10T02:13:18.388Z,1578622398.388 [Micromodem](INFO): Nmea in: $CARXD,12,1,0,1,858A1AB878DB70DBE89704525FDC95B5C5FE9F4147C09B083E4A8CE30F9EA23DB7C2EC1A3F0C8416B2D6BA469C63028C955B1EDAD054116D0BA2C541AD2A74E9*5A

2020-01-10T02:13:18.388Z,1578622398.388 [Micromodem](INFO): FrameSize_:127
2020-01-10T02:13:18.393Z,1578622398.393 [Micromodem](INFO): Frame Count:2
2020-01-10T02:13:18.393Z,1578622398.393 [Micromodem](INFO): Data End:143
2020-01-10T02:13:18.393Z,1578622398.393 [Micromodem](INFO): Data At:16
2020-01-10T02:13:18.393Z,1578622398.393 [Micromodem](INFO): FrameSize_:125
2020-01-10T02:13:18.393Z,1578622398.393 [Micromodem](INFO): Frame Count:2
2020-01-10T02:13:18.394Z,1578622398.394 [Micromodem](INFO): Data End:141
2020-01-10T02:13:18.394Z,1578622398.394 [Micromodem](INFO): Data At:16
2020-01-10T02:13:18.814Z,1578622398.814 [Micromodem](INFO): Nmea in: $CARXD,12,1,0,2,4EEE1310BA042F91D0BD31A76379D458F2E8C122*52

2020-01-10T02:13:18.814Z,1578622398.814 [Micromodem](INFO): FrameSize_:40
2020-01-10T02:13:18.814Z,1578622398.814 [Micromodem](INFO): Frame Count:2
2020-01-10T02:13:18.815Z,1578622398.815 [Micromodem](INFO): Data End:56
2020-01-10T02:13:18.815Z,1578622398.815 [Micromodem](INFO): Data At:16
2020-01-10T02:13:18.815Z,1578622398.815 [Micromodem](INFO): FrameSize_:38
2020-01-10T02:13:18.815Z,1578622398.815 [Micromodem](INFO): Frame Count:2
2020-01-10T02:13:18.815Z,1578622398.815 [Micromodem](INFO): Data End:54
2020-01-10T02:13:18.815Z,1578622398.815 [Micromodem](INFO): Data At:16
2020-01-10T02:13:18.816Z,1578622398.816 [Micromodem](INFO): Deserialize. FrameCount:2
2020-01-10T02:13:18.816Z,1578622398.816 [Micromodem](INFO): Outer loop. Size:40
2020-01-10T02:13:18.816Z,1578622398.816 [Micromodem](INFO): Inner loop. C:133
2020-01-10T02:13:18.816Z,1578622398.816 [Micromodem](INFO): Inner loop. C:138
2020-01-10T02:13:18.816Z,1578622398.816 [Micromodem](INFO): Inner loop. C:26
2020-01-10T02:13:18.821Z,1578622398.821 [Micromodem](INFO): Inner loop. C:184
2020-01-10T02:13:18.821Z,1578622398.821 [Micromodem](INFO): Inner loop. C:120
2020-01-10T02:13:18.821Z,1578622398.821 [Micromodem](INFO): Inner loop. C:219
2020-01-10T02:13:18.821Z,1578622398.821 [Micromodem](INFO): Inner loop. C:112
2020-01-10T02:13:18.821Z,1578622398.821 [Micromodem](INFO): Inner loop. C:219
2020-01-10T02:13:18.822Z,1578622398.822 [Micromodem](INFO): Inner loop. C:232
2020-01-10T02:13:18.822Z,1578622398.822 [Micromodem](INFO): Inner loop. C:151
2020-01-10T02:13:18.822Z,1578622398.822 [Micromodem](INFO): Inner loop. C:4
2020-01-10T02:13:18.822Z,1578622398.822 [Micromodem](INFO): Inner loop. C:82
2020-01-10T02:13:18.822Z,1578622398.822 [Micromodem](INFO): Inner loop. C:95
2020-01-10T02:13:18.822Z,1578622398.822 [Micromodem](INFO): Inner loop. C:220
2020-01-10T02:13:18.823Z,1578622398.823 [Micromodem](INFO): Inner loop. C:149
2020-01-10T02:13:18.823Z,1578622398.823 [Micromodem](INFO): Inner loop. C:181
2020-01-10T02:13:18.823Z,1578622398.823 [Micromodem](INFO): Inner loop. C:197
2020-01-10T02:13:18.823Z,1578622398.823 [Micromodem](INFO): Inner loop. C:254
2020-01-10T02:13:18.823Z,1578622398.823 [Micromodem](INFO): Inner loop. C:159
2020-01-10T02:13:18.823Z,1578622398.823 [Micromodem](INFO): Inner loop. C:65
2020-01-10T02:13:18.824Z,1578622398.824 [Micromodem](INFO): Inner loop. C:71
2020-01-10T02:13:18.824Z,1578622398.824 [Micromodem](INFO): Inner loop. C:192
2020-01-10T02:13:18.824Z,1578622398.824 [Micromodem](INFO): Inner loop. C:155
2020-01-10T02:13:18.824Z,1578622398.824 [Micromodem](INFO): Inner loop. C:8
2020-01-10T02:13:18.824Z,1578622398.824 [Micromodem](INFO): Inner loop. C:62
2020-01-10T02:13:18.824Z,1578622398.824 [Micromodem](INFO): Inner loop. C:74
2020-01-10T02:13:18.865Z,1578622398.865 [Micromodem](INFO): Inner loop. C:140
2020-01-10T02:13:18.865Z,1578622398.865 [Micromodem](INFO): Inner loop. C:227
2020-01-10T02:13:18.865Z,1578622398.865 [Micromodem](INFO): Inner loop. C:15
2020-01-10T02:13:18.865Z,1578622398.865 [Micromodem](INFO): Inner loop. C:158
2020-01-10T02:13:18.866Z,1578622398.866 [Micromodem](INFO): Inner loop. C:162
2020-01-10T02:13:18.866Z,1578622398.866 [Micromodem](INFO): Inner loop. C:61
2020-01-10T02:13:18.866Z,1578622398.866 [Micromodem](INFO): Inner loop. C:183
2020-01-10T02:13:18.866Z,1578622398.866 [Micromodem](INFO): Inner loop. C:194
2020-01-10T02:13:18.866Z,1578622398.866 [Micromodem](INFO): Inner loop. C:236
2020-01-10T02:13:18.867Z,1578622398.867 [Micromodem](INFO): Inner loop. C:26
2020-01-10T02:13:18.867Z,1578622398.867 [Micromodem](INFO): Inner loop. C:63
2020-01-10T02:13:18.867Z,1578622398.867 [Micromodem](INFO): Inner loop. C:12
2020-01-10T02:13:18.867Z,1578622398.867 [Micromodem](INFO): Inner loop. C:132
2020-01-10T02:13:18.867Z,1578622398.867 [Micromodem](INFO): Inner loop. C:22
2020-01-10T02:13:18.867Z,1578622398.867 [Micromodem](INFO): Outer loop. Size:38
2020-01-10T02:13:18.868Z,1578622398.868 [Micromodem](INFO): Inner loop. C:78
2020-01-10T02:13:18.868Z,1578622398.868 [Micromodem](INFO): Inner loop. C:238
2020-01-10T02:13:18.868Z,1578622398.868 [Micromodem](INFO): Inner loop. C:19
2020-01-10T02:13:18.868Z,1578622398.868 [Micromodem](INFO): Inner loop. C:16
2020-01-10T02:13:18.868Z,1578622398.868 [Micromodem](INFO): Inner loop. C:186
2020-01-10T02:13:18.868Z,1578622398.868 [Micromodem](INFO): Inner loop. C:4
2020-01-10T02:13:18.879Z,1578622398.879 [Micromodem](INFO): Inner loop. C:47
2020-01-10T02:13:18.879Z,1578622398.879 [Micromodem](INFO): Inner loop. C:145
2020-01-10T02:13:18.879Z,1578622398.879 [Micromodem](INFO): Inner loop. C:208
2020-01-10T02:13:18.880Z,1578622398.880 [Micromodem](INFO): Inner loop. C:189
2020-01-10T02:13:18.880Z,1578622398.880 [Micromodem](INFO): Inner loop. C:49
2020-01-10T02:13:18.880Z,1578622398.880 [Micromodem](INFO): Inner loop. C:167
2020-01-10T02:13:18.880Z,1578622398.880 [Micromodem](INFO): Inner loop. C:99
2020-01-10T02:13:18.880Z,1578622398.880 [Micromodem](INFO): Inner loop. C:121
2020-01-10T02:13:18.880Z,1578622398.880 [Micromodem](INFO): Inner loop. C:212
2020-01-10T02:13:18.893Z,1578622398.893 [Micromodem](INFO): Inner loop. C:88
2020-01-10T02:13:18.894Z,1578622398.894 [Micromodem](INFO): Inner loop. C:242
2020-01-10T02:13:18.894Z,1578622398.894 [Micromodem](INFO): Inner loop. C:232
2020-01-10T02:13:18.894Z,1578622398.894 [Micromodem](INFO): Inner loop. C:193
2020-01-10T02:13:18.894Z,1578622398.894 [Micromodem](INFO): Inner loop. C:34
2020-01-10T02:13:18.894Z,1578622398.894 [Micromodem](INFO): Inner loop. C:5
2020-01-10T02:13:18.894Z,1578622398.894 [Micromodem](INFO): Inner loop. C:32
2020-01-10T02:13:18.895Z,1578622398.895 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:13:18.895Z,1578622398.895 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:13:18.895Z,1578622398.895 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:13:18.895Z,1578622398.895 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:13:18.895Z,1578622398.895 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:13:18.895Z,1578622398.895 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:13:18.896Z,1578622398.896 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:13:18.896Z,1578622398.896 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:13:18.896Z,1578622398.896 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:13:18.896Z,1578622398.896 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:13:18.896Z,1578622398.896 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:13:18.896Z,1578622398.896 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:13:18.901Z,1578622398.901 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:13:18.901Z,1578622398.901 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:13:18.901Z,1578622398.901 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:13:18.901Z,1578622398.901 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:13:18.902Z,1578622398.902 [Micromodem](CRITICAL): Packet size is not N*16+4: 0x4E
2020-01-10T02:13:18.920Z,1578622398.920 [Micromodem](CRITICAL): Failed to parse uplink message:858A1AB878DB70DBE89704525FDC95B5C5FE9F4147C09B083E4A8CE30F9EA23DB7C2EC1A3F0C84164EEE1310BA042F91D0BD31A76379D458F2E8C122052000000000000000000000000000000000
2020-01-10T02:13:18.944Z,1578622398.944 [Micromodem](INFO): Nmea in: $CACST,6,0,20200110021315.963679,06,1271,31,0095,0150,59,02,02,00,03,1,012,001,0,3,2,0,150,26.4,8.00,00,-5.00,-01,-0.02,24,10000,4000*7E

2020-01-10T02:13:19.063Z,1578622399.063 [CBIT](INFO): Critical error at 20200110T021318
2020-01-10T02:13:25.239Z,1578622405.239 [NAL9602](INFO): NAL9602 initialized
2020-01-10T02:13:27.759Z,1578622407.759 [SBIT](IMPORTANT): Beginning Startup BIT
2020-01-10T02:13:27.767Z,1578622407.767 [CBIT](IMPORTANT): Beginning ground fault scan
2020-01-10T02:13:38.473Z,1578622418.473 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.012211
CHAN A1 (24V): -0.029291
CHAN A2 (12V): -0.007124
CHAN A3 (5V): -0.002224
CHAN B0 (3.3V): -0.000177
CHAN B1 (3.15aV): 0.000054
CHAN B2 (3.15bV): 0.000352
CHAN B3 (GND): 0.002865
OPEN: 0.005244
Full Scale Calc: 4.765 mA, -1.589 mA
2020-01-10T02:13:46.998Z,1578622426.998 [NAL9602](INFO): SBD MO Status=2, MOMSN=10155, MT Status=2, MTMSN=0
2020-01-10T02:13:46.998Z,1578622426.998 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-01-10T02:14:21.571Z,1578622461.571 [SBIT](IMPORTANT): SBIT PASSED
2020-01-10T02:14:21.657Z,1578622461.657 [CommandLine](IMPORTANT): got command configSet list
2020-01-10T02:14:21.657Z,1578622461.657 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-01-10T02:14:21.658Z,1578622461.658 [CommandLine](IMPORTANT): Courier linearApproximation depth 5.000000 meter;

2020-01-10T02:14:21.658Z,1578622461.658 [CommandLine](IMPORTANT): Courier linearApproximation latitude 0.000500 angular_degree;

2020-01-10T02:14:21.659Z,1578622461.659 [CommandLine](IMPORTANT): Courier linearApproximation longitude 0.000500 angular_degree;

2020-01-10T02:14:21.659Z,1578622461.659 [CommandLine](IMPORTANT): DDM.loadAtStartup=1 bool;

2020-01-10T02:14:21.659Z,1578622461.659 [CommandLine](IMPORTANT): DDM.verbosity=3 count;

2020-01-10T02:14:21.659Z,1578622461.659 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold=75 count;

2020-01-10T02:14:21.659Z,1578622461.659 [CommandLine](IMPORTANT): DUSBL_Hydroid.transmitLockout=40 millisecond;

2020-01-10T02:14:21.659Z,1578622461.659 [CommandLine](IMPORTANT): Express linearApproximation DDM.DDMMode 1.000000 count;

2020-01-10T02:14:21.659Z,1578622461.659 [CommandLine](IMPORTANT): Express all DDM.cablePresent;

2020-01-10T02:14:21.659Z,1578622461.659 [CommandLine](IMPORTANT): Express linearApproximation DDM.latchState 1.000000 count;

2020-01-10T02:14:21.659Z,1578622461.659 [CommandLine](IMPORTANT): Express linearApproximation DDM.whiskerState 1.000000 count;

2020-01-10T02:14:21.659Z,1578622461.659 [CommandLine](IMPORTANT): Express linearApproximation DUSBL_Hydroid.acoustic_contact_range 1.000000 meter;

2020-01-10T02:14:21.660Z,1578622461.660 [CommandLine](IMPORTANT): Express linearApproximation DUSBL_Hydroid.xAngle 2.000000 degree;

2020-01-10T02:14:21.660Z,1578622461.660 [CommandLine](IMPORTANT): Express linearApproximation RDI_Pathfinder.height_above_sea_floor 2.000000 meter;

2020-01-10T02:14:21.660Z,1578622461.660 [CommandLine](IMPORTANT): Express linearApproximation platform_orientation 5.000000 degree;

2020-01-10T02:14:21.660Z,1578622461.660 [CommandLine](IMPORTANT): Express linearApproximation platform_x_velocity_wrt_ground 0.100000 meter_per_second;

2020-01-10T02:14:21.660Z,1578622461.660 [CommandLine](IMPORTANT): Express linearApproximation platform_y_velocity_wrt_ground 0.100000 meter_per_second;

2020-01-10T02:14:21.660Z,1578622461.660 [CommandLine](IMPORTANT): HorizontalControl.kiHeading=0.002 reciprocal_second;

2020-01-10T02:14:21.660Z,1578622461.660 [CommandLine](IMPORTANT): HorizontalControl.kpHeading=0.8 none;

2020-01-10T02:14:21.660Z,1578622461.660 [CommandLine](IMPORTANT): HorizontalControl.rudDeadband=0.05 degree;

2020-01-10T02:14:21.660Z,1578622461.660 [CommandLine](IMPORTANT): Micromodem.destinationAddress=12 enum;

2020-01-10T02:14:21.661Z,1578622461.661 [CommandLine](IMPORTANT): Micromodem.surfaceThreshold=0 meter;

2020-01-10T02:14:21.661Z,1578622461.661 [CommandLine](IMPORTANT): PowerOnly.sampleTime=90 second;

2020-01-10T02:14:21.661Z,1578622461.661 [CommandLine](IMPORTANT): RudderServo.deviationAngle=0.0001 degree;

2020-01-10T02:14:21.661Z,1578622461.661 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=105 cubic_centimeter;

2020-01-10T02:14:21.661Z,1578622461.661 [CommandLine](IMPORTANT): VerticalControl.massDefault=-5 millimeter;

2020-01-10T02:14:21.919Z,1578622461.919 [MissionManager](IMPORTANT): Started mission Startup
2020-01-10T02:14:21.920Z,1578622461.920 [Startup] Running Loop=1
2020-01-10T02:14:21.920Z,1578622461.920 [Startup](DEBUG): Aggregate::initialize Startup
2020-01-10T02:14:21.920Z,1578622461.920 [Startup:A.GoToSurface] Running Loop=1
2020-01-10T02:14:21.920Z,1578622461.920 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-01-10T02:14:21.920Z,1578622461.920 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-01-10T02:14:21.925Z,1578622461.925 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-01-10T02:14:21.925Z,1578622461.925 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-01-10T02:14:21.926Z,1578622461.926 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-01-10T02:14:21.926Z,1578622461.926 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-01-10T02:14:21.927Z,1578622461.927 [Startup:StartupSatComms] Running Loop=1
2020-01-10T02:14:21.928Z,1578622461.928 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-01-10T02:14:21.928Z,1578622461.928 [Startup:StartupSatComms:A] Running Loop=1
2020-01-10T02:14:22.315Z,1578622462.315 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-01-10T02:14:57.019Z,1578622497.019 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-10T02:14:57.020Z,1578622497.020 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-01-10T02:15:17.311Z,1578622517.311 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004806
2020-01-10T02:15:22.100Z,1578622522.100 [Startup:StartupSatComms:A](INFO): Timed out from 2020-01-10T02:14:21.9Z
2020-01-10T02:15:22.100Z,1578622522.100 [Startup:StartupSatComms:A] Stopped
2020-01-10T02:15:22.100Z,1578622522.100 [Startup:StartupSatComms:B] Running Loop=1
2020-01-10T02:15:22.491Z,1578622522.491 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-01-10T02:15:23.391Z,1578622523.391 [Micromodem](INFO): Outgoing frame #1, 47 bytes: 2EDC175E040000005D00000400140000000000000000151D80030C5669C08C34EE8792974A8C2435CFC4D532280000
2020-01-10T02:15:23.391Z,1578622523.391 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,1*6B

2020-01-10T02:15:23.563Z,1578622523.563 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,1*69

2020-01-10T02:15:23.935Z,1578622523.935 [Micromodem](INFO): Nmea in: $CADRQ,021524,1,12,0,64,1*74

2020-01-10T02:15:23.935Z,1578622523.935 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,2EDC175E040000005D00000400140000000000000000151D80030C5669C08C34EE8792974A8C2435CFC4D532280000*3F

2020-01-10T02:15:24.350Z,1578622524.350 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,47*4A

2020-01-10T02:15:24.743Z,1578622524.743 [Micromodem](INFO): Nmea in: $CATXP,47*71

2020-01-10T02:15:25.963Z,1578622525.963 [Micromodem](INFO): Nmea in: $CATXF,47*67

2020-01-10T02:15:26.374Z,1578622526.374 [Micromodem](INFO): Nmea in: $CAXST,6,20200110,021524.999830,3,0,200,4000,10000,1,1,12,1,1,1,3,47*41

2020-01-10T02:15:26.806Z,1578622526.806 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2020-01-10T02:15:27.170Z,1578622527.170 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-10T02:15:27.171Z,1578622527.171 [Micromodem](INFO): Sent 39 bytes from file Logs/20200110T020638/Courier0004.lzma.parts
2020-01-10T02:15:27.171Z,1578622527.171 [Micromodem](INFO): Packets left to send: 0
2020-01-10T02:15:27.586Z,1578622527.586 [Micromodem](INFO): Nmea in: $CACST,6,0,20200110021526.998129,06,606,27,0090,0150,59,02,02,00,04,1,012,001,0,4,1,0,150,11.5,6.00,13,-3.00,-01,-0.03,37,10000,4000*46

2020-01-10T02:15:28.611Z,1578622528.611 [Micromodem](INFO): Outgoing frame #1, 64 bytes: DCDC175E000002005D00000400530200000000000000151D80030C5669BF3148E2FA22188B6A388EEB0BC45C9E58EC1F659FAAC29EC6B6829A7CC0606A7B8EA6
2020-01-10T02:15:28.612Z,1578622528.612 [Micromodem](INFO): Outgoing frame #2, 64 bytes: AA422B2385EC73C0FABC6B0C6D70893EB78F88D46452A4A3C7E9D8907F12032C01CF700820374B1C8E5D82357819DDFA5B46F5DFA93FEDD106818297EE367B80
2020-01-10T02:15:28.612Z,1578622528.612 [Micromodem](INFO): Outgoing frame #3, 64 bytes: 9C59355D49370A27569930FD5C1A57DBBA4ED7758EE3A27BED1DFB3C67AC5B9D1834E8B0FEE630602F469E5042D030E80B18D110898761102E3A7F5678FB01F7
2020-01-10T02:15:28.612Z,1578622528.612 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69

2020-01-10T02:15:29.867Z,1578622529.867 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B

2020-01-10T02:15:30.259Z,1578622530.259 [Micromodem](INFO): Nmea in: $CADRQ,021529,1,12,0,64,1*79

2020-01-10T02:15:30.259Z,1578622530.259 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,DCDC175E000002005D00000400530200000000000000151D80030C5669BF3148E2FA22188B6A388EEB0BC45C9E58EC1F659FAAC29EC6B6829A7CC0606A7B8EA6*3C

2020-01-10T02:15:30.516Z,1578622530.516 [DataOverHttps](INFO): Sending 414 bytes from file Logs/20200110T020932/Courier0000.lzma
2020-01-10T02:15:30.671Z,1578622530.671 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-10T02:15:31.071Z,1578622531.071 [Micromodem](INFO): Nmea in: $CADRQ,021531,1,12,0,64,2*73

2020-01-10T02:15:31.071Z,1578622531.071 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,AA422B2385EC73C0FABC6B0C6D70893EB78F88D46452A4A3C7E9D8907F12032C01CF700820374B1C8E5D82357819DDFA5B46F5DFA93FEDD106818297EE367B80*40

2020-01-10T02:15:31.471Z,1578622531.471 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-10T02:15:31.762Z,1578622531.762 [DataOverHttps](INFO): Moved sent file to Logs/20200110T020932/Courier0000.lzma.bak
2020-01-10T02:15:31.762Z,1578622531.762 [DataOverHttps](INFO): SBD MOMSN=12188808
2020-01-10T02:15:31.875Z,1578622531.875 [Micromodem](INFO): Nmea in: $CADRQ,021532,1,12,0,64,3*71

2020-01-10T02:15:31.875Z,1578622531.875 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,9C59355D49370A27569930FD5C1A57DBBA4ED7758EE3A27BED1DFB3C67AC5B9D1834E8B0FEE630602F469E5042D030E80B18D110898761102E3A7F5678FB01F7*4C

2020-01-10T02:15:32.288Z,1578622532.288 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-10T02:15:32.686Z,1578622532.686 [Micromodem](INFO): Nmea in: $CATXP,192*48

2020-01-10T02:15:36.319Z,1578622536.319 [Micromodem](INFO): Nmea in: $CATXF,192*5E

2020-01-10T02:15:36.732Z,1578622536.732 [Micromodem](INFO): Nmea in: $CAXST,6,20200110,021532.980260,3,0,200,4000,10000,1,1,12,1,3,3,3,192*78

2020-01-10T02:15:37.536Z,1578622537.536 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-10T02:15:37.935Z,1578622537.935 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-10T02:15:38.355Z,1578622538.355 [Micromodem](INFO): Nmea in: $CAACK,12,1,3,1*7B

2020-01-10T02:15:38.355Z,1578622538.355 [Micromodem](INFO): Sent 184 bytes from file Logs/20200110T020932/Courier0000.lzma.parts
2020-01-10T02:15:38.355Z,1578622538.355 [Micromodem](INFO): Packets left to send: 2
2020-01-10T02:15:38.762Z,1578622538.762 [Micromodem](INFO): Nmea in: $CACST,6,0,20200110021537.536190,06,589,27,0098,0150,59,01,01,00,03,1,012,001,0,4,1,0,150,21.0,8.00,19,-5.00,-01,-0.02,28,10000,4000*4C

2020-01-10T02:15:39.285Z,1578622539.285 [Micromodem](INFO): Outgoing frame #1, 47 bytes: DCDC175E040000005D00000400140000000000000000151D80030C5669C08C34EE645DD58264AA53A74B5FF0160000
2020-01-10T02:15:39.286Z,1578622539.286 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,1*6B

2020-01-10T02:15:40.183Z,1578622540.183 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,1*69

2020-01-10T02:15:40.583Z,1578622540.583 [Micromodem](INFO): Nmea in: $CADRQ,021540,1,12,0,64,1*76

2020-01-10T02:15:40.583Z,1578622540.583 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,DCDC175E040000005D00000400140000000000000000151D80030C5669C08C34EE645DD58264AA53A74B5FF0160000*4D

2020-01-10T02:15:40.999Z,1578622540.999 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,47*4A

2020-01-10T02:15:41.391Z,1578622541.391 [Micromodem](INFO): Nmea in: $CATXP,47*71

2020-01-10T02:15:42.619Z,1578622542.619 [Micromodem](INFO): Nmea in: $CATXF,47*67

2020-01-10T02:15:43.012Z,1578622543.012 [Micromodem](INFO): Nmea in: $CAXST,6,20200110,021541.643811,3,0,200,4000,10000,1,1,12,1,1,1,3,47*49

2020-01-10T02:15:43.826Z,1578622543.826 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-10T02:15:43.827Z,1578622543.827 [Micromodem](INFO): Sent 39 bytes from file Logs/20200110T020932/Courier0004.lzma.parts
2020-01-10T02:15:43.827Z,1578622543.827 [Micromodem](INFO): Packets left to send: 0
2020-01-10T02:15:44.230Z,1578622544.230 [Micromodem](INFO): Nmea in: $CACST,6,0,20200110021543.642455,06,511,27,0092,0150,59,02,02,00,04,1,012,001,0,4,1,0,150,26.0,7.00,15,-4.00,-01,-0.04,23,10000,4000*47

2020-01-10T02:15:44.980Z,1578622544.980 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 96DD175E000002005D000004003B0200000000000000151D80030C5669BF3148E2FA22188B6A388EEB0BC45C9E58EC1F659FAAC29EC6B6829A7CC0606A7B8EA6
2020-01-10T02:15:44.980Z,1578622544.980 [Micromodem](INFO): Outgoing frame #2, 64 bytes: AA422B2385EC73C0FABC6B0C6D70893EB78F88D46452A4A3C7E9D8907F12032C01CF700820374B1C8E5D82357819DDFA5B46F5DFA93FEDD106818297EE3669A1
2020-01-10T02:15:44.981Z,1578622544.981 [Micromodem](INFO): Outgoing frame #3, 64 bytes: E5896B82FE6470C28DF4759D15BCF5FE993C2AE5E4DAC9DB55D8C8E462508645050F8A96C29804444911F19DEC419F333C53E79E1ECC9E1A99B32FAB7A2EA05E
2020-01-10T02:15:44.981Z,1578622544.981 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69

2020-01-10T02:15:45.923Z,1578622545.923 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B

2020-01-10T02:15:46.331Z,1578622546.331 [Micromodem](INFO): Nmea in: $CADRQ,021545,1,12,0,64,1*73

2020-01-10T02:15:46.331Z,1578622546.331 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,96DD175E000002005D000004003B0200000000000000151D80030C5669BF3148E2FA22188B6A388EEB0BC45C9E58EC1F659FAAC29EC6B6829A7CC0606A7B8EA6*44

2020-01-10T02:15:46.743Z,1578622546.743 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-10T02:15:47.135Z,1578622547.135 [Micromodem](INFO): Nmea in: $CADRQ,021547,1,12,0,64,2*72

2020-01-10T02:15:47.135Z,1578622547.135 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,AA422B2385EC73C0FABC6B0C6D70893EB78F88D46452A4A3C7E9D8907F12032C01CF700820374B1C8E5D82357819DDFA5B46F5DFA93FEDD106818297EE3669A1*42

2020-01-10T02:15:47.542Z,1578622547.542 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-10T02:15:47.947Z,1578622547.947 [Micromodem](INFO): Nmea in: $CADRQ,021548,1,12,0,64,3*7C

2020-01-10T02:15:47.947Z,1578622547.947 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,E5896B82FE6470C28DF4759D15BCF5FE993C2AE5E4DAC9DB55D8C8E462508645050F8A96C29804444911F19DEC419F333C53E79E1ECC9E1A99B32FAB7A2EA05E*38

2020-01-10T02:15:48.090Z,1578622548.090 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2020-01-10T02:15:48.090Z,1578622548.090 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-10T02:15:48.129Z,1578622548.129 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-10T02:15:48.355Z,1578622548.355 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-10T02:15:48.431Z,1578622548.431 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-10T02:15:48.432Z,1578622548.432 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2020-01-10T02:15:48.755Z,1578622548.755 [Micromodem](INFO): Nmea in: $CATXP,192*48

2020-01-10T02:15:50.457Z,1578622550.457 [DataOverHttps](INFO): Sending 408 bytes from file Logs/20200110T021238/Courier0000.lzma
2020-01-10T02:15:52.387Z,1578622552.387 [Micromodem](INFO): Nmea in: $CATXF,192*5E

2020-01-10T02:15:52.462Z,1578622552.462 [DataOverHttps](INFO): Moved sent file to Logs/20200110T021238/Courier0000.lzma.bak
2020-01-10T02:15:52.462Z,1578622552.462 [DataOverHttps](INFO): SBD MOMSN=12188815
2020-01-10T02:15:52.798Z,1578622552.798 [Micromodem](INFO): Nmea in: $CAXST,6,20200110,021549.047692,3,0,200,4000,10000,1,1,12,1,3,3,3,192*7F

2020-01-10T02:15:53.636Z,1578622553.636 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-10T02:15:54.003Z,1578622554.003 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-10T02:15:54.411Z,1578622554.411 [Micromodem](INFO): Nmea in: $CAACK,12,1,3,1*7B

2020-01-10T02:15:54.411Z,1578622554.411 [Micromodem](INFO): Sent 184 bytes from file Logs/20200110T021238/Courier0000.lzma.parts
2020-01-10T02:15:54.412Z,1578622554.412 [Micromodem](INFO): Packets left to send: 2
2020-01-10T02:15:54.814Z,1578622554.814 [Micromodem](INFO): Nmea in: $CACST,6,0,20200110021553.603347,06,555,27,0103,0150,89,02,02,00,03,1,012,001,0,4,1,0,150,25.5,6.00,16,-3.00,-01,-0.03,28,10000,4000*4B

2020-01-10T02:15:55.642Z,1578622555.642 [Startup:StartupSatComms:B] Stopped
2020-01-10T02:15:55.643Z,1578622555.643 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2020-01-10T02:15:55.643Z,1578622555.643 [Startup:StartupSatComms] Stopped
2020-01-10T02:15:55.643Z,1578622555.643 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-01-10T02:15:55.644Z,1578622555.644 [Startup](INFO): Completed Startup
2020-01-10T02:15:55.644Z,1578622555.644 [MissionManager](INFO): Startup is completed.
2020-01-10T02:15:55.644Z,1578622555.644 [MissionManager](INFO): Uninitializing Mission Startup
2020-01-10T02:15:55.644Z,1578622555.644 [Startup] Stopped
2020-01-10T02:15:55.644Z,1578622555.644 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-01-10T02:15:55.644Z,1578622555.644 [Startup:A.GoToSurface] Stopped
2020-01-10T02:15:55.644Z,1578622555.644 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-01-10T02:15:55.822Z,1578622555.822 [MissionManager](IMPORTANT): Started mission Default
2020-01-10T02:15:55.822Z,1578622555.822 [Default] Running Loop=1
2020-01-10T02:15:55.822Z,1578622555.822 [Default](DEBUG): Aggregate::initialize Default
2020-01-10T02:15:55.822Z,1578622555.822 [Default:B.GoToSurface] Running Loop=1
2020-01-10T02:15:55.822Z,1578622555.822 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-01-10T02:15:55.823Z,1578622555.823 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-01-10T02:15:55.823Z,1578622555.823 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-01-10T02:15:55.823Z,1578622555.823 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-01-10T02:15:55.824Z,1578622555.824 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-01-10T02:15:55.824Z,1578622555.824 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-01-10T02:15:55.824Z,1578622555.824 [Default:A.Wait] Running Loop=1
2020-01-10T02:15:55.824Z,1578622555.824 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-01-10T02:16:05.755Z,1578622565.755 [CommandLine](IMPORTANT): got command restart application
2020-01-10T02:16:06.658Z,1578622566.658 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-10T02:16:06.659Z,1578622566.659 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20010919285729,35.0, -0.1,   0.0,1448.RA,  0.00,   0.00,   0.00,   0.00,   0.00
2020-01-10T02:16:06.761Z,1578622566.761 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-10T02:16:06.761Z,1578622566.761 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:16:06.885Z,1578622566.885 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-01-10T02:16:06.885Z,1578622566.885 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:16:06.885Z,1578622566.885 [CommandLine](INFO): Join timeout helper Thread ID is 9180
2020-01-10T02:16:06.886Z,1578622566.886 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-01-10T02:16:06.886Z,1578622566.886 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:16:06.887Z,1578622566.887 [NavChartDb](INFO): Join timeout helper Thread ID is 9181
2020-01-10T02:16:07.121Z,1578622567.121 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-10T02:16:07.121Z,1578622567.121 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:16:07.125Z,1578622567.125 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2020-01-10T02:16:07.125Z,1578622567.125 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:16:07.125Z,1578622567.125 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 9182
2020-01-10T02:16:07.410Z,1578622567.410 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-10T02:16:07.410Z,1578622567.410 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-01-10T02:16:07.411Z,1578622567.411 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:16:07.429Z,1578622567.429 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2020-01-10T02:16:07.429Z,1578622567.429 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:16:07.429Z,1578622567.429 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 9183
2020-01-10T02:16:07.565Z,1578622567.565 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-10T02:16:07.565Z,1578622567.565 [CTD_NeilBrown](INFO): Powering down
2020-01-10T02:16:07.577Z,1578622567.577 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:16:07.593Z,1578622567.593 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-01-10T02:16:07.593Z,1578622567.593 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:16:07.593Z,1578622567.593 [Radio_Surface](INFO): Join timeout helper Thread ID is 9184
2020-01-10T02:16:07.717Z,1578622567.717 [Radio_Surface](INFO): Powering down
2020-01-10T02:16:07.718Z,1578622567.718 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-10T02:16:07.718Z,1578622567.718 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:16:07.734Z,1578622567.734 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-01-10T02:16:07.734Z,1578622567.734 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:16:07.734Z,1578622567.734 [DataOverHttps](INFO): Join timeout helper Thread ID is 9185
2020-01-10T02:16:08.445Z,1578622568.445 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-10T02:16:08.445Z,1578622568.445 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:16:08.458Z,1578622568.458 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-01-10T02:16:08.458Z,1578622568.458 [logger ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:16:08.458Z,1578622568.458 [logger](INFO): Join timeout helper Thread ID is 9186
2020-01-10T02:16:08.505Z,1578622568.505 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-10T02:16:08.505Z,1578622568.505 [logger ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:16:08.518Z,1578622568.518 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-01-10T02:16:08.518Z,1578622568.518 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:16:08.518Z,1578622568.518 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-01-10T02:16:08.518Z,1578622568.518 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:16:08.518Z,1578622568.518 [controlThread](INFO): Join timeout helper Thread ID is 9187
2020-01-10T02:16:08.601Z,1578622568.601 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-10T02:16:08.601Z,1578622568.601 [controlThread](DEBUG): Uninitializing ControlThread
2020-01-10T02:16:08.601Z,1578622568.601 [AHRS_M2](INFO): Powering down
2020-01-10T02:16:08.673Z,1578622568.673 [DDM](INFO): Powering down
2020-01-10T02:16:08.767Z,1578622568.767 [DUSBL_Hydroid](INFO): Powering down
2020-01-10T02:16:08.881Z,1578622568.881 [Micromodem](INFO): Powering down
2020-01-10T02:16:08.977Z,1578622568.977 [NAL9602](INFO): Powering down
2020-01-10T02:16:09.049Z,1578622569.049 [RDI_Pathfinder](INFO): Powering down
2020-01-10T02:16:09.051Z,1578622569.051 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-01-10T02:16:09.052Z,1578622569.052 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-01-10T02:16:09.052Z,1578622569.052 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-01-10T02:16:09.053Z,1578622569.053 [MissionManager](INFO): Uninitializing Mission Default
2020-01-10T02:16:09.053Z,1578622569.053 [Default] Stopped
2020-01-10T02:16:09.053Z,1578622569.053 [Default](DEBUG): Aggregate::uninitialize Default
2020-01-10T02:16:09.053Z,1578622569.053 [Default:A.Wait] Stopped
2020-01-10T02:16:09.053Z,1578622569.053 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-01-10T02:16:09.053Z,1578622569.053 [Default:B.GoToSurface] Stopped
2020-01-10T02:16:09.053Z,1578622569.053 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-01-10T02:16:09.056Z,1578622569.056 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-01-10T02:16:09.056Z,1578622569.056 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-01-10T02:16:09.056Z,1578622569.056 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-01-10T02:16:09.056Z,1578622569.056 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-01-10T02:16:09.057Z,1578622569.057 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-01-10T02:16:09.057Z,1578622569.057 [BuoyancyServo](INFO): Powering down
2020-01-10T02:16:09.069Z,1578622569.069 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-01-10T02:16:09.069Z,1578622569.069 [ElevatorServo](INFO): Powering down
2020-01-10T02:16:09.070Z,1578622569.070 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-01-10T02:16:09.070Z,1578622569.070 [MassServo](INFO): Powering down
2020-01-10T02:16:09.071Z,1578622569.071 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-01-10T02:16:09.071Z,1578622569.071 [RudderServo](INFO): Powering down
2020-01-10T02:16:09.071Z,1578622569.071 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-01-10T02:16:09.072Z,1578622569.072 [ThrusterServo](INFO): Powering down
2020-01-10T02:16:09.072Z,1578622569.072 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-01-10T02:16:09.073Z,1578622569.073 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-01-10T02:16:09.073Z,1578622569.073 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-01-10T02:16:09.073Z,1578622569.073 [CBIT](DEBUG): Powering off loads.
2020-01-10T02:16:09.085Z,1578622569.085 [CBIT](DEBUG): Disabling WDT.
2020-01-10T02:16:09.097Z,1578622569.097 [CBIT](DEBUG): Opening all GF detection circuits.
2020-01-10T02:16:09.098Z,1578622569.098 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:16:09.141Z,1578622569.141 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:16:09.152Z,1578622569.152 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:16:09.199Z,1578622569.199 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:16:09.202Z,1578622569.202 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:16:09.261Z,1578622569.261 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:16:09.323Z,1578622569.323 [logger ThreadHandler](INFO): Thread cancelled.