2019-04-09T22:45:04.345Z,1554849904.345 [Supervisor](DEBUG): Initializing supervisor.
2019-04-09T22:45:04.348Z,1554849904.348 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-04-09T22:45:04.348Z,1554849904.348 [SyncHandler](INFO): Protected caller Thread ID is 7106
2019-04-09T22:45:04.349Z,1554849904.349 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-04-09T22:45:04.350Z,1554849904.350 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-04-09T22:45:04.351Z,1554849904.351 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 7107
2019-04-09T22:45:04.354Z,1554849904.354 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-04-09T22:45:04.366Z,1554849904.366 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-04-09T22:45:04.367Z,1554849904.367 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-04-09T22:45:04.367Z,1554849904.367 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 7108
2019-04-09T22:45:04.368Z,1554849904.368 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-04-09T22:45:04.369Z,1554849904.369 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-04-09T22:45:04.370Z,1554849904.370 [logger ThreadHandler](INFO): Protected caller Thread ID is 7109
2019-04-09T22:45:04.372Z,1554849904.372 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-04-09T22:45:04.372Z,1554849904.372 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-04-09T22:45:04.373Z,1554849904.373 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-04-09T22:45:04.471Z,1554849904.471 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-04-09T22:45:04.471Z,1554849904.471 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-04-09T22:45:04.674Z,1554849904.674 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-04-09T22:45:04.675Z,1554849904.675 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-04-09T22:45:04.814Z,1554849904.814 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-04-09T22:45:04.815Z,1554849904.815 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-04-09T22:45:05.278Z,1554849905.278 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-04-09T22:45:05.278Z,1554849905.278 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-04-09T22:45:05.722Z,1554849905.722 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-04-09T22:45:05.723Z,1554849905.723 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-04-09T22:45:06.441Z,1554849906.441 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-04-09T22:45:06.442Z,1554849906.442 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-04-09T22:45:06.751Z,1554849906.751 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-04-09T22:45:06.752Z,1554849906.752 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-04-09T22:45:07.086Z,1554849907.086 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-04-09T22:45:07.086Z,1554849907.086 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-04-09T22:45:07.483Z,1554849907.483 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-04-09T22:45:07.483Z,1554849907.483 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-04-09T22:45:07.635Z,1554849907.635 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-04-09T22:45:07.635Z,1554849907.635 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-04-09T22:45:07.743Z,1554849907.743 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-04-09T22:45:07.743Z,1554849907.743 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-04-09T22:45:07.825Z,1554849907.825 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-04-09T22:45:07.929Z,1554849907.929 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-04-09T22:45:07.930Z,1554849907.930 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-04-09T22:45:08.126Z,1554849908.126 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-04-09T22:45:08.127Z,1554849908.127 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-04-09T22:45:08.337Z,1554849908.337 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-04-09T22:45:08.339Z,1554849908.339 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2019-04-09T22:45:08.339Z,1554849908.339 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2019-04-09T22:45:08.424Z,1554849908.424 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2019-04-09T22:45:08.659Z,1554849908.659 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-04-09T22:45:08.659Z,1554849908.659 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2019-04-09T22:45:08.757Z,1554849908.757 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2019-04-09T22:45:08.932Z,1554849908.932 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2019-04-09T22:45:09.147Z,1554849909.147 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2019-04-09T22:45:09.235Z,1554849909.235 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2019-04-09T22:45:09.335Z,1554849909.335 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2019-04-09T22:45:09.429Z,1554849909.429 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2019-04-09T22:45:09.584Z,1554849909.584 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2019-04-09T22:45:09.690Z,1554849909.690 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2019-04-09T22:45:09.791Z,1554849909.791 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2019-04-09T22:45:09.791Z,1554849909.791 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-04-09T22:45:09.795Z,1554849909.795 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-04-09T22:45:09.892Z,1554849909.892 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-04-09T22:45:09.893Z,1554849909.893 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-04-09T22:45:10.017Z,1554849910.017 [BuoyancyServo] Loaded
2019-04-09T22:45:10.017Z,1554849910.017 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-04-09T22:45:10.033Z,1554849910.033 [ElevatorServo] Loaded
2019-04-09T22:45:10.033Z,1554849910.033 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-04-09T22:45:10.048Z,1554849910.048 [MassServo] Loaded
2019-04-09T22:45:10.048Z,1554849910.048 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-04-09T22:45:10.063Z,1554849910.063 [RudderServo] Loaded
2019-04-09T22:45:10.064Z,1554849910.064 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-04-09T22:45:10.078Z,1554849910.078 [ThrusterServo] Loaded
2019-04-09T22:45:10.078Z,1554849910.078 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-04-09T22:45:10.079Z,1554849910.079 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-04-09T22:45:10.079Z,1554849910.079 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-04-09T22:45:10.214Z,1554849910.214 [SBIT](DEBUG): Construct Startup Built In Test.
2019-04-09T22:45:10.243Z,1554849910.243 [SBIT] Loaded
2019-04-09T22:45:10.243Z,1554849910.243 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-04-09T22:45:10.244Z,1554849910.244 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-04-09T22:45:10.272Z,1554849910.272 [IBIT] Loaded
2019-04-09T22:45:10.272Z,1554849910.272 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-04-09T22:45:10.275Z,1554849910.275 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-04-09T22:45:10.664Z,1554849910.664 [CBIT] Loaded
2019-04-09T22:45:10.664Z,1554849910.664 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-04-09T22:45:10.664Z,1554849910.664 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-04-09T22:45:10.665Z,1554849910.665 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-04-09T22:45:10.846Z,1554849910.846 [Aanderaa_O2] Loaded
2019-04-09T22:45:10.846Z,1554849910.846 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-04-09T22:45:10.856Z,1554849910.856 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-04-09T22:45:10.861Z,1554849910.861 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-04-09T22:45:10.863Z,1554849910.863 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-04-09T22:45:10.868Z,1554849910.868 [CTD_Seabird](INFO): created writer for : depth
2019-04-09T22:45:10.869Z,1554849910.869 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-04-09T22:45:10.874Z,1554849910.874 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-04-09T22:45:10.875Z,1554849910.875 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-04-09T22:45:10.880Z,1554849910.880 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-04-09T22:45:10.880Z,1554849910.880 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-04-09T22:45:10.886Z,1554849910.886 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-04-09T22:45:10.887Z,1554849910.887 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-04-09T22:45:10.892Z,1554849910.892 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-04-09T22:45:10.892Z,1554849910.892 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-04-09T22:45:10.898Z,1554849910.898 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-04-09T22:45:10.924Z,1554849910.924 [CTD_Seabird] Loaded
2019-04-09T22:45:10.925Z,1554849910.925 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-04-09T22:45:10.926Z,1554849910.926 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406654E0
2019-04-09T22:45:10.926Z,1554849910.926 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 7188
2019-04-09T22:45:10.952Z,1554849910.952 [ESPComponent] Loaded
2019-04-09T22:45:10.952Z,1554849910.952 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2019-04-09T22:45:10.966Z,1554849910.966 [PAR_Licor] Loaded
2019-04-09T22:45:10.966Z,1554849910.966 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-04-09T22:45:11.014Z,1554849911.014 [WetLabsBB2FL] Loaded
2019-04-09T22:45:11.015Z,1554849911.015 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-04-09T22:45:11.016Z,1554849911.016 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406954E0
2019-04-09T22:45:11.016Z,1554849911.016 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 7189
2019-04-09T22:45:11.017Z,1554849911.017 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-04-09T22:45:11.017Z,1554849911.017 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-04-09T22:45:11.057Z,1554849911.057 [DepthRateCalculator] Loaded
2019-04-09T22:45:11.057Z,1554849911.057 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-04-09T22:45:11.063Z,1554849911.063 [PitchRateCalculator] Loaded
2019-04-09T22:45:11.063Z,1554849911.063 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-04-09T22:45:11.079Z,1554849911.079 [SpeedCalculator] Loaded
2019-04-09T22:45:11.080Z,1554849911.080 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-04-09T22:45:11.101Z,1554849911.101 [TempGradientCalculator] Loaded
2019-04-09T22:45:11.102Z,1554849911.102 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-04-09T22:45:11.108Z,1554849911.108 [YawRateCalculator] Loaded
2019-04-09T22:45:11.108Z,1554849911.108 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-04-09T22:45:11.141Z,1554849911.141 [ElevatorOffsetCalculator] Loaded
2019-04-09T22:45:11.141Z,1554849911.141 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-04-09T22:45:11.142Z,1554849911.142 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-04-09T22:45:11.142Z,1554849911.142 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-04-09T22:45:11.169Z,1554849911.169 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-04-09T22:45:11.169Z,1554849911.169 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-04-09T22:45:11.272Z,1554849911.272 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-04-09T22:45:11.272Z,1554849911.272 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-04-09T22:45:11.332Z,1554849911.332 [DeadReckonUsingSpeedCalculator] Loaded
2019-04-09T22:45:11.332Z,1554849911.332 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-04-09T22:45:11.393Z,1554849911.393 [DeadReckonWithRespectToSeafloor] Loaded
2019-04-09T22:45:11.393Z,1554849911.393 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2019-04-09T22:45:11.414Z,1554849911.414 [NavChart] Loaded
2019-04-09T22:45:11.414Z,1554849911.414 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-04-09T22:45:11.418Z,1554849911.418 [UniversalFixResidualReporter] Loaded
2019-04-09T22:45:11.418Z,1554849911.418 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-04-09T22:45:11.419Z,1554849911.419 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-04-09T22:45:11.419Z,1554849911.419 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-04-09T22:45:12.413Z,1554849912.413 [AcousticModem_Benthos_ATM900] Loaded
2019-04-09T22:45:12.414Z,1554849912.414 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2019-04-09T22:45:12.491Z,1554849912.491 [DataOverHttps] Loaded
2019-04-09T22:45:12.491Z,1554849912.491 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-04-09T22:45:12.492Z,1554849912.492 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409714E0
2019-04-09T22:45:12.492Z,1554849912.492 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 7190
2019-04-09T22:45:12.506Z,1554849912.506 [Depth_Keller] Loaded
2019-04-09T22:45:12.506Z,1554849912.506 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-04-09T22:45:12.598Z,1554849912.598 [NAL9602] Loaded
2019-04-09T22:45:12.598Z,1554849912.598 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-04-09T22:45:12.604Z,1554849912.604 [Onboard] Loaded
2019-04-09T22:45:12.604Z,1554849912.604 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-04-09T22:45:12.607Z,1554849912.607 [Radio_Surface] Loaded
2019-04-09T22:45:12.607Z,1554849912.607 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-04-09T22:45:12.608Z,1554849912.608 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409A14E0
2019-04-09T22:45:12.609Z,1554849912.609 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 7191
2019-04-09T22:45:13.909Z,1554849913.909 [BPC1] Loaded
2019-04-09T22:45:13.910Z,1554849913.910 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-04-09T22:45:13.940Z,1554849913.940 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.minrange
2019-04-09T22:45:13.942Z,1554849913.942 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.maxrange
2019-04-09T22:45:13.944Z,1554849913.944 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.soundspeed
2019-04-09T22:45:13.946Z,1554849913.946 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.automode
2019-04-09T22:45:13.949Z,1554849913.949 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.gainindex
2019-04-09T22:45:13.951Z,1554849913.951 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.minconfidence
2019-04-09T22:45:13.953Z,1554849913.953 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.pingenable
2019-04-09T22:45:13.955Z,1554849913.955 [BR_Ping1D] Loaded
2019-04-09T22:45:13.955Z,1554849913.955 [ComponentRegistry](DEBUG): SyncComponent "BR_Ping1D" handled in the control thread.
2019-04-09T22:45:13.956Z,1554849913.956 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-04-09T22:45:13.956Z,1554849913.956 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-04-09T22:45:14.021Z,1554849914.021 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-04-09T22:45:14.021Z,1554849914.021 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-04-09T22:45:14.087Z,1554849914.087 [VerticalControl](DEBUG): Construct VerticalControl.
2019-04-09T22:45:14.171Z,1554849914.171 [VerticalControl] Loaded
2019-04-09T22:45:14.172Z,1554849914.172 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-04-09T22:45:14.172Z,1554849914.172 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-04-09T22:45:14.230Z,1554849914.230 [HorizontalControl] Loaded
2019-04-09T22:45:14.230Z,1554849914.230 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-04-09T22:45:14.231Z,1554849914.231 [SpeedControl](DEBUG): Construct SpeedControl.
2019-04-09T22:45:14.232Z,1554849914.232 [SpeedControl] Loaded
2019-04-09T22:45:14.233Z,1554849914.233 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-04-09T22:45:14.234Z,1554849914.234 [LoopControl](DEBUG): Construct LoopControl.
2019-04-09T22:45:14.234Z,1554849914.234 [LoopControl] Loaded
2019-04-09T22:45:14.234Z,1554849914.234 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-04-09T22:45:14.235Z,1554849914.235 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-04-09T22:45:14.235Z,1554849914.235 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-04-09T22:45:14.249Z,1554849914.249 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-04-09T22:45:14.249Z,1554849914.249 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-04-09T22:45:14.522Z,1554849914.522 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-04-09T22:45:14.526Z,1554849914.526 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-04-09T22:45:14.527Z,1554849914.527 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-04-09T22:45:14.534Z,1554849914.534 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-04-09T22:45:14.535Z,1554849914.535 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ACE4E0
2019-04-09T22:45:14.535Z,1554849914.535 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 7192
2019-04-09T22:45:14.540Z,1554849914.540 [Supervisor](INFO): Main Thread ID is 4347
2019-04-09T22:45:14.540Z,1554849914.540 [Supervisor](DEBUG): Running supervisor.
2019-04-09T22:45:14.541Z,1554849914.541 [CommandLine ThreadHandler](INFO): Handler Thread ID is 7193
2019-04-09T22:45:14.543Z,1554849914.543 [controlThread ThreadHandler](INFO): Handler Thread ID is 7194
2019-04-09T22:45:14.544Z,1554849914.544 [controlThread](DEBUG): Initializing ControlThread
2019-04-09T22:45:14.545Z,1554849914.545 [SBIT](INFO): Initialize SBIT Component.
2019-04-09T22:45:14.546Z,1554849914.546 [SBIT](IMPORTANT): git: 2019-03-18-31-ge98d5dc
2019-04-09T22:45:14.546Z,1554849914.546 [SBIT](INFO): git hash: e98d5dc50cbc39e402f19b9946ff4dc0776c8725
2019-04-09T22:45:14.546Z,1554849914.546 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-04-09T22:45:14.548Z,1554849914.548 [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 Feb 21 11:17:40 PST 2019
2019-04-09T22:45:14.549Z,1554849914.549 [SBIT](INFO): Beginning SBIT in 24.000000 seconds.
2019-04-09T22:45:14.549Z,1554849914.549 [IBIT](INFO): Initialize IBIT Component.
2019-04-09T22:45:14.550Z,1554849914.550 [CBIT](DEBUG): Initialize CBIT Component.
2019-04-09T22:45:14.551Z,1554849914.551 [logger ThreadHandler](INFO): Handler Thread ID is 7195
2019-04-09T22:45:14.562Z,1554849914.562 [CBIT](DEBUG): Initialized mux pins.
2019-04-09T22:45:14.562Z,1554849914.562 [CBIT](DEBUG): Initializing the watchdog timer.
2019-04-09T22:45:14.570Z,1554849914.570 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 7196
2019-04-09T22:45:14.571Z,1554849914.571 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T22:45:14.575Z,1554849914.575 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T22:45:14.576Z,1554849914.576 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 7198
2019-04-09T22:45:14.577Z,1554849914.577 [WetLabsBB2FL](INFO): Powering down
2019-04-09T22:45:14.586Z,1554849914.586 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-04-09T22:45:14.586Z,1554849914.586 [CBIT](DEBUG): Initializing heartbeat.
2019-04-09T22:45:14.603Z,1554849914.603 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 7199
2019-04-09T22:45:14.604Z,1554849914.604 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-04-09T22:45:14.618Z,1554849914.618 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 7200
2019-04-09T22:45:14.634Z,1554849914.634 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 7201
2019-04-09T22:45:14.637Z,1554849914.637 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-04-09T22:45:14.638Z,1554849914.638 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-04-09T22:45:14.638Z,1554849914.638 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-04-09T22:45:14.638Z,1554849914.638 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-04-09T22:45:14.638Z,1554849914.638 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-04-09T22:45:14.638Z,1554849914.638 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-04-09T22:45:14.639Z,1554849914.639 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-04-09T22:45:14.639Z,1554849914.639 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-04-09T22:45:14.639Z,1554849914.639 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-04-09T22:45:14.639Z,1554849914.639 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-04-09T22:45:14.639Z,1554849914.639 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-04-09T22:45:14.639Z,1554849914.639 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-04-09T22:45:14.640Z,1554849914.640 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-04-09T22:45:14.640Z,1554849914.640 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-04-09T22:45:14.640Z,1554849914.640 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-04-09T22:45:14.640Z,1554849914.640 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-04-09T22:45:14.658Z,1554849914.658 [CBIT](DEBUG): Deactivating GF circuits.
2019-04-09T22:45:14.658Z,1554849914.658 [CBIT](DEBUG): Deactivating emergency mode.
2019-04-09T22:45:14.694Z,1554849914.694 [CBIT](DEBUG): Backplane powered.
2019-04-09T22:45:14.698Z,1554849914.698 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-04-09T22:45:14.699Z,1554849914.699 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-04-09T22:45:14.699Z,1554849914.699 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-04-09T22:45:14.699Z,1554849914.699 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-04-09T22:45:14.700Z,1554849914.700 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-04-09T22:45:14.700Z,1554849914.700 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-04-09T22:45:14.701Z,1554849914.701 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-09T22:45:14.702Z,1554849914.702 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-04-09T22:45:14.703Z,1554849914.703 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-04-09T22:45:14.707Z,1554849914.707 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-04-09T22:45:14.709Z,1554849914.709 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-04-09T22:45:14.710Z,1554849914.710 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-04-09T22:45:14.710Z,1554849914.710 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-04-09T22:45:14.711Z,1554849914.711 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-04-09T22:45:14.733Z,1554849914.733 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-04-09T22:45:14.769Z,1554849914.769 [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-04-09T22:45:14.778Z,1554849914.778 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-04-09T22:45:14.862Z,1554849914.862 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-04-09T22:45:14.863Z,1554849914.863 [Default:A.Wait](DEBUG): Construct Wait.
2019-04-09T22:45:14.865Z,1554849914.865 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-04-09T22:45:14.888Z,1554849914.888 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-04-09T22:45:14.915Z,1554849914.915 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-04-09T22:45:14.920Z,1554849914.920 [Default:E.Execute](DEBUG): Construct Execute.
2019-04-09T22:45:14.944Z,1554849914.944 [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-04-09T22:45:14.949Z,1554849914.949 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AcousticModem_Benthos_ATM900,Depth_Keller,NAL9602,Onboard,BPC1,BR_Ping1D,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-04-09T22:45:14.983Z,1554849914.983 [ESPComponent](INFO): powering down ESP
2019-04-09T22:45:15.022Z,1554849915.022 [Radio_Surface](INFO): Powering up
2019-04-09T22:45:15.575Z,1554849915.575 [PAR_Licor](INFO): A/D timeout, 11 tries over 127 ms

2019-04-09T22:45:15.575Z,1554849915.575 [PAR_Licor] Data Fault, FailCount= 1
2019-04-09T22:45:15.575Z,1554849915.575 [PAR_Licor](ERROR): Data Fault
2019-04-09T22:45:15.576Z,1554849915.576 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T22:45:15.576Z,1554849915.576 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T22:45:15.611Z,1554849915.611 [Depth_Keller](ERROR): Pressure reading out of range: 1895.684326 decibar
2019-04-09T22:45:15.663Z,1554849915.663 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-04-09T22:45:15.743Z,1554849915.743 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-04-09T22:45:15.772Z,1554849915.772 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-04-09T22:45:15.774Z,1554849915.774 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-04-09T22:45:15.783Z,1554849915.783 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-04-09T22:45:15.784Z,1554849915.784 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T22:45:15.799Z,1554849915.799 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T22:45:15.800Z,1554849915.800 [RudderServo](DEBUG): Initializing EZServoServo.
2019-04-09T22:45:15.807Z,1554849915.807 [RudderServo](DEBUG): Initializing RudderServo.
2019-04-09T22:45:15.808Z,1554849915.808 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-04-09T22:45:15.818Z,1554849915.818 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-04-09T22:45:15.821Z,1554849915.821 [CommandLine](FAULT): Scheduling is paused
2019-04-09T22:45:15.821Z,1554849915.821 [CBIT](INFO): Critical error at 20190409T224513
2019-04-09T22:45:15.821Z,1554849915.821 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-04-09T22:45:15.825Z,1554849915.825 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T22:45:15.826Z,1554849915.826 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T22:45:15.826Z,1554849915.826 [PAR_Licor] No Fault, FailCount= 1
2019-04-09T22:45:16.362Z,1554849916.362 [PAR_Licor](INFO): A/D timeout, 13 tries over 126 ms

2019-04-09T22:45:16.362Z,1554849916.362 [PAR_Licor] Data Fault, FailCount= 2
2019-04-09T22:45:16.362Z,1554849916.362 [PAR_Licor](ERROR): Data Fault
2019-04-09T22:45:16.387Z,1554849916.387 [BR_Ping1D](INFO): startup: 0
2019-04-09T22:45:16.387Z,1554849916.387 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T22:45:16.398Z,1554849916.398 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T22:45:16.398Z,1554849916.398 [BR_Ping1D](INFO): data ô

2019-04-09T22:45:16.413Z,1554849916.413 [BR_Ping1D](CRITICAL): SIGSEGV: address not mapped to object
2019-04-09T22:45:16.422Z,1554849916.422 [BR_Ping1D](CRITICAL): Bad address: 0x1F4
2019-04-09T22:45:16.422Z,1554849916.422 [BR_Ping1D](CRITICAL): Backtrace: 
./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x179208]
./bin/LRAUV(_ZN9Component7executeEv+0x31c) [0xd6b4c]
./bin/LRAUV(_ZN7PCaller10PThreadRunEPv+0xbc) [0x178da4]

2019-04-09T22:45:16.422Z,1554849916.422 [BR_Ping1D] Software Fault, FailCount= 1
2019-04-09T22:45:16.422Z,1554849916.422 [BR_Ping1D](ERROR): Software Fault
2019-04-09T22:45:16.425Z,1554849916.425 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-04-09T22:45:16.425Z,1554849916.425 [SyncHandler](INFO): Protected caller Thread ID is 7211
2019-04-09T22:45:16.758Z,1554849916.758 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-04-09T22:45:16.758Z,1554849916.758 [RudderServo](FAULT): Rudder failed to initialize
2019-04-09T22:45:16.758Z,1554849916.758 [RudderServo] Communications Fault, FailCount= 1
2019-04-09T22:45:16.758Z,1554849916.758 [RudderServo](ERROR): Communications Fault
2019-04-09T22:45:16.867Z,1554849916.867 [CBIT](INFO): Critical error at 20190409T224516
2019-04-09T22:45:16.870Z,1554849916.870 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-04-09T22:45:16.870Z,1554849916.870 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T22:45:16.870Z,1554849916.870 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T22:45:16.870Z,1554849916.870 [PAR_Licor] No Fault, FailCount= 2
2019-04-09T22:45:16.871Z,1554849916.871 [CBIT](ERROR): Software Fault in component: BR_Ping1D
2019-04-09T22:45:16.909Z,1554849916.909 [BR_Ping1D](INFO): Stop
2019-04-09T22:45:16.909Z,1554849916.909 [BR_Ping1D](INFO): uninitialize
2019-04-09T22:45:16.909Z,1554849916.909 [BR_Ping1D](INFO): Powering down
2019-04-09T22:45:17.187Z,1554849917.187 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-04-09T22:45:17.187Z,1554849917.187 [RudderServo](INFO): Powering down
2019-04-09T22:45:17.308Z,1554849917.308 [BR_Ping1D](INFO): Stopping
2019-04-09T22:45:17.822Z,1554849917.822 [PAR_Licor](INFO): A/D timeout, 8 tries over 138 ms

2019-04-09T22:45:17.822Z,1554849917.822 [PAR_Licor] Data Fault, FailCount= 1
2019-04-09T22:45:17.822Z,1554849917.822 [PAR_Licor](ERROR): Data Fault
2019-04-09T22:45:17.863Z,1554849917.863 [BR_Ping1D](INFO): Stopping
2019-04-09T22:45:17.876Z,1554849917.876 [RudderServo](DEBUG): Initializing EZServoServo.
2019-04-09T22:45:17.995Z,1554849917.995 [RudderServo](DEBUG): Initializing RudderServo.
2019-04-09T22:45:17.999Z,1554849917.999 [CBIT](INFO): Clearing failed state for component RudderServo
2019-04-09T22:45:17.999Z,1554849917.999 [RudderServo] No Fault, FailCount= 1
2019-04-09T22:45:17.000Z,1554849918.000 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T22:45:17.000Z,1554849918.000 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T22:45:17.000Z,1554849918.000 [PAR_Licor] No Fault, FailCount= 1
2019-04-09T22:45:18.110Z,1554849918.110 [BR_Ping1D](INFO): Stopped
2019-04-09T22:45:18.169Z,1554849918.169 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T22:45:18.170Z,1554849918.170 [BR_Ping1D] No Fault, FailCount= 1
2019-04-09T22:45:18.630Z,1554849918.630 [PAR_Licor](INFO): A/D timeout, 9 tries over 138 ms

2019-04-09T22:45:18.630Z,1554849918.630 [PAR_Licor] Data Fault, FailCount= 2
2019-04-09T22:45:18.630Z,1554849918.630 [PAR_Licor](ERROR): Data Fault
2019-04-09T22:45:18.666Z,1554849918.666 [BR_Ping1D](INFO): Start
2019-04-09T22:45:18.794Z,1554849918.794 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T22:45:18.794Z,1554849918.794 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T22:45:18.794Z,1554849918.794 [PAR_Licor] No Fault, FailCount= 2
2019-04-09T22:45:18.918Z,1554849918.918 [BR_Ping1D](INFO): Starting
2019-04-09T22:45:19.333Z,1554849919.333 [BR_Ping1D](INFO): Starting
2019-04-09T22:45:19.333Z,1554849919.333 [BR_Ping1D](INFO): readConfig
2019-04-09T22:45:19.334Z,1554849919.334 [BR_Ping1D](INFO): startup: 0
2019-04-09T22:45:19.334Z,1554849919.334 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T22:45:19.346Z,1554849919.346 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T22:45:19.346Z,1554849919.346 [BR_Ping1D](INFO): data ô

2019-04-09T22:45:19.360Z,1554849919.360 [BR_Ping1D](CRITICAL): SIGSEGV: address not mapped to object
2019-04-09T22:45:19.360Z,1554849919.360 [BR_Ping1D](CRITICAL): Bad address: 0x1F4
2019-04-09T22:45:19.360Z,1554849919.360 [BR_Ping1D](CRITICAL): Backtrace: 
./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x179208]
./bin/LRAUV(_ZN9Component7executeEv+0x31c) [0xd6b4c]
./bin/LRAUV(_ZN7PCaller10PThreadRunEPv+0xbc) [0x178da4]

2019-04-09T22:45:19.360Z,1554849919.360 [BR_Ping1D] Software Fault, FailCount= 2
2019-04-09T22:45:19.360Z,1554849919.360 [BR_Ping1D](ERROR): Software Fault
2019-04-09T22:45:19.361Z,1554849919.361 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-04-09T22:45:19.362Z,1554849919.362 [SyncHandler](INFO): Protected caller Thread ID is 7214
2019-04-09T22:45:19.592Z,1554849919.592 [CBIT](INFO): Critical error at 20190409T224519
2019-04-09T22:45:19.595Z,1554849919.595 [CBIT](ERROR): Software Fault in component: BR_Ping1D
2019-04-09T22:45:19.725Z,1554849919.725 [BR_Ping1D](INFO): Stop
2019-04-09T22:45:19.725Z,1554849919.725 [BR_Ping1D](INFO): uninitialize
2019-04-09T22:45:19.725Z,1554849919.725 [BR_Ping1D](INFO): Powering down
2019-04-09T22:45:20.132Z,1554849920.132 [BR_Ping1D](INFO): Stopping
2019-04-09T22:45:20.650Z,1554849920.650 [PAR_Licor](INFO): A/D timeout, 8 tries over 138 ms

2019-04-09T22:45:20.650Z,1554849920.650 [PAR_Licor] Data Fault, FailCount= 1
2019-04-09T22:45:20.650Z,1554849920.650 [PAR_Licor](ERROR): Data Fault
2019-04-09T22:45:20.686Z,1554849920.686 [BR_Ping1D](INFO): Stopping
2019-04-09T22:45:20.739Z,1554849920.739 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T22:45:20.739Z,1554849920.739 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T22:45:20.739Z,1554849920.739 [PAR_Licor] No Fault, FailCount= 1
2019-04-09T22:45:20.938Z,1554849920.938 [BR_Ping1D](INFO): Stopped
2019-04-09T22:45:21.067Z,1554849921.067 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T22:45:21.067Z,1554849921.067 [BR_Ping1D] No Fault, FailCount= 2
2019-04-09T22:45:21.342Z,1554849921.342 [BR_Ping1D](INFO): Start
2019-04-09T22:45:21.759Z,1554849921.759 [BR_Ping1D](INFO): Starting
2019-04-09T22:45:22.274Z,1554849922.274 [PAR_Licor](INFO): A/D timeout, 8 tries over 138 ms

2019-04-09T22:45:22.274Z,1554849922.274 [PAR_Licor] Data Fault, FailCount= 1
2019-04-09T22:45:22.274Z,1554849922.274 [PAR_Licor](ERROR): Data Fault
2019-04-09T22:45:22.321Z,1554849922.321 [BR_Ping1D](INFO): Starting
2019-04-09T22:45:22.321Z,1554849922.321 [BR_Ping1D](INFO): readConfig
2019-04-09T22:45:22.321Z,1554849922.321 [BR_Ping1D](INFO): startup: 0
2019-04-09T22:45:22.321Z,1554849922.321 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T22:45:22.334Z,1554849922.334 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T22:45:22.334Z,1554849922.334 [BR_Ping1D](INFO): data ô

2019-04-09T22:45:22.347Z,1554849922.347 [BR_Ping1D](CRITICAL): SIGSEGV: address not mapped to object
2019-04-09T22:45:22.347Z,1554849922.347 [BR_Ping1D](CRITICAL): Bad address: 0x1F4
2019-04-09T22:45:22.348Z,1554849922.348 [BR_Ping1D](CRITICAL): Backtrace: 
./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x179208]
./bin/LRAUV(_ZN9Component7executeEv+0x31c) [0xd6b4c]
./bin/LRAUV(_ZN7PCaller10PThreadRunEPv+0xbc) [0x178da4]

