2022-02-01T02:56:42.785Z,1643684202.785 [CommandExec](IMPORTANT): got command restart logs 2022-02-01T02:59:49.738Z,1643684389.738 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-02-01T02:59:49.738Z,1643684389.738 [Default:CheckIn:C.Wait] Stopped 2022-02-01T02:59:49.738Z,1643684389.738 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-02-01T02:59:49.739Z,1643684389.739 [Default:CheckIn:D] Running Loop=1 2022-02-01T02:59:50.109Z,1643684390.109 [Default:CheckIn:D] Stopped 2022-02-01T02:59:50.109Z,1643684390.109 [Default:CheckIn:E] Running Loop=1 2022-02-01T02:59:50.525Z,1643684390.525 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 14.389920 min 2022-02-01T02:59:50.525Z,1643684390.525 [Default:CheckIn:E] Stopped 2022-02-01T02:59:50.525Z,1643684390.525 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-02-01T02:59:50.525Z,1643684390.525 [Default:CheckIn] Stopped 2022-02-01T02:59:50.525Z,1643684390.525 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-02-01T02:59:50.525Z,1643684390.525 [Default:CheckIn](INFO): Running loop #3 2022-02-01T02:59:50.525Z,1643684390.525 [Default:CheckIn] Running Loop=3 2022-02-01T02:59:50.525Z,1643684390.525 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-02-01T02:59:50.525Z,1643684390.525 [Default:CheckIn:Read_GPS] Running Loop=1 2022-02-01T02:59:52.523Z,1643684392.523 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,025954.00,A,4132.53352,N,07043.87667,W,0.564,203.50,010222,,,D*78 2022-02-01T02:59:52.525Z,1643684392.525 [NAL9602](INFO): GPS fix at 20220201T025954: (41.542225, -70.731278) 2022-02-01T02:59:52.554Z,1643684392.554 [Default:CheckIn:Read_GPS] Stopped 2022-02-01T02:59:52.554Z,1643684392.554 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-02-01T02:59:56.571Z,1643684396.571 [DataOverHttps](INFO): Sending 51 bytes from file Logs/20220131T193145/Courier0058.lzma 2022-02-01T02:59:57.574Z,1643684397.574 [DataOverHttps](INFO): Moved sent file to Logs/20220131T193145/Courier0058.lzma.bak 2022-02-01T02:59:57.574Z,1643684397.574 [DataOverHttps](INFO): SBD MOMSN=1383285 2022-02-01T03:00:06.612Z,1643684406.612 [DataOverHttps](INFO): Sending 255 bytes from file Logs/20220201T025642/Courier0000.lzma 2022-02-01T03:00:07.615Z,1643684407.615 [DataOverHttps](INFO): Moved sent file to Logs/20220201T025642/Courier0000.lzma.bak 2022-02-01T03:00:07.616Z,1643684407.616 [DataOverHttps](INFO): SBD MOMSN=1383287 2022-02-01T03:00:16.652Z,1643684416.652 [DataOverHttps](INFO): Sending 51 bytes from file Logs/20220201T025642/Courier0003.lzma 2022-02-01T03:00:17.655Z,1643684417.655 [DataOverHttps](INFO): Moved sent file to Logs/20220201T025642/Courier0003.lzma.bak 2022-02-01T03:00:17.656Z,1643684417.656 [DataOverHttps](INFO): SBD MOMSN=1383291 2022-02-01T03:00:26.691Z,1643684426.691 [DataOverHttps](INFO): Sending 115 bytes from file Logs/20220131T193145/Express0059.lzma 2022-02-01T03:00:27.694Z,1643684427.694 [DataOverHttps](INFO): Moved sent file to Logs/20220131T193145/Express0059.lzma.bak 2022-02-01T03:00:27.694Z,1643684427.694 [DataOverHttps](INFO): SBD MOMSN=1383293 2022-02-01T03:00:36.733Z,1643684436.733 [DataOverHttps](INFO): Sending 420 bytes from file Logs/20220201T025642/Express0001.lzma 2022-02-01T03:00:37.733Z,1643684437.733 [DataOverHttps](INFO): Moved sent file to Logs/20220201T025642/Express0001.lzma.bak 2022-02-01T03:00:37.734Z,1643684437.734 [DataOverHttps](INFO): SBD MOMSN=1383296 2022-02-01T03:00:46.771Z,1643684446.771 [DataOverHttps](INFO): Sending 60 bytes from file Logs/20220201T025642/Express0004.lzma 2022-02-01T03:00:47.775Z,1643684447.775 [DataOverHttps](INFO): Moved sent file to Logs/20220201T025642/Express0004.lzma.bak 2022-02-01T03:00:47.775Z,1643684447.775 [DataOverHttps](IMPORTANT): SBD MOMSN=1383301, MTMSN=20220201T030049 2022-02-01T03:00:49.515Z,1643684449.515 [Default:CheckIn:Read_Iridium] Stopped 2022-02-01T03:00:49.515Z,1643684449.515 [Default:CheckIn:C.Wait] Running Loop=1 2022-02-01T03:00:49.515Z,1643684449.515 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-02-01T03:00:51.792Z,1643684451.792 [DataOverHttps](INFO): Received command: sched asap "load Science/profile_station.xml;set profile_station.MissionTimeout 600 min;set profile_station.NeedCommsTime 120 min;set profile_station.Lat 41.543326 degree;set profile_station.Lon -70.727412 degree" 18oda 1 3 2022-02-01T03:00:51.812Z,1643684451.812 [CommandExec](IMPORTANT): got command schedule asap "load Science/profile_station.xml;set profile_station.MissionTimeout 600 min;set profile_station.NeedCommsTime 120 min;set profile_station.Lat 41.543326 degree;set profile_station.Lon -70.727412 degree" 18oda 1 3.000000 2022-02-01T03:00:51.813Z,1643684451.813 [CommandExec](IMPORTANT): Scheduling command #1 of 3 with id=18oda 2022-02-01T03:00:51.814Z,1643684451.814 [CommandExec](IMPORTANT): Scheduled #7 (#1 of 3 with id='18oda'): "load Science/profile_station.xml;set profile_station.MissionTimeout 600 min;set profile_station.NeedCommsTime 120 min;set profile_station.Lat 41.543326 degree;set profile_station.Lon -70.727412 degree", ASAP 2022-02-01T03:00:52.794Z,1643684452.794 [DataOverHttps](IMPORTANT): SBD MTMSN=20220201T030054 2022-02-01T03:00:56.812Z,1643684456.812 [DataOverHttps](INFO): Received command: sched asap "set profile_station.YoYoMinDepth 3 m;set profile_station.YoYoMaxDepth 7 m;set profile_station.YoYoMinAltitude 3 m;set profile_station.YoYoPitch 15 degree;set profile_station.Speed .8 m/s;set profile_station.MinAltitude 3 m" 18oda 2 3 2022-02-01T03:00:56.829Z,1643684456.829 [CommandExec](IMPORTANT): got command schedule asap "set profile_station.YoYoMinDepth 3 m;set profile_station.YoYoMaxDepth 7 m;set profile_station.YoYoMinAltitude 3 m;set profile_station.YoYoPitch 15 degree;set profile_station.Speed .8 m/s;set profile_station.MinAltitude 3 m" 18oda 2 3.000000 2022-02-01T03:00:56.829Z,1643684456.829 [CommandExec](IMPORTANT): Scheduling command #2 of 3 with id=18oda 2022-02-01T03:00:56.830Z,1643684456.830 [CommandExec](IMPORTANT): Scheduled #8 (#2 of 3 with id='18oda'): "set profile_station.YoYoMinDepth 3 m;set profile_station.YoYoMaxDepth 7 m;set profile_station.YoYoMinAltitude 3 m;set profile_station.YoYoPitch 15 degree;set profile_station.Speed .8 m/s;set profile_station.MinAltitude 3 m", ASAP 2022-02-01T03:00:57.815Z,1643684457.815 [DataOverHttps](IMPORTANT): SBD MTMSN=20220201T030059 2022-02-01T03:01:01.832Z,1643684461.832 [DataOverHttps](INFO): Received command: sched asap "set profile_station.MaxDepth 9 m;set profile_station.MinOffshore 500 m;run" 18oda 3 3 2022-02-01T03:01:01.843Z,1643684461.843 [CommandExec](IMPORTANT): got command schedule asap "set profile_station.MaxDepth 9 m;set profile_station.MinOffshore 500 m;run" 18oda 3 3.000000 2022-02-01T03:01:01.843Z,1643684461.843 [CommandExec](IMPORTANT): Scheduling command #3 of 3 with id=18oda 2022-02-01T03:01:01.845Z,1643684461.845 [CommandExec](IMPORTANT): Scheduled #9 (#3 of 3 with id='18oda'): "set profile_station.MaxDepth 9 m;set profile_station.MinOffshore 500 m;run", ASAP 2022-02-01T03:01:01.944Z,1643684461.944 [CommandExec](IMPORTANT): got command load ./Missions/Science/profile_station.xml 2022-02-01T03:01:01.945Z,1643684461.945 [MissionManager](INFO): Loading Mission from file: ./Missions/Science/profile_station.xml 2022-02-01T03:01:02.125Z,1643684462.125 [MissionManager](INFO): DefineArg profile_station.MissionTimeout = 4.000000 h 2022-02-01T03:01:02.129Z,1643684462.129 [MissionManager](INFO): DefineArg profile_station.NeedCommsTime = 60.000000 min 2022-02-01T03:01:02.132Z,1643684462.132 [MissionManager](INFO): DefineArg profile_station.Lat = 36.806965 arcdeg 2022-02-01T03:01:02.135Z,1643684462.135 [MissionManager](INFO): DefineArg profile_station.Lon = -121.824326 arcdeg 2022-02-01T03:01:02.138Z,1643684462.138 [MissionManager](INFO): DefineArg profile_station.Radius = 300.000000 m 2022-02-01T03:01:02.149Z,1643684462.149 [MissionManager](INFO): DefineArg profile_station.YoYoMinDepth = 2.000000 m 2022-02-01T03:01:02.168Z,1643684462.168 [MissionManager](INFO): DefineArg profile_station.YoYoMaxDepth = 200.000000 m 2022-02-01T03:01:02.171Z,1643684462.171 [MissionManager](INFO): DefineArg profile_station.YoYoMinAltitude = 7.000000 m 2022-02-01T03:01:02.178Z,1643684462.178 [MissionManager](INFO): DefineArg profile_station.YoYoPitch = 20.000000 arcdeg 2022-02-01T03:01:02.185Z,1643684462.185 [MissionManager](INFO): DefineArg profile_station.Speed = 1.000000 m/s 2022-02-01T03:01:02.188Z,1643684462.188 [MissionManager](INFO): DefineArg profile_station.CircleMaxError = 100.000000 m 2022-02-01T03:01:02.191Z,1643684462.191 [MissionManager](INFO): DefineArg profile_station.CircleTurnToPort = 0 bool 2022-02-01T03:01:02.213Z,1643684462.213 [MissionManager](INFO): DefineArg profile_station.KwpHeading = 0.010000 rad/m 2022-02-01T03:01:02.216Z,1643684462.216 [MissionManager](INFO): DefineArg profile_station.MinAltitude = 5.000000 m 2022-02-01T03:01:02.219Z,1643684462.219 [MissionManager](INFO): DefineArg profile_station.MaxDepth = 205.000000 m 2022-02-01T03:01:02.235Z,1643684462.235 [MissionManager](INFO): DefineArg profile_station.MinOffshore = 2.000000 km 2022-02-01T03:01:02.245Z,1643684462.245 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/Science.xml 2022-02-01T03:01:02.623Z,1643684462.623 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectChlActive = 0 bool 2022-02-01T03:01:02.626Z,1643684462.626 [MissionManager](INFO): DefineArg profile_station:Science.TimeWindowPeakReport = nan min 2022-02-01T03:01:02.645Z,1643684462.645 [MissionManager](INFO): DefineArg profile_station:Science.HighestChlPeakReportActive = 0 bool 2022-02-01T03:01:02.647Z,1643684462.647 [MissionManager](INFO): DefineArg profile_station:Science.HighestSaltPeakReportActive = 0 bool 2022-02-01T03:01:02.654Z,1643684462.654 [MissionManager](INFO): DefineArg profile_station:Science.HighestOilPeakReportActive = 0 bool 2022-02-01T03:01:02.657Z,1643684462.657 [MissionManager](INFO): DefineArg profile_station:Science.PatchTracking = 0 bool 2022-02-01T03:01:02.677Z,1643684462.677 [MissionManager](INFO): DefineArg profile_station:Science.DetectTrough = 0 bool 2022-02-01T03:01:02.680Z,1643684462.680 [MissionManager](INFO): DefineArg profile_station:Science.FilterWidthHorizontal = 3.000000 count 2022-02-01T03:01:02.683Z,1643684462.683 [MissionManager](INFO): DefineArg profile_station:Science.NumProfilesSlidingwindow = 100.000000 count 2022-02-01T03:01:02.686Z,1643684462.686 [MissionManager](INFO): DefineArg profile_station:Science.OffPeakFractionHorizontal = 80.000000 % 2022-02-01T03:01:02.699Z,1643684462.699 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectNO3Active = 0 bool 2022-02-01T03:01:02.712Z,1643684462.712 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectOilActive = 0 bool 2022-02-01T03:01:02.714Z,1643684462.714 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectFDOMActive = 0 bool 2022-02-01T03:01:02.719Z,1643684462.719 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectSalinityActive = 0 bool 2022-02-01T03:01:02.722Z,1643684462.722 [MissionManager](INFO): DefineArg profile_station:Science.UpwardDerivativeOfTemperatureActive = 0 bool 2022-02-01T03:01:02.725Z,1643684462.725 [MissionManager](INFO): DefineArg profile_station:Science.EnabledAanderaaO2 = 0 bool 2022-02-01T03:01:02.756Z,1643684462.756 [MissionManager](INFO): DefineArg profile_station:Science.EnabledNeilBrown = 0 bool 2022-02-01T03:01:02.759Z,1643684462.759 [MissionManager](INFO): DefineArg profile_station:Science.EnabledSeabird = 1 bool 2022-02-01T03:01:02.763Z,1643684462.763 [MissionManager](INFO): DefineArg profile_station:Science.EnabledWetLabsBB2FL = 0 bool 2022-02-01T03:01:02.776Z,1643684462.776 [MissionManager](INFO): DefineArg profile_station:Science.EnabledWetLabsSeaOWL_UV_A = 1 bool 2022-02-01T03:01:02.779Z,1643684462.779 [MissionManager](INFO): DefineArg profile_station:Science.EnabledWetLabsUBAT = 0 bool 2022-02-01T03:01:02.782Z,1643684462.782 [MissionManager](INFO): DefineArg profile_station:Science.LowPassWindowLength = 20.000000 count 2022-02-01T03:01:02.829Z,1643684462.829 [MissionManager](INFO): DefineArg profile_station:Science.MedianFilterLen = 5.000000 count 2022-02-01T03:01:02.845Z,1643684462.845 [MissionManager](INFO): DefineArg profile_station:Science.PeakShallowBound = nan m 2022-02-01T03:01:02.847Z,1643684462.847 [MissionManager](INFO): DefineArg profile_station:Science.PeakDeepBound = nan m 2022-02-01T03:01:02.881Z,1643684462.881 [MissionManager](INFO): DefineArg profile_station:Science.DepChangeThreshForAttitudeFlip = 2.000000 m 2022-02-01T03:01:02.896Z,1643684462.896 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChl = 0.000000 ug/l 2022-02-01T03:01:02.904Z,1643684462.904 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlDepth = 0.000000 m 2022-02-01T03:01:02.907Z,1643684462.907 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlTemperature = 0.000000 degC 2022-02-01T03:01:02.914Z,1643684462.914 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlLatitude = nan arcdeg 2022-02-01T03:01:02.922Z,1643684462.922 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlLongitude = nan arcdeg 2022-02-01T03:01:02.925Z,1643684462.925 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3 = 0.000000 umol/l 2022-02-01T03:01:02.937Z,1643684462.937 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3Depth = 0.000000 m 2022-02-01T03:01:02.944Z,1643684462.944 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3Latitude = nan arcdeg 2022-02-01T03:01:02.952Z,1643684462.952 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3Longitude = nan arcdeg 2022-02-01T03:01:02.955Z,1643684462.955 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOil = 0.000000 kg/m3 2022-02-01T03:01:02.962Z,1643684462.962 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOilDepth = 0.000000 m 2022-02-01T03:01:02.973Z,1643684462.973 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOilLatitude = nan arcdeg 2022-02-01T03:01:02.977Z,1643684462.977 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOilLongitude = nan arcdeg 2022-02-01T03:01:02.984Z,1643684462.984 [MissionManager](INFO): DefineOutput profile_station:Science.PeakFDOM = 0.000000 ppb 2022-02-01T03:01:02.989Z,1643684462.989 [MissionManager](INFO): DefineOutput profile_station:Science.PeakFDOMDepth = 0.000000 m 2022-02-01T03:01:02.993Z,1643684462.993 [MissionManager](INFO): DefineOutput profile_station:Science.PeakFDOMTemperature = nan degC 2022-02-01T03:01:02.999Z,1643684462.999 [MissionManager](INFO): DefineOutput profile_station:Science.PeakFDOMLatitude = nan arcdeg 2022-02-01T03:01:03.016Z,1643684463.016 [MissionManager](INFO): DefineOutput profile_station:Science.PeakFDOMLongitude = nan arcdeg 2022-02-01T03:01:03.019Z,1643684463.019 [MissionManager](INFO): DefineOutput profile_station:Science.PatchFDOMDistance = nan m 2022-02-01T03:01:03.030Z,1643684463.030 [MissionManager](INFO): DefineOutput profile_station:Science.PeakSalt = 0.000000 psu 2022-02-01T03:01:03.033Z,1643684463.033 [MissionManager](INFO): DefineOutput profile_station:Science.PeakSaltDepth = 0.000000 m 2022-02-01T03:01:03.038Z,1643684463.038 [MissionManager](INFO): DefineOutput profile_station:Science.PeakSaltTemperature = 0.000000 degC 2022-02-01T03:01:03.050Z,1643684463.050 [MissionManager](INFO): DefineOutput profile_station:Science.PeakSaltLatitude = nan arcdeg 2022-02-01T03:01:03.057Z,1643684463.057 [MissionManager](INFO): DefineOutput profile_station:Science.PeakSaltLongitude = nan arcdeg 2022-02-01T03:01:03.064Z,1643684463.064 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChl = 0.000000 ug/l 2022-02-01T03:01:03.067Z,1643684463.067 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlDepth = 0.000000 m 2022-02-01T03:01:03.075Z,1643684463.075 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlLatitude = nan arcdeg 2022-02-01T03:01:03.091Z,1643684463.091 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlLongitude = nan arcdeg 2022-02-01T03:01:03.095Z,1643684463.095 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlDistance = nan m 2022-02-01T03:01:03.107Z,1643684463.107 [MissionManager](INFO): DefineOutput profile_station:Science.PatchSalt = 0.000000 psu 2022-02-01T03:01:03.120Z,1643684463.120 [MissionManager](INFO): DefineOutput profile_station:Science.PatchSaltDepth = 0.000000 m 2022-02-01T03:01:03.123Z,1643684463.123 [MissionManager](INFO): DefineOutput profile_station:Science.PatchSaltLatitude = nan arcdeg 2022-02-01T03:01:03.131Z,1643684463.131 [MissionManager](INFO): DefineOutput profile_station:Science.PatchSaltLongitude = nan arcdeg 2022-02-01T03:01:03.138Z,1643684463.138 [MissionManager](INFO): DefineOutput profile_station:Science.PatchSaltDistance = nan m 2022-02-01T03:01:03.146Z,1643684463.146 [MissionManager](INFO): DefineOutput profile_station:Science.PatchOil = 0.000000 psu 2022-02-01T03:01:03.153Z,1643684463.153 [MissionManager](INFO): DefineOutput profile_station:Science.PatchOilDepth = 0.000000 m 2022-02-01T03:01:03.160Z,1643684463.160 [MissionManager](INFO): DefineOutput profile_station:Science.PatchOilLatitude = nan arcdeg 2022-02-01T03:01:03.168Z,1643684463.168 [MissionManager](INFO): DefineOutput profile_station:Science.PatchOilLongitude = nan arcdeg 2022-02-01T03:01:03.171Z,1643684463.171 [MissionManager](INFO): DefineOutput profile_station:Science.PatchOilDistance = nan m 2022-02-01T03:01:03.247Z,1643684463.247 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2022-02-01T03:01:03.288Z,1643684463.288 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal. 2022-02-01T03:01:03.325Z,1643684463.325 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2022-02-01T03:01:03.348Z,1643684463.348 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2022-02-01T03:01:03.367Z,1643684463.367 [profile_station:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal. 2022-02-01T03:01:03.389Z,1643684463.389 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2022-02-01T03:01:03.413Z,1643684463.413 [profile_station:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2022-02-01T03:01:03.435Z,1643684463.435 [profile_station:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal. 2022-02-01T03:01:03.466Z,1643684463.466 [MissionManager](INFO): RedefineArg profile_station:Science.PeakDetectChlActive = value:1 bool 2022-02-01T03:01:03.466Z,1643684463.466 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.xml 2022-02-01T03:01:03.555Z,1643684463.555 [MissionManager](INFO): DefineArg profile_station:NeedComms.DiveInterval = 3.000000 h 2022-02-01T03:01:03.563Z,1643684463.563 [MissionManager](INFO): DefineArg profile_station:NeedComms.WaitForPitchUp = 10.000000 min 2022-02-01T03:01:03.577Z,1643684463.577 [MissionManager](INFO): DefineArg profile_station:NeedComms.SurfacePitch = 20.000000 arcdeg 2022-02-01T03:01:03.583Z,1643684463.583 [MissionManager](INFO): DefineArg profile_station:NeedComms.SurfaceDepthRate = nan m/s 2022-02-01T03:01:03.600Z,1643684463.600 [MissionManager](INFO): DefineArg profile_station:NeedComms.SurfaceSpeed = 1.000000 m/s 2022-02-01T03:01:03.645Z,1643684463.645 [MissionManager](INFO): DefineArg profile_station:NeedComms.GPSTimeout = 7.000000 min 2022-02-01T03:01:03.659Z,1643684463.659 [MissionManager](INFO): DefineArg profile_station:NeedComms.CommsTimeout = 30.000000 min 2022-02-01T03:01:03.697Z,1643684463.697 [profile_station:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface. 2022-02-01T03:01:03.746Z,1643684463.746 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.xml 2022-02-01T03:01:03.784Z,1643684463.784 [MissionManager](INFO): DefineArg profile_station:StandardEnvelopes.MinAltitude = 5.000000 m 2022-02-01T03:01:03.790Z,1643684463.790 [MissionManager](INFO): DefineArg profile_station:StandardEnvelopes.MaxDepth = 200.000000 m 2022-02-01T03:01:03.793Z,1643684463.793 [MissionManager](INFO): DefineArg profile_station:StandardEnvelopes.MinOffshore = 2000.000000 m 2022-02-01T03:01:03.794Z,1643684463.794 [profile_station:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2022-02-01T03:01:03.811Z,1643684463.811 [profile_station:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2022-02-01T03:01:03.837Z,1643684463.837 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2022-02-01T03:01:03.863Z,1643684463.863 [profile_station:I.Pitch](DEBUG): Construct. 2022-02-01T03:01:03.877Z,1643684463.877 [profile_station:J.Buoyancy](DEBUG): Construct Buoyancy. 2022-02-01T03:01:03.883Z,1643684463.883 [profile_station:K.SetSpeed](DEBUG): Construct. 2022-02-01T03:01:03.899Z,1643684463.899 [profile_station:L.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2022-02-01T03:01:03.925Z,1643684463.925 [profile_station:M.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2022-02-01T03:01:03.933Z,1643684463.933 [profile_station:N.YoYo](DEBUG): Construct YoYo. 2022-02-01T03:01:03.962Z,1643684463.962 [profile_station:CircleWrapper:B.Circle](DEBUG): Construct. 2022-02-01T03:01:04.105Z,1643684464.105 [MissionManager](DEBUG): This mission yoyos in a circle around a specified location. Maximum duration of mission 4 Elapsed time after previous surface communications when vehicle will begin to ascend for additional surface communications 60 The latitude of the center of the circle. 36.806966 The longitude of the center of the circle. -121.824326 Radius to circle at 300 Minimum depth while performing the YoYo behavior. 2 Maximum depth while performing the YoYo behavior. 200 Minimum altitude while performing the YoYo behavior (for bottom-terminated YoYos). 7 Pitch (plus and minus) for yo-yo behavior. 20 Vehicle speed. 1 If this distance away from the circle, drive straight towards (or away from the center). Otherwise, try to reduce distance from the ideal circle. 100 If true, vehicle turns to the left around the center point. If false, vehicle turns to the right. Used to relax waypoint cross-track error constant that is adjusted for docking. (You can override this setting by passing an argument.) 0.010 Minimum height above the sea floor for the entire mission. 5 Maximum depth for the entire mission. 205 Minimum offshore distance for the entire mission. 2 Most missions will run the science sensors. If you don't place this aggregate above NeedComms, science instruments get turned off on the last upcast and while floating on the surface. Another depth envelope for the YoYo behavior. This envelope should fall within the limits of the standard safety envelopes in Insert/StandardEnvelopes.xml in order to avoid commanding high pitch angles for depth-terminated YoYos. Another altitude envelope for the YoYo behavior. This envelope should fall within the limits of the standard safety envelopes in Insert/StandardEnvelopes.xml in order to avoid commanding high pitch angles for bottom-terminated YoYos. 2022-02-01T03:01:04.106Z,1643684464.106 [CommandExec](IMPORTANT): Loaded ./Missions/Science/profile_station.xml 2022-02-01T03:01:05.239Z,1643684465.239 [NAL9602](INFO): SBD MO Status=1, MOMSN=35559, MT Status=0, MTMSN=0 2022-02-01T03:01:05.239Z,1643684465.239 [NAL9602](INFO): No messages in MT queue 2022-02-01T03:01:13.029Z,1643684473.029 [CommandExec](IMPORTANT): got command set profile_station.MissionTimeout 600 minute 2022-02-01T03:01:13.030Z,1643684473.030 [CommandExec](IMPORTANT): got command set profile_station.NeedCommsTime 120 minute 2022-02-01T03:01:13.031Z,1643684473.031 [CommandExec](IMPORTANT): got command set profile_station.Lat 41.543326 degree 2022-02-01T03:01:13.031Z,1643684473.031 [CommandExec](IMPORTANT): got command set profile_station.Lon -70.727412 degree 2022-02-01T03:01:26.259Z,1643684486.259 [CommandExec](IMPORTANT): got command set profile_station.YoYoMinDepth 3 meter 2022-02-01T03:01:26.260Z,1643684486.260 [CommandExec](IMPORTANT): got command set profile_station.YoYoMaxDepth 7 meter 2022-02-01T03:01:26.261Z,1643684486.261 [CommandExec](IMPORTANT): got command set profile_station.YoYoMinAltitude 3 meter 2022-02-01T03:01:26.261Z,1643684486.261 [CommandExec](IMPORTANT): got command set profile_station.YoYoPitch 15 degree 2022-02-01T03:01:26.262Z,1643684486.262 [CommandExec](IMPORTANT): got command set profile_station.Speed .8 meter_per_second 2022-02-01T03:01:26.263Z,1643684486.263 [CommandExec](IMPORTANT): got command set profile_station.MinAltitude 3 meter 2022-02-01T03:01:30.549Z,1643684490.549 [CommandExec](IMPORTANT): got command set profile_station.MaxDepth 9 meter 2022-02-01T03:01:30.549Z,1643684490.549 [CommandExec](IMPORTANT): got command set profile_station.MinOffshore 500 meter 2022-02-01T03:01:30.550Z,1643684490.550 [CommandExec](IMPORTANT): got command run 2022-02-01T03:01:30.578Z,1643684490.578 [CommandExec](IMPORTANT): Running 2022-02-01T03:01:30.752Z,1643684490.752 [Default] Stopped 2022-02-01T03:01:30.752Z,1643684490.752 [Default](DEBUG): Aggregate::uninitialize Default 2022-02-01T03:01:30.752Z,1643684490.752 [Default:B.GoToSurface] Stopped 2022-02-01T03:01:30.752Z,1643684490.752 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2022-02-01T03:01:30.752Z,1643684490.752 [Default:CheckIn] Stopped 2022-02-01T03:01:30.753Z,1643684490.753 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-02-01T03:01:30.753Z,1643684490.753 [Default:CheckIn:C.Wait] Stopped 2022-02-01T03:01:30.753Z,1643684490.753 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-02-01T03:01:30.753Z,1643684490.753 [MissionManager](IMPORTANT): Started mission profile_station 2022-02-01T03:01:30.753Z,1643684490.753 [profile_station] Running Loop=1 2022-02-01T03:01:30.753Z,1643684490.753 [profile_station](DEBUG): Aggregate::initialize profile_station 2022-02-01T03:01:30.753Z,1643684490.753 [profile_station:Science] Running Loop=1 2022-02-01T03:01:30.753Z,1643684490.753 [profile_station:Science](DEBUG): Aggregate::initialize profile_station:Science 2022-02-01T03:01:30.754Z,1643684490.754 [profile_station:Science:A] Running Loop=1 2022-02-01T03:01:30.754Z,1643684490.754 [profile_station:Science:B] Running Loop=1 2022-02-01T03:01:30.754Z,1643684490.754 [profile_station:Science:C] Running Loop=1 2022-02-01T03:01:30.754Z,1643684490.754 [profile_station:Science:D] Running Loop=1 2022-02-01T03:01:30.754Z,1643684490.754 [profile_station:Science:E] Running Loop=1 2022-02-01T03:01:30.754Z,1643684490.754 [profile_station:Science:F] Running Loop=1 2022-02-01T03:01:30.754Z,1643684490.754 [profile_station:Science:Read_Oil] Running Loop=1 2022-02-01T03:01:30.754Z,1643684490.754 [profile_station:Science:PeakDetectChl] Running Loop=1 2022-02-01T03:01:30.754Z,1643684490.754 [profile_station:Science:PeakDetectChl](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectChl 2022-02-01T03:01:30.755Z,1643684490.755 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1 2022-02-01T03:01:30.755Z,1643684490.755 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize. 2022-02-01T03:01:30.757Z,1643684490.757 [profile_station:Science:HighestChlPeakReport] Running Loop=1 2022-02-01T03:01:30.757Z,1643684490.757 [profile_station:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize profile_station:Science:HighestChlPeakReport 2022-02-01T03:01:30.757Z,1643684490.757 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1 2022-02-01T03:01:30.757Z,1643684490.757 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2022-02-01T03:01:30.757Z,1643684490.757 [profile_station:Science:PeakDetectNO3] Running Loop=1 2022-02-01T03:01:30.758Z,1643684490.758 [profile_station:Science:PeakDetectNO3](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectNO3 2022-02-01T03:01:30.758Z,1643684490.758 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1 2022-02-01T03:01:30.758Z,1643684490.758 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize. 2022-02-01T03:01:30.759Z,1643684490.759 [profile_station:Science:PeakDetectOil] Running Loop=1 2022-02-01T03:01:30.759Z,1643684490.759 [profile_station:Science:PeakDetectOil](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectOil 2022-02-01T03:01:30.759Z,1643684490.759 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1 2022-02-01T03:01:30.760Z,1643684490.760 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize. 2022-02-01T03:01:30.761Z,1643684490.761 [profile_station:Science:HighestOilPeakReport] Running Loop=1 2022-02-01T03:01:30.761Z,1643684490.761 [profile_station:Science:HighestOilPeakReport](DEBUG): Aggregate::initialize profile_station:Science:HighestOilPeakReport 2022-02-01T03:01:30.762Z,1643684490.762 [profile_station:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Running Loop=1 2022-02-01T03:01:30.762Z,1643684490.762 [profile_station:Science:HighestOilPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2022-02-01T03:01:30.762Z,1643684490.762 [profile_station:Science:PeakDetectFDOM] Running Loop=1 2022-02-01T03:01:30.762Z,1643684490.762 [profile_station:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectFDOM 2022-02-01T03:01:30.762Z,1643684490.762 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1 2022-02-01T03:01:30.762Z,1643684490.762 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize. 2022-02-01T03:01:30.764Z,1643684490.764 [profile_station:Science:PeakDetectSalinity] Running Loop=1 2022-02-01T03:01:30.764Z,1643684490.764 [profile_station:Science:PeakDetectSalinity](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectSalinity 2022-02-01T03:01:30.764Z,1643684490.764 [profile_station:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Running Loop=1 2022-02-01T03:01:30.764Z,1643684490.764 [profile_station:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Initialize. 2022-02-01T03:01:30.766Z,1643684490.766 [profile_station:Science:HighestSaltPeakReport] Running Loop=1 2022-02-01T03:01:30.766Z,1643684490.766 [profile_station:Science:HighestSaltPeakReport](DEBUG): Aggregate::initialize profile_station:Science:HighestSaltPeakReport 2022-02-01T03:01:30.766Z,1643684490.766 [profile_station:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Running Loop=1 2022-02-01T03:01:30.766Z,1643684490.766 [profile_station:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2022-02-01T03:01:30.767Z,1643684490.767 [profile_station:Science:OceanCurrent] Running Loop=1 2022-02-01T03:01:30.767Z,1643684490.767 [profile_station:Science:OceanCurrent](DEBUG): Aggregate::initialize profile_station:Science:OceanCurrent 2022-02-01T03:01:30.767Z,1643684490.767 [profile_station:Science:OceanCurrent:A.] Running Loop=1 2022-02-01T03:01:30.767Z,1643684490.767 [profile_station:Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator. 2022-02-01T03:01:30.767Z,1643684490.767 [profile_station:StandardEnvelopes] Running Loop=1 2022-02-01T03:01:30.767Z,1643684490.767 [profile_station:StandardEnvelopes](DEBUG): Aggregate::initialize profile_station:StandardEnvelopes 2022-02-01T03:01:30.767Z,1643684490.767 [profile_station:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2022-02-01T03:01:30.767Z,1643684490.767 [profile_station:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2022-02-01T03:01:30.767Z,1643684490.767 [profile_station:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2022-02-01T03:01:30.767Z,1643684490.767 [profile_station:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2022-02-01T03:01:30.784Z,1643684490.784 [profile_station:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2022-02-01T03:01:30.784Z,1643684490.784 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2022-02-01T03:01:30.784Z,1643684490.784 [profile_station:I.Pitch] Running Loop=1 2022-02-01T03:01:30.784Z,1643684490.784 [profile_station:I.Pitch](DEBUG): Initialize. 2022-02-01T03:01:30.784Z,1643684490.784 [profile_station:J.Buoyancy] Running Loop=1 2022-02-01T03:01:30.784Z,1643684490.784 [profile_station:J.Buoyancy](DEBUG): Initialize Buoyancy Component. 2022-02-01T03:01:30.784Z,1643684490.784 [profile_station:K.SetSpeed] Running Loop=1 2022-02-01T03:01:30.784Z,1643684490.784 [profile_station:K.SetSpeed](DEBUG): Initialize. 2022-02-01T03:01:30.785Z,1643684490.785 [profile_station:L.DepthEnvelope] Running Loop=1 2022-02-01T03:01:30.785Z,1643684490.785 [profile_station:L.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2022-02-01T03:01:30.785Z,1643684490.785 [profile_station:M.AltitudeEnvelope] Running Loop=1 2022-02-01T03:01:30.785Z,1643684490.785 [profile_station:M.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2022-02-01T03:01:30.785Z,1643684490.785 [profile_station:N.YoYo] Running Loop=1 2022-02-01T03:01:30.785Z,1643684490.785 [profile_station:N.YoYo](DEBUG): Initialize YoYoComponent. 2022-02-01T03:01:30.785Z,1643684490.785 [profile_station:C] Running Loop=1 2022-02-01T03:01:30.786Z,1643684490.786 [profile_station:N.YoYo] Running Loop=1 2022-02-01T03:01:30.786Z,1643684490.786 [profile_station:M.AltitudeEnvelope] Running Loop=1 2022-02-01T03:01:30.787Z,1643684490.787 [profile_station:L.DepthEnvelope] Running Loop=1 2022-02-01T03:01:30.787Z,1643684490.787 [profile_station:K.SetSpeed] Running Loop=1 2022-02-01T03:01:30.792Z,1643684490.792 [profile_station:J.Buoyancy] Running Loop=1 2022-02-01T03:01:30.792Z,1643684490.792 [profile_station:I.Pitch] Running Loop=1 2022-02-01T03:01:30.792Z,1643684490.792 [profile_station:StandardEnvelopes] Running Loop=1 2022-02-01T03:01:30.792Z,1643684490.792 [profile_station:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2022-02-01T03:01:30.793Z,1643684490.793 [profile_station:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2022-02-01T03:01:30.793Z,1643684490.793 [profile_station:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2022-02-01T03:01:30.794Z,1643684490.794 [profile_station:C] Stopped 2022-02-01T03:01:30.794Z,1643684490.794 [profile_station:E] Running Loop=1 2022-02-01T03:01:30.794Z,1643684490.794 [profile_station:Science] Running Loop=1 2022-02-01T03:01:30.795Z,1643684490.795 [profile_station:Science:HighestSaltPeakReport] Stopped 2022-02-01T03:01:30.795Z,1643684490.795 [profile_station:Science:HighestSaltPeakReport](DEBUG): Aggregate::uninitialize profile_station:Science:HighestSaltPeakReport 2022-02-01T03:01:30.795Z,1643684490.795 [profile_station:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Stopped 2022-02-01T03:01:30.795Z,1643684490.795 [profile_station:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2022-02-01T03:01:30.795Z,1643684490.795 [profile_station:Science:PeakDetectSalinity] Stopped 2022-02-01T03:01:30.795Z,1643684490.795 [profile_station:Science:PeakDetectSalinity](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectSalinity 2022-02-01T03:01:30.795Z,1643684490.795 [profile_station:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Stopped 2022-02-01T03:01:30.795Z,1643684490.795 [profile_station:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2022-02-01T03:01:30.804Z,1643684490.804 [profile_station:Science:PeakDetectFDOM] Stopped 2022-02-01T03:01:30.804Z,1643684490.804 [profile_station:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectFDOM 2022-02-01T03:01:30.804Z,1643684490.804 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped 2022-02-01T03:01:30.804Z,1643684490.804 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2022-02-01T03:01:30.804Z,1643684490.804 [profile_station:Science:HighestOilPeakReport] Stopped 2022-02-01T03:01:30.804Z,1643684490.804 [profile_station:Science:HighestOilPeakReport](DEBUG): Aggregate::uninitialize profile_station:Science:HighestOilPeakReport 2022-02-01T03:01:30.804Z,1643684490.804 [profile_station:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Stopped 2022-02-01T03:01:30.804Z,1643684490.804 [profile_station:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2022-02-01T03:01:30.805Z,1643684490.805 [profile_station:Science:PeakDetectOil] Stopped 2022-02-01T03:01:30.805Z,1643684490.805 [profile_station:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectOil 2022-02-01T03:01:30.805Z,1643684490.805 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped 2022-02-01T03:01:30.805Z,1643684490.805 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2022-02-01T03:01:30.805Z,1643684490.805 [profile_station:Science:PeakDetectNO3] Stopped 2022-02-01T03:01:30.805Z,1643684490.805 [profile_station:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectNO3 2022-02-01T03:01:30.805Z,1643684490.805 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped 2022-02-01T03:01:30.805Z,1643684490.805 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2022-02-01T03:01:30.805Z,1643684490.805 [profile_station:Science:HighestChlPeakReport] Stopped 2022-02-01T03:01:30.805Z,1643684490.805 [profile_station:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize profile_station:Science:HighestChlPeakReport 2022-02-01T03:01:30.805Z,1643684490.805 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped 2022-02-01T03:01:30.805Z,1643684490.805 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2022-02-01T03:01:30.805Z,1643684490.805 [profile_station:Science:PeakDetectChl] Running Loop=1 2022-02-01T03:01:30.806Z,1643684490.806 [profile_station:Science:OceanCurrent:A.] Running Loop=1 2022-02-01T03:01:30.807Z,1643684490.807 [profile_station:Science:Read_Oil] Running Loop=1 2022-02-01T03:01:30.807Z,1643684490.807 [profile_station:Science:Read_Oil](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_petroleum_hydrocarbons_in_sea_water 2022-02-01T03:01:30.810Z,1643684490.810 [profile_station:Science:F] Stopped 2022-02-01T03:01:30.810Z,1643684490.810 [profile_station:Science:E] Running Loop=1 2022-02-01T03:01:30.811Z,1643684490.811 [profile_station:Science:E](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water 2022-02-01T03:01:30.811Z,1643684490.811 [profile_station:Science:D] Stopped 2022-02-01T03:01:30.811Z,1643684490.811 [profile_station:Science:C] Running Loop=1 2022-02-01T03:01:30.811Z,1643684490.811 [profile_station:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature 2022-02-01T03:01:30.820Z,1643684490.820 [profile_station:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity 2022-02-01T03:01:30.821Z,1643684490.821 [profile_station:Science:B] Running Loop=1 2022-02-01T03:01:30.821Z,1643684490.821 [profile_station:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature 2022-02-01T03:01:30.822Z,1643684490.822 [profile_station:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity 2022-02-01T03:01:30.822Z,1643684490.822 [profile_station:Science:A] Stopped 2022-02-01T03:01:31.096Z,1643684491.096 [WetLabsSeaOWL_UV_A](INFO): Powering up 2022-02-01T03:01:31.112Z,1643684491.112 [profile_station:E] Stopped 2022-02-01T03:01:31.113Z,1643684491.113 [profile_station:F] Running Loop=1 2022-02-01T03:01:31.520Z,1643684491.520 [profile_station:F] Stopped 2022-02-01T03:01:31.520Z,1643684491.520 [profile_station:G] Running Loop=1 2022-02-01T03:01:31.942Z,1643684491.942 [profile_station:G] Stopped 2022-02-01T03:01:31.942Z,1643684491.942 [profile_station:StartingMission] Running Loop=1 2022-02-01T03:01:31.942Z,1643684491.942 [profile_station:StartingMission](DEBUG): Aggregate::initialize profile_station:StartingMission 2022-02-01T03:01:32.323Z,1643684492.323 [profile_station:NeedComms] Running Loop=1 2022-02-01T03:01:32.323Z,1643684492.323 [profile_station:NeedComms](DEBUG): Aggregate::initialize profile_station:NeedComms 2022-02-01T03:01:32.323Z,1643684492.323 [profile_station:NeedComms:B.GoToSurface] Running Loop=1 2022-02-01T03:01:32.323Z,1643684492.323 [profile_station:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2022-02-01T03:01:32.323Z,1643684492.323 [profile_station:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2022-02-01T03:01:32.352Z,1643684492.352 [profile_station:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2022-02-01T03:01:32.352Z,1643684492.352 [profile_station:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2022-02-01T03:01:32.353Z,1643684492.353 [profile_station:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2022-02-01T03:01:32.353Z,1643684492.353 [profile_station:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2022-02-01T03:01:32.353Z,1643684492.353 [profile_station:NeedComms:A] Running Loop=1 2022-02-01T03:01:32.354Z,1643684492.354 [profile_station:NeedComms:A](INFO): last time_fix was: 1643684394.000000 second since 1970/01/01T00:00:00Z 2022-02-01T03:01:32.355Z,1643684492.355 [profile_station:NeedComms:A] Stopped 2022-02-01T03:01:32.725Z,1643684492.725 [profile_station:NeedComms:C] Running Loop=1 2022-02-01T03:01:33.135Z,1643684493.135 [profile_station:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix 2022-02-01T03:01:34.713Z,1643684494.713 [CTD_Seabird](ERROR): Failed to parse device response: 2022-02-01T03:01:34.742Z,1643684494.742 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,030137.00,A,4132.52141,N,07043.89712,W,0.661,225.13,010222,,,D*78 2022-02-01T03:01:34.744Z,1643684494.744 [NAL9602](INFO): GPS fix at 20220201T030137: (41.542023, -70.731619) 2022-02-01T03:01:34.759Z,1643684494.759 [profile_station:NeedComms:C] Stopped 2022-02-01T03:01:34.759Z,1643684494.759 [profile_station:NeedComms:D] Running Loop=1 2022-02-01T03:01:35.173Z,1643684495.173 [profile_station:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications 2022-02-01T03:01:36.975Z,1643684496.975 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220201T025642/Courier0006.lzma 2022-02-01T03:01:37.974Z,1643684497.974 [DataOverHttps](INFO): Moved sent file to Logs/20220201T025642/Courier0006.lzma.bak 2022-02-01T03:01:37.974Z,1643684497.974 [DataOverHttps](INFO): SBD MOMSN=1383308 2022-02-01T03:01:47.011Z,1643684507.011 [DataOverHttps](INFO): Sending 51 bytes from file Logs/20220201T025642/Courier0009.lzma 2022-02-01T03:01:48.014Z,1643684508.014 [DataOverHttps](INFO): Moved sent file to Logs/20220201T025642/Courier0009.lzma.bak 2022-02-01T03:01:48.014Z,1643684508.014 [DataOverHttps](INFO): SBD MOMSN=1383311 2022-02-01T03:01:57.053Z,1643684517.053 [DataOverHttps](INFO): Sending 725 bytes from file Logs/20220201T025642/Express0007.lzma 2022-02-01T03:01:58.053Z,1643684518.053 [DataOverHttps](INFO): Moved sent file to Logs/20220201T025642/Express0007.lzma.bak 2022-02-01T03:01:58.054Z,1643684518.054 [DataOverHttps](INFO): SBD MOMSN=1383315 2022-02-01T03:02:07.092Z,1643684527.092 [DataOverHttps](INFO): Sending 60 bytes from file Logs/20220201T025642/Express0010.lzma 2022-02-01T03:02:07.457Z,1643684527.457 [NAL9602](INFO): Not Powering down - fast GPS 2022-02-01T03:02:08.093Z,1643684528.093 [DataOverHttps](INFO): Moved sent file to Logs/20220201T025642/Express0010.lzma.bak 2022-02-01T03:02:08.094Z,1643684528.094 [DataOverHttps](INFO): SBD MOMSN=1383347 2022-02-01T03:02:09.937Z,1643684529.937 [profile_station:NeedComms:D] Stopped 2022-02-01T03:02:09.938Z,1643684529.938 [profile_station:NeedComms:E] Running Loop=1 2022-02-01T03:02:10.309Z,1643684530.309 [profile_station:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix 2022-02-01T03:02:11.911Z,1643684531.911 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,030214.00,A,4132.51663,N,07043.90415,W,0.603,222.28,010222,,,D*79 2022-02-01T03:02:11.915Z,1643684531.915 [NAL9602](INFO): GPS fix at 20220201T030214: (41.541944, -70.731736) 2022-02-01T03:02:11.983Z,1643684531.983 [profile_station:NeedComms:E] Stopped 2022-02-01T03:02:11.988Z,1643684531.988 [profile_station:NeedComms](INFO): Completed profile_station:NeedComms 2022-02-01T03:02:11.988Z,1643684531.988 [profile_station:NeedComms] Stopped 2022-02-01T03:02:11.988Z,1643684531.988 [profile_station:NeedComms](DEBUG): Aggregate::uninitialize profile_station:NeedComms 2022-02-01T03:02:11.988Z,1643684531.988 [profile_station:NeedComms:B.GoToSurface] Stopped 2022-02-01T03:02:11.989Z,1643684531.989 [profile_station:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2022-02-01T03:02:12.330Z,1643684532.330 [profile_station:StartingMission](INFO): Completed profile_station:StartingMission 2022-02-01T03:02:12.330Z,1643684532.330 [profile_station:StartingMission] Stopped 2022-02-01T03:02:12.330Z,1643684532.330 [profile_station:StartingMission](DEBUG): Aggregate::uninitialize profile_station:StartingMission 2022-02-01T03:02:12.331Z,1643684532.331 [profile_station:CircleWrapper] Running Loop=1 2022-02-01T03:02:12.331Z,1643684532.331 [profile_station:CircleWrapper](DEBUG): Aggregate::initialize profile_station:CircleWrapper 2022-02-01T03:02:12.331Z,1643684532.331 [profile_station:CircleWrapper:A] Running Loop=1 2022-02-01T03:02:12.331Z,1643684532.331 [profile_station:CircleWrapper:B.Circle] Running Loop=1 2022-02-01T03:02:12.331Z,1643684532.331 [profile_station:CircleWrapper:B.Circle](DEBUG): Initialize CircleComponent. 2022-02-01T03:02:12.723Z,1643684532.723 [profile_station:CircleWrapper:A] Running Loop=1 2022-02-01T03:02:29.687Z,1643684549.687 [NAL9602](INFO): SBD MO Status=1, MOMSN=35560, MT Status=0, MTMSN=0 2022-02-01T03:02:29.687Z,1643684549.687 [NAL9602](INFO): No messages in MT queue 2022-02-01T03:03:00.421Z,1643684580.421 [NAL9602](INFO): Not Powering down - fast GPS 2022-02-01T03:10:37.369Z,1643685037.369 [CommandExec](IMPORTANT): got command stop 2022-02-01T03:10:37.369Z,1643685037.369 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop 2022-02-01T03:10:37.373Z,1643685037.373 [MissionManager](INFO): MissionManager is completed. 2022-02-01T03:10:37.373Z,1643685037.373 [MissionManager](INFO): Uninitializing Mission profile_station 2022-02-01T03:10:37.373Z,1643685037.373 [profile_station] Stopped 2022-02-01T03:10:37.373Z,1643685037.373 [profile_station](DEBUG): Aggregate::uninitialize profile_station 2022-02-01T03:10:37.373Z,1643685037.373 [profile_station:Science] Stopped 2022-02-01T03:10:37.373Z,1643685037.373 [profile_station:Science](DEBUG): Aggregate::uninitialize profile_station:Science 2022-02-01T03:10:37.373Z,1643685037.373 [profile_station:Science:B] Stopped 2022-02-01T03:10:37.373Z,1643685037.373 [profile_station:Science:C] Stopped 2022-02-01T03:10:37.373Z,1643685037.373 [profile_station:Science:E] Stopped 2022-02-01T03:10:37.373Z,1643685037.373 [profile_station:Science:Read_Oil] Stopped 2022-02-01T03:10:37.373Z,1643685037.373 [profile_station:Science:OceanCurrent] Stopped 2022-02-01T03:10:37.374Z,1643685037.374 [profile_station:Science:OceanCurrent](DEBUG): Aggregate::uninitialize profile_station:Science:OceanCurrent 2022-02-01T03:10:37.374Z,1643685037.374 [profile_station:Science:OceanCurrent:A.] Stopped 2022-02-01T03:10:37.374Z,1643685037.374 [profile_station:Science:PeakDetectChl] Stopped 2022-02-01T03:10:37.374Z,1643685037.374 [profile_station:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectChl 2022-02-01T03:10:37.374Z,1643685037.374 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped 2022-02-01T03:10:37.374Z,1643685037.374 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2022-02-01T03:10:37.374Z,1643685037.374 [profile_station:StandardEnvelopes] Stopped 2022-02-01T03:10:37.374Z,1643685037.374 [profile_station:StandardEnvelopes](DEBUG): Aggregate::uninitialize profile_station:StandardEnvelopes 2022-02-01T03:10:37.374Z,1643685037.374 [profile_station:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2022-02-01T03:10:37.374Z,1643685037.374 [profile_station:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2022-02-01T03:10:37.374Z,1643685037.374 [profile_station:StandardEnvelopes:B.DepthEnvelope] Stopped 2022-02-01T03:10:37.374Z,1643685037.374 [profile_station:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2022-02-01T03:10:37.374Z,1643685037.374 [profile_station:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2022-02-01T03:10:37.375Z,1643685037.375 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2022-02-01T03:10:37.375Z,1643685037.375 [profile_station:I.Pitch] Stopped 2022-02-01T03:10:37.375Z,1643685037.375 [profile_station:J.Buoyancy] Stopped 2022-02-01T03:10:37.375Z,1643685037.375 [profile_station:J.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2022-02-01T03:10:37.375Z,1643685037.375 [profile_station:K.SetSpeed] Stopped 2022-02-01T03:10:37.375Z,1643685037.375 [profile_station:K.SetSpeed](DEBUG): Uninitialize. 2022-02-01T03:10:37.375Z,1643685037.375 [profile_station:L.DepthEnvelope] Stopped 2022-02-01T03:10:37.375Z,1643685037.375 [profile_station:L.DepthEnvelope](DEBUG): Uninitialize. 2022-02-01T03:10:37.375Z,1643685037.375 [profile_station:M.AltitudeEnvelope] Stopped 2022-02-01T03:10:37.375Z,1643685037.375 [profile_station:M.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2022-02-01T03:10:37.375Z,1643685037.375 [profile_station:N.YoYo] Stopped 2022-02-01T03:10:37.375Z,1643685037.375 [profile_station:N.YoYo](DEBUG): Uninitialize YoYoComponent. 2022-02-01T03:10:37.375Z,1643685037.375 [profile_station:CircleWrapper] Stopped 2022-02-01T03:10:37.375Z,1643685037.375 [profile_station:CircleWrapper](DEBUG): Aggregate::uninitialize profile_station:CircleWrapper 2022-02-01T03:10:37.376Z,1643685037.376 [profile_station:CircleWrapper:A] Stopped 2022-02-01T03:10:37.376Z,1643685037.376 [profile_station:CircleWrapper:B.Circle] Stopped 2022-02-01T03:10:37.376Z,1643685037.376 [profile_station:CircleWrapper:B.Circle](DEBUG): Uninitialize. 2022-02-01T03:10:37.644Z,1643685037.644 [WetLabsSeaOWL_UV_A](INFO): Powering down 2022-02-01T03:10:37.806Z,1643685037.806 [MissionManager](IMPORTANT): Started mission Default 2022-02-01T03:10:37.806Z,1643685037.806 [Default] Running Loop=1 2022-02-01T03:10:37.806Z,1643685037.806 [Default](DEBUG): Aggregate::initialize Default 2022-02-01T03:10:37.806Z,1643685037.806 [Default:B.GoToSurface] Running Loop=1 2022-02-01T03:10:37.806Z,1643685037.806 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2022-02-01T03:10:37.806Z,1643685037.806 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2022-02-01T03:10:37.807Z,1643685037.807 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2022-02-01T03:10:37.807Z,1643685037.807 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2022-02-01T03:10:37.807Z,1643685037.807 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2022-02-01T03:10:37.812Z,1643685037.812 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2022-02-01T03:10:37.812Z,1643685037.812 [Default:A.Wait] Running Loop=1 2022-02-01T03:10:37.812Z,1643685037.812 [Default:A.Wait](DEBUG): Initialize Wait Component. 2022-02-01T03:10:51.109Z,1643685051.109 [Default:A.Wait](INFO): Done Waiting. 2022-02-01T03:10:51.109Z,1643685051.109 [Default:A.Wait] Stopped 2022-02-01T03:10:51.109Z,1643685051.109 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2022-02-01T03:10:51.517Z,1643685051.517 [Default:CheckIn] Running Loop=1 2022-02-01T03:10:51.517Z,1643685051.517 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-02-01T03:10:51.517Z,1643685051.517 [Default:CheckIn:Read_GPS] Running Loop=1 2022-02-01T03:10:53.514Z,1643685053.514 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,031055.00,A,4132.59985,N,07043.89659,W,0.058,21.89,010222,,,D*40 2022-02-01T03:10:53.517Z,1643685053.517 [NAL9602](INFO): GPS fix at 20220201T031055: (41.543331, -70.731610) 2022-02-01T03:10:53.541Z,1643685053.541 [Default:CheckIn:Read_GPS] Stopped 2022-02-01T03:10:53.541Z,1643685053.541 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-02-01T03:10:57.336Z,1643685057.336 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220201T025642/Courier0012.lzma 2022-02-01T03:10:58.338Z,1643685058.338 [DataOverHttps](INFO): Moved sent file to Logs/20220201T025642/Courier0012.lzma.bak 2022-02-01T03:10:58.338Z,1643685058.338 [DataOverHttps](INFO): SBD MOMSN=1383538 2022-02-01T03:11:07.376Z,1643685067.376 [DataOverHttps](INFO): Sending 331 bytes from file Logs/20220201T025642/Express0013.lzma 2022-02-01T03:11:08.058Z,1643685068.058 [NAL9602](INFO): SBD MO Status=1, MOMSN=35561, MT Status=0, MTMSN=0 2022-02-01T03:11:08.058Z,1643685068.058 [NAL9602](INFO): No messages in MT queue 2022-02-01T03:11:08.378Z,1643685068.378 [DataOverHttps](INFO): Moved sent file to Logs/20220201T025642/Express0013.lzma.bak 2022-02-01T03:11:08.378Z,1643685068.378 [DataOverHttps](INFO): SBD MOMSN=1383542 2022-02-01T03:11:10.092Z,1643685070.092 [Default:CheckIn:Read_Iridium] Stopped 2022-02-01T03:11:10.092Z,1643685070.092 [Default:CheckIn:C.Wait] Running Loop=1 2022-02-01T03:11:10.092Z,1643685070.092 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-02-01T03:11:38.755Z,1643685098.755 [NAL9602](INFO): Not Powering down - fast GPS 2022-02-01T03:15:20.430Z,1643685320.430 [DataOverHttps](IMPORTANT): SBD MTMSN=20220201T031522 2022-02-01T03:15:24.448Z,1643685324.448 [DataOverHttps](INFO): Received command: sched asap "load Transport/keepstation.xml;set keepstation.MissionTimeout 600 min;set keepstation.NeedCommsTime 120 min;set keepstation.Latitude 41.543326 degree;set keepstation.Longitude -70.727412 degree;set keepstation.Depth 4 m" 18p1k 1 2 2022-02-01T03:15:24.464Z,1643685324.464 [CommandExec](IMPORTANT): got command schedule asap "load Transport/keepstation.xml;set keepstation.MissionTimeout 600 min;set keepstation.NeedCommsTime 120 min;set keepstation.Latitude 41.543326 degree;set keepstation.Longitude -70.727412 degree;set keepstation.Depth 4 m" 18p1k 1 2.000000 2022-02-01T03:15:24.465Z,1643685324.465 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=18p1k 2022-02-01T03:15:24.466Z,1643685324.466 [CommandExec](IMPORTANT): Scheduled #10 (#1 of 2 with id='18p1k'): "load Transport/keepstation.xml;set keepstation.MissionTimeout 600 min;set keepstation.NeedCommsTime 120 min;set keepstation.Latitude 41.543326 degree;set keepstation.Longitude -70.727412 degree;set keepstation.Depth 4 m", ASAP 2022-02-01T03:15:25.450Z,1643685325.450 [DataOverHttps](IMPORTANT): SBD MTMSN=20220201T031527 2022-02-01T03:15:29.468Z,1643685329.468 [DataOverHttps](INFO): Received command: sched asap "set keepstation.ApproachDepth 4 m;set keepstation.DepthDeadband 1 m;set keepstation.MaxDepth 7 m;run" 18p1k 2 2 2022-02-01T03:15:29.516Z,1643685329.516 [CommandExec](IMPORTANT): got command schedule asap "set keepstation.ApproachDepth 4 m;set keepstation.DepthDeadband 1 m;set keepstation.MaxDepth 7 m;run" 18p1k 2 2.000000 2022-02-01T03:15:29.516Z,1643685329.516 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=18p1k 2022-02-01T03:15:29.517Z,1643685329.517 [CommandExec](IMPORTANT): Scheduled #11 (#2 of 2 with id='18p1k'): "set keepstation.ApproachDepth 4 m;set keepstation.DepthDeadband 1 m;set keepstation.MaxDepth 7 m;run", ASAP 2022-02-01T03:15:29.627Z,1643685329.627 [CommandExec](IMPORTANT): got command load ./Missions/Transport/keepstation.xml 2022-02-01T03:15:29.628Z,1643685329.628 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/keepstation.xml 2022-02-01T03:15:29.725Z,1643685329.725 [MissionManager](INFO): DefineArg keepstation.MissionTimeout = 4.000000 h 2022-02-01T03:15:29.728Z,1643685329.728 [MissionManager](INFO): DefineArg keepstation.NeedCommsTime = 45.000000 min 2022-02-01T03:15:29.731Z,1643685329.731 [MissionManager](INFO): DefineArg keepstation.Latitude = nan arcdeg 2022-02-01T03:15:29.734Z,1643685329.734 [MissionManager](INFO): DefineArg keepstation.Longitude = nan arcdeg 2022-02-01T03:15:29.738Z,1643685329.738 [MissionManager](INFO): DefineArg keepstation.Depth = 30.000000 m 2022-02-01T03:15:29.742Z,1643685329.742 [MissionManager](INFO): DefineArg keepstation.ApproachDepth = 15.000000 m 2022-02-01T03:15:29.745Z,1643685329.745 [MissionManager](INFO): DefineArg keepstation.DepthDeadband = 4.000000 m 2022-02-01T03:15:29.749Z,1643685329.749 [MissionManager](INFO): DefineArg keepstation.Speed = 0.750000 m/s 2022-02-01T03:15:29.752Z,1643685329.752 [MissionManager](INFO): DefineArg keepstation.Radius = 200.000000 m 2022-02-01T03:15:29.755Z,1643685329.755 [MissionManager](INFO): DefineArg keepstation.MaxDepth = 45.000000 m 2022-02-01T03:15:29.759Z,1643685329.759 [MissionManager](INFO): DefineArg keepstation.MinOffshore = 2.000000 km 2022-02-01T03:15:29.759Z,1643685329.759 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.xml 2022-02-01T03:15:29.961Z,1643685329.961 [MissionManager](INFO): DefineArg keepstation:NeedComms.DiveInterval = 3.000000 h 2022-02-01T03:15:29.964Z,1643685329.964 [MissionManager](INFO): DefineArg keepstation:NeedComms.WaitForPitchUp = 10.000000 min 2022-02-01T03:15:29.992Z,1643685329.992 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacePitch = 20.000000 arcdeg 2022-02-01T03:15:29.995Z,1643685329.995 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceDepthRate = nan m/s 2022-02-01T03:15:30.019Z,1643685330.019 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceSpeed = 1.000000 m/s 2022-02-01T03:15:30.023Z,1643685330.023 [MissionManager](INFO): DefineArg keepstation:NeedComms.GPSTimeout = 7.000000 min 2022-02-01T03:15:30.026Z,1643685330.026 [MissionManager](INFO): DefineArg keepstation:NeedComms.CommsTimeout = 30.000000 min 2022-02-01T03:15:30.049Z,1643685330.049 [keepstation:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface. 2022-02-01T03:15:30.095Z,1643685330.095 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.xml 2022-02-01T03:15:30.138Z,1643685330.138 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinAltitude = 5.000000 m 2022-02-01T03:15:30.146Z,1643685330.146 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepth = 200.000000 m 2022-02-01T03:15:30.159Z,1643685330.159 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinOffshore = 2000.000000 m 2022-02-01T03:15:30.181Z,1643685330.181 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2022-02-01T03:15:30.229Z,1643685330.229 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2022-02-01T03:15:30.300Z,1643685330.300 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2022-02-01T03:15:30.329Z,1643685330.329 [keepstation:F.Pitch](DEBUG): Construct. 2022-02-01T03:15:30.357Z,1643685330.357 [keepstation:TransitToStation:A.Buoyancy](DEBUG): Construct Buoyancy. 2022-02-01T03:15:30.365Z,1643685330.365 [keepstation:TransitToStation:B.Pitch](DEBUG): Construct. 2022-02-01T03:15:30.380Z,1643685330.380 [keepstation:TransitToStation:C.SetSpeed](DEBUG): Construct. 2022-02-01T03:15:30.383Z,1643685330.383 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Construct Waypoint. 2022-02-01T03:15:30.407Z,1643685330.407 [keepstation:KeepStation:B.Pitch](DEBUG): Construct. 2022-02-01T03:15:30.421Z,1643685330.421 [keepstation:KeepStation:C.KeepStation](DEBUG): Construct KeepStation. 2022-02-01T03:15:30.436Z,1643685330.436 [keepstation:KeepStation:D.Wait](DEBUG): Construct Wait. 2022-02-01T03:15:30.463Z,1643685330.463 [MissionManager](DEBUG): Vehicle transits to desired waypoint (or stays put if none is commanded) and stays within the specified radius. Maximum duration of mission 4 How often to surface for communications 45 Latitude of waypoint to hold. If NaN, hold the latitude at start of mission. NaN Longitude of waypoint to hold. If NaN, hold the longitude at start of mission. NaN Depth held during drift mode 30 Depth for initial approach to station. 15 How much vertical drift from the specified depth is allowed in drift mode 4 Vehicle transit speed. 0.75 Radius of circle around waypoint to hold. 200 Maximum allowable depth during the mission. 45 Minimum distance from the shoreline to maintain 2 Need a separate aggregate for transit if we want to specify a different depth for the approach. Due to the way the KeepStation behavior is currently implemented, we must run it in parallel and use a Wait to keep from completing the mission before the timeout expires. However, this new version of the mission uses a top-level timeout so that the entire mission will not run for longer then MissionTimeout. 2022-02-01T03:15:30.473Z,1643685330.473 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/keepstation.xml 2022-02-01T03:15:40.466Z,1643685340.466 [CommandExec](IMPORTANT): got command set keepstation.MissionTimeout 600 minute 2022-02-01T03:15:40.466Z,1643685340.466 [CommandExec](IMPORTANT): got command set keepstation.NeedCommsTime 120 minute 2022-02-01T03:15:40.467Z,1643685340.467 [CommandExec](IMPORTANT): got command set keepstation.Latitude 41.543326 degree 2022-02-01T03:15:40.468Z,1643685340.468 [CommandExec](IMPORTANT): got command set keepstation.Longitude -70.727412 degree 2022-02-01T03:15:40.469Z,1643685340.469 [CommandExec](IMPORTANT): got command set keepstation.Depth 4 meter 2022-02-01T03:15:46.689Z,1643685346.689 [CommandExec](IMPORTANT): got command set keepstation.ApproachDepth 4 meter 2022-02-01T03:15:46.690Z,1643685346.690 [CommandExec](IMPORTANT): got command set keepstation.DepthDeadband 1 meter 2022-02-01T03:15:46.691Z,1643685346.691 [CommandExec](IMPORTANT): got command set keepstation.MaxDepth 7 meter 2022-02-01T03:15:46.691Z,1643685346.691 [CommandExec](IMPORTANT): got command run 2022-02-01T03:15:46.744Z,1643685346.744 [CommandExec](IMPORTANT): Running 2022-02-01T03:15:46.844Z,1643685346.844 [Default] Stopped 2022-02-01T03:15:46.844Z,1643685346.844 [Default](DEBUG): Aggregate::uninitialize Default 2022-02-01T03:15:46.845Z,1643685346.845 [Default:B.GoToSurface] Stopped 2022-02-01T03:15:46.845Z,1643685346.845 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2022-02-01T03:15:46.845Z,1643685346.845 [Default:CheckIn] Stopped 2022-02-01T03:15:46.845Z,1643685346.845 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-02-01T03:15:46.845Z,1643685346.845 [Default:CheckIn:C.Wait] Stopped 2022-02-01T03:15:46.845Z,1643685346.845 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-02-01T03:15:46.845Z,1643685346.845 [MissionManager](IMPORTANT): Started mission keepstation 2022-02-01T03:15:46.846Z,1643685346.846 [keepstation] Running Loop=1 2022-02-01T03:15:46.846Z,1643685346.846 [keepstation](DEBUG): Aggregate::initialize keepstation 2022-02-01T03:15:46.846Z,1643685346.846 [keepstation:StandardEnvelopes] Running Loop=1 2022-02-01T03:15:46.846Z,1643685346.846 [keepstation:StandardEnvelopes](DEBUG): Aggregate::initialize keepstation:StandardEnvelopes 2022-02-01T03:15:46.846Z,1643685346.846 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2022-02-01T03:15:46.846Z,1643685346.846 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2022-02-01T03:15:46.846Z,1643685346.846 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2022-02-01T03:15:46.846Z,1643685346.846 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2022-02-01T03:15:46.846Z,1643685346.846 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2022-02-01T03:15:46.847Z,1643685346.847 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2022-02-01T03:15:46.847Z,1643685346.847 [keepstation:F.Pitch] Running Loop=1 2022-02-01T03:15:46.847Z,1643685346.847 [keepstation:F.Pitch](DEBUG): Initialize. 2022-02-01T03:15:46.847Z,1643685346.847 [keepstation:B] Running Loop=1 2022-02-01T03:15:46.847Z,1643685346.847 [keepstation:F.Pitch] Running Loop=1 2022-02-01T03:15:46.864Z,1643685346.864 [keepstation:StandardEnvelopes] Running Loop=1 2022-02-01T03:15:46.864Z,1643685346.864 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2022-02-01T03:15:46.864Z,1643685346.864 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2022-02-01T03:15:46.865Z,1643685346.865 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2022-02-01T03:15:46.865Z,1643685346.865 [keepstation:B] Stopped 2022-02-01T03:15:46.865Z,1643685346.865 [keepstation:D] Running Loop=1 2022-02-01T03:15:47.243Z,1643685347.243 [keepstation:D] Stopped 2022-02-01T03:15:47.243Z,1643685347.243 [keepstation:E] Running Loop=1 2022-02-01T03:15:47.692Z,1643685347.692 [keepstation:E] Stopped 2022-02-01T03:15:47.692Z,1643685347.692 [keepstation:StartingMission] Running Loop=1 2022-02-01T03:15:47.692Z,1643685347.692 [keepstation:StartingMission](DEBUG): Aggregate::initialize keepstation:StartingMission 2022-02-01T03:15:48.049Z,1643685348.049 [keepstation:NeedComms] Running Loop=1 2022-02-01T03:15:48.049Z,1643685348.049 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2022-02-01T03:15:48.049Z,1643685348.049 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2022-02-01T03:15:48.049Z,1643685348.049 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2022-02-01T03:15:48.049Z,1643685348.049 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2022-02-01T03:15:48.050Z,1643685348.050 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2022-02-01T03:15:48.050Z,1643685348.050 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2022-02-01T03:15:48.050Z,1643685348.050 [keepstation:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2022-02-01T03:15:48.051Z,1643685348.051 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2022-02-01T03:15:48.051Z,1643685348.051 [keepstation:NeedComms:A] Running Loop=1 2022-02-01T03:15:48.052Z,1643685348.052 [keepstation:NeedComms:A](INFO): last time_fix was: 1643685055.000000 second since 1970/01/01T00:00:00Z 2022-02-01T03:15:48.053Z,1643685348.053 [keepstation:NeedComms:A] Stopped 2022-02-01T03:15:48.454Z,1643685348.454 [keepstation:NeedComms:C] Running Loop=1 2022-02-01T03:15:48.862Z,1643685348.862 [keepstation:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix 2022-02-01T03:15:50.465Z,1643685350.465 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,031552.00,A,4132.56778,N,07043.94128,W,0.778,228.86,010222,,,D*7D 2022-02-01T03:15:50.467Z,1643685350.467 [NAL9602](INFO): GPS fix at 20220201T031552: (41.542796, -70.732355) 2022-02-01T03:15:50.480Z,1643685350.480 [keepstation:NeedComms:C] Stopped 2022-02-01T03:15:50.481Z,1643685350.481 [keepstation:NeedComms:D] Running Loop=1 2022-02-01T03:15:50.902Z,1643685350.902 [keepstation:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications 2022-02-01T03:15:54.571Z,1643685354.571 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20220201T025642/Courier0015.lzma 2022-02-01T03:15:55.573Z,1643685355.573 [DataOverHttps](INFO): Moved sent file to Logs/20220201T025642/Courier0015.lzma.bak 2022-02-01T03:15:55.574Z,1643685355.574 [DataOverHttps](INFO): SBD MOMSN=1383716 2022-02-01T03:16:03.790Z,1643685363.790 [NAL9602](INFO): SBD MO Status=1, MOMSN=35562, MT Status=0, MTMSN=0 2022-02-01T03:16:03.791Z,1643685363.791 [NAL9602](INFO): No messages in MT queue 2022-02-01T03:16:04.611Z,1643685364.611 [DataOverHttps](INFO): Sending 51 bytes from file Logs/20220201T025642/Courier0018.lzma 2022-02-01T03:16:05.615Z,1643685365.615 [DataOverHttps](INFO): Moved sent file to Logs/20220201T025642/Courier0018.lzma.bak 2022-02-01T03:16:05.616Z,1643685365.616 [DataOverHttps](INFO): SBD MOMSN=1383719 2022-02-01T03:16:14.653Z,1643685374.653 [DataOverHttps](INFO): Sending 551 bytes from file Logs/20220201T025642/Express0016.lzma 2022-02-01T03:16:15.655Z,1643685375.655 [DataOverHttps](INFO): Moved sent file to Logs/20220201T025642/Express0016.lzma.bak 2022-02-01T03:16:15.656Z,1643685375.656 [DataOverHttps](INFO): SBD MOMSN=1383721 2022-02-01T03:16:24.691Z,1643685384.691 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20220201T025642/Express0019.lzma 2022-02-01T03:16:25.694Z,1643685385.694 [DataOverHttps](INFO): Moved sent file to Logs/20220201T025642/Express0019.lzma.bak 2022-02-01T03:16:25.694Z,1643685385.694 [DataOverHttps](INFO): SBD MOMSN=1383744 2022-02-01T03:16:27.251Z,1643685387.251 [keepstation:NeedComms:D] Stopped 2022-02-01T03:16:27.251Z,1643685387.251 [keepstation:NeedComms:E] Running Loop=1 2022-02-01T03:16:27.677Z,1643685387.677 [keepstation:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix 2022-02-01T03:16:29.241Z,1643685389.241 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,031631.00,A,4132.56254,N,07043.94711,W,0.467,225.96,010222,,,D*7D 2022-02-01T03:16:29.243Z,1643685389.243 [NAL9602](INFO): GPS fix at 20220201T031631: (41.542709, -70.732452) 2022-02-01T03:16:29.256Z,1643685389.256 [keepstation:NeedComms:E] Stopped 2022-02-01T03:16:29.257Z,1643685389.257 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2022-02-01T03:16:29.257Z,1643685389.257 [keepstation:NeedComms] Stopped 2022-02-01T03:16:29.257Z,1643685389.257 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2022-02-01T03:16:29.257Z,1643685389.257 [keepstation:NeedComms:B.GoToSurface] Stopped 2022-02-01T03:16:29.257Z,1643685389.257 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2022-02-01T03:16:29.716Z,1643685389.716 [keepstation:StartingMission](INFO): Completed keepstation:StartingMission 2022-02-01T03:16:29.716Z,1643685389.716 [keepstation:StartingMission] Stopped 2022-02-01T03:16:29.717Z,1643685389.717 [keepstation:StartingMission](DEBUG): Aggregate::uninitialize keepstation:StartingMission 2022-02-01T03:16:29.717Z,1643685389.717 [keepstation:TransitToStation] Running Loop=1 2022-02-01T03:16:29.717Z,1643685389.717 [keepstation:TransitToStation](DEBUG): Aggregate::initialize keepstation:TransitToStation 2022-02-01T03:16:29.717Z,1643685389.717 [keepstation:TransitToStation:A.Buoyancy] Running Loop=1 2022-02-01T03:16:29.717Z,1643685389.717 [keepstation:TransitToStation:A.Buoyancy](DEBUG): Initialize Buoyancy Component. 2022-02-01T03:16:29.717Z,1643685389.717 [keepstation:TransitToStation:B.Pitch] Running Loop=1 2022-02-01T03:16:29.717Z,1643685389.717 [keepstation:TransitToStation:B.Pitch](DEBUG): Initialize. 2022-02-01T03:16:29.717Z,1643685389.717 [keepstation:TransitToStation:C.SetSpeed] Running Loop=1 2022-02-01T03:16:29.717Z,1643685389.717 [keepstation:TransitToStation:C.SetSpeed](DEBUG): Initialize. 2022-02-01T03:16:29.718Z,1643685389.718 [keepstation:TransitToStation:Wpt1.Waypoint] Running Loop=1 2022-02-01T03:16:29.718Z,1643685389.718 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent. 2022-02-01T03:16:30.077Z,1643685390.077 [keepstation:TransitToStation:C.SetSpeed] Running Loop=1 2022-02-01T03:16:30.078Z,1643685390.078 [keepstation:TransitToStation:B.Pitch] Running Loop=1 2022-02-01T03:16:30.078Z,1643685390.078 [keepstation:TransitToStation:A.Buoyancy] Running Loop=1 2022-02-01T03:17:01.565Z,1643685421.565 [NAL9602](INFO): Not Powering down - fast GPS 2022-02-01T03:30:47.011Z,1643686247.011 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2022-02-01T03:31:02.713Z,1643686262.713 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2022-02-01T03:31:02.715Z,1643686262.715 [BPC1](INFO): Received data from all battery sticks. 2022-02-01T03:33:25.396Z,1643686405.396 [CBIT](IMPORTANT): Beginning ground fault scan 2022-02-01T03:33:36.291Z,1643686416.291 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.067219 CHAN A1 (24V): 0.130970 CHAN A2 (12V): 0.040002 CHAN A3 (5V): 0.009756 CHAN B0 (3.3V): 0.005878 CHAN B1 (3.15aV): 0.005340 CHAN B2 (3.15bV): 0.004966 CHAN B3 (GND): -0.008991 OPEN: 0.006103 Full Scale: +/- 1 mA 2022-02-01T03:37:17.664Z,1643686637.664 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2022-02-01T03:41:05.918Z,1643686865.918 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Reached Waypoint: 41.543327,-70.727409 2022-02-01T03:41:05.918Z,1643686865.918 [keepstation:TransitToStation:Wpt1.Waypoint] Stopped 2022-02-01T03:41:05.918Z,1643686865.918 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent. 2022-02-01T03:41:05.919Z,1643686865.919 [keepstation:TransitToStation](INFO): Completed keepstation:TransitToStation 2022-02-01T03:41:05.919Z,1643686865.919 [keepstation:TransitToStation] Stopped 2022-02-01T03:41:05.919Z,1643686865.919 [keepstation:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:TransitToStation 2022-02-01T03:41:05.919Z,1643686865.919 [keepstation:TransitToStation:A.Buoyancy] Stopped 2022-02-01T03:41:05.920Z,1643686865.920 [keepstation:TransitToStation:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2022-02-01T03:41:05.920Z,1643686865.920 [keepstation:TransitToStation:B.Pitch] Stopped 2022-02-01T03:41:05.920Z,1643686865.920 [keepstation:TransitToStation:C.SetSpeed] Stopped 2022-02-01T03:41:05.920Z,1643686865.920 [keepstation:TransitToStation:C.SetSpeed](DEBUG): Uninitialize. 2022-02-01T03:41:05.920Z,1643686865.920 [keepstation:OnStation] Running Loop=1 2022-02-01T03:41:05.920Z,1643686865.920 [keepstation:OnStation](DEBUG): Aggregate::initialize keepstation:OnStation 2022-02-01T03:41:06.305Z,1643686866.305 [keepstation:NeedComms] Running Loop=1 2022-02-01T03:41:06.305Z,1643686866.305 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2022-02-01T03:41:06.305Z,1643686866.305 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2022-02-01T03:41:06.305Z,1643686866.305 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2022-02-01T03:41:06.306Z,1643686866.306 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2022-02-01T03:41:06.306Z,1643686866.306 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2022-02-01T03:41:06.306Z,1643686866.306 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2022-02-01T03:41:06.307Z,1643686866.307 [keepstation:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2022-02-01T03:41:06.307Z,1643686866.307 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2022-02-01T03:41:06.307Z,1643686866.307 [keepstation:NeedComms:A] Running Loop=1 2022-02-01T03:41:06.333Z,1643686866.333 [keepstation:NeedComms:A](INFO): last time_fix was: 1643685391.000000 second since 1970/01/01T00:00:00Z 2022-02-01T03:41:06.333Z,1643686866.333 [keepstation:NeedComms:A] Stopped 2022-02-01T03:41:06.710Z,1643686866.710 [keepstation:NeedComms:C] Running Loop=1 2022-02-01T03:41:08.709Z,1643686868.709 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,034111.00,A,4132.60681,N,07043.64689,W,0.622,87.32,010222,,,D*4C 2022-02-01T03:41:08.711Z,1643686868.711 [NAL9602](INFO): GPS fix at 20220201T034111: (41.543447, -70.727448) 2022-02-01T03:41:08.751Z,1643686868.751 [keepstation:NeedComms:C] Stopped 2022-02-01T03:41:08.751Z,1643686868.751 [keepstation:NeedComms:D] Running Loop=1 2022-02-01T03:41:12.804Z,1643686872.804 [DataOverHttps](INFO): Sending 209 bytes from file Logs/20220201T025642/Courier0021.lzma 2022-02-01T03:41:13.805Z,1643686873.805 [DataOverHttps](INFO): Moved sent file to Logs/20220201T025642/Courier0021.lzma.bak 2022-02-01T03:41:13.806Z,1643686873.806 [DataOverHttps](INFO): SBD MOMSN=1384154 2022-02-01T03:41:22.844Z,1643686882.844 [DataOverHttps](INFO): Sending 377 bytes from file Logs/20220201T025642/Express0022.lzma 2022-02-01T03:41:23.846Z,1643686883.846 [DataOverHttps](INFO): Moved sent file to Logs/20220201T025642/Express0022.lzma.bak 2022-02-01T03:41:23.846Z,1643686883.846 [DataOverHttps](INFO): SBD MOMSN=1384157 2022-02-01T03:41:25.725Z,1643686885.725 [keepstation:NeedComms:D] Stopped 2022-02-01T03:41:25.725Z,1643686885.725 [keepstation:NeedComms:E] Running Loop=1 2022-02-01T03:41:31.332Z,1643686891.332 [NAL9602](INFO): SBD MO Status=2, MOMSN=35563, MT Status=2, MTMSN=0 2022-02-01T03:41:31.333Z,1643686891.333 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-02-01T03:41:42.645Z,1643686902.645 [NAL9602](INFO): SBD MO Status=1, MOMSN=35563, MT Status=0, MTMSN=0 2022-02-01T03:41:42.645Z,1643686902.645 [NAL9602](INFO): No messages in MT queue 2022-02-01T03:41:43.863Z,1643686903.863 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,034146.00,A,4132.60542,N,07043.64468,W,0.311,168.92,010222,,,D*70 2022-02-01T03:41:43.865Z,1643686903.865 [NAL9602](INFO): GPS fix at 20220201T034146: (41.543424, -70.727411) 2022-02-01T03:41:43.897Z,1643686903.897 [keepstation:NeedComms:E] Stopped 2022-02-01T03:41:43.898Z,1643686903.898 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2022-02-01T03:41:43.898Z,1643686903.898 [keepstation:NeedComms] Stopped 2022-02-01T03:41:43.898Z,1643686903.898 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2022-02-01T03:41:43.898Z,1643686903.898 [keepstation:NeedComms:B.GoToSurface] Stopped 2022-02-01T03:41:43.898Z,1643686903.898 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2022-02-01T03:41:44.294Z,1643686904.294 [keepstation:OnStation](INFO): Completed keepstation:OnStation 2022-02-01T03:41:44.294Z,1643686904.294 [keepstation:OnStation] Stopped 2022-02-01T03:41:44.294Z,1643686904.294 [keepstation:OnStation](DEBUG): Aggregate::uninitialize keepstation:OnStation 2022-02-01T03:41:44.294Z,1643686904.294 [keepstation:KeepStation] Running Loop=1 2022-02-01T03:41:44.294Z,1643686904.294 [keepstation:KeepStation](DEBUG): Aggregate::initialize keepstation:KeepStation 2022-02-01T03:41:44.294Z,1643686904.294 [keepstation:KeepStation:A] Running Loop=1 2022-02-01T03:41:44.294Z,1643686904.294 [keepstation:KeepStation:B.Pitch] Running Loop=1 2022-02-01T03:41:44.294Z,1643686904.294 [keepstation:KeepStation:B.Pitch](DEBUG): Initialize. 2022-02-01T03:41:44.295Z,1643686904.295 [keepstation:KeepStation:C.KeepStation] Running Loop=1 2022-02-01T03:41:44.295Z,1643686904.295 [keepstation:KeepStation:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2022-02-01T03:41:44.295Z,1643686904.295 [keepstation:KeepStation:D.Wait] Running Loop=1 2022-02-01T03:41:44.295Z,1643686904.295 [keepstation:KeepStation:D.Wait](DEBUG): Initialize Wait Component. 2022-02-01T03:41:44.697Z,1643686904.697 [keepstation:KeepStation:C.KeepStation] Running Loop=1 2022-02-01T03:41:44.697Z,1643686904.697 [keepstation:KeepStation:B.Pitch] Running Loop=1 2022-02-01T03:41:44.698Z,1643686904.698 [keepstation:KeepStation:A] Running Loop=1 2022-02-01T03:42:16.176Z,1643686936.176 [NAL9602](INFO): Not Powering down - fast GPS 2022-02-01T03:43:05.300Z,1643686985.300 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.247349 2022-02-01T03:43:12.180Z,1643686992.180 [Radio_Surface](INFO): Powering down 2022-02-01T03:43:41.460Z,1643687021.460 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-02-01T03:43:42.464Z,1643687022.464 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2022-02-01T03:54:25.806Z,1643687665.806 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2022-02-01T03:58:07.203Z,1643687887.203 [keepstation:KeepStation:C.KeepStation](INFO): Rest->Waypoint 2022-02-01T03:58:07.661Z,1643687887.661 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 0.75 m/s, pitch -15.00 deg, mass-position 6.00 mm (2 active estimators). 2022-02-01T04:02:29.444Z,1643688149.444 [BuoyancyServo](FAULT): Overload Error 2022-02-01T04:02:29.444Z,1643688149.444 [BuoyancyServo] Hardware Fault, FailCount= 1 2022-02-01T04:02:29.444Z,1643688149.444 [BuoyancyServo](ERROR): Hardware Fault 2022-02-01T04:02:29.635Z,1643688149.635 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo 2022-02-01T04:02:31.450Z,1643688151.450 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2022-02-01T04:02:31.450Z,1643688151.450 [BuoyancyServo](INFO): Powering down 2022-02-01T04:02:32.335Z,1643688152.335 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2022-02-01T04:02:32.335Z,1643688152.335 [BuoyancyServo] No Fault, FailCount= 1 2022-02-01T04:02:32.652Z,1643688152.652 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2022-02-01T04:02:32.653Z,1643688152.653 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2022-02-01T04:10:58.480Z,1643688658.480 [BuoyancyServo](FAULT): Overload Error 2022-02-01T04:10:58.480Z,1643688658.480 [BuoyancyServo] Hardware Fault, FailCount= 1 2022-02-01T04:10:58.480Z,1643688658.480 [BuoyancyServo](ERROR): Hardware Fault 2022-02-01T04:10:58.551Z,1643688658.551 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo 2022-02-01T04:11:00.481Z,1643688660.481 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2022-02-01T04:11:00.481Z,1643688660.481 [BuoyancyServo](INFO): Powering down 2022-02-01T04:11:01.335Z,1643688661.335 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2022-02-01T04:11:01.335Z,1643688661.335 [BuoyancyServo] No Fault, FailCount= 1 2022-02-01T04:11:01.701Z,1643688661.701 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2022-02-01T04:11:01.701Z,1643688661.701 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2022-02-01T04:13:26.314Z,1643688806.314 [keepstation:KeepStation:C.KeepStation](INFO): Waypoint->Rest 2022-02-01T04:17:38.016Z,1643689058.016 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2022-02-01T04:38:11.050Z,1643690291.050 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2022-02-01T04:38:25.999Z,1643690305.999 [RDI_Pathfinder](ERROR): DVL BIT error. See manual. Result code: 30 2022-02-01T04:38:26.365Z,1643690306.365 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2022-02-01T04:38:26.367Z,1643690306.367 [BPC1](INFO): Received data from all battery sticks. 2022-02-01T04:46:33.627Z,1643690793.627 [keepstation:KeepStation:C.KeepStation](INFO): Rest->Waypoint 2022-02-01T04:49:05.128Z,1643690945.128 [BuoyancyServo](FAULT): Overload Error 2022-02-01T04:49:05.128Z,1643690945.128 [BuoyancyServo] Hardware Fault, FailCount= 1 2022-02-01T04:49:05.128Z,1643690945.128 [BuoyancyServo](ERROR): Hardware Fault 2022-02-01T04:49:05.311Z,1643690945.311 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo 2022-02-01T04:49:07.102Z,1643690947.102 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2022-02-01T04:49:07.102Z,1643690947.102 [BuoyancyServo](INFO): Powering down 2022-02-01T04:49:07.971Z,1643690947.971 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2022-02-01T04:49:07.971Z,1643690947.971 [BuoyancyServo] No Fault, FailCount= 1 2022-02-01T04:49:08.332Z,1643690948.332 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2022-02-01T04:49:08.333Z,1643690948.333 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2022-02-01T04:52:23.840Z,1643691143.840 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2022-02-01T04:56:31.960Z,1643691391.960 [BuoyancyServo](FAULT): Overload Error 2022-02-01T04:56:31.960Z,1643691391.960 [BuoyancyServo] Hardware Fault, FailCount= 1 2022-02-01T04:56:31.960Z,1643691391.960 [BuoyancyServo](ERROR): Hardware Fault 2022-02-01T04:56:32.147Z,1643691392.147 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo 2022-02-01T04:56:33.958Z,1643691393.958 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2022-02-01T04:56:33.959Z,1643691393.959 [BuoyancyServo](INFO): Powering down 2022-02-01T04:56:34.820Z,1643691394.820 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2022-02-01T04:56:34.820Z,1643691394.820 [BuoyancyServo] No Fault, FailCount= 1 2022-02-01T04:56:35.158Z,1643691395.158 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2022-02-01T04:56:35.164Z,1643691395.164 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2022-02-01T04:59:43.397Z,1643691583.397 [keepstation:KeepStation:C.KeepStation](INFO): Waypoint->Rest 2022-02-01T05:32:25.622Z,1643693545.622 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2022-02-01T05:33:05.633Z,1643693585.633 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range. 2022-02-01T05:33:36.340Z,1643693616.340 [CBIT](IMPORTANT): Beginning ground fault scan 2022-02-01T05:33:47.300Z,1643693627.300 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.070388 CHAN A1 (24V): 0.134038 CHAN A2 (12V): 0.041044 CHAN A3 (5V): 0.009093 CHAN B0 (3.3V): 0.005779 CHAN B1 (3.15aV): 0.005258 CHAN B2 (3.15bV): 0.004522 CHAN B3 (GND): -0.010239 OPEN: 0.006207 Full Scale: +/- 1 mA 2022-02-01T05:41:59.743Z,1643694119.743 [keepstation:KeepStation:C.KeepStation](INFO): Rest->Waypoint 2022-02-01T05:42:28.419Z,1643694148.419 [keepstation:NeedComms] Running Loop=1 2022-02-01T05:42:28.419Z,1643694148.419 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2022-02-01T05:42:28.419Z,1643694148.419 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2022-02-01T05:42:28.419Z,1643694148.419 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2022-02-01T05:42:28.419Z,1643694148.419 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2022-02-01T05:42:28.420Z,1643694148.420 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2022-02-01T05:42:28.420Z,1643694148.420 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2022-02-01T05:42:28.421Z,1643694148.421 [keepstation:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2022-02-01T05:42:28.421Z,1643694148.421 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2022-02-01T05:42:28.421Z,1643694148.421 [keepstation:NeedComms:A] Running Loop=1 2022-02-01T05:42:28.423Z,1643694148.423 [keepstation:NeedComms:A](INFO): last time_fix was: 1643686906.000000 second since 1970/01/01T00:00:00Z 2022-02-01T05:42:28.423Z,1643694148.423 [keepstation:NeedComms:A] Stopped 2022-02-01T05:42:51.059Z,1643694171.059 [keepstation:NeedComms:C] Running Loop=1 2022-02-01T05:42:51.672Z,1643694171.672 [Radio_Surface](INFO): Powering up 2022-02-01T05:42:57.152Z,1643694177.152 [DataOverHttps](INFO): Radio surface powered ON. 2022-02-01T05:43:05.564Z,1643694185.564 [NAL9602](INFO): SBD MO Status=1, MOMSN=35564, MT Status=0, MTMSN=0 2022-02-01T05:43:05.564Z,1643694185.564 [NAL9602](INFO): No messages in MT queue 2022-02-01T05:43:06.771Z,1643694186.771 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,054309.00,A,4132.52505,N,07043.74993,W,0.311,0.00,010222,,,A*74 2022-02-01T05:43:06.773Z,1643694186.773 [NAL9602](INFO): GPS fix at 20220201T054309: (41.542084, -70.729165) 2022-02-01T05:43:06.806Z,1643694186.806 [UniversalFixResidualReporter](INFO): Fix residual: 3.7 %DT, over the last 1707.6 m. Residual distance 63.6 m at bearing -11.6 degrees. Fix at (41.5421, -70.7292) with 208.6 m made good. 2022-02-01T05:43:06.814Z,1643694186.814 [keepstation:NeedComms:C] Stopped 2022-02-01T05:43:06.815Z,1643694186.815 [keepstation:NeedComms:D] Running Loop=1 2022-02-01T05:43:23.123Z,1643694203.123 [NAL9602](INFO): SBD MO Status=1, MOMSN=35565, MT Status=0, MTMSN=0 2022-02-01T05:43:23.180Z,1643694203.180 [NAL9602](INFO): Sent 184 bytes from file Logs/20220201T025642/Courier0024.lzma 2022-02-01T05:43:23.180Z,1643694203.180 [NAL9602](INFO): Packets left to send: 1 2022-02-01T05:43:27.272Z,1643694207.272 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-02-01T05:43:32.706Z,1643694212.706 [NAL9602](INFO): SBD MO Status=1, MOMSN=35566, MT Status=0, MTMSN=0 2022-02-01T05:43:32.764Z,1643694212.764 [NAL9602](INFO): Sent 41 bytes from file Logs/20220201T025642/Courier0024.lzma 2022-02-01T05:43:32.764Z,1643694212.764 [NAL9602](INFO): Packets left to send: 0 2022-02-01T05:43:43.674Z,1643694223.674 [NAL9602](INFO): SBD MO Status=1, MOMSN=35567, MT Status=0, MTMSN=0 2022-02-01T05:43:43.728Z,1643694223.728 [NAL9602](INFO): Sent 184 bytes from file Logs/20220201T025642/Express0025.lzma 2022-02-01T05:43:43.728Z,1643694223.728 [NAL9602](INFO): Packets left to send: 3 2022-02-01T05:43:55.274Z,1643694235.274 [NAL9602](INFO): SBD MO Status=1, MOMSN=35568, MT Status=0, MTMSN=0 2022-02-01T05:43:55.324Z,1643694235.324 [NAL9602](INFO): Sent 184 bytes from file Logs/20220201T025642/Express0025.lzma 2022-02-01T05:43:55.324Z,1643694235.324 [NAL9602](INFO): Packets left to send: 2 2022-02-01T05:44:02.416Z,1643694242.416 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-02-01T05:44:22.865Z,1643694262.865 [NAL9602](INFO): SBD MO Status=1, MOMSN=35569, MT Status=0, MTMSN=0 2022-02-01T05:44:22.912Z,1643694262.912 [NAL9602](INFO): Sent 184 bytes from file Logs/20220201T025642/Express0025.lzma 2022-02-01T05:44:22.912Z,1643694262.912 [NAL9602](INFO): Packets left to send: 1 2022-02-01T05:44:37.556Z,1643694277.556 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-02-01T05:44:44.214Z,1643694284.214 [NAL9602](INFO): SBD MO Status=1, MOMSN=35570, MT Status=0, MTMSN=0 2022-02-01T05:44:44.264Z,1643694284.264 [NAL9602](INFO): Sent 42 bytes from file Logs/20220201T025642/Express0025.lzma 2022-02-01T05:44:44.264Z,1643694284.264 [NAL9602](INFO): Packets left to send: 0 2022-02-01T05:44:54.787Z,1643694294.787 [NAL9602](INFO): SBD MO Status=0, MOMSN=35571, MT Status=0, MTMSN=0 2022-02-01T05:44:54.885Z,1643694294.885 [keepstation:NeedComms:D] Stopped 2022-02-01T05:44:54.885Z,1643694294.885 [keepstation:NeedComms:E] Running Loop=1 2022-02-01T05:44:57.207Z,1643694297.207 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,054459.00,A,4132.51844,N,07043.75582,W,0.447,0.00,010222,,,D*71 2022-02-01T05:44:57.209Z,1643694297.209 [NAL9602](INFO): GPS fix at 20220201T054459: (41.541974, -70.729264) 2022-02-01T05:44:57.245Z,1643694297.245 [keepstation:NeedComms:E] Stopped 2022-02-01T05:44:57.245Z,1643694297.245 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2022-02-01T05:44:57.245Z,1643694297.245 [keepstation:NeedComms] Stopped 2022-02-01T05:44:57.246Z,1643694297.246 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2022-02-01T05:44:57.246Z,1643694297.246 [keepstation:NeedComms:B.GoToSurface] Stopped 2022-02-01T05:44:57.246Z,1643694297.246 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2022-02-01T05:45:12.708Z,1643694312.708 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-02-01T05:45:28.901Z,1643694328.901 [NAL9602](INFO): Not Powering down - fast GPS 2022-02-01T05:45:47.848Z,1643694347.848 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-02-01T05:45:47.885Z,1643694347.885 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2022-02-01T05:45:47.887Z,1643694347.887 [BPC1](INFO): Received data from all battery sticks. 2022-02-01T05:46:16.968Z,1643694376.968 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002980 2022-02-01T05:48:16.154Z,1643694496.154 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 0.75 m/s, pitch 20.00 deg, mass-position 6.00 mm (3 active estimators). 2022-02-01T05:48:42.560Z,1643694522.560 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.241524 2022-02-01T05:49:16.693Z,1643694556.693 [Radio_Surface](INFO): Powering down 2022-02-01T05:49:18.700Z,1643694558.700 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-02-01T05:49:19.704Z,1643694559.704 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2022-02-01T05:52:21.786Z,1643694741.786 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2022-02-01T05:58:07.651Z,1643695087.651 [keepstation:KeepStation:C.KeepStation](INFO): Waypoint->Rest 2022-02-01T05:58:08.020Z,1643695088.020 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 0.80 m/s, pitch -20.00 deg, mass-position 6.00 mm. 2022-02-01T06:12:37.833Z,1643695957.833 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2022-02-01T06:53:08.300Z,1643698388.300 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2022-02-01T06:53:08.302Z,1643698388.302 [BPC1](INFO): Received data from all battery sticks. 2022-02-01T07:04:23.814Z,1643699063.814 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2022-02-01T07:24:41.097Z,1643700281.097 [keepstation:KeepStation:C.KeepStation](INFO): Rest->Waypoint 2022-02-01T07:33:47.348Z,1643700827.348 [CBIT](IMPORTANT): Beginning ground fault scan 2022-02-01T07:33:58.247Z,1643700838.247 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.069340 CHAN A1 (24V): 0.127202 CHAN A2 (12V): 0.042067 CHAN A3 (5V): 0.005233 CHAN B0 (3.3V): 0.006320 CHAN B1 (3.15aV): 0.005116 CHAN B2 (3.15bV): 0.005212 CHAN B3 (GND): -0.010894 OPEN: 0.006538 Full Scale: +/- 1 mA 2022-02-01T07:34:55.164Z,1643700895.164 [keepstation:KeepStation:C.KeepStation](INFO): Waypoint->Rest 2022-02-01T08:00:16.672Z,1643702416.672 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2022-02-01T08:00:31.564Z,1643702431.564 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2022-02-01T08:00:31.566Z,1643702431.566 [BPC1](INFO): Received data from all battery sticks. 2022-02-01T08:32:59.672Z,1643704379.672 [RDI_Pathfinder](ERROR): only read 0 of 4 data items 2022-02-01T08:51:15.721Z,1643705475.721 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2022-02-01T08:51:42.384Z,1643705502.384 [keepstation:KeepStation:C.KeepStation](INFO): Rest->Waypoint 2022-02-01T08:52:05.851Z,1643705525.851 [keepstation:NeedComms] Running Loop=1 2022-02-01T08:52:05.851Z,1643705525.851 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2022-02-01T08:52:05.851Z,1643705525.851 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2022-02-01T08:52:05.851Z,1643705525.851 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2022-02-01T08:52:05.851Z,1643705525.851 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2022-02-01T08:52:05.852Z,1643705525.852 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2022-02-01T08:52:05.852Z,1643705525.852 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2022-02-01T08:52:05.853Z,1643705525.853 [keepstation:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2022-02-01T08:52:05.853Z,1643705525.853 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2022-02-01T08:52:05.854Z,1643705525.854 [keepstation:NeedComms:A] Running Loop=1 2022-02-01T08:52:05.855Z,1643705525.855 [keepstation:NeedComms:A](INFO): last time_fix was: 1643694299.000000 second since 1970/01/01T00:00:00Z 2022-02-01T08:52:05.855Z,1643705525.855 [keepstation:NeedComms:A] Stopped 2022-02-01T08:52:30.503Z,1643705550.503 [keepstation:NeedComms:C] Running Loop=1 2022-02-01T08:52:31.180Z,1643705551.180 [Radio_Surface](INFO): Powering up 2022-02-01T08:52:37.132Z,1643705557.132 [DataOverHttps](INFO): Radio surface powered ON. 2022-02-01T08:52:46.205Z,1643705566.205 [NAL9602](INFO): SBD MO Status=0, MOMSN=35572, MT Status=0, MTMSN=0 2022-02-01T08:52:46.205Z,1643705566.205 [NAL9602](INFO): No messages in MT queue 2022-02-01T08:52:59.943Z,1643705579.943 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,085302.00,A,4132.64430,N,07043.51565,W,0.641,23.37,010222,,,A*46 2022-02-01T08:52:59.946Z,1643705579.946 [NAL9602](INFO): GPS fix at 20220201T085302: (41.544072, -70.725261) 2022-02-01T08:52:59.958Z,1643705579.958 [UniversalFixResidualReporter](INFO): Fix residual: 1.4 %DT, over the last 1367.8 m. Residual distance 19.3 m at bearing 126.7 degrees. Fix at (41.5441, -70.7253) with 406.7 m made good. 2022-02-01T08:52:59.963Z,1643705579.963 [keepstation:NeedComms:C] Stopped 2022-02-01T08:52:59.963Z,1643705579.963 [keepstation:NeedComms:D] Running Loop=1 2022-02-01T08:53:07.273Z,1643705587.273 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-02-01T08:53:15.909Z,1643705595.909 [NAL9602](INFO): SBD MO Status=1, MOMSN=35573, MT Status=0, MTMSN=0 2022-02-01T08:53:15.952Z,1643705595.952 [NAL9602](INFO): Sent 99 bytes from file Logs/20220201T025642/Courier0027.lzma 2022-02-01T08:53:15.952Z,1643705595.952 [NAL9602](INFO): Packets left to send: 0 2022-02-01T08:53:23.139Z,1643705603.139 [NAL9602](INFO): SBD MO Status=1, MOMSN=35574, MT Status=0, MTMSN=0 2022-02-01T08:53:23.196Z,1643705603.196 [NAL9602](INFO): Sent 184 bytes from file Logs/20220201T025642/Express0028.lzma 2022-02-01T08:53:23.196Z,1643705603.196 [NAL9602](INFO): Packets left to send: 2 2022-02-01T08:53:29.510Z,1643705609.510 [NAL9602](INFO): SBD MO Status=1, MOMSN=35575, MT Status=0, MTMSN=0 2022-02-01T08:53:29.564Z,1643705609.564 [NAL9602](INFO): Sent 184 bytes from file Logs/20220201T025642/Express0028.lzma 2022-02-01T08:53:29.564Z,1643705609.564 [NAL9602](INFO): Packets left to send: 1 2022-02-01T08:53:37.614Z,1643705617.614 [NAL9602](INFO): SBD MO Status=1, MOMSN=35576, MT Status=0, MTMSN=0 2022-02-01T08:53:37.664Z,1643705617.664 [NAL9602](INFO): Sent 64 bytes from file Logs/20220201T025642/Express0028.lzma 2022-02-01T08:53:37.664Z,1643705617.664 [NAL9602](INFO): Packets left to send: 0 2022-02-01T08:53:42.135Z,1643705622.135 [NAL9602](INFO): SBD MO Status=0, MOMSN=35577, MT Status=0, MTMSN=0 2022-02-01T08:53:42.229Z,1643705622.229 [keepstation:NeedComms:D] Stopped 2022-02-01T08:53:42.229Z,1643705622.229 [keepstation:NeedComms:E] Running Loop=1 2022-02-01T08:53:42.412Z,1643705622.412 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-02-01T08:53:44.559Z,1643705624.559 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,085347.00,A,4132.64202,N,07043.51682,W,0.486,23.37,010222,,,A*43 2022-02-01T08:53:44.561Z,1643705624.561 [NAL9602](INFO): GPS fix at 20220201T085347: (41.544034, -70.725280) 2022-02-01T08:53:44.597Z,1643705624.597 [keepstation:NeedComms:E] Stopped 2022-02-01T08:53:44.598Z,1643705624.598 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2022-02-01T08:53:44.598Z,1643705624.598 [keepstation:NeedComms] Stopped 2022-02-01T08:53:44.598Z,1643705624.598 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2022-02-01T08:53:44.598Z,1643705624.598 [keepstation:NeedComms:B.GoToSurface] Stopped 2022-02-01T08:53:44.598Z,1643705624.598 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2022-02-01T08:53:45.437Z,1643705625.437 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 0.75 m/s, pitch -15.00 deg, mass-position 6.00 mm. 2022-02-01T08:54:17.280Z,1643705657.280 [NAL9602](INFO): Not Powering down - fast GPS 2022-02-01T08:54:17.552Z,1643705657.552 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-02-01T08:54:52.692Z,1643705692.692 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-02-01T08:55:27.833Z,1643705727.833 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-02-01T08:55:39.860Z,1643705739.860 [Radio_Surface](INFO): Powering down 2022-02-01T08:55:51.980Z,1643705751.980 [Radio_Surface](INFO): Powering up 2022-02-01T08:56:02.976Z,1643705762.976 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-02-01T08:56:38.117Z,1643705798.117 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-02-01T08:57:03.488Z,1643705823.488 [Radio_Surface](INFO): Powering down 2022-02-01T08:57:13.265Z,1643705833.265 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-02-01T08:57:14.268Z,1643705834.268 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2022-02-01T09:05:44.531Z,1643706344.531 [keepstation:KeepStation:C.KeepStation](INFO): Waypoint->Rest 2022-02-01T09:07:40.141Z,1643706460.141 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2022-02-01T09:07:52.991Z,1643706472.991 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2022-02-01T09:07:52.993Z,1643706472.993 [BPC1](INFO): Received data from all battery sticks. 2022-02-01T09:12:35.430Z,1643706755.430 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2022-02-01T09:33:58.565Z,1643708038.565 [CBIT](IMPORTANT): Beginning ground fault scan 2022-02-01T09:34:09.501Z,1643708049.501 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.066345 CHAN A1 (24V): 0.127542 CHAN A2 (12V): 0.037538 CHAN A3 (5V): 0.007802 CHAN B0 (3.3V): 0.004813 CHAN B1 (3.15aV): 0.004278 CHAN B2 (3.15bV): 0.004756 CHAN B3 (GND): -0.010792 OPEN: 0.006160 Full Scale: +/- 1 mA 2022-02-01T09:43:33.423Z,1643708613.423 [keepstation:KeepStation:C.KeepStation](INFO): Rest->Waypoint 2022-02-01T09:57:17.168Z,1643709437.168 [keepstation:KeepStation:C.KeepStation](INFO): Waypoint->Rest 2022-02-01T09:57:17.587Z,1643709437.587 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 0.75 m/s, pitch 20.00 deg, mass-position 6.00 mm. 2022-02-01T10:15:17.842Z,1643710517.842 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2022-02-01T10:15:17.844Z,1643710517.844 [BPC1](INFO): Received data from all battery sticks. 2022-02-01T10:25:19.431Z,1643711119.431 [keepstation:KeepStation:C.KeepStation](INFO): Rest->Waypoint 2022-02-01T10:25:19.814Z,1643711119.814 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 0.75 m/s, pitch 20.00 deg, mass-position 6.00 mm (1 active estimators). 2022-02-01T10:31:35.620Z,1643711495.620 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout 2022-02-01T10:31:35.620Z,1643711495.620 [RDI_Pathfinder] Communications Fault, FailCount= 1 2022-02-01T10:31:35.620Z,1643711495.620 [RDI_Pathfinder](ERROR): Communications Fault 2022-02-01T10:31:35.739Z,1643711495.739 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2022-02-01T10:31:36.052Z,1643711496.052 [RDI_Pathfinder](INFO): Powering down 2022-02-01T10:31:36.871Z,1643711496.871 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2022-02-01T10:31:36.871Z,1643711496.871 [RDI_Pathfinder] No Fault, FailCount= 1 2022-02-01T10:31:54.208Z,1643711514.208 [BuoyancyServo](FAULT): Overload Error 2022-02-01T10:31:54.208Z,1643711514.208 [BuoyancyServo] Hardware Fault, FailCount= 1 2022-02-01T10:31:54.208Z,1643711514.208 [BuoyancyServo](ERROR): Hardware Fault 2022-02-01T10:31:54.275Z,1643711514.275 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo 2022-02-01T10:31:56.213Z,1643711516.213 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2022-02-01T10:31:56.213Z,1643711516.213 [BuoyancyServo](INFO): Powering down 2022-02-01T10:31:57.055Z,1643711517.055 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2022-02-01T10:31:57.055Z,1643711517.055 [BuoyancyServo] No Fault, FailCount= 1 2022-02-01T10:31:57.435Z,1643711517.435 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2022-02-01T10:31:57.435Z,1643711517.435 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2022-02-01T10:36:07.104Z,1643711767.104 [BuoyancyServo](FAULT): Overload Error 2022-02-01T10:36:07.104Z,1643711767.104 [BuoyancyServo] Hardware Fault, FailCount= 1 2022-02-01T10:36:07.104Z,1643711767.104 [BuoyancyServo](ERROR): Hardware Fault 2022-02-01T10:36:07.227Z,1643711767.227 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo 2022-02-01T10:36:09.101Z,1643711769.101 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2022-02-01T10:36:09.101Z,1643711769.101 [BuoyancyServo](INFO): Powering down 2022-02-01T10:36:09.967Z,1643711769.967 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2022-02-01T10:36:09.967Z,1643711769.967 [BuoyancyServo] No Fault, FailCount= 1 2022-02-01T10:36:10.316Z,1643711770.316 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2022-02-01T10:36:10.317Z,1643711770.317 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2022-02-01T10:41:08.860Z,1643712068.860 [keepstation:KeepStation:C.KeepStation](INFO): Waypoint->Rest 2022-02-01T11:07:06.705Z,1643713626.705 [keepstation:KeepStation:C.KeepStation](INFO): Rest->Waypoint 2022-02-01T11:07:33.776Z,1643713653.776 [keepstation:NeedComms] Running Loop=1 2022-02-01T11:07:33.776Z,1643713653.776 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2022-02-01T11:07:33.776Z,1643713653.776 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2022-02-01T11:07:33.776Z,1643713653.776 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2022-02-01T11:07:33.777Z,1643713653.777 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2022-02-01T11:07:33.777Z,1643713653.777 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2022-02-01T11:07:33.777Z,1643713653.777 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2022-02-01T11:07:33.778Z,1643713653.778 [keepstation:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2022-02-01T11:07:33.778Z,1643713653.778 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2022-02-01T11:07:33.778Z,1643713653.778 [keepstation:NeedComms:A] Running Loop=1 2022-02-01T11:07:33.823Z,1643713653.823 [keepstation:NeedComms:A](INFO): last time_fix was: 1643705627.000000 second since 1970/01/01T00:00:00Z 2022-02-01T11:07:33.823Z,1643713653.823 [keepstation:NeedComms:A] Stopped 2022-02-01T11:07:59.631Z,1643713679.631 [keepstation:NeedComms:C] Running Loop=1 2022-02-01T11:08:00.172Z,1643713680.172 [Radio_Surface](INFO): Powering up 2022-02-01T11:08:05.544Z,1643713685.544 [DataOverHttps](INFO): Radio surface powered ON. 2022-02-01T11:08:19.016Z,1643713699.016 [NAL9602](INFO): SBD MO Status=2, MOMSN=35578, MT Status=2, MTMSN=0 2022-02-01T11:08:19.017Z,1643713699.017 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-02-01T11:08:35.664Z,1643713715.664 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-02-01T11:08:49.311Z,1643713729.311 [NAL9602](INFO): SBD MO Status=2, MOMSN=35578, MT Status=2, MTMSN=0 2022-02-01T11:08:49.311Z,1643713729.311 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-02-01T11:08:58.202Z,1643713738.202 [NAL9602](INFO): SBD MO Status=0, MOMSN=35578, MT Status=0, MTMSN=0 2022-02-01T11:08:58.202Z,1643713738.202 [NAL9602](INFO): No messages in MT queue 2022-02-01T11:08:59.411Z,1643713739.411 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,110902.00,A,4132.61049,N,07043.45554,W,0.330,354.85,010222,,,A*70 2022-02-01T11:08:59.413Z,1643713739.413 [NAL9602](INFO): GPS fix at 20220201T110902: (41.543508, -70.724259) 2022-02-01T11:08:59.444Z,1643713739.444 [UniversalFixResidualReporter](INFO): Fix residual: 3.5 %DT, over the last 2204.7 m. Residual distance 77.0 m at bearing -62.9 degrees. Fix at (41.5435, -70.7243) with 103.1 m made good. 2022-02-01T11:08:59.449Z,1643713739.449 [keepstation:NeedComms:C] Stopped 2022-02-01T11:08:59.449Z,1643713739.449 [keepstation:NeedComms:D] Running Loop=1 2022-02-01T11:09:10.808Z,1643713750.808 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-02-01T11:09:21.030Z,1643713761.030 [NAL9602](INFO): SBD MO Status=2, MOMSN=35579, MT Status=2, MTMSN=0 2022-02-01T11:09:21.031Z,1643713761.031 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-02-01T11:09:43.359Z,1643713783.359 [NAL9602](INFO): SBD MO Status=2, MOMSN=35579, MT Status=2, MTMSN=0 2022-02-01T11:09:43.359Z,1643713783.359 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-02-01T11:09:45.952Z,1643713785.952 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-02-01T11:10:01.675Z,1643713801.675 [NAL9602](INFO): SBD MO Status=2, MOMSN=35579, MT Status=2, MTMSN=0 2022-02-01T11:10:01.675Z,1643713801.675 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-02-01T11:10:21.092Z,1643713821.092 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-02-01T11:10:44.581Z,1643713844.581 [NAL9602](INFO): SBD MO Status=1, MOMSN=35579, MT Status=0, MTMSN=0 2022-02-01T11:10:44.632Z,1643713844.632 [NAL9602](INFO): Sent 99 bytes from file Logs/20220201T025642/Courier0030.lzma 2022-02-01T11:10:44.632Z,1643713844.632 [NAL9602](INFO): Packets left to send: 0 2022-02-01T11:10:53.697Z,1643713853.697 [NAL9602](INFO): SBD MO Status=1, MOMSN=35580, MT Status=0, MTMSN=0 2022-02-01T11:10:53.748Z,1643713853.748 [NAL9602](INFO): Sent 184 bytes from file Logs/20220201T025642/Express0031.lzma 2022-02-01T11:10:53.748Z,1643713853.748 [NAL9602](INFO): Packets left to send: 3 2022-02-01T11:10:56.232Z,1643713856.232 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-02-01T11:11:02.362Z,1643713862.362 [NAL9602](INFO): SBD MO Status=1, MOMSN=35581, MT Status=0, MTMSN=0 2022-02-01T11:11:02.420Z,1643713862.420 [NAL9602](INFO): Sent 184 bytes from file Logs/20220201T025642/Express0031.lzma 2022-02-01T11:11:02.420Z,1643713862.420 [NAL9602](INFO): Packets left to send: 2 2022-02-01T11:11:11.456Z,1643713871.456 [NAL9602](INFO): SBD MO Status=1, MOMSN=35582, MT Status=0, MTMSN=0 2022-02-01T11:11:11.508Z,1643713871.508 [NAL9602](INFO): Sent 184 bytes from file Logs/20220201T025642/Express0031.lzma 2022-02-01T11:11:11.508Z,1643713871.508 [NAL9602](INFO): Packets left to send: 1 2022-02-01T11:11:23.753Z,1643713883.753 [NAL9602](INFO): SBD MO Status=1, MOMSN=35583, MT Status=0, MTMSN=0 2022-02-01T11:11:23.808Z,1643713883.808 [NAL9602](INFO): Sent 58 bytes from file Logs/20220201T025642/Express0031.lzma 2022-02-01T11:11:23.808Z,1643713883.808 [NAL9602](INFO): Packets left to send: 0 2022-02-01T11:11:25.360Z,1643713885.360 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002986 2022-02-01T11:11:27.062Z,1643713887.062 [keepstation:NeedComms:D] Stopped 2022-02-01T11:11:27.062Z,1643713887.062 [keepstation:NeedComms:E] Running Loop=1 2022-02-01T11:11:31.886Z,1643713891.886 [NAL9602](INFO): SBD MO Status=0, MOMSN=35584, MT Status=0, MTMSN=0 2022-02-01T11:11:33.099Z,1643713893.099 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,111135.00,A,4132.61181,N,07043.43915,W,0.544,19.84,010222,,,D*4C 2022-02-01T11:11:33.110Z,1643713893.110 [NAL9602](INFO): GPS fix at 20220201T111135: (41.543530, -70.723986) 2022-02-01T11:11:33.137Z,1643713893.137 [keepstation:NeedComms:E] Stopped 2022-02-01T11:11:33.139Z,1643713893.139 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2022-02-01T11:11:33.139Z,1643713893.139 [keepstation:NeedComms] Stopped 2022-02-01T11:11:33.139Z,1643713893.139 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2022-02-01T11:11:33.139Z,1643713893.139 [keepstation:NeedComms:B.GoToSurface] Stopped 2022-02-01T11:11:33.139Z,1643713893.139 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2022-02-01T11:12:05.013Z,1643713925.013 [NAL9602](INFO): Not Powering down - fast GPS 2022-02-01T11:13:06.804Z,1643713986.804 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.251423 2022-02-01T11:13:10.540Z,1643713990.540 [Radio_Surface](INFO): Powering down 2022-02-01T11:13:10.821Z,1643713990.821 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2022-02-01T11:22:26.489Z,1643714546.489 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2022-02-01T11:22:39.300Z,1643714559.300 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2022-02-01T11:22:39.302Z,1643714559.302 [BPC1](INFO): Received data from all battery sticks. 2022-02-01T11:29:23.318Z,1643714963.318 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range. 2022-02-01T11:31:15.246Z,1643715075.246 [keepstation:KeepStation:C.KeepStation](INFO): Waypoint->Rest 2022-02-01T11:34:09.770Z,1643715249.770 [CBIT](IMPORTANT): Beginning ground fault scan 2022-02-01T11:34:20.667Z,1643715260.667 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.077906 CHAN A1 (24V): 0.128632 CHAN A2 (12V): 0.039687 CHAN A3 (5V): 0.007796 CHAN B0 (3.3V): 0.005313 CHAN B1 (3.15aV): 0.004303 CHAN B2 (3.15bV): 0.004836 CHAN B3 (GND): -0.010744 OPEN: 0.006457 Full Scale: +/- 1 mA 2022-02-01T11:58:15.300Z,1643716695.300 [keepstation:KeepStation:C.KeepStation](INFO): Rest->Waypoint 2022-02-01T12:08:45.500Z,1643717325.500 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2022-02-01T12:13:12.137Z,1643717592.137 [keepstation:KeepStation:C.KeepStation](INFO): Waypoint->Rest 2022-02-01T12:30:03.756Z,1643718603.756 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2022-02-01T12:30:03.758Z,1643718603.758 [BPC1](INFO): Received data from all battery sticks. 2022-02-01T12:47:17.198Z,1643719637.198 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2022-02-01T12:51:30.513Z,1643719890.513 [keepstation:KeepStation:C.KeepStation](INFO): Rest->Waypoint 2022-02-01T13:03:33.669Z,1643720613.669 [keepstation:KeepStation:C.KeepStation](INFO): Waypoint->Rest 2022-02-01T13:07:53.448Z,1643720873.448 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2022-02-01T13:15:46.922Z,1643721346.922 [keepstation](INFO): Timed out from 2022-02-01T03:15:46.8Z 2022-02-01T13:15:46.923Z,1643721346.923 [MissionManager](INFO): keepstation is completed. 2022-02-01T13:15:46.923Z,1643721346.923 [MissionManager](INFO): Uninitializing Mission keepstation 2022-02-01T13:15:46.923Z,1643721346.923 [keepstation] Stopped 2022-02-01T13:15:46.923Z,1643721346.923 [keepstation](DEBUG): Aggregate::uninitialize keepstation 2022-02-01T13:15:46.923Z,1643721346.923 [keepstation:StandardEnvelopes] Stopped 2022-02-01T13:15:46.923Z,1643721346.923 [keepstation:StandardEnvelopes](DEBUG): Aggregate::uninitialize keepstation:StandardEnvelopes 2022-02-01T13:15:46.923Z,1643721346.923 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2022-02-01T13:15:46.923Z,1643721346.923 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2022-02-01T13:15:46.923Z,1643721346.923 [keepstation:StandardEnvelopes:B.DepthEnvelope] Stopped 2022-02-01T13:15:46.923Z,1643721346.923 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2022-02-01T13:15:46.924Z,1643721346.924 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2022-02-01T13:15:46.924Z,1643721346.924 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2022-02-01T13:15:46.924Z,1643721346.924 [keepstation:F.Pitch] Stopped 2022-02-01T13:15:46.924Z,1643721346.924 [keepstation:KeepStation] Stopped 2022-02-01T13:15:46.924Z,1643721346.924 [keepstation:KeepStation](DEBUG): Aggregate::uninitialize keepstation:KeepStation 2022-02-01T13:15:46.924Z,1643721346.924 [keepstation:KeepStation:A] Stopped 2022-02-01T13:15:46.924Z,1643721346.924 [keepstation:KeepStation:B.Pitch] Stopped 2022-02-01T13:15:46.924Z,1643721346.924 [keepstation:KeepStation:C.KeepStation] Stopped 2022-02-01T13:15:46.924Z,1643721346.924 [keepstation:KeepStation:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2022-02-01T13:15:46.924Z,1643721346.924 [keepstation:KeepStation:D.Wait] Stopped 2022-02-01T13:15:46.924Z,1643721346.924 [keepstation:KeepStation:D.Wait](DEBUG): Uninitialize Wait Component. 2022-02-01T13:15:47.369Z,1643721347.369 [MissionManager](IMPORTANT): Started mission Default 2022-02-01T13:15:47.369Z,1643721347.369 [Default] Running Loop=1 2022-02-01T13:15:47.369Z,1643721347.369 [Default](DEBUG): Aggregate::initialize Default 2022-02-01T13:15:47.369Z,1643721347.369 [Default:B.GoToSurface] Running Loop=1 2022-02-01T13:15:47.369Z,1643721347.369 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2022-02-01T13:15:47.369Z,1643721347.369 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2022-02-01T13:15:47.370Z,1643721347.370 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2022-02-01T13:15:47.370Z,1643721347.370 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2022-02-01T13:15:47.370Z,1643721347.370 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2022-02-01T13:15:47.371Z,1643721347.371 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2022-02-01T13:15:47.371Z,1643721347.371 [Default:A.Wait] Running Loop=1 2022-02-01T13:15:47.371Z,1643721347.371 [Default:A.Wait](DEBUG): Initialize Wait Component. 2022-02-01T13:15:47.739Z,1643721347.739 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -25.00 mm (2 active estimators). 2022-02-01T13:15:47.912Z,1643721347.912 [Radio_Surface](INFO): Powering up 2022-02-01T13:15:54.132Z,1643721354.132 [DataOverHttps](INFO): Radio surface powered ON. 2022-02-01T13:16:00.661Z,1643721360.661 [Default:A.Wait](INFO): Done Waiting. 2022-02-01T13:16:00.661Z,1643721360.661 [Default:A.Wait] Stopped 2022-02-01T13:16:00.661Z,1643721360.661 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2022-02-01T13:16:13.996Z,1643721373.996 [Default:CheckIn] Running Loop=1 2022-02-01T13:16:13.997Z,1643721373.997 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-02-01T13:16:13.997Z,1643721373.997 [Default:CheckIn:Read_GPS] Running Loop=1 2022-02-01T13:16:24.252Z,1643721384.252 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-02-01T13:16:52.770Z,1643721412.770 [NAL9602](INFO): SBD MO Status=0, MOMSN=35585, MT Status=0, MTMSN=0 2022-02-01T13:16:52.770Z,1643721412.770 [NAL9602](INFO): No messages in MT queue 2022-02-01T13:16:53.979Z,1643721413.979 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,131656.00,A,4132.54470,N,07043.66948,W,0.311,156.70,010222,,,A*7C 2022-02-01T13:16:53.982Z,1643721413.982 [NAL9602](INFO): GPS fix at 20220201T131656: (41.542412, -70.727825) 2022-02-01T13:16:53.994Z,1643721413.994 [UniversalFixResidualReporter](INFO): Fix residual: 5.4 %DT, over the last 2027.8 m. Residual distance 109.8 m at bearing -28.8 degrees. Fix at (41.5424, -70.7278) with 342.8 m made good. 2022-02-01T13:16:53.996Z,1643721413.996 [Default:CheckIn:Read_GPS] Stopped 2022-02-01T13:16:53.996Z,1643721413.996 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-02-01T13:16:59.392Z,1643721419.392 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-02-01T13:17:04.162Z,1643721424.162 [NAL9602](INFO): SBD MO Status=1, MOMSN=35586, MT Status=0, MTMSN=0 2022-02-01T13:17:04.224Z,1643721424.224 [NAL9602](INFO): Sent 98 bytes from file Logs/20220201T025642/Courier0033.lzma 2022-02-01T13:17:04.224Z,1643721424.224 [NAL9602](INFO): Packets left to send: 0 2022-02-01T13:17:21.039Z,1643721441.039 [NAL9602](INFO): SBD MO Status=1, MOMSN=35587, MT Status=0, MTMSN=0 2022-02-01T13:17:21.086Z,1643721441.086 [NAL9602](INFO): Sent 184 bytes from file Logs/20220201T025642/Express0034.lzma 2022-02-01T13:17:21.087Z,1643721441.087 [NAL9602](INFO): Packets left to send: 3 2022-02-01T13:17:34.532Z,1643721454.532 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-02-01T13:17:42.314Z,1643721462.314 [NAL9602](INFO): SBD MO Status=1, MOMSN=35588, MT Status=0, MTMSN=0 2022-02-01T13:17:42.372Z,1643721462.372 [NAL9602](INFO): Sent 184 bytes from file Logs/20220201T025642/Express0034.lzma 2022-02-01T13:17:42.372Z,1643721462.372 [NAL9602](INFO): Packets left to send: 2 2022-02-01T13:17:55.148Z,1643721475.148 [NAL9602](INFO): SBD MO Status=1, MOMSN=35589, MT Status=0, MTMSN=0 2022-02-01T13:17:55.204Z,1643721475.204 [NAL9602](INFO): Sent 184 bytes from file Logs/20220201T025642/Express0034.lzma 2022-02-01T13:17:55.204Z,1643721475.204 [NAL9602](INFO): Packets left to send: 1 2022-02-01T13:18:05.918Z,1643721485.918 [NAL9602](INFO): SBD MO Status=1, MOMSN=35590, MT Status=0, MTMSN=0 2022-02-01T13:18:05.980Z,1643721485.980 [NAL9602](INFO): Sent 32 bytes from file Logs/20220201T025642/Express0034.lzma 2022-02-01T13:18:05.980Z,1643721485.980 [NAL9602](INFO): Packets left to send: 0 2022-02-01T13:18:09.672Z,1643721489.672 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-02-01T13:18:17.690Z,1643721497.690 [NAL9602](INFO): SBD MO Status=0, MOMSN=35591, MT Status=0, MTMSN=0 2022-02-01T13:18:17.766Z,1643721497.766 [Default:CheckIn:Read_Iridium] Stopped 2022-02-01T13:18:17.766Z,1643721497.766 [Default:CheckIn:C.Wait] Running Loop=1 2022-02-01T13:18:17.766Z,1643721497.766 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-02-01T13:18:44.812Z,1643721524.812 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-02-01T13:18:48.397Z,1643721528.397 [NAL9602](INFO): Not Powering down - fast GPS 2022-02-01T13:19:13.932Z,1643721553.932 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002983 2022-02-01T13:22:24.722Z,1643721744.722 [DataOverHttps](IMPORTANT): SBD MTMSN=20220201T132227 2022-02-01T13:22:28.740Z,1643721748.740 [DataOverHttps](INFO): Received command: sched asap "load Transport/transit.xml;set transit.MissionTimeout 120 min;set transit.NeedCommsTime 60 min;set transit.Latitude 41.535836 degree;set transit.Longitude -70.675959 degree;set transit.Depth 5 m;set transit.Speed .8 m/s" 19h4i 1 2 2022-02-01T13:22:28.764Z,1643721748.764 [CommandExec](IMPORTANT): got command schedule asap "load Transport/transit.xml;set transit.MissionTimeout 120 min;set transit.NeedCommsTime 60 min;set transit.Latitude 41.535836 degree;set transit.Longitude -70.675959 degree;set transit.Depth 5 m;set transit.Speed .8 m/s" 19h4i 1 2.000000 2022-02-01T13:22:28.765Z,1643721748.765 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=19h4i 2022-02-01T13:22:28.766Z,1643721748.766 [CommandExec](IMPORTANT): Scheduled #12 (#1 of 2 with id='19h4i'): "load Transport/transit.xml;set transit.MissionTimeout 120 min;set transit.NeedCommsTime 60 min;set transit.Latitude 41.535836 degree;set transit.Longitude -70.675959 degree;set transit.Depth 5 m;set transit.Speed .8 m/s", ASAP 2022-02-01T13:22:29.742Z,1643721749.742 [DataOverHttps](IMPORTANT): SBD MTMSN=20220201T132232 2022-02-01T13:22:33.760Z,1643721753.760 [DataOverHttps](INFO): Received command: sched asap "set transit.MaxDepth 7 m;set transit.MinAltitude 3 m;run" 19h4i 2 2 2022-02-01T13:22:33.767Z,1643721753.767 [CommandExec](IMPORTANT): got command schedule asap "set transit.MaxDepth 7 m;set transit.MinAltitude 3 m;run" 19h4i 2 2.000000 2022-02-01T13:22:33.767Z,1643721753.767 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=19h4i 2022-02-01T13:22:33.768Z,1643721753.768 [CommandExec](IMPORTANT): Scheduled #13 (#2 of 2 with id='19h4i'): "set transit.MaxDepth 7 m;set transit.MinAltitude 3 m;run", ASAP 2022-02-01T13:22:33.954Z,1643721753.954 [CommandExec](IMPORTANT): got command load ./Missions/Transport/transit.xml 2022-02-01T13:22:33.954Z,1643721753.954 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/transit.xml 2022-02-01T13:22:34.049Z,1643721754.049 [MissionManager](INFO): DefineArg transit.MissionTimeout = 60.000000 min 2022-02-01T13:22:34.051Z,1643721754.051 [MissionManager](INFO): DefineArg transit.NeedCommsTime = 30.000000 min 2022-02-01T13:22:34.053Z,1643721754.053 [MissionManager](INFO): DefineArg transit.Latitude = nan arcdeg 2022-02-01T13:22:34.055Z,1643721754.055 [MissionManager](INFO): DefineArg transit.Longitude = nan arcdeg 2022-02-01T13:22:34.057Z,1643721754.057 [MissionManager](INFO): DefineArg transit.Northings = 0.000000 m 2022-02-01T13:22:34.059Z,1643721754.059 [MissionManager](INFO): DefineArg transit.Eastings = 0.000000 m 2022-02-01T13:22:34.062Z,1643721754.062 [MissionManager](INFO): DefineArg transit.Depth = 10.000000 m 2022-02-01T13:22:34.064Z,1643721754.064 [MissionManager](INFO): DefineArg transit.Speed = 1.000000 m/s 2022-02-01T13:22:34.066Z,1643721754.066 [MissionManager](INFO): DefineArg transit.MaxDepth = 20.000000 m 2022-02-01T13:22:34.068Z,1643721754.068 [MissionManager](INFO): DefineArg transit.MinOffshore = 1.000000 km 2022-02-01T13:22:34.071Z,1643721754.071 [MissionManager](INFO): DefineArg transit.MinAltitude = 7.000000 m 2022-02-01T13:22:34.073Z,1643721754.073 [MissionManager](INFO): DefineArg transit.MassHold = 1 bool 2022-02-01T13:22:34.074Z,1643721754.074 [MissionManager](INFO): DefineArg transit.BuoyancyHold = 1 bool 2022-02-01T13:22:34.075Z,1643721754.075 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.xml 2022-02-01T13:22:34.183Z,1643721754.183 [MissionManager](INFO): DefineArg transit:NeedComms.DiveInterval = 3.000000 h 2022-02-01T13:22:34.190Z,1643721754.190 [MissionManager](INFO): DefineArg transit:NeedComms.WaitForPitchUp = 10.000000 min 2022-02-01T13:22:34.192Z,1643721754.192 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacePitch = 20.000000 arcdeg 2022-02-01T13:22:34.194Z,1643721754.194 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceDepthRate = nan m/s 2022-02-01T13:22:34.196Z,1643721754.196 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceSpeed = 1.000000 m/s 2022-02-01T13:22:34.198Z,1643721754.198 [MissionManager](INFO): DefineArg transit:NeedComms.GPSTimeout = 7.000000 min 2022-02-01T13:22:34.247Z,1643721754.247 [MissionManager](INFO): DefineArg transit:NeedComms.CommsTimeout = 30.000000 min 2022-02-01T13:22:34.279Z,1643721754.279 [transit:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface. 2022-02-01T13:22:34.324Z,1643721754.324 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.xml 2022-02-01T13:22:34.341Z,1643721754.341 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinAltitude = 5.000000 m 2022-02-01T13:22:34.343Z,1643721754.343 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepth = 200.000000 m 2022-02-01T13:22:34.358Z,1643721754.358 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinOffshore = 2000.000000 m 2022-02-01T13:22:34.359Z,1643721754.359 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2022-02-01T13:22:34.365Z,1643721754.365 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2022-02-01T13:22:34.390Z,1643721754.390 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2022-02-01T13:22:34.398Z,1643721754.398 [transit:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy. 2022-02-01T13:22:34.410Z,1643721754.410 [transit:MassHold.Pitch](DEBUG): Construct. 2022-02-01T13:22:34.415Z,1643721754.415 [transit:Transit:A.Pitch](DEBUG): Construct. 2022-02-01T13:22:34.419Z,1643721754.419 [transit:Transit:B.SetSpeed](DEBUG): Construct. 2022-02-01T13:22:34.422Z,1643721754.422 [transit:Transit:Wpt1.Waypoint](DEBUG): Construct Waypoint. 2022-02-01T13:22:34.445Z,1643721754.445 [MissionManager](DEBUG): Vehicle transits to desired waypoint and can be commanded to use/hold mass or buoyancy. Maximum duration of mission 60 How often to surface for commumications 30 Latitude of waypoint to seek. If set to NaN, uses latitude at mission initialization. NaN Longitude of waypoint to seek. If set to NaN, uses longitude at mission initialization. NaN Northward distance of the waypoint to seek. If combined with Latitude and/or Longitude, this is an offset from the specified Latitude and/or Longitude. 0 Eastward distance of the waypoint to seek. If combined with Latitude and/or Longitude, this is an offset from the specified Latitude and/or Longitude. 0 Depth of flat and level flight during the mission. 10 Speed of vehicle (relative to water) during the mission. 1 Maximum depth for the entire mission. 20 Minimum distance offshore for the entire mission. 1 Minimum height above the sea floor for the entire mission. 7 Set to True in order to hold mass at default position, False to allow mass to run on its own. Set to True in order to hold buoyancy at neutral position, False to allow buoyancy to run on its own. 1 2022-02-01T13:22:34.445Z,1643721754.445 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/transit.xml 2022-02-01T13:22:43.727Z,1643721763.727 [CommandExec](IMPORTANT): got command set transit.MissionTimeout 120 minute 2022-02-01T13:22:43.728Z,1643721763.728 [CommandExec](IMPORTANT): got command set transit.NeedCommsTime 60 minute 2022-02-01T13:22:43.729Z,1643721763.729 [CommandExec](IMPORTANT): got command set transit.Latitude 41.535836 degree 2022-02-01T13:22:43.730Z,1643721763.730 [CommandExec](IMPORTANT): got command set transit.Longitude -70.675959 degree 2022-02-01T13:22:43.730Z,1643721763.730 [CommandExec](IMPORTANT): got command set transit.Depth 5 meter 2022-02-01T13:22:43.731Z,1643721763.731 [CommandExec](IMPORTANT): got command set transit.Speed .8 meter_per_second 2022-02-01T13:22:46.902Z,1643721766.902 [CommandExec](IMPORTANT): got command set transit.MaxDepth 7 meter 2022-02-01T13:22:46.903Z,1643721766.903 [CommandExec](IMPORTANT): got command set transit.MinAltitude 3 meter 2022-02-01T13:22:46.903Z,1643721766.903 [CommandExec](IMPORTANT): got command run 2022-02-01T13:22:46.905Z,1643721766.905 [CommandExec](IMPORTANT): Running 2022-02-01T13:22:47.187Z,1643721767.187 [Default] Stopped 2022-02-01T13:22:47.187Z,1643721767.187 [Default](DEBUG): Aggregate::uninitialize Default 2022-02-01T13:22:47.187Z,1643721767.187 [Default:B.GoToSurface] Stopped 2022-02-01T13:22:47.187Z,1643721767.187 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2022-02-01T13:22:47.204Z,1643721767.204 [Default:CheckIn] Stopped 2022-02-01T13:22:47.204Z,1643721767.204 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-02-01T13:22:47.204Z,1643721767.204 [Default:CheckIn:C.Wait] Stopped 2022-02-01T13:22:47.204Z,1643721767.204 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-02-01T13:22:47.204Z,1643721767.204 [MissionManager](IMPORTANT): Started mission transit 2022-02-01T13:22:47.204Z,1643721767.204 [transit] Running Loop=1 2022-02-01T13:22:47.205Z,1643721767.205 [transit](DEBUG): Aggregate::initialize transit 2022-02-01T13:22:47.205Z,1643721767.205 [transit:StandardEnvelopes] Running Loop=1 2022-02-01T13:22:47.205Z,1643721767.205 [transit:StandardEnvelopes](DEBUG): Aggregate::initialize transit:StandardEnvelopes 2022-02-01T13:22:47.205Z,1643721767.205 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2022-02-01T13:22:47.205Z,1643721767.205 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2022-02-01T13:22:47.205Z,1643721767.205 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2022-02-01T13:22:47.205Z,1643721767.205 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2022-02-01T13:22:47.205Z,1643721767.205 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2022-02-01T13:22:47.205Z,1643721767.205 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2022-02-01T13:22:47.205Z,1643721767.205 [transit:BuoyancyHold.Buoyancy] Running Loop=1 2022-02-01T13:22:47.206Z,1643721767.206 [transit:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2022-02-01T13:22:47.206Z,1643721767.206 [transit:MassHold.Pitch] Running Loop=1 2022-02-01T13:22:47.206Z,1643721767.206 [transit:MassHold.Pitch](DEBUG): Initialize. 2022-02-01T13:22:47.206Z,1643721767.206 [transit:B] Running Loop=1 2022-02-01T13:22:47.207Z,1643721767.207 [transit:MassHold.Pitch] Running Loop=1 2022-02-01T13:22:47.207Z,1643721767.207 [transit:BuoyancyHold.Buoyancy] Running Loop=1 2022-02-01T13:22:47.207Z,1643721767.207 [transit:StandardEnvelopes] Running Loop=1 2022-02-01T13:22:47.207Z,1643721767.207 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2022-02-01T13:22:47.207Z,1643721767.207 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2022-02-01T13:22:47.212Z,1643721767.212 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2022-02-01T13:22:47.212Z,1643721767.212 [transit:B] Stopped 2022-02-01T13:22:47.213Z,1643721767.213 [transit:C] Running Loop=1 2022-02-01T13:22:47.579Z,1643721767.579 [transit:C] Stopped 2022-02-01T13:22:47.579Z,1643721767.579 [transit:E] Running Loop=1 2022-02-01T13:22:47.983Z,1643721767.983 [transit:E] Stopped 2022-02-01T13:22:47.983Z,1643721767.983 [transit:F] Running Loop=1 2022-02-01T13:22:48.379Z,1643721768.379 [transit:F] Stopped 2022-02-01T13:22:48.379Z,1643721768.379 [transit:G] Running Loop=1 2022-02-01T13:22:48.789Z,1643721768.789 [transit:G] Stopped 2022-02-01T13:22:48.789Z,1643721768.789 [transit:Transit] Running Loop=1 2022-02-01T13:22:48.789Z,1643721768.789 [transit:Transit](DEBUG): Aggregate::initialize transit:Transit 2022-02-01T13:22:48.789Z,1643721768.789 [transit:Transit:A.Pitch] Running Loop=1 2022-02-01T13:22:48.789Z,1643721768.789 [transit:Transit:A.Pitch](DEBUG): Initialize. 2022-02-01T13:22:48.790Z,1643721768.790 [transit:Transit:B.SetSpeed] Running Loop=1 2022-02-01T13:22:48.790Z,1643721768.790 [transit:Transit:B.SetSpeed](DEBUG): Initialize. 2022-02-01T13:22:48.790Z,1643721768.790 [transit:Transit:Wpt1.Waypoint] Running Loop=1 2022-02-01T13:22:48.790Z,1643721768.790 [transit:Transit:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent. 2022-02-01T13:22:49.214Z,1643721769.214 [transit:Transit:B.SetSpeed] Running Loop=1 2022-02-01T13:22:49.214Z,1643721769.214 [transit:Transit:A.Pitch] Running Loop=1 2022-02-01T13:23:14.635Z,1643721794.635 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 0.80 m/s, pitch 30.00 deg, mass-position 6.00 mm (3 active estimators). 2022-02-01T13:24:10.141Z,1643721850.141 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.239857 2022-02-01T13:24:21.188Z,1643721861.188 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002986 2022-02-01T13:25:55.609Z,1643721955.609 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.287163 2022-02-01T13:26:25.464Z,1643721985.464 [Radio_Surface](INFO): Powering down 2022-02-01T13:26:31.752Z,1643721991.752 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-02-01T13:26:32.758Z,1643721992.758 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2022-02-01T13:28:29.382Z,1643722109.382 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2022-02-01T13:34:20.916Z,1643722460.916 [CBIT](IMPORTANT): Beginning ground fault scan 2022-02-01T13:34:31.807Z,1643722471.807 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.068823 CHAN A1 (24V): 0.124571 CHAN A2 (12V): 0.039490 CHAN A3 (5V): 0.008798 CHAN B0 (3.3V): 0.005832 CHAN B1 (3.15aV): 0.004946 CHAN B2 (3.15bV): 0.004813 CHAN B3 (GND): -0.010361 OPEN: 0.006616 Full Scale: +/- 1 mA 2022-02-01T13:37:28.288Z,1643722648.288 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2022-02-01T13:37:28.290Z,1643722648.290 [BPC1](INFO): Received data from all battery sticks. 2022-02-01T13:46:55.136Z,1643723215.136 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2022-02-01T14:16:54.141Z,1643725014.141 [transit:NeedComms] Running Loop=1 2022-02-01T14:16:54.141Z,1643725014.141 [transit:NeedComms](DEBUG): Aggregate::initialize transit:NeedComms 2022-02-01T14:16:54.141Z,1643725014.141 [transit:NeedComms:B.GoToSurface] Running Loop=1 2022-02-01T14:16:54.141Z,1643725014.141 [transit:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2022-02-01T14:16:54.141Z,1643725014.141 [transit:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2022-02-01T14:16:54.142Z,1643725014.142 [transit:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2022-02-01T14:16:54.142Z,1643725014.142 [transit:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2022-02-01T14:16:54.142Z,1643725014.142 [transit:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2022-02-01T14:16:54.143Z,1643725014.143 [transit:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2022-02-01T14:16:54.143Z,1643725014.143 [transit:NeedComms:A] Running Loop=1 2022-02-01T14:16:54.145Z,1643725014.145 [transit:NeedComms:A](INFO): last time_fix was: 1643721416.000000 second since 1970/01/01T00:00:00Z 2022-02-01T14:16:54.145Z,1643725014.145 [transit:NeedComms:A] Stopped 2022-02-01T14:17:18.385Z,1643725038.385 [transit:NeedComms:C] Running Loop=1 2022-02-01T14:17:18.801Z,1643725038.801 [transit:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix 2022-02-01T14:17:18.920Z,1643725038.920 [Radio_Surface](INFO): Powering up 2022-02-01T14:17:24.328Z,1643725044.328 [DataOverHttps](INFO): Radio surface powered ON. 2022-02-01T14:17:34.921Z,1643725054.921 [NAL9602](INFO): SBD MO Status=0, MOMSN=35592, MT Status=0, MTMSN=0 2022-02-01T14:17:34.921Z,1643725054.921 [NAL9602](INFO): No messages in MT queue 2022-02-01T14:17:36.139Z,1643725056.139 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,141738.00,A,4132.32540,N,07041.98911,W,0.778,0.00,010222,,,A*71 2022-02-01T14:17:36.151Z,1643725056.151 [NAL9602](INFO): GPS fix at 20220201T141738: (41.538757, -70.699819) 2022-02-01T14:17:36.163Z,1643725056.163 [UniversalFixResidualReporter](INFO): Fix residual: 0.6 %DT, over the last 2417.3 m. Residual distance 13.7 m at bearing -178.1 degrees. Fix at (41.5388, -70.6998) with 2366.1 m made good. 2022-02-01T14:17:36.167Z,1643725056.167 [transit:NeedComms:C] Stopped 2022-02-01T14:17:36.168Z,1643725056.168 [transit:NeedComms:D] Running Loop=1 2022-02-01T14:17:36.609Z,1643725056.609 [transit:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications 2022-02-01T14:17:52.938Z,1643725072.938 [NAL9602](INFO): SBD MO Status=1, MOMSN=35593, MT Status=0, MTMSN=0 2022-02-01T14:17:52.988Z,1643725072.988 [NAL9602](INFO): Sent 99 bytes from file Logs/20220201T025642/Courier0036.lzma 2022-02-01T14:17:52.988Z,1643725072.988 [NAL9602](INFO): Packets left to send: 0 2022-02-01T14:17:54.449Z,1643725074.449 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-02-01T14:18:04.102Z,1643725084.102 [NAL9602](INFO): SBD MO Status=1, MOMSN=35594, MT Status=0, MTMSN=0 2022-02-01T14:18:04.152Z,1643725084.152 [NAL9602](INFO): Sent 184 bytes from file Logs/20220201T025642/Express0037.lzma 2022-02-01T14:18:04.152Z,1643725084.152 [NAL9602](INFO): Packets left to send: 4 2022-02-01T14:18:14.110Z,1643725094.110 [NAL9602](INFO): SBD MO Status=1, MOMSN=35595, MT Status=0, MTMSN=0 2022-02-01T14:18:14.168Z,1643725094.168 [NAL9602](INFO): Sent 184 bytes from file Logs/20220201T025642/Express0037.lzma 2022-02-01T14:18:14.168Z,1643725094.168 [NAL9602](INFO): Packets left to send: 3 2022-02-01T14:18:20.934Z,1643725100.934 [NAL9602](INFO): SBD MO Status=1, MOMSN=35596, MT Status=0, MTMSN=0 2022-02-01T14:18:20.992Z,1643725100.992 [NAL9602](INFO): Sent 184 bytes from file Logs/20220201T025642/Express0037.lzma 2022-02-01T14:18:20.992Z,1643725100.992 [NAL9602](INFO): Packets left to send: 2 2022-02-01T14:18:29.604Z,1643725109.604 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-02-01T14:18:32.556Z,1643725112.556 [NAL9602](INFO): SBD MO Status=1, MOMSN=35597, MT Status=0, MTMSN=0 2022-02-01T14:18:32.608Z,1643725112.608 [NAL9602](INFO): Sent 184 bytes from file Logs/20220201T025642/Express0037.lzma 2022-02-01T14:18:32.608Z,1643725112.608 [NAL9602](INFO): Packets left to send: 1 2022-02-01T14:18:44.189Z,1643725124.189 [NAL9602](INFO): SBD MO Status=1, MOMSN=35598, MT Status=0, MTMSN=0 2022-02-01T14:18:44.240Z,1643725124.240 [NAL9602](INFO): Sent 138 bytes from file Logs/20220201T025642/Express0037.lzma 2022-02-01T14:18:44.240Z,1643725124.240 [NAL9602](INFO): Packets left to send: 0 2022-02-01T14:18:56.438Z,1643725136.438 [NAL9602](INFO): SBD MO Status=0, MOMSN=35599, MT Status=0, MTMSN=0 2022-02-01T14:18:56.524Z,1643725136.524 [transit:NeedComms:D] Stopped 2022-02-01T14:18:56.524Z,1643725136.524 [transit:NeedComms:E] Running Loop=1 2022-02-01T14:18:56.856Z,1643725136.856 [transit:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix 2022-02-01T14:18:58.863Z,1643725138.863 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,141901.00,A,4132.32025,N,07041.98666,W,0.253,209.58,010222,,,D*73 2022-02-01T14:18:58.865Z,1643725138.865 [NAL9602](INFO): GPS fix at 20220201T141901: (41.538671, -70.699778) 2022-02-01T14:18:58.880Z,1643725138.880 [transit:NeedComms:E] Stopped 2022-02-01T14:18:58.881Z,1643725138.881 [transit:NeedComms](INFO): Completed transit:NeedComms 2022-02-01T14:18:58.881Z,1643725138.881 [transit:NeedComms] Stopped 2022-02-01T14:18:58.881Z,1643725138.881 [transit:NeedComms](DEBUG): Aggregate::uninitialize transit:NeedComms 2022-02-01T14:18:58.881Z,1643725138.881 [transit:NeedComms:B.GoToSurface] Stopped 2022-02-01T14:18:58.881Z,1643725138.881 [transit:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2022-02-01T14:19:04.752Z,1643725144.752 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-02-01T14:19:31.177Z,1643725171.177 [NAL9602](INFO): Not Powering down - fast GPS 2022-02-01T14:19:39.892Z,1643725179.892 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-02-01T14:19:55.426Z,1643725195.426 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2022-02-01T14:20:15.034Z,1643725215.034 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-02-01T14:20:50.172Z,1643725250.172 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-02-01T14:21:08.008Z,1643725268.008 [Radio_Surface](INFO): Powering down 2022-02-01T14:21:25.328Z,1643725285.328 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-02-01T14:21:26.332Z,1643725286.332 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2022-02-01T14:44:51.839Z,1643726691.839 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2022-02-01T14:44:51.849Z,1643726691.849 [BPC1](INFO): Received data from all battery sticks. 2022-02-01T14:46:43.348Z,1643726803.348 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2022-02-01T15:18:14.885Z,1643728694.885 [transit:Transit:Wpt1.Waypoint](IMPORTANT): Reached Waypoint: 41.535835,-70.675957 2022-02-01T15:18:14.885Z,1643728694.885 [transit:Transit:Wpt1.Waypoint] Stopped 2022-02-01T15:18:14.885Z,1643728694.885 [transit:Transit:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent. 2022-02-01T15:18:14.885Z,1643728694.885 [transit:Transit:PhoneHome] Running Loop=1 2022-02-01T15:18:14.886Z,1643728694.886 [transit:Transit:PhoneHome](DEBUG): Aggregate::initialize transit:Transit:PhoneHome 2022-02-01T15:18:15.325Z,1643728695.325 [transit:NeedComms] Running Loop=1 2022-02-01T15:18:15.325Z,1643728695.325 [transit:NeedComms](DEBUG): Aggregate::initialize transit:NeedComms 2022-02-01T15:18:15.325Z,1643728695.325 [transit:NeedComms:B.GoToSurface] Running Loop=1 2022-02-01T15:18:15.325Z,1643728695.325 [transit:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2022-02-01T15:18:15.326Z,1643728695.326 [transit:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2022-02-01T15:18:15.326Z,1643728695.326 [transit:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2022-02-01T15:18:15.327Z,1643728695.327 [transit:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2022-02-01T15:18:15.327Z,1643728695.327 [transit:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2022-02-01T15:18:15.327Z,1643728695.327 [transit:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2022-02-01T15:18:15.327Z,1643728695.327 [transit:NeedComms:A] Running Loop=1 2022-02-01T15:18:15.333Z,1643728695.333 [transit:NeedComms:A](INFO): last time_fix was: 1643725141.000000 second since 1970/01/01T00:00:00Z 2022-02-01T15:18:15.333Z,1643728695.333 [transit:NeedComms:A] Stopped 2022-02-01T15:18:39.530Z,1643728719.530 [transit:NeedComms:C] Running Loop=1 2022-02-01T15:18:40.192Z,1643728720.192 [Radio_Surface](INFO): Powering up 2022-02-01T15:18:46.420Z,1643728726.420 [DataOverHttps](INFO): Radio surface powered ON. 2022-02-01T15:19:05.368Z,1643728745.368 [NAL9602](INFO): SBD MO Status=2, MOMSN=35600, MT Status=2, MTMSN=0 2022-02-01T15:19:05.369Z,1643728745.369 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-02-01T15:19:16.544Z,1643728756.544 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-02-01T15:19:27.591Z,1643728767.591 [NAL9602](INFO): SBD MO Status=0, MOMSN=35600, MT Status=0, MTMSN=0 2022-02-01T15:19:27.592Z,1643728767.592 [NAL9602](INFO): No messages in MT queue 2022-02-01T15:19:28.801Z,1643728768.801 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,151931.00,A,4132.14765,N,07040.54133,W,0.214,0.00,010222,,,D*75 2022-02-01T15:19:28.803Z,1643728768.803 [NAL9602](INFO): GPS fix at 20220201T151931: (41.535794, -70.675689) 2022-02-01T15:19:28.815Z,1643728768.815 [UniversalFixResidualReporter](INFO): Fix residual: 0.1 %DT, over the last 2050.6 m. Residual distance 2.6 m at bearing 71.9 degrees. Fix at (41.5358, -70.6757) with 2030.3 m made good. 2022-02-01T15:19:28.819Z,1643728768.819 [transit:NeedComms:C] Stopped 2022-02-01T15:19:28.819Z,1643728768.819 [transit:NeedComms:D] Running Loop=1 2022-02-01T15:19:39.471Z,1643728779.471 [NAL9602](INFO): SBD MO Status=1, MOMSN=35601, MT Status=0, MTMSN=0 2022-02-01T15:19:39.524Z,1643728779.524 [NAL9602](INFO): Sent 98 bytes from file Logs/20220201T025642/Courier0039.lzma 2022-02-01T15:19:39.524Z,1643728779.524 [NAL9602](INFO): Packets left to send: 0 2022-02-01T15:19:51.684Z,1643728791.684 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-02-01T15:19:55.498Z,1643728795.498 [NAL9602](INFO): SBD MO Status=1, MOMSN=35602, MT Status=0, MTMSN=0 2022-02-01T15:19:55.548Z,1643728795.548 [NAL9602](INFO): Sent 184 bytes from file Logs/20220201T025642/Express0040.lzma 2022-02-01T15:19:55.548Z,1643728795.548 [NAL9602](INFO): Packets left to send: 1 2022-02-01T15:20:05.703Z,1643728805.703 [NAL9602](INFO): SBD MO Status=1, MOMSN=35603, MT Status=0, MTMSN=0 2022-02-01T15:20:05.760Z,1643728805.760 [NAL9602](INFO): Sent 103 bytes from file Logs/20220201T025642/Express0040.lzma 2022-02-01T15:20:05.760Z,1643728805.760 [NAL9602](INFO): Packets left to send: 0 2022-02-01T15:20:21.940Z,1643728821.940 [NAL9602](INFO): SBD MO Status=2, MOMSN=35604, MT Status=2, MTMSN=0 2022-02-01T15:20:21.941Z,1643728821.941 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-02-01T15:20:26.833Z,1643728826.833 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-02-01T15:20:45.875Z,1643728845.875 [NAL9602](INFO): SBD MO Status=2, MOMSN=35604, MT Status=2, MTMSN=0 2022-02-01T15:20:45.875Z,1643728845.875 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-02-01T15:20:56.482Z,1643728856.482 [NAL9602](INFO): SBD MO Status=0, MOMSN=35604, MT Status=0, MTMSN=0 2022-02-01T15:20:56.564Z,1643728856.564 [transit:NeedComms:D] Stopped 2022-02-01T15:20:56.565Z,1643728856.565 [transit:NeedComms:E] Running Loop=1 2022-02-01T15:20:58.915Z,1643728858.915 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,152101.00,A,4132.14249,N,07040.54838,W,0.486,196.31,010222,,,D*75 2022-02-01T15:20:58.917Z,1643728858.917 [NAL9602](INFO): GPS fix at 20220201T152101: (41.535708, -70.675806) 2022-02-01T15:20:58.931Z,1643728858.931 [transit:NeedComms:E] Stopped 2022-02-01T15:20:58.932Z,1643728858.932 [transit:NeedComms](INFO): Completed transit:NeedComms 2022-02-01T15:20:58.932Z,1643728858.932 [transit:NeedComms] Stopped 2022-02-01T15:20:58.932Z,1643728858.932 [transit:NeedComms](DEBUG): Aggregate::uninitialize transit:NeedComms 2022-02-01T15:20:58.932Z,1643728858.932 [transit:NeedComms:B.GoToSurface] Stopped 2022-02-01T15:20:58.932Z,1643728858.932 [transit:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2022-02-01T15:20:59.363Z,1643728859.363 [transit:Transit:PhoneHome](INFO): Completed transit:Transit:PhoneHome 2022-02-01T15:20:59.363Z,1643728859.363 [transit:Transit:PhoneHome] Stopped 2022-02-01T15:20:59.363Z,1643728859.363 [transit:Transit:PhoneHome](DEBUG): Aggregate::uninitialize transit:Transit:PhoneHome 2022-02-01T15:20:59.384Z,1643728859.384 [transit:Transit](INFO): Completed transit:Transit 2022-02-01T15:20:59.384Z,1643728859.384 [transit:Transit] Stopped 2022-02-01T15:20:59.385Z,1643728859.385 [transit:Transit](DEBUG): Aggregate::uninitialize transit:Transit 2022-02-01T15:20:59.385Z,1643728859.385 [transit:Transit:A.Pitch] Stopped 2022-02-01T15:20:59.385Z,1643728859.385 [transit:Transit:B.SetSpeed] Stopped 2022-02-01T15:20:59.385Z,1643728859.385 [transit:Transit:B.SetSpeed](DEBUG): Uninitialize. 2022-02-01T15:20:59.386Z,1643728859.386 [transit](INFO): Completed transit 2022-02-01T15:20:59.386Z,1643728859.386 [MissionManager](INFO): transit is completed. 2022-02-01T15:20:59.386Z,1643728859.386 [MissionManager](INFO): Uninitializing Mission transit 2022-02-01T15:20:59.386Z,1643728859.386 [transit] Stopped 2022-02-01T15:20:59.387Z,1643728859.387 [transit](DEBUG): Aggregate::uninitialize transit 2022-02-01T15:20:59.387Z,1643728859.387 [transit:StandardEnvelopes] Stopped 2022-02-01T15:20:59.387Z,1643728859.387 [transit:StandardEnvelopes](DEBUG): Aggregate::uninitialize transit:StandardEnvelopes 2022-02-01T15:20:59.387Z,1643728859.387 [transit:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2022-02-01T15:20:59.387Z,1643728859.387 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2022-02-01T15:20:59.387Z,1643728859.387 [transit:StandardEnvelopes:B.DepthEnvelope] Stopped 2022-02-01T15:20:59.387Z,1643728859.387 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2022-02-01T15:20:59.387Z,1643728859.387 [transit:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2022-02-01T15:20:59.387Z,1643728859.387 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2022-02-01T15:20:59.387Z,1643728859.387 [transit:BuoyancyHold.Buoyancy] Stopped 2022-02-01T15:20:59.387Z,1643728859.387 [transit:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2022-02-01T15:20:59.387Z,1643728859.387 [transit:MassHold.Pitch] Stopped 2022-02-01T15:20:59.749Z,1643728859.749 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 0.75 m/s, pitch 20.00 deg, mass-position 6.00 mm. 2022-02-01T15:20:59.753Z,1643728859.753 [MissionManager](IMPORTANT): Started mission Default 2022-02-01T15:20:59.754Z,1643728859.754 [Default] Running Loop=1 2022-02-01T15:20:59.754Z,1643728859.754 [Default](DEBUG): Aggregate::initialize Default 2022-02-01T15:20:59.754Z,1643728859.754 [Default:B.GoToSurface] Running Loop=1 2022-02-01T15:20:59.754Z,1643728859.754 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2022-02-01T15:20:59.754Z,1643728859.754 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2022-02-01T15:20:59.754Z,1643728859.754 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2022-02-01T15:20:59.755Z,1643728859.755 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2022-02-01T15:20:59.755Z,1643728859.755 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2022-02-01T15:20:59.755Z,1643728859.755 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2022-02-01T15:20:59.756Z,1643728859.756 [Default:A.Wait] Running Loop=1 2022-02-01T15:20:59.756Z,1643728859.756 [Default:A.Wait](DEBUG): Initialize Wait Component. 2022-02-01T15:21:01.980Z,1643728861.980 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-02-01T15:21:13.083Z,1643728873.083 [Default:A.Wait](INFO): Done Waiting. 2022-02-01T15:21:13.083Z,1643728873.083 [Default:A.Wait] Stopped 2022-02-01T15:21:13.083Z,1643728873.083 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2022-02-01T15:21:13.472Z,1643728873.472 [Default:CheckIn] Running Loop=1 2022-02-01T15:21:13.472Z,1643728873.472 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-02-01T15:21:13.472Z,1643728873.472 [Default:CheckIn:Read_GPS] Running Loop=1 2022-02-01T15:21:15.475Z,1643728875.475 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,152118.00,A,4132.14082,N,07040.55076,W,0.564,196.31,010222,,,D*76 2022-02-01T15:21:15.477Z,1643728875.477 [NAL9602](INFO): GPS fix at 20220201T152118: (41.535680, -70.675846) 2022-02-01T15:21:15.509Z,1643728875.509 [Default:CheckIn:Read_GPS] Stopped 2022-02-01T15:21:15.509Z,1643728875.509 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-02-01T15:21:34.952Z,1643728894.952 [NAL9602](INFO): SBD MO Status=1, MOMSN=35605, MT Status=0, MTMSN=0 2022-02-01T15:21:35.005Z,1643728895.005 [NAL9602](INFO): Sent 79 bytes from file Logs/20220201T025642/Courier0042.lzma 2022-02-01T15:21:35.005Z,1643728895.005 [NAL9602](INFO): Packets left to send: 0 2022-02-01T15:21:37.132Z,1643728897.132 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-02-01T15:21:47.241Z,1643728907.241 [NAL9602](INFO): SBD MO Status=1, MOMSN=35606, MT Status=0, MTMSN=0 2022-02-01T15:21:47.292Z,1643728907.292 [NAL9602](INFO): Sent 115 bytes from file Logs/20220201T025642/Express0043.lzma 2022-02-01T15:21:47.292Z,1643728907.292 [NAL9602](INFO): Packets left to send: 0 2022-02-01T15:21:57.795Z,1643728917.795 [NAL9602](INFO): SBD MO Status=0, MOMSN=35607, MT Status=0, MTMSN=0 2022-02-01T15:21:57.878Z,1643728917.878 [Default:CheckIn:Read_Iridium] Stopped 2022-02-01T15:21:57.879Z,1643728917.879 [Default:CheckIn:C.Wait] Running Loop=1 2022-02-01T15:21:57.879Z,1643728917.879 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-02-01T15:22:06.260Z,1643728926.260 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002969 2022-02-01T15:22:28.497Z,1643728948.497 [NAL9602](INFO): Not Powering down - fast GPS 2022-02-01T15:26:58.394Z,1643729218.394 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-02-01T15:26:58.394Z,1643729218.394 [Default:CheckIn:C.Wait] Stopped 2022-02-01T15:26:58.394Z,1643729218.394 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-02-01T15:26:58.395Z,1643729218.395 [Default:CheckIn:D] Running Loop=1 2022-02-01T15:26:58.788Z,1643729218.788 [Default:CheckIn:D] Stopped 2022-02-01T15:26:58.788Z,1643729218.788 [Default:CheckIn:E] Running Loop=1 2022-02-01T15:26:59.239Z,1643729219.239 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 5.983905 min 2022-02-01T15:26:59.239Z,1643729219.239 [Default:CheckIn:E] Stopped 2022-02-01T15:26:59.239Z,1643729219.239 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-02-01T15:26:59.239Z,1643729219.239 [Default:CheckIn] Stopped 2022-02-01T15:26:59.239Z,1643729219.239 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-02-01T15:26:59.239Z,1643729219.239 [Default:CheckIn](INFO): Running loop #2 2022-02-01T15:26:59.240Z,1643729219.240 [Default:CheckIn] Running Loop=2 2022-02-01T15:26:59.240Z,1643729219.240 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-02-01T15:26:59.240Z,1643729219.240 [Default:CheckIn:Read_GPS] Running Loop=1 2022-02-01T15:27:01.203Z,1643729221.203 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,152704.00,A,4132.11456,N,07040.60752,W,0.603,259.36,010222,,,D*77 2022-02-01T15:27:01.206Z,1643729221.206 [NAL9602](INFO): GPS fix at 20220201T152704: (41.535243, -70.676792) 2022-02-01T15:27:01.236Z,1643729221.236 [Default:CheckIn:Read_GPS] Stopped 2022-02-01T15:27:01.236Z,1643729221.236 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-02-01T15:27:06.551Z,1643729226.551 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20220201T025642/Courier0045.lzma 2022-02-01T15:27:07.554Z,1643729227.554 [DataOverHttps](INFO): Moved sent file to Logs/20220201T025642/Courier0045.lzma.bak 2022-02-01T15:27:07.554Z,1643729227.554 [DataOverHttps](INFO): SBD MOMSN=1397143 2022-02-01T15:27:16.603Z,1643729236.603 [DataOverHttps](INFO): Sending 51 bytes from file Logs/20220201T025642/Courier0048.lzma 2022-02-01T15:27:17.606Z,1643729237.606 [DataOverHttps](INFO): Moved sent file to Logs/20220201T025642/Courier0048.lzma.bak 2022-02-01T15:27:17.606Z,1643729237.606 [DataOverHttps](INFO): SBD MOMSN=1397146 2022-02-01T15:27:26.645Z,1643729246.645 [DataOverHttps](INFO): Sending 159 bytes from file Logs/20220201T025642/Express0046.lzma 2022-02-01T15:27:27.646Z,1643729247.646 [DataOverHttps](INFO): Moved sent file to Logs/20220201T025642/Express0046.lzma.bak 2022-02-01T15:27:27.646Z,1643729247.646 [DataOverHttps](INFO): SBD MOMSN=1397148 2022-02-01T15:27:30.287Z,1643729250.287 [NAL9602](INFO): SBD MO Status=0, MOMSN=35608, MT Status=0, MTMSN=0 2022-02-01T15:27:30.287Z,1643729250.287 [NAL9602](INFO): No messages in MT queue 2022-02-01T15:27:36.683Z,1643729256.683 [DataOverHttps](INFO): Sending 60 bytes from file Logs/20220201T025642/Express0049.lzma 2022-02-01T15:27:37.686Z,1643729257.686 [DataOverHttps](INFO): Moved sent file to Logs/20220201T025642/Express0049.lzma.bak 2022-02-01T15:27:37.686Z,1643729257.686 [DataOverHttps](INFO): SBD MOMSN=1397151 2022-02-01T15:27:39.237Z,1643729259.237 [Default:CheckIn:Read_Iridium] Stopped 2022-02-01T15:27:39.237Z,1643729259.237 [Default:CheckIn:C.Wait] Running Loop=1 2022-02-01T15:27:39.238Z,1643729259.238 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-02-01T15:27:45.264Z,1643729265.264 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout 2022-02-01T15:27:45.264Z,1643729265.264 [RDI_Pathfinder] Communications Fault, FailCount= 1 2022-02-01T15:27:45.264Z,1643729265.264 [RDI_Pathfinder](ERROR): Communications Fault 2022-02-01T15:27:45.284Z,1643729265.284 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2022-02-01T15:27:45.716Z,1643729265.716 [RDI_Pathfinder](INFO): Powering down 2022-02-01T15:27:46.449Z,1643729266.449 [Power24vConverter](INFO): Powering down. 2022-02-01T15:27:46.487Z,1643729266.487 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2022-02-01T15:27:46.487Z,1643729266.487 [RDI_Pathfinder] No Fault, FailCount= 1 2022-02-01T15:27:47.261Z,1643729267.261 [Power24vConverter](INFO): Powering up. 2022-02-01T15:28:00.993Z,1643729280.993 [NAL9602](INFO): Not Powering down - fast GPS 2022-02-01T15:28:18.867Z,1643729298.867 [DataOverHttps](IMPORTANT): SBD MTMSN=20220201T152821 2022-02-01T15:28:22.888Z,1643729302.888 [DataOverHttps](INFO): Received command: restart logs