2021-01-27T13:53:26.492Z,1611755606.492 [CommandLine](IMPORTANT): got command restart logs
2021-01-27T13:55:14.103Z,1611755714.103 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2021-01-27T13:56:33.285Z,1611755793.285 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting.
2021-01-27T13:56:33.286Z,1611755793.286 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped
2021-01-27T13:56:33.286Z,1611755793.286 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-01-27T13:56:33.286Z,1611755793.286 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1
2021-01-27T13:56:33.702Z,1611755793.702 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped
2021-01-27T13:56:33.702Z,1611755793.702 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1
2021-01-27T13:56:34.115Z,1611755794.115 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 16.062304 min 
2021-01-27T13:56:34.115Z,1611755794.115 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped
2021-01-27T13:56:34.115Z,1611755794.115 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn
2021-01-27T13:56:34.115Z,1611755794.115 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped
2021-01-27T13:56:34.115Z,1611755794.115 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn
2021-01-27T13:56:34.115Z,1611755794.115 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #3
2021-01-27T13:56:34.115Z,1611755794.115 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=3
2021-01-27T13:56:34.115Z,1611755794.115 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn
2021-01-27T13:56:34.116Z,1611755794.116 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1
2021-01-27T13:56:36.090Z,1611755796.090 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,135635.00,A,4133.19321,N,07042.58088,W,0.680,224.23,270121,,,A*76
2021-01-27T13:56:36.093Z,1611755796.093 [NAL9602](INFO): GPS fix at 20210127T135635: (41.553220, -70.709681)
2021-01-27T13:56:36.147Z,1611755796.147 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped
2021-01-27T13:56:36.147Z,1611755796.147 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1
2021-01-27T13:56:40.264Z,1611755800.264 [DataOverHttps](INFO): Sending 52 bytes from file Logs/20210127T013451/Courier0027.lzma
2021-01-27T13:56:41.266Z,1611755801.266 [DataOverHttps](INFO): Moved sent file to Logs/20210127T013451/Courier0027.lzma.bak
2021-01-27T13:56:41.266Z,1611755801.266 [DataOverHttps](INFO): SBD MOMSN=68525
2021-01-27T13:56:50.336Z,1611755810.336 [DataOverHttps](INFO): Sending 260 bytes from file Logs/20210127T135326/Courier0000.lzma
2021-01-27T13:56:51.338Z,1611755811.338 [DataOverHttps](INFO): Moved sent file to Logs/20210127T135326/Courier0000.lzma.bak
2021-01-27T13:56:51.338Z,1611755811.338 [DataOverHttps](INFO): SBD MOMSN=68527
2021-01-27T13:56:53.879Z,1611755813.879 [NAL9602](INFO): SBD MO Status=0, MOMSN=13112, MT Status=0, MTMSN=0
2021-01-27T13:56:53.879Z,1611755813.879 [NAL9602](INFO): No messages in MT queue
2021-01-27T13:57:00.375Z,1611755820.375 [DataOverHttps](INFO): Sending 52 bytes from file Logs/20210127T135326/Courier0003.lzma
2021-01-27T13:57:01.377Z,1611755821.377 [DataOverHttps](INFO): Moved sent file to Logs/20210127T135326/Courier0003.lzma.bak
2021-01-27T13:57:01.378Z,1611755821.378 [DataOverHttps](INFO): SBD MOMSN=68531
2021-01-27T13:57:10.430Z,1611755830.430 [DataOverHttps](INFO): Sending 95 bytes from file Logs/20210127T013451/Express0028.lzma
2021-01-27T13:57:11.430Z,1611755831.430 [DataOverHttps](INFO): Moved sent file to Logs/20210127T013451/Express0028.lzma.bak
2021-01-27T13:57:11.430Z,1611755831.430 [DataOverHttps](INFO): SBD MOMSN=68533
2021-01-27T13:57:20.469Z,1611755840.469 [DataOverHttps](INFO): Sending 340 bytes from file Logs/20210127T135326/Express0001.lzma
2021-01-27T13:57:21.470Z,1611755841.470 [DataOverHttps](INFO): Moved sent file to Logs/20210127T135326/Express0001.lzma.bak
2021-01-27T13:57:21.470Z,1611755841.470 [DataOverHttps](INFO): SBD MOMSN=68536
2021-01-27T13:57:24.594Z,1611755844.594 [NAL9602](INFO): Not Powering down - fast GPS
2021-01-27T13:57:30.508Z,1611755850.508 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20210127T135326/Express0004.lzma
2021-01-27T13:57:31.510Z,1611755851.510 [DataOverHttps](INFO): Moved sent file to Logs/20210127T135326/Express0004.lzma.bak
2021-01-27T13:57:31.510Z,1611755851.510 [DataOverHttps](INFO): SBD MOMSN=68541
2021-01-27T13:57:32.697Z,1611755852.697 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped
2021-01-27T13:57:32.697Z,1611755852.697 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1
2021-01-27T13:57:32.697Z,1611755852.697 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-01-27T13:59:57.094Z,1611755997.094 [DataOverHttps](IMPORTANT): SBD MTMSN=20210127T135956
2021-01-27T14:00:01.112Z,1611756001.112 [DataOverHttps](INFO): Received command:sched asap "load underIce/profile_stationUnder.xml;set profile_stationUnder.MissionTimeout 20 minute;set profile_stationUnder.Lat 41.552 degree;set profile_stationUnder.Lon -70.70961 degree;set profile_stationUnder.Radius 200 meter" 2t2vo 1 3
2021-01-27T14:00:01.205Z,1611756001.205 [CommandLine](IMPORTANT): got command schedule asap "load underIce/profile_stationUnder.xml;set profile_stationUnder.MissionTimeout 20 minute;set profile_stationUnder.Lat 41.552 degree;set profile_stationUnder.Lon -70.70961 degree;set profile_stationUnder.Radius 200 meter" 2t2vo 1 3.000000
2021-01-27T14:00:01.205Z,1611756001.205 [CommandLine](IMPORTANT): Scheduling command #1 of 3 with id=2t2vo
2021-01-27T14:00:01.206Z,1611756001.206 [CommandLine](IMPORTANT): Scheduled #21 (#1 of 3 with id='2t2vo'): "load underIce/profile_stationUnder.xml;set profile_stationUnder.MissionTimeout 20 minute;set profile_stationUnder.Lat 41.552 degree;set profile_stationUnder.Lon -70.70961 degree;set profile_stationUnder.Radius 200 meter" ASAP
2021-01-27T14:00:02.114Z,1611756002.114 [DataOverHttps](IMPORTANT): SBD MTMSN=20210127T140001
2021-01-27T14:00:06.132Z,1611756006.132 [DataOverHttps](INFO): Received command:sched asap "set profile_stationUnder.YoYoMinDepth 3 meter;set profile_stationUnder.YoYoMaxDepth 7 meter;set profile_stationUnder.YoYoMinAltitude 3 meter;set profile_stationUnder.CircleMaxError 50 meter" 2t2vo 2 3
2021-01-27T14:00:06.238Z,1611756006.238 [CommandLine](IMPORTANT): got command schedule asap "set profile_stationUnder.YoYoMinDepth 3 meter;set profile_stationUnder.YoYoMaxDepth 7 meter;set profile_stationUnder.YoYoMinAltitude 3 meter;set profile_stationUnder.CircleMaxError 50 meter" 2t2vo 2 3.000000
2021-01-27T14:00:06.239Z,1611756006.239 [CommandLine](IMPORTANT): Scheduling command #2 of 3 with id=2t2vo
2021-01-27T14:00:06.240Z,1611756006.240 [CommandLine](IMPORTANT): Scheduled #22 (#2 of 3 with id='2t2vo'): "set profile_stationUnder.YoYoMinDepth 3 meter;set profile_stationUnder.YoYoMaxDepth 7 meter;set profile_stationUnder.YoYoMinAltitude 3 meter;set profile_stationUnder.CircleMaxError 50 meter" ASAP
2021-01-27T14:00:07.134Z,1611756007.134 [DataOverHttps](IMPORTANT): SBD MTMSN=20210127T140006
2021-01-27T14:00:11.170Z,1611756011.170 [DataOverHttps](INFO): Received command:sched asap "set profile_stationUnder.MinAltitude 3 meter;set profile_stationUnder.MaxDepth 9 meter;set profile_stationUnder.MinOffshore 500 meter;run" 2t2vo 3 3
2021-01-27T14:00:11.265Z,1611756011.265 [CommandLine](IMPORTANT): got command schedule asap "set profile_stationUnder.MinAltitude 3 meter;set profile_stationUnder.MaxDepth 9 meter;set profile_stationUnder.MinOffshore 500 meter;run" 2t2vo 3 3.000000
2021-01-27T14:00:11.265Z,1611756011.265 [CommandLine](IMPORTANT): Scheduling command #3 of 3 with id=2t2vo
2021-01-27T14:00:11.266Z,1611756011.266 [CommandLine](IMPORTANT): Scheduled #23 (#3 of 3 with id='2t2vo'): "set profile_stationUnder.MinAltitude 3 meter;set profile_stationUnder.MaxDepth 9 meter;set profile_stationUnder.MinOffshore 500 meter;run" ASAP
2021-01-27T14:00:11.345Z,1611756011.345 [CommandLine](IMPORTANT): got command load ./Missions/underIce/profile_stationUnder.xml
2021-01-27T14:00:11.346Z,1611756011.346 [MissionManager](INFO): Loading Mission: ./Missions/underIce/profile_stationUnder.xml
2021-01-27T14:00:11.438Z,1611756011.438 [MissionManager](INFO): DefineArg profile_stationUnder.MissionTimeout = 2.000000 h
2021-01-27T14:00:11.440Z,1611756011.440 [MissionManager](INFO): DefineArg profile_stationUnder.DetachTimeout = 20.000000 min
2021-01-27T14:00:11.442Z,1611756011.442 [MissionManager](INFO): DefineArg profile_stationUnder.Lat = 44.847000 arcdeg
2021-01-27T14:00:11.445Z,1611756011.445 [MissionManager](INFO): DefineArg profile_stationUnder.Lon = -67.577535 arcdeg
2021-01-27T14:00:11.447Z,1611756011.447 [MissionManager](INFO): DefineArg profile_stationUnder.Radius = 50.000000 m
2021-01-27T14:00:11.449Z,1611756011.449 [MissionManager](INFO): DefineArg profile_stationUnder.YoYoMinDepth = 2.500000 m
2021-01-27T14:00:11.451Z,1611756011.451 [MissionManager](INFO): DefineArg profile_stationUnder.YoYoMaxDepth = 5.000000 m
2021-01-27T14:00:11.454Z,1611756011.454 [MissionManager](INFO): DefineArg profile_stationUnder.YoYoMinAltitude = 4.000000 m
2021-01-27T14:00:11.456Z,1611756011.456 [MissionManager](INFO): DefineArg profile_stationUnder.YoYoPitch = 15.000000 arcdeg
2021-01-27T14:00:11.458Z,1611756011.458 [MissionManager](INFO): DefineArg profile_stationUnder.Speed = 1.000000 m/s
2021-01-27T14:00:11.517Z,1611756011.517 [MissionManager](INFO): DefineArg profile_stationUnder.CircleMaxError = 1.000000 m
2021-01-27T14:00:11.519Z,1611756011.519 [MissionManager](INFO): DefineArg profile_stationUnder.CircleTurnToPort = 0 bool
2021-01-27T14:00:11.540Z,1611756011.540 [MissionManager](INFO): DefineArg profile_stationUnder.KwpHeading = 0.010000 rad/m
2021-01-27T14:00:11.542Z,1611756011.542 [MissionManager](INFO): DefineArg profile_stationUnder.MinAltitude = 2.000000 m
2021-01-27T14:00:11.561Z,1611756011.561 [MissionManager](INFO): DefineArg profile_stationUnder.MaxDepth = 8.000000 m
2021-01-27T14:00:11.563Z,1611756011.563 [MissionManager](INFO): DefineArg profile_stationUnder.MinOffshore = 10.000000 m
2021-01-27T14:00:11.570Z,1611756011.570 [MissionManager](INFO): DefineArg profile_stationUnder.MicromodemCommsInterval = 5.000000 min
2021-01-27T14:00:11.576Z,1611756011.576 [MissionManager](INFO): DefineArg profile_stationUnder.DetachDepth = 4.500000 m
2021-01-27T14:00:11.578Z,1611756011.578 [MissionManager](INFO): DefineArg profile_stationUnder.DetachBounceTimeout = 20.000000 s
2021-01-27T14:00:11.584Z,1611756011.584 [MissionManager](INFO): DefineArg profile_stationUnder.TransponderCode = 2.000000 count
2021-01-27T14:00:11.587Z,1611756011.587 [MissionManager](INFO): DefineArg profile_stationUnder.TrackingUpdatePeriod = 2.500000 s
2021-01-27T14:00:11.589Z,1611756011.589 [MissionManager](INFO): DefineArg profile_stationUnder.NumberOfPings = 1.000000 count
2021-01-27T14:00:11.590Z,1611756011.590 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml
2021-01-27T14:00:11.805Z,1611756011.805 [MissionManager](INFO): DefineArg profile_stationUnder:Science.PeakDetectChlActive = 0 bool
2021-01-27T14:00:11.807Z,1611756011.807 [MissionManager](INFO): DefineArg profile_stationUnder:Science.TimeWindowPeakReport = nan min
2021-01-27T14:00:11.809Z,1611756011.809 [MissionManager](INFO): DefineArg profile_stationUnder:Science.HighestChlPeakReportActive = 0 bool
2021-01-27T14:00:11.811Z,1611756011.811 [MissionManager](INFO): DefineArg profile_stationUnder:Science.PatchTracking = 0 bool
2021-01-27T14:00:11.813Z,1611756011.813 [MissionManager](INFO): DefineArg profile_stationUnder:Science.FilterWidthHorizontal = 3.000000 count
2021-01-27T14:00:11.815Z,1611756011.815 [MissionManager](INFO): DefineArg profile_stationUnder:Science.NumProfilesSlidingwindow = 100.000000 count
2021-01-27T14:00:11.817Z,1611756011.817 [MissionManager](INFO): DefineArg profile_stationUnder:Science.OffPeakFractionHorizontal = 80.000000 %
2021-01-27T14:00:11.819Z,1611756011.819 [MissionManager](INFO): DefineArg profile_stationUnder:Science.PeakDetectNO3Active = 0 bool
2021-01-27T14:00:11.821Z,1611756011.821 [MissionManager](INFO): DefineArg profile_stationUnder:Science.PeakDetectOilActive = 0 bool
2021-01-27T14:00:11.823Z,1611756011.823 [MissionManager](INFO): DefineArg profile_stationUnder:Science.PeakDetectFDOMActive = 0 bool
2021-01-27T14:00:11.825Z,1611756011.825 [MissionManager](INFO): DefineArg profile_stationUnder:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2021-01-27T14:00:11.827Z,1611756011.827 [MissionManager](INFO): DefineArg profile_stationUnder:Science.EnabledAanderaaO2 = 0.000000 bool
2021-01-27T14:00:11.830Z,1611756011.830 [MissionManager](INFO): DefineArg profile_stationUnder:Science.EnabledNeilBrown = 0.000000 bool
2021-01-27T14:00:11.832Z,1611756011.832 [MissionManager](INFO): DefineArg profile_stationUnder:Science.EnabledSeabird = 1.000000 bool
2021-01-27T14:00:11.835Z,1611756011.835 [MissionManager](INFO): DefineArg profile_stationUnder:Science.EnabledWetLabsBB2FL = 0.000000 bool
2021-01-27T14:00:11.837Z,1611756011.837 [MissionManager](INFO): DefineArg profile_stationUnder:Science.EnabledWetLabsSeaOWL_UV_A = 1.000000 bool
2021-01-27T14:00:11.860Z,1611756011.860 [MissionManager](INFO): DefineArg profile_stationUnder:Science.EnabledWetLabsUBAT = 0.000000 bool
2021-01-27T14:00:11.912Z,1611756011.912 [MissionManager](INFO): DefineArg profile_stationUnder:Science.LowPassWindowLength = 20.000000 count
2021-01-27T14:00:11.914Z,1611756011.914 [MissionManager](INFO): DefineArg profile_stationUnder:Science.MedianFilterLen = 5.000000 count
2021-01-27T14:00:11.924Z,1611756011.924 [MissionManager](INFO): DefineArg profile_stationUnder:Science.PeakShallowBound = nan m
2021-01-27T14:00:11.926Z,1611756011.926 [MissionManager](INFO): DefineArg profile_stationUnder:Science.PeakDeepBound = nan m
2021-01-27T14:00:11.937Z,1611756011.937 [MissionManager](INFO): DefineOutput profile_stationUnder:Science.PeakChl = 0.000000 ug/l
2021-01-27T14:00:11.950Z,1611756011.950 [MissionManager](INFO): DefineOutput profile_stationUnder:Science.PeakChlDepth = 0.000000 m
2021-01-27T14:00:11.968Z,1611756011.968 [MissionManager](INFO): DefineOutput profile_stationUnder:Science.PeakChlTemperature = 0.000000 degC
2021-01-27T14:00:11.973Z,1611756011.973 [MissionManager](INFO): DefineOutput profile_stationUnder:Science.PeakChlLatitude = nan arcdeg
2021-01-27T14:00:11.975Z,1611756011.975 [MissionManager](INFO): DefineOutput profile_stationUnder:Science.PeakChlLongitude = nan arcdeg
2021-01-27T14:00:11.982Z,1611756011.982 [MissionManager](INFO): DefineOutput profile_stationUnder:Science.PeakNO3 = 0.000000 umol/l
2021-01-27T14:00:11.992Z,1611756011.992 [MissionManager](INFO): DefineOutput profile_stationUnder:Science.PeakNO3Depth = 0.000000 m
2021-01-27T14:00:11.994Z,1611756011.994 [MissionManager](INFO): DefineOutput profile_stationUnder:Science.PeakNO3Latitude = nan arcdeg
2021-01-27T14:00:12.016Z,1611756012.016 [MissionManager](INFO): DefineOutput profile_stationUnder:Science.PeakNO3Longitude = nan arcdeg
2021-01-27T14:00:12.019Z,1611756012.019 [MissionManager](INFO): DefineOutput profile_stationUnder:Science.PatchChl = 0.000000 m
2021-01-27T14:00:12.030Z,1611756012.030 [MissionManager](INFO): DefineOutput profile_stationUnder:Science.PatchChlDepth = 0.000000 m
2021-01-27T14:00:12.047Z,1611756012.047 [MissionManager](INFO): DefineOutput profile_stationUnder:Science.PatchChlLatitude = nan arcdeg
2021-01-27T14:00:12.070Z,1611756012.070 [MissionManager](INFO): DefineOutput profile_stationUnder:Science.PatchChlLongitude = nan arcdeg
2021-01-27T14:00:12.082Z,1611756012.082 [MissionManager](INFO): DefineOutput profile_stationUnder:Science.PatchChlDistance = nan m
2021-01-27T14:00:12.088Z,1611756012.088 [MissionManager](INFO): DefineOutput profile_stationUnder:Science.PeakOil = 0.000000 kg/m3
2021-01-27T14:00:12.090Z,1611756012.090 [MissionManager](INFO): DefineOutput profile_stationUnder:Science.PeakOilDepth = 0.000000 m
2021-01-27T14:00:12.094Z,1611756012.094 [MissionManager](INFO): DefineOutput profile_stationUnder:Science.PeakOilLatitude = nan arcdeg
2021-01-27T14:00:12.105Z,1611756012.105 [MissionManager](INFO): DefineOutput profile_stationUnder:Science.PeakOilLongitude = nan arcdeg
2021-01-27T14:00:12.107Z,1611756012.107 [MissionManager](INFO): DefineOutput profile_stationUnder:Science.PeakFDOM = 0.000000 ppb
2021-01-27T14:00:12.128Z,1611756012.128 [MissionManager](INFO): DefineOutput profile_stationUnder:Science.PeakFDOMDepth = 0.000000 m
2021-01-27T14:00:12.130Z,1611756012.130 [MissionManager](INFO): DefineOutput profile_stationUnder:Science.PeakFDOMLatitude = nan arcdeg
2021-01-27T14:00:12.141Z,1611756012.141 [MissionManager](INFO): DefineOutput profile_stationUnder:Science.PeakFDOMLongitude = nan arcdeg
2021-01-27T14:00:12.143Z,1611756012.143 [MissionManager](INFO): DefineOutput profile_stationUnder:Science.PatchFDOMDistance = nan m
2021-01-27T14:00:12.248Z,1611756012.248 [profile_stationUnder:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-01-27T14:00:12.259Z,1611756012.259 [profile_stationUnder:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2021-01-27T14:00:12.335Z,1611756012.335 [profile_stationUnder:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-01-27T14:00:12.369Z,1611756012.369 [profile_stationUnder:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-01-27T14:00:12.387Z,1611756012.387 [profile_stationUnder:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-01-27T14:00:12.429Z,1611756012.429 [MissionManager](INFO): RedefineArg profile_stationUnder:Science.PeakDetectChlActive = value:1 bool
2021-01-27T14:00:12.429Z,1611756012.429 [MissionManager](INFO): Inserting Stack: Missions/Insert/MicromodemComms.xml
2021-01-27T14:00:12.462Z,1611756012.462 [MissionManager](INFO): DefineArg profile_stationUnder:MicromodemComms.Timeout = 10.000000 min
2021-01-27T14:00:12.473Z,1611756012.473 [MissionManager](INFO): DefineArg profile_stationUnder:MicromodemComms.CommsInterval = 5.000000 min
2021-01-27T14:00:12.481Z,1611756012.481 [MissionManager](INFO): RedefineArg profile_stationUnder:MicromodemComms.CommsInterval = reader:profile_stationUnder.MicromodemCommsInterval
2021-01-27T14:00:12.481Z,1611756012.481 [MissionManager](INFO): Inserting Stack: Missions/Insert/StandardEnvelopes.xml
2021-01-27T14:00:12.509Z,1611756012.509 [MissionManager](INFO): DefineArg profile_stationUnder:StandardEnvelopes.MinAltitude = 5.000000 m
2021-01-27T14:00:12.511Z,1611756012.511 [MissionManager](INFO): DefineArg profile_stationUnder:StandardEnvelopes.MaxDepth = 200.000000 m
2021-01-27T14:00:12.522Z,1611756012.522 [MissionManager](INFO): DefineArg profile_stationUnder:StandardEnvelopes.MinOffshore = 2000.000000 m
2021-01-27T14:00:12.523Z,1611756012.523 [profile_stationUnder:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2021-01-27T14:00:12.538Z,1611756012.538 [profile_stationUnder:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2021-01-27T14:00:12.552Z,1611756012.552 [profile_stationUnder:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2021-01-27T14:00:12.571Z,1611756012.571 [profile_stationUnder:Detach:A_Timeout:B.Execute](DEBUG): Construct Execute.
2021-01-27T14:00:12.603Z,1611756012.603 [profile_stationUnder:Detach:C.Undock](DEBUG): Construct.
2021-01-27T14:00:12.603Z,1611756012.603 [profile_stationUnder:Detach:C.Undock](DEBUG): Initializing internal variables to default values.
2021-01-27T14:00:12.633Z,1611756012.633 [profile_stationUnder:ProfileStation:A.Pitch](DEBUG): Construct.
2021-01-27T14:00:12.650Z,1611756012.650 [profile_stationUnder:ProfileStation:B.Buoyancy](DEBUG): Construct Buoyancy.
2021-01-27T14:00:12.653Z,1611756012.653 [profile_stationUnder:ProfileStation:C.SetSpeed](DEBUG): Construct.
2021-01-27T14:00:12.663Z,1611756012.663 [profile_stationUnder:ProfileStation:D.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2021-01-27T14:00:12.671Z,1611756012.671 [profile_stationUnder:ProfileStation:E.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2021-01-27T14:00:12.746Z,1611756012.746 [profile_stationUnder:ProfileStation:F.YoYo](DEBUG): Construct YoYo.
2021-01-27T14:00:12.777Z,1611756012.777 [profile_stationUnder:ProfileStation:CircleWrapper:B.Circle](DEBUG): Construct.
2021-01-27T14:00:12.823Z,1611756012.823 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Derivation="Tethys/Derivation"
       xmlns:Dock="Tethys/Dock"
       xmlns:Estimation="Tethys/Estimation"
       xmlns:Guidance="Tethys/Guidance"
       xmlns:Sensor="Tethys/Sensor"
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Derivation http://okeanids.mbari.org/tethys/Xml/Derivation.xsd
                           Tethys/Dock http://okeanids.mbari.org/tethys/Xml/Dock.xsd
                           Tethys/Estimation http://okeanids.mbari.org/tethys/Xml/Estimation.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Sensor http://okeanids.mbari.org/tethys/Xml/Sensor.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="profile_stationUnder">

    <Description>
        This mission yoyos in a circle around a specified location. Designed for under ice. 
    </Description>

    <!-- Mission-wide settings -->

    <DefineArg Name="MissionTimeout"><Description>
        Maximum duration of mission
    </Description><Units:hour/><Value>2</Value></DefineArg>

    <DefineArg Name="DetachTimeout"><Description>
        Maximum time to spend getting off the dock
    </Description><Units:minute/><Value>20</Value></DefineArg>
    
    <!-- You probably need to change these. -->

    <DefineArg Name="Lat"><Description>
        The latitude of the center of the circle.
    </Description><Units:degree/><Value>44.847</Value></DefineArg>

    <DefineArg Name="Lon"><Description>
        The longitude of the center of the circle.
    </Description><Units:degree/><Value>-67.577535</Value></DefineArg>

    <DefineArg Name="Radius"><Description>
        Radius to circle at
    </Description><Units:meter/><Value>50</Value></DefineArg>

    <!-- You probably do not need to change these. -->

    <DefineArg Name="YoYoMinDepth"><Description>
        Minimum depth while performing the YoYo behavior.
    </Description><Units:meter/><Value>2.5</Value></DefineArg>

    <DefineArg Name="YoYoMaxDepth"><Description>
        Maximum depth while performing the YoYo behavior.
    </Description><Units:meter/><Value>5</Value></DefineArg>

    <DefineArg Name="YoYoMinAltitude"><Description>
        Minimum altitude while performing the YoYo behavior (for
        bottom-terminated YoYos).
    </Description><Units:meter/><Value>4</Value></DefineArg>

    <DefineArg Name="YoYoPitch"><Description>
        Pitch (plus and minus) for yo-yo behavior.
    </Description><Units:degree/><Value>15</Value></DefineArg>

    <DefineArg Name="Speed"><Description>
        Vehicle speed.
    </Description><Units:meter_per_second/><Value>1</Value></DefineArg>

    <!-- You are even less likely to need to change these. -->

    <DefineArg Name="CircleMaxError"><Description>
        If this distance away from the circle, drive straight towards (or away
        from the center). Otherwise, try to reduce distance from the ideal
        circle.
    </Description><Units:meter/><Value>1</Value></DefineArg>

    <DefineArg Name="CircleTurnToPort"><Description>
        If true, vehicle turns to the left around the center point. If false,
        vehicle turns to the right.
    </Description><False/></DefineArg>

    <DefineArg Name="KwpHeading"><Description>
        Used to relax waypoint cross-track error constant that is adjusted for
        docking. (You can override this setting by passing an argument.)
    </Description><Units:radian_per_meter/><Value>0.010</Value></DefineArg>

    <DefineArg Name="MinAltitude"><Description>
        Minimum height above the sea floor for the entire mission.
    </Description><Units:meter/><Value>2</Value></DefineArg>

    <DefineArg Name="MaxDepth"><Description>
        Maximum depth for the entire mission.
    </Description><Units:meter/><Value>8</Value></DefineArg>

    <DefineArg Name="MinOffshore"><Description>
        Minimum offshore distance for the entire mission.
    </Description><Units:meter/><Value>10</Value></DefineArg>

    <DefineArg Name="MicromodemCommsInterval"><Description>
        How often to trigger Umodem communications
    </Description><Units:minute/><Value>5</Value></DefineArg>

    <DefineArg Name="DetachDepth"><Description>
        Desired vehicle depth when leaving the dock.
    </Description><Units:meter/><Value>4.5</Value></DefineArg>

    <DefineArg Name="DetachBounceTimeout"><Description>
        Time duration to keep the thruster engaged to bounce/slingshot off the dock.
    </Description><Units:second/><Value>20</Value></DefineArg>

    <DefineArg Name="TransponderCode"><Description>
        Transponder Address.
    </Description><Units:count/><Value>2</Value></DefineArg>

    <DefineArg Name="TrackingUpdatePeriod"><Description>
        How long to wait between acoustic queries.
    </Description><Units:second/><Value>2.5</Value></DefineArg>

    <DefineArg Name="NumberOfPings"><Description>
        Number of pings requested each time.
    </Description><Units:count/><Value>1</Value></DefineArg>
    
    <!-- Missions should almost always start with a timeout -->

    <Timeout Duration="MissionTimeout"/>

    <Insert Filename="Insert/Science.xml">
        <Description>
            Most missions will run the science sensors. 
        </Description>
        <RedefineArg Name="PeakDetectChlActive"><True/></RedefineArg>
    </Insert>


    <Insert Filename="Insert/MicromodemComms.xml" Id="MicromodemComms">
            <RedefineArg Name="CommsInterval"><Arg Name="MicromodemCommsInterval"/></RedefineArg>
    </Insert>    

    <!-- Missions should almost always start with standard safety envelopes; most missions should not expose the parameters of these envelopes. -->

    <Insert Filename="Insert/StandardEnvelopes.xml"/>

    <Assign><Sequence/><Arg Name="StandardEnvelopes:MinAltitude"/><Arg Name="MinAltitude"/></Assign>

    <Assign><Sequence/><Arg Name="StandardEnvelopes:MaxDepth"/><Arg Name="MaxDepth"/></Assign>

    <Assign><Sequence/><Arg Name="StandardEnvelopes:MinOffshore"/><Arg Name="MinOffshore"/></Assign>


    <!-- Many missions will keep mass position and buoyancy volume fixed at defaults. -->

    <Aggregate Id="Detach">
        <Sequence/>

        <Timeout Duration="DetachTimeout">
            <Syslog Severity="Important">Timed out trying to detach from dock. Stopping mission.</Syslog>
            <Guidance:Execute>
                <Sequence/>
                <Setting><Guidance:Execute.command/><String>stop</String></Setting>
            </Guidance:Execute>
        </Timeout>

        <Syslog Severity="Important">Detaching from dock.</Syslog>

        <!-- Turn on tracking so we get some ranges to see how far we are from the dock -->
        <Estimation:TrackAcousticContact>
            <Parallel/>
            <Setting><Estimation:TrackAcousticContact.contactLabelSetting/><Arg Name="TransponderCode"/></Setting>
            <Setting><Estimation:TrackAcousticContact.numberOfSamplesSetting/><Arg Name="NumberOfPings"/></Setting>
            <Setting><Estimation:TrackAcousticContact.updatePeriodSetting/><Arg Name="TrackingUpdatePeriod"/></Setting>
        </Estimation:TrackAcousticContact>

        <!-- Time to leave the dock! -->
        <Dock:Undock>
            <Sequence/>
        </Dock:Undock>

    </Aggregate>


    <Aggregate Id="ProfileStation">
        <Sequence/>

        <Guidance:Pitch>
            <Parallel/>
            <Setting><Guidance:Pitch.massPosition/><Control:VerticalControl.massDefault/></Setting>
        </Guidance:Pitch>

        <Guidance:Buoyancy>
            <Parallel/>
            <Setting><Guidance:Buoyancy.position/><Control:VerticalControl.buoyancyNeutral/></Setting>
        </Guidance:Buoyancy>

        <Guidance:SetSpeed>
            <Parallel/>
            <Setting><Guidance:SetSpeed.speed/><Arg Name="Speed"/></Setting>
        </Guidance:SetSpeed>

        <Guidance:DepthEnvelope>
            <Description>
                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.
            </Description>
            <Parallel/>
            <Setting><Guidance:DepthEnvelope.minDepth/><Arg Name="YoYoMinDepth"/></Setting>
            <Setting><Guidance:DepthEnvelope.maxDepth/><Arg Name="YoYoMaxDepth"/></Setting>
            <Setting><Guidance:DepthEnvelope.pitch/><Arg Name="YoYoPitch"/></Setting>
        </Guidance:DepthEnvelope>

        <Guidance:AltitudeEnvelope>
            <Description>
                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.
            </Description>
            <Parallel/>
            <Setting><Guidance:AltitudeEnvelope.minAltitude/><Arg Name="YoYoMinAltitude"/></Setting>
            <Setting><Guidance:AltitudeEnvelope.pitch/><Arg Name="YoYoPitch"/></Setting>
        </Guidance:AltitudeEnvelope>

        <Guidance:YoYo>
            <Parallel/>
            <Setting><Guidance:YoYo.pitch/><Arg Name="YoYoPitch"/></Setting>
        </Guidance:YoYo>

        <Aggregate Id="CircleWrapper">

            <Sequence Repeat="4096"/>

            <Assign><Parallel/><Control:HorizontalControl.kwpHeading/><Arg Name="KwpHeading"/></Assign>

            <Guidance:Circle>
                <Sequence/>
                <Setting><Guidance:Circle.latitude/><Arg Name="Lat"/></Setting>
                <Setting><Guidance:Circle.longitude/><Arg Name="Lon"/></Setting>
                <Setting><Guidance:Circle.radius/><Arg Name="Radius"/></Setting>
                <Setting><Guidance:Circle.maxError/><Arg Name="CircleMaxError"/></Setting>
                <Setting><Guidance:Circle.turnToPort/><Arg Name="CircleTurnToPort"/></Setting>
            </Guidance:Circle>

        </Aggregate>

    </Aggregate>

</Mission>


2021-01-27T14:00:12.823Z,1611756012.823 [CommandLine](IMPORTANT): Loaded ./Missions/underIce/profile_stationUnder.xml
2021-01-27T14:00:23.762Z,1611756023.762 [CommandLine](IMPORTANT): got command set profile_stationUnder.MissionTimeout 20.000000 minute
2021-01-27T14:00:23.763Z,1611756023.763 [CommandLine](IMPORTANT): got command set profile_stationUnder.Lat 41.551998 degree
2021-01-27T14:00:23.764Z,1611756023.764 [CommandLine](IMPORTANT): got command set profile_stationUnder.Lon -70.709610 degree
2021-01-27T14:00:23.765Z,1611756023.765 [CommandLine](IMPORTANT): got command set profile_stationUnder.Radius 200.000000 meter
2021-01-27T14:00:36.777Z,1611756036.777 [CommandLine](IMPORTANT): got command set profile_stationUnder.YoYoMinDepth 3.000000 meter
2021-01-27T14:00:36.778Z,1611756036.778 [CommandLine](IMPORTANT): got command set profile_stationUnder.YoYoMaxDepth 7.000000 meter
2021-01-27T14:00:36.779Z,1611756036.779 [CommandLine](IMPORTANT): got command set profile_stationUnder.YoYoMinAltitude 3.000000 meter
2021-01-27T14:00:36.779Z,1611756036.779 [CommandLine](IMPORTANT): got command set profile_stationUnder.CircleMaxError 50.000000 meter
2021-01-27T14:00:45.993Z,1611756045.993 [CommandLine](IMPORTANT): got command set profile_stationUnder.MinAltitude 3.000000 meter
2021-01-27T14:00:45.993Z,1611756045.993 [CommandLine](IMPORTANT): got command set profile_stationUnder.MaxDepth 9.000000 meter
2021-01-27T14:00:45.994Z,1611756045.994 [CommandLine](IMPORTANT): got command set profile_stationUnder.MinOffshore 500.000000 meter
2021-01-27T14:00:45.995Z,1611756045.995 [CommandLine](IMPORTANT): got command run
2021-01-27T14:00:45.996Z,1611756045.996 [CommandLine](IMPORTANT): Running 
2021-01-27T14:00:46.259Z,1611756046.259 [DefaultWithUndock] Stopped
2021-01-27T14:00:46.259Z,1611756046.259 [DefaultWithUndock](DEBUG): Aggregate::uninitialize DefaultWithUndock
2021-01-27T14:00:46.259Z,1611756046.259 [DefaultWithUndock:SurfaceDefault] Stopped
2021-01-27T14:00:46.259Z,1611756046.259 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault
2021-01-27T14:00:46.259Z,1611756046.259 [DefaultWithUndock:SurfaceDefault:B.GoToSurface] Stopped
2021-01-27T14:00:46.259Z,1611756046.259 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-01-27T14:00:46.259Z,1611756046.259 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped
2021-01-27T14:00:46.259Z,1611756046.259 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn
2021-01-27T14:00:46.259Z,1611756046.259 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped
2021-01-27T14:00:46.259Z,1611756046.259 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-01-27T14:00:46.260Z,1611756046.260 [MissionManager](IMPORTANT): Started mission profile_stationUnder
2021-01-27T14:00:46.260Z,1611756046.260 [profile_stationUnder] Running Loop=1
2021-01-27T14:00:46.260Z,1611756046.260 [profile_stationUnder](DEBUG): Aggregate::initialize profile_stationUnder
2021-01-27T14:00:46.260Z,1611756046.260 [profile_stationUnder:Science] Running Loop=1
2021-01-27T14:00:46.260Z,1611756046.260 [profile_stationUnder:Science](DEBUG): Aggregate::initialize profile_stationUnder:Science
2021-01-27T14:00:46.260Z,1611756046.260 [profile_stationUnder:Science:A] Running Loop=1
2021-01-27T14:00:46.261Z,1611756046.261 [profile_stationUnder:Science:B] Running Loop=1
2021-01-27T14:00:46.261Z,1611756046.261 [profile_stationUnder:Science:C] Running Loop=1
2021-01-27T14:00:46.261Z,1611756046.261 [profile_stationUnder:Science:D] Running Loop=1
2021-01-27T14:00:46.261Z,1611756046.261 [profile_stationUnder:Science:E] Running Loop=1
2021-01-27T14:00:46.261Z,1611756046.261 [profile_stationUnder:Science:F] Running Loop=1
2021-01-27T14:00:46.261Z,1611756046.261 [profile_stationUnder:Science:Read_Oil] Running Loop=1
2021-01-27T14:00:46.261Z,1611756046.261 [profile_stationUnder:Science:PeakDetectChl] Running Loop=1
2021-01-27T14:00:46.261Z,1611756046.261 [profile_stationUnder:Science:PeakDetectChl](DEBUG): Aggregate::initialize profile_stationUnder:Science:PeakDetectChl
2021-01-27T14:00:46.261Z,1611756046.261 [profile_stationUnder:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2021-01-27T14:00:46.261Z,1611756046.261 [profile_stationUnder:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-01-27T14:00:46.263Z,1611756046.263 [profile_stationUnder:Science:HighestChlPeakReport] Running Loop=1
2021-01-27T14:00:46.263Z,1611756046.263 [profile_stationUnder:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize profile_stationUnder:Science:HighestChlPeakReport
2021-01-27T14:00:46.263Z,1611756046.263 [profile_stationUnder:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2021-01-27T14:00:46.263Z,1611756046.263 [profile_stationUnder:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2021-01-27T14:00:46.263Z,1611756046.263 [profile_stationUnder:Science:PeakDetectNO3] Running Loop=1
2021-01-27T14:00:46.263Z,1611756046.263 [profile_stationUnder:Science:PeakDetectNO3](DEBUG): Aggregate::initialize profile_stationUnder:Science:PeakDetectNO3
2021-01-27T14:00:46.264Z,1611756046.264 [profile_stationUnder:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2021-01-27T14:00:46.264Z,1611756046.264 [profile_stationUnder:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-01-27T14:00:46.265Z,1611756046.265 [profile_stationUnder:Science:PeakDetectOil] Running Loop=1
2021-01-27T14:00:46.265Z,1611756046.265 [profile_stationUnder:Science:PeakDetectOil](DEBUG): Aggregate::initialize profile_stationUnder:Science:PeakDetectOil
2021-01-27T14:00:46.265Z,1611756046.265 [profile_stationUnder:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2021-01-27T14:00:46.265Z,1611756046.265 [profile_stationUnder:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-01-27T14:00:46.266Z,1611756046.266 [profile_stationUnder:Science:PeakDetectFDOM] Running Loop=1
2021-01-27T14:00:46.266Z,1611756046.266 [profile_stationUnder:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize profile_stationUnder:Science:PeakDetectFDOM
2021-01-27T14:00:46.267Z,1611756046.267 [profile_stationUnder:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2021-01-27T14:00:46.267Z,1611756046.267 [profile_stationUnder:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-01-27T14:00:46.268Z,1611756046.268 [profile_stationUnder:Science:OceanCurrent] Running Loop=1
2021-01-27T14:00:46.268Z,1611756046.268 [profile_stationUnder:Science:OceanCurrent](DEBUG): Aggregate::initialize profile_stationUnder:Science:OceanCurrent
2021-01-27T14:00:46.268Z,1611756046.268 [profile_stationUnder:Science:OceanCurrent:A.] Running Loop=1
2021-01-27T14:00:46.268Z,1611756046.268 [profile_stationUnder:Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator.
2021-01-27T14:00:46.268Z,1611756046.268 [profile_stationUnder:MicromodemComms] Running Loop=1
2021-01-27T14:00:46.268Z,1611756046.268 [profile_stationUnder:MicromodemComms](DEBUG): Aggregate::initialize profile_stationUnder:MicromodemComms
2021-01-27T14:00:46.269Z,1611756046.269 [profile_stationUnder:MicromodemComms:CheckIn] Running Loop=1
2021-01-27T14:00:46.269Z,1611756046.269 [profile_stationUnder:MicromodemComms:CheckIn](DEBUG): Aggregate::initialize profile_stationUnder:MicromodemComms:CheckIn
2021-01-27T14:00:46.269Z,1611756046.269 [profile_stationUnder:MicromodemComms:CheckIn:A] Running Loop=1
2021-01-27T14:00:46.269Z,1611756046.269 [profile_stationUnder:StandardEnvelopes] Running Loop=1
2021-01-27T14:00:46.269Z,1611756046.269 [profile_stationUnder:StandardEnvelopes](DEBUG): Aggregate::initialize profile_stationUnder:StandardEnvelopes
2021-01-27T14:00:46.269Z,1611756046.269 [profile_stationUnder:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2021-01-27T14:00:46.269Z,1611756046.269 [profile_stationUnder:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2021-01-27T14:00:46.269Z,1611756046.269 [profile_stationUnder:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2021-01-27T14:00:46.270Z,1611756046.270 [profile_stationUnder:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2021-01-27T14:00:46.270Z,1611756046.270 [profile_stationUnder:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2021-01-27T14:00:46.270Z,1611756046.270 [profile_stationUnder:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2021-01-27T14:00:46.270Z,1611756046.270 [profile_stationUnder:D] Running Loop=1
2021-01-27T14:00:46.271Z,1611756046.271 [profile_stationUnder:D] Stopped
2021-01-27T14:00:46.271Z,1611756046.271 [profile_stationUnder:E] Running Loop=1
2021-01-27T14:00:46.271Z,1611756046.271 [profile_stationUnder:StandardEnvelopes] Running Loop=1
2021-01-27T14:00:46.271Z,1611756046.271 [profile_stationUnder:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2021-01-27T14:00:46.271Z,1611756046.271 [profile_stationUnder:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2021-01-27T14:00:46.271Z,1611756046.271 [profile_stationUnder:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2021-01-27T14:00:46.272Z,1611756046.272 [profile_stationUnder:MicromodemComms] Stopped
2021-01-27T14:00:46.272Z,1611756046.272 [profile_stationUnder:MicromodemComms](DEBUG): Aggregate::uninitialize profile_stationUnder:MicromodemComms
2021-01-27T14:00:46.272Z,1611756046.272 [profile_stationUnder:MicromodemComms:CheckIn] Stopped
2021-01-27T14:00:46.272Z,1611756046.272 [profile_stationUnder:MicromodemComms:CheckIn](DEBUG): Aggregate::uninitialize profile_stationUnder:MicromodemComms:CheckIn
2021-01-27T14:00:46.272Z,1611756046.272 [profile_stationUnder:MicromodemComms:CheckIn:A] Stopped
2021-01-27T14:00:46.272Z,1611756046.272 [profile_stationUnder:Science] Running Loop=1
2021-01-27T14:00:46.273Z,1611756046.273 [profile_stationUnder:Science:PeakDetectFDOM] Stopped
2021-01-27T14:00:46.274Z,1611756046.274 [profile_stationUnder:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize profile_stationUnder:Science:PeakDetectFDOM
2021-01-27T14:00:46.274Z,1611756046.274 [profile_stationUnder:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2021-01-27T14:00:46.274Z,1611756046.274 [profile_stationUnder:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-01-27T14:00:46.274Z,1611756046.274 [profile_stationUnder:Science:PeakDetectOil] Stopped
2021-01-27T14:00:46.274Z,1611756046.274 [profile_stationUnder:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize profile_stationUnder:Science:PeakDetectOil
2021-01-27T14:00:46.274Z,1611756046.274 [profile_stationUnder:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2021-01-27T14:00:46.274Z,1611756046.274 [profile_stationUnder:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-01-27T14:00:46.274Z,1611756046.274 [profile_stationUnder:Science:PeakDetectNO3] Stopped
2021-01-27T14:00:46.274Z,1611756046.274 [profile_stationUnder:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize profile_stationUnder:Science:PeakDetectNO3
2021-01-27T14:00:46.274Z,1611756046.274 [profile_stationUnder:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2021-01-27T14:00:46.274Z,1611756046.274 [profile_stationUnder:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-01-27T14:00:46.274Z,1611756046.274 [profile_stationUnder:Science:HighestChlPeakReport] Stopped
2021-01-27T14:00:46.275Z,1611756046.275 [profile_stationUnder:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize profile_stationUnder:Science:HighestChlPeakReport
2021-01-27T14:00:46.275Z,1611756046.275 [profile_stationUnder:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2021-01-27T14:00:46.275Z,1611756046.275 [profile_stationUnder:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2021-01-27T14:00:46.275Z,1611756046.275 [profile_stationUnder:Science:PeakDetectChl] Running Loop=1
2021-01-27T14:00:46.275Z,1611756046.275 [profile_stationUnder:Science:OceanCurrent:A.] Running Loop=1
2021-01-27T14:00:46.276Z,1611756046.276 [profile_stationUnder:Science:Read_Oil] Running Loop=1
2021-01-27T14:00:46.277Z,1611756046.277 [profile_stationUnder:Science:Read_Oil](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2021-01-27T14:00:46.277Z,1611756046.277 [profile_stationUnder:Science:F] Stopped
2021-01-27T14:00:46.277Z,1611756046.277 [profile_stationUnder:Science:E] Running Loop=1
2021-01-27T14:00:46.277Z,1611756046.277 [profile_stationUnder:Science:E](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2021-01-27T14:00:46.277Z,1611756046.277 [profile_stationUnder:Science:D] Stopped
2021-01-27T14:00:46.278Z,1611756046.278 [profile_stationUnder:Science:C] Running Loop=1
2021-01-27T14:00:46.278Z,1611756046.278 [profile_stationUnder:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature
2021-01-27T14:00:46.278Z,1611756046.278 [profile_stationUnder:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity
2021-01-27T14:00:46.278Z,1611756046.278 [profile_stationUnder:Science:B] Running Loop=1
2021-01-27T14:00:46.279Z,1611756046.279 [profile_stationUnder:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2021-01-27T14:00:46.279Z,1611756046.279 [profile_stationUnder:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2021-01-27T14:00:46.279Z,1611756046.279 [profile_stationUnder:Science:A] Stopped
2021-01-27T14:00:46.356Z,1611756046.356 [WetLabsSeaOWL_UV_A](INFO): Powering up
2021-01-27T14:00:46.641Z,1611756046.641 [profile_stationUnder:E] Stopped
2021-01-27T14:00:46.641Z,1611756046.641 [profile_stationUnder:F] Running Loop=1
2021-01-27T14:00:47.058Z,1611756047.058 [profile_stationUnder:F] Stopped
2021-01-27T14:00:47.058Z,1611756047.058 [profile_stationUnder:Detach] Running Loop=1
2021-01-27T14:00:47.058Z,1611756047.058 [profile_stationUnder:Detach](DEBUG): Aggregate::initialize profile_stationUnder:Detach
2021-01-27T14:00:47.058Z,1611756047.058 [profile_stationUnder:Detach:B.] Running Loop=1
2021-01-27T14:00:47.059Z,1611756047.059 [profile_stationUnder:Detach:B.](INFO): Initializing TrackAcousticContact.
2021-01-27T14:00:47.059Z,1611756047.059 [profile_stationUnder:Detach:A] Running Loop=1
2021-01-27T14:00:47.451Z,1611756047.451 [profile_stationUnder:Detach:B.] Running Loop=1
2021-01-27T14:00:47.451Z,1611756047.451 [profile_stationUnder:Detach:A](IMPORTANT): Detaching from dock.
2021-01-27T14:00:47.451Z,1611756047.451 [profile_stationUnder:Detach:A] Stopped
2021-01-27T14:00:47.451Z,1611756047.451 [profile_stationUnder:Detach:C.Undock] Running Loop=1
2021-01-27T14:00:47.452Z,1611756047.452 [profile_stationUnder:Detach:C.Undock](DEBUG): Initialize.
2021-01-27T14:00:47.452Z,1611756047.452 [profile_stationUnder:Detach:C.Undock](DEBUG): Initializing internal variables to default values.
2021-01-27T14:00:48.766Z,1611756048.766 [profile_stationUnder:Detach:C.Undock](INFO): Detaching from dock.
2021-01-27T14:00:49.139Z,1611756049.139 [profile_stationUnder:Detach:C.Undock](INFO): Detected possible detachment. Starting timer.
2021-01-27T14:00:49.943Z,1611756049.943 [profile_stationUnder:Detach:B.](INFO): *** querying acoustic contact ***
2021-01-27T14:00:50.158Z,1611756050.158 [CTD_Seabird](ERROR): Failed to parse device response: <!--startlogging-->
2021-01-27T14:00:50.699Z,1611756050.699 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76

2021-01-27T14:00:51.108Z,1611756051.108 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B

2021-01-27T14:00:51.977Z,1611756051.977 [Micromodem](INFO): Nmea in: $SNTTA,0.053728,,,,140052.07*4E

2021-01-27T14:00:54.349Z,1611756054.349 [profile_stationUnder:Detach:B.](INFO): *** querying acoustic contact ***
2021-01-27T14:00:55.147Z,1611756055.147 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76

2021-01-27T14:00:55.548Z,1611756055.548 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B

2021-01-27T14:00:56.397Z,1611756056.397 [Micromodem](INFO): Nmea in: $SNTTA,0.053217,0.079024,0.060391,,140056.52*46

2021-01-27T14:00:58.799Z,1611756058.799 [profile_stationUnder:Detach:B.](INFO): *** querying acoustic contact ***
2021-01-27T14:00:59.586Z,1611756059.586 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76

2021-01-27T14:00:59.992Z,1611756059.992 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B

2021-01-27T14:01:00.837Z,1611756060.837 [Micromodem](INFO): Nmea in: $SNTTA,0.052944,,,,140100.96*45

2021-01-27T14:01:03.274Z,1611756063.274 [profile_stationUnder:Detach:B.](INFO): *** querying acoustic contact ***
2021-01-27T14:01:04.030Z,1611756064.030 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76

2021-01-27T14:01:04.450Z,1611756064.450 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B

2021-01-27T14:01:05.297Z,1611756065.297 [Micromodem](INFO): Nmea in: $SNTTA,0.052761,,,,140105.40*42

2021-01-27T14:01:07.687Z,1611756067.687 [profile_stationUnder:Detach:B.](INFO): *** querying acoustic contact ***
2021-01-27T14:01:08.474Z,1611756068.474 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76

2021-01-27T14:01:08.910Z,1611756068.910 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B

2021-01-27T14:01:09.323Z,1611756069.323 [profile_stationUnder:Detach:C.Undock](INFO): Detached at range: 79.20 m. Transitioning DDM to standby.
2021-01-27T14:01:09.729Z,1611756069.729 [Micromodem](INFO): Nmea in: $SNTTA,0.052584,,,,140109.85*4E

2021-01-27T14:01:12.182Z,1611756072.182 [profile_stationUnder:Detach:C.Undock](INFO): DDM at standby.
2021-01-27T14:01:12.182Z,1611756072.182 [profile_stationUnder:Detach:B.](INFO): *** querying acoustic contact ***
2021-01-27T14:01:12.544Z,1611756072.544 [profile_stationUnder:Detach:C.Undock](IMPORTANT): Undocking sequence complete.
2021-01-27T14:01:12.545Z,1611756072.545 [profile_stationUnder:Detach:C.Undock] Stopped
2021-01-27T14:01:12.545Z,1611756072.545 [profile_stationUnder:Detach](INFO): Completed profile_stationUnder:Detach
2021-01-27T14:01:12.545Z,1611756072.545 [profile_stationUnder:Detach] Stopped
2021-01-27T14:01:12.545Z,1611756072.545 [profile_stationUnder:Detach](DEBUG): Aggregate::uninitialize profile_stationUnder:Detach
2021-01-27T14:01:12.545Z,1611756072.545 [profile_stationUnder:Detach:B.] Stopped
2021-01-27T14:01:12.545Z,1611756072.545 [profile_stationUnder:ProfileStation] Running Loop=1
2021-01-27T14:01:12.545Z,1611756072.545 [profile_stationUnder:ProfileStation](DEBUG): Aggregate::initialize profile_stationUnder:ProfileStation
2021-01-27T14:01:12.546Z,1611756072.546 [profile_stationUnder:ProfileStation:A.Pitch] Running Loop=1
2021-01-27T14:01:12.546Z,1611756072.546 [profile_stationUnder:ProfileStation:A.Pitch](DEBUG): Initialize.
2021-01-27T14:01:12.546Z,1611756072.546 [profile_stationUnder:ProfileStation:B.Buoyancy] Running Loop=1
2021-01-27T14:01:12.546Z,1611756072.546 [profile_stationUnder:ProfileStation:B.Buoyancy](DEBUG): Initialize Buoyancy Component.
2021-01-27T14:01:12.546Z,1611756072.546 [profile_stationUnder:ProfileStation:C.SetSpeed] Running Loop=1
2021-01-27T14:01:12.546Z,1611756072.546 [profile_stationUnder:ProfileStation:C.SetSpeed](DEBUG): Initialize.
2021-01-27T14:01:12.546Z,1611756072.546 [profile_stationUnder:ProfileStation:D.DepthEnvelope] Running Loop=1
2021-01-27T14:01:12.546Z,1611756072.546 [profile_stationUnder:ProfileStation:D.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2021-01-27T14:01:12.547Z,1611756072.547 [profile_stationUnder:ProfileStation:E.AltitudeEnvelope] Running Loop=1
2021-01-27T14:01:12.547Z,1611756072.547 [profile_stationUnder:ProfileStation:E.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2021-01-27T14:01:12.547Z,1611756072.547 [profile_stationUnder:ProfileStation:F.YoYo] Running Loop=1
2021-01-27T14:01:12.547Z,1611756072.547 [profile_stationUnder:ProfileStation:F.YoYo](DEBUG): Initialize YoYoComponent.
2021-01-27T14:01:12.547Z,1611756072.547 [profile_stationUnder:ProfileStation:CircleWrapper] Running Loop=1
2021-01-27T14:01:12.547Z,1611756072.547 [profile_stationUnder:ProfileStation:CircleWrapper](DEBUG): Aggregate::initialize profile_stationUnder:ProfileStation:CircleWrapper
2021-01-27T14:01:12.547Z,1611756072.547 [profile_stationUnder:ProfileStation:CircleWrapper:A] Running Loop=1
2021-01-27T14:01:12.552Z,1611756072.552 [profile_stationUnder:ProfileStation:CircleWrapper:B.Circle] Running Loop=1
2021-01-27T14:01:12.552Z,1611756072.552 [profile_stationUnder:ProfileStation:CircleWrapper:B.Circle](DEBUG): Initialize CircleComponent.
2021-01-27T14:01:12.955Z,1611756072.955 [profile_stationUnder:ProfileStation:CircleWrapper:A] Running Loop=1
2021-01-27T14:01:12.957Z,1611756072.957 [profile_stationUnder:ProfileStation:F.YoYo] Running Loop=1
2021-01-27T14:01:12.957Z,1611756072.957 [profile_stationUnder:ProfileStation:E.AltitudeEnvelope] Running Loop=1
2021-01-27T14:01:12.957Z,1611756072.957 [profile_stationUnder:ProfileStation:D.DepthEnvelope] Running Loop=1
2021-01-27T14:01:12.958Z,1611756072.958 [profile_stationUnder:ProfileStation:C.SetSpeed] Running Loop=1
2021-01-27T14:01:12.958Z,1611756072.958 [profile_stationUnder:ProfileStation:B.Buoyancy] Running Loop=1
2021-01-27T14:01:12.958Z,1611756072.958 [profile_stationUnder:ProfileStation:A.Pitch] Running Loop=1
2021-01-27T14:01:31.935Z,1611756091.935 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2021-01-27T14:01:46.201Z,1611756106.201 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items
2021-01-27T14:02:22.714Z,1611756142.714 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.252670
2021-01-27T14:02:23.000Z,1611756143.000 [profile_stationUnder:MicromodemComms] Running Loop=1
2021-01-27T14:02:23.001Z,1611756143.001 [profile_stationUnder:MicromodemComms](DEBUG): Aggregate::initialize profile_stationUnder:MicromodemComms
2021-01-27T14:02:23.001Z,1611756143.001 [profile_stationUnder:MicromodemComms:CheckIn] Running Loop=1
2021-01-27T14:02:23.001Z,1611756143.001 [profile_stationUnder:MicromodemComms:CheckIn](DEBUG): Aggregate::initialize profile_stationUnder:MicromodemComms:CheckIn
2021-01-27T14:02:23.001Z,1611756143.001 [profile_stationUnder:MicromodemComms:CheckIn:A] Running Loop=1
2021-01-27T14:02:23.001Z,1611756143.001 [profile_stationUnder:MicromodemComms] Running Loop=1
2021-01-27T14:02:23.002Z,1611756143.002 [profile_stationUnder:MicromodemComms:CheckIn:A](DEBUG): Initialize ReadDataComponent to sense platform_communications
2021-01-27T14:02:23.549Z,1611756143.549 [Micromodem](INFO): Outgoing frame #1, 59 bytes: 56701160060000005D00000400260000000000000000151D80030C5669BFD2C1A9018109CA118602FE956B25C06895E9C45B734FE14F139D529B1B
2021-01-27T14:02:23.550Z,1611756143.550 [Micromodem](INFO): Nmea out: $CCCYC,0,1,10,1,0,1*69

2021-01-27T14:02:23.909Z,1611756143.909 [Micromodem](INFO): Nmea in: $CACYC,0,1,10,1,0,1*6B

2021-01-27T14:02:24.301Z,1611756144.301 [Micromodem](INFO): Nmea in: $CADRQ,140224,1,10,0,64,1*77

2021-01-27T14:02:24.301Z,1611756144.301 [Micromodem](INFO): Nmea out: $CCTXD,1,10,1,56701160060000005D00000400260000000000000000151D80030C5669BFD2C1A9018109CA118602FE956B25C06895E9C45B734FE14F139D529B1B*3F

2021-01-27T14:02:24.725Z,1611756144.725 [Micromodem](INFO): Nmea in: $CATXD,1,10,1,59*47

2021-01-27T14:02:25.109Z,1611756145.109 [Micromodem](INFO): Nmea in: $CATXP,59*7E

2021-01-27T14:02:26.316Z,1611756146.316 [Micromodem](INFO): Nmea in: $CATXF,59*68

2021-01-27T14:02:26.753Z,1611756146.753 [Micromodem](INFO): Nmea in: $CAXST,6,20210127,140225.689990,3,0,200,4000,14500,1,1,10,1,1,1,3,59*4D

2021-01-27T14:02:48.028Z,1611756168.028 [Radio_Surface](INFO): Powering down
2021-01-27T14:02:58.857Z,1611756178.857 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-01-27T14:02:59.861Z,1611756179.861 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2021-01-27T14:03:28.941Z,1611756208.941 [Micromodem](ERROR): Buffer fill timout failure.
2021-01-27T14:03:29.407Z,1611756209.407 [Micromodem](INFO): Outgoing frame #1, 59 bytes: 56701160060000005D00000400260000000000000000151D80030C5669BFD2C1A9018109CA118602FE956B25C06895E9C45B734FE14F139D529B1B
2021-01-27T14:03:29.407Z,1611756209.407 [Micromodem](INFO): Nmea out: $CCCYC,0,1,10,1,0,1*69

2021-01-27T14:03:29.762Z,1611756209.762 [Micromodem](INFO): Nmea in: $CACYC,0,1,10,1,0,1*6B

2021-01-27T14:03:30.151Z,1611756210.151 [Micromodem](INFO): Nmea in: $CADRQ,140330,1,10,0,64,1*73

2021-01-27T14:03:30.151Z,1611756210.151 [Micromodem](INFO): Nmea out: $CCTXD,1,10,1,56701160060000005D00000400260000000000000000151D80030C5669BFD2C1A9018109CA118602FE956B25C06895E9C45B734FE14F139D529B1B*3F

2021-01-27T14:03:30.559Z,1611756210.559 [Micromodem](INFO): Nmea in: $CATXD,1,10,1,59*47

2021-01-27T14:03:30.958Z,1611756210.958 [Micromodem](INFO): Nmea in: $CATXP,59*7E

2021-01-27T14:03:32.195Z,1611756212.195 [Micromodem](INFO): Nmea in: $CATXF,59*68

2021-01-27T14:03:33.395Z,1611756213.395 [Micromodem](INFO): Nmea in: $CAXST,6,20210127,140331.545650,3,0,200,4000,14500,1,1,10,1,1,1,3,59*49

2021-01-27T14:04:29.085Z,1611756269.085 [CTD_Seabird](ERROR): Failed to parse device response: ,2.3437,2.82653,6168.02
2021-01-27T14:04:34.795Z,1611756274.795 [Micromodem](ERROR): Buffer fill timout failure.
2021-01-27T14:04:35.281Z,1611756275.281 [Micromodem](INFO): Outgoing frame #1, 59 bytes: 56701160060000005D00000400260000000000000000151D80030C5669BFD2C1A9018109CA118602FE956B25C06895E9C45B734FE14F139D529B1B
2021-01-27T14:04:35.281Z,1611756275.281 [Micromodem](INFO): Nmea out: $CCCYC,0,1,10,1,0,1*69

2021-01-27T14:04:35.610Z,1611756275.610 [Micromodem](INFO): Nmea in: $CACYC,0,1,10,1,0,1*6B

2021-01-27T14:04:36.010Z,1611756276.010 [Micromodem](INFO): Nmea in: $CADRQ,140436,1,10,0,64,1*72

2021-01-27T14:04:36.010Z,1611756276.010 [Micromodem](INFO): Nmea out: $CCTXD,1,10,1,56701160060000005D00000400260000000000000000151D80030C5669BFD2C1A9018109CA118602FE956B25C06895E9C45B734FE14F139D529B1B*3F

2021-01-27T14:04:36.410Z,1611756276.410 [Micromodem](INFO): Nmea in: $CATXD,1,10,1,59*47

2021-01-27T14:04:36.818Z,1611756276.818 [Micromodem](INFO): Nmea in: $CATXP,59*7E

2021-01-27T14:04:38.067Z,1611756278.067 [Micromodem](INFO): Nmea in: $CATXF,59*68

2021-01-27T14:04:38.458Z,1611756278.458 [Micromodem](INFO): Nmea in: $CAXST,6,20210127,140437.402312,3,0,200,4000,14500,1,1,10,1,1,1,3,59*49

2021-01-27T14:04:40.094Z,1611756280.094 [Micromodem](INFO): Nmea in: $CAACK,10,1,1,1*7B

2021-01-27T14:04:40.097Z,1611756280.097 [Micromodem](INFO): Sent 51 bytes from file Logs/20210127T135326/Courier0006.lzma.parts
2021-01-27T14:04:40.097Z,1611756280.097 [Micromodem](INFO): Packets left to send: 0
2021-01-27T14:04:40.485Z,1611756280.485 [Micromodem](INFO): Nmea in: $CACST,6,0,20210127140440.431200,06,1728,32,0161,0150,59,01,01,00,03,1,010,001,0,4,1,0,150,2.4,5.00,13,-2.00,-01,-1.05,43,14500,4000*44

2021-01-27T14:04:40.609Z,1611756280.609 [profile_stationUnder:MicromodemComms] Stopped
2021-01-27T14:04:40.609Z,1611756280.609 [profile_stationUnder:MicromodemComms](DEBUG): Aggregate::uninitialize profile_stationUnder:MicromodemComms
2021-01-27T14:04:40.609Z,1611756280.609 [profile_stationUnder:MicromodemComms:CheckIn] Stopped
2021-01-27T14:04:40.609Z,1611756280.609 [profile_stationUnder:MicromodemComms:CheckIn](DEBUG): Aggregate::uninitialize profile_stationUnder:MicromodemComms:CheckIn
2021-01-27T14:04:40.609Z,1611756280.609 [profile_stationUnder:MicromodemComms:CheckIn:A] Stopped
2021-01-27T14:05:11.586Z,1611756311.586 [NAL9602](INFO): Not Powering down - fast GPS
2021-01-27T14:05:48.405Z,1611756348.405 [CTD_Seabird](ERROR): Failed to parse device response: 5.31,2.341.17
2021-01-27T14:06:12.199Z,1611756372.199 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2021-01-27T14:07:58.075Z,1611756478.075 [RDI_PathfinderUp](ERROR): DVL BIT error. See manual. Result code: -32768
2021-01-27T14:08:59.068Z,1611756539.068 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items
2021-01-27T14:09:03.923Z,1611756543.923 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2021-01-27T14:09:08.763Z,1611756548.763 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2021-01-27T14:09:40.685Z,1611756580.685 [profile_stationUnder:MicromodemComms] Running Loop=1
2021-01-27T14:09:40.685Z,1611756580.685 [profile_stationUnder:MicromodemComms](DEBUG): Aggregate::initialize profile_stationUnder:MicromodemComms
2021-01-27T14:09:40.685Z,1611756580.685 [profile_stationUnder:MicromodemComms:CheckIn] Running Loop=1
2021-01-27T14:09:40.686Z,1611756580.686 [profile_stationUnder:MicromodemComms:CheckIn](DEBUG): Aggregate::initialize profile_stationUnder:MicromodemComms:CheckIn
2021-01-27T14:09:40.686Z,1611756580.686 [profile_stationUnder:MicromodemComms:CheckIn:A] Running Loop=1
2021-01-27T14:09:41.131Z,1611756581.131 [Micromodem](INFO): Outgoing frame #1, 59 bytes: 56701160090000005D00000400260000000000000000151D80030C5669BFD2C1A8F4F1DC594F25789585E0F3F6D36665FA4D0DC8ACEA7EE9568D40
2021-01-27T14:09:41.131Z,1611756581.131 [Micromodem](INFO): Nmea out: $CCCYC,0,1,10,1,0,1*69

2021-01-27T14:09:41.470Z,1611756581.470 [Micromodem](INFO): Nmea in: $CACYC,0,1,10,1,0,1*6B

2021-01-27T14:09:41.893Z,1611756581.893 [Micromodem](INFO): Nmea in: $CADRQ,140942,1,10,0,64,1*7C

2021-01-27T14:09:41.893Z,1611756581.893 [Micromodem](INFO): Nmea out: $CCTXD,1,10,1,56701160090000005D00000400260000000000000000151D80030C5669BFD2C1A8F4F1DC594F25789585E0F3F6D36665FA4D0DC8ACEA7EE9568D40*35

2021-01-27T14:09:42.272Z,1611756582.272 [Micromodem](INFO): Nmea in: $CATXD,1,10,1,59*47

2021-01-27T14:09:42.701Z,1611756582.701 [Micromodem](INFO): Nmea in: $CATXP,59*7E

2021-01-27T14:09:43.914Z,1611756583.914 [Micromodem](INFO): Nmea in: $CATXF,59*68

2021-01-27T14:09:44.315Z,1611756584.315 [Micromodem](INFO): Nmea in: $CAXST,6,20210127,140943.283219,3,0,200,4000,14500,1,1,10,1,1,1,3,59*42

2021-01-27T14:09:45.946Z,1611756585.946 [Micromodem](INFO): Nmea in: $CAACK,10,1,1,1*7B

2021-01-27T14:09:45.948Z,1611756585.948 [Micromodem](INFO): Sent 51 bytes from file Logs/20210127T135326/Courier0009.lzma.parts
2021-01-27T14:09:45.948Z,1611756585.948 [Micromodem](INFO): Packets left to send: 0
2021-01-27T14:09:46.329Z,1611756586.329 [Micromodem](INFO): Nmea in: $CACST,6,0,20210127140946.281541,06,1089,30,0135,0150,29,01,01,00,04,1,010,001,0,4,1,0,150,11.4,4.00,08,-1.00,-01,-0.61,33,14500,4000*71

2021-01-27T14:09:46.437Z,1611756586.437 [profile_stationUnder:MicromodemComms] Stopped
2021-01-27T14:09:46.437Z,1611756586.437 [profile_stationUnder:MicromodemComms](DEBUG): Aggregate::uninitialize profile_stationUnder:MicromodemComms
2021-01-27T14:09:46.437Z,1611756586.437 [profile_stationUnder:MicromodemComms:CheckIn] Stopped
2021-01-27T14:09:46.438Z,1611756586.438 [profile_stationUnder:MicromodemComms:CheckIn](DEBUG): Aggregate::uninitialize profile_stationUnder:MicromodemComms:CheckIn
2021-01-27T14:09:46.438Z,1611756586.438 [profile_stationUnder:MicromodemComms:CheckIn:A] Stopped
2021-01-27T14:09:48.786Z,1611756588.786 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for altitude
2021-01-27T14:10:17.833Z,1611756617.833 [NAL9602](INFO): Not Powering down - fast GPS
2021-01-27T14:10:34.009Z,1611756634.009 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2021-01-27T14:10:44.143Z,1611756644.143 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for altitude
2021-01-27T14:11:27.764Z,1611756687.764 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2021-01-27T14:11:34.189Z,1611756694.189 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2021-01-27T14:11:43.925Z,1611756703.925 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2021-01-27T14:11:49.141Z,1611756709.141 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2021-01-27T14:11:53.991Z,1611756713.991 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2021-01-27T14:12:08.947Z,1611756728.947 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2021-01-27T14:12:14.193Z,1611756734.193 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2021-01-27T14:12:20.255Z,1611756740.255 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items
2021-01-27T14:12:29.153Z,1611756749.153 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2021-01-27T14:13:40.258Z,1611756820.258 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2021-01-27T14:14:46.513Z,1611756886.513 [profile_stationUnder:MicromodemComms] Running Loop=1
2021-01-27T14:14:46.513Z,1611756886.513 [profile_stationUnder:MicromodemComms](DEBUG): Aggregate::initialize profile_stationUnder:MicromodemComms
2021-01-27T14:14:46.513Z,1611756886.513 [profile_stationUnder:MicromodemComms:CheckIn] Running Loop=1
2021-01-27T14:14:46.513Z,1611756886.513 [profile_stationUnder:MicromodemComms:CheckIn](DEBUG): Aggregate::initialize profile_stationUnder:MicromodemComms:CheckIn
2021-01-27T14:14:46.514Z,1611756886.514 [profile_stationUnder:MicromodemComms:CheckIn:A] Running Loop=1
2021-01-27T14:14:46.966Z,1611756886.966 [Micromodem](INFO): Outgoing frame #1, 60 bytes: 567011600C0000005D00000400260000000000000000151D80030C5669BFD2C1A8EFDFD3C29C5A55DAAC45F62EC4A05EDDB32DCF0EA17B20E292E400
2021-01-27T14:14:46.966Z,1611756886.966 [Micromodem](INFO): Nmea out: $CCCYC,0,1,10,1,0,1*69

2021-01-27T14:14:47.306Z,1611756887.306 [Micromodem](INFO): Nmea in: $CACYC,0,1,10,1,0,1*6B

2021-01-27T14:14:47.719Z,1611756887.719 [Micromodem](INFO): Nmea in: $CADRQ,141448,1,10,0,64,1*7A

2021-01-27T14:14:47.721Z,1611756887.721 [Micromodem](INFO): Nmea out: $CCTXD,1,10,1,567011600C0000005D00000400260000000000000000151D80030C5669BFD2C1A8EFDFD3C29C5A55DAAC45F62EC4A05EDDB32DCF0EA17B20E292E400*36

2021-01-27T14:14:48.114Z,1611756888.114 [Micromodem](INFO): Nmea in: $CATXD,1,10,1,60*4D

2021-01-27T14:14:48.512Z,1611756888.512 [Micromodem](INFO): Nmea in: $CATXP,60*74

2021-01-27T14:14:49.761Z,1611756889.761 [Micromodem](INFO): Nmea in: $CATXF,60*62

2021-01-27T14:14:50.135Z,1611756890.135 [Micromodem](INFO): Nmea in: $CAXST,6,20210127,141449.110420,3,0,200,4000,14500,1,1,10,1,1,1,3,60*4B

2021-01-27T14:14:50.139Z,1611756890.139 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2021-01-27T14:14:51.775Z,1611756891.775 [Micromodem](INFO): Nmea in: $CAACK,10,1,1,1*7B

2021-01-27T14:14:51.777Z,1611756891.777 [Micromodem](INFO): Sent 52 bytes from file Logs/20210127T135326/Courier0012.lzma.parts
2021-01-27T14:14:51.777Z,1611756891.777 [Micromodem](INFO): Packets left to send: 0
2021-01-27T14:14:52.175Z,1611756892.175 [Micromodem](INFO): Nmea in: $CACST,6,0,20210127141452.123688,06,889,29,0102,0150,59,02,02,00,04,1,010,001,0,4,1,0,150,-2.8,4.00,13,-1.00,-01,-0.44,45,14500,4000*58

2021-01-27T14:14:52.281Z,1611756892.281 [profile_stationUnder:MicromodemComms] Stopped
2021-01-27T14:14:52.281Z,1611756892.281 [profile_stationUnder:MicromodemComms](DEBUG): Aggregate::uninitialize profile_stationUnder:MicromodemComms
2021-01-27T14:14:52.281Z,1611756892.281 [profile_stationUnder:MicromodemComms:CheckIn] Stopped
2021-01-27T14:14:52.281Z,1611756892.281 [profile_stationUnder:MicromodemComms:CheckIn](DEBUG): Aggregate::uninitialize profile_stationUnder:MicromodemComms:CheckIn
2021-01-27T14:14:52.281Z,1611756892.281 [profile_stationUnder:MicromodemComms:CheckIn:A] Stopped
2021-01-27T14:15:23.678Z,1611756923.678 [NAL9602](INFO): Not Powering down - fast GPS
2021-01-27T14:15:47.118Z,1611756947.118 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2021-01-27T14:16:54.141Z,1611757014.141 [CTD_Seabird](ERROR): Failed to parse device response: 2.37,2.3753,2.829414
2021-01-27T14:18:49.769Z,1611757129.769 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items
2021-01-27T14:19:52.382Z,1611757192.382 [profile_stationUnder:MicromodemComms] Running Loop=1
2021-01-27T14:19:52.383Z,1611757192.383 [profile_stationUnder:MicromodemComms](DEBUG): Aggregate::initialize profile_stationUnder:MicromodemComms
2021-01-27T14:19:52.383Z,1611757192.383 [profile_stationUnder:MicromodemComms:CheckIn] Running Loop=1
2021-01-27T14:19:52.383Z,1611757192.383 [profile_stationUnder:MicromodemComms:CheckIn](DEBUG): Aggregate::initialize profile_stationUnder:MicromodemComms:CheckIn
2021-01-27T14:19:52.383Z,1611757192.383 [profile_stationUnder:MicromodemComms:CheckIn:A] Running Loop=1
2021-01-27T14:19:52.870Z,1611757192.870 [Micromodem](INFO): Outgoing frame #1, 60 bytes: 567011600F0000005D00000400260000000000000000151D80030C5669BFD2C1A8E654861DF59EDF582EBDF61D90680A41226674C5F88EED9ED46000
2021-01-27T14:19:52.872Z,1611757192.872 [Micromodem](INFO): Nmea out: $CCCYC,0,1,10,1,0,1*69

2021-01-27T14:19:53.195Z,1611757193.195 [Micromodem](INFO): Nmea in: $CACYC,0,1,10,1,0,1*6B

2021-01-27T14:19:53.598Z,1611757193.598 [Micromodem](INFO): Nmea in: $CADRQ,141954,1,10,0,64,1*7A

2021-01-27T14:19:53.598Z,1611757193.598 [Micromodem](INFO): Nmea out: $CCTXD,1,10,1,567011600F0000005D00000400260000000000000000151D80030C5669BFD2C1A8E654861DF59EDF582EBDF61D90680A41226674C5F88EED9ED46000*43

2021-01-27T14:19:54.006Z,1611757194.006 [Micromodem](INFO): Nmea in: $CATXD,1,10,1,60*4D

2021-01-27T14:19:54.412Z,1611757194.412 [Micromodem](INFO): Nmea in: $CATXP,60*74

2021-01-27T14:19:55.626Z,1611757195.626 [Micromodem](INFO): Nmea in: $CATXF,60*62

2021-01-27T14:19:56.021Z,1611757196.021 [Micromodem](INFO): Nmea in: $CAXST,6,20210127,141954.991419,3,0,200,4000,14500,1,1,10,1,1,1,3,60*41

2021-01-27T14:19:58.051Z,1611757198.051 [Micromodem](INFO): Nmea in: $CAACK,10,1,1,1*7B

2021-01-27T14:19:58.054Z,1611757198.054 [Micromodem](INFO): Sent 52 bytes from file Logs/20210127T135326/Courier0015.lzma.parts
2021-01-27T14:19:58.054Z,1611757198.054 [Micromodem](INFO): Packets left to send: 0
2021-01-27T14:19:58.459Z,1611757198.459 [Micromodem](INFO): Nmea in: $CACST,6,0,20210127141958.059851,06,3306,35,0255,0150,29,01,01,00,03,1,010,001,0,4,1,0,150,12.1,7.00,16,-4.00,-01,-0.31,30,14500,4000*7C

2021-01-27T14:19:58.575Z,1611757198.575 [profile_stationUnder:MicromodemComms] Stopped
2021-01-27T14:19:58.575Z,1611757198.575 [profile_stationUnder:MicromodemComms](DEBUG): Aggregate::uninitialize profile_stationUnder:MicromodemComms
2021-01-27T14:19:58.575Z,1611757198.575 [profile_stationUnder:MicromodemComms:CheckIn] Stopped
2021-01-27T14:19:58.575Z,1611757198.575 [profile_stationUnder:MicromodemComms:CheckIn](DEBUG): Aggregate::uninitialize profile_stationUnder:MicromodemComms:CheckIn
2021-01-27T14:19:58.575Z,1611757198.575 [profile_stationUnder:MicromodemComms:CheckIn:A] Stopped
2021-01-27T14:20:29.578Z,1611757229.578 [NAL9602](INFO): Not Powering down - fast GPS
2021-01-27T14:20:46.605Z,1611757246.605 [profile_stationUnder](INFO): Timed out from 2021-01-27T14:00:46.3Z
2021-01-27T14:20:46.605Z,1611757246.605 [MissionManager](INFO): profile_stationUnder is completed.
2021-01-27T14:20:46.605Z,1611757246.605 [MissionManager](INFO): Uninitializing Mission profile_stationUnder
2021-01-27T14:20:46.605Z,1611757246.605 [profile_stationUnder] Stopped
2021-01-27T14:20:46.605Z,1611757246.605 [profile_stationUnder](DEBUG): Aggregate::uninitialize profile_stationUnder
2021-01-27T14:20:46.606Z,1611757246.606 [profile_stationUnder:Science] Stopped
2021-01-27T14:20:46.606Z,1611757246.606 [profile_stationUnder:Science](DEBUG): Aggregate::uninitialize profile_stationUnder:Science
2021-01-27T14:20:46.606Z,1611757246.606 [profile_stationUnder:Science:B] Stopped
2021-01-27T14:20:46.606Z,1611757246.606 [profile_stationUnder:Science:C] Stopped
2021-01-27T14:20:46.606Z,1611757246.606 [profile_stationUnder:Science:E] Stopped
2021-01-27T14:20:46.606Z,1611757246.606 [profile_stationUnder:Science:Read_Oil] Stopped
2021-01-27T14:20:46.606Z,1611757246.606 [profile_stationUnder:Science:OceanCurrent] Stopped
2021-01-27T14:20:46.606Z,1611757246.606 [profile_stationUnder:Science:OceanCurrent](DEBUG): Aggregate::uninitialize profile_stationUnder:Science:OceanCurrent
2021-01-27T14:20:46.606Z,1611757246.606 [profile_stationUnder:Science:OceanCurrent:A.] Stopped
2021-01-27T14:20:46.606Z,1611757246.606 [profile_stationUnder:Science:PeakDetectChl] Stopped
2021-01-27T14:20:46.606Z,1611757246.606 [profile_stationUnder:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize profile_stationUnder:Science:PeakDetectChl
2021-01-27T14:20:46.606Z,1611757246.606 [profile_stationUnder:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2021-01-27T14:20:46.606Z,1611757246.606 [profile_stationUnder:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-01-27T14:20:46.607Z,1611757246.607 [profile_stationUnder:StandardEnvelopes] Stopped
2021-01-27T14:20:46.607Z,1611757246.607 [profile_stationUnder:StandardEnvelopes](DEBUG): Aggregate::uninitialize profile_stationUnder:StandardEnvelopes
2021-01-27T14:20:46.607Z,1611757246.607 [profile_stationUnder:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2021-01-27T14:20:46.607Z,1611757246.607 [profile_stationUnder:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2021-01-27T14:20:46.607Z,1611757246.607 [profile_stationUnder:StandardEnvelopes:B.DepthEnvelope] Stopped
2021-01-27T14:20:46.607Z,1611757246.607 [profile_stationUnder:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2021-01-27T14:20:46.607Z,1611757246.607 [profile_stationUnder:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2021-01-27T14:20:46.607Z,1611757246.607 [profile_stationUnder:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2021-01-27T14:20:46.607Z,1611757246.607 [profile_stationUnder:ProfileStation] Stopped
2021-01-27T14:20:46.607Z,1611757246.607 [profile_stationUnder:ProfileStation](DEBUG): Aggregate::uninitialize profile_stationUnder:ProfileStation
2021-01-27T14:20:46.607Z,1611757246.607 [profile_stationUnder:ProfileStation:A.Pitch] Stopped
2021-01-27T14:20:46.607Z,1611757246.607 [profile_stationUnder:ProfileStation:B.Buoyancy] Stopped
2021-01-27T14:20:46.607Z,1611757246.607 [profile_stationUnder:ProfileStation:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2021-01-27T14:20:46.608Z,1611757246.608 [profile_stationUnder:ProfileStation:C.SetSpeed] Stopped
2021-01-27T14:20:46.608Z,1611757246.608 [profile_stationUnder:ProfileStation:C.SetSpeed](DEBUG): Uninitialize.
2021-01-27T14:20:46.608Z,1611757246.608 [profile_stationUnder:ProfileStation:D.DepthEnvelope] Stopped
2021-01-27T14:20:46.608Z,1611757246.608 [profile_stationUnder:ProfileStation:D.DepthEnvelope](DEBUG): Uninitialize.
2021-01-27T14:20:46.608Z,1611757246.608 [profile_stationUnder:ProfileStation:E.AltitudeEnvelope] Stopped
2021-01-27T14:20:46.608Z,1611757246.608 [profile_stationUnder:ProfileStation:E.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2021-01-27T14:20:46.608Z,1611757246.608 [profile_stationUnder:ProfileStation:F.YoYo] Stopped
2021-01-27T14:20:46.608Z,1611757246.608 [profile_stationUnder:ProfileStation:F.YoYo](DEBUG): Uninitialize YoYoComponent.
2021-01-27T14:20:46.608Z,1611757246.608 [profile_stationUnder:ProfileStation:CircleWrapper] Stopped
2021-01-27T14:20:46.608Z,1611757246.608 [profile_stationUnder:ProfileStation:CircleWrapper](DEBUG): Aggregate::uninitialize profile_stationUnder:ProfileStation:CircleWrapper
2021-01-27T14:20:46.609Z,1611757246.609 [profile_stationUnder:ProfileStation:CircleWrapper:A] Stopped
2021-01-27T14:20:46.609Z,1611757246.609 [profile_stationUnder:ProfileStation:CircleWrapper:B.Circle] Stopped
2021-01-27T14:20:46.609Z,1611757246.609 [profile_stationUnder:ProfileStation:CircleWrapper:B.Circle](DEBUG): Uninitialize.
2021-01-27T14:20:46.920Z,1611757246.920 [WetLabsSeaOWL_UV_A](INFO): Powering down
2021-01-27T14:20:47.032Z,1611757247.032 [MissionManager](IMPORTANT): Started mission DefaultWithUndock
2021-01-27T14:20:47.032Z,1611757247.032 [DefaultWithUndock] Running Loop=1
2021-01-27T14:20:47.033Z,1611757247.033 [DefaultWithUndock](DEBUG): Aggregate::initialize DefaultWithUndock
2021-01-27T14:20:47.033Z,1611757247.033 [DefaultWithUndock:A.Wait] Running Loop=1
2021-01-27T14:20:47.033Z,1611757247.033 [DefaultWithUndock:A.Wait](DEBUG): Initialize Wait Component.
2021-01-27T14:21:17.314Z,1611757277.314 [DefaultWithUndock:A.Wait](INFO): Done Waiting.
2021-01-27T14:21:17.314Z,1611757277.314 [DefaultWithUndock:A.Wait] Stopped
2021-01-27T14:21:17.314Z,1611757277.314 [DefaultWithUndock:A.Wait](DEBUG): Uninitialize Wait Component.
2021-01-27T14:21:17.314Z,1611757277.314 [DefaultWithUndock:LeaveDock] Running Loop=1
2021-01-27T14:21:17.314Z,1611757277.314 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::initialize DefaultWithUndock:LeaveDock
2021-01-27T14:21:17.314Z,1611757277.314 [DefaultWithUndock:LeaveDock:A.] Running Loop=1
2021-01-27T14:21:17.314Z,1611757277.314 [DefaultWithUndock:LeaveDock:A.](INFO): Initializing TrackAcousticContact.
2021-01-27T14:21:17.314Z,1611757277.314 [DefaultWithUndock:LeaveDock:B.Undock] Running Loop=1
2021-01-27T14:21:17.315Z,1611757277.315 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize.
2021-01-27T14:21:17.315Z,1611757277.315 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values.
2021-01-27T14:21:20.183Z,1611757280.183 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-01-27T14:21:20.954Z,1611757280.954 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76

2021-01-27T14:21:21.370Z,1611757281.370 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B

2021-01-27T14:21:22.188Z,1611757282.188 [Micromodem](INFO): Nmea in: $SNTTA,0.159841,,,,142122.32*47

2021-01-27T14:21:23.889Z,1611757283.889 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detaching from dock.
2021-01-27T14:21:24.217Z,1611757284.217 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Engaging thruster for 10.0 seconds (max) to disengage.
2021-01-27T14:21:24.624Z,1611757284.624 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Thruster off.
2021-01-27T14:21:25.033Z,1611757285.033 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detected possible detachment. Starting timer.
2021-01-27T14:21:25.034Z,1611757285.034 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-01-27T14:21:25.799Z,1611757285.799 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76

2021-01-27T14:21:26.221Z,1611757286.221 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B

2021-01-27T14:21:27.028Z,1611757287.028 [Micromodem](INFO): Nmea in: $SNTTA,0.160204,,,,142127.17*44

2021-01-27T14:21:29.898Z,1611757289.898 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-01-27T14:21:30.650Z,1611757290.650 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76

2021-01-27T14:21:31.056Z,1611757291.056 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B

2021-01-27T14:21:31.914Z,1611757291.914 [Micromodem](INFO): Nmea in: $SNTTA,0.160565,,,,142132.02*44

2021-01-27T14:21:33.110Z,1611757293.110 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2021-01-27T14:21:34.705Z,1611757294.705 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-01-27T14:21:35.506Z,1611757295.506 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76

2021-01-27T14:21:35.932Z,1611757295.932 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B

2021-01-27T14:21:36.742Z,1611757296.742 [Micromodem](INFO): Nmea in: $SNTTA,0.160992,,,,142136.87*49

2021-01-27T14:21:39.551Z,1611757299.551 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-01-27T14:21:40.343Z,1611757300.343 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76

2021-01-27T14:21:40.768Z,1611757300.768 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B

2021-01-27T14:21:41.576Z,1611757301.576 [Micromodem](INFO): Nmea in: $SNTTA,0.161260,,,,142141.71*47

2021-01-27T14:21:44.446Z,1611757304.446 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-01-27T14:21:45.194Z,1611757305.194 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76

2021-01-27T14:21:45.301Z,1611757305.301 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached at range: 241.98 m. Transitioning DDM to standby.
2021-01-27T14:21:45.596Z,1611757305.596 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B

2021-01-27T14:21:46.431Z,1611757306.431 [Micromodem](INFO): Nmea in: $SNTTA,0.161933,,,,142146.56*48

2021-01-27T14:21:49.263Z,1611757309.263 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-01-27T14:21:50.047Z,1611757310.047 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76

2021-01-27T14:21:50.448Z,1611757310.448 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B

2021-01-27T14:21:51.275Z,1611757311.275 [Micromodem](INFO): Nmea in: $SNTTA,0.161951,,,,142151.42*4F

2021-01-27T14:21:54.120Z,1611757314.120 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-01-27T14:21:54.533Z,1611757314.533 [DefaultWithUndock:LeaveDock:B.Undock](INFO): DDM at standby.
2021-01-27T14:21:54.914Z,1611757314.914 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76

2021-01-27T14:21:55.035Z,1611757315.035 [DefaultWithUndock:LeaveDock:B.Undock](IMPORTANT): Undocking sequence complete.
2021-01-27T14:21:55.035Z,1611757315.035 [DefaultWithUndock:LeaveDock:B.Undock] Stopped
2021-01-27T14:21:55.037Z,1611757315.037 [DefaultWithUndock:LeaveDock](INFO): Completed DefaultWithUndock:LeaveDock
2021-01-27T14:21:55.037Z,1611757315.037 [DefaultWithUndock:LeaveDock] Stopped
2021-01-27T14:21:55.037Z,1611757315.037 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::uninitialize DefaultWithUndock:LeaveDock
2021-01-27T14:21:55.037Z,1611757315.037 [DefaultWithUndock:LeaveDock:A.] Stopped
2021-01-27T14:21:55.037Z,1611757315.037 [DefaultWithUndock:SurfaceDefault] Running Loop=1
2021-01-27T14:21:55.037Z,1611757315.037 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault
2021-01-27T14:21:55.037Z,1611757315.037 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen] Running Loop=1
2021-01-27T14:21:55.037Z,1611757315.037 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:HoldLatchOpen
2021-01-27T14:21:55.037Z,1611757315.037 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen:A] Running Loop=1
2021-01-27T14:21:55.037Z,1611757315.037 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen:B] Running Loop=1
2021-01-27T14:21:55.037Z,1611757315.037 [DefaultWithUndock:SurfaceDefault:B.GoToSurface] Running Loop=1
2021-01-27T14:21:55.037Z,1611757315.037 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-01-27T14:21:55.038Z,1611757315.038 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-01-27T14:21:55.038Z,1611757315.038 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-01-27T14:21:55.038Z,1611757315.038 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-01-27T14:21:55.039Z,1611757315.039 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-01-27T14:21:55.039Z,1611757315.039 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-01-27T14:21:55.264Z,1611757315.264 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B

2021-01-27T14:21:55.845Z,1611757315.845 [Radio_Surface](INFO): Powering up
2021-01-27T14:21:56.474Z,1611757316.474 [Micromodem](INFO): Nmea in: $SNTTA,0.162234,,,,142156.28*4F

2021-01-27T14:22:01.616Z,1611757321.616 [DataOverHttps](INFO): Radio surface powered ON.
2021-01-27T14:22:31.756Z,1611757351.756 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-01-27T14:22:32.081Z,1611757352.081 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items
2021-01-27T14:23:06.912Z,1611757386.912 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-01-27T14:23:32.676Z,1611757412.676 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](ERROR): Pitch down timeout. Pitch: -1.67
2021-01-27T14:23:42.076Z,1611757422.076 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-01-27T14:24:17.235Z,1611757457.235 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-01-27T14:24:40.156Z,1611757480.156 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout
2021-01-27T14:24:40.156Z,1611757480.156 [RDI_Pathfinder] Communications Fault, FailCount= 1
2021-01-27T14:24:40.156Z,1611757480.156 [RDI_Pathfinder](ERROR): Communications Fault
2021-01-27T14:24:40.376Z,1611757480.376 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2021-01-27T14:24:40.600Z,1611757480.600 [RDI_Pathfinder](INFO): Powering down
2021-01-27T14:24:41.444Z,1611757481.444 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2021-01-27T14:24:41.444Z,1611757481.444 [RDI_Pathfinder] No Fault, FailCount= 1
2021-01-27T14:24:42.985Z,1611757482.985 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=1
2021-01-27T14:24:42.985Z,1611757482.985 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn
2021-01-27T14:24:42.986Z,1611757482.986 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1
2021-01-27T14:24:42.986Z,1611757482.986 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen] Stopped
2021-01-27T14:24:42.986Z,1611757482.986 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:HoldLatchOpen
2021-01-27T14:24:42.986Z,1611757482.986 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen:A] Stopped
2021-01-27T14:24:42.986Z,1611757482.986 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen:B] Stopped
2021-01-27T14:24:52.384Z,1611757492.384 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-01-27T14:25:21.505Z,1611757521.505 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003659
2021-01-27T14:25:46.358Z,1611757546.358 [NAL9602](INFO): SBD MO Status=2, MOMSN=13113, MT Status=2, MTMSN=0
2021-01-27T14:25:46.358Z,1611757546.358 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-01-27T14:27:04.348Z,1611757624.348 [NAL9602](INFO): SBD MO Status=2, MOMSN=13113, MT Status=2, MTMSN=0
2021-01-27T14:27:04.348Z,1611757624.348 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-01-27T14:27:04.792Z,1611757624.792 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout
2021-01-27T14:27:04.792Z,1611757624.792 [RDI_Pathfinder] Communications Fault, FailCount= 1
2021-01-27T14:27:04.792Z,1611757624.792 [RDI_Pathfinder](ERROR): Communications Fault
2021-01-27T14:27:04.817Z,1611757624.817 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2021-01-27T14:27:05.228Z,1611757625.228 [RDI_Pathfinder](INFO): Powering down
2021-01-27T14:27:05.977Z,1611757625.977 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2021-01-27T14:27:05.977Z,1611757625.977 [RDI_Pathfinder] No Fault, FailCount= 1
2021-01-27T14:27:16.471Z,1611757636.471 [NAL9602](INFO): SBD MO Status=0, MOMSN=13113, MT Status=0, MTMSN=0
2021-01-27T14:27:16.471Z,1611757636.471 [NAL9602](INFO): No messages in MT queue
2021-01-27T14:27:17.680Z,1611757637.680 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,142717.00,A,4132.92010,N,07042.59938,W,0.778,187.61,270121,,,D*78
2021-01-27T14:27:17.682Z,1611757637.682 [NAL9602](INFO): GPS fix at 20210127T142717: (41.548668, -70.709990)
2021-01-27T14:27:17.698Z,1611757637.698 [UniversalFixResidualReporter](INFO): Fix residual: 2.7 %DT, over the last 1069.0 m. Residual distance 29.0 m at bearing 33.2 degrees. Fix at (41.5487, -70.7100) with 506.8 m made good.
2021-01-27T14:27:17.699Z,1611757637.699 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped
2021-01-27T14:27:17.699Z,1611757637.699 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1
2021-01-27T14:27:21.008Z,1611757641.008 [DataOverHttps](INFO): Sending 212 bytes from file Logs/20210127T135326/Courier0018.lzma
2021-01-27T14:27:22.009Z,1611757642.009 [DataOverHttps](INFO): Moved sent file to Logs/20210127T135326/Courier0018.lzma.bak
2021-01-27T14:27:22.010Z,1611757642.010 [DataOverHttps](INFO): SBD MOMSN=68555
2021-01-27T14:27:31.047Z,1611757651.047 [DataOverHttps](INFO): Sending 52 bytes from file Logs/20210127T135326/Courier0021.lzma
2021-01-27T14:27:32.050Z,1611757652.050 [DataOverHttps](INFO): Moved sent file to Logs/20210127T135326/Courier0021.lzma.bak
2021-01-27T14:27:32.050Z,1611757652.050 [DataOverHttps](INFO): SBD MOMSN=68558
2021-01-27T14:27:41.094Z,1611757661.094 [DataOverHttps](INFO): Sending 859 bytes from file Logs/20210127T135326/Express0007.lzma
2021-01-27T14:27:42.090Z,1611757662.090 [DataOverHttps](INFO): Moved sent file to Logs/20210127T135326/Express0007.lzma.bak
2021-01-27T14:27:42.090Z,1611757662.090 [DataOverHttps](INFO): SBD MOMSN=68561
2021-01-27T14:27:49.998Z,1611757669.998 [NAL9602](INFO): Not Powering down - fast GPS
2021-01-27T14:27:51.127Z,1611757671.127 [DataOverHttps](INFO): Sending 123 bytes from file Logs/20210127T135326/Express0010.lzma
2021-01-27T14:27:52.130Z,1611757672.130 [DataOverHttps](INFO): Moved sent file to Logs/20210127T135326/Express0010.lzma.bak
2021-01-27T14:27:52.130Z,1611757672.130 [DataOverHttps](INFO): SBD MOMSN=68593
2021-01-27T14:28:01.168Z,1611757681.168 [DataOverHttps](INFO): Sending 109 bytes from file Logs/20210127T135326/Express0013.lzma
2021-01-27T14:28:02.170Z,1611757682.170 [DataOverHttps](INFO): Moved sent file to Logs/20210127T135326/Express0013.lzma.bak
2021-01-27T14:28:02.170Z,1611757682.170 [DataOverHttps](INFO): SBD MOMSN=68595
2021-01-27T14:28:11.208Z,1611757691.208 [DataOverHttps](INFO): Sending 103 bytes from file Logs/20210127T135326/Express0016.lzma
2021-01-27T14:28:12.210Z,1611757692.210 [DataOverHttps](INFO): Moved sent file to Logs/20210127T135326/Express0016.lzma.bak
2021-01-27T14:28:12.210Z,1611757692.210 [DataOverHttps](INFO): SBD MOMSN=68597
2021-01-27T14:28:21.256Z,1611757701.256 [DataOverHttps](INFO): Sending 211 bytes from file Logs/20210127T135326/Express0019.lzma
2021-01-27T14:28:22.254Z,1611757702.254 [DataOverHttps](INFO): Moved sent file to Logs/20210127T135326/Express0019.lzma.bak
2021-01-27T14:28:22.255Z,1611757702.255 [DataOverHttps](INFO): SBD MOMSN=68599
2021-01-27T14:28:31.287Z,1611757711.287 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20210127T135326/Express0022.lzma
2021-01-27T14:28:32.290Z,1611757712.290 [DataOverHttps](INFO): Moved sent file to Logs/20210127T135326/Express0022.lzma.bak
2021-01-27T14:28:32.290Z,1611757712.290 [DataOverHttps](INFO): SBD MOMSN=68603
2021-01-27T14:28:33.422Z,1611757713.422 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped
2021-01-27T14:28:33.422Z,1611757713.422 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1
2021-01-27T14:28:33.422Z,1611757713.422 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-01-27T14:28:39.876Z,1611757719.876 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for altitude
2021-01-27T14:28:42.839Z,1611757722.839 [CBIT](IMPORTANT): Beginning ground fault scan
2021-01-27T14:28:53.648Z,1611757733.648 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006831
CHAN A1 (24V): -0.024493
CHAN A2 (12V): -0.004910
CHAN A3 (5V): -0.001468
CHAN B0 (3.3V): 0.001103
CHAN B1 (3.15aV): 0.000819
CHAN B2 (3.15bV): 0.000646
CHAN B3 (GND): 0.002212
OPEN: 0.006535
Full Scale Calc: 4.765 mA, -1.589 mA
2021-01-27T14:30:57.886Z,1611757857.886 [DataOverHttps](IMPORTANT): SBD MTMSN=20210127T143057
2021-01-27T14:31:01.904Z,1611757861.904 [DataOverHttps](INFO): Received command:sched asap "load Maintenance/ballast_and_trim.xml;set ballast_and_trim.MissionTimeout 60 minute;set ballast_and_trim.Depth1 6 meter;set ballast_and_trim.MinAltitude 3 meter;set ballast_and_trim.MaxDepth 9 meter" 2t4ba 1 2
2021-01-27T14:31:01.957Z,1611757861.957 [CommandLine](IMPORTANT): got command schedule asap "load Maintenance/ballast_and_trim.xml;set ballast_and_trim.MissionTimeout 60 minute;set ballast_and_trim.Depth1 6 meter;set ballast_and_trim.MinAltitude 3 meter;set ballast_and_trim.MaxDepth 9 meter" 2t4ba 1 2.000000
2021-01-27T14:31:01.957Z,1611757861.957 [CommandLine](IMPORTANT): Scheduling command #1 of 2 with id=2t4ba
2021-01-27T14:31:01.958Z,1611757861.958 [CommandLine](IMPORTANT): Scheduled #24 (#1 of 2 with id='2t4ba'): "load Maintenance/ballast_and_trim.xml;set ballast_and_trim.MissionTimeout 60 minute;set ballast_and_trim.Depth1 6 meter;set ballast_and_trim.MinAltitude 3 meter;set ballast_and_trim.MaxDepth 9 meter" ASAP
2021-01-27T14:31:02.907Z,1611757862.907 [DataOverHttps](IMPORTANT): SBD MTMSN=20210127T143102
2021-01-27T14:31:04.933Z,1611757864.933 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2021-01-27T14:31:06.923Z,1611757866.923 [DataOverHttps](INFO): Received command:sched asap "set ballast_and_trim.MinOffshore 500 meter;run" 2t4ba 2 2
2021-01-27T14:31:06.992Z,1611757866.992 [CommandLine](IMPORTANT): got command schedule asap "set ballast_and_trim.MinOffshore 500 meter;run" 2t4ba 2 2.000000
2021-01-27T14:31:06.993Z,1611757866.993 [CommandLine](IMPORTANT): Scheduling command #2 of 2 with id=2t4ba
2021-01-27T14:31:06.994Z,1611757866.994 [CommandLine](IMPORTANT): Scheduled #25 (#2 of 2 with id='2t4ba'): "set ballast_and_trim.MinOffshore 500 meter;run" ASAP
2021-01-27T14:31:07.155Z,1611757867.155 [CommandLine](IMPORTANT): got command load ./Missions/Maintenance/ballast_and_trim.xml
2021-01-27T14:31:07.155Z,1611757867.155 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/ballast_and_trim.xml
2021-01-27T14:31:07.284Z,1611757867.284 [MissionManager](INFO): DefineArg ballast_and_trim.MissionTimeout = 90.000000 min
2021-01-27T14:31:07.286Z,1611757867.286 [MissionManager](INFO): DefineArg ballast_and_trim.SkipComms = 0 bool
2021-01-27T14:31:07.289Z,1611757867.289 [MissionManager](INFO): DefineArg ballast_and_trim.Depth1 = 35.000000 m
2021-01-27T14:31:07.291Z,1611757867.291 [MissionManager](INFO): DefineArg ballast_and_trim.Depth2 = nan m
2021-01-27T14:31:07.293Z,1611757867.293 [MissionManager](INFO): DefineArg ballast_and_trim.DepthDeadband = 0.100000 m
2021-01-27T14:31:07.295Z,1611757867.295 [MissionManager](INFO): DefineArg ballast_and_trim.ApproachSpeed = 1.000000 m/s
2021-01-27T14:31:07.297Z,1611757867.297 [MissionManager](INFO): DefineArg ballast_and_trim.ApproachDepthTimeout = 20.000000 min
2021-01-27T14:31:07.300Z,1611757867.300 [MissionManager](INFO): DefineArg ballast_and_trim.ApproachDepthRate = 0.400000 m/s
2021-01-27T14:31:07.302Z,1611757867.302 [MissionManager](INFO): DefineArg ballast_and_trim.ApproachPitchLimit = 20.000000 arcdeg
2021-01-27T14:31:07.356Z,1611757867.356 [MissionManager](INFO): DefineArg ballast_and_trim.ApproachSettleTimePreDive = 0.000000 min
2021-01-27T14:31:07.359Z,1611757867.359 [MissionManager](INFO): DefineArg ballast_and_trim.SettleTime = 10.000000 min
2021-01-27T14:31:07.365Z,1611757867.365 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationTimeout = 45.000000 min
2021-01-27T14:31:07.367Z,1611757867.367 [MissionManager](INFO): DefineArg ballast_and_trim.MinEstimationTime = 10.000000 min
2021-01-27T14:31:07.378Z,1611757867.378 [MissionManager](INFO): DefineArg ballast_and_trim.MassEstimationErrorBound = 0.250000 mm
2021-01-27T14:31:07.388Z,1611757867.388 [MissionManager](INFO): DefineArg ballast_and_trim.BuoyEstimationErrorBound = 15.000000 cc
2021-01-27T14:31:07.390Z,1611757867.390 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationConfidence = 99.900000 %
2021-01-27T14:31:07.405Z,1611757867.405 [MissionManager](INFO): DefineArg ballast_and_trim.kiDepthBuoy = 0.300000 1/s
2021-01-27T14:31:07.407Z,1611757867.407 [MissionManager](INFO): DefineArg ballast_and_trim.massDeadband = 0.250000 mm
2021-01-27T14:31:07.417Z,1611757867.417 [MissionManager](INFO): DefineArg ballast_and_trim.MinAltitude = 5.000000 m
2021-01-27T14:31:07.419Z,1611757867.419 [MissionManager](INFO): DefineArg ballast_and_trim.MaxDepth = 52.000000 m
2021-01-27T14:31:07.430Z,1611757867.430 [MissionManager](INFO): DefineArg ballast_and_trim.MinOffshore = 2.000000 km
2021-01-27T14:31:07.442Z,1611757867.442 [MissionManager](INFO): DefineArg ballast_and_trim.CheckDepths = 1.000000 bool
2021-01-27T14:31:07.443Z,1611757867.443 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml
2021-01-27T14:31:07.678Z,1611757867.678 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectChlActive = 0 bool
2021-01-27T14:31:07.680Z,1611757867.680 [MissionManager](INFO): DefineArg ballast_and_trim:Science.TimeWindowPeakReport = nan min
2021-01-27T14:31:07.682Z,1611757867.682 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestChlPeakReportActive = 0 bool
2021-01-27T14:31:07.683Z,1611757867.683 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PatchTracking = 0 bool
2021-01-27T14:31:07.686Z,1611757867.686 [MissionManager](INFO): DefineArg ballast_and_trim:Science.FilterWidthHorizontal = 3.000000 count
2021-01-27T14:31:07.688Z,1611757867.688 [MissionManager](INFO): DefineArg ballast_and_trim:Science.NumProfilesSlidingwindow = 100.000000 count
2021-01-27T14:31:07.690Z,1611757867.690 [MissionManager](INFO): DefineArg ballast_and_trim:Science.OffPeakFractionHorizontal = 80.000000 %
2021-01-27T14:31:07.692Z,1611757867.692 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectNO3Active = 0 bool
2021-01-27T14:31:07.695Z,1611757867.695 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectOilActive = 0 bool
2021-01-27T14:31:07.761Z,1611757867.761 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectFDOMActive = 0 bool
2021-01-27T14:31:07.763Z,1611757867.763 [MissionManager](INFO): DefineArg ballast_and_trim:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2021-01-27T14:31:07.774Z,1611757867.774 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledAanderaaO2 = 0.000000 bool
2021-01-27T14:31:07.784Z,1611757867.784 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledNeilBrown = 0.000000 bool
2021-01-27T14:31:07.787Z,1611757867.787 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledSeabird = 1.000000 bool
2021-01-27T14:31:07.798Z,1611757867.798 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsBB2FL = 0.000000 bool
2021-01-27T14:31:07.804Z,1611757867.804 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsSeaOWL_UV_A = 1.000000 bool
2021-01-27T14:31:07.807Z,1611757867.807 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsUBAT = 0.000000 bool
2021-01-27T14:31:07.817Z,1611757867.817 [MissionManager](INFO): DefineArg ballast_and_trim:Science.LowPassWindowLength = 20.000000 count
2021-01-27T14:31:07.819Z,1611757867.819 [MissionManager](INFO): DefineArg ballast_and_trim:Science.MedianFilterLen = 5.000000 count
2021-01-27T14:31:07.838Z,1611757867.838 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakShallowBound = nan m
2021-01-27T14:31:07.848Z,1611757867.848 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDeepBound = nan m
2021-01-27T14:31:07.851Z,1611757867.851 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChl = 0.000000 ug/l
2021-01-27T14:31:07.861Z,1611757867.861 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlDepth = 0.000000 m
2021-01-27T14:31:07.863Z,1611757867.863 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlTemperature = 0.000000 degC
2021-01-27T14:31:07.871Z,1611757867.871 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLatitude = nan arcdeg
2021-01-27T14:31:07.878Z,1611757867.878 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLongitude = nan arcdeg
2021-01-27T14:31:07.884Z,1611757867.884 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3 = 0.000000 umol/l
2021-01-27T14:31:07.886Z,1611757867.886 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Depth = 0.000000 m
2021-01-27T14:31:07.889Z,1611757867.889 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Latitude = nan arcdeg
2021-01-27T14:31:07.891Z,1611757867.891 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Longitude = nan arcdeg
2021-01-27T14:31:07.906Z,1611757867.906 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChl = 0.000000 m
2021-01-27T14:31:07.912Z,1611757867.912 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDepth = 0.000000 m
2021-01-27T14:31:07.915Z,1611757867.915 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLatitude = nan arcdeg
2021-01-27T14:31:07.921Z,1611757867.921 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLongitude = nan arcdeg
2021-01-27T14:31:07.923Z,1611757867.923 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDistance = nan m
2021-01-27T14:31:07.937Z,1611757867.937 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOil = 0.000000 kg/m3
2021-01-27T14:31:07.939Z,1611757867.939 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilDepth = 0.000000 m
2021-01-27T14:31:07.942Z,1611757867.942 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLatitude = nan arcdeg
2021-01-27T14:31:07.948Z,1611757867.948 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLongitude = nan arcdeg
2021-01-27T14:31:07.950Z,1611757867.950 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOM = 0.000000 ppb
2021-01-27T14:31:07.968Z,1611757867.968 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMDepth = 0.000000 m
2021-01-27T14:31:07.970Z,1611757867.970 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMLatitude = nan arcdeg
2021-01-27T14:31:07.977Z,1611757867.977 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMLongitude = nan arcdeg
2021-01-27T14:31:07.979Z,1611757867.979 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchFDOMDistance = nan m
2021-01-27T14:31:08.024Z,1611757868.024 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-01-27T14:31:08.054Z,1611757868.054 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2021-01-27T14:31:08.073Z,1611757868.073 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-01-27T14:31:08.100Z,1611757868.100 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-01-27T14:31:08.154Z,1611757868.154 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-01-27T14:31:08.179Z,1611757868.179 [MissionManager](INFO): Inserting Stack: Missions/Insert/NeedComms.xml
2021-01-27T14:31:08.312Z,1611757868.312 [MissionManager](INFO): DefineArg ballast_and_trim:NeedComms.DiveInterval = 3.000000 h
2021-01-27T14:31:08.314Z,1611757868.314 [MissionManager](INFO): DefineArg ballast_and_trim:NeedComms.WaitForPitchUp = 10.000000 min
2021-01-27T14:31:08.317Z,1611757868.317 [MissionManager](INFO): DefineArg ballast_and_trim:NeedComms.SurfacePitch = 20.000000 arcdeg
2021-01-27T14:31:08.319Z,1611757868.319 [MissionManager](INFO): DefineArg ballast_and_trim:NeedComms.SurfaceDepthRate = nan m/s
2021-01-27T14:31:08.329Z,1611757868.329 [MissionManager](INFO): DefineArg ballast_and_trim:NeedComms.SurfaceSpeed = 1.000000 m/s
2021-01-27T14:31:08.331Z,1611757868.331 [MissionManager](INFO): DefineArg ballast_and_trim:NeedComms.GPSTimeout = 7.000000 min
2021-01-27T14:31:08.338Z,1611757868.338 [MissionManager](INFO): DefineArg ballast_and_trim:NeedComms.CommsTimeout = 30.000000 min
2021-01-27T14:31:08.343Z,1611757868.343 [ballast_and_trim:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2021-01-27T14:31:08.376Z,1611757868.376 [MissionManager](INFO): Inserting Stack: Missions/Insert/StandardEnvelopes.xml
2021-01-27T14:31:08.415Z,1611757868.415 [MissionManager](INFO): DefineArg ballast_and_trim:StandardEnvelopes.MinAltitude = 5.000000 m
2021-01-27T14:31:08.421Z,1611757868.421 [MissionManager](INFO): DefineArg ballast_and_trim:StandardEnvelopes.MaxDepth = 200.000000 m
2021-01-27T14:31:08.432Z,1611757868.432 [MissionManager](INFO): DefineArg ballast_and_trim:StandardEnvelopes.MinOffshore = 2000.000000 m
2021-01-27T14:31:08.433Z,1611757868.433 [ballast_and_trim:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2021-01-27T14:31:08.443Z,1611757868.443 [ballast_and_trim:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2021-01-27T14:31:08.453Z,1611757868.453 [ballast_and_trim:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2021-01-27T14:31:08.482Z,1611757868.482 [ballast_and_trim:CheckTargetDepths:CheckDepth1:B.Execute](DEBUG): Construct Execute.
2021-01-27T14:31:08.494Z,1611757868.494 [ballast_and_trim:CheckTargetDepths:CheckDepth2:B.Execute](DEBUG): Construct Execute.
2021-01-27T14:31:08.514Z,1611757868.514 [MissionManager](INFO): Inserting Stack: Missions/Insert/BallastAndTrim.xml
2021-01-27T14:31:08.694Z,1611757868.694 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepth = 25.000000 m
2021-01-27T14:31:08.697Z,1611757868.697 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachSpeed = 0.000000 m/s
2021-01-27T14:31:08.703Z,1611757868.703 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthRate = 0.400000 m/s
2021-01-27T14:31:08.709Z,1611757868.709 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachPitchLimit = 20.000000 arcdeg
2021-01-27T14:31:08.733Z,1611757868.733 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachSettleTimePreDive = 0.000000 min
2021-01-27T14:31:08.739Z,1611757868.739 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthTimeout = 20.000000 min
2021-01-27T14:31:08.754Z,1611757868.754 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertSettleTime = 10.000000 min
2021-01-27T14:31:08.760Z,1611757868.760 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstTimeout = 45.000000 min
2021-01-27T14:31:08.762Z,1611757868.762 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MinEstTime = 10.000000 min
2021-01-27T14:31:08.774Z,1611757868.774 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MassEstErrorBound = 0.250000 mm
2021-01-27T14:31:08.780Z,1611757868.780 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.BuoyEstErrorBound = 15.000000 cc
2021-01-27T14:31:08.789Z,1611757868.789 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstConfidence = 99.900000 %
2021-01-27T14:31:08.791Z,1611757868.791 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepthDeadband = 1.000000 m
2021-01-27T14:31:08.798Z,1611757868.798 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertKiDepthBuoy = 0.300000 1/s
2021-01-27T14:31:08.801Z,1611757868.801 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertMassDeadband = 0.250000 mm
2021-01-27T14:31:08.841Z,1611757868.841 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Construct.
2021-01-27T14:31:08.850Z,1611757868.850 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Construct Buoyancy.
2021-01-27T14:31:08.857Z,1611757868.857 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Construct.
2021-01-27T14:31:08.873Z,1611757868.873 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Construct Wait.
2021-01-27T14:31:08.880Z,1611757868.880 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Construct.
2021-01-27T14:31:08.883Z,1611757868.883 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](DEBUG): Construct.
2021-01-27T14:31:08.893Z,1611757868.893 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch:A_Timeout:B.Execute](DEBUG): Construct Execute.
2021-01-27T14:31:08.899Z,1611757868.899 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Construct.
2021-01-27T14:31:08.902Z,1611757868.902 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch](DEBUG): Construct.
2021-01-27T14:31:08.907Z,1611757868.907 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Construct Wait.
2021-01-27T14:31:08.911Z,1611757868.911 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](DEBUG): Construct BallastAndTrim.
2021-01-27T14:31:08.917Z,1611757868.917 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepthDeadband = reader:ballast_and_trim.DepthDeadband
2021-01-27T14:31:08.918Z,1611757868.918 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachSpeed = reader:ballast_and_trim.ApproachSpeed
2021-01-27T14:31:08.919Z,1611757868.919 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthRate = reader:ballast_and_trim.ApproachDepthRate
2021-01-27T14:31:08.967Z,1611757868.967 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachPitchLimit = reader:ballast_and_trim.ApproachPitchLimit
2021-01-27T14:31:08.981Z,1611757868.981 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachSettleTimePreDive = reader:ballast_and_trim.ApproachSettleTimePreDive
2021-01-27T14:31:08.982Z,1611757868.982 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthTimeout = reader:ballast_and_trim.ApproachDepthTimeout
2021-01-27T14:31:08.992Z,1611757868.992 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertSettleTime = reader:ballast_and_trim.SettleTime
2021-01-27T14:31:08.993Z,1611757868.993 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstTimeout = reader:ballast_and_trim.EstimationTimeout
2021-01-27T14:31:08.995Z,1611757868.995 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MinEstTime = reader:ballast_and_trim.MinEstimationTime
2021-01-27T14:31:09.001Z,1611757869.001 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MassEstErrorBound = reader:ballast_and_trim.MassEstimationErrorBound
2021-01-27T14:31:09.002Z,1611757869.002 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.BuoyEstErrorBound = reader:ballast_and_trim.BuoyEstimationErrorBound
2021-01-27T14:31:09.016Z,1611757869.016 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstConfidence = reader:ballast_and_trim.EstimationConfidence
2021-01-27T14:31:09.017Z,1611757869.017 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertKiDepthBuoy = reader:ballast_and_trim.kiDepthBuoy
2021-01-27T14:31:09.019Z,1611757869.019 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertMassDeadband = reader:ballast_and_trim.massDeadband
2021-01-27T14:31:09.109Z,1611757869.109 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Units="Tethys/Units"
       xmlns:Guidance="Tethys/Guidance"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd"
       Id="ballast_and_trim">

    <DefineArg Name="MissionTimeout"><Description>
        Maximum duration of mission
    </Description><Units:minute/><Value>90</Value></DefineArg>

    <DefineArg Name="SkipComms"><Description>
        Skip communications at start of mission.
    </Description><False/></DefineArg>

    <DefineArg Name="Depth1"><Units:meter/><Value>35</Value></DefineArg>

    <DefineArg Name="Depth2"><Units:meter/><Value>NaN</Value></DefineArg>

    <DefineArg Name="DepthDeadband"><Description>
        How much vertical drift from the specified depth is allowed durnig the mission.
    </Description><Units:meter/><Value>0.1</Value></DefineArg>

    <DefineArg Name="ApproachSpeed"><Description>
        Enables vehicle thruster at the commanded speed to reach target depth (set to 0 m/s to drift).
    </Description><Units:meter_per_second/><Value>1</Value></DefineArg>
    
    <DefineArg Name="ApproachDepthTimeout"><Description>
        Maximum wait time for the vehicle to reach the targeted depth.
    </Description><Units:minute/><Value>20</Value></DefineArg>
    
    <DefineArg Name="ApproachDepthRate"><Description>
        Descent depth rate (Positive depth rate means going down).
    </Description><Units:meter_per_second/><Value>0.4</Value></DefineArg>
    
    <DefineArg Name="ApproachPitchLimit"><Description>
        Max vehicle pitch (+/-) when decending from the surface.
    </Description><Units:degree/><Value>20</Value></DefineArg>
    
    <DefineArg Name="ApproachSettleTimePreDive"><Description>
        Time duration for the vehicle to pump down the VBS *BEFORE* leaving the surface.
    </Description><Units:minute/><Value>0</Value></DefineArg>

    <DefineArg Name="SettleTime"><Description>
        How long to wait after reaching target depth before starting ballast and trim.
    </Description><Units:minute/><Value>10</Value></DefineArg>

    <DefineArg Name="EstimationTimeout"><Description>
        Timeout for ballast and trim estimation.
    </Description><Units:minute/><Value>45</Value></DefineArg>

    <DefineArg Name="MinEstimationTime"><Description>
        Minimum time to run ballast and trim estimation before determining
        convergence (must be shorter than timeout).
    </Description><Units:minute/><Value>10</Value></DefineArg>

    <DefineArg Name="MassEstimationErrorBound"><Description>
        Desired range of the estimated sample mean from the true mean statistic
        (smaller values take longer to converge).
    </Description><Units:millimeter/><Value>0.25</Value></DefineArg>

    <DefineArg Name="BuoyEstimationErrorBound"><Description>
        Desired range of the estimated sample mean from the true mean statistic
        (smaller values take longer to converge).
    </Description><Units:cubic_centimeter/><Value>15</Value></DefineArg>

    <DefineArg Name="EstimationConfidence"><Description>
        Confidence level that the estimator has reached the desired error range
        (larger values take longer to converge).
    </Description><Units:percent/><Value>99.9</Value></DefineArg>
    
    <DefineArg Name="kiDepthBuoy"><Description>
        Bouyancy dive loop integral gain.
    </Description><Units:reciprocal_second/><Value>0.3</Value></DefineArg>
    
    <DefineArg Name="massDeadband"><Description>
        Degree of rounding in mass-shifter command output values.
    </Description><Units:millimeter/><Value>0.25</Value></DefineArg>

    <DefineArg Name="MinAltitude"><Units:meter/><Value>5</Value></DefineArg>

    <DefineArg Name="MaxDepth"><Units:meter/><Value>52</Value></DefineArg>

    <DefineArg Name="MinOffshore"><Units:kilometer/><Value>2</Value></DefineArg>
    
    <DefineArg Name="CheckDepths"><Units:bool/><Value>1</Value></DefineArg>

    <Timeout Duration="MissionTimeout"/>

    <Insert Filename="Insert/Science.xml"/>

    <Insert Filename="Insert/NeedComms.xml" Id="NeedComms"/>

    <Assign><Sequence/><Arg Name="NeedComms:DiveInterval"/><Arg Name="MissionTimeout"/></Assign>

    <Insert Filename="Insert/StandardEnvelopes.xml"/>

    <Assign><Sequence/><Arg Name="StandardEnvelopes:MinAltitude"/><Arg Name="MinAltitude"/></Assign>

    <Assign><Sequence/><Arg Name="StandardEnvelopes:MaxDepth"/><Arg Name="MaxDepth"/></Assign>

    <Assign><Sequence/><Arg Name="StandardEnvelopes:MinOffshore"/><Arg Name="MinOffshore"/></Assign>

    <Aggregate Id="CheckTargetDepths">
        
        <Sequence/>
        
        <Break>
            <Not><Arg Name="CheckDepths"/></Not>
        </Break>
        
        <Aggregate Id="CheckDepth1">
            <Sequence/>
            
            <Break>
                <Not>
                    <Arg Name="Depth1"/>
                    <Gt><Arg Name="MaxDepth"/></Gt>
                </Not>
            </Break>
            
            <Syslog Severity="Important">Aborting ballast and trim mission. Target depth Depth1 exceeds the mission's MaxDepth setting (review mission parameters).</Syslog>
            <Guidance:Execute>
                <Sequence/>
                <Setting><Guidance:Execute.command/><String>stop</String></Setting>
            </Guidance:Execute>
        </Aggregate>
        
        <Aggregate Id="CheckDepth2">
            <Sequence/>
            
            <Break>
                <Not>
                    <Arg Name="Depth2"/>
                    <Gt><Arg Name="MaxDepth"/></Gt>
                </Not>
            </Break>
            
            <Syslog Severity="Important">Aborting ballast and trim mission. Target depth Depth2 exceeds the mission's MaxDepth setting (review mission parameters).</Syslog>
            <Guidance:Execute>
                <Sequence/>
                <Setting><Guidance:Execute.command/><String>stop</String></Setting>
            </Guidance:Execute>
        </Aggregate>
        
        <Assign><Sequence/><Arg Name="CheckDepths"/><Units:bool/><Value>0</Value></Assign>
        
    </Aggregate>


    <Aggregate Id="StartingMission">

        <Sequence/>

        <Break><Arg Name="SkipComms"/>
        </Break>

<!-- DO NOT REMOVE the syslog entry below. The MissionManager seems to ignore the value of SkipComms, does not evaluate the Break statement properly, and always calls NeedComms. Adding the syslog call somehow fixes it. -->

        <Syslog Severity="Info">Checking for additional instructions before submerging.</Syslog>

        <Call Id="StartingMission" RefId="NeedComms"/>

    </Aggregate>
    

    <Aggregate Id="RunBallastAndTrim">

        <Sequence/>
        
        <Insert Filename="Insert/BallastAndTrim.xml">
            <RedefineArg Name="InsertDepthDeadband"><Arg Name="DepthDeadband"/></RedefineArg>
            <RedefineArg Name="InsertApproachSpeed"><Arg Name="ApproachSpeed"/></RedefineArg>
            <RedefineArg Name="InsertApproachDepthRate"><Arg Name="ApproachDepthRate"/></RedefineArg>
            <RedefineArg Name="InsertApproachPitchLimit"><Arg Name="ApproachPitchLimit"/></RedefineArg>
            <RedefineArg Name="InsertApproachSettleTimePreDive"><Arg Name="ApproachSettleTimePreDive"/></RedefineArg>
            <RedefineArg Name="InsertApproachDepthTimeout"><Arg Name="ApproachDepthTimeout"/></RedefineArg>
            <RedefineArg Name="InsertSettleTime"><Arg Name="SettleTime"/></RedefineArg>
            <RedefineArg Name="EstTimeout"><Arg Name="EstimationTimeout"/></RedefineArg>
            <RedefineArg Name="MinEstTime"><Arg Name="MinEstimationTime"/></RedefineArg>
            <RedefineArg Name="MassEstErrorBound"><Arg Name="MassEstimationErrorBound"/></RedefineArg>
            <RedefineArg Name="BuoyEstErrorBound"><Arg Name="BuoyEstimationErrorBound"/></RedefineArg>
            <RedefineArg Name="EstConfidence"><Arg Name="EstimationConfidence"/></RedefineArg>
            <RedefineArg Name="InsertKiDepthBuoy"><Arg Name="kiDepthBuoy"/></RedefineArg>
            <RedefineArg Name="InsertMassDeadband"><Arg Name="massDeadband"/></RedefineArg>
        </Insert>

        <Aggregate Id="RunDepth1">

            <Sequence/>

            <Break>
                <IsNaN>
                    <Arg Name="Depth1"/>
                </IsNaN>
            </Break>

            <Assign><Sequence/><Arg Name="BallastAndTrim:InsertDepth"/><Arg Name="Depth1"/></Assign>
            
            <Call RefId="BallastAndTrim"/>

        </Aggregate>

        <Aggregate Id="RunDepth2">

            <Sequence/>

            <Break>
                <IsNaN>
                    <Arg Name="Depth2"/>
                </IsNaN>
            </Break>

            <Assign><Sequence/><Arg Name="BallastAndTrim:InsertDepth"/><Arg Name="Depth2"/></Assign>

            <Call RefId="BallastAndTrim"/>

        </Aggregate>

    </Aggregate>

    <Call Id="EndingMission" RefId="NeedComms"/>

</Mission>


2021-01-27T14:31:09.110Z,1611757869.110 [CommandLine](IMPORTANT): Loaded ./Missions/Maintenance/ballast_and_trim.xml
2021-01-27T14:31:19.598Z,1611757879.598 [CommandLine](IMPORTANT): got command set ballast_and_trim.MissionTimeout 60.000000 minute
2021-01-27T14:31:19.599Z,1611757879.599 [CommandLine](IMPORTANT): got command set ballast_and_trim.Depth1 6.000000 meter
2021-01-27T14:31:19.600Z,1611757879.600 [CommandLine](IMPORTANT): got command set ballast_and_trim.MinAltitude 3.000000 meter
2021-01-27T14:31:19.601Z,1611757879.601 [CommandLine](IMPORTANT): got command set ballast_and_trim.MaxDepth 9.000000 meter
2021-01-27T14:31:22.516Z,1611757882.516 [CommandLine](IMPORTANT): got command set ballast_and_trim.MinOffshore 500.000000 meter
2021-01-27T14:31:22.516Z,1611757882.516 [CommandLine](IMPORTANT): got command run
2021-01-27T14:31:22.518Z,1611757882.518 [CommandLine](IMPORTANT): Running 
2021-01-27T14:31:22.696Z,1611757882.696 [DefaultWithUndock] Stopped
2021-01-27T14:31:22.696Z,1611757882.696 [DefaultWithUndock](DEBUG): Aggregate::uninitialize DefaultWithUndock
2021-01-27T14:31:22.696Z,1611757882.696 [DefaultWithUndock:SurfaceDefault] Stopped
2021-01-27T14:31:22.696Z,1611757882.696 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault
2021-01-27T14:31:22.696Z,1611757882.696 [DefaultWithUndock:SurfaceDefault:B.GoToSurface] Stopped
2021-01-27T14:31:22.696Z,1611757882.696 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-01-27T14:31:22.696Z,1611757882.696 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped
2021-01-27T14:31:22.697Z,1611757882.697 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn
2021-01-27T14:31:22.697Z,1611757882.697 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped
2021-01-27T14:31:22.697Z,1611757882.697 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-01-27T14:31:22.697Z,1611757882.697 [MissionManager](IMPORTANT): Started mission ballast_and_trim
2021-01-27T14:31:22.697Z,1611757882.697 [ballast_and_trim] Running Loop=1
2021-01-27T14:31:22.697Z,1611757882.697 [ballast_and_trim](DEBUG): Aggregate::initialize ballast_and_trim
2021-01-27T14:31:22.697Z,1611757882.697 [ballast_and_trim:Science] Running Loop=1
2021-01-27T14:31:22.697Z,1611757882.697 [ballast_and_trim:Science](DEBUG): Aggregate::initialize ballast_and_trim:Science
2021-01-27T14:31:22.697Z,1611757882.697 [ballast_and_trim:Science:A] Running Loop=1
2021-01-27T14:31:22.698Z,1611757882.698 [ballast_and_trim:Science:B] Running Loop=1
2021-01-27T14:31:22.698Z,1611757882.698 [ballast_and_trim:Science:C] Running Loop=1
2021-01-27T14:31:22.698Z,1611757882.698 [ballast_and_trim:Science:D] Running Loop=1
2021-01-27T14:31:22.698Z,1611757882.698 [ballast_and_trim:Science:E] Running Loop=1
2021-01-27T14:31:22.698Z,1611757882.698 [ballast_and_trim:Science:F] Running Loop=1
2021-01-27T14:31:22.699Z,1611757882.699 [ballast_and_trim:Science:Read_Oil] Running Loop=1
2021-01-27T14:31:22.699Z,1611757882.699 [ballast_and_trim:Science:PeakDetectChl] Running Loop=1
2021-01-27T14:31:22.699Z,1611757882.699 [ballast_and_trim:Science:PeakDetectChl](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectChl
2021-01-27T14:31:22.699Z,1611757882.699 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2021-01-27T14:31:22.700Z,1611757882.700 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-01-27T14:31:22.703Z,1611757882.703 [ballast_and_trim:Science:HighestChlPeakReport] Running Loop=1
2021-01-27T14:31:22.704Z,1611757882.704 [ballast_and_trim:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestChlPeakReport
2021-01-27T14:31:22.704Z,1611757882.704 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2021-01-27T14:31:22.704Z,1611757882.704 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2021-01-27T14:31:22.704Z,1611757882.704 [ballast_and_trim:Science:PeakDetectNO3] Running Loop=1
2021-01-27T14:31:22.705Z,1611757882.705 [ballast_and_trim:Science:PeakDetectNO3](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectNO3
2021-01-27T14:31:22.705Z,1611757882.705 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2021-01-27T14:31:22.705Z,1611757882.705 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-01-27T14:31:22.706Z,1611757882.706 [ballast_and_trim:Science:PeakDetectOil] Running Loop=1
2021-01-27T14:31:22.706Z,1611757882.706 [ballast_and_trim:Science:PeakDetectOil](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectOil
2021-01-27T14:31:22.706Z,1611757882.706 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2021-01-27T14:31:22.706Z,1611757882.706 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-01-27T14:31:22.707Z,1611757882.707 [ballast_and_trim:Science:PeakDetectFDOM] Running Loop=1
2021-01-27T14:31:22.707Z,1611757882.707 [ballast_and_trim:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectFDOM
2021-01-27T14:31:22.708Z,1611757882.708 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2021-01-27T14:31:22.708Z,1611757882.708 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-01-27T14:31:22.709Z,1611757882.709 [ballast_and_trim:Science:OceanCurrent] Running Loop=1
2021-01-27T14:31:22.709Z,1611757882.709 [ballast_and_trim:Science:OceanCurrent](DEBUG): Aggregate::initialize ballast_and_trim:Science:OceanCurrent
2021-01-27T14:31:22.709Z,1611757882.709 [ballast_and_trim:Science:OceanCurrent:A.] Running Loop=1
2021-01-27T14:31:22.709Z,1611757882.709 [ballast_and_trim:Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator.
2021-01-27T14:31:22.709Z,1611757882.709 [ballast_and_trim:StandardEnvelopes] Running Loop=1
2021-01-27T14:31:22.709Z,1611757882.709 [ballast_and_trim:StandardEnvelopes](DEBUG): Aggregate::initialize ballast_and_trim:StandardEnvelopes
2021-01-27T14:31:22.710Z,1611757882.710 [ballast_and_trim:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2021-01-27T14:31:22.710Z,1611757882.710 [ballast_and_trim:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2021-01-27T14:31:22.710Z,1611757882.710 [ballast_and_trim:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2021-01-27T14:31:22.710Z,1611757882.710 [ballast_and_trim:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2021-01-27T14:31:22.710Z,1611757882.710 [ballast_and_trim:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2021-01-27T14:31:22.710Z,1611757882.710 [ballast_and_trim:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2021-01-27T14:31:22.710Z,1611757882.710 [ballast_and_trim:C] Running Loop=1
2021-01-27T14:31:22.711Z,1611757882.711 [ballast_and_trim:StandardEnvelopes] Running Loop=1
2021-01-27T14:31:22.711Z,1611757882.711 [ballast_and_trim:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2021-01-27T14:31:22.711Z,1611757882.711 [ballast_and_trim:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2021-01-27T14:31:22.732Z,1611757882.732 [ballast_and_trim:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2021-01-27T14:31:22.734Z,1611757882.734 [ballast_and_trim:C] Stopped
2021-01-27T14:31:22.734Z,1611757882.734 [ballast_and_trim:E] Running Loop=1
2021-01-27T14:31:22.734Z,1611757882.734 [ballast_and_trim:Science] Running Loop=1
2021-01-27T14:31:22.741Z,1611757882.741 [ballast_and_trim:Science:PeakDetectFDOM] Stopped
2021-01-27T14:31:22.742Z,1611757882.742 [ballast_and_trim:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectFDOM
2021-01-27T14:31:22.742Z,1611757882.742 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2021-01-27T14:31:22.742Z,1611757882.742 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-01-27T14:31:22.742Z,1611757882.742 [ballast_and_trim:Science:PeakDetectOil] Stopped
2021-01-27T14:31:22.743Z,1611757882.743 [ballast_and_trim:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectOil
2021-01-27T14:31:22.743Z,1611757882.743 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2021-01-27T14:31:22.743Z,1611757882.743 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-01-27T14:31:22.743Z,1611757882.743 [ballast_and_trim:Science:PeakDetectNO3] Stopped
2021-01-27T14:31:22.743Z,1611757882.743 [ballast_and_trim:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectNO3
2021-01-27T14:31:22.748Z,1611757882.748 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2021-01-27T14:31:22.748Z,1611757882.748 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-01-27T14:31:22.749Z,1611757882.749 [ballast_and_trim:Science:HighestChlPeakReport] Stopped
2021-01-27T14:31:22.749Z,1611757882.749 [ballast_and_trim:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestChlPeakReport
2021-01-27T14:31:22.749Z,1611757882.749 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2021-01-27T14:31:22.749Z,1611757882.749 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2021-01-27T14:31:22.750Z,1611757882.750 [ballast_and_trim:Science:PeakDetectChl] Stopped
2021-01-27T14:31:22.750Z,1611757882.750 [ballast_and_trim:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectChl
2021-01-27T14:31:22.750Z,1611757882.750 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2021-01-27T14:31:22.750Z,1611757882.750 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-01-27T14:31:22.751Z,1611757882.751 [ballast_and_trim:Science:OceanCurrent:A.] Running Loop=1
2021-01-27T14:31:22.759Z,1611757882.759 [ballast_and_trim:Science:Read_Oil] Running Loop=1
2021-01-27T14:31:22.764Z,1611757882.764 [ballast_and_trim:Science:Read_Oil](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2021-01-27T14:31:22.764Z,1611757882.764 [ballast_and_trim:Science:F] Stopped
2021-01-27T14:31:22.764Z,1611757882.764 [ballast_and_trim:Science:E] Stopped
2021-01-27T14:31:22.764Z,1611757882.764 [ballast_and_trim:Science:D] Stopped
2021-01-27T14:31:22.764Z,1611757882.764 [ballast_and_trim:Science:C] Running Loop=1
2021-01-27T14:31:22.765Z,1611757882.765 [ballast_and_trim:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature
2021-01-27T14:31:22.765Z,1611757882.765 [ballast_and_trim:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity
2021-01-27T14:31:22.765Z,1611757882.765 [ballast_and_trim:Science:B] Running Loop=1
2021-01-27T14:31:22.766Z,1611757882.766 [ballast_and_trim:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2021-01-27T14:31:22.766Z,1611757882.766 [ballast_and_trim:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2021-01-27T14:31:22.766Z,1611757882.766 [ballast_and_trim:Science:A] Stopped
2021-01-27T14:31:22.860Z,1611757882.860 [WetLabsSeaOWL_UV_A](INFO): Powering up
2021-01-27T14:31:23.177Z,1611757883.177 [ballast_and_trim:E] Stopped
2021-01-27T14:31:23.177Z,1611757883.177 [ballast_and_trim:F] Running Loop=1
2021-01-27T14:31:23.596Z,1611757883.596 [ballast_and_trim:F] Stopped
2021-01-27T14:31:23.597Z,1611757883.597 [ballast_and_trim:G] Running Loop=1
2021-01-27T14:31:23.971Z,1611757883.971 [ballast_and_trim:G] Stopped
2021-01-27T14:31:23.976Z,1611757883.976 [ballast_and_trim:CheckTargetDepths] Running Loop=1
2021-01-27T14:31:23.976Z,1611757883.976 [ballast_and_trim:CheckTargetDepths](DEBUG): Aggregate::initialize ballast_and_trim:CheckTargetDepths
2021-01-27T14:31:23.976Z,1611757883.976 [ballast_and_trim:CheckTargetDepths:CheckDepth1] Running Loop=1
2021-01-27T14:31:23.976Z,1611757883.976 [ballast_and_trim:CheckTargetDepths:CheckDepth1](DEBUG): Aggregate::initialize ballast_and_trim:CheckTargetDepths:CheckDepth1
2021-01-27T14:31:23.976Z,1611757883.976 [ballast_and_trim:CheckTargetDepths:CheckDepth1:A] Running Loop=1
2021-01-27T14:31:24.369Z,1611757884.369 [ballast_and_trim:CheckTargetDepths:CheckDepth1] Stopped
2021-01-27T14:31:24.370Z,1611757884.370 [ballast_and_trim:CheckTargetDepths:CheckDepth1](DEBUG): Aggregate::uninitialize ballast_and_trim:CheckTargetDepths:CheckDepth1
2021-01-27T14:31:24.370Z,1611757884.370 [ballast_and_trim:CheckTargetDepths:CheckDepth1:A] Stopped
2021-01-27T14:31:24.370Z,1611757884.370 [ballast_and_trim:CheckTargetDepths:CheckDepth2] Running Loop=1
2021-01-27T14:31:24.370Z,1611757884.370 [ballast_and_trim:CheckTargetDepths:CheckDepth2](DEBUG): Aggregate::initialize ballast_and_trim:CheckTargetDepths:CheckDepth2
2021-01-27T14:31:24.370Z,1611757884.370 [ballast_and_trim:CheckTargetDepths:CheckDepth2:A] Running Loop=1
2021-01-27T14:31:24.727Z,1611757884.727 [ballast_and_trim:CheckTargetDepths:CheckDepth2] Stopped
2021-01-27T14:31:24.727Z,1611757884.727 [ballast_and_trim:CheckTargetDepths:CheckDepth2](DEBUG): Aggregate::uninitialize ballast_and_trim:CheckTargetDepths:CheckDepth2
2021-01-27T14:31:24.727Z,1611757884.727 [ballast_and_trim:CheckTargetDepths:CheckDepth2:A] Stopped
2021-01-27T14:31:24.727Z,1611757884.727 [ballast_and_trim:CheckTargetDepths:C] Running Loop=1
2021-01-27T14:31:25.134Z,1611757885.134 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items
2021-01-27T14:31:25.146Z,1611757885.146 [ballast_and_trim:CheckTargetDepths:C] Stopped
2021-01-27T14:31:25.146Z,1611757885.146 [ballast_and_trim:CheckTargetDepths](INFO): Completed ballast_and_trim:CheckTargetDepths
2021-01-27T14:31:25.146Z,1611757885.146 [ballast_and_trim:CheckTargetDepths] Stopped
2021-01-27T14:31:25.146Z,1611757885.146 [ballast_and_trim:CheckTargetDepths](DEBUG): Aggregate::uninitialize ballast_and_trim:CheckTargetDepths
2021-01-27T14:31:25.146Z,1611757885.146 [ballast_and_trim:StartingMission] Running Loop=1
2021-01-27T14:31:25.146Z,1611757885.146 [ballast_and_trim:StartingMission](DEBUG): Aggregate::initialize ballast_and_trim:StartingMission
2021-01-27T14:31:25.146Z,1611757885.146 [ballast_and_trim:StartingMission:A] Running Loop=1
2021-01-27T14:31:25.530Z,1611757885.530 [ballast_and_trim:StartingMission:A](INFO): Checking for additional instructions before submerging.
2021-01-27T14:31:25.530Z,1611757885.530 [ballast_and_trim:StartingMission:A] Stopped
2021-01-27T14:31:25.530Z,1611757885.530 [ballast_and_trim:StartingMission:StartingMission] Running Loop=1
2021-01-27T14:31:25.530Z,1611757885.530 [ballast_and_trim:StartingMission:StartingMission](DEBUG): Aggregate::initialize ballast_and_trim:StartingMission:StartingMission
2021-01-27T14:31:25.933Z,1611757885.933 [ballast_and_trim:NeedComms] Running Loop=1
2021-01-27T14:31:25.933Z,1611757885.933 [ballast_and_trim:NeedComms](DEBUG): Aggregate::initialize ballast_and_trim:NeedComms
2021-01-27T14:31:25.933Z,1611757885.933 [ballast_and_trim:NeedComms:B.GoToSurface] Running Loop=1
2021-01-27T14:31:25.933Z,1611757885.933 [ballast_and_trim:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-01-27T14:31:25.934Z,1611757885.934 [ballast_and_trim:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2021-01-27T14:31:25.935Z,1611757885.935 [ballast_and_trim:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2021-01-27T14:31:25.935Z,1611757885.935 [ballast_and_trim:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2021-01-27T14:31:25.935Z,1611757885.935 [ballast_and_trim:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-01-27T14:31:25.936Z,1611757885.936 [ballast_and_trim:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-01-27T14:31:25.936Z,1611757885.936 [ballast_and_trim:NeedComms:A] Running Loop=1
2021-01-27T14:31:25.938Z,1611757885.938 [ballast_and_trim:NeedComms:A](INFO): last time_fix was: 1611757637.000000 second since 1970/01/01T00:00:00Z 
2021-01-27T14:31:25.938Z,1611757885.938 [ballast_and_trim:NeedComms:A] Stopped
2021-01-27T14:31:26.373Z,1611757886.373 [ballast_and_trim:NeedComms:C] Running Loop=1
2021-01-27T14:31:26.738Z,1611757886.738 [ballast_and_trim:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2021-01-27T14:31:26.781Z,1611757886.781 [CTD_Seabird](ERROR): Failed to parse device response: <!--startlogging-->
2021-01-27T14:31:28.344Z,1611757888.344 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,143128.00,A,4132.88832,N,07042.62200,W,0.875,203.98,270121,,,D*73
2021-01-27T14:31:28.350Z,1611757888.350 [NAL9602](INFO): GPS fix at 20210127T143128: (41.548139, -70.710367)
2021-01-27T14:31:28.403Z,1611757888.403 [ballast_and_trim:NeedComms:C] Stopped
2021-01-27T14:31:28.403Z,1611757888.403 [ballast_and_trim:NeedComms:D] Running Loop=1
2021-01-27T14:31:28.808Z,1611757888.808 [ballast_and_trim:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications
2021-01-27T14:31:32.025Z,1611757892.025 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20210127T135326/Courier0024.lzma
2021-01-27T14:31:33.026Z,1611757893.026 [DataOverHttps](INFO): Moved sent file to Logs/20210127T135326/Courier0024.lzma.bak
2021-01-27T14:31:33.026Z,1611757893.026 [DataOverHttps](INFO): SBD MOMSN=68608
2021-01-27T14:31:42.064Z,1611757902.064 [DataOverHttps](INFO): Sending 52 bytes from file Logs/20210127T135326/Courier0027.lzma
2021-01-27T14:31:43.265Z,1611757903.265 [DataOverHttps](INFO): Moved sent file to Logs/20210127T135326/Courier0027.lzma.bak
2021-01-27T14:31:43.266Z,1611757903.266 [DataOverHttps](INFO): SBD MOMSN=68612
2021-01-27T14:31:44.541Z,1611757904.541 [NAL9602](INFO): SBD MO Status=0, MOMSN=13114, MT Status=0, MTMSN=0
2021-01-27T14:31:44.541Z,1611757904.541 [NAL9602](INFO): No messages in MT queue
2021-01-27T14:31:52.105Z,1611757912.105 [DataOverHttps](INFO): Sending 689 bytes from file Logs/20210127T135326/Express0025.lzma
2021-01-27T14:31:53.109Z,1611757913.109 [DataOverHttps](INFO): Moved sent file to Logs/20210127T135326/Express0025.lzma.bak
2021-01-27T14:31:53.109Z,1611757913.109 [DataOverHttps](INFO): SBD MOMSN=68614
2021-01-27T14:32:02.143Z,1611757922.143 [DataOverHttps](INFO): Sending 52 bytes from file Logs/20210127T135326/Express0026.lzma
2021-01-27T14:32:03.146Z,1611757923.146 [DataOverHttps](INFO): Moved sent file to Logs/20210127T135326/Express0026.lzma.bak
2021-01-27T14:32:03.146Z,1611757923.146 [DataOverHttps](INFO): SBD MOMSN=68636
2021-01-27T14:32:04.390Z,1611757924.390 [ballast_and_trim:NeedComms:D] Stopped
2021-01-27T14:32:04.390Z,1611757924.390 [ballast_and_trim:NeedComms:E] Running Loop=1
2021-01-27T14:32:04.758Z,1611757924.758 [ballast_and_trim:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix
2021-01-27T14:32:06.330Z,1611757926.330 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,143206.00,A,4132.88295,N,07042.62519,W,1.419,169.31,270121,,,D*7F
2021-01-27T14:32:06.332Z,1611757926.332 [NAL9602](INFO): GPS fix at 20210127T143206: (41.548049, -70.710420)
2021-01-27T14:32:06.407Z,1611757926.407 [ballast_and_trim:NeedComms:E] Stopped
2021-01-27T14:32:06.407Z,1611757926.407 [ballast_and_trim:NeedComms](INFO): Completed ballast_and_trim:NeedComms
2021-01-27T14:32:06.407Z,1611757926.407 [ballast_and_trim:NeedComms] Stopped
2021-01-27T14:32:06.408Z,1611757926.408 [ballast_and_trim:NeedComms](DEBUG): Aggregate::uninitialize ballast_and_trim:NeedComms
2021-01-27T14:32:06.408Z,1611757926.408 [ballast_and_trim:NeedComms:B.GoToSurface] Stopped
2021-01-27T14:32:06.408Z,1611757926.408 [ballast_and_trim:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-01-27T14:32:06.778Z,1611757926.778 [ballast_and_trim:StartingMission:StartingMission](INFO): Completed ballast_and_trim:StartingMission:StartingMission
2021-01-27T14:32:06.778Z,1611757926.778 [ballast_and_trim:StartingMission:StartingMission] Stopped
2021-01-27T14:32:06.778Z,1611757926.778 [ballast_and_trim:StartingMission:StartingMission](DEBUG): Aggregate::uninitialize ballast_and_trim:StartingMission:StartingMission
2021-01-27T14:32:06.778Z,1611757926.778 [ballast_and_trim:StartingMission](INFO): Completed ballast_and_trim:StartingMission
2021-01-27T14:32:06.778Z,1611757926.778 [ballast_and_trim:StartingMission] Stopped
2021-01-27T14:32:06.778Z,1611757926.778 [ballast_and_trim:StartingMission](DEBUG): Aggregate::uninitialize ballast_and_trim:StartingMission
2021-01-27T14:32:06.778Z,1611757926.778 [ballast_and_trim:RunBallastAndTrim] Running Loop=1
2021-01-27T14:32:06.778Z,1611757926.778 [ballast_and_trim:RunBallastAndTrim](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim
2021-01-27T14:32:06.779Z,1611757926.779 [ballast_and_trim:RunBallastAndTrim:RunDepth1] Running Loop=1
2021-01-27T14:32:06.779Z,1611757926.779 [ballast_and_trim:RunBallastAndTrim:RunDepth1](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:RunDepth1
2021-01-27T14:32:06.779Z,1611757926.779 [ballast_and_trim:RunBallastAndTrim:RunDepth1:A] Running Loop=1
2021-01-27T14:32:07.159Z,1611757927.159 [ballast_and_trim:RunBallastAndTrim:RunDepth1:A] Stopped
2021-01-27T14:32:07.159Z,1611757927.159 [ballast_and_trim:RunBallastAndTrim:RunDepth1:B] Running Loop=1
2021-01-27T14:32:07.159Z,1611757927.159 [ballast_and_trim:RunBallastAndTrim:RunDepth1:B](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:RunDepth1:B
2021-01-27T14:32:07.566Z,1611757927.566 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim] Running Loop=1
2021-01-27T14:32:07.566Z,1611757927.566 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim
2021-01-27T14:32:07.566Z,1611757927.566 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Running Loop=1
2021-01-27T14:32:07.566Z,1611757927.566 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Running Loop=1
2021-01-27T14:32:07.566Z,1611757927.566 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Running Loop=1
2021-01-27T14:32:07.566Z,1611757927.566 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Running Loop=1
2021-01-27T14:32:07.566Z,1611757927.566 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Running Loop=1
2021-01-27T14:32:07.566Z,1611757927.566 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F] Running Loop=1
2021-01-27T14:32:07.566Z,1611757927.566 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:G] Running Loop=1
2021-01-27T14:32:07.570Z,1611757927.570 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:G](INFO): Going to target depth. Speed set to 1.000000 m/s 
2021-01-27T14:32:07.570Z,1611757927.570 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:G] Stopped
2021-01-27T14:32:07.570Z,1611757927.570 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth] Running Loop=1
2021-01-27T14:32:07.570Z,1611757927.570 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth
2021-01-27T14:32:07.570Z,1611757927.570 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Running Loop=1
2021-01-27T14:32:07.570Z,1611757927.570 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold] Running Loop=1
2021-01-27T14:32:07.570Z,1611757927.570 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold
2021-01-27T14:32:07.570Z,1611757927.570 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Running Loop=1
2021-01-27T14:32:07.571Z,1611757927.571 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Initialize.
2021-01-27T14:32:07.571Z,1611757927.571 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Running Loop=1
2021-01-27T14:32:07.571Z,1611757927.571 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Initialize Buoyancy Component.
2021-01-27T14:32:07.571Z,1611757927.571 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Running Loop=1
2021-01-27T14:32:07.571Z,1611757927.571 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Initialize.
2021-01-27T14:32:07.571Z,1611757927.571 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive] Running Loop=1
2021-01-27T14:32:07.571Z,1611757927.571 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive
2021-01-27T14:32:07.571Z,1611757927.571 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A] Running Loop=1
2021-01-27T14:32:07.571Z,1611757927.571 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F] Running Loop=1
2021-01-27T14:32:07.572Z,1611757927.572 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Running Loop=1
2021-01-27T14:32:07.572Z,1611757927.572 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Running Loop=1
2021-01-27T14:32:07.572Z,1611757927.572 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Running Loop=1
2021-01-27T14:32:07.573Z,1611757927.573 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Running Loop=1
2021-01-27T14:32:07.573Z,1611757927.573 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Running Loop=1
2021-01-27T14:32:07.983Z,1611757927.983 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive] Stopped
2021-01-27T14:32:07.983Z,1611757927.983 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive
2021-01-27T14:32:07.983Z,1611757927.983 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A] Stopped
2021-01-27T14:32:07.983Z,1611757927.983 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth] Running Loop=1
2021-01-27T14:32:07.983Z,1611757927.983 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth
2021-01-27T14:32:07.983Z,1611757927.983 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Running Loop=1
2021-01-27T14:32:07.983Z,1611757927.983 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Initialize.
2021-01-27T14:32:07.983Z,1611757927.983 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A] Running Loop=1
2021-01-27T14:32:07.983Z,1611757927.983 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold] Running Loop=1
2021-01-27T14:32:07.988Z,1611757927.988 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Running Loop=1
2021-01-27T14:32:07.988Z,1611757927.988 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Running Loop=1
2021-01-27T14:32:07.988Z,1611757927.988 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Running Loop=1
2021-01-27T14:32:07.989Z,1611757927.989 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Running Loop=1
2021-01-27T14:32:08.410Z,1611757928.410 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Running Loop=1
2021-01-27T14:32:08.411Z,1611757928.411 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A](INFO): Moving to 6.000000 m 
2021-01-27T14:32:08.411Z,1611757928.411 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A] Stopped
2021-01-27T14:32:08.411Z,1611757928.411 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch] Running Loop=1
2021-01-27T14:32:08.411Z,1611757928.411 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](DEBUG): Initialize.
2021-01-27T14:32:25.724Z,1611757945.724 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position 10.00 mm (4 active estimators).
2021-01-27T14:32:39.059Z,1611757959.059 [NAL9602](INFO): Not Powering down - fast GPS
2021-01-27T14:33:08.416Z,1611757988.416 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.251427
2021-01-27T14:33:44.580Z,1611758024.580 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-01-27T14:33:53.621Z,1611758033.621 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003865
2021-01-27T14:35:24.764Z,1611758124.764 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch] Stopped
2021-01-27T14:35:24.766Z,1611758124.766 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth
2021-01-27T14:35:24.766Z,1611758124.766 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth] Stopped
2021-01-27T14:35:24.766Z,1611758124.766 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth
2021-01-27T14:35:24.766Z,1611758124.766 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Stopped
2021-01-27T14:35:24.766Z,1611758124.766 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Uninitialize.
2021-01-27T14:35:24.767Z,1611758124.767 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth
2021-01-27T14:35:24.770Z,1611758124.770 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth] Stopped
2021-01-27T14:35:24.770Z,1611758124.770 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth
2021-01-27T14:35:24.770Z,1611758124.770 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Stopped
2021-01-27T14:35:24.770Z,1611758124.770 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold] Stopped
2021-01-27T14:35:24.770Z,1611758124.770 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold
2021-01-27T14:35:24.770Z,1611758124.770 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Stopped
2021-01-27T14:35:24.771Z,1611758124.771 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Stopped
2021-01-27T14:35:24.771Z,1611758124.771 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2021-01-27T14:35:24.771Z,1611758124.771 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Stopped
2021-01-27T14:35:24.771Z,1611758124.771 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth] Running Loop=1
2021-01-27T14:35:24.771Z,1611758124.771 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth
2021-01-27T14:35:24.771Z,1611758124.771 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Running Loop=1
2021-01-27T14:35:24.771Z,1611758124.771 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Initialize.
2021-01-27T14:35:24.771Z,1611758124.771 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Running Loop=1
2021-01-27T14:35:24.771Z,1611758124.771 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch](DEBUG): Initialize.
2021-01-27T14:35:24.780Z,1611758124.780 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C] Running Loop=1
2021-01-27T14:35:25.175Z,1611758125.175 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C](INFO): Waiting for the vehicle to settle. Depth = 6.190790 m 
2021-01-27T14:35:25.175Z,1611758125.175 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C] Stopped
2021-01-27T14:35:25.175Z,1611758125.175 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait] Running Loop=1
2021-01-27T14:35:25.175Z,1611758125.175 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Initialize Wait Component.
2021-01-27T14:35:25.175Z,1611758125.175 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Running Loop=1
2021-01-27T14:35:25.175Z,1611758125.175 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Running Loop=1
2021-01-27T14:35:29.020Z,1611758129.020 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.251064
2021-01-27T14:36:04.456Z,1611758164.456 [Radio_Surface](INFO): Powering down
2021-01-27T14:36:05.169Z,1611758165.169 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-01-27T14:36:06.173Z,1611758166.173 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2021-01-27T14:37:40.441Z,1611758260.441 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2021-01-27T14:37:55.407Z,1611758275.407 [RDI_Pathfinder](ERROR): only read 1 of 4 data items
2021-01-27T14:38:01.461Z,1611758281.461 [RDI_Pathfinder](ERROR): only read 1 of 4 data items
2021-01-27T14:38:04.470Z,1611758284.470 [CTD_Seabird](ERROR): Failed to parse device response: ,2.3719,2.83273,7554.47
2021-01-27T14:42:04.739Z,1611758524.739 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-01-27T14:45:25.898Z,1611758725.898 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](INFO): Done Waiting.
2021-01-27T14:45:25.898Z,1611758725.898 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait] Stopped
2021-01-27T14:45:25.899Z,1611758725.899 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Uninitialize Wait Component.
2021-01-27T14:45:25.899Z,1611758725.899 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:E] Running Loop=1
2021-01-27T14:45:26.282Z,1611758726.282 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:E](INFO): Running ballast and trim. Depth = 5.736498 m 
2021-01-27T14:45:26.282Z,1611758726.282 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:E] Stopped
2021-01-27T14:45:26.282Z,1611758726.282 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.] Running Loop=1
2021-01-27T14:45:26.283Z,1611758726.283 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](INFO): Initializing BallastAndTrim.
2021-01-27T14:48:01.021Z,1611758881.021 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2021-01-27T14:48:11.101Z,1611758891.101 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2021-01-27T14:48:26.069Z,1611758906.069 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items
2021-01-27T14:48:35.826Z,1611758915.826 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items
2021-01-27T14:50:13.115Z,1611759013.115 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items
2021-01-27T14:56:09.445Z,1611759369.445 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2021-01-27T14:56:09.447Z,1611759369.447 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2021-01-27T14:57:47.246Z,1611759467.246 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2021-01-27T14:57:56.521Z,1611759476.521 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2021-01-27T14:58:06.260Z,1611759486.260 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2021-01-27T15:04:46.973Z,1611759886.973 [Micromodem](INFO): Nmea in: 3

2021-01-27T15:04:46.974Z,1611759886.974 [Micromodem](ERROR): Response from modem failed NMEA checksum: 3
 expected 0
2021-01-27T15:05:08.443Z,1611759908.443 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2021-01-27T15:05:26.973Z,1611759926.973 [Micromodem](INFO): Nmea in: 4

2021-01-27T15:05:26.974Z,1611759926.974 [Micromodem](ERROR): Response from modem failed NMEA checksum: 4
 expected 0
2021-01-27T15:07:56.878Z,1611760076.878 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2021-01-27T15:08:17.107Z,1611760097.107 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items
2021-01-27T15:08:26.806Z,1611760106.806 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items
2021-01-27T15:08:36.878Z,1611760116.878 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for altitude
2021-01-27T15:08:42.133Z,1611760122.133 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for altitude
2021-01-27T15:12:06.010Z,1611760326.010 [CTD_Seabird](ERROR): Failed to parse device response: 5.40,2.3902,7
2021-01-27T15:12:24.804Z,1611760344.804 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for altitude
2021-01-27T15:18:02.482Z,1611760682.482 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2021-01-27T15:18:05.541Z,1611760685.541 [CTD_Seabird](ERROR): Failed to parse device response: 3,2.83473,6341.06
2021-01-27T15:18:17.439Z,1611760697.439 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2021-01-27T15:18:27.533Z,1611760707.533 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2021-01-27T15:23:17.242Z,1611760997.242 [Micromodem](INFO): Nmea in: 0.0.46*4C

2021-01-27T15:23:17.242Z,1611760997.242 [Micromodem](ERROR): Response from modem failed NMEA checksum: 0.0.46*4C
 expected 0
2021-01-27T15:25:31.400Z,1611761131.400 [CTD_Seabird](ERROR): Failed to parse device response: 4.63,26246.87
2021-01-27T15:28:32.787Z,1611761312.787 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2021-01-27T15:28:38.008Z,1611761318.008 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2021-01-27T15:28:48.114Z,1611761328.114 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2021-01-27T15:28:57.822Z,1611761337.822 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2021-01-27T15:29:07.919Z,1611761347.919 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items
2021-01-27T15:29:18.008Z,1611761358.008 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items
2021-01-27T15:29:28.120Z,1611761368.120 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for altitude
2021-01-27T15:29:43.059Z,1611761383.059 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for altitude
2021-01-27T15:30:26.707Z,1611761426.707 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](FAULT): BallastAndTrim completed. Values did not meet error bound requirements requested by user. Will NOT write estimation values to config! Estimation time: 45.00 minutes (time-out limit reached).
2021-01-27T15:30:26.709Z,1611761426.709 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): Estimated buoyancyNeutral: 199.582955 +/- 9.630164 cc (conf. level 99.90%, sigma: 24.897120 cc).
2021-01-27T15:30:26.713Z,1611761426.713 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): Estimated massDefault: -5.867673 +/- 3.848352 mm (conf. level 99.90%, sigma: 9.949246 mm).
2021-01-27T15:30:26.713Z,1611761426.713 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.] Stopped
2021-01-27T15:30:26.713Z,1611761426.713 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](INFO): Uninitializing BallastAndTrim.
2021-01-27T15:30:26.714Z,1611761426.714 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth
2021-01-27T15:30:26.714Z,1611761426.714 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth] Stopped
2021-01-27T15:30:26.714Z,1611761426.714 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth
2021-01-27T15:30:26.714Z,1611761426.714 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Stopped
2021-01-27T15:30:26.714Z,1611761426.714 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Uninitialize.
2021-01-27T15:30:26.714Z,1611761426.714 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Stopped
2021-01-27T15:30:26.716Z,1611761426.716 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim
2021-01-27T15:30:26.716Z,1611761426.716 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim] Stopped
2021-01-27T15:30:26.716Z,1611761426.716 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim
2021-01-27T15:30:26.716Z,1611761426.716 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Stopped
2021-01-27T15:30:26.716Z,1611761426.716 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Stopped
2021-01-27T15:30:26.716Z,1611761426.716 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Stopped
2021-01-27T15:30:26.716Z,1611761426.716 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Stopped
2021-01-27T15:30:26.716Z,1611761426.716 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Stopped
2021-01-27T15:30:26.716Z,1611761426.716 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F] Stopped
2021-01-27T15:30:27.120Z,1611761427.120 [ballast_and_trim:RunBallastAndTrim:RunDepth1:B](INFO): Completed ballast_and_trim:RunBallastAndTrim:RunDepth1:B
2021-01-27T15:30:27.120Z,1611761427.120 [ballast_and_trim:RunBallastAndTrim:RunDepth1:B] Stopped
2021-01-27T15:30:27.120Z,1611761427.120 [ballast_and_trim:RunBallastAndTrim:RunDepth1:B](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:RunDepth1:B
2021-01-27T15:30:27.120Z,1611761427.120 [ballast_and_trim:RunBallastAndTrim:RunDepth1](INFO): Completed ballast_and_trim:RunBallastAndTrim:RunDepth1
2021-01-27T15:30:27.120Z,1611761427.120 [ballast_and_trim:RunBallastAndTrim:RunDepth1] Stopped
2021-01-27T15:30:27.120Z,1611761427.120 [ballast_and_trim:RunBallastAndTrim:RunDepth1](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:RunDepth1
2021-01-27T15:30:27.121Z,1611761427.121 [ballast_and_trim:RunBallastAndTrim:RunDepth2] Running Loop=1
2021-01-27T15:30:27.121Z,1611761427.121 [ballast_and_trim:RunBallastAndTrim:RunDepth2](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:RunDepth2
2021-01-27T15:30:27.121Z,1611761427.121 [ballast_and_trim:RunBallastAndTrim:RunDepth2:A] Running Loop=1
2021-01-27T15:30:27.511Z,1611761427.511 [ballast_and_trim:RunBallastAndTrim:RunDepth2] Stopped
2021-01-27T15:30:27.511Z,1611761427.511 [ballast_and_trim:RunBallastAndTrim:RunDepth2](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:RunDepth2
2021-01-27T15:30:27.511Z,1611761427.511 [ballast_and_trim:RunBallastAndTrim:RunDepth2:A] Stopped
2021-01-27T15:30:27.511Z,1611761427.511 [ballast_and_trim:RunBallastAndTrim](INFO): Completed ballast_and_trim:RunBallastAndTrim
2021-01-27T15:30:27.511Z,1611761427.511 [ballast_and_trim:RunBallastAndTrim] Stopped
2021-01-27T15:30:27.511Z,1611761427.511 [ballast_and_trim:RunBallastAndTrim](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim
2021-01-27T15:30:27.512Z,1611761427.512 [ballast_and_trim:EndingMission] Running Loop=1
2021-01-27T15:30:27.512Z,1611761427.512 [ballast_and_trim:EndingMission](DEBUG): Aggregate::initialize ballast_and_trim:EndingMission
2021-01-27T15:30:27.923Z,1611761427.923 [ballast_and_trim:NeedComms] Running Loop=1
2021-01-27T15:30:27.923Z,1611761427.923 [ballast_and_trim:NeedComms](DEBUG): Aggregate::initialize ballast_and_trim:NeedComms
2021-01-27T15:30:27.923Z,1611761427.923 [ballast_and_trim:NeedComms:B.GoToSurface] Running Loop=1
2021-01-27T15:30:27.923Z,1611761427.923 [ballast_and_trim:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-01-27T15:30:27.923Z,1611761427.923 [ballast_and_trim:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2021-01-27T15:30:27.924Z,1611761427.924 [ballast_and_trim:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2021-01-27T15:30:27.924Z,1611761427.924 [ballast_and_trim:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2021-01-27T15:30:27.925Z,1611761427.925 [ballast_and_trim:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-01-27T15:30:27.925Z,1611761427.925 [ballast_and_trim:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-01-27T15:30:27.925Z,1611761427.925 [ballast_and_trim:NeedComms:A] Running Loop=1
2021-01-27T15:30:27.926Z,1611761427.926 [ballast_and_trim:NeedComms:A](INFO): last time_fix was: 1611757926.000000 second since 1970/01/01T00:00:00Z 
2021-01-27T15:30:27.926Z,1611761427.926 [ballast_and_trim:NeedComms:A] Stopped
2021-01-27T15:30:38.007Z,1611761438.007 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2021-01-27T15:30:50.543Z,1611761450.543 [ballast_and_trim:NeedComms:C] Running Loop=1
2021-01-27T15:30:51.172Z,1611761451.172 [Radio_Surface](INFO): Powering up
2021-01-27T15:30:56.768Z,1611761456.768 [DataOverHttps](INFO): Radio surface powered ON.
2021-01-27T15:31:05.472Z,1611761465.472 [NAL9602](INFO): SBD MO Status=0, MOMSN=13115, MT Status=0, MTMSN=0
2021-01-27T15:31:05.472Z,1611761465.472 [NAL9602](INFO): No messages in MT queue
2021-01-27T15:31:06.684Z,1611761466.684 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,153106.00,A,4132.70737,N,07042.96873,W,1.089,316.12,270121,,,A*7E
2021-01-27T15:31:06.686Z,1611761466.686 [NAL9602](INFO): GPS fix at 20210127T153106: (41.545123, -70.716145)
2021-01-27T15:31:06.738Z,1611761466.738 [ballast_and_trim:NeedComms:C] Stopped
2021-01-27T15:31:06.739Z,1611761466.739 [ballast_and_trim:NeedComms:D] Running Loop=1
2021-01-27T15:31:23.039Z,1611761483.039 [ballast_and_trim](INFO): Timed out from 2021-01-27T14:31:22.7Z
2021-01-27T15:31:23.039Z,1611761483.039 [MissionManager](INFO): ballast_and_trim is completed.
2021-01-27T15:31:23.040Z,1611761483.040 [MissionManager](INFO): Uninitializing Mission ballast_and_trim
2021-01-27T15:31:23.040Z,1611761483.040 [ballast_and_trim] Stopped
2021-01-27T15:31:23.040Z,1611761483.040 [ballast_and_trim](DEBUG): Aggregate::uninitialize ballast_and_trim
2021-01-27T15:31:23.040Z,1611761483.040 [ballast_and_trim:Science] Stopped
2021-01-27T15:31:23.040Z,1611761483.040 [ballast_and_trim:Science](DEBUG): Aggregate::uninitialize ballast_and_trim:Science
2021-01-27T15:31:23.040Z,1611761483.040 [ballast_and_trim:Science:B] Stopped
2021-01-27T15:31:23.040Z,1611761483.040 [ballast_and_trim:Science:C] Stopped
2021-01-27T15:31:23.040Z,1611761483.040 [ballast_and_trim:Science:Read_Oil] Stopped
2021-01-27T15:31:23.040Z,1611761483.040 [ballast_and_trim:Science:OceanCurrent] Stopped
2021-01-27T15:31:23.041Z,1611761483.041 [ballast_and_trim:Science:OceanCurrent](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:OceanCurrent
2021-01-27T15:31:23.041Z,1611761483.041 [ballast_and_trim:Science:OceanCurrent:A.] Stopped
2021-01-27T15:31:23.041Z,1611761483.041 [ballast_and_trim:NeedComms] Stopped
2021-01-27T15:31:23.041Z,1611761483.041 [ballast_and_trim:NeedComms](DEBUG): Aggregate::uninitialize ballast_and_trim:NeedComms
2021-01-27T15:31:23.041Z,1611761483.041 [ballast_and_trim:NeedComms:B.GoToSurface] Stopped
2021-01-27T15:31:23.041Z,1611761483.041 [ballast_and_trim:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-01-27T15:31:23.041Z,1611761483.041 [ballast_and_trim:NeedComms:D] Stopped
2021-01-27T15:31:23.041Z,1611761483.041 [ballast_and_trim:StandardEnvelopes] Stopped
2021-01-27T15:31:23.041Z,1611761483.041 [ballast_and_trim:StandardEnvelopes](DEBUG): Aggregate::uninitialize ballast_and_trim:StandardEnvelopes
2021-01-27T15:31:23.041Z,1611761483.041 [ballast_and_trim:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2021-01-27T15:31:23.041Z,1611761483.041 [ballast_and_trim:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2021-01-27T15:31:23.041Z,1611761483.041 [ballast_and_trim:StandardEnvelopes:B.DepthEnvelope] Stopped
2021-01-27T15:31:23.041Z,1611761483.041 [ballast_and_trim:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2021-01-27T15:31:23.042Z,1611761483.042 [ballast_and_trim:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2021-01-27T15:31:23.042Z,1611761483.042 [ballast_and_trim:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2021-01-27T15:31:23.042Z,1611761483.042 [ballast_and_trim:EndingMission] Stopped
2021-01-27T15:31:23.042Z,1611761483.042 [ballast_and_trim:EndingMission](DEBUG): Aggregate::uninitialize ballast_and_trim:EndingMission
2021-01-27T15:31:23.252Z,1611761483.252 [WetLabsSeaOWL_UV_A](INFO): Powering down
2021-01-27T15:31:23.430Z,1611761483.430 [MissionManager](IMPORTANT): Started mission DefaultWithUndock
2021-01-27T15:31:23.430Z,1611761483.430 [DefaultWithUndock] Running Loop=1
2021-01-27T15:31:23.430Z,1611761483.430 [DefaultWithUndock](DEBUG): Aggregate::initialize DefaultWithUndock
2021-01-27T15:31:23.431Z,1611761483.431 [DefaultWithUndock:A.Wait] Running Loop=1
2021-01-27T15:31:23.431Z,1611761483.431 [DefaultWithUndock:A.Wait](DEBUG): Initialize Wait Component.
2021-01-27T15:31:26.888Z,1611761486.888 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-01-27T15:31:31.900Z,1611761491.900 [NAL9602](INFO): SBD MO Status=1, MOMSN=13116, MT Status=0, MTMSN=0
2021-01-27T15:31:31.948Z,1611761491.948 [NAL9602](INFO): Sent 184 bytes from file Logs/20210127T135326/Courier0029.lzma
2021-01-27T15:31:31.948Z,1611761491.948 [NAL9602](INFO): Packets left to send: 1
2021-01-27T15:31:53.762Z,1611761513.762 [DefaultWithUndock:A.Wait](INFO): Done Waiting.
2021-01-27T15:31:53.762Z,1611761513.762 [DefaultWithUndock:A.Wait] Stopped
2021-01-27T15:31:53.762Z,1611761513.762 [DefaultWithUndock:A.Wait](DEBUG): Uninitialize Wait Component.
2021-01-27T15:31:53.763Z,1611761513.763 [DefaultWithUndock:LeaveDock] Running Loop=1
2021-01-27T15:31:53.763Z,1611761513.763 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::initialize DefaultWithUndock:LeaveDock
2021-01-27T15:31:53.763Z,1611761513.763 [DefaultWithUndock:LeaveDock:A.] Running Loop=1
2021-01-27T15:31:53.763Z,1611761513.763 [DefaultWithUndock:LeaveDock:A.](INFO): Initializing TrackAcousticContact.
2021-01-27T15:31:53.763Z,1611761513.763 [DefaultWithUndock:LeaveDock:B.Undock] Running Loop=1
2021-01-27T15:31:53.763Z,1611761513.763 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize.
2021-01-27T15:31:53.763Z,1611761513.763 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values.
2021-01-27T15:31:55.047Z,1611761515.047 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detaching from dock.
2021-01-27T15:31:55.407Z,1611761515.407 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detected possible detachment. Starting timer.
2021-01-27T15:31:56.618Z,1611761516.618 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-01-27T15:31:57.383Z,1611761517.383 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76

2021-01-27T15:31:57.836Z,1611761517.836 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B

2021-01-27T15:31:58.609Z,1611761518.609 [Micromodem](INFO): Nmea in: $SNTTA,,,,,153158.74*56

2021-01-27T15:31:59.431Z,1611761519.431 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-01-27T15:32:01.009Z,1611761521.009 [DUSBL_Hydroid](ERROR): No response from remote modem.
2021-01-27T15:32:02.040Z,1611761522.040 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-01-27T15:32:02.304Z,1611761522.304 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-01-27T15:32:02.653Z,1611761522.653 [NAL9602](INFO): Not Powering down - fast GPS
2021-01-27T15:32:03.042Z,1611761523.042 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76

2021-01-27T15:32:03.444Z,1611761523.444 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B

2021-01-27T15:32:04.286Z,1611761524.286 [Micromodem](INFO): Nmea in: $SNTTA,,,,,153204.40*5B

2021-01-27T15:32:05.075Z,1611761525.075 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-01-27T15:32:06.669Z,1611761526.669 [DUSBL_Hydroid](ERROR): No response from remote modem.
2021-01-27T15:32:07.929Z,1611761527.929 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-01-27T15:32:08.694Z,1611761528.694 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76

2021-01-27T15:32:09.096Z,1611761529.096 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B

2021-01-27T15:32:09.929Z,1611761529.929 [Micromodem](INFO): Nmea in: $SNTTA,,,,,153210.05*5F

2021-01-27T15:32:10.734Z,1611761530.734 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-01-27T15:32:12.325Z,1611761532.325 [DUSBL_Hydroid](ERROR): No response from remote modem.
2021-01-27T15:32:13.579Z,1611761533.579 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-01-27T15:32:14.346Z,1611761534.346 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76

2021-01-27T15:32:14.768Z,1611761534.768 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B

2021-01-27T15:32:15.276Z,1611761535.276 [MassServo](FAULT): Overload Error
2021-01-27T15:32:15.276Z,1611761535.276 [MassServo] Hardware Fault, FailCount= 1
2021-01-27T15:32:15.276Z,1611761535.276 [MassServo](ERROR): Hardware Fault
2021-01-27T15:32:15.316Z,1611761535.316 [CBIT](ERROR): Hardware Fault in component: MassServo
2021-01-27T15:32:15.574Z,1611761535.574 [Micromodem](INFO): Nmea in: $SNTTA,,,,,153215.70*58

2021-01-27T15:32:15.611Z,1611761535.611 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached at range: 243.00 m. Transitioning DDM to standby.
2021-01-27T15:32:15.685Z,1611761535.685 [MassServo](DEBUG): Uninitialize Mass Servo.
2021-01-27T15:32:15.685Z,1611761535.685 [MassServo](INFO): Powering down
2021-01-27T15:32:16.397Z,1611761536.397 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-01-27T15:32:16.473Z,1611761536.473 [MassServo](DEBUG): Initializing EZServoServo.
2021-01-27T15:32:16.593Z,1611761536.593 [MassServo](DEBUG): Initializing MassServo.
2021-01-27T15:32:16.597Z,1611761536.597 [CBIT](INFO): Clearing failed state for component MassServo
2021-01-27T15:32:16.597Z,1611761536.597 [MassServo] No Fault, FailCount= 1
2021-01-27T15:32:17.985Z,1611761537.985 [DUSBL_Hydroid](ERROR): No response from remote modem.
2021-01-27T15:32:18.434Z,1611761538.434 [DefaultWithUndock:LeaveDock:B.Undock](INFO): DDM at standby.
2021-01-27T15:32:18.840Z,1611761538.840 [DefaultWithUndock:LeaveDock:B.Undock](IMPORTANT): Undocking sequence complete.
2021-01-27T15:32:18.840Z,1611761538.840 [DefaultWithUndock:LeaveDock:B.Undock] Stopped
2021-01-27T15:32:18.840Z,1611761538.840 [DefaultWithUndock:LeaveDock](INFO): Completed DefaultWithUndock:LeaveDock
2021-01-27T15:32:18.840Z,1611761538.840 [DefaultWithUndock:LeaveDock] Stopped
2021-01-27T15:32:18.841Z,1611761538.841 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::uninitialize DefaultWithUndock:LeaveDock
2021-01-27T15:32:18.841Z,1611761538.841 [DefaultWithUndock:LeaveDock:A.] Stopped
2021-01-27T15:32:18.841Z,1611761538.841 [DefaultWithUndock:SurfaceDefault] Running Loop=1
2021-01-27T15:32:18.841Z,1611761538.841 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault
2021-01-27T15:32:18.841Z,1611761538.841 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen] Running Loop=1
2021-01-27T15:32:18.841Z,1611761538.841 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:HoldLatchOpen
2021-01-27T15:32:18.841Z,1611761538.841 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen:A] Running Loop=1
2021-01-27T15:32:18.841Z,1611761538.841 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen:B] Running Loop=1
2021-01-27T15:32:18.841Z,1611761538.841 [DefaultWithUndock:SurfaceDefault:B.GoToSurface] Running Loop=1
2021-01-27T15:32:18.841Z,1611761538.841 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-01-27T15:32:18.842Z,1611761538.842 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-01-27T15:32:18.842Z,1611761538.842 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-01-27T15:32:18.842Z,1611761538.842 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-01-27T15:32:18.842Z,1611761538.842 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-01-27T15:32:18.843Z,1611761538.843 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-01-27T15:32:19.187Z,1611761539.187 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=1
2021-01-27T15:32:19.187Z,1611761539.187 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn
2021-01-27T15:32:19.187Z,1611761539.187 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1
2021-01-27T15:32:19.187Z,1611761539.187 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen] Stopped
2021-01-27T15:32:19.188Z,1611761539.188 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:HoldLatchOpen
2021-01-27T15:32:19.188Z,1611761539.188 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen:A] Stopped
2021-01-27T15:32:19.188Z,1611761539.188 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen:B] Stopped
2021-01-27T15:32:21.183Z,1611761541.183 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,153221.00,A,4132.69465,N,07042.97666,W,0.156,197.57,270121,,,A*77
2021-01-27T15:32:21.186Z,1611761541.186 [NAL9602](INFO): GPS fix at 20210127T153221: (41.544911, -70.716278)
2021-01-27T15:32:21.267Z,1611761541.267 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped
2021-01-27T15:32:21.267Z,1611761541.267 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1
2021-01-27T15:32:32.204Z,1611761552.204 [BuoyancyServo](FAULT): Overload Error
2021-01-27T15:32:32.204Z,1611761552.204 [BuoyancyServo] Hardware Fault, FailCount= 1
2021-01-27T15:32:32.204Z,1611761552.204 [BuoyancyServo](ERROR): Hardware Fault
2021-01-27T15:32:32.280Z,1611761552.280 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo
2021-01-27T15:32:33.949Z,1611761553.949 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2021-01-27T15:32:33.949Z,1611761553.949 [BuoyancyServo](INFO): Powering down
2021-01-27T15:32:34.318Z,1611761554.318 [NAL9602](INFO): SBD MO Status=1, MOMSN=13117, MT Status=0, MTMSN=0
2021-01-27T15:32:34.576Z,1611761554.576 [NAL9602](INFO): Sent 17 bytes from file Logs/20210127T135326/Courier0029.lzma
2021-01-27T15:32:34.576Z,1611761554.576 [NAL9602](INFO): Packets left to send: 0
2021-01-27T15:32:34.946Z,1611761554.946 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2021-01-27T15:32:34.946Z,1611761554.946 [BuoyancyServo] No Fault, FailCount= 1
2021-01-27T15:32:37.184Z,1611761557.184 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-01-27T15:32:37.237Z,1611761557.237 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2021-01-27T15:32:37.251Z,1611761557.251 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2021-01-27T15:32:42.155Z,1611761562.155 [NAL9602](INFO): SBD MO Status=1, MOMSN=13118, MT Status=0, MTMSN=0
2021-01-27T15:32:42.206Z,1611761562.206 [NAL9602](INFO): Sent 79 bytes from file Logs/20210127T135326/Courier0033.lzma
2021-01-27T15:32:42.206Z,1611761562.206 [NAL9602](INFO): Packets left to send: 0
2021-01-27T15:32:55.336Z,1611761575.336 [BuoyancyServo](FAULT): Overload Error
2021-01-27T15:32:55.336Z,1611761575.336 [BuoyancyServo] Hardware Fault, FailCount= 2
2021-01-27T15:32:55.336Z,1611761575.336 [BuoyancyServo](ERROR): Hardware Fault
2021-01-27T15:32:55.404Z,1611761575.404 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo
2021-01-27T15:32:57.294Z,1611761577.294 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2021-01-27T15:32:57.294Z,1611761577.294 [BuoyancyServo](INFO): Powering down
2021-01-27T15:32:58.227Z,1611761578.227 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2021-01-27T15:32:58.232Z,1611761578.232 [BuoyancyServo] No Fault, FailCount= 2
2021-01-27T15:32:58.493Z,1611761578.493 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2021-01-27T15:32:58.494Z,1611761578.494 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2021-01-27T15:33:01.704Z,1611761581.704 [NAL9602](INFO): SBD MO Status=2, MOMSN=13119, MT Status=2, MTMSN=0
2021-01-27T15:33:01.704Z,1611761581.704 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-01-27T15:33:12.344Z,1611761592.344 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-01-27T15:33:12.773Z,1611761592.773 [NAL9602](INFO): SBD MO Status=1, MOMSN=13119, MT Status=0, MTMSN=0
2021-01-27T15:33:12.824Z,1611761592.824 [NAL9602](INFO): Sent 184 bytes from file Logs/20210127T135326/Express0031.lzma
2021-01-27T15:33:12.824Z,1611761592.824 [NAL9602](INFO): Packets left to send: 2
2021-01-27T15:33:21.972Z,1611761601.972 [BuoyancyServo](FAULT): Overload Error
2021-01-27T15:33:21.972Z,1611761601.972 [BuoyancyServo] Hardware Fault, FailCount= 3
2021-01-27T15:33:21.972Z,1611761601.972 [BuoyancyServo](ERROR): Hardware Fault
2021-01-27T15:33:22.040Z,1611761602.040 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo
2021-01-27T15:33:22.726Z,1611761602.726 [NAL9602](INFO): SBD MO Status=1, MOMSN=13120, MT Status=0, MTMSN=0
2021-01-27T15:33:22.780Z,1611761602.780 [NAL9602](INFO): Sent 184 bytes from file Logs/20210127T135326/Express0031.lzma
2021-01-27T15:33:22.780Z,1611761602.780 [NAL9602](INFO): Packets left to send: 1
2021-01-27T15:33:26.011Z,1611761606.011 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2021-01-27T15:33:26.011Z,1611761606.011 [BuoyancyServo](INFO): Powering down
2021-01-27T15:33:26.255Z,1611761606.255 [BuoyancyServo](FAULT): LCB fault:  Current Limiter Activated.
2021-01-27T15:33:26.718Z,1611761606.718 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2021-01-27T15:33:26.718Z,1611761606.718 [BuoyancyServo] No Fault, FailCount= 3
2021-01-27T15:33:27.034Z,1611761607.034 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2021-01-27T15:33:27.035Z,1611761607.035 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2021-01-27T15:33:30.258Z,1611761610.258 [NAL9602](INFO): SBD MO Status=1, MOMSN=13121, MT Status=0, MTMSN=0
2021-01-27T15:33:30.308Z,1611761610.308 [NAL9602](INFO): Sent 113 bytes from file Logs/20210127T135326/Express0031.lzma
2021-01-27T15:33:30.308Z,1611761610.308 [NAL9602](INFO): Packets left to send: 0
2021-01-27T15:33:38.846Z,1611761618.846 [NAL9602](INFO): SBD MO Status=1, MOMSN=13122, MT Status=0, MTMSN=0
2021-01-27T15:33:38.904Z,1611761618.904 [NAL9602](INFO): Sent 183 bytes from file Logs/20210127T135326/Express0034.lzma
2021-01-27T15:33:38.904Z,1611761618.904 [NAL9602](INFO): Packets left to send: 0
2021-01-27T15:33:46.522Z,1611761626.522 [NAL9602](INFO): SBD MO Status=0, MOMSN=13123, MT Status=0, MTMSN=0
2021-01-27T15:33:47.388Z,1611761627.388 [BuoyancyServo](FAULT): Overload Error
2021-01-27T15:33:47.388Z,1611761627.388 [BuoyancyServo] Hardware Fault, FailCount= 4
2021-01-27T15:33:47.388Z,1611761627.388 [BuoyancyServo](ERROR): Hardware Fault
2021-01-27T15:33:47.393Z,1611761627.393 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo
2021-01-27T15:33:47.488Z,1611761627.488 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-01-27T15:33:49.383Z,1611761629.383 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2021-01-27T15:33:49.383Z,1611761629.383 [BuoyancyServo](INFO): Powering down
2021-01-27T15:33:50.204Z,1611761630.204 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2021-01-27T15:33:50.204Z,1611761630.204 [BuoyancyServo] No Fault, FailCount= 4
2021-01-27T15:33:50.587Z,1611761630.587 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2021-01-27T15:33:50.604Z,1611761630.604 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2021-01-27T15:34:04.708Z,1611761644.708 [NAL9602](INFO): SBD MO Status=2, MOMSN=13124, MT Status=2, MTMSN=0
2021-01-27T15:34:04.709Z,1611761644.709 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-01-27T15:34:17.617Z,1611761657.617 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.007666
2021-01-27T15:34:18.882Z,1611761658.882 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped
2021-01-27T15:34:18.883Z,1611761658.883 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1
2021-01-27T15:34:18.883Z,1611761658.883 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-01-27T15:34:20.460Z,1611761660.460 [NAL9602](INFO): SBD MO Status=2, MOMSN=13124, MT Status=2, MTMSN=0
2021-01-27T15:34:20.460Z,1611761660.460 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-01-27T15:34:51.166Z,1611761691.166 [NAL9602](INFO): Not Powering down - fast GPS
2021-01-27T15:38:38.283Z,1611761918.283 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2021-01-27T15:38:53.582Z,1611761933.582 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2021-01-27T15:39:19.466Z,1611761959.466 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting.
2021-01-27T15:39:19.466Z,1611761959.466 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped
2021-01-27T15:39:19.466Z,1611761959.466 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-01-27T15:39:19.467Z,1611761959.467 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1
2021-01-27T15:39:19.874Z,1611761959.874 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped
2021-01-27T15:39:19.874Z,1611761959.874 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1
2021-01-27T15:39:20.290Z,1611761960.290 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 7.940720 min 
2021-01-27T15:39:20.291Z,1611761960.291 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped
2021-01-27T15:39:20.291Z,1611761960.291 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn
2021-01-27T15:39:20.291Z,1611761960.291 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped
2021-01-27T15:39:20.291Z,1611761960.291 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn
2021-01-27T15:39:20.291Z,1611761960.291 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #2
2021-01-27T15:39:20.291Z,1611761960.291 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=2
2021-01-27T15:39:20.291Z,1611761960.291 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn
2021-01-27T15:39:20.291Z,1611761960.291 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1
2021-01-27T15:39:22.263Z,1611761962.263 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,153922.00,A,4132.63911,N,07043.02631,W,0.175,193.15,270121,,,D*72
2021-01-27T15:39:22.265Z,1611761962.265 [NAL9602](INFO): GPS fix at 20210127T153922: (41.543985, -70.717105)
2021-01-27T15:39:22.286Z,1611761962.286 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped
2021-01-27T15:39:22.286Z,1611761962.286 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1
2021-01-27T15:39:25.895Z,1611761965.895 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210127T135326/Courier0036.lzma
2021-01-27T15:39:26.902Z,1611761966.902 [DataOverHttps](INFO): Moved sent file to Logs/20210127T135326/Courier0036.lzma.bak
2021-01-27T15:39:26.903Z,1611761966.903 [DataOverHttps](INFO): SBD MOMSN=68659
2021-01-27T15:39:35.936Z,1611761975.936 [DataOverHttps](INFO): Sending 224 bytes from file Logs/20210127T135326/Express0037.lzma
2021-01-27T15:39:36.938Z,1611761976.938 [DataOverHttps](INFO): Moved sent file to Logs/20210127T135326/Express0037.lzma.bak
2021-01-27T15:39:36.938Z,1611761976.938 [DataOverHttps](INFO): SBD MOMSN=68662
2021-01-27T15:39:38.506Z,1611761978.506 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped
2021-01-27T15:39:38.506Z,1611761978.506 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1
2021-01-27T15:39:38.506Z,1611761978.506 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-01-27T15:39:40.856Z,1611761980.856 [NAL9602](INFO): SBD MO Status=0, MOMSN=13124, MT Status=0, MTMSN=0
2021-01-27T15:39:40.856Z,1611761980.856 [NAL9602](INFO): No messages in MT queue
2021-01-27T15:40:11.562Z,1611762011.562 [NAL9602](INFO): Not Powering down - fast GPS
2021-01-27T15:42:03.561Z,1611762123.561 [DataOverHttps](IMPORTANT): SBD MTMSN=20210127T154203
2021-01-27T15:42:07.580Z,1611762127.580 [DataOverHttps](INFO): Received command:configSet VerticalControl.buoyancyNeutral 175 cc persist
2021-01-27T15:42:07.765Z,1611762127.765 [CommandLine](IMPORTANT): got command configSet VerticalControl.buoyancyNeutral 175.000000 cubic_centimeter persist
2021-01-27T15:42:43.740Z,1611762163.740 [DataOverHttps](IMPORTANT): SBD MTMSN=20210127T154243
2021-01-27T15:42:47.756Z,1611762167.756 [DataOverHttps](INFO): Received command:configSet VerticalControl.massDefault 5 mm persist
2021-01-27T15:42:47.992Z,1611762167.992 [CommandLine](IMPORTANT): got command configSet VerticalControl.massDefault 5.000000 millimeter persist
2021-01-27T15:44:34.205Z,1611762274.205 [DataOverHttps](IMPORTANT): SBD MTMSN=20210127T154433
2021-01-27T15:44:38.235Z,1611762278.235 [DataOverHttps](INFO): Received command:configSet LineCapture.navigationGain 5 none persist
2021-01-27T15:44:38.469Z,1611762278.469 [CommandLine](IMPORTANT): got command configSet LineCapture.navigationGain 5.000000 none persist
2021-01-27T15:44:39.032Z,1611762279.032 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting.
2021-01-27T15:44:39.032Z,1611762279.032 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped
2021-01-27T15:44:39.032Z,1611762279.032 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-01-27T15:44:39.033Z,1611762279.033 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1
2021-01-27T15:44:39.454Z,1611762279.454 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped
2021-01-27T15:44:39.454Z,1611762279.454 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1
2021-01-27T15:44:39.866Z,1611762279.866 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 13.267061 min 
2021-01-27T15:44:39.866Z,1611762279.866 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped
2021-01-27T15:44:39.866Z,1611762279.866 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn
2021-01-27T15:44:39.866Z,1611762279.866 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped
2021-01-27T15:44:39.866Z,1611762279.866 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn
2021-01-27T15:44:39.867Z,1611762279.867 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #3
2021-01-27T15:44:39.867Z,1611762279.867 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=3
2021-01-27T15:44:39.867Z,1611762279.867 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn
2021-01-27T15:44:39.867Z,1611762279.867 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1
2021-01-27T15:44:41.848Z,1611762281.848 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154441.00,A,4132.60336,N,07043.06345,W,0.739,225.17,270121,,,D*71
2021-01-27T15:44:41.851Z,1611762281.851 [NAL9602](INFO): GPS fix at 20210127T154441: (41.543389, -70.717724)
2021-01-27T15:44:41.895Z,1611762281.895 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped
2021-01-27T15:44:41.895Z,1611762281.895 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1
2021-01-27T15:44:46.255Z,1611762286.255 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210127T135326/Courier0039.lzma
2021-01-27T15:44:47.258Z,1611762287.258 [DataOverHttps](INFO): Moved sent file to Logs/20210127T135326/Courier0039.lzma.bak
2021-01-27T15:44:47.258Z,1611762287.258 [DataOverHttps](INFO): SBD MOMSN=68677
2021-01-27T15:44:56.295Z,1611762296.295 [DataOverHttps](INFO): Sending 52 bytes from file Logs/20210127T135326/Courier0042.lzma
2021-01-27T15:44:57.298Z,1611762297.298 [DataOverHttps](INFO): Moved sent file to Logs/20210127T135326/Courier0042.lzma.bak
2021-01-27T15:44:57.298Z,1611762297.298 [DataOverHttps](INFO): SBD MOMSN=68681
2021-01-27T15:45:03.664Z,1611762303.664 [NAL9602](INFO): SBD MO Status=0, MOMSN=13125, MT Status=0, MTMSN=0
2021-01-27T15:45:03.664Z,1611762303.664 [NAL9602](INFO): No messages in MT queue
2021-01-27T15:45:06.336Z,1611762306.336 [DataOverHttps](INFO): Sending 301 bytes from file Logs/20210127T135326/Express0040.lzma
2021-01-27T15:45:07.338Z,1611762307.338 [DataOverHttps](INFO): Moved sent file to Logs/20210127T135326/Express0040.lzma.bak
2021-01-27T15:45:07.338Z,1611762307.338 [DataOverHttps](INFO): SBD MOMSN=68683
2021-01-27T15:45:16.383Z,1611762316.383 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20210127T135326/Express0043.lzma
2021-01-27T15:45:17.386Z,1611762317.386 [DataOverHttps](INFO): Moved sent file to Logs/20210127T135326/Express0043.lzma.bak
2021-01-27T15:45:17.386Z,1611762317.386 [DataOverHttps](INFO): SBD MOMSN=68693
2021-01-27T15:45:18.642Z,1611762318.642 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped
2021-01-27T15:45:18.642Z,1611762318.642 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1
2021-01-27T15:45:18.642Z,1611762318.642 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-01-27T15:45:34.362Z,1611762334.362 [NAL9602](INFO): Not Powering down - fast GPS
2021-01-27T15:46:32.713Z,1611762392.713 [DataOverHttps](IMPORTANT): SBD MTMSN=20210127T154632
2021-01-27T15:46:36.732Z,1611762396.732 [DataOverHttps](INFO): Received command:configSet LineCapture.rolloutdistance 150 m persist
2021-01-27T15:46:36.899Z,1611762396.899 [CommandLine](IMPORTANT): got command configSet LineCapture.rolloutDistance 150.000000 meter persist
2021-01-27T15:48:58.828Z,1611762538.828 [RDI_PathfinderUp](ERROR): only read 1 of 4 data items
2021-01-27T15:49:04.051Z,1611762544.051 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for altitude
2021-01-27T15:49:33.485Z,1611762573.485 [DataOverHttps](IMPORTANT): SBD MTMSN=20210127T154933
2021-01-27T15:49:37.504Z,1611762577.504 [DataOverHttps](INFO): Received command:configSet poweronly.loadatstartup 1 bool persist
2021-01-27T15:49:37.858Z,1611762577.858 [CommandLine](IMPORTANT): got command configSet PowerOnly.loadAtStartup 1.000000 bool persist
2021-01-27T15:49:37.859Z,1611762577.859 [CommandLine](IMPORTANT): configSet PowerOnly.loadAtStartup requires a restart to take effect.
2021-01-27T15:50:13.661Z,1611762613.661 [DataOverHttps](IMPORTANT): SBD MTMSN=20210127T155013
2021-01-27T15:50:17.679Z,1611762617.679 [DataOverHttps](INFO): Received command:restart app
2021-01-27T15:50:17.720Z,1611762617.720 [CommandLine](IMPORTANT): got command restart application
2021-01-27T15:50:18.724Z,1611762618.724 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2021-01-27T15:50:18.724Z,1611762618.724 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2021-01-27T15:50:18.725Z,1611762618.725 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-01-27T15:50:18.728Z,1611762618.728 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2021-01-27T15:50:18.728Z,1611762618.728 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-01-27T15:50:18.729Z,1611762618.729 [CommandLine](INFO): Join timeout helper Thread ID is 5017
2021-01-27T15:50:18.730Z,1611762618.730 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2021-01-27T15:50:18.730Z,1611762618.730 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-01-27T15:50:18.730Z,1611762618.730 [NavChartDb](INFO): Join timeout helper Thread ID is 5018
2021-01-27T15:50:18.956Z,1611762618.956 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2021-01-27T15:50:18.956Z,1611762618.956 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-01-27T15:50:18.964Z,1611762618.964 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2021-01-27T15:50:18.964Z,1611762618.964 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2021-01-27T15:50:18.964Z,1611762618.964 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 5019
2021-01-27T15:50:18.976Z,1611762618.976 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2021-01-27T15:50:18.976Z,1611762618.976 [WetLabsSeaOWL_UV_A](INFO): Powering down
2021-01-27T15:50:18.977Z,1611762618.977 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2021-01-27T15:50:18.984Z,1611762618.984 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2021-01-27T15:50:18.984Z,1611762618.984 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-01-27T15:50:18.984Z,1611762618.984 [CTD_Seabird](INFO): Join timeout helper Thread ID is 5020
2021-01-27T15:50:19.253Z,1611762619.253 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting.
2021-01-27T15:50:19.254Z,1611762619.254 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped
2021-01-27T15:50:19.254Z,1611762619.254 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-01-27T15:50:19.254Z,1611762619.254 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1
2021-01-27T15:50:19.580Z,1611762619.580 [CTD_Seabird](INFO): Powering down
2021-01-27T15:50:19.592Z,1611762619.592 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2021-01-27T15:50:19.592Z,1611762619.592 [CTD_Seabird](INFO): Powering down
2021-01-27T15:50:19.604Z,1611762619.604 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-01-27T15:50:19.608Z,1611762619.608 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2021-01-27T15:50:19.608Z,1611762619.608 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-01-27T15:50:19.608Z,1611762619.608 [Radio_Surface](INFO): Join timeout helper Thread ID is 5021
2021-01-27T15:50:19.620Z,1611762619.620 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped
2021-01-27T15:50:19.620Z,1611762619.620 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1
2021-01-27T15:50:19.808Z,1611762619.808 [Radio_Surface](INFO): Powering down
2021-01-27T15:50:19.809Z,1611762619.809 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2021-01-27T15:50:19.809Z,1611762619.809 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-01-27T15:50:19.817Z,1611762619.817 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2021-01-27T15:50:19.818Z,1611762619.818 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-01-27T15:50:19.819Z,1611762619.819 [Onboard](INFO): Join timeout helper Thread ID is 5022
2021-01-27T15:50:20.014Z,1611762620.014 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 18.936485 min 
2021-01-27T15:50:20.014Z,1611762620.014 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped
2021-01-27T15:50:20.014Z,1611762620.014 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn
2021-01-27T15:50:20.014Z,1611762620.014 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped
2021-01-27T15:50:20.014Z,1611762620.014 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn
2021-01-27T15:50:20.015Z,1611762620.015 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #4
2021-01-27T15:50:20.015Z,1611762620.015 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=4
2021-01-27T15:50:20.015Z,1611762620.015 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn
2021-01-27T15:50:20.015Z,1611762620.015 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1
2021-01-27T15:50:20.688Z,1611762620.688 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2021-01-27T15:50:22.032Z,1611762622.032 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155021.00,A,4132.56114,N,07043.09811,W,0.389,247.98,270121,,,D*7C
2021-01-27T15:50:22.034Z,1611762622.034 [NAL9602](INFO): GPS fix at 20210127T155021: (41.542686, -70.718302)
2021-01-27T15:50:22.054Z,1611762622.054 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped
2021-01-27T15:50:22.054Z,1611762622.054 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1
2021-01-27T15:50:23.813Z,1611762623.813 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2021-01-27T15:50:23.814Z,1611762623.814 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-01-27T15:50:23.816Z,1611762623.816 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2021-01-27T15:50:23.817Z,1611762623.817 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-01-27T15:50:23.817Z,1611762623.817 [DataOverHttps](INFO): Join timeout helper Thread ID is 5023
2021-01-27T15:50:24.705Z,1611762624.705 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2021-01-27T15:50:24.705Z,1611762624.705 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-01-27T15:50:24.722Z,1611762624.722 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2021-01-27T15:50:24.722Z,1611762624.722 [logger ThreadHandler](INFO): Thread cancelled.
2021-01-27T15:50:24.723Z,1611762624.723 [logger](INFO): Join timeout helper Thread ID is 5024
2021-01-27T15:50:24.729Z,1611762624.729 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2021-01-27T15:50:24.730Z,1611762624.730 [logger ThreadHandler](INFO): Thread cancelled.
2021-01-27T15:50:24.746Z,1611762624.746 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2021-01-27T15:50:24.746Z,1611762624.746 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-01-27T15:50:24.746Z,1611762624.746 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2021-01-27T15:50:24.747Z,1611762624.747 [controlThread ThreadHandler](INFO): Thread cancelled.
2021-01-27T15:50:24.748Z,1611762624.748 [controlThread](INFO): Join timeout helper Thread ID is 5025
2021-01-27T15:50:24.828Z,1611762624.828 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2021-01-27T15:50:24.828Z,1611762624.828 [controlThread](DEBUG): Uninitializing ControlThread
2021-01-27T15:50:24.829Z,1611762624.829 [AHRS_M2](INFO): Powering down
2021-01-27T15:50:24.900Z,1611762624.900 [DDM](INFO): Powering down
2021-01-27T15:50:24.972Z,1611762624.972 [DUSBL_Hydroid](INFO): Powering down
2021-01-27T15:50:25.044Z,1611762625.044 [Micromodem](INFO): Powering down
2021-01-27T15:50:25.140Z,1611762625.140 [NAL9602](INFO): Powering down
2021-01-27T15:50:25.212Z,1611762625.212 [RDI_Pathfinder](INFO): Powering down
2021-01-27T15:50:25.284Z,1611762625.284 [RDI_PathfinderUp](INFO): Powering down
2021-01-27T15:50:25.285Z,1611762625.285 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2021-01-27T15:50:25.286Z,1611762625.286 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2021-01-27T15:50:25.287Z,1611762625.287 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2021-01-27T15:50:25.287Z,1611762625.287 [MissionManager](INFO): Uninitializing Mission DefaultWithUndock
2021-01-27T15:50:25.287Z,1611762625.287 [DefaultWithUndock] Stopped
2021-01-27T15:50:25.288Z,1611762625.288 [DefaultWithUndock](DEBUG): Aggregate::uninitialize DefaultWithUndock
2021-01-27T15:50:25.288Z,1611762625.288 [DefaultWithUndock:SurfaceDefault] Stopped
2021-01-27T15:50:25.288Z,1611762625.288 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault
2021-01-27T15:50:25.288Z,1611762625.288 [DefaultWithUndock:SurfaceDefault:B.GoToSurface] Stopped
2021-01-27T15:50:25.288Z,1611762625.288 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-01-27T15:50:25.288Z,1611762625.288 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped
2021-01-27T15:50:25.288Z,1611762625.288 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn
2021-01-27T15:50:25.288Z,1611762625.288 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped
2021-01-27T15:50:25.295Z,1611762625.295 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2021-01-27T15:50:25.296Z,1611762625.296 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2021-01-27T15:50:25.296Z,1611762625.296 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2021-01-27T15:50:25.296Z,1611762625.296 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2021-01-27T15:50:25.297Z,1611762625.297 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2021-01-27T15:50:25.297Z,1611762625.297 [BuoyancyServo](INFO): Powering down
2021-01-27T15:50:25.308Z,1611762625.308 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2021-01-27T15:50:25.308Z,1611762625.308 [ElevatorServo](INFO): Powering down
2021-01-27T15:50:25.309Z,1611762625.309 [MassServo](DEBUG): Uninitialize Mass Servo.
2021-01-27T15:50:25.309Z,1611762625.309 [MassServo](INFO): Powering down
2021-01-27T15:50:25.310Z,1611762625.310 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2021-01-27T15:50:25.310Z,1611762625.310 [RudderServo](INFO): Powering down
2021-01-27T15:50:25.310Z,1611762625.310 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2021-01-27T15:50:25.311Z,1611762625.311 [ThrusterServo](INFO): Powering down
2021-01-27T15:50:25.311Z,1611762625.311 [SBIT](DEBUG): Uninitialize SBIT Component.
2021-01-27T15:50:25.312Z,1611762625.312 [IBIT](DEBUG): Uninitialize IBIT Component.
2021-01-27T15:50:25.312Z,1611762625.312 [CBIT](DEBUG): Uninitialize CBIT Component.
2021-01-27T15:50:25.312Z,1611762625.312 [CBIT](DEBUG): Powering off loads.
2021-01-27T15:50:25.324Z,1611762625.324 [CBIT](DEBUG): Disabling WDT.
2021-01-27T15:50:25.336Z,1611762625.336 [CBIT](DEBUG): Opening all GF detection circuits.
2021-01-27T15:50:25.337Z,1611762625.337 [controlThread ThreadHandler](INFO): Thread cancelled.
2021-01-27T15:50:25.357Z,1611762625.357 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-01-27T15:50:25.359Z,1611762625.359 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-01-27T15:50:25.370Z,1611762625.370 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-01-27T15:50:25.457Z,1611762625.457 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2021-01-27T15:50:25.461Z,1611762625.461 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-01-27T15:50:25.523Z,1611762625.523 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-01-27T15:50:25.592Z,1611762625.592 [logger ThreadHandler](INFO): Thread cancelled.