2019-04-09T22:45:22.348Z,1554849922.348 [BR_Ping1D] Software Fault, FailCount= 3
2019-04-09T22:45:22.348Z,1554849922.348 [BR_Ping1D](ERROR): Software Fault
2019-04-09T22:45:22.354Z,1554849922.354 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-04-09T22:45:22.354Z,1554849922.354 [SyncHandler](INFO): Protected caller Thread ID is 7216
2019-04-09T22:45:22.404Z,1554849922.404 [CBIT](INFO): Critical error at 20190409T224522
2019-04-09T22:45:22.406Z,1554849922.406 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T22:45:22.407Z,1554849922.407 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T22:45:22.407Z,1554849922.407 [PAR_Licor] No Fault, FailCount= 1
2019-04-09T22:45:22.407Z,1554849922.407 [CBIT](ERROR): Software Fault in component: BR_Ping1D
2019-04-09T22:45:22.562Z,1554849922.562 [BR_Ping1D](INFO): Stop
2019-04-09T22:45:22.562Z,1554849922.562 [BR_Ping1D](INFO): uninitialize
2019-04-09T22:45:22.563Z,1554849922.563 [BR_Ping1D](INFO): Powering down
2019-04-09T22:45:22.966Z,1554849922.966 [BR_Ping1D](INFO): Stopping
2019-04-09T22:45:23.370Z,1554849923.370 [BR_Ping1D](INFO): Stopping
2019-04-09T22:45:23.895Z,1554849923.895 [PAR_Licor](INFO): A/D timeout, 11 tries over 143 ms

