2019-05-24T20:04:03.730Z,1558728243.730 [Supervisor](DEBUG): Initializing supervisor.
2019-05-24T20:04:03.733Z,1558728243.733 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-05-24T20:04:03.733Z,1558728243.733 [SyncHandler](INFO): Protected caller Thread ID is 5785
2019-05-24T20:04:03.734Z,1558728243.734 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-05-24T20:04:03.734Z,1558728243.734 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-05-24T20:04:03.735Z,1558728243.735 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 5786
2019-05-24T20:04:03.737Z,1558728243.737 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-05-24T20:04:03.750Z,1558728243.750 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-05-24T20:04:03.751Z,1558728243.751 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-05-24T20:04:03.751Z,1558728243.751 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 5787
2019-05-24T20:04:03.752Z,1558728243.752 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-05-24T20:04:03.753Z,1558728243.753 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-05-24T20:04:03.753Z,1558728243.753 [logger ThreadHandler](INFO): Protected caller Thread ID is 5788
2019-05-24T20:04:03.755Z,1558728243.755 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-05-24T20:04:03.756Z,1558728243.756 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-05-24T20:04:03.760Z,1558728243.760 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-05-24T20:04:04.031Z,1558728244.031 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-05-24T20:04:04.033Z,1558728244.033 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-05-24T20:04:04.116Z,1558728244.116 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-05-24T20:04:04.562Z,1558728244.562 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-05-24T20:04:04.563Z,1558728244.563 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-05-24T20:04:04.909Z,1558728244.909 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-05-24T20:04:04.912Z,1558728244.912 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-05-24T20:04:05.008Z,1558728245.008 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-05-24T20:04:05.011Z,1558728245.011 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-05-24T20:04:05.317Z,1558728245.317 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-05-24T20:04:05.318Z,1558728245.318 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-05-24T20:04:05.518Z,1558728245.518 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-05-24T20:04:05.519Z,1558728245.519 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-05-24T20:04:06.053Z,1558728246.053 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-05-24T20:04:06.054Z,1558728246.054 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-05-24T20:04:06.163Z,1558728246.163 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-05-24T20:04:06.163Z,1558728246.163 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-05-24T20:04:06.264Z,1558728246.264 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-05-24T20:04:06.265Z,1558728246.265 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-05-24T20:04:06.856Z,1558728246.856 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-05-24T20:04:06.856Z,1558728246.856 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-05-24T20:04:07.253Z,1558728247.253 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-05-24T20:04:07.253Z,1558728247.253 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-05-24T20:04:07.457Z,1558728247.457 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-05-24T20:04:07.459Z,1558728247.459 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-05-24T20:04:07.605Z,1558728247.605 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-05-24T20:04:07.606Z,1558728247.606 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-05-24T20:04:07.757Z,1558728247.757 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-05-24T20:04:07.759Z,1558728247.759 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2019-05-24T20:04:07.763Z,1558728247.763 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2019-05-24T20:04:07.854Z,1558728247.854 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2019-05-24T20:04:07.941Z,1558728247.941 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2019-05-24T20:04:08.044Z,1558728248.044 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2019-05-24T20:04:08.127Z,1558728248.127 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2019-05-24T20:04:08.223Z,1558728248.223 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2019-05-24T20:04:08.350Z,1558728248.350 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2019-05-24T20:04:09.280Z,1558728249.280 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2019-05-24T20:04:09.357Z,1558728249.357 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2019-05-24T20:04:09.531Z,1558728249.531 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2019-05-24T20:04:09.670Z,1558728249.670 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2019-05-24T20:04:09.792Z,1558728249.792 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2019-05-24T20:04:10.023Z,1558728250.023 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-05-24T20:04:10.024Z,1558728250.024 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2019-05-24T20:04:10.025Z,1558728250.025 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-05-24T20:04:10.039Z,1558728250.039 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-05-24T20:04:10.121Z,1558728250.121 [VerticalControl](DEBUG): Construct VerticalControl.
2019-05-24T20:04:10.238Z,1558728250.238 [VerticalControl] Loaded
2019-05-24T20:04:10.238Z,1558728250.238 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-05-24T20:04:10.239Z,1558728250.239 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-05-24T20:04:10.310Z,1558728250.310 [HorizontalControl] Loaded
2019-05-24T20:04:10.311Z,1558728250.311 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-05-24T20:04:10.311Z,1558728250.311 [SpeedControl](DEBUG): Construct SpeedControl.
2019-05-24T20:04:10.317Z,1558728250.317 [SpeedControl] Loaded
2019-05-24T20:04:10.317Z,1558728250.317 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-05-24T20:04:10.318Z,1558728250.318 [LoopControl](DEBUG): Construct LoopControl.
2019-05-24T20:04:10.319Z,1558728250.319 [LoopControl] Loaded
2019-05-24T20:04:10.319Z,1558728250.319 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-05-24T20:04:10.319Z,1558728250.319 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-05-24T20:04:10.320Z,1558728250.320 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-05-24T20:04:10.342Z,1558728250.342 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-05-24T20:04:10.343Z,1558728250.343 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-05-24T20:04:10.463Z,1558728250.463 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-05-24T20:04:10.464Z,1558728250.464 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-05-24T20:04:10.585Z,1558728250.585 [BuoyancyServo] Loaded
2019-05-24T20:04:10.586Z,1558728250.586 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-05-24T20:04:10.597Z,1558728250.597 [ElevatorServo] Loaded
2019-05-24T20:04:10.598Z,1558728250.598 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-05-24T20:04:10.609Z,1558728250.609 [MassServo] Loaded
2019-05-24T20:04:10.609Z,1558728250.609 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-05-24T20:04:10.621Z,1558728250.621 [RudderServo] Loaded
2019-05-24T20:04:10.621Z,1558728250.621 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-05-24T20:04:10.632Z,1558728250.632 [ThrusterServo] Loaded
2019-05-24T20:04:10.632Z,1558728250.632 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-05-24T20:04:10.633Z,1558728250.633 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-05-24T20:04:10.634Z,1558728250.634 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-05-24T20:04:10.754Z,1558728250.754 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-05-24T20:04:10.754Z,1558728250.754 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-05-24T20:04:10.775Z,1558728250.775 [NavChart] Loaded
2019-05-24T20:04:10.775Z,1558728250.775 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-05-24T20:04:10.779Z,1558728250.779 [UniversalFixResidualReporter] Loaded
2019-05-24T20:04:10.780Z,1558728250.780 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-05-24T20:04:10.780Z,1558728250.780 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-05-24T20:04:10.781Z,1558728250.781 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-05-24T20:04:10.885Z,1558728250.885 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-05-24T20:04:10.886Z,1558728250.886 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-05-24T20:04:11.409Z,1558728251.409 [AHRS_M2] Loaded
2019-05-24T20:04:11.409Z,1558728251.409 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2019-05-24T20:04:11.486Z,1558728251.486 [DataOverHttps] Loaded
2019-05-24T20:04:11.486Z,1558728251.486 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-05-24T20:04:11.487Z,1558728251.487 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4087D4E0
2019-05-24T20:04:11.487Z,1558728251.487 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 5871
2019-05-24T20:04:11.501Z,1558728251.501 [Depth_Keller] Loaded
2019-05-24T20:04:11.501Z,1558728251.501 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-05-24T20:04:11.506Z,1558728251.506 [DropWeight] Loaded
2019-05-24T20:04:11.506Z,1558728251.506 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-05-24T20:04:11.637Z,1558728251.637 [DVL_micro] Loaded
2019-05-24T20:04:11.637Z,1558728251.637 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2019-05-24T20:04:11.736Z,1558728251.736 [NAL9602] Loaded
2019-05-24T20:04:11.737Z,1558728251.737 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-05-24T20:04:11.753Z,1558728251.753 [Onboard] Loaded
2019-05-24T20:04:11.753Z,1558728251.753 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-05-24T20:04:11.757Z,1558728251.757 [Radio_Surface] Loaded
2019-05-24T20:04:11.757Z,1558728251.757 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-05-24T20:04:11.758Z,1558728251.758 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408AD4E0
2019-05-24T20:04:11.759Z,1558728251.759 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 5872
2019-05-24T20:04:11.887Z,1558728251.887 [DAT] Loaded
2019-05-24T20:04:11.887Z,1558728251.887 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2019-05-24T20:04:13.411Z,1558728253.411 [BPC1] Loaded
2019-05-24T20:04:13.412Z,1558728253.412 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-05-24T20:04:13.412Z,1558728253.412 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-05-24T20:04:13.413Z,1558728253.413 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-05-24T20:04:13.972Z,1558728253.972 [DepthRateCalculator] Loaded
2019-05-24T20:04:13.973Z,1558728253.973 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-05-24T20:04:13.978Z,1558728253.978 [PitchRateCalculator] Loaded
2019-05-24T20:04:13.978Z,1558728253.978 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-05-24T20:04:14.054Z,1558728254.054 [SpeedCalculator] Loaded
2019-05-24T20:04:14.055Z,1558728254.055 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-05-24T20:04:14.077Z,1558728254.077 [TempGradientCalculator] Loaded
2019-05-24T20:04:14.077Z,1558728254.077 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-05-24T20:04:14.082Z,1558728254.082 [YawRateCalculator] Loaded
2019-05-24T20:04:14.083Z,1558728254.083 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-05-24T20:04:14.113Z,1558728254.113 [ElevatorOffsetCalculator] Loaded
2019-05-24T20:04:14.114Z,1558728254.114 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-05-24T20:04:14.114Z,1558728254.114 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-05-24T20:04:14.115Z,1558728254.115 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-05-24T20:04:14.349Z,1558728254.349 [Aanderaa_O2] Loaded
2019-05-24T20:04:14.349Z,1558728254.349 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-05-24T20:04:14.434Z,1558728254.434 [CTD_NeilBrown] Loaded
2019-05-24T20:04:14.434Z,1558728254.434 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-05-24T20:04:14.436Z,1558728254.436 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 409AF4E0
2019-05-24T20:04:14.436Z,1558728254.436 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 5873
2019-05-24T20:04:14.445Z,1558728254.445 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-05-24T20:04:14.451Z,1558728254.451 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-05-24T20:04:14.452Z,1558728254.452 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-05-24T20:04:14.457Z,1558728254.457 [CTD_Seabird](INFO): created writer for : depth
2019-05-24T20:04:14.458Z,1558728254.458 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-05-24T20:04:14.463Z,1558728254.463 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-05-24T20:04:14.463Z,1558728254.463 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-05-24T20:04:14.468Z,1558728254.468 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-05-24T20:04:14.469Z,1558728254.469 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-05-24T20:04:14.474Z,1558728254.474 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-05-24T20:04:14.475Z,1558728254.475 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-05-24T20:04:14.480Z,1558728254.480 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-05-24T20:04:14.480Z,1558728254.480 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-05-24T20:04:14.485Z,1558728254.485 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-05-24T20:04:14.514Z,1558728254.514 [CTD_Seabird] Loaded
2019-05-24T20:04:14.514Z,1558728254.514 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-05-24T20:04:14.515Z,1558728254.515 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409DF4E0
2019-05-24T20:04:14.515Z,1558728254.515 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 5874
2019-05-24T20:04:14.546Z,1558728254.546 [ESPComponent] Loaded
2019-05-24T20:04:14.547Z,1558728254.547 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2019-05-24T20:04:14.561Z,1558728254.561 [PAR_Licor] Loaded
2019-05-24T20:04:14.562Z,1558728254.562 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-05-24T20:04:14.611Z,1558728254.611 [WetLabsBB2FL] Loaded
2019-05-24T20:04:14.611Z,1558728254.611 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-05-24T20:04:14.612Z,1558728254.612 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A0F4E0
2019-05-24T20:04:14.613Z,1558728254.613 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 5875
2019-05-24T20:04:14.614Z,1558728254.614 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-05-24T20:04:14.614Z,1558728254.614 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-05-24T20:04:14.657Z,1558728254.657 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-05-24T20:04:14.658Z,1558728254.658 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-05-24T20:04:14.990Z,1558728254.990 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-05-24T20:04:14.991Z,1558728254.991 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-05-24T20:04:15.143Z,1558728255.143 [SBIT](DEBUG): Construct Startup Built In Test.
2019-05-24T20:04:15.154Z,1558728255.154 [SBIT] Loaded
2019-05-24T20:04:15.155Z,1558728255.155 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-05-24T20:04:15.155Z,1558728255.155 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-05-24T20:04:15.167Z,1558728255.167 [IBIT] Loaded
2019-05-24T20:04:15.168Z,1558728255.168 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-05-24T20:04:15.171Z,1558728255.171 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-05-24T20:04:15.313Z,1558728255.313 [CBIT] Loaded
2019-05-24T20:04:15.314Z,1558728255.314 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-05-24T20:04:15.314Z,1558728255.314 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-05-24T20:04:15.318Z,1558728255.318 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-05-24T20:04:15.319Z,1558728255.319 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-05-24T20:04:15.326Z,1558728255.326 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-05-24T20:04:15.327Z,1558728255.327 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B0E4E0
2019-05-24T20:04:15.327Z,1558728255.327 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 5876
2019-05-24T20:04:15.332Z,1558728255.332 [Supervisor](INFO): Main Thread ID is 5784
2019-05-24T20:04:15.332Z,1558728255.332 [Supervisor](DEBUG): Running supervisor.
2019-05-24T20:04:15.333Z,1558728255.333 [CommandLine ThreadHandler](INFO): Handler Thread ID is 5877
2019-05-24T20:04:15.335Z,1558728255.335 [controlThread ThreadHandler](INFO): Handler Thread ID is 5878
2019-05-24T20:04:15.336Z,1558728255.336 [controlThread](DEBUG): Initializing ControlThread
2019-05-24T20:04:15.337Z,1558728255.337 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-05-24T20:04:15.338Z,1558728255.338 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-05-24T20:04:15.339Z,1558728255.339 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-05-24T20:04:15.339Z,1558728255.339 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-05-24T20:04:15.341Z,1558728255.341 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-05-24T20:04:15.342Z,1558728255.342 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-05-24T20:04:15.345Z,1558728255.345 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-05-24T20:04:15.346Z,1558728255.346 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-05-24T20:04:15.346Z,1558728255.346 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-05-24T20:04:15.346Z,1558728255.346 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-05-24T20:04:15.347Z,1558728255.347 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-05-24T20:04:15.347Z,1558728255.347 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-05-24T20:04:15.351Z,1558728255.351 [SBIT](INFO): Initialize SBIT Component.
2019-05-24T20:04:15.351Z,1558728255.351 [SBIT](IMPORTANT): git: 2019-05-21
2019-05-24T20:04:15.352Z,1558728255.352 [SBIT](INFO): git hash: d33b11e57afc4926c4e35bc3081773f5ba5b446b
2019-05-24T20:04:15.352Z,1558728255.352 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-05-24T20:04:15.353Z,1558728255.353 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
2019-05-24T20:04:15.354Z,1558728255.354 [SBIT](INFO): Beginning SBIT in 23.000000 seconds.
2019-05-24T20:04:15.355Z,1558728255.355 [IBIT](INFO): Initialize IBIT Component.
2019-05-24T20:04:15.356Z,1558728255.356 [CBIT](DEBUG): Initialize CBIT Component.
2019-05-24T20:04:15.357Z,1558728255.357 [logger ThreadHandler](INFO): Handler Thread ID is 5879
2019-05-24T20:04:15.367Z,1558728255.367 [CBIT](DEBUG): Initialized mux pins.
2019-05-24T20:04:15.367Z,1558728255.367 [CBIT](DEBUG): Initializing the watchdog timer.
2019-05-24T20:04:15.375Z,1558728255.375 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 5880
2019-05-24T20:04:15.376Z,1558728255.376 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-05-24T20:04:15.387Z,1558728255.387 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 5881
2019-05-24T20:04:15.391Z,1558728255.391 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-05-24T20:04:15.391Z,1558728255.391 [CBIT](DEBUG): Initializing heartbeat.
2019-05-24T20:04:15.399Z,1558728255.399 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 5882
2019-05-24T20:04:15.400Z,1558728255.400 [CTD_NeilBrown](INFO): Powering down
2019-05-24T20:04:15.428Z,1558728255.428 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 5883
2019-05-24T20:04:15.428Z,1558728255.428 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-24T20:04:15.435Z,1558728255.435 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 5885
2019-05-24T20:04:15.436Z,1558728255.436 [WetLabsBB2FL](INFO): Powering down
2019-05-24T20:04:15.455Z,1558728255.455 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-24T20:04:15.463Z,1558728255.463 [CBIT](DEBUG): Deactivating GF circuits.
2019-05-24T20:04:15.463Z,1558728255.463 [CBIT](DEBUG): Deactivating emergency mode.
2019-05-24T20:04:15.468Z,1558728255.468 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 5886
2019-05-24T20:04:15.476Z,1558728255.476 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-05-24T20:04:15.477Z,1558728255.477 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-05-24T20:04:15.477Z,1558728255.477 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-05-24T20:04:15.477Z,1558728255.477 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-05-24T20:04:15.477Z,1558728255.477 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-05-24T20:04:15.477Z,1558728255.477 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-05-24T20:04:15.477Z,1558728255.477 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-05-24T20:04:15.478Z,1558728255.478 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-05-24T20:04:15.478Z,1558728255.478 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-05-24T20:04:15.478Z,1558728255.478 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-05-24T20:04:15.478Z,1558728255.478 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-05-24T20:04:15.479Z,1558728255.479 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-05-24T20:04:15.479Z,1558728255.479 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-05-24T20:04:15.479Z,1558728255.479 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-05-24T20:04:15.479Z,1558728255.479 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-05-24T20:04:15.479Z,1558728255.479 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-05-24T20:04:15.499Z,1558728255.499 [CBIT](DEBUG): Backplane powered.
2019-05-24T20:04:15.500Z,1558728255.500 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-05-24T20:04:15.543Z,1558728255.543 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-05-24T20:04:15.554Z,1558728255.554 [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>


2019-05-24T20:04:15.555Z,1558728255.555 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-05-24T20:04:15.628Z,1558728255.628 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-05-24T20:04:15.629Z,1558728255.629 [Default:A.Wait](DEBUG): Construct Wait.
2019-05-24T20:04:15.631Z,1558728255.631 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-05-24T20:04:15.661Z,1558728255.661 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-05-24T20:04:15.684Z,1558728255.684 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-05-24T20:04:15.689Z,1558728255.689 [Default:E.Execute](DEBUG): Construct Execute.
2019-05-24T20:04:15.709Z,1558728255.709 [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>


2019-05-24T20:04:15.715Z,1558728255.715 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,DVL_micro,NAL9602,Onboard,DAT,BPC1,Aanderaa_O2,ESPComponent,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,
2019-05-24T20:04:15.731Z,1558728255.731 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-05-24T20:04:15.789Z,1558728255.789 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar
2019-05-24T20:04:15.791Z,1558728255.791 [Radio_Surface](INFO): Powering up
2019-05-24T20:04:15.805Z,1558728255.805 [DVL_micro](INFO): Initializing
2019-05-24T20:04:15.833Z,1558728255.833 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2019-05-24T20:04:15.833Z,1558728255.833 [DAT](INFO): Powering up
2019-05-24T20:04:15.834Z,1558728255.834 [DAT](DEBUG): Initializing DAT.
2019-05-24T20:04:15.841Z,1558728255.841 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2019-05-24T20:04:15.947Z,1558728255.947 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-05-24T20:04:15.985Z,1558728255.985 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-05-24T20:04:15.995Z,1558728255.995 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-05-24T20:04:15.997Z,1558728255.997 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-05-24T20:04:16.003Z,1558728256.003 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-05-24T20:04:16.005Z,1558728256.005 [MassServo](DEBUG): Initializing EZServoServo.
2019-05-24T20:04:16.012Z,1558728256.012 [MassServo](DEBUG): Initializing MassServo.
2019-05-24T20:04:16.013Z,1558728256.013 [RudderServo](DEBUG): Initializing EZServoServo.
2019-05-24T20:04:16.019Z,1558728256.019 [RudderServo](DEBUG): Initializing RudderServo.
2019-05-24T20:04:16.021Z,1558728256.021 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-05-24T20:04:16.027Z,1558728256.027 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-05-24T20:04:16.919Z,1558728256.919 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-05-24T20:04:16.919Z,1558728256.919 [RudderServo](FAULT): Rudder failed to initialize
2019-05-24T20:04:16.919Z,1558728256.919 [RudderServo] Communications Fault, FailCount= 1
2019-05-24T20:04:16.919Z,1558728256.919 [RudderServo](ERROR): Communications Fault
2019-05-24T20:04:17.044Z,1558728257.044 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-05-24T20:04:17.240Z,1558728257.240 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-05-24T20:04:17.240Z,1558728257.240 [RudderServo](INFO): Powering down
2019-05-24T20:04:17.933Z,1558728257.933 [RudderServo](DEBUG): Initializing EZServoServo.
2019-05-24T20:04:18.053Z,1558728258.053 [RudderServo](DEBUG): Initializing RudderServo.
2019-05-24T20:04:18.059Z,1558728258.059 [CBIT](INFO): Clearing failed state for component RudderServo
2019-05-24T20:04:18.059Z,1558728258.059 [RudderServo] No Fault, FailCount= 1
2019-05-24T20:04:19.319Z,1558728259.319 [Aanderaa_O2](INFO): Powering down
2019-05-24T20:04:23.463Z,1558728263.463 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2019-05-24T20:04:29.688Z,1558728269.688 [DAT](INFO): setting local address to 2
2019-05-24T20:04:30.083Z,1558728270.083 [DAT](INFO): set local address to 2
2019-05-24T20:04:39.013Z,1558728279.013 [SBIT](IMPORTANT): Beginning Startup BIT
2019-05-24T20:04:39.035Z,1558728279.035 [CBIT](IMPORTANT): Beginning ground fault scan
2019-05-24T20:04:41.906Z,1558728281.906 [NAL9602](INFO): Powering up NAL9602
2019-05-24T20:04:50.072Z,1558728290.072 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.130293
CHAN A1 (24V): 0.164325
CHAN A2 (12V): 0.073940
CHAN A3 (5V): 0.019263
CHAN B0 (3.3V): 0.014945
CHAN B1 (3.15aV): 4.767929
CHAN B2 (3.15bV): 0.015569
CHAN B3 (GND): 0.000438
OPEN: 0.017059
Full Scale Calc: 4.765 mA, -1.589 mA
2019-05-24T20:04:52.813Z,1558728292.813 [NAL9602](INFO): NAL9602 initialized
2019-05-24T20:04:53.623Z,1558728293.623 [NAL9602](DEBUG): Fix Requested
2019-05-24T20:05:16.548Z,1558728316.548 [CommandLine](IMPORTANT): got command gfscan
2019-05-24T20:05:16.916Z,1558728316.916 [CBIT](IMPORTANT): Beginning ground fault scan
2019-05-24T20:05:20.905Z,1558728320.905 [DVL_micro](ERROR): only read 45 of 46 data items
2019-05-24T20:05:20.906Z,1558728320.906 [DVL_micro](ERROR): Failed to parse:$#NQ.RES 0X0000 1 1 1 1 9.9 10.1 10.1 9.9 0.8 0.4 12.8 -1.9 60 9 98 -136 1 1 1 1 -16.0 3.1 3.2 1 7.0 14.8 2.7 1 -50 193 8 2 200 2 8 2 -16 255.9 9.9 18.3 0.005 35.0 1489 101
2019-05-24T20:05:21.945Z,1558728321.945 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-05-24T20:05:23.150Z,1558728323.150 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2019-05-24T20:05:28.443Z,1558728328.443 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.125046
CHAN A1 (24V): 0.159240
CHAN A2 (12V): 0.070444
CHAN A3 (5V): 0.019752
CHAN B0 (3.3V): 0.013845
CHAN B1 (3.15aV): 0.015889
CHAN B2 (3.15bV): 0.015051
CHAN B3 (GND): -0.001574
OPEN: 0.016956
Full Scale Calc: 4.765 mA, -1.589 mA
2019-05-24T20:05:32.441Z,1558728332.441 [SBIT](IMPORTANT): SBIT PASSED
2019-05-24T20:05:32.472Z,1558728332.472 [CommandLine](IMPORTANT): got command configSet list
2019-05-24T20:05:32.472Z,1558728332.472 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-05-24T20:05:32.473Z,1558728332.473 [CommandLine](IMPORTANT): BPC1.batteryMissingStickThreshold=0 count;

2019-05-24T20:05:32.473Z,1558728332.473 [CommandLine](IMPORTANT): BPC1.batterySamplingInterval=3 hour;

2019-05-24T20:05:32.473Z,1558728332.473 [CommandLine](IMPORTANT): CBIT.abortDepth=296 meter;

2019-05-24T20:05:32.474Z,1558728332.474 [CommandLine](IMPORTANT): CBIT.gf24Offset=172 microampere;

2019-05-24T20:05:32.474Z,1558728332.474 [CommandLine](IMPORTANT): CBIT.stopDepth=288 meter;

2019-05-24T20:05:32.474Z,1558728332.474 [CommandLine](IMPORTANT): DAT.ignoreElevationAngle=1 bool;

2019-05-24T20:05:32.474Z,1558728332.474 [CommandLine](IMPORTANT): ESPComponent.loadAtStartup=1 bool;

2019-05-24T20:05:32.474Z,1558728332.474 [CommandLine](IMPORTANT): Express linearApproximation DAT.range 5.000000 meter;

2019-05-24T20:05:32.474Z,1558728332.474 [CommandLine](IMPORTANT): Express linearApproximation acoustic_contact_range 10.000000 meter;

2019-05-24T20:05:32.474Z,1558728332.474 [CommandLine](IMPORTANT): NAL9602.fastGPSFix=1 bool;

2019-05-24T20:05:32.474Z,1558728332.474 [CommandLine](IMPORTANT): VerticalControl.massPositionLimitAft=-31 millimeter;

2019-05-24T20:05:32.475Z,1558728332.475 [CommandLine](IMPORTANT): VerticalControl.massPositionLimitFwd=30 millimeter;

2019-05-24T20:05:32.926Z,1558728332.926 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-05-24T20:05:32.934Z,1558728332.934 [MissionManager](IMPORTANT): Started mission Startup
2019-05-24T20:05:32.934Z,1558728332.934 [Startup] Running Loop=1
2019-05-24T20:05:32.934Z,1558728332.934 [Startup](DEBUG): Aggregate::initialize Startup
2019-05-24T20:05:32.934Z,1558728332.934 [Startup:A.GoToSurface] Running Loop=1
2019-05-24T20:05:32.935Z,1558728332.935 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-05-24T20:05:32.935Z,1558728332.935 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-05-24T20:05:32.936Z,1558728332.936 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-05-24T20:05:32.936Z,1558728332.936 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-05-24T20:05:32.936Z,1558728332.936 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-05-24T20:05:32.937Z,1558728332.937 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-05-24T20:05:32.938Z,1558728332.938 [Startup:StartupSatComms] Running Loop=1
2019-05-24T20:05:32.938Z,1558728332.938 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-05-24T20:05:32.939Z,1558728332.939 [Startup:StartupSatComms:A] Running Loop=1
2019-05-24T20:05:33.266Z,1558728333.266 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-05-24T20:05:34.508Z,1558728334.508 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2019-05-24T20:05:43.827Z,1558728343.827 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-05-24T20:05:45.443Z,1558728345.443 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2019-05-24T20:05:55.139Z,1558728355.139 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-05-24T20:05:55.945Z,1558728355.945 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2019-05-24T20:06:06.040Z,1558728366.040 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-05-24T20:06:06.853Z,1558728366.853 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2019-05-24T20:06:17.334Z,1558728377.334 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-05-24T20:06:17.759Z,1558728377.759 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2019-05-24T20:06:28.285Z,1558728388.285 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-05-24T20:06:33.069Z,1558728393.069 [Startup:StartupSatComms:A](INFO): Timed out from 2019-05-24T20:05:32.9Z
2019-05-24T20:06:33.069Z,1558728393.069 [Startup:StartupSatComms:A] Stopped
2019-05-24T20:06:33.069Z,1558728393.069 [Startup:StartupSatComms:B] Running Loop=1
2019-05-24T20:06:33.461Z,1558728393.461 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-05-24T20:06:38.774Z,1558728398.774 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-05-24T20:06:49.658Z,1558728409.658 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-05-24T20:06:54.461Z,1558728414.461 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004983
2019-05-24T20:06:59.562Z,1558728419.562 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190524T164314/Courier0051.lzma
2019-05-24T20:07:00.369Z,1558728420.369 [DataOverHttps](INFO): Moved sent file to Logs/20190524T164314/Courier0051.lzma.bak
2019-05-24T20:07:00.369Z,1558728420.369 [DataOverHttps](INFO): SBD MOMSN=11180867
2019-05-24T20:07:00.589Z,1558728420.589 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-05-24T20:07:11.435Z,1558728431.435 [DataOverHttps](INFO): Sending 468 bytes from file Logs/20190524T164314/Express0049.lzma
2019-05-24T20:07:11.908Z,1558728431.908 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-05-24T20:07:12.240Z,1558728432.240 [DataOverHttps](INFO): Moved sent file to Logs/20190524T164314/Express0049.lzma.bak
2019-05-24T20:07:12.241Z,1558728432.241 [DataOverHttps](INFO): SBD MOMSN=11180869
2019-05-24T20:07:15.467Z,1558728435.467 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-05-24T20:07:15.467Z,1558728435.467 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-24T20:07:15.492Z,1558728435.492 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-24T20:07:15.885Z,1558728435.885 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-24T20:07:15.885Z,1558728435.885 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-05-24T20:07:22.055Z,1558728442.055 [CommandLine](IMPORTANT): got command gfscan
2019-05-24T20:07:22.406Z,1558728442.406 [CBIT](IMPORTANT): Beginning ground fault scan
2019-05-24T20:07:22.825Z,1558728442.825 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-05-24T20:07:23.511Z,1558728443.511 [DataOverHttps](INFO): Sending 87 bytes from file Logs/20190524T164314/Express0052.lzma
2019-05-24T20:07:24.317Z,1558728444.317 [DataOverHttps](INFO): Moved sent file to Logs/20190524T164314/Express0052.lzma.bak
2019-05-24T20:07:24.317Z,1558728444.317 [DataOverHttps](INFO): SBD MOMSN=11180880
2019-05-24T20:07:33.233Z,1558728453.233 [Startup:StartupSatComms:B](INFO): Timed out from 2019-05-24T20:06:33.1Z
2019-05-24T20:07:33.233Z,1558728453.233 [Startup:StartupSatComms:B] Stopped
2019-05-24T20:07:33.233Z,1558728453.233 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-05-24T20:07:33.233Z,1558728453.233 [Startup:StartupSatComms] Stopped
2019-05-24T20:07:33.233Z,1558728453.233 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-05-24T20:07:33.234Z,1558728453.234 [Startup](INFO): Completed Startup
2019-05-24T20:07:33.234Z,1558728453.234 [MissionManager](INFO): Startup is completed.
2019-05-24T20:07:33.234Z,1558728453.234 [MissionManager](INFO): Uninitializing Mission Startup
2019-05-24T20:07:33.234Z,1558728453.234 [Startup] Stopped
2019-05-24T20:07:33.234Z,1558728453.234 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-05-24T20:07:33.239Z,1558728453.239 [Startup:A.GoToSurface] Stopped
2019-05-24T20:07:33.239Z,1558728453.239 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-05-24T20:07:33.268Z,1558728453.268 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.109123
CHAN A1 (24V): 0.155976
CHAN A2 (12V): 0.067737
CHAN A3 (5V): 0.015871
CHAN B0 (3.3V): 0.011461
CHAN B1 (3.15aV): 0.014715
CHAN B2 (3.15bV): 0.016619
CHAN B3 (GND): -0.000666
OPEN: 0.016418
Full Scale Calc: 4.765 mA, -1.589 mA
2019-05-24T20:07:33.804Z,1558728453.804 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-05-24T20:07:33.823Z,1558728453.823 [MissionManager](IMPORTANT): Started mission Default
2019-05-24T20:07:33.824Z,1558728453.824 [Default] Running Loop=1
2019-05-24T20:07:33.824Z,1558728453.824 [Default](DEBUG): Aggregate::initialize Default
2019-05-24T20:07:33.824Z,1558728453.824 [Default:B.GoToSurface] Running Loop=1
2019-05-24T20:07:33.824Z,1558728453.824 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-05-24T20:07:33.825Z,1558728453.825 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-05-24T20:07:33.825Z,1558728453.825 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-05-24T20:07:33.825Z,1558728453.825 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-05-24T20:07:33.826Z,1558728453.826 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-05-24T20:07:33.826Z,1558728453.826 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-05-24T20:07:33.826Z,1558728453.826 [Default:A.Wait] Running Loop=1
2019-05-24T20:07:33.826Z,1558728453.826 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-05-24T20:07:35.881Z,1558728455.881 [DataOverHttps](INFO): Sending 981 bytes from file Logs/20190524T200403/Express0001.lzma
2019-05-24T20:07:36.685Z,1558728456.685 [DataOverHttps](INFO): Moved sent file to Logs/20190524T200403/Express0001.lzma.bak
2019-05-24T20:07:36.685Z,1558728456.685 [DataOverHttps](INFO): SBD MOMSN=11180883
2019-05-24T20:07:44.602Z,1558728464.602 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-05-24T20:07:46.958Z,1558728466.958 [Default:A.Wait](INFO): Done Waiting.
2019-05-24T20:07:46.958Z,1558728466.958 [Default:A.Wait] Stopped
2019-05-24T20:07:46.958Z,1558728466.958 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-05-24T20:07:47.372Z,1558728467.372 [Default:CheckIn] Running Loop=1
2019-05-24T20:07:47.372Z,1558728467.372 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-05-24T20:07:47.373Z,1558728467.373 [Default:CheckIn:Read_GPS] Running Loop=1
2019-05-24T20:07:47.777Z,1558728467.777 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-05-24T20:08:25.784Z,1558728505.784 [CommandLine](IMPORTANT): got command show variable gf24
2019-05-24T20:08:25.805Z,1558728505.805 [CommandLine](IMPORTANT): CBIT.gf24Offset (microampere)
2019-05-24T20:08:30.632Z,1558728510.632 [CommandLine](IMPORTANT): got command get CBIT.gf24Offset
2019-05-24T20:08:30.633Z,1558728510.633 [CommandLine](IMPORTANT): CBIT.gf24Offset 172.000000 uA
2019-05-24T20:08:45.920Z,1558728525.920 [NAL9602](INFO): SBD MO Status=2, MOMSN=12971, MT Status=2, MTMSN=0
2019-05-24T20:08:45.920Z,1558728525.920 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-05-24T20:08:47.565Z,1558728527.565 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2019-05-24T20:08:58.933Z,1558728538.933 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2019-05-24T20:09:03.309Z,1558728543.309 [CommandLine](IMPORTANT): got command ! echo i > /dev/loadB2;! echo i > /dev/loadB3
2019-05-24T20:09:05.101Z,1558728545.101 [CommandLine](IMPORTANT): got command gfscan
2019-05-24T20:09:05.349Z,1558728545.349 [CBIT](IMPORTANT): Beginning ground fault scan
2019-05-24T20:09:09.893Z,1558728549.893 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2019-05-24T20:09:15.408Z,1558728555.408 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-05-24T20:09:15.408Z,1558728555.408 [DVL_micro] Communications Fault, FailCount= 1
2019-05-24T20:09:15.408Z,1558728555.408 [DVL_micro](ERROR): Communications Fault
2019-05-24T20:09:15.489Z,1558728555.489 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-05-24T20:09:15.883Z,1558728555.883 [DVL_micro](INFO): uninitialize:Powering down
2019-05-24T20:09:16.257Z,1558728556.257 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.126553
CHAN A1 (24V): -0.156145
CHAN A2 (12V): 0.066876
CHAN A3 (5V): 0.018466
CHAN B0 (3.3V): 0.013248
CHAN B1 (3.15aV): 0.014056
CHAN B2 (3.15bV): 0.014174
CHAN B3 (GND): 0.000227
OPEN: 0.017188
Full Scale Calc: 4.765 mA, -1.589 mA
2019-05-24T20:09:16.653Z,1558728556.653 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-05-24T20:09:16.653Z,1558728556.653 [DVL_micro] No Fault, FailCount= 1
2019-05-24T20:09:17.026Z,1558728557.026 [DVL_micro](INFO): Initializing
2019-05-24T20:09:20.323Z,1558728560.323 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2019-05-24T20:09:27.531Z,1558728567.531 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-05-24T20:09:27.531Z,1558728567.531 [DVL_micro] Communications Fault, FailCount= 2
2019-05-24T20:09:27.531Z,1558728567.531 [DVL_micro](ERROR): Communications Fault
2019-05-24T20:09:27.533Z,1558728567.533 [NAL9602](INFO): SBD MO Status=0, MOMSN=12971, MT Status=0, MTMSN=0
2019-05-24T20:09:27.533Z,1558728567.533 [NAL9602](INFO): No messages in MT queue
2019-05-24T20:09:27.574Z,1558728567.574 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-05-24T20:09:28.019Z,1558728568.019 [DVL_micro](INFO): uninitialize:Powering down
2019-05-24T20:09:28.351Z,1558728568.351 [NAL9602](DEBUG): Fix Requested
2019-05-24T20:09:28.746Z,1558728568.746 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200459.00,A,3648.16664,N,12147.27150,W,0.272,164.92,240519,,,A*78
2019-05-24T20:09:28.751Z,1558728568.751 [NAL9602](INFO): GPS fix at 20190524T200459: (36.802777, -121.787858)
2019-05-24T20:09:28.813Z,1558728568.813 [Default:CheckIn:Read_GPS] Stopped
2019-05-24T20:09:28.813Z,1558728568.813 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-05-24T20:09:28.822Z,1558728568.822 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-05-24T20:09:28.822Z,1558728568.822 [DVL_micro] No Fault, FailCount= 2
2019-05-24T20:09:29.158Z,1558728569.158 [DVL_micro](INFO): Initializing
2019-05-24T20:09:29.214Z,1558728569.214 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-05-24T20:09:31.375Z,1558728571.375 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2019-05-24T20:09:36.791Z,1558728576.791 [DataOverHttps](INFO): Sending 213 bytes from file Logs/20190524T200403/Courier0004.lzma
2019-05-24T20:09:37.596Z,1558728577.596 [DataOverHttps](INFO): Moved sent file to Logs/20190524T200403/Courier0004.lzma.bak
2019-05-24T20:09:37.596Z,1558728577.596 [DataOverHttps](INFO): SBD MOMSN=11180934
2019-05-24T20:09:39.700Z,1558728579.700 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-05-24T20:09:39.700Z,1558728579.700 [DVL_micro] Communications Fault, FailCount= 3
2019-05-24T20:09:39.700Z,1558728579.700 [DVL_micro](ERROR): Communications Fault
2019-05-24T20:09:39.799Z,1558728579.799 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-05-24T20:09:40.175Z,1558728580.175 [DVL_micro](INFO): uninitialize:Powering down
2019-05-24T20:09:40.989Z,1558728580.989 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-05-24T20:09:40.989Z,1558728580.989 [DVL_micro] No Fault, FailCount= 3
2019-05-24T20:09:41.326Z,1558728581.326 [DVL_micro](INFO): Initializing
2019-05-24T20:09:42.272Z,1558728582.272 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2019-05-24T20:09:51.847Z,1558728591.847 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-05-24T20:09:51.847Z,1558728591.847 [DVL_micro] Communications Fault, FailCount= 4
2019-05-24T20:09:51.847Z,1558728591.847 [DVL_micro](ERROR): Communications Fault
2019-05-24T20:09:52.009Z,1558728592.009 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-05-24T20:09:52.308Z,1558728592.308 [DVL_micro](INFO): uninitialize:Powering down
2019-05-24T20:09:53.201Z,1558728593.201 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2019-05-24T20:09:53.247Z,1558728593.247 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-05-24T20:09:53.247Z,1558728593.247 [DVL_micro] No Fault, FailCount= 4
2019-05-24T20:09:53.458Z,1558728593.458 [DVL_micro](INFO): Initializing
2019-05-24T20:09:54.291Z,1558728594.291 [DataOverHttps](INFO): Sending 579 bytes from file Logs/20190524T200403/Express0005.lzma
2019-05-24T20:09:55.096Z,1558728595.096 [DataOverHttps](INFO): Moved sent file to Logs/20190524T200403/Express0005.lzma.bak
2019-05-24T20:09:55.096Z,1558728595.096 [DataOverHttps](INFO): SBD MOMSN=11180937
2019-05-24T20:09:56.717Z,1558728596.717 [Default:CheckIn:Read_Iridium] Stopped
2019-05-24T20:09:56.717Z,1558728596.717 [Default:CheckIn:C.Wait] Running Loop=1
2019-05-24T20:09:56.717Z,1558728596.717 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-05-24T20:10:01.124Z,1558728601.124 [NAL9602](INFO): Not Powering down - fast GPS
2019-05-24T20:10:03.968Z,1558728603.968 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-05-24T20:10:03.968Z,1558728603.968 [DVL_micro] Communications Fault, FailCount= 5
2019-05-24T20:10:03.968Z,1558728603.968 [DVL_micro](ERROR): Communications Fault
2019-05-24T20:10:04.212Z,1558728604.212 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2019-05-24T20:10:04.380Z,1558728604.380 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-05-24T20:10:04.380Z,1558728604.380 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2019-05-24T20:10:04.527Z,1558728604.527 [DVL_micro](INFO): uninitialize:Powering down
2019-05-24T20:10:04.632Z,1558728604.632 [CommandLine](FAULT): Scheduling is paused
2019-05-24T20:10:04.632Z,1558728604.632 [CBIT](INFO): Critical error at 20190524T201004
2019-05-24T20:10:04.633Z,1558728604.633 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-05-24T20:10:14.145Z,1558728614.145 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-05-24T20:10:15.139Z,1558728615.139 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2019-05-24T20:10:25.580Z,1558728625.580 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-05-24T20:10:25.950Z,1558728625.950 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2019-05-24T20:10:36.474Z,1558728636.474 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-05-24T20:10:36.899Z,1558728636.899 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2019-05-24T20:10:47.793Z,1558728647.793 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2019-05-24T20:10:47.944Z,1558728647.944 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-05-24T20:10:58.696Z,1558728658.696 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2019-05-24T20:10:58.883Z,1558728658.883 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-05-24T20:11:09.281Z,1558728669.281 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2019-05-24T20:11:09.725Z,1558728669.725 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-05-24T20:11:20.154Z,1558728680.154 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2019-05-24T20:11:20.581Z,1558728680.581 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-05-24T20:11:31.082Z,1558728691.082 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2019-05-24T20:11:31.314Z,1558728691.314 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-05-24T20:11:42.214Z,1558728702.214 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2019-05-24T20:11:42.485Z,1558728702.485 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-05-24T20:11:53.311Z,1558728713.311 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-05-24T20:11:53.734Z,1558728713.734 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2019-05-24T20:12:04.256Z,1558728724.256 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-05-24T20:12:05.033Z,1558728725.033 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2019-05-24T20:12:14.777Z,1558728734.777 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-05-24T20:12:15.993Z,1558728735.993 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2019-05-24T20:12:25.641Z,1558728745.641 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-05-24T20:12:27.249Z,1558728747.249 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2019-05-24T20:12:36.950Z,1558728756.950 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-05-24T20:12:38.186Z,1558728758.186 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2019-05-24T20:12:48.237Z,1558728768.237 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-05-24T20:12:49.450Z,1558728769.450 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2019-05-24T20:12:49.911Z,1558728769.911 [CommandLine](IMPORTANT): got command gfscan
2019-05-24T20:12:50.181Z,1558728770.181 [CBIT](IMPORTANT): Beginning ground fault scan
2019-05-24T20:12:59.148Z,1558728779.148 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-05-24T20:13:00.350Z,1558728780.350 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2019-05-24T20:13:01.107Z,1558728781.107 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.122020
CHAN A1 (24V): -0.199479
CHAN A2 (12V): 0.069742
CHAN A3 (5V): 0.018952
CHAN B0 (3.3V): 0.014166
CHAN B1 (3.15aV): 0.015127
CHAN B2 (3.15bV): 0.015256
CHAN B3 (GND): 0.000476
OPEN: 0.016576
Full Scale Calc: 4.765 mA, -1.589 mA
2019-05-24T20:13:10.110Z,1558728790.110 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-05-24T20:13:11.822Z,1558728791.822 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2019-05-24T20:13:20.589Z,1558728800.589 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-05-24T20:13:22.631Z,1558728802.631 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2019-05-24T20:13:31.523Z,1558728811.523 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-05-24T20:13:33.551Z,1558728813.551 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2019-05-24T20:13:36.255Z,1558728816.255 [CommandLine](IMPORTANT): got command quit
2019-05-24T20:13:37.259Z,1558728817.259 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-24T20:13:37.259Z,1558728817.259 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-05-24T20:13:37.347Z,1558728817.347 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-05-24T20:13:37.347Z,1558728817.347 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-05-24T20:13:37.348Z,1558728817.348 [CommandLine](INFO): Join timeout helper Thread ID is 5921
2019-05-24T20:13:37.371Z,1558728817.371 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-05-24T20:13:37.371Z,1558728817.371 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-05-24T20:13:37.371Z,1558728817.371 [NavChartDb](INFO): Join timeout helper Thread ID is 5922
2019-05-24T20:13:37.535Z,1558728817.535 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-24T20:13:37.535Z,1558728817.535 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-05-24T20:13:37.543Z,1558728817.543 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-05-24T20:13:37.543Z,1558728817.543 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-05-24T20:13:37.543Z,1558728817.543 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 5923
2019-05-24T20:13:37.651Z,1558728817.651 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-24T20:13:37.652Z,1558728817.652 [WetLabsBB2FL](INFO): Powering down
2019-05-24T20:13:37.652Z,1558728817.652 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-05-24T20:13:37.655Z,1558728817.655 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-05-24T20:13:37.655Z,1558728817.655 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-05-24T20:13:37.656Z,1558728817.656 [CTD_Seabird](INFO): Join timeout helper Thread ID is 5924
2019-05-24T20:13:37.807Z,1558728817.807 [CTD_Seabird](INFO): Powering down
2019-05-24T20:13:37.820Z,1558728817.820 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-24T20:13:37.820Z,1558728817.820 [CTD_Seabird](INFO): Powering down
2019-05-24T20:13:37.835Z,1558728817.835 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-05-24T20:13:37.836Z,1558728817.836 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-05-24T20:13:37.836Z,1558728817.836 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-05-24T20:13:37.836Z,1558728817.836 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 5925
2019-05-24T20:13:37.863Z,1558728817.863 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-24T20:13:37.863Z,1558728817.863 [CTD_NeilBrown](INFO): Powering down
2019-05-24T20:13:37.875Z,1558728817.875 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-05-24T20:13:37.880Z,1558728817.880 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-05-24T20:13:37.880Z,1558728817.880 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-05-24T20:13:37.880Z,1558728817.880 [Radio_Surface](INFO): Join timeout helper Thread ID is 5926
2019-05-24T20:13:37.971Z,1558728817.971 [Radio_Surface](INFO): Powering down
2019-05-24T20:13:37.972Z,1558728817.972 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-24T20:13:37.972Z,1558728817.972 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-05-24T20:13:37.980Z,1558728817.980 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-05-24T20:13:37.980Z,1558728817.980 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-05-24T20:13:37.980Z,1558728817.980 [DataOverHttps](INFO): Join timeout helper Thread ID is 5927
2019-05-24T20:13:38.367Z,1558728818.367 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-24T20:13:38.367Z,1558728818.367 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-05-24T20:13:38.380Z,1558728818.380 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-05-24T20:13:38.380Z,1558728818.380 [logger ThreadHandler](INFO): Thread cancelled.
2019-05-24T20:13:38.380Z,1558728818.380 [logger](INFO): Join timeout helper Thread ID is 5928
2019-05-24T20:13:38.413Z,1558728818.413 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-24T20:13:38.413Z,1558728818.413 [logger ThreadHandler](INFO): Thread cancelled.
2019-05-24T20:13:38.427Z,1558728818.427 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-05-24T20:13:38.428Z,1558728818.428 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-05-24T20:13:38.428Z,1558728818.428 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-05-24T20:13:38.428Z,1558728818.428 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-05-24T20:13:38.428Z,1558728818.428 [controlThread](INFO): Join timeout helper Thread ID is 5929
2019-05-24T20:13:38.603Z,1558728818.603 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-24T20:13:38.603Z,1558728818.603 [controlThread](DEBUG): Uninitializing ControlThread
2019-05-24T20:13:38.604Z,1558728818.604 [AHRS_M2](INFO): Powering down
2019-05-24T20:13:38.676Z,1558728818.676 [DVL_micro](INFO): uninitialize:Powering down
2019-05-24T20:13:38.677Z,1558728818.677 [NAL9602](INFO): Powering down
2019-05-24T20:13:38.678Z,1558728818.678 [DAT](INFO): Powering down
2019-05-24T20:13:38.800Z,1558728818.800 [Aanderaa_O2](INFO): Powering down
2019-05-24T20:13:38.859Z,1558728818.859 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-05-24T20:13:38.860Z,1558728818.860 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-05-24T20:13:38.860Z,1558728818.860 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-05-24T20:13:38.861Z,1558728818.861 [MissionManager](INFO): Uninitializing Mission Default
2019-05-24T20:13:38.861Z,1558728818.861 [Default] Stopped
2019-05-24T20:13:38.861Z,1558728818.861 [Default](DEBUG): Aggregate::uninitialize Default
2019-05-24T20:13:38.861Z,1558728818.861 [Default:B.GoToSurface] Stopped
2019-05-24T20:13:38.861Z,1558728818.861 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-05-24T20:13:38.861Z,1558728818.861 [Default:CheckIn] Stopped
2019-05-24T20:13:38.861Z,1558728818.861 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-05-24T20:13:38.861Z,1558728818.861 [Default:CheckIn:C.Wait] Stopped
2019-05-24T20:13:38.862Z,1558728818.862 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-05-24T20:13:38.872Z,1558728818.872 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-05-24T20:13:38.873Z,1558728818.873 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-05-24T20:13:38.873Z,1558728818.873 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-05-24T20:13:38.873Z,1558728818.873 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-05-24T20:13:38.873Z,1558728818.873 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-05-24T20:13:38.873Z,1558728818.873 [BuoyancyServo](INFO): Powering down
2019-05-24T20:13:38.887Z,1558728818.887 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-05-24T20:13:38.887Z,1558728818.887 [ElevatorServo](INFO): Powering down
2019-05-24T20:13:38.888Z,1558728818.888 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-05-24T20:13:38.888Z,1558728818.888 [MassServo](INFO): Powering down
2019-05-24T20:13:38.889Z,1558728818.889 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-05-24T20:13:38.889Z,1558728818.889 [RudderServo](INFO): Powering down
2019-05-24T20:13:38.890Z,1558728818.890 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-05-24T20:13:38.890Z,1558728818.890 [ThrusterServo](INFO): Powering down
2019-05-24T20:13:38.892Z,1558728818.892 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-05-24T20:13:38.892Z,1558728818.892 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-05-24T20:13:38.892Z,1558728818.892 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-05-24T20:13:38.892Z,1558728818.892 [CBIT](DEBUG): Powering off loads.
2019-05-24T20:13:38.907Z,1558728818.907 [CBIT](DEBUG): Disabling WDT.
2019-05-24T20:13:38.919Z,1558728818.919 [CBIT](DEBUG): Opening all GF detection circuits.
2019-05-24T20:13:38.920Z,1558728818.920 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-05-24T20:13:39.015Z,1558728819.015 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-05-24T20:13:39.025Z,1558728819.025 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-05-24T20:13:39.057Z,1558728819.057 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-05-24T20:13:39.061Z,1558728819.061 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-05-24T20:13:39.065Z,1558728819.065 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-05-24T20:13:39.102Z,1558728819.102 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-05-24T20:13:39.175Z,1558728819.175 [logger ThreadHandler](INFO): Thread cancelled.