2021-04-11T18:30:45.679Z,1618165845.679 [CommandLine](IMPORTANT): got command restart logs 2021-04-11T18:30:47.410Z,1618165847.410 [Default:A.Wait](INFO): Done Waiting. 2021-04-11T18:30:47.410Z,1618165847.410 [Default:A.Wait] Stopped 2021-04-11T18:30:47.410Z,1618165847.410 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2021-04-11T18:30:47.786Z,1618165847.786 [Default:CheckIn] Running Loop=1 2021-04-11T18:30:47.787Z,1618165847.787 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-11T18:30:47.787Z,1618165847.787 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-11T18:30:53.886Z,1618165853.886 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2021-04-11T18:30:59.890Z,1618165859.890 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2021-04-11T18:30:59.890Z,1618165859.890 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-04-11T18:30:59.900Z,1618165859.900 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-04-11T18:31:00.318Z,1618165860.318 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-04-11T18:31:00.318Z,1618165860.318 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2021-04-11T18:31:06.006Z,1618165866.006 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2021-04-11T18:31:17.743Z,1618165877.743 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2021-04-11T18:31:29.842Z,1618165889.842 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2021-04-11T18:31:41.558Z,1618165901.558 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2021-04-11T18:31:48.375Z,1618165908.375 [DVL_micro](ERROR): only read 1 of 4 data items 2021-04-11T18:31:48.375Z,1618165908.375 [DVL_micro](ERROR): Failed to parse: :BI,+00919+00406,+00000,I 2021-04-11T18:31:53.676Z,1618165913.676 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2021-04-11T18:32:39.276Z,1618165959.276 [NAL9602](FAULT): GPS failed to acquire within timeout. 2021-04-11T18:32:39.276Z,1618165959.276 [NAL9602] Data Fault, FailCount= 1 2021-04-11T18:32:39.276Z,1618165959.276 [NAL9602](ERROR): Data Fault 2021-04-11T18:32:39.293Z,1618165959.293 [CBIT](ERROR): Data Fault in component: NAL9602 2021-04-11T18:32:39.675Z,1618165959.675 [NAL9602](INFO): Powering down 2021-04-11T18:32:40.071Z,1618165960.071 [NAL9602](FAULT): LCB fault: Software Overcurrent. 2021-04-11T18:32:40.071Z,1618165960.071 [NAL9602] Hardware Fault, FailCount= 1 2021-04-11T18:32:40.071Z,1618165960.071 [NAL9602](ERROR): Hardware Fault 2021-04-11T18:32:40.496Z,1618165960.496 [CBIT](INFO): Clearing failed state for component NAL9602 2021-04-11T18:32:40.497Z,1618165960.497 [NAL9602] No Fault, FailCount= 1 2021-04-11T18:32:51.043Z,1618165971.043 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2021-04-11T18:33:09.980Z,1618165989.980 [NAL9602](INFO): Powering up NAL9602 2021-04-11T18:33:13.256Z,1618165993.256 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2021-04-11T18:33:20.899Z,1618166000.899 [NAL9602](INFO): NAL9602 initialized 2021-04-11T18:33:55.054Z,1618166035.054 [CommandLine](IMPORTANT): got command run ./Missions/esp_send_test.xml 2021-04-11T18:33:55.054Z,1618166035.054 [MissionManager](INFO): Loading Mission: ./Missions/esp_send_test.xml 2021-04-11T18:33:55.149Z,1618166035.149 [MissionManager](INFO): DefineArg esp_sample_at_depth.MissionTimeout = 4.000000 h 2021-04-11T18:33:55.152Z,1618166035.152 [MissionManager](INFO): DefineArg esp_sample_at_depth.ESPCartridgeType_1 = -4.000000 count 2021-04-11T18:33:55.155Z,1618166035.155 [MissionManager](INFO): DefineArg esp_sample_at_depth.ESPCartridgeType_2 = -15.000000 count 2021-04-11T18:33:55.158Z,1618166035.158 [MissionManager](INFO): DefineArg esp_sample_at_depth.ESPCartridgeType_3 = nan count 2021-04-11T18:33:55.160Z,1618166035.160 [MissionManager](INFO): DefineArg esp_sample_at_depth.ESPCartridgeType_4 = nan count 2021-04-11T18:33:55.196Z,1618166035.196 [MissionManager](INFO): DefineOutput esp_sample_at_depth.ESPCartridgeType = nan count 2021-04-11T18:33:55.198Z,1618166035.198 [MissionManager](INFO): DefineOutput esp_sample_at_depth.CntSamples = 1.000000 count 2021-04-11T18:33:55.199Z,1618166035.199 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml 2021-04-11T18:33:55.513Z,1618166035.513 [MissionManager](INFO): DefineArg esp_sample_at_depth:Science.PeakDetectChlActive = 0 bool 2021-04-11T18:33:55.516Z,1618166035.516 [MissionManager](INFO): DefineArg esp_sample_at_depth:Science.TimeWindowPeakReport = nan min 2021-04-11T18:33:55.519Z,1618166035.519 [MissionManager](INFO): DefineArg esp_sample_at_depth:Science.HighestChlPeakReportActive = 0 bool 2021-04-11T18:33:55.522Z,1618166035.522 [MissionManager](INFO): DefineArg esp_sample_at_depth:Science.PatchTracking = 0 bool 2021-04-11T18:33:55.524Z,1618166035.524 [MissionManager](INFO): DefineArg esp_sample_at_depth:Science.FilterWidthHorizontal = 3.000000 count 2021-04-11T18:33:55.528Z,1618166035.528 [MissionManager](INFO): DefineArg esp_sample_at_depth:Science.NumProfilesSlidingwindow = 100.000000 count 2021-04-11T18:33:55.531Z,1618166035.531 [MissionManager](INFO): DefineArg esp_sample_at_depth:Science.OffPeakFractionHorizontal = 80.000000 % 2021-04-11T18:33:55.534Z,1618166035.534 [MissionManager](INFO): DefineArg esp_sample_at_depth:Science.PeakDetectNO3Active = 0 bool 2021-04-11T18:33:55.536Z,1618166035.536 [MissionManager](INFO): DefineArg esp_sample_at_depth:Science.PeakDetectOilActive = 0 bool 2021-04-11T18:33:55.539Z,1618166035.539 [MissionManager](INFO): DefineArg esp_sample_at_depth:Science.PeakDetectFDOMActive = 0 bool 2021-04-11T18:33:55.562Z,1618166035.562 [MissionManager](INFO): DefineArg esp_sample_at_depth:Science.UpwardDerivativeOfTemperatureActive = 0 bool 2021-04-11T18:33:55.573Z,1618166035.573 [MissionManager](INFO): DefineArg esp_sample_at_depth:Science.EnabledAanderaaO2 = 0.000000 bool 2021-04-11T18:33:55.577Z,1618166035.577 [MissionManager](INFO): DefineArg esp_sample_at_depth:Science.EnabledNeilBrown = 0.000000 bool 2021-04-11T18:33:55.584Z,1618166035.584 [MissionManager](INFO): DefineArg esp_sample_at_depth:Science.EnabledSeabird = 1.000000 bool 2021-04-11T18:33:55.592Z,1618166035.592 [MissionManager](INFO): DefineArg esp_sample_at_depth:Science.EnabledWetLabsBB2FL = 1.000000 bool 2021-04-11T18:33:55.600Z,1618166035.600 [MissionManager](INFO): DefineArg esp_sample_at_depth:Science.EnabledWetLabsSeaOWL_UV_A = 0.000000 bool 2021-04-11T18:33:55.612Z,1618166035.612 [MissionManager](INFO): DefineArg esp_sample_at_depth:Science.EnabledWetLabsUBAT = 0.000000 bool 2021-04-11T18:33:55.619Z,1618166035.619 [MissionManager](INFO): DefineArg esp_sample_at_depth:Science.LowPassWindowLength = 20.000000 count 2021-04-11T18:33:55.659Z,1618166035.659 [MissionManager](INFO): DefineArg esp_sample_at_depth:Science.MedianFilterLen = 5.000000 count 2021-04-11T18:33:55.665Z,1618166035.665 [MissionManager](INFO): DefineArg esp_sample_at_depth:Science.PeakShallowBound = nan m 2021-04-11T18:33:55.668Z,1618166035.668 [MissionManager](INFO): DefineArg esp_sample_at_depth:Science.PeakDeepBound = nan m 2021-04-11T18:33:55.680Z,1618166035.680 [MissionManager](INFO): DefineOutput esp_sample_at_depth:Science.PeakChl = 0.000000 ug/l 2021-04-11T18:33:55.696Z,1618166035.696 [MissionManager](INFO): DefineOutput esp_sample_at_depth:Science.PeakChlDepth = 0.000000 m 2021-04-11T18:33:55.707Z,1618166035.707 [MissionManager](INFO): DefineOutput esp_sample_at_depth:Science.PeakChlTemperature = 0.000000 degC 2021-04-11T18:33:55.715Z,1618166035.715 [MissionManager](INFO): DefineOutput esp_sample_at_depth:Science.PeakChlLatitude = nan arcdeg 2021-04-11T18:33:55.720Z,1618166035.720 [MissionManager](INFO): DefineOutput esp_sample_at_depth:Science.PeakChlLongitude = nan arcdeg 2021-04-11T18:33:55.751Z,1618166035.751 [MissionManager](INFO): DefineOutput esp_sample_at_depth:Science.PeakNO3 = 0.000000 umol/l 2021-04-11T18:33:55.755Z,1618166035.755 [MissionManager](INFO): DefineOutput esp_sample_at_depth:Science.PeakNO3Depth = 0.000000 m 2021-04-11T18:33:55.777Z,1618166035.777 [MissionManager](INFO): DefineOutput esp_sample_at_depth:Science.PeakNO3Latitude = nan arcdeg 2021-04-11T18:33:55.780Z,1618166035.780 [MissionManager](INFO): DefineOutput esp_sample_at_depth:Science.PeakNO3Longitude = nan arcdeg 2021-04-11T18:33:55.794Z,1618166035.794 [MissionManager](INFO): DefineOutput esp_sample_at_depth:Science.PatchChl = 0.000000 m 2021-04-11T18:33:55.801Z,1618166035.801 [MissionManager](INFO): DefineOutput esp_sample_at_depth:Science.PatchChlDepth = 0.000000 m 2021-04-11T18:33:55.804Z,1618166035.804 [MissionManager](INFO): DefineOutput esp_sample_at_depth:Science.PatchChlLatitude = nan arcdeg 2021-04-11T18:33:55.812Z,1618166035.812 [MissionManager](INFO): DefineOutput esp_sample_at_depth:Science.PatchChlLongitude = nan arcdeg 2021-04-11T18:33:55.819Z,1618166035.819 [MissionManager](INFO): DefineOutput esp_sample_at_depth:Science.PatchChlDistance = nan m 2021-04-11T18:33:55.822Z,1618166035.822 [MissionManager](INFO): DefineOutput esp_sample_at_depth:Science.PeakOil = 0.000000 kg/m3 2021-04-11T18:33:55.842Z,1618166035.842 [MissionManager](INFO): DefineOutput esp_sample_at_depth:Science.PeakOilDepth = 0.000000 m 2021-04-11T18:33:55.850Z,1618166035.850 [MissionManager](INFO): DefineOutput esp_sample_at_depth:Science.PeakOilLatitude = nan arcdeg 2021-04-11T18:33:55.859Z,1618166035.859 [MissionManager](INFO): DefineOutput esp_sample_at_depth:Science.PeakOilLongitude = nan arcdeg 2021-04-11T18:33:55.867Z,1618166035.867 [MissionManager](INFO): DefineOutput esp_sample_at_depth:Science.PeakFDOM = 0.000000 ppb 2021-04-11T18:33:55.874Z,1618166035.874 [MissionManager](INFO): DefineOutput esp_sample_at_depth:Science.PeakFDOMDepth = 0.000000 m 2021-04-11T18:33:55.885Z,1618166035.885 [MissionManager](INFO): DefineOutput esp_sample_at_depth:Science.PeakFDOMTemperature = nan degC 2021-04-11T18:33:55.888Z,1618166035.888 [MissionManager](INFO): DefineOutput esp_sample_at_depth:Science.PeakFDOMLatitude = nan arcdeg 2021-04-11T18:33:55.905Z,1618166035.905 [MissionManager](INFO): DefineOutput esp_sample_at_depth:Science.PeakFDOMLongitude = nan arcdeg 2021-04-11T18:33:55.908Z,1618166035.908 [MissionManager](INFO): DefineOutput esp_sample_at_depth:Science.PatchFDOMDistance = nan m 2021-04-11T18:33:55.992Z,1618166035.992 [esp_sample_at_depth:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2021-04-11T18:33:56.062Z,1618166036.062 [esp_sample_at_depth:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal. 2021-04-11T18:33:56.115Z,1618166036.115 [esp_sample_at_depth:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2021-04-11T18:33:56.149Z,1618166036.149 [esp_sample_at_depth:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2021-04-11T18:33:56.168Z,1618166036.168 [esp_sample_at_depth:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2021-04-11T18:33:56.195Z,1618166036.195 [esp_sample_at_depth:B.AbortSample](DEBUG): Construct. 2021-04-11T18:33:56.258Z,1618166036.258 [esp_sample_at_depth:SampleESP:sample:B.ESPCartridgeSelect](DEBUG): Construct. 2021-04-11T18:33:56.268Z,1618166036.268 [esp_sample_at_depth:SampleESP:sample:E.Wait](DEBUG): Construct Wait. 2021-04-11T18:33:56.354Z,1618166036.354 [MissionManager](DEBUG): This mission takes ESP samples at the designated depth. Maximum duration of mission. 4 Specifies ESP cartridge type code for sample# 1. Set to NaN to skip this sample. -4 Specifies ESP cartridge type code for sample# 2. Set to NaN to skip this sample. -15 Specifies ESP cartridge type code for sample# 3. Set to NaN to skip this sample. NaN Specifies ESP cartridge type code for sample# 4. Set to NaN to skip this sample. NaN Mission parameter (don't change). Specifies ESP cartridge type code. NaN Count of water samples (initilized to 1). 1 ESPComponent.sample_number = ESPComponent.cartridge = Science:ESPComponent.sampleVolume = Universal:depth = Universal:sea_water_temperature = Universal:mass_concentration_of_chlorophyll_in_sea_water = Requesting ESP cartridge type: ESP sampling at in water with chlorophyll fluorescence. 30 1 Acquire ESP samples. 2021-04-11T18:33:56.357Z,1618166036.357 [CommandLine](IMPORTANT): Running ./Missions/esp_send_test.xml 2021-04-11T18:33:56.481Z,1618166036.481 [Default] Stopped 2021-04-11T18:33:56.482Z,1618166036.482 [Default](DEBUG): Aggregate::uninitialize Default 2021-04-11T18:33:56.482Z,1618166036.482 [Default:B.GoToSurface] Stopped 2021-04-11T18:33:56.482Z,1618166036.482 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2021-04-11T18:33:56.482Z,1618166036.482 [Default:CheckIn] Stopped 2021-04-11T18:33:56.482Z,1618166036.482 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-11T18:33:56.482Z,1618166036.482 [Default:CheckIn:Read_GPS] Stopped 2021-04-11T18:33:56.482Z,1618166036.482 [MissionManager](IMPORTANT): Started mission esp_sample_at_depth 2021-04-11T18:33:56.482Z,1618166036.482 [esp_sample_at_depth] Running Loop=1 2021-04-11T18:33:56.483Z,1618166036.483 [esp_sample_at_depth](DEBUG): Aggregate::initialize esp_sample_at_depth 2021-04-11T18:33:56.483Z,1618166036.483 [esp_sample_at_depth:Science] Running Loop=1 2021-04-11T18:33:56.483Z,1618166036.483 [esp_sample_at_depth:Science](DEBUG): Aggregate::initialize esp_sample_at_depth:Science 2021-04-11T18:33:56.483Z,1618166036.483 [esp_sample_at_depth:Science:A] Running Loop=1 2021-04-11T18:33:56.483Z,1618166036.483 [esp_sample_at_depth:Science:B] Running Loop=1 2021-04-11T18:33:56.483Z,1618166036.483 [esp_sample_at_depth:Science:C] Running Loop=1 2021-04-11T18:33:56.483Z,1618166036.483 [esp_sample_at_depth:Science:D] Running Loop=1 2021-04-11T18:33:56.483Z,1618166036.483 [esp_sample_at_depth:Science:E] Running Loop=1 2021-04-11T18:33:56.483Z,1618166036.483 [esp_sample_at_depth:Science:F] Running Loop=1 2021-04-11T18:33:56.483Z,1618166036.483 [esp_sample_at_depth:Science:Read_Oil] Running Loop=1 2021-04-11T18:33:56.483Z,1618166036.483 [esp_sample_at_depth:Science:PeakDetectChl] Running Loop=1 2021-04-11T18:33:56.483Z,1618166036.483 [esp_sample_at_depth:Science:PeakDetectChl](DEBUG): Aggregate::initialize esp_sample_at_depth:Science:PeakDetectChl 2021-04-11T18:33:56.492Z,1618166036.492 [esp_sample_at_depth:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1 2021-04-11T18:33:56.492Z,1618166036.492 [esp_sample_at_depth:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize. 2021-04-11T18:33:56.495Z,1618166036.495 [esp_sample_at_depth:Science:HighestChlPeakReport] Running Loop=1 2021-04-11T18:33:56.495Z,1618166036.495 [esp_sample_at_depth:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize esp_sample_at_depth:Science:HighestChlPeakReport 2021-04-11T18:33:56.495Z,1618166036.495 [esp_sample_at_depth:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1 2021-04-11T18:33:56.495Z,1618166036.495 [esp_sample_at_depth:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2021-04-11T18:33:56.496Z,1618166036.496 [esp_sample_at_depth:Science:PeakDetectNO3] Running Loop=1 2021-04-11T18:33:56.496Z,1618166036.496 [esp_sample_at_depth:Science:PeakDetectNO3](DEBUG): Aggregate::initialize esp_sample_at_depth:Science:PeakDetectNO3 2021-04-11T18:33:56.496Z,1618166036.496 [esp_sample_at_depth:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1 2021-04-11T18:33:56.496Z,1618166036.496 [esp_sample_at_depth:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize. 2021-04-11T18:33:56.514Z,1618166036.514 [esp_sample_at_depth:Science:PeakDetectOil] Running Loop=1 2021-04-11T18:33:56.514Z,1618166036.514 [esp_sample_at_depth:Science:PeakDetectOil](DEBUG): Aggregate::initialize esp_sample_at_depth:Science:PeakDetectOil 2021-04-11T18:33:56.514Z,1618166036.514 [esp_sample_at_depth:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1 2021-04-11T18:33:56.514Z,1618166036.514 [esp_sample_at_depth:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize. 2021-04-11T18:33:56.516Z,1618166036.516 [esp_sample_at_depth:Science:PeakDetectFDOM] Running Loop=1 2021-04-11T18:33:56.516Z,1618166036.516 [esp_sample_at_depth:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize esp_sample_at_depth:Science:PeakDetectFDOM 2021-04-11T18:33:56.516Z,1618166036.516 [esp_sample_at_depth:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1 2021-04-11T18:33:56.516Z,1618166036.516 [esp_sample_at_depth:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize. 2021-04-11T18:33:56.523Z,1618166036.523 [esp_sample_at_depth:Science:OceanCurrent] Running Loop=1 2021-04-11T18:33:56.523Z,1618166036.523 [esp_sample_at_depth:Science:OceanCurrent](DEBUG): Aggregate::initialize esp_sample_at_depth:Science:OceanCurrent 2021-04-11T18:33:56.523Z,1618166036.523 [esp_sample_at_depth:Science:OceanCurrent:A.] Running Loop=1 2021-04-11T18:33:56.523Z,1618166036.523 [esp_sample_at_depth:Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator. 2021-04-11T18:33:56.523Z,1618166036.523 [esp_sample_at_depth:B.AbortSample] Running Loop=1 2021-04-11T18:33:56.523Z,1618166036.523 [esp_sample_at_depth:B.AbortSample](INFO): Initializing AbortSample. 2021-04-11T18:33:56.524Z,1618166036.524 [esp_sample_at_depth:SampleInLab] Running Loop=1 2021-04-11T18:33:56.524Z,1618166036.524 [esp_sample_at_depth:SampleInLab](DEBUG): Aggregate::initialize esp_sample_at_depth:SampleInLab 2021-04-11T18:33:56.524Z,1618166036.524 [esp_sample_at_depth:SampleInLab:GrabSamples] Running Loop=1 2021-04-11T18:33:56.524Z,1618166036.524 [esp_sample_at_depth:SampleInLab:GrabSamples](DEBUG): Aggregate::initialize esp_sample_at_depth:SampleInLab:GrabSamples 2021-04-11T18:33:56.533Z,1618166036.533 [esp_sample_at_depth:SampleInLab:GrabSamples:Sample1] Running Loop=1 2021-04-11T18:33:56.533Z,1618166036.533 [esp_sample_at_depth:SampleInLab:GrabSamples:Sample1](DEBUG): Aggregate::initialize esp_sample_at_depth:SampleInLab:GrabSamples:Sample1 2021-04-11T18:33:56.533Z,1618166036.533 [esp_sample_at_depth:SampleInLab:GrabSamples:Sample1:A] Running Loop=1 2021-04-11T18:33:56.534Z,1618166036.534 [esp_sample_at_depth:SampleInLab:GrabSamples:Sample1:A] Stopped 2021-04-11T18:33:56.534Z,1618166036.534 [esp_sample_at_depth:SampleInLab:GrabSamples:Sample1:B] Running Loop=1 2021-04-11T18:33:56.534Z,1618166036.534 [esp_sample_at_depth:SampleInLab:GrabSamples:Sample1:B](DEBUG): Aggregate::initialize esp_sample_at_depth:SampleInLab:GrabSamples:Sample1:B 2021-04-11T18:33:56.534Z,1618166036.534 [esp_sample_at_depth:B.AbortSample] Running Loop=1 2021-04-11T18:33:56.534Z,1618166036.534 [esp_sample_at_depth:Science] Running Loop=1 2021-04-11T18:33:56.535Z,1618166036.535 [esp_sample_at_depth:Science:PeakDetectFDOM] Stopped 2021-04-11T18:33:56.535Z,1618166036.535 [esp_sample_at_depth:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize esp_sample_at_depth:Science:PeakDetectFDOM 2021-04-11T18:33:56.535Z,1618166036.535 [esp_sample_at_depth:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped 2021-04-11T18:33:56.535Z,1618166036.535 [esp_sample_at_depth:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2021-04-11T18:33:56.535Z,1618166036.535 [esp_sample_at_depth:Science:PeakDetectOil] Stopped 2021-04-11T18:33:56.536Z,1618166036.536 [esp_sample_at_depth:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize esp_sample_at_depth:Science:PeakDetectOil 2021-04-11T18:33:56.536Z,1618166036.536 [esp_sample_at_depth:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped 2021-04-11T18:33:56.536Z,1618166036.536 [esp_sample_at_depth:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2021-04-11T18:33:56.536Z,1618166036.536 [esp_sample_at_depth:Science:PeakDetectNO3] Stopped 2021-04-11T18:33:56.536Z,1618166036.536 [esp_sample_at_depth:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize esp_sample_at_depth:Science:PeakDetectNO3 2021-04-11T18:33:56.536Z,1618166036.536 [esp_sample_at_depth:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped 2021-04-11T18:33:56.536Z,1618166036.536 [esp_sample_at_depth:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2021-04-11T18:33:56.536Z,1618166036.536 [esp_sample_at_depth:Science:HighestChlPeakReport] Stopped 2021-04-11T18:33:56.536Z,1618166036.536 [esp_sample_at_depth:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize esp_sample_at_depth:Science:HighestChlPeakReport 2021-04-11T18:33:56.536Z,1618166036.536 [esp_sample_at_depth:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped 2021-04-11T18:33:56.536Z,1618166036.536 [esp_sample_at_depth:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2021-04-11T18:33:56.536Z,1618166036.536 [esp_sample_at_depth:Science:PeakDetectChl] Stopped 2021-04-11T18:33:56.537Z,1618166036.537 [esp_sample_at_depth:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize esp_sample_at_depth:Science:PeakDetectChl 2021-04-11T18:33:56.541Z,1618166036.541 [esp_sample_at_depth:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped 2021-04-11T18:33:56.541Z,1618166036.541 [esp_sample_at_depth:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2021-04-11T18:33:56.541Z,1618166036.541 [esp_sample_at_depth:Science:OceanCurrent:A.] Running Loop=1 2021-04-11T18:33:56.541Z,1618166036.541 [esp_sample_at_depth:Science:Read_Oil] Stopped 2021-04-11T18:33:56.541Z,1618166036.541 [esp_sample_at_depth:Science:F] Stopped 2021-04-11T18:33:56.542Z,1618166036.542 [esp_sample_at_depth:Science:E] Running Loop=1 2021-04-11T18:33:56.542Z,1618166036.542 [esp_sample_at_depth:Science:E](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water 2021-04-11T18:33:56.542Z,1618166036.542 [esp_sample_at_depth:Science:D] Stopped 2021-04-11T18:33:56.542Z,1618166036.542 [esp_sample_at_depth:Science:C] Running Loop=1 2021-04-11T18:33:56.543Z,1618166036.543 [esp_sample_at_depth:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature 2021-04-11T18:33:56.544Z,1618166036.544 [esp_sample_at_depth:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity 2021-04-11T18:33:56.544Z,1618166036.544 [esp_sample_at_depth:Science:B] Running Loop=1 2021-04-11T18:33:56.546Z,1618166036.546 [esp_sample_at_depth:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature 2021-04-11T18:33:56.547Z,1618166036.547 [esp_sample_at_depth:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity 2021-04-11T18:33:56.547Z,1618166036.547 [esp_sample_at_depth:Science:A] Stopped 2021-04-11T18:33:56.822Z,1618166036.822 [WetLabsBB2FL](INFO): Powering up 2021-04-11T18:33:56.884Z,1618166036.884 [esp_sample_at_depth:SampleESP] Running Loop=1 2021-04-11T18:33:56.884Z,1618166036.884 [esp_sample_at_depth:SampleESP](DEBUG): Aggregate::initialize esp_sample_at_depth:SampleESP 2021-04-11T18:33:56.884Z,1618166036.884 [esp_sample_at_depth:SampleESP:sample] Running Loop=1 2021-04-11T18:33:56.884Z,1618166036.884 [esp_sample_at_depth:SampleESP:sample](DEBUG): Aggregate::initialize esp_sample_at_depth:SampleESP:sample 2021-04-11T18:33:56.885Z,1618166036.885 [esp_sample_at_depth:SampleESP:sample:A] Running Loop=1 2021-04-11T18:33:56.897Z,1618166036.897 [esp_sample_at_depth:SampleESP:sample:A](IMPORTANT): Requesting ESP cartridge type: -4.000000 count 2021-04-11T18:33:56.897Z,1618166036.897 [esp_sample_at_depth:SampleESP:sample:A] Stopped 2021-04-11T18:33:56.898Z,1618166036.898 [esp_sample_at_depth:SampleESP:sample:B.ESPCartridgeSelect] Running Loop=1 2021-04-11T18:33:56.898Z,1618166036.898 [esp_sample_at_depth:SampleESP:sample:B.ESPCartridgeSelect](INFO): Initializing ESPCartridgeSelect. 2021-04-11T18:33:57.279Z,1618166037.279 [esp_sample_at_depth:SampleESP:sample:B.ESPCartridgeSelect](INFO): Issuing request for ESP cartridge type: -4. 2021-04-11T18:33:57.279Z,1618166037.279 [esp_sample_at_depth:SampleESP:sample:B.ESPCartridgeSelect] Stopped 2021-04-11T18:33:57.279Z,1618166037.279 [esp_sample_at_depth:SampleESP:sample:B.ESPCartridgeSelect](INFO): Uninitializing ESPCartridgeSelect. 2021-04-11T18:33:57.279Z,1618166037.279 [esp_sample_at_depth:SampleESP:sample:TriggerESP] Running Loop=1 2021-04-11T18:33:57.680Z,1618166037.680 [esp_sample_at_depth:SampleESP:sample:TriggerESP](DEBUG): Initialize ReadDataComponent to sense ESPComponent.sampling 2021-04-11T18:33:59.273Z,1618166039.273 [ESPComponent](DEBUG): Writing samplingActive=1, sampleNumber=0 2021-04-11T18:33:59.292Z,1618166039.292 [esp_sample_at_depth:SampleESP:sample:TriggerESP] Stopped 2021-04-11T18:33:59.292Z,1618166039.292 [esp_sample_at_depth:SampleESP:sample:D] Running Loop=1 2021-04-11T18:33:59.680Z,1618166039.680 [ESPComponent](DEBUG): Writing samplingActive=0, sampleNumber=1 2021-04-11T18:33:59.699Z,1618166039.699 [esp_sample_at_depth:SampleESP:sample:D](ERROR): data element is not active. 2021-04-11T18:33:59.699Z,1618166039.699 [esp_sample_at_depth:SampleESP:sample:D](ERROR): data element is not active. 2021-04-11T18:33:59.700Z,1618166039.700 [esp_sample_at_depth:SampleESP:sample:D](IMPORTANT): ESP sampling at 0.244730 m in nan degC water with nan ug/l chlorophyll fluorescence. 2021-04-11T18:33:59.700Z,1618166039.700 [esp_sample_at_depth:SampleESP:sample:D] Stopped 2021-04-11T18:33:59.700Z,1618166039.700 [esp_sample_at_depth:SampleESP:sample:E.Wait] Running Loop=1 2021-04-11T18:33:59.700Z,1618166039.700 [esp_sample_at_depth:SampleESP:sample:E.Wait](DEBUG): Initialize Wait Component. 2021-04-11T18:33:59.754Z,1618166039.754 [CTD_Seabird](ERROR): Failed to parse device response: 2021-04-11T18:34:00.927Z,1618166040.927 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2021-04-11T18:34:00.927Z,1618166040.927 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-04-11T18:34:00.970Z,1618166040.970 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-04-11T18:34:01.361Z,1618166041.361 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-04-11T18:34:01.361Z,1618166041.361 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2021-04-11T18:34:02.960Z,1618166042.960 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2021-04-11T18:34:06.986Z,1618166046.986 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2021-04-11T18:34:16.701Z,1618166056.701 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2021-04-11T18:34:18.666Z,1618166058.666 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2021-04-11T18:34:18.666Z,1618166058.666 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35.0,+190,1489.0,0+02969,-0213,+00000,A 2021-04-11T18:34:23.850Z,1618166063.850 [CTD_Seabird](FAULT): Failed to acquire real or simulated CTD data within timeout. 2021-04-11T18:34:23.850Z,1618166063.850 [CTD_Seabird] Communications Fault, FailCount= 1 2021-04-11T18:34:23.850Z,1618166063.850 [CTD_Seabird](ERROR): Communications Fault 2021-04-11T18:34:23.956Z,1618166063.956 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2021-04-11T18:34:24.254Z,1618166064.254 [CTD_Seabird](INFO): Powering down 2021-04-11T18:34:25.189Z,1618166065.189 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2021-04-11T18:34:25.561Z,1618166065.561 [CBIT](INFO): Clearing failed state for component CTD_Seabird 2021-04-11T18:34:25.561Z,1618166065.561 [CTD_Seabird] No Fault, FailCount= 1 2021-04-11T18:34:30.386Z,1618166070.386 [esp_sample_at_depth:SampleESP:sample:E.Wait](INFO): Done Waiting. 2021-04-11T18:34:30.387Z,1618166070.387 [esp_sample_at_depth:SampleESP:sample:E.Wait] Stopped 2021-04-11T18:34:30.387Z,1618166070.387 [esp_sample_at_depth:SampleESP:sample:E.Wait](DEBUG): Uninitialize Wait Component. 2021-04-11T18:34:30.387Z,1618166070.387 [esp_sample_at_depth:SampleESP:sample:WaitForESP] Running Loop=1 2021-04-11T18:34:30.791Z,1618166070.791 [esp_sample_at_depth:SampleESP:sample:WaitForESP](DEBUG): Initialize ReadDataComponent to sense ESPComponent.sample_number 2021-04-11T18:34:32.641Z,1618166072.641 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2021-04-11T18:34:52.729Z,1618166092.729 [CTD_Seabird](FAULT): Failed to acquire real or simulated CTD data within timeout. 2021-04-11T18:34:52.729Z,1618166092.729 [CTD_Seabird] Communications Fault, FailCount= 1 2021-04-11T18:34:52.730Z,1618166092.730 [CTD_Seabird](ERROR): Communications Fault 2021-04-11T18:34:53.026Z,1618166093.026 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2021-04-11T18:34:53.134Z,1618166093.134 [CTD_Seabird](INFO): Powering down 2021-04-11T18:34:54.069Z,1618166094.069 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2021-04-11T18:34:54.289Z,1618166094.289 [CBIT](INFO): Clearing failed state for component CTD_Seabird 2021-04-11T18:34:54.289Z,1618166094.289 [CTD_Seabird] No Fault, FailCount= 1 2021-04-11T18:35:21.597Z,1618166121.597 [CTD_Seabird](FAULT): Failed to acquire real or simulated CTD data within timeout. 2021-04-11T18:35:21.598Z,1618166121.598 [CTD_Seabird] Communications Fault, FailCount= 1 2021-04-11T18:35:21.598Z,1618166121.598 [CTD_Seabird](ERROR): Communications Fault 2021-04-11T18:35:21.755Z,1618166121.755 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2021-04-11T18:35:22.005Z,1618166122.005 [CTD_Seabird](INFO): Powering down 2021-04-11T18:35:22.966Z,1618166122.966 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2021-04-11T18:35:23.356Z,1618166123.356 [CBIT](INFO): Clearing failed state for component CTD_Seabird 2021-04-11T18:35:23.361Z,1618166123.361 [CTD_Seabird] No Fault, FailCount= 1 2021-04-11T18:35:25.345Z,1618166125.345 [CBIT](INFO): Clearing failed state for component DAT 2021-04-11T18:35:25.346Z,1618166125.346 [DAT] No Fault, FailCount= 8 2021-04-11T18:35:25.738Z,1618166125.738 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2 2021-04-11T18:35:25.739Z,1618166125.739 [DAT](INFO): Powering up 2021-04-11T18:35:25.739Z,1618166125.739 [DAT](DEBUG): Initializing DAT. 2021-04-11T18:35:25.751Z,1618166125.751 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2 2021-04-11T18:35:39.895Z,1618166139.895 [DAT](INFO): commRate: 800 2021-04-11T18:35:40.322Z,1618166140.322 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2021-04-11T18:35:41.906Z,1618166141.906 [DAT](INFO): entering command mode 2021-04-11T18:35:42.298Z,1618166142.298 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-11T18:35:42.719Z,1618166142.719 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-11T18:35:43.118Z,1618166143.118 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-11T18:35:43.517Z,1618166143.517 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-11T18:35:43.982Z,1618166143.982 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-11T18:35:44.334Z,1618166144.334 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-11T18:35:44.742Z,1618166144.742 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-11T18:35:45.134Z,1618166145.134 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-11T18:35:45.557Z,1618166145.557 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-11T18:35:45.949Z,1618166145.949 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-11T18:35:46.350Z,1618166146.350 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-11T18:35:46.739Z,1618166146.739 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-11T18:35:47.142Z,1618166147.142 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-11T18:35:47.563Z,1618166147.563 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-11T18:35:47.948Z,1618166147.948 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-11T18:35:48.369Z,1618166148.369 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-11T18:35:48.773Z,1618166148.773 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-11T18:35:49.183Z,1618166149.183 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-11T18:35:49.568Z,1618166149.568 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-11T18:35:50.004Z,1618166150.004 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-11T18:35:50.371Z,1618166150.371 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-11T18:35:50.493Z,1618166150.493 [CTD_Seabird](FAULT): Failed to acquire real or simulated CTD data within timeout. 2021-04-11T18:35:50.493Z,1618166150.493 [CTD_Seabird] Communications Fault, FailCount= 1 2021-04-11T18:35:50.493Z,1618166150.493 [CTD_Seabird](ERROR): Communications Fault 2021-04-11T18:35:50.794Z,1618166150.794 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-11T18:35:50.811Z,1618166150.811 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2021-04-11T18:35:50.898Z,1618166150.898 [CTD_Seabird](INFO): Powering down 2021-04-11T18:35:51.179Z,1618166151.179 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-11T18:35:51.602Z,1618166151.602 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-11T18:35:51.837Z,1618166151.837 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2021-04-11T18:35:52.005Z,1618166152.005 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-11T18:35:52.047Z,1618166152.047 [CBIT](INFO): Clearing failed state for component CTD_Seabird 2021-04-11T18:35:52.047Z,1618166152.047 [CTD_Seabird] No Fault, FailCount= 1 2021-04-11T18:35:52.409Z,1618166152.409 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-11T18:35:52.823Z,1618166152.823 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-11T18:35:53.265Z,1618166153.265 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-11T18:35:53.617Z,1618166153.617 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-11T18:35:54.027Z,1618166154.027 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-11T18:35:54.445Z,1618166154.445 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-11T18:35:54.833Z,1618166154.833 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-11T18:35:55.245Z,1618166155.246 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-11T18:35:55.629Z,1618166155.629 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-11T18:35:56.028Z,1618166156.028 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-11T18:35:56.436Z,1618166156.436 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-11T18:35:56.836Z,1618166156.836 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-11T18:35:57.262Z,1618166157.262 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-11T18:35:57.262Z,1618166157.262 [DAT](FAULT): failed to enter command mode 2021-04-11T18:35:57.682Z,1618166157.682 [DAT](INFO): entering command mode 2021-04-11T18:35:58.063Z,1618166158.063 [DAT](INFO): setting verbose to 3 2021-04-11T18:35:58.483Z,1618166158.483 [DAT](INFO): set verbose to 3 2021-04-11T18:35:58.484Z,1618166158.484 [DAT](INFO): setting DatVerbose to 27440 2021-04-11T18:35:58.961Z,1618166158.961 [DAT](INFO): set DatVerbose to 27440 2021-04-11T18:35:58.961Z,1618166158.961 [DAT](INFO): setting transmit power to 8 2021-04-11T18:35:59.289Z,1618166159.289 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2021-04-11T18:35:59.302Z,1618166159.302 [DAT](INFO): set transmit power to 8 2021-04-11T18:35:59.302Z,1618166159.302 [DAT](INFO): setting local address to 8 2021-04-11T18:35:59.682Z,1618166159.682 [DAT](INFO): set local address to 8 2021-04-11T18:35:59.684Z,1618166159.684 [DAT](INFO): modem://6: set _.LRAUVcntSamples 1.000000 count 2021-04-11T18:35:59.684Z,1618166159.684 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT 2021-04-11T18:36:00.087Z,1618166160.087 [DAT](INFO): setting remote address to 6 2021-04-11T18:36:00.507Z,1618166160.507 [DAT](INFO): set remote address to 6 2021-04-11T18:36:00.508Z,1618166160.508 [DAT](INFO): entering online mode 2021-04-11T18:36:00.891Z,1618166160.891 [DAT](INFO): commRate: 800 2021-04-11T18:36:00.891Z,1618166160.891 [DAT](INFO): online mode acknowledged 2021-04-11T18:36:00.892Z,1618166160.892 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2021-04-11T18:36:04.138Z,1618166164.138 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING 2021-04-11T18:36:10.979Z,1618166170.979 [DAT](FAULT): Ack receipt timeout failure. 2021-04-11T18:36:10.980Z,1618166170.980 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER 2021-04-11T18:36:11.394Z,1618166171.394 [DAT](INFO): modem://6: set _.ESPsampleVolume nan milliliter 2021-04-11T18:36:11.395Z,1618166171.395 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT 2021-04-11T18:36:11.788Z,1618166171.788 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2021-04-11T18:36:14.235Z,1618166174.235 [DAT](INFO): unknown deviceResponse_: WARNING: Modem reset during xmit; lowering xmit power level by 3 dB. 2021-04-11T18:36:14.237Z,1618166174.237 [DAT](INFO): unknown deviceResponse_: WARNING: Reduced xmit power level to 7 due to weak battery. 2021-04-11T18:36:14.239Z,1618166174.239 [DAT](INFO): commRate: 800 2021-04-11T18:36:16.246Z,1618166176.246 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING 2021-04-11T18:36:18.370Z,1618166178.370 [CTD_Seabird](FAULT): Failed to acquire real or simulated CTD data within timeout. 2021-04-11T18:36:18.370Z,1618166178.370 [CTD_Seabird] Communications Fault, FailCount= 1 2021-04-11T18:36:18.370Z,1618166178.370 [CTD_Seabird](ERROR): Communications Fault 2021-04-11T18:36:18.700Z,1618166178.700 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2021-04-11T18:36:18.774Z,1618166178.774 [CTD_Seabird](INFO): Powering down 2021-04-11T18:36:19.100Z,1618166179.100 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2021-04-11T18:36:19.713Z,1618166179.713 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2021-04-11T18:36:19.906Z,1618166179.906 [CBIT](INFO): Clearing failed state for component CTD_Seabird 2021-04-11T18:36:19.906Z,1618166179.906 [CTD_Seabird] No Fault, FailCount= 1 2021-04-11T18:36:21.901Z,1618166181.901 [DAT](FAULT): Ack receipt timeout failure. 2021-04-11T18:36:21.902Z,1618166181.902 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER 2021-04-11T18:36:22.306Z,1618166182.306 [DAT](INFO): modem://6: set _.sampleDepth 0.249484 meter 2021-04-11T18:36:22.307Z,1618166182.307 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT 2021-04-11T18:36:22.696Z,1618166182.696 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2021-04-11T18:36:25.942Z,1618166185.942 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING 2021-04-11T18:36:27.165Z,1618166187.165 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2021-04-11T18:36:32.797Z,1618166192.797 [DAT](FAULT): Ack receipt timeout failure. 2021-04-11T18:36:32.797Z,1618166192.797 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER 2021-04-11T18:36:33.457Z,1618166193.457 [DAT](CRITICAL): SIGSEGV: address not mapped to object 2021-04-11T18:36:33.457Z,1618166193.457 [DAT] Software Fault, FailCount= 1 2021-04-11T18:36:33.457Z,1618166193.457 [DAT](ERROR): Software Fault 2021-04-11T18:36:33.476Z,1618166193.476 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2021-04-11T18:36:33.476Z,1618166193.476 [SyncHandler](INFO): Protected caller Thread ID is 1627 2021-04-11T18:36:33.556Z,1618166193.556 [CommandLine](FAULT): Scheduling is paused 2021-04-11T18:36:33.561Z,1618166193.561 [CBIT](INFO): Critical error at 20210411T183633 2021-04-11T18:36:33.561Z,1618166193.561 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2021-04-11T18:36:33.564Z,1618166193.564 [CBIT](ERROR): Software Fault in component: DAT 2021-04-11T18:36:33.677Z,1618166193.677 [DAT](INFO): Powering down 2021-04-11T18:36:33.824Z,1618166193.824 [MissionManager](INFO): MissionManager is completed. 2021-04-11T18:36:33.824Z,1618166193.824 [MissionManager](INFO): Uninitializing Mission esp_sample_at_depth 2021-04-11T18:36:33.824Z,1618166193.824 [esp_sample_at_depth] Stopped 2021-04-11T18:36:33.824Z,1618166193.824 [esp_sample_at_depth](DEBUG): Aggregate::uninitialize esp_sample_at_depth 2021-04-11T18:36:33.824Z,1618166193.824 [esp_sample_at_depth:Science] Stopped 2021-04-11T18:36:33.824Z,1618166193.824 [esp_sample_at_depth:Science](DEBUG): Aggregate::uninitialize esp_sample_at_depth:Science 2021-04-11T18:36:33.824Z,1618166193.824 [esp_sample_at_depth:Science:B] Stopped 2021-04-11T18:36:33.824Z,1618166193.824 [esp_sample_at_depth:Science:C] Stopped 2021-04-11T18:36:33.824Z,1618166193.824 [esp_sample_at_depth:Science:E] Stopped 2021-04-11T18:36:33.857Z,1618166193.857 [esp_sample_at_depth:Science:OceanCurrent] Stopped 2021-04-11T18:36:33.857Z,1618166193.857 [esp_sample_at_depth:Science:OceanCurrent](DEBUG): Aggregate::uninitialize esp_sample_at_depth:Science:OceanCurrent 2021-04-11T18:36:33.857Z,1618166193.857 [esp_sample_at_depth:Science:OceanCurrent:A.] Stopped 2021-04-11T18:36:33.857Z,1618166193.857 [esp_sample_at_depth:B.AbortSample] Stopped 2021-04-11T18:36:33.857Z,1618166193.857 [esp_sample_at_depth:B.AbortSample](INFO): Uninitializing AbortSample. 2021-04-11T18:36:33.857Z,1618166193.857 [esp_sample_at_depth:SampleESP] Stopped 2021-04-11T18:36:33.857Z,1618166193.857 [esp_sample_at_depth:SampleESP](DEBUG): Aggregate::uninitialize esp_sample_at_depth:SampleESP 2021-04-11T18:36:33.858Z,1618166193.858 [esp_sample_at_depth:SampleESP:sample] Stopped 2021-04-11T18:36:33.858Z,1618166193.858 [esp_sample_at_depth:SampleESP:sample](DEBUG): Aggregate::uninitialize esp_sample_at_depth:SampleESP:sample 2021-04-11T18:36:33.858Z,1618166193.858 [esp_sample_at_depth:SampleESP:sample:WaitForESP] Stopped 2021-04-11T18:36:33.858Z,1618166193.858 [esp_sample_at_depth:SampleInLab] Stopped 2021-04-11T18:36:33.858Z,1618166193.858 [esp_sample_at_depth:SampleInLab](DEBUG): Aggregate::uninitialize esp_sample_at_depth:SampleInLab 2021-04-11T18:36:33.858Z,1618166193.858 [esp_sample_at_depth:SampleInLab:GrabSamples] Stopped 2021-04-11T18:36:33.858Z,1618166193.858 [esp_sample_at_depth:SampleInLab:GrabSamples](DEBUG): Aggregate::uninitialize esp_sample_at_depth:SampleInLab:GrabSamples 2021-04-11T18:36:33.858Z,1618166193.858 [esp_sample_at_depth:SampleInLab:GrabSamples:Sample1] Stopped 2021-04-11T18:36:33.858Z,1618166193.858 [esp_sample_at_depth:SampleInLab:GrabSamples:Sample1](DEBUG): Aggregate::uninitialize esp_sample_at_depth:SampleInLab:GrabSamples:Sample1 2021-04-11T18:36:33.858Z,1618166193.858 [esp_sample_at_depth:SampleInLab:GrabSamples:Sample1:B] Stopped 2021-04-11T18:36:33.859Z,1618166193.859 [esp_sample_at_depth:SampleInLab:GrabSamples:Sample1:B](DEBUG): Aggregate::uninitialize esp_sample_at_depth:SampleInLab:GrabSamples:Sample1:B 2021-04-11T18:36:34.055Z,1618166194.055 [MissionManager](IMPORTANT): Started mission Default 2021-04-11T18:36:34.056Z,1618166194.056 [Default] Running Loop=1 2021-04-11T18:36:34.056Z,1618166194.056 [Default](DEBUG): Aggregate::initialize Default 2021-04-11T18:36:34.056Z,1618166194.056 [Default:B.GoToSurface] Running Loop=1 2021-04-11T18:36:34.056Z,1618166194.056 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2021-04-11T18:36:34.056Z,1618166194.056 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2021-04-11T18:36:34.056Z,1618166194.056 [Default:B.GoToSurface](DEBUG): No pitch setting specified. U