2019-02-21T23:26:54.664Z,1550791614.664 [Supervisor](DEBUG): Initializing supervisor.
2019-02-21T23:26:54.837Z,1550791614.837 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-02-21T23:26:54.837Z,1550791614.837 [SyncHandler](INFO): Protected caller Thread ID is 3989
2019-02-21T23:26:54.838Z,1550791614.838 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-02-21T23:26:54.917Z,1550791614.917 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-02-21T23:26:54.917Z,1550791614.917 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 3990
2019-02-21T23:26:54.920Z,1550791614.920 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-02-21T23:26:55.451Z,1550791615.451 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-02-21T23:26:55.529Z,1550791615.529 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-02-21T23:26:55.529Z,1550791615.529 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 3991
2019-02-21T23:26:55.530Z,1550791615.530 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-02-21T23:26:55.658Z,1550791615.658 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-02-21T23:26:55.659Z,1550791615.659 [logger ThreadHandler](INFO): Protected caller Thread ID is 3992
2019-02-21T23:26:55.745Z,1550791615.745 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-02-21T23:26:55.745Z,1550791615.745 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-02-21T23:26:55.925Z,1550791615.925 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-02-21T23:26:58.841Z,1550791618.841 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-02-21T23:26:58.842Z,1550791618.842 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-02-21T23:27:04.872Z,1550791624.872 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-02-21T23:27:04.872Z,1550791624.872 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-02-21T23:27:08.488Z,1550791628.488 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-02-21T23:27:08.489Z,1550791628.489 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-02-21T23:27:22.967Z,1550791642.967 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-02-21T23:27:22.967Z,1550791642.967 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-02-21T23:27:37.130Z,1550791657.130 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-02-21T23:27:37.131Z,1550791657.131 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-02-21T23:27:50.459Z,1550791670.459 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-02-21T23:27:50.460Z,1550791670.460 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-02-21T23:27:59.136Z,1550791679.136 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-02-21T23:27:59.137Z,1550791679.137 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-02-21T23:28:07.861Z,1550791687.861 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-02-21T23:28:08.763Z,1550791688.763 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-02-21T23:28:14.623Z,1550791694.623 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-02-21T23:28:14.624Z,1550791694.624 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-02-21T23:28:15.193Z,1550791695.193 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-02-21T23:28:15.193Z,1550791695.193 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-02-21T23:28:15.407Z,1550791695.407 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-02-21T23:28:15.407Z,1550791695.407 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-02-21T23:28:16.171Z,1550791696.171 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-02-21T23:28:16.454Z,1550791696.454 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-02-21T23:28:16.455Z,1550791696.455 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-02-21T23:28:16.650Z,1550791696.650 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-02-21T23:28:16.651Z,1550791696.651 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-02-21T23:28:16.858Z,1550791696.858 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-02-21T23:28:16.862Z,1550791696.862 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2019-02-21T23:28:16.865Z,1550791696.865 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2019-02-21T23:28:16.951Z,1550791696.951 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2019-02-21T23:28:17.178Z,1550791697.178 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-02-21T23:28:17.179Z,1550791697.179 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2019-02-21T23:28:17.275Z,1550791697.275 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2019-02-21T23:28:17.436Z,1550791697.436 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2019-02-21T23:28:17.640Z,1550791697.640 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2019-02-21T23:28:17.726Z,1550791697.726 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2019-02-21T23:28:17.826Z,1550791697.826 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2019-02-21T23:28:17.955Z,1550791697.955 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2019-02-21T23:28:18.625Z,1550791698.625 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2019-02-21T23:28:19.060Z,1550791699.060 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2019-02-21T23:28:19.159Z,1550791699.159 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-02-21T23:28:19.168Z,1550791699.168 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-02-21T23:28:19.292Z,1550791699.292 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-02-21T23:28:19.294Z,1550791699.294 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-02-21T23:28:19.416Z,1550791699.416 [BuoyancyServo] Loaded
2019-02-21T23:28:19.417Z,1550791699.417 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-02-21T23:28:19.432Z,1550791699.432 [ElevatorServo] Loaded
2019-02-21T23:28:19.432Z,1550791699.432 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-02-21T23:28:19.446Z,1550791699.446 [MassServo] Loaded
2019-02-21T23:28:19.447Z,1550791699.447 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-02-21T23:28:19.462Z,1550791699.462 [RudderServo] Loaded
2019-02-21T23:28:19.462Z,1550791699.462 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-02-21T23:28:19.476Z,1550791699.476 [ThrusterServo] Loaded
2019-02-21T23:28:19.476Z,1550791699.476 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-02-21T23:28:19.477Z,1550791699.477 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-02-21T23:28:19.477Z,1550791699.477 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-02-21T23:28:19.636Z,1550791699.636 [SBIT](DEBUG): Construct Startup Built In Test.
2019-02-21T23:28:19.664Z,1550791699.664 [SBIT] Loaded
2019-02-21T23:28:19.664Z,1550791699.664 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-02-21T23:28:19.665Z,1550791699.665 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-02-21T23:28:19.694Z,1550791699.694 [IBIT] Loaded
2019-02-21T23:28:19.694Z,1550791699.694 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-02-21T23:28:19.697Z,1550791699.697 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-02-21T23:28:20.076Z,1550791700.076 [CBIT] Loaded
2019-02-21T23:28:20.077Z,1550791700.077 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-02-21T23:28:20.077Z,1550791700.077 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-02-21T23:28:20.078Z,1550791700.078 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-02-21T23:28:20.328Z,1550791700.328 [Aanderaa_O2] Loaded
2019-02-21T23:28:20.328Z,1550791700.328 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-02-21T23:28:20.338Z,1550791700.338 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-02-21T23:28:20.343Z,1550791700.343 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-02-21T23:28:20.345Z,1550791700.345 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-02-21T23:28:20.349Z,1550791700.349 [CTD_Seabird](INFO): created writer for : depth
2019-02-21T23:28:20.350Z,1550791700.350 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-02-21T23:28:20.355Z,1550791700.355 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-02-21T23:28:20.356Z,1550791700.356 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-02-21T23:28:20.361Z,1550791700.361 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-02-21T23:28:20.362Z,1550791700.362 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-02-21T23:28:20.367Z,1550791700.367 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-02-21T23:28:20.368Z,1550791700.368 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-02-21T23:28:20.373Z,1550791700.373 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-02-21T23:28:20.373Z,1550791700.373 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-02-21T23:28:20.379Z,1550791700.379 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-02-21T23:28:20.405Z,1550791700.405 [CTD_Seabird] Loaded
2019-02-21T23:28:20.406Z,1550791700.406 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-02-21T23:28:20.407Z,1550791700.407 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406644E0
2019-02-21T23:28:20.407Z,1550791700.407 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 4074
2019-02-21T23:28:20.429Z,1550791700.429 [ESPComponent] Loaded
2019-02-21T23:28:20.429Z,1550791700.429 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2019-02-21T23:28:20.443Z,1550791700.443 [PAR_Licor] Loaded
2019-02-21T23:28:20.444Z,1550791700.444 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-02-21T23:28:20.493Z,1550791700.493 [WetLabsBB2FL] Loaded
2019-02-21T23:28:20.493Z,1550791700.493 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-02-21T23:28:20.494Z,1550791700.494 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406944E0
2019-02-21T23:28:20.494Z,1550791700.494 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 4075
2019-02-21T23:28:20.495Z,1550791700.495 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-02-21T23:28:20.496Z,1550791700.496 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-02-21T23:28:20.551Z,1550791700.551 [DepthRateCalculator] Loaded
2019-02-21T23:28:20.551Z,1550791700.551 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-02-21T23:28:20.557Z,1550791700.557 [PitchRateCalculator] Loaded
2019-02-21T23:28:20.557Z,1550791700.557 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-02-21T23:28:20.573Z,1550791700.573 [SpeedCalculator] Loaded
2019-02-21T23:28:20.573Z,1550791700.573 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-02-21T23:28:20.594Z,1550791700.594 [TempGradientCalculator] Loaded
2019-02-21T23:28:20.594Z,1550791700.594 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-02-21T23:28:20.600Z,1550791700.600 [YawRateCalculator] Loaded
2019-02-21T23:28:20.600Z,1550791700.600 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-02-21T23:28:20.633Z,1550791700.633 [ElevatorOffsetCalculator] Loaded
2019-02-21T23:28:20.633Z,1550791700.633 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-02-21T23:28:20.633Z,1550791700.633 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-02-21T23:28:20.634Z,1550791700.634 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-02-21T23:28:20.675Z,1550791700.675 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-02-21T23:28:20.676Z,1550791700.676 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-02-21T23:28:20.796Z,1550791700.796 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-02-21T23:28:20.796Z,1550791700.796 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-02-21T23:28:20.854Z,1550791700.854 [DeadReckonUsingSpeedCalculator] Loaded
2019-02-21T23:28:20.855Z,1550791700.855 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-02-21T23:28:20.913Z,1550791700.913 [DeadReckonWithRespectToSeafloor] Loaded
2019-02-21T23:28:20.914Z,1550791700.914 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2019-02-21T23:28:20.930Z,1550791700.930 [NavChart] Loaded
2019-02-21T23:28:20.930Z,1550791700.930 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-02-21T23:28:20.934Z,1550791700.934 [UniversalFixResidualReporter] Loaded
2019-02-21T23:28:20.934Z,1550791700.934 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-02-21T23:28:20.935Z,1550791700.935 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-02-21T23:28:20.935Z,1550791700.935 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-02-21T23:28:21.789Z,1550791701.789 [AcousticModem_Benthos_ATM900] Loaded
2019-02-21T23:28:21.789Z,1550791701.789 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2019-02-21T23:28:22.015Z,1550791702.015 [DataOverHttps] Loaded
2019-02-21T23:28:22.015Z,1550791702.015 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-02-21T23:28:22.021Z,1550791702.021 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4095B4E0
2019-02-21T23:28:22.021Z,1550791702.021 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 4077
2019-02-21T23:28:22.041Z,1550791702.041 [Depth_Keller] Loaded
2019-02-21T23:28:22.041Z,1550791702.041 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-02-21T23:28:22.136Z,1550791702.136 [NAL9602] Loaded
2019-02-21T23:28:22.136Z,1550791702.136 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-02-21T23:28:22.141Z,1550791702.141 [Onboard] Loaded
2019-02-21T23:28:22.141Z,1550791702.141 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-02-21T23:28:22.145Z,1550791702.145 [Radio_Surface] Loaded
2019-02-21T23:28:22.145Z,1550791702.145 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-02-21T23:28:22.146Z,1550791702.146 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4098B4E0
2019-02-21T23:28:22.146Z,1550791702.146 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4078
2019-02-21T23:28:22.193Z,1550791702.193 [PNI_TCM] Loaded
2019-02-21T23:28:22.193Z,1550791702.193 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2019-02-21T23:28:23.883Z,1550791703.883 [BPC1] Loaded
2019-02-21T23:28:23.884Z,1550791703.884 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-02-21T23:28:23.884Z,1550791703.884 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-02-21T23:28:23.885Z,1550791703.885 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-02-21T23:28:23.988Z,1550791703.988 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-02-21T23:28:23.988Z,1550791703.988 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-02-21T23:28:24.074Z,1550791704.074 [VerticalControl](DEBUG): Construct VerticalControl.
2019-02-21T23:28:24.158Z,1550791704.158 [VerticalControl] Loaded
2019-02-21T23:28:24.158Z,1550791704.158 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-02-21T23:28:24.159Z,1550791704.159 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-02-21T23:28:24.216Z,1550791704.216 [HorizontalControl] Loaded
2019-02-21T23:28:24.216Z,1550791704.216 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-02-21T23:28:24.217Z,1550791704.217 [SpeedControl](DEBUG): Construct SpeedControl.
2019-02-21T23:28:24.218Z,1550791704.218 [SpeedControl] Loaded
2019-02-21T23:28:24.219Z,1550791704.219 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-02-21T23:28:24.219Z,1550791704.219 [LoopControl](DEBUG): Construct LoopControl.
2019-02-21T23:28:24.220Z,1550791704.220 [LoopControl] Loaded
2019-02-21T23:28:24.220Z,1550791704.220 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-02-21T23:28:24.221Z,1550791704.221 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-02-21T23:28:24.221Z,1550791704.221 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-02-21T23:28:24.246Z,1550791704.246 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-02-21T23:28:24.246Z,1550791704.246 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-02-21T23:28:24.676Z,1550791704.676 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-02-21T23:28:24.680Z,1550791704.680 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-02-21T23:28:24.681Z,1550791704.681 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-02-21T23:28:24.688Z,1550791704.688 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-02-21T23:28:24.689Z,1550791704.689 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AAD4E0
2019-02-21T23:28:24.689Z,1550791704.689 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4079
2019-02-21T23:28:24.694Z,1550791704.694 [Supervisor](INFO): Main Thread ID is 3959
2019-02-21T23:28:24.694Z,1550791704.694 [Supervisor](DEBUG): Running supervisor.
2019-02-21T23:28:24.694Z,1550791704.694 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4080
2019-02-21T23:28:24.697Z,1550791704.697 [controlThread ThreadHandler](INFO): Handler Thread ID is 4081
2019-02-21T23:28:24.697Z,1550791704.697 [controlThread](DEBUG): Initializing ControlThread
2019-02-21T23:28:24.699Z,1550791704.699 [SBIT](INFO): Initialize SBIT Component.
2019-02-21T23:28:24.699Z,1550791704.699 [SBIT](IMPORTANT): git: 2019-02-11-71-g9c7399e
2019-02-21T23:28:24.700Z,1550791704.700 [SBIT](INFO): git hash: 9c7399e77d563bdda1c6e0d72b5b07cc90ba4f4b
2019-02-21T23:28:24.700Z,1550791704.700 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-02-21T23:28:24.701Z,1550791704.701 [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-02-21T23:28:24.702Z,1550791704.702 [SBIT](INFO): Beginning SBIT in 24.000000 seconds.
2019-02-21T23:28:24.703Z,1550791704.703 [IBIT](INFO): Initialize IBIT Component.
2019-02-21T23:28:24.704Z,1550791704.704 [CBIT](DEBUG): Initialize CBIT Component.
2019-02-21T23:28:24.705Z,1550791704.705 [logger ThreadHandler](INFO): Handler Thread ID is 4082
2019-02-21T23:28:24.717Z,1550791704.717 [CBIT](DEBUG): Initialized mux pins.
2019-02-21T23:28:24.717Z,1550791704.717 [CBIT](DEBUG): Initializing the watchdog timer.
2019-02-21T23:28:24.725Z,1550791704.725 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 4083
2019-02-21T23:28:24.726Z,1550791704.726 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-02-21T23:28:24.729Z,1550791704.729 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-02-21T23:28:24.731Z,1550791704.731 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 4085
2019-02-21T23:28:24.732Z,1550791704.732 [WetLabsBB2FL](INFO): Powering down
2019-02-21T23:28:24.741Z,1550791704.741 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-02-21T23:28:24.741Z,1550791704.741 [CBIT](DEBUG): Initializing heartbeat.
2019-02-21T23:28:24.769Z,1550791704.769 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 4086
2019-02-21T23:28:24.770Z,1550791704.770 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-02-21T23:28:24.793Z,1550791704.793 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4087
2019-02-21T23:28:24.813Z,1550791704.813 [CBIT](DEBUG): Deactivating GF circuits.
2019-02-21T23:28:24.813Z,1550791704.813 [CBIT](DEBUG): Deactivating emergency mode.
2019-02-21T23:28:24.845Z,1550791704.845 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4088
2019-02-21T23:28:24.853Z,1550791704.853 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-02-21T23:28:24.854Z,1550791704.854 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-02-21T23:28:24.854Z,1550791704.854 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-02-21T23:28:24.854Z,1550791704.854 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-02-21T23:28:24.855Z,1550791704.855 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-02-21T23:28:24.855Z,1550791704.855 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-02-21T23:28:24.856Z,1550791704.856 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-21T23:28:24.858Z,1550791704.858 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-02-21T23:28:24.861Z,1550791704.861 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-02-21T23:28:24.861Z,1550791704.861 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-02-21T23:28:24.861Z,1550791704.861 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-02-21T23:28:24.861Z,1550791704.861 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-02-21T23:28:24.862Z,1550791704.862 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-02-21T23:28:24.862Z,1550791704.862 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-02-21T23:28:24.862Z,1550791704.862 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-02-21T23:28:24.862Z,1550791704.862 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-02-21T23:28:24.862Z,1550791704.862 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-02-21T23:28:24.862Z,1550791704.862 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-02-21T23:28:24.863Z,1550791704.863 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-02-21T23:28:24.863Z,1550791704.863 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-02-21T23:28:24.863Z,1550791704.863 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-02-21T23:28:24.863Z,1550791704.863 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-02-21T23:28:24.863Z,1550791704.863 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-02-21T23:28:24.863Z,1550791704.863 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-02-21T23:28:24.866Z,1550791704.866 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-02-21T23:28:24.890Z,1550791704.890 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-02-21T23:28:24.892Z,1550791704.892 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-02-21T23:28:24.909Z,1550791704.909 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-02-21T23:28:24.910Z,1550791704.910 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-02-21T23:28:24.911Z,1550791704.911 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-02-21T23:28:25.037Z,1550791705.037 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-02-21T23:28:25.092Z,1550791705.092 [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-02-21T23:28:25.113Z,1550791705.113 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-02-21T23:28:25.197Z,1550791705.197 [Radio_Surface](INFO): Powering up
2019-02-21T23:28:25.282Z,1550791705.282 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-02-21T23:28:25.283Z,1550791705.283 [Default:A.Wait](DEBUG): Construct Wait.
2019-02-21T23:28:25.313Z,1550791705.313 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-02-21T23:28:25.364Z,1550791705.364 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-02-21T23:28:25.383Z,1550791705.383 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-02-21T23:28:25.433Z,1550791705.433 [Default:E.Execute](DEBUG): Construct Execute.
2019-02-21T23:28:25.436Z,1550791705.436 [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-02-21T23:28:25.457Z,1550791705.457 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AcousticModem_Benthos_ATM900,Depth_Keller,NAL9602,Onboard,PNI_TCM,BPC1,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-02-21T23:28:25.494Z,1550791705.494 [ESPComponent](INFO): powering down ESP
2019-02-21T23:28:26.169Z,1550791706.169 [PAR_Licor](INFO): A/D timeout, 12 tries over 139 ms

2019-02-21T23:28:26.169Z,1550791706.169 [PAR_Licor] Data Fault, FailCount= 1
2019-02-21T23:28:26.169Z,1550791706.169 [PAR_Licor](ERROR): Data Fault
2019-02-21T23:28:26.170Z,1550791706.170 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-02-21T23:28:26.170Z,1550791706.170 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-02-21T23:28:26.384Z,1550791706.384 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-02-21T23:28:26.421Z,1550791706.421 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-02-21T23:28:26.422Z,1550791706.422 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-02-21T23:28:26.437Z,1550791706.437 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-02-21T23:28:26.438Z,1550791706.438 [MassServo](DEBUG): Initializing EZServoServo.
2019-02-21T23:28:26.457Z,1550791706.457 [MassServo](DEBUG): Initializing MassServo.
2019-02-21T23:28:26.458Z,1550791706.458 [RudderServo](DEBUG): Initializing EZServoServo.
2019-02-21T23:28:26.489Z,1550791706.489 [RudderServo](DEBUG): Initializing RudderServo.
2019-02-21T23:28:26.490Z,1550791706.490 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-02-21T23:28:26.505Z,1550791706.505 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-02-21T23:28:26.511Z,1550791706.511 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-21T23:28:26.511Z,1550791706.511 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-21T23:28:26.512Z,1550791706.512 [PAR_Licor] No Fault, FailCount= 1
2019-02-21T23:28:27.457Z,1550791707.457 [PAR_Licor](INFO): A/D timeout, 11 tries over 129 ms

2019-02-21T23:28:27.457Z,1550791707.457 [PAR_Licor] Data Fault, FailCount= 1
2019-02-21T23:28:27.457Z,1550791707.457 [PAR_Licor](ERROR): Data Fault
2019-02-21T23:28:27.864Z,1550791707.864 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-21T23:28:27.864Z,1550791707.864 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-21T23:28:27.864Z,1550791707.864 [PAR_Licor] No Fault, FailCount= 1
2019-02-21T23:28:28.405Z,1550791708.405 [PAR_Licor](INFO): A/D timeout, 6 tries over 129 ms

2019-02-21T23:28:28.405Z,1550791708.405 [PAR_Licor] Data Fault, FailCount= 2
2019-02-21T23:28:28.405Z,1550791708.405 [PAR_Licor](ERROR): Data Fault
2019-02-21T23:28:28.567Z,1550791708.567 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-21T23:28:28.567Z,1550791708.567 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-21T23:28:28.568Z,1550791708.568 [PAR_Licor] No Fault, FailCount= 2
2019-02-21T23:28:28.797Z,1550791708.797 [Aanderaa_O2](INFO): Powering down
2019-02-21T23:28:33.734Z,1550791713.734 [AcousticModem_Benthos_ATM900](DEBUG): 
2019-02-21T23:28:34.765Z,1550791714.765 [CBIT](CRITICAL): Environmental Failure. Press:14.595641 PSI. Humidity:19%. Temp:25 C. ABORTING MISSION
2019-02-21T23:28:34.765Z,1550791714.765 [Supervisor](INFO): Stop Mission called by CBIT::monitorEnvironmentals
2019-02-21T23:28:34.935Z,1550791714.935 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire
2019-02-21T23:28:35.026Z,1550791715.026 [CommandLine](FAULT): Scheduling is paused
2019-02-21T23:28:35.027Z,1550791715.027 [CBIT](INFO): Critical error at 20190221T232834
2019-02-21T23:28:35.346Z,1550791715.346 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band
2019-02-21T23:28:35.347Z,1550791715.347 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3
2019-02-21T23:28:35.347Z,1550791715.347 [AcousticModem_Benthos_ATM900](DEBUG): Feb 12 2019 20:55:20 
2019-02-21T23:28:35.742Z,1550791715.742 [AcousticModem_Benthos_ATM900](DEBUG): WARNING: battery low
2019-02-21T23:28:36.553Z,1550791716.553 [AcousticModem_Benthos_ATM900](INFO): entering command mode
2019-02-21T23:28:36.953Z,1550791716.953 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment
2019-02-21T23:28:36.954Z,1550791716.954 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 1: user:1>
2019-02-21T23:28:36.954Z,1550791716.954 [AcousticModem_Benthos_ATM900](INFO): command mode acknowledged
2019-02-21T23:28:37.358Z,1550791717.358 [AcousticModem_Benthos_ATM900](INFO): setting local address to 3
2019-02-21T23:28:37.765Z,1550791717.765 [AcousticModem_Benthos_ATM900](DEBUG): checking for local address setting acknowledgment
2019-02-21T23:28:37.766Z,1550791717.766 [AcousticModem_Benthos_ATM900](INFO): set local address to 3
2019-02-21T23:28:38.174Z,1550791718.174 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 2: user:2>
2019-02-21T23:28:40.798Z,1550791720.798 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004415
2019-02-21T23:28:45.993Z,1550791725.993 [PAR_Licor](INFO): A/D timeout, 6 tries over 129 ms

2019-02-21T23:28:45.993Z,1550791725.993 [PAR_Licor] Data Fault, FailCount= 1
2019-02-21T23:28:45.994Z,1550791725.994 [PAR_Licor](ERROR): Data Fault
2019-02-21T23:28:46.090Z,1550791726.090 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-21T23:28:46.090Z,1550791726.090 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-21T23:28:46.090Z,1550791726.090 [PAR_Licor] No Fault, FailCount= 1
2019-02-21T23:28:49.348Z,1550791729.348 [SBIT](IMPORTANT): Beginning Startup BIT
2019-02-21T23:28:49.414Z,1550791729.414 [CBIT](IMPORTANT): Beginning ground fault scan
2019-02-21T23:28:52.226Z,1550791732.226 [NAL9602](INFO): Powering up NAL9602
2019-02-21T23:29:00.109Z,1550791740.109 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.007985
CHAN A1 (24V): -0.010645
CHAN A2 (12V): -0.005234
CHAN A3 (5V): -0.002536
CHAN B0 (3.3V): -0.001122
CHAN B1 (3.15aV): -0.000893
CHAN B2 (3.15bV): -0.001294
CHAN B3 (GND): 0.000374
OPEN: 0.004452
Full Scale Calc: 4.765 mA, -1.589 mA
2019-02-21T23:29:03.220Z,1550791743.220 [NAL9602](INFO): NAL9602 initialized
2019-02-21T23:29:04.089Z,1550791744.089 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:29:31.299Z,1550791771.299 [BPC1](FAULT): Failed to parse data from all battery packs.
2019-02-21T23:29:31.299Z,1550791771.299 [BPC1] Data Fault, FailCount= 1
2019-02-21T23:29:31.299Z,1550791771.299 [BPC1](ERROR): Data Fault
2019-02-21T23:29:31.549Z,1550791771.549 [CBIT](ERROR): Data Fault in component: BPC1
2019-02-21T23:29:38.865Z,1550791778.865 [PAR_Licor](INFO): A/D timeout, 7 tries over 125 ms

2019-02-21T23:29:38.865Z,1550791778.865 [PAR_Licor] Data Fault, FailCount= 1
2019-02-21T23:29:38.865Z,1550791778.865 [PAR_Licor](ERROR): Data Fault
2019-02-21T23:29:38.969Z,1550791778.969 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-21T23:29:38.969Z,1550791778.969 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-21T23:29:38.969Z,1550791778.969 [PAR_Licor] No Fault, FailCount= 1
2019-02-21T23:29:43.304Z,1550791783.304 [SBIT](IMPORTANT): SBIT PASSED
2019-02-21T23:29:43.354Z,1550791783.354 [CommandLine](IMPORTANT): got command configSet list
2019-02-21T23:29:43.354Z,1550791783.354 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-02-21T23:29:43.355Z,1550791783.355 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2019-02-21T23:29:43.355Z,1550791783.355 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=0 bool;

2019-02-21T23:29:43.819Z,1550791783.819 [MissionManager](IMPORTANT): Started mission Startup
2019-02-21T23:29:43.820Z,1550791783.820 [Startup] Running Loop=1
2019-02-21T23:29:43.820Z,1550791783.820 [Startup](DEBUG): Aggregate::initialize Startup
2019-02-21T23:29:43.820Z,1550791783.820 [Startup:A.GoToSurface] Running Loop=1
2019-02-21T23:29:43.820Z,1550791783.820 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-02-21T23:29:43.829Z,1550791783.829 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-02-21T23:29:43.830Z,1550791783.830 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-02-21T23:29:43.830Z,1550791783.830 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-02-21T23:29:43.830Z,1550791783.830 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-02-21T23:29:43.831Z,1550791783.831 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-02-21T23:29:43.832Z,1550791783.832 [Startup:StartupSatComms] Running Loop=1
2019-02-21T23:29:43.832Z,1550791783.832 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-02-21T23:29:43.865Z,1550791783.865 [Startup:StartupSatComms:A] Running Loop=1
2019-02-21T23:29:44.104Z,1550791784.104 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-02-21T23:30:43.900Z,1550791843.900 [Startup:StartupSatComms:A](INFO): Timed out from 2019-02-21T23:29:43.9Z
2019-02-21T23:30:43.900Z,1550791843.900 [Startup:StartupSatComms:A] Stopped
2019-02-21T23:30:43.900Z,1550791843.900 [Startup:StartupSatComms:B] Running Loop=1
2019-02-21T23:30:44.316Z,1550791844.316 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-02-21T23:31:11.502Z,1550791871.502 [DataOverHttps](INFO): Sending 227 bytes from file Logs/20190221T222319/Courier0019.lzma
2019-02-21T23:31:12.275Z,1550791872.275 [DataOverHttps](INFO): Moved sent file to Logs/20190221T222319/Courier0019.lzma.bak
2019-02-21T23:31:12.275Z,1550791872.275 [DataOverHttps](INFO): SBD MOMSN=10067769
2019-02-21T23:31:13.465Z,1550791873.465 [PAR_Licor](INFO): A/D timeout, 2 tries over 123 ms

2019-02-21T23:31:13.465Z,1550791873.465 [PAR_Licor] Data Fault, FailCount= 1
2019-02-21T23:31:13.465Z,1550791873.465 [PAR_Licor](ERROR): Data Fault
2019-02-21T23:31:13.992Z,1550791873.992 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-21T23:31:13.992Z,1550791873.992 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-21T23:31:13.992Z,1550791873.992 [PAR_Licor] No Fault, FailCount= 1
2019-02-21T23:31:19.885Z,1550791879.885 [PAR_Licor](INFO): A/D timeout, 5 tries over 146 ms

2019-02-21T23:31:19.885Z,1550791879.885 [PAR_Licor] Data Fault, FailCount= 1
2019-02-21T23:31:19.885Z,1550791879.885 [PAR_Licor](ERROR): Data Fault
2019-02-21T23:31:20.319Z,1550791880.319 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-21T23:31:20.319Z,1550791880.319 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-21T23:31:20.319Z,1550791880.319 [PAR_Licor] No Fault, FailCount= 1
2019-02-21T23:31:23.053Z,1550791883.053 [PAR_Licor](INFO): A/D timeout, 5 tries over 123 ms

2019-02-21T23:31:23.053Z,1550791883.053 [PAR_Licor] Data Fault, FailCount= 1
2019-02-21T23:31:23.053Z,1550791883.053 [PAR_Licor](ERROR): Data Fault
2019-02-21T23:31:23.440Z,1550791883.440 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-21T23:31:23.440Z,1550791883.440 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-21T23:31:23.440Z,1550791883.440 [PAR_Licor] No Fault, FailCount= 1
2019-02-21T23:31:25.026Z,1550791885.026 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-02-21T23:31:25.026Z,1550791885.026 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-02-21T23:31:25.027Z,1550791885.027 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-02-21T23:31:25.027Z,1550791885.027 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-02-21T23:31:25.028Z,1550791885.028 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2019-02-21T23:31:25.028Z,1550791885.028 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-21T23:31:25.322Z,1550791885.322 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-02-21T23:31:25.322Z,1550791885.322 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-02-21T23:31:25.322Z,1550791885.322 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-21T23:31:25.799Z,1550791885.799 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-02-21T23:31:25.799Z,1550791885.799 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-02-21T23:31:25.800Z,1550791885.800 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-02-21T23:31:25.800Z,1550791885.800 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-02-21T23:31:25.800Z,1550791885.800 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-21T23:31:25.800Z,1550791885.800 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2019-02-21T23:31:26.246Z,1550791886.246 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-21T23:31:44.389Z,1550791904.389 [Startup:StartupSatComms:B](INFO): Timed out from 2019-02-21T23:30:43.9Z
2019-02-21T23:31:44.389Z,1550791904.389 [Startup:StartupSatComms:B] Stopped
2019-02-21T23:31:44.389Z,1550791904.389 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-02-21T23:31:44.389Z,1550791904.389 [Startup:StartupSatComms] Stopped
2019-02-21T23:31:44.390Z,1550791904.390 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-02-21T23:31:44.390Z,1550791904.390 [Startup](INFO): Completed Startup
2019-02-21T23:31:44.390Z,1550791904.390 [MissionManager](INFO): Startup is completed.
2019-02-21T23:31:44.391Z,1550791904.391 [MissionManager](INFO): Uninitializing Mission Startup
2019-02-21T23:31:44.391Z,1550791904.391 [Startup] Stopped
2019-02-21T23:31:44.391Z,1550791904.391 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-02-21T23:31:44.391Z,1550791904.391 [Startup:A.GoToSurface] Stopped
2019-02-21T23:31:44.391Z,1550791904.391 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-02-21T23:31:44.856Z,1550791904.856 [MissionManager](IMPORTANT): Started mission Default
2019-02-21T23:31:44.856Z,1550791904.856 [Default] Running Loop=1
2019-02-21T23:31:44.856Z,1550791904.856 [Default](DEBUG): Aggregate::initialize Default
2019-02-21T23:31:44.856Z,1550791904.856 [Default:B.GoToSurface] Running Loop=1
2019-02-21T23:31:44.856Z,1550791904.856 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-02-21T23:31:44.921Z,1550791904.921 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-02-21T23:31:44.921Z,1550791904.921 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-02-21T23:31:44.922Z,1550791904.922 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-02-21T23:31:44.922Z,1550791904.922 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-02-21T23:31:44.922Z,1550791904.922 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-02-21T23:31:44.923Z,1550791904.923 [Default:A.Wait] Running Loop=1
2019-02-21T23:31:44.923Z,1550791904.923 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-02-21T23:31:47.129Z,1550791907.129 [PAR_Licor](INFO): A/D timeout, 6 tries over 140 ms

2019-02-21T23:31:47.129Z,1550791907.129 [PAR_Licor] Data Fault, FailCount= 1
2019-02-21T23:31:47.129Z,1550791907.129 [PAR_Licor](ERROR): Data Fault
2019-02-21T23:31:47.572Z,1550791907.572 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-21T23:31:47.623Z,1550791907.623 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-21T23:31:47.623Z,1550791907.623 [PAR_Licor] No Fault, FailCount= 1
2019-02-21T23:31:57.959Z,1550791917.959 [Default:A.Wait](INFO): Done Waiting.
2019-02-21T23:31:57.959Z,1550791917.959 [Default:A.Wait] Stopped
2019-02-21T23:31:57.959Z,1550791917.959 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-02-21T23:31:58.703Z,1550791918.703 [Default:CheckIn] Running Loop=1
2019-02-21T23:31:58.703Z,1550791918.703 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-02-21T23:31:58.703Z,1550791918.703 [Default:CheckIn:Read_GPS] Running Loop=1
2019-02-21T23:31:59.225Z,1550791919.225 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-02-21T23:32:04.830Z,1550791924.830 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2019-02-21T23:33:23.253Z,1550792003.253 [PAR_Licor](INFO): A/D timeout, 7 tries over 129 ms

2019-02-21T23:33:23.253Z,1550792003.253 [PAR_Licor] Data Fault, FailCount= 1
2019-02-21T23:33:23.253Z,1550792003.253 [PAR_Licor](ERROR): Data Fault
2019-02-21T23:33:23.553Z,1550792003.553 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-21T23:33:23.553Z,1550792003.553 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-21T23:33:23.554Z,1550792003.554 [PAR_Licor] No Fault, FailCount= 1
2019-02-21T23:33:40.077Z,1550792020.077 [PAR_Licor](INFO): A/D timeout, 7 tries over 148 ms

2019-02-21T23:33:40.077Z,1550792020.077 [PAR_Licor] Data Fault, FailCount= 1
2019-02-21T23:33:40.077Z,1550792020.077 [PAR_Licor](ERROR): Data Fault
2019-02-21T23:33:40.464Z,1550792020.464 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-21T23:33:40.464Z,1550792020.464 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-21T23:33:40.517Z,1550792020.517 [PAR_Licor] No Fault, FailCount= 1
2019-02-21T23:34:06.623Z,1550792046.622 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-02-21T23:34:07.865Z,1550792047.865 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:34:10.345Z,1550792050.345 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:34:13.461Z,1550792053.461 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:34:14.549Z,1550792054.549 [PAR_Licor](INFO): A/D timeout, 8 tries over 152 ms

2019-02-21T23:34:14.549Z,1550792054.549 [PAR_Licor] Data Fault, FailCount= 1
2019-02-21T23:34:14.549Z,1550792054.549 [PAR_Licor](ERROR): Data Fault
2019-02-21T23:34:15.228Z,1550792055.228 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-21T23:34:15.228Z,1550792055.228 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-21T23:34:15.228Z,1550792055.228 [PAR_Licor] No Fault, FailCount= 1
2019-02-21T23:34:16.593Z,1550792056.593 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:34:17.737Z,1550792057.737 [PAR_Licor](INFO): A/D timeout, 5 tries over 125 ms

2019-02-21T23:34:17.737Z,1550792057.737 [PAR_Licor] Data Fault, FailCount= 1
2019-02-21T23:34:17.737Z,1550792057.737 [PAR_Licor](ERROR): Data Fault
2019-02-21T23:34:18.094Z,1550792058.094 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-21T23:34:18.095Z,1550792058.095 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-21T23:34:18.095Z,1550792058.095 [PAR_Licor] No Fault, FailCount= 1
2019-02-21T23:34:19.853Z,1550792059.853 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:34:22.553Z,1550792062.553 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:34:25.315Z,1550792065.315 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=193.040390
2019-02-21T23:34:25.378Z,1550792065.378 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:34:26.312Z,1550792066.312 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-02-21T23:34:26.312Z,1550792066.312 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-02-21T23:34:26.408Z,1550792066.408 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2019-02-21T23:34:26.408Z,1550792066.408 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-02-21T23:34:26.468Z,1550792066.468 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2019-02-21T23:34:26.517Z,1550792066.517 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-21T23:34:26.689Z,1550792066.689 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-02-21T23:34:26.689Z,1550792066.689 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-02-21T23:34:26.689Z,1550792066.689 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-21T23:34:27.253Z,1550792067.253 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-02-21T23:34:27.253Z,1550792067.253 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-02-21T23:34:27.254Z,1550792067.254 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-02-21T23:34:27.254Z,1550792067.254 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2019-02-21T23:34:27.254Z,1550792067.254 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-21T23:34:27.254Z,1550792067.254 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2019-02-21T23:34:27.507Z,1550792067.507 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-21T23:34:28.685Z,1550792068.685 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:34:31.765Z,1550792071.765 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:34:34.167Z,1550792074.167 [PAR_Licor](INFO): A/D timeout, 5 tries over 134 ms

2019-02-21T23:34:34.167Z,1550792074.167 [PAR_Licor] Data Fault, FailCount= 1
2019-02-21T23:34:34.167Z,1550792074.167 [PAR_Licor](ERROR): Data Fault
2019-02-21T23:34:34.648Z,1550792074.648 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-21T23:34:34.713Z,1550792074.713 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-21T23:34:34.713Z,1550792074.713 [PAR_Licor] No Fault, FailCount= 1
2019-02-21T23:34:35.033Z,1550792075.033 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:34:37.874Z,1550792077.874 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:34:40.597Z,1550792080.597 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:34:43.629Z,1550792083.629 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:34:46.646Z,1550792086.646 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:34:49.437Z,1550792089.437 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:34:52.609Z,1550792092.609 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:34:55.941Z,1550792095.941 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:34:58.558Z,1550792098.558 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:35:01.793Z,1550792101.793 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:35:04.869Z,1550792104.869 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:35:07.905Z,1550792107.905 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:35:10.881Z,1550792110.881 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:35:13.250Z,1550792113.250 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:35:16.604Z,1550792116.604 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:35:19.649Z,1550792119.649 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:35:22.617Z,1550792122.617 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:35:25.741Z,1550792125.741 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:35:28.873Z,1550792128.873 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:35:31.447Z,1550792131.447 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:35:35.169Z,1550792135.169 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:35:37.664Z,1550792137.664 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:35:40.591Z,1550792140.591 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:35:43.729Z,1550792143.729 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:35:46.849Z,1550792146.849 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:35:50.119Z,1550792150.119 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:35:50.658Z,1550792150.658 [PAR_Licor](INFO): A/D timeout, 4 tries over 130 ms

2019-02-21T23:35:50.658Z,1550792150.658 [PAR_Licor] Data Fault, FailCount= 1
2019-02-21T23:35:50.659Z,1550792150.659 [PAR_Licor](ERROR): Data Fault
2019-02-21T23:35:51.253Z,1550792151.253 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-21T23:35:51.254Z,1550792151.254 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-21T23:35:51.254Z,1550792151.254 [PAR_Licor] No Fault, FailCount= 1
2019-02-21T23:35:53.817Z,1550792153.817 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:35:55.409Z,1550792155.409 [PAR_Licor](INFO): A/D timeout, 1 tries over 152 ms

2019-02-21T23:35:55.409Z,1550792155.409 [PAR_Licor] Data Fault, FailCount= 1
2019-02-21T23:35:55.409Z,1550792155.409 [PAR_Licor](ERROR): Data Fault
2019-02-21T23:35:55.703Z,1550792155.703 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-21T23:35:55.703Z,1550792155.703 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-21T23:35:55.704Z,1550792155.704 [PAR_Licor] No Fault, FailCount= 1
2019-02-21T23:35:56.982Z,1550792156.982 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:36:00.137Z,1550792160.137 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:36:02.589Z,1550792162.589 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:36:05.917Z,1550792165.917 [PAR_Licor](INFO): A/D timeout, 7 tries over 145 ms

2019-02-21T23:36:05.917Z,1550792165.917 [PAR_Licor] Data Fault, FailCount= 1
2019-02-21T23:36:05.917Z,1550792165.917 [PAR_Licor](ERROR): Data Fault
2019-02-21T23:36:05.933Z,1550792165.933 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:36:06.171Z,1550792166.171 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-21T23:36:06.171Z,1550792166.171 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-21T23:36:06.171Z,1550792166.171 [PAR_Licor] No Fault, FailCount= 1
2019-02-21T23:36:08.925Z,1550792168.925 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:36:11.965Z,1550792171.965 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:36:15.169Z,1550792175.169 [PAR_Licor](INFO): A/D timeout, 6 tries over 166 ms

2019-02-21T23:36:15.169Z,1550792175.169 [PAR_Licor] Data Fault, FailCount= 1
2019-02-21T23:36:15.169Z,1550792175.169 [PAR_Licor](ERROR): Data Fault
2019-02-21T23:36:15.186Z,1550792175.186 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:36:15.490Z,1550792175.490 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-21T23:36:15.491Z,1550792175.491 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-21T23:36:15.491Z,1550792175.491 [PAR_Licor] No Fault, FailCount= 1
2019-02-21T23:36:17.501Z,1550792177.501 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:36:20.729Z,1550792180.729 [PAR_Licor](INFO): A/D timeout, 6 tries over 143 ms

2019-02-21T23:36:20.729Z,1550792180.729 [PAR_Licor] Data Fault, FailCount= 1
2019-02-21T23:36:20.730Z,1550792180.730 [PAR_Licor](ERROR): Data Fault
2019-02-21T23:36:21.094Z,1550792181.094 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-21T23:36:21.094Z,1550792181.094 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-21T23:36:21.094Z,1550792181.094 [PAR_Licor] No Fault, FailCount= 1
2019-02-21T23:36:21.213Z,1550792181.214 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:36:25.205Z,1550792185.205 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:36:28.865Z,1550792188.865 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:36:31.961Z,1550792191.961 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:36:35.289Z,1550792195.289 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:36:39.333Z,1550792199.333 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:36:41.901Z,1550792201.901 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:36:45.829Z,1550792205.829 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:36:48.685Z,1550792208.685 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:36:52.009Z,1550792212.009 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:36:54.929Z,1550792214.929 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:36:57.729Z,1550792217.729 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:36:58.778Z,1550792218.778 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-02-21T23:31:58.7Z
2019-02-21T23:36:58.778Z,1550792218.778 [Default:CheckIn:Read_GPS] Stopped
2019-02-21T23:36:58.779Z,1550792218.779 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-02-21T23:36:59.415Z,1550792219.415 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-02-21T23:37:25.385Z,1550792245.385 [PAR_Licor](INFO): A/D timeout, 4 tries over 305 ms

2019-02-21T23:37:25.385Z,1550792245.385 [PAR_Licor] Data Fault, FailCount= 1
2019-02-21T23:37:25.385Z,1550792245.385 [PAR_Licor](ERROR): Data Fault
2019-02-21T23:37:25.908Z,1550792245.908 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-21T23:37:25.908Z,1550792245.908 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-21T23:37:25.908Z,1550792245.908 [PAR_Licor] No Fault, FailCount= 1
2019-02-21T23:37:28.024Z,1550792248.024 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-02-21T23:37:28.024Z,1550792248.024 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-02-21T23:37:28.071Z,1550792248.071 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2019-02-21T23:37:28.072Z,1550792248.072 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-02-21T23:37:28.129Z,1550792248.129 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2019-02-21T23:37:28.129Z,1550792248.129 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-21T23:37:28.290Z,1550792248.290 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-02-21T23:37:28.290Z,1550792248.290 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-02-21T23:37:28.290Z,1550792248.290 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-21T23:37:28.635Z,1550792248.635 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-02-21T23:37:28.635Z,1550792248.635 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-02-21T23:37:28.636Z,1550792248.636 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-02-21T23:37:28.636Z,1550792248.636 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2019-02-21T23:37:28.636Z,1550792248.636 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-21T23:37:28.636Z,1550792248.636 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3
2019-02-21T23:37:28.963Z,1550792248.963 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-21T23:37:29.780Z,1550792249.780 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response. 
2019-02-21T23:37:29.865Z,1550792249.865 [NAL9602](ERROR): received: 
+CSQ:0

OK
2019-02-21T23:37:29.865Z,1550792249.865 [NAL9602] Data Fault, FailCount= 1
2019-02-21T23:37:29.865Z,1550792249.865 [NAL9602](ERROR): Data Fault
2019-02-21T23:37:30.233Z,1550792250.233 [CBIT](ERROR): Data Fault in component: NAL9602
2019-02-21T23:37:30.465Z,1550792250.465 [PAR_Licor](INFO): A/D timeout, 4 tries over 151 ms

2019-02-21T23:37:30.465Z,1550792250.465 [PAR_Licor] Data Fault, FailCount= 1
2019-02-21T23:37:30.465Z,1550792250.465 [PAR_Licor](ERROR): Data Fault
2019-02-21T23:37:30.468Z,1550792250.468 [NAL9602](INFO): Powering down
2019-02-21T23:37:30.917Z,1550792250.917 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-21T23:37:30.918Z,1550792250.918 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-21T23:37:30.918Z,1550792250.918 [PAR_Licor] No Fault, FailCount= 1
2019-02-21T23:37:31.736Z,1550792251.736 [CBIT](INFO): Clearing failed state for component NAL9602
2019-02-21T23:37:31.736Z,1550792251.736 [NAL9602] No Fault, FailCount= 1
2019-02-21T23:38:00.650Z,1550792280.650 [NAL9602](INFO): Powering up NAL9602
2019-02-21T23:38:12.040Z,1550792292.040 [NAL9602](INFO): NAL9602 initialized
2019-02-21T23:38:12.985Z,1550792292.985 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:38:22.281Z,1550792302.281 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:38:28.521Z,1550792308.521 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:38:34.541Z,1550792314.541 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:38:41.917Z,1550792321.917 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:38:43.761Z,1550792323.761 [PAR_Licor](INFO): A/D timeout, 6 tries over 166 ms

2019-02-21T23:38:43.761Z,1550792323.761 [PAR_Licor] Data Fault, FailCount= 1
2019-02-21T23:38:43.761Z,1550792323.761 [PAR_Licor](ERROR): Data Fault
2019-02-21T23:38:44.162Z,1550792324.162 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-21T23:38:44.162Z,1550792324.162 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-21T23:38:44.162Z,1550792324.162 [PAR_Licor] No Fault, FailCount= 1
2019-02-21T23:38:50.865Z,1550792330.865 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:38:57.586Z,1550792337.586 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:39:04.148Z,1550792344.148 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-02-21T23:39:04.185Z,1550792344.185 [NAL9602] Data Fault, FailCount= 2
2019-02-21T23:39:04.185Z,1550792344.185 [NAL9602](ERROR): Data Fault
2019-02-21T23:39:04.564Z,1550792344.564 [CBIT](ERROR): Data Fault in component: NAL9602
2019-02-21T23:39:04.666Z,1550792344.666 [NAL9602](INFO): Powering down
2019-02-21T23:39:06.094Z,1550792346.094 [CBIT](INFO): Clearing failed state for component NAL9602
2019-02-21T23:39:06.094Z,1550792346.094 [NAL9602] No Fault, FailCount= 2
2019-02-21T23:39:10.993Z,1550792350.993 [PAR_Licor](INFO): A/D timeout, 7 tries over 127 ms

2019-02-21T23:39:10.993Z,1550792350.993 [PAR_Licor] Data Fault, FailCount= 1
2019-02-21T23:39:10.994Z,1550792350.994 [PAR_Licor](ERROR): Data Fault
2019-02-21T23:39:11.398Z,1550792351.398 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-21T23:39:11.398Z,1550792351.398 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-21T23:39:11.398Z,1550792351.398 [PAR_Licor] No Fault, FailCount= 1
2019-02-21T23:39:32.645Z,1550792372.645 [CBIT](INFO): Clearing failed state for component BPC1
2019-02-21T23:39:32.646Z,1550792372.646 [BPC1] No Fault, FailCount= 1
2019-02-21T23:39:34.795Z,1550792374.795 [NAL9602](INFO): Powering up NAL9602
2019-02-21T23:39:46.534Z,1550792386.534 [NAL9602](INFO): NAL9602 initialized
2019-02-21T23:39:47.389Z,1550792387.389 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:39:54.719Z,1550792394.719 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:39:55.845Z,1550792395.845 [PAR_Licor](INFO): A/D timeout, 5 tries over 159 ms

2019-02-21T23:39:55.845Z,1550792395.845 [PAR_Licor] Data Fault, FailCount= 1
2019-02-21T23:39:55.845Z,1550792395.845 [PAR_Licor](ERROR): Data Fault
2019-02-21T23:39:56.332Z,1550792396.332 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-21T23:39:56.332Z,1550792396.332 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-21T23:39:56.332Z,1550792396.332 [PAR_Licor] No Fault, FailCount= 1
2019-02-21T23:40:02.109Z,1550792402.109 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:40:10.001Z,1550792410.001 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:40:16.725Z,1550792416.725 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:40:23.273Z,1550792423.273 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:40:29.844Z,1550792429.844 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2019-02-21T23:40:29.844Z,1550792429.844 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-02-21T23:40:29.902Z,1550792429.902 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2019-02-21T23:40:29.902Z,1550792429.902 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-02-21T23:40:29.903Z,1550792429.903 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 4
2019-02-21T23:40:29.903Z,1550792429.903 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-21T23:40:30.150Z,1550792430.150 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-02-21T23:40:30.151Z,1550792430.151 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-02-21T23:40:30.151Z,1550792430.151 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-21T23:40:30.561Z,1550792430.561 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-02-21T23:40:30.561Z,1550792430.561 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2019-02-21T23:40:30.562Z,1550792430.562 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-02-21T23:40:30.562Z,1550792430.562 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2019-02-21T23:40:30.562Z,1550792430.562 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-21T23:40:30.563Z,1550792430.563 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 4
2019-02-21T23:40:30.709Z,1550792430.709 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:40:30.759Z,1550792430.759 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-21T23:40:31.505Z,1550792431.505 [PAR_Licor](INFO): A/D timeout, 7 tries over 129 ms

2019-02-21T23:40:31.505Z,1550792431.505 [PAR_Licor] Data Fault, FailCount= 1
2019-02-21T23:40:31.505Z,1550792431.505 [PAR_Licor](ERROR): Data Fault
2019-02-21T23:40:31.936Z,1550792431.936 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-21T23:40:31.936Z,1550792431.936 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-21T23:40:31.936Z,1550792431.936 [PAR_Licor] No Fault, FailCount= 1
2019-02-21T23:40:38.926Z,1550792438.926 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:40:39.110Z,1550792439.110 [BPC1](FAULT): Failed to parse data from all battery packs.
2019-02-21T23:40:39.110Z,1550792439.110 [BPC1] Data Fault, FailCount= 2
2019-02-21T23:40:39.110Z,1550792439.110 [BPC1](ERROR): Data Fault
2019-02-21T23:40:39.406Z,1550792439.406 [CBIT](ERROR): Data Fault in component: BPC1
2019-02-21T23:40:46.417Z,1550792446.417 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:40:50.331Z,1550792450.331 [PAR_Licor](INFO): A/D timeout, 7 tries over 135 ms

2019-02-21T23:40:50.331Z,1550792450.331 [PAR_Licor] Data Fault, FailCount= 1
2019-02-21T23:40:50.331Z,1550792450.331 [PAR_Licor](ERROR): Data Fault
2019-02-21T23:40:51.092Z,1550792451.092 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-21T23:40:51.092Z,1550792451.092 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-21T23:40:51.092Z,1550792451.092 [PAR_Licor] No Fault, FailCount= 1
2019-02-21T23:40:54.425Z,1550792454.425 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:41:01.181Z,1550792461.181 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:41:08.029Z,1550792468.029 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:41:16.061Z,1550792476.061 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:41:22.461Z,1550792482.461 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:41:24.402Z,1550792484.402 [PAR_Licor](INFO): A/D timeout, 4 tries over 127 ms

2019-02-21T23:41:24.402Z,1550792484.402 [PAR_Licor] Data Fault, FailCount= 1
2019-02-21T23:41:24.402Z,1550792484.402 [PAR_Licor](ERROR): Data Fault
2019-02-21T23:41:24.700Z,1550792484.700 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-21T23:41:24.754Z,1550792484.754 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-21T23:41:24.754Z,1550792484.754 [PAR_Licor] No Fault, FailCount= 1
2019-02-21T23:41:29.133Z,1550792489.133 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:41:36.289Z,1550792496.289 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:41:43.777Z,1550792503.777 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:41:50.145Z,1550792510.145 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:41:51.213Z,1550792511.213 [PAR_Licor](INFO): A/D timeout, 5 tries over 131 ms

2019-02-21T23:41:51.214Z,1550792511.214 [PAR_Licor] Data Fault, FailCount= 1
2019-02-21T23:41:51.214Z,1550792511.214 [PAR_Licor](ERROR): Data Fault
2019-02-21T23:41:51.593Z,1550792511.593 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-21T23:41:51.593Z,1550792511.593 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-21T23:41:51.593Z,1550792511.593 [PAR_Licor] No Fault, FailCount= 1
2019-02-21T23:41:56.321Z,1550792516.321 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:42:02.489Z,1550792522.489 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:42:05.653Z,1550792525.653 [PAR_Licor](INFO): A/D timeout, 9 tries over 130 ms

2019-02-21T23:42:05.653Z,1550792525.653 [PAR_Licor] Data Fault, FailCount= 1
2019-02-21T23:42:05.653Z,1550792525.653 [PAR_Licor](ERROR): Data Fault
2019-02-21T23:42:06.009Z,1550792526.009 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-21T23:42:06.010Z,1550792526.010 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-21T23:42:06.010Z,1550792526.010 [PAR_Licor] No Fault, FailCount= 1
2019-02-21T23:42:09.835Z,1550792529.835 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:42:17.507Z,1550792537.507 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:42:25.053Z,1550792545.053 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:42:30.957Z,1550792550.957 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:42:37.625Z,1550792557.625 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:42:43.974Z,1550792563.974 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:42:50.581Z,1550792570.581 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:42:57.212Z,1550792577.212 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:43:03.405Z,1550792583.405 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:43:09.601Z,1550792589.601 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:43:15.373Z,1550792595.373 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:43:22.899Z,1550792602.899 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:43:29.538Z,1550792609.538 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:43:30.886Z,1550792610.886 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2019-02-21T23:43:30.886Z,1550792610.886 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-02-21T23:43:30.966Z,1550792610.966 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2019-02-21T23:43:30.966Z,1550792610.966 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-02-21T23:43:30.968Z,1550792610.968 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 5
2019-02-21T23:43:30.968Z,1550792610.968 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-21T23:43:31.220Z,1550792611.220 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-02-21T23:43:31.253Z,1550792611.253 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-02-21T23:43:31.253Z,1550792611.253 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-21T23:43:31.958Z,1550792611.958 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-02-21T23:43:31.958Z,1550792611.958 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2019-02-21T23:43:31.959Z,1550792611.959 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-02-21T23:43:31.959Z,1550792611.959 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2019-02-21T23:43:31.959Z,1550792611.959 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-21T23:43:31.960Z,1550792611.960 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 5
2019-02-21T23:43:32.335Z,1550792612.335 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-21T23:43:37.637Z,1550792617.637 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:43:44.089Z,1550792624.089 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:43:53.009Z,1550792633.009 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:44:00.093Z,1550792640.093 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:44:07.201Z,1550792647.201 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:44:15.297Z,1550792655.297 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:44:23.637Z,1550792663.637 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:44:30.682Z,1550792670.682 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:44:37.509Z,1550792677.509 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:44:44.331Z,1550792684.331 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:44:51.637Z,1550792691.637 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:44:58.345Z,1550792698.345 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:45:04.849Z,1550792704.849 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:45:06.697Z,1550792706.697 [PAR_Licor](INFO): A/D timeout, 7 tries over 126 ms

2019-02-21T23:45:06.697Z,1550792706.697 [PAR_Licor] Data Fault, FailCount= 1
2019-02-21T23:45:06.698Z,1550792706.698 [PAR_Licor](ERROR): Data Fault
2019-02-21T23:45:07.039Z,1550792707.039 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-21T23:45:07.039Z,1550792707.039 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-21T23:45:07.039Z,1550792707.039 [PAR_Licor] No Fault, FailCount= 1
2019-02-21T23:45:12.065Z,1550792712.065 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:45:18.833Z,1550792718.833 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:45:26.409Z,1550792726.409 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:45:33.197Z,1550792733.197 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:45:40.030Z,1550792740.030 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:45:46.421Z,1550792746.421 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:45:52.405Z,1550792752.405 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:46:00.266Z,1550792760.266 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:46:06.905Z,1550792766.905 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:46:14.125Z,1550792774.125 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:46:22.217Z,1550792782.217 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:46:29.003Z,1550792789.003 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:46:32.574Z,1550792792.574 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2019-02-21T23:46:32.574Z,1550792792.574 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-02-21T23:46:32.576Z,1550792792.576 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6
2019-02-21T23:46:32.576Z,1550792792.576 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-02-21T23:46:32.577Z,1550792792.577 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 6
2019-02-21T23:46:32.577Z,1550792792.577 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-21T23:46:32.826Z,1550792792.826 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-02-21T23:46:32.827Z,1550792792.827 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-02-21T23:46:32.827Z,1550792792.827 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-21T23:46:35.379Z,1550792795.379 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-02-21T23:46:35.379Z,1550792795.379 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2019-02-21T23:46:35.380Z,1550792795.380 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-02-21T23:46:35.380Z,1550792795.380 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6
2019-02-21T23:46:35.380Z,1550792795.380 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-21T23:46:35.380Z,1550792795.380 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 6
2019-02-21T23:46:35.661Z,1550792795.661 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-21T23:46:35.893Z,1550792795.893 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:46:43.242Z,1550792803.242 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:46:50.101Z,1550792810.101 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:46:56.891Z,1550792816.891 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:47:04.437Z,1550792824.437 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:47:11.558Z,1550792831.558 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:47:18.279Z,1550792838.279 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:47:25.492Z,1550792845.492 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:47:32.001Z,1550792852.001 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:47:35.414Z,1550792855.414 [PAR_Licor](INFO): A/D timeout, 4 tries over 189 ms

2019-02-21T23:47:35.414Z,1550792855.414 [PAR_Licor] Data Fault, FailCount= 1
2019-02-21T23:47:35.414Z,1550792855.414 [PAR_Licor](ERROR): Data Fault
2019-02-21T23:47:35.812Z,1550792855.812 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-21T23:47:35.812Z,1550792855.812 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-21T23:47:35.812Z,1550792855.812 [PAR_Licor] No Fault, FailCount= 1
2019-02-21T23:47:39.130Z,1550792859.130 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:47:46.039Z,1550792866.039 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:47:48.573Z,1550792868.573 [PAR_Licor](INFO): A/D timeout, 6 tries over 123 ms

2019-02-21T23:47:48.573Z,1550792868.573 [PAR_Licor] Data Fault, FailCount= 1
2019-02-21T23:47:48.573Z,1550792868.573 [PAR_Licor](ERROR): Data Fault
2019-02-21T23:47:48.963Z,1550792868.963 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-21T23:47:48.963Z,1550792868.963 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-21T23:47:48.963Z,1550792868.963 [PAR_Licor] No Fault, FailCount= 1
2019-02-21T23:47:55.677Z,1550792875.677 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:48:01.069Z,1550792881.069 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:48:04.749Z,1550792884.749 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:48:09.125Z,1550792889.125 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:48:12.413Z,1550792892.413 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003176
2019-02-21T23:48:13.577Z,1550792893.577 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:48:16.409Z,1550792896.409 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:48:17.784Z,1550792897.784 [DataOverHttps](INFO): Sending 142 bytes from file Logs/20190221T232654/Courier0000.lzma
2019-02-21T23:48:18.590Z,1550792898.590 [DataOverHttps](INFO): Moved sent file to Logs/20190221T232654/Courier0000.lzma.bak
2019-02-21T23:48:18.591Z,1550792898.591 [DataOverHttps](INFO): SBD MOMSN=10067953
2019-02-21T23:48:19.225Z,1550792899.225 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:48:22.465Z,1550792902.465 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:48:25.289Z,1550792905.289 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:48:28.213Z,1550792908.213 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:48:31.413Z,1550792911.413 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:48:31.524Z,1550792911.524 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190221T232654/Courier0004.lzma
2019-02-21T23:48:32.330Z,1550792912.330 [DataOverHttps](INFO): Moved sent file to Logs/20190221T232654/Courier0004.lzma.bak
2019-02-21T23:48:32.331Z,1550792912.331 [DataOverHttps](INFO): SBD MOMSN=10067964
2019-02-21T23:48:34.233Z,1550792914.233 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:48:37.441Z,1550792917.441 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:48:40.265Z,1550792920.265 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:48:43.496Z,1550792923.496 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:48:45.562Z,1550792925.562 [DataOverHttps](INFO): Sending 464 bytes from file Logs/20190221T222319/Express0020.lzma
2019-02-21T23:48:46.325Z,1550792926.325 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:48:46.366Z,1550792926.366 [DataOverHttps](INFO): Moved sent file to Logs/20190221T222319/Express0020.lzma.bak
2019-02-21T23:48:46.367Z,1550792926.367 [DataOverHttps](INFO): SBD MOMSN=10068016
2019-02-21T23:48:49.149Z,1550792929.149 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:48:51.169Z,1550792931.169 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:48:53.189Z,1550792933.189 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:48:56.429Z,1550792936.429 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:48:59.249Z,1550792939.249 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:49:01.359Z,1550792941.359 [DataOverHttps](INFO): Sending 693 bytes from file Logs/20190221T232654/Express0001.lzma
2019-02-21T23:49:02.162Z,1550792942.162 [DataOverHttps](INFO): Moved sent file to Logs/20190221T232654/Express0001.lzma.bak
2019-02-21T23:49:02.162Z,1550792942.162 [DataOverHttps](INFO): SBD MOMSN=10068046
2019-02-21T23:49:02.489Z,1550792942.489 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:49:05.313Z,1550792945.313 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:49:08.549Z,1550792948.549 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:49:11.373Z,1550792951.373 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:49:14.654Z,1550792954.654 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:49:17.453Z,1550792957.453 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:49:20.747Z,1550792960.747 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:49:23.808Z,1550792963.808 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:49:25.594Z,1550792965.594 [PAR_Licor](INFO): A/D timeout, 3 tries over 123 ms

2019-02-21T23:49:25.595Z,1550792965.595 [PAR_Licor] Data Fault, FailCount= 1
2019-02-21T23:49:25.595Z,1550792965.595 [PAR_Licor](ERROR): Data Fault
2019-02-21T23:49:26.265Z,1550792966.265 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-21T23:49:26.265Z,1550792966.265 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-21T23:49:26.265Z,1550792966.265 [PAR_Licor] No Fault, FailCount= 1
2019-02-21T23:49:27.661Z,1550792967.661 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:49:30.789Z,1550792970.789 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:49:33.791Z,1550792973.791 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:49:35.945Z,1550792975.945 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2019-02-21T23:49:35.945Z,1550792975.945 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-02-21T23:49:35.946Z,1550792975.946 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7
2019-02-21T23:49:35.946Z,1550792975.946 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-02-21T23:49:35.947Z,1550792975.947 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 7
2019-02-21T23:49:35.947Z,1550792975.947 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-21T23:49:36.144Z,1550792976.144 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-02-21T23:49:36.144Z,1550792976.144 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-02-21T23:49:36.144Z,1550792976.144 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-21T23:49:36.401Z,1550792976.401 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:49:37.022Z,1550792977.022 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-02-21T23:49:37.022Z,1550792977.022 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2019-02-21T23:49:37.022Z,1550792977.022 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-02-21T23:49:37.023Z,1550792977.023 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7
2019-02-21T23:49:37.023Z,1550792977.023 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-21T23:49:37.023Z,1550792977.023 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 7
2019-02-21T23:49:37.340Z,1550792977.340 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-21T23:49:39.545Z,1550792979.545 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:49:42.353Z,1550792982.353 [PAR_Licor](INFO): A/D timeout, 8 tries over 134 ms

2019-02-21T23:49:42.353Z,1550792982.353 [PAR_Licor] Data Fault, FailCount= 1
2019-02-21T23:49:42.353Z,1550792982.353 [PAR_Licor](ERROR): Data Fault
2019-02-21T23:49:42.369Z,1550792982.369 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:49:42.814Z,1550792982.814 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-21T23:49:42.815Z,1550792982.815 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-21T23:49:42.815Z,1550792982.815 [PAR_Licor] No Fault, FailCount= 1
2019-02-21T23:49:45.423Z,1550792985.423 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:49:48.537Z,1550792988.537 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-02-21T23:49:48.537Z,1550792988.537 [NAL9602] Data Fault, FailCount= 3
2019-02-21T23:49:48.538Z,1550792988.538 [NAL9602](ERROR): Data Fault
2019-02-21T23:49:49.088Z,1550792989.088 [CBIT](ERROR): Data Fault in component: NAL9602
2019-02-21T23:49:49.247Z,1550792989.247 [NAL9602](INFO): Powering down
2019-02-21T23:49:50.468Z,1550792990.468 [CBIT](INFO): Clearing failed state for component NAL9602
2019-02-21T23:49:50.468Z,1550792990.468 [NAL9602] No Fault, FailCount= 3
2019-02-21T23:50:19.439Z,1550793019.439 [NAL9602](INFO): Powering up NAL9602
2019-02-21T23:50:20.829Z,1550793020.829 [PAR_Licor](INFO): A/D timeout, 6 tries over 150 ms

2019-02-21T23:50:20.829Z,1550793020.829 [PAR_Licor] Data Fault, FailCount= 1
2019-02-21T23:50:20.829Z,1550793020.829 [PAR_Licor](ERROR): Data Fault
2019-02-21T23:50:21.357Z,1550793021.357 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-21T23:50:21.357Z,1550793021.357 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-21T23:50:21.357Z,1550793021.357 [PAR_Licor] No Fault, FailCount= 1
2019-02-21T23:50:24.089Z,1550793024.089 [PAR_Licor](INFO): A/D timeout, 8 tries over 214 ms

2019-02-21T23:50:24.089Z,1550793024.089 [PAR_Licor] Data Fault, FailCount= 1
2019-02-21T23:50:24.089Z,1550793024.089 [PAR_Licor](ERROR): Data Fault
2019-02-21T23:50:24.756Z,1550793024.756 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-21T23:50:24.756Z,1550793024.756 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-21T23:50:24.756Z,1550793024.756 [PAR_Licor] No Fault, FailCount= 1
2019-02-21T23:50:30.889Z,1550793030.889 [NAL9602](INFO): NAL9602 initialized
2019-02-21T23:50:31.706Z,1550793031.706 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:50:35.503Z,1550793035.503 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:50:38.665Z,1550793038.665 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:50:40.694Z,1550793040.694 [CBIT](INFO): Clearing failed state for component BPC1
2019-02-21T23:50:40.694Z,1550793040.694 [BPC1] No Fault, FailCount= 2
2019-02-21T23:50:42.261Z,1550793042.261 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:50:45.789Z,1550793045.789 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:50:48.313Z,1550793048.313 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:50:51.275Z,1550793051.275 [PAR_Licor](INFO): A/D timeout, 6 tries over 151 ms

2019-02-21T23:50:51.275Z,1550793051.275 [PAR_Licor] Data Fault, FailCount= 1
2019-02-21T23:50:51.275Z,1550793051.275 [PAR_Licor](ERROR): Data Fault
2019-02-21T23:50:51.715Z,1550793051.715 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-21T23:50:51.715Z,1550793051.715 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-21T23:50:51.715Z,1550793051.715 [PAR_Licor] No Fault, FailCount= 1
2019-02-21T23:50:52.101Z,1550793052.101 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:50:56.169Z,1550793056.169 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:50:59.609Z,1550793059.609 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:51:02.485Z,1550793062.485 [PAR_Licor](INFO): A/D timeout, 8 tries over 144 ms

2019-02-21T23:51:02.485Z,1550793062.485 [PAR_Licor] Data Fault, FailCount= 1
2019-02-21T23:51:02.485Z,1550793062.485 [PAR_Licor](ERROR): Data Fault
2019-02-21T23:51:02.992Z,1550793062.992 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-21T23:51:02.992Z,1550793062.992 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-21T23:51:02.992Z,1550793062.992 [PAR_Licor] No Fault, FailCount= 1
2019-02-21T23:51:03.654Z,1550793063.654 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:51:07.909Z,1550793067.909 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:51:11.329Z,1550793071.329 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:51:14.458Z,1550793074.458 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:51:17.285Z,1550793077.285 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:51:20.721Z,1550793080.721 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:51:23.197Z,1550793083.197 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:51:26.805Z,1550793086.805 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:51:30.641Z,1550793090.641 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:51:33.653Z,1550793093.653 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:51:36.177Z,1550793096.177 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:51:39.433Z,1550793099.433 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:51:42.431Z,1550793102.431 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:51:45.585Z,1550793105.585 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:51:47.312Z,1550793107.312 [BPC1](FAULT): Failed to parse data from all battery packs.
2019-02-21T23:51:47.312Z,1550793107.312 [BPC1] Data Fault, FailCount= 3
2019-02-21T23:51:47.312Z,1550793107.312 [BPC1](ERROR): Data Fault
2019-02-21T23:51:47.614Z,1550793107.614 [CBIT](ERROR): Data Fault in component: BPC1
2019-02-21T23:51:49.389Z,1550793109.389 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:51:52.321Z,1550793112.321 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:51:55.936Z,1550793115.936 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:51:59.621Z,1550793119.621 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:52:02.397Z,1550793122.397 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:52:05.460Z,1550793125.460 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:52:08.557Z,1550793128.557 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:52:09.547Z,1550793129.547 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=181.269394
2019-02-21T23:52:17.085Z,1550793137.085 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:52:26.209Z,1550793146.209 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:52:34.793Z,1550793154.793 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:52:37.574Z,1550793157.574 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2019-02-21T23:52:37.575Z,1550793157.575 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-02-21T23:52:37.576Z,1550793157.576 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 8
2019-02-21T23:52:37.576Z,1550793157.576 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-02-21T23:52:37.629Z,1550793157.629 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 8
2019-02-21T23:52:37.629Z,1550793157.629 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-21T23:52:37.772Z,1550793157.772 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-02-21T23:52:37.772Z,1550793157.772 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-02-21T23:52:37.829Z,1550793157.829 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-21T23:52:42.589Z,1550793162.589 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-02-21T23:52:42.589Z,1550793162.589 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2019-02-21T23:52:42.590Z,1550793162.590 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-02-21T23:52:42.590Z,1550793162.590 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 8
2019-02-21T23:52:42.591Z,1550793162.591 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-21T23:52:42.591Z,1550793162.591 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 8
2019-02-21T23:52:42.920Z,1550793162.920 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-21T23:52:43.233Z,1550793163.233 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:52:52.189Z,1550793172.189 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:53:00.865Z,1550793180.865 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:53:09.189Z,1550793189.189 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:53:18.429Z,1550793198.429 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:53:27.717Z,1550793207.717 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:53:35.870Z,1550793215.870 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:53:36.292Z,1550793216.292 [PAR_Licor](INFO): A/D timeout, 7 tries over 127 ms

2019-02-21T23:53:36.292Z,1550793216.292 [PAR_Licor] Data Fault, FailCount= 1
2019-02-21T23:53:36.292Z,1550793216.292 [PAR_Licor](ERROR): Data Fault
2019-02-21T23:53:36.843Z,1550793216.843 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-21T23:53:36.843Z,1550793216.843 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-21T23:53:36.843Z,1550793216.843 [PAR_Licor] No Fault, FailCount= 1
2019-02-21T23:53:44.961Z,1550793224.961 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:53:54.117Z,1550793234.117 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:54:03.137Z,1550793243.137 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:54:11.242Z,1550793251.242 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:54:19.246Z,1550793259.246 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:54:28.045Z,1550793268.045 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:54:36.612Z,1550793276.612 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:54:46.648Z,1550793286.648 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:54:50.041Z,1550793290.041 [PAR_Licor](INFO): A/D timeout, 5 tries over 158 ms

2019-02-21T23:54:50.041Z,1550793290.041 [PAR_Licor] Data Fault, FailCount= 1
2019-02-21T23:54:50.041Z,1550793290.041 [PAR_Licor](ERROR): Data Fault
2019-02-21T23:54:50.407Z,1550793290.407 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-21T23:54:50.408Z,1550793290.408 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-21T23:54:50.408Z,1550793290.408 [PAR_Licor] No Fault, FailCount= 1
2019-02-21T23:54:55.090Z,1550793295.090 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:55:03.153Z,1550793303.153 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:55:13.005Z,1550793313.005 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:55:20.521Z,1550793320.521 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:55:29.704Z,1550793329.704 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:55:38.337Z,1550793338.337 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:55:46.856Z,1550793346.856 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2019-02-21T23:55:46.856Z,1550793346.856 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-02-21T23:55:46.906Z,1550793346.906 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 9
2019-02-21T23:55:46.906Z,1550793346.906 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-02-21T23:55:46.907Z,1550793346.907 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 9
2019-02-21T23:55:46.907Z,1550793346.907 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-21T23:55:47.066Z,1550793347.066 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-02-21T23:55:47.066Z,1550793347.066 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-02-21T23:55:47.066Z,1550793347.066 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-21T23:55:47.457Z,1550793347.457 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-02-21T23:55:47.457Z,1550793347.457 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2019-02-21T23:55:47.458Z,1550793347.458 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-02-21T23:55:47.458Z,1550793347.458 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 9
2019-02-21T23:55:47.458Z,1550793347.458 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-21T23:55:47.458Z,1550793347.458 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 9
2019-02-21T23:55:47.493Z,1550793347.493 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:55:47.728Z,1550793347.728 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-21T23:55:55.929Z,1550793355.929 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:56:06.197Z,1550793366.197 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:56:17.785Z,1550793377.785 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:56:19.041Z,1550793379.041 [PAR_Licor](INFO): A/D timeout, 4 tries over 161 ms

2019-02-21T23:56:19.041Z,1550793379.041 [PAR_Licor] Data Fault, FailCount= 1
2019-02-21T23:56:19.041Z,1550793379.041 [PAR_Licor](ERROR): Data Fault
2019-02-21T23:56:19.512Z,1550793379.512 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-21T23:56:19.513Z,1550793379.513 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-21T23:56:19.513Z,1550793379.513 [PAR_Licor] No Fault, FailCount= 1
2019-02-21T23:56:26.749Z,1550793386.749 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:56:36.393Z,1550793396.393 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:56:45.501Z,1550793405.501 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:56:56.702Z,1550793416.702 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:57:06.361Z,1550793426.361 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:57:13.989Z,1550793433.989 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:57:22.831Z,1550793442.831 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:57:30.737Z,1550793450.737 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:57:39.145Z,1550793459.145 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:57:47.261Z,1550793467.261 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:57:56.055Z,1550793476.055 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:58:04.489Z,1550793484.489 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:58:13.179Z,1550793493.179 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:58:21.137Z,1550793501.137 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:58:31.249Z,1550793511.249 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:58:39.397Z,1550793519.397 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:58:47.750Z,1550793527.750 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2019-02-21T23:58:47.750Z,1550793527.750 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-02-21T23:58:47.752Z,1550793527.752 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 10
2019-02-21T23:58:47.752Z,1550793527.752 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-02-21T23:58:47.845Z,1550793527.845 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 10
2019-02-21T23:58:47.845Z,1550793527.845 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-21T23:58:47.944Z,1550793527.944 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-02-21T23:58:47.944Z,1550793527.944 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-02-21T23:58:47.944Z,1550793527.944 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-21T23:58:48.157Z,1550793528.157 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:58:48.737Z,1550793528.737 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-02-21T23:58:48.737Z,1550793528.737 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2019-02-21T23:58:48.738Z,1550793528.738 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-02-21T23:58:48.738Z,1550793528.738 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 10
2019-02-21T23:58:48.738Z,1550793528.738 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-21T23:58:48.739Z,1550793528.739 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 10
2019-02-21T23:58:49.299Z,1550793529.299 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-21T23:58:51.245Z,1550793531.245 [PAR_Licor](INFO): A/D timeout, 6 tries over 146 ms

2019-02-21T23:58:51.245Z,1550793531.245 [PAR_Licor] Data Fault, FailCount= 1
2019-02-21T23:58:51.245Z,1550793531.245 [PAR_Licor](ERROR): Data Fault
2019-02-21T23:58:51.745Z,1550793531.745 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-21T23:58:51.745Z,1550793531.745 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-21T23:58:51.745Z,1550793531.745 [PAR_Licor] No Fault, FailCount= 1
2019-02-21T23:58:57.299Z,1550793537.299 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:59:06.445Z,1550793546.445 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:59:14.242Z,1550793554.242 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:59:21.990Z,1550793561.990 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:59:30.083Z,1550793570.083 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:59:37.909Z,1550793577.909 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:59:38.561Z,1550793578.561 [PAR_Licor](INFO): A/D timeout, 3 tries over 150 ms

2019-02-21T23:59:38.561Z,1550793578.561 [PAR_Licor] Data Fault, FailCount= 1
2019-02-21T23:59:38.561Z,1550793578.561 [PAR_Licor](ERROR): Data Fault
2019-02-21T23:59:39.027Z,1550793579.027 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-21T23:59:39.027Z,1550793579.027 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-21T23:59:39.027Z,1550793579.027 [PAR_Licor] No Fault, FailCount= 1
2019-02-21T23:59:46.308Z,1550793586.308 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:59:48.089Z,1550793588.089 [PAR_Licor](INFO): A/D timeout, 7 tries over 144 ms

2019-02-21T23:59:48.089Z,1550793588.089 [PAR_Licor] Data Fault, FailCount= 1
2019-02-21T23:59:48.089Z,1550793588.089 [PAR_Licor](ERROR): Data Fault
2019-02-21T23:59:48.586Z,1550793588.586 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-21T23:59:48.586Z,1550793588.586 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-21T23:59:48.586Z,1550793588.586 [PAR_Licor] No Fault, FailCount= 1
2019-02-21T23:59:56.170Z,1550793596.170 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:00:05.341Z,1550793605.341 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:00:12.664Z,1550793612.664 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:00:22.792Z,1550793622.792 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:00:32.652Z,1550793632.652 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-02-22T00:00:32.652Z,1550793632.652 [NAL9602] Data Fault, FailCount= 4
2019-02-22T00:00:32.652Z,1550793632.652 [NAL9602](ERROR): Data Fault
2019-02-22T00:00:33.277Z,1550793633.277 [CBIT](ERROR): Data Fault in component: NAL9602
2019-02-22T00:00:33.292Z,1550793633.292 [NAL9602](INFO): Powering down
2019-02-22T00:00:35.146Z,1550793635.146 [CBIT](INFO): Clearing failed state for component NAL9602
2019-02-22T00:00:35.147Z,1550793635.147 [NAL9602] No Fault, FailCount= 4
2019-02-22T00:00:35.385Z,1550793635.385 [PAR_Licor](INFO): A/D timeout, 3 tries over 130 ms

2019-02-22T00:00:35.385Z,1550793635.385 [PAR_Licor] Data Fault, FailCount= 1
2019-02-22T00:00:35.385Z,1550793635.385 [PAR_Licor](ERROR): Data Fault
2019-02-22T00:00:36.062Z,1550793636.062 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-22T00:00:36.062Z,1550793636.062 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-22T00:00:36.062Z,1550793636.062 [PAR_Licor] No Fault, FailCount= 1
2019-02-22T00:01:03.451Z,1550793663.451 [NAL9602](INFO): Powering up NAL9602
2019-02-22T00:01:15.133Z,1550793675.133 [NAL9602](INFO): NAL9602 initialized
2019-02-22T00:01:16.317Z,1550793676.317 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:01:26.033Z,1550793686.033 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:01:34.321Z,1550793694.321 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:01:36.317Z,1550793696.317 [PAR_Licor](INFO): A/D timeout, 3 tries over 133 ms

2019-02-22T00:01:36.317Z,1550793696.317 [PAR_Licor] Data Fault, FailCount= 1
2019-02-22T00:01:36.317Z,1550793696.317 [PAR_Licor](ERROR): Data Fault
2019-02-22T00:01:36.884Z,1550793696.884 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-22T00:01:36.884Z,1550793696.884 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-22T00:01:36.884Z,1550793696.884 [PAR_Licor] No Fault, FailCount= 1
2019-02-22T00:01:42.507Z,1550793702.507 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:01:50.787Z,1550793710.787 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2019-02-22T00:01:50.787Z,1550793710.787 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-02-22T00:01:50.788Z,1550793710.788 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 11
2019-02-22T00:01:50.829Z,1550793710.829 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-02-22T00:01:50.830Z,1550793710.830 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 11
2019-02-22T00:01:50.830Z,1550793710.830 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-22T00:01:50.983Z,1550793710.983 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-02-22T00:01:50.983Z,1550793710.983 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-02-22T00:01:50.983Z,1550793710.983 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-22T00:01:51.544Z,1550793711.544 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-02-22T00:01:51.544Z,1550793711.544 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2019-02-22T00:01:51.544Z,1550793711.544 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-02-22T00:01:51.544Z,1550793711.544 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 11
2019-02-22T00:01:51.597Z,1550793711.597 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-22T00:01:51.597Z,1550793711.597 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 11
2019-02-22T00:01:51.598Z,1550793711.598 [CBIT](INFO): Clearing failed state for component BPC1
2019-02-22T00:01:51.598Z,1550793711.598 [BPC1] No Fault, FailCount= 3
2019-02-22T00:01:51.713Z,1550793711.713 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:01:51.961Z,1550793711.961 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-22T00:01:59.221Z,1550793719.221 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:02:09.969Z,1550793729.969 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:02:18.849Z,1550793738.849 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:02:26.573Z,1550793746.573 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:02:35.027Z,1550793755.027 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:02:44.061Z,1550793764.061 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:02:52.293Z,1550793772.293 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:03:02.205Z,1550793782.205 [BPC1](FAULT): Failed to parse data from all battery packs.
2019-02-22T00:03:02.205Z,1550793782.205 [BPC1] Data Fault, FailCount= 4
2019-02-22T00:03:02.206Z,1550793782.206 [BPC1](ERROR): Data Fault
2019-02-22T00:03:02.460Z,1550793782.460 [CBIT](ERROR): Data Fault in component: BPC1
2019-02-22T00:03:02.633Z,1550793782.633 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:03:11.930Z,1550793791.930 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:03:20.142Z,1550793800.142 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:03:28.558Z,1550793808.558 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:03:37.302Z,1550793817.302 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:03:46.077Z,1550793826.077 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:03:55.741Z,1550793835.741 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:04:04.524Z,1550793844.524 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:04:12.614Z,1550793852.614 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:04:20.658Z,1550793860.658 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:04:28.809Z,1550793868.809 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:04:36.613Z,1550793876.613 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:04:45.169Z,1550793885.169 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:04:53.062Z,1550793893.062 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2019-02-22T00:04:53.062Z,1550793893.062 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-02-22T00:04:53.063Z,1550793893.063 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 12
2019-02-22T00:04:53.063Z,1550793893.063 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-02-22T00:04:53.064Z,1550793893.064 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 12
2019-02-22T00:04:53.064Z,1550793893.064 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-22T00:04:53.212Z,1550793893.212 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-02-22T00:04:53.212Z,1550793893.212 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-02-22T00:04:53.212Z,1550793893.212 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-22T00:04:53.757Z,1550793893.757 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-02-22T00:04:53.758Z,1550793893.758 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2019-02-22T00:04:53.758Z,1550793893.758 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-02-22T00:04:53.758Z,1550793893.758 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 12
2019-02-22T00:04:53.759Z,1550793893.759 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-22T00:04:53.759Z,1550793893.759 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 12
2019-02-22T00:04:53.905Z,1550793893.905 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:04:54.209Z,1550793894.209 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-22T00:05:02.337Z,1550793902.337 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:05:05.381Z,1550793905.381 [PAR_Licor](INFO): A/D timeout, 9 tries over 143 ms

2019-02-22T00:05:05.381Z,1550793905.381 [PAR_Licor] Data Fault, FailCount= 1
2019-02-22T00:05:05.382Z,1550793905.382 [PAR_Licor](ERROR): Data Fault
2019-02-22T00:05:05.897Z,1550793905.897 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-22T00:05:05.897Z,1550793905.897 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-22T00:05:05.897Z,1550793905.897 [PAR_Licor] No Fault, FailCount= 1
2019-02-22T00:05:11.001Z,1550793911.001 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:05:19.618Z,1550793919.618 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:05:27.813Z,1550793927.813 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:05:36.005Z,1550793936.005 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:05:44.373Z,1550793944.373 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:05:51.654Z,1550793951.654 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:05:55.945Z,1550793955.945 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:05:59.985Z,1550793959.985 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:06:00.182Z,1550793960.182 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003975
2019-02-22T00:06:02.429Z,1550793962.429 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:06:05.184Z,1550793965.184 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20190221T232654/Express0005.lzma
2019-02-22T00:06:05.641Z,1550793965.641 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:06:05.990Z,1550793965.990 [DataOverHttps](INFO): Moved sent file to Logs/20190221T232654/Express0005.lzma.bak
2019-02-22T00:06:05.990Z,1550793965.990 [DataOverHttps](INFO): SBD MOMSN=10068183
2019-02-22T00:06:06.917Z,1550793966.917 [Default:CheckIn:Read_Iridium] Stopped
2019-02-22T00:06:06.917Z,1550793966.917 [Default:CheckIn:C.Wait] Running Loop=1
2019-02-22T00:06:06.917Z,1550793966.917 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-02-22T00:06:08.485Z,1550793968.485 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:06:11.721Z,1550793971.721 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:06:14.545Z,1550793974.545 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:06:17.485Z,1550793977.485 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:06:19.397Z,1550793979.397 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:06:22.685Z,1550793982.685 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:06:25.457Z,1550793985.457 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:06:28.689Z,1550793988.689 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:06:31.517Z,1550793991.517 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:06:34.749Z,1550793994.749 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:06:37.577Z,1550793997.577 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:06:40.809Z,1550794000.809 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:06:43.633Z,1550794003.633 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:06:46.461Z,1550794006.461 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:06:49.693Z,1550794009.693 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:06:52.541Z,1550794012.541 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:06:55.777Z,1550794015.777 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:06:58.601Z,1550794018.601 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:07:01.838Z,1550794021.838 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:07:04.737Z,1550794024.737 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:07:07.561Z,1550794027.561 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:07:10.761Z,1550794030.761 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:07:13.557Z,1550794033.557 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:07:16.785Z,1550794036.785 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:07:19.617Z,1550794039.617 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:07:22.841Z,1550794042.841 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:07:25.677Z,1550794045.677 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:07:28.505Z,1550794048.505 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:07:30.525Z,1550794050.525 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:07:33.757Z,1550794053.757 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:07:36.585Z,1550794056.585 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:07:39.809Z,1550794059.809 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:07:42.645Z,1550794062.645 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:07:45.110Z,1550794065.110 [CommandLine](IMPORTANT): got command quit
2019-02-22T00:07:45.873Z,1550794065.873 [NAL9602](DEBUG): Fix Requested
2019-02-22T00:07:46.121Z,1550794066.121 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-22T00:07:46.121Z,1550794066.121 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-02-22T00:07:46.185Z,1550794066.185 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-02-22T00:07:46.185Z,1550794066.185 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-02-22T00:07:46.186Z,1550794066.186 [CommandLine](INFO): Join timeout helper Thread ID is 4208
2019-02-22T00:07:46.193Z,1550794066.193 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-02-22T00:07:46.193Z,1550794066.193 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-02-22T00:07:46.193Z,1550794066.193 [NavChartDb](INFO): Join timeout helper Thread ID is 4209
2019-02-22T00:07:46.221Z,1550794066.221 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-22T00:07:46.221Z,1550794066.221 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-02-22T00:07:46.222Z,1550794066.222 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-02-22T00:07:46.222Z,1550794066.222 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-02-22T00:07:46.222Z,1550794066.222 [Radio_Surface](INFO): Join timeout helper Thread ID is 4210
2019-02-22T00:07:46.453Z,1550794066.453 [Radio_Surface](INFO): Powering down
2019-02-22T00:07:46.454Z,1550794066.454 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-22T00:07:46.454Z,1550794066.454 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-02-22T00:07:46.469Z,1550794066.469 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-02-22T00:07:46.469Z,1550794066.469 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-02-22T00:07:46.469Z,1550794066.469 [DataOverHttps](INFO): Join timeout helper Thread ID is 4211
2019-02-22T00:07:47.705Z,1550794067.705 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-22T00:07:47.708Z,1550794067.708 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-02-22T00:07:47.725Z,1550794067.725 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-02-22T00:07:47.725Z,1550794067.725 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-02-22T00:07:47.725Z,1550794067.725 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 4212
2019-02-22T00:07:48.053Z,1550794068.053 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-22T00:07:48.053Z,1550794068.053 [WetLabsBB2FL](INFO): Powering down
2019-02-22T00:07:48.054Z,1550794068.054 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-02-22T00:07:48.058Z,1550794068.058 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-02-22T00:07:48.058Z,1550794068.058 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-02-22T00:07:48.058Z,1550794068.058 [CTD_Seabird](INFO): Join timeout helper Thread ID is 4213
2019-02-22T00:07:48.585Z,1550794068.585 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-22T00:07:48.585Z,1550794068.585 [CTD_Seabird](INFO): Powering down
2019-02-22T00:07:48.597Z,1550794068.597 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-02-22T00:07:48.614Z,1550794068.614 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-02-22T00:07:48.614Z,1550794068.614 [logger ThreadHandler](INFO): Thread cancelled.
2019-02-22T00:07:48.614Z,1550794068.614 [logger](INFO): Join timeout helper Thread ID is 4214
2019-02-22T00:07:48.669Z,1550794068.669 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-22T00:07:48.669Z,1550794068.669 [logger ThreadHandler](INFO): Thread cancelled.
2019-02-22T00:07:48.674Z,1550794068.674 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-02-22T00:07:48.674Z,1550794068.674 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-02-22T00:07:48.674Z,1550794068.674 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-02-22T00:07:48.674Z,1550794068.674 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-02-22T00:07:48.674Z,1550794068.674 [controlThread](INFO): Join timeout helper Thread ID is 4215
2019-02-22T00:07:48.677Z,1550794068.677 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-22T00:07:48.677Z,1550794068.677 [controlThread](DEBUG): Uninitializing ControlThread
2019-02-22T00:07:48.678Z,1550794068.678 [Aanderaa_O2](INFO): Powering down
2019-02-22T00:07:48.680Z,1550794068.680 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-02-22T00:07:48.797Z,1550794068.797 [NAL9602](INFO): Powering down
2019-02-22T00:07:48.799Z,1550794068.799 [PNI_TCM](INFO): Powering down
2019-02-22T00:07:48.886Z,1550794068.886 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-02-22T00:07:48.887Z,1550794068.887 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-02-22T00:07:48.887Z,1550794068.887 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-02-22T00:07:48.888Z,1550794068.888 [MissionManager](INFO): Uninitializing Mission Default
2019-02-22T00:07:48.888Z,1550794068.888 [Default] Stopped
2019-02-22T00:07:48.888Z,1550794068.888 [Default](DEBUG): Aggregate::uninitialize Default
2019-02-22T00:07:48.888Z,1550794068.888 [Default:B.GoToSurface] Stopped
2019-02-22T00:07:48.888Z,1550794068.888 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-02-22T00:07:48.888Z,1550794068.888 [Default:CheckIn] Stopped
2019-02-22T00:07:48.888Z,1550794068.888 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-02-22T00:07:48.888Z,1550794068.888 [Default:CheckIn:C.Wait] Stopped
2019-02-22T00:07:48.888Z,1550794068.888 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-02-22T00:07:48.891Z,1550794068.891 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-02-22T00:07:48.891Z,1550794068.891 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-02-22T00:07:48.891Z,1550794068.891 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-02-22T00:07:48.892Z,1550794068.892 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-02-22T00:07:48.892Z,1550794068.892 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-02-22T00:07:48.892Z,1550794068.892 [BuoyancyServo](INFO): Powering down
2019-02-22T00:07:48.905Z,1550794068.905 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-02-22T00:07:48.905Z,1550794068.905 [ElevatorServo](INFO): Powering down
2019-02-22T00:07:48.906Z,1550794068.906 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-02-22T00:07:48.906Z,1550794068.906 [MassServo](INFO): Powering down
2019-02-22T00:07:48.907Z,1550794068.907 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-02-22T00:07:48.907Z,1550794068.907 [RudderServo](INFO): Powering down
2019-02-22T00:07:48.908Z,1550794068.908 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-02-22T00:07:48.908Z,1550794068.908 [ThrusterServo](INFO): Powering down
2019-02-22T00:07:48.908Z,1550794068.908 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-02-22T00:07:48.909Z,1550794068.909 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-02-22T00:07:48.909Z,1550794068.909 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-02-22T00:07:48.909Z,1550794068.909 [CBIT](DEBUG): Powering off loads.
2019-02-22T00:07:48.921Z,1550794068.921 [CBIT](DEBUG): Disabling WDT.
2019-02-22T00:07:48.933Z,1550794068.933 [CBIT](DEBUG): Opening all GF detection circuits.
2019-02-22T00:07:48.934Z,1550794068.934 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-02-22T00:07:48.980Z,1550794068.980 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-02-22T00:07:48.985Z,1550794068.985 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-02-22T00:07:49.073Z,1550794069.073 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-02-22T00:07:49.078Z,1550794069.078 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-02-22T00:07:49.124Z,1550794069.124 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-02-22T00:07:49.179Z,1550794069.179 [logger ThreadHandler](INFO): Thread cancelled.