2021-02-17T20:41:39.353Z,1613594499.353 [CommandLine](IMPORTANT): got command restart logs
2021-02-17T20:42:16.757Z,1613594536.757 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-02-17T20:42:16.758Z,1613594536.758 [Default:CheckIn:C.Wait] Stopped
2021-02-17T20:42:16.758Z,1613594536.758 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-02-17T20:42:16.758Z,1613594536.758 [Default:CheckIn:D] Running Loop=1
2021-02-17T20:42:17.182Z,1613594537.182 [Default:CheckIn:D] Stopped
2021-02-17T20:42:17.182Z,1613594537.182 [Default:CheckIn:E] Running Loop=1
2021-02-17T20:42:17.568Z,1613594537.568 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 24.173832 min 
2021-02-17T20:42:17.568Z,1613594537.568 [Default:CheckIn:E] Stopped
2021-02-17T20:42:17.568Z,1613594537.568 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-02-17T20:42:17.569Z,1613594537.569 [Default:CheckIn] Stopped
2021-02-17T20:42:17.569Z,1613594537.569 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-02-17T20:42:17.569Z,1613594537.569 [Default:CheckIn](INFO): Running loop #5
2021-02-17T20:42:17.569Z,1613594537.569 [Default:CheckIn] Running Loop=5
2021-02-17T20:42:17.569Z,1613594537.569 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-02-17T20:42:17.569Z,1613594537.569 [Default:CheckIn:Read_GPS] Running Loop=1
2021-02-17T20:42:19.580Z,1613594539.580 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204218.00,A,3648.50647,N,12149.39034,W,0.428,52.84,170221,,,D*4D
2021-02-17T20:42:19.591Z,1613594539.591 [NAL9602](INFO): GPS fix at 20210217T204218: (36.808441, -121.823172)
2021-02-17T20:42:19.601Z,1613594539.601 [Default:CheckIn:Read_GPS] Stopped
2021-02-17T20:42:19.601Z,1613594539.601 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-02-17T20:42:31.868Z,1613594551.868 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20210217T183413/Courier0051.lzma
2021-02-17T20:42:32.871Z,1613594552.871 [DataOverHttps](INFO): Moved sent file to Logs/20210217T183413/Courier0051.lzma.bak
2021-02-17T20:42:32.871Z,1613594552.871 [DataOverHttps](INFO): SBD MOMSN=15232639
2021-02-17T20:42:53.661Z,1613594573.661 [DataOverHttps](INFO): Sending 266 bytes from file Logs/20210217T204139/Courier0000.lzma
2021-02-17T20:42:56.671Z,1613594576.671 [DataOverHttps](INFO): Moved sent file to Logs/20210217T204139/Courier0000.lzma.bak
2021-02-17T20:42:56.671Z,1613594576.671 [DataOverHttps](INFO): SBD MOMSN=15232641
2021-02-17T20:43:21.618Z,1613594601.618 [DataOverHttps](INFO): Sending 475 bytes from file Logs/20210217T183413/Express0052.lzma
2021-02-17T20:43:24.639Z,1613594604.639 [DataOverHttps](INFO): Moved sent file to Logs/20210217T183413/Express0052.lzma.bak
2021-02-17T20:43:24.639Z,1613594604.639 [DataOverHttps](INFO): SBD MOMSN=15232645
2021-02-17T20:43:45.762Z,1613594625.762 [CommandLine](IMPORTANT): got command show stack
2021-02-17T20:43:45.762Z,1613594625.762 [CommandLine](IMPORTANT): Behavior Stack:

