2020-11-12T18:41:28.218Z,1605206488.218 [DataOverHttps](INFO): Received command:restart logs
2020-11-12T18:41:28.240Z,1605206488.240 [CommandLine](IMPORTANT): got command restart logs
2020-11-12T18:41:39.152Z,1605206499.152 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T18:41:39.152Z,1605206499.152 [Default:CheckIn:C.Wait] Stopped
2020-11-12T18:41:39.152Z,1605206499.152 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T18:41:39.152Z,1605206499.152 [Default:CheckIn:D] Running Loop=1
2020-11-12T18:41:39.521Z,1605206499.521 [Default:CheckIn:D] Stopped
2020-11-12T18:41:39.521Z,1605206499.521 [Default:CheckIn:E] Running Loop=1
2020-11-12T18:41:39.925Z,1605206499.925 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 22.917503 min 
2020-11-12T18:41:39.925Z,1605206499.925 [Default:CheckIn:E] Stopped
2020-11-12T18:41:39.925Z,1605206499.925 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T18:41:39.926Z,1605206499.926 [Default:CheckIn] Stopped
2020-11-12T18:41:39.926Z,1605206499.926 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T18:41:39.926Z,1605206499.926 [Default:CheckIn](INFO): Running loop #5
2020-11-12T18:41:39.926Z,1605206499.926 [Default:CheckIn] Running Loop=5
2020-11-12T18:41:39.926Z,1605206499.926 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T18:41:39.926Z,1605206499.926 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T18:41:41.933Z,1605206501.933 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184140.00,A,3647.81098,N,12151.65770,W,22.140,331.33,121120,,,D*4F
2020-11-12T18:41:41.935Z,1605206501.935 [NAL9602](INFO): GPS fix at 20201112T184140: (36.796850, -121.860962)
2020-11-12T18:41:41.997Z,1605206501.997 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T18:41:41.998Z,1605206501.998 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T18:41:51.769Z,1605206511.769 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201112T174354/Courier0051.lzma
2020-11-12T18:41:52.772Z,1605206512.772 [DataOverHttps](INFO): Moved sent file to Logs/20201112T174354/Courier0051.lzma.bak
2020-11-12T18:41:52.772Z,1605206512.772 [DataOverHttps](INFO): SBD MOMSN=13194545
2020-11-12T18:42:00.113Z,1605206520.113 [NAL9602](INFO): SBD MO Status=0, MOMSN=24074, MT Status=0, MTMSN=0
2020-11-12T18:42:00.113Z,1605206520.113 [NAL9602](INFO): No messages in MT queue
2020-11-12T18:42:06.737Z,1605206526.737 [CommandLine](IMPORTANT): got command show variable keepstation
2020-11-12T18:42:06.877Z,1605206526.877 [CommandLine](IMPORTANT): keepstation_3km:keepstation.MissionTimeout (hour)
2020-11-12T18:42:06.878Z,1605206526.878 [CommandLine](IMPORTANT): keepstation_3km:keepstation.NeedCommsTime (minute)
2020-11-12T18:42:06.890Z,1605206526.890 [CommandLine](IMPORTANT): keepstation_3km:keepstation.Latitude (degree)
2020-11-12T18:42:06.891Z,1605206526.891 [CommandLine](IMPORTANT): keepstation_3km:keepstation.Longitude (degree)
2020-11-12T18:42:06.891Z,1605206526.891 [CommandLine](IMPORTANT): keepstation_3km:keepstation.Depth (meter)
2020-11-12T18:42:06.891Z,1605206526.891 [CommandLine](IMPORTANT): keepstation_3km:keepstation.ApproachDepth (meter)
2020-11-12T18:42:06.892Z,1605206526.892 [CommandLine](IMPORTANT): keepstation_3km:keepstation.DepthDeadband (meter)
2020-11-12T18:42:06.892Z,1605206526.892 [CommandLine](IMPORTANT): keepstation_3km:keepstation.Speed (meter_per_second)
2020-11-12T18:42:06.893Z,1605206526.893 [CommandLine](IMPORTANT): keepstation_3km:keepstation.Radius (meter)
2020-11-12T18:42:06.893Z,1605206526.893 [CommandLine](IMPORTANT): keepstation_3km:keepstation.MaxDepth (meter)
2020-11-12T18:42:06.893Z,1605206526.893 [CommandLine](IMPORTANT): keepstation_3km:keepstation.MinOffshore (kilometer)
2020-11-12T18:42:06.894Z,1605206526.894 [CommandLine](IMPORTANT): keepstation_3km:keepstation:NeedComms.DiveInterval (hour)
2020-11-12T18:42:06.894Z,1605206526.894 [CommandLine](IMPORTANT): keepstation_3km:keepstation:NeedComms.WaitForPitchUp (minute)
2020-11-12T18:42:06.894Z,1605206526.894 [CommandLine](IMPORTANT): keepstation_3km:keepstation:NeedComms.SurfacePitch (degree)
2020-11-12T18:42:06.895Z,1605206526.895 [CommandLine](IMPORTANT): keepstation_3km:keepstation:NeedComms.SurfaceDepthRate (meter_per_second)
2020-11-12T18:42:06.895Z,1605206526.895 [CommandLine](IMPORTANT): keepstation_3km:keepstation:NeedComms.SurfaceSpeed (meter_per_second)
2020-11-12T18:42:06.895Z,1605206526.895 [CommandLine](IMPORTANT): keepstation_3km:keepstation:NeedComms.GPSTimeout (minute)
2020-11-12T18:42:06.896Z,1605206526.896 [CommandLine](IMPORTANT): keepstation_3km:keepstation:NeedComms.CommsTimeout (minute)
2020-11-12T18:42:06.896Z,1605206526.896 [CommandLine](IMPORTANT): keepstation_3km:keepstation:StandardEnvelopes.MinAltitude (meter)
2020-11-12T18:42:06.897Z,1605206526.897 [CommandLine](IMPORTANT): keepstation_3km:keepstation:StandardEnvelopes.MaxDepth (meter)
2020-11-12T18:42:06.897Z,1605206526.897 [CommandLine](IMPORTANT): keepstation_3km:keepstation:StandardEnvelopes.MinOffshore (meter)
2020-11-12T18:42:10.903Z,1605206530.903 [DataOverHttps](INFO): Sending 267 bytes from file Logs/20201112T184128/Courier0000.lzma
2020-11-12T18:42:11.904Z,1605206531.904 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Courier0000.lzma.bak
2020-11-12T18:42:11.904Z,1605206531.904 [DataOverHttps](INFO): SBD MOMSN=13194547
2020-11-12T18:42:30.535Z,1605206550.535 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-12T18:42:44.930Z,1605206564.930 [DataOverHttps](INFO): Sending 221 bytes from file Logs/20201112T174354/Express0052.lzma
2020-11-12T18:42:45.936Z,1605206565.936 [DataOverHttps](INFO): Moved sent file to Logs/20201112T174354/Express0052.lzma.bak
2020-11-12T18:42:45.937Z,1605206565.937 [DataOverHttps](INFO): SBD MOMSN=13194551
2020-11-12T18:43:10.538Z,1605206590.538 [DataOverHttps](INFO): Sending 393 bytes from file Logs/20201112T184128/Express0001.lzma
2020-11-12T18:43:11.540Z,1605206591.540 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Express0001.lzma.bak
2020-11-12T18:43:11.540Z,1605206591.540 [DataOverHttps](INFO): SBD MOMSN=13194558
2020-11-12T18:43:24.153Z,1605206604.153 [CommandLine](IMPORTANT): got command show variable profile
2020-11-12T18:43:24.319Z,1605206604.319 [CommandLine](IMPORTANT): Rowe_600.writeAmplitudeProfile (bool)
2020-11-12T18:43:24.319Z,1605206604.319 [CommandLine](IMPORTANT): Rowe_600.writeBeamVelocityProfile (bool)
2020-11-12T18:43:24.320Z,1605206604.320 [CommandLine](IMPORTANT): Rowe_600.writeCorrelationProfile (bool)
2020-11-12T18:43:24.320Z,1605206604.320 [CommandLine](IMPORTANT): Rowe_600.writeEarthVelocityProfile (bool)
2020-11-12T18:43:24.321Z,1605206604.321 [CommandLine](IMPORTANT): Rowe_600.writeGoodBeamPingsProfile (bool)
2020-11-12T18:43:24.321Z,1605206604.321 [CommandLine](IMPORTANT): Rowe_600.writeGoodEarthPingsProfile (bool)
2020-11-12T18:43:24.321Z,1605206604.321 [CommandLine](IMPORTANT): Rowe_600.writeInstrumentVelocityProfile (bool)
2020-11-12T18:43:24.382Z,1605206604.382 [CommandLine](IMPORTANT): TempGradientCalculator.numProfilesGap (count)
2020-11-12T18:43:24.382Z,1605206604.382 [CommandLine](IMPORTANT): TempGradientCalculator.numProfilesLP (count)
2020-11-12T18:43:27.762Z,1605206607.762 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20201112T184128/Express0004.lzma
2020-11-12T18:43:28.764Z,1605206608.764 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Express0004.lzma.bak
2020-11-12T18:43:28.764Z,1605206608.764 [DataOverHttps](INFO): SBD MOMSN=13194562
2020-11-12T18:43:29.867Z,1605206609.867 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T18:43:29.867Z,1605206609.867 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T18:43:29.867Z,1605206609.867 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T18:43:47.116Z,1605206627.116 [CommandLine](IMPORTANT): got command load ./Missions/Science/profile_station.xml
2020-11-12T18:43:47.116Z,1605206627.116 [MissionManager](INFO): Uninitializing Mission keepstation_3km
2020-11-12T18:43:47.128Z,1605206627.128 [MissionManager](INFO): Loading Mission: ./Missions/Science/profile_station.xml
2020-11-12T18:43:47.291Z,1605206627.291 [MissionManager](INFO): DefineArg profile_station.MissionTimeout = 4.000000 h
2020-11-12T18:43:47.293Z,1605206627.293 [MissionManager](INFO): DefineArg profile_station.NeedCommsTime = 60.000000 min
2020-11-12T18:43:47.311Z,1605206627.311 [MissionManager](INFO): DefineArg profile_station.Lat = 36.806966 arcdeg
2020-11-12T18:43:47.313Z,1605206627.313 [MissionManager](INFO): DefineArg profile_station.Lon = -121.824326 arcdeg
2020-11-12T18:43:47.315Z,1605206627.315 [MissionManager](INFO): DefineArg profile_station.Radius = 300.000000 m
2020-11-12T18:43:47.317Z,1605206627.317 [MissionManager](INFO): DefineArg profile_station.YoYoMinDepth = 2.000000 m
2020-11-12T18:43:47.319Z,1605206627.319 [MissionManager](INFO): DefineArg profile_station.YoYoMaxDepth = 200.000000 m
2020-11-12T18:43:47.321Z,1605206627.321 [MissionManager](INFO): DefineArg profile_station.YoYoMinAltitude = 7.000000 m
2020-11-12T18:43:47.375Z,1605206627.375 [MissionManager](INFO): DefineArg profile_station.YoYoPitch = 20.000000 arcdeg
2020-11-12T18:43:47.377Z,1605206627.377 [MissionManager](INFO): DefineArg profile_station.Speed = 1.000000 m/s
2020-11-12T18:43:47.400Z,1605206627.400 [MissionManager](INFO): DefineArg profile_station.CircleMaxError = 100.000000 m
2020-11-12T18:43:47.402Z,1605206627.402 [MissionManager](INFO): DefineArg profile_station.CircleTurnToPort = 0 bool
2020-11-12T18:43:47.404Z,1605206627.404 [MissionManager](INFO): DefineArg profile_station.KwpHeading = 0.010000 rad/m
2020-11-12T18:43:47.436Z,1605206627.436 [MissionManager](INFO): DefineArg profile_station.MinAltitude = 5.000000 m
2020-11-12T18:43:47.438Z,1605206627.438 [MissionManager](INFO): DefineArg profile_station.MaxDepth = 205.000000 m
2020-11-12T18:43:47.440Z,1605206627.440 [MissionManager](INFO): DefineArg profile_station.MinOffshore = 2.000000 km
2020-11-12T18:43:47.440Z,1605206627.440 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml
2020-11-12T18:43:47.820Z,1605206627.820 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectChlActive = 0 bool
2020-11-12T18:43:47.822Z,1605206627.822 [MissionManager](INFO): DefineArg profile_station:Science.TimeWindowPeakReport = nan min
2020-11-12T18:43:47.823Z,1605206627.823 [MissionManager](INFO): DefineArg profile_station:Science.HighestChlPeakReportActive = 0 bool
2020-11-12T18:43:47.825Z,1605206627.825 [MissionManager](INFO): DefineArg profile_station:Science.PatchTracking = 0 bool
2020-11-12T18:43:47.827Z,1605206627.827 [MissionManager](INFO): DefineArg profile_station:Science.FilterWidthHorizontal = 3.000000 count
2020-11-12T18:43:47.829Z,1605206627.829 [MissionManager](INFO): DefineArg profile_station:Science.NumProfilesSlidingwindow = 100.000000 count
2020-11-12T18:43:47.831Z,1605206627.831 [MissionManager](INFO): DefineArg profile_station:Science.OffPeakFractionHorizontal = 80.000000 %
2020-11-12T18:43:47.833Z,1605206627.833 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectNO3Active = 0 bool
2020-11-12T18:43:47.879Z,1605206627.879 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectOilActive = 0 bool
2020-11-12T18:43:47.881Z,1605206627.881 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectFDOMActive = 0 bool
2020-11-12T18:43:47.883Z,1605206627.883 [MissionManager](INFO): DefineArg profile_station:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2020-11-12T18:43:47.885Z,1605206627.885 [MissionManager](INFO): DefineArg profile_station:Science.EnabledAanderaaO2 = 0.000000 bool
2020-11-12T18:43:47.888Z,1605206627.888 [MissionManager](INFO): DefineArg profile_station:Science.EnabledNeilBrown = 0.000000 bool
2020-11-12T18:43:47.890Z,1605206627.890 [MissionManager](INFO): DefineArg profile_station:Science.EnabledSeabird = 1.000000 bool
2020-11-12T18:43:47.908Z,1605206627.908 [MissionManager](INFO): DefineArg profile_station:Science.EnabledWetLabsBB2FL = 1.000000 bool
2020-11-12T18:43:47.911Z,1605206627.911 [MissionManager](INFO): DefineArg profile_station:Science.EnabledWetLabsSeaOWL_UV_A = 0.000000 bool
2020-11-12T18:43:47.913Z,1605206627.913 [MissionManager](INFO): DefineArg profile_station:Science.EnabledWetLabsUBAT = 0.000000 bool
2020-11-12T18:43:47.915Z,1605206627.915 [MissionManager](INFO): DefineArg profile_station:Science.LowPassWindowLength = 20.000000 count
2020-11-12T18:43:47.917Z,1605206627.917 [MissionManager](INFO): DefineArg profile_station:Science.MedianFilterLen = 5.000000 count
2020-11-12T18:43:47.936Z,1605206627.936 [MissionManager](INFO): DefineArg profile_station:Science.PeakChlShallowBound = nan m
2020-11-12T18:43:47.938Z,1605206627.938 [MissionManager](INFO): DefineArg profile_station:Science.PeakChlDeepBound = nan m
2020-11-12T18:43:47.939Z,1605206627.939 [MissionManager](INFO): DefineArg profile_station:Science.PeakShallowBound = nan m
2020-11-12T18:43:47.942Z,1605206627.942 [MissionManager](INFO): DefineArg profile_station:Science.PeakDeepBound = nan m
2020-11-12T18:43:47.944Z,1605206627.944 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChl = 0.000000 ug/l
2020-11-12T18:43:47.946Z,1605206627.946 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlDepth = 0.000000 m
2020-11-12T18:43:47.972Z,1605206627.972 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlTemperature = 0.000000 degC
2020-11-12T18:43:47.974Z,1605206627.974 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlLatitude = nan arcdeg
2020-11-12T18:43:47.976Z,1605206627.976 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlLongitude = nan arcdeg
2020-11-12T18:43:48.013Z,1605206628.013 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3 = 0.000000 umol/l
2020-11-12T18:43:48.015Z,1605206628.015 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3Depth = 0.000000 m
2020-11-12T18:43:48.017Z,1605206628.017 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3Latitude = nan arcdeg
2020-11-12T18:43:48.019Z,1605206628.019 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3Longitude = nan arcdeg
2020-11-12T18:43:48.021Z,1605206628.021 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChl = 0.000000 m
2020-11-12T18:43:48.052Z,1605206628.052 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlDepth = 0.000000 m
2020-11-12T18:43:48.054Z,1605206628.054 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlLatitude = nan arcdeg
2020-11-12T18:43:48.056Z,1605206628.056 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlLongitude = nan arcdeg
2020-11-12T18:43:48.074Z,1605206628.074 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlDistance = nan m
2020-11-12T18:43:48.076Z,1605206628.076 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOil = 0.000000 kg/m3
2020-11-12T18:43:48.089Z,1605206628.089 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOilDepth = 0.000000 m
2020-11-12T18:43:48.091Z,1605206628.091 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOilLatitude = nan arcdeg
2020-11-12T18:43:48.093Z,1605206628.093 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOilLongitude = nan arcdeg
2020-11-12T18:43:48.124Z,1605206628.124 [MissionManager](INFO): DefineOutput profile_station:Science.PeakFDOM = 0.000000 ppb
2020-11-12T18:43:48.126Z,1605206628.126 [MissionManager](INFO): DefineOutput profile_station:Science.PeakFDOMDepth = 0.000000 m
2020-11-12T18:43:48.136Z,1605206628.136 [MissionManager](INFO): DefineOutput profile_station:Science.PeakFDOMLatitude = nan arcdeg
2020-11-12T18:43:48.147Z,1605206628.147 [MissionManager](INFO): DefineOutput profile_station:Science.PeakFDOMLongitude = nan arcdeg
2020-11-12T18:43:48.149Z,1605206628.149 [MissionManager](INFO): DefineOutput profile_station:Science.PatchFDOMDistance = nan m
2020-11-12T18:43:48.180Z,1605206628.180 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2020-11-12T18:43:48.231Z,1605206628.231 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2020-11-12T18:43:48.277Z,1605206628.277 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2020-11-12T18:43:48.290Z,1605206628.290 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2020-11-12T18:43:48.340Z,1605206628.340 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2020-11-12T18:43:48.367Z,1605206628.367 [MissionManager](INFO): RedefineArg profile_station:Science.PeakDetectChlActive = value:1 bool
2020-11-12T18:43:48.368Z,1605206628.368 [MissionManager](INFO): Inserting Stack: Missions/Insert/NeedComms.xml
2020-11-12T18:43:48.502Z,1605206628.502 [MissionManager](INFO): DefineArg profile_station:NeedComms.DiveInterval = 3.000000 h
2020-11-12T18:43:48.504Z,1605206628.504 [MissionManager](INFO): DefineArg profile_station:NeedComms.WaitForPitchUp = 10.000000 min
2020-11-12T18:43:48.518Z,1605206628.518 [MissionManager](INFO): DefineArg profile_station:NeedComms.SurfacePitch = 20.000000 arcdeg
2020-11-12T18:43:48.520Z,1605206628.520 [MissionManager](INFO): DefineArg profile_station:NeedComms.SurfaceDepthRate = nan m/s
2020-11-12T18:43:48.522Z,1605206628.522 [MissionManager](INFO): DefineArg profile_station:NeedComms.SurfaceSpeed = 1.000000 m/s
2020-11-12T18:43:48.524Z,1605206628.524 [MissionManager](INFO): DefineArg profile_station:NeedComms.GPSTimeout = 7.000000 min
2020-11-12T18:43:48.526Z,1605206628.526 [MissionManager](INFO): DefineArg profile_station:NeedComms.CommsTimeout = 30.000000 min
2020-11-12T18:43:48.554Z,1605206628.554 [profile_station:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-11-12T18:43:48.611Z,1605206628.611 [MissionManager](INFO): Inserting Stack: Missions/Insert/StandardEnvelopes.xml
2020-11-12T18:43:48.665Z,1605206628.665 [MissionManager](INFO): DefineArg profile_station:StandardEnvelopes.MinAltitude = 5.000000 m
2020-11-12T18:43:48.668Z,1605206628.668 [MissionManager](INFO): DefineArg profile_station:StandardEnvelopes.MaxDepth = 200.000000 m
2020-11-12T18:43:48.670Z,1605206628.670 [MissionManager](INFO): DefineArg profile_station:StandardEnvelopes.MinOffshore = 2000.000000 m
2020-11-12T18:43:48.699Z,1605206628.699 [profile_station:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2020-11-12T18:43:48.703Z,1605206628.703 [profile_station:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2020-11-12T18:43:48.709Z,1605206628.709 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2020-11-12T18:43:48.717Z,1605206628.717 [profile_station:I.Pitch](DEBUG): Construct.
2020-11-12T18:43:48.734Z,1605206628.734 [profile_station:J.Buoyancy](DEBUG): Construct Buoyancy.
2020-11-12T18:43:48.736Z,1605206628.736 [profile_station:K.SetSpeed](DEBUG): Construct.
2020-11-12T18:43:48.747Z,1605206628.747 [profile_station:L.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2020-11-12T18:43:48.753Z,1605206628.753 [profile_station:M.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2020-11-12T18:43:48.779Z,1605206628.779 [profile_station:N.YoYo](DEBUG): Construct YoYo.
2020-11-12T18:43:48.786Z,1605206628.786 [profile_station:CircleWrapper:B.Circle](DEBUG): Construct.
2020-11-12T18:43:48.937Z,1605206628.937 [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>


2020-11-12T18:43:48.937Z,1605206628.937 [CommandLine](IMPORTANT): Loaded ./Missions/Science/profile_station.xml
2020-11-12T18:43:57.039Z,1605206637.039 [CommandLine](IMPORTANT): got command show variable profile_station
2020-11-12T18:43:57.185Z,1605206637.185 [CommandLine](IMPORTANT): profile_station.MissionTimeout (hour)
2020-11-12T18:43:57.186Z,1605206637.186 [CommandLine](IMPORTANT): profile_station.NeedCommsTime (minute)
2020-11-12T18:43:57.186Z,1605206637.186 [CommandLine](IMPORTANT): profile_station.Lat (degree)
2020-11-12T18:43:57.187Z,1605206637.187 [CommandLine](IMPORTANT): profile_station.Lon (degree)
2020-11-12T18:43:57.187Z,1605206637.187 [CommandLine](IMPORTANT): profile_station.Radius (meter)
2020-11-12T18:43:57.187Z,1605206637.187 [CommandLine](IMPORTANT): profile_station.YoYoMinDepth (meter)
2020-11-12T18:43:57.188Z,1605206637.188 [CommandLine](IMPORTANT): profile_station.YoYoMaxDepth (meter)
2020-11-12T18:43:57.188Z,1605206637.188 [CommandLine](IMPORTANT): profile_station.YoYoMinAltitude (meter)
2020-11-12T18:43:57.189Z,1605206637.189 [CommandLine](IMPORTANT): profile_station.YoYoPitch (degree)
2020-11-12T18:43:57.189Z,1605206637.189 [CommandLine](IMPORTANT): profile_station.Speed (meter_per_second)
2020-11-12T18:43:57.189Z,1605206637.189 [CommandLine](IMPORTANT): profile_station.CircleMaxError (meter)
2020-11-12T18:43:57.190Z,1605206637.190 [CommandLine](IMPORTANT): profile_station.CircleTurnToPort (bool)
2020-11-12T18:43:57.226Z,1605206637.226 [CommandLine](IMPORTANT): profile_station.KwpHeading (radian_per_meter)
2020-11-12T18:43:57.227Z,1605206637.227 [CommandLine](IMPORTANT): profile_station.MinAltitude (meter)
2020-11-12T18:43:57.227Z,1605206637.227 [CommandLine](IMPORTANT): profile_station.MaxDepth (meter)
2020-11-12T18:43:57.227Z,1605206637.227 [CommandLine](IMPORTANT): profile_station.MinOffshore (kilometer)
2020-11-12T18:43:57.228Z,1605206637.228 [CommandLine](IMPORTANT): profile_station:Science.PeakDetectChlActive (bool)
2020-11-12T18:43:57.228Z,1605206637.228 [CommandLine](IMPORTANT): profile_station:Science.TimeWindowPeakReport (minute)
2020-11-12T18:43:57.229Z,1605206637.229 [CommandLine](IMPORTANT): profile_station:Science.HighestChlPeakReportActive (bool)
2020-11-12T18:43:57.229Z,1605206637.229 [CommandLine](IMPORTANT): profile_station:Science.PatchTracking (bool)
2020-11-12T18:43:57.229Z,1605206637.229 [CommandLine](IMPORTANT): profile_station:Science.FilterWidthHorizontal (count)
2020-11-12T18:43:57.230Z,1605206637.230 [CommandLine](IMPORTANT): profile_station:Science.NumProfilesSlidingwindow (count)
2020-11-12T18:43:57.230Z,1605206637.230 [CommandLine](IMPORTANT): profile_station:Science.OffPeakFractionHorizontal (percent)
2020-11-12T18:43:57.231Z,1605206637.231 [CommandLine](IMPORTANT): profile_station:Science.PeakDetectNO3Active (bool)
2020-11-12T18:43:57.231Z,1605206637.231 [CommandLine](IMPORTANT): profile_station:Science.PeakDetectOilActive (bool)
2020-11-12T18:43:57.231Z,1605206637.231 [CommandLine](IMPORTANT): profile_station:Science.PeakDetectFDOMActive (bool)
2020-11-12T18:43:57.232Z,1605206637.232 [CommandLine](IMPORTANT): profile_station:Science.UpwardDerivativeOfTemperatureActive (bool)
2020-11-12T18:43:57.232Z,1605206637.232 [CommandLine](IMPORTANT): profile_station:Science.EnabledAanderaaO2 (bool)
2020-11-12T18:43:57.232Z,1605206637.232 [CommandLine](IMPORTANT): profile_station:Science.EnabledNeilBrown (bool)
2020-11-12T18:43:57.233Z,1605206637.233 [CommandLine](IMPORTANT): profile_station:Science.EnabledSeabird (bool)
2020-11-12T18:43:57.233Z,1605206637.233 [CommandLine](IMPORTANT): profile_station:Science.EnabledWetLabsBB2FL (bool)
2020-11-12T18:43:57.234Z,1605206637.234 [CommandLine](IMPORTANT): profile_station:Science.EnabledWetLabsSeaOWL_UV_A (bool)
2020-11-12T18:43:57.266Z,1605206637.266 [CommandLine](IMPORTANT): profile_station:Science.EnabledWetLabsUBAT (bool)
2020-11-12T18:43:57.267Z,1605206637.267 [CommandLine](IMPORTANT): profile_station:Science.LowPassWindowLength (count)
2020-11-12T18:43:57.267Z,1605206637.267 [CommandLine](IMPORTANT): profile_station:Science.MedianFilterLen (count)
2020-11-12T18:43:57.267Z,1605206637.267 [CommandLine](IMPORTANT): profile_station:Science.PeakChlShallowBound (meter)
2020-11-12T18:43:57.268Z,1605206637.268 [CommandLine](IMPORTANT): profile_station:Science.PeakChlDeepBound (meter)
2020-11-12T18:43:57.268Z,1605206637.268 [CommandLine](IMPORTANT): profile_station:Science.PeakShallowBound (meter)
2020-11-12T18:43:57.269Z,1605206637.269 [CommandLine](IMPORTANT): profile_station:Science.PeakDeepBound (meter)
2020-11-12T18:43:57.269Z,1605206637.269 [CommandLine](IMPORTANT): profile_station:Science.PeakChl (microgram_per_liter)
2020-11-12T18:43:57.269Z,1605206637.269 [CommandLine](IMPORTANT): profile_station:Science.PeakChlDepth (meter)
2020-11-12T18:43:57.270Z,1605206637.270 [CommandLine](IMPORTANT): profile_station:Science.PeakChlTemperature (celsius)
2020-11-12T18:43:57.270Z,1605206637.270 [CommandLine](IMPORTANT): profile_station:Science.PeakChlLatitude (degree)
2020-11-12T18:43:57.271Z,1605206637.271 [CommandLine](IMPORTANT): profile_station:Science.PeakChlLongitude (degree)
2020-11-12T18:43:57.271Z,1605206637.271 [CommandLine](IMPORTANT): profile_station:Science.PeakNO3 (micromole_per_liter)
2020-11-12T18:43:57.271Z,1605206637.271 [CommandLine](IMPORTANT): profile_station:Science.PeakNO3Depth (meter)
2020-11-12T18:43:57.272Z,1605206637.272 [CommandLine](IMPORTANT): profile_station:Science.PeakNO3Latitude (degree)
2020-11-12T18:43:57.272Z,1605206637.272 [CommandLine](IMPORTANT): profile_station:Science.PeakNO3Longitude (degree)
2020-11-12T18:43:57.272Z,1605206637.272 [CommandLine](IMPORTANT): profile_station:Science.PatchChl (meter)
2020-11-12T18:43:57.273Z,1605206637.273 [CommandLine](IMPORTANT): profile_station:Science.PatchChlDepth (meter)
2020-11-12T18:43:57.273Z,1605206637.273 [CommandLine](IMPORTANT): profile_station:Science.PatchChlLatitude (degree)
2020-11-12T18:43:57.274Z,1605206637.274 [CommandLine](IMPORTANT): profile_station:Science.PatchChlLongitude (degree)
2020-11-12T18:43:57.274Z,1605206637.274 [CommandLine](IMPORTANT): profile_station:Science.PatchChlDistance (meter)
2020-11-12T18:43:57.274Z,1605206637.274 [CommandLine](IMPORTANT): profile_station:Science.PeakOil (kilogram_per_cubic_meter)
2020-11-12T18:43:57.275Z,1605206637.275 [CommandLine](IMPORTANT): profile_station:Science.PeakOilDepth (meter)
2020-11-12T18:43:57.275Z,1605206637.275 [CommandLine](IMPORTANT): profile_station:Science.PeakOilLatitude (degree)
2020-11-12T18:43:57.276Z,1605206637.276 [CommandLine](IMPORTANT): profile_station:Science.PeakOilLongitude (degree)
2020-11-12T18:43:57.276Z,1605206637.276 [CommandLine](IMPORTANT): profile_station:Science.PeakFDOM (part_per_billion)
2020-11-12T18:43:57.276Z,1605206637.276 [CommandLine](IMPORTANT): profile_station:Science.PeakFDOMDepth (meter)
2020-11-12T18:43:57.277Z,1605206637.277 [CommandLine](IMPORTANT): profile_station:Science.PeakFDOMLatitude (degree)
2020-11-12T18:43:57.277Z,1605206637.277 [CommandLine](IMPORTANT): profile_station:Science.PeakFDOMLongitude (degree)
2020-11-12T18:43:57.277Z,1605206637.277 [CommandLine](IMPORTANT): profile_station:Science.PatchFDOMDistance (meter)
2020-11-12T18:43:57.278Z,1605206637.278 [CommandLine](IMPORTANT): profile_station:NeedComms.DiveInterval (hour)
2020-11-12T18:43:57.319Z,1605206637.319 [CommandLine](IMPORTANT): profile_station:NeedComms.WaitForPitchUp (minute)
2020-11-12T18:43:57.319Z,1605206637.319 [CommandLine](IMPORTANT): profile_station:NeedComms.SurfacePitch (degree)
2020-11-12T18:43:57.319Z,1605206637.319 [CommandLine](IMPORTANT): profile_station:NeedComms.SurfaceDepthRate (meter_per_second)
2020-11-12T18:43:57.320Z,1605206637.320 [CommandLine](IMPORTANT): profile_station:NeedComms.SurfaceSpeed (meter_per_second)
2020-11-12T18:43:57.320Z,1605206637.320 [CommandLine](IMPORTANT): profile_station:NeedComms.GPSTimeout (minute)
2020-11-12T18:43:57.321Z,1605206637.321 [CommandLine](IMPORTANT): profile_station:NeedComms.CommsTimeout (minute)
2020-11-12T18:43:57.321Z,1605206637.321 [CommandLine](IMPORTANT): profile_station:StandardEnvelopes.MinAltitude (meter)
2020-11-12T18:43:57.321Z,1605206637.321 [CommandLine](IMPORTANT): profile_station:StandardEnvelopes.MaxDepth (meter)
2020-11-12T18:43:57.322Z,1605206637.322 [CommandLine](IMPORTANT): profile_station:StandardEnvelopes.MinOffshore (meter)
2020-11-12T18:45:50.866Z,1605206750.866 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-11-12T18:45:50.868Z,1605206750.868 [BPC1](INFO): Received data from all battery sticks.
2020-11-12T18:46:02.731Z,1605206762.731 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-11-12T18:46:33.855Z,1605206793.855 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-11-12T18:47:04.978Z,1605206824.978 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-11-12T18:47:36.127Z,1605206856.127 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-11-12T18:48:30.453Z,1605206910.453 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T18:48:30.453Z,1605206910.453 [Default:CheckIn:C.Wait] Stopped
2020-11-12T18:48:30.453Z,1605206910.453 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T18:48:30.453Z,1605206910.453 [Default:CheckIn:D] Running Loop=1
2020-11-12T18:48:30.852Z,1605206910.852 [Default:CheckIn:D] Stopped
2020-11-12T18:48:30.852Z,1605206910.852 [Default:CheckIn:E] Running Loop=1
2020-11-12T18:48:31.251Z,1605206911.251 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 29.773029 min 
2020-11-12T18:48:31.251Z,1605206911.251 [Default:CheckIn:E] Stopped
2020-11-12T18:48:31.251Z,1605206911.251 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T18:48:31.251Z,1605206911.251 [Default:CheckIn] Stopped
2020-11-12T18:48:31.252Z,1605206911.252 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T18:48:31.252Z,1605206911.252 [Default:CheckIn](INFO): Running loop #6
2020-11-12T18:48:31.252Z,1605206911.252 [Default:CheckIn] Running Loop=6
2020-11-12T18:48:31.252Z,1605206911.252 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T18:48:31.252Z,1605206911.252 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T18:48:33.269Z,1605206913.269 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184831.00,A,3648.54755,N,12151.57803,W,0.875,122.00,121120,,,D*74
2020-11-12T18:48:33.271Z,1605206913.271 [NAL9602](INFO): GPS fix at 20201112T184831: (36.809126, -121.859634)
2020-11-12T18:48:33.281Z,1605206913.281 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T18:48:33.281Z,1605206913.281 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T18:48:37.859Z,1605206917.859 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-11-12T18:48:56.697Z,1605206936.697 [NAL9602](INFO): SBD MO Status=2, MOMSN=24075, MT Status=2, MTMSN=0
2020-11-12T18:48:56.697Z,1605206936.697 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-11-12T18:49:08.995Z,1605206948.995 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-11-12T18:49:31.445Z,1605206971.445 [NAL9602](INFO): SBD MO Status=2, MOMSN=24075, MT Status=2, MTMSN=0
2020-11-12T18:49:31.445Z,1605206971.445 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-11-12T18:49:40.119Z,1605206980.119 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-11-12T18:49:50.841Z,1605206990.841 [NAL9602](INFO): SBD MO Status=2, MOMSN=24075, MT Status=2, MTMSN=0
2020-11-12T18:49:50.841Z,1605206990.841 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-11-12T18:50:08.617Z,1605207008.617 [NAL9602](INFO): SBD MO Status=0, MOMSN=24075, MT Status=0, MTMSN=0
2020-11-12T18:50:08.617Z,1605207008.617 [NAL9602](INFO): No messages in MT queue
2020-11-12T18:50:11.247Z,1605207011.247 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-11-12T18:50:39.319Z,1605207039.319 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-12T18:50:42.371Z,1605207042.371 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-11-12T18:50:51.407Z,1605207051.407 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.735031
2020-11-12T18:51:06.020Z,1605207066.020 [NAL9602](INFO): SBD MO Status=1, MOMSN=24076, MT Status=0, MTMSN=0
2020-11-12T18:51:06.077Z,1605207066.077 [NAL9602](INFO): Sent 203 bytes from file Logs/20201112T184128/Courier0006.lzma
2020-11-12T18:51:06.077Z,1605207066.077 [NAL9602](INFO): Packets left to send: 0
2020-11-12T18:51:18.589Z,1605207078.589 [NAL9602](INFO): SBD MO Status=1, MOMSN=24077, MT Status=0, MTMSN=0
2020-11-12T18:51:18.642Z,1605207078.642 [NAL9602](INFO): Sent 332 bytes from file Logs/20201112T184128/Express0007.lzma
2020-11-12T18:51:18.643Z,1605207078.643 [NAL9602](INFO): Packets left to send: 4
2020-11-12T18:51:22.530Z,1605207082.530 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-11-12T18:51:29.947Z,1605207089.947 [NAL9602](INFO): SBD MO Status=2, MOMSN=24078, MT Status=2, MTMSN=0
2020-11-12T18:51:29.947Z,1605207089.947 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-11-12T18:51:53.655Z,1605207113.655 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-11-12T18:51:55.837Z,1605207115.837 [NAL9602](INFO): SBD MO Status=2, MOMSN=24078, MT Status=2, MTMSN=0
2020-11-12T18:51:55.837Z,1605207115.837 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-11-12T18:52:24.779Z,1605207144.779 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-11-12T18:52:47.583Z,1605207167.583 [NAL9602](INFO): SBD MO Status=2, MOMSN=24078, MT Status=2, MTMSN=0
2020-11-12T18:52:47.584Z,1605207167.584 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-11-12T18:52:55.902Z,1605207175.902 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-11-12T18:53:27.026Z,1605207207.026 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-11-12T18:53:58.151Z,1605207238.151 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-11-12T18:54:01.961Z,1605207241.961 [NAL9602](INFO): SBD MO Status=2, MOMSN=24078, MT Status=2, MTMSN=0
2020-11-12T18:54:01.961Z,1605207241.961 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-11-12T18:54:29.275Z,1605207269.275 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-11-12T18:54:31.895Z,1605207271.895 [NAL9602](INFO): SBD MO Status=2, MOMSN=24078, MT Status=2, MTMSN=0
2020-11-12T18:54:31.896Z,1605207271.896 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-11-12T18:54:44.081Z,1605207284.081 [NAL9602](INFO): SBD MO Status=1, MOMSN=24078, MT Status=0, MTMSN=0
2020-11-12T18:54:44.142Z,1605207284.142 [NAL9602](INFO): Sent 332 bytes from file Logs/20201112T184128/Express0007.lzma
2020-11-12T18:54:44.143Z,1605207284.143 [NAL9602](INFO): Packets left to send: 3
2020-11-12T18:54:58.659Z,1605207298.659 [NAL9602](INFO): SBD MO Status=1, MOMSN=24079, MT Status=0, MTMSN=0
2020-11-12T18:54:58.710Z,1605207298.710 [NAL9602](INFO): Sent 332 bytes from file Logs/20201112T184128/Express0007.lzma
2020-11-12T18:54:58.711Z,1605207298.711 [NAL9602](INFO): Packets left to send: 2
2020-11-12T18:55:00.403Z,1605207300.403 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-11-12T18:55:15.693Z,1605207315.693 [NAL9602](INFO): SBD MO Status=2, MOMSN=24080, MT Status=2, MTMSN=0
2020-11-12T18:55:15.693Z,1605207315.693 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-11-12T18:55:31.527Z,1605207331.527 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-11-12T18:55:41.604Z,1605207341.604 [NAL9602](INFO): SBD MO Status=2, MOMSN=24080, MT Status=2, MTMSN=0
2020-11-12T18:55:41.604Z,1605207341.604 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-11-12T18:56:02.659Z,1605207362.659 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-11-12T18:56:12.341Z,1605207372.341 [NAL9602](INFO): SBD MO Status=1, MOMSN=24080, MT Status=0, MTMSN=0
2020-11-12T18:56:12.390Z,1605207372.390 [NAL9602](INFO): Sent 332 bytes from file Logs/20201112T184128/Express0007.lzma
2020-11-12T18:56:12.391Z,1605207372.391 [NAL9602](INFO): Packets left to send: 1
2020-11-12T18:56:32.167Z,1605207392.167 [NAL9602](INFO): SBD MO Status=2, MOMSN=24081, MT Status=2, MTMSN=0
2020-11-12T18:56:32.167Z,1605207392.167 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-11-12T18:56:33.783Z,1605207393.783 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-11-12T18:56:43.927Z,1605207403.927 [NAL9602](INFO): SBD MO Status=1, MOMSN=24081, MT Status=0, MTMSN=0
2020-11-12T18:56:43.975Z,1605207403.975 [NAL9602](INFO): Sent 125 bytes from file Logs/20201112T184128/Express0007.lzma
2020-11-12T18:56:43.975Z,1605207403.975 [NAL9602](INFO): Packets left to send: 0
2020-11-12T18:56:53.617Z,1605207413.617 [NAL9602](INFO): SBD MO Status=0, MOMSN=24082, MT Status=0, MTMSN=0
2020-11-12T18:56:53.694Z,1605207413.694 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T18:56:53.694Z,1605207413.694 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T18:56:53.694Z,1605207413.694 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T18:57:04.914Z,1605207424.914 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-11-12T18:57:24.333Z,1605207444.333 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-12T18:57:36.039Z,1605207456.039 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-11-12T18:58:07.163Z,1605207487.163 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-11-12T18:58:38.286Z,1605207518.286 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-11-12T18:59:09.411Z,1605207549.411 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-11-12T18:59:40.534Z,1605207580.534 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-11-12T19:00:08.959Z,1605207608.959 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003646
2020-11-12T19:01:54.217Z,1605207714.217 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T19:01:54.217Z,1605207714.217 [Default:CheckIn:C.Wait] Stopped
2020-11-12T19:01:54.217Z,1605207714.217 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T19:01:54.217Z,1605207714.217 [Default:CheckIn:D] Running Loop=1
2020-11-12T19:01:54.616Z,1605207714.616 [Default:CheckIn:D] Stopped
2020-11-12T19:01:54.616Z,1605207714.616 [Default:CheckIn:E] Running Loop=1
2020-11-12T19:01:55.027Z,1605207715.027 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 43.169100 min 
2020-11-12T19:01:55.028Z,1605207715.028 [Default:CheckIn:E] Stopped
2020-11-12T19:01:55.028Z,1605207715.028 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T19:01:55.028Z,1605207715.028 [Default:CheckIn] Stopped
2020-11-12T19:01:55.028Z,1605207715.028 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T19:01:55.028Z,1605207715.028 [Default:CheckIn](INFO): Running loop #7
2020-11-12T19:01:55.028Z,1605207715.028 [Default:CheckIn] Running Loop=7
2020-11-12T19:01:55.028Z,1605207715.028 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T19:01:55.028Z,1605207715.028 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T19:01:57.033Z,1605207717.033 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190155.00,A,3648.30388,N,12147.13257,W,4.374,185.06,121120,,,D*75
2020-11-12T19:01:57.035Z,1605207717.035 [NAL9602](INFO): GPS fix at 20201112T190155: (36.805065, -121.785543)
2020-11-12T19:01:57.045Z,1605207717.045 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T19:01:57.045Z,1605207717.045 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T19:02:04.786Z,1605207724.786 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201112T184128/Courier0009.lzma
2020-11-12T19:02:05.788Z,1605207725.788 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Courier0009.lzma.bak
2020-11-12T19:02:05.788Z,1605207725.788 [DataOverHttps](INFO): SBD MOMSN=13194914
2020-11-12T19:02:24.554Z,1605207744.554 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20201112T184128/Express0010.lzma
2020-11-12T19:02:25.556Z,1605207745.556 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Express0010.lzma.bak
2020-11-12T19:02:25.556Z,1605207745.556 [DataOverHttps](INFO): SBD MOMSN=13194917
2020-11-12T19:02:27.026Z,1605207747.026 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T19:02:27.026Z,1605207747.026 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T19:02:27.026Z,1605207747.026 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T19:02:30.241Z,1605207750.241 [NAL9602](INFO): SBD MO Status=0, MOMSN=24083, MT Status=0, MTMSN=0
2020-11-12T19:02:30.241Z,1605207750.241 [NAL9602](INFO): No messages in MT queue
2020-11-12T19:03:00.979Z,1605207780.979 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-12T19:07:27.670Z,1605208047.670 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T19:07:27.670Z,1605208047.670 [Default:CheckIn:C.Wait] Stopped
2020-11-12T19:07:27.670Z,1605208047.670 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T19:07:27.670Z,1605208047.670 [Default:CheckIn:D] Running Loop=1
2020-11-12T19:07:28.074Z,1605208048.074 [Default:CheckIn:D] Stopped
2020-11-12T19:07:28.074Z,1605208048.074 [Default:CheckIn:E] Running Loop=1
2020-11-12T19:07:28.467Z,1605208048.467 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 48.726733 min 
2020-11-12T19:07:28.467Z,1605208048.467 [Default:CheckIn:E] Stopped
2020-11-12T19:07:28.467Z,1605208048.467 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T19:07:28.467Z,1605208048.467 [Default:CheckIn] Stopped
2020-11-12T19:07:28.467Z,1605208048.467 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T19:07:28.468Z,1605208048.468 [Default:CheckIn](INFO): Running loop #8
2020-11-12T19:07:28.468Z,1605208048.468 [Default:CheckIn] Running Loop=8
2020-11-12T19:07:28.468Z,1605208048.468 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T19:07:28.468Z,1605208048.468 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T19:07:30.481Z,1605208050.481 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190729.00,A,3648.16517,N,12147.21443,W,0.019,8.90,121120,,,D*79
2020-11-12T19:07:30.483Z,1605208050.483 [NAL9602](INFO): GPS fix at 20201112T190729: (36.802753, -121.786907)
2020-11-12T19:07:30.493Z,1605208050.493 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T19:07:30.493Z,1605208050.493 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T19:07:55.933Z,1605208075.933 [NAL9602](INFO): SBD MO Status=0, MOMSN=24084, MT Status=0, MTMSN=0
2020-11-12T19:07:55.933Z,1605208075.933 [NAL9602](INFO): No messages in MT queue
2020-11-12T19:08:02.454Z,1605208082.454 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20201112T184128/Courier0012.lzma
2020-11-12T19:08:03.456Z,1605208083.456 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Courier0012.lzma.bak
2020-11-12T19:08:03.456Z,1605208083.456 [DataOverHttps](INFO): SBD MOMSN=13195017
2020-11-12T19:08:20.150Z,1605208100.150 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20201112T184128/Express0013.lzma
2020-11-12T19:08:21.152Z,1605208101.152 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Express0013.lzma.bak
2020-11-12T19:08:21.152Z,1605208101.152 [DataOverHttps](INFO): SBD MOMSN=13195023
2020-11-12T19:08:22.634Z,1605208102.634 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T19:08:22.634Z,1605208102.634 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T19:08:22.634Z,1605208102.634 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T19:08:26.667Z,1605208106.667 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-12T19:13:23.356Z,1605208403.356 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T19:13:23.356Z,1605208403.356 [Default:CheckIn:C.Wait] Stopped
2020-11-12T19:13:23.357Z,1605208403.357 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T19:13:23.357Z,1605208403.357 [Default:CheckIn:D] Running Loop=1
2020-11-12T19:13:23.761Z,1605208403.761 [Default:CheckIn:D] Stopped
2020-11-12T19:13:23.761Z,1605208403.761 [Default:CheckIn:E] Running Loop=1
2020-11-12T19:13:24.169Z,1605208404.169 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 54.654838 min 
2020-11-12T19:13:24.169Z,1605208404.169 [Default:CheckIn:E] Stopped
2020-11-12T19:13:24.170Z,1605208404.170 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T19:13:24.170Z,1605208404.170 [Default:CheckIn] Stopped
2020-11-12T19:13:24.170Z,1605208404.170 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T19:13:24.170Z,1605208404.170 [Default:CheckIn](INFO): Running loop #9
2020-11-12T19:13:24.170Z,1605208404.170 [Default:CheckIn] Running Loop=9
2020-11-12T19:13:24.170Z,1605208404.170 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T19:13:24.170Z,1605208404.170 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T19:13:26.177Z,1605208406.177 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191324.00,A,3648.15000,N,12147.22028,W,0.253,200.15,121120,,,A*75
2020-11-12T19:13:26.179Z,1605208406.179 [NAL9602](INFO): GPS fix at 20201112T191324: (36.802500, -121.787005)
2020-11-12T19:13:26.189Z,1605208406.189 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T19:13:26.189Z,1605208406.189 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T19:13:32.814Z,1605208412.814 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20201112T184128/Courier0015.lzma
2020-11-12T19:13:33.816Z,1605208413.816 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Courier0015.lzma.bak
2020-11-12T19:13:33.816Z,1605208413.816 [DataOverHttps](INFO): SBD MOMSN=13195032
2020-11-12T19:13:42.735Z,1605208422.735 [NAL9602](INFO): SBD MO Status=0, MOMSN=24085, MT Status=0, MTMSN=0
2020-11-12T19:13:42.735Z,1605208422.735 [NAL9602](INFO): No messages in MT queue
2020-11-12T19:13:49.483Z,1605208429.483 [DataOverHttps](INFO): Sending 138 bytes from file Logs/20201112T184128/Express0016.lzma
2020-11-12T19:13:50.484Z,1605208430.484 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Express0016.lzma.bak
2020-11-12T19:13:50.484Z,1605208430.484 [DataOverHttps](INFO): SBD MOMSN=13195035
2020-11-12T19:13:51.646Z,1605208431.646 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T19:13:51.646Z,1605208431.646 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T19:13:51.646Z,1605208431.646 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T19:14:13.437Z,1605208453.437 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-12T19:18:52.371Z,1605208732.371 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T19:18:52.371Z,1605208732.371 [Default:CheckIn:C.Wait] Stopped
2020-11-12T19:18:52.371Z,1605208732.371 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T19:18:52.371Z,1605208732.371 [Default:CheckIn:D] Running Loop=1
2020-11-12T19:18:52.777Z,1605208732.777 [Default:CheckIn:D] Stopped
2020-11-12T19:18:52.777Z,1605208732.777 [Default:CheckIn:E] Running Loop=1
2020-11-12T19:18:53.185Z,1605208733.185 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 60.138436 min 
2020-11-12T19:18:53.185Z,1605208733.185 [Default:CheckIn:E] Stopped
2020-11-12T19:18:53.185Z,1605208733.185 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T19:18:53.185Z,1605208733.185 [Default:CheckIn] Stopped
2020-11-12T19:18:53.185Z,1605208733.185 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T19:18:53.186Z,1605208733.186 [Default:CheckIn](INFO): Running loop #10
2020-11-12T19:18:53.186Z,1605208733.186 [Default:CheckIn] Running Loop=10
2020-11-12T19:18:53.186Z,1605208733.186 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T19:18:53.186Z,1605208733.186 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T19:18:55.203Z,1605208735.203 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191853.00,A,3648.16882,N,12147.27665,W,0.253,216.78,121120,,,A*79
2020-11-12T19:18:55.205Z,1605208735.205 [NAL9602](INFO): GPS fix at 20201112T191853: (36.802814, -121.787944)
2020-11-12T19:18:55.215Z,1605208735.215 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T19:18:55.215Z,1605208735.215 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T19:19:02.194Z,1605208742.194 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20201112T184128/Courier0018.lzma
2020-11-12T19:19:03.196Z,1605208743.196 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Courier0018.lzma.bak
2020-11-12T19:19:03.196Z,1605208743.196 [DataOverHttps](INFO): SBD MOMSN=13195141
2020-11-12T19:19:18.954Z,1605208758.954 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20201112T184128/Express0019.lzma
2020-11-12T19:19:19.956Z,1605208759.956 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Express0019.lzma.bak
2020-11-12T19:19:19.956Z,1605208759.956 [DataOverHttps](INFO): SBD MOMSN=13195144
2020-11-12T19:19:21.461Z,1605208761.461 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T19:19:21.461Z,1605208761.461 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T19:19:21.461Z,1605208761.461 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T19:20:26.941Z,1605208826.941 [NAL9602](INFO): SBD MO Status=2, MOMSN=24086, MT Status=2, MTMSN=0
2020-11-12T19:20:26.941Z,1605208826.941 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-11-12T19:23:09.445Z,1605208989.445 [NAL9602](INFO): SBD MO Status=2, MOMSN=24086, MT Status=2, MTMSN=0
2020-11-12T19:23:09.445Z,1605208989.445 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-11-12T19:23:57.920Z,1605209037.920 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-11-12T19:24:22.171Z,1605209062.171 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T19:24:22.171Z,1605209062.171 [Default:CheckIn:C.Wait] Stopped
2020-11-12T19:24:22.171Z,1605209062.171 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T19:24:22.171Z,1605209062.171 [Default:CheckIn:D] Running Loop=1
2020-11-12T19:24:22.580Z,1605209062.580 [Default:CheckIn:D] Stopped
2020-11-12T19:24:22.580Z,1605209062.580 [Default:CheckIn:E] Running Loop=1
2020-11-12T19:24:23.000Z,1605209063.000 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 65.635160 min 
2020-11-12T19:24:23.000Z,1605209063.000 [Default:CheckIn:E] Stopped
2020-11-12T19:24:23.000Z,1605209063.000 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T19:24:23.001Z,1605209063.001 [Default:CheckIn] Stopped
2020-11-12T19:24:23.001Z,1605209063.001 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T19:24:23.001Z,1605209063.001 [Default:CheckIn](INFO): Running loop #11
2020-11-12T19:24:23.001Z,1605209063.001 [Default:CheckIn] Running Loop=11
2020-11-12T19:24:23.001Z,1605209063.001 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T19:24:23.001Z,1605209063.001 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T19:24:24.999Z,1605209064.999 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192423.00,A,3648.16753,N,12147.28939,W,1.419,216.78,121120,,,A*72
2020-11-12T19:24:25.001Z,1605209065.001 [NAL9602](INFO): GPS fix at 20201112T192423: (36.802792, -121.788156)
2020-11-12T19:24:25.018Z,1605209065.018 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T19:24:25.018Z,1605209065.018 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T19:24:34.479Z,1605209074.479 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201112T184128/Courier0021.lzma
2020-11-12T19:24:35.424Z,1605209075.424 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Courier0021.lzma.bak
2020-11-12T19:24:35.424Z,1605209075.424 [DataOverHttps](INFO): SBD MOMSN=13195154
2020-11-12T19:24:51.058Z,1605209091.058 [DataOverHttps](INFO): Sending 243 bytes from file Logs/20201112T184128/Express0022.lzma
2020-11-12T19:24:52.060Z,1605209092.060 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Express0022.lzma.bak
2020-11-12T19:24:52.060Z,1605209092.060 [DataOverHttps](INFO): SBD MOMSN=13195157
2020-11-12T19:24:53.346Z,1605209093.346 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T19:24:53.346Z,1605209093.346 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T19:24:53.346Z,1605209093.346 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T19:24:56.971Z,1605209096.971 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-12T19:29:54.041Z,1605209394.041 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T19:29:54.041Z,1605209394.041 [Default:CheckIn:C.Wait] Stopped
2020-11-12T19:29:54.041Z,1605209394.041 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T19:29:54.041Z,1605209394.041 [Default:CheckIn:D] Running Loop=1
2020-11-12T19:29:54.454Z,1605209394.454 [Default:CheckIn:D] Stopped
2020-11-12T19:29:54.454Z,1605209394.454 [Default:CheckIn:E] Running Loop=1
2020-11-12T19:29:54.849Z,1605209394.849 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 71.166382 min 
2020-11-12T19:29:54.849Z,1605209394.849 [Default:CheckIn:E] Stopped
2020-11-12T19:29:54.849Z,1605209394.849 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T19:29:54.849Z,1605209394.849 [Default:CheckIn] Stopped
2020-11-12T19:29:54.849Z,1605209394.849 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T19:29:54.849Z,1605209394.849 [Default:CheckIn](INFO): Running loop #12
2020-11-12T19:29:54.849Z,1605209394.849 [Default:CheckIn] Running Loop=12
2020-11-12T19:29:54.849Z,1605209394.849 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T19:29:54.849Z,1605209394.849 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T19:29:56.861Z,1605209396.861 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192955.00,A,3648.16463,N,12147.28143,W,0.136,236.23,121120,,,A*7E
2020-11-12T19:29:56.863Z,1605209396.863 [NAL9602](INFO): GPS fix at 20201112T192955: (36.802744, -121.788024)
2020-11-12T19:29:56.873Z,1605209396.873 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T19:29:56.873Z,1605209396.873 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T19:30:04.106Z,1605209404.106 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201112T184128/Courier0024.lzma
2020-11-12T19:30:05.108Z,1605209405.108 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Courier0024.lzma.bak
2020-11-12T19:30:05.109Z,1605209405.109 [DataOverHttps](INFO): SBD MOMSN=13195258
2020-11-12T19:30:23.610Z,1605209423.610 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20201112T184128/Express0025.lzma
2020-11-12T19:30:24.608Z,1605209424.608 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Express0025.lzma.bak
2020-11-12T19:30:24.608Z,1605209424.608 [DataOverHttps](INFO): SBD MOMSN=13195261
2020-11-12T19:30:26.015Z,1605209426.015 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T19:30:26.015Z,1605209426.015 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T19:30:26.015Z,1605209426.015 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T19:30:27.616Z,1605209427.616 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2020-11-12T19:30:27.706Z,1605209427.706 [NAL9602](FAULT): received: 
+CSQ:0

OK086, 2, 0, 0, 0

OK
2020-11-12T19:30:27.706Z,1605209427.706 [NAL9602] Data Fault, FailCount= 1
2020-11-12T19:30:27.706Z,1605209427.706 [NAL9602](ERROR): Data Fault
2020-11-12T19:30:27.796Z,1605209427.796 [CBIT](ERROR): Data Fault in component: NAL9602
2020-11-12T19:30:28.016Z,1605209428.016 [NAL9602](INFO): Powering down
2020-11-12T19:30:28.429Z,1605209428.429 [NAL9602](FAULT): LCB fault:  Software Overcurrent.
2020-11-12T19:30:28.429Z,1605209428.429 [NAL9602] Hardware Fault, FailCount= 1
2020-11-12T19:30:28.429Z,1605209428.429 [NAL9602](ERROR): Hardware Fault
2020-11-12T19:30:28.928Z,1605209428.928 [CBIT](INFO): Clearing failed state for component NAL9602
2020-11-12T19:30:28.928Z,1605209428.928 [NAL9602] No Fault, FailCount= 1
2020-11-12T19:30:58.361Z,1605209458.361 [NAL9602](INFO): Powering up NAL9602
2020-11-12T19:31:09.275Z,1605209469.275 [NAL9602](INFO): NAL9602 initialized
2020-11-12T19:31:40.385Z,1605209500.385 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-12T19:35:26.683Z,1605209726.683 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T19:35:26.683Z,1605209726.683 [Default:CheckIn:C.Wait] Stopped
2020-11-12T19:35:26.683Z,1605209726.683 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T19:35:26.683Z,1605209726.683 [Default:CheckIn:D] Running Loop=1
2020-11-12T19:35:27.107Z,1605209727.107 [Default:CheckIn:D] Stopped
2020-11-12T19:35:27.107Z,1605209727.107 [Default:CheckIn:E] Running Loop=1
2020-11-12T19:35:27.484Z,1605209727.484 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 76.710262 min 
2020-11-12T19:35:27.484Z,1605209727.484 [Default:CheckIn:E] Stopped
2020-11-12T19:35:27.484Z,1605209727.484 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T19:35:27.484Z,1605209727.484 [Default:CheckIn] Stopped
2020-11-12T19:35:27.484Z,1605209727.484 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T19:35:27.484Z,1605209727.484 [Default:CheckIn](INFO): Running loop #13
2020-11-12T19:35:27.484Z,1605209727.484 [Default:CheckIn] Running Loop=13
2020-11-12T19:35:27.485Z,1605209727.485 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T19:35:27.485Z,1605209727.485 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T19:35:29.501Z,1605209729.501 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193528.00,A,3648.16652,N,12147.28060,W,0.117,329.34,121120,,,A*73
2020-11-12T19:35:29.503Z,1605209729.503 [NAL9602](INFO): GPS fix at 20201112T193528: (36.802775, -121.788010)
2020-11-12T19:35:29.513Z,1605209729.513 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T19:35:29.513Z,1605209729.513 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T19:35:31.519Z,1605209731.519 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-11-12T19:35:38.274Z,1605209738.274 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20201112T184128/Courier0027.lzma
2020-11-12T19:35:39.276Z,1605209739.276 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Courier0027.lzma.bak
2020-11-12T19:35:39.276Z,1605209739.276 [DataOverHttps](INFO): SBD MOMSN=13195271
2020-11-12T19:35:54.898Z,1605209754.898 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20201112T184128/Express0028.lzma
2020-11-12T19:35:55.900Z,1605209755.900 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Express0028.lzma.bak
2020-11-12T19:35:55.900Z,1605209755.900 [DataOverHttps](INFO): SBD MOMSN=13195274
2020-11-12T19:35:57.399Z,1605209757.399 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T19:35:57.399Z,1605209757.399 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T19:35:57.399Z,1605209757.399 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T19:36:02.219Z,1605209762.219 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-12T19:40:58.018Z,1605210058.018 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T19:40:58.018Z,1605210058.018 [Default:CheckIn:C.Wait] Stopped
2020-11-12T19:40:58.019Z,1605210058.019 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T19:40:58.019Z,1605210058.019 [Default:CheckIn:D] Running Loop=1
2020-11-12T19:40:58.436Z,1605210058.436 [Default:CheckIn:D] Stopped
2020-11-12T19:40:58.436Z,1605210058.436 [Default:CheckIn:E] Running Loop=1
2020-11-12T19:40:58.825Z,1605210058.825 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 82.232756 min 
2020-11-12T19:40:58.825Z,1605210058.825 [Default:CheckIn:E] Stopped
2020-11-12T19:40:58.825Z,1605210058.825 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T19:40:58.825Z,1605210058.825 [Default:CheckIn] Stopped
2020-11-12T19:40:58.825Z,1605210058.825 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T19:40:58.825Z,1605210058.825 [Default:CheckIn](INFO): Running loop #14
2020-11-12T19:40:58.826Z,1605210058.826 [Default:CheckIn] Running Loop=14
2020-11-12T19:40:58.826Z,1605210058.826 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T19:40:58.826Z,1605210058.826 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T19:41:00.837Z,1605210060.837 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194059.00,A,3648.15989,N,12147.28280,W,0.058,329.34,121120,,,A*7B
2020-11-12T19:41:00.850Z,1605210060.850 [NAL9602](INFO): GPS fix at 20201112T194059: (36.802665, -121.788047)
2020-11-12T19:41:00.860Z,1605210060.860 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T19:41:00.860Z,1605210060.860 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T19:41:08.786Z,1605210068.786 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201112T184128/Courier0030.lzma
2020-11-12T19:41:09.788Z,1605210069.788 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Courier0030.lzma.bak
2020-11-12T19:41:09.788Z,1605210069.788 [DataOverHttps](INFO): SBD MOMSN=13195371
2020-11-12T19:41:25.502Z,1605210085.502 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20201112T184128/Express0031.lzma
2020-11-12T19:41:26.504Z,1605210086.504 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Express0031.lzma.bak
2020-11-12T19:41:26.504Z,1605210086.504 [DataOverHttps](INFO): SBD MOMSN=13195374
2020-11-12T19:41:27.915Z,1605210087.915 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T19:41:27.915Z,1605210087.915 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T19:41:27.915Z,1605210087.915 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T19:43:44.077Z,1605210224.077 [NAL9602](INFO): SBD MO Status=2, MOMSN=24086, MT Status=2, MTMSN=0
2020-11-12T19:43:44.077Z,1605210224.077 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-11-12T19:46:03.063Z,1605210363.063 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-11-12T19:46:28.515Z,1605210388.515 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T19:46:28.515Z,1605210388.515 [Default:CheckIn:C.Wait] Stopped
2020-11-12T19:46:28.515Z,1605210388.515 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T19:46:28.515Z,1605210388.515 [Default:CheckIn:D] Running Loop=1
2020-11-12T19:46:28.924Z,1605210388.924 [Default:CheckIn:D] Stopped
2020-11-12T19:46:28.924Z,1605210388.924 [Default:CheckIn:E] Running Loop=1
2020-11-12T19:46:29.339Z,1605210389.339 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 87.740894 min 
2020-11-12T19:46:29.339Z,1605210389.339 [Default:CheckIn:E] Stopped
2020-11-12T19:46:29.340Z,1605210389.340 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T19:46:29.340Z,1605210389.340 [Default:CheckIn] Stopped
2020-11-12T19:46:29.340Z,1605210389.340 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T19:46:29.340Z,1605210389.340 [Default:CheckIn](INFO): Running loop #15
2020-11-12T19:46:29.340Z,1605210389.340 [Default:CheckIn] Running Loop=15
2020-11-12T19:46:29.340Z,1605210389.340 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T19:46:29.340Z,1605210389.340 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T19:46:31.341Z,1605210391.341 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194630.00,A,3648.16406,N,12147.27691,W,0.097,329.34,121120,,,A*73
2020-11-12T19:46:31.343Z,1605210391.343 [NAL9602](INFO): GPS fix at 20201112T194630: (36.802734, -121.787948)
2020-11-12T19:46:31.385Z,1605210391.385 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T19:46:31.385Z,1605210391.385 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T19:46:38.798Z,1605210398.798 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201112T184128/Courier0033.lzma
2020-11-12T19:46:39.800Z,1605210399.800 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Courier0033.lzma.bak
2020-11-12T19:46:39.800Z,1605210399.800 [DataOverHttps](INFO): SBD MOMSN=13195385
2020-11-12T19:46:55.542Z,1605210415.542 [DataOverHttps](INFO): Sending 203 bytes from file Logs/20201112T184128/Express0034.lzma
2020-11-12T19:46:56.544Z,1605210416.544 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Express0034.lzma.bak
2020-11-12T19:46:56.544Z,1605210416.544 [DataOverHttps](INFO): SBD MOMSN=13195388
2020-11-12T19:46:58.041Z,1605210418.041 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T19:46:58.041Z,1605210418.041 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T19:46:58.041Z,1605210418.041 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T19:47:04.069Z,1605210424.069 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-12T19:51:43.307Z,1605210703.307 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2020-11-12T19:51:58.663Z,1605210718.663 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T19:51:58.664Z,1605210718.664 [Default:CheckIn:C.Wait] Stopped
2020-11-12T19:51:58.664Z,1605210718.664 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T19:51:58.664Z,1605210718.664 [Default:CheckIn:D] Running Loop=1
2020-11-12T19:51:59.073Z,1605210719.073 [Default:CheckIn:D] Stopped
2020-11-12T19:51:59.073Z,1605210719.073 [Default:CheckIn:E] Running Loop=1
2020-11-12T19:51:59.488Z,1605210719.488 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 93.243376 min 
2020-11-12T19:51:59.488Z,1605210719.488 [Default:CheckIn:E] Stopped
2020-11-12T19:51:59.488Z,1605210719.488 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T19:51:59.488Z,1605210719.488 [Default:CheckIn] Stopped
2020-11-12T19:51:59.488Z,1605210719.488 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T19:51:59.488Z,1605210719.488 [Default:CheckIn](INFO): Running loop #16
2020-11-12T19:51:59.488Z,1605210719.488 [Default:CheckIn] Running Loop=16
2020-11-12T19:51:59.489Z,1605210719.489 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T19:51:59.489Z,1605210719.489 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T19:52:01.489Z,1605210721.489 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195200.00,A,3648.16870,N,12147.27830,W,0.097,329.34,121120,,,A*7D
2020-11-12T19:52:01.491Z,1605210721.491 [NAL9602](INFO): GPS fix at 20201112T195200: (36.802812, -121.787972)
2020-11-12T19:52:01.523Z,1605210721.523 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T19:52:01.523Z,1605210721.523 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T19:52:08.350Z,1605210728.350 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20201112T184128/Courier0036.lzma
2020-11-12T19:52:09.352Z,1605210729.352 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Courier0036.lzma.bak
2020-11-12T19:52:09.352Z,1605210729.352 [DataOverHttps](INFO): SBD MOMSN=13195497
2020-11-12T19:52:28.298Z,1605210748.298 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20201112T184128/Express0037.lzma
2020-11-12T19:52:29.301Z,1605210749.301 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Express0037.lzma.bak
2020-11-12T19:52:29.301Z,1605210749.301 [DataOverHttps](INFO): SBD MOMSN=13195500
2020-11-12T19:52:30.658Z,1605210750.658 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T19:52:30.658Z,1605210750.658 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T19:52:30.658Z,1605210750.658 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T19:52:32.255Z,1605210752.255 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2020-11-12T19:52:32.339Z,1605210752.339 [NAL9602](FAULT): received: 
+CSQ:0

OK086, 2, 0, 0, 0

OK
2020-11-12T19:52:32.339Z,1605210752.339 [NAL9602] Data Fault, FailCount= 1
2020-11-12T19:52:32.339Z,1605210752.339 [NAL9602](ERROR): Data Fault
2020-11-12T19:52:32.428Z,1605210752.428 [CBIT](ERROR): Data Fault in component: NAL9602
2020-11-12T19:52:32.682Z,1605210752.682 [NAL9602](INFO): Powering down
2020-11-12T19:52:33.088Z,1605210753.088 [NAL9602](FAULT): LCB fault:  Software Overcurrent.
2020-11-12T19:52:33.088Z,1605210753.088 [NAL9602] Hardware Fault, FailCount= 1
2020-11-12T19:52:33.088Z,1605210753.088 [NAL9602](ERROR): Hardware Fault
2020-11-12T19:52:33.547Z,1605210753.547 [CBIT](INFO): Clearing failed state for component NAL9602
2020-11-12T19:52:33.548Z,1605210753.548 [NAL9602] No Fault, FailCount= 1
2020-11-12T19:53:03.048Z,1605210783.048 [NAL9602](INFO): Powering up NAL9602
2020-11-12T19:53:13.955Z,1605210793.955 [NAL9602](INFO): NAL9602 initialized
2020-11-12T19:53:18.809Z,1605210798.809 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-11-12T19:53:18.811Z,1605210798.811 [BPC1](INFO): Received data from all battery sticks.
2020-11-12T19:53:45.067Z,1605210825.067 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-12T19:57:31.320Z,1605211051.320 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T19:57:31.320Z,1605211051.320 [Default:CheckIn:C.Wait] Stopped
2020-11-12T19:57:31.320Z,1605211051.320 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T19:57:31.321Z,1605211051.321 [Default:CheckIn:D] Running Loop=1
2020-11-12T19:57:31.738Z,1605211051.738 [Default:CheckIn:D] Stopped
2020-11-12T19:57:31.738Z,1605211051.738 [Default:CheckIn:E] Running Loop=1
2020-11-12T19:57:32.127Z,1605211052.127 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 98.787785 min 
2020-11-12T19:57:32.127Z,1605211052.127 [Default:CheckIn:E] Stopped
2020-11-12T19:57:32.127Z,1605211052.127 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T19:57:32.127Z,1605211052.127 [Default:CheckIn] Stopped
2020-11-12T19:57:32.127Z,1605211052.127 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T19:57:32.128Z,1605211052.128 [Default:CheckIn](INFO): Running loop #17
2020-11-12T19:57:32.128Z,1605211052.128 [Default:CheckIn] Running Loop=17
2020-11-12T19:57:32.128Z,1605211052.128 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T19:57:32.128Z,1605211052.128 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T19:57:35.754Z,1605211055.754 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-11-12T19:57:36.973Z,1605211056.973 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195734.00,A,3648.17489,N,12147.27093,W,0.486,64.71,121120,,,A*4A
2020-11-12T19:57:36.975Z,1605211056.975 [NAL9602](INFO): GPS fix at 20201112T195734: (36.802915, -121.787849)
2020-11-12T19:57:36.985Z,1605211056.985 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T19:57:36.985Z,1605211056.985 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T19:57:41.842Z,1605211061.842 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20201112T184128/Courier0039.lzma
2020-11-12T19:57:42.844Z,1605211062.844 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Courier0039.lzma.bak
2020-11-12T19:57:42.844Z,1605211062.844 [DataOverHttps](INFO): SBD MOMSN=13195511
2020-11-12T19:57:58.658Z,1605211078.658 [DataOverHttps](INFO): Sending 279 bytes from file Logs/20201112T184128/Express0040.lzma
2020-11-12T19:57:59.660Z,1605211079.660 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Express0040.lzma.bak
2020-11-12T19:57:59.660Z,1605211079.660 [DataOverHttps](INFO): SBD MOMSN=13195514
2020-11-12T19:58:00.885Z,1605211080.885 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T19:58:00.885Z,1605211080.885 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T19:58:00.885Z,1605211080.885 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T19:58:08.943Z,1605211088.943 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-12T20:03:01.610Z,1605211381.610 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T20:03:01.611Z,1605211381.611 [Default:CheckIn:C.Wait] Stopped
2020-11-12T20:03:01.611Z,1605211381.611 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T20:03:01.611Z,1605211381.611 [Default:CheckIn:D] Running Loop=1
2020-11-12T20:03:02.021Z,1605211382.021 [Default:CheckIn:D] Stopped
2020-11-12T20:03:02.021Z,1605211382.021 [Default:CheckIn:E] Running Loop=1
2020-11-12T20:03:02.429Z,1605211382.429 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 104.292505 min 
2020-11-12T20:03:02.429Z,1605211382.429 [Default:CheckIn:E] Stopped
2020-11-12T20:03:02.429Z,1605211382.429 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T20:03:02.429Z,1605211382.429 [Default:CheckIn] Stopped
2020-11-12T20:03:02.429Z,1605211382.429 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T20:03:02.429Z,1605211382.429 [Default:CheckIn](INFO): Running loop #18
2020-11-12T20:03:02.429Z,1605211382.429 [Default:CheckIn] Running Loop=18
2020-11-12T20:03:02.429Z,1605211382.429 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T20:03:02.430Z,1605211382.430 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T20:03:04.433Z,1605211384.433 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200303.00,A,3648.17110,N,12147.27869,W,0.078,35.33,121120,,,A*4A
2020-11-12T20:03:04.439Z,1605211384.439 [NAL9602](INFO): GPS fix at 20201112T200303: (36.802852, -121.787978)
2020-11-12T20:03:04.477Z,1605211384.477 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T20:03:04.477Z,1605211384.477 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T20:03:11.030Z,1605211391.030 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201112T184128/Courier0042.lzma
2020-11-12T20:03:12.032Z,1605211392.032 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Courier0042.lzma.bak
2020-11-12T20:03:12.032Z,1605211392.032 [DataOverHttps](INFO): SBD MOMSN=13195613
2020-11-12T20:03:27.758Z,1605211407.758 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20201112T184128/Express0043.lzma
2020-11-12T20:03:28.760Z,1605211408.760 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Express0043.lzma.bak
2020-11-12T20:03:28.760Z,1605211408.760 [DataOverHttps](INFO): SBD MOMSN=13195616
2020-11-12T20:03:29.906Z,1605211409.906 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T20:03:29.906Z,1605211409.906 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T20:03:29.907Z,1605211409.907 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T20:08:24.817Z,1605211704.817 [NAL9602](INFO): SBD MO Status=2, MOMSN=24086, MT Status=2, MTMSN=0
2020-11-12T20:08:24.817Z,1605211704.817 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-11-12T20:08:25.219Z,1605211705.219 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-11-12T20:08:30.489Z,1605211710.489 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T20:08:30.489Z,1605211710.489 [Default:CheckIn:C.Wait] Stopped
2020-11-12T20:08:30.489Z,1605211710.489 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T20:08:30.489Z,1605211710.489 [Default:CheckIn:D] Running Loop=1
2020-11-12T20:08:30.892Z,1605211710.892 [Default:CheckIn:D] Stopped
2020-11-12T20:08:30.892Z,1605211710.892 [Default:CheckIn:E] Running Loop=1
2020-11-12T20:08:31.332Z,1605211711.332 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 109.773698 min 
2020-11-12T20:08:31.332Z,1605211711.332 [Default:CheckIn:E] Stopped
2020-11-12T20:08:31.332Z,1605211711.332 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T20:08:31.332Z,1605211711.332 [Default:CheckIn] Stopped
2020-11-12T20:08:31.333Z,1605211711.333 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T20:08:31.333Z,1605211711.333 [Default:CheckIn](INFO): Running loop #19
2020-11-12T20:08:31.333Z,1605211711.333 [Default:CheckIn] Running Loop=19
2020-11-12T20:08:31.333Z,1605211711.333 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T20:08:31.333Z,1605211711.333 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T20:08:33.309Z,1605211713.309 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200832.00,A,3648.17096,N,12147.27591,W,0.097,35.33,121120,,,A*47
2020-11-12T20:08:33.321Z,1605211713.321 [NAL9602](INFO): GPS fix at 20201112T200832: (36.802849, -121.787932)
2020-11-12T20:08:33.331Z,1605211713.331 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T20:08:33.331Z,1605211713.331 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T20:08:40.158Z,1605211720.158 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20201112T184128/Courier0045.lzma
2020-11-12T20:08:41.160Z,1605211721.160 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Courier0045.lzma.bak
2020-11-12T20:08:41.160Z,1605211721.160 [DataOverHttps](INFO): SBD MOMSN=13195625
2020-11-12T20:08:57.034Z,1605211737.034 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20201112T184128/Express0046.lzma
2020-11-12T20:08:58.036Z,1605211738.036 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Express0046.lzma.bak
2020-11-12T20:08:58.036Z,1605211738.036 [DataOverHttps](INFO): SBD MOMSN=13195628
2020-11-12T20:08:59.196Z,1605211739.196 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T20:08:59.196Z,1605211739.196 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T20:08:59.196Z,1605211739.196 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T20:09:06.059Z,1605211746.059 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-12T20:13:59.833Z,1605212039.833 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T20:13:59.833Z,1605212039.833 [Default:CheckIn:C.Wait] Stopped
2020-11-12T20:13:59.833Z,1605212039.833 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T20:13:59.833Z,1605212039.833 [Default:CheckIn:D] Running Loop=1
2020-11-12T20:14:00.229Z,1605212040.229 [Default:CheckIn:D] Stopped
2020-11-12T20:14:00.229Z,1605212040.229 [Default:CheckIn:E] Running Loop=1
2020-11-12T20:14:00.637Z,1605212040.637 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 115.262638 min 
2020-11-12T20:14:00.637Z,1605212040.637 [Default:CheckIn:E] Stopped
2020-11-12T20:14:00.637Z,1605212040.637 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T20:14:00.637Z,1605212040.637 [Default:CheckIn] Stopped
2020-11-12T20:14:00.637Z,1605212040.637 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T20:14:00.637Z,1605212040.637 [Default:CheckIn](INFO): Running loop #20
2020-11-12T20:14:00.637Z,1605212040.637 [Default:CheckIn] Running Loop=20
2020-11-12T20:14:00.637Z,1605212040.637 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T20:14:00.637Z,1605212040.637 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T20:14:02.649Z,1605212042.649 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201401.00,A,3648.16536,N,12147.28275,W,0.058,35.33,121120,,,A*45
2020-11-12T20:14:02.651Z,1605212042.651 [NAL9602](INFO): GPS fix at 20201112T201401: (36.802756, -121.788046)
2020-11-12T20:14:02.661Z,1605212042.661 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T20:14:02.661Z,1605212042.661 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T20:14:09.990Z,1605212049.990 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201112T184128/Courier0048.lzma
2020-11-12T20:14:10.992Z,1605212050.992 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Courier0048.lzma.bak
2020-11-12T20:14:10.992Z,1605212050.992 [DataOverHttps](INFO): SBD MOMSN=13195736
2020-11-12T20:14:30.526Z,1605212070.526 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20201112T184128/Express0049.lzma
2020-11-12T20:14:31.528Z,1605212071.528 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Express0049.lzma.bak
2020-11-12T20:14:31.528Z,1605212071.528 [DataOverHttps](INFO): SBD MOMSN=13195739
2020-11-12T20:14:33.036Z,1605212073.036 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T20:14:33.036Z,1605212073.036 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T20:14:33.036Z,1605212073.036 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T20:17:53.873Z,1605212273.873 [NAL9602](INFO): SBD MO Status=2, MOMSN=24086, MT Status=2, MTMSN=0
2020-11-12T20:17:53.873Z,1605212273.873 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-11-12T20:18:09.629Z,1605212289.629 [NAL9602](INFO): SBD MO Status=2, MOMSN=24086, MT Status=2, MTMSN=0
2020-11-12T20:18:09.629Z,1605212289.629 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-11-12T20:19:04.987Z,1605212344.987 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-11-12T20:19:33.675Z,1605212373.675 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T20:19:33.675Z,1605212373.675 [Default:CheckIn:C.Wait] Stopped
2020-11-12T20:19:33.675Z,1605212373.675 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T20:19:33.675Z,1605212373.675 [Default:CheckIn:D] Running Loop=1
2020-11-12T20:19:34.086Z,1605212374.086 [Default:CheckIn:D] Stopped
2020-11-12T20:19:34.086Z,1605212374.086 [Default:CheckIn:E] Running Loop=1
2020-11-12T20:19:34.485Z,1605212374.485 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 120.826929 min 
2020-11-12T20:19:34.485Z,1605212374.485 [Default:CheckIn:E] Stopped
2020-11-12T20:19:34.485Z,1605212374.485 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T20:19:34.485Z,1605212374.485 [Default:CheckIn] Stopped
2020-11-12T20:19:34.485Z,1605212374.485 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T20:19:34.485Z,1605212374.485 [Default:CheckIn](INFO): Running loop #21
2020-11-12T20:19:34.485Z,1605212374.485 [Default:CheckIn] Running Loop=21
2020-11-12T20:19:34.485Z,1605212374.485 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T20:19:34.486Z,1605212374.486 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T20:19:36.501Z,1605212376.501 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201935.00,A,3648.16925,N,12147.27827,W,0.097,0.00,121120,,,A*76
2020-11-12T20:19:36.503Z,1605212376.503 [NAL9602](INFO): GPS fix at 20201112T201935: (36.802821, -121.787971)
2020-11-12T20:19:36.513Z,1605212376.513 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T20:19:36.513Z,1605212376.513 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T20:19:43.866Z,1605212383.866 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201112T184128/Courier0051.lzma
2020-11-12T20:19:44.868Z,1605212384.868 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Courier0051.lzma.bak
2020-11-12T20:19:44.868Z,1605212384.868 [DataOverHttps](INFO): SBD MOMSN=13195823
2020-11-12T20:20:00.614Z,1605212400.614 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20201112T184128/Express0052.lzma
2020-11-12T20:20:01.616Z,1605212401.616 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Express0052.lzma.bak
2020-11-12T20:20:01.616Z,1605212401.616 [DataOverHttps](INFO): SBD MOMSN=13195826
2020-11-12T20:20:02.771Z,1605212402.771 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T20:20:02.771Z,1605212402.771 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T20:20:02.771Z,1605212402.771 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T20:20:09.230Z,1605212409.230 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-12T20:25:03.356Z,1605212703.356 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T20:25:03.356Z,1605212703.356 [Default:CheckIn:C.Wait] Stopped
2020-11-12T20:25:03.356Z,1605212703.356 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T20:25:03.356Z,1605212703.356 [Default:CheckIn:D] Running Loop=1
2020-11-12T20:25:03.753Z,1605212703.753 [Default:CheckIn:D] Stopped
2020-11-12T20:25:03.753Z,1605212703.753 [Default:CheckIn:E] Running Loop=1
2020-11-12T20:25:04.147Z,1605212704.147 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 126.321379 min 
2020-11-12T20:25:04.147Z,1605212704.147 [Default:CheckIn:E] Stopped
2020-11-12T20:25:04.147Z,1605212704.147 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T20:25:04.147Z,1605212704.147 [Default:CheckIn] Stopped
2020-11-12T20:25:04.147Z,1605212704.147 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T20:25:04.147Z,1605212704.147 [Default:CheckIn](INFO): Running loop #22
2020-11-12T20:25:04.147Z,1605212704.147 [Default:CheckIn] Running Loop=22
2020-11-12T20:25:04.147Z,1605212704.147 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T20:25:04.147Z,1605212704.147 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T20:25:06.161Z,1605212706.161 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202504.00,A,3648.16746,N,12147.27678,W,0.019,0.00,121120,,,A*72
2020-11-12T20:25:06.163Z,1605212706.163 [NAL9602](INFO): GPS fix at 20201112T202504: (36.802791, -121.787946)
2020-11-12T20:25:06.173Z,1605212706.173 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T20:25:06.173Z,1605212706.173 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T20:25:13.558Z,1605212713.558 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20201112T184128/Courier0054.lzma
2020-11-12T20:25:14.560Z,1605212714.560 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Courier0054.lzma.bak
2020-11-12T20:25:14.560Z,1605212714.560 [DataOverHttps](INFO): SBD MOMSN=13195952
2020-11-12T20:25:30.386Z,1605212730.386 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20201112T184128/Express0055.lzma
2020-11-12T20:25:31.388Z,1605212731.388 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Express0055.lzma.bak
2020-11-12T20:25:31.389Z,1605212731.389 [DataOverHttps](INFO): SBD MOMSN=13195955
2020-11-12T20:25:32.841Z,1605212732.841 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T20:25:32.841Z,1605212732.841 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T20:25:32.841Z,1605212732.841 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T20:25:36.873Z,1605212736.873 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2020-11-12T20:25:36.946Z,1605212736.946 [NAL9602](FAULT): received: 
+CSQ:0

OK086, 2, 0, 0, 0

OK
2020-11-12T20:25:36.946Z,1605212736.946 [NAL9602] Data Fault, FailCount= 1
2020-11-12T20:25:36.946Z,1605212736.946 [NAL9602](ERROR): Data Fault
2020-11-12T20:25:37.019Z,1605212737.019 [CBIT](ERROR): Data Fault in component: NAL9602
2020-11-12T20:25:37.271Z,1605212737.271 [NAL9602](INFO): Powering down
2020-11-12T20:25:37.675Z,1605212737.675 [NAL9602](FAULT): LCB fault:  Software Overcurrent.
2020-11-12T20:25:37.675Z,1605212737.675 [NAL9602] Hardware Fault, FailCount= 1
2020-11-12T20:25:37.675Z,1605212737.675 [NAL9602](ERROR): Hardware Fault
2020-11-12T20:25:38.110Z,1605212738.110 [CBIT](INFO): Clearing failed state for component NAL9602
2020-11-12T20:25:38.110Z,1605212738.110 [NAL9602] No Fault, FailCount= 1
2020-11-12T20:26:07.571Z,1605212767.571 [NAL9602](INFO): Powering up NAL9602
2020-11-12T20:26:18.486Z,1605212778.486 [NAL9602](INFO): NAL9602 initialized
2020-11-12T20:26:49.613Z,1605212809.613 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-12T20:30:33.433Z,1605213033.433 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T20:30:33.433Z,1605213033.433 [Default:CheckIn:C.Wait] Stopped
2020-11-12T20:30:33.434Z,1605213033.434 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T20:30:33.434Z,1605213033.434 [Default:CheckIn:D] Running Loop=1
2020-11-12T20:30:33.850Z,1605213033.850 [Default:CheckIn:D] Stopped
2020-11-12T20:30:33.850Z,1605213033.850 [Default:CheckIn:E] Running Loop=1
2020-11-12T20:30:34.231Z,1605213034.231 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 131.822990 min 
2020-11-12T20:30:34.231Z,1605213034.231 [Default:CheckIn:E] Stopped
2020-11-12T20:30:34.232Z,1605213034.232 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T20:30:34.232Z,1605213034.232 [Default:CheckIn] Stopped
2020-11-12T20:30:34.232Z,1605213034.232 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T20:30:34.232Z,1605213034.232 [Default:CheckIn](INFO): Running loop #23
2020-11-12T20:30:34.232Z,1605213034.232 [Default:CheckIn] Running Loop=23
2020-11-12T20:30:34.232Z,1605213034.232 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T20:30:34.232Z,1605213034.232 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T20:30:36.249Z,1605213036.249 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203033.00,A,3648.16523,N,12147.27749,W,0.272,0.00,121120,,,A*7F
2020-11-12T20:30:36.251Z,1605213036.251 [NAL9602](INFO): GPS fix at 20201112T203033: (36.802754, -121.787958)
2020-11-12T20:30:36.261Z,1605213036.261 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T20:30:36.261Z,1605213036.261 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T20:30:38.667Z,1605213038.667 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-11-12T20:30:43.202Z,1605213043.202 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201112T184128/Courier0057.lzma
2020-11-12T20:30:44.204Z,1605213044.204 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Courier0057.lzma.bak
2020-11-12T20:30:44.205Z,1605213044.205 [DataOverHttps](INFO): SBD MOMSN=13195965
2020-11-12T20:30:59.834Z,1605213059.834 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20201112T184128/Express0058.lzma
2020-11-12T20:31:00.836Z,1605213060.836 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Express0058.lzma.bak
2020-11-12T20:31:00.836Z,1605213060.836 [DataOverHttps](INFO): SBD MOMSN=13195968
2020-11-12T20:31:02.109Z,1605213062.109 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T20:31:02.109Z,1605213062.109 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T20:31:02.109Z,1605213062.109 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T20:31:09.369Z,1605213069.369 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-12T20:36:02.683Z,1605213362.683 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T20:36:02.683Z,1605213362.683 [Default:CheckIn:C.Wait] Stopped
2020-11-12T20:36:02.683Z,1605213362.683 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T20:36:02.683Z,1605213362.683 [Default:CheckIn:D] Running Loop=1
2020-11-12T20:36:03.093Z,1605213363.093 [Default:CheckIn:D] Stopped
2020-11-12T20:36:03.093Z,1605213363.093 [Default:CheckIn:E] Running Loop=1
2020-11-12T20:36:03.496Z,1605213363.496 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 137.310368 min 
2020-11-12T20:36:03.497Z,1605213363.497 [Default:CheckIn:E] Stopped
2020-11-12T20:36:03.497Z,1605213363.497 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T20:36:03.497Z,1605213363.497 [Default:CheckIn] Stopped
2020-11-12T20:36:03.497Z,1605213363.497 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T20:36:03.497Z,1605213363.497 [Default:CheckIn](INFO): Running loop #24
2020-11-12T20:36:03.497Z,1605213363.497 [Default:CheckIn] Running Loop=24
2020-11-12T20:36:03.497Z,1605213363.497 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T20:36:03.497Z,1605213363.497 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T20:36:05.515Z,1605213365.515 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203603.00,A,3648.16883,N,12147.27824,W,0.117,0.00,121120,,,A*79
2020-11-12T20:36:05.517Z,1605213365.517 [NAL9602](INFO): GPS fix at 20201112T203603: (36.802814, -121.787971)
2020-11-12T20:36:05.531Z,1605213365.531 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T20:36:05.531Z,1605213365.531 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T20:36:12.738Z,1605213372.738 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201112T184128/Courier0060.lzma
2020-11-12T20:36:13.740Z,1605213373.740 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Courier0060.lzma.bak
2020-11-12T20:36:13.740Z,1605213373.740 [DataOverHttps](INFO): SBD MOMSN=13196071
2020-11-12T20:36:28.593Z,1605213388.593 [NAL9602](INFO): SBD MO Status=2, MOMSN=24086, MT Status=2, MTMSN=0
2020-11-12T20:36:28.593Z,1605213388.593 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-11-12T20:36:35.095Z,1605213395.095 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20201112T184128/Express0061.lzma
2020-11-12T20:36:36.097Z,1605213396.097 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Express0061.lzma.bak
2020-11-12T20:36:36.097Z,1605213396.097 [DataOverHttps](INFO): SBD MOMSN=13196142
2020-11-12T20:36:37.542Z,1605213397.542 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T20:36:37.542Z,1605213397.542 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T20:36:37.543Z,1605213397.543 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T20:39:28.819Z,1605213568.819 [NAL9602](INFO): SBD MO Status=0, MOMSN=24086, MT Status=0, MTMSN=0
2020-11-12T20:39:28.819Z,1605213568.819 [NAL9602](INFO): No messages in MT queue
2020-11-12T20:39:59.527Z,1605213599.527 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-12T20:41:38.110Z,1605213698.110 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T20:41:38.110Z,1605213698.110 [Default:CheckIn:C.Wait] Stopped
2020-11-12T20:41:38.133Z,1605213698.133 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T20:41:38.133Z,1605213698.133 [Default:CheckIn:D] Running Loop=1
2020-11-12T20:41:38.515Z,1605213698.515 [Default:CheckIn:D] Stopped
2020-11-12T20:41:38.515Z,1605213698.515 [Default:CheckIn:E] Running Loop=1
2020-11-12T20:41:38.925Z,1605213698.925 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 142.900732 min 
2020-11-12T20:41:38.925Z,1605213698.925 [Default:CheckIn:E] Stopped
2020-11-12T20:41:38.925Z,1605213698.925 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T20:41:38.925Z,1605213698.925 [Default:CheckIn] Stopped
2020-11-12T20:41:38.925Z,1605213698.925 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T20:41:38.925Z,1605213698.925 [Default:CheckIn](INFO): Running loop #25
2020-11-12T20:41:38.925Z,1605213698.925 [Default:CheckIn] Running Loop=25
2020-11-12T20:41:38.925Z,1605213698.925 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T20:41:38.926Z,1605213698.926 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T20:41:40.937Z,1605213700.937 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204139.00,A,3648.16640,N,12147.27820,W,0.389,0.00,121120,,,A*70
2020-11-12T20:41:40.939Z,1605213700.939 [NAL9602](INFO): GPS fix at 20201112T204139: (36.802773, -121.787970)
2020-11-12T20:41:40.949Z,1605213700.949 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T20:41:40.949Z,1605213700.949 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T20:41:48.638Z,1605213708.638 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201112T184128/Courier0063.lzma
2020-11-12T20:41:49.640Z,1605213709.640 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Courier0063.lzma.bak
2020-11-12T20:41:49.640Z,1605213709.640 [DataOverHttps](INFO): SBD MOMSN=13196167
2020-11-12T20:42:13.954Z,1605213733.954 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20201112T184128/Express0064.lzma
2020-11-12T20:42:14.956Z,1605213734.956 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Express0064.lzma.bak
2020-11-12T20:42:14.956Z,1605213734.956 [DataOverHttps](INFO): SBD MOMSN=13196180
2020-11-12T20:42:16.125Z,1605213736.125 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T20:42:16.125Z,1605213736.125 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T20:42:16.125Z,1605213736.125 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T20:46:44.114Z,1605214004.114 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-11-12T20:47:14.827Z,1605214034.827 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-12T20:47:16.862Z,1605214036.862 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T20:47:16.862Z,1605214036.862 [Default:CheckIn:C.Wait] Stopped
2020-11-12T20:47:16.862Z,1605214036.862 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T20:47:16.862Z,1605214036.862 [Default:CheckIn:D] Running Loop=1
2020-11-12T20:47:17.256Z,1605214037.256 [Default:CheckIn:D] Stopped
2020-11-12T20:47:17.256Z,1605214037.256 [Default:CheckIn:E] Running Loop=1
2020-11-12T20:47:17.661Z,1605214037.661 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 148.546436 min 
2020-11-12T20:47:17.661Z,1605214037.661 [Default:CheckIn:E] Stopped
2020-11-12T20:47:17.661Z,1605214037.661 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T20:47:17.661Z,1605214037.661 [Default:CheckIn] Stopped
2020-11-12T20:47:17.661Z,1605214037.661 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T20:47:17.661Z,1605214037.661 [Default:CheckIn](INFO): Running loop #26
2020-11-12T20:47:17.661Z,1605214037.661 [Default:CheckIn] Running Loop=26
2020-11-12T20:47:17.661Z,1605214037.661 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T20:47:17.661Z,1605214037.661 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T20:47:19.669Z,1605214039.669 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204718.00,A,3648.16824,N,12147.27830,W,0.097,0.00,121120,,,A*74
2020-11-12T20:47:19.671Z,1605214039.671 [NAL9602](INFO): GPS fix at 20201112T204718: (36.802804, -121.787972)
2020-11-12T20:47:19.681Z,1605214039.681 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T20:47:19.681Z,1605214039.681 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T20:47:26.906Z,1605214046.906 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201112T184128/Courier0066.lzma
2020-11-12T20:47:27.908Z,1605214047.908 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Courier0066.lzma.bak
2020-11-12T20:47:27.908Z,1605214047.908 [DataOverHttps](INFO): SBD MOMSN=13196268
2020-11-12T20:47:43.599Z,1605214063.599 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20201112T184128/Express0067.lzma
2020-11-12T20:47:44.600Z,1605214064.600 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Express0067.lzma.bak
2020-11-12T20:47:44.600Z,1605214064.600 [DataOverHttps](INFO): SBD MOMSN=13196271
2020-11-12T20:47:45.949Z,1605214065.949 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T20:47:45.949Z,1605214065.949 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T20:47:45.949Z,1605214065.949 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T20:47:50.371Z,1605214070.371 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2020-11-12T20:47:50.446Z,1605214070.446 [NAL9602](FAULT): received: 
+CSQ:0

OK086, 0, 0, 0, 0

OK
2020-11-12T20:47:50.446Z,1605214070.446 [NAL9602] Data Fault, FailCount= 1
2020-11-12T20:47:50.446Z,1605214070.446 [NAL9602](ERROR): Data Fault
2020-11-12T20:47:50.511Z,1605214070.511 [CBIT](ERROR): Data Fault in component: NAL9602
2020-11-12T20:47:50.775Z,1605214070.775 [NAL9602](INFO): Powering down
2020-11-12T20:47:51.599Z,1605214071.599 [CBIT](INFO): Clearing failed state for component NAL9602
2020-11-12T20:47:51.599Z,1605214071.599 [NAL9602] No Fault, FailCount= 1
2020-11-12T20:48:21.112Z,1605214101.112 [NAL9602](INFO): Powering up NAL9602
2020-11-12T20:48:32.034Z,1605214112.034 [NAL9602](INFO): NAL9602 initialized
2020-11-12T20:49:03.147Z,1605214143.147 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-12T20:52:46.673Z,1605214366.673 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T20:52:46.673Z,1605214366.673 [Default:CheckIn:C.Wait] Stopped
2020-11-12T20:52:46.673Z,1605214366.673 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T20:52:46.673Z,1605214366.673 [Default:CheckIn:D] Running Loop=1
2020-11-12T20:52:47.090Z,1605214367.090 [Default:CheckIn:D] Stopped
2020-11-12T20:52:47.090Z,1605214367.090 [Default:CheckIn:E] Running Loop=1
2020-11-12T20:52:47.478Z,1605214367.478 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 154.043669 min 
2020-11-12T20:52:47.478Z,1605214367.478 [Default:CheckIn:E] Stopped
2020-11-12T20:52:47.479Z,1605214367.479 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T20:52:47.479Z,1605214367.479 [Default:CheckIn] Stopped
2020-11-12T20:52:47.479Z,1605214367.479 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T20:52:47.479Z,1605214367.479 [Default:CheckIn](INFO): Running loop #27
2020-11-12T20:52:47.479Z,1605214367.479 [Default:CheckIn] Running Loop=27
2020-11-12T20:52:47.479Z,1605214367.479 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T20:52:47.479Z,1605214367.479 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T20:52:49.497Z,1605214369.497 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205247.00,A,3648.16657,N,12147.27829,W,0.039,167.21,121120,,,A*7F
2020-11-12T20:52:49.499Z,1605214369.499 [NAL9602](INFO): GPS fix at 20201112T205247: (36.802776, -121.787971)
2020-11-12T20:52:49.509Z,1605214369.509 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T20:52:49.509Z,1605214369.509 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T20:52:51.509Z,1605214371.509 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-11-12T20:53:22.219Z,1605214402.219 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-12T20:53:22.258Z,1605214402.258 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201112T184128/Courier0069.lzma
2020-11-12T20:53:23.260Z,1605214403.260 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Courier0069.lzma.bak
2020-11-12T20:53:23.260Z,1605214403.260 [DataOverHttps](INFO): SBD MOMSN=13196338
2020-11-12T20:53:39.142Z,1605214419.142 [DataOverHttps](INFO): Sending 170 bytes from file Logs/20201112T184128/Express0070.lzma
2020-11-12T20:53:40.144Z,1605214420.144 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Express0070.lzma.bak
2020-11-12T20:53:40.144Z,1605214420.144 [DataOverHttps](INFO): SBD MOMSN=13196341
2020-11-12T20:53:41.620Z,1605214421.620 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T20:53:41.620Z,1605214421.620 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T20:53:41.620Z,1605214421.620 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T20:58:42.298Z,1605214722.298 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T20:58:42.298Z,1605214722.298 [Default:CheckIn:C.Wait] Stopped
2020-11-12T20:58:42.298Z,1605214722.298 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T20:58:42.298Z,1605214722.298 [Default:CheckIn:D] Running Loop=1
2020-11-12T20:58:42.687Z,1605214722.687 [Default:CheckIn:D] Stopped
2020-11-12T20:58:42.687Z,1605214722.687 [Default:CheckIn:E] Running Loop=1
2020-11-12T20:58:43.091Z,1605214723.091 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 159.970280 min 
2020-11-12T20:58:43.091Z,1605214723.091 [Default:CheckIn:E] Stopped
2020-11-12T20:58:43.092Z,1605214723.092 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T20:58:43.092Z,1605214723.092 [Default:CheckIn] Stopped
2020-11-12T20:58:43.092Z,1605214723.092 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T20:58:43.092Z,1605214723.092 [Default:CheckIn](INFO): Running loop #28
2020-11-12T20:58:43.092Z,1605214723.092 [Default:CheckIn] Running Loop=28
2020-11-12T20:58:43.092Z,1605214723.092 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T20:58:43.092Z,1605214723.092 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T20:58:45.105Z,1605214725.105 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205842.00,A,3648.16751,N,12147.27908,W,0.039,167.21,121120,,,A*75
2020-11-12T20:58:45.107Z,1605214725.107 [NAL9602](INFO): GPS fix at 20201112T205842: (36.802792, -121.787985)
2020-11-12T20:58:45.117Z,1605214725.117 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T20:58:45.117Z,1605214725.117 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T20:58:52.590Z,1605214732.590 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201112T184128/Courier0072.lzma
2020-11-12T20:58:53.592Z,1605214733.592 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Courier0072.lzma.bak
2020-11-12T20:58:53.593Z,1605214733.593 [DataOverHttps](INFO): SBD MOMSN=13196394
2020-11-12T20:59:09.642Z,1605214749.642 [DataOverHttps](INFO): Sending 209 bytes from file Logs/20201112T184128/Express0073.lzma
2020-11-12T20:59:10.644Z,1605214750.644 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Express0073.lzma.bak
2020-11-12T20:59:10.644Z,1605214750.644 [DataOverHttps](INFO): SBD MOMSN=13196397
2020-11-12T20:59:11.778Z,1605214751.778 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T20:59:11.778Z,1605214751.778 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T20:59:11.779Z,1605214751.779 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T21:00:47.139Z,1605214847.139 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-11-12T21:00:47.141Z,1605214847.141 [BPC1](INFO): Received data from all battery sticks.
2020-11-12T21:03:46.907Z,1605215026.907 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-11-12T21:04:12.364Z,1605215052.364 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T21:04:12.364Z,1605215052.364 [Default:CheckIn:C.Wait] Stopped
2020-11-12T21:04:12.364Z,1605215052.364 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T21:04:12.364Z,1605215052.364 [Default:CheckIn:D] Running Loop=1
2020-11-12T21:04:12.767Z,1605215052.767 [Default:CheckIn:D] Stopped
2020-11-12T21:04:12.767Z,1605215052.767 [Default:CheckIn:E] Running Loop=1
2020-11-12T21:04:13.179Z,1605215053.179 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 165.471615 min 
2020-11-12T21:04:13.179Z,1605215053.179 [Default:CheckIn:E] Stopped
2020-11-12T21:04:13.179Z,1605215053.179 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T21:04:13.179Z,1605215053.179 [Default:CheckIn] Stopped
2020-11-12T21:04:13.180Z,1605215053.180 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T21:04:13.180Z,1605215053.180 [Default:CheckIn](INFO): Running loop #29
2020-11-12T21:04:13.180Z,1605215053.180 [Default:CheckIn] Running Loop=29
2020-11-12T21:04:13.180Z,1605215053.180 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T21:04:13.180Z,1605215053.180 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T21:04:15.189Z,1605215055.189 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210413.00,A,3648.15986,N,12147.27345,W,0.914,167.21,121120,,,A*7B
2020-11-12T21:04:15.191Z,1605215055.191 [NAL9602](INFO): GPS fix at 20201112T210413: (36.802664, -121.787891)
2020-11-12T21:04:15.221Z,1605215055.221 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T21:04:15.221Z,1605215055.221 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T21:04:25.410Z,1605215065.410 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20201112T184128/Courier0075.lzma
2020-11-12T21:04:26.412Z,1605215066.412 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Courier0075.lzma.bak
2020-11-12T21:04:26.413Z,1605215066.413 [DataOverHttps](INFO): SBD MOMSN=13196455
2020-11-12T21:04:44.806Z,1605215084.806 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20201112T184128/Express0076.lzma
2020-11-12T21:04:45.808Z,1605215085.808 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Express0076.lzma.bak
2020-11-12T21:04:45.808Z,1605215085.808 [DataOverHttps](INFO): SBD MOMSN=13196458
2020-11-12T21:04:47.228Z,1605215087.228 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T21:04:47.229Z,1605215087.229 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T21:04:47.229Z,1605215087.229 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T21:04:47.623Z,1605215087.623 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-12T21:09:47.919Z,1605215387.919 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T21:09:47.919Z,1605215387.919 [Default:CheckIn:C.Wait] Stopped
2020-11-12T21:09:47.919Z,1605215387.919 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T21:09:47.919Z,1605215387.919 [Default:CheckIn:D] Running Loop=1
2020-11-12T21:09:48.316Z,1605215388.316 [Default:CheckIn:D] Stopped
2020-11-12T21:09:48.316Z,1605215388.316 [Default:CheckIn:E] Running Loop=1
2020-11-12T21:09:48.715Z,1605215388.715 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 171.064095 min 
2020-11-12T21:09:48.715Z,1605215388.715 [Default:CheckIn:E] Stopped
2020-11-12T21:09:48.715Z,1605215388.715 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T21:09:48.715Z,1605215388.715 [Default:CheckIn] Stopped
2020-11-12T21:09:48.715Z,1605215388.715 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T21:09:48.716Z,1605215388.716 [Default:CheckIn](INFO): Running loop #30
2020-11-12T21:09:48.716Z,1605215388.716 [Default:CheckIn] Running Loop=30
2020-11-12T21:09:48.716Z,1605215388.716 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T21:09:48.716Z,1605215388.716 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T21:09:50.733Z,1605215390.733 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210949.00,A,3648.16028,N,12147.27678,W,1.225,0.00,121120,,,A*77
2020-11-12T21:09:50.735Z,1605215390.735 [NAL9602](INFO): GPS fix at 20201112T210949: (36.802671, -121.787946)
2020-11-12T21:09:50.745Z,1605215390.745 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T21:09:50.745Z,1605215390.745 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T21:09:58.246Z,1605215398.246 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20201112T184128/Courier0078.lzma
2020-11-12T21:09:59.248Z,1605215399.248 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Courier0078.lzma.bak
2020-11-12T21:09:59.248Z,1605215399.248 [DataOverHttps](INFO): SBD MOMSN=13196509
2020-11-12T21:10:15.010Z,1605215415.010 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20201112T184128/Express0079.lzma
2020-11-12T21:10:16.012Z,1605215416.012 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Express0079.lzma.bak
2020-11-12T21:10:16.012Z,1605215416.012 [DataOverHttps](INFO): SBD MOMSN=13196512
2020-11-12T21:10:17.439Z,1605215417.439 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T21:10:17.439Z,1605215417.439 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T21:10:17.439Z,1605215417.439 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T21:10:21.464Z,1605215421.464 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2020-11-12T21:10:21.546Z,1605215421.546 [NAL9602](FAULT): received: 
+CSQ:0

OK086, 0, 0, 0, 0

OK
2020-11-12T21:10:21.546Z,1605215421.546 [NAL9602] Data Fault, FailCount= 1
2020-11-12T21:10:21.546Z,1605215421.546 [NAL9602](ERROR): Data Fault
2020-11-12T21:10:21.675Z,1605215421.675 [CBIT](ERROR): Data Fault in component: NAL9602
2020-11-12T21:10:21.872Z,1605215421.872 [NAL9602](INFO): Powering down
2020-11-12T21:10:22.279Z,1605215422.279 [NAL9602](FAULT): LCB fault:  Software Overcurrent.
2020-11-12T21:10:22.280Z,1605215422.280 [NAL9602] Hardware Fault, FailCount= 1
2020-11-12T21:10:22.280Z,1605215422.280 [NAL9602](ERROR): Hardware Fault
2020-11-12T21:10:22.796Z,1605215422.796 [CBIT](INFO): Clearing failed state for component NAL9602
2020-11-12T21:10:22.796Z,1605215422.796 [NAL9602] No Fault, FailCount= 1
2020-11-12T21:10:52.203Z,1605215452.203 [NAL9602](INFO): Powering up NAL9602
2020-11-12T21:11:03.119Z,1605215463.119 [NAL9602](INFO): NAL9602 initialized
2020-11-12T21:11:34.219Z,1605215494.219 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-12T21:15:18.171Z,1605215718.171 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T21:15:18.171Z,1605215718.171 [Default:CheckIn:C.Wait] Stopped
2020-11-12T21:15:18.171Z,1605215718.171 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T21:15:18.171Z,1605215718.171 [Default:CheckIn:D] Running Loop=1
2020-11-12T21:15:18.555Z,1605215718.555 [Default:CheckIn:D] Stopped
2020-11-12T21:15:18.555Z,1605215718.555 [Default:CheckIn:E] Running Loop=1
2020-11-12T21:15:18.965Z,1605215718.965 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 176.568083 min 
2020-11-12T21:15:18.965Z,1605215718.965 [Default:CheckIn:E] Stopped
2020-11-12T21:15:18.965Z,1605215718.965 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T21:15:18.965Z,1605215718.965 [Default:CheckIn] Stopped
2020-11-12T21:15:18.965Z,1605215718.965 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T21:15:18.966Z,1605215718.966 [Default:CheckIn](INFO): Running loop #31
2020-11-12T21:15:18.966Z,1605215718.966 [Default:CheckIn] Running Loop=31
2020-11-12T21:15:18.966Z,1605215718.966 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T21:15:18.966Z,1605215718.966 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T21:15:22.592Z,1605215722.592 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-11-12T21:15:32.289Z,1605215732.289 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211531.00,A,3648.16670,N,12147.28553,W,0.350,140.72,121120,,,A*79
2020-11-12T21:15:32.291Z,1605215732.291 [NAL9602](INFO): GPS fix at 20201112T211531: (36.802778, -121.788092)
2020-11-12T21:15:32.305Z,1605215732.305 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T21:15:32.305Z,1605215732.305 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T21:15:39.594Z,1605215739.594 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201112T184128/Courier0081.lzma
2020-11-12T21:15:40.596Z,1605215740.596 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Courier0081.lzma.bak
2020-11-12T21:15:40.596Z,1605215740.596 [DataOverHttps](INFO): SBD MOMSN=13196567
2020-11-12T21:16:04.546Z,1605215764.546 [DataOverHttps](INFO): Sending 276 bytes from file Logs/20201112T184128/Express0082.lzma
2020-11-12T21:16:05.005Z,1605215765.005 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-12T21:16:05.548Z,1605215765.548 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Express0082.lzma.bak
2020-11-12T21:16:05.548Z,1605215765.548 [DataOverHttps](INFO): SBD MOMSN=13196579
2020-11-12T21:16:06.669Z,1605215766.669 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T21:16:06.669Z,1605215766.669 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T21:16:06.669Z,1605215766.669 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T21:17:07.627Z,1605215827.627 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2020-11-12T21:17:07.627Z,1605215827.627 [DropWeight] Hardware Fault, FailCount= 1
2020-11-12T21:17:07.627Z,1605215827.627 [DropWeight](ERROR): Hardware Fault
2020-11-12T21:17:07.643Z,1605215827.643 [CommandLine](FAULT): Scheduling is paused
2020-11-12T21:17:07.644Z,1605215827.644 [CBIT](INFO): Critical error at 20201112T211707
2020-11-12T21:17:07.644Z,1605215827.644 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2020-11-12T21:17:07.646Z,1605215827.646 [CBIT](ERROR): Hardware Fault in component: DropWeight
2020-11-12T21:17:07.647Z,1605215827.647 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2020-11-12T21:17:08.060Z,1605215828.060 [CBIT](INFO): Critical error at 20201112T211707
2020-11-12T21:21:07.214Z,1605216067.214 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T21:21:07.214Z,1605216067.214 [Default:CheckIn:C.Wait] Stopped
2020-11-12T21:21:07.214Z,1605216067.214 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T21:21:07.214Z,1605216067.214 [Default:CheckIn:D] Running Loop=1
2020-11-12T21:21:07.611Z,1605216067.611 [Default:CheckIn:D] Stopped
2020-11-12T21:21:07.611Z,1605216067.611 [Default:CheckIn:E] Running Loop=1
2020-11-12T21:21:08.016Z,1605216068.016 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 182.385677 min 
2020-11-12T21:21:08.016Z,1605216068.016 [Default:CheckIn:E] Stopped
2020-11-12T21:21:08.016Z,1605216068.016 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T21:21:08.016Z,1605216068.016 [Default:CheckIn] Stopped
2020-11-12T21:21:08.016Z,1605216068.016 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T21:21:08.016Z,1605216068.016 [Default:CheckIn](INFO): Running loop #32
2020-11-12T21:21:08.016Z,1605216068.016 [Default:CheckIn] Running Loop=32
2020-11-12T21:21:08.016Z,1605216068.016 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T21:21:08.016Z,1605216068.016 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T21:21:10.033Z,1605216070.033 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212108.00,A,3648.16804,N,12147.28272,W,0.078,140.72,121120,,,A*74
2020-11-12T21:21:10.035Z,1605216070.035 [NAL9602](INFO): GPS fix at 20201112T212108: (36.802801, -121.788045)
2020-11-12T21:21:10.045Z,1605216070.045 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T21:21:10.045Z,1605216070.045 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T21:21:17.694Z,1605216077.694 [DataOverHttps](INFO): Sending 178 bytes from file Logs/20201112T184128/Courier0084.lzma
2020-11-12T21:21:18.696Z,1605216078.696 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Courier0084.lzma.bak
2020-11-12T21:21:18.696Z,1605216078.696 [DataOverHttps](INFO): SBD MOMSN=13196627
2020-11-12T21:21:34.582Z,1605216094.582 [DataOverHttps](INFO): Sending 157 bytes from file Logs/20201112T184128/Express0085.lzma
2020-11-12T21:21:35.584Z,1605216095.584 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Express0085.lzma.bak
2020-11-12T21:21:35.584Z,1605216095.584 [DataOverHttps](INFO): SBD MOMSN=13196632
2020-11-12T21:21:36.701Z,1605216096.701 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T21:21:36.701Z,1605216096.701 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T21:21:36.701Z,1605216096.701 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T21:25:18.093Z,1605216318.093 [NAL9602](INFO): SBD MO Status=2, MOMSN=24087, MT Status=2, MTMSN=0
2020-11-12T21:25:18.093Z,1605216318.093 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-11-12T21:26:11.831Z,1605216371.831 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-11-12T21:26:37.295Z,1605216397.295 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T21:26:37.295Z,1605216397.295 [Default:CheckIn:C.Wait] Stopped
2020-11-12T21:26:37.295Z,1605216397.295 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T21:26:37.295Z,1605216397.295 [Default:CheckIn:D] Running Loop=1
2020-11-12T21:26:37.706Z,1605216397.706 [Default:CheckIn:D] Stopped
2020-11-12T21:26:37.706Z,1605216397.706 [Default:CheckIn:E] Running Loop=1
2020-11-12T21:26:38.103Z,1605216398.103 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 187.887272 min 
2020-11-12T21:26:38.103Z,1605216398.103 [Default:CheckIn:E] Stopped
2020-11-12T21:26:38.103Z,1605216398.103 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T21:26:38.103Z,1605216398.103 [Default:CheckIn] Stopped
2020-11-12T21:26:38.103Z,1605216398.103 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T21:26:38.103Z,1605216398.103 [Default:CheckIn](INFO): Running loop #33
2020-11-12T21:26:38.103Z,1605216398.103 [Default:CheckIn] Running Loop=33
2020-11-12T21:26:38.103Z,1605216398.103 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T21:26:38.103Z,1605216398.103 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T21:26:40.123Z,1605216400.123 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212638.00,A,3648.16707,N,12147.27919,W,0.039,267.09,121120,,,A*7A
2020-11-12T21:26:40.125Z,1605216400.125 [NAL9602](INFO): GPS fix at 20201112T212638: (36.802785, -121.787987)
2020-11-12T21:26:40.136Z,1605216400.136 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T21:26:40.136Z,1605216400.136 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T21:26:47.678Z,1605216407.678 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201112T184128/Courier0087.lzma
2020-11-12T21:26:48.680Z,1605216408.680 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Courier0087.lzma.bak
2020-11-12T21:26:48.680Z,1605216408.680 [DataOverHttps](INFO): SBD MOMSN=13196699
2020-11-12T21:27:04.382Z,1605216424.382 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20201112T184128/Express0088.lzma
2020-11-12T21:27:05.384Z,1605216425.384 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Express0088.lzma.bak
2020-11-12T21:27:05.384Z,1605216425.384 [DataOverHttps](INFO): SBD MOMSN=13196702
2020-11-12T21:27:06.802Z,1605216426.802 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T21:27:06.802Z,1605216426.802 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T21:27:06.802Z,1605216426.802 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T21:27:12.028Z,1605216432.028 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-12T21:32:07.374Z,1605216727.374 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T21:32:07.375Z,1605216727.375 [Default:CheckIn:C.Wait] Stopped
2020-11-12T21:32:07.375Z,1605216727.375 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T21:32:07.375Z,1605216727.375 [Default:CheckIn:D] Running Loop=1
2020-11-12T21:32:07.772Z,1605216727.772 [Default:CheckIn:D] Stopped
2020-11-12T21:32:07.772Z,1605216727.772 [Default:CheckIn:E] Running Loop=1
2020-11-12T21:32:07.808Z,1605216727.808 [CBIT](INFO): Clearing failed state for component DropWeight
2020-11-12T21:32:07.808Z,1605216727.808 [DropWeight] No Fault, FailCount= 1
2020-11-12T21:32:08.171Z,1605216728.171 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 193.388363 min 
2020-11-12T21:32:08.171Z,1605216728.171 [Default:CheckIn:E] Stopped
2020-11-12T21:32:08.172Z,1605216728.172 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T21:32:08.172Z,1605216728.172 [Default:CheckIn] Stopped
2020-11-12T21:32:08.172Z,1605216728.172 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T21:32:08.172Z,1605216728.172 [Default:CheckIn](INFO): Running loop #34
2020-11-12T21:32:08.172Z,1605216728.172 [Default:CheckIn] Running Loop=34
2020-11-12T21:32:08.172Z,1605216728.172 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T21:32:08.172Z,1605216728.172 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T21:32:40.901Z,1605216760.901 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2020-11-12T21:32:40.974Z,1605216760.974 [NAL9602](FAULT): received: 
+CSQ:0

OK087, 2, 0, 0, 0

OK
2020-11-12T21:32:40.974Z,1605216760.974 [NAL9602] Data Fault, FailCount= 1
2020-11-12T21:32:40.974Z,1605216760.974 [NAL9602](ERROR): Data Fault
2020-11-12T21:32:40.999Z,1605216760.999 [CBIT](ERROR): Data Fault in component: NAL9602
2020-11-12T21:32:41.301Z,1605216761.301 [NAL9602](INFO): Powering down
2020-11-12T21:32:42.149Z,1605216762.149 [CBIT](INFO): Clearing failed state for component NAL9602
2020-11-12T21:32:42.149Z,1605216762.149 [NAL9602] No Fault, FailCount= 1
2020-11-12T21:33:11.603Z,1605216791.603 [NAL9602](INFO): Powering up NAL9602
2020-11-12T21:33:22.511Z,1605216802.511 [NAL9602](INFO): NAL9602 initialized
2020-11-12T21:37:08.409Z,1605217028.409 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-11-12T21:32:08.2Z
2020-11-12T21:37:08.409Z,1605217028.409 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T21:37:08.409Z,1605217028.409 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T21:37:12.844Z,1605217032.844 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-11-12T21:37:15.202Z,1605217035.202 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201112T184128/Courier0090.lzma
2020-11-12T21:37:16.204Z,1605217036.204 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Courier0090.lzma.bak
2020-11-12T21:37:16.204Z,1605217036.204 [DataOverHttps](INFO): SBD MOMSN=13196899
2020-11-12T21:37:43.553Z,1605217063.553 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-12T21:37:48.870Z,1605217068.870 [DataOverHttps](INFO): Sending 174 bytes from file Logs/20201112T184128/Express0091.lzma
2020-11-12T21:37:49.874Z,1605217069.874 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Express0091.lzma.bak
2020-11-12T21:37:49.874Z,1605217069.874 [DataOverHttps](INFO): SBD MOMSN=13196908
2020-11-12T21:37:51.235Z,1605217071.235 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T21:37:51.235Z,1605217071.235 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T21:37:51.235Z,1605217071.235 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T21:42:52.001Z,1605217372.001 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T21:42:52.001Z,1605217372.001 [Default:CheckIn:C.Wait] Stopped
2020-11-12T21:42:52.001Z,1605217372.001 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T21:42:52.001Z,1605217372.001 [Default:CheckIn:D] Running Loop=1
2020-11-12T21:42:52.405Z,1605217372.405 [Default:CheckIn:D] Stopped
2020-11-12T21:42:52.405Z,1605217372.405 [Default:CheckIn:E] Running Loop=1
2020-11-12T21:42:52.813Z,1605217372.813 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 204.132243 min 
2020-11-12T21:42:52.813Z,1605217372.813 [Default:CheckIn:E] Stopped
2020-11-12T21:42:52.813Z,1605217372.813 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T21:42:52.813Z,1605217372.813 [Default:CheckIn] Stopped
2020-11-12T21:42:52.813Z,1605217372.813 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T21:42:52.813Z,1605217372.813 [Default:CheckIn](INFO): Running loop #35
2020-11-12T21:42:52.813Z,1605217372.813 [Default:CheckIn] Running Loop=35
2020-11-12T21:42:52.813Z,1605217372.813 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T21:42:52.813Z,1605217372.813 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T21:42:54.411Z,1605217374.411 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-11-12T21:42:54.412Z,1605217374.412 [NAL9602] Data Fault, FailCount= 2
2020-11-12T21:42:54.412Z,1605217374.412 [NAL9602](ERROR): Data Fault
2020-11-12T21:42:54.431Z,1605217374.431 [CBIT](ERROR): Data Fault in component: NAL9602
2020-11-12T21:42:54.815Z,1605217374.815 [NAL9602](INFO): Powering down
2020-11-12T21:42:55.639Z,1605217375.639 [CBIT](INFO): Clearing failed state for component NAL9602
2020-11-12T21:42:55.639Z,1605217375.639 [NAL9602] No Fault, FailCount= 2
2020-11-12T21:43:25.119Z,1605217405.119 [NAL9602](INFO): Powering up NAL9602
2020-11-12T21:43:36.027Z,1605217416.027 [NAL9602](INFO): NAL9602 initialized
2020-11-12T21:47:53.089Z,1605217673.089 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-11-12T21:42:52.8Z
2020-11-12T21:47:53.090Z,1605217673.090 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T21:47:53.090Z,1605217673.090 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T21:48:00.462Z,1605217680.462 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201112T184128/Courier0093.lzma
2020-11-12T21:48:01.464Z,1605217681.464 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Courier0093.lzma.bak
2020-11-12T21:48:01.464Z,1605217681.464 [DataOverHttps](INFO): SBD MOMSN=13196996
2020-11-12T21:48:33.974Z,1605217713.974 [DataOverHttps](INFO): Sending 226 bytes from file Logs/20201112T184128/Express0094.lzma
2020-11-12T21:48:34.976Z,1605217714.976 [DataOverHttps](INFO): Moved sent file to Logs/20201112T184128/Express0094.lzma.bak
2020-11-12T21:48:34.976Z,1605217714.976 [DataOverHttps](INFO): SBD MOMSN=13197007
2020-11-12T21:48:36.313Z,1605217716.313 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T21:48:36.313Z,1605217716.313 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T21:48:36.313Z,1605217716.313 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T21:48:39.131Z,1605217719.131 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-11-12T21:49:09.835Z,1605217749.835 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-12T21:53:36.965Z,1605218016.965 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T21:53:36.965Z,1605218016.965 [Default:CheckIn:C.Wait] Stopped
2020-11-12T21:53:36.965Z,1605218016.965 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T21:53:36.965Z,1605218016.965 [Default:CheckIn:D] Running Loop=1
2020-11-12T21:53:37.374Z,1605218017.374 [Default:CheckIn:D] Stopped
2020-11-12T21:53:37.374Z,1605218017.374 [Default:CheckIn:E] Running Loop=1
2020-11-12T21:53:37.748Z,1605218017.748 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 214.881722 min 
2020-11-12T21:53:37.748Z,1605218017.748 [Default:CheckIn:E] Stopped
2020-11-12T21:53:37.748Z,1605218017.748 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T21:53:37.749Z,1605218017.749 [Default:CheckIn] Stopped
2020-11-12T21:53:37.749Z,1605218017.749 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T21:53:37.749Z,1605218017.749 [Default:CheckIn](INFO): Running loop #36
2020-11-12T21:53:37.749Z,1605218017.749 [Default:CheckIn] Running Loop=36
2020-11-12T21:53:37.749Z,1605218017.749 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T21:53:37.749Z,1605218017.749 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T21:53:39.355Z,1605218019.355 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-11-12T21:53:39.356Z,1605218019.356 [NAL9602] Data Fault, FailCount= 3
2020-11-12T21:53:39.356Z,1605218019.356 [NAL9602](ERROR): Data Fault
2020-11-12T21:53:39.424Z,1605218019.424 [CBIT](ERROR): Data Fault in component: NAL9602
2020-11-12T21:53:39.759Z,1605218019.759 [NAL9602](INFO): Powering down
2020-11-12T21:53:40.618Z,1605218020.618 [CBIT](INFO): Clearing failed state for component NAL9602
2020-11-12T21:53:40.618Z,1605218020.618 [NAL9602] No Fault, FailCount= 3
2020-11-12T21:54:10.059Z,1605218050.059 [NAL9602](INFO): Powering up NAL9602
2020-11-12T21:54:20.967Z,1605218060.967 [NAL9602](INFO): NAL9602 initialized
2020-11-12T21:54:27.936Z,1605218067.936 [CBIT](CRITICAL): run-time exception #7
2020-11-12T21:54:27.936Z,1605218067.936 [CBIT](CRITICAL): Bad address: 0x4041E008
2020-11-12T21:54:27.937Z,1605218067.937 [CBIT](CRITICAL): Backtrace: 
./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x17ddd4]
./bin/LRAUV(_ZN7PCaller10PThreadRunEPv+0xc8) [0x17d920]

2020-11-12T21:54:27.937Z,1605218067.937 [CBIT] Software Fault, FailCount= 1
2020-11-12T21:54:27.937Z,1605218067.937 [CBIT](ERROR): Software Fault
2020-11-12T21:54:27.951Z,1605218067.951 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-11-12T21:54:27.951Z,1605218067.951 [SyncHandler](INFO): Protected caller Thread ID is 9683
2020-11-12T21:54:28.313Z,1605218068.313 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-11-12T21:54:28.313Z,1605218068.313 [CBIT](DEBUG): Powering off loads.
2020-11-12T21:54:28.326Z,1605218068.326 [CBIT](DEBUG): Disabling WDT.
2020-11-12T21:54:28.338Z,1605218068.338 [CBIT](DEBUG): Opening all GF detection circuits.
2020-11-12T21:54:38.750Z,1605218078.750 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2020-11-12T21:54:38.750Z,1605218078.750 [AHRS_M2] Data Fault, FailCount= 1
2020-11-12T21:54:38.750Z,1605218078.750 [AHRS_M2](ERROR): Data Fault
2020-11-12T21:54:39.134Z,1605218079.134 [AHRS_M2](INFO): Powering down
2020-11-12T21:54:39.538Z,1605218079.538 [AHRS_M2](FAULT): LCB fault:  Software Overcurrent.
2020-11-12T21:54:39.538Z,1605218079.538 [AHRS_M2] Hardware Fault, FailCount= 1
2020-11-12T21:54:39.538Z,1605218079.538 [AHRS_M2](ERROR): Hardware Fault
2020-11-12T21:54:59.340Z,1605218099.340 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2020-11-12T21:54:59.862Z,1605218099.862 [NAL9602](FAULT): received: 
2020-11-12T21:54:59.862Z,1605218099.862 [NAL9602] Data Fault, FailCount= 4
2020-11-12T21:54:59.862Z,1605218099.862 [NAL9602](ERROR): Data Fault
2020-11-12T21:54:59.947Z,1605218099.947 [NAL9602](INFO): Powering down
2020-11-12T21:55:00.311Z,1605218100.311 [NAL9602](FAULT): LCB fault:  Current Limiter Activated.
2020-11-12T21:55:00.311Z,1605218100.311 [NAL9602] Hardware Fault, FailCount= 4
2020-11-12T21:55:00.311Z,1605218100.311 [NAL9602](ERROR): Hardware Fault
2020-11-12T21:55:14.907Z,1605218114.907 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-11-12T21:55:21.375Z,1605218121.375 [CommandLine](IMPORTANT): got command quit
2020-11-12T21:55:22.382Z,1605218122.382 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-11-12T21:55:22.383Z,1605218122.383 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-11-12T21:55:22.554Z,1605218122.554 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-11-12T21:55:22.554Z,1605218122.554 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-11-12T21:55:22.555Z,1605218122.555 [CommandLine](INFO): Join timeout helper Thread ID is 309
2020-11-12T21:55:22.566Z,1605218122.566 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-11-12T21:55:22.566Z,1605218122.566 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-11-12T21:55:22.567Z,1605218122.567 [NavChartDb](INFO): Join timeout helper Thread ID is 311
2020-11-12T21:55:22.838Z,1605218122.838 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-11-12T21:55:22.839Z,1605218122.839 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-11-12T21:55:22.849Z,1605218122.849 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2020-11-12T21:55:22.849Z,1605218122.849 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-11-12T21:55:22.850Z,1605218122.850 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 312
2020-11-12T21:55:23.315Z,1605218123.315 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2020-11-12T21:55:23.315Z,1605218123.315 [WetLabsBB2FL](INFO): Powering down
2020-11-12T21:55:23.316Z,1605218123.316 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-11-12T21:55:23.330Z,1605218123.330 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2020-11-12T21:55:23.330Z,1605218123.330 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-11-12T21:55:23.331Z,1605218123.331 [CTD_Seabird](INFO): Join timeout helper Thread ID is 317
2020-11-12T21:55:24.162Z,1605218124.162 [CTD_Seabird](INFO): Powering down
2020-11-12T21:55:24.175Z,1605218124.175 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2020-11-12T21:55:24.175Z,1605218124.175 [CTD_Seabird](INFO): Powering down
2020-11-12T21:55:24.186Z,1605218124.186 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-11-12T21:55:24.198Z,1605218124.198 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-11-12T21:55:24.198Z,1605218124.198 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-11-12T21:55:24.199Z,1605218124.199 [Radio_Surface](INFO): Join timeout helper Thread ID is 322
2020-11-12T21:55:24.246Z,1605218124.246 [Radio_Surface](INFO): Powering down
2020-11-12T21:55:24.247Z,1605218124.247 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-11-12T21:55:24.247Z,1605218124.247 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-11-12T21:55:24.255Z,1605218124.255 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2020-11-12T21:55:24.255Z,1605218124.255 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-11-12T21:55:24.256Z,1605218124.256 [Onboard](INFO): Join timeout helper Thread ID is 325
2020-11-12T21:55:29.174Z,1605218129.174 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2020-11-12T21:55:29.175Z,1605218129.175 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-11-12T21:55:29.195Z,1605218129.195 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-11-12T21:55:29.195Z,1605218129.195 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-11-12T21:55:29.195Z,1605218129.195 [DataOverHttps](INFO): Join timeout helper Thread ID is 354
2020-11-12T21:55:29.966Z,1605218129.966 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-11-12T21:55:29.967Z,1605218129.967 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-11-12T21:55:29.987Z,1605218129.987 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-11-12T21:55:29.987Z,1605218129.987 [logger ThreadHandler](INFO): Thread cancelled.
2020-11-12T21:55:29.987Z,1605218129.987 [logger](INFO): Join timeout helper Thread ID is 359
2020-11-12T21:55:30.002Z,1605218130.002 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-11-12T21:55:30.003Z,1605218130.003 [logger ThreadHandler](INFO): Thread cancelled.
2020-11-12T21:55:30.007Z,1605218130.007 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-11-12T21:55:30.007Z,1605218130.007 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-11-12T21:55:30.007Z,1605218130.007 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-11-12T21:55:30.007Z,1605218130.007 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-11-12T21:55:30.008Z,1605218130.008 [controlThread](INFO): Join timeout helper Thread ID is 360
2020-11-12T21:55:30.202Z,1605218130.202 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-11-12T21:55:30.203Z,1605218130.203 [controlThread](DEBUG): Uninitializing ControlThread
2020-11-12T21:55:30.203Z,1605218130.203 [AHRS_M2](INFO): Powering down
2020-11-12T21:55:30.204Z,1605218130.204 [NAL9602](INFO): Powering down
2020-11-12T21:55:30.205Z,1605218130.205 [AMEcho](INFO): Powering down
2020-11-12T21:55:30.207Z,1605218130.207 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-11-12T21:55:30.208Z,1605218130.208 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-11-12T21:55:30.209Z,1605218130.209 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-11-12T21:55:30.209Z,1605218130.209 [MissionManager](INFO): Uninitializing Mission Default
2020-11-12T21:55:30.209Z,1605218130.209 [Default] Stopped
2020-11-12T21:55:30.209Z,1605218130.209 [Default](DEBUG): Aggregate::uninitialize Default
2020-11-12T21:55:30.209Z,1605218130.209 [Default:B.GoToSurface] Stopped
2020-11-12T21:55:30.210Z,1605218130.210 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-11-12T21:55:30.210Z,1605218130.210 [Default:CheckIn] Stopped
2020-11-12T21:55:30.210Z,1605218130.210 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T21:55:30.210Z,1605218130.210 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T21:55:30.212Z,1605218130.212 [MissionManager](INFO): Uninitializing Mission profile_station
2020-11-12T21:55:30.236Z,1605218130.236 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-11-12T21:55:30.236Z,1605218130.236 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-11-12T21:55:30.236Z,1605218130.236 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-11-12T21:55:30.236Z,1605218130.236 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-11-12T21:55:30.237Z,1605218130.237 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-11-12T21:55:30.237Z,1605218130.237 [BuoyancyServo](INFO): Powering down
2020-11-12T21:55:30.250Z,1605218130.250 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-11-12T21:55:30.250Z,1605218130.250 [ElevatorServo](INFO): Powering down
2020-11-12T21:55:30.251Z,1605218130.251 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-11-12T21:55:30.251Z,1605218130.251 [MassServo](INFO): Powering down
2020-11-12T21:55:30.252Z,1605218130.252 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-11-12T21:55:30.252Z,1605218130.252 [RudderServo](INFO): Powering down
2020-11-12T21:55:30.253Z,1605218130.253 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-11-12T21:55:30.253Z,1605218130.253 [ThrusterServo](INFO): Powering down
2020-11-12T21:55:30.254Z,1605218130.254 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-11-12T21:55:30.254Z,1605218130.254 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-11-12T21:55:30.254Z,1605218130.254 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-11-12T21:55:30.254Z,1605218130.254 [CBIT](DEBUG): Powering off loads.
2020-11-12T21:55:30.266Z,1605218130.266 [CBIT](DEBUG): Disabling WDT.
2020-11-12T21:55:30.278Z,1605218130.278 [CBIT](DEBUG): Opening all GF detection circuits.
2020-11-12T21:55:30.279Z,1605218130.279 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-11-12T21:55:30.362Z,1605218130.362 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-11-12T21:55:30.364Z,1605218130.364 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-11-12T21:55:30.370Z,1605218130.370 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-11-12T21:55:30.406Z,1605218130.406 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-11-12T21:55:30.410Z,1605218130.410 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-11-12T21:55:30.489Z,1605218130.489 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-11-12T21:55:30.589Z,1605218130.589 [logger ThreadHandler](INFO): Thread cancelled.