2019-04-09T22:45:23.895Z,1554849923.895 [PAR_Licor] Data Fault, FailCount= 1
2019-04-09T22:45:23.895Z,1554849923.895 [PAR_Licor](ERROR): Data Fault
2019-04-09T22:45:23.921Z,1554849923.921 [BR_Ping1D](INFO): Stopped
2019-04-09T22:45:24.003Z,1554849924.003 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T22:45:24.003Z,1554849924.003 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T22:45:24.003Z,1554849924.003 [PAR_Licor] No Fault, FailCount= 1
2019-04-09T22:45:24.003Z,1554849924.003 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T22:45:24.003Z,1554849924.003 [BR_Ping1D] No Fault, FailCount= 3
2019-04-09T22:45:24.177Z,1554849924.177 [BR_Ping1D](INFO): Start
2019-04-09T22:45:24.710Z,1554849924.710 [PAR_Licor](INFO): A/D timeout, 7 tries over 150 ms

2019-04-09T22:45:24.710Z,1554849924.710 [PAR_Licor] Data Fault, FailCount= 2
2019-04-09T22:45:24.710Z,1554849924.710 [PAR_Licor](ERROR): Data Fault
2019-04-09T22:45:24.745Z,1554849924.745 [BR_Ping1D](INFO): Starting
2019-04-09T22:45:24.745Z,1554849924.745 [BR_Ping1D](INFO): readConfig
2019-04-09T22:45:24.745Z,1554849924.745 [BR_Ping1D](INFO): startup: 0
2019-04-09T22:45:24.746Z,1554849924.746 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T22:45:24.758Z,1554849924.758 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T22:45:24.758Z,1554849924.758 [BR_Ping1D](INFO): data ô

