2020-11-09T15:39:32.697Z,1604936372.697 [Supervisor](DEBUG): Initializing supervisor.
2020-11-09T15:39:32.700Z,1604936372.700 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-11-09T15:39:32.701Z,1604936372.701 [SyncHandler](INFO): Protected caller Thread ID is 5679
2020-11-09T15:39:32.701Z,1604936372.701 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-11-09T15:39:32.702Z,1604936372.702 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-11-09T15:39:32.702Z,1604936372.702 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 5680
2020-11-09T15:39:32.706Z,1604936372.706 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-11-09T15:39:32.723Z,1604936372.723 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-11-09T15:39:32.724Z,1604936372.724 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-11-09T15:39:32.724Z,1604936372.724 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 5681
2020-11-09T15:39:32.725Z,1604936372.725 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-11-09T15:39:32.726Z,1604936372.726 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-11-09T15:39:32.726Z,1604936372.726 [logger ThreadHandler](INFO): Protected caller Thread ID is 5682
2020-11-09T15:39:32.728Z,1604936372.728 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-11-09T15:39:32.729Z,1604936372.729 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-11-09T15:39:32.730Z,1604936372.730 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-11-09T15:39:33.146Z,1604936373.146 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-11-09T15:39:33.146Z,1604936373.146 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-11-09T15:39:33.241Z,1604936373.241 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-11-09T15:39:33.241Z,1604936373.241 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-11-09T15:39:33.539Z,1604936373.539 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-11-09T15:39:33.540Z,1604936373.540 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-11-09T15:39:33.673Z,1604936373.673 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-11-09T15:39:33.673Z,1604936373.673 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-11-09T15:39:33.854Z,1604936373.854 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-11-09T15:39:33.854Z,1604936373.854 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-11-09T15:39:34.340Z,1604936374.340 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-11-09T15:39:34.341Z,1604936374.341 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-11-09T15:39:34.543Z,1604936374.543 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-11-09T15:39:34.544Z,1604936374.544 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-11-09T15:39:34.682Z,1604936374.682 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-11-09T15:39:34.683Z,1604936374.683 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-11-09T15:39:34.863Z,1604936374.863 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-11-09T15:39:34.864Z,1604936374.864 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-11-09T15:39:34.958Z,1604936374.958 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-11-09T15:39:34.958Z,1604936374.958 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2020-11-09T15:39:35.150Z,1604936375.150 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2020-11-09T15:39:35.150Z,1604936375.150 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-11-09T15:39:35.438Z,1604936375.438 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-11-09T15:39:35.439Z,1604936375.439 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-11-09T15:39:35.517Z,1604936375.517 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-11-09T15:39:35.621Z,1604936375.621 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-11-09T15:39:35.622Z,1604936375.622 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-11-09T15:39:36.250Z,1604936376.250 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-11-09T15:39:36.251Z,1604936376.251 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-11-09T15:39:36.648Z,1604936376.648 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-11-09T15:39:36.650Z,1604936376.650 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2020-11-09T15:39:36.651Z,1604936376.651 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2020-11-09T15:39:36.944Z,1604936376.944 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2020-11-09T15:39:37.215Z,1604936377.215 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2020-11-09T15:39:37.430Z,1604936377.430 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2020-11-09T15:39:37.843Z,1604936377.843 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-11-09T15:39:37.844Z,1604936377.844 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2020-11-09T15:39:37.927Z,1604936377.927 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2020-11-09T15:39:38.019Z,1604936378.019 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2020-11-09T15:39:38.127Z,1604936378.127 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2020-11-09T15:39:38.211Z,1604936378.211 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Dock.cfg
2020-11-09T15:39:38.294Z,1604936378.294 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2020-11-09T15:39:38.399Z,1604936378.399 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/workSite.cfg
2020-11-09T15:39:38.490Z,1604936378.490 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2020-11-09T15:39:38.669Z,1604936378.669 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2020-11-09T15:39:38.802Z,1604936378.802 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/
2020-11-09T15:39:38.802Z,1604936378.802 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-11-09T15:39:38.805Z,1604936378.805 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-11-09T15:39:39.124Z,1604936379.124 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-11-09T15:39:39.127Z,1604936379.127 [AHRS_M2](INFO): created writer for : platform_orientation
2020-11-09T15:39:39.128Z,1604936379.128 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-11-09T15:39:39.131Z,1604936379.131 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-11-09T15:39:39.132Z,1604936379.132 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-11-09T15:39:39.134Z,1604936379.134 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-11-09T15:39:39.135Z,1604936379.135 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-11-09T15:39:39.138Z,1604936379.138 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-11-09T15:39:39.173Z,1604936379.173 [AHRS_M2] Loaded
2020-11-09T15:39:39.174Z,1604936379.174 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-11-09T15:39:39.243Z,1604936379.243 [DataOverHttps] Loaded
2020-11-09T15:39:39.243Z,1604936379.243 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-11-09T15:39:39.244Z,1604936379.244 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4077A4E0
2020-11-09T15:39:39.245Z,1604936379.245 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 5770
2020-11-09T15:39:39.263Z,1604936379.263 [DDM] Loaded
2020-11-09T15:39:39.263Z,1604936379.263 [ComponentRegistry](DEBUG): SyncComponent "DDM" handled in the control thread.
2020-11-09T15:39:39.279Z,1604936379.279 [Depth_Keller] Loaded
2020-11-09T15:39:39.279Z,1604936379.279 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-11-09T15:39:39.282Z,1604936379.282 [DropWeight] Loaded
2020-11-09T15:39:39.282Z,1604936379.282 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2020-11-09T15:39:39.320Z,1604936379.320 [DUSBL_Hydroid] Loaded
2020-11-09T15:39:39.320Z,1604936379.320 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2020-11-09T15:39:39.352Z,1604936379.352 [Micromodem] Loaded
2020-11-09T15:39:39.352Z,1604936379.352 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2020-11-09T15:39:39.404Z,1604936379.404 [NAL9602] Loaded
2020-11-09T15:39:39.404Z,1604936379.404 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-11-09T15:39:39.429Z,1604936379.429 [Onboard] Loaded
2020-11-09T15:39:39.429Z,1604936379.429 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2020-11-09T15:39:39.430Z,1604936379.430 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 407AA4E0
2020-11-09T15:39:39.430Z,1604936379.430 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 5771
2020-11-09T15:39:39.434Z,1604936379.434 [PowerOnly](INFO): Adding load control power supply at /dev/loadC1
2020-11-09T15:39:39.443Z,1604936379.443 [PowerOnly] Loaded
2020-11-09T15:39:39.443Z,1604936379.443 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2020-11-09T15:39:39.454Z,1604936379.454 [Radio_Surface] Loaded
2020-11-09T15:39:39.454Z,1604936379.454 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-11-09T15:39:39.455Z,1604936379.455 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407DA4E0
2020-11-09T15:39:39.456Z,1604936379.456 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 5772
2020-11-09T15:39:39.482Z,1604936379.482 [RDI_Pathfinder] Loaded
2020-11-09T15:39:39.482Z,1604936379.482 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2020-11-09T15:39:39.504Z,1604936379.504 [RDI_PathfinderUp] Loaded
2020-11-09T15:39:39.504Z,1604936379.504 [ComponentRegistry](DEBUG): SyncComponent "RDI_PathfinderUp" handled in the control thread.
2020-11-09T15:39:40.592Z,1604936380.592 [BPC1] Loaded
2020-11-09T15:39:40.592Z,1604936380.592 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-11-09T15:39:40.593Z,1604936380.593 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-11-09T15:39:40.593Z,1604936380.593 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-11-09T15:39:40.611Z,1604936380.611 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-11-09T15:39:40.612Z,1604936380.612 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-11-09T15:39:40.688Z,1604936380.688 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-11-09T15:39:40.689Z,1604936380.689 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-11-09T15:39:40.699Z,1604936380.699 [NavChart] Loaded
2020-11-09T15:39:40.699Z,1604936380.699 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-11-09T15:39:40.703Z,1604936380.703 [UniversalFixResidualReporter] Loaded
2020-11-09T15:39:40.703Z,1604936380.703 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-11-09T15:39:40.711Z,1604936380.711 [WorkSite] Loaded
2020-11-09T15:39:40.711Z,1604936380.711 [ComponentRegistry](DEBUG): SyncComponent "WorkSite" handled in the control thread.
2020-11-09T15:39:40.712Z,1604936380.712 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-11-09T15:39:40.712Z,1604936380.712 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2020-11-09T15:39:40.788Z,1604936380.788 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2020-11-09T15:39:40.789Z,1604936380.789 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-11-09T15:39:40.897Z,1604936380.897 [BuoyancyServo] Loaded
2020-11-09T15:39:40.897Z,1604936380.897 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-11-09T15:39:40.915Z,1604936380.915 [ElevatorServo] Loaded
2020-11-09T15:39:40.915Z,1604936380.915 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-11-09T15:39:40.932Z,1604936380.932 [MassServo] Loaded
2020-11-09T15:39:40.932Z,1604936380.932 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-11-09T15:39:40.949Z,1604936380.949 [RudderServo] Loaded
2020-11-09T15:39:40.949Z,1604936380.949 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-11-09T15:39:40.965Z,1604936380.965 [ThrusterServo] Loaded
2020-11-09T15:39:40.965Z,1604936380.965 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-11-09T15:39:40.966Z,1604936380.966 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-11-09T15:39:40.966Z,1604936380.966 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-11-09T15:39:41.155Z,1604936381.155 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2020-11-09T15:39:41.158Z,1604936381.158 [CTD_Seabird](INFO): created writer for : sea_water_density
2020-11-09T15:39:41.158Z,1604936381.158 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2020-11-09T15:39:41.161Z,1604936381.161 [CTD_Seabird](INFO): created writer for : depth
2020-11-09T15:39:41.161Z,1604936381.161 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2020-11-09T15:39:41.164Z,1604936381.164 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2020-11-09T15:39:41.164Z,1604936381.164 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2020-11-09T15:39:41.168Z,1604936381.168 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2020-11-09T15:39:41.168Z,1604936381.168 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2020-11-09T15:39:41.171Z,1604936381.171 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2020-11-09T15:39:41.172Z,1604936381.172 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2020-11-09T15:39:41.175Z,1604936381.175 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2020-11-09T15:39:41.175Z,1604936381.175 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2020-11-09T15:39:41.178Z,1604936381.178 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2020-11-09T15:39:41.178Z,1604936381.178 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): mass_concentration_of_oxygen_in_sea_water
2020-11-09T15:39:41.181Z,1604936381.181 [CTD_Seabird](INFO): created writer for : mass_concentration_of_oxygen_in_sea_water
2020-11-09T15:39:41.182Z,1604936381.182 [CTD_Seabird](DEBUG): LcmSlateWriter::add(DAtaURI): CTD_Seabird.sea_water_dissolved_oxygen_freq
2020-11-09T15:39:41.182Z,1604936381.182 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): dataName: sea_water_dissolved_oxygen_freq
2020-11-09T15:39:41.198Z,1604936381.198 [CTD_Seabird] Loaded
2020-11-09T15:39:41.198Z,1604936381.198 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2020-11-09T15:39:41.199Z,1604936381.199 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409614E0
2020-11-09T15:39:41.200Z,1604936381.200 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 5773
2020-11-09T15:39:41.216Z,1604936381.216 [PAR_Licor] Loaded
2020-11-09T15:39:41.217Z,1604936381.217 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2020-11-09T15:39:41.245Z,1604936381.245 [WetLabsSeaOWL_UV_A] Loaded
2020-11-09T15:39:41.245Z,1604936381.245 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2020-11-09T15:39:41.246Z,1604936381.246 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 409914E0
2020-11-09T15:39:41.246Z,1604936381.246 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 5774
2020-11-09T15:39:41.247Z,1604936381.247 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-11-09T15:39:41.247Z,1604936381.247 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-11-09T15:39:41.525Z,1604936381.525 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-11-09T15:39:41.526Z,1604936381.526 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-11-09T15:39:41.563Z,1604936381.563 [DepthRateCalculator] Loaded
2020-11-09T15:39:41.563Z,1604936381.563 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-11-09T15:39:41.566Z,1604936381.566 [PitchRateCalculator] Loaded
2020-11-09T15:39:41.566Z,1604936381.566 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-11-09T15:39:41.573Z,1604936381.573 [SpeedCalculator] Loaded
2020-11-09T15:39:41.574Z,1604936381.574 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-11-09T15:39:41.584Z,1604936381.584 [TempGradientCalculator] Loaded
2020-11-09T15:39:41.585Z,1604936381.585 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-11-09T15:39:41.587Z,1604936381.587 [YawRateCalculator] Loaded
2020-11-09T15:39:41.588Z,1604936381.588 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-11-09T15:39:41.605Z,1604936381.605 [ElevatorOffsetCalculator] Loaded
2020-11-09T15:39:41.606Z,1604936381.606 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-11-09T15:39:41.606Z,1604936381.606 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-11-09T15:39:41.607Z,1604936381.607 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-11-09T15:39:41.735Z,1604936381.735 [SBIT](DEBUG): Construct Startup Built In Test.
2020-11-09T15:39:41.747Z,1604936381.747 [SBIT] Loaded
2020-11-09T15:39:41.747Z,1604936381.747 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-11-09T15:39:41.748Z,1604936381.748 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-11-09T15:39:41.760Z,1604936381.760 [IBIT] Loaded
2020-11-09T15:39:41.760Z,1604936381.760 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-11-09T15:39:41.763Z,1604936381.763 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-11-09T15:39:41.857Z,1604936381.857 [CBIT] Loaded
2020-11-09T15:39:41.857Z,1604936381.857 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-11-09T15:39:41.857Z,1604936381.857 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-11-09T15:39:41.858Z,1604936381.858 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-11-09T15:39:41.941Z,1604936381.941 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-11-09T15:39:41.941Z,1604936381.941 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-11-09T15:39:42.235Z,1604936382.235 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-11-09T15:39:42.236Z,1604936382.236 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-11-09T15:39:42.402Z,1604936382.402 [VerticalControl](DEBUG): Construct VerticalControl.
2020-11-09T15:39:42.451Z,1604936382.451 [VerticalControl] Loaded
2020-11-09T15:39:42.451Z,1604936382.451 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-11-09T15:39:42.452Z,1604936382.452 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-11-09T15:39:42.490Z,1604936382.490 [HorizontalControl] Loaded
2020-11-09T15:39:42.490Z,1604936382.490 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-11-09T15:39:42.491Z,1604936382.491 [SpeedControl](DEBUG): Construct SpeedControl.
2020-11-09T15:39:42.492Z,1604936382.492 [SpeedControl] Loaded
2020-11-09T15:39:42.493Z,1604936382.493 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-11-09T15:39:42.493Z,1604936382.493 [LoopControl](DEBUG): Construct LoopControl.
2020-11-09T15:39:42.494Z,1604936382.494 [LoopControl] Loaded
2020-11-09T15:39:42.494Z,1604936382.494 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-11-09T15:39:42.494Z,1604936382.494 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-11-09T15:39:42.495Z,1604936382.495 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-11-09T15:39:42.521Z,1604936382.521 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-11-09T15:39:42.525Z,1604936382.525 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-11-09T15:39:42.526Z,1604936382.526 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-11-09T15:39:42.534Z,1604936382.534 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-11-09T15:39:42.535Z,1604936382.535 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B674E0
2020-11-09T15:39:42.536Z,1604936382.536 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 5775
2020-11-09T15:39:42.540Z,1604936382.540 [Supervisor](INFO): Main Thread ID is 5521
2020-11-09T15:39:42.540Z,1604936382.540 [Supervisor](DEBUG): Running supervisor.
2020-11-09T15:39:42.541Z,1604936382.541 [CommandLine ThreadHandler](INFO): Handler Thread ID is 5776
2020-11-09T15:39:42.543Z,1604936382.543 [controlThread ThreadHandler](INFO): Handler Thread ID is 5777
2020-11-09T15:39:42.544Z,1604936382.544 [controlThread](DEBUG): Initializing ControlThread
2020-11-09T15:39:42.552Z,1604936382.552 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-11-09T15:39:42.553Z,1604936382.553 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-11-09T15:39:42.553Z,1604936382.553 [WorkSite](DEBUG): Initializing WorkSite component.
2020-11-09T15:39:42.557Z,1604936382.557 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-11-09T15:39:42.557Z,1604936382.557 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-11-09T15:39:42.558Z,1604936382.558 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-11-09T15:39:42.558Z,1604936382.558 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-11-09T15:39:42.559Z,1604936382.559 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-11-09T15:39:42.559Z,1604936382.559 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-11-09T15:39:42.559Z,1604936382.559 [SBIT](INFO): Initialize SBIT Component.
2020-11-09T15:39:42.560Z,1604936382.560 [SBIT](IMPORTANT): git: 2020-11-06
2020-11-09T15:39:42.560Z,1604936382.560 [SBIT](INFO): git hash: 159c5b66861882d5f99dda1b1932a945ab78dfb6
2020-11-09T15:39:42.560Z,1604936382.560 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-11-09T15:39:42.562Z,1604936382.562 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Thu Aug 6 12:04:53 PDT 2020
2020-11-09T15:39:42.563Z,1604936382.563 [SBIT](INFO): Beginning SBIT in 32.000000 seconds.
2020-11-09T15:39:42.563Z,1604936382.563 [IBIT](INFO): Initialize IBIT Component.
2020-11-09T15:39:42.564Z,1604936382.564 [CBIT](DEBUG): Initialize CBIT Component.
2020-11-09T15:39:42.565Z,1604936382.565 [logger ThreadHandler](INFO): Handler Thread ID is 5778
2020-11-09T15:39:42.577Z,1604936382.577 [CBIT](DEBUG): Initialized mux pins.
2020-11-09T15:39:42.577Z,1604936382.577 [CBIT](DEBUG): Initializing the watchdog timer.
2020-11-09T15:39:42.585Z,1604936382.585 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 5779
2020-11-09T15:39:42.586Z,1604936382.586 [DataOverHttps](DEBUG): dashIP=128.128.181.155 starts with a digit so assuming it is a numeric IP
2020-11-09T15:39:42.597Z,1604936382.597 [Onboard ThreadHandler](INFO): Handler Thread ID is 5780
2020-11-09T15:39:42.601Z,1604936382.601 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2020-11-09T15:39:42.602Z,1604936382.602 [CBIT](DEBUG): Initializing heartbeat.
2020-11-09T15:39:42.627Z,1604936382.627 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 5781
2020-11-09T15:39:42.633Z,1604936382.633 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 5782
2020-11-09T15:39:42.634Z,1604936382.634 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-11-09T15:39:42.638Z,1604936382.638 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 5784
2020-11-09T15:39:42.639Z,1604936382.639 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-11-09T15:39:42.650Z,1604936382.650 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 5785
2020-11-09T15:39:42.652Z,1604936382.652 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-11-09T15:39:42.653Z,1604936382.653 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-11-09T15:39:42.653Z,1604936382.653 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-11-09T15:39:42.653Z,1604936382.653 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-11-09T15:39:42.653Z,1604936382.653 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-11-09T15:39:42.653Z,1604936382.653 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-11-09T15:39:42.653Z,1604936382.653 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-11-09T15:39:42.654Z,1604936382.654 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-11-09T15:39:42.654Z,1604936382.654 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-11-09T15:39:42.654Z,1604936382.654 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-11-09T15:39:42.654Z,1604936382.654 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-11-09T15:39:42.654Z,1604936382.654 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-11-09T15:39:42.654Z,1604936382.654 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-11-09T15:39:42.654Z,1604936382.654 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-11-09T15:39:42.655Z,1604936382.655 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-11-09T15:39:42.655Z,1604936382.655 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-11-09T15:39:42.673Z,1604936382.673 [CBIT](DEBUG): Deactivating GF circuits.
2020-11-09T15:39:42.673Z,1604936382.673 [CBIT](DEBUG): Deactivating emergency mode.
2020-11-09T15:39:42.709Z,1604936382.709 [CBIT](DEBUG): Backplane powered.
2020-11-09T15:39:42.709Z,1604936382.709 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-11-09T15:39:42.711Z,1604936382.711 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-11-09T15:39:42.712Z,1604936382.712 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-11-09T15:39:42.712Z,1604936382.712 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-11-09T15:39:42.713Z,1604936382.713 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-11-09T15:39:42.723Z,1604936382.723 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-11-09T15:39:42.749Z,1604936382.749 [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-11-09T15:39:42.750Z,1604936382.750 [MissionManager](INFO): Loading Mission: Missions/underIce/DefaultWithUndock.xml
2020-11-09T15:39:42.897Z,1604936382.897 [MissionManager](INFO): DefineArg DefaultWithUndock.ElapsedSinceDefaultStarted = 0.000000 min
2020-11-09T15:39:42.901Z,1604936382.901 [MissionManager](INFO): DefineArg DefaultWithUndock.DetachTimeout = 30.000000 min
2020-11-09T15:39:42.925Z,1604936382.925 [MissionManager](INFO): DefineArg DefaultWithUndock.TransponderCode = 2.000000 count
2020-11-09T15:39:42.928Z,1604936382.928 [MissionManager](INFO): DefineArg DefaultWithUndock.TrackingUpdatePeriod = 2.500000 s
2020-11-09T15:39:42.932Z,1604936382.932 [MissionManager](INFO): DefineArg DefaultWithUndock.NumberOfPings = 1.000000 count
2020-11-09T15:39:42.951Z,1604936382.951 [MissionManager](INFO): DefineArg DefaultWithUndock.DetachModeDDM = 2.000000 count
2020-11-09T15:39:42.952Z,1604936382.952 [DefaultWithUndock:A.Wait](DEBUG): Construct Wait.
2020-11-09T15:39:43.029Z,1604936383.029 [Radio_Surface](INFO): Powering up
2020-11-09T15:39:43.104Z,1604936383.104 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Construct.
2020-11-09T15:39:43.104Z,1604936383.104 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values.
2020-11-09T15:39:43.149Z,1604936383.149 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-11-09T15:39:43.176Z,1604936383.176 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-11-09T15:39:43.179Z,1604936383.179 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-11-09T15:39:43.209Z,1604936383.209 [DefaultWithUndock:E.Execute](DEBUG): Construct Execute.
2020-11-09T15:39:43.223Z,1604936383.223 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Dock="Tethys/Dock"
         xmlns:Estimation="Tethys/Estimation"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Sensor="Tethys/Sensor"
         xmlns:Units="Tethys/Units"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Dock http://okeanids.mbari.org/tethys/Xml/Dock.xsd
                             Tethys/Estimation http://okeanids.mbari.org/tethys/Xml/Estimation.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Sensor http://okeanids.mbari.org/tethys/Xml/Sensor.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="DefaultWithUndock">

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

    <DefineArg Name="DetachTimeout"><Description>
        Maximum time duration for the vehicle to detach form the dock.
    </Description><Units:minute/><Value>30</Value></DefineArg>

    <DefineArg Name="TransponderCode"><Description>
        Dock transponder address.
    </Description><Dock:Dock.transponderCode/></DefineArg>

    <DefineArg Name="TrackingUpdatePeriod"><Description>
        Time duration between acoustic range queries while the
        mission is detaching from the dock.
    </Description><Units:second/><Value>2.5</Value></DefineArg>

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

    <DefineArg Name="DetachModeDDM"><Description>
        DDM DETACH mode enumeration (whiskers retracted, latch open).
    </Description><Units:count/><Value>2</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>30</Value></Setting>
    </Guidance:Wait>

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

        <Timeout Duration="DetachTimeout">
            <Syslog Severity="Important">Timed out while trying to detach from dock at current depth of <Universal:depth/><Units:meter/>.</Syslog>
        </Timeout>

        <!-- Acoustic tracking directives -->
        <Estimation:TrackAcousticContact>
            <Parallel/>
            <Setting><Estimation:TrackAcousticContact.contactLabelSetting/><Arg Name="TransponderCode"/></Setting>
            <Setting><Estimation:TrackAcousticContact.numberOfSamplesSetting/><Arg Name="NumberOfPings"/></Setting>
            <Setting><Estimation:TrackAcousticContact.updatePeriodSetting/><Arg Name="TrackingUpdatePeriod"/></Setting>
        </Estimation:TrackAcousticContact>

        <!-- Leave the dock -->
        <Dock:Undock>
            <Sequence/>
        </Dock:Undock>

    </Aggregate>


    <Aggregate Id="SurfaceDefault">

        <Sequence/>

        <Aggregate Id="HoldLatchOpen">

            <While>
                <Universal:depth/>
                <Gt><Control:VerticalControl.surfaceThreshold/></Gt>
            </While>

            <!-- Power the DDM -->
            <ReadData>
                <Sensor:DDM.DDMMode/>
            </ReadData>

            <!-- Hold the DDM in DETACH mode (whiskers retracted, latch open) -->
            <Assign><Parallel/><Sensor:DDM.DDMModeCmd/><Arg Name="DetachModeDDM"/></Assign>

        </Aggregate>

        <!-- This mission assumes open water so from here on it's the same old default that's always used -->
        <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>

    </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-11-09T15:39:43.241Z,1604936383.241 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,DDM,Depth_Keller,DropWeight,DUSBL_Hydroid,Micromodem,NAL9602,PowerOnly,RDI_Pathfinder,RDI_PathfinderUp,BPC1,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,WorkSite,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2020-11-09T15:39:43.246Z,1604936383.246 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-11-09T15:39:43.285Z,1604936383.285 [DDM](INFO): Powering up
2020-11-09T15:39:43.285Z,1604936383.285 [DDM](DEBUG): Initializing DDM.
2020-11-09T15:39:43.406Z,1604936383.406 [DUSBL_Hydroid](INFO): Powering up
2020-11-09T15:39:43.406Z,1604936383.406 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2020-11-09T15:39:43.623Z,1604936383.623 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-11-09T15:39:43.629Z,1604936383.629 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-11-09T15:39:43.630Z,1604936383.630 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-11-09T15:39:43.638Z,1604936383.638 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-11-09T15:39:43.638Z,1604936383.638 [MassServo](DEBUG): Initializing EZServoServo.
2020-11-09T15:39:43.645Z,1604936383.645 [MassServo](DEBUG): Initializing MassServo.
2020-11-09T15:39:43.646Z,1604936383.646 [RudderServo](DEBUG): Initializing EZServoServo.
2020-11-09T15:39:43.653Z,1604936383.653 [RudderServo](DEBUG): Initializing RudderServo.
2020-11-09T15:39:43.654Z,1604936383.654 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-11-09T15:39:43.661Z,1604936383.661 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-11-09T15:39:43.663Z,1604936383.663 [CommandLine](FAULT): Scheduling is paused
2020-11-09T15:39:43.663Z,1604936383.663 [CBIT](INFO): Critical error at 20201109T153942
2020-11-09T15:39:43.664Z,1604936383.664 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2020-11-09T15:39:43.738Z,1604936383.738 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2020-11-09T15:39:43.738Z,1604936383.738 [DropWeight] Hardware Fault, FailCount= 1
2020-11-09T15:39:43.738Z,1604936383.738 [DropWeight](ERROR): Hardware Fault
2020-11-09T15:39:43.740Z,1604936383.740 [Micromodem](INFO): Powering up
2020-11-09T15:39:43.740Z,1604936383.740 [Micromodem](DEBUG): Initializing Micromodem.
2020-11-09T15:39:43.820Z,1604936383.820 [CBIT](INFO): Critical error at 20201109T153943
2020-11-09T15:39:43.823Z,1604936383.823 [CBIT](ERROR): Hardware Fault in component: DropWeight
2020-11-09T15:39:43.823Z,1604936383.823 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2020-11-09T15:39:44.097Z,1604936384.097 [CBIT](INFO): Critical error at 20201109T153943
2020-11-09T15:39:48.569Z,1604936388.569 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2020-11-09T15:39:48.967Z,1604936388.967 [Micromodem](INFO): Nmea in: $CATMG,2020-11-09T15:39:48.648662Z,RTC,RTC*53

2020-11-09T15:39:48.968Z,1604936388.968 [Micromodem](ERROR): CFG response from modem unexpected: $CATMG,2020-11-09T15:39:48.648662Z,RTC,RTC*53

2020-11-09T15:39:49.375Z,1604936389.375 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31

2020-11-09T15:39:49.375Z,1604936389.375 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2020-11-09T15:39:49.779Z,1604936389.779 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33

2020-11-09T15:39:49.779Z,1604936389.779 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64

2020-11-09T15:39:50.183Z,1604936390.183 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66

2020-11-09T15:39:50.183Z,1604936390.183 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F

2020-11-09T15:39:50.587Z,1604936390.587 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D

2020-11-09T15:39:50.587Z,1604936390.587 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E

2020-11-09T15:39:50.991Z,1604936390.991 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C

2020-11-09T15:39:50.991Z,1604936390.991 [Micromodem](INFO): Nmea out: $CCCFG,FC0,14500*47

2020-11-09T15:39:51.391Z,1604936391.391 [Micromodem](INFO): Nmea in: $CACFG,FC0,14500*45

2020-11-09T15:39:51.391Z,1604936391.391 [Micromodem](INFO): Nmea out: $CCCFG,BW0,4000*63

2020-11-09T15:39:51.811Z,1604936391.811 [Micromodem](INFO): Nmea in: $CACFG,BW0,4000*61

2020-11-09T15:39:51.811Z,1604936391.811 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A

2020-11-09T15:39:52.175Z,1604936392.175 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38

2020-11-09T15:39:52.176Z,1604936392.176 [Micromodem](INFO): Nmea out: $CCCLK,2020,11,09,15,39,53*45

2020-11-09T15:39:52.575Z,1604936392.575 [Micromodem](INFO): Nmea in: $CACLK,2020,11,9,15,39,53*77

2020-11-09T15:39:53.379Z,1604936393.379 [Micromodem](INFO): Nmea in: $CATMS,0,2020-11-09T15:39:54Z*70

2020-11-09T15:39:53.381Z,1604936393.381 [Micromodem](INFO): Nmea in: $CATMG,2020-11-09T15:39:54.025982Z,USER_CMD,RTC*13

2020-11-09T15:39:59.030Z,1604936399.030 [DDM](INFO): Dynamic Docking Module:t2&
2020-11-09T15:40:01.458Z,1604936401.458 [DUSBL_Hydroid](INFO): DUSBL Version:O
2020-11-09T15:40:07.927Z,1604936407.927 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-11-09T15:40:09.539Z,1604936409.539 [NAL9602](INFO): Powering up NAL9602
2020-11-09T15:40:15.260Z,1604936415.260 [SBIT](IMPORTANT): Beginning Startup BIT
2020-11-09T15:40:15.276Z,1604936415.276 [CBIT](IMPORTANT): Beginning ground fault scan
2020-11-09T15:40:17.695Z,1604936417.695 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-11-09T15:40:20.447Z,1604936420.447 [NAL9602](INFO): NAL9602 initialized
2020-11-09T15:40:26.245Z,1604936426.245 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.004602
CHAN A1 (24V): -0.029244
CHAN A2 (12V): -0.007232
CHAN A3 (5V): -0.002616
CHAN B0 (3.3V): -0.000401
CHAN B1 (3.15aV): 0.000554
CHAN B2 (3.15bV): -0.000229
CHAN B3 (GND): 0.002174
OPEN: 0.005486
Full Scale Calc: 4.765 mA, -1.589 mA
2020-11-09T15:40:27.729Z,1604936427.729 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-11-09T15:40:47.930Z,1604936447.930 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-11-09T15:41:09.346Z,1604936469.346 [SBIT](IMPORTANT): SBIT PASSED
2020-11-09T15:41:09.346Z,1604936469.346 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-11-09T15:41:09.347Z,1604936469.347 [SBIT](IMPORTANT): 

2020-11-09T15:41:09.919Z,1604936469.919 [WorkSite](IMPORTANT): Reading workSite position from Data/workSite.cfg
2020-11-09T15:41:09.939Z,1604936469.939 [WorkSite](IMPORTANT): WorkSite fix at 20201109T153833: (41.552760, -70.709690)
2020-11-09T15:41:09.940Z,1604936469.940 [MissionManager](IMPORTANT): Started mission Startup
2020-11-09T15:41:09.941Z,1604936469.941 [Startup] Running Loop=1
2020-11-09T15:41:09.941Z,1604936469.941 [Startup](DEBUG): Aggregate::initialize Startup
2020-11-09T15:41:09.941Z,1604936469.941 [Startup:A.GoToSurface] Running Loop=1
2020-11-09T15:41:09.941Z,1604936469.941 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-11-09T15:41:09.942Z,1604936469.942 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-11-09T15:41:09.942Z,1604936469.942 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-11-09T15:41:09.942Z,1604936469.942 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-11-09T15:41:09.943Z,1604936469.943 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-11-09T15:41:09.943Z,1604936469.943 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-11-09T15:41:09.944Z,1604936469.944 [Startup:StartupSatComms] Running Loop=1
2020-11-09T15:41:09.944Z,1604936469.944 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-11-09T15:41:09.945Z,1604936469.945 [Startup:StartupSatComms:A] Running Loop=1
2020-11-09T15:41:10.195Z,1604936470.195 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-11-09T15:42:07.213Z,1604936527.213 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005182
2020-11-09T15:42:09.974Z,1604936529.974 [Startup:StartupSatComms:A](INFO): Timed out from 2020-11-09T15:41:09.9Z
2020-11-09T15:42:09.974Z,1604936529.974 [Startup:StartupSatComms:A] Stopped
2020-11-09T15:42:09.974Z,1604936529.974 [Startup:StartupSatComms:B] Running Loop=1
2020-11-09T15:42:10.360Z,1604936530.360 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-11-09T15:42:14.240Z,1604936534.240 [DataOverHttps](INFO): Sending 52 bytes from file Logs/20201109T153155/Courier0004.lzma
2020-11-09T15:42:15.243Z,1604936535.243 [DataOverHttps](INFO): Moved sent file to Logs/20201109T153155/Courier0004.lzma.bak
2020-11-09T15:42:15.243Z,1604936535.243 [DataOverHttps](INFO): SBD MOMSN=54865
2020-11-09T15:42:24.285Z,1604936544.285 [DataOverHttps](INFO): Sending 389 bytes from file Logs/20201109T153932/Courier0000.lzma
2020-11-09T15:42:25.286Z,1604936545.286 [DataOverHttps](INFO): Moved sent file to Logs/20201109T153932/Courier0000.lzma.bak
2020-11-09T15:42:25.287Z,1604936545.287 [DataOverHttps](INFO): SBD MOMSN=54867
2020-11-09T15:42:34.325Z,1604936554.325 [DataOverHttps](INFO): Sending 287 bytes from file Logs/20201109T153155/Express0005.lzma
2020-11-09T15:42:35.327Z,1604936555.327 [DataOverHttps](INFO): Moved sent file to Logs/20201109T153155/Express0005.lzma.bak
2020-11-09T15:42:35.327Z,1604936555.327 [DataOverHttps](INFO): SBD MOMSN=54874
2020-11-09T15:42:44.366Z,1604936564.366 [DataOverHttps](INFO): Sending 738 bytes from file Logs/20201109T153932/Express0001.lzma
2020-11-09T15:42:45.367Z,1604936565.367 [DataOverHttps](INFO): Moved sent file to Logs/20201109T153932/Express0001.lzma.bak
2020-11-09T15:42:45.367Z,1604936565.367 [DataOverHttps](INFO): SBD MOMSN=54880
2020-11-09T15:42:46.744Z,1604936566.744 [Startup:StartupSatComms:B] Stopped
2020-11-09T15:42:46.744Z,1604936566.744 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2020-11-09T15:42:46.744Z,1604936566.744 [Startup:StartupSatComms] Stopped
2020-11-09T15:42:46.744Z,1604936566.744 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-11-09T15:42:46.745Z,1604936566.745 [Startup](INFO): Completed Startup
2020-11-09T15:42:46.745Z,1604936566.745 [MissionManager](INFO): Startup is completed.
2020-11-09T15:42:46.745Z,1604936566.745 [MissionManager](INFO): Uninitializing Mission Startup
2020-11-09T15:42:46.745Z,1604936566.745 [Startup] Stopped
2020-11-09T15:42:46.745Z,1604936566.745 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-11-09T15:42:46.746Z,1604936566.746 [Startup:A.GoToSurface] Stopped
2020-11-09T15:42:46.746Z,1604936566.746 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-11-09T15:42:47.139Z,1604936567.139 [MissionManager](IMPORTANT): Started mission DefaultWithUndock
2020-11-09T15:42:47.139Z,1604936567.139 [DefaultWithUndock] Running Loop=1
2020-11-09T15:42:47.139Z,1604936567.139 [DefaultWithUndock](DEBUG): Aggregate::initialize DefaultWithUndock
2020-11-09T15:42:47.139Z,1604936567.139 [DefaultWithUndock:A.Wait] Running Loop=1
2020-11-09T15:42:47.139Z,1604936567.139 [DefaultWithUndock:A.Wait](DEBUG): Initialize Wait Component.
2020-11-09T15:43:17.440Z,1604936597.440 [DefaultWithUndock:A.Wait](INFO): Done Waiting.
2020-11-09T15:43:17.441Z,1604936597.441 [DefaultWithUndock:A.Wait] Stopped
2020-11-09T15:43:17.441Z,1604936597.441 [DefaultWithUndock:A.Wait](DEBUG): Uninitialize Wait Component.
2020-11-09T15:43:17.441Z,1604936597.441 [DefaultWithUndock:LeaveDock] Running Loop=1
2020-11-09T15:43:17.441Z,1604936597.441 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::initialize DefaultWithUndock:LeaveDock
2020-11-09T15:43:17.441Z,1604936597.441 [DefaultWithUndock:LeaveDock:A.] Running Loop=1
2020-11-09T15:43:17.441Z,1604936597.441 [DefaultWithUndock:LeaveDock:A.](INFO): Initializing TrackAcousticContact.
2020-11-09T15:43:17.442Z,1604936597.442 [DefaultWithUndock:LeaveDock:B.Undock] Running Loop=1
2020-11-09T15:43:17.442Z,1604936597.442 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize.
2020-11-09T15:43:17.442Z,1604936597.442 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values.
2020-11-09T15:43:17.866Z,1604936597.866 [DefaultWithUndock:LeaveDock:A.] Running Loop=1
2020-11-09T15:43:18.220Z,1604936598.220 [PowerOnly](INFO): Powering up loadControl
2020-11-09T15:43:19.988Z,1604936599.988 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2020-11-09T15:43:20.699Z,1604936600.699 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76

2020-11-09T15:43:21.116Z,1604936601.116 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B

2020-11-09T15:43:21.149Z,1604936601.149 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detaching from dock.
2020-11-09T15:43:21.545Z,1604936601.545 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detected possible detachment. Starting timer.
2020-11-09T15:43:21.900Z,1604936601.900 [Micromodem](INFO): Nmea in: $SNTTA,,,,,154321.79*50

2020-11-09T15:43:22.765Z,1604936602.765 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2020-11-09T15:43:24.306Z,1604936604.306 [DUSBL_Hydroid](ERROR): No response from remote modem.
2020-11-09T15:43:25.536Z,1604936605.536 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2020-11-09T15:43:26.333Z,1604936606.333 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76

2020-11-09T15:43:26.745Z,1604936606.745 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B

2020-11-09T15:43:27.579Z,1604936607.579 [Micromodem](INFO): Nmea in: $SNTTA,,,,,154327.42*5E

2020-11-09T15:43:28.389Z,1604936608.389 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2020-11-09T15:43:29.966Z,1604936609.966 [DUSBL_Hydroid](ERROR): No response from remote modem.
2020-11-09T15:43:31.191Z,1604936611.191 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2020-11-09T15:43:31.987Z,1604936611.987 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76

2020-11-09T15:43:32.403Z,1604936612.403 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B

2020-11-09T15:43:33.219Z,1604936613.219 [Micromodem](INFO): Nmea in: $SNTTA,,,,,154333.08*55

2020-11-09T15:43:34.093Z,1604936614.093 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2020-11-09T15:43:35.626Z,1604936615.626 [DUSBL_Hydroid](ERROR): No response from remote modem.
2020-11-09T15:43:36.877Z,1604936616.877 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2020-11-09T15:43:37.642Z,1604936617.642 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76

2020-11-09T15:43:38.054Z,1604936618.054 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B

2020-11-09T15:43:38.875Z,1604936618.875 [Micromodem](INFO): Nmea in: $SNTTA,,,,,154338.73*52

2020-11-09T15:43:39.721Z,1604936619.721 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2020-11-09T15:43:41.274Z,1604936621.274 [DUSBL_Hydroid](ERROR): No response from remote modem.
2020-11-09T15:43:41.737Z,1604936621.737 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached at range: nan m. Transitioning DDM to standby.
2020-11-09T15:43:42.503Z,1604936622.503 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2020-11-09T15:43:43.299Z,1604936623.299 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76

2020-11-09T15:43:43.732Z,1604936623.732 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B

2020-11-09T15:43:44.547Z,1604936624.547 [Micromodem](INFO): Nmea in: $SNTTA,,,,,154344.39*57

2020-11-09T15:43:44.626Z,1604936624.626 [DefaultWithUndock:LeaveDock:B.Undock](INFO): DDM at standby.
2020-11-09T15:43:45.041Z,1604936625.041 [DefaultWithUndock:LeaveDock:B.Undock](IMPORTANT): Undocking sequence complete.
2020-11-09T15:43:45.041Z,1604936625.041 [DefaultWithUndock:LeaveDock:B.Undock] Stopped
2020-11-09T15:43:45.041Z,1604936625.041 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2020-11-09T15:43:45.042Z,1604936625.042 [DefaultWithUndock:LeaveDock](INFO): Completed DefaultWithUndock:LeaveDock
2020-11-09T15:43:45.042Z,1604936625.042 [DefaultWithUndock:LeaveDock] Stopped
2020-11-09T15:43:45.042Z,1604936625.042 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::uninitialize DefaultWithUndock:LeaveDock
2020-11-09T15:43:45.042Z,1604936625.042 [DefaultWithUndock:LeaveDock:A.] Stopped
2020-11-09T15:43:45.042Z,1604936625.042 [DefaultWithUndock:SurfaceDefault] Running Loop=1
2020-11-09T15:43:45.042Z,1604936625.042 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault
2020-11-09T15:43:45.042Z,1604936625.042 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen] Running Loop=1
2020-11-09T15:43:45.042Z,1604936625.042 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:HoldLatchOpen
2020-11-09T15:43:45.042Z,1604936625.042 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen:A] Running Loop=1
2020-11-09T15:43:45.042Z,1604936625.042 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen:B] Running Loop=1
2020-11-09T15:43:45.042Z,1604936625.042 [DefaultWithUndock:SurfaceDefault:B.GoToSurface] Running Loop=1
2020-11-09T15:43:45.042Z,1604936625.042 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-11-09T15:43:45.043Z,1604936625.043 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-11-09T15:43:45.043Z,1604936625.043 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-11-09T15:43:45.043Z,1604936625.043 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-11-09T15:43:45.044Z,1604936625.044 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-11-09T15:43:45.044Z,1604936625.044 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-11-09T15:43:45.360Z,1604936625.360 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=1
2020-11-09T15:43:45.360Z,1604936625.360 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn
2020-11-09T15:43:45.360Z,1604936625.360 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1
2020-11-09T15:43:45.365Z,1604936625.365 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen] Stopped
2020-11-09T15:43:45.365Z,1604936625.365 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:HoldLatchOpen
2020-11-09T15:43:45.365Z,1604936625.365 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen:A] Stopped
2020-11-09T15:43:45.365Z,1604936625.365 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen:B] Stopped
2020-11-09T15:43:45.773Z,1604936625.773 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-11-09T15:44:33.798Z,1604936673.798 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2020-11-09T15:44:49.532Z,1604936689.532 [PowerOnly](INFO): Powering down loadControl
2020-11-09T15:45:24.271Z,1604936724.271 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-11-09T15:46:14.719Z,1604936774.719 [CommandLine](IMPORTANT): got command configSet VerticalControl.buoyancyNeutral 200.000000 milliliter persist
2020-11-09T15:46:21.914Z,1604936781.914 [CommandLine](IMPORTANT): got command restart application
2020-11-09T15:46:22.921Z,1604936782.921 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-11-09T15:46:22.921Z,1604936782.921 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-11-09T15:46:22.950Z,1604936782.950 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-11-09T15:46:22.950Z,1604936782.950 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-11-09T15:46:22.951Z,1604936782.951 [CommandLine](INFO): Join timeout helper Thread ID is 5812
2020-11-09T15:46:22.953Z,1604936782.953 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-11-09T15:46:22.953Z,1604936782.953 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-11-09T15:46:22.955Z,1604936782.955 [NavChartDb](INFO): Join timeout helper Thread ID is 5813
2020-11-09T15:46:23.029Z,1604936783.029 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-11-09T15:46:23.029Z,1604936783.029 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-11-09T15:46:23.033Z,1604936783.033 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2020-11-09T15:46:23.033Z,1604936783.033 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-11-09T15:46:23.033Z,1604936783.033 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 5814
2020-11-09T15:46:23.034Z,1604936783.034 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2020-11-09T15:46:23.034Z,1604936783.034 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-11-09T15:46:23.035Z,1604936783.035 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-11-09T15:46:23.035Z,1604936783.035 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2020-11-09T15:46:23.035Z,1604936783.035 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-11-09T15:46:23.036Z,1604936783.036 [CTD_Seabird](INFO): Join timeout helper Thread ID is 5815
2020-11-09T15:46:23.273Z,1604936783.273 [CTD_Seabird](INFO): Powering down
2020-11-09T15:46:23.285Z,1604936783.285 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2020-11-09T15:46:23.285Z,1604936783.285 [CTD_Seabird](INFO): Powering down
2020-11-09T15:46:23.297Z,1604936783.297 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-11-09T15:46:23.321Z,1604936783.321 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-11-09T15:46:23.321Z,1604936783.321 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-11-09T15:46:23.321Z,1604936783.321 [Radio_Surface](INFO): Join timeout helper Thread ID is 5816
2020-11-09T15:46:23.421Z,1604936783.421 [Radio_Surface](INFO): Powering down
2020-11-09T15:46:23.422Z,1604936783.422 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-11-09T15:46:23.422Z,1604936783.422 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-11-09T15:46:23.430Z,1604936783.430 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2020-11-09T15:46:23.430Z,1604936783.430 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-11-09T15:46:23.430Z,1604936783.430 [Onboard](INFO): Join timeout helper Thread ID is 5817
2020-11-09T15:46:24.245Z,1604936784.245 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2020-11-09T15:46:27.937Z,1604936787.937 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2020-11-09T15:46:27.937Z,1604936787.937 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-11-09T15:46:27.950Z,1604936787.950 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-11-09T15:46:27.950Z,1604936787.950 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-11-09T15:46:27.950Z,1604936787.950 [DataOverHttps](INFO): Join timeout helper Thread ID is 5818
2020-11-09T15:46:28.261Z,1604936788.261 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-11-09T15:46:28.261Z,1604936788.261 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-11-09T15:46:28.282Z,1604936788.282 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-11-09T15:46:28.282Z,1604936788.282 [logger ThreadHandler](INFO): Thread cancelled.
2020-11-09T15:46:28.282Z,1604936788.282 [logger](INFO): Join timeout helper Thread ID is 5819
2020-11-09T15:46:28.325Z,1604936788.325 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-11-09T15:46:28.325Z,1604936788.325 [logger ThreadHandler](INFO): Thread cancelled.
2020-11-09T15:46:28.341Z,1604936788.341 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-11-09T15:46:28.342Z,1604936788.342 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-11-09T15:46:28.342Z,1604936788.342 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-11-09T15:46:28.342Z,1604936788.342 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-11-09T15:46:28.342Z,1604936788.342 [controlThread](INFO): Join timeout helper Thread ID is 5820
2020-11-09T15:46:28.486Z,1604936788.486 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-11-09T15:46:28.488Z,1604936788.488 [controlThread](DEBUG): Uninitializing ControlThread
2020-11-09T15:46:28.490Z,1604936788.490 [AHRS_M2](INFO): Powering down
2020-11-09T15:46:28.561Z,1604936788.561 [DDM](INFO): Powering down
2020-11-09T15:46:28.635Z,1604936788.635 [DUSBL_Hydroid](INFO): Powering down
2020-11-09T15:46:28.709Z,1604936788.709 [Micromodem](INFO): Powering down
2020-11-09T15:46:28.805Z,1604936788.805 [NAL9602](INFO): Powering down
2020-11-09T15:46:28.877Z,1604936788.877 [RDI_Pathfinder](INFO): Powering down
2020-11-09T15:46:28.949Z,1604936788.949 [RDI_PathfinderUp](INFO): Powering down
2020-11-09T15:46:28.951Z,1604936788.951 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-11-09T15:46:28.952Z,1604936788.952 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-11-09T15:46:28.952Z,1604936788.952 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-11-09T15:46:28.953Z,1604936788.953 [MissionManager](INFO): Uninitializing Mission DefaultWithUndock
2020-11-09T15:46:28.953Z,1604936788.953 [DefaultWithUndock] Stopped
2020-11-09T15:46:28.953Z,1604936788.953 [DefaultWithUndock](DEBUG): Aggregate::uninitialize DefaultWithUndock
2020-11-09T15:46:28.953Z,1604936788.953 [DefaultWithUndock:SurfaceDefault] Stopped
2020-11-09T15:46:28.953Z,1604936788.953 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault
2020-11-09T15:46:28.954Z,1604936788.954 [DefaultWithUndock:SurfaceDefault:B.GoToSurface] Stopped
2020-11-09T15:46:28.954Z,1604936788.954 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-11-09T15:46:28.954Z,1604936788.954 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped
2020-11-09T15:46:28.954Z,1604936788.954 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn
2020-11-09T15:46:28.954Z,1604936788.954 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped
2020-11-09T15:46:28.960Z,1604936788.960 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-11-09T15:46:28.960Z,1604936788.960 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-11-09T15:46:28.960Z,1604936788.960 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-11-09T15:46:28.961Z,1604936788.961 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-11-09T15:46:28.961Z,1604936788.961 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-11-09T15:46:28.961Z,1604936788.961 [BuoyancyServo](INFO): Powering down
2020-11-09T15:46:28.973Z,1604936788.973 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-11-09T15:46:28.973Z,1604936788.973 [ElevatorServo](INFO): Powering down
2020-11-09T15:46:28.974Z,1604936788.974 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-11-09T15:46:28.974Z,1604936788.974 [MassServo](INFO): Powering down
2020-11-09T15:46:28.975Z,1604936788.975 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-11-09T15:46:28.975Z,1604936788.975 [RudderServo](INFO): Powering down
2020-11-09T15:46:28.976Z,1604936788.976 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-11-09T15:46:28.976Z,1604936788.976 [ThrusterServo](INFO): Powering down
2020-11-09T15:46:28.976Z,1604936788.976 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-11-09T15:46:28.977Z,1604936788.977 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-11-09T15:46:28.977Z,1604936788.977 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-11-09T15:46:28.977Z,1604936788.977 [CBIT](DEBUG): Powering off loads.
2020-11-09T15:46:28.989Z,1604936788.989 [CBIT](DEBUG): Disabling WDT.
2020-11-09T15:46:29.001Z,1604936789.001 [CBIT](DEBUG): Opening all GF detection circuits.
2020-11-09T15:46:29.002Z,1604936789.002 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-11-09T15:46:29.059Z,1604936789.059 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-11-09T15:46:29.062Z,1604936789.062 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-11-09T15:46:29.072Z,1604936789.072 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-11-09T15:46:29.118Z,1604936789.118 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-11-09T15:46:29.121Z,1604936789.121 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-11-09T15:46:29.178Z,1604936789.178 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-11-09T15:46:29.246Z,1604936789.246 [logger ThreadHandler](INFO): Thread cancelled.