2019-04-09T01:30:45.854Z,1554773445.854 [Supervisor](DEBUG): Initializing supervisor.
2019-04-09T01:30:45.857Z,1554773445.857 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-04-09T01:30:45.858Z,1554773445.858 [SyncHandler](INFO): Protected caller Thread ID is 5957
2019-04-09T01:30:45.858Z,1554773445.858 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-04-09T01:30:45.859Z,1554773445.859 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-04-09T01:30:45.860Z,1554773445.860 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 5958
2019-04-09T01:30:45.863Z,1554773445.863 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-04-09T01:30:45.875Z,1554773445.875 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-04-09T01:30:45.876Z,1554773445.876 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-04-09T01:30:45.876Z,1554773445.876 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 5959
2019-04-09T01:30:45.877Z,1554773445.877 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-04-09T01:30:45.878Z,1554773445.878 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-04-09T01:30:45.879Z,1554773445.879 [logger ThreadHandler](INFO): Protected caller Thread ID is 5960
2019-04-09T01:30:45.881Z,1554773445.881 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-04-09T01:30:45.881Z,1554773445.881 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-04-09T01:30:45.883Z,1554773445.883 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-04-09T01:30:45.977Z,1554773445.977 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-04-09T01:30:45.978Z,1554773445.978 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-04-09T01:30:46.180Z,1554773446.180 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-04-09T01:30:46.180Z,1554773446.180 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-04-09T01:30:46.317Z,1554773446.317 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-04-09T01:30:46.318Z,1554773446.318 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-04-09T01:30:46.782Z,1554773446.782 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-04-09T01:30:46.782Z,1554773446.782 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-04-09T01:30:47.320Z,1554773447.320 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-04-09T01:30:47.320Z,1554773447.320 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-04-09T01:30:47.795Z,1554773447.795 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-04-09T01:30:47.796Z,1554773447.796 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-04-09T01:30:48.105Z,1554773448.105 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-04-09T01:30:48.106Z,1554773448.106 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-04-09T01:30:48.440Z,1554773448.440 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-04-09T01:30:48.440Z,1554773448.440 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-04-09T01:30:48.840Z,1554773448.840 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-04-09T01:30:48.841Z,1554773448.841 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-04-09T01:30:48.991Z,1554773448.991 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-04-09T01:30:48.992Z,1554773448.992 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-04-09T01:30:49.097Z,1554773449.097 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-04-09T01:30:49.097Z,1554773449.097 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-04-09T01:30:49.177Z,1554773449.177 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-04-09T01:30:49.279Z,1554773449.279 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-04-09T01:30:49.279Z,1554773449.279 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-04-09T01:30:49.473Z,1554773449.473 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-04-09T01:30:49.474Z,1554773449.474 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-04-09T01:30:49.684Z,1554773449.684 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-04-09T01:30:49.686Z,1554773449.686 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2019-04-09T01:30:49.687Z,1554773449.687 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2019-04-09T01:30:49.770Z,1554773449.770 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2019-04-09T01:30:50.005Z,1554773450.005 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-04-09T01:30:50.005Z,1554773450.005 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2019-04-09T01:30:50.100Z,1554773450.100 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2019-04-09T01:30:50.274Z,1554773450.274 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2019-04-09T01:30:50.487Z,1554773450.487 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2019-04-09T01:30:50.571Z,1554773450.571 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2019-04-09T01:30:50.669Z,1554773450.669 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2019-04-09T01:30:50.761Z,1554773450.761 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2019-04-09T01:30:50.913Z,1554773450.913 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2019-04-09T01:30:51.016Z,1554773451.016 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2019-04-09T01:30:51.114Z,1554773451.114 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2019-04-09T01:30:51.114Z,1554773451.114 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-04-09T01:30:51.118Z,1554773451.118 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-04-09T01:30:51.220Z,1554773451.220 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-04-09T01:30:51.220Z,1554773451.220 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-04-09T01:30:51.349Z,1554773451.349 [BuoyancyServo] Loaded
2019-04-09T01:30:51.349Z,1554773451.349 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-04-09T01:30:51.364Z,1554773451.364 [ElevatorServo] Loaded
2019-04-09T01:30:51.365Z,1554773451.365 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-04-09T01:30:51.380Z,1554773451.380 [MassServo] Loaded
2019-04-09T01:30:51.380Z,1554773451.380 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-04-09T01:30:51.395Z,1554773451.395 [RudderServo] Loaded
2019-04-09T01:30:51.396Z,1554773451.396 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-04-09T01:30:51.410Z,1554773451.410 [ThrusterServo] Loaded
2019-04-09T01:30:51.410Z,1554773451.410 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-04-09T01:30:51.411Z,1554773451.411 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-04-09T01:30:51.411Z,1554773451.411 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-04-09T01:30:51.551Z,1554773451.551 [SBIT](DEBUG): Construct Startup Built In Test.
2019-04-09T01:30:51.580Z,1554773451.580 [SBIT] Loaded
2019-04-09T01:30:51.580Z,1554773451.580 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-04-09T01:30:51.581Z,1554773451.581 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-04-09T01:30:51.609Z,1554773451.609 [IBIT] Loaded
2019-04-09T01:30:51.609Z,1554773451.609 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-04-09T01:30:51.612Z,1554773451.612 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-04-09T01:30:52.008Z,1554773452.008 [CBIT] Loaded
2019-04-09T01:30:52.008Z,1554773452.008 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-04-09T01:30:52.008Z,1554773452.008 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-04-09T01:30:52.009Z,1554773452.009 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-04-09T01:30:52.196Z,1554773452.196 [Aanderaa_O2] Loaded
2019-04-09T01:30:52.197Z,1554773452.197 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-04-09T01:30:52.206Z,1554773452.206 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-04-09T01:30:52.212Z,1554773452.212 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-04-09T01:30:52.213Z,1554773452.213 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-04-09T01:30:52.218Z,1554773452.218 [CTD_Seabird](INFO): created writer for : depth
2019-04-09T01:30:52.219Z,1554773452.219 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-04-09T01:30:52.224Z,1554773452.224 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-04-09T01:30:52.225Z,1554773452.225 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-04-09T01:30:52.230Z,1554773452.230 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-04-09T01:30:52.231Z,1554773452.231 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-04-09T01:30:52.236Z,1554773452.236 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-04-09T01:30:52.237Z,1554773452.237 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-04-09T01:30:52.242Z,1554773452.242 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-04-09T01:30:52.243Z,1554773452.243 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-04-09T01:30:52.248Z,1554773452.248 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-04-09T01:30:52.275Z,1554773452.275 [CTD_Seabird] Loaded
2019-04-09T01:30:52.276Z,1554773452.276 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-04-09T01:30:52.277Z,1554773452.277 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406654E0
2019-04-09T01:30:52.277Z,1554773452.277 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 6039
2019-04-09T01:30:52.303Z,1554773452.303 [ESPComponent] Loaded
2019-04-09T01:30:52.303Z,1554773452.303 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2019-04-09T01:30:52.317Z,1554773452.317 [PAR_Licor] Loaded
2019-04-09T01:30:52.318Z,1554773452.318 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-04-09T01:30:52.367Z,1554773452.367 [WetLabsBB2FL] Loaded
2019-04-09T01:30:52.367Z,1554773452.367 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-04-09T01:30:52.368Z,1554773452.368 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406954E0
2019-04-09T01:30:52.368Z,1554773452.368 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 6040
2019-04-09T01:30:52.369Z,1554773452.369 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-04-09T01:30:52.370Z,1554773452.370 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-04-09T01:30:52.549Z,1554773452.549 [DepthRateCalculator] Loaded
2019-04-09T01:30:52.549Z,1554773452.549 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-04-09T01:30:52.556Z,1554773452.556 [PitchRateCalculator] Loaded
2019-04-09T01:30:52.557Z,1554773452.557 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-04-09T01:30:52.589Z,1554773452.589 [SpeedCalculator] Loaded
2019-04-09T01:30:52.589Z,1554773452.589 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-04-09T01:30:52.619Z,1554773452.619 [TempGradientCalculator] Loaded
2019-04-09T01:30:52.619Z,1554773452.619 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-04-09T01:30:52.625Z,1554773452.625 [YawRateCalculator] Loaded
2019-04-09T01:30:52.625Z,1554773452.625 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-04-09T01:30:52.765Z,1554773452.765 [ElevatorOffsetCalculator] Loaded
2019-04-09T01:30:52.765Z,1554773452.765 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-04-09T01:30:52.766Z,1554773452.766 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-04-09T01:30:52.766Z,1554773452.766 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-04-09T01:30:52.793Z,1554773452.793 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-04-09T01:30:52.794Z,1554773452.794 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-04-09T01:30:53.032Z,1554773453.032 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-04-09T01:30:53.032Z,1554773453.032 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-04-09T01:30:53.093Z,1554773453.093 [DeadReckonUsingSpeedCalculator] Loaded
2019-04-09T01:30:53.093Z,1554773453.093 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-04-09T01:30:53.288Z,1554773453.288 [DeadReckonWithRespectToSeafloor] Loaded
2019-04-09T01:30:53.288Z,1554773453.288 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2019-04-09T01:30:53.309Z,1554773453.309 [NavChart] Loaded
2019-04-09T01:30:53.309Z,1554773453.309 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-04-09T01:30:53.313Z,1554773453.313 [UniversalFixResidualReporter] Loaded
2019-04-09T01:30:53.314Z,1554773453.314 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-04-09T01:30:53.314Z,1554773453.314 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-04-09T01:30:53.315Z,1554773453.315 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-04-09T01:30:53.633Z,1554773453.633 [AcousticModem_Benthos_ATM900] Loaded
2019-04-09T01:30:53.634Z,1554773453.634 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2019-04-09T01:30:53.710Z,1554773453.710 [DataOverHttps] Loaded
2019-04-09T01:30:53.710Z,1554773453.710 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-04-09T01:30:53.711Z,1554773453.711 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409704E0
2019-04-09T01:30:53.712Z,1554773453.712 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 6041
2019-04-09T01:30:53.725Z,1554773453.725 [Depth_Keller] Loaded
2019-04-09T01:30:53.725Z,1554773453.725 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-04-09T01:30:53.819Z,1554773453.819 [NAL9602] Loaded
2019-04-09T01:30:53.820Z,1554773453.820 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-04-09T01:30:53.825Z,1554773453.825 [Onboard] Loaded
2019-04-09T01:30:53.825Z,1554773453.825 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-04-09T01:30:53.828Z,1554773453.828 [Radio_Surface] Loaded
2019-04-09T01:30:53.829Z,1554773453.829 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-04-09T01:30:53.830Z,1554773453.830 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409A04E0
2019-04-09T01:30:53.830Z,1554773453.830 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 6042
2019-04-09T01:30:55.161Z,1554773455.161 [BPC1] Loaded
2019-04-09T01:30:55.161Z,1554773455.161 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-04-09T01:30:55.163Z,1554773455.163 [BR_Ping1D](INFO): constructor
2019-04-09T01:30:55.192Z,1554773455.192 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.minrange
2019-04-09T01:30:55.194Z,1554773455.194 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.maxrange
2019-04-09T01:30:55.196Z,1554773455.196 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.soundspeed
2019-04-09T01:30:55.198Z,1554773455.198 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.automode
2019-04-09T01:30:55.201Z,1554773455.201 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.gainindex
2019-04-09T01:30:55.203Z,1554773455.203 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.minconfidence
2019-04-09T01:30:55.205Z,1554773455.205 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.pingenable
2019-04-09T01:30:55.207Z,1554773455.207 [BR_Ping1D] Loaded
2019-04-09T01:30:55.207Z,1554773455.207 [ComponentRegistry](DEBUG): SyncComponent "BR_Ping1D" handled in the control thread.
2019-04-09T01:30:55.208Z,1554773455.208 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-04-09T01:30:55.208Z,1554773455.208 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-04-09T01:30:55.273Z,1554773455.273 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-04-09T01:30:55.274Z,1554773455.274 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-04-09T01:30:55.341Z,1554773455.341 [VerticalControl](DEBUG): Construct VerticalControl.
2019-04-09T01:30:55.427Z,1554773455.427 [VerticalControl] Loaded
2019-04-09T01:30:55.427Z,1554773455.427 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-04-09T01:30:55.428Z,1554773455.428 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-04-09T01:30:55.486Z,1554773455.486 [HorizontalControl] Loaded
2019-04-09T01:30:55.486Z,1554773455.486 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-04-09T01:30:55.487Z,1554773455.487 [SpeedControl](DEBUG): Construct SpeedControl.
2019-04-09T01:30:55.488Z,1554773455.488 [SpeedControl] Loaded
2019-04-09T01:30:55.489Z,1554773455.489 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-04-09T01:30:55.489Z,1554773455.489 [LoopControl](DEBUG): Construct LoopControl.
2019-04-09T01:30:55.490Z,1554773455.490 [LoopControl] Loaded
2019-04-09T01:30:55.491Z,1554773455.491 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-04-09T01:30:55.491Z,1554773455.491 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-04-09T01:30:55.492Z,1554773455.492 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-04-09T01:30:55.505Z,1554773455.505 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-04-09T01:30:55.506Z,1554773455.506 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-04-09T01:30:55.790Z,1554773455.790 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-04-09T01:30:55.794Z,1554773455.794 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-04-09T01:30:55.795Z,1554773455.795 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-04-09T01:30:55.801Z,1554773455.801 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-04-09T01:30:55.802Z,1554773455.802 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ACD4E0
2019-04-09T01:30:55.803Z,1554773455.803 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 6043
2019-04-09T01:30:55.808Z,1554773455.808 [Supervisor](INFO): Main Thread ID is 4347
2019-04-09T01:30:55.808Z,1554773455.808 [Supervisor](DEBUG): Running supervisor.
2019-04-09T01:30:55.808Z,1554773455.808 [CommandLine ThreadHandler](INFO): Handler Thread ID is 6044
2019-04-09T01:30:55.811Z,1554773455.811 [controlThread ThreadHandler](INFO): Handler Thread ID is 6045
2019-04-09T01:30:55.811Z,1554773455.811 [controlThread](DEBUG): Initializing ControlThread
2019-04-09T01:30:55.813Z,1554773455.813 [SBIT](INFO): Initialize SBIT Component.
2019-04-09T01:30:55.813Z,1554773455.813 [SBIT](IMPORTANT): git: 2019-03-18-31-ge98d5dc
2019-04-09T01:30:55.814Z,1554773455.814 [SBIT](INFO): git hash: e98d5dc50cbc39e402f19b9946ff4dc0776c8725
2019-04-09T01:30:55.814Z,1554773455.814 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-04-09T01:30:55.816Z,1554773455.816 [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-09T01:30:55.817Z,1554773455.817 [SBIT](INFO): Beginning SBIT in 24.000000 seconds.
2019-04-09T01:30:55.817Z,1554773455.817 [IBIT](INFO): Initialize IBIT Component.
2019-04-09T01:30:55.818Z,1554773455.818 [CBIT](DEBUG): Initialize CBIT Component.
2019-04-09T01:30:55.819Z,1554773455.819 [logger ThreadHandler](INFO): Handler Thread ID is 6046
2019-04-09T01:30:55.830Z,1554773455.830 [CBIT](DEBUG): Initialized mux pins.
2019-04-09T01:30:55.830Z,1554773455.830 [CBIT](DEBUG): Initializing the watchdog timer.
2019-04-09T01:30:55.838Z,1554773455.838 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 6047
2019-04-09T01:30:55.839Z,1554773455.839 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T01:30:55.843Z,1554773455.843 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T01:30:55.844Z,1554773455.844 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 6049
2019-04-09T01:30:55.845Z,1554773455.845 [WetLabsBB2FL](INFO): Powering down
2019-04-09T01:30:55.854Z,1554773455.854 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-04-09T01:30:55.854Z,1554773455.854 [CBIT](DEBUG): Initializing heartbeat.
2019-04-09T01:30:55.874Z,1554773455.874 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 6050
2019-04-09T01:30:55.875Z,1554773455.875 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-04-09T01:30:55.883Z,1554773455.883 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 6051
2019-04-09T01:30:55.902Z,1554773455.902 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 6052
2019-04-09T01:30:55.905Z,1554773455.905 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-04-09T01:30:55.905Z,1554773455.905 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-04-09T01:30:55.906Z,1554773455.906 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-04-09T01:30:55.906Z,1554773455.906 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-04-09T01:30:55.906Z,1554773455.906 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-04-09T01:30:55.906Z,1554773455.906 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-04-09T01:30:55.906Z,1554773455.906 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-04-09T01:30:55.907Z,1554773455.907 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-04-09T01:30:55.907Z,1554773455.907 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-04-09T01:30:55.907Z,1554773455.907 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-04-09T01:30:55.907Z,1554773455.907 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-04-09T01:30:55.907Z,1554773455.907 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-04-09T01:30:55.907Z,1554773455.907 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-04-09T01:30:55.908Z,1554773455.908 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-04-09T01:30:55.908Z,1554773455.908 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-04-09T01:30:55.908Z,1554773455.908 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-04-09T01:30:55.926Z,1554773455.926 [CBIT](DEBUG): Deactivating GF circuits.
2019-04-09T01:30:55.926Z,1554773455.926 [CBIT](DEBUG): Deactivating emergency mode.
2019-04-09T01:30:55.962Z,1554773455.962 [CBIT](DEBUG): Backplane powered.
2019-04-09T01:30:55.966Z,1554773455.966 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-04-09T01:30:55.967Z,1554773455.967 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-04-09T01:30:55.967Z,1554773455.967 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-04-09T01:30:55.967Z,1554773455.967 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-04-09T01:30:55.968Z,1554773455.968 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-04-09T01:30:55.968Z,1554773455.968 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-04-09T01:30:55.969Z,1554773455.969 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-09T01:30:55.970Z,1554773455.970 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-04-09T01:30:55.971Z,1554773455.971 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-04-09T01:30:55.975Z,1554773455.975 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-04-09T01:30:55.977Z,1554773455.977 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-04-09T01:30:55.978Z,1554773455.978 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-04-09T01:30:55.979Z,1554773455.979 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-04-09T01:30:55.979Z,1554773455.979 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-04-09T01:30:56.001Z,1554773456.001 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-04-09T01:30:56.037Z,1554773456.037 [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-09T01:30:56.046Z,1554773456.046 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-04-09T01:30:56.101Z,1554773456.101 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-04-09T01:30:56.103Z,1554773456.103 [Default:A.Wait](DEBUG): Construct Wait.
2019-04-09T01:30:56.104Z,1554773456.104 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-04-09T01:30:56.155Z,1554773456.155 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-04-09T01:30:56.174Z,1554773456.174 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-04-09T01:30:56.180Z,1554773456.180 [Default:E.Execute](DEBUG): Construct Execute.
2019-04-09T01:30:56.199Z,1554773456.199 [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-09T01:30:56.204Z,1554773456.204 [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-09T01:30:56.231Z,1554773456.231 [ESPComponent](INFO): powering down ESP
2019-04-09T01:30:56.286Z,1554773456.286 [Radio_Surface](INFO): Powering up
2019-04-09T01:30:56.691Z,1554773456.691 [PAR_Licor](INFO): A/D timeout, 8 tries over 129 ms

2019-04-09T01:30:56.691Z,1554773456.691 [PAR_Licor] Data Fault, FailCount= 1
2019-04-09T01:30:56.691Z,1554773456.691 [PAR_Licor](ERROR): Data Fault
2019-04-09T01:30:56.692Z,1554773456.692 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T01:30:56.692Z,1554773456.692 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T01:30:56.858Z,1554773456.858 [BR_Ping1D](INFO): Start
2019-04-09T01:30:56.900Z,1554773456.900 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-04-09T01:30:56.906Z,1554773456.906 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-04-09T01:30:56.907Z,1554773456.907 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-04-09T01:30:56.914Z,1554773456.914 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-04-09T01:30:56.915Z,1554773456.915 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T01:30:56.922Z,1554773456.922 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T01:30:56.923Z,1554773456.923 [RudderServo](DEBUG): Initializing EZServoServo.
2019-04-09T01:30:56.930Z,1554773456.930 [RudderServo](DEBUG): Initializing RudderServo.
2019-04-09T01:30:56.931Z,1554773456.931 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-04-09T01:30:56.938Z,1554773456.938 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-04-09T01:30:56.941Z,1554773456.941 [CommandLine](FAULT): Scheduling is paused
2019-04-09T01:30:56.941Z,1554773456.941 [CBIT](INFO): Critical error at 20190409T013055
2019-04-09T01:30:56.941Z,1554773456.941 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-04-09T01:30:56.942Z,1554773456.942 [CBIT](FAULT): Main Battery Failure. Count: 1
2019-04-09T01:30:56.946Z,1554773456.946 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T01:30:56.946Z,1554773456.946 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T01:30:56.946Z,1554773456.946 [PAR_Licor] No Fault, FailCount= 1
2019-04-09T01:30:57.079Z,1554773457.079 [BR_Ping1D](INFO): Starting
2019-04-09T01:30:57.491Z,1554773457.491 [PAR_Licor](INFO): A/D timeout, 8 tries over 135 ms

2019-04-09T01:30:57.491Z,1554773457.491 [PAR_Licor] Data Fault, FailCount= 2
2019-04-09T01:30:57.491Z,1554773457.491 [PAR_Licor](ERROR): Data Fault
2019-04-09T01:30:57.548Z,1554773457.548 [BR_Ping1D](INFO): Starting
2019-04-09T01:30:57.548Z,1554773457.548 [BR_Ping1D](INFO): readConfig
2019-04-09T01:30:57.548Z,1554773457.548 [BR_Ping1D](INFO): XXXX automode1: 1
2019-04-09T01:30:57.549Z,1554773457.549 [BR_Ping1D](INFO): XXXX automode2: 1
2019-04-09T01:30:57.549Z,1554773457.549 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T01:30:57.562Z,1554773457.562 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T01:30:57.562Z,1554773457.562 [BR_Ping1D](INFO): packet size:10
2019-04-09T01:30:57.562Z,1554773457.562 [BR_Ping1D](INFO): 66
2019-04-09T01:30:57.562Z,1554773457.562 [BR_Ping1D](INFO): 82
2019-04-09T01:30:57.563Z,1554773457.563 [BR_Ping1D](INFO): 2
2019-04-09T01:30:57.563Z,1554773457.563 [BR_Ping1D](INFO): 0
2019-04-09T01:30:57.563Z,1554773457.563 [BR_Ping1D](INFO): 236
2019-04-09T01:30:57.563Z,1554773457.563 [BR_Ping1D](INFO): 3
2019-04-09T01:30:57.563Z,1554773457.563 [BR_Ping1D](INFO): 0
2019-04-09T01:30:57.563Z,1554773457.563 [BR_Ping1D](INFO): 0
2019-04-09T01:30:57.563Z,1554773457.563 [BR_Ping1D](INFO): 1
2019-04-09T01:30:57.563Z,1554773457.563 [BR_Ping1D](INFO): 30
2019-04-09T01:30:57.893Z,1554773457.893 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T01:30:57.894Z,1554773457.894 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T01:30:57.894Z,1554773457.894 [PAR_Licor] No Fault, FailCount= 2
2019-04-09T01:30:57.937Z,1554773457.937 [BR_Ping1D](INFO): Starting
2019-04-09T01:30:58.330Z,1554773458.330 [BR_Ping1D](INFO): Starting
2019-04-09T01:30:58.330Z,1554773458.330 [BR_Ping1D](INFO): readConfig
2019-04-09T01:30:58.330Z,1554773458.330 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:30:58.331Z,1554773458.331 [BR_Ping1D](INFO): XXXX automode2: 1
2019-04-09T01:30:58.331Z,1554773458.331 [BR_Ping1D](INFO): UART requesting status
2019-04-09T01:30:58.331Z,1554773458.331 [BR_Ping1D](INFO): packet size:8
2019-04-09T01:30:58.331Z,1554773458.331 [BR_Ping1D](INFO): 66
2019-04-09T01:30:58.331Z,1554773458.331 [BR_Ping1D](INFO): 82
2019-04-09T01:30:58.331Z,1554773458.331 [BR_Ping1D](INFO): 0
2019-04-09T01:30:58.331Z,1554773458.331 [BR_Ping1D](INFO): 0
2019-04-09T01:30:58.331Z,1554773458.331 [BR_Ping1D](INFO): 186
2019-04-09T01:30:58.332Z,1554773458.332 [BR_Ping1D](INFO): 4
2019-04-09T01:30:58.332Z,1554773458.332 [BR_Ping1D](INFO): 0
2019-04-09T01:30:58.332Z,1554773458.332 [BR_Ping1D](INFO): 0
2019-04-09T01:30:58.332Z,1554773458.332 [BR_Ping1D](INFO): packet size:8
2019-04-09T01:30:58.332Z,1554773458.332 [BR_Ping1D](INFO): 66
2019-04-09T01:30:58.332Z,1554773458.332 [BR_Ping1D](INFO): 82
2019-04-09T01:30:58.332Z,1554773458.332 [BR_Ping1D](INFO): 0
2019-04-09T01:30:58.332Z,1554773458.332 [BR_Ping1D](INFO): 0
2019-04-09T01:30:58.333Z,1554773458.333 [BR_Ping1D](INFO): 191
2019-04-09T01:30:58.333Z,1554773458.333 [BR_Ping1D](INFO): 4
2019-04-09T01:30:58.333Z,1554773458.333 [BR_Ping1D](INFO): 0
2019-04-09T01:30:58.333Z,1554773458.333 [BR_Ping1D](INFO): 0
2019-04-09T01:30:58.333Z,1554773458.333 [BR_Ping1D](INFO): packet size:8
2019-04-09T01:30:58.333Z,1554773458.333 [BR_Ping1D](INFO): 66
2019-04-09T01:30:58.333Z,1554773458.333 [BR_Ping1D](INFO): 82
2019-04-09T01:30:58.333Z,1554773458.333 [BR_Ping1D](INFO): 0
2019-04-09T01:30:58.333Z,1554773458.333 [BR_Ping1D](INFO): 0
2019-04-09T01:30:58.334Z,1554773458.334 [BR_Ping1D](INFO): 179
2019-04-09T01:30:58.334Z,1554773458.334 [BR_Ping1D](INFO): 4
2019-04-09T01:30:58.335Z,1554773458.335 [BR_Ping1D](INFO): 0
2019-04-09T01:30:58.335Z,1554773458.335 [BR_Ping1D](INFO): 0
2019-04-09T01:30:58.838Z,1554773458.838 [PAR_Licor](INFO): A/D timeout, 9 tries over 130 ms

2019-04-09T01:30:58.838Z,1554773458.838 [PAR_Licor] Data Fault, FailCount= 1
2019-04-09T01:30:58.838Z,1554773458.838 [PAR_Licor](ERROR): Data Fault
2019-04-09T01:30:58.881Z,1554773458.881 [BR_Ping1D](INFO): Starting
2019-04-09T01:30:58.881Z,1554773458.881 [BR_Ping1D](INFO): readConfig
2019-04-09T01:30:58.882Z,1554773458.882 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:30:58.882Z,1554773458.882 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:30:58.882Z,1554773458.882 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:30:58.882Z,1554773458.882 [BR_Ping1D](INFO): elapsed1.3333
2019-04-09T01:30:58.882Z,1554773458.882 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:30:58.883Z,1554773458.883 [BR_Ping1D](INFO): No UART data
2019-04-09T01:30:58.911Z,1554773458.911 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T01:30:58.911Z,1554773458.911 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T01:30:58.911Z,1554773458.911 [PAR_Licor] No Fault, FailCount= 1
2019-04-09T01:30:59.134Z,1554773459.134 [BR_Ping1D](INFO): Starting
2019-04-09T01:30:59.134Z,1554773459.134 [BR_Ping1D](INFO): readConfig
2019-04-09T01:30:59.134Z,1554773459.134 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:30:59.135Z,1554773459.135 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:30:59.135Z,1554773459.135 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:30:59.135Z,1554773459.135 [BR_Ping1D](INFO): elapsed1.5861
2019-04-09T01:30:59.135Z,1554773459.135 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:30:59.135Z,1554773459.135 [BR_Ping1D](INFO): No UART data
2019-04-09T01:30:59.478Z,1554773459.478 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T01:30:59.478Z,1554773459.478 [MassServo] Communications Fault, FailCount= 1
2019-04-09T01:30:59.478Z,1554773459.478 [MassServo](ERROR): Communications Fault
2019-04-09T01:30:59.530Z,1554773459.530 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T01:30:59.579Z,1554773459.579 [BR_Ping1D](INFO): Starting
2019-04-09T01:30:59.579Z,1554773459.579 [BR_Ping1D](INFO): readConfig
2019-04-09T01:30:59.579Z,1554773459.579 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:30:59.579Z,1554773459.579 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:30:59.579Z,1554773459.579 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:30:59.580Z,1554773459.580 [BR_Ping1D](INFO): elapsed2.0307
2019-04-09T01:30:59.580Z,1554773459.580 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:30:59.580Z,1554773459.580 [BR_Ping1D](INFO): No UART data
2019-04-09T01:30:59.686Z,1554773459.686 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T01:30:59.686Z,1554773459.686 [MassServo](INFO): Powering down
2019-04-09T01:31:00.074Z,1554773460.074 [PAR_Licor](INFO): A/D timeout, 8 tries over 135 ms

2019-04-09T01:31:00.074Z,1554773460.074 [PAR_Licor] Data Fault, FailCount= 1
2019-04-09T01:31:00.074Z,1554773460.074 [PAR_Licor](ERROR): Data Fault
2019-04-09T01:31:00.128Z,1554773460.128 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:00.128Z,1554773460.128 [BR_Ping1D](INFO): readConfig
2019-04-09T01:31:00.128Z,1554773460.128 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:31:00.128Z,1554773460.128 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:31:00.128Z,1554773460.128 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:31:00.129Z,1554773460.129 [BR_Ping1D](INFO): elapsed2.5799
2019-04-09T01:31:00.129Z,1554773460.129 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:31:00.129Z,1554773460.129 [BR_Ping1D](INFO): No UART data
2019-04-09T01:31:00.254Z,1554773460.254 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T01:31:00.254Z,1554773460.254 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T01:31:00.254Z,1554773460.254 [PAR_Licor] No Fault, FailCount= 1
2019-04-09T01:31:00.379Z,1554773460.379 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:00.380Z,1554773460.380 [BR_Ping1D](INFO): readConfig
2019-04-09T01:31:00.380Z,1554773460.380 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:31:00.380Z,1554773460.380 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:31:00.380Z,1554773460.380 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:31:00.380Z,1554773460.380 [BR_Ping1D](INFO): elapsed2.8315
2019-04-09T01:31:00.380Z,1554773460.380 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:31:00.381Z,1554773460.381 [BR_Ping1D](INFO): No UART data
2019-04-09T01:31:00.472Z,1554773460.472 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T01:31:00.591Z,1554773460.591 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T01:31:00.898Z,1554773460.898 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T01:31:00.898Z,1554773460.898 [MassServo] No Fault, FailCount= 1
2019-04-09T01:31:01.046Z,1554773461.046 [PAR_Licor](INFO): A/D timeout, 7 tries over 141 ms

2019-04-09T01:31:01.046Z,1554773461.046 [PAR_Licor] Data Fault, FailCount= 2
2019-04-09T01:31:01.046Z,1554773461.046 [PAR_Licor](ERROR): Data Fault
2019-04-09T01:31:01.080Z,1554773461.080 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:01.080Z,1554773461.080 [BR_Ping1D](INFO): readConfig
2019-04-09T01:31:01.080Z,1554773461.080 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:31:01.080Z,1554773461.080 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:31:01.080Z,1554773461.080 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:31:01.081Z,1554773461.081 [BR_Ping1D](INFO): elapsed3.5318
2019-04-09T01:31:01.081Z,1554773461.081 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:31:01.081Z,1554773461.081 [BR_Ping1D](INFO): No UART data
2019-04-09T01:31:01.132Z,1554773461.132 [CBIT](FAULT): Main Battery Failure. Count: 11
2019-04-09T01:31:01.142Z,1554773461.142 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T01:31:01.143Z,1554773461.143 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T01:31:01.143Z,1554773461.143 [PAR_Licor] No Fault, FailCount= 2
2019-04-09T01:31:01.330Z,1554773461.330 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:01.330Z,1554773461.330 [BR_Ping1D](INFO): readConfig
2019-04-09T01:31:01.331Z,1554773461.331 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:31:01.331Z,1554773461.331 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:31:01.331Z,1554773461.331 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:31:01.331Z,1554773461.331 [BR_Ping1D](INFO): elapsed3.7823
2019-04-09T01:31:01.331Z,1554773461.331 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:31:01.332Z,1554773461.332 [BR_Ping1D](INFO): No UART data
2019-04-09T01:31:01.854Z,1554773461.854 [PAR_Licor](INFO): A/D timeout, 9 tries over 142 ms

2019-04-09T01:31:01.854Z,1554773461.854 [PAR_Licor] Data Fault, FailCount= 3
2019-04-09T01:31:01.854Z,1554773461.854 [PAR_Licor](ERROR): Data Fault
2019-04-09T01:31:01.898Z,1554773461.898 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:01.898Z,1554773461.898 [BR_Ping1D](INFO): readConfig
2019-04-09T01:31:01.898Z,1554773461.898 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:31:01.898Z,1554773461.898 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:31:01.898Z,1554773461.898 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:31:01.899Z,1554773461.899 [BR_Ping1D](INFO): elapsed4.3499
2019-04-09T01:31:01.899Z,1554773461.899 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:31:01.899Z,1554773461.899 [BR_Ping1D](INFO): No UART data
2019-04-09T01:31:01.953Z,1554773461.953 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T01:31:01.962Z,1554773461.962 [CBIT](CRITICAL): Data Fault in component: PAR_Licor
2019-04-09T01:31:02.147Z,1554773462.147 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:02.147Z,1554773462.147 [BR_Ping1D](INFO): readConfig
2019-04-09T01:31:02.147Z,1554773462.147 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:31:02.147Z,1554773462.147 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:31:02.147Z,1554773462.147 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:31:02.148Z,1554773462.148 [BR_Ping1D](INFO): elapsed4.5989
2019-04-09T01:31:02.148Z,1554773462.148 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:31:02.148Z,1554773462.148 [BR_Ping1D](INFO): No UART data
2019-04-09T01:31:02.182Z,1554773462.182 [CBIT](INFO): Critical error at 20190409T013101
2019-04-09T01:31:02.537Z,1554773462.537 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:02.537Z,1554773462.537 [BR_Ping1D](INFO): readConfig
2019-04-09T01:31:02.537Z,1554773462.537 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:31:02.538Z,1554773462.538 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:31:02.538Z,1554773462.538 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:31:02.538Z,1554773462.538 [BR_Ping1D](INFO): elapsed4.9893
2019-04-09T01:31:02.538Z,1554773462.538 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:31:02.538Z,1554773462.538 [BR_Ping1D](INFO): No UART data
2019-04-09T01:31:02.941Z,1554773462.941 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:02.942Z,1554773462.942 [BR_Ping1D](INFO): readConfig
2019-04-09T01:31:02.942Z,1554773462.942 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:31:02.942Z,1554773462.942 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:31:02.942Z,1554773462.942 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:31:02.942Z,1554773462.942 [BR_Ping1D](INFO): elapsed5.3937
2019-04-09T01:31:02.943Z,1554773462.943 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:31:02.943Z,1554773462.943 [BR_Ping1D] Data Fault, FailCount= 1
2019-04-09T01:31:02.943Z,1554773462.943 [BR_Ping1D](ERROR): Data Fault
2019-04-09T01:31:03.474Z,1554773463.474 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T01:31:03.474Z,1554773463.474 [MassServo] Communications Fault, FailCount= 2
2019-04-09T01:31:03.474Z,1554773463.474 [MassServo](ERROR): Communications Fault
2019-04-09T01:31:03.478Z,1554773463.478 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T01:31:03.479Z,1554773463.479 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T01:31:03.502Z,1554773463.502 [BR_Ping1D](INFO): Stop
2019-04-09T01:31:03.502Z,1554773463.502 [BR_Ping1D](INFO): uninitialize
2019-04-09T01:31:03.659Z,1554773463.659 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T01:31:03.659Z,1554773463.659 [MassServo](INFO): Powering down
2019-04-09T01:31:03.907Z,1554773463.907 [BR_Ping1D](INFO): Stopping
2019-04-09T01:31:04.309Z,1554773464.309 [BR_Ping1D](INFO): Stopping
2019-04-09T01:31:04.319Z,1554773464.319 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T01:31:04.439Z,1554773464.439 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T01:31:04.443Z,1554773464.443 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T01:31:04.443Z,1554773464.443 [MassServo] No Fault, FailCount= 2
2019-04-09T01:31:04.714Z,1554773464.714 [BR_Ping1D](INFO): Stopped
2019-04-09T01:31:04.747Z,1554773464.747 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T01:31:04.747Z,1554773464.747 [BR_Ping1D] No Fault, FailCount= 1
2019-04-09T01:31:05.117Z,1554773465.117 [BR_Ping1D](INFO): Start
2019-04-09T01:31:05.219Z,1554773465.219 [CBIT](FAULT): Main Battery Failure. Count: 21
2019-04-09T01:31:05.522Z,1554773465.522 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:05.926Z,1554773465.926 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:05.926Z,1554773465.926 [BR_Ping1D](INFO): readConfig
2019-04-09T01:31:05.927Z,1554773465.927 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:31:05.927Z,1554773465.927 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:31:05.927Z,1554773465.927 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T01:31:05.938Z,1554773465.938 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T01:31:05.938Z,1554773465.938 [BR_Ping1D](INFO): packet size:10
2019-04-09T01:31:05.938Z,1554773465.938 [BR_Ping1D](INFO): 66
2019-04-09T01:31:05.938Z,1554773465.938 [BR_Ping1D](INFO): 82
2019-04-09T01:31:05.938Z,1554773465.938 [BR_Ping1D](INFO): 2
2019-04-09T01:31:05.938Z,1554773465.938 [BR_Ping1D](INFO): 0
2019-04-09T01:31:05.939Z,1554773465.939 [BR_Ping1D](INFO): 236
2019-04-09T01:31:05.939Z,1554773465.939 [BR_Ping1D](INFO): 3
2019-04-09T01:31:05.939Z,1554773465.939 [BR_Ping1D](INFO): 0
2019-04-09T01:31:05.939Z,1554773465.939 [BR_Ping1D](INFO): 0
2019-04-09T01:31:05.939Z,1554773465.939 [BR_Ping1D](INFO): 1
2019-04-09T01:31:05.939Z,1554773465.939 [BR_Ping1D](INFO): 30
2019-04-09T01:31:05.955Z,1554773465.955 [CBIT](CRITICAL): Environmental Failure. Press:14.847911 PSI. Humidity:44%. Temp:26 C. ABORTING MISSION
2019-04-09T01:31:06.310Z,1554773466.310 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T01:31:06.310Z,1554773466.310 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-04-09T01:31:06.310Z,1554773466.310 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T01:31:06.330Z,1554773466.330 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:06.345Z,1554773466.345 [CBIT](INFO): Critical error at 20190409T013105
2019-04-09T01:31:06.347Z,1554773466.347 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T01:31:06.834Z,1554773466.834 [Aanderaa_O2](INFO): Powering down
2019-04-09T01:31:06.897Z,1554773466.897 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:06.897Z,1554773466.897 [BR_Ping1D](INFO): readConfig
2019-04-09T01:31:06.898Z,1554773466.898 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:31:06.898Z,1554773466.898 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:31:06.898Z,1554773466.898 [BR_Ping1D](INFO): UART requesting status
2019-04-09T01:31:06.898Z,1554773466.898 [BR_Ping1D](INFO): packet size:8
2019-04-09T01:31:06.898Z,1554773466.898 [BR_Ping1D](INFO): 66
2019-04-09T01:31:06.898Z,1554773466.898 [BR_Ping1D](INFO): 82
2019-04-09T01:31:06.899Z,1554773466.899 [BR_Ping1D](INFO): 0
2019-04-09T01:31:06.899Z,1554773466.899 [BR_Ping1D](INFO): 0
2019-04-09T01:31:06.899Z,1554773466.899 [BR_Ping1D](INFO): 186
2019-04-09T01:31:06.899Z,1554773466.899 [BR_Ping1D](INFO): 4
2019-04-09T01:31:06.899Z,1554773466.899 [BR_Ping1D](INFO): 0
2019-04-09T01:31:06.899Z,1554773466.899 [BR_Ping1D](INFO): 0
2019-04-09T01:31:06.900Z,1554773466.900 [BR_Ping1D](INFO): packet size:8
2019-04-09T01:31:06.901Z,1554773466.901 [BR_Ping1D](INFO): 66
2019-04-09T01:31:06.901Z,1554773466.901 [BR_Ping1D](INFO): 82
2019-04-09T01:31:06.901Z,1554773466.901 [BR_Ping1D](INFO): 0
2019-04-09T01:31:06.901Z,1554773466.901 [BR_Ping1D](INFO): 0
2019-04-09T01:31:06.901Z,1554773466.901 [BR_Ping1D](INFO): 191
2019-04-09T01:31:06.901Z,1554773466.901 [BR_Ping1D](INFO): 4
2019-04-09T01:31:06.901Z,1554773466.901 [BR_Ping1D](INFO): 0
2019-04-09T01:31:06.901Z,1554773466.901 [BR_Ping1D](INFO): 0
2019-04-09T01:31:06.910Z,1554773466.910 [BR_Ping1D](INFO): packet size:8
2019-04-09T01:31:06.910Z,1554773466.910 [BR_Ping1D](INFO): 66
2019-04-09T01:31:06.910Z,1554773466.910 [BR_Ping1D](INFO): 82
2019-04-09T01:31:06.910Z,1554773466.910 [BR_Ping1D](INFO): 0
2019-04-09T01:31:06.910Z,1554773466.910 [BR_Ping1D](INFO): 0
2019-04-09T01:31:06.910Z,1554773466.910 [BR_Ping1D](INFO): 179
2019-04-09T01:31:06.911Z,1554773466.911 [BR_Ping1D](INFO): 4
2019-04-09T01:31:06.911Z,1554773466.911 [BR_Ping1D](INFO): 0
2019-04-09T01:31:06.911Z,1554773466.911 [BR_Ping1D](INFO): 0
2019-04-09T01:31:07.242Z,1554773467.242 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T01:31:07.242Z,1554773467.242 [MassServo] Communications Fault, FailCount= 3
2019-04-09T01:31:07.242Z,1554773467.242 [MassServo](ERROR): Communications Fault
2019-04-09T01:31:07.246Z,1554773467.246 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T01:31:07.270Z,1554773467.270 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:07.270Z,1554773467.270 [BR_Ping1D](INFO): readConfig
2019-04-09T01:31:07.270Z,1554773467.270 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:31:07.270Z,1554773467.270 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:31:07.270Z,1554773467.270 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:31:07.271Z,1554773467.271 [BR_Ping1D](INFO): elapsed1.3435
2019-04-09T01:31:07.271Z,1554773467.271 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:31:07.271Z,1554773467.271 [BR_Ping1D](INFO): No UART data
2019-04-09T01:31:07.315Z,1554773467.315 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T01:31:07.315Z,1554773467.315 [MassServo](INFO): Powering down
2019-04-09T01:31:07.673Z,1554773467.673 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:07.673Z,1554773467.673 [BR_Ping1D](INFO): readConfig
2019-04-09T01:31:07.674Z,1554773467.674 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:31:07.674Z,1554773467.674 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:31:07.674Z,1554773467.674 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:31:07.674Z,1554773467.674 [BR_Ping1D](INFO): elapsed1.7470
2019-04-09T01:31:07.674Z,1554773467.674 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:31:07.675Z,1554773467.675 [BR_Ping1D](INFO): No UART data
2019-04-09T01:31:07.706Z,1554773467.706 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T01:31:07.707Z,1554773467.707 [Aanderaa_O2] No Fault, FailCount= 1
2019-04-09T01:31:08.078Z,1554773468.078 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:08.078Z,1554773468.078 [BR_Ping1D](INFO): readConfig
2019-04-09T01:31:08.078Z,1554773468.078 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:31:08.079Z,1554773468.079 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:31:08.079Z,1554773468.079 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:31:08.079Z,1554773468.079 [BR_Ping1D](INFO): elapsed2.1519
2019-04-09T01:31:08.079Z,1554773468.079 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:31:08.079Z,1554773468.079 [BR_Ping1D](INFO): No UART data
2019-04-09T01:31:08.091Z,1554773468.091 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T01:31:08.211Z,1554773468.211 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T01:31:08.216Z,1554773468.216 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T01:31:08.216Z,1554773468.216 [MassServo] No Fault, FailCount= 3
2019-04-09T01:31:08.493Z,1554773468.493 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:08.493Z,1554773468.493 [BR_Ping1D](INFO): readConfig
2019-04-09T01:31:08.493Z,1554773468.493 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:31:08.494Z,1554773468.494 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:31:08.494Z,1554773468.494 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:31:08.494Z,1554773468.494 [BR_Ping1D](INFO): elapsed2.5669
2019-04-09T01:31:08.494Z,1554773468.494 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:31:08.494Z,1554773468.494 [BR_Ping1D](INFO): No UART data
2019-04-09T01:31:08.886Z,1554773468.886 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:08.886Z,1554773468.886 [BR_Ping1D](INFO): readConfig
2019-04-09T01:31:08.886Z,1554773468.886 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:31:08.886Z,1554773468.886 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:31:08.886Z,1554773468.886 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:31:08.887Z,1554773468.887 [BR_Ping1D](INFO): elapsed2.9594
2019-04-09T01:31:08.887Z,1554773468.887 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:31:08.887Z,1554773468.887 [BR_Ping1D](INFO): No UART data
2019-04-09T01:31:08.890Z,1554773468.890 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T01:31:08.890Z,1554773468.890 [CTD_Seabird] Communications Fault, FailCount= 1
2019-04-09T01:31:08.890Z,1554773468.890 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T01:31:08.920Z,1554773468.920 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T01:31:09.290Z,1554773469.290 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:09.290Z,1554773469.290 [BR_Ping1D](INFO): readConfig
2019-04-09T01:31:09.290Z,1554773469.290 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:31:09.290Z,1554773469.290 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:31:09.290Z,1554773469.290 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:31:09.291Z,1554773469.291 [BR_Ping1D](INFO): elapsed3.3634
2019-04-09T01:31:09.291Z,1554773469.291 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:31:09.291Z,1554773469.291 [BR_Ping1D](INFO): No UART data
2019-04-09T01:31:09.294Z,1554773469.294 [CTD_Seabird](INFO): Powering down
2019-04-09T01:31:09.347Z,1554773469.347 [CBIT](FAULT): Main Battery Failure. Count: 31
2019-04-09T01:31:09.694Z,1554773469.694 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:09.694Z,1554773469.694 [BR_Ping1D](INFO): readConfig
2019-04-09T01:31:09.694Z,1554773469.694 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:31:09.694Z,1554773469.694 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:31:09.694Z,1554773469.694 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:31:09.695Z,1554773469.695 [BR_Ping1D](INFO): elapsed3.7674
2019-04-09T01:31:09.695Z,1554773469.695 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:31:09.695Z,1554773469.695 [BR_Ping1D](INFO): No UART data
2019-04-09T01:31:10.097Z,1554773470.097 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:10.097Z,1554773470.097 [BR_Ping1D](INFO): readConfig
2019-04-09T01:31:10.098Z,1554773470.098 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:31:10.098Z,1554773470.098 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:31:10.098Z,1554773470.098 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:31:10.098Z,1554773470.098 [BR_Ping1D](INFO): elapsed4.1710
2019-04-09T01:31:10.098Z,1554773470.098 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:31:10.099Z,1554773470.099 [BR_Ping1D](INFO): No UART data
2019-04-09T01:31:10.502Z,1554773470.502 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:10.502Z,1554773470.502 [BR_Ping1D](INFO): readConfig
2019-04-09T01:31:10.503Z,1554773470.503 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:31:10.503Z,1554773470.503 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:31:10.503Z,1554773470.503 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:31:10.503Z,1554773470.503 [BR_Ping1D](INFO): elapsed4.5760
2019-04-09T01:31:10.503Z,1554773470.503 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:31:10.504Z,1554773470.504 [BR_Ping1D](INFO): No UART data
2019-04-09T01:31:10.826Z,1554773470.826 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T01:31:10.826Z,1554773470.826 [MassServo] Communications Fault, FailCount= 4
2019-04-09T01:31:10.826Z,1554773470.826 [MassServo](ERROR): Communications Fault
2019-04-09T01:31:10.831Z,1554773470.831 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T01:31:10.831Z,1554773470.831 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T01:31:10.831Z,1554773470.831 [CTD_Seabird] No Fault, FailCount= 1
2019-04-09T01:31:10.905Z,1554773470.905 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:10.905Z,1554773470.905 [BR_Ping1D](INFO): readConfig
2019-04-09T01:31:10.906Z,1554773470.906 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:31:10.906Z,1554773470.906 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:31:10.906Z,1554773470.906 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:31:10.906Z,1554773470.906 [BR_Ping1D](INFO): elapsed4.9791
2019-04-09T01:31:10.906Z,1554773470.906 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:31:10.907Z,1554773470.907 [BR_Ping1D](INFO): No UART data
2019-04-09T01:31:10.919Z,1554773470.919 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T01:31:10.919Z,1554773470.919 [MassServo](INFO): Powering down
2019-04-09T01:31:11.150Z,1554773471.150 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T01:31:11.152Z,1554773471.152 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T01:31:11.311Z,1554773471.311 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:11.311Z,1554773471.311 [BR_Ping1D](INFO): readConfig
2019-04-09T01:31:11.311Z,1554773471.311 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:31:11.311Z,1554773471.311 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:31:11.311Z,1554773471.311 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:31:11.311Z,1554773471.311 [BR_Ping1D](INFO): elapsed5.3843
2019-04-09T01:31:11.312Z,1554773471.312 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:31:11.312Z,1554773471.312 [BR_Ping1D] Data Fault, FailCount= 2
2019-04-09T01:31:11.312Z,1554773471.312 [BR_Ping1D](ERROR): Data Fault
2019-04-09T01:31:11.327Z,1554773471.327 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T01:31:11.714Z,1554773471.714 [BR_Ping1D](INFO): Stop
2019-04-09T01:31:11.714Z,1554773471.714 [BR_Ping1D](INFO): uninitialize
2019-04-09T01:31:11.800Z,1554773471.800 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T01:31:11.919Z,1554773471.919 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T01:31:11.924Z,1554773471.924 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T01:31:11.924Z,1554773471.924 [MassServo] No Fault, FailCount= 4
2019-04-09T01:31:12.118Z,1554773472.118 [BR_Ping1D](INFO): Stopping
2019-04-09T01:31:12.522Z,1554773472.522 [BR_Ping1D](INFO): Stopping
2019-04-09T01:31:12.926Z,1554773472.926 [BR_Ping1D](INFO): Stopped
2019-04-09T01:31:12.977Z,1554773472.977 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T01:31:12.978Z,1554773472.978 [BR_Ping1D] No Fault, FailCount= 2
2019-04-09T01:31:13.329Z,1554773473.329 [BR_Ping1D](INFO): Start
2019-04-09T01:31:13.374Z,1554773473.374 [CBIT](FAULT): Main Battery Failure. Count: 41
2019-04-09T01:31:13.734Z,1554773473.734 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:14.138Z,1554773474.138 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:14.138Z,1554773474.138 [BR_Ping1D](INFO): readConfig
2019-04-09T01:31:14.138Z,1554773474.138 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:31:14.138Z,1554773474.138 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:31:14.138Z,1554773474.138 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T01:31:14.150Z,1554773474.150 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T01:31:14.150Z,1554773474.150 [BR_Ping1D](INFO): packet size:10
2019-04-09T01:31:14.150Z,1554773474.150 [BR_Ping1D](INFO): 66
2019-04-09T01:31:14.150Z,1554773474.150 [BR_Ping1D](INFO): 82
2019-04-09T01:31:14.150Z,1554773474.150 [BR_Ping1D](INFO): 2
2019-04-09T01:31:14.151Z,1554773474.151 [BR_Ping1D](INFO): 0
2019-04-09T01:31:14.151Z,1554773474.151 [BR_Ping1D](INFO): 236
2019-04-09T01:31:14.151Z,1554773474.151 [BR_Ping1D](INFO): 3
2019-04-09T01:31:14.151Z,1554773474.151 [BR_Ping1D](INFO): 0
2019-04-09T01:31:14.151Z,1554773474.151 [BR_Ping1D](INFO): 0
2019-04-09T01:31:14.151Z,1554773474.151 [BR_Ping1D](INFO): 1
2019-04-09T01:31:14.151Z,1554773474.151 [BR_Ping1D](INFO): 30
2019-04-09T01:31:14.474Z,1554773474.474 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T01:31:14.474Z,1554773474.474 [MassServo] Communications Fault, FailCount= 5
2019-04-09T01:31:14.474Z,1554773474.474 [MassServo](ERROR): Communications Fault
2019-04-09T01:31:14.478Z,1554773474.478 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T01:31:14.479Z,1554773474.479 [CBIT](CRITICAL): Communications Fault in component: MassServo
2019-04-09T01:31:14.542Z,1554773474.542 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:14.567Z,1554773474.567 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T01:31:14.567Z,1554773474.567 [MassServo](INFO): Powering down
2019-04-09T01:31:14.569Z,1554773474.569 [CBIT](INFO): Critical error at 20190409T013114
2019-04-09T01:31:14.945Z,1554773474.945 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:14.945Z,1554773474.945 [BR_Ping1D](INFO): readConfig
2019-04-09T01:31:14.946Z,1554773474.946 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:31:14.946Z,1554773474.946 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:31:14.946Z,1554773474.946 [BR_Ping1D](INFO): UART requesting status
2019-04-09T01:31:14.946Z,1554773474.946 [BR_Ping1D](INFO): packet size:8
2019-04-09T01:31:14.946Z,1554773474.946 [BR_Ping1D](INFO): 66
2019-04-09T01:31:14.946Z,1554773474.946 [BR_Ping1D](INFO): 82
2019-04-09T01:31:14.946Z,1554773474.946 [BR_Ping1D](INFO): 0
2019-04-09T01:31:14.947Z,1554773474.947 [BR_Ping1D](INFO): 0
2019-04-09T01:31:14.947Z,1554773474.947 [BR_Ping1D](INFO): 186
2019-04-09T01:31:14.947Z,1554773474.947 [BR_Ping1D](INFO): 4
2019-04-09T01:31:14.947Z,1554773474.947 [BR_Ping1D](INFO): 0
2019-04-09T01:31:14.947Z,1554773474.947 [BR_Ping1D](INFO): 0
2019-04-09T01:31:14.947Z,1554773474.947 [BR_Ping1D](INFO): packet size:8
2019-04-09T01:31:14.947Z,1554773474.947 [BR_Ping1D](INFO): 66
2019-04-09T01:31:14.947Z,1554773474.947 [BR_Ping1D](INFO): 82
2019-04-09T01:31:14.947Z,1554773474.947 [BR_Ping1D](INFO): 0
2019-04-09T01:31:14.948Z,1554773474.948 [BR_Ping1D](INFO): 0
2019-04-09T01:31:14.948Z,1554773474.948 [BR_Ping1D](INFO): 191
2019-04-09T01:31:14.948Z,1554773474.948 [BR_Ping1D](INFO): 4
2019-04-09T01:31:14.948Z,1554773474.948 [BR_Ping1D](INFO): 0
2019-04-09T01:31:14.948Z,1554773474.948 [BR_Ping1D](INFO): 0
2019-04-09T01:31:14.948Z,1554773474.948 [BR_Ping1D](INFO): packet size:8
2019-04-09T01:31:14.948Z,1554773474.948 [BR_Ping1D](INFO): 66
2019-04-09T01:31:14.948Z,1554773474.948 [BR_Ping1D](INFO): 82
2019-04-09T01:31:14.948Z,1554773474.948 [BR_Ping1D](INFO): 0
2019-04-09T01:31:14.949Z,1554773474.949 [BR_Ping1D](INFO): 0
2019-04-09T01:31:14.949Z,1554773474.949 [BR_Ping1D](INFO): 179
2019-04-09T01:31:14.949Z,1554773474.949 [BR_Ping1D](INFO): 4
2019-04-09T01:31:14.949Z,1554773474.949 [BR_Ping1D](INFO): 0
2019-04-09T01:31:14.949Z,1554773474.949 [BR_Ping1D](INFO): 0
2019-04-09T01:31:15.362Z,1554773475.362 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:15.362Z,1554773475.362 [BR_Ping1D](INFO): readConfig
2019-04-09T01:31:15.363Z,1554773475.363 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:31:15.363Z,1554773475.363 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:31:15.363Z,1554773475.363 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:31:15.363Z,1554773475.363 [BR_Ping1D](INFO): elapsed1.2246
2019-04-09T01:31:15.363Z,1554773475.363 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:31:15.364Z,1554773475.364 [BR_Ping1D](INFO): No UART data
2019-04-09T01:31:15.375Z,1554773475.375 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T01:31:15.495Z,1554773475.495 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T01:31:15.753Z,1554773475.753 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:15.754Z,1554773475.754 [BR_Ping1D](INFO): readConfig
2019-04-09T01:31:15.754Z,1554773475.754 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:31:15.754Z,1554773475.754 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:31:15.754Z,1554773475.754 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:31:15.754Z,1554773475.754 [BR_Ping1D](INFO): elapsed1.6158
2019-04-09T01:31:15.755Z,1554773475.755 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:31:15.755Z,1554773475.755 [BR_Ping1D](INFO): No UART data
2019-04-09T01:31:16.158Z,1554773476.158 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:16.158Z,1554773476.158 [BR_Ping1D](INFO): readConfig
2019-04-09T01:31:16.158Z,1554773476.158 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:31:16.159Z,1554773476.159 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:31:16.159Z,1554773476.159 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:31:16.159Z,1554773476.159 [BR_Ping1D](INFO): elapsed2.0203
2019-04-09T01:31:16.159Z,1554773476.159 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:31:16.159Z,1554773476.159 [BR_Ping1D](INFO): No UART data
2019-04-09T01:31:16.562Z,1554773476.562 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:16.562Z,1554773476.562 [BR_Ping1D](INFO): readConfig
2019-04-09T01:31:16.562Z,1554773476.562 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:31:16.563Z,1554773476.563 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:31:16.563Z,1554773476.563 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:31:16.563Z,1554773476.563 [BR_Ping1D](INFO): elapsed2.4243
2019-04-09T01:31:16.563Z,1554773476.563 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:31:16.563Z,1554773476.563 [BR_Ping1D](INFO): No UART data
2019-04-09T01:31:16.947Z,1554773476.947 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T01:31:16.947Z,1554773476.947 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 1
2019-04-09T01:31:16.947Z,1554773476.947 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T01:31:16.966Z,1554773476.966 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:16.966Z,1554773476.966 [BR_Ping1D](INFO): readConfig
2019-04-09T01:31:16.966Z,1554773476.966 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:31:16.966Z,1554773476.966 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:31:16.966Z,1554773476.966 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:31:16.967Z,1554773476.967 [BR_Ping1D](INFO): elapsed2.8281
2019-04-09T01:31:16.967Z,1554773476.967 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:31:16.967Z,1554773476.967 [BR_Ping1D](INFO): No UART data
2019-04-09T01:31:16.995Z,1554773476.995 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T01:31:17.351Z,1554773477.351 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T01:31:17.510Z,1554773477.510 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:17.510Z,1554773477.510 [BR_Ping1D](INFO): readConfig
2019-04-09T01:31:17.510Z,1554773477.510 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:31:17.510Z,1554773477.510 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:31:17.510Z,1554773477.510 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:31:17.511Z,1554773477.511 [BR_Ping1D](INFO): elapsed3.3719
2019-04-09T01:31:17.511Z,1554773477.511 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:31:17.511Z,1554773477.511 [BR_Ping1D](INFO): No UART data
2019-04-09T01:31:17.539Z,1554773477.539 [CBIT](FAULT): Main Battery Failure. Count: 51
2019-04-09T01:31:17.774Z,1554773477.774 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:17.774Z,1554773477.774 [BR_Ping1D](INFO): readConfig
2019-04-09T01:31:17.775Z,1554773477.775 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:31:17.775Z,1554773477.775 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:31:17.775Z,1554773477.775 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:31:17.775Z,1554773477.775 [BR_Ping1D](INFO): elapsed3.6367
2019-04-09T01:31:17.775Z,1554773477.775 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:31:17.776Z,1554773477.776 [BR_Ping1D](INFO): No UART data
2019-04-09T01:31:18.158Z,1554773478.158 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T01:31:18.158Z,1554773478.158 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-04-09T01:31:18.159Z,1554773478.159 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T01:31:18.177Z,1554773478.177 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:18.177Z,1554773478.177 [BR_Ping1D](INFO): readConfig
2019-04-09T01:31:18.178Z,1554773478.178 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:31:18.178Z,1554773478.178 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:31:18.179Z,1554773478.179 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:31:18.179Z,1554773478.179 [BR_Ping1D](INFO): elapsed4.0403
2019-04-09T01:31:18.179Z,1554773478.179 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:31:18.179Z,1554773478.179 [BR_Ping1D](INFO): No UART data
2019-04-09T01:31:18.231Z,1554773478.231 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T01:31:18.231Z,1554773478.231 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T01:31:18.231Z,1554773478.231 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 1
2019-04-09T01:31:18.682Z,1554773478.682 [Aanderaa_O2](INFO): Powering down
2019-04-09T01:31:18.702Z,1554773478.702 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:18.702Z,1554773478.702 [BR_Ping1D](INFO): readConfig
2019-04-09T01:31:18.703Z,1554773478.703 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:31:18.703Z,1554773478.703 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:31:18.703Z,1554773478.703 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:31:18.703Z,1554773478.703 [BR_Ping1D](INFO): elapsed4.5645
2019-04-09T01:31:18.703Z,1554773478.703 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:31:18.703Z,1554773478.703 [BR_Ping1D](INFO): No UART data
2019-04-09T01:31:18.985Z,1554773478.985 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:18.986Z,1554773478.986 [BR_Ping1D](INFO): readConfig
2019-04-09T01:31:18.986Z,1554773478.986 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:31:18.986Z,1554773478.986 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:31:18.987Z,1554773478.987 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:31:18.987Z,1554773478.987 [BR_Ping1D](INFO): elapsed4.8482
2019-04-09T01:31:18.987Z,1554773478.987 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:31:18.987Z,1554773478.987 [BR_Ping1D](INFO): No UART data
2019-04-09T01:31:19.390Z,1554773479.390 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:19.390Z,1554773479.390 [BR_Ping1D](INFO): readConfig
2019-04-09T01:31:19.390Z,1554773479.390 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:31:19.390Z,1554773479.390 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:31:19.391Z,1554773479.391 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:31:19.391Z,1554773479.391 [BR_Ping1D](INFO): elapsed5.2522
2019-04-09T01:31:19.391Z,1554773479.391 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:31:19.391Z,1554773479.391 [BR_Ping1D] Data Fault, FailCount= 3
2019-04-09T01:31:19.391Z,1554773479.391 [BR_Ping1D](ERROR): Data Fault
2019-04-09T01:31:19.407Z,1554773479.407 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T01:31:19.407Z,1554773479.407 [Aanderaa_O2] No Fault, FailCount= 2
2019-04-09T01:31:19.407Z,1554773479.407 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T01:31:19.801Z,1554773479.801 [BR_Ping1D](INFO): Stop
2019-04-09T01:31:19.802Z,1554773479.802 [BR_Ping1D](INFO): uninitialize
2019-04-09T01:31:20.210Z,1554773480.210 [BR_Ping1D](INFO): Stopping
2019-04-09T01:31:20.228Z,1554773480.228 [SBIT](IMPORTANT): Beginning Startup BIT
2019-04-09T01:31:20.253Z,1554773480.253 [CBIT](IMPORTANT): Beginning ground fault scan
2019-04-09T01:31:20.583Z,1554773480.583 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T01:31:20.584Z,1554773480.584 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T01:31:20.619Z,1554773480.619 [BR_Ping1D](INFO): Stopping
2019-04-09T01:31:21.006Z,1554773481.006 [BR_Ping1D](INFO): Stopped
2019-04-09T01:31:21.075Z,1554773481.075 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T01:31:21.075Z,1554773481.075 [BR_Ping1D] No Fault, FailCount= 3
2019-04-09T01:31:21.409Z,1554773481.409 [BR_Ping1D](INFO): Start
2019-04-09T01:31:21.501Z,1554773481.501 [CBIT](FAULT): Main Battery Failure. Count: 61
2019-04-09T01:31:21.813Z,1554773481.813 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:22.217Z,1554773482.217 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:22.217Z,1554773482.217 [BR_Ping1D](INFO): readConfig
2019-04-09T01:31:22.217Z,1554773482.217 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:31:22.218Z,1554773482.218 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:31:22.218Z,1554773482.218 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T01:31:22.230Z,1554773482.230 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T01:31:22.230Z,1554773482.230 [BR_Ping1D](INFO): packet size:10
2019-04-09T01:31:22.230Z,1554773482.230 [BR_Ping1D](INFO): 66
2019-04-09T01:31:22.230Z,1554773482.230 [BR_Ping1D](INFO): 82
2019-04-09T01:31:22.230Z,1554773482.230 [BR_Ping1D](INFO): 2
2019-04-09T01:31:22.230Z,1554773482.230 [BR_Ping1D](INFO): 0
2019-04-09T01:31:22.231Z,1554773482.231 [BR_Ping1D](INFO): 236
2019-04-09T01:31:22.231Z,1554773482.231 [BR_Ping1D](INFO): 3
2019-04-09T01:31:22.231Z,1554773482.231 [BR_Ping1D](INFO): 0
2019-04-09T01:31:22.231Z,1554773482.231 [BR_Ping1D](INFO): 0
2019-04-09T01:31:22.231Z,1554773482.231 [BR_Ping1D](INFO): 1
2019-04-09T01:31:22.231Z,1554773482.231 [BR_Ping1D](INFO): 30
2019-04-09T01:31:22.622Z,1554773482.622 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:23.025Z,1554773483.025 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:23.025Z,1554773483.025 [BR_Ping1D](INFO): readConfig
2019-04-09T01:31:23.025Z,1554773483.025 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:31:23.026Z,1554773483.026 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:31:23.026Z,1554773483.026 [BR_Ping1D](INFO): UART requesting status
2019-04-09T01:31:23.026Z,1554773483.026 [BR_Ping1D](INFO): packet size:8
2019-04-09T01:31:23.026Z,1554773483.026 [BR_Ping1D](INFO): 66
2019-04-09T01:31:23.026Z,1554773483.026 [BR_Ping1D](INFO): 82
2019-04-09T01:31:23.026Z,1554773483.026 [BR_Ping1D](INFO): 0
2019-04-09T01:31:23.026Z,1554773483.026 [BR_Ping1D](INFO): 0
2019-04-09T01:31:23.026Z,1554773483.026 [BR_Ping1D](INFO): 186
2019-04-09T01:31:23.027Z,1554773483.027 [BR_Ping1D](INFO): 4
2019-04-09T01:31:23.027Z,1554773483.027 [BR_Ping1D](INFO): 0
2019-04-09T01:31:23.027Z,1554773483.027 [BR_Ping1D](INFO): 0
2019-04-09T01:31:23.027Z,1554773483.027 [BR_Ping1D](INFO): packet size:8
2019-04-09T01:31:23.027Z,1554773483.027 [BR_Ping1D](INFO): 66
2019-04-09T01:31:23.027Z,1554773483.027 [BR_Ping1D](INFO): 82
2019-04-09T01:31:23.027Z,1554773483.027 [BR_Ping1D](INFO): 0
2019-04-09T01:31:23.027Z,1554773483.027 [BR_Ping1D](INFO): 0
2019-04-09T01:31:23.027Z,1554773483.027 [BR_Ping1D](INFO): 191
2019-04-09T01:31:23.028Z,1554773483.028 [BR_Ping1D](INFO): 4
2019-04-09T01:31:23.028Z,1554773483.028 [BR_Ping1D](INFO): 0
2019-04-09T01:31:23.028Z,1554773483.028 [BR_Ping1D](INFO): 0
2019-04-09T01:31:23.028Z,1554773483.028 [BR_Ping1D](INFO): packet size:8
2019-04-09T01:31:23.028Z,1554773483.028 [BR_Ping1D](INFO): 66
2019-04-09T01:31:23.028Z,1554773483.028 [BR_Ping1D](INFO): 82
2019-04-09T01:31:23.028Z,1554773483.028 [BR_Ping1D](INFO): 0
2019-04-09T01:31:23.028Z,1554773483.028 [BR_Ping1D](INFO): 0
2019-04-09T01:31:23.028Z,1554773483.028 [BR_Ping1D](INFO): 179
2019-04-09T01:31:23.029Z,1554773483.029 [BR_Ping1D](INFO): 4
2019-04-09T01:31:23.029Z,1554773483.029 [BR_Ping1D](INFO): 0
2019-04-09T01:31:23.029Z,1554773483.029 [BR_Ping1D](INFO): 0
2019-04-09T01:31:23.430Z,1554773483.430 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:23.430Z,1554773483.430 [BR_Ping1D](INFO): readConfig
2019-04-09T01:31:23.430Z,1554773483.430 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:31:23.430Z,1554773483.430 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:31:23.430Z,1554773483.430 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:31:23.431Z,1554773483.431 [BR_Ping1D](INFO): elapsed1.2127
2019-04-09T01:31:23.431Z,1554773483.431 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:31:23.431Z,1554773483.431 [BR_Ping1D](INFO): No UART data
2019-04-09T01:31:23.827Z,1554773483.827 [NAL9602](INFO): Powering up NAL9602
2019-04-09T01:31:23.843Z,1554773483.843 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:23.843Z,1554773483.843 [BR_Ping1D](INFO): readConfig
2019-04-09T01:31:23.843Z,1554773483.843 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:31:23.843Z,1554773483.843 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:31:23.843Z,1554773483.843 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:31:23.844Z,1554773483.844 [BR_Ping1D](INFO): elapsed1.6258
2019-04-09T01:31:23.844Z,1554773483.844 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:31:23.844Z,1554773483.844 [BR_Ping1D](INFO): No UART data
2019-04-09T01:31:24.210Z,1554773484.210 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T01:31:24.210Z,1554773484.210 [CTD_Seabird] Communications Fault, FailCount= 2
2019-04-09T01:31:24.210Z,1554773484.210 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T01:31:24.251Z,1554773484.251 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:24.251Z,1554773484.251 [BR_Ping1D](INFO): readConfig
2019-04-09T01:31:24.251Z,1554773484.251 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:31:24.251Z,1554773484.251 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:31:24.251Z,1554773484.251 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:31:24.252Z,1554773484.252 [BR_Ping1D](INFO): elapsed2.0339
2019-04-09T01:31:24.252Z,1554773484.252 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:31:24.252Z,1554773484.252 [BR_Ping1D](INFO): No UART data
2019-04-09T01:31:24.295Z,1554773484.295 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T01:31:24.614Z,1554773484.614 [CTD_Seabird](INFO): Powering down
2019-04-09T01:31:24.641Z,1554773484.641 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:24.641Z,1554773484.641 [BR_Ping1D](INFO): readConfig
2019-04-09T01:31:24.641Z,1554773484.641 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:31:24.642Z,1554773484.642 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:31:24.642Z,1554773484.642 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:31:24.642Z,1554773484.642 [BR_Ping1D](INFO): elapsed2.4242
2019-04-09T01:31:24.642Z,1554773484.642 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:31:24.642Z,1554773484.642 [BR_Ping1D](INFO): No UART data
2019-04-09T01:31:25.046Z,1554773485.046 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:25.046Z,1554773485.046 [BR_Ping1D](INFO): readConfig
2019-04-09T01:31:25.046Z,1554773485.046 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:31:25.046Z,1554773485.046 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:31:25.047Z,1554773485.047 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:31:25.047Z,1554773485.047 [BR_Ping1D](INFO): elapsed2.8289
2019-04-09T01:31:25.047Z,1554773485.047 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:31:25.047Z,1554773485.047 [BR_Ping1D](INFO): No UART data
2019-04-09T01:31:25.449Z,1554773485.449 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:25.449Z,1554773485.449 [BR_Ping1D](INFO): readConfig
2019-04-09T01:31:25.449Z,1554773485.449 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:31:25.450Z,1554773485.450 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:31:25.450Z,1554773485.450 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:31:25.450Z,1554773485.450 [BR_Ping1D](INFO): elapsed3.2322
2019-04-09T01:31:25.450Z,1554773485.450 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:31:25.450Z,1554773485.450 [BR_Ping1D](INFO): No UART data
2019-04-09T01:31:25.477Z,1554773485.477 [CBIT](FAULT): Main Battery Failure. Count: 71
2019-04-09T01:31:25.853Z,1554773485.853 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:25.853Z,1554773485.853 [BR_Ping1D](INFO): readConfig
2019-04-09T01:31:25.854Z,1554773485.854 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:31:25.854Z,1554773485.854 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:31:25.854Z,1554773485.854 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:31:25.854Z,1554773485.854 [BR_Ping1D](INFO): elapsed3.6365
2019-04-09T01:31:25.855Z,1554773485.855 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:31:25.855Z,1554773485.855 [BR_Ping1D](INFO): No UART data
2019-04-09T01:31:25.883Z,1554773485.883 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T01:31:25.883Z,1554773485.883 [CTD_Seabird] No Fault, FailCount= 2
2019-04-09T01:31:25.954Z,1554773485.954 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T01:31:25.956Z,1554773485.956 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T01:31:26.274Z,1554773486.274 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:26.274Z,1554773486.274 [BR_Ping1D](INFO): readConfig
2019-04-09T01:31:26.275Z,1554773486.275 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:31:26.275Z,1554773486.275 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:31:26.275Z,1554773486.275 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:31:26.275Z,1554773486.275 [BR_Ping1D](INFO): elapsed4.0573
2019-04-09T01:31:26.275Z,1554773486.275 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:31:26.276Z,1554773486.276 [BR_Ping1D](INFO): No UART data
2019-04-09T01:31:26.662Z,1554773486.662 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:26.662Z,1554773486.662 [BR_Ping1D](INFO): readConfig
2019-04-09T01:31:26.662Z,1554773486.662 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:31:26.663Z,1554773486.663 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:31:26.663Z,1554773486.663 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:31:26.663Z,1554773486.663 [BR_Ping1D](INFO): elapsed4.4451
2019-04-09T01:31:26.663Z,1554773486.663 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:31:26.663Z,1554773486.663 [BR_Ping1D](INFO): No UART data
2019-04-09T01:31:27.069Z,1554773487.069 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:27.070Z,1554773487.070 [BR_Ping1D](INFO): readConfig
2019-04-09T01:31:27.070Z,1554773487.070 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:31:27.070Z,1554773487.070 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:31:27.070Z,1554773487.070 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:31:27.070Z,1554773487.070 [BR_Ping1D](INFO): elapsed4.8527
2019-04-09T01:31:27.071Z,1554773487.071 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:31:27.071Z,1554773487.071 [BR_Ping1D](INFO): No UART data
2019-04-09T01:31:27.471Z,1554773487.471 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:27.471Z,1554773487.471 [BR_Ping1D](INFO): readConfig
2019-04-09T01:31:27.471Z,1554773487.471 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:31:27.471Z,1554773487.471 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:31:27.471Z,1554773487.471 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:31:27.471Z,1554773487.471 [BR_Ping1D](INFO): elapsed5.2536
2019-04-09T01:31:27.472Z,1554773487.472 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:31:27.472Z,1554773487.472 [BR_Ping1D] Data Fault, FailCount= 4
2019-04-09T01:31:27.472Z,1554773487.472 [BR_Ping1D](ERROR): Data Fault
2019-04-09T01:31:27.536Z,1554773487.536 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T01:31:27.874Z,1554773487.874 [BR_Ping1D](INFO): Stop
2019-04-09T01:31:27.874Z,1554773487.874 [BR_Ping1D](INFO): uninitialize
2019-04-09T01:31:28.278Z,1554773488.278 [BR_Ping1D](INFO): Stopping
2019-04-09T01:31:28.681Z,1554773488.681 [BR_Ping1D](INFO): Stopping
2019-04-09T01:31:29.085Z,1554773489.085 [BR_Ping1D](INFO): Stopped
2019-04-09T01:31:29.103Z,1554773489.103 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T01:31:29.104Z,1554773489.104 [BR_Ping1D] No Fault, FailCount= 4
2019-04-09T01:31:29.489Z,1554773489.489 [BR_Ping1D](INFO): Start
2019-04-09T01:31:29.513Z,1554773489.513 [CBIT](FAULT): Main Battery Failure. Count: 81
2019-04-09T01:31:29.875Z,1554773489.875 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T01:31:29.875Z,1554773489.875 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-04-09T01:31:29.875Z,1554773489.875 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T01:31:29.894Z,1554773489.894 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:29.916Z,1554773489.916 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T01:31:29.916Z,1554773489.916 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-04-09T01:31:30.398Z,1554773490.398 [Aanderaa_O2](INFO): Powering down
2019-04-09T01:31:30.418Z,1554773490.418 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:30.418Z,1554773490.418 [BR_Ping1D](INFO): readConfig
2019-04-09T01:31:30.418Z,1554773490.418 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:31:30.418Z,1554773490.418 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:31:30.418Z,1554773490.418 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T01:31:30.430Z,1554773490.430 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T01:31:30.430Z,1554773490.430 [BR_Ping1D](INFO): packet size:10
2019-04-09T01:31:30.430Z,1554773490.430 [BR_Ping1D](INFO): 66
2019-04-09T01:31:30.430Z,1554773490.430 [BR_Ping1D](INFO): 82
2019-04-09T01:31:30.430Z,1554773490.430 [BR_Ping1D](INFO): 2
2019-04-09T01:31:30.430Z,1554773490.430 [BR_Ping1D](INFO): 0
2019-04-09T01:31:30.431Z,1554773490.431 [BR_Ping1D](INFO): 236
2019-04-09T01:31:30.431Z,1554773490.431 [BR_Ping1D](INFO): 3
2019-04-09T01:31:30.431Z,1554773490.431 [BR_Ping1D](INFO): 0
2019-04-09T01:31:30.431Z,1554773490.431 [BR_Ping1D](INFO): 0
2019-04-09T01:31:30.431Z,1554773490.431 [BR_Ping1D](INFO): 1
2019-04-09T01:31:30.431Z,1554773490.431 [BR_Ping1D](INFO): 30
2019-04-09T01:31:30.706Z,1554773490.706 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:31.105Z,1554773491.105 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:31.105Z,1554773491.105 [BR_Ping1D](INFO): readConfig
2019-04-09T01:31:31.106Z,1554773491.106 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:31:31.106Z,1554773491.106 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:31:31.106Z,1554773491.106 [BR_Ping1D](INFO): UART requesting status
2019-04-09T01:31:31.106Z,1554773491.106 [BR_Ping1D](INFO): packet size:8
2019-04-09T01:31:31.106Z,1554773491.106 [BR_Ping1D](INFO): 66
2019-04-09T01:31:31.106Z,1554773491.106 [BR_Ping1D](INFO): 82
2019-04-09T01:31:31.107Z,1554773491.107 [BR_Ping1D](INFO): 0
2019-04-09T01:31:31.107Z,1554773491.107 [BR_Ping1D](INFO): 0
2019-04-09T01:31:31.107Z,1554773491.107 [BR_Ping1D](INFO): 186
2019-04-09T01:31:31.107Z,1554773491.107 [BR_Ping1D](INFO): 4
2019-04-09T01:31:31.107Z,1554773491.107 [BR_Ping1D](INFO): 0
2019-04-09T01:31:31.107Z,1554773491.107 [BR_Ping1D](INFO): 0
2019-04-09T01:31:31.108Z,1554773491.108 [BR_Ping1D](INFO): packet size:8
2019-04-09T01:31:31.109Z,1554773491.109 [BR_Ping1D](INFO): 66
2019-04-09T01:31:31.109Z,1554773491.109 [BR_Ping1D](INFO): 82
2019-04-09T01:31:31.109Z,1554773491.109 [BR_Ping1D](INFO): 0
2019-04-09T01:31:31.109Z,1554773491.109 [BR_Ping1D](INFO): 0
2019-04-09T01:31:31.109Z,1554773491.109 [BR_Ping1D](INFO): 191
2019-04-09T01:31:31.109Z,1554773491.109 [BR_Ping1D](INFO): 4
2019-04-09T01:31:31.109Z,1554773491.109 [BR_Ping1D](INFO): 0
2019-04-09T01:31:31.109Z,1554773491.109 [BR_Ping1D](INFO): 0
2019-04-09T01:31:31.110Z,1554773491.110 [BR_Ping1D](INFO): packet size:8
2019-04-09T01:31:31.110Z,1554773491.110 [BR_Ping1D](INFO): 66
2019-04-09T01:31:31.111Z,1554773491.111 [BR_Ping1D](INFO): 82
2019-04-09T01:31:31.111Z,1554773491.111 [BR_Ping1D](INFO): 0
2019-04-09T01:31:31.111Z,1554773491.111 [BR_Ping1D](INFO): 0
2019-04-09T01:31:31.111Z,1554773491.111 [BR_Ping1D](INFO): 179
2019-04-09T01:31:31.111Z,1554773491.111 [BR_Ping1D](INFO): 4
2019-04-09T01:31:31.112Z,1554773491.112 [BR_Ping1D](INFO): 0
2019-04-09T01:31:31.112Z,1554773491.112 [BR_Ping1D](INFO): 0
2019-04-09T01:31:31.228Z,1554773491.228 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.006632
CHAN A1 (24V): -0.127767
CHAN A2 (12V): -0.005037
CHAN A3 (5V): -0.002539
CHAN B0 (3.3V): -0.001043
CHAN B1 (3.15aV): -0.001393
CHAN B2 (3.15bV): -0.001364
CHAN B3 (GND): 0.000170
OPEN: -0.000829
Full Scale Calc: 4.765 mA, -1.589 mA
2019-04-09T01:31:31.510Z,1554773491.510 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:31.510Z,1554773491.510 [BR_Ping1D](INFO): readConfig
2019-04-09T01:31:31.510Z,1554773491.510 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:31:31.510Z,1554773491.510 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:31:31.510Z,1554773491.510 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:31:31.511Z,1554773491.511 [BR_Ping1D](INFO): elapsed1.0922
2019-04-09T01:31:31.511Z,1554773491.511 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:31:31.511Z,1554773491.511 [BR_Ping1D](INFO): No UART data
2019-04-09T01:31:31.913Z,1554773491.913 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:31.913Z,1554773491.913 [BR_Ping1D](INFO): readConfig
2019-04-09T01:31:31.913Z,1554773491.913 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:31:31.914Z,1554773491.914 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:31:31.914Z,1554773491.914 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:31:31.914Z,1554773491.914 [BR_Ping1D](INFO): elapsed1.4956
2019-04-09T01:31:31.914Z,1554773491.914 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:31:31.914Z,1554773491.914 [BR_Ping1D](INFO): No UART data
2019-04-09T01:31:32.318Z,1554773492.318 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:32.318Z,1554773492.318 [BR_Ping1D](INFO): readConfig
2019-04-09T01:31:32.318Z,1554773492.318 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:31:32.318Z,1554773492.318 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:31:32.318Z,1554773492.318 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:31:32.319Z,1554773492.319 [BR_Ping1D](INFO): elapsed1.9002
2019-04-09T01:31:32.319Z,1554773492.319 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:31:32.319Z,1554773492.319 [BR_Ping1D](INFO): No UART data
2019-04-09T01:31:32.723Z,1554773492.723 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:32.723Z,1554773492.723 [BR_Ping1D](INFO): readConfig
2019-04-09T01:31:32.724Z,1554773492.724 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:31:32.724Z,1554773492.724 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:31:32.724Z,1554773492.724 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:31:32.724Z,1554773492.724 [BR_Ping1D](INFO): elapsed2.3057
2019-04-09T01:31:32.724Z,1554773492.724 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:31:32.725Z,1554773492.725 [BR_Ping1D](INFO): No UART data
2019-04-09T01:31:33.130Z,1554773493.130 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:33.130Z,1554773493.130 [BR_Ping1D](INFO): readConfig
2019-04-09T01:31:33.130Z,1554773493.130 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:31:33.130Z,1554773493.130 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:31:33.130Z,1554773493.130 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:31:33.131Z,1554773493.131 [BR_Ping1D](INFO): elapsed2.7121
2019-04-09T01:31:33.131Z,1554773493.131 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:31:33.131Z,1554773493.131 [BR_Ping1D](INFO): No UART data
2019-04-09T01:31:33.529Z,1554773493.529 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:33.529Z,1554773493.529 [BR_Ping1D](INFO): readConfig
2019-04-09T01:31:33.530Z,1554773493.530 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:31:33.530Z,1554773493.530 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:31:33.530Z,1554773493.530 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:31:33.530Z,1554773493.530 [BR_Ping1D](INFO): elapsed3.1118
2019-04-09T01:31:33.531Z,1554773493.531 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:31:33.531Z,1554773493.531 [BR_Ping1D](INFO): No UART data
2019-04-09T01:31:33.587Z,1554773493.587 [SBIT](ERROR): Could not read massPosReader_.
2019-04-09T01:31:33.588Z,1554773493.588 [CBIT](FAULT): Main Battery Failure. Count: 91
2019-04-09T01:31:33.934Z,1554773493.934 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:33.934Z,1554773493.934 [BR_Ping1D](INFO): readConfig
2019-04-09T01:31:33.934Z,1554773493.934 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:31:33.934Z,1554773493.934 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:31:33.934Z,1554773493.934 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:31:33.935Z,1554773493.935 [BR_Ping1D](INFO): elapsed3.5162
2019-04-09T01:31:33.935Z,1554773493.935 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:31:33.935Z,1554773493.935 [BR_Ping1D](INFO): No UART data
2019-04-09T01:31:34.349Z,1554773494.349 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:34.349Z,1554773494.349 [BR_Ping1D](INFO): readConfig
2019-04-09T01:31:34.349Z,1554773494.349 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:31:34.349Z,1554773494.349 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:31:34.349Z,1554773494.349 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:31:34.350Z,1554773494.350 [BR_Ping1D](INFO): elapsed3.9312
2019-04-09T01:31:34.350Z,1554773494.350 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:31:34.350Z,1554773494.350 [BR_Ping1D](INFO): No UART data
2019-04-09T01:31:34.727Z,1554773494.727 [NAL9602](INFO): NAL9602 initialized
2019-04-09T01:31:34.742Z,1554773494.742 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:34.742Z,1554773494.742 [BR_Ping1D](INFO): readConfig
2019-04-09T01:31:34.743Z,1554773494.743 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:31:34.743Z,1554773494.743 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:31:34.743Z,1554773494.743 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:31:34.743Z,1554773494.743 [BR_Ping1D](INFO): elapsed4.3247
2019-04-09T01:31:34.743Z,1554773494.743 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:31:34.744Z,1554773494.744 [BR_Ping1D](INFO): No UART data
2019-04-09T01:31:35.156Z,1554773495.156 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:35.156Z,1554773495.156 [BR_Ping1D](INFO): readConfig
2019-04-09T01:31:35.156Z,1554773495.156 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:31:35.156Z,1554773495.156 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:31:35.156Z,1554773495.156 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:31:35.157Z,1554773495.157 [BR_Ping1D](INFO): elapsed4.7381
2019-04-09T01:31:35.157Z,1554773495.157 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:31:35.157Z,1554773495.157 [BR_Ping1D](INFO): No UART data
2019-04-09T01:31:35.546Z,1554773495.546 [NAL9602](DEBUG): Fix Requested
2019-04-09T01:31:35.561Z,1554773495.561 [BR_Ping1D](INFO): Starting
2019-04-09T01:31:35.561Z,1554773495.561 [BR_Ping1D](INFO): readConfig
2019-04-09T01:31:35.561Z,1554773495.561 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:31:35.561Z,1554773495.561 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:31:35.562Z,1554773495.562 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:31:35.562Z,1554773495.562 [BR_Ping1D](INFO): elapsed5.1433
2019-04-09T01:31:35.562Z,1554773495.562 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:31:35.562Z,1554773495.562 [BR_Ping1D] Data Fault, FailCount= 5
2019-04-09T01:31:35.562Z,1554773495.562 [BR_Ping1D](ERROR): Data Fault
2019-04-09T01:31:35.703Z,1554773495.703 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T01:31:35.703Z,1554773495.703 [CBIT](CRITICAL): Data Fault in component: BR_Ping1D
2019-04-09T01:31:35.969Z,1554773495.969 [BR_Ping1D](INFO): Stop
2019-04-09T01:31:35.969Z,1554773495.969 [BR_Ping1D](INFO): uninitialize
2019-04-09T01:31:36.113Z,1554773496.113 [CBIT](INFO): Critical error at 20190409T013135
2019-04-09T01:31:36.389Z,1554773496.389 [BR_Ping1D](INFO): Stopping
2019-04-09T01:31:36.765Z,1554773496.765 [BR_Ping1D](INFO): Stopping
2019-04-09T01:31:37.166Z,1554773497.166 [BR_Ping1D](INFO): Stopped
2019-04-09T01:31:37.641Z,1554773497.641 [CBIT](FAULT): Main Battery Failure. Count: 101
2019-04-09T01:31:39.006Z,1554773499.006 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T01:31:39.006Z,1554773499.006 [CTD_Seabird] Communications Fault, FailCount= 3
2019-04-09T01:31:39.006Z,1554773499.006 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T01:31:39.267Z,1554773499.267 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T01:31:39.267Z,1554773499.267 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-04-09T01:31:39.410Z,1554773499.410 [CTD_Seabird](INFO): Powering down
2019-04-09T01:31:40.783Z,1554773500.783 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T01:31:40.783Z,1554773500.783 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 2
2019-04-09T01:31:40.783Z,1554773500.783 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T01:31:40.935Z,1554773500.935 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T01:31:41.187Z,1554773501.187 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T01:31:41.673Z,1554773501.673 [CBIT](FAULT): Main Battery Failure. Count: 111
2019-04-09T01:31:42.077Z,1554773502.077 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T01:31:42.077Z,1554773502.077 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 2
2019-04-09T01:31:44.443Z,1554773504.443 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T01:31:44.443Z,1554773504.443 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T01:31:45.685Z,1554773505.685 [CBIT](FAULT): Main Battery Failure. Count: 121
2019-04-09T01:31:49.735Z,1554773509.735 [CBIT](FAULT): Main Battery Failure. Count: 131
2019-04-09T01:31:53.785Z,1554773513.785 [CBIT](FAULT): Main Battery Failure. Count: 141
2019-04-09T01:31:57.809Z,1554773517.809 [CBIT](FAULT): Main Battery Failure. Count: 151
2019-04-09T01:32:00.248Z,1554773520.248 [SBIT](ERROR): Could not read massPosReader_.
2019-04-09T01:32:00.248Z,1554773520.248 [SBIT](FAULT): Control surface position failure.
2019-04-09T01:32:01.848Z,1554773521.848 [CBIT](FAULT): Main Battery Failure. Count: 161
2019-04-09T01:32:04.645Z,1554773524.645 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T01:32:04.645Z,1554773524.645 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 3
2019-04-09T01:32:04.645Z,1554773524.645 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T01:32:04.767Z,1554773524.767 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T01:32:05.046Z,1554773525.046 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T01:32:05.889Z,1554773525.889 [CBIT](FAULT): Main Battery Failure. Count: 171
2019-04-09T01:32:05.908Z,1554773525.908 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T01:32:05.908Z,1554773525.908 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 3
2019-04-09T01:32:08.278Z,1554773528.278 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T01:32:08.279Z,1554773528.279 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T01:32:09.959Z,1554773529.959 [CBIT](FAULT): Main Battery Failure. Count: 181
2019-04-09T01:32:13.969Z,1554773533.969 [SBIT](ERROR): Could not read massPosReader_.
2019-04-09T01:32:13.969Z,1554773533.969 [SBIT](FAULT): Control surface position failure.
2019-04-09T01:32:13.971Z,1554773533.971 [CBIT](FAULT): Main Battery Failure. Count: 191
2019-04-09T01:32:14.375Z,1554773534.375 [SBIT](CRITICAL): SBIT FAILED
2019-04-09T01:32:14.390Z,1554773534.390 [CommandLine](IMPORTANT): got command configSet list
2019-04-09T01:32:14.391Z,1554773534.391 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-04-09T01:32:14.391Z,1554773534.391 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2019-04-09T01:32:14.391Z,1554773534.391 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=0 bool;

2019-04-09T01:32:14.394Z,1554773534.394 [CBIT](INFO): Critical error at 20190409T013214
2019-04-09T01:32:14.787Z,1554773534.787 [MissionManager](IMPORTANT): Started mission Startup
2019-04-09T01:32:14.787Z,1554773534.787 [Startup] Running Loop=1
2019-04-09T01:32:14.788Z,1554773534.788 [Startup](DEBUG): Aggregate::initialize Startup
2019-04-09T01:32:14.788Z,1554773534.788 [Startup:A.GoToSurface] Running Loop=1
2019-04-09T01:32:14.788Z,1554773534.788 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-04-09T01:32:14.788Z,1554773534.788 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-04-09T01:32:14.789Z,1554773534.789 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-04-09T01:32:14.789Z,1554773534.789 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-04-09T01:32:14.790Z,1554773534.790 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-04-09T01:32:14.790Z,1554773534.790 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-04-09T01:32:14.792Z,1554773534.792 [Startup:StartupSatComms] Running Loop=1
2019-04-09T01:32:14.792Z,1554773534.792 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-04-09T01:32:14.792Z,1554773534.792 [Startup:StartupSatComms:A] Running Loop=1
2019-04-09T01:32:15.192Z,1554773535.192 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-04-09T01:32:18.010Z,1554773538.010 [CBIT](FAULT): Main Battery Failure. Count: 201
2019-04-09T01:32:22.053Z,1554773542.053 [CBIT](FAULT): Main Battery Failure. Count: 211
2019-04-09T01:32:26.109Z,1554773546.109 [CBIT](FAULT): Main Battery Failure. Count: 221
2019-04-09T01:32:28.478Z,1554773548.478 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T01:32:28.479Z,1554773548.479 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 4
2019-04-09T01:32:28.479Z,1554773548.479 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T01:32:28.516Z,1554773548.516 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T01:32:28.882Z,1554773548.882 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T01:32:29.735Z,1554773549.735 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T01:32:29.735Z,1554773549.735 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 4
2019-04-09T01:32:30.123Z,1554773550.123 [CBIT](FAULT): Main Battery Failure. Count: 231
2019-04-09T01:32:32.116Z,1554773552.116 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T01:32:32.116Z,1554773552.116 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T01:32:34.170Z,1554773554.170 [CBIT](FAULT): Main Battery Failure. Count: 241
2019-04-09T01:32:38.209Z,1554773558.209 [CBIT](FAULT): Main Battery Failure. Count: 251
2019-04-09T01:32:42.256Z,1554773562.256 [CBIT](FAULT): Main Battery Failure. Count: 261
2019-04-09T01:32:46.305Z,1554773566.305 [CBIT](FAULT): Main Battery Failure. Count: 271
2019-04-09T01:32:50.335Z,1554773570.335 [CBIT](FAULT): Main Battery Failure. Count: 281
2019-04-09T01:32:52.315Z,1554773572.315 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T01:32:52.315Z,1554773572.315 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 5
2019-04-09T01:32:52.315Z,1554773572.315 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T01:32:52.368Z,1554773572.368 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T01:32:52.719Z,1554773572.719 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T01:32:53.567Z,1554773573.567 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T01:32:53.567Z,1554773573.567 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 5
2019-04-09T01:32:54.363Z,1554773574.363 [CBIT](FAULT): Main Battery Failure. Count: 291
2019-04-09T01:32:55.951Z,1554773575.951 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T01:32:55.951Z,1554773575.951 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T01:32:55.997Z,1554773575.997 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T01:32:55.997Z,1554773575.997 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-04-09T01:32:55.997Z,1554773575.997 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-09T01:32:55.999Z,1554773575.999 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T01:32:55.999Z,1554773575.999 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-04-09T01:32:55.999Z,1554773575.999 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-09T01:32:56.000Z,1554773576.000 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T01:32:56.000Z,1554773576.000 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2019-04-09T01:32:56.001Z,1554773576.001 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-04-09T01:32:56.011Z,1554773576.011 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-09T01:32:56.012Z,1554773576.012 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-09T01:32:56.012Z,1554773576.012 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-04-09T01:32:56.397Z,1554773576.397 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-09T01:32:56.398Z,1554773576.398 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-04-09T01:32:56.398Z,1554773576.398 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-09T01:32:56.398Z,1554773576.398 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-04-09T01:32:56.399Z,1554773576.399 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-04-09T01:32:56.399Z,1554773576.399 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2019-04-09T01:32:56.801Z,1554773576.801 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-09T01:32:58.409Z,1554773578.409 [CBIT](FAULT): Main Battery Failure. Count: 301
2019-04-09T01:33:02.461Z,1554773582.461 [CBIT](FAULT): Main Battery Failure. Count: 311
2019-04-09T01:33:06.489Z,1554773586.489 [CBIT](FAULT): Main Battery Failure. Count: 321
2019-04-09T01:33:10.532Z,1554773590.532 [CBIT](FAULT): Main Battery Failure. Count: 331
2019-04-09T01:33:14.569Z,1554773594.569 [CBIT](FAULT): Main Battery Failure. Count: 341
2019-04-09T01:33:14.975Z,1554773594.975 [Startup:StartupSatComms:A](INFO): Timed out from 2019-04-09T01:32:14.8Z
2019-04-09T01:33:14.975Z,1554773594.975 [Startup:StartupSatComms:A] Stopped
2019-04-09T01:33:14.976Z,1554773594.976 [Startup:StartupSatComms:B] Running Loop=1
2019-04-09T01:33:15.372Z,1554773595.372 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-04-09T01:33:16.150Z,1554773596.150 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T01:33:16.151Z,1554773596.151 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 6
2019-04-09T01:33:16.151Z,1554773596.151 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T01:33:16.183Z,1554773596.183 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T01:33:16.554Z,1554773596.554 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T01:33:17.404Z,1554773597.404 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T01:33:17.404Z,1554773597.404 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 6
2019-04-09T01:33:18.620Z,1554773598.620 [CBIT](FAULT): Main Battery Failure. Count: 351
2019-04-09T01:33:19.786Z,1554773599.786 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T01:33:19.787Z,1554773599.787 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T01:33:22.650Z,1554773602.650 [CBIT](FAULT): Main Battery Failure. Count: 361
2019-04-09T01:33:26.690Z,1554773606.690 [CBIT](FAULT): Main Battery Failure. Count: 371
2019-04-09T01:33:30.732Z,1554773610.732 [CBIT](FAULT): Main Battery Failure. Count: 381
2019-04-09T01:33:34.789Z,1554773614.789 [CBIT](FAULT): Main Battery Failure. Count: 391
2019-04-09T01:33:35.328Z,1554773615.328 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004895
2019-04-09T01:33:38.838Z,1554773618.838 [CBIT](FAULT): Main Battery Failure. Count: 401
2019-04-09T01:33:39.937Z,1554773619.937 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190409T012230/Courier0004.lzma
2019-04-09T01:33:39.986Z,1554773619.986 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T01:33:39.987Z,1554773619.987 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 7
2019-04-09T01:33:39.987Z,1554773619.987 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T01:33:40.036Z,1554773620.036 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T01:33:40.390Z,1554773620.390 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T01:33:40.744Z,1554773620.744 [DataOverHttps](INFO): Moved sent file to Logs/20190409T012230/Courier0004.lzma.bak
2019-04-09T01:33:40.744Z,1554773620.744 [DataOverHttps](INFO): SBD MOMSN=10493280
2019-04-09T01:33:41.260Z,1554773621.260 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T01:33:41.260Z,1554773621.260 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 7
2019-04-09T01:33:42.861Z,1554773622.861 [CBIT](FAULT): Main Battery Failure. Count: 411
2019-04-09T01:33:43.622Z,1554773623.622 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T01:33:43.623Z,1554773623.623 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T01:33:46.893Z,1554773626.893 [CBIT](FAULT): Main Battery Failure. Count: 421
2019-04-09T01:33:50.970Z,1554773630.970 [CBIT](FAULT): Main Battery Failure. Count: 431
2019-04-09T01:33:55.094Z,1554773635.094 [CBIT](FAULT): Main Battery Failure. Count: 441
2019-04-09T01:33:55.870Z,1554773635.870 [DataOverHttps](INFO): Sending 358 bytes from file Logs/20190409T012802/Courier0000.lzma
2019-04-09T01:33:56.391Z,1554773636.391 [DataOverHttps](INFO): Moved sent file to Logs/20190409T012802/Courier0000.lzma.bak
2019-04-09T01:33:56.392Z,1554773636.392 [DataOverHttps](INFO): SBD MOMSN=10493282
2019-04-09T01:33:59.038Z,1554773639.038 [CBIT](FAULT): Main Battery Failure. Count: 451
2019-04-09T01:34:00.268Z,1554773640.268 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T01:34:00.268Z,1554773640.268 [Aanderaa_O2] No Fault, FailCount= 3
2019-04-09T01:34:00.516Z,1554773640.516 [CommandLine](IMPORTANT): got command restart application
2019-04-09T01:34:01.522Z,1554773641.522 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T01:34:01.522Z,1554773641.522 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:34:01.702Z,1554773641.702 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-04-09T01:34:01.702Z,1554773641.702 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:34:01.703Z,1554773641.703 [CommandLine](INFO): Join timeout helper Thread ID is 6097
2019-04-09T01:34:01.703Z,1554773641.703 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-04-09T01:34:01.703Z,1554773641.703 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:34:01.704Z,1554773641.704 [NavChartDb](INFO): Join timeout helper Thread ID is 6098
2019-04-09T01:34:02.026Z,1554773642.026 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T01:34:02.027Z,1554773642.027 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:34:02.046Z,1554773642.046 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-04-09T01:34:02.046Z,1554773642.046 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:34:02.046Z,1554773642.046 [Radio_Surface](INFO): Join timeout helper Thread ID is 6099
2019-04-09T01:34:02.146Z,1554773642.146 [Radio_Surface](INFO): Powering down
2019-04-09T01:34:02.147Z,1554773642.147 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T01:34:02.147Z,1554773642.147 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:34:02.166Z,1554773642.166 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-04-09T01:34:02.166Z,1554773642.166 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:34:02.166Z,1554773642.166 [DataOverHttps](INFO): Join timeout helper Thread ID is 6100
2019-04-09T01:34:02.370Z,1554773642.370 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T01:34:02.370Z,1554773642.370 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:34:02.390Z,1554773642.390 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-04-09T01:34:02.390Z,1554773642.390 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:34:02.390Z,1554773642.390 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 6101
2019-04-09T01:34:02.391Z,1554773642.391 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T01:34:02.391Z,1554773642.391 [WetLabsBB2FL](INFO): Powering down
2019-04-09T01:34:02.392Z,1554773642.392 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:34:02.394Z,1554773642.394 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-04-09T01:34:02.394Z,1554773642.394 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:34:02.394Z,1554773642.394 [CTD_Seabird](INFO): Join timeout helper Thread ID is 6102
2019-04-09T01:34:02.578Z,1554773642.578 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T01:34:02.578Z,1554773642.578 [CTD_Seabird](INFO): Powering down
2019-04-09T01:34:02.590Z,1554773642.590 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:34:02.595Z,1554773642.595 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-04-09T01:34:02.595Z,1554773642.595 [logger ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:34:02.595Z,1554773642.595 [logger](INFO): Join timeout helper Thread ID is 6103
2019-04-09T01:34:02.610Z,1554773642.610 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T01:34:02.610Z,1554773642.610 [logger ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:34:02.615Z,1554773642.615 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-04-09T01:34:02.615Z,1554773642.615 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:34:02.615Z,1554773642.615 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-04-09T01:34:02.615Z,1554773642.615 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:34:02.615Z,1554773642.615 [controlThread](INFO): Join timeout helper Thread ID is 6104
2019-04-09T01:34:02.622Z,1554773642.622 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T01:34:02.622Z,1554773642.622 [controlThread](DEBUG): Uninitializing ControlThread
2019-04-09T01:34:02.742Z,1554773642.742 [Aanderaa_O2](INFO): Powering down
2019-04-09T01:34:02.744Z,1554773642.744 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T01:34:02.862Z,1554773642.862 [NAL9602](INFO): Powering down
2019-04-09T01:34:02.864Z,1554773642.864 [BR_Ping1D](INFO): uninitialize
2019-04-09T01:34:02.866Z,1554773642.866 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-04-09T01:34:02.867Z,1554773642.867 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-04-09T01:34:02.867Z,1554773642.867 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-04-09T01:34:02.868Z,1554773642.868 [MissionManager](INFO): Uninitializing Mission Default
2019-04-09T01:34:02.870Z,1554773642.870 [MissionManager](INFO): Uninitializing Mission Startup
2019-04-09T01:34:02.870Z,1554773642.870 [Startup] Stopped
2019-04-09T01:34:02.870Z,1554773642.870 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-04-09T01:34:02.870Z,1554773642.870 [Startup:A.GoToSurface] Stopped
2019-04-09T01:34:02.871Z,1554773642.871 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-04-09T01:34:02.871Z,1554773642.871 [Startup:StartupSatComms] Stopped
2019-04-09T01:34:02.871Z,1554773642.871 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-04-09T01:34:02.871Z,1554773642.871 [Startup:StartupSatComms:B] Stopped
2019-04-09T01:34:02.872Z,1554773642.872 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-04-09T01:34:02.872Z,1554773642.872 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-04-09T01:34:02.873Z,1554773642.873 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-04-09T01:34:02.873Z,1554773642.873 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-04-09T01:34:02.873Z,1554773642.873 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-04-09T01:34:02.873Z,1554773642.873 [BuoyancyServo](INFO): Powering down
2019-04-09T01:34:02.886Z,1554773642.886 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-04-09T01:34:02.886Z,1554773642.886 [ElevatorServo](INFO): Powering down
2019-04-09T01:34:02.887Z,1554773642.887 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T01:34:02.887Z,1554773642.887 [MassServo](INFO): Powering down
2019-04-09T01:34:02.888Z,1554773642.888 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-04-09T01:34:02.888Z,1554773642.888 [RudderServo](INFO): Powering down
2019-04-09T01:34:02.889Z,1554773642.889 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-04-09T01:34:02.889Z,1554773642.889 [ThrusterServo](INFO): Powering down
2019-04-09T01:34:02.890Z,1554773642.890 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-04-09T01:34:02.890Z,1554773642.890 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-04-09T01:34:02.890Z,1554773642.890 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-04-09T01:34:02.890Z,1554773642.890 [CBIT](DEBUG): Powering off loads.
2019-04-09T01:34:02.902Z,1554773642.902 [CBIT](DEBUG): Disabling WDT.
2019-04-09T01:34:02.914Z,1554773642.914 [CBIT](DEBUG): Opening all GF detection circuits.
2019-04-09T01:34:02.915Z,1554773642.915 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:34:03.077Z,1554773643.077 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:34:03.082Z,1554773643.082 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:34:03.149Z,1554773643.149 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:34:03.154Z,1554773643.154 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:34:03.205Z,1554773643.205 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:34:03.262Z,1554773643.262 [logger ThreadHandler](INFO): Thread cancelled.