2019-04-09T22:45:24.786Z,1554849924.786 [BR_Ping1D](CRITICAL): SIGSEGV: address not mapped to object
2019-04-09T22:45:24.787Z,1554849924.787 [BR_Ping1D](CRITICAL): Bad address: 0x1F4
2019-04-09T22:45:24.787Z,1554849924.787 [BR_Ping1D](CRITICAL): Backtrace: 
./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x179208]
./bin/LRAUV(_ZN9Component7executeEv+0x31c) [0xd6b4c]
./bin/LRAUV(_ZN7PCaller10PThreadRunEPv+0xbc) [0x178da4]

2019-04-09T22:45:24.787Z,1554849924.787 [BR_Ping1D] Software Fault, FailCount= 4
2019-04-09T22:45:24.787Z,1554849924.787 [BR_Ping1D](ERROR): Software Fault
2019-04-09T22:45:24.794Z,1554849924.794 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-04-09T22:45:24.794Z,1554849924.794 [SyncHandler](INFO): Protected caller Thread ID is 7218
2019-04-09T22:45:24.860Z,1554849924.860 [CBIT](INFO): Critical error at 20190409T224524
2019-04-09T22:45:24.863Z,1554849924.863 [CBIT](CRITICAL): Environmental Failure. Press:14.820937 PSI. Humidity:38%. Temp:25 C. ABORTING MISSION
2019-04-09T22:45:24.864Z,1554849924.864 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T22:45:24.864Z,1554849924.864 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T22:45:24.864Z,1554849924.864 [PAR_Licor] No Fault, FailCount= 2
2019-04-09T22:45:24.865Z,1554849924.865 [CBIT](ERROR): Software Fault in component: BR_Ping1D
2019-04-09T22:45:24.986Z,1554849924.986 [BR_Ping1D](INFO): Stop
2019-04-09T22:45:24.986Z,1554849924.986 [BR_Ping1D](INFO): uninitialize
2019-04-09T22:45:24.986Z,1554849924.986 [BR_Ping1D](INFO): Powering down
2019-04-09T22:45:25.224Z,1554849925.224 [CBIT](INFO): Critical error at 20190409T224524
2019-04-09T22:45:25.367Z,1554849925.367 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T22:45:25.367Z,1554849925.367 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-04-09T22:45:25.367Z,1554849925.367 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T22:45:25.502Z,1554849925.502 [PAR_Licor](INFO): A/D timeout, 8 tries over 134 ms