2021-02-17T20:43:45.762Z,1613594625.762 [Default](IMPORTANT): Priority 0: Default:B.GoToSurface
2021-02-17T20:43:45.763Z,1613594625.763 [Default:CheckIn](IMPORTANT): Priority 1: Default:CheckIn:Read_Iridium
2021-02-17T20:43:46.321Z,1613594626.321 [DataOverHttps](INFO): Sending 381 bytes from file Logs/20210217T204139/Express0001.lzma
2021-02-17T20:43:52.679Z,1613594632.679 [DataOverHttps](INFO): Moved sent file to Logs/20210217T204139/Express0001.lzma.bak
2021-02-17T20:43:52.679Z,1613594632.679 [DataOverHttps](INFO): SBD MOMSN=15232658
2021-02-17T20:44:13.856Z,1613594653.856 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20210217T204139/Express0004.lzma
2021-02-17T20:44:14.859Z,1613594654.859 [DataOverHttps](INFO): Moved sent file to Logs/20210217T204139/Express0004.lzma.bak
2021-02-17T20:44:14.859Z,1613594654.859 [DataOverHttps](INFO): SBD MOMSN=15232665
2021-02-17T20:44:17.210Z,1613594657.210 [Default:CheckIn:Read_Iridium] Stopped
2021-02-17T20:44:17.210Z,1613594657.210 [Default:CheckIn:C.Wait] Running Loop=1
2021-02-17T20:44:17.210Z,1613594657.210 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-02-17T20:44:24.380Z,1613594664.380 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=12475, MT Status=1, MTMSN=736
2021-02-17T20:44:24.380Z,1613594664.380 [NAL9602](INFO): Data available in MT queue
2021-02-17T20:44:24.866Z,1613594664.866 [NAL9602](INFO): Received command:stop
2021-02-17T20:44:24.961Z,1613594664.961 [CommandLine](IMPORTANT): got command stop
2021-02-17T20:44:55.406Z,1613594695.406 [NAL9602](INFO): Not Powering down - fast GPS
2021-02-17T20:48:30.920Z,1613594910.920 [CommandLine](IMPORTANT): got command ibit
2021-02-17T20:48:31.059Z,1613594911.059 [IBIT](IMPORTANT): Beginning Initiated BIT
2021-02-17T20:48:31.059Z,1613594911.059 [IBIT](IMPORTANT): Beginning control surface checks.
2021-02-17T20:48:31.070Z,1613594911.070 [CBIT](IMPORTANT): Beginning ground fault scan
2021-02-17T20:48:32.544Z,1613594912.544 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204831.00,A,3648.52951,N,12149.44390,W,1.808,14.57,170221,,,D*42
2021-02-17T20:48:32.547Z,1613594912.547 [NAL9602](INFO): GPS fix at 20210217T204831: (36.808825, -121.824065)
2021-02-17T20:48:41.920Z,1613594921.920 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.082442
CHAN A1 (24V): 0.162267
CHAN A2 (12V): 0.072710
CHAN A3 (5V): 0.030763
CHAN B0 (3.3V): 0.023726
CHAN B1 (3.15aV): 0.023058
CHAN B2 (3.15bV): 0.022201
CHAN B3 (GND): 0.004314
OPEN: 0.008092
Full Scale Calc: 4.765 mA, -1.589 mA
2021-02-17T20:48:52.845Z,1613594932.845 [RudderServo](FAULT): Overload Error
2021-02-17T20:48:52.845Z,1613594932.845 [RudderServo] Hardware Fault, FailCount= 1
2021-02-17T20:48:52.845Z,1613594932.845 [RudderServo](ERROR): Hardware Fault
2021-02-17T20:48:52.897Z,1613594932.897 [CBIT](ERROR): Hardware Fault in component: RudderServo
2021-02-17T20:48:53.246Z,1613594933.246 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2021-02-17T20:48:53.246Z,1613594933.246 [RudderServo](INFO): Powering down
2021-02-17T20:48:54.130Z,1613594934.130 [RudderServo](DEBUG): Initializing EZServoServo.
2021-02-17T20:48:54.250Z,1613594934.250 [RudderServo](DEBUG): Initializing RudderServo.
2021-02-17T20:48:54.255Z,1613594934.255 [CBIT](INFO): Clearing failed state for component RudderServo
2021-02-17T20:48:54.255Z,1613594934.255 [RudderServo] No Fault, FailCount= 1
2021-02-17T20:48:54.769Z,1613594934.769 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=12476, MT Status=1, MTMSN=737
2021-02-17T20:48:54.770Z,1613594934.770 [NAL9602](INFO): Data available in MT queue
2021-02-17T20:48:55.097Z,1613594935.097 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2021-02-17T20:48:55.097Z,1613594935.097 [RudderServo](FAULT): Rudder failed to initialize
2021-02-17T20:48:55.097Z,1613594935.097 [RudderServo] Communications Fault, FailCount= 2
2021-02-17T20:48:55.097Z,1613594935.097 [RudderServo](ERROR): Communications Fault
2021-02-17T20:48:55.102Z,1613594935.102 [CBIT](ERROR): Communications Fault in component: RudderServo
2021-02-17T20:48:55.458Z,1613594935.458 [NAL9602](INFO): Received command:load Science/profile_station.xml;set profile_station.MissionTimeout 7 hour;set profile_station.NeedCommsTime 45 minute;set profile_station.YoYoMaxDepth 20 meter;set profile_station.MaxDepth 25 meter;run
2021-02-17T20:48:55.502Z,1613594935.502 [CommandLine](IMPORTANT): got command load ./Missions/Science/profile_station.xml
2021-02-17T20:48:55.502Z,1613594935.502 [MissionManager](INFO): Loading Mission: ./Missions/Science/profile_station.xml
2021-02-17T20:48:55.599Z,1613594935.599 [MissionManager](INFO): DefineArg profile_station.MissionTimeout = 4.000000 h
2021-02-17T20:48:55.601Z,1613594935.601 [MissionManager](INFO): DefineArg profile_station.NeedCommsTime = 60.000000 min
2021-02-17T20:48:55.603Z,1613594935.603 [MissionManager](INFO): DefineArg profile_station.Lat = 36.806966 arcdeg
2021-02-17T20:48:55.606Z,1613594935.606 [MissionManager](INFO): DefineArg profile_station.Lon = -121.824326 arcdeg
2021-02-17T20:48:55.608Z,1613594935.608 [MissionManager](INFO): DefineArg profile_station.Radius = 300.000000 m
2021-02-17T20:48:55.610Z,1613594935.610 [MissionManager](INFO): DefineArg profile_station.YoYoMinDepth = 2.000000 m
2021-02-17T20:48:55.612Z,1613594935.612 [MissionManager](INFO): DefineArg profile_station.YoYoMaxDepth = 200.000000 m
2021-02-17T20:48:55.614Z,1613594935.614 [MissionManager](INFO): DefineArg profile_station.YoYoMinAltitude = 7.000000 m
2021-02-17T20:48:55.616Z,1613594935.616 [MissionManager](INFO): DefineArg profile_station.YoYoPitch = 20.000000 arcdeg
2021-02-17T20:48:55.618Z,1613594935.618 [MissionManager](INFO): DefineArg profile_station.Speed = 1.000000 m/s
2021-02-17T20:48:55.620Z,1613594935.620 [MissionManager](INFO): DefineArg profile_station.CircleMaxError = 100.000000 m
2021-02-17T20:48:55.622Z,1613594935.622 [MissionManager](INFO): DefineArg profile_station.CircleTurnToPort = 0 bool
2021-02-17T20:48:55.624Z,1613594935.624 [MissionManager](INFO): DefineArg profile_station.KwpHeading = 0.010000 rad/m
2021-02-17T20:48:55.626Z,1613594935.626 [MissionManager](INFO): DefineArg profile_station.MinAltitude = 5.000000 m
2021-02-17T20:48:55.628Z,1613594935.628 [MissionManager](INFO): DefineArg profile_station.MaxDepth = 205.000000 m
2021-02-17T20:48:55.630Z,1613594935.630 [MissionManager](INFO): DefineArg profile_station.MinOffshore = 2.000000 km
2021-02-17T20:48:55.631Z,1613594935.631 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml
2021-02-17T20:48:56.036Z,1613594936.036 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectChlActive = 0 bool
2021-02-17T20:48:56.039Z,1613594936.039 [MissionManager](INFO): DefineArg profile_station:Science.TimeWindowPeakReport = nan min
2021-02-17T20:48:56.040Z,1613594936.040 [MissionManager](INFO): DefineArg profile_station:Science.HighestChlPeakReportActive = 0 bool
2021-02-17T20:48:56.042Z,1613594936.042 [MissionManager](INFO): DefineArg profile_station:Science.PatchTracking = 0 bool
2021-02-17T20:48:56.044Z,1613594936.044 [MissionManager](INFO): DefineArg profile_station:Science.FilterWidthHorizontal = 3.000000 count
2021-02-17T20:48:56.046Z,1613594936.046 [MissionManager](INFO): DefineArg profile_station:Science.NumProfilesSlidingwindow = 100.000000 count
2021-02-17T20:48:56.048Z,1613594936.048 [MissionManager](INFO): DefineArg profile_station:Science.OffPeakFractionHorizontal = 80.000000 %
2021-02-17T20:48:56.050Z,1613594936.050 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectNO3Active = 0 bool
2021-02-17T20:48:56.052Z,1613594936.052 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectOilActive = 0 bool
2021-02-17T20:48:56.053Z,1613594936.053 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectFDOMActive = 0 bool
2021-02-17T20:48:56.055Z,1613594936.055 [MissionManager](INFO): DefineArg profile_station:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2021-02-17T20:48:56.057Z,1613594936.057 [MissionManager](INFO): DefineArg profile_station:Science.EnabledAanderaaO2 = 0.000000 bool
2021-02-17T20:48:56.060Z,1613594936.060 [MissionManager](INFO): DefineArg profile_station:Science.EnabledNeilBrown = 0.000000 bool
2021-02-17T20:48:56.071Z,1613594936.071 [MissionManager](INFO): DefineArg profile_station:Science.EnabledSeabird = 1.000000 bool
2021-02-17T20:48:56.073Z,1613594936.073 [MissionManager](INFO): DefineArg profile_station:Science.EnabledWetLabsBB2FL = 1.000000 bool
2021-02-17T20:48:56.075Z,1613594936.075 [MissionManager](INFO): DefineArg profile_station:Science.EnabledWetLabsSeaOWL_UV_A = 0.000000 bool
2021-02-17T20:48:56.078Z,1613594936.078 [MissionManager](INFO): DefineArg profile_station:Science.EnabledWetLabsUBAT = 0.000000 bool
2021-02-17T20:48:56.080Z,1613594936.080 [MissionManager](INFO): DefineArg profile_station:Science.LowPassWindowLength = 20.000000 count
2021-02-17T20:48:56.082Z,1613594936.082 [MissionManager](INFO): DefineArg profile_station:Science.MedianFilterLen = 5.000000 count
2021-02-17T20:48:56.084Z,1613594936.084 [MissionManager](INFO): DefineArg profile_station:Science.PeakShallowBound = nan m
2021-02-17T20:48:56.142Z,1613594936.142 [MissionManager](INFO): DefineArg profile_station:Science.PeakDeepBound = nan m
2021-02-17T20:48:56.144Z,1613594936.144 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChl = 0.000000 ug/l
2021-02-17T20:48:56.146Z,1613594936.146 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlDepth = 0.000000 m
2021-02-17T20:48:56.148Z,1613594936.148 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlTemperature = 0.000000 degC
2021-02-17T20:48:56.166Z,1613594936.166 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlLatitude = nan arcdeg
2021-02-17T20:48:56.169Z,1613594936.169 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlLongitude = nan arcdeg
2021-02-17T20:48:56.171Z,1613594936.171 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3 = 0.000000 umol/l
2021-02-17T20:48:56.173Z,1613594936.173 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3Depth = 0.000000 m
2021-02-17T20:48:56.175Z,1613594936.175 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3Latitude = nan arcdeg
2021-02-17T20:48:56.198Z,1613594936.198 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3Longitude = nan arcdeg
2021-02-17T20:48:56.201Z,1613594936.201 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChl = 0.000000 m
2021-02-17T20:48:56.203Z,1613594936.203 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlDepth = 0.000000 m
2021-02-17T20:48:56.213Z,1613594936.213 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlLatitude = nan arcdeg
2021-02-17T20:48:56.216Z,1613594936.216 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlLongitude = nan arcdeg
2021-02-17T20:48:56.218Z,1613594936.218 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlDistance = nan m
2021-02-17T20:48:56.220Z,1613594936.220 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOil = 0.000000 kg/m3
2021-02-17T20:48:56.246Z,1613594936.246 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOilDepth = 0.000000 m
2021-02-17T20:48:56.248Z,1613594936.248 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOilLatitude = nan arcdeg
2021-02-17T20:48:56.250Z,1613594936.250 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOilLongitude = nan arcdeg
2021-02-17T20:48:56.252Z,1613594936.252 [MissionManager](INFO): DefineOutput profile_station:Science.PeakFDOM = 0.000000 ppb
2021-02-17T20:48:56.271Z,1613594936.271 [MissionManager](INFO): DefineOutput profile_station:Science.PeakFDOMDepth = 0.000000 m
2021-02-17T20:48:56.273Z,1613594936.273 [MissionManager](INFO): DefineOutput profile_station:Science.PeakFDOMLatitude = nan arcdeg
2021-02-17T20:48:56.275Z,1613594936.275 [MissionManager](INFO): DefineOutput profile_station:Science.PeakFDOMLongitude = nan arcdeg
2021-02-17T20:48:56.304Z,1613594936.304 [MissionManager](INFO): DefineOutput profile_station:Science.PatchFDOMDistance = nan m
2021-02-17T20:48:56.344Z,1613594936.344 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-02-17T20:48:56.375Z,1613594936.375 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2021-02-17T20:48:56.416Z,1613594936.416 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-02-17T20:48:56.438Z,1613594936.438 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-02-17T20:48:56.459Z,1613594936.459 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-02-17T20:48:56.516Z,1613594936.516 [MissionManager](INFO): RedefineArg profile_station:Science.PeakDetectChlActive = value:1 bool
2021-02-17T20:48:56.526Z,1613594936.526 [MissionManager](INFO): Inserting Stack: Missions/Insert/NeedComms.xml
2021-02-17T20:48:56.625Z,1613594936.625 [MissionManager](INFO): DefineArg profile_station:NeedComms.DiveInterval = 3.000000 h
2021-02-17T20:48:56.627Z,1613594936.627 [MissionManager](INFO): DefineArg profile_station:NeedComms.WaitForPitchUp = 10.000000 min
2021-02-17T20:48:56.658Z,1613594936.658 [MissionManager](INFO): DefineArg profile_station:NeedComms.SurfacePitch = 20.000000 arcdeg
2021-02-17T20:48:56.660Z,1613594936.660 [MissionManager](INFO): DefineArg profile_station:NeedComms.SurfaceDepthRate = nan m/s
2021-02-17T20:48:56.662Z,1613594936.662 [MissionManager](INFO): DefineArg profile_station:NeedComms.SurfaceSpeed = 1.000000 m/s
2021-02-17T20:48:56.664Z,1613594936.664 [MissionManager](INFO): DefineArg profile_station:NeedComms.GPSTimeout = 7.000000 min
2021-02-17T20:48:56.666Z,1613594936.666 [MissionManager](INFO): DefineArg profile_station:NeedComms.CommsTimeout = 30.000000 min
2021-02-17T20:48:56.697Z,1613594936.697 [profile_station:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2021-02-17T20:48:56.725Z,1613594936.725 [MissionManager](INFO): Inserting Stack: Missions/Insert/StandardEnvelopes.xml
2021-02-17T20:48:56.773Z,1613594936.773 [MissionManager](INFO): DefineArg profile_station:StandardEnvelopes.MinAltitude = 5.000000 m
2021-02-17T20:48:56.775Z,1613594936.775 [MissionManager](INFO): DefineArg profile_station:StandardEnvelopes.MaxDepth = 200.000000 m
2021-02-17T20:48:56.777Z,1613594936.777 [MissionManager](INFO): DefineArg profile_station:StandardEnvelopes.MinOffshore = 2000.000000 m
2021-02-17T20:48:56.778Z,1613594936.778 [profile_station:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2021-02-17T20:48:56.811Z,1613594936.811 [profile_station:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2021-02-17T20:48:56.825Z,1613594936.825 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2021-02-17T20:48:56.839Z,1613594936.839 [profile_station:I.Pitch](DEBUG): Construct.
2021-02-17T20:48:56.844Z,1613594936.844 [profile_station:J.Buoyancy](DEBUG): Construct Buoyancy.
2021-02-17T20:48:56.847Z,1613594936.847 [profile_station:K.SetSpeed](DEBUG): Construct.
2021-02-17T20:48:56.849Z,1613594936.849 [profile_station:L.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2021-02-17T20:48:56.856Z,1613594936.856 [profile_station:M.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2021-02-17T20:48:56.861Z,1613594936.861 [profile_station:N.YoYo](DEBUG): Construct YoYo.
2021-02-17T20:48:56.868Z,1613594936.868 [profile_station:CircleWrapper:B.Circle](DEBUG): Construct.
2021-02-17T20:48:56.914Z,1613594936.914 [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="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>


2021-02-17T20:48:56.915Z,1613594936.915 [CommandLine](IMPORTANT): Loaded ./Missions/Science/profile_station.xml
2021-02-17T20:49:07.681Z,1613594947.681 [CommandLine](IMPORTANT): got command set profile_station.MissionTimeout 7.000000 hour
2021-02-17T20:49:07.681Z,1613594947.681 [CommandLine](IMPORTANT): got command set profile_station.NeedCommsTime 45.000000 minute
2021-02-17T20:49:07.682Z,1613594947.682 [CommandLine](IMPORTANT): got command set profile_station.YoYoMaxDepth 20.000000 meter
2021-02-17T20:49:07.683Z,1613594947.683 [CommandLine](IMPORTANT): got command set profile_station.MaxDepth 25.000000 meter
2021-02-17T20:49:07.683Z,1613594947.683 [CommandLine](IMPORTANT): got command run
2021-02-17T20:49:07.685Z,1613594947.685 [CommandLine](IMPORTANT): Running 
2021-02-17T20:49:07.690Z,1613594947.690 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2021-02-17T20:49:07.690Z,1613594947.690 [RDI_Pathfinder] Communications Fault, FailCount= 1
2021-02-17T20:49:07.691Z,1613594947.691 [RDI_Pathfinder](ERROR): Communications Fault
2021-02-17T20:49:07.705Z,1613594947.705 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2021-02-17T20:49:07.729Z,1613594947.729 [RudderServo](INFO): Powering down
2021-02-17T20:49:07.731Z,1613594947.731 [IBIT](FAULT): Could not read rudderAngleReader_.
2021-02-17T20:49:07.731Z,1613594947.731 [IBIT](FAULT): Control surface position failure.
2021-02-17T20:49:07.746Z,1613594947.746 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2021-02-17T20:49:08.129Z,1613594948.129 [RDI_Pathfinder](INFO): Powering down
2021-02-17T20:49:08.286Z,1613594948.286 [RudderServo](DEBUG): Initializing EZServoServo.
2021-02-17T20:49:08.406Z,1613594948.406 [RudderServo](DEBUG): Initializing RudderServo.
2021-02-17T20:49:08.411Z,1613594948.411 [CBIT](INFO): Clearing failed state for component RudderServo
2021-02-17T20:49:08.411Z,1613594948.411 [RudderServo] No Fault, FailCount= 2
2021-02-17T20:49:08.640Z,1613594948.640 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204907.00,A,3648.53282,N,12149.44678,W,0.525,64.93,170221,,,D*4D
2021-02-17T20:49:08.642Z,1613594948.642 [NAL9602](INFO): GPS fix at 20210217T204907: (36.808880, -121.824113)
2021-02-17T20:49:08.698Z,1613594948.698 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2021-02-17T20:49:08.698Z,1613594948.698 [RDI_Pathfinder] No Fault, FailCount= 1
2021-02-17T20:49:11.840Z,1613594951.840 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204910.00,A,3648.53296,N,12149.44649,W,0.350,62.86,170221,,,D*4A
2021-02-17T20:49:11.842Z,1613594951.842 [NAL9602](INFO): GPS fix at 20210217T204910: (36.808883, -121.824108)
2021-02-17T20:49:15.068Z,1613594955.068 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204913.00,A,3648.53299,N,12149.44490,W,1.691,88.91,170221,,,D*4B
2021-02-17T20:49:15.071Z,1613594955.071 [NAL9602](INFO): GPS fix at 20210217T204913: (36.808883, -121.824082)
2021-02-17T20:49:17.900Z,1613594957.900 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204916.00,A,3648.53279,N,12149.44280,W,2.294,95.64,170221,,,D*43
2021-02-17T20:49:17.907Z,1613594957.907 [NAL9602](INFO): GPS fix at 20210217T204916: (36.808880, -121.824047)
2021-02-17T20:49:20.723Z,1613594960.723 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204919.00,A,3648.53283,N,12149.44102,W,1.555,96.76,170221,,,D*49
2021-02-17T20:49:20.725Z,1613594960.725 [NAL9602](INFO): GPS fix at 20210217T204919: (36.808881, -121.824017)
2021-02-17T20:49:23.243Z,1613594963.243 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 4
Latitude: 36.808880 Longitude: -121.824020
2021-02-17T20:49:23.683Z,1613594963.683 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 72.602997
Voltage: 13.836000
2021-02-17T20:49:23.683Z,1613594963.683 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2021-02-17T20:49:23.684Z,1613594963.684 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2021-02-17T20:49:23.956Z,1613594963.956 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204922.00,A,3648.53258,N,12149.43937,W,1.594,99.53,170221,,,D*4B
2021-02-17T20:49:23.958Z,1613594963.958 [NAL9602](INFO): GPS fix at 20210217T204922: (36.808876, -121.823989)
2021-02-17T20:49:24.050Z,1613594964.050 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2021-02-17T20:49:24.051Z,1613594964.051 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2021-02-17T20:49:24.051Z,1613594964.051 [IBIT](IMPORTANT): Pressure:7.063389 PSI
2021-02-17T20:49:24.051Z,1613594964.051 [IBIT](IMPORTANT): Humidity:19.210108 %
2021-02-17T20:49:24.459Z,1613594964.459 [IBIT](IMPORTANT): Vehicle Pitch:-2.837921 degrees
2021-02-17T20:49:24.459Z,1613594964.459 [IBIT](IMPORTANT): Vehicle  Roll:-15.107342 degrees
2021-02-17T20:49:24.459Z,1613594964.459 [IBIT](IMPORTANT): Vehicle  Heading:296.099884 degrees
2021-02-17T20:49:24.854Z,1613594964.854 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2021-02-17T20:49:24.855Z,1613594964.855 [IBIT](IMPORTANT): buoyancyNeutral: 250.315491 cc
2021-02-17T20:49:24.855Z,1613594964.855 [IBIT](IMPORTANT): massDefault: 0.522435 cm
2021-02-17T20:49:24.855Z,1613594964.855 [IBIT](IMPORTANT): stopDepth: 215.000000 m
2021-02-17T20:49:24.856Z,1613594964.856 [IBIT](IMPORTANT): abortDepth: 255.000000 m
2021-02-17T20:49:24.856Z,1613594964.856 [IBIT](IMPORTANT): IBIT FAILED
2021-02-17T20:49:25.236Z,1613594965.236 [Default] Stopped
2021-02-17T20:49:25.236Z,1613594965.236 [Default](DEBUG): Aggregate::uninitialize Default
2021-02-17T20:49:25.236Z,1613594965.236 [Default:B.GoToSurface] Stopped
2021-02-17T20:49:25.236Z,1613594965.236 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-02-17T20:49:25.237Z,1613594965.237 [Default:CheckIn] Stopped
2021-02-17T20:49:25.242Z,1613594965.242 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-02-17T20:49:25.242Z,1613594965.242 [Default:CheckIn:C.Wait] Stopped
2021-02-17T20:49:25.242Z,1613594965.242 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-02-17T20:49:25.242Z,1613594965.242 [MissionManager](IMPORTANT): Started mission profile_station
2021-02-17T20:49:25.242Z,1613594965.242 [profile_station] Running Loop=1
2021-02-17T20:49:25.242Z,1613594965.242 [profile_station](DEBUG): Aggregate::initialize profile_station
2021-02-17T20:49:25.242Z,1613594965.242 [profile_station:Science] Running Loop=1
2021-02-17T20:49:25.242Z,1613594965.242 [profile_station:Science](DEBUG): Aggregate::initialize profile_station:Science
2021-02-17T20:49:25.243Z,1613594965.243 [profile_station:Science:A] Running Loop=1
2021-02-17T20:49:25.243Z,1613594965.243 [profile_station:Science:B] Running Loop=1
2021-02-17T20:49:25.243Z,1613594965.243 [profile_station:Science:C] Running Loop=1
2021-02-17T20:49:25.243Z,1613594965.243 [profile_station:Science:D] Running Loop=1
2021-02-17T20:49:25.243Z,1613594965.243 [profile_station:Science:E] Running Loop=1
2021-02-17T20:49:25.243Z,1613594965.243 [profile_station:Science:F] Running Loop=1
2021-02-17T20:49:25.243Z,1613594965.243 [profile_station:Science:Read_Oil] Running Loop=1
2021-02-17T20:49:25.243Z,1613594965.243 [profile_station:Science:PeakDetectChl] Running Loop=1
2021-02-17T20:49:25.243Z,1613594965.243 [profile_station:Science:PeakDetectChl](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectChl
2021-02-17T20:49:25.243Z,1613594965.243 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2021-02-17T20:49:25.243Z,1613594965.243 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-02-17T20:49:25.244Z,1613594965.244 [profile_station:Science:HighestChlPeakReport] Running Loop=1
2021-02-17T20:49:25.245Z,1613594965.245 [profile_station:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize profile_station:Science:HighestChlPeakReport
2021-02-17T20:49:25.269Z,1613594965.269 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2021-02-17T20:49:25.269Z,1613594965.269 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2021-02-17T20:49:25.270Z,1613594965.270 [profile_station:Science:PeakDetectNO3] Running Loop=1
2021-02-17T20:49:25.270Z,1613594965.270 [profile_station:Science:PeakDetectNO3](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectNO3
2021-02-17T20:49:25.270Z,1613594965.270 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2021-02-17T20:49:25.270Z,1613594965.270 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-02-17T20:49:25.278Z,1613594965.278 [profile_station:Science:PeakDetectOil] Running Loop=1
2021-02-17T20:49:25.278Z,1613594965.278 [profile_station:Science:PeakDetectOil](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectOil
2021-02-17T20:49:25.278Z,1613594965.278 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2021-02-17T20:49:25.278Z,1613594965.278 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-02-17T20:49:25.279Z,1613594965.279 [profile_station:Science:PeakDetectFDOM] Running Loop=1
2021-02-17T20:49:25.279Z,1613594965.279 [profile_station:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectFDOM
2021-02-17T20:49:25.280Z,1613594965.280 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2021-02-17T20:49:25.280Z,1613594965.280 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-02-17T20:49:25.281Z,1613594965.281 [profile_station:Science:OceanCurrent] Running Loop=1
2021-02-17T20:49:25.281Z,1613594965.281 [profile_station:Science:OceanCurrent](DEBUG): Aggregate::initialize profile_station:Science:OceanCurrent
2021-02-17T20:49:25.281Z,1613594965.281 [profile_station:Science:OceanCurrent:A.] Running Loop=1
2021-02-17T20:49:25.281Z,1613594965.281 [profile_station:Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator.
2021-02-17T20:49:25.281Z,1613594965.281 [profile_station:StandardEnvelopes] Running Loop=1
2021-02-17T20:49:25.281Z,1613594965.281 [profile_station:StandardEnvelopes](DEBUG): Aggregate::initialize profile_station:StandardEnvelopes
2021-02-17T20:49:25.282Z,1613594965.282 [profile_station:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2021-02-17T20:49:25.282Z,1613594965.282 [profile_station:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2021-02-17T20:49:25.282Z,1613594965.282 [profile_station:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2021-02-17T20:49:25.282Z,1613594965.282 [profile_station:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2021-02-17T20:49:25.282Z,1613594965.282 [profile_station:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2021-02-17T20:49:25.282Z,1613594965.282 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2021-02-17T20:49:25.282Z,1613594965.282 [profile_station:I.Pitch] Running Loop=1
2021-02-17T20:49:25.282Z,1613594965.282 [profile_station:I.Pitch](DEBUG): Initialize.
2021-02-17T20:49:25.282Z,1613594965.282 [profile_station:J.Buoyancy] Running Loop=1
2021-02-17T20:49:25.282Z,1613594965.282 [profile_station:J.Buoyancy](DEBUG): Initialize Buoyancy Component.
2021-02-17T20:49:25.283Z,1613594965.283 [profile_station:K.SetSpeed] Running Loop=1
2021-02-17T20:49:25.283Z,1613594965.283 [profile_station:K.SetSpeed](DEBUG): Initialize.
2021-02-17T20:49:25.283Z,1613594965.283 [profile_station:L.DepthEnvelope] Running Loop=1
2021-02-17T20:49:25.283Z,1613594965.283 [profile_station:L.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2021-02-17T20:49:25.283Z,1613594965.283 [profile_station:M.AltitudeEnvelope] Running Loop=1
2021-02-17T20:49:25.283Z,1613594965.283 [profile_station:M.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2021-02-17T20:49:25.283Z,1613594965.283 [profile_station:N.YoYo] Running Loop=1
2021-02-17T20:49:25.283Z,1613594965.283 [profile_station:N.YoYo](DEBUG): Initialize YoYoComponent.
2021-02-17T20:49:25.284Z,1613594965.284 [profile_station:C] Running Loop=1
2021-02-17T20:49:25.284Z,1613594965.284 [profile_station:N.YoYo] Running Loop=1
2021-02-17T20:49:25.293Z,1613594965.293 [profile_station:M.AltitudeEnvelope] Running Loop=1
2021-02-17T20:49:25.294Z,1613594965.294 [profile_station:L.DepthEnvelope] Running Loop=1
2021-02-17T20:49:25.294Z,1613594965.294 [profile_station:K.SetSpeed] Running Loop=1
2021-02-17T20:49:25.294Z,1613594965.294 [profile_station:J.Buoyancy] Running Loop=1
2021-02-17T20:49:25.295Z,1613594965.295 [profile_station:I.Pitch] Running Loop=1
2021-02-17T20:49:25.295Z,1613594965.295 [profile_station:StandardEnvelopes] Running Loop=1
2021-02-17T20:49:25.295Z,1613594965.295 [profile_station:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2021-02-17T20:49:25.295Z,1613594965.295 [profile_station:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2021-02-17T20:49:25.296Z,1613594965.296 [profile_station:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2021-02-17T20:49:25.296Z,1613594965.296 [profile_station:C] Stopped
2021-02-17T20:49:25.296Z,1613594965.296 [profile_station:E] Running Loop=1
2021-02-17T20:49:25.296Z,1613594965.296 [profile_station:Science] Running Loop=1
2021-02-17T20:49:25.306Z,1613594965.306 [profile_station:Science:PeakDetectFDOM] Stopped
2021-02-17T20:49:25.306Z,1613594965.306 [profile_station:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectFDOM
2021-02-17T20:49:25.306Z,1613594965.306 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2021-02-17T20:49:25.306Z,1613594965.306 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-02-17T20:49:25.306Z,1613594965.306 [profile_station:Science:PeakDetectOil] Stopped
2021-02-17T20:49:25.306Z,1613594965.306 [profile_station:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectOil
2021-02-17T20:49:25.306Z,1613594965.306 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2021-02-17T20:49:25.306Z,1613594965.306 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-02-17T20:49:25.306Z,1613594965.306 [profile_station:Science:PeakDetectNO3] Stopped
2021-02-17T20:49:25.307Z,1613594965.307 [profile_station:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectNO3
2021-02-17T20:49:25.307Z,1613594965.307 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2021-02-17T20:49:25.307Z,1613594965.307 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-02-17T20:49:25.307Z,1613594965.307 [profile_station:Science:HighestChlPeakReport] Stopped
2021-02-17T20:49:25.307Z,1613594965.307 [profile_station:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize profile_station:Science:HighestChlPeakReport
2021-02-17T20:49:25.307Z,1613594965.307 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2021-02-17T20:49:25.307Z,1613594965.307 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2021-02-17T20:49:25.307Z,1613594965.307 [profile_station:Science:PeakDetectChl] Running Loop=1
2021-02-17T20:49:25.307Z,1613594965.307 [profile_station:Science:OceanCurrent:A.] Running Loop=1
2021-02-17T20:49:25.307Z,1613594965.307 [profile_station:Science:Read_Oil] Stopped
2021-02-17T20:49:25.307Z,1613594965.307 [profile_station:Science:F] Stopped
2021-02-17T20:49:25.307Z,1613594965.307 [profile_station:Science:E] Running Loop=1
2021-02-17T20:49:25.308Z,1613594965.308 [profile_station:Science:E](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2021-02-17T20:49:25.308Z,1613594965.308 [profile_station:Science:D] Stopped
2021-02-17T20:49:25.308Z,1613594965.308 [profile_station:Science:C] Running Loop=1
2021-02-17T20:49:25.309Z,1613594965.309 [profile_station:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature
2021-02-17T20:49:25.315Z,1613594965.315 [profile_station:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity
2021-02-17T20:49:25.315Z,1613594965.315 [profile_station:Science:B] Running Loop=1
2021-02-17T20:49:25.316Z,1613594965.316 [profile_station:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2021-02-17T20:49:25.316Z,1613594965.316 [profile_station:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2021-02-17T20:49:25.316Z,1613594965.316 [profile_station:Science:A] Stopped
2021-02-17T20:49:25.620Z,1613594965.620 [profile_station:E] Stopped
2021-02-17T20:49:25.620Z,1613594965.620 [profile_station:F] Running Loop=1
2021-02-17T20:49:25.699Z,1613594965.699 [WetLabsBB2FL](INFO): Powering up
2021-02-17T20:49:26.007Z,1613594966.007 [profile_station:F] Stopped
2021-02-17T20:49:26.007Z,1613594966.007 [profile_station:G] Running Loop=1
2021-02-17T20:49:26.437Z,1613594966.437 [profile_station:G] Stopped
2021-02-17T20:49:26.441Z,1613594966.441 [profile_station:StartingMission] Running Loop=1
2021-02-17T20:49:26.441Z,1613594966.441 [profile_station:StartingMission](DEBUG): Aggregate::initialize profile_station:StartingMission
2021-02-17T20:49:26.803Z,1613594966.803 [profile_station:NeedComms] Running Loop=1
2021-02-17T20:49:26.803Z,1613594966.803 [profile_station:NeedComms](DEBUG): Aggregate::initialize profile_station:NeedComms
2021-02-17T20:49:26.803Z,1613594966.803 [profile_station:NeedComms:B.GoToSurface] Running Loop=1
2021-02-17T20:49:26.803Z,1613594966.803 [profile_station:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-02-17T20:49:26.804Z,1613594966.804 [profile_station:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2021-02-17T20:49:26.804Z,1613594966.804 [profile_station:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2021-02-17T20:49:26.804Z,1613594966.804 [profile_station:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2021-02-17T20:49:26.805Z,1613594966.805 [profile_station:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-02-17T20:49:26.805Z,1613594966.805 [profile_station:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-02-17T20:49:26.805Z,1613594966.805 [profile_station:NeedComms:A] Running Loop=1
2021-02-17T20:49:26.807Z,1613594966.807 [profile_station:NeedComms:A](INFO): last time_fix was: 1613594962.000000 second since 1970/01/01T00:00:00Z 
2021-02-17T20:49:26.807Z,1613594966.807 [profile_station:NeedComms:A] Stopped
2021-02-17T20:49:27.208Z,1613594967.208 [profile_station:NeedComms:C] Running Loop=1
2021-02-17T20:49:27.642Z,1613594967.642 [profile_station:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2021-02-17T20:49:27.758Z,1613594967.758 [CommandLine](IMPORTANT): got command stop
2021-02-17T20:49:27.759Z,1613594967.759 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop
2021-02-17T20:49:28.009Z,1613594968.009 [MissionManager](INFO): MissionManager is completed.
2021-02-17T20:49:28.009Z,1613594968.009 [MissionManager](INFO): Uninitializing Mission profile_station
2021-02-17T20:49:28.009Z,1613594968.009 [profile_station] Stopped
2021-02-17T20:49:28.009Z,1613594968.009 [profile_station](DEBUG): Aggregate::uninitialize profile_station
2021-02-17T20:49:28.009Z,1613594968.009 [profile_station:Science] Stopped
2021-02-17T20:49:28.009Z,1613594968.009 [profile_station:Science](DEBUG): Aggregate::uninitialize profile_station:Science
2021-02-17T20:49:28.010Z,1613594968.010 [profile_station:Science:B] Stopped
2021-02-17T20:49:28.010Z,1613594968.010 [profile_station:Science:C] Stopped
2021-02-17T20:49:28.010Z,1613594968.010 [profile_station:Science:E] Stopped
2021-02-17T20:49:28.010Z,1613594968.010 [profile_station:Science:OceanCurrent] Stopped
2021-02-17T20:49:28.010Z,1613594968.010 [profile_station:Science:OceanCurrent](DEBUG): Aggregate::uninitialize profile_station:Science:OceanCurrent
2021-02-17T20:49:28.010Z,1613594968.010 [profile_station:Science:OceanCurrent:A.] Stopped
2021-02-17T20:49:28.010Z,1613594968.010 [profile_station:Science:PeakDetectChl] Stopped
2021-02-17T20:49:28.010Z,1613594968.010 [profile_station:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectChl
2021-02-17T20:49:28.010Z,1613594968.010 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2021-02-17T20:49:28.010Z,1613594968.010 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-02-17T20:49:28.010Z,1613594968.010 [profile_station:NeedComms] Stopped
2021-02-17T20:49:28.010Z,1613594968.010 [profile_station:NeedComms](DEBUG): Aggregate::uninitialize profile_station:NeedComms
2021-02-17T20:49:28.010Z,1613594968.010 [profile_station:NeedComms:B.GoToSurface] Stopped
2021-02-17T20:49:28.011Z,1613594968.011 [profile_station:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-02-17T20:49:28.011Z,1613594968.011 [profile_station:NeedComms:C] Stopped
2021-02-17T20:49:28.011Z,1613594968.011 [profile_station:StandardEnvelopes] Stopped
2021-02-17T20:49:28.011Z,1613594968.011 [profile_station:StandardEnvelopes](DEBUG): Aggregate::uninitialize profile_station:StandardEnvelopes
2021-02-17T20:49:28.011Z,1613594968.011 [profile_station:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2021-02-17T20:49:28.011Z,1613594968.011 [profile_station:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2021-02-17T20:49:28.011Z,1613594968.011 [profile_station:StandardEnvelopes:B.DepthEnvelope] Stopped
2021-02-17T20:49:28.011Z,1613594968.011 [profile_station:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2021-02-17T20:49:28.011Z,1613594968.011 [profile_station:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2021-02-17T20:49:28.011Z,1613594968.011 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2021-02-17T20:49:28.011Z,1613594968.011 [profile_station:StartingMission] Stopped
2021-02-17T20:49:28.011Z,1613594968.011 [profile_station:StartingMission](DEBUG): Aggregate::uninitialize profile_station:StartingMission
2021-02-17T20:49:28.011Z,1613594968.011 [profile_station:I.Pitch] Stopped
2021-02-17T20:49:28.011Z,1613594968.011 [profile_station:J.Buoyancy] Stopped
2021-02-17T20:49:28.012Z,1613594968.012 [profile_station:J.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2021-02-17T20:49:28.012Z,1613594968.012 [profile_station:K.SetSpeed] Stopped
2021-02-17T20:49:28.012Z,1613594968.012 [profile_station:K.SetSpeed](DEBUG): Uninitialize.
2021-02-17T20:49:28.012Z,1613594968.012 [profile_station:L.DepthEnvelope] Stopped
2021-02-17T20:49:28.012Z,1613594968.012 [profile_station:L.DepthEnvelope](DEBUG): Uninitialize.
2021-02-17T20:49:28.012Z,1613594968.012 [profile_station:M.AltitudeEnvelope] Stopped
2021-02-17T20:49:28.012Z,1613594968.012 [profile_station:M.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2021-02-17T20:49:28.012Z,1613594968.012 [profile_station:N.YoYo] Stopped
2021-02-17T20:49:28.012Z,1613594968.012 [profile_station:N.YoYo](DEBUG): Uninitialize YoYoComponent.
2021-02-17T20:49:28.313Z,1613594968.313 [WetLabsBB2FL](INFO): Powering down
2021-02-17T20:49:28.426Z,1613594968.426 [NAL9602](INFO): Failure count cleared after critical for NAL9602
2021-02-17T20:49:28.448Z,1613594968.448 [MissionManager](IMPORTANT): Started mission Default
2021-02-17T20:49:28.448Z,1613594968.448 [Default] Running Loop=1
2021-02-17T20:49:28.448Z,1613594968.448 [Default](DEBUG): Aggregate::initialize Default
2021-02-17T20:49:28.448Z,1613594968.448 [Default:B.GoToSurface] Running Loop=1
2021-02-17T20:49:28.448Z,1613594968.448 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-02-17T20:49:28.448Z,1613594968.448 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-02-17T20:49:28.449Z,1613594968.449 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-02-17T20:49:28.449Z,1613594968.449 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-02-17T20:49:28.449Z,1613594968.449 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-02-17T20:49:28.450Z,1613594968.450 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-02-17T20:49:28.450Z,1613594968.450 [Default:A.Wait] Running Loop=1
2021-02-17T20:49:28.450Z,1613594968.450 [Default:A.Wait](DEBUG): Initialize Wait Component.
2021-02-17T20:49:41.763Z,1613594981.763 [Default:A.Wait](INFO): Done Waiting.
2021-02-17T20:49:41.764Z,1613594981.764 [Default:A.Wait] Stopped
2021-02-17T20:49:41.764Z,1613594981.764 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2021-02-17T20:49:42.184Z,1613594982.184 [Default:CheckIn] Running Loop=1
2021-02-17T20:49:42.184Z,1613594982.184 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-02-17T20:49:42.184Z,1613594982.184 [Default:CheckIn:Read_GPS] Running Loop=1
2021-02-17T20:49:44.154Z,1613594984.154 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204942.00,A,3648.52957,N,12149.42289,W,2.527,96.48,170221,,,D*49
2021-02-17T20:49:44.156Z,1613594984.156 [NAL9602](INFO): GPS fix at 20210217T204942: (36.808826, -121.823715)
2021-02-17T20:49:44.178Z,1613594984.178 [Default:CheckIn:Read_GPS] Stopped
2021-02-17T20:49:44.178Z,1613594984.178 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-02-17T20:49:55.062Z,1613594995.062 [CommandLine](IMPORTANT): got command report mod NAL9602.sigQuality
2021-02-17T20:49:55.396Z,1613594995.396 [Reporter](INFO): NAL9602.sigQuality 4 count
2021-02-17T20:50:00.916Z,1613595000.916 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210217T204139/Courier0006.lzma
2021-02-17T20:50:01.911Z,1613595001.911 [DataOverHttps](INFO): Moved sent file to Logs/20210217T204139/Courier0006.lzma.bak
2021-02-17T20:50:01.911Z,1613595001.911 [DataOverHttps](INFO): SBD MOMSN=15232686
2021-02-17T20:50:08.568Z,1613595008.568 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2021-02-17T20:50:08.573Z,1613595008.573 [BPC1](INFO): Received data from all battery sticks.
2021-02-17T20:50:16.222Z,1613595016.222 [NAL9602](INFO): Not Powering down - fast GPS
2021-02-17T20:50:16.314Z,1613595016.314 [Reporter](INFO): NAL9602.sigQuality 0 count
2021-02-17T20:50:37.991Z,1613595037.991 [DataOverHttps](INFO): Sending 1192 bytes from file Logs/20210217T204139/Express0007.lzma
2021-02-17T20:50:38.995Z,1613595038.995 [DataOverHttps](INFO): Moved sent file to Logs/20210217T204139/Express0007.lzma.bak
2021-02-17T20:50:38.995Z,1613595038.995 [DataOverHttps](INFO): SBD MOMSN=15232691
2021-02-17T20:50:42.712Z,1613595042.712 [Default:CheckIn:Read_Iridium] Stopped
2021-02-17T20:50:42.713Z,1613595042.713 [Default:CheckIn:C.Wait] Running Loop=1
2021-02-17T20:50:42.713Z,1613595042.713 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-02-17T20:50:46.991Z,1613595046.991 [CommandLine](IMPORTANT): got command gfscan
2021-02-17T20:50:47.222Z,1613595047.222 [CBIT](IMPORTANT): Beginning ground fault scan
2021-02-17T20:50:58.112Z,1613595058.112 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.139741
CHAN A1 (24V): 0.252078
CHAN A2 (12V): 0.114921
CHAN A3 (5V): 0.043844
CHAN B0 (3.3V): 0.033238
CHAN B1 (3.15aV): 0.031962
CHAN B2 (3.15bV): 0.032289
CHAN B3 (GND): 0.003449
OPEN: 0.013808
Full Scale Calc: 4.765 mA, -1.589 mA
2021-02-17T20:51:57.862Z,1613595117.862 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-02-17T20:52:00.873Z,1613595120.873 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.251270
2021-02-17T20:52:48.925Z,1613595168.925 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002963
2021-02-17T20:53:49.922Z,1613595229.922 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.999851
2021-02-17T20:54:55.225Z,1613595295.225 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-02-17T20:55:43.311Z,1613595343.311 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-02-17T20:55:43.311Z,1613595343.311 [Default:CheckIn:C.Wait] Stopped
2021-02-17T20:55:43.311Z,1613595343.311 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-02-17T20:55:43.311Z,1613595343.311 [Default:CheckIn:D] Running Loop=1
2021-02-17T20:55:43.712Z,1613595343.712 [Default:CheckIn:D] Stopped
2021-02-17T20:55:43.712Z,1613595343.712 [Default:CheckIn:E] Running Loop=1
2021-02-17T20:55:44.129Z,1613595344.129 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 6.254398 min 
2021-02-17T20:55:44.129Z,1613595344.129 [Default:CheckIn:E] Stopped
2021-02-17T20:55:44.129Z,1613595344.129 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-02-17T20:55:44.129Z,1613595344.129 [Default:CheckIn] Stopped
2021-02-17T20:55:44.129Z,1613595344.129 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-02-17T20:55:44.129Z,1613595344.129 [Default:CheckIn](INFO): Running loop #2
2021-02-17T20:55:44.130Z,1613595344.130 [Default:CheckIn] Running Loop=2
2021-02-17T20:55:44.130Z,1613595344.130 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-02-17T20:55:44.130Z,1613595344.130 [Default:CheckIn:Read_GPS] Running Loop=1
2021-02-17T20:55:46.124Z,1613595346.124 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205544.00,A,3648.53820,N,12149.22681,W,6.512,112.53,170221,,,D*7D
2021-02-17T20:55:46.126Z,1613595346.126 [NAL9602](INFO): GPS fix at 20210217T205544: (36.808970, -121.820447)
2021-02-17T20:55:46.136Z,1613595346.136 [Default:CheckIn:Read_GPS] Stopped
2021-02-17T20:55:46.137Z,1613595346.137 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-02-17T20:55:53.045Z,1613595353.045 [Reporter](INFO): NAL9602.sigQuality 3 count
2021-02-17T20:56:15.056Z,1613595375.056 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=12477, MT Status=1, MTMSN=738
2021-02-17T20:56:15.109Z,1613595375.109 [NAL9602](INFO): Sent 202 bytes from file Logs/20210217T204139/Courier0009.lzma
2021-02-17T20:56:15.110Z,1613595375.110 [NAL9602](INFO): Packets left to send: 0
2021-02-17T20:56:16.530Z,1613595376.530 [NAL9602](INFO): Received command:sched "load Transport/transit.xml;set transit.MissionTimeout 6 hour;set transit.Latitude 36.797 degree;set transit.Longitude -121.849 degree;run"
2021-02-17T20:56:16.582Z,1613595376.582 [CommandLine](IMPORTANT): got command schedule "load Transport/transit.xml;set transit.MissionTimeout 6 hour;set transit.Latitude 36.797 degree;set transit.Longitude -121.849 degree;run"
2021-02-17T20:56:16.582Z,1613595376.582 [CommandLine](IMPORTANT): Scheduled #71: "load Transport/transit.xml;set transit.MissionTimeout 6 hour;set transit.Latitude 36.797 degree;set transit.Longitude -121.849 degree;run"
2021-02-17T20:56:16.583Z,1613595376.583 [CommandLine](FAULT): Scheduling was paused by a command at 2021-02-17T20:49:27Z
2021-02-17T20:56:19.511Z,1613595379.511 [Reporter](INFO): NAL9602.sigQuality 5 count
2021-02-17T20:56:29.209Z,1613595389.209 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002971
2021-02-17T20:56:39.012Z,1613595399.012 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=12478, MT Status=1, MTMSN=739
2021-02-17T20:56:39.081Z,1613595399.081 [NAL9602](INFO): Sent 332 bytes from file Logs/20210217T204139/Express0010.lzma
2021-02-17T20:56:39.082Z,1613595399.082 [NAL9602](INFO): Packets left to send: 1
2021-02-17T20:56:39.762Z,1613595399.762 [NAL9602](INFO): Received command:load Transport/keepstation.xml;set keepstation.MissionTimeout 3 hour;set keepstation.NeedCommsTime 30 minute;set keepstation.Latitude 36.806966 degree;set keepstation.Longitude -121.824326 degree;set keepstation.Depth 20 meter;run
2021-02-17T20:56:39.785Z,1613595399.785 [CommandLine](IMPORTANT): got command load ./Missions/Transport/keepstation.xml
2021-02-17T20:56:39.786Z,1613595399.786 [MissionManager](INFO): Loading Mission: ./Missions/Transport/keepstation.xml
2021-02-17T20:56:39.970Z,1613595399.970 [MissionManager](INFO): DefineArg keepstation.MissionTimeout = 4.000000 h
2021-02-17T20:56:39.972Z,1613595399.972 [MissionManager](INFO): DefineArg keepstation.NeedCommsTime = 45.000000 min
2021-02-17T20:56:39.983Z,1613595399.983 [MissionManager](INFO): DefineArg keepstation.Latitude = nan arcdeg
2021-02-17T20:56:39.993Z,1613595399.993 [MissionManager](INFO): DefineArg keepstation.Longitude = nan arcdeg
2021-02-17T20:56:39.995Z,1613595399.995 [MissionManager](INFO): DefineArg keepstation.Depth = 30.000000 m
2021-02-17T20:56:40.007Z,1613595400.007 [MissionManager](INFO): DefineArg keepstation.ApproachDepth = 15.000000 m
2021-02-17T20:56:40.018Z,1613595400.018 [MissionManager](INFO): DefineArg keepstation.DepthDeadband = 4.000000 m
2021-02-17T20:56:40.020Z,1613595400.020 [MissionManager](INFO): DefineArg keepstation.Speed = 0.750000 m/s
2021-02-17T20:56:40.078Z,1613595400.078 [MissionManager](INFO): DefineArg keepstation.Radius = 200.000000 m
2021-02-17T20:56:40.080Z,1613595400.080 [MissionManager](INFO): DefineArg keepstation.MaxDepth = 45.000000 m
2021-02-17T20:56:40.103Z,1613595400.103 [MissionManager](INFO): DefineArg keepstation.MinOffshore = 2.000000 km
2021-02-17T20:56:40.103Z,1613595400.103 [MissionManager](INFO): Inserting Stack: Missions/Insert/NeedComms.xml
2021-02-17T20:56:40.246Z,1613595400.246 [MissionManager](INFO): DefineArg keepstation:NeedComms.DiveInterval = 3.000000 h
2021-02-17T20:56:40.248Z,1613595400.248 [MissionManager](INFO): DefineArg keepstation:NeedComms.WaitForPitchUp = 10.000000 min
2021-02-17T20:56:40.250Z,1613595400.250 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacePitch = 20.000000 arcdeg
2021-02-17T20:56:40.252Z,1613595400.252 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceDepthRate = nan m/s
2021-02-17T20:56:40.303Z,1613595400.303 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceSpeed = 1.000000 m/s
2021-02-17T20:56:40.305Z,1613595400.305 [MissionManager](INFO): DefineArg keepstation:NeedComms.GPSTimeout = 7.000000 min
2021-02-17T20:56:40.307Z,1613595400.307 [MissionManager](INFO): DefineArg keepstation:NeedComms.CommsTimeout = 30.000000 min
2021-02-17T20:56:40.346Z,1613595400.346 [keepstation:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2021-02-17T20:56:40.422Z,1613595400.422 [MissionManager](INFO): Inserting Stack: Missions/Insert/StandardEnvelopes.xml
2021-02-17T20:56:40.471Z,1613595400.471 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinAltitude = 5.000000 m
2021-02-17T20:56:40.497Z,1613595400.497 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepth = 200.000000 m
2021-02-17T20:56:40.499Z,1613595400.499 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinOffshore = 2000.000000 m
2021-02-17T20:56:40.500Z,1613595400.500 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2021-02-17T20:56:40.518Z,1613595400.518 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2021-02-17T20:56:40.524Z,1613595400.524 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2021-02-17T20:56:40.595Z,1613595400.595 [keepstation:F.Pitch](DEBUG): Construct.
2021-02-17T20:56:40.617Z,1613595400.617 [keepstation:TransitToStation:A.Buoyancy](DEBUG): Construct Buoyancy.
2021-02-17T20:56:40.620Z,1613595400.620 [keepstation:TransitToStation:B.Pitch](DEBUG): Construct.
2021-02-17T20:56:40.624Z,1613595400.624 [keepstation:TransitToStation:C.SetSpeed](DEBUG): Construct.
2021-02-17T20:56:40.657Z,1613595400.657 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2021-02-17T20:56:40.680Z,1613595400.680 [keepstation:KeepStation:B.Pitch](DEBUG): Construct.
2021-02-17T20:56:40.684Z,1613595400.684 [keepstation:KeepStation:C.KeepStation](DEBUG): Construct KeepStation.
2021-02-17T20:56:40.713Z,1613595400.713 [keepstation:KeepStation:D.Wait](DEBUG): Construct Wait.
2021-02-17T20:56:40.739Z,1613595400.739 [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:Guidance="Tethys/Guidance"
         xmlns:Control="Tethys/Control"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd"
       Id="keepstation">

    <Description>
        Vehicle transits to desired waypoint (or stays put if none is commanded)
        and stays within the specified radius.
    </Description>
    <DefineArg Name="MissionTimeout"><Description>
        Maximum duration of mission
    </Description><Units:hour/><Value>4</Value></DefineArg>

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

    <DefineArg Name="Latitude"><Description>
        Latitude of waypoint to hold. If NaN, hold the latitude at start of
        mission.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Longitude"><Description>
        Longitude of waypoint to hold. If NaN, hold the longitude at start of
        mission.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Depth"><Description>
        Depth held during drift mode
    </Description><Units:meter/><Value>30</Value></DefineArg>

    <DefineArg Name="ApproachDepth"><Description>
        Depth for initial approach to station.
    </Description><Units:meter/><Value>15</Value></DefineArg>

    <DefineArg Name="DepthDeadband"><Description>
        How much vertical drift from the specified depth is allowed in drift
        mode
    </Description><Units:meter/><Value>4</Value></DefineArg>

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

    <DefineArg Name="Radius"><Description>
        Radius of circle around waypoint to hold.
    </Description><Units:meter/><Value>200</Value></DefineArg>

    <DefineArg Name="MaxDepth"><Description>
        Maximum allowable depth during the mission.
    </Description><Units:meter/><Value>45</Value></DefineArg>

    <DefineArg Name="MinOffshore"><Description>
        Minimum distance from the shoreline to maintain
    </Description><Units:kilometer/><Value>2</Value></DefineArg>
    <Timeout Duration="MissionTimeout"/>
    <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:MaxDepth"/><Arg Name="MaxDepth"/></Assign>

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

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

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

    <Aggregate Id="TransitToStation">
        <Description>
            Need a separate aggregate for transit if we want to specify a
            different depth for the approach.
        </Description>

        <Sequence/>

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

        <Guidance:Pitch>
            <Parallel/>
            <Setting><Guidance:Pitch.depth/><Arg Name="ApproachDepth"/></Setting>
        </Guidance:Pitch>

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

        <Guidance:Waypoint Id="Wpt1">
            <Sequence/>
            <Setting><Guidance:Waypoint.latitude/><Arg Name="Latitude"/></Setting>
            <Setting><Guidance:Waypoint.longitude/><Arg Name="Longitude"/></Setting>
        </Guidance:Waypoint>
    </Aggregate>

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

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

        <Assign><Parallel/><Control:VerticalControl.depthDeadband/><Arg Name="DepthDeadband"/></Assign>

        <Guidance:Pitch>
            <Parallel/>
            <Setting><Guidance:Pitch.depth/><Arg Name="Depth"/></Setting>
        </Guidance:Pitch>

        <Guidance:KeepStation>
            <Parallel/>
            <Setting><Guidance:KeepStation.latitude/><Arg Name="Latitude"/></Setting>
            <Setting><Guidance:KeepStation.longitude/><Arg Name="Longitude"/></Setting>
            <Setting><Guidance:KeepStation.radius/><Arg Name="Radius"/></Setting>
            <Setting><Guidance:KeepStation.speed/><Arg Name="Speed"/></Setting>
        </Guidance:KeepStation>

        <Guidance:Wait>
            <Description>
                Due to the way the KeepStation behavior is currently
                implemented, we must run it in parallel and use a Wait to keep
                from completing the mission before the timeout expires. However,
                this new version of the mission uses a top-level timeout so that
                the entire mission will not run for longer then MissionTimeout.
            </Description>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Arg Name="MissionTimeout"/></Setting>
        </Guidance:Wait>
    </Aggregate>
</Mission>

2021-02-17T20:56:40.740Z,1613595400.740 [CommandLine](IMPORTANT): Loaded ./Missions/Transport/keepstation.xml
2021-02-17T20:56:42.045Z,1613595402.045 [DataOverHttps](INFO): Sending 371 bytes from file Logs/20210217T204139/Express0010.lzma
2021-02-17T20:56:48.087Z,1613595408.087 [DataOverHttps](INFO): Moved sent file to Logs/20210217T204139/Express0010.lzma.bak
2021-02-17T20:56:48.087Z,1613595408.087 [DataOverHttps](INFO): SBD MOMSN=15232743
2021-02-17T20:57:01.805Z,1613595421.805 [CommandLine](IMPORTANT): got command set keepstation.MissionTimeout 3.000000 hour
2021-02-17T20:57:01.806Z,1613595421.806 [CommandLine](IMPORTANT): got command set keepstation.NeedCommsTime 30.000000 minute
2021-02-17T20:57:01.807Z,1613595421.807 [CommandLine](IMPORTANT): got command set keepstation.Latitude 36.806965 degree
2021-02-17T20:57:01.808Z,1613595421.808 [CommandLine](IMPORTANT): got command set keepstation.Longitude -121.824326 degree
2021-02-17T20:57:01.808Z,1613595421.808 [CommandLine](IMPORTANT): got command set keepstation.Depth 20.000000 meter
2021-02-17T20:57:01.809Z,1613595421.809 [CommandLine](IMPORTANT): got command run
2021-02-17T20:57:01.811Z,1613595421.811 [CommandLine](IMPORTANT): Running 
2021-02-17T20:57:01.812Z,1613595421.812 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2021-02-17T20:57:01.812Z,1613595421.812 [RDI_Pathfinder] Communications Fault, FailCount= 1
2021-02-17T20:57:01.812Z,1613595421.812 [RDI_Pathfinder](ERROR): Communications Fault
2021-02-17T20:57:01.830Z,1613595421.830 [Default] Stopped
2021-02-17T20:57:01.830Z,1613595421.830 [Default](DEBUG): Aggregate::uninitialize Default
2021-02-17T20:57:01.830Z,1613595421.830 [Default:B.GoToSurface] Stopped
2021-02-17T20:57:01.831Z,1613595421.831 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-02-17T20:57:01.831Z,1613595421.831 [Default:CheckIn] Stopped
2021-02-17T20:57:01.831Z,1613595421.831 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-02-17T20:57:01.831Z,1613595421.831 [Default:CheckIn:Read_Iridium] Stopped
2021-02-17T20:57:01.831Z,1613595421.831 [MissionManager](IMPORTANT): Started mission keepstation
2021-02-17T20:57:01.831Z,1613595421.831 [keepstation] Running Loop=1
2021-02-17T20:57:01.831Z,1613595421.831 [keepstation](DEBUG): Aggregate::initialize keepstation
2021-02-17T20:57:01.831Z,1613595421.831 [keepstation:StandardEnvelopes] Running Loop=1
2021-02-17T20:57:01.831Z,1613595421.832 [keepstation:StandardEnvelopes](DEBUG): Aggregate::initialize keepstation:StandardEnvelopes
2021-02-17T20:57:01.832Z,1613595421.832 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2021-02-17T20:57:01.832Z,1613595421.832 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2021-02-17T20:57:01.832Z,1613595421.832 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2021-02-17T20:57:01.832Z,1613595421.832 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2021-02-17T20:57:01.832Z,1613595421.832 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2021-02-17T20:57:01.832Z,1613595421.832 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2021-02-17T20:57:01.832Z,1613595421.832 [keepstation:F.Pitch] Running Loop=1
2021-02-17T20:57:01.832Z,1613595421.832 [keepstation:F.Pitch](DEBUG): Initialize.
2021-02-17T20:57:01.833Z,1613595421.833 [keepstation:B] Running Loop=1
2021-02-17T20:57:01.857Z,1613595421.857 [keepstation:F.Pitch] Running Loop=1
2021-02-17T20:57:01.858Z,1613595421.858 [keepstation:StandardEnvelopes] Running Loop=1
2021-02-17T20:57:01.858Z,1613595421.858 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2021-02-17T20:57:01.858Z,1613595421.858 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2021-02-17T20:57:01.858Z,1613595421.858 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2021-02-17T20:57:01.859Z,1613595421.859 [keepstation:B] Stopped
2021-02-17T20:57:01.859Z,1613595421.859 [keepstation:D] Running Loop=1
2021-02-17T20:57:01.888Z,1613595421.888 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2021-02-17T20:57:02.029Z,1613595422.029 [RDI_Pathfinder](INFO): Powering down
2021-02-17T20:57:02.049Z,1613595422.049 [keepstation:D] Stopped
2021-02-17T20:57:02.049Z,1613595422.049 [keepstation:E] Running Loop=1
2021-02-17T20:57:02.345Z,1613595422.345 [keepstation:E] Stopped
2021-02-17T20:57:02.345Z,1613595422.345 [keepstation:StartingMission] Running Loop=1
2021-02-17T20:57:02.346Z,1613595422.346 [keepstation:StartingMission](DEBUG): Aggregate::initialize keepstation:StartingMission
2021-02-17T20:57:02.745Z,1613595422.745 [keepstation:NeedComms] Running Loop=1
2021-02-17T20:57:02.745Z,1613595422.745 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2021-02-17T20:57:02.746Z,1613595422.746 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2021-02-17T20:57:02.746Z,1613595422.746 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-02-17T20:57:02.746Z,1613595422.746 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2021-02-17T20:57:02.746Z,1613595422.746 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2021-02-17T20:57:02.747Z,1613595422.747 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2021-02-17T20:57:02.747Z,1613595422.747 [keepstation:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-02-17T20:57:02.747Z,1613595422.747 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-02-17T20:57:02.748Z,1613595422.748 [keepstation:NeedComms:A] Running Loop=1
2021-02-17T20:57:02.749Z,1613595422.749 [keepstation:NeedComms:A](INFO): last time_fix was: 1613595344.000000 second since 1970/01/01T00:00:00Z 
2021-02-17T20:57:02.749Z,1613595422.749 [keepstation:NeedComms:A] Stopped
2021-02-17T20:57:02.757Z,1613595422.757 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2021-02-17T20:57:02.757Z,1613595422.757 [RDI_Pathfinder] No Fault, FailCount= 1
2021-02-17T20:57:03.139Z,1613595423.139 [keepstation:NeedComms:C] Running Loop=1
2021-02-17T20:57:03.538Z,1613595423.538 [keepstation:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2021-02-17T20:57:05.144Z,1613595425.144 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205703.00,A,3648.50969,N,12149.04994,W,7.873,94.71,170221,,,D*48
2021-02-17T20:57:05.146Z,1613595425.146 [NAL9602](INFO): GPS fix at 20210217T205703: (36.808495, -121.817499)
2021-02-17T20:57:05.157Z,1613595425.157 [keepstation:NeedComms:C] Stopped
2021-02-17T20:57:05.157Z,1613595425.157 [keepstation:NeedComms:D] Running Loop=1
2021-02-17T20:57:05.576Z,1613595425.576 [keepstation:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications
2021-02-17T20:57:37.498Z,1613595457.498 [NAL9602](INFO): Not Powering down - fast GPS
2021-02-17T20:57:37.522Z,1613595457.522 [Reporter](INFO): NAL9602.sigQuality 0 count
2021-02-17T20:57:38.384Z,1613595458.384 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210217T204139/Courier0012.lzma
2021-02-17T20:57:39.387Z,1613595459.387 [DataOverHttps](INFO): Moved sent file to Logs/20210217T204139/Courier0012.lzma.bak
2021-02-17T20:57:39.387Z,1613595459.387 [DataOverHttps](INFO): SBD MOMSN=15232752
2021-02-17T20:58:41.513Z,1613595521.513 [DataOverHttps](INFO): Sending 471 bytes from file Logs/20210217T204139/Express0013.lzma
2021-02-17T20:58:42.515Z,1613595522.515 [DataOverHttps](INFO): Moved sent file to Logs/20210217T204139/Express0013.lzma.bak
2021-02-17T20:58:42.515Z,1613595522.515 [DataOverHttps](INFO): SBD MOMSN=15232762
2021-02-17T20:58:44.653Z,1613595524.653 [keepstation:NeedComms:D] Stopped
2021-02-17T20:58:44.654Z,1613595524.654 [keepstation:NeedComms:E] Running Loop=1
2021-02-17T20:58:45.021Z,1613595525.021 [keepstation:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix
2021-02-17T20:58:46.592Z,1613595526.592 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205845.00,A,3648.46578,N,12148.82436,W,6.531,97.26,170221,,,D*4F
2021-02-17T20:58:46.606Z,1613595526.606 [NAL9602](INFO): GPS fix at 20210217T205845: (36.807763, -121.813739)
2021-02-17T20:58:46.678Z,1613595526.678 [keepstation:NeedComms:E] Stopped
2021-02-17T20:58:46.678Z,1613595526.678 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2021-02-17T20:58:46.678Z,1613595526.678 [keepstation:NeedComms] Stopped
2021-02-17T20:58:46.679Z,1613595526.679 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2021-02-17T20:58:46.679Z,1613595526.679 [keepstation:NeedComms:B.GoToSurface] Stopped
2021-02-17T20:58:46.679Z,1613595526.679 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-02-17T20:58:47.051Z,1613595527.051 [keepstation:StartingMission](INFO): Completed keepstation:StartingMission
2021-02-17T20:58:47.051Z,1613595527.051 [keepstation:StartingMission] Stopped
2021-02-17T20:58:47.051Z,1613595527.051 [keepstation:StartingMission](DEBUG): Aggregate::uninitialize keepstation:StartingMission
2021-02-17T20:58:47.051Z,1613595527.051 [keepstation:TransitToStation] Running Loop=1
2021-02-17T20:58:47.051Z,1613595527.051 [keepstation:TransitToStation](DEBUG): Aggregate::initialize keepstation:TransitToStation
2021-02-17T20:58:47.051Z,1613595527.051 [keepstation:TransitToStation:A.Buoyancy] Running Loop=1
2021-02-17T20:58:47.052Z,1613595527.052 [keepstation:TransitToStation:A.Buoyancy](DEBUG): Initialize Buoyancy Component.
2021-02-17T20:58:47.052Z,1613595527.052 [keepstation:TransitToStation:B.Pitch] Running Loop=1
2021-02-17T20:58:47.052Z,1613595527.052 [keepstation:TransitToStation:B.Pitch](DEBUG): Initialize.
2021-02-17T20:58:47.052Z,1613595527.052 [keepstation:TransitToStation:C.SetSpeed] Running Loop=1
2021-02-17T20:58:47.052Z,1613595527.052 [keepstation:TransitToStation:C.SetSpeed](DEBUG): Initialize.
2021-02-17T20:58:47.052Z,1613595527.052 [keepstation:TransitToStation:Wpt1.Waypoint] Running Loop=1
2021-02-17T20:58:47.052Z,1613595527.052 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2021-02-17T20:58:47.423Z,1613595527.423 [keepstation:TransitToStation:C.SetSpeed] Running Loop=1
2021-02-17T20:58:47.423Z,1613595527.423 [keepstation:TransitToStation:B.Pitch] Running Loop=1
2021-02-17T20:58:47.423Z,1613595527.423 [keepstation:TransitToStation:A.Buoyancy] Running Loop=1
2021-02-17T20:58:53.677Z,1613595533.677 [Reporter](INFO): NAL9602.sigQuality 5 count
2021-02-17T20:59:01.942Z,1613595541.942 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=12479, MT Status=1, MTMSN=740
2021-02-17T20:59:01.942Z,1613595541.942 [NAL9602](INFO): Data available in MT queue
2021-02-17T20:59:02.501Z,1613595542.501 [NAL9602](INFO): Received command:load Transport/keepstation_3km.xml;set keepstation.Speed 1 meter_per_second;run
2021-02-17T20:59:02.523Z,1613595542.523 [CommandLine](IMPORTANT): got command load ./Missions/Transport/keepstation_3km.xml
2021-02-17T20:59:02.523Z,1613595542.523 [MissionManager](INFO): Uninitializing Mission keepstation
2021-02-17T20:59:02.523Z,1613595542.523 [keepstation] Stopped
2021-02-17T20:59:02.524Z,1613595542.524 [keepstation](DEBUG): Aggregate::uninitialize keepstation
2021-02-17T20:59:02.524Z,1613595542.524 [keepstation:StandardEnvelopes] Stopped
2021-02-17T20:59:02.524Z,1613595542.524 [keepstation:StandardEnvelopes](DEBUG): Aggregate::uninitialize keepstation:StandardEnvelopes
2021-02-17T20:59:02.524Z,1613595542.524 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2021-02-17T20:59:02.524Z,1613595542.524 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2021-02-17T20:59:02.524Z,1613595542.524 [keepstation:StandardEnvelopes:B.DepthEnvelope] Stopped
2021-02-17T20:59:02.524Z,1613595542.524 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2021-02-17T20:59:02.524Z,1613595542.524 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2021-02-17T20:59:02.524Z,1613595542.524 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2021-02-17T20:59:02.524Z,1613595542.524 [keepstation:F.Pitch] Stopped
2021-02-17T20:59:02.524Z,1613595542.524 [keepstation:TransitToStation] Stopped
2021-02-17T20:59:02.524Z,1613595542.524 [keepstation:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:TransitToStation
2021-02-17T20:59:02.524Z,1613595542.524 [keepstation:TransitToStation:A.Buoyancy] Stopped
2021-02-17T20:59:02.525Z,1613595542.525 [keepstation:TransitToStation:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2021-02-17T20:59:02.525Z,1613595542.525 [keepstation:TransitToStation:B.Pitch] Stopped
2021-02-17T20:59:02.525Z,1613595542.525 [keepstation:TransitToStation:C.SetSpeed] Stopped
2021-02-17T20:59:02.525Z,1613595542.525 [keepstation:TransitToStation:C.SetSpeed](DEBUG): Uninitialize.
2021-02-17T20:59:02.525Z,1613595542.525 [keepstation:TransitToStation:Wpt1.Waypoint] Stopped
2021-02-17T20:59:02.525Z,1613595542.525 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2021-02-17T20:59:02.537Z,1613595542.537 [MissionManager](INFO): Loading Mission: ./Missions/Transport/keepstation_3km.xml
2021-02-17T20:59:02.578Z,1613595542.578 [MissionManager](INFO): Inserting Stack: Missions/Transport/keepstation.xml
2021-02-17T20:59:02.668Z,1613595542.668 [MissionManager](INFO): DefineArg keepstation_3km:keepstation.MissionTimeout = 4.000000 h
2021-02-17T20:59:02.670Z,1613595542.670 [MissionManager](INFO): DefineArg keepstation_3km:keepstation.NeedCommsTime = 45.000000 min
2021-02-17T20:59:02.672Z,1613595542.672 [MissionManager](INFO): DefineArg keepstation_3km:keepstation.Latitude = nan arcdeg
2021-02-17T20:59:02.675Z,1613595542.675 [MissionManager](INFO): DefineArg keepstation_3km:keepstation.Longitude = nan arcdeg
2021-02-17T20:59:02.677Z,1613595542.677 [MissionManager](INFO): DefineArg keepstation_3km:keepstation.Depth = 30.000000 m
2021-02-17T20:59:02.679Z,1613595542.679 [MissionManager](INFO): DefineArg keepstation_3km:keepstation.ApproachDepth = 15.000000 m
2021-02-17T20:59:02.681Z,1613595542.681 [MissionManager](INFO): DefineArg keepstation_3km:keepstation.DepthDeadband = 4.000000 m
2021-02-17T20:59:02.683Z,1613595542.683 [MissionManager](INFO): DefineArg keepstation_3km:keepstation.Speed = 0.750000 m/s
2021-02-17T20:59:02.686Z,1613595542.686 [MissionManager](INFO): DefineArg keepstation_3km:keepstation.Radius = 200.000000 m
2021-02-17T20:59:02.688Z,1613595542.688 [MissionManager](INFO): DefineArg keepstation_3km:keepstation.MaxDepth = 45.000000 m
2021-02-17T20:59:02.690Z,1613595542.690 [MissionManager](INFO): DefineArg keepstation_3km:keepstation.MinOffshore = 2.000000 km
2021-02-17T20:59:02.691Z,1613595542.691 [MissionManager](INFO): Inserting Stack: Missions/Insert/NeedComms.xml
2021-02-17T20:59:02.785Z,1613595542.785 [MissionManager](INFO): DefineArg keepstation_3km:keepstation:NeedComms.DiveInterval = 3.000000 h
2021-02-17T20:59:02.787Z,1613595542.787 [MissionManager](INFO): DefineArg keepstation_3km:keepstation:NeedComms.WaitForPitchUp = 10.000000 min
2021-02-17T20:59:02.790Z,1613595542.790 [MissionManager](INFO): DefineArg keepstation_3km:keepstation:NeedComms.SurfacePitch = 20.000000 arcdeg
2021-02-17T20:59:02.792Z,1613595542.792 [MissionManager](INFO): DefineArg keepstation_3km:keepstation:NeedComms.SurfaceDepthRate = nan m/s
2021-02-17T20:59:02.794Z,1613595542.794 [MissionManager](INFO): DefineArg keepstation_3km:keepstation:NeedComms.SurfaceSpeed = 1.000000 m/s
2021-02-17T20:59:02.796Z,1613595542.796 [MissionManager](INFO): DefineArg keepstation_3km:keepstation:NeedComms.GPSTimeout = 7.000000 min
2021-02-17T20:59:02.799Z,1613595542.799 [MissionManager](INFO): DefineArg keepstation_3km:keepstation:NeedComms.CommsTimeout = 30.000000 min
2021-02-17T20:59:02.804Z,1613595542.804 [keepstation_3km:keepstation:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2021-02-17T20:59:02.851Z,1613595542.851 [MissionManager](INFO): Inserting Stack: Missions/Insert/StandardEnvelopes.xml
2021-02-17T20:59:02.872Z,1613595542.872 [MissionManager](INFO): DefineArg keepstation_3km:keepstation:StandardEnvelopes.MinAltitude = 5.000000 m
2021-02-17T20:59:02.875Z,1613595542.875 [MissionManager](INFO): DefineArg keepstation_3km:keepstation:StandardEnvelopes.MaxDepth = 200.000000 m
2021-02-17T20:59:02.877Z,1613595542.877 [MissionManager](INFO): DefineArg keepstation_3km:keepstation:StandardEnvelopes.MinOffshore = 2000.000000 m
2021-02-17T20:59:02.878Z,1613595542.878 [keepstation_3km:keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2021-02-17T20:59:02.883Z,1613595542.883 [keepstation_3km:keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2021-02-17T20:59:02.890Z,1613595542.890 [keepstation_3km:keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2021-02-17T20:59:02.903Z,1613595542.903 [keepstation_3km:keepstation:F.Pitch](DEBUG): Construct.
2021-02-17T20:59:02.930Z,1613595542.930 [keepstation_3km:keepstation:TransitToStation:A.Buoyancy](DEBUG): Construct Buoyancy.
2021-02-17T20:59:02.936Z,1613595542.936 [keepstation_3km:keepstation:TransitToStation:B.Pitch](DEBUG): Construct.
2021-02-17T20:59:02.940Z,1613595542.940 [keepstation_3km:keepstation:TransitToStation:C.SetSpeed](DEBUG): Construct.
2021-02-17T20:59:02.943Z,1613595542.943 [keepstation_3km:keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2021-02-17T20:59:02.950Z,1613595542.950 [keepstation_3km:keepstation:KeepStation:B.Pitch](DEBUG): Construct.
2021-02-17T20:59:02.955Z,1613595542.955 [keepstation_3km:keepstation:KeepStation:C.KeepStation](DEBUG): Construct KeepStation.
2021-02-17T20:59:02.959Z,1613595542.959 [keepstation_3km:keepstation:KeepStation:D.Wait](DEBUG): Construct Wait.
2021-02-17T20:59:02.972Z,1613595542.972 [MissionManager](INFO): RedefineArg keepstation_3km:keepstation.Latitude = value:36.806966 arcdeg
2021-02-17T20:59:02.974Z,1613595542.974 [MissionManager](INFO): RedefineArg keepstation_3km:keepstation.Longitude = value:-121.824326 arcdeg
2021-02-17T20:59:02.984Z,1613595542.984 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="utf-8"?>
<Mission xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd"
         Id="keepstation_3km">

    <Description>
        Vehicle keeps station at waypoint 3km WNW of Moss Landing.
    </Description>

    <Insert Filename="Transport/keepstation.xml">
        <RedefineArg Name="Latitude"><Units:degree/><Value>36.806966</Value></RedefineArg>
        <RedefineArg Name="Longitude"><Units:degree/><Value>-121.824326</Value></RedefineArg>
    </Insert>

</Mission>


2021-02-17T20:59:02.984Z,1613595542.984 [CommandLine](IMPORTANT): Loaded ./Missions/Transport/keepstation_3km.xml
2021-02-17T20:59:04.947Z,1613595544.947 [CommandLine](IMPORTANT): got command set keepstation.Speed 1.000000 meter_per_second
2021-02-17T20:59:04.950Z,1613595544.950 [CommandLine](IMPORTANT): got command run
2021-02-17T20:59:05.166Z,1613595545.166 [MissionManager](IMPORTANT): Started mission keepstation_3km
2021-02-17T20:59:05.167Z,1613595545.167 [keepstation_3km] Running Loop=1
2021-02-17T20:59:05.167Z,1613595545.167 [keepstation_3km](DEBUG): Aggregate::initialize keepstation_3km
2021-02-17T20:59:05.167Z,1613595545.167 [keepstation_3km:keepstation] Running Loop=1
2021-02-17T20:59:05.167Z,1613595545.167 [keepstation_3km:keepstation](DEBUG): Aggregate::initialize keepstation_3km:keepstation
2021-02-17T20:59:05.167Z,1613595545.167 [keepstation_3km:keepstation:StandardEnvelopes] Running Loop=1
2021-02-17T20:59:05.167Z,1613595545.167 [keepstation_3km:keepstation:StandardEnvelopes](DEBUG): Aggregate::initialize keepstation_3km:keepstation:StandardEnvelopes
2021-02-17T20:59:05.167Z,1613595545.167 [keepstation_3km:keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2021-02-17T20:59:05.167Z,1613595545.167 [keepstation_3km:keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2021-02-17T20:59:05.168Z,1613595545.168 [keepstation_3km:keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2021-02-17T20:59:05.168Z,1613595545.168 [keepstation_3km:keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2021-02-17T20:59:05.168Z,1613595545.168 [keepstation_3km:keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2021-02-17T20:59:05.168Z,1613595545.168 [keepstation_3km:keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2021-02-17T20:59:05.168Z,1613595545.168 [keepstation_3km:keepstation:F.Pitch] Running Loop=1
2021-02-17T20:59:05.168Z,1613595545.168 [keepstation_3km:keepstation:F.Pitch](DEBUG): Initialize.
2021-02-17T20:59:05.168Z,1613595545.168 [keepstation_3km:keepstation:B] Running Loop=1
2021-02-17T20:59:05.169Z,1613595545.169 [keepstation_3km:keepstation:F.Pitch] Running Loop=1
2021-02-17T20:59:05.169Z,1613595545.169 [keepstation_3km:keepstation:StandardEnvelopes] Running Loop=1
2021-02-17T20:59:05.170Z,1613595545.170 [keepstation_3km:keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2021-02-17T20:59:05.170Z,1613595545.170 [keepstation_3km:keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2021-02-17T20:59:05.170Z,1613595545.170 [keepstation_3km:keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2021-02-17T20:59:05.171Z,1613595545.171 [keepstation_3km:keepstation:B] Stopped
2021-02-17T20:59:05.171Z,1613595545.171 [keepstation_3km:keepstation:D] Running Loop=1
2021-02-17T20:59:05.346Z,1613595545.346 [keepstation_3km:keepstation:D] Stopped
2021-02-17T20:59:05.346Z,1613595545.346 [keepstation_3km:keepstation:E] Running Loop=1
2021-02-17T20:59:05.850Z,1613595545.850 [keepstation_3km:keepstation:E] Stopped
2021-02-17T20:59:05.850Z,1613595545.850 [keepstation_3km:keepstation:StartingMission] Running Loop=1
2021-02-17T20:59:05.850Z,1613595545.850 [keepstation_3km:keepstation:StartingMission](DEBUG): Aggregate::initialize keepstation_3km:keepstation:StartingMission
2021-02-17T20:59:06.221Z,1613595546.221 [keepstation_3km:keepstation:NeedComms] Running Loop=1
2021-02-17T20:59:06.224Z,1613595546.224 [keepstation_3km:keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation_3km:keepstation:NeedComms
2021-02-17T20:59:06.224Z,1613595546.224 [keepstation_3km:keepstation:NeedComms:B.GoToSurface] Running Loop=1
2021-02-17T20:59:06.233Z,1613595546.233 [keepstation_3km:keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-02-17T20:59:06.233Z,1613595546.233 [keepstation_3km:keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2021-02-17T20:59:06.234Z,1613595546.234 [keepstation_3km:keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2021-02-17T20:59:06.234Z,1613595546.234 [keepstation_3km:keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2021-02-17T20:59:06.234Z,1613595546.234 [keepstation_3km:keepstation:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-02-17T20:59:06.235Z,1613595546.235 [keepstation_3km:keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-02-17T20:59:06.235Z,1613595546.235 [keepstation_3km:keepstation:NeedComms:A] Running Loop=1
2021-02-17T20:59:06.236Z,1613595546.236 [keepstation_3km:keepstation:NeedComms:A](INFO): last time_fix was: 1613595525.000000 second since 1970/01/01T00:00:00Z 
2021-02-17T20:59:06.236Z,1613595546.236 [keepstation_3km:keepstation:NeedComms:A] Stopped
2021-02-17T20:59:06.295Z,1613595546.295 [CommandLine](IMPORTANT): Running 
2021-02-17T20:59:06.534Z,1613595546.534 [keepstation_3km:keepstation:NeedComms:C] Running Loop=1
2021-02-17T20:59:06.954Z,1613595546.954 [keepstation_3km:keepstation:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2021-02-17T20:59:08.561Z,1613595548.561 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205907.00,A,3648.45877,N,12148.77456,W,6.803,102.44,170221,,,D*70
2021-02-17T20:59:08.564Z,1613595548.564 [NAL9602](INFO): GPS fix at 20210217T205907: (36.807646, -121.812909)
2021-02-17T20:59:08.587Z,1613595548.587 [keepstation_3km:keepstation:NeedComms:C] Stopped
2021-02-17T20:59:08.587Z,1613595548.587 [keepstation_3km:keepstation:NeedComms:D] Running Loop=1
2021-02-17T20:59:08.984Z,1613595548.984 [keepstation_3km:keepstation:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications
2021-02-17T20:59:41.270Z,1613595581.270 [NAL9602](INFO): Not Powering down - fast GPS
2021-02-17T20:59:41.312Z,1613595581.312 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20210217T204139/Courier0015.lzma
2021-02-17T20:59:41.323Z,1613595581.323 [Reporter](INFO): NAL9602.sigQuality 0 count
2021-02-17T20:59:42.315Z,1613595582.315 [DataOverHttps](INFO): Moved sent file to Logs/20210217T204139/Courier0015.lzma.bak
2021-02-17T20:59:42.315Z,1613595582.315 [DataOverHttps](INFO): SBD MOMSN=15232780
2021-02-17T21:01:11.825Z,1613595671.825 [CommandLine](IMPORTANT): got command failComponent hardware NAL9602
2021-02-17T21:01:11.826Z,1613595671.826 [NAL9602] Hardware Fault, FailCount= 1
2021-02-17T21:01:11.826Z,1613595671.826 [NAL9602](ERROR): Hardware Fault
2021-02-17T21:01:11.826Z,1613595671.826 [CommandLine](IMPORTANT): NAL9602 failureMode is Hardware Fault
2021-02-17T21:01:12.166Z,1613595672.166 [NAL9602](INFO): Powering down
2021-02-17T21:01:12.215Z,1613595672.215 [CBIT](ERROR): Hardware Fault in component: NAL9602
2021-02-17T21:01:12.216Z,1613595672.216 [Reporter](INFO): NAL9602.sigQuality no_value
2021-02-17T21:01:12.793Z,1613595672.793 [DataOverHttps](INFO): Sending 270 bytes from file Logs/20210217T204139/Express0016.lzma
2021-02-17T21:01:13.019Z,1613595673.019 [CBIT](INFO): Clearing failed state for component NAL9602
2021-02-17T21:01:13.019Z,1613595673.019 [NAL9602] No Fault, FailCount= 1
2021-02-17T21:01:13.797Z,1613595673.797 [DataOverHttps](INFO): Moved sent file to Logs/20210217T204139/Express0016.lzma.bak
2021-02-17T21:01:13.797Z,1613595673.797 [DataOverHttps](INFO): SBD MOMSN=15232790
2021-02-17T21:01:14.883Z,1613595674.883 [CommandLine](IMPORTANT): got command failComponent hardware NAL9602
2021-02-17T21:01:14.883Z,1613595674.883 [NAL9602] Hardware Fault, FailCount= 2
2021-02-17T21:01:14.883Z,1613595674.883 [NAL9602](ERROR): Hardware Fault
2021-02-17T21:01:14.883Z,1613595674.883 [CommandLine](IMPORTANT): NAL9602 failureMode is Hardware Fault
2021-02-17T21:01:14.998Z,1613595674.998 [NAL9602](INFO): Powering down
2021-02-17T21:01:15.084Z,1613595675.084 [CBIT](ERROR): Hardware Fault in component: NAL9602
2021-02-17T21:01:15.887Z,1613595675.887 [keepstation_3km:keepstation:NeedComms:D] Stopped
2021-02-17T21:01:15.888Z,1613595675.888 [keepstation_3km:keepstation:NeedComms:E] Running Loop=1
2021-02-17T21:01:15.921Z,1613595675.921 [CBIT](INFO): Clearing failed state for component NAL9602
2021-02-17T21:01:15.925Z,1613595675.925 [NAL9602] No Fault, FailCount= 2
2021-02-17T21:01:16.251Z,1613595676.251 [keepstation_3km:keepstation:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix
2021-02-17T21:01:18.806Z,1613595678.806 [CommandLine](IMPORTANT): got command failComponent hardware NAL9602
2021-02-17T21:01:18.806Z,1613595678.806 [NAL9602] Hardware Fault, FailCount= 3
2021-02-17T21:01:18.806Z,1613595678.806 [NAL9602](ERROR): Hardware Fault
2021-02-17T21:01:18.807Z,1613595678.807 [CommandLine](IMPORTANT): NAL9602 failureMode is Hardware Fault
2021-02-17T21:01:19.034Z,1613595679.034 [NAL9602](INFO): Powering down
2021-02-17T21:01:19.123Z,1613595679.123 [CBIT](ERROR): Hardware Fault in component: NAL9602
2021-02-17T21:01:19.933Z,1613595679.933 [CBIT](INFO): Clearing failed state for component NAL9602
2021-02-17T21:01:19.933Z,1613595679.933 [NAL9602] No Fault, FailCount= 3
2021-02-17T21:01:21.055Z,1613595681.055 [CommandLine](IMPORTANT): got command failComponent hardware NAL9602
2021-02-17T21:01:21.055Z,1613595681.055 [NAL9602] Hardware Fault, FailCount= 4
2021-02-17T21:01:21.055Z,1613595681.055 [NAL9602](ERROR): Hardware Fault
2021-02-17T21:01:21.056Z,1613595681.056 [CommandLine](IMPORTANT): NAL9602 failureMode is Hardware Fault
2021-02-17T21:01:21.070Z,1613595681.070 [NAL9602](INFO): Powering down
2021-02-17T21:01:21.177Z,1613595681.177 [CBIT](ERROR): Hardware Fault in component: NAL9602
2021-02-17T21:01:21.903Z,1613595681.903 [CBIT](INFO): Clearing failed state for component NAL9602
2021-02-17T21:01:21.903Z,1613595681.903 [NAL9602] No Fault, FailCount= 4
2021-02-17T21:01:23.769Z,1613595683.769 [CommandLine](IMPORTANT): got command failComponent hardware NAL9602
2021-02-17T21:01:23.769Z,1613595683.769 [NAL9602] Hardware Fault, FailCount= 5
2021-02-17T21:01:23.769Z,1613595683.769 [NAL9602](ERROR): Hardware Fault
2021-02-17T21:01:23.769Z,1613595683.769 [CommandLine](IMPORTANT): NAL9602 failureMode is Hardware Fault
2021-02-17T21:01:23.882Z,1613595683.882 [NAL9602](INFO): Powering down
2021-02-17T21:01:23.938Z,1613595683.938 [CBIT](ERROR): Hardware Fault in component: NAL9602
2021-02-17T21:01:23.938Z,1613595683.938 [CBIT](CRITICAL): Hardware Fault in component: NAL9602
2021-02-17T21:01:24.310Z,1613595684.310 [CommandLine](FAULT): Scheduling is paused for 1 commands
2021-02-17T21:01:24.310Z,1613595684.310 [CBIT](INFO): Critical error at 20210217T210123
2021-02-17T21:01:24.311Z,1613595684.311 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2021-02-17T21:01:24.733Z,1613595684.733 [MissionManager](INFO): MissionManager is completed.
2021-02-17T21:01:24.734Z,1613595684.734 [MissionManager](INFO): Uninitializing Mission keepstation_3km
2021-02-17T21:01:24.734Z,1613595684.734 [keepstation_3km] Stopped
2021-02-17T21:01:24.734Z,1613595684.734 [keepstation_3km](DEBUG): Aggregate::uninitialize keepstation_3km
2021-02-17T21:01:24.734Z,1613595684.734 [keepstation_3km:keepstation] Stopped
2021-02-17T21:01:24.734Z,1613595684.734 [keepstation_3km:keepstation](DEBUG): Aggregate::uninitialize keepstation_3km:keepstation
2021-02-17T21:01:24.734Z,1613595684.734 [keepstation_3km:keepstation:NeedComms] Stopped
2021-02-17T21:01:24.734Z,1613595684.734 [keepstation_3km:keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation_3km:keepstation:NeedComms
2021-02-17T21:01:24.734Z,1613595684.734 [keepstation_3km:keepstation:NeedComms:B.GoToSurface] Stopped
2021-02-17T21:01:24.734Z,1613595684.734 [keepstation_3km:keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-02-17T21:01:24.734Z,1613595684.734 [keepstation_3km:keepstation:NeedComms:E] Stopped
2021-02-17T21:01:24.734Z,1613595684.734 [keepstation_3km:keepstation:StandardEnvelopes] Stopped
2021-02-17T21:01:24.734Z,1613595684.734 [keepstation_3km:keepstation:StandardEnvelopes](DEBUG): Aggregate::uninitialize keepstation_3km:keepstation:StandardEnvelopes
2021-02-17T21:01:24.735Z,1613595684.735 [keepstation_3km:keepstation:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2021-02-17T21:01:24.735Z,1613595684.735 [keepstation_3km:keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2021-02-17T21:01:24.735Z,1613595684.735 [keepstation_3km:keepstation:StandardEnvelopes:B.DepthEnvelope] Stopped
2021-02-17T21:01:24.735Z,1613595684.735 [keepstation_3km:keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2021-02-17T21:01:24.735Z,1613595684.735 [keepstation_3km:keepstation:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2021-02-17T21:01:24.735Z,1613595684.735 [keepstation_3km:keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2021-02-17T21:01:24.735Z,1613595684.735 [keepstation_3km:keepstation:F.Pitch] Stopped
2021-02-17T21:01:24.735Z,1613595684.735 [keepstation_3km:keepstation:StartingMission] Stopped
2021-02-17T21:01:24.735Z,1613595684.735 [keepstation_3km:keepstation:StartingMission](DEBUG): Aggregate::uninitialize keepstation_3km:keepstation:StartingMission
2021-02-17T21:01:25.109Z,1613595685.109 [MissionManager](IMPORTANT): Started mission Default
2021-02-17T21:01:25.109Z,1613595685.109 [Default] Running Loop=1
2021-02-17T21:01:25.109Z,1613595685.109 [Default](DEBUG): Aggregate::initialize Default
2021-02-17T21:01:25.110Z,1613595685.110 [Default:B.GoToSurface] Running Loop=1
2021-02-17T21:01:25.110Z,1613595685.110 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-02-17T21:01:25.110Z,1613595685.110 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-02-17T21:01:25.110Z,1613595685.110 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-02-17T21:01:25.111Z,1613595685.111 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-02-17T21:01:25.111Z,1613595685.111 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-02-17T21:01:25.111Z,1613595685.111 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-02-17T21:01:25.111Z,1613595685.111 [Default:A.Wait] Running Loop=1
2021-02-17T21:01:25.112Z,1613595685.112 [Default:A.Wait](DEBUG): Initialize Wait Component.
2021-02-17T21:01:38.448Z,1613595698.448 [Default:A.Wait](INFO): Done Waiting.
2021-02-17T21:01:38.448Z,1613595698.448 [Default:A.Wait] Stopped
2021-02-17T21:01:38.448Z,1613595698.448 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2021-02-17T21:01:38.884Z,1613595698.884 [Default:CheckIn] Running Loop=1
2021-02-17T21:01:38.884Z,1613595698.884 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-02-17T21:01:38.884Z,1613595698.884 [Default:CheckIn:Read_GPS] Running Loop=1
2021-02-17T21:01:47.818Z,1613595707.818 [CommandLine](IMPORTANT): got command failComponent hardware NAL9602
2021-02-17T21:01:47.818Z,1613595707.818 [CommandLine](IMPORTANT): NAL9602 failureMode is Hardware Fault
2021-02-17T21:01:57.273Z,1613595717.273 [CommandLine](IMPORTANT): got command gfscan
2021-02-17T21:01:57.437Z,1613595717.437 [CBIT](IMPORTANT): Beginning ground fault scan
2021-02-17T21:02:08.374Z,1613595728.374 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.153702
CHAN A1 (24V): 0.271800
CHAN A2 (12V): 0.123639
CHAN A3 (5V): 0.045421
CHAN B0 (3.3V): 0.032648
CHAN B1 (3.15aV): 0.031569
CHAN B2 (3.15bV): 0.032016
CHAN B3 (GND): 0.002166
OPEN: 0.013823
Full Scale Calc: 4.765 mA, -1.589 mA
2021-02-17T21:02:26.609Z,1613595746.609 [CommandLine](IMPORTANT): got command ! echo i > /dev/loadB7
2021-02-17T21:02:31.312Z,1613595751.312 [CommandLine](IMPORTANT): got command gfscan
2021-02-17T21:02:31.428Z,1613595751.428 [CBIT](IMPORTANT): Beginning ground fault scan
2021-02-17T21:02:42.304Z,1613595762.304 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.152990
CHAN A1 (24V): 0.271782
CHAN A2 (12V): 0.122359
CHAN A3 (5V): 0.043739
CHAN B0 (3.3V): 0.032110
CHAN B1 (3.15aV): 0.031897
CHAN B2 (3.15bV): 0.031586
CHAN B3 (GND): 0.001191
OPEN: 0.013734
Full Scale Calc: 4.765 mA, -1.589 mA
2021-02-17T21:02:55.772Z,1613595775.772 [CommandLine](IMPORTANT): got command ! echo i > /dev/loadB3
2021-02-17T21:02:59.615Z,1613595779.615 [CommandLine](IMPORTANT): got command gfscan
2021-02-17T21:02:59.727Z,1613595779.727 [CBIT](IMPORTANT): Beginning ground fault scan
2021-02-17T21:03:10.589Z,1613595790.589 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.152354
CHAN A1 (24V): 0.269953
CHAN A2 (12V): 0.121875
CHAN A3 (5V): 0.043701
CHAN B0 (3.3V): 0.032255
CHAN B1 (3.15aV): 0.030847
CHAN B2 (3.15bV): 0.031318
CHAN B3 (GND): 0.001047
OPEN: 0.013693
Full Scale Calc: 4.765 mA, -1.589 mA
2021-02-17T21:03:18.800Z,1613595798.800 [CommandLine](IMPORTANT): got command failComponent hardware RDI_Pathfinder
2021-02-17T21:03:18.801Z,1613595798.801 [RDI_Pathfinder] Hardware Fault, FailCount= 1
2021-02-17T21:03:18.801Z,1613595798.801 [RDI_Pathfinder](ERROR): Hardware Fault
2021-02-17T21:03:18.801Z,1613595798.801 [CommandLine](IMPORTANT): RDI_Pathfinder failureMode is Hardware Fault
2021-02-17T21:03:19.109Z,1613595799.109 [RDI_Pathfinder](INFO): Powering down
2021-02-17T21:03:19.157Z,1613595799.157 [CBIT](ERROR): Hardware Fault in component: RDI_Pathfinder
2021-02-17T21:03:19.858Z,1613595799.858 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2021-02-17T21:03:19.858Z,1613595799.858 [RDI_Pathfinder] No Fault, FailCount= 1
2021-02-17T21:03:20.920Z,1613595800.920 [CommandLine](IMPORTANT): got command failComponent hardware RDI_Pathfinder
2021-02-17T21:03:20.920Z,1613595800.920 [RDI_Pathfinder] Hardware Fault, FailCount= 2
2021-02-17T21:03:20.920Z,1613595800.920 [RDI_Pathfinder](ERROR): Hardware Fault
2021-02-17T21:03:20.921Z,1613595800.921 [CommandLine](IMPORTANT): RDI_Pathfinder failureMode is Hardware Fault
2021-02-17T21:03:21.125Z,1613595801.125 [RDI_Pathfinder](INFO): Powering down
2021-02-17T21:03:21.176Z,1613595801.176 [CBIT](ERROR): Hardware Fault in component: RDI_Pathfinder
2021-02-17T21:03:21.895Z,1613595801.895 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2021-02-17T21:03:21.895Z,1613595801.895 [RDI_Pathfinder] No Fault, FailCount= 2
2021-02-17T21:03:22.840Z,1613595802.840 [CommandLine](IMPORTANT): got command failComponent hardware RDI_Pathfinder
2021-02-17T21:03:22.840Z,1613595802.840 [RDI_Pathfinder] Hardware Fault, FailCount= 3
2021-02-17T21:03:22.840Z,1613595802.840 [RDI_Pathfinder](ERROR): Hardware Fault
2021-02-17T21:03:22.841Z,1613595802.841 [CommandLine](IMPORTANT): RDI_Pathfinder failureMode is Hardware Fault
2021-02-17T21:03:23.145Z,1613595803.145 [RDI_Pathfinder](INFO): Powering down
2021-02-17T21:03:23.166Z,1613595803.166 [CBIT](ERROR): Hardware Fault in component: RDI_Pathfinder
2021-02-17T21:03:23.166Z,1613595803.166 [CBIT](CRITICAL): Hardware Fault in component: RDI_Pathfinder
2021-02-17T21:03:23.504Z,1613595803.504 [CBIT](INFO): Critical error at 20210217T210323
2021-02-17T21:03:23.917Z,1613595803.917 [CBIT](INFO): Clearing failed state for component NAL9602
2021-02-17T21:03:23.917Z,1613595803.917 [NAL9602] No Fault, FailCount= 5
2021-02-17T21:03:24.290Z,1613595804.290 [NAL9602](INFO): Powering up NAL9602
2021-02-17T21:03:24.650Z,1613595804.650 [CommandLine](IMPORTANT): got command failComponent hardware RDI_Pathfinder
2021-02-17T21:03:24.650Z,1613595804.650 [CommandLine](IMPORTANT): RDI_Pathfinder failureMode is Hardware Fault
2021-02-17T21:03:27.640Z,1613595807.640 [CommandLine](IMPORTANT): got command failComponent hardware RDI_Pathfinder
2021-02-17T21:03:27.640Z,1613595807.640 [CommandLine](IMPORTANT): RDI_Pathfinder failureMode is Hardware Fault
2021-02-17T21:03:34.419Z,1613595814.419 [Reporter](INFO): NAL9602.sigQuality 0 count
2021-02-17T21:03:35.194Z,1613595815.194 [NAL9602](INFO): NAL9602 initialized
2021-02-17T21:03:36.928Z,1613595816.928 [CommandLine](IMPORTANT): got command ! echo i > /dev/loadB4
2021-02-17T21:03:43.721Z,1613595823.721 [Reporter](INFO): NAL9602.sigQuality 2 count
2021-02-17T21:03:45.881Z,1613595825.881 [CommandLine](IMPORTANT): got command gfscan
2021-02-17T21:03:46.127Z,1613595826.127 [CBIT](IMPORTANT): Beginning ground fault scan
2021-02-17T21:03:55.394Z,1613595835.394 [NAL9602](INFO): SBD MO Status=2, MOMSN=12480, MT Status=2, MTMSN=0
2021-02-17T21:03:55.394Z,1613595835.394 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-02-17T21:03:57.058Z,1613595837.058 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.151010
CHAN A1 (24V): 0.266783
CHAN A2 (12V): 0.120375
CHAN A3 (5V): 0.043074
CHAN B0 (3.3V): 0.030969
CHAN B1 (3.15aV): 0.030153
CHAN B2 (3.15bV): 0.030903
CHAN B3 (GND): 0.001182
OPEN: 0.013108
Full Scale Calc: 4.765 mA, -1.589 mA
2021-02-17T21:03:59.472Z,1613595839.472 [Reporter](INFO): NAL9602.sigQuality 5 count
2021-02-17T21:04:01.860Z,1613595841.860 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=12480, MT Status=1, MTMSN=741
2021-02-17T21:04:01.860Z,1613595841.860 [NAL9602](INFO): Data available in MT queue
2021-02-17T21:04:02.345Z,1613595842.345 [NAL9602](INFO): Received command:stop
2021-02-17T21:04:02.360Z,1613595842.360 [CommandLine](IMPORTANT): got command stop
2021-02-17T21:04:02.361Z,1613595842.361 [CommandLine](IMPORTANT): Scheduling is paused for 1 commands
2021-02-17T21:04:21.656Z,1613595861.656 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210419.00,A,3648.30961,N,12148.09333,W,6.143,91.77,170221,,,A*4C
2021-02-17T21:04:21.658Z,1613595861.658 [NAL9602](INFO): GPS fix at 20210217T210419: (36.805160, -121.801556)
2021-02-17T21:04:21.672Z,1613595861.672 [Default:CheckIn:Read_GPS] Stopped
2021-02-17T21:04:21.672Z,1613595861.672 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-02-17T21:04:53.405Z,1613595893.405 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-02-17T21:04:54.417Z,1613595894.417 [NAL9602](INFO): Not Powering down - fast GPS
2021-02-17T21:04:54.438Z,1613595894.438 [Reporter](INFO): NAL9602.sigQuality 0 count
2021-02-17T21:05:09.672Z,1613595909.672 [DataOverHttps](INFO): Sending 161 bytes from file Logs/20210217T204139/Courier0018.lzma
2021-02-17T21:05:09.677Z,1613595909.677 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.469917
2021-02-17T21:05:15.091Z,1613595915.091 [Reporter](INFO): NAL9602.sigQuality 3 count
2021-02-17T21:05:18.393Z,1613595918.393 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003010
2021-02-17T21:05:22.336Z,1613595922.336 [NAL9602](INFO): SBD MO Status=1, MOMSN=12481, MT Status=0, MTMSN=0
2021-02-17T21:05:22.382Z,1613595922.382 [NAL9602](INFO): Sent 161 bytes from file Logs/20210217T204139/Courier0018.lzma
2021-02-17T21:05:22.382Z,1613595922.382 [NAL9602](INFO): Packets left to send: 0
2021-02-17T21:05:22.385Z,1613595922.385 [NAL9602](INFO): Failure count cleared after critical for NAL9602
2021-02-17T21:05:25.432Z,1613595925.432 [DataOverHttps](INFO): Sending 748 bytes from file Logs/20210217T204139/Express0019.lzma
2021-02-17T21:05:25.731Z,1613595925.731 [DataOverHttps](INFO): Moved sent file to Logs/20210217T204139/Express0019.lzma.bak
2021-02-17T21:05:25.731Z,1613595925.731 [DataOverHttps](INFO): SBD MOMSN=15232804
2021-02-17T21:05:27.595Z,1613595927.595 [Default:CheckIn:Read_Iridium] Stopped
2021-02-17T21:05:27.596Z,1613595927.596 [Default:CheckIn:C.Wait] Running Loop=1
2021-02-17T21:05:27.596Z,1613595927.596 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-02-17T21:05:39.704Z,1613595939.704 [CommandLine](IMPORTANT): got command ! echo i > /dev/loadC6
2021-02-17T21:05:42.895Z,1613595942.895 [CommandLine](IMPORTANT): got command gfscan
2021-02-17T21:05:42.969Z,1613595942.969 [CBIT](IMPORTANT): Beginning ground fault scan
2021-02-17T21:05:53.036Z,1613595953.036 [NAL9602](INFO): Not Powering down - fast GPS
2021-02-17T21:05:53.062Z,1613595953.062 [Reporter](INFO): NAL9602.sigQuality 0 count
2021-02-17T21:05:53.888Z,1613595953.888 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.147425
CHAN A1 (24V): 0.261541
CHAN A2 (12V): 0.117696
CHAN A3 (5V): 0.042458
CHAN B0 (3.3V): 0.030289
CHAN B1 (3.15aV): 0.030145
CHAN B2 (3.15bV): 0.030323
CHAN B3 (GND): 0.000141
OPEN: 0.013030
Full Scale Calc: 4.765 mA, -1.589 mA
2021-02-17T21:06:22.978Z,1613595982.978 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2021-02-17T21:06:22.978Z,1613595982.978 [RDI_Pathfinder] No Fault, FailCount= 3
2021-02-17T21:06:35.879Z,1613595995.879 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout.
2021-02-17T21:06:35.879Z,1613595995.879 [RDI_Pathfinder] Data Fault, FailCount= 1
2021-02-17T21:06:35.880Z,1613595995.880 [RDI_Pathfinder](ERROR): Data Fault
2021-02-17T21:06:35.908Z,1613595995.908 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder
2021-02-17T21:06:36.329Z,1613595996.329 [RDI_Pathfinder](INFO): Powering down
2021-02-17T21:06:37.097Z,1613595997.097 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2021-02-17T21:06:37.098Z,1613595997.098 [RDI_Pathfinder] No Fault, FailCount= 1
2021-02-17T21:06:52.034Z,1613596012.034 [RDI_Pathfinder](INFO): Failure count cleared after critical for RDI_Pathfinder
2021-02-17T21:10:28.324Z,1613596228.324 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-02-17T21:10:28.324Z,1613596228.324 [Default:CheckIn:C.Wait] Stopped
2021-02-17T21:10:28.324Z,1613596228.324 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-02-17T21:10:28.325Z,1613596228.325 [Default:CheckIn:D] Running Loop=1
2021-02-17T21:10:28.714Z,1613596228.714 [Default:CheckIn:D] Stopped
2021-02-17T21:10:28.714Z,1613596228.714 [Default:CheckIn:E] Running Loop=1
2021-02-17T21:10:29.111Z,1613596229.111 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 9.060067 min 
2021-02-17T21:10:29.111Z,1613596229.111 [Default:CheckIn:E] Stopped
2021-02-17T21:10:29.111Z,1613596229.111 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-02-17T21:10:29.111Z,1613596229.111 [Default:CheckIn] Stopped
2021-02-17T21:10:29.111Z,1613596229.111 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-02-17T21:10:29.111Z,1613596229.111 [Default:CheckIn](INFO): Running loop #2
2021-02-17T21:10:29.112Z,1613596229.112 [Default:CheckIn] Running Loop=2
2021-02-17T21:10:29.112Z,1613596229.112 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-02-17T21:10:29.112Z,1613596229.112 [Default:CheckIn:Read_GPS] Running Loop=1
2021-02-17T21:10:31.124Z,1613596231.124 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211028.00,A,3648.43854,N,12147.32360,W,5.268,54.01,170221,,,A*48
2021-02-17T21:10:31.126Z,1613596231.126 [NAL9602](INFO): GPS fix at 20210217T211028: (36.807309, -121.788727)
2021-02-17T21:10:31.210Z,1613596231.210 [Default:CheckIn:Read_GPS] Stopped
2021-02-17T21:10:31.210Z,1613596231.210 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-02-17T21:10:39.877Z,1613596239.877 [Reporter](INFO): NAL9602.sigQuality 1 count
2021-02-17T21:10:42.588Z,1613596242.588 [Reporter](INFO): NAL9602.sigQuality 4 count
2021-02-17T21:10:43.784Z,1613596243.784 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20210217T204139/Courier0021.lzma
2021-02-17T21:10:44.787Z,1613596244.787 [DataOverHttps](INFO): Moved sent file to Logs/20210217T204139/Courier0021.lzma.bak
2021-02-17T21:10:44.787Z,1613596244.787 [DataOverHttps](INFO): SBD MOMSN=15232857
2021-02-17T21:10:53.813Z,1613596253.813 [NAL9602](INFO): SBD MO Status=0, MOMSN=12482, MT Status=0, MTMSN=0
2021-02-17T21:10:53.813Z,1613596253.813 [NAL9602](INFO): No messages in MT queue
2021-02-17T21:11:02.289Z,1613596262.289 [DataOverHttps](INFO): Sending 448 bytes from file Logs/20210217T204139/Express0022.lzma
2021-02-17T21:11:03.291Z,1613596263.291 [DataOverHttps](INFO): Moved sent file to Logs/20210217T204139/Express0022.lzma.bak
2021-02-17T21:11:03.291Z,1613596263.291 [DataOverHttps](INFO): SBD MOMSN=15232863
2021-02-17T21:11:05.144Z,1613596265.144 [Default:CheckIn:Read_Iridium] Stopped
2021-02-17T21:11:05.144Z,1613596265.144 [Default:CheckIn:C.Wait] Running Loop=1
2021-02-17T21:11:05.145Z,1613596265.145 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-02-17T21:11:24.522Z,1613596284.522 [NAL9602](INFO): Not Powering down - fast GPS
2021-02-17T21:11:24.610Z,1613596284.610 [Reporter](INFO): NAL9602.sigQuality 0 count
2021-02-17T21:16:05.805Z,1613596565.805 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-02-17T21:16:05.805Z,1613596565.805 [Default:CheckIn:C.Wait] Stopped
2021-02-17T21:16:05.805Z,1613596565.805 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-02-17T21:16:05.806Z,1613596565.806 [Default:CheckIn:D] Running Loop=1
2021-02-17T21:16:06.208Z,1613596566.208 [Default:CheckIn:D] Stopped
2021-02-17T21:16:06.208Z,1613596566.208 [Default:CheckIn:E] Running Loop=1
2021-02-17T21:16:06.627Z,1613596566.627 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 14.684973 min 
2021-02-17T21:16:06.627Z,1613596566.627 [Default:CheckIn:E] Stopped
2021-02-17T21:16:06.627Z,1613596566.627 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-02-17T21:16:06.627Z,1613596566.627 [Default:CheckIn] Stopped
2021-02-17T21:16:06.627Z,1613596566.627 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-02-17T21:16:06.628Z,1613596566.628 [Default:CheckIn](INFO): Running loop #3
2021-02-17T21:16:06.628Z,1613596566.628 [Default:CheckIn] Running Loop=3
2021-02-17T21:16:06.628Z,1613596566.628 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-02-17T21:16:06.628Z,1613596566.628 [Default:CheckIn:Read_GPS] Running Loop=1
2021-02-17T21:16:08.617Z,1613596568.617 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211607.00,A,3648.28666,N,12147.13509,W,4.257,194.55,170221,,,D*7F
2021-02-17T21:16:08.620Z,1613596568.620 [NAL9602](INFO): GPS fix at 20210217T211607: (36.804778, -121.785585)
2021-02-17T21:16:08.639Z,1613596568.639 [Default:CheckIn:Read_GPS] Stopped
2021-02-17T21:16:08.639Z,1613596568.639 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-02-17T21:16:15.559Z,1613596575.559 [Reporter](INFO): NAL9602.sigQuality 5 count
2021-02-17T21:16:16.520Z,1613596576.520 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210217T204139/Courier0024.lzma
2021-02-17T21:16:17.523Z,1613596577.523 [DataOverHttps](INFO): Moved sent file to Logs/20210217T204139/Courier0024.lzma.bak
2021-02-17T21:16:17.523Z,1613596577.523 [DataOverHttps](INFO): SBD MOMSN=15232876
2021-02-17T21:16:21.550Z,1613596581.550 [NAL9602](INFO): SBD MO Status=0, MOMSN=12483, MT Status=0, MTMSN=0
2021-02-17T21:16:21.550Z,1613596581.550 [NAL9602](INFO): No messages in MT queue
2021-02-17T21:16:42.589Z,1613596602.589 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20210217T204139/Express0025.lzma
2021-02-17T21:16:43.591Z,1613596603.591 [DataOverHttps](INFO): Moved sent file to Logs/20210217T204139/Express0025.lzma.bak
2021-02-17T21:16:43.591Z,1613596603.591 [DataOverHttps](INFO): SBD MOMSN=15232882
2021-02-17T21:16:45.534Z,1613596605.534 [Default:CheckIn:Read_Iridium] Stopped
2021-02-17T21:16:45.534Z,1613596605.534 [Default:CheckIn:C.Wait] Running Loop=1
2021-02-17T21:16:45.534Z,1613596605.534 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-02-17T21:16:52.388Z,1613596612.388 [NAL9602](INFO): Not Powering down - fast GPS
2021-02-17T21:16:52.422Z,1613596612.422 [Reporter](INFO): NAL9602.sigQuality 0 count
2021-02-17T21:18:14.852Z,1613596694.852 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2021-02-17T21:18:45.118Z,1613596725.118 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2021-02-17T21:21:46.187Z,1613596906.187 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-02-17T21:21:46.187Z,1613596906.187 [Default:CheckIn:C.Wait] Stopped
2021-02-17T21:21:46.187Z,1613596906.187 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-02-17T21:21:46.187Z,1613596906.187 [Default:CheckIn:D] Running Loop=1
2021-02-17T21:21:46.583Z,1613596906.583 [Default:CheckIn:D] Stopped
2021-02-17T21:21:46.584Z,1613596906.584 [Default:CheckIn:E] Running Loop=1
2021-02-17T21:21:46.996Z,1613596906.996 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 20.357900 min 
2021-02-17T21:21:46.996Z,1613596906.996 [Default:CheckIn:E] Stopped
2021-02-17T21:21:46.996Z,1613596906.996 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-02-17T21:21:46.996Z,1613596906.996 [Default:CheckIn] Stopped
2021-02-17T21:21:46.996Z,1613596906.996 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-02-17T21:21:46.996Z,1613596906.996 [Default:CheckIn](INFO): Running loop #4
2021-02-17T21:21:46.996Z,1613596906.996 [Default:CheckIn] Running Loop=4
2021-02-17T21:21:46.996Z,1613596906.996 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-02-17T21:21:46.997Z,1613596906.997 [Default:CheckIn:Read_GPS] Running Loop=1
2021-02-17T21:21:48.996Z,1613596908.996 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212147.00,A,3648.16482,N,12147.21450,W,0.039,227.45,170221,,,D*72
2021-02-17T21:21:48.998Z,1613596908.998 [NAL9602](INFO): GPS fix at 20210217T212147: (36.802747, -121.786908)
2021-02-17T21:21:49.044Z,1613596909.044 [Default:CheckIn:Read_GPS] Stopped
2021-02-17T21:21:49.044Z,1613596909.044 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-02-17T21:21:56.288Z,1613596916.288 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210217T204139/Courier0027.lzma
2021-02-17T21:21:57.127Z,1613596917.127 [Reporter](INFO): NAL9602.sigQuality 3 count
2021-02-17T21:21:57.287Z,1613596917.287 [DataOverHttps](INFO): Moved sent file to Logs/20210217T204139/Courier0027.lzma.bak
2021-02-17T21:21:57.287Z,1613596917.287 [DataOverHttps](INFO): SBD MOMSN=15232899
2021-02-17T21:22:10.031Z,1613596930.031 [NAL9602](INFO): SBD MO Status=0, MOMSN=12484, MT Status=0, MTMSN=0
2021-02-17T21:22:10.031Z,1613596930.031 [NAL9602](INFO): No messages in MT queue
2021-02-17T21:22:13.601Z,1613596933.601 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20210217T204139/Express0028.lzma
2021-02-17T21:22:14.603Z,1613596934.603 [DataOverHttps](INFO): Moved sent file to Logs/20210217T204139/Express0028.lzma.bak
2021-02-17T21:22:14.603Z,1613596934.603 [DataOverHttps](INFO): SBD MOMSN=15232905
2021-02-17T21:22:16.482Z,1613596936.482 [Default:CheckIn:Read_Iridium] Stopped
2021-02-17T21:22:16.482Z,1613596936.482 [Default:CheckIn:C.Wait] Running Loop=1
2021-02-17T21:22:16.482Z,1613596936.482 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-02-17T21:22:40.651Z,1613596960.651 [NAL9602](INFO): Not Powering down - fast GPS
2021-02-17T21:22:40.673Z,1613596960.673 [Reporter](INFO): NAL9602.sigQuality 0 count
2021-02-17T21:27:17.065Z,1613597237.065 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-02-17T21:27:17.065Z,1613597237.065 [Default:CheckIn:C.Wait] Stopped
2021-02-17T21:27:17.065Z,1613597237.065 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-02-17T21:27:17.065Z,1613597237.065 [Default:CheckIn:D] Running Loop=1
2021-02-17T21:27:17.488Z,1613597237.488 [Default:CheckIn:D] Stopped
2021-02-17T21:27:17.488Z,1613597237.488 [Default:CheckIn:E] Running Loop=1
2021-02-17T21:27:17.959Z,1613597237.959 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 25.872978 min 
2021-02-17T21:27:17.959Z,1613597237.959 [Default:CheckIn:E] Stopped
2021-02-17T21:27:17.959Z,1613597237.959 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-02-17T21:27:17.959Z,1613597237.959 [Default:CheckIn] Stopped
2021-02-17T21:27:17.959Z,1613597237.959 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-02-17T21:27:17.960Z,1613597237.960 [Default:CheckIn](INFO): Running loop #5
2021-02-17T21:27:17.960Z,1613597237.960 [Default:CheckIn] Running Loop=5
2021-02-17T21:27:17.960Z,1613597237.960 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-02-17T21:27:17.960Z,1613597237.960 [Default:CheckIn:Read_GPS] Running Loop=1
2021-02-17T21:27:19.876Z,1613597239.876 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212718.00,A,3648.14843,N,12147.22254,W,0.214,218.98,170221,,,D*7D
2021-02-17T21:27:19.878Z,1613597239.878 [NAL9602](INFO): GPS fix at 20210217T212718: (36.802474, -121.787042)
2021-02-17T21:27:19.900Z,1613597239.900 [Default:CheckIn:Read_GPS] Stopped
2021-02-17T21:27:19.900Z,1613597239.900 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-02-17T21:27:28.304Z,1613597248.304 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210217T204139/Courier0030.lzma
2021-02-17T21:27:29.307Z,1613597249.307 [DataOverHttps](INFO): Moved sent file to Logs/20210217T204139/Courier0030.lzma.bak
2021-02-17T21:27:29.307Z,1613597249.307 [DataOverHttps](INFO): SBD MOMSN=15232927
2021-02-17T21:27:45.485Z,1613597265.485 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20210217T204139/Express0031.lzma
2021-02-17T21:27:46.487Z,1613597266.487 [DataOverHttps](INFO): Moved sent file to Logs/20210217T204139/Express0031.lzma.bak
2021-02-17T21:27:46.487Z,1613597266.487 [DataOverHttps](INFO): SBD MOMSN=15232935
2021-02-17T21:27:48.227Z,1613597268.227 [Default:CheckIn:Read_Iridium] Stopped
2021-02-17T21:27:48.227Z,1613597268.227 [Default:CheckIn:C.Wait] Running Loop=1
2021-02-17T21:27:48.227Z,1613597268.227 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-02-17T21:27:51.075Z,1613597271.075 [Reporter](INFO): NAL9602.sigQuality 2 count
2021-02-17T21:28:05.941Z,1613597285.941 [NAL9602](INFO): SBD MO Status=2, MOMSN=12485, MT Status=2, MTMSN=0
2021-02-17T21:28:05.941Z,1613597285.941 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-02-17T21:28:10.456Z,1613597290.456 [Reporter](INFO): NAL9602.sigQuality 4 count
2021-02-17T21:28:14.010Z,1613597294.010 [NAL9602](INFO): SBD MO Status=0, MOMSN=12485, MT Status=0, MTMSN=0
2021-02-17T21:28:14.010Z,1613597294.010 [NAL9602](INFO): No messages in MT queue
2021-02-17T21:28:44.790Z,1613597324.790 [NAL9602](INFO): Not Powering down - fast GPS
2021-02-17T21:28:44.812Z,1613597324.812 [Reporter](INFO): NAL9602.sigQuality 0 count
2021-02-17T21:32:48.828Z,1613597568.828 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-02-17T21:32:48.828Z,1613597568.828 [Default:CheckIn:C.Wait] Stopped
2021-02-17T21:32:48.828Z,1613597568.828 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-02-17T21:32:48.828Z,1613597568.828 [Default:CheckIn:D] Running Loop=1
2021-02-17T21:32:49.220Z,1613597569.220 [Default:CheckIn:D] Stopped
2021-02-17T21:32:49.220Z,1613597569.220 [Default:CheckIn:E] Running Loop=1
2021-02-17T21:32:49.635Z,1613597569.635 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.401845 min 
2021-02-17T21:32:49.635Z,1613597569.635 [Default:CheckIn:E] Stopped
2021-02-17T21:32:49.636Z,1613597569.636 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-02-17T21:32:49.636Z,1613597569.636 [Default:CheckIn] Stopped
2021-02-17T21:32:49.636Z,1613597569.636 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-02-17T21:32:49.636Z,1613597569.636 [Default:CheckIn](INFO): Running loop #6
2021-02-17T21:32:49.636Z,1613597569.636 [Default:CheckIn] Running Loop=6
2021-02-17T21:32:49.636Z,1613597569.636 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-02-17T21:32:49.636Z,1613597569.636 [Default:CheckIn:Read_GPS] Running Loop=1
2021-02-17T21:32:51.637Z,1613597571.637 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213250.00,A,3648.16973,N,12147.28202,W,0.136,207.60,170221,,,D*76
2021-02-17T21:32:51.639Z,1613597571.639 [NAL9602](INFO): GPS fix at 20210217T213250: (36.802829, -121.788034)
2021-02-17T21:32:51.650Z,1613597571.650 [Default:CheckIn:Read_GPS] Stopped
2021-02-17T21:32:51.650Z,1613597571.650 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-02-17T21:32:59.696Z,1613597579.696 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210217T204139/Courier0033.lzma
2021-02-17T21:33:00.699Z,1613597580.699 [DataOverHttps](INFO): Moved sent file to Logs/20210217T204139/Courier0033.lzma.bak
2021-02-17T21:33:00.699Z,1613597580.699 [DataOverHttps](INFO): SBD MOMSN=15232944
2021-02-17T21:33:02.588Z,1613597582.588 [Reporter](INFO): NAL9602.sigQuality 5 count
2021-02-17T21:33:07.794Z,1613597587.794 [NAL9602](INFO): SBD MO Status=0, MOMSN=12486, MT Status=0, MTMSN=0
2021-02-17T21:33:07.794Z,1613597587.794 [NAL9602](INFO): No messages in MT queue
2021-02-17T21:33:16.817Z,1613597596.817 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20210217T204139/Express0034.lzma
2021-02-17T21:33:17.822Z,1613597597.822 [DataOverHttps](INFO): Moved sent file to Logs/20210217T204139/Express0034.lzma.bak
2021-02-17T21:33:17.823Z,1613597597.823 [DataOverHttps](INFO): SBD MOMSN=15232950
2021-02-17T21:33:19.528Z,1613597599.528 [Default:CheckIn:Read_Iridium] Stopped
2021-02-17T21:33:19.528Z,1613597599.528 [Default:CheckIn:C.Wait] Running Loop=1
2021-02-17T21:33:19.529Z,1613597599.529 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-02-17T21:33:38.502Z,1613597618.502 [NAL9602](INFO): Not Powering down - fast GPS
2021-02-17T21:33:38.551Z,1613597618.551 [Reporter](INFO): NAL9602.sigQuality 0 count
2021-02-17T21:38:20.202Z,1613597900.202 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-02-17T21:38:20.202Z,1613597900.202 [Default:CheckIn:C.Wait] Stopped
2021-02-17T21:38:20.202Z,1613597900.202 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-02-17T21:38:20.203Z,1613597900.203 [Default:CheckIn:D] Running Loop=1
2021-02-17T21:38:20.568Z,1613597900.568 [Default:CheckIn:D] Stopped
2021-02-17T21:38:20.568Z,1613597900.568 [Default:CheckIn:E] Running Loop=1
2021-02-17T21:38:20.994Z,1613597900.994 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 36.924308 min 
2021-02-17T21:38:20.994Z,1613597900.994 [Default:CheckIn:E] Stopped
2021-02-17T21:38:20.994Z,1613597900.994 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-02-17T21:38:20.994Z,1613597900.994 [Default:CheckIn] Stopped
2021-02-17T21:38:20.994Z,1613597900.994 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-02-17T21:38:20.995Z,1613597900.995 [Default:CheckIn](INFO): Running loop #7
2021-02-17T21:38:20.995Z,1613597900.995 [Default:CheckIn] Running Loop=7
2021-02-17T21:38:20.995Z,1613597900.995 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-02-17T21:38:20.995Z,1613597900.995 [Default:CheckIn:Read_GPS] Running Loop=1
2021-02-17T21:38:23.004Z,1613597903.004 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213821.00,A,3648.16697,N,12147.28034,W,0.078,207.60,170221,,,A*76
2021-02-17T21:38:23.014Z,1613597903.014 [NAL9602](INFO): GPS fix at 20210217T213821: (36.802783, -121.788006)
2021-02-17T21:38:23.099Z,1613597903.099 [Default:CheckIn:Read_GPS] Stopped
2021-02-17T21:38:23.099Z,1613597903.099 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-02-17T21:38:35.229Z,1613597915.229 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210217T204139/Courier0036.lzma
2021-02-17T21:38:36.231Z,1613597916.231 [DataOverHttps](INFO): Moved sent file to Logs/20210217T204139/Courier0036.lzma.bak
2021-02-17T21:38:36.231Z,1613597916.231 [DataOverHttps](INFO): SBD MOMSN=15232958
2021-02-17T21:38:55.065Z,1613597935.065 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20210217T204139/Express0037.lzma
2021-02-17T21:38:56.067Z,1613597936.067 [DataOverHttps](INFO): Moved sent file to Logs/20210217T204139/Express0037.lzma.bak
2021-02-17T21:38:56.067Z,1613597936.067 [DataOverHttps](INFO): SBD MOMSN=15232964
2021-02-17T21:38:58.261Z,1613597938.261 [Default:CheckIn:Read_Iridium] Stopped
2021-02-17T21:38:58.265Z,1613597938.265 [Default:CheckIn:C.Wait] Running Loop=1
2021-02-17T21:38:58.265Z,1613597938.265 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-02-17T21:43:25.367Z,1613598205.367 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2021-02-17T21:43:38.474Z,1613598218.474 [CommandLine](IMPORTANT): got command gfscan
2021-02-17T21:43:38.725Z,1613598218.725 [CBIT](IMPORTANT): Beginning ground fault scan
2021-02-17T21:43:49.628Z,1613598229.628 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.101129
CHAN A1 (24V): 0.187860
CHAN A2 (12V): 0.082496
CHAN A3 (5V): 0.030638
CHAN B0 (3.3V): 0.022593
CHAN B1 (3.15aV): 0.021398
CHAN B2 (3.15bV): 0.021433
CHAN B3 (GND): 0.000394
OPEN: 0.011492
Full Scale Calc: 4.765 mA, -1.589 mA
2021-02-17T21:43:54.202Z,1613598234.202 [CommandLine](IMPORTANT): got command quit
2021-02-17T21:43:55.209Z,1613598235.209 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2021-02-17T21:43:55.210Z,1613598235.210 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-02-17T21:43:55.309Z,1613598235.309 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2021-02-17T21:43:55.309Z,1613598235.309 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-02-17T21:43:55.310Z,1613598235.310 [CommandLine](INFO): Join timeout helper Thread ID is 5932
2021-02-17T21:43:55.325Z,1613598235.325 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2021-02-17T21:43:55.325Z,1613598235.325 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-02-17T21:43:55.325Z,1613598235.325 [NavChartDb](INFO): Join timeout helper Thread ID is 5933
2021-02-17T21:43:55.597Z,1613598235.597 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2021-02-17T21:43:55.597Z,1613598235.597 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-02-17T21:43:55.613Z,1613598235.613 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2021-02-17T21:43:55.613Z,1613598235.613 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-02-17T21:43:55.613Z,1613598235.613 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 5934
2021-02-17T21:43:55.981Z,1613598235.981 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2021-02-17T21:43:55.981Z,1613598235.981 [WetLabsBB2FL](INFO): Powering down
2021-02-17T21:43:55.982Z,1613598235.982 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-02-17T21:43:55.993Z,1613598235.993 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2021-02-17T21:43:55.993Z,1613598235.993 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-02-17T21:43:55.993Z,1613598235.993 [CTD_Seabird](INFO): Join timeout helper Thread ID is 5935
2021-02-17T21:43:56.073Z,1613598236.073 [CTD_Seabird](INFO): Powering down
2021-02-17T21:43:56.082Z,1613598236.082 [NAL9602](INFO): Not Powering down - fast GPS
2021-02-17T21:43:56.085Z,1613598236.085 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2021-02-17T21:43:56.085Z,1613598236.085 [CTD_Seabird](INFO): Powering down
2021-02-17T21:43:56.097Z,1613598236.097 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-02-17T21:43:56.105Z,1613598236.105 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2021-02-17T21:43:56.105Z,1613598236.105 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-02-17T21:43:56.106Z,1613598236.106 [Radio_Surface](INFO): Join timeout helper Thread ID is 5936
2021-02-17T21:43:56.333Z,1613598236.333 [Radio_Surface](INFO): Powering down
2021-02-17T21:43:56.334Z,1613598236.334 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2021-02-17T21:43:56.334Z,1613598236.334 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-02-17T21:43:56.346Z,1613598236.346 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2021-02-17T21:43:56.346Z,1613598236.346 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-02-17T21:43:56.346Z,1613598236.346 [Onboard](INFO): Join timeout helper Thread ID is 5937
2021-02-17T21:43:58.911Z,1613598238.911 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-02-17T21:43:58.911Z,1613598238.911 [Default:CheckIn:C.Wait] Stopped
2021-02-17T21:43:58.911Z,1613598238.911 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-02-17T21:43:58.912Z,1613598238.912 [Default:CheckIn:D] Running Loop=1
2021-02-17T21:43:59.320Z,1613598239.320 [Default:CheckIn:D] Stopped
2021-02-17T21:43:59.320Z,1613598239.320 [Default:CheckIn:E] Running Loop=1
2021-02-17T21:43:59.732Z,1613598239.732 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.570170 min 
2021-02-17T21:43:59.732Z,1613598239.732 [Default:CheckIn:E] Stopped
2021-02-17T21:43:59.732Z,1613598239.732 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-02-17T21:43:59.732Z,1613598239.732 [Default:CheckIn] Stopped
2021-02-17T21:43:59.732Z,1613598239.732 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-02-17T21:43:59.733Z,1613598239.733 [Default:CheckIn](INFO): Running loop #8
2021-02-17T21:43:59.733Z,1613598239.733 [Default:CheckIn] Running Loop=8
2021-02-17T21:43:59.733Z,1613598239.733 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-02-17T21:43:59.733Z,1613598239.733 [Default:CheckIn:Read_GPS] Running Loop=1
2021-02-17T21:44:01.137Z,1613598241.137 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2021-02-17T21:44:01.137Z,1613598241.137 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-02-17T21:44:01.142Z,1613598241.142 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2021-02-17T21:44:01.142Z,1613598241.142 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-02-17T21:44:01.142Z,1613598241.142 [DataOverHttps](INFO): Join timeout helper Thread ID is 5938
2021-02-17T21:44:01.457Z,1613598241.457 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2021-02-17T21:44:01.460Z,1613598241.460 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-02-17T21:44:01.474Z,1613598241.474 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2021-02-17T21:44:01.474Z,1613598241.474 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2021-02-17T21:44:01.474Z,1613598241.474 [BackseatComponent](INFO): Join timeout helper Thread ID is 5939
2021-02-17T21:44:01.613Z,1613598241.613 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2021-02-17T21:44:01.613Z,1613598241.613 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2021-02-17T21:44:01.634Z,1613598241.634 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2021-02-17T21:44:01.634Z,1613598241.634 [logger ThreadHandler](INFO): Thread cancelled.
2021-02-17T21:44:01.634Z,1613598241.634 [logger](INFO): Join timeout helper Thread ID is 5940
2021-02-17T21:44:01.637Z,1613598241.637 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2021-02-17T21:44:01.637Z,1613598241.637 [logger ThreadHandler](INFO): Thread cancelled.
2021-02-17T21:44:01.654Z,1613598241.654 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2021-02-17T21:44:01.654Z,1613598241.654 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-02-17T21:44:01.654Z,1613598241.654 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2021-02-17T21:44:01.654Z,1613598241.654 [controlThread ThreadHandler](INFO): Thread cancelled.
2021-02-17T21:44:01.654Z,1613598241.654 [controlThread](INFO): Join timeout helper Thread ID is 5941
2021-02-17T21:44:01.709Z,1613598241.709 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2021-02-17T21:44:01.709Z,1613598241.709 [controlThread](DEBUG): Uninitializing ControlThread
2021-02-17T21:44:01.710Z,1613598241.710 [AHRS_M2](INFO): Powering down
2021-02-17T21:44:01.782Z,1613598241.782 [NAL9602](INFO): Powering down
2021-02-17T21:44:01.853Z,1613598241.853 [RDI_Pathfinder](INFO): Powering down
2021-02-17T21:44:01.854Z,1613598241.854 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2021-02-17T21:44:01.855Z,1613598241.855 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2021-02-17T21:44:01.856Z,1613598241.856 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2021-02-17T21:44:01.856Z,1613598241.856 [MissionManager](INFO): Uninitializing Mission Default
2021-02-17T21:44:01.856Z,1613598241.856 [Default] Stopped
2021-02-17T21:44:01.857Z,1613598241.857 [Default](DEBUG): Aggregate::uninitialize Default
2021-02-17T21:44:01.857Z,1613598241.857 [Default:B.GoToSurface] Stopped
2021-02-17T21:44:01.857Z,1613598241.857 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-02-17T21:44:01.857Z,1613598241.857 [Default:CheckIn] Stopped
2021-02-17T21:44:01.857Z,1613598241.857 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-02-17T21:44:01.857Z,1613598241.857 [Default:CheckIn:Read_GPS] Stopped
2021-02-17T21:44:01.860Z,1613598241.860 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2021-02-17T21:44:01.860Z,1613598241.860 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2021-02-17T21:44:01.861Z,1613598241.861 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2021-02-17T21:44:01.861Z,1613598241.861 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2021-02-17T21:44:01.861Z,1613598241.861 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2021-02-17T21:44:01.861Z,1613598241.861 [BuoyancyServo](INFO): Powering down
2021-02-17T21:44:01.873Z,1613598241.873 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2021-02-17T21:44:01.873Z,1613598241.873 [ElevatorServo](INFO): Powering down
2021-02-17T21:44:01.874Z,1613598241.874 [MassServo](DEBUG): Uninitialize Mass Servo.
2021-02-17T21:44:01.874Z,1613598241.874 [MassServo](INFO): Powering down
2021-02-17T21:44:01.875Z,1613598241.875 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2021-02-17T21:44:01.875Z,1613598241.875 [RudderServo](INFO): Powering down
2021-02-17T21:44:01.876Z,1613598241.876 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2021-02-17T21:44:01.876Z,1613598241.876 [ThrusterServo](INFO): Powering down
2021-02-17T21:44:01.877Z,1613598241.877 [SBIT](DEBUG): Uninitialize SBIT Component.
2021-02-17T21:44:01.877Z,1613598241.877 [IBIT](DEBUG): Uninitialize IBIT Component.
2021-02-17T21:44:01.877Z,1613598241.877 [CBIT](DEBUG): Uninitialize CBIT Component.
2021-02-17T21:44:01.878Z,1613598241.878 [CBIT](DEBUG): Powering off loads.
2021-02-17T21:44:01.889Z,1613598241.889 [CBIT](DEBUG): Disabling WDT.
2021-02-17T21:44:01.901Z,1613598241.901 [CBIT](DEBUG): Opening all GF detection circuits.
2021-02-17T21:44:01.902Z,1613598241.902 [controlThread ThreadHandler](INFO): Thread cancelled.
2021-02-17T21:44:01.968Z,1613598241.968 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-02-17T21:44:01.971Z,1613598241.971 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-02-17T21:44:01.976Z,1613598241.976 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-02-17T21:44:02.018Z,1613598242.018 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2021-02-17T21:44:02.071Z,1613598242.071 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-02-17T21:44:02.074Z,1613598242.074 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-02-17T21:44:02.082Z,1613598242.082 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-02-17T21:44:02.178Z,1613598242.178 [logger ThreadHandler](INFO): Thread cancelled.