2021-11-02T17:40:27.254Z,1635874827.254 [DataOverHttps](INFO): Received command: restart logs 2021-11-02T17:40:27.268Z,1635874827.268 [CommandExec](IMPORTANT): got command restart logs 2021-11-02T17:42:59.647Z,1635874979.647 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting. 2021-11-02T17:42:59.647Z,1635874979.647 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped 2021-11-02T17:42:59.647Z,1635874979.647 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-11-02T17:42:59.648Z,1635874979.648 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1 2021-11-02T17:43:00.031Z,1635874980.031 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped 2021-11-02T17:43:00.031Z,1635874980.031 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1 2021-11-02T17:43:00.439Z,1635874980.439 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 6.305038 min 2021-11-02T17:43:00.439Z,1635874980.439 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped 2021-11-02T17:43:00.439Z,1635874980.439 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn 2021-11-02T17:43:00.439Z,1635874980.439 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped 2021-11-02T17:43:00.439Z,1635874980.439 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn 2021-11-02T17:43:00.440Z,1635874980.440 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #2 2021-11-02T17:43:00.440Z,1635874980.440 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=2 2021-11-02T17:43:00.440Z,1635874980.440 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2021-11-02T17:43:00.440Z,1635874980.440 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2021-11-02T17:43:02.451Z,1635874982.451 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174301.00,A,4133.09672,N,07042.82111,W,0.447,108.51,021121,,,A*72 2021-11-02T17:43:02.454Z,1635874982.454 [NAL9602](INFO): GPS fix at 20211102T174301: (41.551612, -70.713685) 2021-11-02T17:43:02.472Z,1635874982.472 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2021-11-02T17:43:02.472Z,1635874982.472 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2021-11-02T17:43:06.911Z,1635874986.911 [DataOverHttps](INFO): Sending 53 bytes from file Logs/20211102T154305/Courier0036.lzma 2021-11-02T17:43:07.912Z,1635874987.912 [DataOverHttps](INFO): Moved sent file to Logs/20211102T154305/Courier0036.lzma.bak 2021-11-02T17:43:07.912Z,1635874987.912 [DataOverHttps](INFO): SBD MOMSN=595359 2021-11-02T17:43:16.950Z,1635874996.950 [DataOverHttps](INFO): Sending 261 bytes from file Logs/20211102T174027/Courier0000.lzma 2021-11-02T17:43:17.952Z,1635874997.952 [DataOverHttps](INFO): Moved sent file to Logs/20211102T174027/Courier0000.lzma.bak 2021-11-02T17:43:17.952Z,1635874997.952 [DataOverHttps](INFO): SBD MOMSN=595361 2021-11-02T17:43:26.990Z,1635875006.990 [DataOverHttps](INFO): Sending 53 bytes from file Logs/20211102T174027/Courier0003.lzma 2021-11-02T17:43:27.118Z,1635875007.118 [NAL9602](INFO): SBD MO Status=0, MOMSN=28004, MT Status=0, MTMSN=0 2021-11-02T17:43:27.118Z,1635875007.118 [NAL9602](INFO): No messages in MT queue 2021-11-02T17:43:27.992Z,1635875007.992 [DataOverHttps](INFO): Moved sent file to Logs/20211102T174027/Courier0003.lzma.bak 2021-11-02T17:43:27.992Z,1635875007.992 [DataOverHttps](INFO): SBD MOMSN=595365 2021-11-02T17:43:37.038Z,1635875017.038 [DataOverHttps](INFO): Sending 115 bytes from file Logs/20211102T154305/Express0037.lzma 2021-11-02T17:43:38.048Z,1635875018.048 [DataOverHttps](INFO): Moved sent file to Logs/20211102T154305/Express0037.lzma.bak 2021-11-02T17:43:38.048Z,1635875018.048 [DataOverHttps](INFO): SBD MOMSN=595367 2021-11-02T17:43:47.086Z,1635875027.086 [DataOverHttps](INFO): Sending 398 bytes from file Logs/20211102T174027/Express0001.lzma 2021-11-02T17:43:48.088Z,1635875028.088 [DataOverHttps](INFO): Moved sent file to Logs/20211102T174027/Express0001.lzma.bak 2021-11-02T17:43:48.088Z,1635875028.088 [DataOverHttps](INFO): SBD MOMSN=595370 2021-11-02T17:43:57.126Z,1635875037.126 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20211102T174027/Express0004.lzma 2021-11-02T17:43:57.829Z,1635875037.829 [NAL9602](INFO): Not Powering down - fast GPS 2021-11-02T17:43:58.128Z,1635875038.128 [DataOverHttps](INFO): Moved sent file to Logs/20211102T174027/Express0004.lzma.bak 2021-11-02T17:43:58.128Z,1635875038.128 [DataOverHttps](INFO): SBD MOMSN=595374 2021-11-02T17:43:59.456Z,1635875039.456 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2021-11-02T17:43:59.456Z,1635875039.456 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1 2021-11-02T17:43:59.456Z,1635875039.456 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-11-02T17:44:18.834Z,1635875058.834 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2021-11-02T17:44:58.848Z,1635875098.848 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2021-11-02T17:45:03.690Z,1635875103.690 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2021-11-02T17:45:08.637Z,1635875108.637 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2021-11-02T17:45:18.670Z,1635875118.670 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2021-11-02T17:45:28.738Z,1635875128.738 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range. 2021-11-02T17:48:09.244Z,1635875289.244 [DataOverHttps](IMPORTANT): SBD MTMSN=20211102T174808 2021-11-02T17:48:13.262Z,1635875293.262 [DataOverHttps](INFO): Received command: sched asap "load Maintenance/calibrate_sparton_compass.xml;set SpartonCompassCal.YoYoMinDepth 3 m;set SpartonCompassCal.YoYoMaxDepth 7 m;set SpartonCompassCal.YoYoMinAltitude 3 m;set SpartonCompassCal.YoYoPitch 15 degree" 1qhfa 1 2 2021-11-02T17:48:13.268Z,1635875293.268 [CommandExec](IMPORTANT): got command schedule asap "load Maintenance/calibrate_sparton_compass.xml;set SpartonCompassCal.YoYoMinDepth 3 m;set SpartonCompassCal.YoYoMaxDepth 7 m;set SpartonCompassCal.YoYoMinAltitude 3 m;set SpartonCompassCal.YoYoPitch 15 degree" 1qhfa 1 2.000000 2021-11-02T17:48:13.268Z,1635875293.268 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=1qhfa 2021-11-02T17:48:13.272Z,1635875293.272 [CommandExec](IMPORTANT): Scheduled #1 (#1 of 2 with id='1qhfa'): "load Maintenance/calibrate_sparton_compass.xml;set SpartonCompassCal.YoYoMinDepth 3 m;set SpartonCompassCal.YoYoMaxDepth 7 m;set SpartonCompassCal.YoYoMinAltitude 3 m;set SpartonCompassCal.YoYoPitch 15 degree", ASAP 2021-11-02T17:48:14.264Z,1635875294.264 [DataOverHttps](IMPORTANT): SBD MTMSN=20211102T174813 2021-11-02T17:48:18.283Z,1635875298.283 [DataOverHttps](INFO): Received command: sched asap "set SpartonCompassCal.MinAltitude 3 m;set SpartonCompassCal.MaxDepth 9 m;set SpartonCompassCal.MinOffshore 500 m;run" 1qhfa 2 2 2021-11-02T17:48:18.334Z,1635875298.334 [CommandExec](IMPORTANT): got command schedule asap "set SpartonCompassCal.MinAltitude 3 m;set SpartonCompassCal.MaxDepth 9 m;set SpartonCompassCal.MinOffshore 500 m;run" 1qhfa 2 2.000000 2021-11-02T17:48:18.334Z,1635875298.334 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=1qhfa 2021-11-02T17:48:18.335Z,1635875298.335 [CommandExec](IMPORTANT): Scheduled #2 (#2 of 2 with id='1qhfa'): "set SpartonCompassCal.MinAltitude 3 m;set SpartonCompassCal.MaxDepth 9 m;set SpartonCompassCal.MinOffshore 500 m;run", ASAP 2021-11-02T17:48:18.415Z,1635875298.415 [CommandExec](IMPORTANT): got command load ./Missions/Maintenance/calibrate_sparton_compass.xml 2021-11-02T17:48:18.416Z,1635875298.416 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/calibrate_sparton_compass.xml 2021-11-02T17:48:18.566Z,1635875298.566 [MissionManager](INFO): DefineArg SpartonCompassCal.MissionTimeout = 60.000000 min 2021-11-02T17:48:18.586Z,1635875298.586 [MissionManager](INFO): DefineArg SpartonCompassCal.NeedCommsTime = 60.000000 min 2021-11-02T17:48:18.590Z,1635875298.590 [MissionManager](INFO): DefineArg SpartonCompassCal.YoYoMinDepth = 5.000000 m 2021-11-02T17:48:18.599Z,1635875298.599 [MissionManager](INFO): DefineArg SpartonCompassCal.YoYoMaxDepth = 40.000000 m 2021-11-02T17:48:18.603Z,1635875298.603 [MissionManager](INFO): DefineArg SpartonCompassCal.YoYoMinAltitude = 7.000000 m 2021-11-02T17:48:18.635Z,1635875298.635 [MissionManager](INFO): DefineArg SpartonCompassCal.YoYoPitch = 20.000000 arcdeg 2021-11-02T17:48:18.660Z,1635875298.660 [MissionManager](INFO): DefineArg SpartonCompassCal.RudderAngle = 13.000000 arcdeg 2021-11-02T17:48:18.682Z,1635875298.682 [MissionManager](INFO): DefineArg SpartonCompassCal.Speed = 1.000000 m/s 2021-11-02T17:48:18.694Z,1635875298.694 [MissionManager](INFO): DefineArg SpartonCompassCal.CalibrationInitTimeout = 2.000000 min 2021-11-02T17:48:18.709Z,1635875298.709 [MissionManager](INFO): DefineArg SpartonCompassCal.MinAltitude = 5.000000 m 2021-11-02T17:48:18.721Z,1635875298.721 [MissionManager](INFO): DefineArg SpartonCompassCal.MaxDepth = 50.000000 m 2021-11-02T17:48:18.733Z,1635875298.733 [MissionManager](INFO): DefineArg SpartonCompassCal.MinOffshore = 2.000000 km 2021-11-02T17:48:18.734Z,1635875298.734 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml 2021-11-02T17:48:19.210Z,1635875299.210 [MissionManager](INFO): DefineArg SpartonCompassCal:Science.PeakDetectChlActive = 0 bool 2021-11-02T17:48:19.214Z,1635875299.214 [MissionManager](INFO): DefineArg SpartonCompassCal:Science.TimeWindowPeakReport = nan min 2021-11-02T17:48:19.217Z,1635875299.217 [MissionManager](INFO): DefineArg SpartonCompassCal:Science.HighestChlPeakReportActive = 0 bool 2021-11-02T17:48:19.220Z,1635875299.220 [MissionManager](INFO): DefineArg SpartonCompassCal:Science.HighestSaltPeakReportActive = 0 bool 2021-11-02T17:48:19.224Z,1635875299.224 [MissionManager](INFO): DefineArg SpartonCompassCal:Science.HighestOilPeakReportActive = 0 bool 2021-11-02T17:48:19.251Z,1635875299.251 [MissionManager](INFO): DefineArg SpartonCompassCal:Science.PatchTracking = 0 bool 2021-11-02T17:48:19.259Z,1635875299.259 [MissionManager](INFO): DefineArg SpartonCompassCal:Science.DetectTrough = 0 bool 2021-11-02T17:48:19.266Z,1635875299.266 [MissionManager](INFO): DefineArg SpartonCompassCal:Science.FilterWidthHorizontal = 3.000000 count 2021-11-02T17:48:19.270Z,1635875299.270 [MissionManager](INFO): DefineArg SpartonCompassCal:Science.NumProfilesSlidingwindow = 100.000000 count 2021-11-02T17:48:19.277Z,1635875299.277 [MissionManager](INFO): DefineArg SpartonCompassCal:Science.OffPeakFractionHorizontal = 80.000000 % 2021-11-02T17:48:19.290Z,1635875299.290 [MissionManager](INFO): DefineArg SpartonCompassCal:Science.PeakDetectNO3Active = 0 bool 2021-11-02T17:48:19.297Z,1635875299.297 [MissionManager](INFO): DefineArg SpartonCompassCal:Science.PeakDetectOilActive = 0 bool 2021-11-02T17:48:19.301Z,1635875299.301 [MissionManager](INFO): DefineArg SpartonCompassCal:Science.PeakDetectFDOMActive = 0 bool 2021-11-02T17:48:19.312Z,1635875299.312 [MissionManager](INFO): DefineArg SpartonCompassCal:Science.PeakDetectSalinityActive = 0 bool 2021-11-02T17:48:19.316Z,1635875299.316 [MissionManager](INFO): DefineArg SpartonCompassCal:Science.UpwardDerivativeOfTemperatureActive = 0 bool 2021-11-02T17:48:19.332Z,1635875299.332 [MissionManager](INFO): DefineArg SpartonCompassCal:Science.EnabledAanderaaO2 = 0.000000 bool 2021-11-02T17:48:19.337Z,1635875299.337 [MissionManager](INFO): DefineArg SpartonCompassCal:Science.EnabledNeilBrown = 0.000000 bool 2021-11-02T17:48:19.359Z,1635875299.359 [MissionManager](INFO): DefineArg SpartonCompassCal:Science.EnabledSeabird = 1.000000 bool 2021-11-02T17:48:19.398Z,1635875299.398 [MissionManager](INFO): DefineArg SpartonCompassCal:Science.EnabledWetLabsBB2FL = 0.000000 bool 2021-11-02T17:48:19.407Z,1635875299.407 [MissionManager](INFO): DefineArg SpartonCompassCal:Science.EnabledWetLabsSeaOWL_UV_A = 1.000000 bool 2021-11-02T17:48:19.411Z,1635875299.411 [MissionManager](INFO): DefineArg SpartonCompassCal:Science.EnabledWetLabsUBAT = 0.000000 bool 2021-11-02T17:48:19.447Z,1635875299.447 [MissionManager](INFO): DefineArg SpartonCompassCal:Science.LowPassWindowLength = 20.000000 count 2021-11-02T17:48:19.459Z,1635875299.459 [MissionManager](INFO): DefineArg SpartonCompassCal:Science.MedianFilterLen = 5.000000 count 2021-11-02T17:48:19.471Z,1635875299.471 [MissionManager](INFO): DefineArg SpartonCompassCal:Science.PeakShallowBound = nan m 2021-11-02T17:48:19.480Z,1635875299.480 [MissionManager](INFO): DefineArg SpartonCompassCal:Science.PeakDeepBound = nan m 2021-11-02T17:48:19.504Z,1635875299.504 [MissionManager](INFO): DefineArg SpartonCompassCal:Science.DepChangeThreshForAttitudeFlip = 2.000000 m 2021-11-02T17:48:19.524Z,1635875299.524 [MissionManager](INFO): DefineOutput SpartonCompassCal:Science.PeakChl = 0.000000 ug/l 2021-11-02T17:48:19.532Z,1635875299.532 [MissionManager](INFO): DefineOutput SpartonCompassCal:Science.PeakChlDepth = 0.000000 m 2021-11-02T17:48:19.536Z,1635875299.536 [MissionManager](INFO): DefineOutput SpartonCompassCal:Science.PeakChlTemperature = 0.000000 degC 2021-11-02T17:48:19.552Z,1635875299.552 [MissionManager](INFO): DefineOutput SpartonCompassCal:Science.PeakChlLatitude = nan arcdeg 2021-11-02T17:48:19.556Z,1635875299.556 [MissionManager](INFO): DefineOutput SpartonCompassCal:Science.PeakChlLongitude = nan arcdeg 2021-11-02T17:48:19.568Z,1635875299.568 [MissionManager](INFO): DefineOutput SpartonCompassCal:Science.PeakNO3 = 0.000000 umol/l 2021-11-02T17:48:19.573Z,1635875299.573 [MissionManager](INFO): DefineOutput SpartonCompassCal:Science.PeakNO3Depth = 0.000000 m 2021-11-02T17:48:19.589Z,1635875299.589 [MissionManager](INFO): DefineOutput SpartonCompassCal:Science.PeakNO3Latitude = nan arcdeg 2021-11-02T17:48:19.597Z,1635875299.597 [MissionManager](INFO): DefineOutput SpartonCompassCal:Science.PeakNO3Longitude = nan arcdeg 2021-11-02T17:48:19.609Z,1635875299.609 [MissionManager](INFO): DefineOutput SpartonCompassCal:Science.PeakOil = 0.000000 kg/m3 2021-11-02T17:48:19.613Z,1635875299.613 [MissionManager](INFO): DefineOutput SpartonCompassCal:Science.PeakOilDepth = 0.000000 m 2021-11-02T17:48:19.625Z,1635875299.625 [MissionManager](INFO): DefineOutput SpartonCompassCal:Science.PeakOilLatitude = nan arcdeg 2021-11-02T17:48:19.629Z,1635875299.629 [MissionManager](INFO): DefineOutput SpartonCompassCal:Science.PeakOilLongitude = nan arcdeg 2021-11-02T17:48:19.640Z,1635875299.640 [MissionManager](INFO): DefineOutput SpartonCompassCal:Science.PeakFDOM = 0.000000 ppb 2021-11-02T17:48:19.645Z,1635875299.645 [MissionManager](INFO): DefineOutput SpartonCompassCal:Science.PeakFDOMDepth = 0.000000 m 2021-11-02T17:48:19.657Z,1635875299.657 [MissionManager](INFO): DefineOutput SpartonCompassCal:Science.PeakFDOMTemperature = nan degC 2021-11-02T17:48:19.661Z,1635875299.661 [MissionManager](INFO): DefineOutput SpartonCompassCal:Science.PeakFDOMLatitude = nan arcdeg 2021-11-02T17:48:19.673Z,1635875299.673 [MissionManager](INFO): DefineOutput SpartonCompassCal:Science.PeakFDOMLongitude = nan arcdeg 2021-11-02T17:48:19.688Z,1635875299.688 [MissionManager](INFO): DefineOutput SpartonCompassCal:Science.PatchFDOMDistance = nan m 2021-11-02T17:48:19.693Z,1635875299.693 [MissionManager](INFO): DefineOutput SpartonCompassCal:Science.PeakSalt = 0.000000 psu 2021-11-02T17:48:19.705Z,1635875299.705 [MissionManager](INFO): DefineOutput SpartonCompassCal:Science.PeakSaltDepth = 0.000000 m 2021-11-02T17:48:19.709Z,1635875299.709 [MissionManager](INFO): DefineOutput SpartonCompassCal:Science.PeakSaltTemperature = 0.000000 degC 2021-11-02T17:48:19.720Z,1635875299.720 [MissionManager](INFO): DefineOutput SpartonCompassCal:Science.PeakSaltLatitude = nan arcdeg 2021-11-02T17:48:19.724Z,1635875299.724 [MissionManager](INFO): DefineOutput SpartonCompassCal:Science.PeakSaltLongitude = nan arcdeg 2021-11-02T17:48:19.733Z,1635875299.733 [MissionManager](INFO): DefineOutput SpartonCompassCal:Science.PatchChl = 0.000000 ug/l 2021-11-02T17:48:19.744Z,1635875299.744 [MissionManager](INFO): DefineOutput SpartonCompassCal:Science.PatchChlDepth = 0.000000 m 2021-11-02T17:48:19.748Z,1635875299.748 [MissionManager](INFO): DefineOutput SpartonCompassCal:Science.PatchChlLatitude = nan arcdeg 2021-11-02T17:48:19.760Z,1635875299.760 [MissionManager](INFO): DefineOutput SpartonCompassCal:Science.PatchChlLongitude = nan arcdeg 2021-11-02T17:48:19.764Z,1635875299.764 [MissionManager](INFO): DefineOutput SpartonCompassCal:Science.PatchChlDistance = nan m 2021-11-02T17:48:19.775Z,1635875299.775 [MissionManager](INFO): DefineOutput SpartonCompassCal:Science.PatchSalt = 0.000000 psu 2021-11-02T17:48:19.803Z,1635875299.803 [MissionManager](INFO): DefineOutput SpartonCompassCal:Science.PatchSaltDepth = 0.000000 m 2021-11-02T17:48:19.809Z,1635875299.809 [MissionManager](INFO): DefineOutput SpartonCompassCal:Science.PatchSaltLatitude = nan arcdeg 2021-11-02T17:48:19.822Z,1635875299.822 [MissionManager](INFO): DefineOutput SpartonCompassCal:Science.PatchSaltLongitude = nan arcdeg 2021-11-02T17:48:19.845Z,1635875299.845 [MissionManager](INFO): DefineOutput SpartonCompassCal:Science.PatchSaltDistance = nan m 2021-11-02T17:48:19.853Z,1635875299.853 [MissionManager](INFO): DefineOutput SpartonCompassCal:Science.PatchOil = 0.000000 psu 2021-11-02T17:48:19.864Z,1635875299.864 [MissionManager](INFO): DefineOutput SpartonCompassCal:Science.PatchOilDepth = 0.000000 m 2021-11-02T17:48:19.880Z,1635875299.880 [MissionManager](INFO): DefineOutput SpartonCompassCal:Science.PatchOilLatitude = nan arcdeg 2021-11-02T17:48:19.888Z,1635875299.888 [MissionManager](INFO): DefineOutput SpartonCompassCal:Science.PatchOilLongitude = nan arcdeg 2021-11-02T17:48:19.904Z,1635875299.904 [MissionManager](INFO): DefineOutput SpartonCompassCal:Science.PatchOilDistance = nan m 2021-11-02T17:48:19.944Z,1635875299.944 [SpartonCompassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2021-11-02T17:48:19.965Z,1635875299.965 [SpartonCompassCal:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal. 2021-11-02T17:48:19.000Z,1635875300.000 [SpartonCompassCal:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2021-11-02T17:48:20.028Z,1635875300.028 [SpartonCompassCal:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2021-11-02T17:48:20.052Z,1635875300.052 [SpartonCompassCal:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal. 2021-11-02T17:48:20.083Z,1635875300.083 [SpartonCompassCal:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2021-11-02T17:48:20.103Z,1635875300.103 [SpartonCompassCal:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2021-11-02T17:48:20.130Z,1635875300.130 [SpartonCompassCal:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal. 2021-11-02T17:48:20.162Z,1635875300.162 [MissionManager](INFO): Inserting Stack: Missions/Insert/NeedComms.xml 2021-11-02T17:48:20.308Z,1635875300.308 [MissionManager](INFO): DefineArg SpartonCompassCal:NeedComms.DiveInterval = 3.000000 h 2021-11-02T17:48:20.313Z,1635875300.313 [MissionManager](INFO): DefineArg SpartonCompassCal:NeedComms.WaitForPitchUp = 10.000000 min 2021-11-02T17:48:20.325Z,1635875300.325 [MissionManager](INFO): DefineArg SpartonCompassCal:NeedComms.SurfacePitch = 20.000000 arcdeg 2021-11-02T17:48:20.339Z,1635875300.339 [MissionManager](INFO): DefineArg SpartonCompassCal:NeedComms.SurfaceDepthRate = nan m/s 2021-11-02T17:48:20.347Z,1635875300.347 [MissionManager](INFO): DefineArg SpartonCompassCal:NeedComms.SurfaceSpeed = 1.000000 m/s 2021-11-02T17:48:20.353Z,1635875300.353 [MissionManager](INFO): DefineArg SpartonCompassCal:NeedComms.GPSTimeout = 7.000000 min 2021-11-02T17:48:20.363Z,1635875300.363 [MissionManager](INFO): DefineArg SpartonCompassCal:NeedComms.CommsTimeout = 30.000000 min 2021-11-02T17:48:20.373Z,1635875300.373 [SpartonCompassCal:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface. 2021-11-02T17:48:20.418Z,1635875300.418 [MissionManager](INFO): Inserting Stack: Missions/Insert/StandardEnvelopes.xml 2021-11-02T17:48:20.455Z,1635875300.455 [MissionManager](INFO): DefineArg SpartonCompassCal:StandardEnvelopes.MinAltitude = 5.000000 m 2021-11-02T17:48:20.469Z,1635875300.469 [MissionManager](INFO): DefineArg SpartonCompassCal:StandardEnvelopes.MaxDepth = 200.000000 m 2021-11-02T17:48:20.500Z,1635875300.500 [MissionManager](INFO): DefineArg SpartonCompassCal:StandardEnvelopes.MinOffshore = 2000.000000 m 2021-11-02T17:48:20.515Z,1635875300.515 [SpartonCompassCal:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2021-11-02T17:48:20.542Z,1635875300.542 [SpartonCompassCal:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2021-11-02T17:48:20.571Z,1635875300.571 [SpartonCompassCal:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2021-11-02T17:48:20.642Z,1635875300.642 [SpartonCompassCal:I.Pitch](DEBUG): Construct. 2021-11-02T17:48:20.667Z,1635875300.667 [SpartonCompassCal:J.Buoyancy](DEBUG): Construct Buoyancy. 2021-11-02T17:48:20.679Z,1635875300.679 [SpartonCompassCal:K.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2021-11-02T17:48:20.705Z,1635875300.705 [SpartonCompassCal:L.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2021-11-02T17:48:20.748Z,1635875300.748 [SpartonCompassCal:M.YoYo](DEBUG): Construct YoYo. 2021-11-02T17:48:20.788Z,1635875300.788 [SpartonCompassCal:AdaptCal:WaitForCalibrationInit:A_Timeout:B.Execute](DEBUG): Construct Execute. 2021-11-02T17:48:20.795Z,1635875300.795 [SpartonCompassCal:AdaptCal:AcquireCalibrationPoints:A.Point](DEBUG): Construct. 2021-11-02T17:48:20.806Z,1635875300.806 [SpartonCompassCal:AdaptCal:AcquireCalibrationPoints:B.SetSpeed](DEBUG): Construct. 2021-11-02T17:48:20.818Z,1635875300.818 [SpartonCompassCal:AdaptCal:C.CalibrateAHRS_M2](DEBUG): Construct CalibrateSparton. 2021-11-02T17:48:20.883Z,1635875300.883 [MissionManager](DEBUG): This mission yoyos in a spiral while calibrating the Sparton AHRS-M2 Micro AHRS/IMU with AdaptCal. Maximum duration of mission 60 Elapsed time after previous surface communications when vehicle will begin to ascend for additional surface communications 60 Minimum depth while performing the YoYo behavior. 5 Maximum depth while performing the YoYo behavior. 40 Minimum altitude while performing the YoYo behavior (for bottom-terminated YoYos). 7 Pitch (plus and minus) for yo-yo behavior. 20 Rudder angle to use while performing the spiral cast. 13 Vehicle speed. 1 Elapsed time allowed for calibration to initialize 2 Minimum height above the sea floor for the entire mission. 5 Maximum depth for the entire mission. 50 Minimum offshore distance for the entire mission. 2 Most missions will run the science sensors. If you don't place this aggregate above NeedComms, science instruments get turned off on the last upcast and while floating on the surface. Another depth envelope for the YoYo behavior. This envelope should fall within the limits of the standard safety envelopes in Insert/StandardEnvelopes.xml in order to avoid commanding high pitch angles for depth-terminated YoYos. Another altitude envelope for the YoYo behavior. This envelope should fall within the limits of the standard safety envelopes in Insert/StandardEnvelopes.xml in order to avoid commanding high pitch angles for bottom-terminated YoYos. Failed to initialize calibration within timeout. Stopping mission. stop 2021-11-02T17:48:20.883Z,1635875300.883 [CommandExec](IMPORTANT): Loaded ./Missions/Maintenance/calibrate_sparton_compass.xml 2021-11-02T17:48:35.347Z,1635875315.347 [CommandExec](IMPORTANT): got command set SpartonCompassCal.YoYoMinDepth 3.000000 meter 2021-11-02T17:48:35.348Z,1635875315.348 [CommandExec](IMPORTANT): got command set SpartonCompassCal.YoYoMaxDepth 7.000000 meter 2021-11-02T17:48:35.349Z,1635875315.349 [CommandExec](IMPORTANT): got command set SpartonCompassCal.YoYoMinAltitude 3.000000 meter 2021-11-02T17:48:35.350Z,1635875315.350 [CommandExec](IMPORTANT): got command set SpartonCompassCal.YoYoPitch 15.000000 degree 2021-11-02T17:48:45.221Z,1635875325.221 [CommandExec](IMPORTANT): got command set SpartonCompassCal.MinAltitude 3.000000 meter 2021-11-02T17:48:45.221Z,1635875325.221 [CommandExec](IMPORTANT): got command set SpartonCompassCal.MaxDepth 9.000000 meter 2021-11-02T17:48:45.223Z,1635875325.223 [CommandExec](IMPORTANT): got command set SpartonCompassCal.MinOffshore 500.000000 meter 2021-11-02T17:48:45.226Z,1635875325.226 [CommandExec](IMPORTANT): got command run 2021-11-02T17:48:45.247Z,1635875325.247 [CommandExec](IMPORTANT): Running 2021-11-02T17:48:45.319Z,1635875325.319 [DefaultWithUndock] Stopped 2021-11-02T17:48:45.319Z,1635875325.319 [DefaultWithUndock](DEBUG): Aggregate::uninitialize DefaultWithUndock 2021-11-02T17:48:45.319Z,1635875325.319 [DefaultWithUndock:SurfaceDefault] Stopped 2021-11-02T17:48:45.319Z,1635875325.319 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault 2021-11-02T17:48:45.319Z,1635875325.319 [DefaultWithUndock:SurfaceDefault:B.GoToSurface] Stopped 2021-11-02T17:48:45.319Z,1635875325.319 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2021-11-02T17:48:45.319Z,1635875325.319 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped 2021-11-02T17:48:45.319Z,1635875325.319 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn 2021-11-02T17:48:45.319Z,1635875325.319 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped 2021-11-02T17:48:45.320Z,1635875325.320 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-11-02T17:48:45.320Z,1635875325.320 [MissionManager](IMPORTANT): Started mission SpartonCompassCal 2021-11-02T17:48:45.320Z,1635875325.320 [SpartonCompassCal] Running Loop=1 2021-11-02T17:48:45.320Z,1635875325.320 [SpartonCompassCal](DEBUG): Aggregate::initialize SpartonCompassCal 2021-11-02T17:48:45.320Z,1635875325.320 [SpartonCompassCal:Science] Running Loop=1 2021-11-02T17:48:45.320Z,1635875325.320 [SpartonCompassCal:Science](DEBUG): Aggregate::initialize SpartonCompassCal:Science 2021-11-02T17:48:45.320Z,1635875325.320 [SpartonCompassCal:Science:A] Running Loop=1 2021-11-02T17:48:45.321Z,1635875325.321 [SpartonCompassCal:Science:B] Running Loop=1 2021-11-02T17:48:45.321Z,1635875325.321 [SpartonCompassCal:Science:C] Running Loop=1 2021-11-02T17:48:45.321Z,1635875325.321 [SpartonCompassCal:Science:D] Running Loop=1 2021-11-02T17:48:45.321Z,1635875325.321 [SpartonCompassCal:Science:E] Running Loop=1 2021-11-02T17:48:45.321Z,1635875325.321 [SpartonCompassCal:Science:F] Running Loop=1 2021-11-02T17:48:45.321Z,1635875325.321 [SpartonCompassCal:Science:Read_Oil] Running Loop=1 2021-11-02T17:48:45.321Z,1635875325.321 [SpartonCompassCal:Science:PeakDetectChl] Running Loop=1 2021-11-02T17:48:45.321Z,1635875325.321 [SpartonCompassCal:Science:PeakDetectChl](DEBUG): Aggregate::initialize SpartonCompassCal:Science:PeakDetectChl 2021-11-02T17:48:45.321Z,1635875325.321 [SpartonCompassCal:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1 2021-11-02T17:48:45.321Z,1635875325.321 [SpartonCompassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize. 2021-11-02T17:48:45.324Z,1635875325.324 [SpartonCompassCal:Science:HighestChlPeakReport] Running Loop=1 2021-11-02T17:48:45.324Z,1635875325.324 [SpartonCompassCal:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize SpartonCompassCal:Science:HighestChlPeakReport 2021-11-02T17:48:45.324Z,1635875325.324 [SpartonCompassCal:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1 2021-11-02T17:48:45.324Z,1635875325.324 [SpartonCompassCal:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2021-11-02T17:48:45.325Z,1635875325.325 [SpartonCompassCal:Science:PeakDetectNO3] Running Loop=1 2021-11-02T17:48:45.325Z,1635875325.325 [SpartonCompassCal:Science:PeakDetectNO3](DEBUG): Aggregate::initialize SpartonCompassCal:Science:PeakDetectNO3 2021-11-02T17:48:45.325Z,1635875325.325 [SpartonCompassCal:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1 2021-11-02T17:48:45.325Z,1635875325.325 [SpartonCompassCal:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize. 2021-11-02T17:48:45.335Z,1635875325.335 [SpartonCompassCal:Science:PeakDetectOil] Running Loop=1 2021-11-02T17:48:45.335Z,1635875325.335 [SpartonCompassCal:Science:PeakDetectOil](DEBUG): Aggregate::initialize SpartonCompassCal:Science:PeakDetectOil 2021-11-02T17:48:45.335Z,1635875325.335 [SpartonCompassCal:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1 2021-11-02T17:48:45.335Z,1635875325.335 [SpartonCompassCal:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize. 2021-11-02T17:48:45.337Z,1635875325.337 [SpartonCompassCal:Science:HighestOilPeakReport] Running Loop=1 2021-11-02T17:48:45.337Z,1635875325.337 [SpartonCompassCal:Science:HighestOilPeakReport](DEBUG): Aggregate::initialize SpartonCompassCal:Science:HighestOilPeakReport 2021-11-02T17:48:45.337Z,1635875325.337 [SpartonCompassCal:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Running Loop=1 2021-11-02T17:48:45.337Z,1635875325.337 [SpartonCompassCal:Science:HighestOilPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2021-11-02T17:48:45.342Z,1635875325.342 [SpartonCompassCal:Science:PeakDetectFDOM] Running Loop=1 2021-11-02T17:48:45.342Z,1635875325.342 [SpartonCompassCal:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize SpartonCompassCal:Science:PeakDetectFDOM 2021-11-02T17:48:45.343Z,1635875325.343 [SpartonCompassCal:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1 2021-11-02T17:48:45.343Z,1635875325.343 [SpartonCompassCal:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize. 2021-11-02T17:48:45.347Z,1635875325.347 [SpartonCompassCal:Science:PeakDetectSalinity] Running Loop=1 2021-11-02T17:48:45.348Z,1635875325.348 [SpartonCompassCal:Science:PeakDetectSalinity](DEBUG): Aggregate::initialize SpartonCompassCal:Science:PeakDetectSalinity 2021-11-02T17:48:45.348Z,1635875325.348 [SpartonCompassCal:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Running Loop=1 2021-11-02T17:48:45.348Z,1635875325.348 [SpartonCompassCal:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Initialize. 2021-11-02T17:48:45.350Z,1635875325.350 [SpartonCompassCal:Science:HighestSaltPeakReport] Running Loop=1 2021-11-02T17:48:45.350Z,1635875325.350 [SpartonCompassCal:Science:HighestSaltPeakReport](DEBUG): Aggregate::initialize SpartonCompassCal:Science:HighestSaltPeakReport 2021-11-02T17:48:45.351Z,1635875325.351 [SpartonCompassCal:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Running Loop=1 2021-11-02T17:48:45.351Z,1635875325.351 [SpartonCompassCal:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2021-11-02T17:48:45.351Z,1635875325.351 [SpartonCompassCal:Science:OceanCurrent] Running Loop=1 2021-11-02T17:48:45.351Z,1635875325.351 [SpartonCompassCal:Science:OceanCurrent](DEBUG): Aggregate::initialize SpartonCompassCal:Science:OceanCurrent 2021-11-02T17:48:45.352Z,1635875325.352 [SpartonCompassCal:Science:OceanCurrent:A.] Running Loop=1 2021-11-02T17:48:45.352Z,1635875325.352 [SpartonCompassCal:Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator. 2021-11-02T17:48:45.353Z,1635875325.353 [SpartonCompassCal:StandardEnvelopes] Running Loop=1 2021-11-02T17:48:45.353Z,1635875325.353 [SpartonCompassCal:StandardEnvelopes](DEBUG): Aggregate::initialize SpartonCompassCal:StandardEnvelopes 2021-11-02T17:48:45.354Z,1635875325.354 [SpartonCompassCal:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2021-11-02T17:48:45.358Z,1635875325.358 [SpartonCompassCal:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2021-11-02T17:48:45.358Z,1635875325.358 [SpartonCompassCal:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2021-11-02T17:48:45.358Z,1635875325.358 [SpartonCompassCal:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2021-11-02T17:48:45.359Z,1635875325.359 [SpartonCompassCal:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2021-11-02T17:48:45.359Z,1635875325.359 [SpartonCompassCal:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2021-11-02T17:48:45.359Z,1635875325.359 [SpartonCompassCal:I.Pitch] Running Loop=1 2021-11-02T17:48:45.359Z,1635875325.359 [SpartonCompassCal:I.Pitch](DEBUG): Initialize. 2021-11-02T17:48:45.359Z,1635875325.359 [SpartonCompassCal:J.Buoyancy] Running Loop=1 2021-11-02T17:48:45.359Z,1635875325.359 [SpartonCompassCal:J.Buoyancy](DEBUG): Initialize Buoyancy Component. 2021-11-02T17:48:45.359Z,1635875325.359 [SpartonCompassCal:K.DepthEnvelope] Running Loop=1 2021-11-02T17:48:45.359Z,1635875325.359 [SpartonCompassCal:K.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2021-11-02T17:48:45.360Z,1635875325.360 [SpartonCompassCal:L.AltitudeEnvelope] Running Loop=1 2021-11-02T17:48:45.360Z,1635875325.360 [SpartonCompassCal:L.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2021-11-02T17:48:45.360Z,1635875325.360 [SpartonCompassCal:M.YoYo] Running Loop=1 2021-11-02T17:48:45.360Z,1635875325.360 [SpartonCompassCal:M.YoYo](DEBUG): Initialize YoYoComponent. 2021-11-02T17:48:45.361Z,1635875325.361 [SpartonCompassCal:C] Running Loop=1 2021-11-02T17:48:45.361Z,1635875325.361 [SpartonCompassCal:M.YoYo] Running Loop=1 2021-11-02T17:48:45.362Z,1635875325.362 [SpartonCompassCal:L.AltitudeEnvelope] Running Loop=1 2021-11-02T17:48:45.366Z,1635875325.366 [SpartonCompassCal:K.DepthEnvelope] Running Loop=1 2021-11-02T17:48:45.367Z,1635875325.367 [SpartonCompassCal:J.Buoyancy] Running Loop=1 2021-11-02T17:48:45.367Z,1635875325.367 [SpartonCompassCal:I.Pitch] Running Loop=1 2021-11-02T17:48:45.368Z,1635875325.368 [SpartonCompassCal:StandardEnvelopes] Running Loop=1 2021-11-02T17:48:45.368Z,1635875325.368 [SpartonCompassCal:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2021-11-02T17:48:45.368Z,1635875325.368 [SpartonCompassCal:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2021-11-02T17:48:45.369Z,1635875325.369 [SpartonCompassCal:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2021-11-02T17:48:45.369Z,1635875325.369 [SpartonCompassCal:C] Stopped 2021-11-02T17:48:45.369Z,1635875325.369 [SpartonCompassCal:E] Running Loop=1 2021-11-02T17:48:45.369Z,1635875325.369 [SpartonCompassCal:Science] Running Loop=1 2021-11-02T17:48:45.375Z,1635875325.375 [SpartonCompassCal:Science:HighestSaltPeakReport] Stopped 2021-11-02T17:48:45.375Z,1635875325.375 [SpartonCompassCal:Science:HighestSaltPeakReport](DEBUG): Aggregate::uninitialize SpartonCompassCal:Science:HighestSaltPeakReport 2021-11-02T17:48:45.375Z,1635875325.375 [SpartonCompassCal:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Stopped 2021-11-02T17:48:45.375Z,1635875325.375 [SpartonCompassCal:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2021-11-02T17:48:45.375Z,1635875325.375 [SpartonCompassCal:Science:PeakDetectSalinity] Stopped 2021-11-02T17:48:45.375Z,1635875325.375 [SpartonCompassCal:Science:PeakDetectSalinity](DEBUG): Aggregate::uninitialize SpartonCompassCal:Science:PeakDetectSalinity 2021-11-02T17:48:45.375Z,1635875325.375 [SpartonCompassCal:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Stopped 2021-11-02T17:48:45.375Z,1635875325.375 [SpartonCompassCal:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2021-11-02T17:48:45.375Z,1635875325.375 [SpartonCompassCal:Science:PeakDetectFDOM] Stopped 2021-11-02T17:48:45.376Z,1635875325.376 [SpartonCompassCal:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize SpartonCompassCal:Science:PeakDetectFDOM 2021-11-02T17:48:45.376Z,1635875325.376 [SpartonCompassCal:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped 2021-11-02T17:48:45.376Z,1635875325.376 [SpartonCompassCal:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2021-11-02T17:48:45.376Z,1635875325.376 [SpartonCompassCal:Science:HighestOilPeakReport] Stopped 2021-11-02T17:48:45.376Z,1635875325.376 [SpartonCompassCal:Science:HighestOilPeakReport](DEBUG): Aggregate::uninitialize SpartonCompassCal:Science:HighestOilPeakReport 2021-11-02T17:48:45.376Z,1635875325.376 [SpartonCompassCal:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Stopped 2021-11-02T17:48:45.376Z,1635875325.376 [SpartonCompassCal:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2021-11-02T17:48:45.376Z,1635875325.376 [SpartonCompassCal:Science:PeakDetectOil] Stopped 2021-11-02T17:48:45.376Z,1635875325.376 [SpartonCompassCal:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize SpartonCompassCal:Science:PeakDetectOil 2021-11-02T17:48:45.376Z,1635875325.376 [SpartonCompassCal:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped 2021-11-02T17:48:45.377Z,1635875325.377 [SpartonCompassCal:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2021-11-02T17:48:45.377Z,1635875325.377 [SpartonCompassCal:Science:PeakDetectNO3] Stopped 2021-11-02T17:48:45.377Z,1635875325.377 [SpartonCompassCal:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize SpartonCompassCal:Science:PeakDetectNO3 2021-11-02T17:48:45.377Z,1635875325.377 [SpartonCompassCal:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped 2021-11-02T17:48:45.377Z,1635875325.377 [SpartonCompassCal:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2021-11-02T17:48:45.377Z,1635875325.377 [SpartonCompassCal:Science:HighestChlPeakReport] Stopped 2021-11-02T17:48:45.377Z,1635875325.377 [SpartonCompassCal:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize SpartonCompassCal:Science:HighestChlPeakReport 2021-11-02T17:48:45.377Z,1635875325.377 [SpartonCompassCal:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped 2021-11-02T17:48:45.377Z,1635875325.377 [SpartonCompassCal:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2021-11-02T17:48:45.377Z,1635875325.377 [SpartonCompassCal:Science:PeakDetectChl] Stopped 2021-11-02T17:48:45.378Z,1635875325.378 [SpartonCompassCal:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize SpartonCompassCal:Science:PeakDetectChl 2021-11-02T17:48:45.378Z,1635875325.378 [SpartonCompassCal:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped 2021-11-02T17:48:45.378Z,1635875325.378 [SpartonCompassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2021-11-02T17:48:45.382Z,1635875325.382 [SpartonCompassCal:Science:OceanCurrent:A.] Running Loop=1 2021-11-02T17:48:45.383Z,1635875325.383 [SpartonCompassCal:Science:Read_Oil] Running Loop=1 2021-11-02T17:48:45.384Z,1635875325.384 [SpartonCompassCal:Science:Read_Oil](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_petroleum_hydrocarbons_in_sea_water 2021-11-02T17:48:45.384Z,1635875325.384 [SpartonCompassCal:Science:F] Stopped 2021-11-02T17:48:45.384Z,1635875325.384 [SpartonCompassCal:Science:E] Stopped 2021-11-02T17:48:45.384Z,1635875325.384 [SpartonCompassCal:Science:D] Stopped 2021-11-02T17:48:45.384Z,1635875325.384 [SpartonCompassCal:Science:C] Running Loop=1 2021-11-02T17:48:45.385Z,1635875325.385 [SpartonCompassCal:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature 2021-11-02T17:48:45.390Z,1635875325.390 [SpartonCompassCal:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity 2021-11-02T17:48:45.390Z,1635875325.390 [SpartonCompassCal:Science:B] Running Loop=1 2021-11-02T17:48:45.391Z,1635875325.391 [SpartonCompassCal:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature 2021-11-02T17:48:45.392Z,1635875325.392 [SpartonCompassCal:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity 2021-11-02T17:48:45.392Z,1635875325.392 [SpartonCompassCal:Science:A] Stopped 2021-11-02T17:48:45.500Z,1635875325.500 [WetLabsSeaOWL_UV_A](INFO): Powering up 2021-11-02T17:48:45.713Z,1635875325.713 [SpartonCompassCal:E] Stopped 2021-11-02T17:48:45.713Z,1635875325.713 [SpartonCompassCal:F] Running Loop=1 2021-11-02T17:48:46.097Z,1635875326.097 [SpartonCompassCal:F] Stopped 2021-11-02T17:48:46.097Z,1635875326.097 [SpartonCompassCal:G] Running Loop=1 2021-11-02T17:48:46.589Z,1635875326.589 [SpartonCompassCal:G] Stopped 2021-11-02T17:48:46.589Z,1635875326.589 [SpartonCompassCal:StartingMission] Running Loop=1 2021-11-02T17:48:46.589Z,1635875326.589 [SpartonCompassCal:StartingMission](DEBUG): Aggregate::initialize SpartonCompassCal:StartingMission 2021-11-02T17:48:46.935Z,1635875326.935 [SpartonCompassCal:NeedComms] Running Loop=1 2021-11-02T17:48:46.935Z,1635875326.935 [SpartonCompassCal:NeedComms](DEBUG): Aggregate::initialize SpartonCompassCal:NeedComms 2021-11-02T17:48:46.935Z,1635875326.935 [SpartonCompassCal:NeedComms:B.GoToSurface] Running Loop=1 2021-11-02T17:48:46.935Z,1635875326.935 [SpartonCompassCal:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2021-11-02T17:48:46.936Z,1635875326.936 [SpartonCompassCal:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2021-11-02T17:48:46.936Z,1635875326.936 [SpartonCompassCal:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2021-11-02T17:48:46.937Z,1635875326.937 [SpartonCompassCal:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2021-11-02T17:48:46.937Z,1635875326.937 [SpartonCompassCal:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2021-11-02T17:48:46.937Z,1635875326.937 [SpartonCompassCal:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2021-11-02T17:48:46.938Z,1635875326.938 [SpartonCompassCal:NeedComms:A] Running Loop=1 2021-11-02T17:48:46.943Z,1635875326.943 [SpartonCompassCal:NeedComms:A](INFO): last time_fix was: 1635874981.000000 second since 1970/01/01T00:00:00Z 2021-11-02T17:48:46.943Z,1635875326.943 [SpartonCompassCal:NeedComms:A] Stopped 2021-11-02T17:48:47.344Z,1635875327.344 [SpartonCompassCal:NeedComms:C] Running Loop=1 2021-11-02T17:48:47.733Z,1635875327.733 [SpartonCompassCal:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix 2021-11-02T17:48:48.423Z,1635875328.423 [CTD_Seabird](ERROR): Failed to parse device response: 2021-11-02T17:48:49.332Z,1635875329.332 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174848.00,A,4133.09985,N,07042.82228,W,0.972,143.86,021121,,,A*74 2021-11-02T17:48:49.334Z,1635875329.334 [NAL9602](INFO): GPS fix at 20211102T174848: (41.551664, -70.713705) 2021-11-02T17:48:49.353Z,1635875329.353 [SpartonCompassCal:NeedComms:C] Stopped 2021-11-02T17:48:49.353Z,1635875329.353 [SpartonCompassCal:NeedComms:D] Running Loop=1 2021-11-02T17:48:49.787Z,1635875329.787 [SpartonCompassCal:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications 2021-11-02T17:48:53.451Z,1635875333.451 [DataOverHttps](INFO): Sending 78 bytes from file Logs/20211102T174027/Courier0006.lzma 2021-11-02T17:48:54.452Z,1635875334.452 [DataOverHttps](INFO): Moved sent file to Logs/20211102T174027/Courier0006.lzma.bak 2021-11-02T17:48:54.452Z,1635875334.452 [DataOverHttps](INFO): SBD MOMSN=595380 2021-11-02T17:49:03.499Z,1635875343.499 [DataOverHttps](INFO): Sending 53 bytes from file Logs/20211102T174027/Courier0009.lzma 2021-11-02T17:49:04.499Z,1635875344.499 [DataOverHttps](INFO): Moved sent file to Logs/20211102T174027/Courier0009.lzma.bak 2021-11-02T17:49:04.500Z,1635875344.500 [DataOverHttps](INFO): SBD MOMSN=595383 2021-11-02T17:49:08.777Z,1635875348.777 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2021-11-02T17:49:12.763Z,1635875352.763 [NAL9602](INFO): SBD MO Status=0, MOMSN=28005, MT Status=0, MTMSN=0 2021-11-02T17:49:12.763Z,1635875352.763 [NAL9602](INFO): No messages in MT queue 2021-11-02T17:49:13.531Z,1635875353.531 [DataOverHttps](INFO): Sending 705 bytes from file Logs/20211102T174027/Express0007.lzma 2021-11-02T17:49:14.532Z,1635875354.532 [DataOverHttps](INFO): Moved sent file to Logs/20211102T174027/Express0007.lzma.bak 2021-11-02T17:49:14.532Z,1635875354.532 [DataOverHttps](INFO): SBD MOMSN=595385 2021-11-02T17:49:23.570Z,1635875363.570 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20211102T174027/Express0010.lzma 2021-11-02T17:49:24.572Z,1635875364.572 [DataOverHttps](INFO): Moved sent file to Logs/20211102T174027/Express0010.lzma.bak 2021-11-02T17:49:24.572Z,1635875364.572 [DataOverHttps](INFO): SBD MOMSN=595407 2021-11-02T17:49:25.736Z,1635875365.736 [SpartonCompassCal:NeedComms:D] Stopped 2021-11-02T17:49:25.736Z,1635875365.736 [SpartonCompassCal:NeedComms:E] Running Loop=1 2021-11-02T17:49:26.107Z,1635875366.107 [SpartonCompassCal:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix 2021-11-02T17:49:27.717Z,1635875367.717 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174927.00,A,4133.09968,N,07042.82137,W,0.039,143.86,021121,,,A*74 2021-11-02T17:49:27.720Z,1635875367.720 [NAL9602](INFO): GPS fix at 20211102T174927: (41.551661, -70.713690) 2021-11-02T17:49:27.745Z,1635875367.745 [SpartonCompassCal:NeedComms:E] Stopped 2021-11-02T17:49:27.745Z,1635875367.745 [SpartonCompassCal:NeedComms](INFO): Completed SpartonCompassCal:NeedComms 2021-11-02T17:49:27.745Z,1635875367.745 [SpartonCompassCal:NeedComms] Stopped 2021-11-02T17:49:27.746Z,1635875367.746 [SpartonCompassCal:NeedComms](DEBUG): Aggregate::uninitialize SpartonCompassCal:NeedComms 2021-11-02T17:49:27.746Z,1635875367.746 [SpartonCompassCal:NeedComms:B.GoToSurface] Stopped 2021-11-02T17:49:27.746Z,1635875367.746 [SpartonCompassCal:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2021-11-02T17:49:28.151Z,1635875368.151 [SpartonCompassCal:StartingMission](INFO): Completed SpartonCompassCal:StartingMission 2021-11-02T17:49:28.151Z,1635875368.151 [SpartonCompassCal:StartingMission] Stopped 2021-11-02T17:49:28.151Z,1635875368.151 [SpartonCompassCal:StartingMission](DEBUG): Aggregate::uninitialize SpartonCompassCal:StartingMission 2021-11-02T17:49:28.151Z,1635875368.151 [SpartonCompassCal:AdaptCal] Running Loop=1 2021-11-02T17:49:28.152Z,1635875368.152 [SpartonCompassCal:AdaptCal](DEBUG): Aggregate::initialize SpartonCompassCal:AdaptCal 2021-11-02T17:49:28.152Z,1635875368.152 [SpartonCompassCal:AdaptCal:C.CalibrateAHRS_M2] Running Loop=1 2021-11-02T17:49:28.152Z,1635875368.152 [SpartonCompassCal:AdaptCal:C.CalibrateAHRS_M2](INFO): Initialize CalibrateSpartonComponent. 2021-11-02T17:49:28.152Z,1635875368.152 [SpartonCompassCal:AdaptCal:WaitForCalibrationInit] Running Loop=1 2021-11-02T17:49:28.662Z,1635875368.662 [SpartonCompassCal:AdaptCal:C.CalibrateAHRS_M2] Running Loop=1 2021-11-02T17:49:28.662Z,1635875368.662 [SpartonCompassCal:AdaptCal:C.CalibrateAHRS_M2](INFO): Initialize CalibrateSpartonComponent. 2021-11-02T17:49:28.663Z,1635875368.663 [SpartonCompassCal:AdaptCal:WaitForCalibrationInit](DEBUG): Initialize ReadDataComponent to sense AHRS_M2.number_of_calbration_points 2021-11-02T17:49:28.955Z,1635875368.955 [SpartonCompassCal:AdaptCal:C.CalibrateAHRS_M2](INFO): Initialize CalibrateSpartonComponent. 2021-11-02T17:49:29.348Z,1635875369.348 [SpartonCompassCal:AdaptCal:C.CalibrateAHRS_M2](INFO): Initialize CalibrateSpartonComponent. 2021-11-02T17:49:29.828Z,1635875369.828 [SpartonCompassCal:AdaptCal:C.CalibrateAHRS_M2](INFO): Initialize CalibrateSpartonComponent. 2021-11-02T17:49:31.379Z,1635875371.379 [SpartonCompassCal:AdaptCal:WaitForCalibrationInit] Stopped 2021-11-02T17:49:31.379Z,1635875371.379 [SpartonCompassCal:AdaptCal:AcquireCalibrationPoints] Running Loop=1 2021-11-02T17:49:31.379Z,1635875371.379 [SpartonCompassCal:AdaptCal:AcquireCalibrationPoints](DEBUG): Aggregate::initialize SpartonCompassCal:AdaptCal:AcquireCalibrationPoints 2021-11-02T17:49:31.379Z,1635875371.379 [SpartonCompassCal:AdaptCal:AcquireCalibrationPoints:A.Point] Running Loop=1 2021-11-02T17:49:31.379Z,1635875371.379 [SpartonCompassCal:AdaptCal:AcquireCalibrationPoints:A.Point](DEBUG): Initialize. 2021-11-02T17:49:31.379Z,1635875371.379 [SpartonCompassCal:AdaptCal:AcquireCalibrationPoints:B.SetSpeed] Running Loop=1 2021-11-02T17:49:31.380Z,1635875371.380 [SpartonCompassCal:AdaptCal:AcquireCalibrationPoints:B.SetSpeed](DEBUG): Initialize. 2021-11-02T17:49:31.767Z,1635875371.767 [SpartonCompassCal:AdaptCal:AcquireCalibrationPoints:B.SetSpeed] Running Loop=1 2021-11-02T17:49:31.767Z,1635875371.767 [SpartonCompassCal:AdaptCal:AcquireCalibrationPoints:A.Point] Running Loop=1 2021-11-02T17:49:56.852Z,1635875396.852 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2021-11-02T17:49:56.857Z,1635875396.857 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2021-11-02T17:49:58.907Z,1635875398.907 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch -15.00 deg, mass-position 4.00 mm (2 active estimators). 2021-11-02T17:50:00.469Z,1635875400.469 [NAL9602](INFO): Not Powering down - fast GPS 2021-11-02T17:50:00.894Z,1635875400.894 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2021-11-02T17:50:23.517Z,1635875423.517 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2021-11-02T17:50:28.859Z,1635875428.859 [CTD_Seabird](ERROR): Failed to parse device response: 5.51,15.1756,3.941.04 2021-11-02T17:50:29.860Z,1635875429.860 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.267124 2021-11-02T17:50:30.773Z,1635875430.773 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range. 2021-11-02T17:50:37.645Z,1635875437.645 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 15.00 deg, mass-position 4.00 mm (3 active estimators). 2021-11-02T17:50:59.270Z,1635875459.270 [Radio_Surface](INFO): Powering down 2021-11-02T17:51:05.998Z,1635875465.998 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-11-02T17:51:07.003Z,1635875467.003 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2021-11-02T17:54:20.718Z,1635875660.718 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range. 2021-11-02T17:54:26.822Z,1635875666.822 [RDI_PathfinderUp](ERROR): DVL uart error: serial timeout 2021-11-02T17:54:26.822Z,1635875666.822 [RDI_PathfinderUp] Communications Fault, FailCount= 1 2021-11-02T17:54:26.822Z,1635875666.822 [RDI_PathfinderUp](ERROR): Communications Fault 2021-11-02T17:54:26.959Z,1635875666.959 [CBIT](ERROR): Communications Fault in component: RDI_PathfinderUp 2021-11-02T17:54:27.242Z,1635875667.242 [RDI_PathfinderUp](INFO): Powering down 2021-11-02T17:54:28.022Z,1635875668.022 [CBIT](INFO): Clearing failed state for component RDI_PathfinderUp 2021-11-02T17:54:28.022Z,1635875668.022 [RDI_PathfinderUp] No Fault, FailCount= 1 2021-11-02T17:54:36.883Z,1635875676.883 [RDI_Pathfinder](ERROR): only read 0 of 4 data items 2021-11-02T17:56:30.841Z,1635875790.841 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2021-11-02T17:57:56.558Z,1635875876.558 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2021-11-02T17:58:48.612Z,1635875928.612 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2021-11-02T17:59:14.865Z,1635875954.865 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2021-11-02T18:00:04.231Z,1635876004.231 [CTD_Seabird](ERROR): Failed to parse device response: .99 2021-11-02T18:00:36.553Z,1635876036.553 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2021-11-02T18:02:34.551Z,1635876154.551 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range. 2021-11-02T18:03:06.876Z,1635876186.876 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2021-11-02T18:03:28.670Z,1635876208.670 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range. 2021-11-02T18:03:54.578Z,1635876234.578 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2021-11-02T18:04:02.602Z,1635876242.602 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2021-11-02T18:04:04.617Z,1635876244.617 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2021-11-02T18:04:04.621Z,1635876244.621 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2021-11-02T18:04:09.510Z,1635876249.510 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items 2021-11-02T18:04:14.717Z,1635876254.717 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2021-11-02T18:04:14.723Z,1635876254.723 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2021-11-02T18:04:19.564Z,1635876259.564 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2021-11-02T18:04:24.808Z,1635876264.808 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2021-11-02T18:04:29.670Z,1635876269.670 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for altitude 2021-11-02T18:04:39.769Z,1635876279.769 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2021-11-02T18:04:44.637Z,1635876284.637 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range. 2021-11-02T18:04:50.689Z,1635876290.689 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2021-11-02T18:05:04.832Z,1635876304.832 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for altitude 2021-11-02T18:05:09.668Z,1635876309.668 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for altitude 2021-11-02T18:05:24.629Z,1635876324.629 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2021-11-02T18:08:28.543Z,1635876508.543 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2021-11-02T18:10:42.748Z,1635876642.748 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2021-11-02T18:10:42.748Z,1635876642.748 [ThrusterHE](INFO): Powering down 2021-11-02T18:10:43.538Z,1635876643.538 [ThrusterHE](DEBUG): Initializing EZServoServo. 2021-11-02T18:10:43.659Z,1635876643.659 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2021-11-02T18:13:10.623Z,1635876790.623 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2021-11-02T18:14:45.625Z,1635876885.625 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2021-11-02T18:14:51.736Z,1635876891.736 [RDI_Pathfinder](ERROR): only read 1 of 4 data items 2021-11-02T18:15:21.569Z,1635876921.569 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2021-11-02T18:16:17.230Z,1635876977.230 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2021-11-02T18:16:17.233Z,1635876977.233 [BPC1](INFO): Received data from all battery sticks. 2021-11-02T18:22:25.761Z,1635877345.761 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2021-11-02T18:23:45.769Z,1635877425.769 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2021-11-02T18:23:50.597Z,1635877430.597 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2021-11-02T18:23:55.493Z,1635877435.493 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2021-11-02T18:24:00.706Z,1635877440.706 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2021-11-02T18:24:05.559Z,1635877445.559 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2021-11-02T18:24:15.692Z,1635877455.692 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2021-11-02T18:24:45.595Z,1635877485.595 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2021-11-02T18:24:55.689Z,1635877495.689 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2021-11-02T18:25:05.787Z,1635877505.787 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2021-11-02T18:25:35.718Z,1635877535.718 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2021-11-02T18:25:40.901Z,1635877540.901 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2021-11-02T18:27:19.898Z,1635877639.898 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range. 2021-11-02T18:27:25.955Z,1635877645.955 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2021-11-02T18:27:25.963Z,1635877645.963 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items 2021-11-02T18:27:35.694Z,1635877655.694 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2021-11-02T18:28:16.046Z,1635877696.046 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2021-11-02T18:28:45.958Z,1635877725.958 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2021-11-02T18:28:56.067Z,1635877736.067 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2021-11-02T18:31:49.777Z,1635877909.777 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2021-11-02T18:38:13.740Z,1635878293.740 [CTD_Seabird](ERROR): Failed to parse device response: 82 2021-11-02T18:39:14.015Z,1635878354.015 [CTD_Seabird](ERROR): Failed to parse device response: 07,4586.53 2021-11-02T18:45:21.893Z,1635878721.893 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2021-11-02T18:45:31.602Z,1635878731.602 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2021-11-02T18:45:36.809Z,1635878736.809 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2021-11-02T18:45:41.724Z,1635878741.724 [RDI_PathfinderUp](ERROR): only read 0 of 4 data items 2021-11-02T18:46:06.747Z,1635878766.747 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for altitude 2021-11-02T18:46:11.686Z,1635878771.686 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for altitude 2021-11-02T18:47:46.935Z,1635878866.935 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2021-11-02T18:47:48.971Z,1635878868.971 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2021-11-02T18:47:53.022Z,1635878873.022 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout 2021-11-02T18:47:53.022Z,1635878873.022 [RDI_Pathfinder] Communications Fault, FailCount= 1 2021-11-02T18:47:53.022Z,1635878873.022 [RDI_Pathfinder](ERROR): Communications Fault 2021-11-02T18:47:53.134Z,1635878873.134 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2021-11-02T18:47:53.451Z,1635878873.451 [RDI_Pathfinder](INFO): Powering down 2021-11-02T18:47:54.258Z,1635878874.258 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2021-11-02T18:47:54.259Z,1635878874.259 [RDI_Pathfinder] No Fault, FailCount= 1 2021-11-02T18:48:16.845Z,1635878896.845 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2021-11-02T18:48:31.780Z,1635878911.780 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2021-11-02T18:48:32.989Z,1635878912.989 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2021-11-02T18:48:37.047Z,1635878917.047 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2021-11-02T18:48:39.084Z,1635878919.084 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2021-11-02T18:48:45.538Z,1635878925.538 [SpartonCompassCal](INFO): Timed out from 2021-11-02T17:48:45.3Z 2021-11-02T18:48:45.538Z,1635878925.538 [MissionManager](INFO): SpartonCompassCal is completed. 2021-11-02T18:48:45.539Z,1635878925.539 [MissionManager](INFO): Uninitializing Mission SpartonCompassCal 2021-11-02T18:48:45.539Z,1635878925.539 [SpartonCompassCal] Stopped 2021-11-02T18:48:45.539Z,1635878925.539 [SpartonCompassCal](DEBUG): Aggregate::uninitialize SpartonCompassCal 2021-11-02T18:48:45.539Z,1635878925.539 [SpartonCompassCal:Science] Stopped 2021-11-02T18:48:45.539Z,1635878925.539 [SpartonCompassCal:Science](DEBUG): Aggregate::uninitialize SpartonCompassCal:Science 2021-11-02T18:48:45.539Z,1635878925.539 [SpartonCompassCal:Science:B] Stopped 2021-11-02T18:48:45.539Z,1635878925.539 [SpartonCompassCal:Science:C] Stopped 2021-11-02T18:48:45.539Z,1635878925.539 [SpartonCompassCal:Science:Read_Oil] Stopped 2021-11-02T18:48:45.539Z,1635878925.539 [SpartonCompassCal:Science:OceanCurrent] Stopped 2021-11-02T18:48:45.540Z,1635878925.540 [SpartonCompassCal:Science:OceanCurrent](DEBUG): Aggregate::uninitialize SpartonCompassCal:Science:OceanCurrent 2021-11-02T18:48:45.540Z,1635878925.540 [SpartonCompassCal:Science:OceanCurrent:A.] Stopped 2021-11-02T18:48:45.540Z,1635878925.540 [SpartonCompassCal:StandardEnvelopes] Stopped 2021-11-02T18:48:45.540Z,1635878925.540 [SpartonCompassCal:StandardEnvelopes](DEBUG): Aggregate::uninitialize SpartonCompassCal:StandardEnvelopes 2021-11-02T18:48:45.540Z,1635878925.540 [SpartonCompassCal:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2021-11-02T18:48:45.540Z,1635878925.540 [SpartonCompassCal:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2021-11-02T18:48:45.540Z,1635878925.540 [SpartonCompassCal:StandardEnvelopes:B.DepthEnvelope] Stopped 2021-11-02T18:48:45.540Z,1635878925.540 [SpartonCompassCal:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2021-11-02T18:48:45.540Z,1635878925.540 [SpartonCompassCal:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2021-11-02T18:48:45.540Z,1635878925.540 [SpartonCompassCal:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2021-11-02T18:48:45.540Z,1635878925.540 [SpartonCompassCal:I.Pitch] Stopped 2021-11-02T18:48:45.540Z,1635878925.540 [SpartonCompassCal:J.Buoyancy] Stopped 2021-11-02T18:48:45.541Z,1635878925.541 [SpartonCompassCal:J.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2021-11-02T18:48:45.541Z,1635878925.541 [SpartonCompassCal:K.DepthEnvelope] Stopped 2021-11-02T18:48:45.541Z,1635878925.541 [SpartonCompassCal:K.DepthEnvelope](DEBUG): Uninitialize. 2021-11-02T18:48:45.541Z,1635878925.541 [SpartonCompassCal:L.AltitudeEnvelope] Stopped 2021-11-02T18:48:45.541Z,1635878925.541 [SpartonCompassCal:L.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2021-11-02T18:48:45.541Z,1635878925.541 [SpartonCompassCal:M.YoYo] Stopped 2021-11-02T18:48:45.541Z,1635878925.541 [SpartonCompassCal:M.YoYo](DEBUG): Uninitialize YoYoComponent. 2021-11-02T18:48:45.541Z,1635878925.541 [SpartonCompassCal:AdaptCal] Stopped 2021-11-02T18:48:45.541Z,1635878925.541 [SpartonCompassCal:AdaptCal](DEBUG): Aggregate::uninitialize SpartonCompassCal:AdaptCal 2021-11-02T18:48:45.541Z,1635878925.541 [SpartonCompassCal:AdaptCal:AcquireCalibrationPoints] Stopped 2021-11-02T18:48:45.542Z,1635878925.542 [SpartonCompassCal:AdaptCal:AcquireCalibrationPoints](DEBUG): Aggregate::uninitialize SpartonCompassCal:AdaptCal:AcquireCalibrationPoints 2021-11-02T18:48:45.542Z,1635878925.542 [SpartonCompassCal:AdaptCal:AcquireCalibrationPoints:A.Point] Stopped 2021-11-02T18:48:45.542Z,1635878925.542 [SpartonCompassCal:AdaptCal:AcquireCalibrationPoints:B.SetSpeed] Stopped 2021-11-02T18:48:45.542Z,1635878925.542 [SpartonCompassCal:AdaptCal:AcquireCalibrationPoints:B.SetSpeed](DEBUG): Uninitialize. 2021-11-02T18:48:45.542Z,1635878925.542 [SpartonCompassCal:AdaptCal:C.CalibrateAHRS_M2] Stopped 2021-11-02T18:48:45.542Z,1635878925.542 [SpartonCompassCal:AdaptCal:C.CalibrateAHRS_M2](INFO): Uninitialize CalibrateSpartonComponent. 2021-11-02T18:48:45.888Z,1635878925.888 [WetLabsSeaOWL_UV_A](INFO): Powering down 2021-11-02T18:48:45.927Z,1635878925.927 [AHRS_M2](IMPORTANT): Acquired 7 calibration points. 2021-11-02T18:48:46.001Z,1635878926.001 [MissionManager](IMPORTANT): Started mission DefaultWithUndock 2021-11-02T18:48:46.002Z,1635878926.002 [DefaultWithUndock] Running Loop=1 2021-11-02T18:48:46.002Z,1635878926.002 [DefaultWithUndock](DEBUG): Aggregate::initialize DefaultWithUndock 2021-11-02T18:48:46.007Z,1635878926.007 [DefaultWithUndock:A.Wait] Running Loop=1 2021-11-02T18:48:46.007Z,1635878926.007 [DefaultWithUndock:A.Wait](DEBUG): Initialize Wait Component. 2021-11-02T18:48:46.753Z,1635878926.753 [AHRS_M2](IMPORTANT): Magnetic calibration quality (0[best] to 10000) is 0.000158. 2021-11-02T18:48:46.836Z,1635878926.836 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2021-11-02T18:48:51.975Z,1635878931.975 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2021-11-02T18:48:56.858Z,1635878936.858 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2021-11-02T18:49:02.925Z,1635878942.925 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2021-11-02T18:49:06.948Z,1635878946.948 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2021-11-02T18:49:16.222Z,1635878956.222 [DefaultWithUndock:A.Wait](INFO): Done Waiting. 2021-11-02T18:49:16.222Z,1635878956.222 [DefaultWithUndock:A.Wait] Stopped 2021-11-02T18:49:16.222Z,1635878956.222 [DefaultWithUndock:A.Wait](DEBUG): Uninitialize Wait Component. 2021-11-02T18:49:16.223Z,1635878956.223 [DefaultWithUndock:LeaveDock] Running Loop=1 2021-11-02T18:49:16.223Z,1635878956.223 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::initialize DefaultWithUndock:LeaveDock 2021-11-02T18:49:16.223Z,1635878956.223 [DefaultWithUndock:LeaveDock:A.] Running Loop=1 2021-11-02T18:49:16.223Z,1635878956.223 [DefaultWithUndock:LeaveDock:A.](INFO): Initializing TrackAcousticContact. 2021-11-02T18:49:16.223Z,1635878956.223 [DefaultWithUndock:LeaveDock:B.Undock] Running Loop=1 2021-11-02T18:49:16.223Z,1635878956.223 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize. 2021-11-02T18:49:16.223Z,1635878956.223 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values. 2021-11-02T18:49:16.991Z,1635878956.991 [DDM](INFO): Detaching. Opening Latch 2021-11-02T18:49:17.037Z,1635878957.037 [PowerOnly](INFO): Powering up loadControl 2021-11-02T18:49:17.112Z,1635878957.112 [RDI_PathfinderUp](ERROR): DVL uart error: serial timeout 2021-11-02T18:49:17.113Z,1635878957.113 [RDI_PathfinderUp] Communications Fault, FailCount= 1 2021-11-02T18:49:17.113Z,1635878957.113 [RDI_PathfinderUp](ERROR): Communications Fault 2021-11-02T18:49:17.190Z,1635878957.190 [CBIT](ERROR): Communications Fault in component: RDI_PathfinderUp 2021-11-02T18:49:17.385Z,1635878957.385 [DDM](INFO): Detaching. Opening Latch 2021-11-02T18:49:17.502Z,1635878957.502 [RDI_PathfinderUp](INFO): Powering down 2021-11-02T18:49:17.791Z,1635878957.791 [DDM](INFO): Detaching. Opening Latch 2021-11-02T18:49:18.276Z,1635878958.276 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detaching from dock. 2021-11-02T18:49:18.422Z,1635878958.422 [CBIT](INFO): Clearing failed state for component RDI_PathfinderUp 2021-11-02T18:49:18.422Z,1635878958.422 [RDI_PathfinderUp] No Fault, FailCount= 1 2021-11-02T18:49:18.696Z,1635878958.696 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Engaging thruster for 10.0 seconds (max) to disengage. 2021-11-02T18:49:19.081Z,1635878959.081 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2021-11-02T18:49:19.853Z,1635878959.853 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76 2021-11-02T18:49:20.255Z,1635878960.255 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B 2021-11-02T18:49:21.085Z,1635878961.085 [Micromodem](INFO): Nmea in: $SNTTA,,,,,184920.21*5B 2021-11-02T18:49:21.891Z,1635878961.891 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Thruster off. 2021-11-02T18:49:22.307Z,1635878962.307 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detected possible detachment. Starting timer. 2021-11-02T18:49:24.314Z,1635878964.314 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2021-11-02T18:49:25.101Z,1635878965.101 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76 2021-11-02T18:49:25.514Z,1635878965.514 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B 2021-11-02T18:49:26.325Z,1635878966.325 [Micromodem](INFO): Nmea in: $SNTTA,,,,,184925.45*5C 2021-11-02T18:49:29.559Z,1635878969.559 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2021-11-02T18:49:30.359Z,1635878970.359 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76 2021-11-02T18:49:30.755Z,1635878970.755 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B 2021-11-02T18:49:31.577Z,1635878971.577 [Micromodem](INFO): Nmea in: $SNTTA,,,,,184930.71*5F 2021-11-02T18:49:32.396Z,1635878972.396 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2021-11-02T18:49:33.984Z,1635878973.984 [DUSBL_Hydroid](ERROR): No response from remote modem. 2021-11-02T18:49:35.218Z,1635878975.218 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2021-11-02T18:49:36.005Z,1635878976.005 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76 2021-11-02T18:49:36.417Z,1635878976.417 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B 2021-11-02T18:49:37.225Z,1635878977.225 [Micromodem](INFO): Nmea in: $SNTTA,0.387171,,,,184936.36*4F 2021-11-02T18:49:40.506Z,1635878980.506 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2021-11-02T18:49:41.267Z,1635878981.267 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76 2021-11-02T18:49:41.663Z,1635878981.663 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B 2021-11-02T18:49:42.071Z,1635878982.071 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2021-11-02T18:49:42.485Z,1635878982.485 [Micromodem](INFO): Nmea in: $SNTTA,0.387371,,,,184941.62*4C 2021-11-02T18:49:42.497Z,1635878982.497 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached at range: 577.86 m. Transitioning DDM to standby. 2021-11-02T18:49:43.241Z,1635878983.241 [DDM](INFO): Closing Latch 2021-11-02T18:49:43.645Z,1635878983.645 [DDM](INFO): Closing Latch 2021-11-02T18:49:44.050Z,1635878984.050 [DDM](INFO): Closing Latch 2021-11-02T18:49:44.543Z,1635878984.543 [DefaultWithUndock:LeaveDock:B.Undock](INFO): DDM at standby. 2021-11-02T18:49:44.967Z,1635878984.967 [DefaultWithUndock:LeaveDock:B.Undock](IMPORTANT): Undocking sequence complete. 2021-11-02T18:49:44.968Z,1635878984.968 [DefaultWithUndock:LeaveDock:B.Undock] Stopped 2021-11-02T18:49:44.968Z,1635878984.968 [DefaultWithUndock:LeaveDock](INFO): Completed DefaultWithUndock:LeaveDock 2021-11-02T18:49:44.968Z,1635878984.968 [DefaultWithUndock:LeaveDock] Stopped 2021-11-02T18:49:44.969Z,1635878984.969 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::uninitialize DefaultWithUndock:LeaveDock 2021-11-02T18:49:44.969Z,1635878984.969 [DefaultWithUndock:LeaveDock:A.] Stopped 2021-11-02T18:49:44.969Z,1635878984.969 [DefaultWithUndock:SurfaceDefault] Running Loop=1 2021-11-02T18:49:44.969Z,1635878984.969 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault 2021-11-02T18:49:44.969Z,1635878984.969 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen] Running Loop=1 2021-11-02T18:49:44.969Z,1635878984.969 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:HoldLatchOpen 2021-11-02T18:49:44.969Z,1635878984.969 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen:A] Running Loop=1 2021-11-02T18:49:44.969Z,1635878984.969 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen:B] Running Loop=1 2021-11-02T18:49:44.969Z,1635878984.969 [DefaultWithUndock:SurfaceDefault:B.GoToSurface] Running Loop=1 2021-11-02T18:49:44.969Z,1635878984.969 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2021-11-02T18:49:44.970Z,1635878984.970 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2021-11-02T18:49:44.975Z,1635878984.975 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2021-11-02T18:49:44.975Z,1635878984.975 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2021-11-02T18:49:44.975Z,1635878984.975 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2021-11-02T18:49:44.976Z,1635878984.976 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2021-11-02T18:49:45.310Z,1635878985.310 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen] Running Loop=1 2021-11-02T18:49:45.310Z,1635878985.310 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen:B] Running Loop=1 2021-11-02T18:49:45.326Z,1635878985.326 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen:A] Running Loop=1 2021-11-02T18:49:45.327Z,1635878985.327 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen:A](DEBUG): Initialize ReadDataComponent to sense Dock.docking_state 2021-11-02T18:49:45.665Z,1635878985.665 [DDM](INFO): Extending Whiskers. 2021-11-02T18:49:45.699Z,1635878985.699 [DDM](INFO): Arming. Opening Latch 2021-11-02T18:49:45.780Z,1635878985.780 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -26.00 mm (4 active estimators). 2021-11-02T18:49:45.887Z,1635878985.887 [Radio_Surface](INFO): Powering up 2021-11-02T18:49:46.070Z,1635878986.070 [DDM](INFO): Extending Whiskers. 2021-11-02T18:49:46.098Z,1635878986.098 [DDM](INFO): Arming. Opening Latch 2021-11-02T18:49:46.483Z,1635878986.483 [DDM](INFO): Extending Whiskers. 2021-11-02T18:49:46.516Z,1635878986.516 [DDM](INFO): Arming. Opening Latch 2021-11-02T18:49:51.594Z,1635878991.594 [DataOverHttps](INFO): Radio surface powered ON. 2021-11-02T18:49:51.815Z,1635878991.815 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2021-11-02T18:49:57.054Z,1635878997.054 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2021-11-02T18:50:06.830Z,1635879006.830 [RDI_PathfinderUp](ERROR): DVL uart error: serial timeout 2021-11-02T18:50:06.830Z,1635879006.830 [RDI_PathfinderUp] Communications Fault, FailCount= 1 2021-11-02T18:50:06.830Z,1635879006.830 [RDI_PathfinderUp](ERROR): Communications Fault 2021-11-02T18:50:07.042Z,1635879007.042 [CBIT](ERROR): Communications Fault in component: RDI_PathfinderUp 2021-11-02T18:50:07.190Z,1635879007.190 [RDI_PathfinderUp](INFO): Powering down 2021-11-02T18:50:08.035Z,1635879008.035 [CBIT](INFO): Clearing failed state for component RDI_PathfinderUp 2021-11-02T18:50:08.035Z,1635879008.035 [RDI_PathfinderUp] No Fault, FailCount= 1 2021-11-02T18:50:19.244Z,1635879019.244 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=1 2021-11-02T18:50:19.244Z,1635879019.244 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2021-11-02T18:50:19.244Z,1635879019.244 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2021-11-02T18:50:19.244Z,1635879019.244 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen] Stopped 2021-11-02T18:50:19.244Z,1635879019.244 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:HoldLatchOpen 2021-11-02T18:50:19.245Z,1635879019.245 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen:A] Stopped 2021-11-02T18:50:19.245Z,1635879019.245 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen:B] Stopped 2021-11-02T18:50:21.719Z,1635879021.719 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-11-02T18:50:48.318Z,1635879048.318 [NAL9602](INFO): SBD MO Status=0, MOMSN=28006, MT Status=0, MTMSN=0 2021-11-02T18:50:48.319Z,1635879048.319 [NAL9602](INFO): No messages in MT queue 2021-11-02T18:50:48.319Z,1635879048.319 [PowerOnly](INFO): Powering down loadControl 2021-11-02T18:50:49.543Z,1635879049.543 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185048.00,A,4133.16090,N,07042.93406,W,0.603,308.06,021121,,,A*77 2021-11-02T18:50:49.545Z,1635879049.545 [NAL9602](INFO): GPS fix at 20211102T185048: (41.552682, -70.715568) 2021-11-02T18:50:49.558Z,1635879049.558 [UniversalFixResidualReporter](INFO): Fix residual: 1.7 %DT, over the last 2306.7 m. Residual distance 38.2 m at bearing 136.6 degrees. Fix at (41.5527, -70.7156) with 193.1 m made good. 2021-11-02T18:50:49.559Z,1635879049.559 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2021-11-02T18:50:49.560Z,1635879049.560 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2021-11-02T18:50:56.866Z,1635879056.866 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-11-02T18:51:05.885Z,1635879065.885 [NAL9602](INFO): SBD MO Status=2, MOMSN=28007, MT Status=2, MTMSN=0 2021-11-02T18:51:05.885Z,1635879065.885 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-11-02T18:51:20.597Z,1635879080.597 [NAL9602](INFO): SBD MO Status=2, MOMSN=28007, MT Status=2, MTMSN=0 2021-11-02T18:51:20.597Z,1635879080.597 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-11-02T18:51:32.023Z,1635879092.023 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-11-02T18:51:38.111Z,1635879098.111 [NAL9602](INFO): SBD MO Status=2, MOMSN=28007, MT Status=2, MTMSN=0 2021-11-02T18:51:38.111Z,1635879098.111 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-11-02T18:52:06.122Z,1635879126.122 [NAL9602](INFO): SBD MO Status=1, MOMSN=28007, MT Status=0, MTMSN=0 2021-11-02T18:52:06.178Z,1635879126.178 [NAL9602](INFO): Sent 184 bytes from file Logs/20211102T174027/Courier0012.lzma 2021-11-02T18:52:06.179Z,1635879126.179 [NAL9602](INFO): Packets left to send: 1 2021-11-02T18:52:07.163Z,1635879127.163 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-11-02T18:52:17.245Z,1635879137.245 [NAL9602](INFO): SBD MO Status=1, MOMSN=28008, MT Status=0, MTMSN=0 2021-11-02T18:52:17.294Z,1635879137.294 [NAL9602](INFO): Sent 87 bytes from file Logs/20211102T174027/Courier0012.lzma 2021-11-02T18:52:17.295Z,1635879137.295 [NAL9602](INFO): Packets left to send: 0 2021-11-02T18:52:29.894Z,1635879149.894 [NAL9602](INFO): SBD MO Status=2, MOMSN=28009, MT Status=2, MTMSN=0 2021-11-02T18:52:29.895Z,1635879149.895 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-11-02T18:52:42.303Z,1635879162.303 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-11-02T18:52:49.906Z,1635879169.906 [NAL9602](INFO): SBD MO Status=1, MOMSN=28009, MT Status=0, MTMSN=0 2021-11-02T18:52:49.958Z,1635879169.958 [NAL9602](INFO): Sent 184 bytes from file Logs/20211102T174027/Express0013.lzma 2021-11-02T18:52:49.959Z,1635879169.959 [NAL9602](INFO): Packets left to send: 8 2021-11-02T18:53:00.222Z,1635879180.222 [NAL9602](INFO): SBD MO Status=1, MOMSN=28010, MT Status=0, MTMSN=0 2021-11-02T18:53:00.270Z,1635879180.270 [NAL9602](INFO): Sent 184 bytes from file Logs/20211102T174027/Express0013.lzma 2021-11-02T18:53:00.271Z,1635879180.271 [NAL9602](INFO): Packets left to send: 7 2021-11-02T18:53:09.255Z,1635879189.255 [NAL9602](INFO): SBD MO Status=1, MOMSN=28011, MT Status=0, MTMSN=0 2021-11-02T18:53:09.310Z,1635879189.310 [NAL9602](INFO): Sent 184 bytes from file Logs/20211102T174027/Express0013.lzma 2021-11-02T18:53:09.311Z,1635879189.311 [NAL9602](INFO): Packets left to send: 6 2021-11-02T18:53:11.438Z,1635879191.438 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002987 2021-11-02T18:53:15.457Z,1635879195.457 [DataOverHttps](INFO): Sending 1507 bytes from file Logs/20211102T174027/Express0013.lzma 2021-11-02T18:53:16.456Z,1635879196.456 [DataOverHttps](INFO): Moved sent file to Logs/20211102T174027/Express0013.lzma.bak 2021-11-02T18:53:16.456Z,1635879196.456 [DataOverHttps](INFO): SBD MOMSN=595416 2021-11-02T18:53:17.555Z,1635879197.555 [NAL9602](INFO): SBD MO Status=1, MOMSN=28012, MT Status=0, MTMSN=0 2021-11-02T18:53:17.603Z,1635879197.603 [NAL9602](INFO): Sent 184 bytes from file Logs/20211102T174027/Express0013.lzma 2021-11-02T18:53:17.603Z,1635879197.603 [NAL9602](INFO): Packets left to send: 5 2021-11-02T18:53:17.635Z,1635879197.635 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2021-11-02T18:53:17.636Z,1635879197.636 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1 2021-11-02T18:53:17.636Z,1635879197.636 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-11-02T18:53:48.228Z,1635879228.228 [NAL9602](INFO): Not Powering down - fast GPS 2021-11-02T18:54:29.445Z,1635879269.445 [CBIT](IMPORTANT): Beginning ground fault scan 2021-11-02T18:54:31.771Z,1635879271.771 [DataOverHttps](IMPORTANT): SBD MTMSN=20211102T185430 2021-11-02T18:54:35.790Z,1635879275.790 [DataOverHttps](INFO): Received command: restart logs