2019-04-09T22:45:25.502Z,1554849925.502 [PAR_Licor] Data Fault, FailCount= 3
2019-04-09T22:45:25.502Z,1554849925.502 [PAR_Licor](ERROR): Data Fault
2019-04-09T22:45:25.542Z,1554849925.542 [BR_Ping1D](INFO): Stopping
2019-04-09T22:45:25.575Z,1554849925.575 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T22:45:25.575Z,1554849925.575 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T22:45:25.576Z,1554849925.576 [CBIT](CRITICAL): Data Fault in component: PAR_Licor
2019-04-09T22:45:25.890Z,1554849925.890 [Aanderaa_O2](INFO): Powering down
2019-04-09T22:45:25.969Z,1554849925.969 [BR_Ping1D](INFO): Stopping
2019-04-09T22:45:25.981Z,1554849925.981 [CBIT](INFO): Critical error at 20190409T224525
2019-04-09T22:45:26.194Z,1554849926.194 [BR_Ping1D](INFO): Stopped
2019-04-09T22:45:26.211Z,1554849926.211 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T22:45:26.211Z,1554849926.211 [BR_Ping1D] No Fault, FailCount= 4
2019-04-09T22:45:26.598Z,1554849926.598 [BR_Ping1D](INFO): Start
2019-04-09T22:45:26.660Z,1554849926.660 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T22:45:26.660Z,1554849926.660 [Aanderaa_O2] No Fault, FailCount= 1
2019-04-09T22:45:27.001Z,1554849927.001 [BR_Ping1D](INFO): Starting
2019-04-09T22:45:27.418Z,1554849927.418 [BR_Ping1D](INFO): Starting
2019-04-09T22:45:27.418Z,1554849927.418 [BR_Ping1D](INFO): readConfig
2019-04-09T22:45:27.418Z,1554849927.418 [BR_Ping1D](INFO): startup: 0
2019-04-09T22:45:27.418Z,1554849927.418 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T22:45:27.430Z,1554849927.430 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T22:45:27.430Z,1554849927.430 [BR_Ping1D](INFO): data ô

