2020-05-09T16:01:17.825Z,1589040077.825 [DataOverHttps](INFO): Received command:restart logs
2020-05-09T16:01:17.838Z,1589040077.838 [CommandLine](IMPORTANT): got command restart logs
2020-05-09T16:01:30.122Z,1589040090.122 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items
2020-05-09T16:01:56.758Z,1589040116.758 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-05-09T16:02:17.766Z,1589040137.766 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-05-09T16:02:29.524Z,1589040149.524 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for altitude
2020-05-09T16:02:33.139Z,1589040153.139 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:02:36.399Z,1589040156.399 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-05-09T16:02:38.792Z,1589040158.792 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting.
2020-05-09T16:02:38.792Z,1589040158.792 [DefaultWithUndock:CheckIn:C.Wait] Stopped
2020-05-09T16:02:38.797Z,1589040158.797 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-05-09T16:02:38.797Z,1589040158.797 [DefaultWithUndock:CheckIn:D] Running Loop=1
2020-05-09T16:02:39.216Z,1589040159.216 [DefaultWithUndock:CheckIn:D] Stopped
2020-05-09T16:02:39.216Z,1589040159.216 [DefaultWithUndock:CheckIn:E] Running Loop=1
2020-05-09T16:02:39.629Z,1589040159.629 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 10.372697 min 
2020-05-09T16:02:39.630Z,1589040159.630 [DefaultWithUndock:CheckIn:E] Stopped
2020-05-09T16:02:39.630Z,1589040159.630 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn
2020-05-09T16:02:39.630Z,1589040159.630 [DefaultWithUndock:CheckIn] Stopped
2020-05-09T16:02:39.630Z,1589040159.630 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-05-09T16:02:39.630Z,1589040159.630 [DefaultWithUndock:CheckIn](INFO): Running loop #2
2020-05-09T16:02:39.630Z,1589040159.630 [DefaultWithUndock:CheckIn] Running Loop=2
2020-05-09T16:02:39.630Z,1589040159.630 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-05-09T16:02:39.630Z,1589040159.630 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-05-09T16:02:41.574Z,1589040161.574 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160216.00,A,4133.08368,N,07042.51038,W,1.030,193.60,090520,,,A*71
2020-05-09T16:02:41.576Z,1589040161.576 [NAL9602](INFO): GPS fix at 20200509T160216: (41.551395, -70.708506)
2020-05-09T16:02:41.637Z,1589040161.637 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-05-09T16:02:41.637Z,1589040161.637 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-05-09T16:02:51.391Z,1589040171.391 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-05-09T16:03:13.297Z,1589040193.297 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-05-09T16:03:17.318Z,1589040197.318 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200509T144751/Courier0048.lzma
2020-05-09T16:03:18.319Z,1589040198.319 [DataOverHttps](INFO): Moved sent file to Logs/20200509T144751/Courier0048.lzma.bak
2020-05-09T16:03:18.319Z,1589040198.319 [DataOverHttps](INFO): SBD MOMSN=12941
2020-05-09T16:03:19.216Z,1589040199.216 [NAL9602](INFO): SBD MO Status=2, MOMSN=11107, MT Status=2, MTMSN=0
2020-05-09T16:03:19.217Z,1589040199.217 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-05-09T16:03:27.381Z,1589040207.381 [DataOverHttps](INFO): Sending 268 bytes from file Logs/20200509T160117/Courier0000.lzma
2020-05-09T16:03:28.363Z,1589040208.363 [DataOverHttps](INFO): Moved sent file to Logs/20200509T160117/Courier0000.lzma.bak
2020-05-09T16:03:28.363Z,1589040208.363 [DataOverHttps](INFO): SBD MOMSN=12943
2020-05-09T16:03:39.405Z,1589040219.405 [DataOverHttps](INFO): Sending 95 bytes from file Logs/20200509T144751/Express0049.lzma
2020-05-09T16:03:39.817Z,1589040219.817 [NAL9602](INFO): SBD MO Status=0, MOMSN=11107, MT Status=0, MTMSN=0
2020-05-09T16:03:39.817Z,1589040219.817 [NAL9602](INFO): No messages in MT queue
2020-05-09T16:03:40.408Z,1589040220.408 [DataOverHttps](INFO): Moved sent file to Logs/20200509T144751/Express0049.lzma.bak
2020-05-09T16:03:40.408Z,1589040220.408 [DataOverHttps](INFO): SBD MOMSN=12947
2020-05-09T16:03:45.511Z,1589040225.511 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items
2020-05-09T16:03:49.445Z,1589040229.445 [DataOverHttps](INFO): Sending 332 bytes from file Logs/20200509T160117/Express0001.lzma
2020-05-09T16:03:50.446Z,1589040230.446 [DataOverHttps](INFO): Moved sent file to Logs/20200509T160117/Express0001.lzma.bak
2020-05-09T16:03:50.447Z,1589040230.447 [DataOverHttps](INFO): SBD MOMSN=12950
2020-05-09T16:03:50.750Z,1589040230.750 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:03:51.980Z,1589040231.980 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-05-09T16:03:51.980Z,1589040231.980 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1
2020-05-09T16:03:51.980Z,1589040231.980 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-05-09T16:04:10.523Z,1589040250.523 [NAL9602](INFO): Not Powering down - fast GPS
2020-05-09T16:05:31.825Z,1589040331.825 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout
2020-05-09T16:05:31.825Z,1589040331.825 [RDI_Pathfinder] Communications Fault, FailCount= 1
2020-05-09T16:05:31.825Z,1589040331.825 [RDI_Pathfinder](ERROR): Communications Fault
2020-05-09T16:05:31.874Z,1589040331.874 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-05-09T16:05:32.233Z,1589040332.233 [RDI_Pathfinder](INFO): Powering down
2020-05-09T16:05:32.975Z,1589040332.975 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-05-09T16:05:32.976Z,1589040332.976 [RDI_Pathfinder] No Fault, FailCount= 1
2020-05-09T16:08:06.503Z,1589040486.503 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:08:37.631Z,1589040517.631 [DataOverHttps](IMPORTANT): SBD MTMSN=20200509T160811
2020-05-09T16:08:41.649Z,1589040521.649 [DataOverHttps](INFO): Received command:sched asap "load Science/profile_station.xml;set profile_station.MissionTimeout 90 minute;set profile_station.NeedCommsTime 30 minute;set profile_station.Lat 41.552317 degree;set profile_station.Lon -70.7086 degree" 4prgr 1 3
2020-05-09T16:08:41.702Z,1589040521.702 [CommandLine](IMPORTANT): got command schedule asap "load Science/profile_station.xml;set profile_station.MissionTimeout 90 minute;set profile_station.NeedCommsTime 30 minute;set profile_station.Lat 41.552317 degree;set profile_station.Lon -70.7086 degree" 4prgr 1 3.000000
2020-05-09T16:08:41.702Z,1589040521.702 [CommandLine](IMPORTANT): Scheduling command #1 of 3 with id=4prgr
2020-05-09T16:08:41.703Z,1589040521.703 [CommandLine](IMPORTANT): Scheduled #18 (#1 of 3 with id='4prgr'): "load Science/profile_station.xml;set profile_station.MissionTimeout 90 minute;set profile_station.NeedCommsTime 30 minute;set profile_station.Lat 41.552317 degree;set profile_station.Lon -70.7086 degree" ASAP
2020-05-09T16:08:42.652Z,1589040522.652 [DataOverHttps](IMPORTANT): SBD MTMSN=20200509T160816
2020-05-09T16:08:46.669Z,1589040526.669 [DataOverHttps](INFO): Received command:sched asap "set profile_station.YoYoMinDepth 3 meter;set profile_station.YoYoMaxDepth 7 meter;set profile_station.YoYoMinAltitude 3 meter;set profile_station.YoYoPitch 15 degree;set profile_station.MinAltitude 3 meter" 4prgr 2 3
2020-05-09T16:08:46.754Z,1589040526.754 [CommandLine](IMPORTANT): got command schedule asap "set profile_station.YoYoMinDepth 3 meter;set profile_station.YoYoMaxDepth 7 meter;set profile_station.YoYoMinAltitude 3 meter;set profile_station.YoYoPitch 15 degree;set profile_station.MinAltitude 3 meter" 4prgr 2 3.000000
2020-05-09T16:08:46.754Z,1589040526.754 [CommandLine](IMPORTANT): Scheduling command #2 of 3 with id=4prgr
2020-05-09T16:08:46.755Z,1589040526.755 [CommandLine](IMPORTANT): Scheduled #19 (#2 of 3 with id='4prgr'): "set profile_station.YoYoMinDepth 3 meter;set profile_station.YoYoMaxDepth 7 meter;set profile_station.YoYoMinAltitude 3 meter;set profile_station.YoYoPitch 15 degree;set profile_station.MinAltitude 3 meter" ASAP
2020-05-09T16:08:47.671Z,1589040527.671 [DataOverHttps](IMPORTANT): SBD MTMSN=20200509T160821
2020-05-09T16:08:51.689Z,1589040531.689 [DataOverHttps](INFO): Received command:sched asap "set profile_station.MaxDepth 9 meter;set profile_station.MinOffshore 500 meter;run " 4prgr 3 3
2020-05-09T16:08:51.706Z,1589040531.706 [CommandLine](IMPORTANT): got command schedule asap "set profile_station.MaxDepth 9 meter;set profile_station.MinOffshore 500 meter;run " 4prgr 3 3.000000
2020-05-09T16:08:51.707Z,1589040531.707 [CommandLine](IMPORTANT): Scheduling command #3 of 3 with id=4prgr
2020-05-09T16:08:51.708Z,1589040531.708 [CommandLine](IMPORTANT): Scheduled #20 (#3 of 3 with id='4prgr'): "set profile_station.MaxDepth 9 meter;set profile_station.MinOffshore 500 meter;run " ASAP
2020-05-09T16:08:51.827Z,1589040531.827 [CommandLine](IMPORTANT): got command load ./Missions/Science/profile_station.xml
2020-05-09T16:08:51.828Z,1589040531.828 [MissionManager](INFO): Loading Mission: ./Missions/Science/profile_station.xml
2020-05-09T16:08:51.912Z,1589040531.912 [MissionManager](INFO): DefineArg profile_station.MissionTimeout = 4.000000 h
2020-05-09T16:08:51.914Z,1589040531.914 [MissionManager](INFO): DefineArg profile_station.NeedCommsTime = 60.000000 min
2020-05-09T16:08:51.917Z,1589040531.917 [MissionManager](INFO): DefineArg profile_station.Lat = 36.806966 arcdeg
2020-05-09T16:08:51.919Z,1589040531.919 [MissionManager](INFO): DefineArg profile_station.Lon = -121.824326 arcdeg
2020-05-09T16:08:51.922Z,1589040531.922 [MissionManager](INFO): DefineArg profile_station.Radius = 300.000000 m
2020-05-09T16:08:51.924Z,1589040531.924 [MissionManager](INFO): DefineArg profile_station.YoYoMinDepth = 2.000000 m
2020-05-09T16:08:51.926Z,1589040531.926 [MissionManager](INFO): DefineArg profile_station.YoYoMaxDepth = 200.000000 m
2020-05-09T16:08:51.957Z,1589040531.957 [MissionManager](INFO): DefineArg profile_station.YoYoMinAltitude = 7.000000 m
2020-05-09T16:08:51.959Z,1589040531.959 [MissionManager](INFO): DefineArg profile_station.YoYoPitch = 20.000000 arcdeg
2020-05-09T16:08:51.966Z,1589040531.966 [MissionManager](INFO): DefineArg profile_station.Speed = 1.000000 m/s
2020-05-09T16:08:51.968Z,1589040531.968 [MissionManager](INFO): DefineArg profile_station.CircleMaxError = 100.000000 m
2020-05-09T16:08:51.974Z,1589040531.974 [MissionManager](INFO): DefineArg profile_station.CircleTurnToPort = 0 bool
2020-05-09T16:08:51.981Z,1589040531.981 [MissionManager](INFO): DefineArg profile_station.KwpHeading = 0.010000 rad/m
2020-05-09T16:08:51.983Z,1589040531.983 [MissionManager](INFO): DefineArg profile_station.MinAltitude = 5.000000 m
2020-05-09T16:08:51.996Z,1589040531.996 [MissionManager](INFO): DefineArg profile_station.MaxDepth = 205.000000 m
2020-05-09T16:08:52.002Z,1589040532.002 [MissionManager](INFO): DefineArg profile_station.MinOffshore = 2.000000 km
2020-05-09T16:08:52.003Z,1589040532.003 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml
2020-05-09T16:08:52.347Z,1589040532.347 [MissionManager](INFO): DefineArg profile_station:Science.SampleISUS = 0 bool
2020-05-09T16:08:52.349Z,1589040532.349 [MissionManager](INFO): DefineArg profile_station:Science.SampleISUSmaxPitch = 60.000000 arcdeg
2020-05-09T16:08:52.352Z,1589040532.352 [MissionManager](INFO): DefineArg profile_station:Science.SampleISUSminPitch = -60.000000 arcdeg
2020-05-09T16:08:52.367Z,1589040532.367 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectChlActive = 0 bool
2020-05-09T16:08:52.369Z,1589040532.369 [MissionManager](INFO): DefineArg profile_station:Science.TimeWindowPeakReport = nan min
2020-05-09T16:08:52.371Z,1589040532.371 [MissionManager](INFO): DefineArg profile_station:Science.HighestChlPeakReportActive = 0 bool
2020-05-09T16:08:52.373Z,1589040532.373 [MissionManager](INFO): DefineArg profile_station:Science.PatchTracking = 0 bool
2020-05-09T16:08:52.375Z,1589040532.375 [MissionManager](INFO): DefineArg profile_station:Science.FilterWidthHorizontal = 3.000000 count
2020-05-09T16:08:52.378Z,1589040532.378 [MissionManager](INFO): DefineArg profile_station:Science.NumProfilesSlidingwindow = 100.000000 count
2020-05-09T16:08:52.380Z,1589040532.380 [MissionManager](INFO): DefineArg profile_station:Science.OffPeakFractionHorizontal = 80.000000 %
2020-05-09T16:08:52.382Z,1589040532.382 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectNO3Active = 0 bool
2020-05-09T16:08:52.384Z,1589040532.384 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectOilActive = 0 bool
2020-05-09T16:08:52.386Z,1589040532.386 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectFDOMActive = 0 bool
2020-05-09T16:08:52.388Z,1589040532.388 [MissionManager](INFO): DefineArg profile_station:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2020-05-09T16:08:52.396Z,1589040532.396 [MissionManager](INFO): DefineArg profile_station:Science.EnabledAanderaaO2 = 0.000000 bool
2020-05-09T16:08:52.398Z,1589040532.398 [MissionManager](INFO): DefineArg profile_station:Science.EnabledNeilBrown = 0.000000 bool
2020-05-09T16:08:52.401Z,1589040532.401 [MissionManager](INFO): DefineArg profile_station:Science.EnabledSeabird = 0.000000 bool
2020-05-09T16:08:52.404Z,1589040532.404 [MissionManager](INFO): DefineArg profile_station:Science.EnabledTurbulence_NPS = 0.000000 bool
2020-05-09T16:08:52.423Z,1589040532.423 [MissionManager](INFO): DefineArg profile_station:Science.EnabledWetLabsBB2FL = 0.000000 bool
2020-05-09T16:08:52.429Z,1589040532.429 [MissionManager](INFO): DefineArg profile_station:Science.EnabledWetLabsSeaOWL_UV_A = 1.000000 bool
2020-05-09T16:08:52.432Z,1589040532.432 [MissionManager](INFO): DefineArg profile_station:Science.EnabledWetLabsUBAT = 0.000000 bool
2020-05-09T16:08:52.439Z,1589040532.439 [MissionManager](INFO): DefineArg profile_station:Science.EnabledISUS = 0.000000 bool
2020-05-09T16:08:52.445Z,1589040532.445 [MissionManager](INFO): DefineArg profile_station:Science.LowPassWindowLength = 20.000000 count
2020-05-09T16:08:52.448Z,1589040532.448 [MissionManager](INFO): DefineArg profile_station:Science.MedianFilterLen = 5.000000 count
2020-05-09T16:08:52.458Z,1589040532.458 [MissionManager](INFO): DefineArg profile_station:Science.PeakChlShallowBound = nan m
2020-05-09T16:08:52.460Z,1589040532.460 [MissionManager](INFO): DefineArg profile_station:Science.PeakChlDeepBound = nan m
2020-05-09T16:08:52.469Z,1589040532.469 [MissionManager](INFO): DefineArg profile_station:Science.PeakShallowBound = nan m
2020-05-09T16:08:52.472Z,1589040532.472 [MissionManager](INFO): DefineArg profile_station:Science.PeakDeepBound = nan m
2020-05-09T16:08:52.474Z,1589040532.474 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChl = 0.000000 ug/l
2020-05-09T16:08:52.476Z,1589040532.476 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlDepth = 0.000000 m
2020-05-09T16:08:52.498Z,1589040532.498 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlTemperature = 0.000000 degC
2020-05-09T16:08:52.529Z,1589040532.529 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlLatitude = nan arcdeg
2020-05-09T16:08:52.567Z,1589040532.567 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlLongitude = nan arcdeg
2020-05-09T16:08:52.574Z,1589040532.574 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3 = 0.000000 umol/l
2020-05-09T16:08:52.576Z,1589040532.576 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3Depth = 0.000000 m
2020-05-09T16:08:52.594Z,1589040532.594 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3Latitude = nan arcdeg
2020-05-09T16:08:52.605Z,1589040532.605 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3Longitude = nan arcdeg
2020-05-09T16:08:52.608Z,1589040532.608 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChl = 0.000000 m
2020-05-09T16:08:52.614Z,1589040532.614 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlDepth = 0.000000 m
2020-05-09T16:08:52.625Z,1589040532.625 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlLatitude = nan arcdeg
2020-05-09T16:08:52.627Z,1589040532.627 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlLongitude = nan arcdeg
2020-05-09T16:08:52.636Z,1589040532.636 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlDistance = nan m
2020-05-09T16:08:52.642Z,1589040532.642 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOil = 0.000000 kg/m3
2020-05-09T16:08:52.649Z,1589040532.649 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOilDepth = 0.000000 m
2020-05-09T16:08:52.652Z,1589040532.652 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOilLatitude = nan arcdeg
2020-05-09T16:08:52.659Z,1589040532.659 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOilLongitude = nan arcdeg
2020-05-09T16:08:52.666Z,1589040532.666 [MissionManager](INFO): DefineOutput profile_station:Science.PeakFDOM = 0.000000 ppb
2020-05-09T16:08:52.670Z,1589040532.670 [MissionManager](INFO): DefineOutput profile_station:Science.PeakFDOMDepth = 0.000000 m
2020-05-09T16:08:52.672Z,1589040532.672 [MissionManager](INFO): DefineOutput profile_station:Science.PeakFDOMLatitude = nan arcdeg
2020-05-09T16:08:52.683Z,1589040532.683 [MissionManager](INFO): DefineOutput profile_station:Science.PeakFDOMLongitude = nan arcdeg
2020-05-09T16:08:52.688Z,1589040532.688 [MissionManager](INFO): DefineOutput profile_station:Science.PatchFDOMDistance = nan m
2020-05-09T16:08:52.728Z,1589040532.728 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2020-05-09T16:08:52.751Z,1589040532.751 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2020-05-09T16:08:52.782Z,1589040532.782 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2020-05-09T16:08:52.807Z,1589040532.807 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2020-05-09T16:08:52.840Z,1589040532.840 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2020-05-09T16:08:52.866Z,1589040532.866 [MissionManager](INFO): RedefineArg profile_station:Science.SampleISUS = value:1 bool
2020-05-09T16:08:52.867Z,1589040532.867 [MissionManager](INFO): RedefineArg profile_station:Science.PeakDetectChlActive = value:1 bool
2020-05-09T16:08:52.868Z,1589040532.868 [MissionManager](INFO): Inserting Stack: Missions/Insert/NeedComms.xml
2020-05-09T16:08:53.018Z,1589040533.018 [MissionManager](INFO): DefineArg profile_station:NeedComms.DiveInterval = 3.000000 h
2020-05-09T16:08:53.025Z,1589040533.025 [MissionManager](INFO): DefineArg profile_station:NeedComms.WaitForPitchUp = 10.000000 min
2020-05-09T16:08:53.027Z,1589040533.027 [MissionManager](INFO): DefineArg profile_station:NeedComms.SurfacePitch = 20.000000 arcdeg
2020-05-09T16:08:53.032Z,1589040533.032 [MissionManager](INFO): DefineArg profile_station:NeedComms.SurfaceDepthRate = nan m/s
2020-05-09T16:08:53.042Z,1589040533.042 [MissionManager](INFO): DefineArg profile_station:NeedComms.SurfaceSpeed = 1.000000 m/s
2020-05-09T16:08:53.049Z,1589040533.049 [MissionManager](INFO): DefineArg profile_station:NeedComms.GPSTimeout = 7.000000 min
2020-05-09T16:08:53.052Z,1589040533.052 [MissionManager](INFO): DefineArg profile_station:NeedComms.CommsTimeout = 30.000000 min
2020-05-09T16:08:53.066Z,1589040533.066 [profile_station:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-05-09T16:08:53.118Z,1589040533.118 [MissionManager](INFO): Inserting Stack: Missions/Insert/StandardEnvelopes.xml
2020-05-09T16:08:53.150Z,1589040533.150 [MissionManager](INFO): DefineArg profile_station:StandardEnvelopes.MinAltitude = 5.000000 m
2020-05-09T16:08:53.152Z,1589040533.152 [MissionManager](INFO): DefineArg profile_station:StandardEnvelopes.MaxDepth = 200.000000 m
2020-05-09T16:08:53.167Z,1589040533.167 [MissionManager](INFO): DefineArg profile_station:StandardEnvelopes.MinOffshore = 2000.000000 m
2020-05-09T16:08:53.168Z,1589040533.168 [profile_station:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2020-05-09T16:08:53.181Z,1589040533.181 [profile_station:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2020-05-09T16:08:53.202Z,1589040533.202 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2020-05-09T16:08:53.226Z,1589040533.226 [profile_station:I.Pitch](DEBUG): Construct.
2020-05-09T16:08:53.241Z,1589040533.241 [profile_station:J.Buoyancy](DEBUG): Construct Buoyancy.
2020-05-09T16:08:53.244Z,1589040533.244 [profile_station:K.SetSpeed](DEBUG): Construct.
2020-05-09T16:08:53.246Z,1589040533.246 [profile_station:L.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2020-05-09T16:08:53.263Z,1589040533.263 [profile_station:M.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2020-05-09T16:08:53.280Z,1589040533.280 [profile_station:N.YoYo](DEBUG): Construct YoYo.
2020-05-09T16:08:53.303Z,1589040533.303 [profile_station:CircleWrapper:B.Circle](DEBUG): Construct.
2020-05-09T16:08:53.416Z,1589040533.416 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Derivation="Tethys/Derivation"
       xmlns:Estimation="Tethys/Estimation"
       xmlns:Guidance="Tethys/Guidance"
       xmlns:Sensor="Tethys/Sensor"
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Derivation http://okeanids.mbari.org/tethys/Xml/Derivation.xsd
                           Tethys/Estimation http://okeanids.mbari.org/tethys/Xml/Estimation.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Sensor http://okeanids.mbari.org/tethys/Xml/Sensor.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="profile_station">

    <Description>
        This mission yoyos in a circle around a specified location.
    </Description>

<!-- Mission-wide settings -->

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

    <DefineArg Name="NeedCommsTime"><Description>
        Elapsed time after previous surface communications when vehicle will
        begin to ascend for additional surface communications
    </Description><Units:minute/><Value>60</Value></DefineArg>

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

<!-- Missions should almost always start with a timeout -->

    <Timeout Duration="MissionTimeout"/>

    <Insert Filename="Insert/Science.xml">
        <Description>
            Most missions will run the science sensors. If you don't place this
            aggregate above NeedComms, science instruments get turned off on the
            last upcast and while floating on the surface.
        </Description>
        <RedefineArg Name="SampleISUS"><True/></RedefineArg>
        <RedefineArg Name="PeakDetectChlActive"><True/></RedefineArg>
    </Insert>

<!-- Most missions should use the NeedComms aggregate. -->

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

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

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

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

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

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

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

<!-- Check for additional instructions first. -->

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

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

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

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

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

    <Guidance:DepthEnvelope>
        <Description>
            Another depth envelope for the YoYo behavior. This envelope should
            fall within the limits of the standard safety envelopes in
            Insert/StandardEnvelopes.xml in order to avoid commanding high pitch
            angles for depth-terminated YoYos.
        </Description>
        <Parallel/>
        <Setting><Guidance:DepthEnvelope.minDepth/><Arg Name="YoYoMinDepth"/></Setting>
        <Setting><Guidance:DepthEnvelope.maxDepth/><Arg Name="YoYoMaxDepth"/></Setting>
        <Setting><Guidance:DepthEnvelope.pitch/><Arg Name="YoYoPitch"/></Setting>
    </Guidance:DepthEnvelope>

    <Guidance:AltitudeEnvelope>
        <Description>
            Another altitude envelope for the YoYo behavior. This envelope
            should fall within the limits of the standard safety envelopes in
            Insert/StandardEnvelopes.xml in order to avoid commanding high pitch
            angles for bottom-terminated YoYos.
        </Description>
        <Parallel/>
        <Setting><Guidance:AltitudeEnvelope.minAltitude/><Arg Name="YoYoMinAltitude"/></Setting>
        <Setting><Guidance:AltitudeEnvelope.pitch/><Arg Name="YoYoPitch"/></Setting>
    </Guidance:AltitudeEnvelope>

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

    <Aggregate Id="CircleWrapper">

        <Sequence Repeat="4096"/>

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

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

    </Aggregate>

</Mission>


2020-05-09T16:08:53.425Z,1589040533.425 [CommandLine](IMPORTANT): Loaded ./Missions/Science/profile_station.xml
2020-05-09T16:08:53.780Z,1589040533.780 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting.
2020-05-09T16:08:53.780Z,1589040533.780 [DefaultWithUndock:CheckIn:C.Wait] Stopped
2020-05-09T16:08:53.780Z,1589040533.780 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-05-09T16:08:53.780Z,1589040533.780 [DefaultWithUndock:CheckIn:D] Running Loop=1
2020-05-09T16:08:54.206Z,1589040534.206 [DefaultWithUndock:CheckIn:D] Stopped
2020-05-09T16:08:54.206Z,1589040534.206 [DefaultWithUndock:CheckIn:E] Running Loop=1
2020-05-09T16:08:54.640Z,1589040534.640 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 16.622535 min 
2020-05-09T16:08:54.640Z,1589040534.640 [DefaultWithUndock:CheckIn:E] Stopped
2020-05-09T16:08:54.640Z,1589040534.640 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn
2020-05-09T16:08:54.640Z,1589040534.640 [DefaultWithUndock:CheckIn] Stopped
2020-05-09T16:08:54.640Z,1589040534.640 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-05-09T16:08:54.640Z,1589040534.640 [DefaultWithUndock:CheckIn](INFO): Running loop #3
2020-05-09T16:08:54.640Z,1589040534.640 [DefaultWithUndock:CheckIn] Running Loop=3
2020-05-09T16:08:54.655Z,1589040534.655 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-05-09T16:08:54.655Z,1589040534.655 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-05-09T16:08:56.565Z,1589040536.565 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160831.00,A,4133.04282,N,07042.50962,W,0.447,223.31,090520,,,A*79
2020-05-09T16:08:56.567Z,1589040536.567 [NAL9602](INFO): GPS fix at 20200509T160831: (41.550714, -70.708494)
2020-05-09T16:08:56.665Z,1589040536.665 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-05-09T16:08:56.665Z,1589040536.665 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-05-09T16:09:00.789Z,1589040540.789 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200509T160117/Courier0003.lzma
2020-05-09T16:09:01.727Z,1589040541.727 [DataOverHttps](INFO): Moved sent file to Logs/20200509T160117/Courier0003.lzma.bak
2020-05-09T16:09:01.727Z,1589040541.727 [DataOverHttps](INFO): SBD MOMSN=12959
2020-05-09T16:09:04.579Z,1589040544.579 [CommandLine](IMPORTANT): got command set profile_station.MissionTimeout 90.000000 minute
2020-05-09T16:09:04.579Z,1589040544.579 [CommandLine](IMPORTANT): got command set profile_station.NeedCommsTime 30.000000 minute
2020-05-09T16:09:04.580Z,1589040544.580 [CommandLine](IMPORTANT): got command set profile_station.Lat 41.552319 degree
2020-05-09T16:09:04.581Z,1589040544.581 [CommandLine](IMPORTANT): got command set profile_station.Lon -70.708603 degree
2020-05-09T16:09:10.770Z,1589040550.770 [DataOverHttps](INFO): Sending 558 bytes from file Logs/20200509T160117/Express0005.lzma
2020-05-09T16:09:11.767Z,1589040551.767 [DataOverHttps](INFO): Moved sent file to Logs/20200509T160117/Express0005.lzma.bak
2020-05-09T16:09:11.767Z,1589040551.767 [DataOverHttps](INFO): SBD MOMSN=12962
2020-05-09T16:09:13.300Z,1589040553.300 [NAL9602](INFO): SBD MO Status=0, MOMSN=11108, MT Status=0, MTMSN=0
2020-05-09T16:09:13.305Z,1589040553.305 [NAL9602](INFO): No messages in MT queue
2020-05-09T16:09:18.874Z,1589040558.874 [CommandLine](IMPORTANT): got command set profile_station.YoYoMinDepth 3.000000 meter
2020-05-09T16:09:18.875Z,1589040558.875 [CommandLine](IMPORTANT): got command set profile_station.YoYoMaxDepth 7.000000 meter
2020-05-09T16:09:18.875Z,1589040558.875 [CommandLine](IMPORTANT): got command set profile_station.YoYoMinAltitude 3.000000 meter
2020-05-09T16:09:18.876Z,1589040558.876 [CommandLine](IMPORTANT): got command set profile_station.YoYoPitch 15.000000 degree
2020-05-09T16:09:18.877Z,1589040558.877 [CommandLine](IMPORTANT): got command set profile_station.MinAltitude 3.000000 meter
2020-05-09T16:09:20.804Z,1589040560.804 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20200509T160117/Express0010.lzma
2020-05-09T16:09:21.807Z,1589040561.807 [DataOverHttps](INFO): Moved sent file to Logs/20200509T160117/Express0010.lzma.bak
2020-05-09T16:09:21.807Z,1589040561.807 [DataOverHttps](INFO): SBD MOMSN=12979
2020-05-09T16:09:23.465Z,1589040563.465 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-05-09T16:09:23.465Z,1589040563.465 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1
2020-05-09T16:09:23.465Z,1589040563.465 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-05-09T16:09:24.496Z,1589040564.496 [CommandLine](IMPORTANT): got command set profile_station.MaxDepth 9.000000 meter
2020-05-09T16:09:24.497Z,1589040564.497 [CommandLine](IMPORTANT): got command set profile_station.MinOffshore 500.000000 meter
2020-05-09T16:09:24.497Z,1589040564.497 [CommandLine](IMPORTANT): got command run 
2020-05-09T16:09:24.639Z,1589040564.639 [CommandLine](IMPORTANT): Running 
2020-05-09T16:09:24.656Z,1589040564.656 [DefaultWithUndock] Stopped
2020-05-09T16:09:24.656Z,1589040564.656 [DefaultWithUndock](DEBUG): Aggregate::uninitialize DefaultWithUndock
2020-05-09T16:09:24.656Z,1589040564.656 [DefaultWithUndock:C.GoToSurface] Stopped
2020-05-09T16:09:24.656Z,1589040564.656 [DefaultWithUndock:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-05-09T16:09:24.656Z,1589040564.656 [DefaultWithUndock:CheckIn] Stopped
2020-05-09T16:09:24.656Z,1589040564.656 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-05-09T16:09:24.656Z,1589040564.656 [DefaultWithUndock:CheckIn:C.Wait] Stopped
2020-05-09T16:09:24.656Z,1589040564.656 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-05-09T16:09:24.656Z,1589040564.656 [MissionManager](IMPORTANT): Started mission profile_station
2020-05-09T16:09:24.657Z,1589040564.657 [profile_station] Running Loop=1
2020-05-09T16:09:24.657Z,1589040564.657 [profile_station](DEBUG): Aggregate::initialize profile_station
2020-05-09T16:09:24.657Z,1589040564.657 [profile_station:Science] Running Loop=1
2020-05-09T16:09:24.697Z,1589040564.697 [profile_station:Science](DEBUG): Aggregate::initialize profile_station:Science
2020-05-09T16:09:24.697Z,1589040564.697 [profile_station:Science:A] Running Loop=1
2020-05-09T16:09:24.697Z,1589040564.697 [profile_station:Science:B] Running Loop=1
2020-05-09T16:09:24.697Z,1589040564.697 [profile_station:Science:C] Running Loop=1
2020-05-09T16:09:24.697Z,1589040564.697 [profile_station:Science:D] Running Loop=1
2020-05-09T16:09:24.697Z,1589040564.697 [profile_station:Science:E] Running Loop=1
2020-05-09T16:09:24.697Z,1589040564.697 [profile_station:Science:F] Running Loop=1
2020-05-09T16:09:24.697Z,1589040564.697 [profile_station:Science:G] Running Loop=1
2020-05-09T16:09:24.697Z,1589040564.697 [profile_station:Science:H] Running Loop=1
2020-05-09T16:09:24.698Z,1589040564.698 [profile_station:Science:Read_Oil] Running Loop=1
2020-05-09T16:09:24.698Z,1589040564.698 [profile_station:Science:PeakDetectChl] Running Loop=1
2020-05-09T16:09:24.698Z,1589040564.698 [profile_station:Science:PeakDetectChl](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectChl
2020-05-09T16:09:24.698Z,1589040564.698 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2020-05-09T16:09:24.698Z,1589040564.698 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2020-05-09T16:09:24.699Z,1589040564.699 [profile_station:Science:HighestChlPeakReport] Running Loop=1
2020-05-09T16:09:24.699Z,1589040564.699 [profile_station:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize profile_station:Science:HighestChlPeakReport
2020-05-09T16:09:24.699Z,1589040564.699 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2020-05-09T16:09:24.699Z,1589040564.699 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2020-05-09T16:09:24.700Z,1589040564.700 [profile_station:Science:PeakDetectNO3] Running Loop=1
2020-05-09T16:09:24.700Z,1589040564.700 [profile_station:Science:PeakDetectNO3](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectNO3
2020-05-09T16:09:24.700Z,1589040564.700 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2020-05-09T16:09:24.700Z,1589040564.700 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2020-05-09T16:09:24.709Z,1589040564.709 [profile_station:Science:PeakDetectOil] Running Loop=1
2020-05-09T16:09:24.709Z,1589040564.709 [profile_station:Science:PeakDetectOil](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectOil
2020-05-09T16:09:24.710Z,1589040564.710 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2020-05-09T16:09:24.710Z,1589040564.710 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2020-05-09T16:09:24.711Z,1589040564.711 [profile_station:Science:PeakDetectFDOM] Running Loop=1
2020-05-09T16:09:24.711Z,1589040564.711 [profile_station:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectFDOM
2020-05-09T16:09:24.711Z,1589040564.711 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2020-05-09T16:09:24.711Z,1589040564.711 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2020-05-09T16:09:24.712Z,1589040564.712 [profile_station:StandardEnvelopes] Running Loop=1
2020-05-09T16:09:24.712Z,1589040564.712 [profile_station:StandardEnvelopes](DEBUG): Aggregate::initialize profile_station:StandardEnvelopes
2020-05-09T16:09:24.712Z,1589040564.712 [profile_station:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2020-05-09T16:09:24.712Z,1589040564.712 [profile_station:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2020-05-09T16:09:24.712Z,1589040564.712 [profile_station:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2020-05-09T16:09:24.712Z,1589040564.712 [profile_station:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2020-05-09T16:09:24.718Z,1589040564.718 [profile_station:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2020-05-09T16:09:24.718Z,1589040564.718 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2020-05-09T16:09:24.718Z,1589040564.718 [profile_station:I.Pitch] Running Loop=1
2020-05-09T16:09:24.719Z,1589040564.719 [profile_station:I.Pitch](DEBUG): Initialize.
2020-05-09T16:09:24.719Z,1589040564.719 [profile_station:J.Buoyancy] Running Loop=1
2020-05-09T16:09:24.719Z,1589040564.719 [profile_station:J.Buoyancy](DEBUG): Initialize Buoyancy Component.
2020-05-09T16:09:24.719Z,1589040564.719 [profile_station:K.SetSpeed] Running Loop=1
2020-05-09T16:09:24.719Z,1589040564.719 [profile_station:K.SetSpeed](DEBUG): Initialize.
2020-05-09T16:09:24.719Z,1589040564.719 [profile_station:L.DepthEnvelope] Running Loop=1
2020-05-09T16:09:24.719Z,1589040564.719 [profile_station:L.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2020-05-09T16:09:24.719Z,1589040564.719 [profile_station:M.AltitudeEnvelope] Running Loop=1
2020-05-09T16:09:24.720Z,1589040564.720 [profile_station:M.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2020-05-09T16:09:24.720Z,1589040564.720 [profile_station:N.YoYo] Running Loop=1
2020-05-09T16:09:24.720Z,1589040564.720 [profile_station:N.YoYo](DEBUG): Initialize YoYoComponent.
2020-05-09T16:09:24.720Z,1589040564.720 [profile_station:C] Running Loop=1
2020-05-09T16:09:24.725Z,1589040564.725 [profile_station:N.YoYo] Running Loop=1
2020-05-09T16:09:24.725Z,1589040564.725 [profile_station:M.AltitudeEnvelope] Running Loop=1
2020-05-09T16:09:24.725Z,1589040564.725 [profile_station:L.DepthEnvelope] Running Loop=1
2020-05-09T16:09:24.733Z,1589040564.733 [profile_station:K.SetSpeed] Running Loop=1
2020-05-09T16:09:24.733Z,1589040564.733 [profile_station:J.Buoyancy] Running Loop=1
2020-05-09T16:09:24.734Z,1589040564.734 [profile_station:I.Pitch] Running Loop=1
2020-05-09T16:09:24.734Z,1589040564.734 [profile_station:StandardEnvelopes] Running Loop=1
2020-05-09T16:09:24.734Z,1589040564.734 [profile_station:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2020-05-09T16:09:24.734Z,1589040564.734 [profile_station:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2020-05-09T16:09:24.734Z,1589040564.734 [profile_station:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2020-05-09T16:09:24.735Z,1589040564.735 [profile_station:C] Stopped
2020-05-09T16:09:24.735Z,1589040564.735 [profile_station:E] Running Loop=1
2020-05-09T16:09:24.735Z,1589040564.735 [profile_station:Science] Running Loop=1
2020-05-09T16:09:24.736Z,1589040564.736 [profile_station:Science:PeakDetectFDOM] Stopped
2020-05-09T16:09:24.736Z,1589040564.736 [profile_station:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectFDOM
2020-05-09T16:09:24.741Z,1589040564.741 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2020-05-09T16:09:24.741Z,1589040564.741 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2020-05-09T16:09:24.742Z,1589040564.742 [profile_station:Science:PeakDetectOil] Stopped
2020-05-09T16:09:24.742Z,1589040564.742 [profile_station:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectOil
2020-05-09T16:09:24.743Z,1589040564.743 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2020-05-09T16:09:24.743Z,1589040564.743 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2020-05-09T16:09:24.743Z,1589040564.743 [profile_station:Science:PeakDetectNO3] Stopped
2020-05-09T16:09:24.743Z,1589040564.743 [profile_station:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectNO3
2020-05-09T16:09:24.743Z,1589040564.743 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2020-05-09T16:09:24.744Z,1589040564.744 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2020-05-09T16:09:24.744Z,1589040564.744 [profile_station:Science:HighestChlPeakReport] Stopped
2020-05-09T16:09:24.744Z,1589040564.744 [profile_station:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize profile_station:Science:HighestChlPeakReport
2020-05-09T16:09:24.744Z,1589040564.744 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2020-05-09T16:09:24.744Z,1589040564.744 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2020-05-09T16:09:24.744Z,1589040564.744 [profile_station:Science:PeakDetectChl] Running Loop=1
2020-05-09T16:09:24.744Z,1589040564.744 [profile_station:Science:Read_Oil] Running Loop=1
2020-05-09T16:09:24.753Z,1589040564.753 [profile_station:Science:Read_Oil](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2020-05-09T16:09:24.753Z,1589040564.753 [profile_station:Science:H] Stopped
2020-05-09T16:09:24.753Z,1589040564.753 [profile_station:Science:G] Running Loop=1
2020-05-09T16:09:24.754Z,1589040564.754 [profile_station:Science:G](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2020-05-09T16:09:24.754Z,1589040564.754 [profile_station:Science:F] Stopped
2020-05-09T16:09:24.754Z,1589040564.754 [profile_station:Science:E] Stopped
2020-05-09T16:09:24.754Z,1589040564.754 [profile_station:Science:D] Stopped
2020-05-09T16:09:24.754Z,1589040564.754 [profile_station:Science:C] Stopped
2020-05-09T16:09:24.754Z,1589040564.754 [profile_station:Science:B] Stopped
2020-05-09T16:09:24.754Z,1589040564.754 [profile_station:Science:A] Stopped
2020-05-09T16:09:25.005Z,1589040565.005 [WetLabsSeaOWL_UV_A](INFO): Powering up
2020-05-09T16:09:25.062Z,1589040565.062 [profile_station:E] Stopped
2020-05-09T16:09:25.062Z,1589040565.062 [profile_station:F] Running Loop=1
2020-05-09T16:09:25.478Z,1589040565.478 [profile_station:F] Stopped
2020-05-09T16:09:25.478Z,1589040565.478 [profile_station:G] Running Loop=1
2020-05-09T16:09:25.854Z,1589040565.854 [profile_station:G] Stopped
2020-05-09T16:09:25.854Z,1589040565.854 [profile_station:StartingMission] Running Loop=1
2020-05-09T16:09:25.854Z,1589040565.854 [profile_station:StartingMission](DEBUG): Aggregate::initialize profile_station:StartingMission
2020-05-09T16:09:26.231Z,1589040566.231 [profile_station:NeedComms] Running Loop=1
2020-05-09T16:09:26.231Z,1589040566.231 [profile_station:NeedComms](DEBUG): Aggregate::initialize profile_station:NeedComms
2020-05-09T16:09:26.231Z,1589040566.231 [profile_station:NeedComms:B.GoToSurface] Running Loop=1
2020-05-09T16:09:26.231Z,1589040566.231 [profile_station:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-05-09T16:09:26.231Z,1589040566.231 [profile_station:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2020-05-09T16:09:26.232Z,1589040566.232 [profile_station:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2020-05-09T16:09:26.232Z,1589040566.232 [profile_station:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2020-05-09T16:09:26.232Z,1589040566.232 [profile_station:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-05-09T16:09:26.232Z,1589040566.232 [profile_station:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-05-09T16:09:26.233Z,1589040566.233 [profile_station:NeedComms:A] Running Loop=1
2020-05-09T16:09:26.234Z,1589040566.234 [profile_station:NeedComms:A](INFO): last time_fix was: 1589040511.000000 second since 1970/01/01T00:00:00Z 
2020-05-09T16:09:26.235Z,1589040566.235 [profile_station:NeedComms:A] Stopped
2020-05-09T16:09:26.657Z,1589040566.657 [profile_station:NeedComms:C] Running Loop=1
2020-05-09T16:09:27.062Z,1589040567.062 [profile_station:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-05-09T16:09:28.625Z,1589040568.625 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160903.00,A,4133.03999,N,07042.50854,W,0.700,189.71,090520,,,A*7C
2020-05-09T16:09:28.627Z,1589040568.627 [NAL9602](INFO): GPS fix at 20200509T160903: (41.550666, -70.708476)
2020-05-09T16:09:28.654Z,1589040568.654 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-05-09T16:09:28.684Z,1589040568.684 [profile_station:NeedComms:C] Stopped
2020-05-09T16:09:28.684Z,1589040568.684 [profile_station:NeedComms:D] Running Loop=1
2020-05-09T16:09:29.128Z,1589040569.128 [profile_station:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-05-09T16:09:32.852Z,1589040572.852 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200509T160117/Courier0012.lzma
2020-05-09T16:09:33.855Z,1589040573.855 [DataOverHttps](INFO): Moved sent file to Logs/20200509T160117/Courier0012.lzma.bak
2020-05-09T16:09:33.855Z,1589040573.855 [DataOverHttps](INFO): SBD MOMSN=12981
2020-05-09T16:09:42.893Z,1589040582.893 [DataOverHttps](INFO): Sending 353 bytes from file Logs/20200509T160117/Express0013.lzma
2020-05-09T16:09:43.895Z,1589040583.895 [DataOverHttps](INFO): Moved sent file to Logs/20200509T160117/Express0013.lzma.bak
2020-05-09T16:09:43.895Z,1589040583.895 [DataOverHttps](INFO): SBD MOMSN=12985
2020-05-09T16:09:45.274Z,1589040585.274 [profile_station:NeedComms:D] Stopped
2020-05-09T16:09:45.274Z,1589040585.274 [profile_station:NeedComms:E] Running Loop=1
2020-05-09T16:09:45.662Z,1589040585.662 [profile_station:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-05-09T16:09:47.222Z,1589040587.222 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160921.00,A,4133.03747,N,07042.50764,W,1.205,196.30,090520,,,A*77
2020-05-09T16:09:47.225Z,1589040587.225 [NAL9602](INFO): GPS fix at 20200509T160921: (41.550624, -70.708461)
2020-05-09T16:09:47.286Z,1589040587.286 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-05-09T16:09:47.318Z,1589040587.318 [profile_station:NeedComms:E] Stopped
2020-05-09T16:09:47.319Z,1589040587.319 [profile_station:NeedComms](INFO): Completed profile_station:NeedComms
2020-05-09T16:09:47.319Z,1589040587.319 [profile_station:NeedComms] Stopped
2020-05-09T16:09:47.319Z,1589040587.319 [profile_station:NeedComms](DEBUG): Aggregate::uninitialize profile_station:NeedComms
2020-05-09T16:09:47.319Z,1589040587.319 [profile_station:NeedComms:B.GoToSurface] Stopped
2020-05-09T16:09:47.319Z,1589040587.319 [profile_station:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-05-09T16:09:47.682Z,1589040587.682 [profile_station:StartingMission](INFO): Completed profile_station:StartingMission
2020-05-09T16:09:47.682Z,1589040587.682 [profile_station:StartingMission] Stopped
2020-05-09T16:09:47.682Z,1589040587.682 [profile_station:StartingMission](DEBUG): Aggregate::uninitialize profile_station:StartingMission
2020-05-09T16:09:47.682Z,1589040587.682 [profile_station:CircleWrapper] Running Loop=1
2020-05-09T16:09:47.682Z,1589040587.682 [profile_station:CircleWrapper](DEBUG): Aggregate::initialize profile_station:CircleWrapper
2020-05-09T16:09:47.682Z,1589040587.682 [profile_station:CircleWrapper:A] Running Loop=1
2020-05-09T16:09:47.682Z,1589040587.682 [profile_station:CircleWrapper:B.Circle] Running Loop=1
2020-05-09T16:09:47.682Z,1589040587.682 [profile_station:CircleWrapper:B.Circle](DEBUG): Initialize CircleComponent.
2020-05-09T16:09:48.053Z,1589040588.053 [profile_station:CircleWrapper:A] Running Loop=1
2020-05-09T16:09:49.654Z,1589040589.654 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:10:07.486Z,1589040607.486 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items
2020-05-09T16:10:08.646Z,1589040608.646 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:10:11.135Z,1589040611.135 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for altitude
2020-05-09T16:10:11.488Z,1589040611.488 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-05-09T16:10:19.526Z,1589040619.526 [NAL9602](INFO): Not Powering down - fast GPS
2020-05-09T16:10:28.473Z,1589040628.473 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:10:29.656Z,1589040629.656 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-05-09T16:10:43.431Z,1589040643.431 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:10:50.651Z,1589040650.651 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:11:03.586Z,1589040663.586 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-05-09T16:11:16.138Z,1589040676.138 [RDI_PathfinderUp](ERROR): only read 1 of 4 data items
2020-05-09T16:11:25.318Z,1589040685.318 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.247421
2020-05-09T16:11:28.260Z,1589040688.260 [RDI_Pathfinder](ERROR): only read 1 of 4 data items
2020-05-09T16:11:35.505Z,1589040695.505 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:11:47.620Z,1589040707.620 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:11:49.274Z,1589040709.274 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-05-09T16:11:49.345Z,1589040709.345 [Radio_Surface](INFO): Powering down
2020-05-09T16:11:56.137Z,1589040716.137 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items
2020-05-09T16:12:01.470Z,1589040721.470 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-05-09T16:12:02.473Z,1589040722.473 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2020-05-09T16:12:10.273Z,1589040730.273 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-05-09T16:12:13.492Z,1589040733.492 [RDI_PathfinderUp](ERROR): only read 0 of 4 data items
2020-05-09T16:12:34.119Z,1589040754.119 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items
2020-05-09T16:12:50.264Z,1589040770.264 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:12:55.514Z,1589040775.514 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:13:16.536Z,1589040796.536 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items
2020-05-09T16:13:26.613Z,1589040806.613 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:14:10.649Z,1589040850.649 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:14:15.131Z,1589040855.131 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items
2020-05-09T16:14:36.512Z,1589040876.512 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items
2020-05-09T16:14:48.631Z,1589040888.631 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:14:50.264Z,1589040890.264 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-05-09T16:15:09.620Z,1589040909.620 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:15:39.146Z,1589040939.146 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items
2020-05-09T16:15:49.254Z,1589040949.254 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-05-09T16:15:58.142Z,1589040958.142 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for altitude
2020-05-09T16:16:15.116Z,1589040975.116 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items
2020-05-09T16:16:28.458Z,1589040988.458 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items
2020-05-09T16:16:31.638Z,1589040991.638 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-05-09T16:16:59.129Z,1589041019.129 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items
2020-05-09T16:18:15.474Z,1589041095.474 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items
2020-05-09T16:18:26.417Z,1589041106.417 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:18:50.612Z,1589041130.612 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-05-09T16:20:02.149Z,1589041202.149 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items
2020-05-09T16:20:12.614Z,1589041212.614 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:20:21.522Z,1589041221.522 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items
2020-05-09T16:20:33.630Z,1589041233.630 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:20:54.272Z,1589041254.272 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:21:13.626Z,1589041273.626 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:21:22.140Z,1589041282.140 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items
2020-05-09T16:21:34.642Z,1589041294.642 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:21:39.490Z,1589041299.490 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:21:41.124Z,1589041301.124 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:21:51.619Z,1589041311.619 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-05-09T16:22:02.139Z,1589041322.139 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for altitude
2020-05-09T16:22:14.268Z,1589041334.268 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-05-09T16:22:21.132Z,1589041341.132 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:22:54.642Z,1589041374.642 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:23:24.621Z,1589041404.621 [ThrusterServo](FAULT): Invalid EZ Servo response:"/0@5242"
2020-05-09T16:23:24.621Z,1589041404.621 [ThrusterServo] Communications Fault, FailCount= 1
2020-05-09T16:23:24.621Z,1589041404.621 [ThrusterServo](ERROR): Communications Fault
2020-05-09T16:23:24.625Z,1589041404.625 [CBIT](ERROR): Communications Fault in component: ThrusterServo
2020-05-09T16:23:24.974Z,1589041404.974 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-05-09T16:23:24.974Z,1589041404.974 [ThrusterServo](INFO): Powering down
2020-05-09T16:23:25.752Z,1589041405.752 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-05-09T16:23:25.870Z,1589041405.870 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-05-09T16:23:25.873Z,1589041405.873 [CBIT](INFO): Clearing failed state for component ThrusterServo
2020-05-09T16:23:25.874Z,1589041405.874 [ThrusterServo] No Fault, FailCount= 1
2020-05-09T16:23:36.345Z,1589041416.345 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout
2020-05-09T16:23:36.345Z,1589041416.345 [RDI_Pathfinder] Communications Fault, FailCount= 1
2020-05-09T16:23:36.345Z,1589041416.345 [RDI_Pathfinder](ERROR): Communications Fault
2020-05-09T16:23:36.452Z,1589041416.452 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-05-09T16:23:36.705Z,1589041416.705 [RDI_Pathfinder](INFO): Powering down
2020-05-09T16:23:37.600Z,1589041417.600 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-05-09T16:23:37.600Z,1589041417.600 [RDI_Pathfinder] No Fault, FailCount= 1
2020-05-09T16:23:49.566Z,1589041429.566 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items
2020-05-09T16:24:04.146Z,1589041444.146 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items
2020-05-09T16:24:44.152Z,1589041484.152 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-05-09T16:24:45.752Z,1589041485.752 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-05-09T16:25:07.535Z,1589041507.535 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:25:22.134Z,1589041522.134 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:25:24.131Z,1589041524.131 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:25:38.652Z,1589041538.652 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:25:43.104Z,1589041543.104 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:25:43.505Z,1589041543.505 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items
2020-05-09T16:25:58.860Z,1589041558.860 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:26:04.142Z,1589041564.142 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items
2020-05-09T16:26:05.739Z,1589041565.739 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-05-09T16:26:24.738Z,1589041584.738 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-05-09T16:26:42.488Z,1589041602.488 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items
2020-05-09T16:26:44.155Z,1589041604.155 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items
2020-05-09T16:26:55.082Z,1589041615.082 [ThrusterServo](FAULT): Invalid EZ Servo response:"/0@5242"
2020-05-09T16:26:55.082Z,1589041615.082 [ThrusterServo] Communications Fault, FailCount= 1
2020-05-09T16:26:55.082Z,1589041615.082 [ThrusterServo](ERROR): Communications Fault
2020-05-09T16:26:55.088Z,1589041615.088 [CBIT](ERROR): Communications Fault in component: ThrusterServo
2020-05-09T16:26:55.469Z,1589041615.469 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-05-09T16:26:55.469Z,1589041615.469 [ThrusterServo](INFO): Powering down
2020-05-09T16:26:56.302Z,1589041616.302 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-05-09T16:26:56.422Z,1589041616.422 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-05-09T16:26:56.425Z,1589041616.425 [CBIT](INFO): Clearing failed state for component ThrusterServo
2020-05-09T16:26:56.425Z,1589041616.425 [ThrusterServo] No Fault, FailCount= 1
2020-05-09T16:27:00.755Z,1589041620.755 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-05-09T16:27:16.126Z,1589041636.126 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:27:18.151Z,1589041638.151 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items
2020-05-09T16:27:38.793Z,1589041658.793 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout
2020-05-09T16:27:38.793Z,1589041658.793 [RDI_Pathfinder] Communications Fault, FailCount= 1
2020-05-09T16:27:38.793Z,1589041658.793 [RDI_Pathfinder](ERROR): Communications Fault
2020-05-09T16:27:38.968Z,1589041658.968 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-05-09T16:27:39.197Z,1589041659.197 [RDI_Pathfinder](INFO): Powering down
2020-05-09T16:27:39.202Z,1589041659.202 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items
2020-05-09T16:27:40.020Z,1589041660.020 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-05-09T16:27:40.020Z,1589041660.020 [RDI_Pathfinder] No Fault, FailCount= 1
2020-05-09T16:27:58.523Z,1589041678.523 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items
2020-05-09T16:28:42.200Z,1589041722.200 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-05-09T16:28:44.562Z,1589041724.562 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-05-09T16:28:46.206Z,1589041726.206 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-05-09T16:29:20.524Z,1589041760.524 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:29:28.217Z,1589041768.217 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-05-09T16:29:37.498Z,1589041777.498 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:29:45.566Z,1589041785.566 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:30:25.575Z,1589041825.575 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:30:46.573Z,1589041846.573 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:30:59.518Z,1589041859.518 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:31:39.516Z,1589041899.516 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:32:29.255Z,1589041949.255 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-05-09T16:32:29.271Z,1589041949.271 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items
2020-05-09T16:33:07.573Z,1589041987.573 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-05-09T16:33:24.539Z,1589042004.539 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:33:30.265Z,1589042010.265 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:33:53.279Z,1589042033.279 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:33:55.653Z,1589042035.653 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:34:02.155Z,1589042042.155 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items
2020-05-09T16:34:04.545Z,1589042044.545 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:34:08.204Z,1589042048.204 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-05-09T16:34:44.220Z,1589042084.220 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-05-09T16:34:45.419Z,1589042085.419 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for altitude
2020-05-09T16:34:57.146Z,1589042097.146 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items
2020-05-09T16:34:59.531Z,1589042099.531 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:35:01.199Z,1589042101.199 [RDI_Pathfinder](ERROR): only read 1 of 4 data items
2020-05-09T16:35:03.208Z,1589042103.208 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-05-09T16:35:24.212Z,1589042124.212 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-05-09T16:36:02.206Z,1589042162.206 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-05-09T16:36:15.525Z,1589042175.525 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items
2020-05-09T16:36:19.542Z,1589042179.542 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-05-09T16:36:42.581Z,1589042202.581 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:37:16.138Z,1589042236.138 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items
2020-05-09T16:37:20.616Z,1589042240.616 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-05-09T16:37:32.314Z,1589042252.314 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for altitude
2020-05-09T16:37:35.521Z,1589042255.521 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:37:41.562Z,1589042261.562 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:37:43.571Z,1589042263.571 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:37:45.261Z,1589042265.261 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items
2020-05-09T16:38:19.532Z,1589042299.532 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:38:36.522Z,1589042316.522 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items
2020-05-09T16:38:44.208Z,1589042324.208 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-05-09T16:39:01.544Z,1589042341.544 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:39:05.216Z,1589042345.216 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-05-09T16:39:19.342Z,1589042359.342 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items
2020-05-09T16:39:20.542Z,1589042360.542 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:39:47.273Z,1589042387.273 [profile_station:NeedComms] Running Loop=1
2020-05-09T16:39:47.273Z,1589042387.273 [profile_station:NeedComms](DEBUG): Aggregate::initialize profile_station:NeedComms
2020-05-09T16:39:47.273Z,1589042387.273 [profile_station:NeedComms:B.GoToSurface] Running Loop=1
2020-05-09T16:39:47.274Z,1589042387.274 [profile_station:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-05-09T16:39:47.274Z,1589042387.274 [profile_station:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2020-05-09T16:39:47.274Z,1589042387.274 [profile_station:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2020-05-09T16:39:47.275Z,1589042387.275 [profile_station:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2020-05-09T16:39:47.275Z,1589042387.275 [profile_station:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-05-09T16:39:47.275Z,1589042387.275 [profile_station:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-05-09T16:39:47.276Z,1589042387.276 [profile_station:NeedComms:A] Running Loop=1
2020-05-09T16:39:47.281Z,1589042387.281 [profile_station:NeedComms:A](INFO): last time_fix was: 1589040561.000000 second since 1970/01/01T00:00:00Z 
2020-05-09T16:39:47.281Z,1589042387.281 [profile_station:NeedComms:A] Stopped
2020-05-09T16:39:56.557Z,1589042396.557 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:40:13.766Z,1589042413.766 [profile_station:NeedComms:C] Running Loop=1
2020-05-09T16:40:14.461Z,1589042414.461 [Radio_Surface](INFO): Powering up
2020-05-09T16:40:20.469Z,1589042420.469 [DataOverHttps](INFO): Radio surface powered ON.
2020-05-09T16:40:25.141Z,1589042425.141 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items
2020-05-09T16:40:33.962Z,1589042433.962 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=11109, MT Status=1, MTMSN=501
2020-05-09T16:40:33.963Z,1589042433.963 [NAL9602](INFO): Data available in MT queue
2020-05-09T16:40:34.453Z,1589042434.453 [NAL9602](INFO): Received command:stop
2020-05-09T16:40:34.525Z,1589042434.525 [CommandLine](IMPORTANT): got command stop
2020-05-09T16:40:34.525Z,1589042434.525 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop
2020-05-09T16:40:34.820Z,1589042434.820 [MissionManager](INFO): MissionManager is completed.
2020-05-09T16:40:34.820Z,1589042434.820 [MissionManager](INFO): Uninitializing Mission profile_station
2020-05-09T16:40:34.820Z,1589042434.820 [profile_station] Stopped
2020-05-09T16:40:34.820Z,1589042434.820 [profile_station](DEBUG): Aggregate::uninitialize profile_station
2020-05-09T16:40:34.821Z,1589042434.821 [profile_station:Science] Stopped
2020-05-09T16:40:34.821Z,1589042434.821 [profile_station:Science](DEBUG): Aggregate::uninitialize profile_station:Science
2020-05-09T16:40:34.821Z,1589042434.821 [profile_station:Science:G] Stopped
2020-05-09T16:40:34.821Z,1589042434.821 [profile_station:Science:Read_Oil] Stopped
2020-05-09T16:40:34.821Z,1589042434.821 [profile_station:Science:PeakDetectChl] Stopped
2020-05-09T16:40:34.821Z,1589042434.821 [profile_station:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectChl
2020-05-09T16:40:34.821Z,1589042434.821 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2020-05-09T16:40:34.821Z,1589042434.821 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2020-05-09T16:40:34.821Z,1589042434.821 [profile_station:NeedComms] Stopped
2020-05-09T16:40:34.821Z,1589042434.821 [profile_station:NeedComms](DEBUG): Aggregate::uninitialize profile_station:NeedComms
2020-05-09T16:40:34.822Z,1589042434.822 [profile_station:NeedComms:B.GoToSurface] Stopped
2020-05-09T16:40:34.822Z,1589042434.822 [profile_station:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-05-09T16:40:34.822Z,1589042434.822 [profile_station:NeedComms:C] Stopped
2020-05-09T16:40:34.822Z,1589042434.822 [profile_station:StandardEnvelopes] Stopped
2020-05-09T16:40:34.822Z,1589042434.822 [profile_station:StandardEnvelopes](DEBUG): Aggregate::uninitialize profile_station:StandardEnvelopes
2020-05-09T16:40:34.822Z,1589042434.822 [profile_station:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2020-05-09T16:40:34.822Z,1589042434.822 [profile_station:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2020-05-09T16:40:34.822Z,1589042434.822 [profile_station:StandardEnvelopes:B.DepthEnvelope] Stopped
2020-05-09T16:40:34.822Z,1589042434.822 [profile_station:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2020-05-09T16:40:34.822Z,1589042434.822 [profile_station:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2020-05-09T16:40:34.822Z,1589042434.822 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2020-05-09T16:40:34.822Z,1589042434.822 [profile_station:I.Pitch] Stopped
2020-05-09T16:40:34.822Z,1589042434.822 [profile_station:J.Buoyancy] Stopped
2020-05-09T16:40:34.822Z,1589042434.822 [profile_station:J.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2020-05-09T16:40:34.823Z,1589042434.823 [profile_station:K.SetSpeed] Stopped
2020-05-09T16:40:34.823Z,1589042434.823 [profile_station:K.SetSpeed](DEBUG): Uninitialize.
2020-05-09T16:40:34.823Z,1589042434.823 [profile_station:L.DepthEnvelope] Stopped
2020-05-09T16:40:34.823Z,1589042434.823 [profile_station:L.DepthEnvelope](DEBUG): Uninitialize.
2020-05-09T16:40:34.823Z,1589042434.823 [profile_station:M.AltitudeEnvelope] Stopped
2020-05-09T16:40:34.823Z,1589042434.823 [profile_station:M.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2020-05-09T16:40:34.823Z,1589042434.823 [profile_station:N.YoYo] Stopped
2020-05-09T16:40:34.823Z,1589042434.823 [profile_station:N.YoYo](DEBUG): Uninitialize YoYoComponent.
2020-05-09T16:40:34.823Z,1589042434.823 [profile_station:CircleWrapper] Stopped
2020-05-09T16:40:34.823Z,1589042434.823 [profile_station:CircleWrapper](DEBUG): Aggregate::uninitialize profile_station:CircleWrapper
2020-05-09T16:40:34.823Z,1589042434.823 [profile_station:CircleWrapper:A] Stopped
2020-05-09T16:40:34.823Z,1589042434.823 [profile_station:CircleWrapper:B.Circle] Stopped
2020-05-09T16:40:34.823Z,1589042434.823 [profile_station:CircleWrapper:B.Circle](DEBUG): Uninitialize.
2020-05-09T16:40:35.109Z,1589042435.109 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-05-09T16:40:35.292Z,1589042435.292 [MissionManager](IMPORTANT): Started mission DefaultWithUndock
2020-05-09T16:40:35.292Z,1589042435.292 [DefaultWithUndock] Running Loop=1
2020-05-09T16:40:35.292Z,1589042435.292 [DefaultWithUndock](DEBUG): Aggregate::initialize DefaultWithUndock
2020-05-09T16:40:35.293Z,1589042435.293 [DefaultWithUndock:C.GoToSurface] Running Loop=1
2020-05-09T16:40:35.293Z,1589042435.293 [DefaultWithUndock:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-05-09T16:40:35.293Z,1589042435.293 [DefaultWithUndock:C.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-05-09T16:40:35.293Z,1589042435.293 [DefaultWithUndock:C.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-05-09T16:40:35.294Z,1589042435.294 [DefaultWithUndock:C.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-05-09T16:40:35.294Z,1589042435.294 [DefaultWithUndock:C.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-05-09T16:40:35.294Z,1589042435.294 [DefaultWithUndock:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-05-09T16:40:35.295Z,1589042435.295 [DefaultWithUndock:A.Wait] Running Loop=1
2020-05-09T16:40:35.295Z,1589042435.295 [DefaultWithUndock:A.Wait](DEBUG): Initialize Wait Component.
2020-05-09T16:40:35.513Z,1589042435.513 [WetLabsSeaOWL_UV_A](FAULT): LCB fault:  Software Overcurrent.
2020-05-09T16:40:35.513Z,1589042435.513 [WetLabsSeaOWL_UV_A] Hardware Fault, FailCount= 1
2020-05-09T16:40:35.513Z,1589042435.513 [WetLabsSeaOWL_UV_A](ERROR): Hardware Fault
2020-05-09T16:40:35.582Z,1589042435.582 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164010.00,A,4133.16116,N,07042.35618,W,1.691,208.02,090520,,,A*7B
2020-05-09T16:40:35.589Z,1589042435.589 [NAL9602](INFO): GPS fix at 20200509T164010: (41.552686, -70.705936)
2020-05-09T16:40:35.634Z,1589042435.634 [UniversalFixResidualReporter](INFO): Fix residual: 9.9 %DT, over the last 1348.7 m. Residual distance 133.5 m at bearing 6.1 degrees. Fix at (41.5527, -70.7059) with 310.9 m made good.
2020-05-09T16:40:35.679Z,1589042435.679 [CBIT](ERROR): Hardware Fault in component: WetLabsSeaOWL_UV_A
2020-05-09T16:40:36.078Z,1589042436.078 [CBIT](INFO): Clearing failed state for component WetLabsSeaOWL_UV_A
2020-05-09T16:40:36.078Z,1589042436.078 [WetLabsSeaOWL_UV_A] No Fault, FailCount= 1
2020-05-09T16:40:36.323Z,1589042436.323 [WetLabsSeaOWL_UV_A](INFO): Powering up
2020-05-09T16:40:38.473Z,1589042438.473 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-05-09T16:40:50.589Z,1589042450.589 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-05-09T16:41:05.524Z,1589042465.524 [DefaultWithUndock:A.Wait](INFO): Done Waiting.
2020-05-09T16:41:05.524Z,1589042465.524 [DefaultWithUndock:A.Wait] Stopped
2020-05-09T16:41:05.525Z,1589042465.525 [DefaultWithUndock:A.Wait](DEBUG): Uninitialize Wait Component.
2020-05-09T16:41:05.525Z,1589042465.525 [DefaultWithUndock:B.Undock] Running Loop=1
2020-05-09T16:41:05.525Z,1589042465.525 [DefaultWithUndock:B.Undock](DEBUG): Initialize.
2020-05-09T16:41:05.525Z,1589042465.525 [DefaultWithUndock:B.Undock](DEBUG): Initializing internal variables to default values.
2020-05-09T16:41:05.998Z,1589042465.998 [Reporter](INFO): DDM.DDMModeCmd 2 enum
2020-05-09T16:41:06.301Z,1589042466.301 [PowerOnly](INFO): Powering up
2020-05-09T16:41:06.855Z,1589042466.855 [Reporter](INFO): DDM.DDMMode 0 enum
2020-05-09T16:41:06.856Z,1589042466.856 [Reporter](INFO): DDM.whiskerState 2 count
2020-05-09T16:41:06.856Z,1589042466.856 [Reporter](INFO): DDM.latchState 1 count
2020-05-09T16:41:06.857Z,1589042466.857 [Reporter](INFO): DDM.cablePresent 0 bool
2020-05-09T16:41:07.086Z,1589042467.086 [DDM](INFO): Detaching. Opening Latch
2020-05-09T16:41:07.490Z,1589042467.490 [DDM](INFO): Detaching. Opening Latch
2020-05-09T16:41:07.894Z,1589042467.894 [DDM](INFO): Detaching. Opening Latch
2020-05-09T16:41:08.301Z,1589042468.301 [DDM](INFO): Detaching. Opening Latch
2020-05-09T16:41:08.335Z,1589042468.335 [NAL9602](INFO): Not Powering down - fast GPS
2020-05-09T16:41:08.550Z,1589042468.550 [Reporter](INFO): DDM.latchState 0 count
2020-05-09T16:41:09.740Z,1589042469.740 [DefaultWithUndock:B.Undock](INFO): Detaching from dock.
2020-05-09T16:41:09.811Z,1589042469.811 [Reporter](INFO): DDM.DDMMode 2 enum
2020-05-09T16:41:09.812Z,1589042469.812 [Reporter](INFO): DDM.latchState 2 count
2020-05-09T16:41:10.122Z,1589042470.122 [DefaultWithUndock:B.Undock](INFO): Detected possible detachment. Starting timer.
2020-05-09T16:41:25.729Z,1589042485.729 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-05-09T16:41:30.137Z,1589042490.137 [DefaultWithUndock:B.Undock](INFO): Detached at range: nan m. Transitioning DDM to standby.
2020-05-09T16:41:30.726Z,1589042490.726 [Reporter](INFO): DDM.DDMModeCmd 0 enum
2020-05-09T16:41:30.871Z,1589042490.871 [DDM](INFO): Closing Latch
2020-05-09T16:41:31.279Z,1589042491.279 [DDM](INFO): Closing Latch
2020-05-09T16:41:31.679Z,1589042491.679 [DDM](INFO): Closing Latch
2020-05-09T16:41:32.082Z,1589042492.082 [DDM](INFO): Closing Latch
2020-05-09T16:41:32.261Z,1589042492.261 [Reporter](INFO): DDM.latchState 0 count
2020-05-09T16:41:33.402Z,1589042493.402 [DefaultWithUndock:B.Undock](INFO): DDM at standby.
2020-05-09T16:41:33.530Z,1589042493.530 [Reporter](INFO): DDM.DDMMode 0 enum
2020-05-09T16:41:33.531Z,1589042493.531 [Reporter](INFO): DDM.latchState 1 count
2020-05-09T16:41:33.756Z,1589042493.756 [DefaultWithUndock:B.Undock](IMPORTANT): Undocking sequence complete.
2020-05-09T16:41:33.756Z,1589042493.756 [DefaultWithUndock:B.Undock] Stopped
2020-05-09T16:41:34.323Z,1589042494.323 [DefaultWithUndock:CheckIn] Running Loop=1
2020-05-09T16:41:34.323Z,1589042494.323 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-05-09T16:41:34.323Z,1589042494.323 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-05-09T16:41:34.398Z,1589042494.398 [Reporter](INFO): DDM.DDMMode no_value
2020-05-09T16:41:34.398Z,1589042494.398 [Reporter](INFO): DDM.whiskerState no_value
2020-05-09T16:41:34.399Z,1589042494.399 [Reporter](INFO): DDM.latchState no_value
2020-05-09T16:41:34.399Z,1589042494.399 [Reporter](INFO): DDM.cablePresent no_value
2020-05-09T16:41:36.272Z,1589042496.272 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164110.00,A,4133.15279,N,07042.35579,W,2.060,178.86,090520,,,A*74
2020-05-09T16:41:36.274Z,1589042496.274 [NAL9602](INFO): GPS fix at 20200509T164110: (41.552546, -70.705930)
2020-05-09T16:41:36.334Z,1589042496.334 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-05-09T16:41:36.334Z,1589042496.334 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-05-09T16:41:47.976Z,1589042507.976 [NAL9602](INFO): SBD MO Status=0, MOMSN=11110, MT Status=0, MTMSN=0
2020-05-09T16:41:47.981Z,1589042507.981 [NAL9602](INFO): No messages in MT queue
2020-05-09T16:42:00.889Z,1589042520.889 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-05-09T16:42:18.675Z,1589042538.675 [NAL9602](INFO): Not Powering down - fast GPS
2020-05-09T16:42:27.222Z,1589042547.222 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-05-09T16:42:27.226Z,1589042547.226 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:42:36.033Z,1589042556.033 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-05-09T16:42:37.684Z,1589042557.684 [PowerOnly](INFO): Powering down
2020-05-09T16:42:38.115Z,1589042558.115 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items
2020-05-09T16:42:48.238Z,1589042568.238 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:43:11.174Z,1589042591.174 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-05-09T16:43:28.591Z,1589042608.591 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:43:40.303Z,1589042620.303 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003835
2020-05-09T16:43:44.317Z,1589042624.317 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20200509T160117/Courier0015.lzma
2020-05-09T16:43:45.319Z,1589042625.319 [DataOverHttps](INFO): Moved sent file to Logs/20200509T160117/Courier0015.lzma.bak
2020-05-09T16:43:45.319Z,1589042625.319 [DataOverHttps](INFO): SBD MOMSN=13006
2020-05-09T16:43:49.601Z,1589042629.601 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-05-09T16:43:54.358Z,1589042634.358 [DataOverHttps](INFO): Sending 686 bytes from file Logs/20200509T160117/Express0016.lzma
2020-05-09T16:43:55.359Z,1589042635.359 [DataOverHttps](INFO): Moved sent file to Logs/20200509T160117/Express0016.lzma.bak
2020-05-09T16:43:55.359Z,1589042635.359 [DataOverHttps](INFO): SBD MOMSN=13009
2020-05-09T16:43:56.893Z,1589042636.893 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-05-09T16:43:56.894Z,1589042636.894 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1
2020-05-09T16:43:56.894Z,1589042636.894 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-05-09T16:44:45.739Z,1589042685.739 [Micromodem](INFO): Nmea in: $CARE,AUV,2.0.32731*1E

2020-05-09T16:44:45.739Z,1589042685.739 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CARE,AUV,2.0.32731*1E
 expected 61
2020-05-09T16:45:28.246Z,1589042728.246 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-05-09T16:46:08.234Z,1589042768.234 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:46:37.295Z,1589042797.295 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-05-09T16:48:57.540Z,1589042937.540 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting.
2020-05-09T16:48:57.540Z,1589042937.540 [DefaultWithUndock:CheckIn:C.Wait] Stopped
2020-05-09T16:48:57.540Z,1589042937.540 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-05-09T16:48:57.540Z,1589042937.540 [DefaultWithUndock:CheckIn:D] Running Loop=1
2020-05-09T16:48:57.915Z,1589042937.915 [DefaultWithUndock:CheckIn:D] Stopped
2020-05-09T16:48:57.916Z,1589042937.916 [DefaultWithUndock:CheckIn:E] Running Loop=1
2020-05-09T16:48:58.388Z,1589042938.388 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 8.377050 min 
2020-05-09T16:48:58.388Z,1589042938.388 [DefaultWithUndock:CheckIn:E] Stopped
2020-05-09T16:48:58.388Z,1589042938.388 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn
2020-05-09T16:48:58.388Z,1589042938.388 [DefaultWithUndock:CheckIn] Stopped
2020-05-09T16:48:58.388Z,1589042938.388 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-05-09T16:48:58.388Z,1589042938.388 [DefaultWithUndock:CheckIn](INFO): Running loop #2
2020-05-09T16:48:58.388Z,1589042938.388 [DefaultWithUndock:CheckIn] Running Loop=2
2020-05-09T16:48:58.388Z,1589042938.388 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-05-09T16:48:58.389Z,1589042938.389 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-05-09T16:49:00.328Z,1589042940.328 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164834.00,A,4133.11935,N,07042.35316,W,1.438,29.10,090520,,,A*43
2020-05-09T16:49:00.330Z,1589042940.330 [NAL9602](INFO): GPS fix at 20200509T164834: (41.551989, -70.705886)
2020-05-09T16:49:00.364Z,1589042940.364 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-05-09T16:49:00.364Z,1589042940.364 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-05-09T16:49:04.645Z,1589042944.645 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200509T160117/Courier0018.lzma
2020-05-09T16:49:05.714Z,1589042945.714 [DataOverHttps](INFO): Moved sent file to Logs/20200509T160117/Courier0018.lzma.bak
2020-05-09T16:49:05.715Z,1589042945.715 [DataOverHttps](INFO): SBD MOMSN=13018
2020-05-09T16:49:14.685Z,1589042954.685 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20200509T160117/Express0019.lzma
2020-05-09T16:49:15.270Z,1589042955.270 [NAL9602](INFO): SBD MO Status=0, MOMSN=11111, MT Status=0, MTMSN=0
2020-05-09T16:49:15.270Z,1589042955.270 [NAL9602](INFO): No messages in MT queue
2020-05-09T16:49:15.687Z,1589042955.687 [DataOverHttps](INFO): Moved sent file to Logs/20200509T160117/Express0019.lzma.bak
2020-05-09T16:49:15.687Z,1589042955.687 [DataOverHttps](INFO): SBD MOMSN=13021
2020-05-09T16:49:17.319Z,1589042957.319 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-05-09T16:49:17.319Z,1589042957.319 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1
2020-05-09T16:49:17.319Z,1589042957.319 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-05-09T16:49:25.404Z,1589042965.404 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-05-09T16:49:41.191Z,1589042981.191 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-05-09T16:49:45.970Z,1589042985.970 [NAL9602](INFO): Not Powering down - fast GPS
2020-05-09T16:53:09.671Z,1589043189.671 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2020-05-09T16:53:29.887Z,1589043209.887 [CBIT](IMPORTANT): Beginning ground fault scan
2020-05-09T16:53:34.293Z,1589043214.293 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:53:40.806Z,1589043220.806 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.009088
CHAN A1 (24V): -0.028735
CHAN A2 (12V): -0.007089
CHAN A3 (5V): -0.002171
CHAN B0 (3.3V): 0.000313
CHAN B1 (3.15aV): 0.000140
CHAN B2 (3.15bV): 0.000448
CHAN B3 (GND): 0.002386
OPEN: 0.005976
Full Scale Calc: 4.765 mA, -1.589 mA
2020-05-09T16:54:17.959Z,1589043257.959 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting.
2020-05-09T16:54:17.959Z,1589043257.959 [DefaultWithUndock:CheckIn:C.Wait] Stopped
2020-05-09T16:54:17.959Z,1589043257.959 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-05-09T16:54:17.959Z,1589043257.959 [DefaultWithUndock:CheckIn:D] Running Loop=1
2020-05-09T16:54:18.482Z,1589043258.482 [DefaultWithUndock:CheckIn:D] Stopped
2020-05-09T16:54:18.482Z,1589043258.482 [DefaultWithUndock:CheckIn:E] Running Loop=1
2020-05-09T16:54:18.773Z,1589043258.773 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 13.719824 min 
2020-05-09T16:54:18.773Z,1589043258.773 [DefaultWithUndock:CheckIn:E] Stopped
2020-05-09T16:54:18.773Z,1589043258.773 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn
2020-05-09T16:54:18.773Z,1589043258.773 [DefaultWithUndock:CheckIn] Stopped
2020-05-09T16:54:18.773Z,1589043258.773 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-05-09T16:54:18.774Z,1589043258.774 [DefaultWithUndock:CheckIn](INFO): Running loop #3
2020-05-09T16:54:18.774Z,1589043258.774 [DefaultWithUndock:CheckIn] Running Loop=3
2020-05-09T16:54:18.774Z,1589043258.774 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-05-09T16:54:18.774Z,1589043258.774 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-05-09T16:54:20.737Z,1589043260.737 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165355.00,A,4133.09868,N,07042.34771,W,0.039,229.91,090520,,,A*75
2020-05-09T16:54:20.739Z,1589043260.739 [NAL9602](INFO): GPS fix at 20200509T165355: (41.551645, -70.705795)
2020-05-09T16:54:20.774Z,1589043260.774 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-05-09T16:54:20.774Z,1589043260.774 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-05-09T16:54:24.944Z,1589043264.944 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200509T160117/Courier0021.lzma
2020-05-09T16:54:25.947Z,1589043265.947 [DataOverHttps](INFO): Moved sent file to Logs/20200509T160117/Courier0021.lzma.bak
2020-05-09T16:54:25.947Z,1589043265.947 [DataOverHttps](INFO): SBD MOMSN=13025
2020-05-09T16:54:34.985Z,1589043274.985 [DataOverHttps](INFO): Sending 330 bytes from file Logs/20200509T160117/Express0022.lzma
2020-05-09T16:54:35.987Z,1589043275.987 [DataOverHttps](INFO): Moved sent file to Logs/20200509T160117/Express0022.lzma.bak
2020-05-09T16:54:35.987Z,1589043275.987 [DataOverHttps](INFO): SBD MOMSN=13028
2020-05-09T16:54:36.514Z,1589043276.514 [NAL9602](INFO): SBD MO Status=0, MOMSN=11112, MT Status=0, MTMSN=0
2020-05-09T16:54:36.514Z,1589043276.514 [NAL9602](INFO): No messages in MT queue
2020-05-09T16:54:37.370Z,1589043277.370 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-05-09T16:54:37.370Z,1589043277.370 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1
2020-05-09T16:54:37.370Z,1589043277.370 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-05-09T16:55:07.240Z,1589043307.240 [NAL9602](INFO): Not Powering down - fast GPS
2020-05-09T16:55:48.499Z,1589043348.499 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:55:54.160Z,1589043354.160 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items
2020-05-09T16:56:28.494Z,1589043388.494 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:57:53.706Z,1589043473.706 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2020-05-09T16:59:12.071Z,1589043552.071 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-05-09T16:59:12.074Z,1589043552.074 [BPC1](INFO): Received data from all battery sticks.
2020-05-09T16:59:37.928Z,1589043577.928 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting.
2020-05-09T16:59:37.928Z,1589043577.928 [DefaultWithUndock:CheckIn:C.Wait] Stopped
2020-05-09T16:59:37.928Z,1589043577.928 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-05-09T16:59:37.928Z,1589043577.928 [DefaultWithUndock:CheckIn:D] Running Loop=1
2020-05-09T16:59:38.370Z,1589043578.370 [DefaultWithUndock:CheckIn:D] Stopped
2020-05-09T16:59:38.370Z,1589043578.370 [DefaultWithUndock:CheckIn:E] Running Loop=1
2020-05-09T16:59:38.736Z,1589043578.736 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 19.051300 min 
2020-05-09T16:59:38.736Z,1589043578.736 [DefaultWithUndock:CheckIn:E] Stopped
2020-05-09T16:59:38.736Z,1589043578.736 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn
2020-05-09T16:59:38.737Z,1589043578.737 [DefaultWithUndock:CheckIn] Stopped
2020-05-09T16:59:38.737Z,1589043578.737 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-05-09T16:59:38.737Z,1589043578.737 [DefaultWithUndock:CheckIn](INFO): Running loop #4
2020-05-09T16:59:38.737Z,1589043578.737 [DefaultWithUndock:CheckIn] Running Loop=4
2020-05-09T16:59:38.737Z,1589043578.737 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-05-09T16:59:38.737Z,1589043578.737 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-05-09T16:59:40.736Z,1589043580.736 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165915.00,A,4133.08189,N,07042.34938,W,2.274,359.75,090520,,,A*7A
2020-05-09T16:59:40.738Z,1589043580.738 [NAL9602](INFO): GPS fix at 20200509T165915: (41.551365, -70.705823)
2020-05-09T16:59:40.773Z,1589043580.773 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-05-09T16:59:40.773Z,1589043580.773 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-05-09T16:59:41.207Z,1589043581.207 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-05-09T16:59:41.218Z,1589043581.218 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T16:59:44.253Z,1589043584.253 [DataOverHttps](INFO): Sending 201 bytes from file Logs/20200509T160117/Courier0024.lzma
2020-05-09T16:59:45.255Z,1589043585.255 [DataOverHttps](INFO): Moved sent file to Logs/20200509T160117/Courier0024.lzma.bak
2020-05-09T16:59:45.255Z,1589043585.255 [DataOverHttps](INFO): SBD MOMSN=13034
2020-05-09T16:59:54.293Z,1589043594.293 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20200509T160117/Express0025.lzma
2020-05-09T16:59:54.996Z,1589043594.996 [NAL9602](INFO): SBD MO Status=0, MOMSN=11113, MT Status=0, MTMSN=0
2020-05-09T16:59:54.997Z,1589043594.997 [NAL9602](INFO): No messages in MT queue
2020-05-09T16:59:55.295Z,1589043595.295 [DataOverHttps](INFO): Moved sent file to Logs/20200509T160117/Express0025.lzma.bak
2020-05-09T16:59:55.295Z,1589043595.295 [DataOverHttps](INFO): SBD MOMSN=13037
2020-05-09T16:59:56.656Z,1589043596.656 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-05-09T16:59:56.656Z,1589043596.656 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1
2020-05-09T16:59:56.656Z,1589043596.656 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-05-09T17:00:03.153Z,1589043603.153 [RDI_PathfinderUp](ERROR): only read 1 of 4 data items
2020-05-09T17:00:25.700Z,1589043625.700 [NAL9602](INFO): Not Powering down - fast GPS
2020-05-09T17:00:37.478Z,1589043637.478 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T17:01:42.495Z,1589043702.495 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T17:04:57.255Z,1589043897.255 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting.
2020-05-09T17:04:57.255Z,1589043897.255 [DefaultWithUndock:CheckIn:C.Wait] Stopped
2020-05-09T17:04:57.255Z,1589043897.255 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-05-09T17:04:57.256Z,1589043897.256 [DefaultWithUndock:CheckIn:D] Running Loop=1
2020-05-09T17:04:57.621Z,1589043897.621 [DefaultWithUndock:CheckIn:D] Stopped
2020-05-09T17:04:57.621Z,1589043897.621 [DefaultWithUndock:CheckIn:E] Running Loop=1
2020-05-09T17:04:58.028Z,1589043898.028 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 24.372144 min 
2020-05-09T17:04:58.028Z,1589043898.028 [DefaultWithUndock:CheckIn:E] Stopped
2020-05-09T17:04:58.028Z,1589043898.028 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn
2020-05-09T17:04:58.028Z,1589043898.028 [DefaultWithUndock:CheckIn] Stopped
2020-05-09T17:04:58.029Z,1589043898.029 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-05-09T17:04:58.029Z,1589043898.029 [DefaultWithUndock:CheckIn](INFO): Running loop #5
2020-05-09T17:04:58.029Z,1589043898.029 [DefaultWithUndock:CheckIn] Running Loop=5
2020-05-09T17:04:58.029Z,1589043898.029 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-05-09T17:04:58.029Z,1589043898.029 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-05-09T17:05:00.012Z,1589043900.012 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170434.00,A,4133.06513,N,07042.35315,W,0.914,220.12,090520,,,A*7C
2020-05-09T17:05:00.014Z,1589043900.014 [NAL9602](INFO): GPS fix at 20200509T170434: (41.551085, -70.705886)
2020-05-09T17:05:00.038Z,1589043900.038 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-05-09T17:05:00.039Z,1589043900.039 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-05-09T17:05:03.556Z,1589043903.556 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200509T160117/Courier0027.lzma
2020-05-09T17:05:04.560Z,1589043904.560 [DataOverHttps](INFO): Moved sent file to Logs/20200509T160117/Courier0027.lzma.bak
2020-05-09T17:05:04.560Z,1589043904.560 [DataOverHttps](INFO): SBD MOMSN=13041
2020-05-09T17:05:13.605Z,1589043913.605 [DataOverHttps](INFO): Sending 118 bytes from file Logs/20200509T160117/Express0028.lzma
2020-05-09T17:05:14.607Z,1589043914.607 [DataOverHttps](INFO): Moved sent file to Logs/20200509T160117/Express0028.lzma.bak
2020-05-09T17:05:14.607Z,1589043914.607 [DataOverHttps](INFO): SBD MOMSN=13044
2020-05-09T17:05:15.922Z,1589043915.922 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-05-09T17:05:15.923Z,1589043915.923 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1
2020-05-09T17:05:15.923Z,1589043915.923 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-05-09T17:05:17.100Z,1589043917.100 [NAL9602](INFO): SBD MO Status=0, MOMSN=11114, MT Status=0, MTMSN=0
2020-05-09T17:05:17.100Z,1589043917.100 [NAL9602](INFO): No messages in MT queue
2020-05-09T17:05:47.795Z,1589043947.795 [NAL9602](INFO): Not Powering down - fast GPS
2020-05-09T17:06:13.321Z,1589043973.321 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items
2020-05-09T17:07:55.504Z,1589044075.504 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items
2020-05-09T17:08:07.233Z,1589044087.233 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-05-09T17:08:22.202Z,1589044102.202 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T17:08:39.141Z,1589044119.141 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items
2020-05-09T17:09:27.596Z,1589044167.596 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T17:10:14.450Z,1589044214.450 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-05-09T17:10:16.525Z,1589044216.525 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting.
2020-05-09T17:10:16.525Z,1589044216.525 [DefaultWithUndock:CheckIn:C.Wait] Stopped
2020-05-09T17:10:16.525Z,1589044216.525 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-05-09T17:10:16.526Z,1589044216.526 [DefaultWithUndock:CheckIn:D] Running Loop=1
2020-05-09T17:10:16.890Z,1589044216.890 [DefaultWithUndock:CheckIn:D] Stopped
2020-05-09T17:10:16.890Z,1589044216.890 [DefaultWithUndock:CheckIn:E] Running Loop=1
2020-05-09T17:10:17.306Z,1589044217.306 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 29.693296 min 
2020-05-09T17:10:17.306Z,1589044217.306 [DefaultWithUndock:CheckIn:E] Stopped
2020-05-09T17:10:17.306Z,1589044217.306 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn
2020-05-09T17:10:17.307Z,1589044217.307 [DefaultWithUndock:CheckIn] Stopped
2020-05-09T17:10:17.307Z,1589044217.307 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-05-09T17:10:17.307Z,1589044217.307 [DefaultWithUndock:CheckIn](INFO): Running loop #6
2020-05-09T17:10:17.307Z,1589044217.307 [DefaultWithUndock:CheckIn] Running Loop=6
2020-05-09T17:10:17.307Z,1589044217.307 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-05-09T17:10:17.307Z,1589044217.307 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-05-09T17:10:19.280Z,1589044219.280 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170953.00,A,4133.04343,N,07042.36229,W,1.089,215.29,090520,,,A*7E
2020-05-09T17:10:19.283Z,1589044219.283 [NAL9602](INFO): GPS fix at 20200509T170953: (41.550724, -70.706038)
2020-05-09T17:10:19.333Z,1589044219.333 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-05-09T17:10:19.333Z,1589044219.333 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-05-09T17:10:22.880Z,1589044222.880 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200509T160117/Courier0030.lzma
2020-05-09T17:10:23.883Z,1589044223.883 [DataOverHttps](INFO): Moved sent file to Logs/20200509T160117/Courier0030.lzma.bak
2020-05-09T17:10:23.883Z,1589044223.883 [DataOverHttps](INFO): SBD MOMSN=13048
2020-05-09T17:10:32.740Z,1589044232.740 [NAL9602](INFO): SBD MO Status=0, MOMSN=11115, MT Status=0, MTMSN=0
2020-05-09T17:10:32.740Z,1589044232.740 [NAL9602](INFO): No messages in MT queue
2020-05-09T17:10:32.921Z,1589044232.921 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20200509T160117/Express0031.lzma
2020-05-09T17:10:33.923Z,1589044233.923 [DataOverHttps](INFO): Moved sent file to Logs/20200509T160117/Express0031.lzma.bak
2020-05-09T17:10:33.923Z,1589044233.923 [DataOverHttps](INFO): SBD MOMSN=13051
2020-05-09T17:10:35.260Z,1589044235.260 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-05-09T17:10:35.260Z,1589044235.260 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1
2020-05-09T17:10:35.260Z,1589044235.260 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-05-09T17:11:03.433Z,1589044263.433 [NAL9602](INFO): Not Powering down - fast GPS
2020-05-09T17:11:48.328Z,1589044308.328 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T17:12:05.280Z,1589044325.280 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T17:12:28.724Z,1589044348.724 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-05-09T17:13:08.715Z,1589044388.715 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-05-09T17:14:34.359Z,1589044474.359 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T17:15:21.127Z,1589044521.127 [DataOverHttps](IMPORTANT): SBD MTMSN=20200509T171455
2020-05-09T17:15:25.147Z,1589044525.147 [DataOverHttps](INFO): Received command:configSet RDI_Pathfinder.loadAtStartup 0 bool persist;configSet RDI_PathfinderUp.loadAtStartup 0 bool persist
2020-05-09T17:15:25.862Z,1589044525.862 [CommandLine](IMPORTANT): got command configSet RDI_Pathfinder.loadAtStartup 0.000000 bool persist
2020-05-09T17:15:25.862Z,1589044525.862 [CommandLine](IMPORTANT): configSet RDI_Pathfinder.loadAtStartup requires a restart to take effect.
2020-05-09T17:15:25.865Z,1589044525.865 [CommandLine](IMPORTANT): got command configSet RDI_PathfinderUp.loadAtStartup 0.000000 bool persist
2020-05-09T17:15:25.865Z,1589044525.865 [CommandLine](IMPORTANT): configSet RDI_PathfinderUp.loadAtStartup requires a restart to take effect.
2020-05-09T17:15:32.180Z,1589044532.180 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-05-09T17:15:35.791Z,1589044535.791 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting.
2020-05-09T17:15:35.791Z,1589044535.791 [DefaultWithUndock:CheckIn:C.Wait] Stopped
2020-05-09T17:15:35.791Z,1589044535.791 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-05-09T17:15:35.792Z,1589044535.792 [DefaultWithUndock:CheckIn:D] Running Loop=1
2020-05-09T17:15:36.222Z,1589044536.222 [DefaultWithUndock:CheckIn:D] Stopped
2020-05-09T17:15:36.222Z,1589044536.222 [DefaultWithUndock:CheckIn:E] Running Loop=1
2020-05-09T17:15:36.589Z,1589044536.589 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 35.015495 min 
2020-05-09T17:15:36.589Z,1589044536.589 [DefaultWithUndock:CheckIn:E] Stopped
2020-05-09T17:15:36.589Z,1589044536.589 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn
2020-05-09T17:15:36.589Z,1589044536.589 [DefaultWithUndock:CheckIn] Stopped
2020-05-09T17:15:36.590Z,1589044536.590 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-05-09T17:15:36.590Z,1589044536.590 [DefaultWithUndock:CheckIn](INFO): Running loop #7
2020-05-09T17:15:36.590Z,1589044536.590 [DefaultWithUndock:CheckIn] Running Loop=7
2020-05-09T17:15:36.590Z,1589044536.590 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-05-09T17:15:36.590Z,1589044536.590 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-05-09T17:15:38.573Z,1589044538.573 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171513.00,A,4133.02857,N,07042.36411,W,0.700,157.43,090520,,,A*7C
2020-05-09T17:15:38.575Z,1589044538.575 [NAL9602](INFO): GPS fix at 20200509T171513: (41.550476, -70.706069)
2020-05-09T17:15:38.626Z,1589044538.626 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-05-09T17:15:38.626Z,1589044538.626 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-05-09T17:15:42.213Z,1589044542.213 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200509T160117/Courier0033.lzma
2020-05-09T17:15:43.215Z,1589044543.215 [DataOverHttps](INFO): Moved sent file to Logs/20200509T160117/Courier0033.lzma.bak
2020-05-09T17:15:43.215Z,1589044543.215 [DataOverHttps](INFO): SBD MOMSN=13057
2020-05-09T17:15:52.257Z,1589044552.257 [DataOverHttps](INFO): Sending 273 bytes from file Logs/20200509T160117/Express0034.lzma
2020-05-09T17:15:53.259Z,1589044553.259 [DataOverHttps](INFO): Moved sent file to Logs/20200509T160117/Express0034.lzma.bak
2020-05-09T17:15:53.259Z,1589044553.259 [DataOverHttps](INFO): SBD MOMSN=13060
2020-05-09T17:16:02.297Z,1589044562.297 [DataOverHttps](INFO): Sending 51 bytes from file Logs/20200509T160117/Express0035.lzma
2020-05-09T17:16:02.812Z,1589044562.812 [NAL9602](INFO): SBD MO Status=2, MOMSN=11116, MT Status=2, MTMSN=0
2020-05-09T17:16:02.812Z,1589044562.812 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-05-09T17:16:04.303Z,1589044564.303 [DataOverHttps](INFO): Moved sent file to Logs/20200509T160117/Express0035.lzma.bak
2020-05-09T17:16:04.303Z,1589044564.303 [DataOverHttps](INFO): SBD MOMSN=13068
2020-05-09T17:16:05.676Z,1589044565.676 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-05-09T17:16:05.676Z,1589044565.676 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1
2020-05-09T17:16:05.676Z,1589044565.676 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-05-09T17:16:12.910Z,1589044572.910 [NAL9602](INFO): SBD MO Status=0, MOMSN=11116, MT Status=0, MTMSN=0
2020-05-09T17:16:12.910Z,1589044572.910 [NAL9602](INFO): No messages in MT queue
2020-05-09T17:16:25.187Z,1589044585.187 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-05-09T17:16:43.688Z,1589044603.688 [NAL9602](INFO): Not Powering down - fast GPS
2020-05-09T17:16:45.466Z,1589044605.466 [DataOverHttps](IMPORTANT): SBD MTMSN=20200509T171619
2020-05-09T17:16:50.497Z,1589044610.497 [DataOverHttps](INFO): Received command:restart app
2020-05-09T17:16:50.526Z,1589044610.526 [CommandLine](IMPORTANT): got command restart application
2020-05-09T17:16:51.529Z,1589044611.529 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-05-09T17:16:51.529Z,1589044611.529 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-05-09T17:16:51.657Z,1589044611.657 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-05-09T17:16:51.657Z,1589044611.657 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-05-09T17:16:51.658Z,1589044611.658 [CommandLine](INFO): Join timeout helper Thread ID is 9518
2020-05-09T17:16:51.659Z,1589044611.659 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-05-09T17:16:51.659Z,1589044611.659 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-05-09T17:16:51.660Z,1589044611.660 [NavChartDb](INFO): Join timeout helper Thread ID is 9519
2020-05-09T17:16:51.841Z,1589044611.841 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-05-09T17:16:51.841Z,1589044611.841 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-05-09T17:16:51.842Z,1589044611.842 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2020-05-09T17:16:51.842Z,1589044611.842 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-05-09T17:16:51.842Z,1589044611.842 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 9520
2020-05-09T17:16:51.861Z,1589044611.861 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2020-05-09T17:16:51.861Z,1589044611.861 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-05-09T17:16:51.862Z,1589044611.862 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-05-09T17:16:51.869Z,1589044611.869 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-05-09T17:16:51.869Z,1589044611.869 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-05-09T17:16:51.869Z,1589044611.869 [Radio_Surface](INFO): Join timeout helper Thread ID is 9521
2020-05-09T17:16:51.961Z,1589044611.961 [Radio_Surface](INFO): Powering down
2020-05-09T17:16:51.962Z,1589044611.962 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-05-09T17:16:51.962Z,1589044611.962 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-05-09T17:16:51.973Z,1589044611.973 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-05-09T17:16:51.973Z,1589044611.973 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-05-09T17:16:51.973Z,1589044611.973 [DataOverHttps](INFO): Join timeout helper Thread ID is 9522
2020-05-09T17:16:52.493Z,1589044612.493 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-05-09T17:16:52.493Z,1589044612.493 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-05-09T17:16:52.514Z,1589044612.514 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-05-09T17:16:52.515Z,1589044612.515 [logger ThreadHandler](INFO): Thread cancelled.
2020-05-09T17:16:52.515Z,1589044612.515 [logger](INFO): Join timeout helper Thread ID is 9523
2020-05-09T17:16:52.529Z,1589044612.529 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-05-09T17:16:52.529Z,1589044612.529 [logger ThreadHandler](INFO): Thread cancelled.
2020-05-09T17:16:52.533Z,1589044612.533 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-05-09T17:16:52.534Z,1589044612.534 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-05-09T17:16:52.534Z,1589044612.534 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-05-09T17:16:52.534Z,1589044612.534 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-05-09T17:16:52.534Z,1589044612.534 [controlThread](INFO): Join timeout helper Thread ID is 9524
2020-05-09T17:16:52.549Z,1589044612.549 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-05-09T17:16:52.549Z,1589044612.549 [controlThread](DEBUG): Uninitializing ControlThread
2020-05-09T17:16:52.550Z,1589044612.550 [AHRS_M2](INFO): Powering down
2020-05-09T17:16:52.621Z,1589044612.621 [DDM](INFO): Powering down
2020-05-09T17:16:52.694Z,1589044612.694 [DUSBL_Hydroid](INFO): Powering down
2020-05-09T17:16:52.765Z,1589044612.765 [Micromodem](INFO): Powering down
2020-05-09T17:16:52.861Z,1589044612.861 [NAL9602](INFO): Powering down
2020-05-09T17:16:52.933Z,1589044612.933 [RDI_Pathfinder](INFO): Powering down
2020-05-09T17:16:53.005Z,1589044613.005 [RDI_PathfinderUp](INFO): Powering down
2020-05-09T17:16:53.006Z,1589044613.006 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-05-09T17:16:53.007Z,1589044613.007 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-05-09T17:16:53.008Z,1589044613.008 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-05-09T17:16:53.008Z,1589044613.008 [MissionManager](INFO): Uninitializing Mission DefaultWithUndock
2020-05-09T17:16:53.009Z,1589044613.009 [DefaultWithUndock] Stopped
2020-05-09T17:16:53.009Z,1589044613.009 [DefaultWithUndock](DEBUG): Aggregate::uninitialize DefaultWithUndock
2020-05-09T17:16:53.009Z,1589044613.009 [DefaultWithUndock:C.GoToSurface] Stopped
2020-05-09T17:16:53.009Z,1589044613.009 [DefaultWithUndock:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-05-09T17:16:53.009Z,1589044613.009 [DefaultWithUndock:CheckIn] Stopped
2020-05-09T17:16:53.009Z,1589044613.009 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-05-09T17:16:53.009Z,1589044613.009 [DefaultWithUndock:CheckIn:C.Wait] Stopped
2020-05-09T17:16:53.009Z,1589044613.009 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-05-09T17:16:53.012Z,1589044613.012 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-05-09T17:16:53.012Z,1589044613.012 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-05-09T17:16:53.013Z,1589044613.013 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-05-09T17:16:53.013Z,1589044613.013 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-05-09T17:16:53.014Z,1589044613.014 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-05-09T17:16:53.014Z,1589044613.014 [BuoyancyServo](INFO): Powering down
2020-05-09T17:16:53.025Z,1589044613.025 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-05-09T17:16:53.025Z,1589044613.025 [ElevatorServo](INFO): Powering down
2020-05-09T17:16:53.026Z,1589044613.026 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-05-09T17:16:53.026Z,1589044613.026 [MassServo](INFO): Powering down
2020-05-09T17:16:53.027Z,1589044613.027 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-05-09T17:16:53.027Z,1589044613.027 [RudderServo](INFO): Powering down
2020-05-09T17:16:53.028Z,1589044613.028 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-05-09T17:16:53.028Z,1589044613.028 [ThrusterServo](INFO): Powering down
2020-05-09T17:16:53.029Z,1589044613.029 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-05-09T17:16:53.029Z,1589044613.029 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-05-09T17:16:53.029Z,1589044613.029 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-05-09T17:16:53.029Z,1589044613.029 [CBIT](DEBUG): Powering off loads.
2020-05-09T17:16:53.041Z,1589044613.041 [CBIT](DEBUG): Disabling WDT.
2020-05-09T17:16:53.053Z,1589044613.053 [CBIT](DEBUG): Opening all GF detection circuits.
2020-05-09T17:16:53.054Z,1589044613.054 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-05-09T17:16:53.115Z,1589044613.115 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-05-09T17:16:53.127Z,1589044613.127 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-05-09T17:16:53.183Z,1589044613.183 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-05-09T17:16:53.251Z,1589044613.251 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-05-09T17:16:53.318Z,1589044613.318 [logger ThreadHandler](INFO): Thread cancelled.