2019-08-26T17:37:57.663Z,1566841077.663 [DataOverHttps](INFO): Received command:stop;restart logs
2019-08-26T17:37:57.736Z,1566841077.736 [CommandLine](IMPORTANT): got command stop
2019-08-26T17:37:57.737Z,1566841077.737 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop
2019-08-26T17:37:57.737Z,1566841077.737 [CommandLine](IMPORTANT): got command restart logs
2019-08-26T17:37:58.017Z,1566841078.017 [MissionManager](INFO): MissionManager is completed.
2019-08-26T17:37:58.017Z,1566841078.017 [MissionManager](INFO): Uninitializing Mission sci2
2019-08-26T17:37:58.017Z,1566841078.017 [sci2] Stopped
2019-08-26T17:37:58.017Z,1566841078.017 [sci2](DEBUG): Aggregate::uninitialize sci2
2019-08-26T17:37:58.017Z,1566841078.017 [sci2:Science] Stopped
2019-08-26T17:37:58.017Z,1566841078.017 [sci2:Science](DEBUG): Aggregate::uninitialize sci2:Science
2019-08-26T17:37:58.017Z,1566841078.017 [sci2:Science:A] Stopped
2019-08-26T17:37:58.018Z,1566841078.018 [sci2:Science:C] Stopped
2019-08-26T17:37:58.018Z,1566841078.018 [sci2:Science:G] Stopped
2019-08-26T17:37:58.018Z,1566841078.018 [sci2:Science:Read_Oil] Stopped
2019-08-26T17:37:58.018Z,1566841078.018 [sci2:Science:PeakDetectChl] Stopped
2019-08-26T17:37:58.018Z,1566841078.018 [sci2:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize sci2:Science:PeakDetectChl
2019-08-26T17:37:58.018Z,1566841078.018 [sci2:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2019-08-26T17:37:58.018Z,1566841078.018 [sci2:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2019-08-26T17:37:58.018Z,1566841078.018 [sci2:NeedComms] Stopped
2019-08-26T17:37:58.018Z,1566841078.018 [sci2:NeedComms](DEBUG): Aggregate::uninitialize sci2:NeedComms
2019-08-26T17:37:58.018Z,1566841078.018 [sci2:NeedComms:B.GoToSurface] Stopped
2019-08-26T17:37:58.018Z,1566841078.018 [sci2:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-08-26T17:37:58.018Z,1566841078.018 [sci2:NeedComms:E] Stopped
2019-08-26T17:37:58.018Z,1566841078.018 [sci2:StandardEnvelopes] Stopped
2019-08-26T17:37:58.019Z,1566841078.019 [sci2:StandardEnvelopes](DEBUG): Aggregate::uninitialize sci2:StandardEnvelopes
2019-08-26T17:37:58.019Z,1566841078.019 [sci2:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2019-08-26T17:37:58.019Z,1566841078.019 [sci2:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2019-08-26T17:37:58.023Z,1566841078.023 [sci2:StandardEnvelopes:B.DepthEnvelope] Stopped
2019-08-26T17:37:58.023Z,1566841078.023 [sci2:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2019-08-26T17:37:58.023Z,1566841078.023 [sci2:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2019-08-26T17:37:58.023Z,1566841078.023 [sci2:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2019-08-26T17:37:58.023Z,1566841078.023 [sci2:StartingMission] Stopped
2019-08-26T17:37:58.023Z,1566841078.023 [sci2:StartingMission](DEBUG): Aggregate::uninitialize sci2:StartingMission
2019-08-26T17:37:58.023Z,1566841078.023 [sci2:I.Pitch] Stopped
2019-08-26T17:37:58.024Z,1566841078.024 [sci2:J.Buoyancy] Stopped
2019-08-26T17:37:58.024Z,1566841078.024 [sci2:J.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2019-08-26T17:37:58.024Z,1566841078.024 [sci2:K.SetSpeed] Stopped
2019-08-26T17:37:58.024Z,1566841078.024 [sci2:K.SetSpeed](DEBUG): Uninitialize.
2019-08-26T17:37:58.024Z,1566841078.024 [sci2:L.DepthEnvelope] Stopped
2019-08-26T17:37:58.024Z,1566841078.024 [sci2:L.DepthEnvelope](DEBUG): Uninitialize.
2019-08-26T17:37:58.024Z,1566841078.024 [sci2:M.AltitudeEnvelope] Stopped
2019-08-26T17:37:58.024Z,1566841078.024 [sci2:M.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2019-08-26T17:37:58.024Z,1566841078.024 [sci2:N.YoYo] Stopped
2019-08-26T17:37:58.024Z,1566841078.024 [sci2:N.YoYo](DEBUG): Uninitialize YoYoComponent.
2019-08-26T17:37:58.303Z,1566841078.303 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-08-26T17:37:58.394Z,1566841078.394 [MissionManager](IMPORTANT): Started mission Default
2019-08-26T17:37:58.394Z,1566841078.394 [Default] Running Loop=1
2019-08-26T17:37:58.395Z,1566841078.395 [Default](DEBUG): Aggregate::initialize Default
2019-08-26T17:37:58.395Z,1566841078.395 [Default:B.GoToSurface] Running Loop=1
2019-08-26T17:37:58.395Z,1566841078.395 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-08-26T17:37:58.400Z,1566841078.400 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-08-26T17:37:58.401Z,1566841078.401 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-08-26T17:37:58.402Z,1566841078.402 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-08-26T17:37:58.408Z,1566841078.408 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-08-26T17:37:58.408Z,1566841078.408 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-08-26T17:37:58.408Z,1566841078.408 [Default:A.Wait] Running Loop=1
2019-08-26T17:37:58.409Z,1566841078.409 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-08-26T17:37:58.911Z,1566841078.911 [Aanderaa_O2](INFO): Powering down
2019-08-26T17:38:06.062Z,1566841086.062 [NAL9602](INFO): SBD MO Status=0, MOMSN=8702, MT Status=0, MTMSN=0
2019-08-26T17:38:06.062Z,1566841086.062 [NAL9602](INFO): No messages in MT queue
2019-08-26T17:38:11.791Z,1566841091.791 [Default:A.Wait](INFO): Done Waiting.
2019-08-26T17:38:11.799Z,1566841091.799 [Default:A.Wait] Stopped
2019-08-26T17:38:11.799Z,1566841091.799 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-08-26T17:38:12.169Z,1566841092.169 [Default:CheckIn] Running Loop=1
2019-08-26T17:38:12.169Z,1566841092.169 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-08-26T17:38:12.169Z,1566841092.169 [Default:CheckIn:Read_GPS] Running Loop=1
2019-08-26T17:38:13.752Z,1566841093.752 [NAL9602](DEBUG): Fix Requested
2019-08-26T17:38:14.144Z,1566841094.144 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173813.00,A,3422.67680,N,11948.67849,W,0.894,234.36,260819,,,A*7B
2019-08-26T17:38:14.146Z,1566841094.146 [NAL9602](INFO): GPS fix at 20190826T173813: (34.377947, -119.811308)
2019-08-26T17:38:14.241Z,1566841094.241 [Default:CheckIn:Read_GPS] Stopped
2019-08-26T17:38:14.241Z,1566841094.241 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-08-26T17:38:24.490Z,1566841104.490 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20190826T165434/Courier0011.lzma
2019-08-26T17:38:25.297Z,1566841105.297 [DataOverHttps](INFO): Moved sent file to Logs/20190826T165434/Courier0011.lzma.bak
2019-08-26T17:38:25.297Z,1566841105.297 [DataOverHttps](INFO): SBD MOMSN=11696065
2019-08-26T17:38:37.610Z,1566841117.610 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20190826T173757/Courier0003.lzma
2019-08-26T17:38:38.417Z,1566841118.417 [DataOverHttps](INFO): Moved sent file to Logs/20190826T173757/Courier0003.lzma.bak
2019-08-26T17:38:38.417Z,1566841118.417 [DataOverHttps](INFO): SBD MOMSN=11696068
2019-08-26T17:38:46.737Z,1566841126.737 [NAL9602](INFO): Not Powering down - fast GPS
2019-08-26T17:38:51.506Z,1566841131.506 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20190826T165434/Express0013.lzma
2019-08-26T17:38:52.313Z,1566841132.313 [DataOverHttps](INFO): Moved sent file to Logs/20190826T165434/Express0013.lzma.bak
2019-08-26T17:38:52.313Z,1566841132.313 [DataOverHttps](INFO): SBD MOMSN=11696071
2019-08-26T17:39:05.186Z,1566841145.186 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20190826T173757/Express0005.lzma
2019-08-26T17:39:05.993Z,1566841145.993 [DataOverHttps](INFO): Moved sent file to Logs/20190826T173757/Express0005.lzma.bak
2019-08-26T17:39:05.994Z,1566841145.994 [DataOverHttps](IMPORTANT): SBD MOMSN=11696076, MTMSN=20190826T173905
2019-08-26T17:39:07.792Z,1566841147.792 [Default:CheckIn:Read_Iridium] Stopped
2019-08-26T17:39:07.793Z,1566841147.793 [Default:CheckIn:C.Wait] Running Loop=1
2019-08-26T17:39:07.793Z,1566841147.793 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-08-26T17:39:13.663Z,1566841153.663 [DataOverHttps](INFO): Received command:sched asap "load Science/sci2.xml;set sci2.NeedCommsTime 15 minute;set sci2.Lat1 34.39665 degree;set sci2.Lon1 -119.875317 degree;set sci2.Lat2 34.4047 degree;set sci2.Lon2 -119.933 degree;set sci2.Lat3 34.384231 degree" 1sbp1 1 3
2019-08-26T17:39:13.670Z,1566841153.670 [CommandLine](IMPORTANT): got command schedule asap "load Science/sci2.xml;set sci2.NeedCommsTime 15 minute;set sci2.Lat1 34.39665 degree;set sci2.Lon1 -119.875317 degree;set sci2.Lat2 34.4047 degree;set sci2.Lon2 -119.933 degree;set sci2.Lat3 34.384231 degree" 1sbp1 1 3.000000
2019-08-26T17:39:13.671Z,1566841153.671 [CommandLine](IMPORTANT): Scheduling command #1 of 3 with id=1sbp1
2019-08-26T17:39:13.671Z,1566841153.671 [CommandLine](IMPORTANT): Scheduled #45 (#1 of 3 with id='1sbp1'): "load Science/sci2.xml;set sci2.NeedCommsTime 15 minute;set sci2.Lat1 34.39665 degree;set sci2.Lon1 -119.875317 degree;set sci2.Lat2 34.4047 degree;set sci2.Lon2 -119.933 degree;set sci2.Lat3 34.384231 degree" ASAP
2019-08-26T17:39:14.469Z,1566841154.469 [DataOverHttps](IMPORTANT): SBD MTMSN=20190826T173914
2019-08-26T17:39:19.755Z,1566841159.755 [DataOverHttps](INFO): Received command:sched asap "set sci2.Lon3 -119.94913 degree;set sci2.Lat4 34.370162 degree;set sci2.Lon4 -119.895112 degree;set sci2.Lat5 34.384383 degree;set sci2.Lon5 -119.890467 degree;set sci2.YoYoMaxDepth 30 meter;set sci2.MaxDepth 80 meter" 1sbp1 2 3
2019-08-26T17:39:19.773Z,1566841159.773 [CommandLine](IMPORTANT): got command schedule asap "set sci2.Lon3 -119.94913 degree;set sci2.Lat4 34.370162 degree;set sci2.Lon4 -119.895112 degree;set sci2.Lat5 34.384383 degree;set sci2.Lon5 -119.890467 degree;set sci2.YoYoMaxDepth 30 meter;set sci2.MaxDepth 80 meter" 1sbp1 2 3.000000
2019-08-26T17:39:19.773Z,1566841159.773 [CommandLine](IMPORTANT): Scheduling command #2 of 3 with id=1sbp1
2019-08-26T17:39:19.774Z,1566841159.774 [CommandLine](IMPORTANT): Scheduled #46 (#2 of 3 with id='1sbp1'): "set sci2.Lon3 -119.94913 degree;set sci2.Lat4 34.370162 degree;set sci2.Lon4 -119.895112 degree;set sci2.Lat5 34.384383 degree;set sci2.Lon5 -119.890467 degree;set sci2.YoYoMaxDepth 30 meter;set sci2.MaxDepth 80 meter" ASAP
2019-08-26T17:39:20.560Z,1566841160.560 [DataOverHttps](IMPORTANT): SBD MTMSN=20190826T173920
2019-08-26T17:39:26.595Z,1566841166.595 [DataOverHttps](INFO): Received command:sched asap "set sci2.MinOffshore 1 kilometer;run " 1sbp1 3 3
2019-08-26T17:39:26.692Z,1566841166.692 [CommandLine](IMPORTANT): got command schedule asap "set sci2.MinOffshore 1 kilometer;run " 1sbp1 3 3.000000
2019-08-26T17:39:26.693Z,1566841166.693 [CommandLine](IMPORTANT): Scheduling command #3 of 3 with id=1sbp1
2019-08-26T17:39:26.694Z,1566841166.694 [CommandLine](IMPORTANT): Scheduled #47 (#3 of 3 with id='1sbp1'): "set sci2.MinOffshore 1 kilometer;run " ASAP
2019-08-26T17:39:26.888Z,1566841166.888 [CommandLine](IMPORTANT): got command load ./Missions/Science/sci2.xml
2019-08-26T17:39:26.889Z,1566841166.889 [MissionManager](INFO): Loading Mission: ./Missions/Science/sci2.xml
2019-08-26T17:39:26.995Z,1566841166.995 [MissionManager](INFO): DefineArg sci2.MissionTimeout = 2.000000 h
2019-08-26T17:39:26.998Z,1566841166.998 [MissionManager](INFO): DefineArg sci2.NeedCommsTime = 60.000000 min
2019-08-26T17:39:27.001Z,1566841167.001 [MissionManager](INFO): DefineArg sci2.Repeat = 1.000000 count
2019-08-26T17:39:27.003Z,1566841167.003 [MissionManager](INFO): DefineArg sci2.Lat1 = nan arcdeg
2019-08-26T17:39:27.005Z,1566841167.005 [MissionManager](INFO): DefineArg sci2.Lon1 = nan arcdeg
2019-08-26T17:39:27.008Z,1566841167.008 [MissionManager](INFO): DefineArg sci2.Lat2 = nan arcdeg
2019-08-26T17:39:27.010Z,1566841167.010 [MissionManager](INFO): DefineArg sci2.Lon2 = nan arcdeg
2019-08-26T17:39:27.012Z,1566841167.012 [MissionManager](INFO): DefineArg sci2.Lat3 = nan arcdeg
2019-08-26T17:39:27.014Z,1566841167.014 [MissionManager](INFO): DefineArg sci2.Lon3 = nan arcdeg
2019-08-26T17:39:27.016Z,1566841167.016 [MissionManager](INFO): DefineArg sci2.Lat4 = nan arcdeg
2019-08-26T17:39:27.018Z,1566841167.018 [MissionManager](INFO): DefineArg sci2.Lon4 = nan arcdeg
2019-08-26T17:39:27.021Z,1566841167.021 [MissionManager](INFO): DefineArg sci2.Lat5 = nan arcdeg
2019-08-26T17:39:27.023Z,1566841167.023 [MissionManager](INFO): DefineArg sci2.Lon5 = nan arcdeg
2019-08-26T17:39:27.025Z,1566841167.025 [MissionManager](INFO): DefineArg sci2.Lat6 = nan arcdeg
2019-08-26T17:39:27.027Z,1566841167.027 [MissionManager](INFO): DefineArg sci2.Lon6 = nan arcdeg
2019-08-26T17:39:27.029Z,1566841167.029 [MissionManager](INFO): DefineArg sci2.Lat7 = nan arcdeg
2019-08-26T17:39:27.060Z,1566841167.060 [MissionManager](INFO): DefineArg sci2.Lon7 = nan arcdeg
2019-08-26T17:39:27.062Z,1566841167.062 [MissionManager](INFO): DefineArg sci2.Speed = 1.000000 m/s
2019-08-26T17:39:27.068Z,1566841167.068 [MissionManager](INFO): DefineArg sci2.CaptureRadius = nan m
2019-08-26T17:39:27.070Z,1566841167.070 [MissionManager](INFO): DefineArg sci2.YoYoMinDepth = 2.000000 m
2019-08-26T17:39:27.081Z,1566841167.081 [MissionManager](INFO): DefineArg sci2.YoYoMaxDepth = 200.000000 m
2019-08-26T17:39:27.095Z,1566841167.095 [MissionManager](INFO): DefineArg sci2.YoYoMinAltitude = 9.000000 m
2019-08-26T17:39:27.098Z,1566841167.098 [MissionManager](INFO): DefineArg sci2.YoYoUpPitch = 20.000000 arcdeg
2019-08-26T17:39:27.142Z,1566841167.142 [MissionManager](INFO): DefineArg sci2.YoYoDownPitch = -20.000000 arcdeg
2019-08-26T17:39:27.166Z,1566841167.166 [MissionManager](INFO): DefineArg sci2.BuoyancyNeutral = 277.999992 cc
2019-08-26T17:39:27.184Z,1566841167.184 [MissionManager](INFO): DefineArg sci2.MassDefault = 2.500000 cm
2019-08-26T17:39:27.186Z,1566841167.186 [MissionManager](INFO): DefineArg sci2.MinAltitude = 5.000000 m
2019-08-26T17:39:27.202Z,1566841167.202 [MissionManager](INFO): DefineArg sci2.MaxDepth = 205.000000 m
2019-08-26T17:39:27.220Z,1566841167.220 [MissionManager](INFO): DefineArg sci2.MinOffshore = 2.000000 km
2019-08-26T17:39:27.221Z,1566841167.221 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml
2019-08-26T17:39:27.449Z,1566841167.449 [MissionManager](INFO): DefineArg sci2:Science.SampleISUS = 0 bool
2019-08-26T17:39:27.452Z,1566841167.452 [MissionManager](INFO): DefineArg sci2:Science.SampleISUSmaxPitch = 60.000000 arcdeg
2019-08-26T17:39:27.454Z,1566841167.454 [MissionManager](INFO): DefineArg sci2:Science.SampleISUSminPitch = -60.000000 arcdeg
2019-08-26T17:39:27.456Z,1566841167.456 [MissionManager](INFO): DefineArg sci2:Science.PeakDetectChlActive = 0 bool
2019-08-26T17:39:27.458Z,1566841167.458 [MissionManager](INFO): DefineArg sci2:Science.TimeWindowPeakReport = nan min
2019-08-26T17:39:27.460Z,1566841167.460 [MissionManager](INFO): DefineArg sci2:Science.HighestChlPeakReportActive = 0 bool
2019-08-26T17:39:27.462Z,1566841167.462 [MissionManager](INFO): DefineArg sci2:Science.PatchTracking = 0 bool
2019-08-26T17:39:27.464Z,1566841167.464 [MissionManager](INFO): DefineArg sci2:Science.FilterWidthHorizontal = 3.000000 count
2019-08-26T17:39:27.466Z,1566841167.466 [MissionManager](INFO): DefineArg sci2:Science.NumProfilesSlidingwindow = 100.000000 count
2019-08-26T17:39:27.489Z,1566841167.489 [MissionManager](INFO): DefineArg sci2:Science.OffPeakFractionHorizontal = 80.000000 %
2019-08-26T17:39:27.491Z,1566841167.491 [MissionManager](INFO): DefineArg sci2:Science.PeakDetectNO3Active = 0 bool
2019-08-26T17:39:27.497Z,1566841167.497 [MissionManager](INFO): DefineArg sci2:Science.PeakDetectOilActive = 0 bool
2019-08-26T17:39:27.500Z,1566841167.500 [MissionManager](INFO): DefineArg sci2:Science.PeakDetectFDOMActive = 0 bool
2019-08-26T17:39:27.502Z,1566841167.502 [MissionManager](INFO): DefineArg sci2:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2019-08-26T17:39:27.590Z,1566841167.590 [MissionManager](INFO): DefineArg sci2:Science.EnabledAanderaaO2 = 1.000000 bool
2019-08-26T17:39:27.596Z,1566841167.596 [MissionManager](INFO): DefineArg sci2:Science.EnabledNeilBrown = 1.000000 bool
2019-08-26T17:39:27.615Z,1566841167.615 [MissionManager](INFO): DefineArg sci2:Science.EnabledSeabird = 0.000000 bool
2019-08-26T17:39:27.618Z,1566841167.618 [MissionManager](INFO): DefineArg sci2:Science.EnabledTurbulence_NPS = 0.000000 bool
2019-08-26T17:39:27.629Z,1566841167.629 [MissionManager](INFO): DefineArg sci2:Science.EnabledWetLabsBB2FL = 0.000000 bool
2019-08-26T17:39:27.639Z,1566841167.639 [MissionManager](INFO): DefineArg sci2:Science.EnabledWetLabsSeaOWL_UV_A = 1.000000 bool
2019-08-26T17:39:27.642Z,1566841167.642 [MissionManager](INFO): DefineArg sci2:Science.EnabledWetLabsUBAT = 0.000000 bool
2019-08-26T17:39:27.650Z,1566841167.650 [MissionManager](INFO): DefineArg sci2:Science.EnabledISUS = 0.000000 bool
2019-08-26T17:39:27.672Z,1566841167.672 [MissionManager](INFO): DefineArg sci2:Science.LowPassWindowLength = 20.000000 count
2019-08-26T17:39:27.674Z,1566841167.674 [MissionManager](INFO): DefineArg sci2:Science.MedianFilterLen = 5.000000 count
2019-08-26T17:39:27.676Z,1566841167.676 [MissionManager](INFO): DefineArg sci2:Science.PeakChlShallowBound = nan m
2019-08-26T17:39:27.678Z,1566841167.678 [MissionManager](INFO): DefineArg sci2:Science.PeakChlDeepBound = nan m
2019-08-26T17:39:27.680Z,1566841167.680 [MissionManager](INFO): DefineArg sci2:Science.PeakShallowBound = nan m
2019-08-26T17:39:27.682Z,1566841167.682 [MissionManager](INFO): DefineArg sci2:Science.PeakDeepBound = nan m
2019-08-26T17:39:27.689Z,1566841167.689 [MissionManager](INFO): DefineOutput sci2:Science.PeakChl = 0.000000 ug/l
2019-08-26T17:39:27.704Z,1566841167.704 [MissionManager](INFO): DefineOutput sci2:Science.PeakChlDepth = 0.000000 m
2019-08-26T17:39:27.706Z,1566841167.706 [MissionManager](INFO): DefineOutput sci2:Science.PeakChlTemperature = 0.000000 degC
2019-08-26T17:39:27.709Z,1566841167.709 [MissionManager](INFO): DefineOutput sci2:Science.PeakChlLatitude = nan arcdeg
2019-08-26T17:39:27.711Z,1566841167.711 [MissionManager](INFO): DefineOutput sci2:Science.PeakChlLongitude = nan arcdeg
2019-08-26T17:39:27.714Z,1566841167.714 [MissionManager](INFO): DefineOutput sci2:Science.PeakNO3 = 0.000000 umol/l
2019-08-26T17:39:27.716Z,1566841167.716 [MissionManager](INFO): DefineOutput sci2:Science.PeakNO3Depth = 0.000000 m
2019-08-26T17:39:27.718Z,1566841167.718 [MissionManager](INFO): DefineOutput sci2:Science.PeakNO3Latitude = nan arcdeg
2019-08-26T17:39:27.720Z,1566841167.720 [MissionManager](INFO): DefineOutput sci2:Science.PeakNO3Longitude = nan arcdeg
2019-08-26T17:39:27.722Z,1566841167.722 [MissionManager](INFO): DefineOutput sci2:Science.PatchChl = 0.000000 m
2019-08-26T17:39:27.737Z,1566841167.737 [MissionManager](INFO): DefineOutput sci2:Science.PatchChlDepth = 0.000000 m
2019-08-26T17:39:27.740Z,1566841167.740 [MissionManager](INFO): DefineOutput sci2:Science.PatchChlLatitude = nan arcdeg
2019-08-26T17:39:27.742Z,1566841167.742 [MissionManager](INFO): DefineOutput sci2:Science.PatchChlLongitude = nan arcdeg
2019-08-26T17:39:27.744Z,1566841167.744 [MissionManager](INFO): DefineOutput sci2:Science.PatchChlDistance = nan m
2019-08-26T17:39:27.746Z,1566841167.746 [MissionManager](INFO): DefineOutput sci2:Science.PeakOil = 0.000000 kg/m3
2019-08-26T17:39:27.759Z,1566841167.759 [MissionManager](INFO): DefineOutput sci2:Science.PeakOilDepth = 0.000000 m
2019-08-26T17:39:27.761Z,1566841167.761 [MissionManager](INFO): DefineOutput sci2:Science.PeakOilLatitude = nan arcdeg
2019-08-26T17:39:27.764Z,1566841167.764 [MissionManager](INFO): DefineOutput sci2:Science.PeakOilLongitude = nan arcdeg
2019-08-26T17:39:27.766Z,1566841167.766 [MissionManager](INFO): DefineOutput sci2:Science.PeakFDOM = 0.000000 ppb
2019-08-26T17:39:27.784Z,1566841167.784 [MissionManager](INFO): DefineOutput sci2:Science.PeakFDOMDepth = 0.000000 m
2019-08-26T17:39:27.786Z,1566841167.786 [MissionManager](INFO): DefineOutput sci2:Science.PeakFDOMLatitude = nan arcdeg
2019-08-26T17:39:27.789Z,1566841167.789 [MissionManager](INFO): DefineOutput sci2:Science.PeakFDOMLongitude = nan arcdeg
2019-08-26T17:39:27.791Z,1566841167.791 [MissionManager](INFO): DefineOutput sci2:Science.PatchFDOMDistance = nan m
2019-08-26T17:39:27.836Z,1566841167.836 [sci2:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2019-08-26T17:39:27.846Z,1566841167.846 [sci2:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2019-08-26T17:39:27.887Z,1566841167.887 [sci2:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2019-08-26T17:39:27.912Z,1566841167.912 [sci2:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2019-08-26T17:39:27.973Z,1566841167.973 [sci2:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2019-08-26T17:39:28.011Z,1566841168.011 [MissionManager](INFO): RedefineArg sci2:Science.SampleISUS = value:1 bool
2019-08-26T17:39:28.032Z,1566841168.032 [MissionManager](INFO): RedefineArg sci2:Science.PeakDetectChlActive = value:1 bool
2019-08-26T17:39:28.033Z,1566841168.033 [MissionManager](INFO): Inserting Stack: Missions/Insert/NeedComms.xml
2019-08-26T17:39:28.120Z,1566841168.120 [MissionManager](INFO): DefineArg sci2:NeedComms.DiveInterval = 3.000000 h
2019-08-26T17:39:28.122Z,1566841168.122 [MissionManager](INFO): DefineArg sci2:NeedComms.WaitForPitchUp = 10.000000 min
2019-08-26T17:39:28.129Z,1566841168.129 [MissionManager](INFO): DefineArg sci2:NeedComms.SurfacePitch = 20.000000 arcdeg
2019-08-26T17:39:28.135Z,1566841168.135 [MissionManager](INFO): DefineArg sci2:NeedComms.SurfaceDepthRate = nan m/s
2019-08-26T17:39:28.138Z,1566841168.138 [MissionManager](INFO): DefineArg sci2:NeedComms.SurfaceSpeed = 1.000000 m/s
2019-08-26T17:39:28.144Z,1566841168.144 [MissionManager](INFO): DefineArg sci2:NeedComms.GPSTimeout = 7.000000 min
2019-08-26T17:39:28.146Z,1566841168.146 [MissionManager](INFO): DefineArg sci2:NeedComms.CommsTimeout = 30.000000 min
2019-08-26T17:39:28.160Z,1566841168.160 [sci2:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-08-26T17:39:28.183Z,1566841168.183 [MissionManager](INFO): Inserting Stack: Missions/Insert/StandardEnvelopes.xml
2019-08-26T17:39:28.215Z,1566841168.215 [MissionManager](INFO): DefineArg sci2:StandardEnvelopes.MinAltitude = 5.000000 m
2019-08-26T17:39:28.218Z,1566841168.218 [MissionManager](INFO): DefineArg sci2:StandardEnvelopes.MaxDepth = 200.000000 m
2019-08-26T17:39:28.236Z,1566841168.236 [MissionManager](INFO): DefineArg sci2:StandardEnvelopes.MinOffshore = 2000.000000 m
2019-08-26T17:39:28.237Z,1566841168.237 [sci2:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2019-08-26T17:39:28.256Z,1566841168.256 [sci2:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2019-08-26T17:39:28.266Z,1566841168.266 [sci2:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2019-08-26T17:39:28.289Z,1566841168.289 [sci2:I.Pitch](DEBUG): Construct.
2019-08-26T17:39:28.307Z,1566841168.307 [sci2:J.Buoyancy](DEBUG): Construct Buoyancy.
2019-08-26T17:39:28.317Z,1566841168.317 [sci2:K.SetSpeed](DEBUG): Construct.
2019-08-26T17:39:28.321Z,1566841168.321 [sci2:L.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2019-08-26T17:39:28.393Z,1566841168.393 [sci2:M.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2019-08-26T17:39:28.407Z,1566841168.407 [sci2:N.YoYo](DEBUG): Construct YoYo.
2019-08-26T17:39:28.430Z,1566841168.430 [sci2:Lap:Wpt1:A.Waypoint](DEBUG): Construct Waypoint.
2019-08-26T17:39:28.444Z,1566841168.444 [sci2:Lap:Wpt2:A.Waypoint](DEBUG): Construct Waypoint.
2019-08-26T17:39:28.450Z,1566841168.450 [sci2:Lap:Wpt3:A.Waypoint](DEBUG): Construct Waypoint.
2019-08-26T17:39:28.467Z,1566841168.467 [sci2:Lap:Wpt4:A.Waypoint](DEBUG): Construct Waypoint.
2019-08-26T17:39:28.476Z,1566841168.476 [sci2:Lap:Wpt5:A.Waypoint](DEBUG): Construct Waypoint.
2019-08-26T17:39:28.482Z,1566841168.482 [sci2:Lap:Wpt6:A.Waypoint](DEBUG): Construct Waypoint.
2019-08-26T17:39:28.501Z,1566841168.501 [sci2:Lap:Wpt7:A.Waypoint](DEBUG): Construct Waypoint.
2019-08-26T17:39:28.564Z,1566841168.564 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<!-- ====================
     Generated by TethysL
     ==================== -->
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Control="Tethys/Control"
         xmlns:Guidance="Tethys/Guidance"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd"
       Id="sci2">

    <Description>
        Vehicle yo-yo's to the specified waypoints, with science turned on.
    </Description>
    <DefineArg Name="MissionTimeout"><Description>
        Maximum duration of mission
    </Description><Units:hour/><Value>2</Value></DefineArg>

    <DefineArg Name="NeedCommsTime"><Description>
        How often to surface for commumications
    </Description><Units:minute/><Value>60</Value></DefineArg>

    <DefineArg Name="Repeat"><Description>
        Number of times the vehicle should try to cycle through waypoints..
    </Description><Units:count/><Value>1</Value></DefineArg>

    <DefineArg Name="Lat1"><Description>
        Latitude of waypoint 1. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lon1"><Description>
        Longitude of waypoint 1. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lat2"><Description>
        Latitude of waypoint 2. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lon2"><Description>
        Longitude of waypoint 2. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lat3"><Description>
        Latitude of waypoint 3. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lon3"><Description>
        Longitude of waypoint 3. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lat4"><Description>
        Latitude of waypoint 4. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lon4"><Description>
        Longitude of waypoint 4. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lat5"><Description>
        Latitude of waypoint 5. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lon5"><Description>
        Longitude of waypoint 5. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lat6"><Description>
        Latitude of waypoint 6. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lon6"><Description>
        Longitude of waypoint 6. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lat7"><Description>
        Latitude of waypoint 7. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lon7"><Description>
        Longitude of waypoint 7. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Speed"><Description>
        Speed while performing the YoYo behavior.
    </Description><Units:meter_per_second/><Value>1</Value></DefineArg>

    <DefineArg Name="CaptureRadius"><Description>
        Speed while performing the YoYo behavior.
    </Description><Units:meter/><Value>NaN</Value></DefineArg>

    
    <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>9</Value></DefineArg>

    <DefineArg Name="YoYoUpPitch"><Description>
        Vehicle up pitch while performing the YoYo behavior.
    </Description><Units:degree/><Value>20</Value></DefineArg>

    <DefineArg Name="YoYoDownPitch"><Description>
        Vehicle down pitch while performing the YoYo behavior.
    </Description><Units:degree/><Value>-20</Value></DefineArg>

    <DefineArg Name="BuoyancyNeutral"><Description>
        Buoyancy bladder position while performing the YoYo behavior. Defaults
        to buoyancyNeutral setting in the Config/Control.cfg file. Set to NaN cc
        for active buoyancy
    </Description><Control:VerticalControl.buoyancyNeutral/></DefineArg>

    <DefineArg Name="MassDefault"><Description>
        Static setting for mass during the mission. Set to NaN mm for active
        mass position
    </Description><Control:VerticalControl.massDefault/></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>
    <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>

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

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

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

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

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

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

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

    <Guidance:Pitch>
        <Parallel/>
        <Setting><Guidance:Pitch.massPosition/><Arg Name="MassDefault"/></Setting>
    </Guidance:Pitch>

    <Guidance:Buoyancy>
        <Parallel/>
        <Setting><Guidance:Buoyancy.position/><Arg Name="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.downPitch/><Arg Name="YoYoDownPitch"/></Setting>
        <Setting><Guidance:DepthEnvelope.upPitch/><Arg Name="YoYoUpPitch"/></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.upPitch/><Arg Name="YoYoUpPitch"/></Setting>
    </Guidance:AltitudeEnvelope>

    <Guidance:YoYo>
        <Parallel/>
        <Setting><Guidance:YoYo.downPitch/><Arg Name="YoYoDownPitch"/></Setting>
        <Setting><Guidance:YoYo.upPitch/><Arg Name="YoYoUpPitch"/></Setting>
    </Guidance:YoYo>

    <Aggregate Id="Lap">
        <Sequence Repeat="Repeat"/>

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

            <Guidance:Waypoint>
                <Sequence/>
                <Setting><Guidance:Waypoint.latitude/><Arg Name="Lat1"/></Setting>
                <Setting><Guidance:Waypoint.longitude/><Arg Name="Lon1"/></Setting>
                <Setting><Guidance:Waypoint.captureRadius/><Arg Name="CaptureRadius"/></Setting>
            </Guidance:Waypoint>
        </Aggregate>

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

            <Guidance:Waypoint>
                <Sequence/>
                <Setting><Guidance:Waypoint.latitude/><Arg Name="Lat2"/></Setting>
                <Setting><Guidance:Waypoint.longitude/><Arg Name="Lon2"/></Setting>
            </Guidance:Waypoint>
        </Aggregate>

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

            <Guidance:Waypoint>
                <Sequence/>
                <Setting><Guidance:Waypoint.latitude/><Arg Name="Lat3"/></Setting>
                <Setting><Guidance:Waypoint.longitude/><Arg Name="Lon3"/></Setting>
            </Guidance:Waypoint>
        </Aggregate>

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

            <Guidance:Waypoint>
                <Sequence/>
                <Setting><Guidance:Waypoint.latitude/><Arg Name="Lat4"/></Setting>
                <Setting><Guidance:Waypoint.longitude/><Arg Name="Lon4"/></Setting>
            </Guidance:Waypoint>
        </Aggregate>

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

            <Guidance:Waypoint>
                <Sequence/>
                <Setting><Guidance:Waypoint.latitude/><Arg Name="Lat5"/></Setting>
                <Setting><Guidance:Waypoint.longitude/><Arg Name="Lon5"/></Setting>
            </Guidance:Waypoint>
        </Aggregate>

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

            <Guidance:Waypoint>
                <Sequence/>
                <Setting><Guidance:Waypoint.latitude/><Arg Name="Lat6"/></Setting>
                <Setting><Guidance:Waypoint.longitude/><Arg Name="Lon6"/></Setting>
            </Guidance:Waypoint>
        </Aggregate>

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

            <Guidance:Waypoint>
                <Sequence/>
                <Setting><Guidance:Waypoint.latitude/><Arg Name="Lat7"/></Setting>
                <Setting><Guidance:Waypoint.longitude/><Arg Name="Lon7"/></Setting>
            </Guidance:Waypoint>
        </Aggregate>
    </Aggregate>
</Mission>


2019-08-26T17:39:28.565Z,1566841168.565 [CommandLine](IMPORTANT): Loaded ./Missions/Science/sci2.xml
2019-08-26T17:39:35.157Z,1566841175.157 [CommandLine](IMPORTANT): got command set sci2.NeedCommsTime 15.000000 minute
2019-08-26T17:39:35.158Z,1566841175.158 [CommandLine](IMPORTANT): got command set sci2.Lat1 34.396648 degree
2019-08-26T17:39:35.159Z,1566841175.159 [CommandLine](IMPORTANT): got command set sci2.Lon1 -119.875320 degree
2019-08-26T17:39:35.160Z,1566841175.160 [CommandLine](IMPORTANT): got command set sci2.Lat2 34.404701 degree
2019-08-26T17:39:35.160Z,1566841175.160 [CommandLine](IMPORTANT): got command set sci2.Lon2 -119.932999 degree
2019-08-26T17:39:35.161Z,1566841175.161 [CommandLine](IMPORTANT): got command set sci2.Lat3 34.384232 degree
2019-08-26T17:39:43.256Z,1566841183.256 [CommandLine](IMPORTANT): got command set sci2.Lon3 -119.949127 degree
2019-08-26T17:39:43.257Z,1566841183.257 [CommandLine](IMPORTANT): got command set sci2.Lat4 34.370163 degree
2019-08-26T17:39:43.257Z,1566841183.257 [CommandLine](IMPORTANT): got command set sci2.Lon4 -119.895111 degree
2019-08-26T17:39:43.258Z,1566841183.258 [CommandLine](IMPORTANT): got command set sci2.Lat5 34.384384 degree
2019-08-26T17:39:43.259Z,1566841183.259 [CommandLine](IMPORTANT): got command set sci2.Lon5 -119.890465 degree
2019-08-26T17:39:43.260Z,1566841183.260 [CommandLine](IMPORTANT): got command set sci2.YoYoMaxDepth 30.000000 meter
2019-08-26T17:39:43.261Z,1566841183.261 [CommandLine](IMPORTANT): got command set sci2.MaxDepth 80.000000 meter
2019-08-26T17:39:45.049Z,1566841185.049 [CommandLine](IMPORTANT): got command set sci2.MinOffshore 1.000000 kilometer
2019-08-26T17:39:45.050Z,1566841185.050 [CommandLine](IMPORTANT): got command run 
2019-08-26T17:39:45.055Z,1566841185.055 [CommandLine](IMPORTANT): Running 
2019-08-26T17:39:45.356Z,1566841185.356 [Default] Stopped
2019-08-26T17:39:45.357Z,1566841185.357 [Default](DEBUG): Aggregate::uninitialize Default
2019-08-26T17:39:45.357Z,1566841185.357 [Default:B.GoToSurface] Stopped
2019-08-26T17:39:45.357Z,1566841185.357 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-08-26T17:39:45.357Z,1566841185.357 [Default:CheckIn] Stopped
2019-08-26T17:39:45.357Z,1566841185.357 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-08-26T17:39:45.357Z,1566841185.357 [Default:CheckIn:C.Wait] Stopped
2019-08-26T17:39:45.357Z,1566841185.357 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-08-26T17:39:45.357Z,1566841185.357 [MissionManager](IMPORTANT): Started mission sci2
2019-08-26T17:39:45.357Z,1566841185.357 [sci2] Running Loop=1
2019-08-26T17:39:45.358Z,1566841185.358 [sci2](DEBUG): Aggregate::initialize sci2
2019-08-26T17:39:45.358Z,1566841185.358 [sci2:Science] Running Loop=1
2019-08-26T17:39:45.358Z,1566841185.358 [sci2:Science](DEBUG): Aggregate::initialize sci2:Science
2019-08-26T17:39:45.358Z,1566841185.358 [sci2:Science:A] Running Loop=1
2019-08-26T17:39:45.358Z,1566841185.358 [sci2:Science:B] Running Loop=1
2019-08-26T17:39:45.358Z,1566841185.358 [sci2:Science:C] Running Loop=1
2019-08-26T17:39:45.358Z,1566841185.358 [sci2:Science:D] Running Loop=1
2019-08-26T17:39:45.358Z,1566841185.358 [sci2:Science:E] Running Loop=1
2019-08-26T17:39:45.358Z,1566841185.358 [sci2:Science:F] Running Loop=1
2019-08-26T17:39:45.358Z,1566841185.358 [sci2:Science:G] Running Loop=1
2019-08-26T17:39:45.358Z,1566841185.358 [sci2:Science:H] Running Loop=1
2019-08-26T17:39:45.358Z,1566841185.358 [sci2:Science:Read_Oil] Running Loop=1
2019-08-26T17:39:45.358Z,1566841185.358 [sci2:Science:PeakDetectChl] Running Loop=1
2019-08-26T17:39:45.359Z,1566841185.359 [sci2:Science:PeakDetectChl](DEBUG): Aggregate::initialize sci2:Science:PeakDetectChl
2019-08-26T17:39:45.359Z,1566841185.359 [sci2:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2019-08-26T17:39:45.359Z,1566841185.359 [sci2:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2019-08-26T17:39:45.360Z,1566841185.360 [sci2:Science:HighestChlPeakReport] Running Loop=1
2019-08-26T17:39:45.360Z,1566841185.360 [sci2:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize sci2:Science:HighestChlPeakReport
2019-08-26T17:39:45.361Z,1566841185.361 [sci2:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2019-08-26T17:39:45.361Z,1566841185.361 [sci2:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2019-08-26T17:39:45.361Z,1566841185.361 [sci2:Science:PeakDetectNO3] Running Loop=1
2019-08-26T17:39:45.361Z,1566841185.361 [sci2:Science:PeakDetectNO3](DEBUG): Aggregate::initialize sci2:Science:PeakDetectNO3
2019-08-26T17:39:45.361Z,1566841185.361 [sci2:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2019-08-26T17:39:45.361Z,1566841185.361 [sci2:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2019-08-26T17:39:45.362Z,1566841185.362 [sci2:Science:PeakDetectOil] Running Loop=1
2019-08-26T17:39:45.362Z,1566841185.362 [sci2:Science:PeakDetectOil](DEBUG): Aggregate::initialize sci2:Science:PeakDetectOil
2019-08-26T17:39:45.363Z,1566841185.363 [sci2:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2019-08-26T17:39:45.363Z,1566841185.363 [sci2:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2019-08-26T17:39:45.364Z,1566841185.364 [sci2:Science:PeakDetectFDOM] Running Loop=1
2019-08-26T17:39:45.364Z,1566841185.364 [sci2:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize sci2:Science:PeakDetectFDOM
2019-08-26T17:39:45.364Z,1566841185.364 [sci2:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2019-08-26T17:39:45.364Z,1566841185.364 [sci2:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2019-08-26T17:39:45.365Z,1566841185.365 [sci2:StandardEnvelopes] Running Loop=1
2019-08-26T17:39:45.366Z,1566841185.366 [sci2:StandardEnvelopes](DEBUG): Aggregate::initialize sci2:StandardEnvelopes
2019-08-26T17:39:45.366Z,1566841185.366 [sci2:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2019-08-26T17:39:45.366Z,1566841185.366 [sci2:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2019-08-26T17:39:45.366Z,1566841185.366 [sci2:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2019-08-26T17:39:45.366Z,1566841185.366 [sci2:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2019-08-26T17:39:45.366Z,1566841185.366 [sci2:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2019-08-26T17:39:45.366Z,1566841185.366 [sci2:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2019-08-26T17:39:45.366Z,1566841185.366 [sci2:I.Pitch] Running Loop=1
2019-08-26T17:39:45.366Z,1566841185.366 [sci2:I.Pitch](DEBUG): Initialize.
2019-08-26T17:39:45.366Z,1566841185.366 [sci2:J.Buoyancy] Running Loop=1
2019-08-26T17:39:45.367Z,1566841185.367 [sci2:J.Buoyancy](DEBUG): Initialize Buoyancy Component.
2019-08-26T17:39:45.367Z,1566841185.367 [sci2:K.SetSpeed] Running Loop=1
2019-08-26T17:39:45.367Z,1566841185.367 [sci2:K.SetSpeed](DEBUG): Initialize.
2019-08-26T17:39:45.367Z,1566841185.367 [sci2:L.DepthEnvelope] Running Loop=1
2019-08-26T17:39:45.367Z,1566841185.367 [sci2:L.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2019-08-26T17:39:45.367Z,1566841185.367 [sci2:M.AltitudeEnvelope] Running Loop=1
2019-08-26T17:39:45.367Z,1566841185.367 [sci2:M.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2019-08-26T17:39:45.368Z,1566841185.368 [sci2:N.YoYo] Running Loop=1
2019-08-26T17:39:45.368Z,1566841185.368 [sci2:N.YoYo](DEBUG): Initialize YoYoComponent.
2019-08-26T17:39:45.368Z,1566841185.368 [sci2:C] Running Loop=1
2019-08-26T17:39:45.369Z,1566841185.369 [sci2:N.YoYo] Running Loop=1
2019-08-26T17:39:45.369Z,1566841185.369 [sci2:M.AltitudeEnvelope] Running Loop=1
2019-08-26T17:39:45.369Z,1566841185.369 [sci2:L.DepthEnvelope] Running Loop=1
2019-08-26T17:39:45.370Z,1566841185.370 [sci2:K.SetSpeed] Running Loop=1
2019-08-26T17:39:45.370Z,1566841185.370 [sci2:J.Buoyancy] Running Loop=1
2019-08-26T17:39:45.370Z,1566841185.370 [sci2:I.Pitch] Running Loop=1
2019-08-26T17:39:45.371Z,1566841185.371 [sci2:StandardEnvelopes] Running Loop=1
2019-08-26T17:39:45.371Z,1566841185.371 [sci2:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2019-08-26T17:39:45.371Z,1566841185.371 [sci2:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2019-08-26T17:39:45.372Z,1566841185.372 [sci2:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2019-08-26T17:39:45.372Z,1566841185.372 [sci2:C] Stopped
2019-08-26T17:39:45.372Z,1566841185.372 [sci2:E] Running Loop=1
2019-08-26T17:39:45.372Z,1566841185.372 [sci2:Science] Running Loop=1
2019-08-26T17:39:45.373Z,1566841185.373 [sci2:Science:PeakDetectFDOM] Stopped
2019-08-26T17:39:45.374Z,1566841185.374 [sci2:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize sci2:Science:PeakDetectFDOM
2019-08-26T17:39:45.374Z,1566841185.374 [sci2:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2019-08-26T17:39:45.374Z,1566841185.374 [sci2:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2019-08-26T17:39:45.374Z,1566841185.374 [sci2:Science:PeakDetectOil] Stopped
2019-08-26T17:39:45.374Z,1566841185.374 [sci2:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize sci2:Science:PeakDetectOil
2019-08-26T17:39:45.374Z,1566841185.374 [sci2:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2019-08-26T17:39:45.374Z,1566841185.374 [sci2:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2019-08-26T17:39:45.374Z,1566841185.374 [sci2:Science:PeakDetectNO3] Stopped
2019-08-26T17:39:45.374Z,1566841185.374 [sci2:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize sci2:Science:PeakDetectNO3
2019-08-26T17:39:45.374Z,1566841185.374 [sci2:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2019-08-26T17:39:45.374Z,1566841185.374 [sci2:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2019-08-26T17:39:45.374Z,1566841185.374 [sci2:Science:HighestChlPeakReport] Stopped
2019-08-26T17:39:45.375Z,1566841185.375 [sci2:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize sci2:Science:HighestChlPeakReport
2019-08-26T17:39:45.375Z,1566841185.375 [sci2:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2019-08-26T17:39:45.375Z,1566841185.375 [sci2:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2019-08-26T17:39:45.375Z,1566841185.375 [sci2:Science:PeakDetectChl] Running Loop=1
2019-08-26T17:39:45.375Z,1566841185.375 [sci2:Science:Read_Oil] Running Loop=1
2019-08-26T17:39:45.376Z,1566841185.376 [sci2:Science:Read_Oil](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2019-08-26T17:39:45.376Z,1566841185.376 [sci2:Science:H] Stopped
2019-08-26T17:39:45.376Z,1566841185.376 [sci2:Science:G] Running Loop=1
2019-08-26T17:39:45.376Z,1566841185.376 [sci2:Science:G](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2019-08-26T17:39:45.377Z,1566841185.377 [sci2:Science:F] Stopped
2019-08-26T17:39:45.377Z,1566841185.377 [sci2:Science:E] Stopped
2019-08-26T17:39:45.377Z,1566841185.377 [sci2:Science:D] Stopped
2019-08-26T17:39:45.377Z,1566841185.377 [sci2:Science:C] Running Loop=1
2019-08-26T17:39:45.377Z,1566841185.377 [sci2:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2019-08-26T17:39:45.378Z,1566841185.378 [sci2:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2019-08-26T17:39:45.378Z,1566841185.378 [sci2:Science:B] Stopped
2019-08-26T17:39:45.378Z,1566841185.378 [sci2:Science:A] Running Loop=1
2019-08-26T17:39:45.378Z,1566841185.378 [sci2:Science:A](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_oxygen_in_sea_water
2019-08-26T17:39:45.392Z,1566841185.392 [WetLabsSeaOWL_UV_A](INFO): Opening uart, block timeout 10ths=4
2019-08-26T17:39:45.393Z,1566841185.393 [WetLabsSeaOWL_UV_A](INFO): Powering up
2019-08-26T17:39:45.540Z,1566841185.540 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2019-08-26T17:39:45.762Z,1566841185.762 [sci2:E] Stopped
2019-08-26T17:39:45.762Z,1566841185.762 [sci2:F] Running Loop=1
2019-08-26T17:39:46.170Z,1566841186.170 [sci2:F] Stopped
2019-08-26T17:39:46.171Z,1566841186.171 [sci2:G] Running Loop=1
2019-08-26T17:39:46.602Z,1566841186.602 [sci2:G] Stopped
2019-08-26T17:39:46.602Z,1566841186.602 [sci2:StartingMission] Running Loop=1
2019-08-26T17:39:46.602Z,1566841186.602 [sci2:StartingMission](DEBUG): Aggregate::initialize sci2:StartingMission
2019-08-26T17:39:46.978Z,1566841186.978 [sci2:NeedComms] Running Loop=1
2019-08-26T17:39:46.978Z,1566841186.978 [sci2:NeedComms](DEBUG): Aggregate::initialize sci2:NeedComms
2019-08-26T17:39:46.978Z,1566841186.978 [sci2:NeedComms:B.GoToSurface] Running Loop=1
2019-08-26T17:39:46.978Z,1566841186.978 [sci2:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-08-26T17:39:46.978Z,1566841186.978 [sci2:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2019-08-26T17:39:46.999Z,1566841186.999 [sci2:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2019-08-26T17:39:47.000Z,1566841187.000 [sci2:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2019-08-26T17:39:47.000Z,1566841187.000 [sci2:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-08-26T17:39:47.001Z,1566841187.001 [sci2:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-08-26T17:39:47.001Z,1566841187.001 [sci2:NeedComms:A] Running Loop=1
2019-08-26T17:39:47.012Z,1566841187.012 [sci2:NeedComms:A](INFO): last time_fix was: 1566841093.000000 second since 1970/01/01T00:00:00Z 
2019-08-26T17:39:47.012Z,1566841187.012 [sci2:NeedComms:A] Stopped
2019-08-26T17:39:47.392Z,1566841187.392 [sci2:NeedComms:C] Running Loop=1
2019-08-26T17:39:47.773Z,1566841187.773 [sci2:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-08-26T17:39:48.966Z,1566841188.966 [NAL9602](DEBUG): Fix Requested
2019-08-26T17:39:49.358Z,1566841189.358 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173949.00,A,3422.68242,N,11948.67871,W,0.369,127.81,260819,,,A*7F
2019-08-26T17:39:49.361Z,1566841189.361 [NAL9602](INFO): GPS fix at 20190826T173949: (34.378040, -119.811312)
2019-08-26T17:39:49.440Z,1566841189.440 [sci2:NeedComms:C] Stopped
2019-08-26T17:39:49.440Z,1566841189.440 [sci2:NeedComms:D] Running Loop=1
2019-08-26T17:39:49.819Z,1566841189.819 [sci2:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-08-26T17:40:07.632Z,1566841207.632 [NAL9602](INFO): SBD MO Status=0, MOMSN=8703, MT Status=0, MTMSN=0
2019-08-26T17:40:07.632Z,1566841207.632 [NAL9602](INFO): No messages in MT queue
2019-08-26T17:40:11.151Z,1566841211.151 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20190826T173757/Courier0009.lzma
2019-08-26T17:40:11.958Z,1566841211.958 [DataOverHttps](INFO): Moved sent file to Logs/20190826T173757/Courier0009.lzma.bak
2019-08-26T17:40:11.958Z,1566841211.958 [DataOverHttps](INFO): SBD MOMSN=11696089
2019-08-26T17:40:28.791Z,1566841228.791 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20190826T173757/Express0010.lzma
2019-08-26T17:40:29.597Z,1566841229.597 [DataOverHttps](INFO): Moved sent file to Logs/20190826T173757/Express0010.lzma.bak
2019-08-26T17:40:29.597Z,1566841229.597 [DataOverHttps](INFO): SBD MOMSN=11696091
2019-08-26T17:40:31.879Z,1566841231.879 [sci2:NeedComms:D] Stopped
2019-08-26T17:40:31.883Z,1566841231.883 [sci2:NeedComms:E] Running Loop=1
2019-08-26T17:40:32.298Z,1566841232.298 [sci2:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-08-26T17:40:33.443Z,1566841233.443 [NAL9602](DEBUG): Fix Requested
2019-08-26T17:40:33.848Z,1566841233.848 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174033.00,A,3422.68454,N,11948.67935,W,0.311,112.15,260819,,,A*78
2019-08-26T17:40:33.850Z,1566841233.850 [NAL9602](INFO): GPS fix at 20190826T174033: (34.378076, -119.811323)
2019-08-26T17:40:33.890Z,1566841233.890 [sci2:NeedComms:E] Stopped
2019-08-26T17:40:33.895Z,1566841233.895 [sci2:NeedComms](INFO): Completed sci2:NeedComms
2019-08-26T17:40:33.895Z,1566841233.895 [sci2:NeedComms] Stopped
2019-08-26T17:40:33.895Z,1566841233.895 [sci2:NeedComms](DEBUG): Aggregate::uninitialize sci2:NeedComms
2019-08-26T17:40:33.895Z,1566841233.895 [sci2:NeedComms:B.GoToSurface] Stopped
2019-08-26T17:40:33.896Z,1566841233.896 [sci2:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-08-26T17:40:34.332Z,1566841234.332 [sci2:StartingMission](INFO): Completed sci2:StartingMission
2019-08-26T17:40:34.332Z,1566841234.332 [sci2:StartingMission] Stopped
2019-08-26T17:40:34.332Z,1566841234.332 [sci2:StartingMission](DEBUG): Aggregate::uninitialize sci2:StartingMission
2019-08-26T17:40:34.332Z,1566841234.332 [sci2:Lap] Running Loop=1
2019-08-26T17:40:34.332Z,1566841234.332 [sci2:Lap](DEBUG): Aggregate::initialize sci2:Lap
2019-08-26T17:40:34.333Z,1566841234.333 [sci2:Lap:Wpt1] Running Loop=1
2019-08-26T17:40:34.333Z,1566841234.333 [sci2:Lap:Wpt1](DEBUG): Aggregate::initialize sci2:Lap:Wpt1
2019-08-26T17:40:34.333Z,1566841234.333 [sci2:Lap:Wpt1:A.Waypoint] Running Loop=1
2019-08-26T17:40:34.333Z,1566841234.333 [sci2:Lap:Wpt1:A.Waypoint](DEBUG): Initialize WaypointComponent.
2019-08-26T17:40:49.299Z,1566841249.299 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout
2019-08-26T17:40:49.299Z,1566841249.299 [RDI_Pathfinder] Communications Fault, FailCount= 1
2019-08-26T17:40:49.299Z,1566841249.299 [RDI_Pathfinder](ERROR): Communications Fault
2019-08-26T17:40:49.300Z,1566841249.300 [RDI_Pathfinder](ERROR): Failed to parse:
2019-08-26T17:40:49.459Z,1566841249.459 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2019-08-26T17:40:49.676Z,1566841249.676 [RDI_Pathfinder](INFO): Powering down
2019-08-26T17:40:50.539Z,1566841250.539 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2019-08-26T17:40:50.539Z,1566841250.539 [RDI_Pathfinder] No Fault, FailCount= 1
2019-08-26T17:40:58.277Z,1566841258.277 [CTD_NeilBrown](ERROR): Failed to match checksum. Expected: 31 got: 26
2019-08-26T17:40:58.277Z,1566841258.277 [CTD_NeilBrown](ERROR): Bad response:  -0.19,18.80908,45.41137
2019-08-26T17:41:03.768Z,1566841263.768 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2019-08-26T17:41:05.753Z,1566841265.753 [NAL9602](INFO): Not Powering down - fast GPS
2019-08-26T17:41:16.726Z,1566841276.726 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2019-08-26T17:41:16.726Z,1566841276.726 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,32768,-32768,V
2019-08-26T17:41:32.456Z,1566841292.456 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-08-26T17:41:32.456Z,1566841292.456 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,19082610522062,35.0, -0.11448.9,  0
2019-08-26T17:41:47.045Z,1566841307.045 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-08-26T17:41:49.468Z,1566841309.468 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.196003
2019-08-26T17:41:53.887Z,1566841313.887 [RDI_Pathfinder](ERROR): Failed to parse:
    +0.00,        +0.00,        +0.00, 49.00
2019-08-26T17:41:58.879Z,1566841318.879 [Radio_Surface](INFO): Powering down
2019-08-26T17:42:12.474Z,1566841332.474 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-08-26T17:42:12.474Z,1566841332.474 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,19082610530062,35.0, 0.0,1448.9,  0
2019-08-26T17:42:22.192Z,1566841342.192 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-08-26T17:42:22.596Z,1566841342.596 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2019-08-26T17:42:33.081Z,1566841353.081 [RDI_Pathfinder](ERROR): Failed to parse:
6,-32768,V
2019-08-26T17:42:38.746Z,1566841358.746 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2019-08-26T17:42:38.746Z,1566841358.746 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2019-08-26T17:43:17.104Z,1566841397.104 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2019-08-26T17:43:17.105Z,1566841397.105 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,

2019-08-26T17:43:18.748Z,1566841398.748 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2019-08-26T17:43:32.451Z,1566841412.451 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-08-26T17:43:32.451Z,1566841412.451 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,19082610542062,35.0, -0.,1448.9,  0
2019-08-26T17:43:37.734Z,1566841417.734 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2019-08-26T17:43:37.734Z,1566841417.734 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-3768,V
2019-08-26T17:43:47.804Z,1566841427.804 [RDI_Pathfinder](ERROR): Failed to parse:
:SA,   +0.00,   +0.00,  0.00
2019-08-26T17:43:58.749Z,1566841438.749 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2019-08-26T17:43:58.749Z,1566841438.749 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2019-08-26T17:44:12.450Z,1566841452.450 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-08-26T17:44:12.450Z,1566841452.450 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,19082610550062,35.0, -.0,10
2019-08-26T17:44:33.087Z,1566841473.087 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2019-08-26T17:44:36.742Z,1566841476.742 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2019-08-26T17:44:36.742Z,1566841476.742 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +0.00,     ,        +0.00,   0.00,213.00
2019-08-26T17:44:52.452Z,1566841492.452 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-08-26T17:44:52.453Z,1566841492.453 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,190826135.0, -0.1,   0.0,1448.9,  0
2019-08-26T17:44:56.094Z,1566841496.094 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2019-08-26T17:44:56.095Z,1566841496.095 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2019-08-26T17:44:57.739Z,1566841497.739 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,32768,-32768,V
2019-08-26T17:45:13.213Z,1566841513.213 [CTD_NeilBrown](ERROR): Failed to match checksum. Expected: 11 got: 0
2019-08-26T17:45:13.213Z,1566841513.213 [CTD_NeilBrown](ERROR): Bad response:  16.883,42.97820,33.8891
2019-08-26T17:45:59.121Z,1566841559.121 [RDI_Pathfinder](ERROR): Failed to parse:.00,   0.00,   0.00,   0.00,   0.00
2019-08-26T17:46:18.098Z,1566841578.098 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2019-08-26T17:46:18.098Z,1566841578.098 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-32768,-3:BE,-32768,-32768,-32768,V
2019-08-26T17:46:33.454Z,1566841593.454 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-08-26T17:46:33.455Z,1566841593.455 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,190826105721 -0.1,   0.0,1448.9,  0
2019-08-26T17:46:36.738Z,1566841596.738 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2019-08-26T17:46:36.738Z,1566841596.738 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +0    +0.00,        +0.00,   0.00,333.00
2019-08-26T17:46:58.101Z,1566841618.101 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,
2019-08-26T17:47:17.909Z,1566841637.909 [RDI_Pathfinder](ERROR): Failed to parse:
:B  +0.00,        +0.00,        +0.00,   0.00,372.99
2019-08-26T17:47:32.446Z,1566841652.446 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-08-26T17:47:32.447Z,1566841652.447 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,19082610582062,35.0, -0.1,   0.0,14
2019-08-26T17:47:53.078Z,1566841673.078 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2019-08-26T17:47:53.078Z,1566841673.078 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-32768,-

2019-08-26T17:48:17.742Z,1566841697.742 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2019-08-26T17:48:17.742Z,1566841697.742 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,  .00,        +0.00 +0.00,   0.00,433.99
2019-08-26T17:48:58.091Z,1566841738.091 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2019-08-26T17:48:58.091Z,1566841738.091 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-3278,-32768,V
2019-08-26T17:49:13.080Z,1566841753.080 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-08-26T17:49:13.080Z,1566841753.080 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,19082611000162,31,   0.0,1448.9,  0
2019-08-26T17:49:15.078Z,1566841755.078 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2019-08-26T17:49:16.730Z,1566841756.730 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2019-08-26T17:49:16.730Z,1566841756.730 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-32768,
2019-08-26T17:49:20.804Z,1566841760.804 [CTD_NeilBrown](ERROR): Failed to match checksum. Expected: 255 got: 10
2019-08-26T17:49:20.805Z,1566841760.805 [CTD_NeilBrown](ERROR): Bad response:  28.80,13.42184,40.1

2019-08-26T17:49:59.102Z,1566841799.102 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-08-26T17:49:59.102Z,1566841799.102 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,190826115.0, -0.1,448.9,  0
2019-08-26T17:50:33.444Z,1566841833.444 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-08-26T17:50:33.445Z,1566841833.445 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,19082611012162,35.0, -0.1,   0.0,10
2019-08-26T17:50:39.905Z,1566841839.905 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2019-08-26T17:50:39.905Z,1566841839.905 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.009
2019-08-26T17:50:52.441Z,1566841852.441 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-08-26T17:50:52.441Z,1566841852.441 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,19082611014062,35.0, -0.1, 48.9,  0
2019-08-26T17:50:58.101Z,1566841858.101 [RDI_Pathfinder](ERROR): Failed to parse:
:B,-32768,-32768,32768,-32768,-32768,V
2019-08-26T17:51:37.720Z,1566841897.720 [RDI_Pathfinder](ERROR): Failed to parse:2768,V
2019-08-26T17:52:39.094Z,1566841959.094 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2019-08-26T17:52:57.734Z,1566841977.734 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2019-08-26T17:52:57.734Z,1566841977.734 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-32768,
2019-08-26T17:53:10.796Z,1566841990.796 [CTD_NeilBrown](ERROR): Failed to match checksum. Expected: 7 got: 10
2019-08-26T17:53:10.796Z,1566841990.796 [CTD_NeilBrown](ERROR): Bad response: ,33.8606
2019-08-26T17:53:13.441Z,1566841993.441 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2019-08-26T17:53:13.442Z,1566841993.442 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2019-08-26T17:53:57.103Z,1566842037.103 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-08-26T17:53:57.107Z,1566842037.107 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,19082611044562,35.0, -0.1,   .9,,  0.00,   0.00,   0.00,   0.00,   0.00
2019-08-26T17:54:33.080Z,1566842073.080 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2019-08-26T17:54:33.081Z,1566842073.081 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-32768,-

2019-08-26T17:54:34.241Z,1566842074.241 [Micromodem](INFO): Nmea in: 0

2019-08-26T17:54:34.241Z,1566842074.241 [Micromodem](ERROR): Response from modem failed NMEA checksum: 0
 expected 0
2019-08-26T17:54:37.074Z,1566842077.074 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2019-08-26T17:55:57.481Z,1566842157.481 [sci2:NeedComms] Running Loop=1
2019-08-26T17:55:57.481Z,1566842157.481 [sci2:NeedComms](DEBUG): Aggregate::initialize sci2:NeedComms
2019-08-26T17:55:57.481Z,1566842157.481 [sci2:NeedComms:B.GoToSurface] Running Loop=1
2019-08-26T17:55:57.481Z,1566842157.481 [sci2:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-08-26T17:55:57.482Z,1566842157.482 [sci2:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2019-08-26T17:55:57.482Z,1566842157.482 [sci2:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2019-08-26T17:55:57.483Z,1566842157.483 [sci2:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2019-08-26T17:55:57.484Z,1566842157.484 [sci2:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-08-26T17:55:57.484Z,1566842157.484 [sci2:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-08-26T17:55:57.485Z,1566842157.485 [sci2:NeedComms:A] Running Loop=1
2019-08-26T17:55:57.487Z,1566842157.487 [sci2:NeedComms:A](INFO): last time_fix was: 1566841233.000000 second since 1970/01/01T00:00:00Z 
2019-08-26T17:55:57.487Z,1566842157.487 [sci2:NeedComms:A] Stopped
2019-08-26T17:57:19.867Z,1566842239.867 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout
2019-08-26T17:57:19.867Z,1566842239.867 [RDI_Pathfinder] Communications Fault, FailCount= 1
2019-08-26T17:57:19.867Z,1566842239.867 [RDI_Pathfinder](ERROR): Communications Fault
2019-08-26T17:57:19.868Z,1566842239.868 [RDI_Pathfinder](ERROR): Failed to parse:
2019-08-26T17:57:19.939Z,1566842239.939 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2019-08-26T17:57:20.227Z,1566842240.227 [RDI_Pathfinder](INFO): Powering down
2019-08-26T17:57:21.059Z,1566842241.059 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2019-08-26T17:57:21.059Z,1566842241.059 [RDI_Pathfinder] No Fault, FailCount= 1
2019-08-26T17:57:33.121Z,1566842253.121 [sci2:NeedComms:C] Running Loop=1
2019-08-26T17:57:33.539Z,1566842253.539 [Radio_Surface](INFO): Powering up
2019-08-26T17:57:34.209Z,1566842254.209 [CTD_NeilBrown](ERROR): Salinity reading out of range: 5.761100 psu
2019-08-26T17:57:34.214Z,1566842254.214 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-08-26T17:57:34.313Z,1566842254.313 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2019-08-26T17:57:34.703Z,1566842254.703 [NAL9602](DEBUG): Fix Requested
2019-08-26T17:57:38.642Z,1566842258.642 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2019-08-26T17:57:38.642Z,1566842258.642 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2019-08-26T17:57:39.065Z,1566842259.065 [DataOverHttps](INFO): Radio surface powered ON.
2019-08-26T17:58:09.372Z,1566842289.372 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-08-26T17:58:18.265Z,1566842298.265 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2019-08-26T17:58:18.265Z,1566842298.265 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2019-08-26T17:58:44.520Z,1566842324.520 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-08-26T17:58:58.633Z,1566842338.633 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-08-26T17:58:58.634Z,1566842338.634 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,19082611094717,35.0, -0.1,   09,  0
2019-08-26T17:59:07.106Z,1566842347.106 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=8704, MT Status=1, MTMSN=359
2019-08-26T17:59:07.106Z,1566842347.106 [NAL9602](INFO): Data available in MT queue
2019-08-26T17:59:07.600Z,1566842347.600 [NAL9602](INFO): Received command:stop
2019-08-26T17:59:07.609Z,1566842347.609 [CommandLine](IMPORTANT): got command stop
2019-08-26T17:59:07.609Z,1566842347.609 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop
2019-08-26T17:59:07.663Z,1566842347.663 [MissionManager](INFO): MissionManager is completed.
2019-08-26T17:59:07.664Z,1566842347.664 [MissionManager](INFO): Uninitializing Mission sci2
2019-08-26T17:59:07.664Z,1566842347.664 [sci2] Stopped
2019-08-26T17:59:07.664Z,1566842347.664 [sci2](DEBUG): Aggregate::uninitialize sci2
2019-08-26T17:59:07.664Z,1566842347.664 [sci2:Science] Stopped
2019-08-26T17:59:07.664Z,1566842347.664 [sci2:Science](DEBUG): Aggregate::uninitialize sci2:Science
2019-08-26T17:59:07.664Z,1566842347.664 [sci2:Science:A] Stopped
2019-08-26T17:59:07.664Z,1566842347.664 [sci2:Science:C] Stopped
2019-08-26T17:59:07.664Z,1566842347.664 [sci2:Science:G] Stopped
2019-08-26T17:59:07.664Z,1566842347.664 [sci2:Science:Read_Oil] Stopped
2019-08-26T17:59:07.664Z,1566842347.664 [sci2:Science:PeakDetectChl] Stopped
2019-08-26T17:59:07.664Z,1566842347.664 [sci2:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize sci2:Science:PeakDetectChl
2019-08-26T17:59:07.665Z,1566842347.665 [sci2:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2019-08-26T17:59:07.665Z,1566842347.665 [sci2:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2019-08-26T17:59:07.665Z,1566842347.665 [sci2:NeedComms] Stopped
2019-08-26T17:59:07.665Z,1566842347.665 [sci2:NeedComms](DEBUG): Aggregate::uninitialize sci2:NeedComms
2019-08-26T17:59:07.665Z,1566842347.665 [sci2:NeedComms:B.GoToSurface] Stopped
2019-08-26T17:59:07.665Z,1566842347.665 [sci2:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-08-26T17:59:07.665Z,1566842347.665 [sci2:NeedComms:C] Stopped
2019-08-26T17:59:07.665Z,1566842347.665 [sci2:StandardEnvelopes] Stopped
2019-08-26T17:59:07.665Z,1566842347.665 [sci2:StandardEnvelopes](DEBUG): Aggregate::uninitialize sci2:StandardEnvelopes
2019-08-26T17:59:07.665Z,1566842347.665 [sci2:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2019-08-26T17:59:07.665Z,1566842347.665 [sci2:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2019-08-26T17:59:07.665Z,1566842347.665 [sci2:StandardEnvelopes:B.DepthEnvelope] Stopped
2019-08-26T17:59:07.665Z,1566842347.665 [sci2:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2019-08-26T17:59:07.666Z,1566842347.666 [sci2:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2019-08-26T17:59:07.666Z,1566842347.666 [sci2:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2019-08-26T17:59:07.666Z,1566842347.666 [sci2:I.Pitch] Stopped
2019-08-26T17:59:07.666Z,1566842347.666 [sci2:J.Buoyancy] Stopped
2019-08-26T17:59:07.666Z,1566842347.666 [sci2:J.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2019-08-26T17:59:07.666Z,1566842347.666 [sci2:K.SetSpeed] Stopped
2019-08-26T17:59:07.666Z,1566842347.666 [sci2:K.SetSpeed](DEBUG): Uninitialize.
2019-08-26T17:59:07.666Z,1566842347.666 [sci2:L.DepthEnvelope] Stopped
2019-08-26T17:59:07.666Z,1566842347.666 [sci2:L.DepthEnvelope](DEBUG): Uninitialize.
2019-08-26T17:59:07.666Z,1566842347.666 [sci2:M.AltitudeEnvelope] Stopped
2019-08-26T17:59:07.666Z,1566842347.666 [sci2:M.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2019-08-26T17:59:07.666Z,1566842347.666 [sci2:N.YoYo] Stopped
2019-08-26T17:59:07.666Z,1566842347.666 [sci2:N.YoYo](DEBUG): Uninitialize YoYoComponent.
2019-08-26T17:59:07.666Z,1566842347.666 [sci2:Lap] Stopped
2019-08-26T17:59:07.667Z,1566842347.667 [sci2:Lap](DEBUG): Aggregate::uninitialize sci2:Lap
2019-08-26T17:59:07.667Z,1566842347.667 [sci2:Lap:Wpt1] Stopped
2019-08-26T17:59:07.667Z,1566842347.667 [sci2:Lap:Wpt1](DEBUG): Aggregate::uninitialize sci2:Lap:Wpt1
2019-08-26T17:59:07.671Z,1566842347.671 [sci2:Lap:Wpt1:A.Waypoint] Stopped
2019-08-26T17:59:07.671Z,1566842347.671 [sci2:Lap:Wpt1:A.Waypoint](DEBUG): Uninitialize WaypointComponent.
2019-08-26T17:59:07.948Z,1566842347.948 [MissionManager](IMPORTANT): Started mission Default
2019-08-26T17:59:07.948Z,1566842347.948 [Default] Running Loop=1
2019-08-26T17:59:07.948Z,1566842347.948 [Default](DEBUG): Aggregate::initialize Default
2019-08-26T17:59:07.948Z,1566842347.948 [Default:B.GoToSurface] Running Loop=1
2019-08-26T17:59:07.948Z,1566842347.948 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-08-26T17:59:07.948Z,1566842347.948 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-08-26T17:59:07.949Z,1566842347.949 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-08-26T17:59:07.949Z,1566842347.949 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-08-26T17:59:07.949Z,1566842347.949 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-08-26T17:59:07.950Z,1566842347.950 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-08-26T17:59:07.950Z,1566842347.950 [Default:A.Wait] Running Loop=1
2019-08-26T17:59:07.950Z,1566842347.950 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-08-26T17:59:07.999Z,1566842347.999 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-08-26T17:59:08.455Z,1566842348.455 [Aanderaa_O2](INFO): Powering down
2019-08-26T17:59:19.667Z,1566842359.667 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-08-26T17:59:21.269Z,1566842361.269 [Default:A.Wait](INFO): Done Waiting.
2019-08-26T17:59:21.269Z,1566842361.269 [Default:A.Wait] Stopped
2019-08-26T17:59:21.269Z,1566842361.269 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-08-26T17:59:21.688Z,1566842361.688 [Default:CheckIn] Running Loop=1
2019-08-26T17:59:21.688Z,1566842361.688 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-08-26T17:59:21.688Z,1566842361.688 [Default:CheckIn:Read_GPS] Running Loop=1
2019-08-26T17:59:23.275Z,1566842363.275 [NAL9602](DEBUG): Fix Requested
2019-08-26T17:59:23.664Z,1566842363.664 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175923.00,A,3422.84364,N,11949.29271,W,0.253,257.70,260819,,,A*71
2019-08-26T17:59:23.667Z,1566842363.667 [NAL9602](INFO): GPS fix at 20190826T175923: (34.380727, -119.821545)
2019-08-26T17:59:23.703Z,1566842363.703 [UniversalFixResidualReporter](INFO): Fix residual: 4.7 %DT, over the last 964.0 m. Residual distance 44.8 m at bearing 65.2 degrees. Fix at (34.3807, -119.8215) with 983.4 m made good.
2019-08-26T17:59:23.704Z,1566842363.704 [Default:CheckIn:Read_GPS] Stopped
2019-08-26T17:59:23.704Z,1566842363.704 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-08-26T17:59:54.839Z,1566842394.839 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-08-26T17:59:55.985Z,1566842395.985 [NAL9602](INFO): Not Powering down - fast GPS
2019-08-26T18:00:29.987Z,1566842429.987 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-08-26T18:01:01.555Z,1566842461.555 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003031
2019-08-26T18:01:08.603Z,1566842468.603 [DataOverHttps](INFO): Sending 253 bytes from file Logs/20190826T173757/Courier0012.lzma
2019-08-26T18:01:09.409Z,1566842469.409 [DataOverHttps](INFO): Moved sent file to Logs/20190826T173757/Courier0012.lzma.bak
2019-08-26T18:01:09.409Z,1566842469.409 [DataOverHttps](INFO): SBD MOMSN=11696157
2019-08-26T18:01:22.599Z,1566842482.599 [DataOverHttps](INFO): Sending 816 bytes from file Logs/20190826T173757/Express0013.lzma
2019-08-26T18:01:23.397Z,1566842483.397 [DataOverHttps](INFO): Moved sent file to Logs/20190826T173757/Express0013.lzma.bak
2019-08-26T18:01:23.397Z,1566842483.397 [DataOverHttps](INFO): SBD MOMSN=11696163
2019-08-26T18:01:25.328Z,1566842485.328 [Default:CheckIn:Read_Iridium] Stopped
2019-08-26T18:01:25.329Z,1566842485.329 [Default:CheckIn:C.Wait] Running Loop=1
2019-08-26T18:01:25.329Z,1566842485.329 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-08-26T18:05:20.032Z,1566842720.032 [CommandLine](IMPORTANT): got command get depth
2019-08-26T18:05:20.032Z,1566842720.032 [CommandLine](IMPORTANT): depth 0.225837 m
2019-08-26T18:05:44.736Z,1566842744.736 [DataOverHttps](IMPORTANT): SBD MTMSN=20190826T180544
2019-08-26T18:05:50.095Z,1566842750.095 [DataOverHttps](INFO): Received command:configSet Micromodem.surfaceThreshold 0.1 m persist;restart app
2019-08-26T18:05:50.253Z,1566842750.253 [CommandLine](IMPORTANT): got command configSet Micromodem.surfaceThreshold 0.100000 meter persist
2019-08-26T18:05:50.256Z,1566842750.256 [CommandLine](IMPORTANT): got command restart application
2019-08-26T18:05:51.259Z,1566842751.259 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-08-26T18:05:51.259Z,1566842751.259 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-08-26T18:05:51.275Z,1566842751.275 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-08-26T18:05:51.276Z,1566842751.276 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-08-26T18:05:51.279Z,1566842751.279 [CommandLine](INFO): Join timeout helper Thread ID is 7050
2019-08-26T18:05:51.280Z,1566842751.280 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-08-26T18:05:51.280Z,1566842751.280 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-08-26T18:05:51.281Z,1566842751.281 [NavChartDb](INFO): Join timeout helper Thread ID is 7051
2019-08-26T18:05:51.335Z,1566842751.335 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-08-26T18:05:51.336Z,1566842751.336 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-08-26T18:05:51.339Z,1566842751.339 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2019-08-26T18:05:51.339Z,1566842751.339 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-08-26T18:05:51.340Z,1566842751.340 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 7052
2019-08-26T18:05:51.671Z,1566842751.671 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2019-08-26T18:05:51.671Z,1566842751.671 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-08-26T18:05:51.672Z,1566842751.672 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-08-26T18:05:51.679Z,1566842751.679 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-08-26T18:05:51.680Z,1566842751.680 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-08-26T18:05:51.680Z,1566842751.680 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 7053
2019-08-26T18:05:51.723Z,1566842751.723 [CTD_NeilBrown](INFO): Powering down
2019-08-26T18:05:51.739Z,1566842751.739 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-08-26T18:05:51.739Z,1566842751.739 [CTD_NeilBrown](INFO): Powering down
2019-08-26T18:05:51.751Z,1566842751.751 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-08-26T18:05:51.759Z,1566842751.759 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-08-26T18:05:51.759Z,1566842751.759 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-08-26T18:05:51.760Z,1566842751.760 [Radio_Surface](INFO): Join timeout helper Thread ID is 7054
2019-08-26T18:05:52.123Z,1566842752.123 [Radio_Surface](INFO): Powering down
2019-08-26T18:05:52.124Z,1566842752.124 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-08-26T18:05:52.124Z,1566842752.124 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-08-26T18:05:52.140Z,1566842752.140 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-08-26T18:05:52.140Z,1566842752.140 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-08-26T18:05:52.140Z,1566842752.140 [DataOverHttps](INFO): Join timeout helper Thread ID is 7055
2019-08-26T18:05:52.515Z,1566842752.515 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-08-26T18:05:52.515Z,1566842752.515 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-08-26T18:05:52.532Z,1566842752.532 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-08-26T18:05:52.532Z,1566842752.532 [logger ThreadHandler](INFO): Thread cancelled.
2019-08-26T18:05:52.532Z,1566842752.532 [logger](INFO): Join timeout helper Thread ID is 7056
2019-08-26T18:05:52.571Z,1566842752.571 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-08-26T18:05:52.571Z,1566842752.571 [logger ThreadHandler](INFO): Thread cancelled.
2019-08-26T18:05:52.593Z,1566842752.593 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-08-26T18:05:52.593Z,1566842752.593 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-08-26T18:05:52.594Z,1566842752.594 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-08-26T18:05:52.594Z,1566842752.594 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-08-26T18:05:52.595Z,1566842752.595 [controlThread](INFO): Join timeout helper Thread ID is 7057
2019-08-26T18:05:52.715Z,1566842752.715 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-08-26T18:05:52.715Z,1566842752.715 [controlThread](DEBUG): Uninitializing ControlThread
2019-08-26T18:05:52.716Z,1566842752.716 [AHRS_M2](INFO): Powering down
2019-08-26T18:05:52.788Z,1566842752.788 [Micromodem](INFO): Powering down
2019-08-26T18:05:52.883Z,1566842752.883 [NAL9602](INFO): Powering down
2019-08-26T18:05:52.955Z,1566842752.955 [RDI_Pathfinder](INFO): Powering down
2019-08-26T18:05:52.956Z,1566842752.956 [Aanderaa_O2](INFO): Powering down
2019-08-26T18:05:52.958Z,1566842752.958 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-08-26T18:05:52.958Z,1566842752.958 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-08-26T18:05:52.959Z,1566842752.959 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-08-26T18:05:52.960Z,1566842752.960 [MissionManager](INFO): Uninitializing Mission Default
2019-08-26T18:05:52.960Z,1566842752.960 [Default] Stopped
2019-08-26T18:05:52.960Z,1566842752.960 [Default](DEBUG): Aggregate::uninitialize Default
2019-08-26T18:05:52.960Z,1566842752.960 [Default:B.GoToSurface] Stopped
2019-08-26T18:05:52.960Z,1566842752.960 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-08-26T18:05:52.960Z,1566842752.960 [Default:CheckIn] Stopped
2019-08-26T18:05:52.960Z,1566842752.960 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-08-26T18:05:52.960Z,1566842752.960 [Default:CheckIn:C.Wait] Stopped
2019-08-26T18:05:52.960Z,1566842752.960 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-08-26T18:05:52.963Z,1566842752.963 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-08-26T18:05:52.963Z,1566842752.963 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-08-26T18:05:52.963Z,1566842752.963 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-08-26T18:05:52.964Z,1566842752.964 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-08-26T18:05:52.964Z,1566842752.964 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-08-26T18:05:52.964Z,1566842752.964 [BuoyancyServo](INFO): Powering down
2019-08-26T18:05:52.975Z,1566842752.975 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-08-26T18:05:52.975Z,1566842752.975 [ElevatorServo](INFO): Powering down
2019-08-26T18:05:52.976Z,1566842752.976 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-08-26T18:05:52.976Z,1566842752.976 [MassServo](INFO): Powering down
2019-08-26T18:05:52.977Z,1566842752.977 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-08-26T18:05:52.977Z,1566842752.977 [RudderServo](INFO): Powering down
2019-08-26T18:05:52.978Z,1566842752.978 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-08-26T18:05:52.978Z,1566842752.978 [ThrusterServo](INFO): Powering down
2019-08-26T18:05:52.979Z,1566842752.979 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-08-26T18:05:52.979Z,1566842752.979 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-08-26T18:05:52.980Z,1566842752.980 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-08-26T18:05:52.980Z,1566842752.980 [CBIT](DEBUG): Powering off loads.
2019-08-26T18:05:52.991Z,1566842752.991 [CBIT](DEBUG): Disabling WDT.
2019-08-26T18:05:53.003Z,1566842753.003 [CBIT](DEBUG): Opening all GF detection circuits.
2019-08-26T18:05:53.004Z,1566842753.004 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-08-26T18:05:53.055Z,1566842753.055 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-08-26T18:05:53.063Z,1566842753.063 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-08-26T18:05:53.104Z,1566842753.104 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-08-26T18:05:53.107Z,1566842753.107 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-08-26T18:05:53.180Z,1566842753.180 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-08-26T18:05:53.260Z,1566842753.260 [logger ThreadHandler](INFO): Thread cancelled.