2019-04-09T22:45:27.443Z,1554849927.443 [BR_Ping1D](CRITICAL): SIGSEGV: address not mapped to object
2019-04-09T22:45:27.443Z,1554849927.443 [BR_Ping1D](CRITICAL): Bad address: 0x1F4
2019-04-09T22:45:27.444Z,1554849927.444 [BR_Ping1D](CRITICAL): Backtrace: 
./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x179208]
./bin/LRAUV(_ZN9Component7executeEv+0x31c) [0xd6b4c]
./bin/LRAUV(_ZN7PCaller10PThreadRunEPv+0xbc) [0x178da4]

2019-04-09T22:45:27.444Z,1554849927.444 [BR_Ping1D] Software Fault, FailCount= 5
2019-04-09T22:45:27.444Z,1554849927.444 [BR_Ping1D](ERROR): Software Fault
2019-04-09T22:45:27.445Z,1554849927.445 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-04-09T22:45:27.445Z,1554849927.445 [SyncHandler](INFO): Protected caller Thread ID is 7221
2019-04-09T22:45:27.465Z,1554849927.465 [CBIT](INFO): Critical error at 20190409T224527
2019-04-09T22:45:27.469Z,1554849927.469 [CBIT](ERROR): Software Fault in component: BR_Ping1D
2019-04-09T22:45:27.469Z,1554849927.469 [CBIT](CRITICAL): Software Fault in component: BR_Ping1D
2019-04-09T22:45:27.626Z,1554849927.626 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T22:45:27.626Z,1554849927.626 [CTD_Seabird] Communications Fault, FailCount= 1
2019-04-09T22:45:27.626Z,1554849927.626 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T22:45:27.809Z,1554849927.809 [BR_Ping1D](INFO): Stop
2019-04-09T22:45:27.809Z,1554849927.809 [BR_Ping1D](INFO): uninitialize
2019-04-09T22:45:27.809Z,1554849927.809 [BR_Ping1D](INFO): Powering down
2019-04-09T22:45:27.905Z,1554849927.905 [CBIT](INFO): Critical error at 20190409T224527
2019-04-09T22:45:27.908Z,1554849927.908 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T22:45:28.030Z,1554849928.030 [CTD_Seabird](INFO): Powering down
2019-04-09T22:45:28.213Z,1554849928.213 [BR_Ping1D](INFO): Stopping
2019-04-09T22:45:28.618Z,1554849928.618 [BR_Ping1D](INFO): Stopping
2019-04-09T22:45:29.022Z,1554849929.022 [BR_Ping1D](INFO): Stopped
2019-04-09T22:45:29.055Z,1554849929.055 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T22:45:29.055Z,1554849929.055 [CTD_Seabird] No Fault, FailCount= 1
2019-04-09T22:45:29.410Z,1554849929.410 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T22:45:29.414Z,1554849929.414 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T22:45:35.871Z,1554849935.871 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T22:45:35.871Z,1554849935.871 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 1
2019-04-09T22:45:35.871Z,1554849935.871 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T22:45:35.903Z,1554849935.903 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T22:45:36.275Z,1554849936.275 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T22:45:37.082Z,1554849937.082 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T22:45:37.082Z,1554849937.082 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-04-09T22:45:37.082Z,1554849937.082 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T22:45:37.116Z,1554849937.116 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T22:45:37.116Z,1554849937.116 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T22:45:37.116Z,1554849937.116 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 1
2019-04-09T22:45:37.606Z,1554849937.606 [Aanderaa_O2](INFO): Powering down
2019-04-09T22:45:38.327Z,1554849938.327 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T22:45:38.327Z,1554849938.327 [Aanderaa_O2] No Fault, FailCount= 2
2019-04-09T22:45:39.156Z,1554849939.156 [SBIT](IMPORTANT): Beginning Startup BIT
2019-04-09T22:45:39.160Z,1554849939.160 [CBIT](IMPORTANT): Beginning ground fault scan
2019-04-09T22:45:39.506Z,1554849939.506 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T22:45:39.507Z,1554849939.507 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T22:45:42.462Z,1554849942.462 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T22:45:42.462Z,1554849942.462 [CTD_Seabird] Communications Fault, FailCount= 2
2019-04-09T22:45:42.462Z,1554849942.462 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T22:45:42.551Z,1554849942.551 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T22:45:42.803Z,1554849942.803 [NAL9602](INFO): Powering up NAL9602
2019-04-09T22:45:42.866Z,1554849942.866 [CTD_Seabird](INFO): Powering down
2019-04-09T22:45:44.203Z,1554849944.203 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T22:45:44.203Z,1554849944.203 [CTD_Seabird] No Fault, FailCount= 2
2019-04-09T22:45:44.210Z,1554849944.210 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T22:45:44.230Z,1554849944.230 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T22:45:48.858Z,1554849948.858 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T22:45:48.858Z,1554849948.858 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-04-09T22:45:48.858Z,1554849948.858 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T22:45:48.939Z,1554849948.939 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T22:45:48.939Z,1554849948.939 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-04-09T22:45:49.382Z,1554849949.382 [Aanderaa_O2](INFO): Powering down
2019-04-09T22:45:50.167Z,1554849950.167 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.006898
CHAN A1 (24V): -0.127437
CHAN A2 (12V): -0.005113
CHAN A3 (5V): -0.002483
CHAN B0 (3.3V): -0.000716
CHAN B1 (3.15aV): -0.000900
CHAN B2 (3.15bV): -0.000746
CHAN B3 (GND): 0.000341
OPEN: -0.001065
Full Scale Calc: 4.765 mA, -1.589 mA
2019-04-09T22:45:53.711Z,1554849953.711 [NAL9602](INFO): NAL9602 initialized
2019-04-09T22:45:54.690Z,1554849954.690 [NAL9602](DEBUG): Fix Requested
2019-04-09T22:45:58.266Z,1554849958.266 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T22:45:58.266Z,1554849958.266 [CTD_Seabird] Communications Fault, FailCount= 3
2019-04-09T22:45:58.266Z,1554849958.266 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T22:45:58.447Z,1554849958.447 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T22:45:58.447Z,1554849958.447 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-04-09T22:45:58.670Z,1554849958.670 [CTD_Seabird](INFO): Powering down
2019-04-09T22:45:59.519Z,1554849959.519 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T22:45:59.519Z,1554849959.519 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 2
2019-04-09T22:45:59.519Z,1554849959.519 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T22:45:59.688Z,1554849959.688 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T22:45:59.923Z,1554849959.923 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T22:46:00.855Z,1554849960.855 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T22:46:00.856Z,1554849960.856 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 2
2019-04-09T22:46:03.207Z,1554849963.207 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T22:46:03.207Z,1554849963.207 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T22:46:22.300Z,1554849982.300 [BPC1](ERROR): BPC1A: No match for serial number 0441 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2019-04-09T22:46:22.301Z,1554849982.301 [BPC1](ERROR): BPC1B: No match for serial number 0480 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2019-04-09T22:46:22.301Z,1554849982.301 [BPC1](FAULT): Failed to parse data from all battery packs.
2019-04-09T22:46:22.302Z,1554849982.302 [BPC1] Data Fault, FailCount= 1
2019-04-09T22:46:22.302Z,1554849982.302 [BPC1](ERROR): Data Fault
2019-04-09T22:46:22.427Z,1554849982.427 [CBIT](ERROR): Data Fault in component: BPC1
2019-04-09T22:46:23.495Z,1554849983.495 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T22:46:23.495Z,1554849983.495 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 3
2019-04-09T22:46:23.495Z,1554849983.495 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T22:46:23.711Z,1554849983.711 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T22:46:23.898Z,1554849983.898 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T22:46:24.823Z,1554849984.823 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T22:46:24.823Z,1554849984.823 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 3
2019-04-09T22:46:27.174Z,1554849987.174 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T22:46:27.175Z,1554849987.175 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T22:46:32.460Z,1554849992.460 [SBIT](IMPORTANT): SBIT PASSED
2019-04-09T22:46:32.511Z,1554849992.511 [CommandLine](IMPORTANT): got command configSet list
2019-04-09T22:46:32.511Z,1554849992.511 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-04-09T22:46:32.512Z,1554849992.512 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2019-04-09T22:46:32.512Z,1554849992.512 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=0 bool;

