2020-11-11T20:05:17.072Z,1605125117.072 [CommandLine](IMPORTANT): got command restart logs 2020-11-11T20:05:18.009Z,1605125118.009 [DataOverHttps](IMPORTANT): SBD MTMSN=20201111T200516 2020-11-11T20:05:22.027Z,1605125122.027 [DataOverHttps](INFO): Received command:restart logs 2020-11-11T20:07:48.558Z,1605125268.558 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting. 2020-11-11T20:07:48.559Z,1605125268.559 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped 2020-11-11T20:07:48.559Z,1605125268.559 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-11-11T20:07:48.559Z,1605125268.559 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1 2020-11-11T20:07:48.943Z,1605125268.943 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped 2020-11-11T20:07:48.943Z,1605125268.943 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1 2020-11-11T20:07:49.408Z,1605125269.408 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 12.321947 min 2020-11-11T20:07:49.408Z,1605125269.408 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped 2020-11-11T20:07:49.409Z,1605125269.409 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn 2020-11-11T20:07:49.409Z,1605125269.409 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped 2020-11-11T20:07:49.409Z,1605125269.409 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn 2020-11-11T20:07:49.409Z,1605125269.409 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #2 2020-11-11T20:07:49.409Z,1605125269.409 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=2 2020-11-11T20:07:49.409Z,1605125269.409 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2020-11-11T20:07:49.409Z,1605125269.409 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2020-11-11T20:07:51.353Z,1605125271.353 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200750.00,A,4133.13289,N,07042.43476,W,0.194,40.94,111120,,,D*49 2020-11-11T20:07:51.355Z,1605125271.355 [NAL9602](INFO): GPS fix at 20201111T200750: (41.552215, -70.707246) 2020-11-11T20:07:51.505Z,1605125271.505 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2020-11-11T20:07:51.505Z,1605125271.505 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2020-11-11T20:07:57.003Z,1605125277.003 [DataOverHttps](INFO): Sending 51 bytes from file Logs/20201111T172746/Courier0048.lzma 2020-11-11T20:07:58.010Z,1605125278.010 [DataOverHttps](INFO): Moved sent file to Logs/20201111T172746/Courier0048.lzma.bak 2020-11-11T20:07:58.010Z,1605125278.010 [DataOverHttps](INFO): SBD MOMSN=57088 2020-11-11T20:07:59.295Z,1605125279.295 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-11-11T20:08:07.080Z,1605125287.080 [DataOverHttps](INFO): Sending 253 bytes from file Logs/20201111T200517/Courier0000.lzma 2020-11-11T20:08:08.086Z,1605125288.086 [DataOverHttps](INFO): Moved sent file to Logs/20201111T200517/Courier0000.lzma.bak 2020-11-11T20:08:08.086Z,1605125288.086 [DataOverHttps](INFO): SBD MOMSN=57090 2020-11-11T20:08:09.585Z,1605125289.585 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-11-11T20:08:17.127Z,1605125297.127 [DataOverHttps](INFO): Sending 52 bytes from file Logs/20201111T200517/Courier0003.lzma 2020-11-11T20:08:18.130Z,1605125298.130 [DataOverHttps](INFO): Moved sent file to Logs/20201111T200517/Courier0003.lzma.bak 2020-11-11T20:08:18.130Z,1605125298.130 [DataOverHttps](INFO): SBD MOMSN=57094 2020-11-11T20:08:18.715Z,1605125298.715 [NAL9602](INFO): SBD MO Status=2, MOMSN=11977, MT Status=2, MTMSN=0 2020-11-11T20:08:18.715Z,1605125298.715 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-11-11T20:08:27.168Z,1605125307.168 [DataOverHttps](INFO): Sending 92 bytes from file Logs/20201111T172746/Express0049.lzma 2020-11-11T20:08:28.172Z,1605125308.172 [DataOverHttps](INFO): Moved sent file to Logs/20201111T172746/Express0049.lzma.bak 2020-11-11T20:08:28.172Z,1605125308.172 [DataOverHttps](INFO): SBD MOMSN=57096 2020-11-11T20:08:34.500Z,1605125314.500 [NAL9602](INFO): SBD MO Status=2, MOMSN=11977, MT Status=2, MTMSN=0 2020-11-11T20:08:34.500Z,1605125314.500 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-11-11T20:08:37.218Z,1605125317.218 [DataOverHttps](INFO): Sending 388 bytes from file Logs/20201111T200517/Express0001.lzma 2020-11-11T20:08:38.214Z,1605125318.214 [DataOverHttps](INFO): Moved sent file to Logs/20201111T200517/Express0001.lzma.bak 2020-11-11T20:08:38.214Z,1605125318.214 [DataOverHttps](INFO): SBD MOMSN=57099 2020-11-11T20:08:47.247Z,1605125327.247 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20201111T200517/Express0004.lzma 2020-11-11T20:08:48.250Z,1605125328.250 [DataOverHttps](INFO): Moved sent file to Logs/20201111T200517/Express0004.lzma.bak 2020-11-11T20:08:48.250Z,1605125328.250 [DataOverHttps](INFO): SBD MOMSN=57104 2020-11-11T20:08:49.905Z,1605125329.905 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2020-11-11T20:08:49.905Z,1605125329.905 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1 2020-11-11T20:08:49.905Z,1605125329.905 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-11-11T20:08:53.059Z,1605125333.059 [NAL9602](INFO): SBD MO Status=0, MOMSN=11977, MT Status=0, MTMSN=0 2020-11-11T20:08:53.059Z,1605125333.059 [NAL9602](INFO): No messages in MT queue 2020-11-11T20:09:23.762Z,1605125363.762 [NAL9602](INFO): Not Powering down - fast GPS 2020-11-11T20:12:25.176Z,1605125545.176 [DataOverHttps](IMPORTANT): SBD MTMSN=20201111T201223 2020-11-11T20:12:29.199Z,1605125549.199 [DataOverHttps](INFO): Received command:sched asap "load Science/profile_station.xml;set profile_station.MissionTimeout 1 hour;set profile_station.Lat 41.552317 degree;set profile_station.Lon -70.7086 degree;set profile_station.YoYoMinDepth 3 meter" 405fz 1 3 2020-11-11T20:12:29.223Z,1605125549.223 [CommandLine](IMPORTANT): got command schedule asap "load Science/profile_station.xml;set profile_station.MissionTimeout 1 hour;set profile_station.Lat 41.552317 degree;set profile_station.Lon -70.7086 degree;set profile_station.YoYoMinDepth 3 meter" 405fz 1 3.000000 2020-11-11T20:12:29.224Z,1605125549.224 [CommandLine](IMPORTANT): Scheduling command #1 of 3 with id=405fz 2020-11-11T20:12:29.226Z,1605125549.226 [CommandLine](IMPORTANT): Scheduled #28 (#1 of 3 with id='405fz'): "load Science/profile_station.xml;set profile_station.MissionTimeout 1 hour;set profile_station.Lat 41.552317 degree;set profile_station.Lon -70.7086 degree;set profile_station.YoYoMinDepth 3 meter" ASAP 2020-11-11T20:12:30.197Z,1605125550.197 [DataOverHttps](IMPORTANT): SBD MTMSN=20201111T201228 2020-11-11T20:12:34.220Z,1605125554.220 [DataOverHttps](INFO): Received command:sched asap "set profile_station.YoYoMaxDepth 7 meter;set profile_station.YoYoMinAltitude 3 meter;set profile_station.YoYoPitch 15 degree;set profile_station.MinAltitude 3 meter;set profile_station.MaxDepth 9 meter" 405fz 2 3 2020-11-11T20:12:34.238Z,1605125554.238 [CommandLine](IMPORTANT): got command schedule asap "set profile_station.YoYoMaxDepth 7 meter;set profile_station.YoYoMinAltitude 3 meter;set profile_station.YoYoPitch 15 degree;set profile_station.MinAltitude 3 meter;set profile_station.MaxDepth 9 meter" 405fz 2 3.000000 2020-11-11T20:12:34.239Z,1605125554.239 [CommandLine](IMPORTANT): Scheduling command #2 of 3 with id=405fz 2020-11-11T20:12:34.239Z,1605125554.239 [CommandLine](IMPORTANT): Scheduled #29 (#2 of 3 with id='405fz'): "set profile_station.YoYoMaxDepth 7 meter;set profile_station.YoYoMinAltitude 3 meter;set profile_station.YoYoPitch 15 degree;set profile_station.MinAltitude 3 meter;set profile_station.MaxDepth 9 meter" ASAP 2020-11-11T20:12:35.216Z,1605125555.216 [DataOverHttps](IMPORTANT): SBD MTMSN=20201111T201233 2020-11-11T20:12:39.232Z,1605125559.232 [DataOverHttps](INFO): Received command:sched asap "set profile_station.MinOffshore 500 meter;run" 405fz 3 3 2020-11-11T20:12:39.289Z,1605125559.289 [CommandLine](IMPORTANT): got command schedule asap "set profile_station.MinOffshore 500 meter;run" 405fz 3 3.000000 2020-11-11T20:12:39.289Z,1605125559.289 [CommandLine](IMPORTANT): Scheduling command #3 of 3 with id=405fz 2020-11-11T20:12:39.290Z,1605125559.290 [CommandLine](IMPORTANT): Scheduled #30 (#3 of 3 with id='405fz'): "set profile_station.MinOffshore 500 meter;run" ASAP 2020-11-11T20:12:39.387Z,1605125559.387 [CommandLine](IMPORTANT): got command load ./Missions/Science/profile_station.xml 2020-11-11T20:12:39.394Z,1605125559.394 [MissionManager](INFO): Loading Mission: ./Missions/Science/profile_station.xml 2020-11-11T20:12:39.514Z,1605125559.514 [MissionManager](INFO): DefineArg profile_station.MissionTimeout = 4.000000 h 2020-11-11T20:12:39.517Z,1605125559.517 [MissionManager](INFO): DefineArg profile_station.NeedCommsTime = 60.000000 min 2020-11-11T20:12:39.519Z,1605125559.519 [MissionManager](INFO): DefineArg profile_station.Lat = 36.806966 arcdeg 2020-11-11T20:12:39.521Z,1605125559.521 [MissionManager](INFO): DefineArg profile_station.Lon = -121.824326 arcdeg 2020-11-11T20:12:39.523Z,1605125559.523 [MissionManager](INFO): DefineArg profile_station.Radius = 300.000000 m 2020-11-11T20:12:39.526Z,1605125559.526 [MissionManager](INFO): DefineArg profile_station.YoYoMinDepth = 2.000000 m 2020-11-11T20:12:39.528Z,1605125559.528 [MissionManager](INFO): DefineArg profile_station.YoYoMaxDepth = 200.000000 m 2020-11-11T20:12:39.530Z,1605125559.530 [MissionManager](INFO): DefineArg profile_station.YoYoMinAltitude = 7.000000 m 2020-11-11T20:12:39.532Z,1605125559.532 [MissionManager](INFO): DefineArg profile_station.YoYoPitch = 20.000000 arcdeg 2020-11-11T20:12:39.534Z,1605125559.534 [MissionManager](INFO): DefineArg profile_station.Speed = 1.000000 m/s 2020-11-11T20:12:39.537Z,1605125559.537 [MissionManager](INFO): DefineArg profile_station.CircleMaxError = 100.000000 m 2020-11-11T20:12:39.539Z,1605125559.539 [MissionManager](INFO): DefineArg profile_station.CircleTurnToPort = 0 bool 2020-11-11T20:12:39.541Z,1605125559.541 [MissionManager](INFO): DefineArg profile_station.KwpHeading = 0.010000 rad/m 2020-11-11T20:12:39.543Z,1605125559.543 [MissionManager](INFO): DefineArg profile_station.MinAltitude = 5.000000 m 2020-11-11T20:12:39.545Z,1605125559.545 [MissionManager](INFO): DefineArg profile_station.MaxDepth = 205.000000 m 2020-11-11T20:12:39.547Z,1605125559.547 [MissionManager](INFO): DefineArg profile_station.MinOffshore = 2.000000 km 2020-11-11T20:12:39.548Z,1605125559.548 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml 2020-11-11T20:12:39.891Z,1605125559.891 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectChlActive = 0 bool 2020-11-11T20:12:39.893Z,1605125559.893 [MissionManager](INFO): DefineArg profile_station:Science.TimeWindowPeakReport = nan min 2020-11-11T20:12:39.895Z,1605125559.895 [MissionManager](INFO): DefineArg profile_station:Science.HighestChlPeakReportActive = 0 bool 2020-11-11T20:12:39.897Z,1605125559.897 [MissionManager](INFO): DefineArg profile_station:Science.PatchTracking = 0 bool 2020-11-11T20:12:39.899Z,1605125559.899 [MissionManager](INFO): DefineArg profile_station:Science.FilterWidthHorizontal = 3.000000 count 2020-11-11T20:12:39.901Z,1605125559.901 [MissionManager](INFO): DefineArg profile_station:Science.NumProfilesSlidingwindow = 100.000000 count 2020-11-11T20:12:39.903Z,1605125559.903 [MissionManager](INFO): DefineArg profile_station:Science.OffPeakFractionHorizontal = 80.000000 % 2020-11-11T20:12:39.905Z,1605125559.905 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectNO3Active = 0 bool 2020-11-11T20:12:39.907Z,1605125559.907 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectOilActive = 0 bool 2020-11-11T20:12:39.910Z,1605125559.910 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectFDOMActive = 0 bool 2020-11-11T20:12:39.912Z,1605125559.912 [MissionManager](INFO): DefineArg profile_station:Science.UpwardDerivativeOfTemperatureActive = 0 bool 2020-11-11T20:12:39.914Z,1605125559.914 [MissionManager](INFO): DefineArg profile_station:Science.EnabledAanderaaO2 = 0.000000 bool 2020-11-11T20:12:39.917Z,1605125559.917 [MissionManager](INFO): DefineArg profile_station:Science.EnabledNeilBrown = 0.000000 bool 2020-11-11T20:12:39.919Z,1605125559.919 [MissionManager](INFO): DefineArg profile_station:Science.EnabledSeabird = 1.000000 bool 2020-11-11T20:12:39.921Z,1605125559.921 [MissionManager](INFO): DefineArg profile_station:Science.EnabledWetLabsBB2FL = 0.000000 bool 2020-11-11T20:12:39.924Z,1605125559.924 [MissionManager](INFO): DefineArg profile_station:Science.EnabledWetLabsSeaOWL_UV_A = 1.000000 bool 2020-11-11T20:12:39.926Z,1605125559.926 [MissionManager](INFO): DefineArg profile_station:Science.EnabledWetLabsUBAT = 0.000000 bool 2020-11-11T20:12:39.960Z,1605125559.960 [MissionManager](INFO): DefineArg profile_station:Science.LowPassWindowLength = 20.000000 count 2020-11-11T20:12:39.963Z,1605125559.963 [MissionManager](INFO): DefineArg profile_station:Science.MedianFilterLen = 5.000000 count 2020-11-11T20:12:39.966Z,1605125559.966 [MissionManager](INFO): DefineArg profile_station:Science.PeakChlShallowBound = nan m 2020-11-11T20:12:39.980Z,1605125559.980 [MissionManager](INFO): DefineArg profile_station:Science.PeakChlDeepBound = nan m 2020-11-11T20:12:39.982Z,1605125559.982 [MissionManager](INFO): DefineArg profile_station:Science.PeakShallowBound = nan m 2020-11-11T20:12:39.989Z,1605125559.989 [MissionManager](INFO): DefineArg profile_station:Science.PeakDeepBound = nan m 2020-11-11T20:12:39.993Z,1605125559.993 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChl = 0.000000 ug/l 2020-11-11T20:12:39.995Z,1605125559.995 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlDepth = 0.000000 m 2020-11-11T20:12:39.998Z,1605125559.998 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlTemperature = 0.000000 degC 2020-11-11T20:12:40.004Z,1605125560.004 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlLatitude = nan arcdeg 2020-11-11T20:12:40.006Z,1605125560.006 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlLongitude = nan arcdeg 2020-11-11T20:12:40.013Z,1605125560.013 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3 = 0.000000 umol/l 2020-11-11T20:12:40.015Z,1605125560.015 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3Depth = 0.000000 m 2020-11-11T20:12:40.018Z,1605125560.018 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3Latitude = nan arcdeg 2020-11-11T20:12:40.032Z,1605125560.032 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3Longitude = nan arcdeg 2020-11-11T20:12:40.034Z,1605125560.034 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChl = 0.000000 m 2020-11-11T20:12:40.048Z,1605125560.048 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlDepth = 0.000000 m 2020-11-11T20:12:40.051Z,1605125560.051 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlLatitude = nan arcdeg 2020-11-11T20:12:40.055Z,1605125560.055 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlLongitude = nan arcdeg 2020-11-11T20:12:40.066Z,1605125560.066 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlDistance = nan m 2020-11-11T20:12:40.072Z,1605125560.072 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOil = 0.000000 kg/m3 2020-11-11T20:12:40.074Z,1605125560.074 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOilDepth = 0.000000 m 2020-11-11T20:12:40.081Z,1605125560.081 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOilLatitude = nan arcdeg 2020-11-11T20:12:40.083Z,1605125560.083 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOilLongitude = nan arcdeg 2020-11-11T20:12:40.125Z,1605125560.125 [MissionManager](INFO): DefineOutput profile_station:Science.PeakFDOM = 0.000000 ppb 2020-11-11T20:12:40.136Z,1605125560.136 [MissionManager](INFO): DefineOutput profile_station:Science.PeakFDOMDepth = 0.000000 m 2020-11-11T20:12:40.138Z,1605125560.138 [MissionManager](INFO): DefineOutput profile_station:Science.PeakFDOMLatitude = nan arcdeg 2020-11-11T20:12:40.164Z,1605125560.164 [MissionManager](INFO): DefineOutput profile_station:Science.PeakFDOMLongitude = nan arcdeg 2020-11-11T20:12:40.166Z,1605125560.166 [MissionManager](INFO): DefineOutput profile_station:Science.PatchFDOMDistance = nan m 2020-11-11T20:12:40.245Z,1605125560.245 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2020-11-11T20:12:40.278Z,1605125560.278 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal. 2020-11-11T20:12:40.306Z,1605125560.306 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2020-11-11T20:12:40.327Z,1605125560.327 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2020-11-11T20:12:40.357Z,1605125560.357 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2020-11-11T20:12:40.375Z,1605125560.375 [MissionManager](INFO): RedefineArg profile_station:Science.PeakDetectChlActive = value:1 bool 2020-11-11T20:12:40.375Z,1605125560.375 [MissionManager](INFO): Inserting Stack: Missions/Insert/NeedComms.xml 2020-11-11T20:12:40.592Z,1605125560.592 [MissionManager](INFO): DefineArg profile_station:NeedComms.DiveInterval = 3.000000 h 2020-11-11T20:12:40.594Z,1605125560.594 [MissionManager](INFO): DefineArg profile_station:NeedComms.WaitForPitchUp = 10.000000 min 2020-11-11T20:12:40.608Z,1605125560.608 [MissionManager](INFO): DefineArg profile_station:NeedComms.SurfacePitch = 20.000000 arcdeg 2020-11-11T20:12:40.610Z,1605125560.610 [MissionManager](INFO): DefineArg profile_station:NeedComms.SurfaceDepthRate = nan m/s 2020-11-11T20:12:40.624Z,1605125560.624 [MissionManager](INFO): DefineArg profile_station:NeedComms.SurfaceSpeed = 1.000000 m/s 2020-11-11T20:12:40.626Z,1605125560.626 [MissionManager](INFO): DefineArg profile_station:NeedComms.GPSTimeout = 7.000000 min 2020-11-11T20:12:40.629Z,1605125560.629 [MissionManager](INFO): DefineArg profile_station:NeedComms.CommsTimeout = 30.000000 min 2020-11-11T20:12:40.649Z,1605125560.649 [profile_station:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface. 2020-11-11T20:12:40.673Z,1605125560.673 [MissionManager](INFO): Inserting Stack: Missions/Insert/StandardEnvelopes.xml 2020-11-11T20:12:40.701Z,1605125560.701 [MissionManager](INFO): DefineArg profile_station:StandardEnvelopes.MinAltitude = 5.000000 m 2020-11-11T20:12:40.703Z,1605125560.703 [MissionManager](INFO): DefineArg profile_station:StandardEnvelopes.MaxDepth = 200.000000 m 2020-11-11T20:12:40.713Z,1605125560.713 [MissionManager](INFO): DefineArg profile_station:StandardEnvelopes.MinOffshore = 2000.000000 m 2020-11-11T20:12:40.714Z,1605125560.714 [profile_station:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2020-11-11T20:12:40.730Z,1605125560.730 [profile_station:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2020-11-11T20:12:40.748Z,1605125560.748 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2020-11-11T20:12:40.767Z,1605125560.767 [profile_station:I.Pitch](DEBUG): Construct. 2020-11-11T20:12:40.788Z,1605125560.788 [profile_station:J.Buoyancy](DEBUG): Construct Buoyancy. 2020-11-11T20:12:40.791Z,1605125560.791 [profile_station:K.SetSpeed](DEBUG): Construct. 2020-11-11T20:12:40.805Z,1605125560.805 [profile_station:L.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2020-11-11T20:12:40.818Z,1605125560.818 [profile_station:M.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2020-11-11T20:12:40.824Z,1605125560.824 [profile_station:N.YoYo](DEBUG): Construct YoYo. 2020-11-11T20:12:40.831Z,1605125560.831 [profile_station:CircleWrapper:B.Circle](DEBUG): Construct. 2020-11-11T20:12:40.864Z,1605125560.864 [MissionManager](DEBUG): This mission yoyos in a circle around a specified location. Maximum duration of mission 4 Elapsed time after previous surface communications when vehicle will begin to ascend for additional surface communications 60 The latitude of the center of the circle. 36.806966 The longitude of the center of the circle. -121.824326 Radius to circle at 300 Minimum depth while performing the YoYo behavior. 2 Maximum depth while performing the YoYo behavior. 200 Minimum altitude while performing the YoYo behavior (for bottom-terminated YoYos). 7 Pitch (plus and minus) for yo-yo behavior. 20 Vehicle speed. 1 If this distance away from the circle, drive straight towards (or away from the center). Otherwise, try to reduce distance from the ideal circle. 100 If true, vehicle turns to the left around the center point. If false, vehicle turns to the right. Used to relax waypoint cross-track error constant that is adjusted for docking. (You can override this setting by passing an argument.) 0.010 Minimum height above the sea floor for the entire mission. 5 Maximum depth for the entire mission. 205 Minimum offshore distance for the entire mission. 2 Most missions will run the science sensors. If you don't place this aggregate above NeedComms, science instruments get turned off on the last upcast and while floating on the surface. Another depth envelope for the YoYo behavior. This envelope should fall within the limits of the standard safety envelopes in Insert/StandardEnvelopes.xml in order to avoid commanding high pitch angles for depth-terminated YoYos. Another altitude envelope for the YoYo behavior. This envelope should fall within the limits of the standard safety envelopes in Insert/StandardEnvelopes.xml in order to avoid commanding high pitch angles for bottom-terminated YoYos. 2020-11-11T20:12:40.864Z,1605125560.864 [CommandLine](IMPORTANT): Loaded ./Missions/Science/profile_station.xml 2020-11-11T20:12:53.390Z,1605125573.390 [CommandLine](IMPORTANT): got command set profile_station.MissionTimeout 1.000000 hour 2020-11-11T20:12:53.391Z,1605125573.391 [CommandLine](IMPORTANT): got command set profile_station.Lat 41.552319 degree 2020-11-11T20:12:53.392Z,1605125573.392 [CommandLine](IMPORTANT): got command set profile_station.Lon -70.708603 degree 2020-11-11T20:12:53.393Z,1605125573.393 [CommandLine](IMPORTANT): got command set profile_station.YoYoMinDepth 3.000000 meter 2020-11-11T20:12:54.670Z,1605125574.670 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2020-11-11T20:13:10.878Z,1605125590.878 [CommandLine](IMPORTANT): got command set profile_station.YoYoMaxDepth 7.000000 meter 2020-11-11T20:13:10.878Z,1605125590.878 [CommandLine](IMPORTANT): got command set profile_station.YoYoMinAltitude 3.000000 meter 2020-11-11T20:13:10.879Z,1605125590.879 [CommandLine](IMPORTANT): got command set profile_station.YoYoPitch 15.000000 degree 2020-11-11T20:13:10.884Z,1605125590.884 [CommandLine](IMPORTANT): got command set profile_station.MinAltitude 3.000000 meter 2020-11-11T20:13:10.885Z,1605125590.885 [CommandLine](IMPORTANT): got command set profile_station.MaxDepth 9.000000 meter 2020-11-11T20:13:14.674Z,1605125594.674 [CommandLine](IMPORTANT): got command set profile_station.MinOffshore 500.000000 meter 2020-11-11T20:13:14.674Z,1605125594.674 [CommandLine](IMPORTANT): got command run 2020-11-11T20:13:14.676Z,1605125594.676 [CommandLine](IMPORTANT): Running 2020-11-11T20:13:14.884Z,1605125594.884 [DefaultWithUndock] Stopped 2020-11-11T20:13:14.884Z,1605125594.884 [DefaultWithUndock](DEBUG): Aggregate::uninitialize DefaultWithUndock 2020-11-11T20:13:14.884Z,1605125594.884 [DefaultWithUndock:SurfaceDefault] Stopped 2020-11-11T20:13:14.884Z,1605125594.884 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault 2020-11-11T20:13:14.884Z,1605125594.884 [DefaultWithUndock:SurfaceDefault:B.GoToSurface] Stopped 2020-11-11T20:13:14.884Z,1605125594.884 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2020-11-11T20:13:14.884Z,1605125594.884 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped 2020-11-11T20:13:14.885Z,1605125594.885 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn 2020-11-11T20:13:14.885Z,1605125594.885 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped 2020-11-11T20:13:14.885Z,1605125594.885 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-11-11T20:13:14.885Z,1605125594.885 [MissionManager](IMPORTANT): Started mission profile_station 2020-11-11T20:13:14.885Z,1605125594.885 [profile_station] Running Loop=1 2020-11-11T20:13:14.885Z,1605125594.885 [profile_station](DEBUG): Aggregate::initialize profile_station 2020-11-11T20:13:14.885Z,1605125594.885 [profile_station:Science] Running Loop=1 2020-11-11T20:13:14.885Z,1605125594.885 [profile_station:Science](DEBUG): Aggregate::initialize profile_station:Science 2020-11-11T20:13:14.885Z,1605125594.885 [profile_station:Science:A] Running Loop=1 2020-11-11T20:13:14.886Z,1605125594.886 [profile_station:Science:B] Running Loop=1 2020-11-11T20:13:14.886Z,1605125594.886 [profile_station:Science:C] Running Loop=1 2020-11-11T20:13:14.886Z,1605125594.886 [profile_station:Science:D] Running Loop=1 2020-11-11T20:13:14.886Z,1605125594.886 [profile_station:Science:E] Running Loop=1 2020-11-11T20:13:14.886Z,1605125594.886 [profile_station:Science:F] Running Loop=1 2020-11-11T20:13:14.886Z,1605125594.886 [profile_station:Science:Read_Oil] Running Loop=1 2020-11-11T20:13:14.886Z,1605125594.886 [profile_station:Science:PeakDetectChl] Running Loop=1 2020-11-11T20:13:14.886Z,1605125594.886 [profile_station:Science:PeakDetectChl](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectChl 2020-11-11T20:13:14.886Z,1605125594.886 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1 2020-11-11T20:13:14.886Z,1605125594.886 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize. 2020-11-11T20:13:14.887Z,1605125594.887 [profile_station:Science:HighestChlPeakReport] Running Loop=1 2020-11-11T20:13:14.887Z,1605125594.887 [profile_station:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize profile_station:Science:HighestChlPeakReport 2020-11-11T20:13:14.888Z,1605125594.888 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1 2020-11-11T20:13:14.888Z,1605125594.888 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2020-11-11T20:13:14.888Z,1605125594.888 [profile_station:Science:PeakDetectNO3] Running Loop=1 2020-11-11T20:13:14.888Z,1605125594.888 [profile_station:Science:PeakDetectNO3](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectNO3 2020-11-11T20:13:14.889Z,1605125594.889 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1 2020-11-11T20:13:14.889Z,1605125594.889 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize. 2020-11-11T20:13:14.890Z,1605125594.890 [profile_station:Science:PeakDetectOil] Running Loop=1 2020-11-11T20:13:14.890Z,1605125594.890 [profile_station:Science:PeakDetectOil](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectOil 2020-11-11T20:13:14.890Z,1605125594.890 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1 2020-11-11T20:13:14.890Z,1605125594.890 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize. 2020-11-11T20:13:14.891Z,1605125594.891 [profile_station:Science:PeakDetectFDOM] Running Loop=1 2020-11-11T20:13:14.891Z,1605125594.891 [profile_station:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectFDOM 2020-11-11T20:13:14.891Z,1605125594.891 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1 2020-11-11T20:13:14.891Z,1605125594.891 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize. 2020-11-11T20:13:14.893Z,1605125594.893 [profile_station:Science:OceanCurrent] Running Loop=1 2020-11-11T20:13:14.893Z,1605125594.893 [profile_station:Science:OceanCurrent](DEBUG): Aggregate::initialize profile_station:Science:OceanCurrent 2020-11-11T20:13:14.893Z,1605125594.893 [profile_station:Science:OceanCurrent:A.] Running Loop=1 2020-11-11T20:13:14.893Z,1605125594.893 [profile_station:Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator. 2020-11-11T20:13:14.893Z,1605125594.893 [profile_station:StandardEnvelopes] Running Loop=1 2020-11-11T20:13:14.893Z,1605125594.893 [profile_station:StandardEnvelopes](DEBUG): Aggregate::initialize profile_station:StandardEnvelopes 2020-11-11T20:13:14.893Z,1605125594.893 [profile_station:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2020-11-11T20:13:14.893Z,1605125594.893 [profile_station:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2020-11-11T20:13:14.893Z,1605125594.893 [profile_station:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2020-11-11T20:13:14.894Z,1605125594.894 [profile_station:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2020-11-11T20:13:14.894Z,1605125594.894 [profile_station:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2020-11-11T20:13:14.894Z,1605125594.894 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2020-11-11T20:13:14.894Z,1605125594.894 [profile_station:I.Pitch] Running Loop=1 2020-11-11T20:13:14.894Z,1605125594.894 [profile_station:I.Pitch](DEBUG): Initialize. 2020-11-11T20:13:14.894Z,1605125594.894 [profile_station:J.Buoyancy] Running Loop=1 2020-11-11T20:13:14.894Z,1605125594.894 [profile_station:J.Buoyancy](DEBUG): Initialize Buoyancy Component. 2020-11-11T20:13:14.894Z,1605125594.894 [profile_station:K.SetSpeed] Running Loop=1 2020-11-11T20:13:14.894Z,1605125594.894 [profile_station:K.SetSpeed](DEBUG): Initialize. 2020-11-11T20:13:14.894Z,1605125594.894 [profile_station:L.DepthEnvelope] Running Loop=1 2020-11-11T20:13:14.894Z,1605125594.894 [profile_station:L.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2020-11-11T20:13:14.895Z,1605125594.895 [profile_station:M.AltitudeEnvelope] Running Loop=1 2020-11-11T20:13:14.895Z,1605125594.895 [profile_station:M.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2020-11-11T20:13:14.895Z,1605125594.895 [profile_station:N.YoYo] Running Loop=1 2020-11-11T20:13:14.895Z,1605125594.895 [profile_station:N.YoYo](DEBUG): Initialize YoYoComponent. 2020-11-11T20:13:14.895Z,1605125594.895 [profile_station:C] Running Loop=1 2020-11-11T20:13:14.896Z,1605125594.896 [profile_station:N.YoYo] Running Loop=1 2020-11-11T20:13:14.896Z,1605125594.896 [profile_station:M.AltitudeEnvelope] Running Loop=1 2020-11-11T20:13:14.897Z,1605125594.897 [profile_station:L.DepthEnvelope] Running Loop=1 2020-11-11T20:13:14.897Z,1605125594.897 [profile_station:K.SetSpeed] Running Loop=1 2020-11-11T20:13:14.897Z,1605125594.897 [profile_station:J.Buoyancy] Running Loop=1 2020-11-11T20:13:14.898Z,1605125594.898 [profile_station:I.Pitch] Running Loop=1 2020-11-11T20:13:14.898Z,1605125594.898 [profile_station:StandardEnvelopes] Running Loop=1 2020-11-11T20:13:14.898Z,1605125594.898 [profile_station:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2020-11-11T20:13:14.898Z,1605125594.898 [profile_station:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2020-11-11T20:13:14.898Z,1605125594.898 [profile_station:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2020-11-11T20:13:14.899Z,1605125594.899 [profile_station:C] Stopped 2020-11-11T20:13:14.899Z,1605125594.899 [profile_station:E] Running Loop=1 2020-11-11T20:13:14.899Z,1605125594.899 [profile_station:Science] Running Loop=1 2020-11-11T20:13:14.900Z,1605125594.900 [profile_station:Science:PeakDetectFDOM] Stopped 2020-11-11T20:13:14.900Z,1605125594.900 [profile_station:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectFDOM 2020-11-11T20:13:14.901Z,1605125594.901 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped 2020-11-11T20:13:14.901Z,1605125594.901 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2020-11-11T20:13:14.901Z,1605125594.901 [profile_station:Science:PeakDetectOil] Stopped 2020-11-11T20:13:14.901Z,1605125594.901 [profile_station:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectOil 2020-11-11T20:13:14.901Z,1605125594.901 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped 2020-11-11T20:13:14.901Z,1605125594.901 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2020-11-11T20:13:14.901Z,1605125594.901 [profile_station:Science:PeakDetectNO3] Stopped 2020-11-11T20:13:14.901Z,1605125594.901 [profile_station:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectNO3 2020-11-11T20:13:14.901Z,1605125594.901 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped 2020-11-11T20:13:14.901Z,1605125594.901 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2020-11-11T20:13:14.901Z,1605125594.901 [profile_station:Science:HighestChlPeakReport] Stopped 2020-11-11T20:13:14.901Z,1605125594.901 [profile_station:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize profile_station:Science:HighestChlPeakReport 2020-11-11T20:13:14.901Z,1605125594.901 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped 2020-11-11T20:13:14.902Z,1605125594.902 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2020-11-11T20:13:14.902Z,1605125594.902 [profile_station:Science:PeakDetectChl] Running Loop=1 2020-11-11T20:13:14.902Z,1605125594.902 [profile_station:Science:OceanCurrent:A.] Running Loop=1 2020-11-11T20:13:14.903Z,1605125594.903 [profile_station:Science:Read_Oil] Running Loop=1 2020-11-11T20:13:14.903Z,1605125594.903 [profile_station:Science:Read_Oil](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water 2020-11-11T20:13:14.903Z,1605125594.903 [profile_station:Science:F] Stopped 2020-11-11T20:13:14.903Z,1605125594.903 [profile_station:Science:E] Running Loop=1 2020-11-11T20:13:14.904Z,1605125594.904 [profile_station:Science:E](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water 2020-11-11T20:13:14.904Z,1605125594.904 [profile_station:Science:D] Stopped 2020-11-11T20:13:14.904Z,1605125594.904 [profile_station:Science:C] Running Loop=1 2020-11-11T20:13:14.905Z,1605125594.905 [profile_station:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature 2020-11-11T20:13:14.905Z,1605125594.905 [profile_station:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity 2020-11-11T20:13:14.905Z,1605125594.905 [profile_station:Science:B] Running Loop=1 2020-11-11T20:13:14.906Z,1605125594.906 [profile_station:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature 2020-11-11T20:13:14.906Z,1605125594.906 [profile_station:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity 2020-11-11T20:13:14.906Z,1605125594.906 [profile_station:Science:A] Stopped 2020-11-11T20:13:15.264Z,1605125595.264 [WetLabsSeaOWL_UV_A](INFO): Powering up 2020-11-11T20:13:15.302Z,1605125595.302 [profile_station:E] Stopped 2020-11-11T20:13:15.302Z,1605125595.302 [profile_station:F] Running Loop=1 2020-11-11T20:13:15.716Z,1605125595.716 [profile_station:F] Stopped 2020-11-11T20:13:15.716Z,1605125595.716 [profile_station:G] Running Loop=1 2020-11-11T20:13:16.094Z,1605125596.094 [profile_station:G] Stopped 2020-11-11T20:13:16.095Z,1605125596.095 [profile_station:StartingMission] Running Loop=1 2020-11-11T20:13:16.095Z,1605125596.095 [profile_station:StartingMission](DEBUG): Aggregate::initialize profile_station:StartingMission 2020-11-11T20:13:16.551Z,1605125596.551 [profile_station:NeedComms] Running Loop=1 2020-11-11T20:13:16.551Z,1605125596.551 [profile_station:NeedComms](DEBUG): Aggregate::initialize profile_station:NeedComms 2020-11-11T20:13:16.551Z,1605125596.551 [profile_station:NeedComms:B.GoToSurface] Running Loop=1 2020-11-11T20:13:16.551Z,1605125596.551 [profile_station:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2020-11-11T20:13:16.551Z,1605125596.551 [profile_station:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2020-11-11T20:13:16.556Z,1605125596.556 [profile_station:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2020-11-11T20:13:16.557Z,1605125596.557 [profile_station:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2020-11-11T20:13:16.557Z,1605125596.557 [profile_station:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2020-11-11T20:13:16.557Z,1605125596.557 [profile_station:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2020-11-11T20:13:16.558Z,1605125596.558 [profile_station:NeedComms:A] Running Loop=1 2020-11-11T20:13:16.559Z,1605125596.559 [profile_station:NeedComms:A](INFO): last time_fix was: 1605125270.000000 second since 1970/01/01T00:00:00Z 2020-11-11T20:13:16.559Z,1605125596.559 [profile_station:NeedComms:A] Stopped 2020-11-11T20:13:16.921Z,1605125596.921 [profile_station:NeedComms:C] Running Loop=1 2020-11-11T20:13:17.343Z,1605125597.343 [profile_station:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix 2020-11-11T20:13:18.801Z,1605125598.801 [CTD_Seabird](ERROR): Failed to parse device response: 2020-11-11T20:13:18.908Z,1605125598.908 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201317.00,A,4133.15995,N,07042.37853,W,0.272,48.75,111120,,,D*4B 2020-11-11T20:13:18.910Z,1605125598.910 [NAL9602](INFO): GPS fix at 20201111T201317: (41.552666, -70.706309) 2020-11-11T20:13:18.961Z,1605125598.961 [profile_station:NeedComms:C] Stopped 2020-11-11T20:13:18.961Z,1605125598.961 [profile_station:NeedComms:D] Running Loop=1 2020-11-11T20:13:19.359Z,1605125599.359 [profile_station:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications 2020-11-11T20:13:22.431Z,1605125602.431 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20201111T200517/Courier0006.lzma 2020-11-11T20:13:23.434Z,1605125603.434 [DataOverHttps](INFO): Moved sent file to Logs/20201111T200517/Courier0006.lzma.bak 2020-11-11T20:13:23.434Z,1605125603.434 [DataOverHttps](INFO): SBD MOMSN=57111 2020-11-11T20:13:24.594Z,1605125604.594 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items 2020-11-11T20:13:28.604Z,1605125608.604 [NAL9602](INFO): SBD MO Status=0, MOMSN=11978, MT Status=0, MTMSN=0 2020-11-11T20:13:28.604Z,1605125608.604 [NAL9602](INFO): No messages in MT queue 2020-11-11T20:13:32.482Z,1605125612.482 [DataOverHttps](INFO): Sending 867 bytes from file Logs/20201111T200517/Express0007.lzma 2020-11-11T20:13:33.477Z,1605125613.477 [DataOverHttps](INFO): Moved sent file to Logs/20201111T200517/Express0007.lzma.bak 2020-11-11T20:13:33.477Z,1605125613.477 [DataOverHttps](INFO): SBD MOMSN=57114 2020-11-11T20:13:35.123Z,1605125615.123 [profile_station:NeedComms:D] Stopped 2020-11-11T20:13:35.132Z,1605125615.132 [profile_station:NeedComms:E] Running Loop=1 2020-11-11T20:13:35.598Z,1605125615.598 [profile_station:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix 2020-11-11T20:13:37.107Z,1605125617.107 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201335.00,A,4133.16165,N,07042.37474,W,0.719,55.64,111120,,,D*42 2020-11-11T20:13:37.114Z,1605125617.114 [NAL9602](INFO): GPS fix at 20201111T201335: (41.552694, -70.706246) 2020-11-11T20:13:37.257Z,1605125617.257 [profile_station:NeedComms:E] Stopped 2020-11-11T20:13:37.257Z,1605125617.257 [profile_station:NeedComms](INFO): Completed profile_station:NeedComms 2020-11-11T20:13:37.257Z,1605125617.257 [profile_station:NeedComms] Stopped 2020-11-11T20:13:37.258Z,1605125617.258 [profile_station:NeedComms](DEBUG): Aggregate::uninitialize profile_station:NeedComms 2020-11-11T20:13:37.258Z,1605125617.258 [profile_station:NeedComms:B.GoToSurface] Stopped 2020-11-11T20:13:37.258Z,1605125617.258 [profile_station:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2020-11-11T20:13:37.570Z,1605125617.570 [profile_station:StartingMission](INFO): Completed profile_station:StartingMission 2020-11-11T20:13:37.570Z,1605125617.570 [profile_station:StartingMission] Stopped 2020-11-11T20:13:37.570Z,1605125617.570 [profile_station:StartingMission](DEBUG): Aggregate::uninitialize profile_station:StartingMission 2020-11-11T20:13:37.571Z,1605125617.571 [profile_station:CircleWrapper] Running Loop=1 2020-11-11T20:13:37.571Z,1605125617.571 [profile_station:CircleWrapper](DEBUG): Aggregate::initialize profile_station:CircleWrapper 2020-11-11T20:13:37.571Z,1605125617.571 [profile_station:CircleWrapper:A] Running Loop=1 2020-11-11T20:13:37.571Z,1605125617.571 [profile_station:CircleWrapper:B.Circle] Running Loop=1 2020-11-11T20:13:37.571Z,1605125617.571 [profile_station:CircleWrapper:B.Circle](DEBUG): Initialize CircleComponent. 2020-11-11T20:13:37.964Z,1605125617.964 [profile_station:CircleWrapper:A] Running Loop=1 2020-11-11T20:13:44.791Z,1605125624.791 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for altitude 2020-11-11T20:14:09.570Z,1605125649.570 [NAL9602](INFO): Not Powering down - fast GPS 2020-11-11T20:14:13.610Z,1605125653.610 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2020-11-11T20:14:30.607Z,1605125670.607 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items 2020-11-11T20:14:57.697Z,1605125697.697 [RDI_PathfinderUp](ERROR): only read 1 of 4 data items 2020-11-11T20:15:08.601Z,1605125708.601 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2020-11-11T20:16:10.402Z,1605125770.402 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-11-11T20:16:33.483Z,1605125793.483 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2020-11-11T20:17:00.325Z,1605125820.325 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.242588 2020-11-11T20:17:34.137Z,1605125854.137 [Radio_Surface](INFO): Powering down 2020-11-11T20:17:36.469Z,1605125856.469 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-11-11T20:17:37.473Z,1605125857.473 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2020-11-11T20:19:00.542Z,1605125940.542 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items 2020-11-11T20:20:05.159Z,1605126005.159 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2020-11-11T20:20:05.551Z,1605126005.551 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-11-11T20:21:31.617Z,1605126091.617 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2020-11-11T20:22:31.105Z,1605126151.105 [CTD_Seabird](ERROR): Failed to parse device response: 0,3.84612,5816.74 2020-11-11T20:23:33.288Z,1605126213.288 [ThrusterServo](FAULT): Invalid EZ Servo response:"/0@5242" 2020-11-11T20:23:33.289Z,1605126213.289 [ThrusterServo] Communications Fault, FailCount= 1 2020-11-11T20:23:33.289Z,1605126213.289 [ThrusterServo](ERROR): Communications Fault 2020-11-11T20:23:33.292Z,1605126213.292 [CBIT](ERROR): Communications Fault in component: ThrusterServo 2020-11-11T20:23:33.695Z,1605126213.695 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2020-11-11T20:23:33.695Z,1605126213.695 [ThrusterServo](INFO): Powering down 2020-11-11T20:23:34.476Z,1605126214.476 [ThrusterServo](DEBUG): Initializing EZServoServo. 2020-11-11T20:23:34.599Z,1605126214.599 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2020-11-11T20:23:34.603Z,1605126214.603 [CBIT](INFO): Clearing failed state for component ThrusterServo 2020-11-11T20:23:34.603Z,1605126214.603 [ThrusterServo] No Fault, FailCount= 1 2020-11-11T20:28:00.275Z,1605126480.275 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-11-11T20:28:05.535Z,1605126485.535 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2020-11-11T20:28:10.385Z,1605126490.385 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2020-11-11T20:28:47.550Z,1605126527.550 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2020-11-11T20:33:18.725Z,1605126798.725 [CTD_Seabird](ERROR): Failed to parse device response: 7.28,13.2763,3.83570, 2020-11-11T20:33:30.761Z,1605126810.761 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2020-11-11T20:33:40.523Z,1605126820.523 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2020-11-11T20:34:30.564Z,1605126870.564 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for altitude 2020-11-11T20:34:35.045Z,1605126875.045 [CTD_Seabird](ERROR): Failed to parse device response: 6.93,13.2555,3.838.43 2020-11-11T20:35:46.514Z,1605126946.514 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-11-11T20:41:20.662Z,1605127280.662 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2020-11-11T20:47:41.273Z,1605127661.273 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-11-11T20:47:46.530Z,1605127666.530 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-11-11T20:47:55.377Z,1605127675.377 [CTD_Seabird](ERROR): Failed to parse device response: .02 2020-11-11T20:48:21.288Z,1605127701.288 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout 2020-11-11T20:48:21.288Z,1605127701.288 [RDI_Pathfinder] Communications Fault, FailCount= 1 2020-11-11T20:48:21.288Z,1605127701.288 [RDI_Pathfinder](ERROR): Communications Fault 2020-11-11T20:48:21.376Z,1605127701.376 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2020-11-11T20:48:21.740Z,1605127701.740 [RDI_Pathfinder](INFO): Powering down 2020-11-11T20:48:22.612Z,1605127702.612 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2020-11-11T20:48:22.612Z,1605127702.612 [RDI_Pathfinder] No Fault, FailCount= 1 2020-11-11T20:49:13.812Z,1605127753.812 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2020-11-11T20:49:27.940Z,1605127767.940 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-11-11T20:53:20.688Z,1605128000.688 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items 2020-11-11T20:53:41.672Z,1605128021.672 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2020-11-11T20:53:46.524Z,1605128026.524 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2020-11-11T20:54:01.515Z,1605128041.515 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2020-11-11T20:54:07.537Z,1605128047.537 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2020-11-11T20:54:22.516Z,1605128062.516 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items 2020-11-11T20:54:40.670Z,1605128080.670 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items 2020-11-11T20:54:44.711Z,1605128084.711 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items 2020-11-11T20:55:51.827Z,1605128151.827 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for altitude 2020-11-11T20:55:57.831Z,1605128157.831 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2020-11-11T20:56:49.537Z,1605128209.537 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2020-11-11T20:56:56.853Z,1605128216.853 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2020-11-11T20:56:56.855Z,1605128216.855 [BPC1](INFO): Received data from all battery sticks. 2020-11-11T20:57:40.437Z,1605128260.437 [Micromodem](INFO): Nmea in: 5 2020-11-11T20:57:40.438Z,1605128260.438 [Micromodem](ERROR): Response from modem failed NMEA checksum: 5 expected 0 2020-11-11T20:57:49.808Z,1605128269.808 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2020-11-11T20:58:30.945Z,1605128310.945 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-11-11T20:58:38.640Z,1605128318.640 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2020-11-11T20:58:56.900Z,1605128336.900 [ThrusterServo](FAULT): Invalid EZ Servo response:"/0@5242" 2020-11-11T20:58:56.900Z,1605128336.900 [ThrusterServo] Communications Fault, FailCount= 1 2020-11-11T20:58:56.901Z,1605128336.901 [ThrusterServo](ERROR): Communications Fault 2020-11-11T20:58:56.904Z,1605128336.904 [CBIT](ERROR): Communications Fault in component: ThrusterServo 2020-11-11T20:58:57.257Z,1605128337.257 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2020-11-11T20:58:57.257Z,1605128337.257 [ThrusterServo](INFO): Powering down 2020-11-11T20:58:58.100Z,1605128338.100 [ThrusterServo](DEBUG): Initializing EZServoServo. 2020-11-11T20:58:58.229Z,1605128338.229 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2020-11-11T20:58:58.232Z,1605128338.232 [CBIT](INFO): Clearing failed state for component ThrusterServo 2020-11-11T20:58:58.233Z,1605128338.233 [ThrusterServo] No Fault, FailCount= 1 2020-11-11T20:59:11.792Z,1605128351.792 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2020-11-11T20:59:17.005Z,1605128357.005 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-11-11T20:59:21.853Z,1605128361.853 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-11-11T20:59:27.105Z,1605128367.105 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-11-11T20:59:31.958Z,1605128371.958 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2020-11-11T20:59:42.059Z,1605128382.059 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-11-11T20:59:46.912Z,1605128386.912 [RDI_Pathfinder](ERROR): only read 1 of 4 data items 2020-11-11T20:59:51.806Z,1605128391.806 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2020-11-11T20:59:57.015Z,1605128397.015 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-11-11T20:59:57.852Z,1605128397.852 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for altitude 2020-11-11T21:00:01.863Z,1605128401.863 [RDI_Pathfinder](ERROR): only read 0 of 4 data items 2020-11-11T21:00:32.973Z,1605128432.973 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-11-11T21:02:00.853Z,1605128520.853 [CTD_Seabird](ERROR): Failed to parse device response: 5.17,13.3290,3.841067 2020-11-11T21:02:07.129Z,1605128527.129 [profile_station:CircleWrapper:B.Circle] Stopped 2020-11-11T21:02:07.129Z,1605128527.129 [profile_station:CircleWrapper:B.Circle](DEBUG): Uninitialize. 2020-11-11T21:02:07.130Z,1605128527.130 [profile_station:CircleWrapper](INFO): Completed profile_station:CircleWrapper 2020-11-11T21:02:07.130Z,1605128527.130 [profile_station:CircleWrapper] Stopped 2020-11-11T21:02:07.130Z,1605128527.130 [profile_station:CircleWrapper](DEBUG): Aggregate::uninitialize profile_station:CircleWrapper 2020-11-11T21:02:07.130Z,1605128527.130 [profile_station:CircleWrapper:A] Stopped 2020-11-11T21:02:07.130Z,1605128527.130 [profile_station:CircleWrapper](INFO): Running loop #2 2020-11-11T21:02:07.130Z,1605128527.130 [profile_station:CircleWrapper] Running Loop=2 2020-11-11T21:02:07.130Z,1605128527.130 [profile_station:CircleWrapper](DEBUG): Aggregate::initialize profile_station:CircleWrapper 2020-11-11T21:02:07.130Z,1605128527.130 [profile_station:CircleWrapper:A] Running Loop=1 2020-11-11T21:02:07.130Z,1605128527.130 [profile_station:CircleWrapper:B.Circle] Running Loop=1 2020-11-11T21:02:07.130Z,1605128527.130 [profile_station:CircleWrapper:B.Circle](DEBUG): Initialize CircleComponent. 2020-11-11T21:02:39.823Z,1605128559.823 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-11-11T21:06:30.938Z,1605128790.938 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-11-11T21:13:15.005Z,1605129195.005 [profile_station](INFO): Timed out from 2020-11-11T20:13:14.9Z 2020-11-11T21:13:15.005Z,1605129195.005 [MissionManager](INFO): profile_station is completed. 2020-11-11T21:13:15.005Z,1605129195.005 [MissionManager](INFO): Uninitializing Mission profile_station 2020-11-11T21:13:15.005Z,1605129195.005 [profile_station] Stopped 2020-11-11T21:13:15.005Z,1605129195.005 [profile_station](DEBUG): Aggregate::uninitialize profile_station 2020-11-11T21:13:15.005Z,1605129195.005 [profile_station:Science] Stopped 2020-11-11T21:13:15.005Z,1605129195.005 [profile_station:Science](DEBUG): Aggregate::uninitialize profile_station:Science 2020-11-11T21:13:15.006Z,1605129195.006 [profile_station:Science:B] Stopped 2020-11-11T21:13:15.006Z,1605129195.006 [profile_station:Science:C] Stopped 2020-11-11T21:13:15.006Z,1605129195.006 [profile_station:Science:E] Stopped 2020-11-11T21:13:15.006Z,1605129195.006 [profile_station:Science:Read_Oil] Stopped 2020-11-11T21:13:15.006Z,1605129195.006 [profile_station:Science:OceanCurrent] Stopped 2020-11-11T21:13:15.006Z,1605129195.006 [profile_station:Science:OceanCurrent](DEBUG): Aggregate::uninitialize profile_station:Science:OceanCurrent 2020-11-11T21:13:15.006Z,1605129195.006 [profile_station:Science:OceanCurrent:A.] Stopped 2020-11-11T21:13:15.006Z,1605129195.006 [profile_station:Science:PeakDetectChl] Stopped 2020-11-11T21:13:15.006Z,1605129195.006 [profile_station:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectChl 2020-11-11T21:13:15.006Z,1605129195.006 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped 2020-11-11T21:13:15.006Z,1605129195.006 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2020-11-11T21:13:15.006Z,1605129195.006 [profile_station:StandardEnvelopes] Stopped 2020-11-11T21:13:15.006Z,1605129195.006 [profile_station:StandardEnvelopes](DEBUG): Aggregate::uninitialize profile_station:StandardEnvelopes 2020-11-11T21:13:15.006Z,1605129195.006 [profile_station:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2020-11-11T21:13:15.007Z,1605129195.007 [profile_station:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2020-11-11T21:13:15.007Z,1605129195.007 [profile_station:StandardEnvelopes:B.DepthEnvelope] Stopped 2020-11-11T21:13:15.007Z,1605129195.007 [profile_station:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2020-11-11T21:13:15.007Z,1605129195.007 [profile_station:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2020-11-11T21:13:15.007Z,1605129195.007 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2020-11-11T21:13:15.007Z,1605129195.007 [profile_station:I.Pitch] Stopped 2020-11-11T21:13:15.007Z,1605129195.007 [profile_station:J.Buoyancy] Stopped 2020-11-11T21:13:15.007Z,1605129195.007 [profile_station:J.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2020-11-11T21:13:15.007Z,1605129195.007 [profile_station:K.SetSpeed] Stopped 2020-11-11T21:13:15.007Z,1605129195.007 [profile_station:K.SetSpeed](DEBUG): Uninitialize. 2020-11-11T21:13:15.007Z,1605129195.007 [profile_station:L.DepthEnvelope] Stopped 2020-11-11T21:13:15.007Z,1605129195.007 [profile_station:L.DepthEnvelope](DEBUG): Uninitialize. 2020-11-11T21:13:15.007Z,1605129195.007 [profile_station:M.AltitudeEnvelope] Stopped 2020-11-11T21:13:15.007Z,1605129195.007 [profile_station:M.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2020-11-11T21:13:15.007Z,1605129195.007 [profile_station:N.YoYo] Stopped 2020-11-11T21:13:15.012Z,1605129195.012 [profile_station:N.YoYo](DEBUG): Uninitialize YoYoComponent. 2020-11-11T21:13:15.012Z,1605129195.012 [profile_station:CircleWrapper] Stopped 2020-11-11T21:13:15.012Z,1605129195.012 [profile_station:CircleWrapper](DEBUG): Aggregate::uninitialize profile_station:CircleWrapper 2020-11-11T21:13:15.012Z,1605129195.012 [profile_station:CircleWrapper:A] Stopped 2020-11-11T21:13:15.012Z,1605129195.012 [profile_station:CircleWrapper:B.Circle] Stopped 2020-11-11T21:13:15.012Z,1605129195.012 [profile_station:CircleWrapper:B.Circle](DEBUG): Uninitialize. 2020-11-11T21:13:15.280Z,1605129195.280 [WetLabsSeaOWL_UV_A](INFO): Powering down 2020-11-11T21:13:15.384Z,1605129195.384 [MissionManager](IMPORTANT): Started mission DefaultWithUndock 2020-11-11T21:13:15.384Z,1605129195.384 [DefaultWithUndock] Running Loop=1 2020-11-11T21:13:15.384Z,1605129195.384 [DefaultWithUndock](DEBUG): Aggregate::initialize DefaultWithUndock 2020-11-11T21:13:15.385Z,1605129195.385 [DefaultWithUndock:A.Wait] Running Loop=1 2020-11-11T21:13:15.385Z,1605129195.385 [DefaultWithUndock:A.Wait](DEBUG): Initialize Wait Component. 2020-11-11T21:13:45.711Z,1605129225.711 [DefaultWithUndock:A.Wait](INFO): Done Waiting. 2020-11-11T21:13:45.711Z,1605129225.711 [DefaultWithUndock:A.Wait] Stopped 2020-11-11T21:13:45.711Z,1605129225.711 [DefaultWithUndock:A.Wait](DEBUG): Uninitialize Wait Component. 2020-11-11T21:13:45.711Z,1605129225.711 [DefaultWithUndock:LeaveDock] Running Loop=1 2020-11-11T21:13:45.711Z,1605129225.711 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::initialize DefaultWithUndock:LeaveDock 2020-11-11T21:13:45.711Z,1605129225.711 [DefaultWithUndock:LeaveDock:A.] Running Loop=1 2020-11-11T21:13:45.711Z,1605129225.711 [DefaultWithUndock:LeaveDock:A.](INFO): Initializing TrackAcousticContact. 2020-11-11T21:13:45.712Z,1605129225.712 [DefaultWithUndock:LeaveDock:B.Undock] Running Loop=1 2020-11-11T21:13:45.712Z,1605129225.712 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize. 2020-11-11T21:13:45.712Z,1605129225.712 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values. 2020-11-11T21:13:46.498Z,1605129226.498 [PowerOnly](INFO): Powering up loadControl 2020-11-11T21:13:46.932Z,1605129226.932 [DDM](INFO): Latch Status Query:!F1,01,90,S,00,+017,F 1.1 2020-11-11T21:13:46.933Z,1605129226.933 [DDM](INFO): Latch State:1 2020-11-11T21:13:46.956Z,1605129226.956 [DDM](INFO): Whisker Status Query:!F2,10,90,S,00,+007,F 1.1 2020-11-11T21:13:46.956Z,1605129226.956 [DDM](INFO): Whisker State:2 2020-11-11T21:13:46.980Z,1605129226.980 [DDM](INFO): Cable Status Query:!CP247 2020-11-11T21:13:46.980Z,1605129226.980 [DDM](INFO): Cable State:247 2020-11-11T21:13:47.277Z,1605129227.277 [DDM](INFO): Detaching. Opening Latch 2020-11-11T21:13:47.309Z,1605129227.309 [DDM](INFO): Latch Status Query:!F1,01,90,S,00,+017,F 1.1 2020-11-11T21:13:47.310Z,1605129227.310 [DDM](INFO): Latch State:1 2020-11-11T21:13:47.329Z,1605129227.329 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detaching from dock. 2020-11-11T21:13:47.682Z,1605129227.682 [DDM](INFO): Detaching. Opening Latch 2020-11-11T21:13:47.708Z,1605129227.708 [DDM](INFO): Latch Status Query:!F1,01,90,R,00,+026,F 1.1 2020-11-11T21:13:47.708Z,1605129227.708 [DDM](INFO): Latch State:1 2020-11-11T21:13:47.806Z,1605129227.806 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Engaging thruster for 10.0 seconds (max) to disengage. 2020-11-11T21:13:48.087Z,1605129228.087 [DDM](INFO): Detaching. Opening Latch 2020-11-11T21:13:48.112Z,1605129228.112 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+064,F 1.1 2020-11-11T21:13:48.112Z,1605129228.112 [DDM](INFO): Latch State:0 2020-11-11T21:13:48.532Z,1605129228.532 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+120,F 1.1 2020-11-11T21:13:48.532Z,1605129228.532 [DDM](INFO): Latch State:0 2020-11-11T21:13:48.590Z,1605129228.590 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2020-11-11T21:13:48.921Z,1605129228.921 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+162,F 1.1 2020-11-11T21:13:48.922Z,1605129228.922 [DDM](INFO): Latch State:0 2020-11-11T21:13:49.320Z,1605129229.320 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+178,F 1.1 2020-11-11T21:13:49.320Z,1605129229.320 [DDM](INFO): Latch State:0 2020-11-11T21:13:49.330Z,1605129229.330 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76 2020-11-11T21:13:49.732Z,1605129229.732 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+180,F 1.1 2020-11-11T21:13:49.733Z,1605129229.733 [DDM](INFO): Latch State:0 2020-11-11T21:13:49.754Z,1605129229.754 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B 2020-11-11T21:13:50.140Z,1605129230.140 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+180,F 1.1 2020-11-11T21:13:50.140Z,1605129230.140 [DDM](INFO): Latch State:0 2020-11-11T21:13:50.556Z,1605129230.556 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+180,F 1.1 2020-11-11T21:13:50.556Z,1605129230.556 [DDM](INFO): Latch State:0 2020-11-11T21:13:50.568Z,1605129230.568 [Micromodem](INFO): Nmea in: $SNTTA,0.246742,,,0.250736,211350.90*57 2020-11-11T21:13:50.940Z,1605129230.940 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+179,F 1.1 2020-11-11T21:13:50.940Z,1605129230.940 [DDM](INFO): Latch State:0 2020-11-11T21:13:50.994Z,1605129230.994 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Thruster off. 2020-11-11T21:13:51.344Z,1605129231.344 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+179,F 1.1 2020-11-11T21:13:51.344Z,1605129231.344 [DDM](INFO): Latch State:0 2020-11-11T21:13:51.399Z,1605129231.399 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detected possible detachment. Starting timer. 2020-11-11T21:13:51.768Z,1605129231.768 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+180,F 1.1 2020-11-11T21:13:51.768Z,1605129231.768 [DDM](INFO): Latch State:0 2020-11-11T21:13:52.153Z,1605129232.153 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+179,F 1.1 2020-11-11T21:13:52.154Z,1605129232.154 [DDM](INFO): Latch State:0 2020-11-11T21:13:52.552Z,1605129232.552 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+180,F 1.1 2020-11-11T21:13:52.552Z,1605129232.552 [DDM](INFO): Latch State:0 2020-11-11T21:13:52.960Z,1605129232.960 [DDM](INFO): Latch Status Query:!F1,10,90,R,00,+181,F 1.1 2020-11-11T21:13:52.960Z,1605129232.960 [DDM](INFO): Latch State:2 2020-11-11T21:13:52.987Z,1605129232.987 [DDM](INFO): Cable Status Query:!CP216 2020-11-11T21:13:52.987Z,1605129232.987 [DDM](INFO): Cable State:216 2020-11-11T21:13:53.364Z,1605129233.364 [DDM](INFO): Cable Status Query:!CP222 2020-11-11T21:13:53.364Z,1605129233.364 [DDM](INFO): Cable State:222 2020-11-11T21:13:53.385Z,1605129233.385 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2020-11-11T21:13:53.788Z,1605129233.788 [DDM](INFO): Cable Status Query:!CP223 2020-11-11T21:13:53.788Z,1605129233.788 [DDM](INFO): Cable State:223 2020-11-11T21:13:54.168Z,1605129234.168 [DDM](INFO): Cable Status Query:!CP218 2020-11-11T21:13:54.168Z,1605129234.168 [DDM](INFO): Cable State:218 2020-11-11T21:13:54.194Z,1605129234.194 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76 2020-11-11T21:13:54.576Z,1605129234.576 [DDM](INFO): Cable Status Query:!CP218 2020-11-11T21:13:54.576Z,1605129234.576 [DDM](INFO): Cable State:218 2020-11-11T21:13:54.588Z,1605129234.588 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B 2020-11-11T21:13:54.992Z,1605129234.992 [DDM](INFO): Cable Status Query:!CP219 2020-11-11T21:13:54.992Z,1605129234.992 [DDM](INFO): Cable State:219 2020-11-11T21:13:55.400Z,1605129235.400 [DDM](INFO): Cable Status Query:!CP210 2020-11-11T21:13:55.401Z,1605129235.401 [DDM](INFO): Cable State:210 2020-11-11T21:13:55.412Z,1605129235.412 [Micromodem](INFO): Nmea in: $SNTTA,0.246947,,,0.253778,211355.77*59 2020-11-11T21:13:55.792Z,1605129235.792 [DDM](INFO): Cable Status Query:!CP204 2020-11-11T21:13:55.792Z,1605129235.792 [DDM](INFO): Cable State:204 2020-11-11T21:13:56.192Z,1605129236.192 [DDM](INFO): Cable Status Query:!CP222 2020-11-11T21:13:56.192Z,1605129236.192 [DDM](INFO): Cable State:222 2020-11-11T21:13:56.593Z,1605129236.593 [DDM](INFO): Cable Status Query:!CP215 2020-11-11T21:13:56.594Z,1605129236.594 [DDM](INFO): Cable State:215 2020-11-11T21:13:56.996Z,1605129236.996 [DDM](INFO): Cable Status Query:!CP203 2020-11-11T21:13:56.996Z,1605129236.996 [DDM](INFO): Cable State:203 2020-11-11T21:13:57.404Z,1605129237.404 [DDM](INFO): Cable Status Query:!CP220 2020-11-11T21:13:57.404Z,1605129237.404 [DDM](INFO): Cable State:220 2020-11-11T21:13:57.810Z,1605129237.810 [DDM](INFO): Cable Status Query:!CP217 2020-11-11T21:13:57.810Z,1605129237.810 [DDM](INFO): Cable State:217 2020-11-11T21:13:57.842Z,1605129237.842 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2020-11-11T21:13:58.208Z,1605129238.208 [DDM](INFO): Cable Status Query:!CP206 2020-11-11T21:13:58.208Z,1605129238.208 [DDM](INFO): Cable State:206 2020-11-11T21:13:58.253Z,1605129238.253 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2020-11-11T21:13:58.616Z,1605129238.616 [DDM](INFO): Cable Status Query:!CP207 2020-11-11T21:13:58.616Z,1605129238.616 [DDM](INFO): Cable State:207 2020-11-11T21:13:59.032Z,1605129239.032 [DDM](INFO): Cable Status Query:!CP212 2020-11-11T21:13:59.032Z,1605129239.032 [DDM](INFO): Cable State:212 2020-11-11T21:13:59.042Z,1605129239.042 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76 2020-11-11T21:13:59.424Z,1605129239.424 [DDM](INFO): Cable Status Query:!CP205 2020-11-11T21:13:59.424Z,1605129239.424 [DDM](INFO): Cable State:205 2020-11-11T21:13:59.440Z,1605129239.440 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B 2020-11-11T21:13:59.828Z,1605129239.828 [DDM](INFO): Cable Status Query:!CP201 2020-11-11T21:13:59.828Z,1605129239.828 [DDM](INFO): Cable State:201 2020-11-11T21:14:00.244Z,1605129240.244 [DDM](INFO): Cable Status Query:!CP211 2020-11-11T21:14:00.245Z,1605129240.245 [DDM](INFO): Cable State:211 2020-11-11T21:14:00.255Z,1605129240.255 [Micromodem](INFO): Nmea in: $SNTTA,0.247063,,,,211400.61*45 2020-11-11T21:14:00.640Z,1605129240.640 [DDM](INFO): Cable Status Query:!CP219 2020-11-11T21:14:00.640Z,1605129240.640 [DDM](INFO): Cable State:219 2020-11-11T21:14:01.041Z,1605129241.041 [DDM](INFO): Cable Status Query:!CP223 2020-11-11T21:14:01.042Z,1605129241.042 [DDM](INFO): Cable State:223 2020-11-11T21:14:01.445Z,1605129241.445 [DDM](INFO): Cable Status Query:!CP207 2020-11-11T21:14:01.446Z,1605129241.446 [DDM](INFO): Cable State:207 2020-11-11T21:14:01.848Z,1605129241.848 [DDM](INFO): Cable Status Query:!CP214 2020-11-11T21:14:01.848Z,1605129241.848 [DDM](INFO): Cable State:214 2020-11-11T21:14:02.260Z,1605129242.260 [DDM](INFO): Cable Status Query:!CP207 2020-11-11T21:14:02.260Z,1605129242.260 [DDM](INFO): Cable State:207 2020-11-11T21:14:02.660Z,1605129242.660 [DDM](INFO): Cable Status Query:!CP217 2020-11-11T21:14:02.660Z,1605129242.660 [DDM](INFO): Cable State:217 2020-11-11T21:14:02.694Z,1605129242.694 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for altitude 2020-11-11T21:14:03.060Z,1605129243.060 [DDM](INFO): Cable Status Query:!CP216 2020-11-11T21:14:03.060Z,1605129243.060 [DDM](INFO): Cable State:216 2020-11-11T21:14:03.111Z,1605129243.111 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2020-11-11T21:14:03.464Z,1605129243.464 [DDM](INFO): Cable Status Query:!CP208 2020-11-11T21:14:03.465Z,1605129243.465 [DDM](INFO): Cable State:208 2020-11-11T21:14:03.872Z,1605129243.872 [DDM](INFO): Cable Status Query:!CP213 2020-11-11T21:14:03.872Z,1605129243.872 [DDM](INFO): Cable State:213 2020-11-11T21:14:03.882Z,1605129243.882 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76 2020-11-11T21:14:04.277Z,1605129244.277 [DDM](INFO): Cable Status Query:!CP207 2020-11-11T21:14:04.278Z,1605129244.278 [DDM](INFO): Cable State:207 2020-11-11T21:14:04.288Z,1605129244.288 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B 2020-11-11T21:14:04.676Z,1605129244.676 [DDM](INFO): Cable Status Query:!CP224 2020-11-11T21:14:04.676Z,1605129244.676 [DDM](INFO): Cable State:224 2020-11-11T21:14:05.092Z,1605129245.092 [DDM](INFO): Cable Status Query:!CP211 2020-11-11T21:14:05.092Z,1605129245.092 [DDM](INFO): Cable State:211 2020-11-11T21:14:05.102Z,1605129245.102 [Micromodem](INFO): Nmea in: $SNTTA,0.247249,,,,211405.45*4C 2020-11-11T21:14:05.504Z,1605129245.504 [DDM](INFO): Cable Status Query:!CP209 2020-11-11T21:14:05.504Z,1605129245.504 [DDM](INFO): Cable State:209 2020-11-11T21:14:05.890Z,1605129245.890 [DDM](INFO): Cable Status Query:!CP198 2020-11-11T21:14:05.890Z,1605129245.890 [DDM](INFO): Cable State:198 2020-11-11T21:14:05.922Z,1605129245.922 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2020-11-11T21:14:06.292Z,1605129246.292 [DDM](INFO): Cable Status Query:!CP219 2020-11-11T21:14:06.292Z,1605129246.292 [DDM](INFO): Cable State:219 2020-11-11T21:14:06.696Z,1605129246.696 [DDM](INFO): Cable Status Query:!CP203 2020-11-11T21:14:06.696Z,1605129246.696 [DDM](INFO): Cable State:203 2020-11-11T21:14:07.100Z,1605129247.100 [DDM](INFO): Cable Status Query:!CP219 2020-11-11T21:14:07.100Z,1605129247.100 [DDM](INFO): Cable State:219 2020-11-11T21:14:07.540Z,1605129247.540 [DDM](INFO): Cable Status Query:!CP208 2020-11-11T21:14:07.540Z,1605129247.540 [DDM](INFO): Cable State:208 2020-11-11T21:14:07.560Z,1605129247.560 [DUSBL_Hydroid](ERROR): No response from remote modem. 2020-11-11T21:14:07.909Z,1605129247.909 [DDM](INFO): Cable Status Query:!CP220 2020-11-11T21:14:07.910Z,1605129247.910 [DDM](INFO): Cable State:220 2020-11-11T21:14:08.312Z,1605129248.312 [DDM](INFO): Cable Status Query:!CP214 2020-11-11T21:14:08.312Z,1605129248.312 [DDM](INFO): Cable State:214 2020-11-11T21:14:08.725Z,1605129248.725 [DDM](INFO): Cable Status Query:!CP208 2020-11-11T21:14:08.725Z,1605129248.725 [DDM](INFO): Cable State:208 2020-11-11T21:14:08.795Z,1605129248.795 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2020-11-11T21:14:09.124Z,1605129249.124 [DDM](INFO): Cable Status Query:!CP205 2020-11-11T21:14:09.125Z,1605129249.125 [DDM](INFO): Cable State:205 2020-11-11T21:14:09.528Z,1605129249.528 [DDM](INFO): Cable Status Query:!CP204 2020-11-11T21:14:09.528Z,1605129249.528 [DDM](INFO): Cable State:204 2020-11-11T21:14:09.552Z,1605129249.552 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76 2020-11-11T21:14:09.932Z,1605129249.932 [DDM](INFO): Cable Status Query:!CP214 2020-11-11T21:14:09.932Z,1605129249.932 [DDM](INFO): Cable State:214 2020-11-11T21:14:09.940Z,1605129249.940 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B 2020-11-11T21:14:10.336Z,1605129250.336 [DDM](INFO): Cable Status Query:!CP203 2020-11-11T21:14:10.336Z,1605129250.336 [DDM](INFO): Cable State:203 2020-11-11T21:14:10.753Z,1605129250.753 [DDM](INFO): Cable Status Query:!CP207 2020-11-11T21:14:10.754Z,1605129250.754 [DDM](INFO): Cable State:207 2020-11-11T21:14:10.770Z,1605129250.770 [Micromodem](INFO): Nmea in: $SNTTA,0.247477,,,,211411.12*40 2020-11-11T21:14:11.144Z,1605129251.144 [DDM](INFO): Cable Status Query:!CP208 2020-11-11T21:14:11.144Z,1605129251.144 [DDM](INFO): Cable State:208 2020-11-11T21:14:11.544Z,1605129251.544 [DDM](INFO): Cable Status Query:!CP198 2020-11-11T21:14:11.544Z,1605129251.544 [DDM](INFO): Cable State:198 2020-11-11T21:14:11.574Z,1605129251.574 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached at range: 370.74 m. Transitioning DDM to standby. 2020-11-11T21:14:11.574Z,1605129251.574 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2020-11-11T21:14:11.948Z,1605129251.948 [DDM](INFO): Cable Status Query:!CP210 2020-11-11T21:14:11.948Z,1605129251.948 [DDM](INFO): Cable State:210 2020-11-11T21:14:12.326Z,1605129252.326 [DDM](INFO): Changing to mode: 0 2020-11-11T21:14:12.326Z,1605129252.326 [DDM](INFO): Closing Latch 2020-11-11T21:14:12.352Z,1605129252.352 [DDM](INFO): Latch Status Query:!F1,00,90,X,00,+178,F 1.1 2020-11-11T21:14:12.352Z,1605129252.352 [DDM](INFO): Latch State:0 2020-11-11T21:14:12.780Z,1605129252.780 [DDM](INFO): Latch Status Query:!F1,00,90,X,00,+180,F 1.1 2020-11-11T21:14:12.780Z,1605129252.780 [DDM](INFO): Latch State:0 2020-11-11T21:14:13.164Z,1605129253.164 [DDM](INFO): Latch Status Query:!F1,00,90,X,00,+157,F 1.1 2020-11-11T21:14:13.164Z,1605129253.164 [DDM](INFO): Latch State:0 2020-11-11T21:14:13.173Z,1605129253.173 [DUSBL_Hydroid](ERROR): No response from remote modem. 2020-11-11T21:14:13.566Z,1605129253.566 [DDM](INFO): Latch Status Query:!F1,00,90,X,00,+105,F 1.1 2020-11-11T21:14:13.566Z,1605129253.566 [DDM](INFO): Latch State:0 2020-11-11T21:14:13.968Z,1605129253.968 [DDM](INFO): Latch Status Query:!F1,00,90,X,00,+053,F 1.1 2020-11-11T21:14:13.968Z,1605129253.968 [DDM](INFO): Latch State:0 2020-11-11T21:14:14.372Z,1605129254.372 [DDM](INFO): Latch Status Query:!F1,01,90,S,00,+019,F 1.1 2020-11-11T21:14:14.372Z,1605129254.372 [DDM](INFO): Latch State:1 2020-11-11T21:14:14.409Z,1605129254.409 [DDM](INFO): Cable Status Query:!CP213 2020-11-11T21:14:14.410Z,1605129254.410 [DDM](INFO): Cable State:213 2020-11-11T21:14:14.428Z,1605129254.428 [DefaultWithUndock:LeaveDock:B.Undock](INFO): DDM at standby. 2020-11-11T21:14:14.429Z,1605129254.429 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2020-11-11T21:14:14.792Z,1605129254.792 [DDM](INFO): Cable Status Query:!CP209 2020-11-11T21:14:14.792Z,1605129254.792 [DDM](INFO): Cable State:209 2020-11-11T21:14:14.875Z,1605129254.875 [DefaultWithUndock:LeaveDock:B.Undock](IMPORTANT): Undocking sequence complete. 2020-11-11T21:14:14.875Z,1605129254.875 [DefaultWithUndock:LeaveDock:B.Undock] Stopped 2020-11-11T21:14:14.875Z,1605129254.875 [DefaultWithUndock:LeaveDock](INFO): Completed DefaultWithUndock:LeaveDock 2020-11-11T21:14:14.875Z,1605129254.875 [DefaultWithUndock:LeaveDock] Stopped 2020-11-11T21:14:14.875Z,1605129254.875 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::uninitialize DefaultWithUndock:LeaveDock 2020-11-11T21:14:14.875Z,1605129254.875 [DefaultWithUndock:LeaveDock:A.] Stopped 2020-11-11T21:14:14.880Z,1605129254.880 [DefaultWithUndock:SurfaceDefault] Running Loop=1 2020-11-11T21:14:14.880Z,1605129254.880 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault 2020-11-11T21:14:14.880Z,1605129254.880 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen] Running Loop=1 2020-11-11T21:14:14.880Z,1605129254.880 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:HoldLatchOpen 2020-11-11T21:14:14.880Z,1605129254.880 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen:A] Running Loop=1 2020-11-11T21:14:14.880Z,1605129254.880 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen:B] Running Loop=1 2020-11-11T21:14:14.880Z,1605129254.880 [DefaultWithUndock:SurfaceDefault:B.GoToSurface] Running Loop=1 2020-11-11T21:14:14.880Z,1605129254.880 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2020-11-11T21:14:14.881Z,1605129254.881 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2020-11-11T21:14:14.881Z,1605129254.881 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2020-11-11T21:14:14.881Z,1605129254.881 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2020-11-11T21:14:14.881Z,1605129254.881 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2020-11-11T21:14:14.882Z,1605129254.882 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2020-11-11T21:14:15.720Z,1605129255.720 [Radio_Surface](INFO): Powering up 2020-11-11T21:14:16.828Z,1605129256.828 [DDM](INFO): Latch Status Query:!F1,01,90,S,00,+017,F 1.1 2020-11-11T21:14:16.828Z,1605129256.828 [DDM](INFO): Latch State:1 2020-11-11T21:14:16.856Z,1605129256.856 [DDM](INFO): Whisker Status Query:!F2,10,90,S,00,+008,F 1.1 2020-11-11T21:14:16.857Z,1605129256.857 [DDM](INFO): Whisker State:2 2020-11-11T21:14:16.880Z,1605129256.880 [DDM](INFO): Cable Status Query:!CP216 2020-11-11T21:14:16.880Z,1605129256.880 [DDM](INFO): Cable State:216 2020-11-11T21:14:17.175Z,1605129257.175 [DDM](INFO): Changing to mode: 2 2020-11-11T21:14:17.175Z,1605129257.175 [DDM](INFO): Detaching. Opening Latch 2020-11-11T21:14:17.200Z,1605129257.200 [DDM](INFO): Latch Status Query:!F1,01,90,S,00,+018,F 1.1 2020-11-11T21:14:17.200Z,1605129257.200 [DDM](INFO): Latch State:1 2020-11-11T21:14:17.588Z,1605129257.588 [DDM](INFO): Detaching. Opening Latch 2020-11-11T21:14:17.616Z,1605129257.616 [DDM](INFO): Latch Status Query:!F1,01,90,R,00,+017,F 1.1 2020-11-11T21:14:17.616Z,1605129257.616 [DDM](INFO): Latch State:1 2020-11-11T21:14:17.643Z,1605129257.643 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items 2020-11-11T21:14:17.982Z,1605129257.982 [DDM](INFO): Detaching. Opening Latch 2020-11-11T21:14:18.012Z,1605129258.012 [DDM](INFO): Latch Status Query:!F1,00,+032,F !F1,00, 1.1 2020-11-11T21:14:18.012Z,1605129258.012 [DDM](INFO): Latch State:0 2020-11-11T21:14:18.413Z,1605129258.413 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+084,F 1.1 2020-11-11T21:14:18.414Z,1605129258.414 [DDM](INFO): Latch State:0 2020-11-11T21:14:18.816Z,1605129258.816 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+134,F 1.1 2020-11-11T21:14:18.816Z,1605129258.816 [DDM](INFO): Latch State:0 2020-11-11T21:14:19.220Z,1605129259.220 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+173,F 1.1 2020-11-11T21:14:19.220Z,1605129259.220 [DDM](INFO): Latch State:0 2020-11-11T21:14:19.632Z,1605129259.632 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+180,F 1.1 2020-11-11T21:14:19.632Z,1605129259.632 [DDM](INFO): Latch State:0 2020-11-11T21:14:20.040Z,1605129260.040 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+179,F 1.1 2020-11-11T21:14:20.040Z,1605129260.040 [DDM](INFO): Latch State:0 2020-11-11T21:14:20.432Z,1605129260.432 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+179,F 1.1 2020-11-11T21:14:20.433Z,1605129260.433 [DDM](INFO): Latch State:0 2020-11-11T21:14:20.844Z,1605129260.844 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+179,F 1.1 2020-11-11T21:14:20.844Z,1605129260.844 [DDM](INFO): Latch State:0 2020-11-11T21:14:21.245Z,1605129261.245 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+178,F 1.1 2020-11-11T21:14:21.246Z,1605129261.246 [DDM](INFO): Latch State:0 2020-11-11T21:14:21.428Z,1605129261.428 [DataOverHttps](INFO): Radio surface powered ON. 2020-11-11T21:14:21.644Z,1605129261.644 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+179,F 1.1 2020-11-11T21:14:21.644Z,1605129261.644 [DDM](INFO): Latch State:0 2020-11-11T21:14:22.048Z,1605129262.048 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+179,F 1.1 2020-11-11T21:14:22.048Z,1605129262.048 [DDM](INFO): Latch State:0 2020-11-11T21:14:22.457Z,1605129262.457 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+180,F 1.1 2020-11-11T21:14:22.458Z,1605129262.458 [DDM](INFO): Latch State:0 2020-11-11T21:14:22.857Z,1605129262.857 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+180,F 1.1 2020-11-11T21:14:22.858Z,1605129262.858 [DDM](INFO): Latch State:0 2020-11-11T21:14:23.260Z,1605129263.260 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+180,F 1.1 2020-11-11T21:14:23.260Z,1605129263.260 [DDM](INFO): Latch State:0 2020-11-11T21:14:23.664Z,1605129263.664 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+179,F 1.1 2020-11-11T21:14:23.664Z,1605129263.664 [DDM](INFO): Latch State:0 2020-11-11T21:14:24.076Z,1605129264.076 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+179,F 1.1 2020-11-11T21:14:24.076Z,1605129264.076 [DDM](INFO): Latch State:0 2020-11-11T21:14:24.488Z,1605129264.488 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+180,F 1.1 2020-11-11T21:14:24.488Z,1605129264.488 [DDM](INFO): Latch State:0 2020-11-11T21:14:24.876Z,1605129264.876 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+180,F 1.1 2020-11-11T21:14:24.876Z,1605129264.876 [DDM](INFO): Latch State:0 2020-11-11T21:14:25.280Z,1605129265.280 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+180,F 1.1 2020-11-11T21:14:25.280Z,1605129265.280 [DDM](INFO): Latch State:0 2020-11-11T21:14:25.688Z,1605129265.688 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+179,F 1.1 2020-11-11T21:14:25.688Z,1605129265.688 [DDM](INFO): Latch State:0 2020-11-11T21:14:26.090Z,1605129266.090 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+179,F 1.1 2020-11-11T21:14:26.090Z,1605129266.090 [DDM](INFO): Latch State:0 2020-11-11T21:14:26.512Z,1605129266.512 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+180,F 1.1 2020-11-11T21:14:26.512Z,1605129266.512 [DDM](INFO): Latch State:0 2020-11-11T21:14:26.896Z,1605129266.896 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+179,F 1.1 2020-11-11T21:14:26.896Z,1605129266.896 [DDM](INFO): Latch State:0 2020-11-11T21:14:27.312Z,1605129267.312 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+179,F 1.1 2020-11-11T21:14:27.312Z,1605129267.312 [DDM](INFO): Latch State:0 2020-11-11T21:14:27.712Z,1605129267.712 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+179,F 1.1 2020-11-11T21:14:27.712Z,1605129267.712 [DDM](INFO): Latch State:0 2020-11-11T21:14:28.104Z,1605129268.104 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+179,F 1.1 2020-11-11T21:14:28.104Z,1605129268.104 [DDM](INFO): Latch State:0 2020-11-11T21:14:28.520Z,1605129268.520 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+180,F 1.1 2020-11-11T21:14:28.520Z,1605129268.520 [DDM](INFO): Latch State:0 2020-11-11T21:14:28.916Z,1605129268.916 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+179,F 1.1 2020-11-11T21:14:28.916Z,1605129268.916 [DDM](INFO): Latch State:0 2020-11-11T21:14:29.318Z,1605129269.318 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+179,F 1.1 2020-11-11T21:14:29.318Z,1605129269.318 [DDM](INFO): Latch State:0 2020-11-11T21:14:29.724Z,1605129269.724 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+180,F 1.1 2020-11-11T21:14:29.724Z,1605129269.724 [DDM](INFO): Latch State:0 2020-11-11T21:14:30.128Z,1605129270.128 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+179,F 1.1 2020-11-11T21:14:30.128Z,1605129270.128 [DDM](INFO): Latch State:0 2020-11-11T21:14:30.532Z,1605129270.532 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+180,F 1.1 2020-11-11T21:14:30.532Z,1605129270.532 [DDM](INFO): Latch State:0 2020-11-11T21:14:30.944Z,1605129270.944 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+179,F 1.1 2020-11-11T21:14:30.944Z,1605129270.944 [DDM](INFO): Latch State:0 2020-11-11T21:14:31.340Z,1605129271.340 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+180,F 1.1 2020-11-11T21:14:31.340Z,1605129271.340 [DDM](INFO): Latch State:0 2020-11-11T21:14:31.761Z,1605129271.761 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+180,F 1.1 2020-11-11T21:14:31.762Z,1605129271.762 [DDM](INFO): Latch State:0 2020-11-11T21:14:32.148Z,1605129272.148 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+179,F 1.1 2020-11-11T21:14:32.148Z,1605129272.148 [DDM](INFO): Latch State:0 2020-11-11T21:14:32.548Z,1605129272.548 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+180,F 1.1 2020-11-11T21:14:32.548Z,1605129272.548 [DDM](INFO): Latch State:0 2020-11-11T21:14:32.632Z,1605129272.632 [RDI_PathfinderUp](ERROR): DVL uart error: serial timeout 2020-11-11T21:14:32.632Z,1605129272.632 [RDI_PathfinderUp] Communications Fault, FailCount= 1 2020-11-11T21:14:32.632Z,1605129272.632 [RDI_PathfinderUp](ERROR): Communications Fault 2020-11-11T21:14:32.776Z,1605129272.776 [CBIT](ERROR): Communications Fault in component: RDI_PathfinderUp 2020-11-11T21:14:32.952Z,1605129272.952 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+179,F 1.1 2020-11-11T21:14:32.952Z,1605129272.952 [DDM](INFO): Latch State:0 2020-11-11T21:14:33.052Z,1605129273.052 [RDI_PathfinderUp](INFO): Powering down 2020-11-11T21:14:33.360Z,1605129273.360 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+180,F 1.1 2020-11-11T21:14:33.360Z,1605129273.360 [DDM](INFO): Latch State:0 2020-11-11T21:14:33.789Z,1605129273.789 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+179,F 1.1 2020-11-11T21:14:33.789Z,1605129273.789 [DDM](INFO): Latch State:0 2020-11-11T21:14:33.916Z,1605129273.916 [CBIT](INFO): Clearing failed state for component RDI_PathfinderUp 2020-11-11T21:14:33.916Z,1605129273.916 [RDI_PathfinderUp] No Fault, FailCount= 1 2020-11-11T21:14:34.168Z,1605129274.168 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+180,F 1.1 2020-11-11T21:14:34.168Z,1605129274.168 [DDM](INFO): Latch State:0 2020-11-11T21:14:34.569Z,1605129274.569 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+179,F 1.1 2020-11-11T21:14:34.570Z,1605129274.570 [DDM](INFO): Latch State:0 2020-11-11T21:14:35.380Z,1605129275.380 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+179,F 1.1 2020-11-11T21:14:35.380Z,1605129275.380 [DDM](INFO): Latch State:0 2020-11-11T21:14:35.800Z,1605129275.800 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+180,F 1.1 2020-11-11T21:14:35.800Z,1605129275.800 [DDM](INFO): Latch State:0 2020-11-11T21:14:36.184Z,1605129276.184 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+179,F 1.1 2020-11-11T21:14:36.184Z,1605129276.184 [DDM](INFO): Latch State:0 2020-11-11T21:14:36.592Z,1605129276.592 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+179,F 1.1 2020-11-11T21:14:36.592Z,1605129276.592 [DDM](INFO): Latch State:0 2020-11-11T21:14:36.996Z,1605129276.996 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+178,F 1.1 2020-11-11T21:14:36.996Z,1605129276.996 [DDM](INFO): Latch State:0 2020-11-11T21:14:37.404Z,1605129277.404 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+180,F 1.1 2020-11-11T21:14:37.404Z,1605129277.404 [DDM](INFO): Latch State:0 2020-11-11T21:14:37.808Z,1605129277.808 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+179,F 1.1 2020-11-11T21:14:37.808Z,1605129277.808 [DDM](INFO): Latch State:0 2020-11-11T21:14:38.208Z,1605129278.208 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+179,F 1.1 2020-11-11T21:14:38.208Z,1605129278.208 [DDM](INFO): Latch State:0 2020-11-11T21:14:38.620Z,1605129278.620 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+179,F 1.1 2020-11-11T21:14:38.620Z,1605129278.620 [DDM](INFO): Latch State:0 2020-11-11T21:14:39.020Z,1605129279.020 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+180,F 1.1 2020-11-11T21:14:39.020Z,1605129279.020 [DDM](INFO): Latch State:0 2020-11-11T21:14:39.422Z,1605129279.422 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+180,F 1.1 2020-11-11T21:14:39.422Z,1605129279.422 [DDM](INFO): Latch State:0 2020-11-11T21:14:39.824Z,1605129279.824 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+179,F 1.1 2020-11-11T21:14:39.824Z,1605129279.824 [DDM](INFO): Latch State:0 2020-11-11T21:14:40.228Z,1605129280.228 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+179,F 1.1 2020-11-11T21:14:40.228Z,1605129280.228 [DDM](INFO): Latch State:0 2020-11-11T21:14:40.640Z,1605129280.640 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+180,F 1.1 2020-11-11T21:14:40.640Z,1605129280.640 [DDM](INFO): Latch State:0 2020-11-11T21:14:41.040Z,1605129281.040 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+180,F 1.1 2020-11-11T21:14:41.040Z,1605129281.040 [DDM](INFO): Latch State:0 2020-11-11T21:14:41.440Z,1605129281.440 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+179,F 1.1 2020-11-11T21:14:41.440Z,1605129281.440 [DDM](INFO): Latch State:0 2020-11-11T21:14:41.844Z,1605129281.844 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+180,F 1.1 2020-11-11T21:14:41.844Z,1605129281.844 [DDM](INFO): Latch State:0 2020-11-11T21:14:42.248Z,1605129282.248 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+179,F 1.1 2020-11-11T21:14:42.248Z,1605129282.248 [DDM](INFO): Latch State:0 2020-11-11T21:14:42.660Z,1605129282.660 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+179,F 1.1 2020-11-11T21:14:42.660Z,1605129282.660 [DDM](INFO): Latch State:0 2020-11-11T21:14:43.057Z,1605129283.057 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+179,F 1.1 2020-11-11T21:14:43.058Z,1605129283.058 [DDM](INFO): Latch State:0 2020-11-11T21:14:43.501Z,1605129283.501 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+180,F 1.1 2020-11-11T21:14:43.501Z,1605129283.501 [DDM](INFO): Latch State:0 2020-11-11T21:14:43.876Z,1605129283.876 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+180,F 1.1 2020-11-11T21:14:43.876Z,1605129283.876 [DDM](INFO): Latch State:0 2020-11-11T21:14:44.276Z,1605129284.276 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+180,F 1.1 2020-11-11T21:14:44.276Z,1605129284.276 [DDM](INFO): Latch State:0 2020-11-11T21:14:44.672Z,1605129284.672 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+179,F 1.1 2020-11-11T21:14:44.672Z,1605129284.672 [DDM](INFO): Latch State:0 2020-11-11T21:14:45.072Z,1605129285.072 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+178,F 1.1 2020-11-11T21:14:45.072Z,1605129285.072 [DDM](INFO): Latch State:0 2020-11-11T21:14:45.484Z,1605129285.484 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+179,F 1.1 2020-11-11T21:14:45.484Z,1605129285.484 [DDM](INFO): Latch State:0 2020-11-11T21:14:45.888Z,1605129285.888 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+180,F 1.1 2020-11-11T21:14:45.888Z,1605129285.888 [DDM](INFO): Latch State:0 2020-11-11T21:14:46.292Z,1605129286.292 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+180,F 1.1 2020-11-11T21:14:46.292Z,1605129286.292 [DDM](INFO): Latch State:0 2020-11-11T21:14:46.692Z,1605129286.692 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+179,F 1.1 2020-11-11T21:14:46.692Z,1605129286.692 [DDM](INFO): Latch State:0 2020-11-11T21:14:47.116Z,1605129287.116 [DDM](INFO): Latch Status Query:!F1,00,90,R,00,+179,F 1.1 2020-11-11T21:14:47.116Z,1605129287.116 [DDM](INFO): Latch State:0 2020-11-11T21:14:47.474Z,1605129287.474 [DDM](FAULT): Failed to open latch before timeout during detach. 2020-11-11T21:14:47.474Z,1605129287.474 [DDM] Hardware Fault, FailCount= 1 2020-11-11T21:14:47.474Z,1605129287.474 [DDM](ERROR): Hardware Fault 2020-11-11T21:14:47.599Z,1605129287.599 [CBIT](ERROR): Hardware Fault in component: DDM 2020-11-11T21:14:47.879Z,1605129287.879 [DDM](INFO): Powering down 2020-11-11T21:14:48.795Z,1605129288.795 [CBIT](INFO): Clearing failed state for component DDM 2020-11-11T21:14:48.795Z,1605129288.795 [DDM] No Fault, FailCount= 1 2020-11-11T21:14:51.548Z,1605129291.548 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-11-11T21:14:53.141Z,1605129293.141 [DDM](INFO): Powering up 2020-11-11T21:14:53.141Z,1605129293.141 [DDM](DEBUG): Initializing DDM. 2020-11-11T21:15:08.886Z,1605129308.886 [DDM](INFO): Dynamic Docking Module:!C REMUS Capture Rev 1.1 2020-11-11T21:15:08.948Z,1605129308.948 [DDM](INFO): Latch Status Query:!F1,00,00,S,40,+180,F 1.1 2020-11-11T21:15:08.948Z,1605129308.948 [DDM](INFO): Latch State:0 2020-11-11T21:15:08.972Z,1605129308.972 [DDM](INFO): Whisker Status Query:!F2,10,00,S,40,+008,F 1.1 2020-11-11T21:15:08.972Z,1605129308.972 [DDM](INFO): Whisker State:2 2020-11-11T21:15:09.017Z,1605129309.017 [DDM](INFO): Cable Status Query:!CP232 2020-11-11T21:15:09.018Z,1605129309.018 [DDM](INFO): Cable State:232 2020-11-11T21:15:09.290Z,1605129309.290 [DDM](INFO): Detaching. Opening Latch 2020-11-11T21:15:09.316Z,1605129309.316 [DDM](INFO): Latch Status Query:!F1,10,90,S,00,+181,F 1.1 2020-11-11T21:15:09.316Z,1605129309.316 [DDM](INFO): Latch State:2 2020-11-11T21:15:09.344Z,1605129309.344 [DDM](INFO): Cable Status Query:!CP225 2020-11-11T21:15:09.344Z,1605129309.344 [DDM](INFO): Cable State:225 2020-11-11T21:15:09.720Z,1605129309.720 [DDM](INFO): Cable Status Query:!CP223 2020-11-11T21:15:09.721Z,1605129309.721 [DDM](INFO): Cable State:223 2020-11-11T21:15:10.128Z,1605129310.128 [DDM](INFO): Cable Status Query:!CP216 2020-11-11T21:15:10.128Z,1605129310.128 [DDM](INFO): Cable State:216 2020-11-11T21:15:10.530Z,1605129310.530 [DDM](INFO): Cable Status Query:!CP216 2020-11-11T21:15:10.530Z,1605129310.530 [DDM](INFO): Cable State:216 2020-11-11T21:15:10.932Z,1605129310.932 [DDM](INFO): Cable Status Query:!CP214 2020-11-11T21:15:10.932Z,1605129310.932 [DDM](INFO): Cable State:214 2020-11-11T21:15:11.336Z,1605129311.336 [DDM](INFO): Cable Status Query:!CP214 2020-11-11T21:15:11.336Z,1605129311.336 [DDM](INFO): Cable State:214 2020-11-11T21:15:11.745Z,1605129311.745 [DDM](INFO): Cable Status Query:!CP208 2020-11-11T21:15:11.746Z,1605129311.746 [DDM](INFO): Cable State:208 2020-11-11T21:15:12.146Z,1605129312.146 [DDM](INFO): Cable Status Query:!CP212 2020-11-11T21:15:12.146Z,1605129312.146 [DDM](INFO): Cable State:212 2020-11-11T21:15:12.552Z,1605129312.552 [DDM](INFO): Cable Status Query:!CP215 2020-11-11T21:15:12.552Z,1605129312.552 [DDM](INFO): Cable State:215 2020-11-11T21:15:12.952Z,1605129312.952 [DDM](INFO): Cable Status Query:!CP216 2020-11-11T21:15:12.952Z,1605129312.952 [DDM](INFO): Cable State:216 2020-11-11T21:15:13.360Z,1605129313.360 [DDM](INFO): Cable Status Query:!CP210 2020-11-11T21:15:13.360Z,1605129313.360 [DDM](INFO): Cable State:210 2020-11-11T21:15:13.765Z,1605129313.765 [DDM](INFO): Cable Status Query:!CP216 2020-11-11T21:15:13.765Z,1605129313.765 [DDM](INFO): Cable State:216 2020-11-11T21:15:14.284Z,1605129314.284 [DDM](INFO): Cable Status Query:!CP222 2020-11-11T21:15:14.284Z,1605129314.284 [DDM](INFO): Cable State:222 2020-11-11T21:15:14.576Z,1605129314.576 [DDM](INFO): Cable Status Query:!CP212 2020-11-11T21:15:14.576Z,1605129314.576 [DDM](INFO): Cable State:212 2020-11-11T21:15:14.980Z,1605129314.980 [DDM](INFO): Cable Status Query:!CP209 2020-11-11T21:15:14.980Z,1605129314.980 [DDM](INFO): Cable State:209 2020-11-11T21:15:15.372Z,1605129315.372 [DDM](INFO): Cable Status Query:!CP216 2020-11-11T21:15:15.372Z,1605129315.372 [DDM](INFO): Cable State:216 2020-11-11T21:15:15.805Z,1605129315.805 [DDM](INFO): Cable Status Query:!CP201 2020-11-11T21:15:15.807Z,1605129315.807 [DDM](INFO): Cable State:201 2020-11-11T21:15:16.184Z,1605129316.184 [DDM](INFO): Cable Status Query:!CP207 2020-11-11T21:15:16.184Z,1605129316.184 [DDM](INFO): Cable State:207 2020-11-11T21:15:16.592Z,1605129316.592 [DDM](INFO): Cable Status Query:!CP214 2020-11-11T21:15:16.592Z,1605129316.592 [DDM](INFO): Cable State:214 2020-11-11T21:15:16.000Z,1605129317.000 [DDM](INFO): Cable Status Query:!CP223 2020-11-11T21:15:17.000Z,1605129317.000 [DDM](INFO): Cable State:223 2020-11-11T21:15:17.392Z,1605129317.392 [DDM](INFO): Cable Status Query:!CP208 2020-11-11T21:15:17.392Z,1605129317.392 [DDM](INFO): Cable State:208 2020-11-11T21:15:17.812Z,1605129317.812 [DDM](INFO): Cable Status Query:!CP208 2020-11-11T21:15:17.812Z,1605129317.812 [DDM](INFO): Cable State:208 2020-11-11T21:15:17.838Z,1605129317.838 [PowerOnly](INFO): Powering down loadControl 2020-11-11T21:15:18.201Z,1605129318.201 [DDM](INFO): Cable Status Query:!CP218 2020-11-11T21:15:18.202Z,1605129318.202 [DDM](INFO): Cable State:218 2020-11-11T21:15:18.610Z,1605129318.610 [DDM](INFO): Cable Status Query:!CP210 2020-11-11T21:15:18.610Z,1605129318.610 [DDM](INFO): Cable State:210 2020-11-11T21:15:19.008Z,1605129319.008 [DDM](INFO): Cable Status Query:!CP210 2020-11-11T21:15:19.009Z,1605129319.009 [DDM](INFO): Cable State:210 2020-11-11T21:15:19.412Z,1605129319.412 [DDM](INFO): Cable Status Query:!CP213 2020-11-11T21:15:19.412Z,1605129319.412 [DDM](INFO): Cable State:213 2020-11-11T21:15:19.820Z,1605129319.820 [DDM](INFO): Cable Status Query:!CP216 2020-11-11T21:15:19.820Z,1605129319.820 [DDM](INFO): Cable State:216 2020-11-11T21:15:20.224Z,1605129320.224 [DDM](INFO): Cable Status Query:!CP217 2020-11-11T21:15:20.224Z,1605129320.224 [DDM](INFO): Cable State:217 2020-11-11T21:15:20.632Z,1605129320.632 [DDM](INFO): Cable Status Query:!CP205 2020-11-11T21:15:20.632Z,1605129320.632 [DDM](INFO): Cable State:205 2020-11-11T21:15:21.041Z,1605129321.041 [DDM](INFO): Cable Status Query:!CP205 2020-11-11T21:15:21.042Z,1605129321.042 [DDM](INFO): Cable State:205 2020-11-11T21:15:21.448Z,1605129321.448 [DDM](INFO): Cable Status Query:!CP207 2020-11-11T21:15:21.448Z,1605129321.448 [DDM](INFO): Cable State:207 2020-11-11T21:15:21.840Z,1605129321.840 [DDM](INFO): Cable Status Query:!CP217 2020-11-11T21:15:21.840Z,1605129321.840 [DDM](INFO): Cable State:217 2020-11-11T21:15:22.244Z,1605129322.244 [DDM](INFO): Cable Status Query:!CP212 2020-11-11T21:15:22.244Z,1605129322.244 [DDM](INFO): Cable State:212 2020-11-11T21:15:22.644Z,1605129322.644 [DDM](INFO): Cable Status Query:!CP210 2020-11-11T21:15:22.644Z,1605129322.644 [DDM](INFO): Cable State:210 2020-11-11T21:15:23.048Z,1605129323.048 [DDM](INFO): Cable Status Query:!CP215 2020-11-11T21:15:23.048Z,1605129323.048 [DDM](INFO): Cable State:215 2020-11-11T21:15:23.452Z,1605129323.452 [DDM](INFO): Cable Status Query:!CP197 2020-11-11T21:15:23.452Z,1605129323.452 [DDM](INFO): Cable State:197 2020-11-11T21:15:23.856Z,1605129323.856 [DDM](INFO): Cable Status Query:!CP212 2020-11-11T21:15:23.856Z,1605129323.856 [DDM](INFO): Cable State:212 2020-11-11T21:15:24.276Z,1605129324.276 [DDM](INFO): Cable Status Query:!CP214 2020-11-11T21:15:24.276Z,1605129324.276 [DDM](INFO): Cable State:214 2020-11-11T21:15:24.668Z,1605129324.668 [DDM](INFO): Cable Status Query:!CP203 2020-11-11T21:15:24.668Z,1605129324.668 [DDM](INFO): Cable State:203 2020-11-11T21:15:25.072Z,1605129325.072 [DDM](INFO): Cable Status Query:!CP204 2020-11-11T21:15:25.072Z,1605129325.072 [DDM](INFO): Cable State:204 2020-11-11T21:15:25.480Z,1605129325.480 [DDM](INFO): Cable Status Query:!CP193 2020-11-11T21:15:25.480Z,1605129325.480 [DDM](INFO): Cable State:193 2020-11-11T21:15:25.882Z,1605129325.882 [DDM](INFO): Cable Status Query:!CP216 2020-11-11T21:15:25.882Z,1605129325.882 [DDM](INFO): Cable State:216 2020-11-11T21:15:26.280Z,1605129326.280 [DDM](INFO): Cable Status Query:!CP197 2020-11-11T21:15:26.280Z,1605129326.280 [DDM](INFO): Cable State:197 2020-11-11T21:15:26.688Z,1605129326.688 [DDM](INFO): Cable Status Query:!CP219 2020-11-11T21:15:26.688Z,1605129326.688 [DDM](INFO): Cable State:219 2020-11-11T21:15:26.689Z,1605129326.689 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-11-11T21:15:27.112Z,1605129327.112 [DDM](INFO): Cable Status Query:!CP206 2020-11-11T21:15:27.112Z,1605129327.112 [DDM](INFO): Cable State:206 2020-11-11T21:15:27.497Z,1605129327.497 [DDM](INFO): Cable Status Query:!CP209 2020-11-11T21:15:27.498Z,1605129327.498 [DDM](INFO): Cable State:209 2020-11-11T21:15:27.900Z,1605129327.900 [DDM](INFO): Cable Status Query:!CP197 2020-11-11T21:15:27.900Z,1605129327.900 [DDM](INFO): Cable State:197 2020-11-11T21:15:28.304Z,1605129328.304 [DDM](INFO): Cable Status Query:!CP210 2020-11-11T21:15:28.304Z,1605129328.304 [DDM](INFO): Cable State:210 2020-11-11T21:15:28.713Z,1605129328.713 [DDM](INFO): Cable Status Query:!CP209 2020-11-11T21:15:28.714Z,1605129328.714 [DDM](INFO): Cable State:209 2020-11-11T21:15:29.114Z,1605129329.114 [DDM](INFO): Cable Status Query:!CP216 2020-11-11T21:15:29.114Z,1605129329.114 [DDM](INFO): Cable State:216 2020-11-11T21:15:29.516Z,1605129329.516 [DDM](INFO): Cable Status Query:!CP203 2020-11-11T21:15:29.516Z,1605129329.516 [DDM](INFO): Cable State:203 2020-11-11T21:15:29.920Z,1605129329.920 [DDM](INFO): Cable Status Query:!CP205 2020-11-11T21:15:29.920Z,1605129329.920 [DDM](INFO): Cable State:205 2020-11-11T21:15:30.328Z,1605129330.328 [DDM](INFO): Cable Status Query:!CP205 2020-11-11T21:15:30.328Z,1605129330.328 [DDM](INFO): Cable State:205 2020-11-11T21:15:30.732Z,1605129330.732 [DDM](INFO): Cable Status Query:!CP211 2020-11-11T21:15:30.733Z,1605129330.733 [DDM](INFO): Cable State:211 2020-11-11T21:15:31.136Z,1605129331.136 [DDM](INFO): Cable Status Query:!CP219 2020-11-11T21:15:31.136Z,1605129331.136 [DDM](INFO): Cable State:219 2020-11-11T21:15:31.536Z,1605129331.536 [DDM](INFO): Cable Status Query:!CP211 2020-11-11T21:15:31.536Z,1605129331.536 [DDM](INFO): Cable State:211 2020-11-11T21:15:31.940Z,1605129331.940 [DDM](INFO): Cable Status Query:!CP221 2020-11-11T21:15:31.940Z,1605129331.940 [DDM](INFO): Cable State:221 2020-11-11T21:15:32.340Z,1605129332.340 [DDM](INFO): Cable Status Query:!CP197 2020-11-11T21:15:32.340Z,1605129332.340 [DDM](INFO): Cable State:197 2020-11-11T21:15:32.752Z,1605129332.752 [DDM](INFO): Cable Status Query:!CP205 2020-11-11T21:15:32.753Z,1605129332.753 [DDM](INFO): Cable State:205 2020-11-11T21:15:33.168Z,1605129333.168 [DDM](INFO): Cable Status Query:!CP201 2020-11-11T21:15:33.168Z,1605129333.168 [DDM](INFO): Cable State:201 2020-11-11T21:15:33.557Z,1605129333.557 [DDM](INFO): Cable Status Query:!CP207 2020-11-11T21:15:33.558Z,1605129333.558 [DDM](INFO): Cable State:207 2020-11-11T21:15:33.960Z,1605129333.960 [DDM](INFO): Cable Status Query:!CP202 2020-11-11T21:15:33.960Z,1605129333.960 [DDM](INFO): Cable State:202 2020-11-11T21:15:34.364Z,1605129334.364 [DDM](INFO): Cable Status Query:!CP209 2020-11-11T21:15:34.364Z,1605129334.364 [DDM](INFO): Cable State:209 2020-11-11T21:15:34.788Z,1605129334.788 [DDM](INFO): Cable Status Query:!CP203 2020-11-11T21:15:34.788Z,1605129334.788 [DDM](INFO): Cable State:203 2020-11-11T21:15:35.172Z,1605129335.172 [DDM](INFO): Cable Status Query:!CP210 2020-11-11T21:15:35.172Z,1605129335.172 [DDM](INFO): Cable State:210 2020-11-11T21:15:35.576Z,1605129335.576 [DDM](INFO): Cable Status Query:!CP203 2020-11-11T21:15:35.576Z,1605129335.576 [DDM](INFO): Cable State:203 2020-11-11T21:15:35.980Z,1605129335.980 [DDM](INFO): Cable Status Query:!CP205 2020-11-11T21:15:35.980Z,1605129335.980 [DDM](INFO): Cable State:205 2020-11-11T21:15:36.388Z,1605129336.388 [DDM](INFO): Cable Status Query:!CP217 2020-11-11T21:15:36.389Z,1605129336.389 [DDM](INFO): Cable State:217 2020-11-11T21:15:36.804Z,1605129336.804 [DDM](INFO): Cable Status Query:!CP212 2020-11-11T21:15:36.804Z,1605129336.804 [DDM](INFO): Cable State:212 2020-11-11T21:15:37.192Z,1605129337.192 [DDM](INFO): Cable Status Query:!CP203 2020-11-11T21:15:37.192Z,1605129337.192 [DDM](INFO): Cable State:203 2020-11-11T21:15:37.600Z,1605129337.600 [DDM](INFO): Cable Status Query:!CP203 2020-11-11T21:15:37.600Z,1605129337.600 [DDM](INFO): Cable State:203 2020-11-11T21:15:37.000Z,1605129338.000 [DDM](INFO): Cable Status Query:!CP203 2020-11-11T21:15:38.000Z,1605129338.000 [DDM](INFO): Cable State:203 2020-11-11T21:15:38.401Z,1605129338.401 [DDM](INFO): Cable Status Query:!CP211 2020-11-11T21:15:38.402Z,1605129338.402 [DDM](INFO): Cable State:211 2020-11-11T21:15:38.816Z,1605129338.816 [DDM](INFO): Cable Status Query:!CP205 2020-11-11T21:15:38.816Z,1605129338.816 [DDM](INFO): Cable State:205 2020-11-11T21:15:39.212Z,1605129339.212 [DDM](INFO): Cable Status Query:!CP202 2020-11-11T21:15:39.212Z,1605129339.212 [DDM](INFO): Cable State:202 2020-11-11T21:15:39.612Z,1605129339.612 [DDM](INFO): Cable Status Query:!CP205 2020-11-11T21:15:39.612Z,1605129339.612 [DDM](INFO): Cable State:205 2020-11-11T21:15:40.021Z,1605129340.021 [DDM](INFO): Cable Status Query:!CP214 2020-11-11T21:15:40.022Z,1605129340.022 [DDM](INFO): Cable State:214 2020-11-11T21:15:40.424Z,1605129340.424 [DDM](INFO): Cable Status Query:!CP207 2020-11-11T21:15:40.424Z,1605129340.424 [DDM](INFO): Cable State:207 2020-11-11T21:15:40.824Z,1605129340.824 [DDM](INFO): Cable Status Query:!CP207 2020-11-11T21:15:40.824Z,1605129340.824 [DDM](INFO): Cable State:207 2020-11-11T21:15:41.234Z,1605129341.234 [DDM](INFO): Cable Status Query:!CP215 2020-11-11T21:15:41.234Z,1605129341.234 [DDM](INFO): Cable State:215 2020-11-11T21:15:41.632Z,1605129341.632 [DDM](INFO): Cable Status Query:!CP203 2020-11-11T21:15:41.632Z,1605129341.632 [DDM](INFO): Cable State:203 2020-11-11T21:15:42.041Z,1605129342.041 [DDM](INFO): Cable Status Query:!CP204 2020-11-11T21:15:42.041Z,1605129342.041 [DDM](INFO): Cable State:204 2020-11-11T21:15:42.448Z,1605129342.448 [DDM](INFO): Cable Status Query:!CP204 2020-11-11T21:15:42.448Z,1605129342.448 [DDM](INFO): Cable State:204 2020-11-11T21:15:42.844Z,1605129342.844 [DDM](INFO): Cable Status Query:!CP206 2020-11-11T21:15:42.844Z,1605129342.844 [DDM](INFO): Cable State:206 2020-11-11T21:15:43.252Z,1605129343.252 [DDM](INFO): Cable Status Query:!CP208 2020-11-11T21:15:43.252Z,1605129343.252 [DDM](INFO): Cable State:208 2020-11-11T21:15:43.664Z,1605129343.664 [DDM](INFO): Cable Status Query:!CP208 2020-11-11T21:15:43.664Z,1605129343.664 [DDM](INFO): Cable State:208 2020-11-11T21:15:44.089Z,1605129344.089 [DDM](INFO): Cable Status Query:!CP201 2020-11-11T21:15:44.089Z,1605129344.089 [DDM](INFO): Cable State:201 2020-11-11T21:15:44.460Z,1605129344.460 [DDM](INFO): Cable Status Query:!CP202 2020-11-11T21:15:44.460Z,1605129344.460 [DDM](INFO): Cable State:202 2020-11-11T21:15:44.868Z,1605129344.868 [DDM](INFO): Cable Status Query:!CP205 2020-11-11T21:15:44.868Z,1605129344.868 [DDM](INFO): Cable State:205 2020-11-11T21:15:45.272Z,1605129345.272 [DDM](INFO): Cable Status Query:!CP201 2020-11-11T21:15:45.272Z,1605129345.272 [DDM](INFO): Cable State:201 2020-11-11T21:15:45.672Z,1605129345.672 [DDM](INFO): Cable Status Query:!CP204 2020-11-11T21:15:45.672Z,1605129345.672 [DDM](INFO): Cable State:204 2020-11-11T21:15:46.080Z,1605129346.080 [DDM](INFO): Cable Status Query:!CP204 2020-11-11T21:15:46.080Z,1605129346.080 [DDM](INFO): Cable State:204 2020-11-11T21:15:46.484Z,1605129346.484 [DDM](INFO): Cable Status Query:!CP198 2020-11-11T21:15:46.484Z,1605129346.484 [DDM](INFO): Cable State:198 2020-11-11T21:15:46.884Z,1605129346.884 [DDM](INFO): Cable Status Query:!CP205 2020-11-11T21:15:46.884Z,1605129346.884 [DDM](INFO): Cable State:205 2020-11-11T21:15:47.296Z,1605129347.296 [DDM](INFO): Cable Status Query:!CP203 2020-11-11T21:15:47.296Z,1605129347.296 [DDM](INFO): Cable State:203 2020-11-11T21:15:47.337Z,1605129347.337 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](ERROR): Pitch down timeout. Pitch: -2.94 2020-11-11T21:15:47.696Z,1605129347.696 [DDM](INFO): Cable Status Query:!CP200 2020-11-11T21:15:47.696Z,1605129347.696 [DDM](INFO): Cable State:200 2020-11-11T21:15:48.096Z,1605129348.096 [DDM](INFO): Cable Status Query:!CP214 2020-11-11T21:15:48.096Z,1605129348.096 [DDM](INFO): Cable State:214 2020-11-11T21:15:48.508Z,1605129348.508 [DDM](INFO): Cable Status Query:!CP221 2020-11-11T21:15:48.508Z,1605129348.508 [DDM](INFO): Cable State:221 2020-11-11T21:15:48.913Z,1605129348.913 [DDM](INFO): Cable Status Query:!CP217 2020-11-11T21:15:48.914Z,1605129348.914 [DDM](INFO): Cable State:217 2020-11-11T21:15:49.312Z,1605129349.312 [DDM](INFO): Cable Status Query:!CP210 2020-11-11T21:15:49.312Z,1605129349.312 [DDM](INFO): Cable State:210 2020-11-11T21:15:49.716Z,1605129349.716 [DDM](INFO): Cable Status Query:!CP206 2020-11-11T21:15:49.716Z,1605129349.716 [DDM](INFO): Cable State:206 2020-11-11T21:15:50.124Z,1605129350.124 [DDM](INFO): Cable Status Query:!CP214 2020-11-11T21:15:50.124Z,1605129350.124 [DDM](INFO): Cable State:214 2020-11-11T21:15:50.526Z,1605129350.526 [DDM](INFO): Cable Status Query:!CP216 2020-11-11T21:15:50.526Z,1605129350.526 [DDM](INFO): Cable State:216 2020-11-11T21:15:50.928Z,1605129350.928 [DDM](INFO): Cable Status Query:!CP204 2020-11-11T21:15:50.928Z,1605129350.928 [DDM](INFO): Cable State:204 2020-11-11T21:15:51.332Z,1605129351.332 [DDM](INFO): Cable Status Query:!CP203 2020-11-11T21:15:51.332Z,1605129351.332 [DDM](INFO): Cable State:203 2020-11-11T21:15:51.741Z,1605129351.741 [DDM](INFO): Cable Status Query:!CP200 2020-11-11T21:15:51.742Z,1605129351.742 [DDM](INFO): Cable State:200 2020-11-11T21:15:52.142Z,1605129352.142 [DDM](INFO): Cable Status Query:!CP210 2020-11-11T21:15:52.142Z,1605129352.142 [DDM](INFO): Cable State:210 2020-11-11T21:15:52.551Z,1605129352.551 [DDM](INFO): Cable Status Query:!CP198 2020-11-11T21:15:52.560Z,1605129352.560 [DDM](INFO): Cable State:198 2020-11-11T21:15:52.949Z,1605129352.949 [DDM](INFO): Cable Status Query:!CP207 2020-11-11T21:15:52.950Z,1605129352.950 [DDM](INFO): Cable State:207 2020-11-11T21:15:53.356Z,1605129353.356 [DDM](INFO): Cable Status Query:!CP204 2020-11-11T21:15:53.356Z,1605129353.356 [DDM](INFO): Cable State:204 2020-11-11T21:15:53.756Z,1605129353.756 [DDM](INFO): Cable Status Query:!CP202 2020-11-11T21:15:53.757Z,1605129353.757 [DDM](INFO): Cable State:202 2020-11-11T21:15:54.168Z,1605129354.168 [DDM](INFO): Cable Status Query:!CP205 2020-11-11T21:15:54.168Z,1605129354.168 [DDM](INFO): Cable State:205 2020-11-11T21:15:54.568Z,1605129354.568 [DDM](INFO): Cable Status Query:!CP202 2020-11-11T21:15:54.568Z,1605129354.568 [DDM](INFO): Cable State:202 2020-11-11T21:15:54.970Z,1605129354.970 [DDM](INFO): Cable Status Query:!CP201 2020-11-11T21:15:54.970Z,1605129354.970 [DDM](INFO): Cable State:201 2020-11-11T21:15:55.376Z,1605129355.376 [DDM](INFO): Cable Status Query:!CP204 2020-11-11T21:15:55.376Z,1605129355.376 [DDM](INFO): Cable State:204 2020-11-11T21:15:55.812Z,1605129355.812 [DDM](INFO): Cable Status Query:!CP200 2020-11-11T21:15:55.812Z,1605129355.812 [DDM](INFO): Cable State:200 2020-11-11T21:15:56.184Z,1605129356.184 [DDM](INFO): Cable Status Query:!CP202 2020-11-11T21:15:56.184Z,1605129356.184 [DDM](INFO): Cable State:202 2020-11-11T21:15:56.586Z,1605129356.586 [DDM](INFO): Cable Status Query:!CP205 2020-11-11T21:15:56.586Z,1605129356.586 [DDM](INFO): Cable State:205 2020-11-11T21:15:56.988Z,1605129356.988 [DDM](INFO): Cable Status Query:!CP205 2020-11-11T21:15:56.988Z,1605129356.988 [DDM](INFO): Cable State:205 2020-11-11T21:15:57.392Z,1605129357.392 [DDM](INFO): Cable Status Query:!CP203 2020-11-11T21:15:57.392Z,1605129357.392 [DDM](INFO): Cable State:203 2020-11-11T21:15:57.812Z,1605129357.812 [DDM](INFO): Cable Status Query:!CP199 2020-11-11T21:15:57.812Z,1605129357.812 [DDM](INFO): Cable State:199 2020-11-11T21:15:58.202Z,1605129358.202 [DDM](INFO): Cable Status Query:!CP210 2020-11-11T21:15:58.202Z,1605129358.202 [DDM](INFO): Cable State:210 2020-11-11T21:15:58.600Z,1605129358.600 [DDM](INFO): Cable Status Query:!CP199 2020-11-11T21:15:58.600Z,1605129358.600 [DDM](INFO): Cable State:199 2020-11-11T21:15:59.008Z,1605129359.008 [DDM](INFO): Cable Status Query:!CP201 2020-11-11T21:15:59.008Z,1605129359.008 [DDM](INFO): Cable State:201 2020-11-11T21:15:59.432Z,1605129359.432 [DDM](INFO): Cable Status Query:!CP205 2020-11-11T21:15:59.432Z,1605129359.432 [DDM](INFO): Cable State:205 2020-11-11T21:15:59.820Z,1605129359.820 [DDM](INFO): Cable Status Query:!CP203 2020-11-11T21:15:59.820Z,1605129359.820 [DDM](INFO): Cable State:203 2020-11-11T21:16:00.216Z,1605129360.216 [DDM](INFO): Cable Status Query:!CP201 2020-11-11T21:16:00.216Z,1605129360.216 [DDM](INFO): Cable State:201 2020-11-11T21:16:00.624Z,1605129360.624 [DDM](INFO): Cable Status Query:!CP203 2020-11-11T21:16:00.624Z,1605129360.624 [DDM](INFO): Cable State:203 2020-11-11T21:16:01.024Z,1605129361.024 [DDM](INFO): Cable Status Query:!CP211 2020-11-11T21:16:01.024Z,1605129361.024 [DDM](INFO): Cable State:211 2020-11-11T21:16:01.106Z,1605129361.106 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=1 2020-11-11T21:16:01.106Z,1605129361.106 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2020-11-11T21:16:01.108Z,1605129361.108 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2020-11-11T21:16:01.108Z,1605129361.108 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen] Stopped 2020-11-11T21:16:01.108Z,1605129361.108 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:HoldLatchOpen 2020-11-11T21:16:01.108Z,1605129361.108 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen:A] Stopped 2020-11-11T21:16:01.108Z,1605129361.108 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen:B] Stopped 2020-11-11T21:16:01.809Z,1605129361.809 [DDM](INFO): Pause: Retracting whiskers 2020-11-11T21:16:01.828Z,1605129361.828 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-11-11T21:16:02.224Z,1605129362.224 [DDM](INFO): Pausing: Closing Latch 2020-11-11T21:16:02.252Z,1605129362.252 [DDM](INFO): Latch Status Query:!F1,10,90,X,00,+181,F 1.1 2020-11-11T21:16:02.252Z,1605129362.252 [DDM](INFO): Latch State:2 2020-11-11T21:16:02.618Z,1605129362.618 [DDM](INFO): Pausing: Closing Latch 2020-11-11T21:16:02.648Z,1605129362.648 [DDM](INFO): Latch Status Query:!F1,00,90,X,00,+163,F 1.1 2020-11-11T21:16:02.648Z,1605129362.648 [DDM](INFO): Latch State:0 2020-11-11T21:16:03.064Z,1605129363.064 [DDM](INFO): Latch Status Query:!F1,00,90,X,00,+105,F 1.1 2020-11-11T21:16:03.064Z,1605129363.064 [DDM](INFO): Latch State:0 2020-11-11T21:16:03.452Z,1605129363.452 [DDM](INFO): Latch Status Query:!F1,00,90,X,00,+054,F 1.1 2020-11-11T21:16:03.452Z,1605129363.452 [DDM](INFO): Latch State:0 2020-11-11T21:16:03.856Z,1605129363.856 [DDM](INFO): Latch Status Query:!F1,01,90,S,00,+016,F 1.1 2020-11-11T21:16:03.856Z,1605129363.856 [DDM](INFO): Latch State:1 2020-11-11T21:16:22.823Z,1605129382.823 [NAL9602](INFO): SBD MO Status=0, MOMSN=11979, MT Status=0, MTMSN=0 2020-11-11T21:16:22.823Z,1605129382.823 [NAL9602](INFO): No messages in MT queue 2020-11-11T21:16:24.071Z,1605129384.071 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211622.00,A,4132.93667,N,07042.54589,W,0.486,345.10,111120,,,A*7E 2020-11-11T21:16:24.073Z,1605129384.073 [NAL9602](INFO): GPS fix at 20201111T211622: (41.548944, -70.709098) 2020-11-11T21:16:24.108Z,1605129384.108 [UniversalFixResidualReporter](INFO): Fix residual: 4.2 %DT, over the last 2481.1 m. Residual distance 104.6 m at bearing 49.7 degrees. Fix at (41.5489, -70.7091) with 479.8 m made good. 2020-11-11T21:16:24.109Z,1605129384.109 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2020-11-11T21:16:24.109Z,1605129384.109 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2020-11-11T21:16:34.839Z,1605129394.839 [NAL9602](INFO): SBD MO Status=1, MOMSN=11980, MT Status=0, MTMSN=0 2020-11-11T21:16:34.892Z,1605129394.892 [NAL9602](INFO): Sent 184 bytes from file Logs/20201111T200517/Courier0009.lzma 2020-11-11T21:16:34.892Z,1605129394.892 [NAL9602](INFO): Packets left to send: 1 2020-11-11T21:16:36.972Z,1605129396.972 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-11-11T21:16:51.146Z,1605129411.146 [NAL9602](INFO): SBD MO Status=2, MOMSN=11981, MT Status=2, MTMSN=0 2020-11-11T21:16:51.146Z,1605129411.146 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-11-11T21:17:12.112Z,1605129432.112 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-11-11T21:17:21.153Z,1605129441.153 [NAL9602](INFO): SBD MO Status=2, MOMSN=11981, MT Status=2, MTMSN=0 2020-11-11T21:17:21.153Z,1605129441.153 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-11-11T21:17:41.079Z,1605129461.079 [NAL9602](INFO): SBD MO Status=1, MOMSN=11981, MT Status=0, MTMSN=0 2020-11-11T21:17:41.128Z,1605129461.128 [NAL9602](INFO): Sent 87 bytes from file Logs/20201111T200517/Courier0009.lzma 2020-11-11T21:17:41.128Z,1605129461.128 [NAL9602](INFO): Packets left to send: 0 2020-11-11T21:17:41.233Z,1605129461.233 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003607 2020-11-11T21:17:45.260Z,1605129465.260 [DataOverHttps](INFO): Sending 7459 bytes from file Logs/20201111T200517/Express0011.lzma 2020-11-11T21:17:47.254Z,1605129467.254 [DataOverHttps](INFO): Moved sent file to Logs/20201111T200517/Express0011.lzma.bak 2020-11-11T21:17:47.254Z,1605129467.254 [DataOverHttps](INFO): SBD MOMSN=57157 2020-11-11T21:17:48.581Z,1605129468.581 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2020-11-11T21:17:48.581Z,1605129468.581 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1 2020-11-11T21:17:48.582Z,1605129468.582 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-11-11T21:17:56.632Z,1605129476.632 [NAL9602](INFO): SBD MO Status=1, MOMSN=11982, MT Status=0, MTMSN=0 2020-11-11T21:17:56.680Z,1605129476.680 [NAL9602](INFO): Sent 184 bytes from file Logs/20201111T200517/Express0011.lzma 2020-11-11T21:17:56.680Z,1605129476.680 [NAL9602](INFO): Packets left to send: 40 2020-11-11T21:18:27.330Z,1605129507.330 [NAL9602](INFO): Not Powering down - fast GPS 2020-11-11T21:20:07.945Z,1605129607.945 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-11-11T21:20:18.055Z,1605129618.055 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-11-11T21:21:09.237Z,1605129669.237 [CommandLine](IMPORTANT): got command configSet DDM.currentLimit 99.000000 count persist 2020-11-11T21:21:26.731Z,1605129686.731 [CommandLine](IMPORTANT): got command restart application 2020-11-11T21:21:27.740Z,1605129687.740 [Supervisor](INFO): Stop Mission called by Supervisor::terminate 2020-11-11T21:21:27.741Z,1605129687.741 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2020-11-11T21:21:27.742Z,1605129687.742 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-11-11T21:21:27.900Z,1605129687.900 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2020-11-11T21:21:27.900Z,1605129687.900 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-11-11T21:21:27.901Z,1605129687.901 [CommandLine](INFO): Join timeout helper Thread ID is 2376 2020-11-11T21:21:27.901Z,1605129687.901 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2020-11-11T21:21:27.901Z,1605129687.901 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-11-11T21:21:27.901Z,1605129687.901 [NavChartDb](INFO): Join timeout helper Thread ID is 2377 2020-11-11T21:21:28.084Z,1605129688.084 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2020-11-11T21:21:28.084Z,1605129688.084 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-11-11T21:21:28.100Z,1605129688.100 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler 2020-11-11T21:21:28.100Z,1605129688.100 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2020-11-11T21:21:28.100Z,1605129688.100 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 2378 2020-11-11T21:21:28.196Z,1605129688.196 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread. 2020-11-11T21:21:28.196Z,1605129688.196 [WetLabsSeaOWL_UV_A](INFO): Powering down 2020-11-11T21:21:28.197Z,1605129688.197 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2020-11-11T21:21:28.200Z,1605129688.200 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2020-11-11T21:21:28.200Z,1605129688.200 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2020-11-11T21:21:28.200Z,1605129688.200 [CTD_Seabird](INFO): Join timeout helper Thread ID is 2379 2020-11-11T21:21:28.640Z,1605129688.640 [CTD_Seabird](INFO): Powering down 2020-11-11T21:21:28.656Z,1605129688.656 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2020-11-11T21:21:28.656Z,1605129688.656 [CTD_Seabird](INFO): Powering down 2020-11-11T21:21:28.668Z,1605129688.668 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2020-11-11T21:21:28.688Z,1605129688.688 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2020-11-11T21:21:28.688Z,1605129688.688 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-11-11T21:21:28.688Z,1605129688.688 [Radio_Surface](INFO): Join timeout helper Thread ID is 2380 2020-11-11T21:21:28.896Z,1605129688.896 [Radio_Surface](INFO): Powering down 2020-11-11T21:21:28.897Z,1605129688.897 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2020-11-11T21:21:28.897Z,1605129688.897 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-11-11T21:21:28.905Z,1605129688.905 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2020-11-11T21:21:28.905Z,1605129688.905 [Onboard ThreadHandler](INFO): Thread cancelled. 2020-11-11T21:21:28.905Z,1605129688.905 [Onboard](INFO): Join timeout helper Thread ID is 2381 2020-11-11T21:21:29.192Z,1605129689.192 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2020-11-11T21:21:32.812Z,1605129692.812 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2020-11-11T21:21:32.812Z,1605129692.812 [Onboard ThreadHandler](INFO): Thread cancelled. 2020-11-11T21:21:32.832Z,1605129692.832 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2020-11-11T21:21:32.833Z,1605129692.833 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-11-11T21:21:32.833Z,1605129692.833 [DataOverHttps](INFO): Join timeout helper Thread ID is 2382 2020-11-11T21:21:33.208Z,1605129693.208 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2020-11-11T21:21:33.208Z,1605129693.208 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-11-11T21:21:33.225Z,1605129693.225 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2020-11-11T21:21:33.225Z,1605129693.225 [logger ThreadHandler](INFO): Thread cancelled. 2020-11-11T21:21:33.225Z,1605129693.225 [logger](INFO): Join timeout helper Thread ID is 2383 2020-11-11T21:21:33.246Z,1605129693.246 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2020-11-11T21:21:33.247Z,1605129693.247 [logger ThreadHandler](INFO): Thread cancelled. 2020-11-11T21:21:33.264Z,1605129693.264 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2020-11-11T21:21:33.265Z,1605129693.265 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-11-11T21:21:33.265Z,1605129693.265 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2020-11-11T21:21:33.265Z,1605129693.265 [controlThread ThreadHandler](INFO): Thread cancelled. 2020-11-11T21:21:33.265Z,1605129693.265 [controlThread](INFO): Join timeout helper Thread ID is 2384 2020-11-11T21:21:33.564Z,1605129693.564 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2020-11-11T21:21:33.564Z,1605129693.564 [controlThread](DEBUG): Uninitializing ControlThread 2020-11-11T21:21:33.564Z,1605129693.564 [AHRS_M2](INFO): Powering down 2020-11-11T21:21:33.636Z,1605129693.636 [DDM](INFO): Powering down 2020-11-11T21:21:33.708Z,1605129693.708 [DUSBL_Hydroid](INFO): Powering down 2020-11-11T21:21:33.781Z,1605129693.781 [Micromodem](INFO): Powering down 2020-11-11T21:21:33.876Z,1605129693.876 [NAL9602](INFO): Powering down 2020-11-11T21:21:33.948Z,1605129693.948 [RDI_Pathfinder](INFO): Powering down 2020-11-11T21:21:34.020Z,1605129694.020 [RDI_PathfinderUp](INFO): Powering down 2020-11-11T21:21:34.021Z,1605129694.021 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2020-11-11T21:21:34.022Z,1605129694.022 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2020-11-11T21:21:34.023Z,1605129694.023 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2020-11-11T21:21:34.023Z,1605129694.023 [MissionManager](INFO): Uninitializing Mission DefaultWithUndock 2020-11-11T21:21:34.023Z,1605129694.023 [DefaultWithUndock] Stopped 2020-11-11T21:21:34.024Z,1605129694.024 [DefaultWithUndock](DEBUG): Aggregate::uninitialize DefaultWithUndock 2020-11-11T21:21:34.024Z,1605129694.024 [DefaultWithUndock:SurfaceDefault] Stopped 2020-11-11T21:21:34.024Z,1605129694.024 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault 2020-11-11T21:21:34.024Z,1605129694.024 [DefaultWithUndock:SurfaceDefault:B.GoToSurface] Stopped 2020-11-11T21:21:34.024Z,1605129694.024 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2020-11-11T21:21:34.024Z,1605129694.024 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped 2020-11-11T21:21:34.024Z,1605129694.024 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn 2020-11-11T21:21:34.024Z,1605129694.024 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped 2020-11-11T21:21:34.024Z,1605129694.024 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-11-11T21:21:34.030Z,1605129694.030 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2020-11-11T21:21:34.030Z,1605129694.030 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2020-11-11T21:21:34.031Z,1605129694.031 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2020-11-11T21:21:34.031Z,1605129694.031 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2020-11-11T21:21:34.031Z,1605129694.031 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2020-11-11T21:21:34.031Z,1605129694.031 [BuoyancyServo](INFO): Powering down 2020-11-11T21:21:34.044Z,1605129694.044 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2020-11-11T21:21:34.044Z,1605129694.044 [ElevatorServo](INFO): Powering down 2020-11-11T21:21:34.045Z,1605129694.045 [MassServo](DEBUG): Uninitialize Mass Servo. 2020-11-11T21:21:34.045Z,1605129694.045 [MassServo](INFO): Powering down 2020-11-11T21:21:34.046Z,1605129694.046 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2020-11-11T21:21:34.046Z,1605129694.046 [RudderServo](INFO): Powering down 2020-11-11T21:21:34.046Z,1605129694.046 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2020-11-11T21:21:34.046Z,1605129694.046 [ThrusterServo](INFO): Powering down 2020-11-11T21:21:34.047Z,1605129694.047 [SBIT](DEBUG): Uninitialize SBIT Component. 2020-11-11T21:21:34.047Z,1605129694.047 [IBIT](DEBUG): Uninitialize IBIT Component. 2020-11-11T21:21:34.048Z,1605129694.048 [CBIT](DEBUG): Uninitialize CBIT Component. 2020-11-11T21:21:34.048Z,1605129694.048 [CBIT](DEBUG): Powering off loads. 2020-11-11T21:21:34.060Z,1605129694.060 [CBIT](DEBUG): Disabling WDT. 2020-11-11T21:21:34.072Z,1605129694.072 [CBIT](DEBUG): Opening all GF detection circuits. 2020-11-11T21:21:34.073Z,1605129694.073 [controlThread ThreadHandler](INFO): Thread cancelled. 2020-11-11T21:21:34.127Z,1605129694.127 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-11-11T21:21:34.130Z,1605129694.130 [Onboard ThreadHandler](INFO): Thread cancelled. 2020-11-11T21:21:34.140Z,1605129694.140 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-11-11T21:21:34.192Z,1605129694.192 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2020-11-11T21:21:34.195Z,1605129694.195 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2020-11-11T21:21:34.256Z,1605129694.256 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-11-11T21:21:34.325Z,1605129694.325 [logger ThreadHandler](INFO): Thread cancelled.