2019-04-09T22:46:32.855Z,1554849992.855 [MissionManager](IMPORTANT): Started mission Startup
2019-04-09T22:46:32.856Z,1554849992.856 [Startup] Running Loop=1
2019-04-09T22:46:32.856Z,1554849992.856 [Startup](DEBUG): Aggregate::initialize Startup
2019-04-09T22:46:32.856Z,1554849992.856 [Startup:A.GoToSurface] Running Loop=1
2019-04-09T22:46:32.856Z,1554849992.856 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-04-09T22:46:32.857Z,1554849992.857 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-04-09T22:46:32.857Z,1554849992.857 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-04-09T22:46:32.857Z,1554849992.857 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-04-09T22:46:32.858Z,1554849992.858 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-04-09T22:46:32.858Z,1554849992.858 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-04-09T22:46:32.860Z,1554849992.860 [Startup:StartupSatComms] Running Loop=1
2019-04-09T22:46:32.860Z,1554849992.860 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-04-09T22:46:32.860Z,1554849992.860 [Startup:StartupSatComms:A] Running Loop=1
2019-04-09T22:46:33.275Z,1554849993.275 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-04-09T22:46:47.374Z,1554850007.374 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T22:46:47.375Z,1554850007.375 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 4
2019-04-09T22:46:47.375Z,1554850007.375 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T22:46:47.419Z,1554850007.419 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T22:46:47.778Z,1554850007.778 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T22:46:48.624Z,1554850008.624 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T22:46:48.624Z,1554850008.624 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 4
2019-04-09T22:46:51.010Z,1554850011.010 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T22:46:51.011Z,1554850011.011 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T22:47:11.210Z,1554850031.210 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T22:47:11.211Z,1554850031.211 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 5
2019-04-09T22:47:11.211Z,1554850031.211 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T22:47:11.264Z,1554850031.264 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T22:47:11.614Z,1554850031.614 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T22:47:12.465Z,1554850032.465 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T22:47:12.465Z,1554850032.465 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 5
2019-04-09T22:47:14.847Z,1554850034.847 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T22:47:14.847Z,1554850034.847 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T22:47:14.889Z,1554850034.889 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T22:47:14.889Z,1554850034.889 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-04-09T22:47:14.889Z,1554850034.889 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-09T22:47:14.890Z,1554850034.890 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T22:47:14.891Z,1554850034.891 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-04-09T22:47:14.891Z,1554850034.891 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-09T22:47:14.892Z,1554850034.892 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T22:47:14.892Z,1554850034.892 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2019-04-09T22:47:14.892Z,1554850034.892 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-04-09T22:47:14.903Z,1554850034.903 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-09T22:47:14.903Z,1554850034.903 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-09T22:47:14.903Z,1554850034.903 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-04-09T22:47:15.293Z,1554850035.293 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-09T22:47:15.293Z,1554850035.293 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-04-09T22:47:15.294Z,1554850035.294 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-09T22:47:15.294Z,1554850035.294 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-04-09T22:47:15.295Z,1554850035.295 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-04-09T22:47:15.295Z,1554850035.295 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2019-04-09T22:47:15.697Z,1554850035.697 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-09T22:47:33.058Z,1554850053.058 [Startup:StartupSatComms:A](INFO): Timed out from 2019-04-09T22:46:32.9Z
2019-04-09T22:47:33.059Z,1554850053.059 [Startup:StartupSatComms:A] Stopped
2019-04-09T22:47:33.059Z,1554850053.059 [Startup:StartupSatComms:B] Running Loop=1
2019-04-09T22:47:33.452Z,1554850053.452 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-04-09T22:47:35.046Z,1554850055.046 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T22:47:35.047Z,1554850055.047 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 6
2019-04-09T22:47:35.047Z,1554850055.047 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T22:47:35.078Z,1554850055.078 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T22:47:35.450Z,1554850055.450 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T22:47:36.306Z,1554850056.306 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T22:47:36.306Z,1554850056.306 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 6
2019-04-09T22:47:38.682Z,1554850058.682 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T22:47:38.683Z,1554850058.683 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T22:47:55.944Z,1554850075.944 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004836
2019-04-09T22:47:58.882Z,1554850078.882 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T22:47:58.882Z,1554850078.882 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 7
2019-04-09T22:47:58.883Z,1554850078.883 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T22:47:58.927Z,1554850078.927 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T22:47:59.286Z,1554850079.286 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T22:47:59.847Z,1554850079.847 [CommandLine](IMPORTANT): got command restart application
2019-04-09T22:48:00.132Z,1554850080.132 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T22:48:00.132Z,1554850080.132 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 7
2019-04-09T22:48:00.850Z,1554850080.850 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T22:48:00.850Z,1554850080.850 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-09T22:48:01.030Z,1554850081.030 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-04-09T22:48:01.030Z,1554850081.030 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-09T22:48:01.031Z,1554850081.031 [CommandLine](INFO): Join timeout helper Thread ID is 7247
2019-04-09T22:48:01.046Z,1554850081.046 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-04-09T22:48:01.046Z,1554850081.046 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-09T22:48:01.046Z,1554850081.046 [NavChartDb](INFO): Join timeout helper Thread ID is 7248
2019-04-09T22:48:01.210Z,1554850081.210 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T22:48:01.210Z,1554850081.210 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-09T22:48:01.214Z,1554850081.214 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-04-09T22:48:01.214Z,1554850081.214 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-09T22:48:01.214Z,1554850081.214 [Radio_Surface](INFO): Join timeout helper Thread ID is 7249
2019-04-09T22:48:01.498Z,1554850081.498 [Radio_Surface](INFO): Powering down
2019-04-09T22:48:01.499Z,1554850081.499 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T22:48:01.499Z,1554850081.499 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-09T22:48:01.518Z,1554850081.518 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-04-09T22:48:01.518Z,1554850081.518 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-09T22:48:01.518Z,1554850081.518 [DataOverHttps](INFO): Join timeout helper Thread ID is 7250
2019-04-09T22:48:01.914Z,1554850081.914 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T22:48:01.917Z,1554850081.917 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-09T22:48:01.918Z,1554850081.918 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-04-09T22:48:01.918Z,1554850081.918 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-09T22:48:01.919Z,1554850081.919 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 7251
2019-04-09T22:48:01.954Z,1554850081.954 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T22:48:01.955Z,1554850081.955 [WetLabsBB2FL](INFO): Powering down
2019-04-09T22:48:01.955Z,1554850081.955 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-09T22:48:01.959Z,1554850081.959 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-04-09T22:48:01.959Z,1554850081.959 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-09T22:48:01.960Z,1554850081.960 [CTD_Seabird](INFO): Join timeout helper Thread ID is 7252
2019-04-09T22:48:02.022Z,1554850082.022 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T22:48:02.022Z,1554850082.022 [CTD_Seabird](INFO): Powering down
2019-04-09T22:48:02.034Z,1554850082.034 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-09T22:48:02.051Z,1554850082.051 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-04-09T22:48:02.051Z,1554850082.051 [logger ThreadHandler](INFO): Thread cancelled.
2019-04-09T22:48:02.051Z,1554850082.051 [logger](INFO): Join timeout helper Thread ID is 7253
2019-04-09T22:48:02.078Z,1554850082.078 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T22:48:02.078Z,1554850082.078 [logger ThreadHandler](INFO): Thread cancelled.
2019-04-09T22:48:02.091Z,1554850082.091 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-04-09T22:48:02.091Z,1554850082.091 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-09T22:48:02.091Z,1554850082.091 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-04-09T22:48:02.091Z,1554850082.091 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-04-09T22:48:02.091Z,1554850082.091 [controlThread](INFO): Join timeout helper Thread ID is 7254
2019-04-09T22:48:02.110Z,1554850082.110 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T22:48:02.110Z,1554850082.110 [controlThread](DEBUG): Uninitializing ControlThread
2019-04-09T22:48:02.111Z,1554850082.111 [Aanderaa_O2](INFO): Powering down
2019-04-09T22:48:02.113Z,1554850082.113 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T22:48:02.114Z,1554850082.114 [NAL9602](INFO): Powering down
2019-04-09T22:48:02.115Z,1554850082.115 [BR_Ping1D](INFO): uninitialize
2019-04-09T22:48:02.115Z,1554850082.115 [BR_Ping1D](INFO): Powering down
2019-04-09T22:48:02.116Z,1554850082.116 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-04-09T22:48:02.117Z,1554850082.117 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-04-09T22:48:02.117Z,1554850082.117 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-04-09T22:48:02.118Z,1554850082.118 [MissionManager](INFO): Uninitializing Mission Default
2019-04-09T22:48:02.121Z,1554850082.121 [MissionManager](INFO): Uninitializing Mission Startup
2019-04-09T22:48:02.121Z,1554850082.121 [Startup] Stopped
2019-04-09T22:48:02.121Z,1554850082.121 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-04-09T22:48:02.121Z,1554850082.121 [Startup:A.GoToSurface] Stopped
2019-04-09T22:48:02.121Z,1554850082.121 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-04-09T22:48:02.121Z,1554850082.121 [Startup:StartupSatComms] Stopped
2019-04-09T22:48:02.121Z,1554850082.121 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-04-09T22:48:02.121Z,1554850082.121 [Startup:StartupSatComms:B] Stopped
2019-04-09T22:48:02.123Z,1554850082.123 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-04-09T22:48:02.123Z,1554850082.123 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-04-09T22:48:02.123Z,1554850082.123 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-04-09T22:48:02.123Z,1554850082.123 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-04-09T22:48:02.124Z,1554850082.124 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-04-09T22:48:02.124Z,1554850082.124 [BuoyancyServo](INFO): Powering down
2019-04-09T22:48:02.138Z,1554850082.138 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-04-09T22:48:02.138Z,1554850082.138 [ElevatorServo](INFO): Powering down
2019-04-09T22:48:02.139Z,1554850082.139 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T22:48:02.139Z,1554850082.139 [MassServo](INFO): Powering down
2019-04-09T22:48:02.140Z,1554850082.140 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-04-09T22:48:02.140Z,1554850082.140 [RudderServo](INFO): Powering down
2019-04-09T22:48:02.141Z,1554850082.141 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-04-09T22:48:02.141Z,1554850082.141 [ThrusterServo](INFO): Powering down
2019-04-09T22:48:02.142Z,1554850082.142 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-04-09T22:48:02.142Z,1554850082.142 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-04-09T22:48:02.142Z,1554850082.142 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-04-09T22:48:02.142Z,1554850082.142 [CBIT](DEBUG): Powering off loads.
2019-04-09T22:48:02.154Z,1554850082.154 [CBIT](DEBUG): Disabling WDT.
2019-04-09T22:48:02.166Z,1554850082.166 [CBIT](DEBUG): Opening all GF detection circuits.
2019-04-09T22:48:02.167Z,1554850082.167 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-04-09T22:48:02.198Z,1554850082.198 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-09T22:48:02.203Z,1554850082.203 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-09T22:48:02.271Z,1554850082.271 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-09T22:48:02.275Z,1554850082.275 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-09T22:48:02.327Z,1554850082.327 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-09T22:48:02.385Z,1554850082.385 [logger ThreadHandler](INFO): Thread cancelled.