2019-04-04T06:29:36.401Z,1554359376.401 [NAL9602](INFO): Received command:restart logs 2019-04-04T06:29:36.414Z,1554359376.414 [CommandLine](IMPORTANT): got command restart logs 2019-04-04T06:29:37.137Z,1554359377.137 [NAL9602](DEBUG): Fix Requested 2019-04-04T06:29:37.531Z,1554359377.531 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,062931.00,A,3645.88010,N,12150.22233,W,0.369,42.95,040419,,,D*48 2019-04-04T06:29:37.533Z,1554359377.533 [NAL9602](INFO): GPS fix at 20190404T062931: (36.764668, -121.837039) 2019-04-04T06:29:37.594Z,1554359377.594 [UniversalFixResidualReporter](INFO): Fix residual: 24.3 %DT, over the last 1416.9 m. Residual distance 344.2 m at bearing 27.1 degrees. Fix at (36.7647, -121.8370) with 236.3 m made good. 2019-04-04T06:29:37.601Z,1554359377.601 [keepstation:NeedComms:C] Stopped 2019-04-04T06:29:37.602Z,1554359377.602 [keepstation:NeedComms:D] Running Loop=1 2019-04-04T06:29:48.981Z,1554359388.981 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T06:30:07.352Z,1554359407.352 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=2704, MT Status=1, MTMSN=110 2019-04-04T06:30:07.401Z,1554359407.401 [NAL9602](INFO): Sent 79 bytes from file Logs/20190404T033102/Courier0030.lzma 2019-04-04T06:30:07.401Z,1554359407.401 [NAL9602](INFO): Packets left to send: 0 2019-04-04T06:30:08.077Z,1554359408.077 [NAL9602](INFO): Received command:load Transport/keepstation.xml;set keepstation.MissionTimeout 11 hour;set keepstation.Latitude 36.76804 degree;set keepstation.Longitude -121.85 degree;set keepstation.Depth 20 meter;set keepstation.Speed 1 meter_per_second;run 2019-04-04T06:30:08.091Z,1554359408.091 [CommandLine](IMPORTANT): got command load ./Missions/Transport/keepstation.xml 2019-04-04T06:30:08.091Z,1554359408.091 [MissionManager](INFO): Uninitializing Mission keepstation 2019-04-04T06:30:08.092Z,1554359408.092 [keepstation] Stopped 2019-04-04T06:30:08.092Z,1554359408.092 [keepstation](DEBUG): Aggregate::uninitialize keepstation 2019-04-04T06:30:08.092Z,1554359408.092 [keepstation:NeedComms] Stopped 2019-04-04T06:30:08.092Z,1554359408.092 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2019-04-04T06:30:08.092Z,1554359408.092 [keepstation:NeedComms:B.GoToSurface] Stopped 2019-04-04T06:30:08.092Z,1554359408.092 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-04-04T06:30:08.092Z,1554359408.092 [keepstation:NeedComms:D] Stopped 2019-04-04T06:30:08.092Z,1554359408.092 [keepstation:StandardEnvelopes] Stopped 2019-04-04T06:30:08.092Z,1554359408.092 [keepstation:StandardEnvelopes](DEBUG): Aggregate::uninitialize keepstation:StandardEnvelopes 2019-04-04T06:30:08.092Z,1554359408.092 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2019-04-04T06:30:08.092Z,1554359408.092 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2019-04-04T06:30:08.092Z,1554359408.092 [keepstation:StandardEnvelopes:B.DepthEnvelope] Stopped 2019-04-04T06:30:08.093Z,1554359408.093 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2019-04-04T06:30:08.093Z,1554359408.093 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2019-04-04T06:30:08.093Z,1554359408.093 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2019-04-04T06:30:08.093Z,1554359408.093 [keepstation:F.Pitch] Stopped 2019-04-04T06:30:08.093Z,1554359408.093 [keepstation:KeepStation] Stopped 2019-04-04T06:30:08.093Z,1554359408.093 [keepstation:KeepStation](DEBUG): Aggregate::uninitialize keepstation:KeepStation 2019-04-04T06:30:08.093Z,1554359408.093 [keepstation:KeepStation:A] Stopped 2019-04-04T06:30:08.093Z,1554359408.093 [keepstation:KeepStation:B.Pitch] Stopped 2019-04-04T06:30:08.093Z,1554359408.093 [keepstation:KeepStation:C.KeepStation] Stopped 2019-04-04T06:30:08.093Z,1554359408.093 [keepstation:KeepStation:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2019-04-04T06:30:08.094Z,1554359408.094 [keepstation:KeepStation:D.Wait] Stopped 2019-04-04T06:30:08.094Z,1554359408.094 [keepstation:KeepStation:D.Wait](DEBUG): Uninitialize Wait Component. 2019-04-04T06:30:08.105Z,1554359408.105 [MissionManager](INFO): Loading Mission: ./Missions/Transport/keepstation.xml 2019-04-04T06:30:08.192Z,1554359408.192 [MissionManager](INFO): DefineArg keepstation.MissionTimeout = 4.000000 h 2019-04-04T06:30:08.194Z,1554359408.194 [MissionManager](INFO): DefineArg keepstation.NeedCommsTime = 45.000000 min 2019-04-04T06:30:08.196Z,1554359408.196 [MissionManager](INFO): DefineArg keepstation.Latitude = nan arcdeg 2019-04-04T06:30:08.198Z,1554359408.198 [MissionManager](INFO): DefineArg keepstation.Longitude = nan arcdeg 2019-04-04T06:30:08.200Z,1554359408.200 [MissionManager](INFO): DefineArg keepstation.Depth = 30.000000 m 2019-04-04T06:30:08.202Z,1554359408.202 [MissionManager](INFO): DefineArg keepstation.ApproachDepth = 15.000000 m 2019-04-04T06:30:08.204Z,1554359408.204 [MissionManager](INFO): DefineArg keepstation.DepthDeadband = 4.000000 m 2019-04-04T06:30:08.207Z,1554359408.207 [MissionManager](INFO): DefineArg keepstation.Speed = 0.750000 m/s 2019-04-04T06:30:08.209Z,1554359408.209 [MissionManager](INFO): DefineArg keepstation.Radius = 200.000000 m 2019-04-04T06:30:08.211Z,1554359408.211 [MissionManager](INFO): DefineArg keepstation.MaxDepth = 45.000000 m 2019-04-04T06:30:08.225Z,1554359408.225 [MissionManager](INFO): DefineArg keepstation.MinOffshore = 2.000000 km 2019-04-04T06:30:08.226Z,1554359408.226 [MissionManager](INFO): Inserting Stack: Missions/Insert/NeedComms.xml 2019-04-04T06:30:08.304Z,1554359408.304 [MissionManager](INFO): DefineArg keepstation:NeedComms.DiveInterval = 3.000000 h 2019-04-04T06:30:08.307Z,1554359408.307 [MissionManager](INFO): DefineArg keepstation:NeedComms.WaitForPitchUp = 10.000000 min 2019-04-04T06:30:08.329Z,1554359408.329 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacePitch = 20.000000 arcdeg 2019-04-04T06:30:08.331Z,1554359408.331 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceDepthRate = nan m/s 2019-04-04T06:30:08.334Z,1554359408.334 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceSpeed = 1.000000 m/s 2019-04-04T06:30:08.336Z,1554359408.336 [MissionManager](INFO): DefineArg keepstation:NeedComms.GPSTimeout = 7.000000 min 2019-04-04T06:30:08.338Z,1554359408.338 [MissionManager](INFO): DefineArg keepstation:NeedComms.CommsTimeout = 30.000000 min 2019-04-04T06:30:08.353Z,1554359408.353 [keepstation:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface. 2019-04-04T06:30:08.378Z,1554359408.378 [MissionManager](INFO): Inserting Stack: Missions/Insert/StandardEnvelopes.xml 2019-04-04T06:30:08.433Z,1554359408.433 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinAltitude = 5.000000 m 2019-04-04T06:30:08.436Z,1554359408.436 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepth = 200.000000 m 2019-04-04T06:30:08.438Z,1554359408.438 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinOffshore = 2000.000000 m 2019-04-04T06:30:08.439Z,1554359408.439 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2019-04-04T06:30:08.456Z,1554359408.456 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2019-04-04T06:30:08.479Z,1554359408.479 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2019-04-04T06:30:08.491Z,1554359408.491 [keepstation:F.Pitch](DEBUG): Construct. 2019-04-04T06:30:08.497Z,1554359408.497 [keepstation:TransitToStation:A.Buoyancy](DEBUG): Construct Buoyancy. 2019-04-04T06:30:08.500Z,1554359408.500 [keepstation:TransitToStation:B.Pitch](DEBUG): Construct. 2019-04-04T06:30:08.504Z,1554359408.504 [keepstation:TransitToStation:C.SetSpeed](DEBUG): Construct. 2019-04-04T06:30:08.506Z,1554359408.506 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Construct Waypoint. 2019-04-04T06:30:08.514Z,1554359408.514 [keepstation:KeepStation:B.Pitch](DEBUG): Construct. 2019-04-04T06:30:08.518Z,1554359408.518 [keepstation:KeepStation:C.KeepStation](DEBUG): Construct KeepStation. 2019-04-04T06:30:08.522Z,1554359408.522 [keepstation:KeepStation:D.Wait](DEBUG): Construct Wait. 2019-04-04T06:30:08.536Z,1554359408.536 [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. 2019-04-04T06:30:08.537Z,1554359408.537 [CommandLine](IMPORTANT): Loaded ./Missions/Transport/keepstation.xml 2019-04-04T06:30:18.827Z,1554359418.827 [CommandLine](IMPORTANT): got command set keepstation.MissionTimeout 11.000000 hour 2019-04-04T06:30:18.828Z,1554359418.828 [CommandLine](IMPORTANT): got command set keepstation.Latitude 36.768040 degree 2019-04-04T06:30:18.829Z,1554359418.829 [CommandLine](IMPORTANT): got command set keepstation.Longitude -121.849998 degree 2019-04-04T06:30:18.829Z,1554359418.829 [CommandLine](IMPORTANT): got command set keepstation.Depth 20.000000 meter 2019-04-04T06:30:18.830Z,1554359418.830 [CommandLine](IMPORTANT): got command set keepstation.Speed 1.000000 meter_per_second 2019-04-04T06:30:18.831Z,1554359418.831 [CommandLine](IMPORTANT): got command run 2019-04-04T06:30:18.832Z,1554359418.832 [CommandLine](IMPORTANT): Running 2019-04-04T06:30:18.848Z,1554359418.848 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2019-04-04T06:30:18.848Z,1554359418.848 [RDI_Pathfinder] Communications Fault, FailCount= 1 2019-04-04T06:30:18.848Z,1554359418.848 [RDI_Pathfinder](ERROR): Communications Fault 2019-04-04T06:30:18.856Z,1554359418.856 [MissionManager](IMPORTANT): Started mission keepstation 2019-04-04T06:30:18.856Z,1554359418.856 [keepstation] Running Loop=1 2019-04-04T06:30:18.857Z,1554359418.857 [keepstation](DEBUG): Aggregate::initialize keepstation 2019-04-04T06:30:18.857Z,1554359418.857 [keepstation:StandardEnvelopes] Running Loop=1 2019-04-04T06:30:18.857Z,1554359418.857 [keepstation:StandardEnvelopes](DEBUG): Aggregate::initialize keepstation:StandardEnvelopes 2019-04-04T06:30:18.857Z,1554359418.857 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2019-04-04T06:30:18.857Z,1554359418.857 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2019-04-04T06:30:18.857Z,1554359418.857 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2019-04-04T06:30:18.857Z,1554359418.857 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2019-04-04T06:30:18.858Z,1554359418.858 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2019-04-04T06:30:18.858Z,1554359418.858 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2019-04-04T06:30:18.858Z,1554359418.858 [keepstation:F.Pitch] Running Loop=1 2019-04-04T06:30:18.858Z,1554359418.858 [keepstation:F.Pitch](DEBUG): Initialize. 2019-04-04T06:30:18.858Z,1554359418.858 [keepstation:B] Running Loop=1 2019-04-04T06:30:18.859Z,1554359418.859 [keepstation:F.Pitch] Running Loop=1 2019-04-04T06:30:18.859Z,1554359418.859 [keepstation:StandardEnvelopes] Running Loop=1 2019-04-04T06:30:18.859Z,1554359418.859 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2019-04-04T06:30:18.859Z,1554359418.859 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2019-04-04T06:30:18.860Z,1554359418.860 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2019-04-04T06:30:18.860Z,1554359418.860 [keepstation:B] Stopped 2019-04-04T06:30:18.860Z,1554359418.860 [keepstation:D] Running Loop=1 2019-04-04T06:30:18.989Z,1554359418.989 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2019-04-04T06:30:19.117Z,1554359419.117 [RDI_Pathfinder](INFO): Powering down 2019-04-04T06:30:19.133Z,1554359419.133 [keepstation:D] Stopped 2019-04-04T06:30:19.133Z,1554359419.133 [keepstation:E] Running Loop=1 2019-04-04T06:30:19.439Z,1554359419.439 [keepstation:E] Stopped 2019-04-04T06:30:19.439Z,1554359419.439 [keepstation:StartingMission] Running Loop=1 2019-04-04T06:30:19.439Z,1554359419.439 [keepstation:StartingMission](DEBUG): Aggregate::initialize keepstation:StartingMission 2019-04-04T06:30:19.853Z,1554359419.853 [keepstation:NeedComms] Running Loop=1 2019-04-04T06:30:19.853Z,1554359419.853 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2019-04-04T06:30:19.853Z,1554359419.853 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2019-04-04T06:30:19.853Z,1554359419.853 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-04-04T06:30:19.854Z,1554359419.854 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2019-04-04T06:30:19.854Z,1554359419.854 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2019-04-04T06:30:19.855Z,1554359419.855 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2019-04-04T06:30:19.855Z,1554359419.855 [keepstation:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-04-04T06:30:19.855Z,1554359419.855 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-04-04T06:30:19.856Z,1554359419.856 [keepstation:NeedComms:A] Running Loop=1 2019-04-04T06:30:19.857Z,1554359419.857 [keepstation:NeedComms:A](INFO): last time_fix was: 1554359371.000000 second since 1970/01/01T00:00:00Z 2019-04-04T06:30:19.857Z,1554359419.857 [keepstation:NeedComms:A] Stopped 2019-04-04T06:30:19.957Z,1554359419.957 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2019-04-04T06:30:19.957Z,1554359419.957 [RDI_Pathfinder] No Fault, FailCount= 1 2019-04-04T06:30:20.258Z,1554359420.258 [keepstation:NeedComms:C] Running Loop=1 2019-04-04T06:30:20.659Z,1554359420.659 [keepstation:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix 2019-04-04T06:30:21.853Z,1554359421.853 [NAL9602](DEBUG): Fix Requested 2019-04-04T06:30:22.241Z,1554359422.241 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,063016.00,A,3645.88076,N,12150.22426,W,0.369,338.06,040419,,,D*73 2019-04-04T06:30:22.243Z,1554359422.243 [NAL9602](INFO): GPS fix at 20190404T063016: (36.764679, -121.837071) 2019-04-04T06:30:22.273Z,1554359422.273 [keepstation:NeedComms:C] Stopped 2019-04-04T06:30:22.273Z,1554359422.273 [keepstation:NeedComms:D] Running Loop=1 2019-04-04T06:30:22.686Z,1554359422.686 [keepstation:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications 2019-04-04T06:30:24.129Z,1554359424.129 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T06:30:33.694Z,1554359433.694 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 2019-04-04T06:30:50.204Z,1554359450.204 [NAL9602](INFO): SBD MO Status=1, MOMSN=2705, MT Status=0, MTMSN=0 2019-04-04T06:30:50.258Z,1554359450.258 [NAL9602](INFO): Sent 25 bytes from file Logs/20190404T062936/Courier0000.lzma 2019-04-04T06:30:50.258Z,1554359450.258 [NAL9602](INFO): Packets left to send: 0 2019-04-04T06:30:59.278Z,1554359459.278 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T06:31:02.828Z,1554359462.828 [NAL9602](INFO): SBD MO Status=1, MOMSN=2706, MT Status=0, MTMSN=0 2019-04-04T06:31:02.885Z,1554359462.885 [NAL9602](INFO): Sent 277 bytes from file Logs/20190404T062936/Courier0003.lzma 2019-04-04T06:31:02.885Z,1554359462.885 [NAL9602](INFO): Packets left to send: 0 2019-04-04T06:31:22.296Z,1554359482.296 [NAL9602](INFO): SBD MO Status=1, MOMSN=2707, MT Status=0, MTMSN=0 2019-04-04T06:31:22.353Z,1554359482.353 [NAL9602](INFO): Sent 186 bytes from file Logs/20190404T033102/Express0031.lzma 2019-04-04T06:31:22.353Z,1554359482.353 [NAL9602](INFO): Packets left to send: 0 2019-04-04T06:31:32.499Z,1554359492.499 [NAL9602](INFO): SBD MO Status=1, MOMSN=2708, MT Status=0, MTMSN=0 2019-04-04T06:31:32.557Z,1554359492.557 [NAL9602](INFO): Sent 25 bytes from file Logs/20190404T062936/Express0001.lzma 2019-04-04T06:31:32.557Z,1554359492.557 [NAL9602](INFO): Packets left to send: 0 2019-04-04T06:31:34.469Z,1554359494.469 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T06:31:44.262Z,1554359504.262 [NAL9602](INFO): SBD MO Status=1, MOMSN=2709, MT Status=0, MTMSN=0 2019-04-04T06:31:44.313Z,1554359504.313 [NAL9602](INFO): Sent 332 bytes from file Logs/20190404T062936/Express0004.lzma 2019-04-04T06:31:44.313Z,1554359504.313 [NAL9602](INFO): Packets left to send: 1 2019-04-04T06:32:06.373Z,1554359526.373 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003030 2019-04-04T06:32:06.586Z,1554359526.586 [NAL9602](INFO): SBD MO Status=1, MOMSN=2710, MT Status=0, MTMSN=0 2019-04-04T06:32:06.637Z,1554359526.637 [NAL9602](INFO): Sent 193 bytes from file Logs/20190404T062936/Express0004.lzma 2019-04-04T06:32:06.638Z,1554359526.638 [NAL9602](INFO): Packets left to send: 0 2019-04-04T06:32:20.900Z,1554359540.900 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190404T062936/Courier0006.lzma 2019-04-04T06:32:21.706Z,1554359541.706 [DataOverHttps](INFO): Moved sent file to Logs/20190404T062936/Courier0006.lzma.bak 2019-04-04T06:32:21.707Z,1554359541.707 [DataOverHttps](INFO): SBD MOMSN=10422370 2019-04-04T06:32:37.693Z,1554359557.693 [NAL9602](INFO): Not Powering down - fast GPS 2019-04-04T06:33:42.136Z,1554359622.136 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20190404T062936/Express0007.lzma 2019-04-04T06:33:42.942Z,1554359622.942 [DataOverHttps](INFO): Moved sent file to Logs/20190404T062936/Express0007.lzma.bak 2019-04-04T06:33:42.943Z,1554359622.943 [DataOverHttps](INFO): SBD MOMSN=10422653 2019-04-04T06:33:43.569Z,1554359623.569 [keepstation:NeedComms:D] Stopped 2019-04-04T06:33:43.570Z,1554359623.570 [keepstation:NeedComms:E] Running Loop=1 2019-04-04T06:33:43.971Z,1554359623.971 [keepstation:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix 2019-04-04T06:33:45.169Z,1554359625.169 [NAL9602](DEBUG): Fix Requested 2019-04-04T06:33:45.573Z,1554359625.573 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,063339.00,A,3645.87923,N,12150.23202,W,0.816,245.65,040419,,,D*77 2019-04-04T06:33:45.575Z,1554359625.575 [NAL9602](INFO): GPS fix at 20190404T063339: (36.764654, -121.837200) 2019-04-04T06:33:45.637Z,1554359625.637 [keepstation:NeedComms:E] Stopped 2019-04-04T06:33:45.638Z,1554359625.638 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2019-04-04T06:33:45.638Z,1554359625.638 [keepstation:NeedComms] Stopped 2019-04-04T06:33:45.638Z,1554359625.638 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2019-04-04T06:33:45.638Z,1554359625.638 [keepstation:NeedComms:B.GoToSurface] Stopped 2019-04-04T06:33:45.638Z,1554359625.638 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-04-04T06:33:46.012Z,1554359626.012 [keepstation:StartingMission](INFO): Completed keepstation:StartingMission 2019-04-04T06:33:46.012Z,1554359626.012 [keepstation:StartingMission] Stopped 2019-04-04T06:33:46.013Z,1554359626.013 [keepstation:StartingMission](DEBUG): Aggregate::uninitialize keepstation:StartingMission 2019-04-04T06:33:46.013Z,1554359626.013 [keepstation:TransitToStation] Running Loop=1 2019-04-04T06:33:46.013Z,1554359626.013 [keepstation:TransitToStation](DEBUG): Aggregate::initialize keepstation:TransitToStation 2019-04-04T06:33:46.013Z,1554359626.013 [keepstation:TransitToStation:A.Buoyancy] Running Loop=1 2019-04-04T06:33:46.013Z,1554359626.013 [keepstation:TransitToStation:A.Buoyancy](DEBUG): Initialize Buoyancy Component. 2019-04-04T06:33:46.013Z,1554359626.013 [keepstation:TransitToStation:B.Pitch] Running Loop=1 2019-04-04T06:33:46.014Z,1554359626.014 [keepstation:TransitToStation:B.Pitch](DEBUG): Initialize. 2019-04-04T06:33:46.014Z,1554359626.014 [keepstation:TransitToStation:C.SetSpeed] Running Loop=1 2019-04-04T06:33:46.014Z,1554359626.014 [keepstation:TransitToStation:C.SetSpeed](DEBUG): Initialize. 2019-04-04T06:33:46.014Z,1554359626.014 [keepstation:TransitToStation:Wpt1.Waypoint] Running Loop=1 2019-04-04T06:33:46.014Z,1554359626.014 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent. 2019-04-04T06:33:46.469Z,1554359626.469 [keepstation:TransitToStation:C.SetSpeed] Running Loop=1 2019-04-04T06:33:46.469Z,1554359626.469 [keepstation:TransitToStation:B.Pitch] Running Loop=1 2019-04-04T06:33:46.470Z,1554359626.470 [keepstation:TransitToStation:A.Buoyancy] Running Loop=1 2019-04-04T06:33:58.516Z,1554359638.516 [RDI_Pathfinder](ERROR): only read -1 of 1 data item for altitude 2019-04-04T06:33:58.516Z,1554359638.516 [RDI_Pathfinder](ERROR): Failed to parse: :BD, +0.00, 2019-04-04T06:34:09.813Z,1554359649.813 [NAL9602](INFO): SBD MO Status=0, MOMSN=2711, MT Status=0, MTMSN=0 2019-04-04T06:34:09.813Z,1554359649.813 [NAL9602](INFO): No messages in MT queue 2019-04-04T06:34:40.505Z,1554359680.505 [NAL9602](INFO): Not Powering down - fast GPS 2019-04-04T06:35:59.149Z,1554359759.149 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T06:36:00.361Z,1554359760.361 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.195492 2019-04-04T06:36:17.365Z,1554359777.365 [Radio_Surface](INFO): Powering down 2019-04-04T06:36:33.272Z,1554359793.272 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2019-04-04T06:36:33.272Z,1554359793.272 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 257.95, 262.95, 242.98, 2019-04-04T06:36:34.297Z,1554359794.297 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T06:36:34.701Z,1554359794.701 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2019-04-04T06:37:28.587Z,1554359848.587 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T06:37:28.587Z,1554359848.587 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19040323460185,35.0 0.0,1448.9, 0 2019-04-04T06:39:30.605Z,1554359970.605 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T06:39:30.606Z,1554359970.606 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19040323480385,35.0, -0.1, 0.0,10 2019-04-04T06:46:33.656Z,1554360393.656 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T06:46:33.657Z,1554360393.657 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19040323550685,35.0, -0.1, 09, 0 2019-04-04T06:47:19.656Z,1554360439.656 [RDI_Pathfinder](ERROR): Failed to parse:193, -757, +29,A 2019-04-04T06:50:04.485Z,1554360604.485 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T06:50:04.485Z,1554360604.485 [RDI_Pathfinder](ERROR): Failed to parse: :TS,190403235837850.1, 0.0,1448.9, 0 2019-04-04T06:53:01.549Z,1554360781.549 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout 2019-04-04T06:53:01.549Z,1554360781.549 [RDI_Pathfinder] Communications Fault, FailCount= 1 2019-04-04T06:53:01.549Z,1554360781.549 [RDI_Pathfinder](ERROR): Communications Fault 2019-04-04T06:53:01.549Z,1554360781.549 [RDI_Pathfinder](ERROR): Failed to parse: 2019-04-04T06:53:01.617Z,1554360781.617 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2019-04-04T06:53:01.941Z,1554360781.941 [RDI_Pathfinder](INFO): Powering down 2019-04-04T06:53:02.721Z,1554360782.721 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2019-04-04T06:53:02.721Z,1554360782.721 [RDI_Pathfinder] No Fault, FailCount= 1 2019-04-04T06:53:16.016Z,1554360796.016 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 2019-04-04T06:53:25.713Z,1554360805.713 [RDI_Pathfinder](ERROR): Failed to parse: :BE,-32768,-32768,-32768,V 2019-04-04T06:53:29.336Z,1554360809.336 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T06:53:29.336Z,1554360809.336 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19040400020269,.1, 0.0,1448.9, 0 2019-04-04T06:54:05.327Z,1554360845.327 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2019-04-04T06:54:05.327Z,1554360845.327 [RDI_Pathfinder](ERROR): Failed to parse: :BS,-32768,-32768,-32BE,-32768,-32768,-32768,V 2019-04-04T06:56:32.772Z,1554360992.772 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Reached Waypoint: 36.768039,-121.850001 2019-04-04T06:56:32.772Z,1554360992.772 [keepstation:TransitToStation:Wpt1.Waypoint] Stopped 2019-04-04T06:56:32.772Z,1554360992.772 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent. 2019-04-04T06:56:32.774Z,1554360992.774 [keepstation:TransitToStation](INFO): Completed keepstation:TransitToStation 2019-04-04T06:56:32.774Z,1554360992.774 [keepstation:TransitToStation] Stopped 2019-04-04T06:56:32.774Z,1554360992.774 [keepstation:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:TransitToStation 2019-04-04T06:56:32.774Z,1554360992.774 [keepstation:TransitToStation:A.Buoyancy] Stopped 2019-04-04T06:56:32.774Z,1554360992.774 [keepstation:TransitToStation:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2019-04-04T06:56:32.774Z,1554360992.774 [keepstation:TransitToStation:B.Pitch] Stopped 2019-04-04T06:56:32.774Z,1554360992.774 [keepstation:TransitToStation:C.SetSpeed] Stopped 2019-04-04T06:56:32.774Z,1554360992.774 [keepstation:TransitToStation:C.SetSpeed](DEBUG): Uninitialize. 2019-04-04T06:56:32.774Z,1554360992.774 [keepstation:OnStation] Running Loop=1 2019-04-04T06:56:32.775Z,1554360992.775 [keepstation:OnStation](DEBUG): Aggregate::initialize keepstation:OnStation 2019-04-04T06:56:33.213Z,1554360993.213 [keepstation:NeedComms] Running Loop=1 2019-04-04T06:56:33.213Z,1554360993.213 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2019-04-04T06:56:33.213Z,1554360993.213 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2019-04-04T06:56:33.213Z,1554360993.213 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-04-04T06:56:33.214Z,1554360993.214 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2019-04-04T06:56:33.214Z,1554360993.214 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2019-04-04T06:56:33.214Z,1554360993.214 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2019-04-04T06:56:33.215Z,1554360993.215 [keepstation:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-04-04T06:56:33.215Z,1554360993.215 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-04-04T06:56:33.215Z,1554360993.215 [keepstation:NeedComms:A] Running Loop=1 2019-04-04T06:56:33.221Z,1554360993.221 [keepstation:NeedComms:A](INFO): last time_fix was: 1554359619.000000 second since 1970/01/01T00:00:00Z 2019-04-04T06:56:33.221Z,1554360993.221 [keepstation:NeedComms:A] Stopped 2019-04-04T06:56:34.001Z,1554360994.001 [BuoyancyServo](FAULT): Reading outside of valid range:0.000000 2019-04-04T06:56:34.002Z,1554360994.002 [BuoyancyServo](FAULT): Buoyancy engine reporting null position 2019-04-04T06:56:34.002Z,1554360994.002 [BuoyancyServo] Hardware Fault, FailCount= 1 2019-04-04T06:56:34.002Z,1554360994.002 [BuoyancyServo](ERROR): Hardware Fault 2019-04-04T06:56:34.095Z,1554360994.095 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo 2019-04-04T06:56:36.031Z,1554360996.031 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2019-04-04T06:56:36.031Z,1554360996.031 [BuoyancyServo](INFO): Powering down 2019-04-04T06:56:36.893Z,1554360996.893 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2019-04-04T06:56:36.893Z,1554360996.893 [BuoyancyServo] No Fault, FailCount= 1 2019-04-04T06:56:37.278Z,1554360997.278 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2019-04-04T06:56:37.285Z,1554360997.285 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2019-04-04T06:57:42.784Z,1554361062.784 [keepstation:NeedComms:C] Running Loop=1 2019-04-04T06:57:43.297Z,1554361063.297 [Radio_Surface](INFO): Powering up 2019-04-04T06:57:44.389Z,1554361064.389 [NAL9602](DEBUG): Fix Requested 2019-04-04T06:57:48.950Z,1554361068.950 [DataOverHttps](INFO): Radio surface powered ON. 2019-04-04T06:58:03.524Z,1554361083.524 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2019-04-04T06:58:03.524Z,1554361083.524 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2019-04-04T06:58:03.901Z,1554361083.901 [NAL9602](INFO): SBD MO Status=0, MOMSN=2712, MT Status=0, MTMSN=0 2019-04-04T06:58:03.901Z,1554361083.901 [NAL9602](INFO): No messages in MT queue 2019-04-04T06:58:04.721Z,1554361084.721 [NAL9602](DEBUG): Fix Requested 2019-04-04T06:58:05.115Z,1554361085.115 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,065759.00,A,3645.83621,N,12151.07229,W,0.719,272.47,040419,,,A*75 2019-04-04T06:58:05.118Z,1554361085.118 [NAL9602](INFO): GPS fix at 20190404T065759: (36.763937, -121.851205) 2019-04-04T06:58:05.149Z,1554361085.149 [UniversalFixResidualReporter](INFO): Fix residual: 36.9 %DT, over the last 1311.2 m. Residual distance 483.4 m at bearing 5.3 degrees. Fix at (36.7639, -121.8512) with 1250.0 m made good. 2019-04-04T06:58:05.151Z,1554361085.151 [keepstation:NeedComms:C] Stopped 2019-04-04T06:58:05.151Z,1554361085.151 [keepstation:NeedComms:D] Running Loop=1 2019-04-04T06:58:19.249Z,1554361099.249 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T06:58:21.372Z,1554361101.372 [NAL9602](INFO): SBD MO Status=1, MOMSN=2713, MT Status=0, MTMSN=0 2019-04-04T06:58:21.421Z,1554361101.421 [NAL9602](INFO): Sent 72 bytes from file Logs/20190404T062936/Courier0009.lzma 2019-04-04T06:58:21.421Z,1554361101.421 [NAL9602](INFO): Packets left to send: 0 2019-04-04T06:58:54.397Z,1554361134.397 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T06:58:54.636Z,1554361134.636 [NAL9602](INFO): SBD MO Status=2, MOMSN=2714, MT Status=2, MTMSN=0 2019-04-04T06:58:54.636Z,1554361134.636 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-04-04T06:59:15.761Z,1554361155.761 [NAL9602](INFO): SBD MO Status=1, MOMSN=2714, MT Status=0, MTMSN=0 2019-04-04T06:59:15.809Z,1554361155.809 [NAL9602](INFO): Sent 296 bytes from file Logs/20190404T062936/Express0010.lzma 2019-04-04T06:59:15.809Z,1554361155.809 [NAL9602](INFO): Packets left to send: 0 2019-04-04T06:59:29.554Z,1554361169.554 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T06:59:34.348Z,1554361174.348 [NAL9602](INFO): SBD MO Status=0, MOMSN=2715, MT Status=0, MTMSN=0 2019-04-04T06:59:34.455Z,1554361174.455 [keepstation:NeedComms:D] Stopped 2019-04-04T06:59:34.456Z,1554361174.456 [keepstation:NeedComms:E] Running Loop=1 2019-04-04T06:59:36.385Z,1554361176.385 [NAL9602](DEBUG): Fix Requested 2019-04-04T06:59:36.773Z,1554361176.773 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,065930.00,A,3645.82972,N,12151.07954,W,0.214,182.18,040419,,,A*73 2019-04-04T06:59:36.783Z,1554361176.783 [NAL9602](INFO): GPS fix at 20190404T065930: (36.763829, -121.851326) 2019-04-04T06:59:36.807Z,1554361176.807 [keepstation:NeedComms:E] Stopped 2019-04-04T06:59:36.808Z,1554361176.808 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2019-04-04T06:59:36.808Z,1554361176.808 [keepstation:NeedComms] Stopped 2019-04-04T06:59:36.808Z,1554361176.808 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2019-04-04T06:59:36.808Z,1554361176.808 [keepstation:NeedComms:B.GoToSurface] Stopped 2019-04-04T06:59:36.808Z,1554361176.808 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-04-04T06:59:37.231Z,1554361177.231 [keepstation:OnStation](INFO): Completed keepstation:OnStation 2019-04-04T06:59:37.231Z,1554361177.231 [keepstation:OnStation] Stopped 2019-04-04T06:59:37.231Z,1554361177.231 [keepstation:OnStation](DEBUG): Aggregate::uninitialize keepstation:OnStation 2019-04-04T06:59:37.231Z,1554361177.231 [keepstation:KeepStation] Running Loop=1 2019-04-04T06:59:37.231Z,1554361177.231 [keepstation:KeepStation](DEBUG): Aggregate::initialize keepstation:KeepStation 2019-04-04T06:59:37.231Z,1554361177.231 [keepstation:KeepStation:A] Running Loop=1 2019-04-04T06:59:37.232Z,1554361177.232 [keepstation:KeepStation:B.Pitch] Running Loop=1 2019-04-04T06:59:37.232Z,1554361177.232 [keepstation:KeepStation:B.Pitch](DEBUG): Initialize. 2019-04-04T06:59:37.233Z,1554361177.233 [keepstation:KeepStation:C.KeepStation] Running Loop=1 2019-04-04T06:59:37.233Z,1554361177.233 [keepstation:KeepStation:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2019-04-04T06:59:37.235Z,1554361177.235 [keepstation:KeepStation:D.Wait] Running Loop=1 2019-04-04T06:59:37.235Z,1554361177.235 [keepstation:KeepStation:D.Wait](DEBUG): Initialize Wait Component. 2019-04-04T06:59:37.604Z,1554361177.604 [keepstation:KeepStation:C.KeepStation] Running Loop=1 2019-04-04T06:59:37.621Z,1554361177.621 [keepstation:KeepStation:C.KeepStation](INFO): Rest->Waypoint 2019-04-04T06:59:37.622Z,1554361177.622 [keepstation:KeepStation:B.Pitch] Running Loop=1 2019-04-04T06:59:37.622Z,1554361177.622 [keepstation:KeepStation:A] Running Loop=1 2019-04-04T06:59:53.359Z,1554361193.359 [RDI_Pathfinder](ERROR): Failed to parse: :BE,-32768,-32768,-32768,V 2019-04-04T07:00:04.702Z,1554361204.702 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T07:00:08.680Z,1554361208.680 [NAL9602](INFO): Not Powering down - fast GPS 2019-04-04T07:00:09.515Z,1554361209.515 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2019-04-04T07:00:09.516Z,1554361209.516 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2019-04-04T07:00:39.849Z,1554361239.849 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T07:01:14.997Z,1554361274.997 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T07:01:41.257Z,1554361301.257 [Radio_Surface](INFO): Powering down 2019-04-04T07:01:50.145Z,1554361310.145 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T07:01:50.550Z,1554361310.550 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2019-04-04T07:03:05.337Z,1554361385.337 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2019-04-04T07:03:05.338Z,1554361385.338 [RDI_Pathfinder](ERROR): Failed to parse: :BD, +0.00, +0.00, 88.98 2019-04-04T07:07:22.648Z,1554361642.648 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T07:07:30.324Z,1554361650.324 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T07:07:30.324Z,1554361650.324 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19040400160369,35.0, -.0,1448.9, 0 2019-04-04T07:07:32.358Z,1554361652.358 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T07:07:42.447Z,1554361662.447 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T07:07:52.154Z,1554361672.154 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T07:08:01.832Z,1554361681.832 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T07:08:11.939Z,1554361691.939 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T07:08:19.231Z,1554361699.231 [keepstation:KeepStation:C.KeepStation](INFO): Waypoint->Rest 2019-04-04T07:08:19.629Z,1554361699.629 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -22.00 mm. 2019-04-04T07:08:22.064Z,1554361702.064 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T07:08:32.150Z,1554361712.150 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T07:08:42.252Z,1554361722.252 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T07:08:52.346Z,1554361732.346 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T07:09:02.445Z,1554361742.445 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T07:09:12.150Z,1554361752.150 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T07:09:21.831Z,1554361761.831 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T07:09:31.931Z,1554361771.931 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T07:09:41.628Z,1554361781.628 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T07:11:02.440Z,1554361862.440 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T07:11:02.440Z,1554361862.440 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19040400190, -0.1, 0.0,1448.9, 0 2019-04-04T07:12:17.572Z,1554361937.572 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T07:12:17.596Z,1554361937.596 [BPC1](INFO): Calculating totals. Valid battery stick count: 52. Valid reserve battery stick count: 4. 2019-04-04T07:12:17.599Z,1554361937.599 [BPC1](INFO): Received data from all battery sticks. 2019-04-04T07:20:02.186Z,1554362402.186 [RDI_Pathfinder](ERROR): only read 0 of 4 data items 2019-04-04T07:20:02.186Z,1554362402.186 [RDI_Pathfinder](ERROR): Failed to parse: :RA233.00 2019-04-04T07:20:14.381Z,1554362414.381 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout 2019-04-04T07:20:14.381Z,1554362414.381 [RDI_Pathfinder] Communications Fault, FailCount= 1 2019-04-04T07:20:14.381Z,1554362414.381 [RDI_Pathfinder](ERROR): Communications Fault 2019-04-04T07:20:14.381Z,1554362414.381 [RDI_Pathfinder](ERROR): Failed to parse: 2019-04-04T07:20:14.408Z,1554362414.408 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2019-04-04T07:20:14.769Z,1554362414.769 [RDI_Pathfinder](INFO): Powering down 2019-04-04T07:20:15.523Z,1554362415.523 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2019-04-04T07:20:15.523Z,1554362415.523 [RDI_Pathfinder] No Fault, FailCount= 1 2019-04-04T07:20:28.854Z,1554362428.854 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 2019-04-04T07:24:45.811Z,1554362685.811 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2019-04-04T07:24:45.811Z,1554362685.811 [RDI_Pathfinder](ERROR): Failed to parse: :BS,32768,-32768,V 2019-04-04T07:26:29.226Z,1554362789.226 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2019-04-04T07:26:29.226Z,1554362789.226 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00 2019-04-04T07:27:06.816Z,1554362826.816 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2019-04-04T07:27:06.816Z,1554362826.816 [RDI_Pathfinder](ERROR): Failed to parse: :BD, +0.00, , +0.00, 0.00,397.99 2019-04-04T07:27:49.212Z,1554362869.212 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2019-04-04T07:27:49.212Z,1554362869.212 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2019-04-04T07:30:02.526Z,1554363002.526 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00, 0.00 2019-04-04T07:31:09.198Z,1554363069.198 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00, 0.00 2019-04-04T07:33:14.031Z,1554363194.031 [RDI_Pathfinder](ERROR): Failed to parse: : +0.23, +0.41, -0.17, 0.00,762.97 2019-04-04T07:36:25.514Z,1554363385.514 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00, 0.00 2019-04-04T07:38:48.121Z,1554363528.121 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T07:38:48.122Z,1554363528.122 [RDI_Pathfinder](ERROR): Failed to parse: :TS2.08, -0.51, 0.00, 2.00 2019-04-04T07:39:28.138Z,1554363568.138 [RDI_Pathfinder](ERROR): Failed to parse: :SA, +0.00, +0.00, 0.00 2019-04-04T07:40:35.187Z,1554363635.187 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2019-04-04T07:40:35.187Z,1554363635.187 [RDI_Pathfinder](ERROR): Failed to parse: :BS,-32768,-3278,V 2019-04-04T07:41:04.284Z,1554363664.284 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T07:41:04.284Z,1554363664.284 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19040400493754,35.0, 0.0,1448.9, 0 2019-04-04T07:41:08.327Z,1554363668.327 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T07:41:08.327Z,1554363668.327 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19040400494154,35.0, -0.,1448.9, 0 2019-04-04T07:43:54.363Z,1554363834.363 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2019-04-04T07:43:54.363Z,1554363834.363 [RDI_Pathfinder](ERROR): Failed to parse: :BS, +92, +49,A 2019-04-04T07:44:56.173Z,1554363896.173 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T07:44:56.173Z,1554363896.173 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19040400532954,35.0, -0.1, 0.0,144 2019-04-04T07:45:37.817Z,1554363937.817 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T07:45:37.817Z,1554363937.817 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19040400541154,35.0, -0.1, V 2019-04-04T07:54:37.144Z,1554364477.144 [keepstation:NeedComms] Running Loop=1 2019-04-04T07:54:37.145Z,1554364477.145 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2019-04-04T07:54:37.145Z,1554364477.145 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2019-04-04T07:54:37.145Z,1554364477.145 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-04-04T07:54:37.145Z,1554364477.145 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2019-04-04T07:54:37.146Z,1554364477.146 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2019-04-04T07:54:37.146Z,1554364477.146 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2019-04-04T07:54:37.147Z,1554364477.147 [keepstation:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-04-04T07:54:37.147Z,1554364477.147 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-04-04T07:54:37.147Z,1554364477.147 [keepstation:NeedComms:A] Running Loop=1 2019-04-04T07:54:37.149Z,1554364477.149 [keepstation:NeedComms:A](INFO): last time_fix was: 1554361170.000000 second since 1970/01/01T00:00:00Z 2019-04-04T07:54:37.149Z,1554364477.149 [keepstation:NeedComms:A] Stopped 2019-04-04T07:54:51.824Z,1554364491.824 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2019-04-04T07:54:51.824Z,1554364491.824 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2019-04-04T07:54:59.058Z,1554364499.058 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2019-04-04T07:54:59.059Z,1554364499.059 [RDI_Pathfinder](ERROR): Failed to parse: :BS,-32768,-32768,8 2019-04-04T07:55:34.073Z,1554364534.073 [RDI_Pathfinder](ERROR): Failed to parse: :BI,-32768,-32768,-32768,-32768,V 2019-04-04T07:55:41.766Z,1554364541.766 [keepstation:NeedComms:C] Running Loop=1 2019-04-04T07:55:42.321Z,1554364542.321 [Radio_Surface](INFO): Powering up 2019-04-04T07:55:43.361Z,1554364543.361 [NAL9602](DEBUG): Fix Requested 2019-04-04T07:55:47.585Z,1554364547.585 [DataOverHttps](INFO): Radio surface powered ON. 2019-04-04T07:55:53.977Z,1554364553.977 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2019-04-04T07:55:53.981Z,1554364553.981 [RDI_Pathfinder](ERROR): Failed to parse: :BS,-32768,-3768,V 2019-04-04T07:56:16.152Z,1554364576.152 [NAL9602](INFO): SBD MO Status=0, MOMSN=2716, MT Status=0, MTMSN=0 2019-04-04T07:56:16.152Z,1554364576.152 [NAL9602](INFO): No messages in MT queue 2019-04-04T07:56:16.973Z,1554364576.973 [NAL9602](DEBUG): Fix Requested 2019-04-04T07:56:17.361Z,1554364577.361 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,075611.00,A,3645.86362,N,12151.20486,W,0.350,283.12,040419,,,A*7F 2019-04-04T07:56:17.363Z,1554364577.363 [NAL9602](INFO): GPS fix at 20190404T075611: (36.764394, -121.853414) 2019-04-04T07:56:17.394Z,1554364577.394 [UniversalFixResidualReporter](INFO): Fix residual: 78.1 %DT, over the last 695.2 m. Residual distance 542.7 m at bearing 35.1 degrees. Fix at (36.7644, -121.8534) with 196.4 m made good. 2019-04-04T07:56:17.395Z,1554364577.395 [keepstation:KeepStation:C.KeepStation](INFO): Rest->Waypoint 2019-04-04T07:56:17.397Z,1554364577.397 [keepstation:NeedComms:C] Stopped 2019-04-04T07:56:17.398Z,1554364577.398 [keepstation:NeedComms:D] Running Loop=1 2019-04-04T07:56:17.909Z,1554364577.909 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T07:56:18.983Z,1554364578.983 [NAL9602](ERROR): parseGSV uart error: serial buffer full 2019-04-04T07:56:37.000Z,1554364597.000 [NAL9602](INFO): SBD MO Status=1, MOMSN=2717, MT Status=0, MTMSN=0 2019-04-04T07:56:37.061Z,1554364597.061 [NAL9602](INFO): Sent 230 bytes from file Logs/20190404T062936/Courier0012.lzma 2019-04-04T07:56:37.061Z,1554364597.061 [NAL9602](INFO): Packets left to send: 0 2019-04-04T07:56:53.057Z,1554364613.057 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T07:56:55.685Z,1554364615.685 [NAL9602](INFO): SBD MO Status=1, MOMSN=2718, MT Status=0, MTMSN=0 2019-04-04T07:56:55.733Z,1554364615.733 [NAL9602](INFO): Sent 149 bytes from file Logs/20190404T062936/Express0013.lzma 2019-04-04T07:56:55.733Z,1554364615.733 [NAL9602](INFO): Packets left to send: 0 2019-04-04T07:57:04.576Z,1554364624.576 [NAL9602](INFO): SBD MO Status=0, MOMSN=2719, MT Status=0, MTMSN=0 2019-04-04T07:57:04.667Z,1554364624.667 [keepstation:NeedComms:D] Stopped 2019-04-04T07:57:04.667Z,1554364624.667 [keepstation:NeedComms:E] Running Loop=1 2019-04-04T07:57:06.609Z,1554364626.609 [NAL9602](DEBUG): Fix Requested 2019-04-04T07:57:06.997Z,1554364626.997 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,075700.00,A,3645.86278,N,12151.20751,W,0.156,317.46,040419,,,A*74 2019-04-04T07:57:06.999Z,1554364626.999 [NAL9602](INFO): GPS fix at 20190404T075700: (36.764380, -121.853459) 2019-04-04T07:57:07.058Z,1554364627.058 [keepstation:NeedComms:E] Stopped 2019-04-04T07:57:07.058Z,1554364627.058 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2019-04-04T07:57:07.058Z,1554364627.058 [keepstation:NeedComms] Stopped 2019-04-04T07:57:07.058Z,1554364627.058 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2019-04-04T07:57:07.059Z,1554364627.059 [keepstation:NeedComms:B.GoToSurface] Stopped 2019-04-04T07:57:07.059Z,1554364627.059 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-04-04T07:57:09.023Z,1554364629.023 [NAL9602](ERROR): parseGSV uart error: serial buffer full 2019-04-04T07:57:28.205Z,1554364648.205 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T07:57:39.450Z,1554364659.450 [NAL9602](INFO): Not Powering down - fast GPS 2019-04-04T07:58:03.381Z,1554364683.381 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T07:58:33.738Z,1554364713.738 [Radio_Surface](INFO): Powering down 2019-04-04T07:58:38.583Z,1554364718.583 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T07:58:38.986Z,1554364718.986 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2019-04-04T07:58:52.189Z,1554364732.189 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2019-04-04T07:58:52.189Z,1554364732.189 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2019-04-04T07:59:54.832Z,1554364794.832 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2019-04-04T07:59:54.832Z,1554364794.832 [RDI_Pathfinder](ERROR): Failed to parse: :BD, +12.65, +3.19, , 0.00,233.00 2019-04-04T08:00:10.160Z,1554364810.160 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2019-04-04T08:00:10.160Z,1554364810.160 [RDI_Pathfinder](ERROR): Failed to parse: :BD, +1248.00 2019-04-04T08:00:51.805Z,1554364851.805 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2019-04-04T08:00:51.805Z,1554364851.805 [RDI_Pathfinder](ERROR): Failed to parse: :BD, +12.65 +3.19, -0.38, 0.00,290.00 2019-04-04T08:02:08.156Z,1554364928.156 [RDI_Pathfinder](ERROR): Failed to parse: :SA, +0.00, +0.00, 0.00 2019-04-04T08:02:12.163Z,1554364932.163 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2019-04-04T08:02:12.163Z,1554364932.163 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2019-04-04T08:02:52.166Z,1554364972.166 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T08:02:52.166Z,1554364972.166 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19040401112554,35.0, -0.1, 09, 0 2019-04-04T08:03:32.169Z,1554365012.169 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T08:03:32.169Z,1554365012.169 [RDI_Pathfinder](ERROR): Failed to parse: :TS,190404015.0, -0.1, 0.0,1448.9, 0 2019-04-04T08:03:53.171Z,1554365033.171 [RDI_Pathfinder](ERROR): Failed to parse: :BE,-32768,-32768,-32768,V 2019-04-04T08:04:29.530Z,1554365069.530 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2019-04-04T08:04:29.530Z,1554365069.530 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2019-04-04T08:06:20.633Z,1554365180.633 [keepstation:KeepStation:C.KeepStation](INFO): Waypoint->Rest 2019-04-04T08:06:21.044Z,1554365181.044 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position 6.00 mm. 2019-04-04T08:09:09.993Z,1554365349.993 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout 2019-04-04T08:09:09.993Z,1554365349.993 [RDI_Pathfinder] Communications Fault, FailCount= 1 2019-04-04T08:09:09.993Z,1554365349.993 [RDI_Pathfinder](ERROR): Communications Fault 2019-04-04T08:09:09.994Z,1554365349.994 [RDI_Pathfinder](ERROR): Failed to parse: 2019-04-04T08:09:10.104Z,1554365350.104 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2019-04-04T08:09:10.369Z,1554365350.369 [RDI_Pathfinder](INFO): Powering down 2019-04-04T08:09:11.209Z,1554365351.209 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2019-04-04T08:09:11.210Z,1554365351.210 [RDI_Pathfinder] No Fault, FailCount= 1 2019-04-04T08:09:24.455Z,1554365364.455 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 2019-04-04T08:11:54.743Z,1554365514.743 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 331.49, 0.00, 317.51, 325.50 2019-04-04T08:14:46.083Z,1554365686.083 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-04-04T08:14:48.861Z,1554365688.861 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2019-04-04T08:14:48.861Z,1554365688.861 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2019-04-04T08:14:50.905Z,1554365690.905 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T08:15:00.574Z,1554365700.574 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T08:15:10.670Z,1554365710.670 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T08:15:20.807Z,1554365720.807 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T08:15:30.884Z,1554365730.884 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T08:15:40.981Z,1554365740.981 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T08:15:51.084Z,1554365751.084 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T08:16:00.809Z,1554365760.809 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T08:16:08.849Z,1554365768.849 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T08:16:08.849Z,1554365768.849 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19040401244216,35.0, -0.1, 0.0,10 2019-04-04T08:16:10.460Z,1554365770.460 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T08:16:20.155Z,1554365780.155 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T08:16:30.262Z,1554365790.262 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T08:16:40.362Z,1554365800.362 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T08:16:50.054Z,1554365810.054 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T08:16:59.764Z,1554365819.764 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T08:17:09.858Z,1554365829.858 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T08:17:22.789Z,1554365842.789 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00, 0.00 2019-04-04T08:19:44.986Z,1554365984.986 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T08:19:45.015Z,1554365985.015 [BPC1](INFO): Calculating totals. Valid battery stick count: 52. Valid reserve battery stick count: 4. 2019-04-04T08:19:45.054Z,1554365985.054 [BPC1](INFO): Received data from all battery sticks. 2019-04-04T08:20:40.763Z,1554366040.763 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2019-04-04T08:20:40.763Z,1554366040.763 [RDI_Pathfinder](ERROR): Failed to parse: :BS,-3268,-32768,V 2019-04-04T08:21:08.634Z,1554366068.634 [RDI_Pathfinder](ERROR): Failed to parse: : +12.02, -4.31, +0.96, 0.00,512.99 2019-04-04T08:24:04.765Z,1554366244.765 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T08:24:04.765Z,1554366244.765 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19040401323816,35, 0.0,1448.9, 0 2019-04-04T08:27:05.753Z,1554366425.753 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00, 0.00 2019-04-04T08:40:11.133Z,1554367211.133 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T08:40:11.133Z,1554367211.133 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19040401484416,35.0, -0.1, 0.0,1448.9,, 0.00, 0.00, 0.00, 0.00, 0.00 2019-04-04T08:40:14.771Z,1554367214.771 [RDI_Pathfinder](ERROR): Failed to parse: :BE,-32768,-32768,-32768,V 2019-04-04T08:47:59.774Z,1554367679.774 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2019-04-04T08:47:59.775Z,1554367679.775 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2019-04-04T08:50:11.881Z,1554367811.881 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 319.01, 0.00, 310.02, 308.53 2019-04-04T08:52:07.031Z,1554367927.031 [keepstation:NeedComms] Running Loop=1 2019-04-04T08:52:07.031Z,1554367927.031 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2019-04-04T08:52:07.031Z,1554367927.031 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2019-04-04T08:52:07.031Z,1554367927.031 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-04-04T08:52:07.032Z,1554367927.032 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2019-04-04T08:52:07.032Z,1554367927.032 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2019-04-04T08:52:07.033Z,1554367927.033 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2019-04-04T08:52:07.033Z,1554367927.033 [keepstation:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-04-04T08:52:07.034Z,1554367927.034 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-04-04T08:52:07.034Z,1554367927.034 [keepstation:NeedComms:A] Running Loop=1 2019-04-04T08:52:07.035Z,1554367927.035 [keepstation:NeedComms:A](INFO): last time_fix was: 1554364620.000000 second since 1970/01/01T00:00:00Z 2019-04-04T08:52:07.036Z,1554367927.036 [keepstation:NeedComms:A] Stopped 2019-04-04T08:52:29.776Z,1554367949.776 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T08:52:29.776Z,1554367949.776 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19040402010316,35.0, -0.1, 0., 0 2019-04-04T08:52:31.790Z,1554367951.790 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T08:52:31.790Z,1554367951.790 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19040402010516,35.0, -0.1, 0., 0 2019-04-04T08:52:45.116Z,1554367965.116 [RDI_Pathfinder](ERROR): only read 0 of 4 data items 2019-04-04T08:52:45.117Z,1554367965.117 [RDI_Pathfinder](ERROR): Failed to parse: :RA 0.00, 0.00, 0.00, 0.00 2019-04-04T08:53:32.367Z,1554368012.367 [keepstation:NeedComms:C] Running Loop=1 2019-04-04T08:53:32.897Z,1554368012.897 [Radio_Surface](INFO): Powering up 2019-04-04T08:53:33.957Z,1554368013.957 [NAL9602](DEBUG): Fix Requested 2019-04-04T08:53:38.157Z,1554368018.157 [DataOverHttps](INFO): Radio surface powered ON. 2019-04-04T08:54:16.478Z,1554368056.478 [NAL9602](INFO): SBD MO Status=2, MOMSN=2720, MT Status=2, MTMSN=0 2019-04-04T08:54:16.478Z,1554368056.478 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-04-04T08:54:40.395Z,1554368080.395 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2019-04-04T08:54:40.396Z,1554368080.396 [RDI_Pathfinder](ERROR): Failed to parse: :BD, +12. -6.51, -3.00, 0.00,142.00 2019-04-04T08:55:27.616Z,1554368127.616 [RDI_Pathfinder](ERROR): Failed to parse: +12.99, -6.51, -3.00, 0.00,188.00 2019-04-04T08:55:45.358Z,1554368145.358 [NAL9602](INFO): SBD MO Status=0, MOMSN=2720, MT Status=0, MTMSN=0 2019-04-04T08:55:45.358Z,1554368145.358 [NAL9602](INFO): No messages in MT queue 2019-04-04T08:55:46.173Z,1554368146.173 [NAL9602](DEBUG): Fix Requested 2019-04-04T08:55:46.571Z,1554368146.571 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,085540.00,A,3645.90550,N,12151.07588,W,0.486,135.80,040419,,,A*74 2019-04-04T08:55:46.573Z,1554368146.573 [NAL9602](INFO): GPS fix at 20190404T085540: (36.765092, -121.851265) 2019-04-04T08:55:46.609Z,1554368146.609 [UniversalFixResidualReporter](INFO): Fix residual: 62.2 %DT, over the last 672.5 m. Residual distance 418.1 m at bearing 11.1 degrees. Fix at (36.7651, -121.8513) with 210.9 m made good. 2019-04-04T08:55:46.610Z,1554368146.610 [keepstation:KeepStation:C.KeepStation](INFO): Rest->Waypoint 2019-04-04T08:55:46.613Z,1554368146.613 [keepstation:NeedComms:C] Stopped 2019-04-04T08:55:46.613Z,1554368146.613 [keepstation:NeedComms:D] Running Loop=1 2019-04-04T08:56:05.308Z,1554368165.308 [NAL9602](INFO): SBD MO Status=1, MOMSN=2721, MT Status=0, MTMSN=0 2019-04-04T08:56:05.357Z,1554368165.357 [NAL9602](INFO): Sent 87 bytes from file Logs/20190404T062936/Courier0015.lzma 2019-04-04T08:56:05.357Z,1554368165.357 [NAL9602](INFO): Packets left to send: 0 2019-04-04T08:56:16.696Z,1554368176.696 [NAL9602](INFO): SBD MO Status=1, MOMSN=2722, MT Status=0, MTMSN=0 2019-04-04T08:56:16.745Z,1554368176.745 [NAL9602](INFO): Sent 135 bytes from file Logs/20190404T062936/Express0016.lzma 2019-04-04T08:56:16.745Z,1554368176.745 [NAL9602](INFO): Packets left to send: 0 2019-04-04T08:56:19.366Z,1554368179.366 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T08:56:34.876Z,1554368194.876 [NAL9602](INFO): SBD MO Status=0, MOMSN=2723, MT Status=0, MTMSN=0 2019-04-04T08:56:34.984Z,1554368194.984 [keepstation:NeedComms:D] Stopped 2019-04-04T08:56:34.986Z,1554368194.986 [keepstation:NeedComms:E] Running Loop=1 2019-04-04T08:56:36.905Z,1554368196.905 [NAL9602](DEBUG): Fix Requested 2019-04-04T08:56:37.297Z,1554368197.297 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,085631.00,A,3645.90581,N,12151.07545,W,1.050,22.82,040419,,,A*47 2019-04-04T08:56:37.299Z,1554368197.299 [NAL9602](INFO): GPS fix at 20190404T085631: (36.765097, -121.851258) 2019-04-04T08:56:37.331Z,1554368197.331 [keepstation:NeedComms:E] Stopped 2019-04-04T08:56:37.332Z,1554368197.332 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2019-04-04T08:56:37.332Z,1554368197.332 [keepstation:NeedComms] Stopped 2019-04-04T08:56:37.332Z,1554368197.332 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2019-04-04T08:56:37.332Z,1554368197.332 [keepstation:NeedComms:B.GoToSurface] Stopped 2019-04-04T08:56:37.332Z,1554368197.332 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-04-04T08:56:54.513Z,1554368214.513 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T08:57:05.513Z,1554368225.513 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003101 2019-04-04T08:57:09.612Z,1554368229.612 [NAL9602](INFO): Not Powering down - fast GPS 2019-04-04T08:58:04.897Z,1554368284.897 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T08:58:05.707Z,1554368285.707 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.196396 2019-04-04T08:58:14.081Z,1554368294.081 [Radio_Surface](INFO): Powering down 2019-04-04T08:58:38.985Z,1554368318.985 [CBIT](IMPORTANT): Beginning ground fault scan 2019-04-04T08:58:40.045Z,1554368320.045 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T08:58:40.449Z,1554368320.449 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2019-04-04T08:58:49.872Z,1554368329.872 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.118316 CHAN A1 (24V): -0.026874 CHAN A2 (12V): -0.007221 CHAN A3 (5V): -0.002056 CHAN B0 (3.3V): 0.000641 CHAN B1 (3.15aV): -0.000311 CHAN B2 (3.15bV): 0.000579 CHAN B3 (GND): 0.002163 OPEN: 0.005854 Full Scale Calc: 4.765 mA, -1.589 mA 2019-04-04T09:02:26.783Z,1554368546.783 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T09:02:26.783Z,1554368546.783 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19040402110016,35.0, -0.1, 0., 0 2019-04-04T09:05:27.765Z,1554368727.765 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2019-04-04T09:05:27.765Z,1554368727.765 [RDI_Pathfinder](ERROR): Failed to parse: :BS,-2768,-32768,V 2019-04-04T09:05:45.149Z,1554368745.149 [keepstation:KeepStation:C.KeepStation](INFO): Waypoint->Rest 2019-04-04T09:05:45.556Z,1554368745.556 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 1.00 m/s, pitch 20.00 deg, mass-position 6.00 mm. 2019-04-04T09:19:31.752Z,1554369571.752 [RDI_Pathfinder](ERROR): Failed to parse: :BE,-32768,-32768,-32768,V 2019-04-04T09:22:18.170Z,1554369738.170 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T09:22:27.871Z,1554369747.871 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T09:22:37.962Z,1554369757.962 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T09:22:48.059Z,1554369768.059 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T09:22:58.166Z,1554369778.166 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T09:23:07.862Z,1554369787.862 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T09:23:17.962Z,1554369797.962 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T09:23:18.389Z,1554369798.389 [RDI_Pathfinder](ERROR): only read 1 of 4 data items 2019-04-04T09:23:18.389Z,1554369798.389 [RDI_Pathfinder](ERROR): Failed to parse: :BS,-32768-32768,V 2019-04-04T09:23:28.062Z,1554369808.062 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T09:23:37.756Z,1554369817.756 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T09:23:47.460Z,1554369827.460 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T09:23:57.150Z,1554369837.150 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T09:24:06.438Z,1554369846.438 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T09:24:16.535Z,1554369856.535 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T09:24:26.232Z,1554369866.232 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T09:24:36.338Z,1554369876.338 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T09:27:13.094Z,1554370033.094 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T09:27:13.099Z,1554370033.099 [BPC1](INFO): Calculating totals. Valid battery stick count: 52. Valid reserve battery stick count: 4. 2019-04-04T09:27:13.101Z,1554370033.101 [BPC1](INFO): Received data from all battery sticks. 2019-04-04T09:31:01.758Z,1554370261.758 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2019-04-04T09:31:01.758Z,1554370261.758 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2019-04-04T09:32:18.115Z,1554370338.115 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2019-04-04T09:32:18.115Z,1554370338.115 [RDI_Pathfinder](ERROR): Failed to parse: :BS,-32768,-3768,V 2019-04-04T09:32:22.560Z,1554370342.560 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2019-04-04T09:32:22.561Z,1554370342.561 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 046.96 2019-04-04T09:37:00.533Z,1554370620.533 [RDI_Pathfinder](ERROR): Failed to parse: :BE,-32768,-32768,-32768,V 2019-04-04T09:39:17.496Z,1554370757.496 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2019-04-04T09:39:17.496Z,1554370757.496 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 306.20, 287.57, 295.55 2019-04-04T09:40:39.506Z,1554370839.506 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2019-04-04T09:40:39.506Z,1554370839.506 [RDI_Pathfinder](ERROR): Failed to parse: :BD, +6.58, + -3.10, 28.99, 0.00 2019-04-04T09:44:01.893Z,1554371041.893 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2019-04-04T09:44:01.893Z,1554371041.893 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 299.54, 305.02 2019-04-04T09:46:20.876Z,1554371180.876 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T09:46:20.876Z,1554371180.876 [RDI_Pathfinder](ERROR): Failed to parse: :TS,190404025454160.1, 0.0,1448.9, 0 2019-04-04T09:51:00.456Z,1554371460.456 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2019-04-04T09:51:00.456Z,1554371460.456 [RDI_Pathfinder](ERROR): Failed to parse: :BD, +21.02, +6.21, , 0.00, 14.00 2019-04-04T09:51:37.607Z,1554371497.607 [keepstation:NeedComms] Running Loop=1 2019-04-04T09:51:37.607Z,1554371497.607 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2019-04-04T09:51:37.608Z,1554371497.608 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2019-04-04T09:51:37.608Z,1554371497.608 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-04-04T09:51:37.608Z,1554371497.608 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2019-04-04T09:51:37.608Z,1554371497.608 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2019-04-04T09:51:37.609Z,1554371497.609 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2019-04-04T09:51:37.609Z,1554371497.609 [keepstation:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-04-04T09:51:37.610Z,1554371497.610 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-04-04T09:51:37.610Z,1554371497.610 [keepstation:NeedComms:A] Running Loop=1 2019-04-04T09:51:37.612Z,1554371497.612 [keepstation:NeedComms:A](INFO): last time_fix was: 1554368191.000000 second since 1970/01/01T00:00:00Z 2019-04-04T09:51:37.612Z,1554371497.612 [keepstation:NeedComms:A] Stopped 2019-04-04T09:52:14.516Z,1554371534.516 [keepstation:KeepStation:C.KeepStation](INFO): Rest->Waypoint 2019-04-04T09:52:43.936Z,1554371563.936 [keepstation:NeedComms:C] Running Loop=1 2019-04-04T09:52:44.509Z,1554371564.509 [Radio_Surface](INFO): Powering up 2019-04-04T09:52:45.561Z,1554371565.561 [NAL9602](DEBUG): Fix Requested 2019-04-04T09:52:49.821Z,1554371569.821 [DataOverHttps](INFO): Radio surface powered ON. 2019-04-04T09:53:04.698Z,1554371584.698 [NAL9602](INFO): SBD MO Status=0, MOMSN=2724, MT Status=0, MTMSN=0 2019-04-04T09:53:04.698Z,1554371584.698 [NAL9602](INFO): No messages in MT queue 2019-04-04T09:53:05.513Z,1554371585.513 [NAL9602](DEBUG): Fix Requested 2019-04-04T09:53:05.559Z,1554371585.559 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2019-04-04T09:53:05.559Z,1554371585.559 [RDI_Pathfinder](ERROR): Failed to parse: :BS,-32768,-32768,- 2019-04-04T09:53:05.902Z,1554371585.902 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,095259.00,A,3646.01615,N,12151.00396,W,0.486,311.39,040419,,,A*7B 2019-04-04T09:53:05.905Z,1554371585.905 [NAL9602](INFO): GPS fix at 20190404T095259: (36.766936, -121.850066) 2019-04-04T09:53:05.930Z,1554371585.930 [UniversalFixResidualReporter](INFO): Fix residual: 47.5 %DT, over the last 672.4 m. Residual distance 319.4 m at bearing 9.7 degrees. Fix at (36.7669, -121.8501) with 230.4 m made good. 2019-04-04T09:53:05.934Z,1554371585.934 [keepstation:NeedComms:C] Stopped 2019-04-04T09:53:05.934Z,1554371585.934 [keepstation:NeedComms:D] Running Loop=1 2019-04-04T09:53:07.127Z,1554371587.127 [NAL9602](ERROR): parseGSV uart error: serial buffer full 2019-04-04T09:53:20.177Z,1554371600.177 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T09:53:21.489Z,1554371601.489 [NAL9602](INFO): SBD MO Status=1, MOMSN=2725, MT Status=0, MTMSN=0 2019-04-04T09:53:21.537Z,1554371601.537 [NAL9602](INFO): Sent 88 bytes from file Logs/20190404T062936/Courier0018.lzma 2019-04-04T09:53:21.537Z,1554371601.537 [NAL9602](INFO): Packets left to send: 0 2019-04-04T09:53:40.250Z,1554371620.250 [NAL9602](INFO): SBD MO Status=1, MOMSN=2726, MT Status=0, MTMSN=0 2019-04-04T09:53:40.297Z,1554371620.297 [NAL9602](INFO): Sent 332 bytes from file Logs/20190404T062936/Express0019.lzma 2019-04-04T09:53:40.297Z,1554371620.297 [NAL9602](INFO): Packets left to send: 1 2019-04-04T09:53:44.441Z,1554371624.441 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2019-04-04T09:53:44.442Z,1554371624.442 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2019-04-04T09:53:55.325Z,1554371635.325 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T09:54:30.545Z,1554371670.545 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T09:54:43.404Z,1554371683.404 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2019-04-04T09:54:43.405Z,1554371683.405 [RDI_Pathfinder](ERROR): Failed to parse: :BD, +21.09, +6.2 -0.53, 0.00,183.00 2019-04-04T09:54:46.188Z,1554371686.188 [NAL9602](INFO): SBD MO Status=2, MOMSN=2727, MT Status=2, MTMSN=0 2019-04-04T09:54:46.189Z,1554371686.189 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-04-04T09:54:56.480Z,1554371696.480 [NAL9602](INFO): SBD MO Status=1, MOMSN=2727, MT Status=0, MTMSN=0 2019-04-04T09:54:56.529Z,1554371696.529 [NAL9602](INFO): Sent 41 bytes from file Logs/20190404T062936/Express0019.lzma 2019-04-04T09:54:56.529Z,1554371696.529 [NAL9602](INFO): Packets left to send: 0 2019-04-04T09:55:04.961Z,1554371704.961 [NAL9602](INFO): SBD MO Status=0, MOMSN=2728, MT Status=0, MTMSN=0 2019-04-04T09:55:05.071Z,1554371705.071 [keepstation:NeedComms:D] Stopped 2019-04-04T09:55:05.072Z,1554371705.072 [keepstation:NeedComms:E] Running Loop=1 2019-04-04T09:55:05.713Z,1554371705.713 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T09:55:06.997Z,1554371706.997 [NAL9602](DEBUG): Fix Requested 2019-04-04T09:55:07.385Z,1554371707.385 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,095501.00,A,3646.02138,N,12150.99998,W,0.583,75.67,040419,,,A*41 2019-04-04T09:55:07.387Z,1554371707.387 [NAL9602](INFO): GPS fix at 20190404T095501: (36.767023, -121.850000) 2019-04-04T09:55:07.419Z,1554371707.419 [keepstation:NeedComms:E] Stopped 2019-04-04T09:55:07.420Z,1554371707.420 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2019-04-04T09:55:07.420Z,1554371707.420 [keepstation:NeedComms] Stopped 2019-04-04T09:55:07.420Z,1554371707.420 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2019-04-04T09:55:07.420Z,1554371707.420 [keepstation:NeedComms:B.GoToSurface] Stopped 2019-04-04T09:55:07.420Z,1554371707.420 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-04-04T09:55:09.010Z,1554371709.010 [NAL9602](ERROR): parseGSV uart error: serial buffer full 2019-04-04T09:55:17.094Z,1554371717.094 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T09:55:17.094Z,1554371717.094 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19040403035016,35.0, -0.1,448.9, 0 2019-04-04T09:55:39.700Z,1554371739.700 [NAL9602](INFO): Not Powering down - fast GPS 2019-04-04T09:55:40.861Z,1554371740.861 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T09:56:09.233Z,1554371769.233 [keepstation:KeepStation:C.KeepStation](INFO): Waypoint->Rest 2019-04-04T09:56:15.838Z,1554371775.838 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2019-04-04T09:56:26.837Z,1554371786.837 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003145 2019-04-04T09:58:57.801Z,1554371937.801 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout 2019-04-04T09:58:57.801Z,1554371937.801 [RDI_Pathfinder] Communications Fault, FailCount= 1 2019-04-04T09:58:57.801Z,1554371937.801 [RDI_Pathfinder](ERROR): Communications Fault 2019-04-04T09:58:57.802Z,1554371937.802 [RDI_Pathfinder](ERROR): Failed to parse: 2019-04-04T09:58:57.828Z,1554371937.828 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2019-04-04T09:58:58.185Z,1554371938.185 [RDI_Pathfinder](INFO): Powering down 2019-04-04T09:58:58.956Z,1554371938.956 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2019-04-04T09:58:58.956Z,1554371938.956 [RDI_Pathfinder] No Fault, FailCount= 1 2019-04-04T09:59:12.249Z,1554371952.249 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 2019-04-04T10:01:04.225Z,1554372064.225 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2019-04-04T10:01:04.226Z,1554372064.226 [RDI_Pathfinder](ERROR): Failed to parse: :BD, +0. +0.00, +0.00, 0.00,112.00 2019-04-04T10:05:52.271Z,1554372352.271 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2019-04-04T10:05:52.271Z,1554372352.271 [RDI_Pathfinder](ERROR): Failed to parse: :BD, +0.00, +0.00, 99 2019-04-04T10:07:13.426Z,1554372433.426 [RDI_Pathfinder](ERROR): Failed to parse: 9.99 2019-04-04T10:12:53.216Z,1554372773.216 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2019-04-04T10:12:53.216Z,1554372773.216 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2019-04-04T10:21:10.942Z,1554373270.942 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T10:21:10.942Z,1554373270.942 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19040403294400,35.0, -0.1, 0.0,1448.9,, 0.00, 0.00, 0.00, 0.00, 0.00 2019-04-04T10:22:55.587Z,1554373375.587 [RDI_Pathfinder](ERROR): only read 0 of 4 data items 2019-04-04T10:22:55.587Z,1554373375.587 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, ,-32768,-32768,V 2019-04-04T10:24:15.553Z,1554373455.553 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T10:24:15.554Z,1554373455.554 [RDI_Pathfinder](ERROR): Failed to parse: :TS3324900,35.0, -0.1, 0.0,1448.9, 0 2019-04-04T10:25:16.581Z,1554373516.581 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T10:25:16.581Z,1554373516.581 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19040403335000,35.0, -0.1, 09, 0 2019-04-04T10:29:41.674Z,1554373781.674 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-04-04T10:29:46.440Z,1554373786.440 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T10:29:56.134Z,1554373796.134 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T10:30:06.266Z,1554373806.266 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T10:30:16.336Z,1554373816.336 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T10:30:26.023Z,1554373826.023 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T10:30:36.121Z,1554373836.121 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T10:30:45.823Z,1554373845.823 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T10:30:55.922Z,1554373855.922 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T10:31:05.651Z,1554373865.651 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T10:31:15.732Z,1554373875.732 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T10:31:25.838Z,1554373885.838 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T10:31:35.540Z,1554373895.540 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T10:31:45.248Z,1554373905.248 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T10:31:55.337Z,1554373915.337 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T10:32:05.436Z,1554373925.436 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T10:33:23.416Z,1554374003.416 [RDI_Pathfinder](ERROR): Failed to parse: :SA, +0.00, +0.00, 0.00 2019-04-04T10:34:41.376Z,1554374081.376 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T10:34:41.381Z,1554374081.381 [BPC1](INFO): Calculating totals. Valid battery stick count: 52. Valid reserve battery stick count: 4. 2019-04-04T10:34:41.383Z,1554374081.383 [BPC1](INFO): Received data from all battery sticks. 2019-04-04T10:38:39.734Z,1554374319.734 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2019-04-04T10:38:39.734Z,1554374319.734 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2019-04-04T10:39:19.730Z,1554374359.730 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T10:39:19.730Z,1554374359.730 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19040403475300,35.0, -0.1, .9, 0 2019-04-04T10:40:08.660Z,1554374408.660 [keepstation:NeedComms] Running Loop=1 2019-04-04T10:40:08.660Z,1554374408.660 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2019-04-04T10:40:08.660Z,1554374408.660 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2019-04-04T10:40:08.660Z,1554374408.660 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-04-04T10:40:08.661Z,1554374408.661 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2019-04-04T10:40:08.661Z,1554374408.661 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2019-04-04T10:40:08.662Z,1554374408.662 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2019-04-04T10:40:08.662Z,1554374408.662 [keepstation:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-04-04T10:40:08.662Z,1554374408.662 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-04-04T10:40:08.663Z,1554374408.663 [keepstation:NeedComms:A] Running Loop=1 2019-04-04T10:40:08.664Z,1554374408.664 [keepstation:NeedComms:A](INFO): last time_fix was: 1554371701.000000 second since 1970/01/01T00:00:00Z 2019-04-04T10:40:08.664Z,1554374408.664 [keepstation:NeedComms:A] Stopped 2019-04-04T10:40:09.028Z,1554374409.028 [keepstation:NeedComms:C] Running Loop=1 2019-04-04T10:40:10.633Z,1554374410.633 [NAL9602](DEBUG): Fix Requested 2019-04-04T10:40:11.030Z,1554374411.030 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,104004.00,A,3646.17275,N,12150.87943,W,0.739,19.35,040419,,,A*41 2019-04-04T10:40:11.033Z,1554374411.033 [NAL9602](INFO): GPS fix at 20190404T104004: (36.769546, -121.847990) 2019-04-04T10:40:11.056Z,1554374411.056 [keepstation:KeepStation:C.KeepStation](INFO): Rest->Waypoint 2019-04-04T10:40:11.059Z,1554374411.059 [keepstation:NeedComms:C] Stopped 2019-04-04T10:40:11.059Z,1554374411.059 [keepstation:NeedComms:D] Running Loop=1 2019-04-04T10:40:13.047Z,1554374413.047 [NAL9602](ERROR): parseGSV uart error: serial buffer full 2019-04-04T10:40:23.880Z,1554374423.880 [DataOverHttps](INFO): Sending 79 bytes from file Logs/20190404T062936/Courier0021.lzma 2019-04-04T10:40:24.686Z,1554374424.686 [DataOverHttps](INFO): Moved sent file to Logs/20190404T062936/Courier0021.lzma.bak 2019-04-04T10:40:24.687Z,1554374424.687 [DataOverHttps](INFO): SBD MOMSN=10429078 2019-04-04T10:40:27.584Z,1554374427.584 [NAL9602](INFO): SBD MO Status=0, MOMSN=2729, MT Status=0, MTMSN=0 2019-04-04T10:40:27.584Z,1554374427.584 [NAL9602](INFO): No messages in MT queue 2019-04-04T10:40:37.360Z,1554374437.360 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20190404T062936/Express0022.lzma 2019-04-04T10:40:38.167Z,1554374438.167 [DataOverHttps](INFO): Moved sent file to Logs/20190404T062936/Express0022.lzma.bak 2019-04-04T10:40:38.167Z,1554374438.167 [DataOverHttps](INFO): SBD MOMSN=10429081 2019-04-04T10:40:38.934Z,1554374438.934 [keepstation:NeedComms:D] Stopped 2019-04-04T10:40:38.934Z,1554374438.934 [keepstation:NeedComms:E] Running Loop=1 2019-04-04T10:40:40.533Z,1554374440.533 [NAL9602](DEBUG): Fix Requested 2019-04-04T10:40:40.916Z,1554374440.916 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,104034.00,A,3646.17485,N,12150.87872,W,0.739,16.76,040419,,,A*40 2019-04-04T10:40:40.919Z,1554374440.919 [NAL9602](INFO): GPS fix at 20190404T104034: (36.769581, -121.847979) 2019-04-04T10:40:40.947Z,1554374440.947 [keepstation:NeedComms:E] Stopped 2019-04-04T10:40:40.948Z,1554374440.948 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2019-04-04T10:40:40.948Z,1554374440.948 [keepstation:NeedComms] Stopped 2019-04-04T10:40:40.948Z,1554374440.948 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2019-04-04T10:40:40.948Z,1554374440.948 [keepstation:NeedComms:B.GoToSurface] Stopped 2019-04-04T10:40:40.948Z,1554374440.948 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-04-04T10:40:42.150Z,1554374442.150 [NAL9602](ERROR): parseGSV uart error: serial buffer full 2019-04-04T10:41:03.558Z,1554374463.558 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T10:41:03.558Z,1554374463.558 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19040403493700,35.0, -0.1, 0.0,144 2019-04-04T10:41:07.230Z,1554374467.230 [RDI_Pathfinder](ERROR): only read 1 of 4 data items 2019-04-04T10:41:07.230Z,1554374467.230 [RDI_Pathfinder](ERROR): Failed to parse: :BS,-32768-32768,V 2019-04-04T10:41:09.628Z,1554374469.628 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 20.00 deg, mass-position 6.00 mm (1 active estimators). 2019-04-04T10:41:12.831Z,1554374472.831 [NAL9602](INFO): Not Powering down - fast GPS 2019-04-04T10:41:44.201Z,1554374504.201 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T10:41:45.413Z,1554374505.413 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.195564 2019-04-04T10:42:10.041Z,1554374530.041 [Radio_Surface](INFO): Powering down 2019-04-04T10:42:19.350Z,1554374539.350 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T10:42:19.753Z,1554374539.753 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2019-04-04T10:43:07.591Z,1554374587.591 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2019-04-04T10:43:07.591Z,1554374587.591 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2019-04-04T10:43:20.915Z,1554374600.915 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2019-04-04T10:43:20.915Z,1554374600.915 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2019-04-04T10:44:25.569Z,1554374665.569 [RDI_Pathfinder](ERROR): Failed to parse: :BE,-32768,-32768,-32768,V 2019-04-04T10:45:45.557Z,1554374745.557 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2019-04-04T10:45:45.557Z,1554374745.557 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2019-04-04T10:47:23.733Z,1554374843.733 [keepstation:KeepStation:C.KeepStation](INFO): Waypoint->Rest 2019-04-04T10:51:46.344Z,1554375106.344 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2019-04-04T10:51:46.344Z,1554375106.344 [RDI_Pathfinder](ERROR): Failed to parse: :BD, + +3.11, -3.28, 28.88, 0.00 2019-04-04T10:52:23.489Z,1554375143.489 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T10:52:23.489Z,1554375143.489 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19040404005600,35.0, -0.1, 0.0,1448.0 2019-04-04T10:56:12.569Z,1554375372.569 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00, 0.00 2019-04-04T11:03:23.635Z,1554375803.635 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T11:03:23.635Z,1554375803.635 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19040404115700,35.0, -0.1, 0.0, 0 2019-04-04T11:06:39.189Z,1554375999.189 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2019-04-04T11:06:39.189Z,1554375999.189 [RDI_Pathfinder](ERROR): Failed to parse: :BS,-32768,-32768V 2019-04-04T11:12:16.903Z,1554376336.903 [RDI_Pathfinder](ERROR): Failed to parse: 00, 0.00, 0.00, 0.00, 0.00 2019-04-04T11:14:16.907Z,1554376456.907 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T11:14:16.908Z,1554376456.908 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19040404225000,35.0, -0.1, 0.0,1448.9,, 0.00, 0.00, 0.00, 0.00, 0.00 2019-04-04T11:20:57.665Z,1554376857.665 [RDI_Pathfinder](ERROR): Failed to parse:.00, 295.55, 279.58, 286.24, 0.00 2019-04-04T11:24:17.645Z,1554377057.645 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 295.55, 287.57, 288.90, 292.89 2019-04-04T11:28:00.299Z,1554377280.299 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T11:28:00.300Z,1554377280.300 [RDI_Pathfinder](ERROR): Failed to parse: :TS0,A 2019-04-04T11:28:21.672Z,1554377301.672 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T11:28:21.672Z,1554377301.672 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19040404365500,35.0, -0.1, :RA,298.21, 0.00, 283.57, 298.21 2019-04-04T11:29:43.667Z,1554377383.667 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2019-04-04T11:29:43.667Z,1554377383.667 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 298.21, 283.57, 282.24 2019-04-04T11:32:04.290Z,1554377524.290 [RDI_Pathfinder](ERROR): Failed to parse: :BE, +34, -17, +34,A 2019-04-04T11:35:41.224Z,1554377741.224 [keepstation:NeedComms] Running Loop=1 2019-04-04T11:35:41.224Z,1554377741.224 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2019-04-04T11:35:41.224Z,1554377741.224 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2019-04-04T11:35:41.224Z,1554377741.224 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-04-04T11:35:41.225Z,1554377741.225 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2019-04-04T11:35:41.225Z,1554377741.225 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2019-04-04T11:35:41.226Z,1554377741.226 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2019-04-04T11:35:41.226Z,1554377741.226 [keepstation:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-04-04T11:35:41.227Z,1554377741.227 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-04-04T11:35:41.227Z,1554377741.227 [keepstation:NeedComms:A] Running Loop=1 2019-04-04T11:35:41.228Z,1554377741.228 [keepstation:NeedComms:A](INFO): last time_fix was: 1554374434.000000 second since 1970/01/01T00:00:00Z 2019-04-04T11:35:41.228Z,1554377741.228 [keepstation:NeedComms:A] Stopped 2019-04-04T11:35:54.698Z,1554377754.698 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2019-04-04T11:35:54.698Z,1554377754.698 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.72, 294.72, 327.66, 269.10 2019-04-04T11:37:01.030Z,1554377821.030 [keepstation:NeedComms:C] Running Loop=1 2019-04-04T11:37:01.485Z,1554377821.485 [Radio_Surface](INFO): Powering up 2019-04-04T11:37:02.633Z,1554377822.633 [NAL9602](DEBUG): Fix Requested 2019-04-04T11:37:07.125Z,1554377827.125 [DataOverHttps](INFO): Radio surface powered ON. 2019-04-04T11:37:09.681Z,1554377829.681 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-04-04T11:37:14.888Z,1554377834.888 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T11:37:20.922Z,1554377840.922 [NAL9602](INFO): SBD MO Status=0, MOMSN=2730, MT Status=0, MTMSN=0 2019-04-04T11:37:20.922Z,1554377840.922 [NAL9602](INFO): No messages in MT queue 2019-04-04T11:37:21.745Z,1554377841.745 [NAL9602](DEBUG): Fix Requested 2019-04-04T11:37:22.140Z,1554377842.140 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,113716.00,A,3645.99938,N,12150.90521,W,0.253,164.68,040419,,,A*70 2019-04-04T11:37:22.143Z,1554377842.143 [NAL9602](INFO): GPS fix at 20190404T113716: (36.766656, -121.848420) 2019-04-04T11:37:22.236Z,1554377842.236 [UniversalFixResidualReporter](INFO): Fix residual: 33.5 %DT, over the last 559.8 m. Residual distance 187.6 m at bearing -94.1 degrees. Fix at (36.7667, -121.8484) with 327.6 m made good. 2019-04-04T11:37:22.239Z,1554377842.239 [keepstation:KeepStation:C.KeepStation](INFO): Rest->Waypoint 2019-04-04T11:37:22.248Z,1554377842.248 [keepstation:NeedComms:C] Stopped 2019-04-04T11:37:22.248Z,1554377842.248 [keepstation:NeedComms:D] Running Loop=1 2019-04-04T11:37:24.160Z,1554377844.160 [NAL9602](ERROR): parseGSV uart error: serial buffer full 2019-04-04T11:37:24.615Z,1554377844.615 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T11:37:33.699Z,1554377853.699 [RDI_Pathfinder](ERROR): Failed to parse: :R, 0.00, 0.00, 0.00 2019-04-04T11:37:34.510Z,1554377854.510 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T11:37:37.429Z,1554377857.429 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T11:37:37.712Z,1554377857.712 [NAL9602](INFO): SBD MO Status=1, MOMSN=2731, MT Status=0, MTMSN=0 2019-04-04T11:37:37.765Z,1554377857.765 [NAL9602](INFO): Sent 71 bytes from file Logs/20190404T062936/Courier0024.lzma 2019-04-04T11:37:37.765Z,1554377857.765 [NAL9602](INFO): Packets left to send: 0 2019-04-04T11:37:44.398Z,1554377864.398 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T11:37:49.633Z,1554377869.633 [NAL9602](INFO): SBD MO Status=1, MOMSN=2732, MT Status=0, MTMSN=0 2019-04-04T11:37:49.681Z,1554377869.681 [NAL9602](INFO): Sent 130 bytes from file Logs/20190404T062936/Express0025.lzma 2019-04-04T11:37:49.681Z,1554377869.681 [NAL9602](INFO): Packets left to send: 0 2019-04-04T11:37:54.537Z,1554377874.537 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T11:37:58.541Z,1554377878.541 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2019-04-04T11:37:58.541Z,1554377878.541 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2019-04-04T11:38:04.625Z,1554377884.625 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T11:38:10.640Z,1554377890.640 [NAL9602](INFO): SBD MO Status=0, MOMSN=2733, MT Status=0, MTMSN=0 2019-04-04T11:38:10.731Z,1554377890.731 [keepstation:NeedComms:D] Stopped 2019-04-04T11:38:10.731Z,1554377890.731 [keepstation:NeedComms:E] Running Loop=1 2019-04-04T11:38:12.577Z,1554377892.577 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T11:38:12.673Z,1554377892.673 [NAL9602](DEBUG): Fix Requested 2019-04-04T11:38:13.064Z,1554377893.064 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,113807.00,A,3646.00051,N,12150.90410,W,0.486,257.12,040419,,,A*79 2019-04-04T11:38:13.067Z,1554377893.067 [NAL9602](INFO): GPS fix at 20190404T113807: (36.766675, -121.848402) 2019-04-04T11:38:13.095Z,1554377893.095 [keepstation:NeedComms:E] Stopped 2019-04-04T11:38:13.096Z,1554377893.096 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2019-04-04T11:38:13.096Z,1554377893.096 [keepstation:NeedComms] Stopped 2019-04-04T11:38:13.096Z,1554377893.096 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2019-04-04T11:38:13.096Z,1554377893.096 [keepstation:NeedComms:B.GoToSurface] Stopped 2019-04-04T11:38:13.096Z,1554377893.096 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-04-04T11:38:14.705Z,1554377894.705 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T11:38:15.095Z,1554377895.095 [NAL9602](ERROR): parseGSV uart error: serial buffer full 2019-04-04T11:38:19.578Z,1554377899.578 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2019-04-04T11:38:19.578Z,1554377899.578 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2019-04-04T11:38:21.195Z,1554377901.195 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2019-04-04T11:38:21.195Z,1554377901.195 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2019-04-04T11:38:24.819Z,1554377904.819 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T11:38:34.891Z,1554377914.891 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T11:38:45.005Z,1554377925.005 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T11:38:45.791Z,1554377925.791 [NAL9602](INFO): Not Powering down - fast GPS 2019-04-04T11:38:47.725Z,1554377927.725 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T11:38:55.114Z,1554377935.114 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T11:39:01.560Z,1554377941.560 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T11:39:01.560Z,1554377941.560 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19040404473500,35.0, -0.11448.9, 0 2019-04-04T11:39:04.808Z,1554377944.808 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T11:39:14.909Z,1554377954.909 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T11:39:22.873Z,1554377962.873 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T11:39:24.998Z,1554377964.998 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T11:39:34.704Z,1554377974.704 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T11:39:41.192Z,1554377981.192 [RDI_Pathfinder](ERROR): Failed to parse: :BE,-32768,-32768,-32768,V 2019-04-04T11:39:58.021Z,1554377998.021 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T11:40:10.557Z,1554378010.557 [Radio_Surface](INFO): Powering down 2019-04-04T11:40:33.169Z,1554378033.169 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T11:40:33.573Z,1554378033.573 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2019-04-04T11:42:10.638Z,1554378130.638 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T11:42:10.644Z,1554378130.644 [BPC1](INFO): Calculating totals. Valid battery stick count: 52. Valid reserve battery stick count: 4. 2019-04-04T11:42:10.646Z,1554378130.646 [BPC1](INFO): Received data from all battery sticks. 2019-04-04T11:42:19.526Z,1554378139.526 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T11:42:19.526Z,1554378139.526 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19040404505300,35.0, -0.1,448.9, 0 2019-04-04T11:44:36.897Z,1554378276.897 [keepstation:KeepStation:C.KeepStation](INFO): Waypoint->Rest 2019-04-04T11:48:44.553Z,1554378524.553 [RDI_Pathfinder](ERROR): Failed to parse: 00, 0.00, 0.00, 0.00, 0.00 2019-04-04T11:52:18.678Z,1554378738.678 [RDI_Pathfinder](ERROR): Failed to parse: :B, 2019-04-04T11:52:57.451Z,1554378777.451 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 316.01, 0.00, 310.02, 304.04 2019-04-04T12:05:34.536Z,1554379534.536 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T12:05:34.536Z,1554379534.536 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19040405140800,.1, 0.0,1448.9, 0 2019-04-04T12:11:32.888Z,1554379892.888 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T12:11:32.888Z,1554379892.888 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19040405200600,35.0, -0.1, 0.0,10 2019-04-04T12:13:17.521Z,1554379997.521 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T12:13:17.522Z,1554379997.522 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19040405215100,35.0, -0.1, 0.0,1448.9,, 0.00, 0.00, 0.00, 0.00, 0.00 2019-04-04T12:16:15.703Z,1554380175.703 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2019-04-04T12:16:15.703Z,1554380175.703 [RDI_Pathfinder](ERROR): Failed to parse: :BS,-32768,32768,V 2019-04-04T12:31:58.255Z,1554381118.255 [RDI_Pathfinder](ERROR): Failed to parse: 68-32768,V 2019-04-04T12:32:44.314Z,1554381164.314 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2019-04-04T12:32:44.314Z,1554381164.314 [RDI_Pathfinder](ERROR): Failed to parse: :BS, +46, +42,A 2019-04-04T12:33:13.399Z,1554381193.399 [keepstation:NeedComms] Running Loop=1 2019-04-04T12:33:13.399Z,1554381193.399 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2019-04-04T12:33:13.399Z,1554381193.399 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2019-04-04T12:33:13.399Z,1554381193.399 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-04-04T12:33:13.400Z,1554381193.400 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2019-04-04T12:33:13.400Z,1554381193.400 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2019-04-04T12:33:13.400Z,1554381193.400 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2019-04-04T12:33:13.401Z,1554381193.401 [keepstation:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-04-04T12:33:13.401Z,1554381193.401 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-04-04T12:33:13.402Z,1554381193.402 [keepstation:NeedComms:A] Running Loop=1 2019-04-04T12:33:13.406Z,1554381193.406 [keepstation:NeedComms:A](INFO): last time_fix was: 1554377887.000000 second since 1970/01/01T00:00:00Z 2019-04-04T12:33:13.406Z,1554381193.406 [keepstation:NeedComms:A] Stopped 2019-04-04T12:34:25.070Z,1554381265.070 [keepstation:NeedComms:C] Running Loop=1 2019-04-04T12:34:25.589Z,1554381265.589 [Radio_Surface](INFO): Powering up 2019-04-04T12:34:26.673Z,1554381266.673 [NAL9602](DEBUG): Fix Requested 2019-04-04T12:34:29.176Z,1554381269.176 [NAL9602](ERROR): parseGSV uart error: serial buffer full 2019-04-04T12:34:31.231Z,1554381271.231 [DataOverHttps](INFO): Radio surface powered ON. 2019-04-04T12:34:38.452Z,1554381278.452 [NAL9602](INFO): SBD MO Status=0, MOMSN=2734, MT Status=0, MTMSN=0 2019-04-04T12:34:38.452Z,1554381278.452 [NAL9602](INFO): No messages in MT queue 2019-04-04T12:34:38.868Z,1554381278.868 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2019-04-04T12:34:38.868Z,1554381278.868 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2019-04-04T12:34:39.273Z,1554381279.273 [NAL9602](DEBUG): Fix Requested 2019-04-04T12:34:39.658Z,1554381279.658 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,123433.00,A,3646.09495,N,12150.98342,W,1.244,0.00,040419,,,A*76 2019-04-04T12:34:39.660Z,1554381279.660 [NAL9602](INFO): GPS fix at 20190404T123433: (36.768249, -121.849724) 2019-04-04T12:34:39.685Z,1554381279.685 [UniversalFixResidualReporter](INFO): Fix residual: 20.3 %DT, over the last 565.3 m. Residual distance 114.5 m at bearing -43.3 degrees. Fix at (36.7682, -121.8497) with 210.9 m made good. 2019-04-04T12:34:39.688Z,1554381279.688 [keepstation:NeedComms:C] Stopped 2019-04-04T12:34:39.689Z,1554381279.689 [keepstation:NeedComms:D] Running Loop=1 2019-04-04T12:34:41.299Z,1554381281.299 [NAL9602](ERROR): parseGSV uart error: serial buffer full 2019-04-04T12:34:55.984Z,1554381295.984 [NAL9602](INFO): SBD MO Status=1, MOMSN=2735, MT Status=0, MTMSN=0 2019-04-04T12:34:56.033Z,1554381296.033 [NAL9602](INFO): Sent 88 bytes from file Logs/20190404T062936/Courier0027.lzma 2019-04-04T12:34:56.033Z,1554381296.033 [NAL9602](INFO): Packets left to send: 0 2019-04-04T12:35:01.529Z,1554381301.529 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T12:35:07.038Z,1554381307.038 [NAL9602](INFO): SBD MO Status=1, MOMSN=2736, MT Status=0, MTMSN=0 2019-04-04T12:35:07.085Z,1554381307.085 [NAL9602](INFO): Sent 129 bytes from file Logs/20190404T062936/Express0028.lzma 2019-04-04T12:35:07.085Z,1554381307.085 [NAL9602](INFO): Packets left to send: 0 2019-04-04T12:35:17.539Z,1554381317.539 [NAL9602](INFO): SBD MO Status=0, MOMSN=2737, MT Status=0, MTMSN=0 2019-04-04T12:35:17.644Z,1554381317.644 [keepstation:NeedComms:D] Stopped 2019-04-04T12:35:17.645Z,1554381317.645 [keepstation:NeedComms:E] Running Loop=1 2019-04-04T12:35:19.573Z,1554381319.573 [NAL9602](DEBUG): Fix Requested 2019-04-04T12:35:19.964Z,1554381319.964 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,123513.00,A,3646.09791,N,12150.98117,W,0.097,0.00,040419,,,A*7D 2019-04-04T12:35:19.967Z,1554381319.967 [NAL9602](INFO): GPS fix at 20190404T123513: (36.768299, -121.849686) 2019-04-04T12:35:20.008Z,1554381320.008 [keepstation:NeedComms:E] Stopped 2019-04-04T12:35:20.029Z,1554381320.029 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2019-04-04T12:35:20.029Z,1554381320.029 [keepstation:NeedComms] Stopped 2019-04-04T12:35:20.029Z,1554381320.029 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2019-04-04T12:35:20.029Z,1554381320.029 [keepstation:NeedComms:B.GoToSurface] Stopped 2019-04-04T12:35:20.029Z,1554381320.029 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-04-04T12:35:21.991Z,1554381321.991 [NAL9602](ERROR): parseGSV uart error: serial buffer full 2019-04-04T12:35:36.677Z,1554381336.677 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T12:35:52.696Z,1554381352.696 [NAL9602](INFO): Not Powering down - fast GPS 2019-04-04T12:36:11.825Z,1554381371.825 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T12:36:46.973Z,1554381406.973 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T12:36:48.501Z,1554381408.501 [RDI_Pathfinder](ERROR): Failed to parse:32768,V 2019-04-04T12:37:22.123Z,1554381442.123 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T12:37:54.397Z,1554381474.397 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002995 2019-04-04T12:44:05.211Z,1554381845.211 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2019-04-04T12:44:05.211Z,1554381845.211 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00, 2019-04-04T12:44:43.564Z,1554381883.564 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T12:44:53.270Z,1554381893.270 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T12:45:03.377Z,1554381903.377 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T12:45:13.073Z,1554381913.073 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T12:45:22.767Z,1554381922.767 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T12:45:32.869Z,1554381932.869 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T12:45:34.055Z,1554381934.055 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAREV,124522,AUV,0.46*42 2019-04-04T12:45:42.954Z,1554381942.954 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T12:45:53.058Z,1554381953.058 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T12:46:02.754Z,1554381962.754 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T12:46:12.854Z,1554381972.854 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T12:46:22.958Z,1554381982.958 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T12:46:33.054Z,1554381993.054 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T12:46:42.750Z,1554382002.750 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T12:46:52.846Z,1554382012.846 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T12:47:02.943Z,1554382022.943 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T12:48:05.589Z,1554382085.589 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T12:48:05.590Z,1554382085.590 [RDI_Pathfinder](ERROR): Failed to parse: :TS,190404055639 -0.1, 0.0,1448.9, 0 2019-04-04T12:49:37.713Z,1554382177.713 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T12:49:37.723Z,1554382177.723 [BPC1](INFO): Calculating totals. Valid battery stick count: 52. Valid reserve battery stick count: 4. 2019-04-04T12:49:37.729Z,1554382177.729 [BPC1](INFO): Received data from all battery sticks. 2019-04-04T12:49:42.151Z,1554382182.151 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2019-04-04T12:49:42.151Z,1554382182.151 [RDI_Pathfinder](ERROR): Failed to parse: :BD, , +58.95, -6.03, 0.00,980.96 2019-04-04T12:49:58.695Z,1554382198.695 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2019-04-04T12:49:58.695Z,1554382198.695 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00 2019-04-04T12:51:58.681Z,1554382318.681 [RDI_Pathfinder](ERROR): Failed to parse:115.95 2019-04-04T12:54:02.339Z,1554382442.339 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2019-04-04T12:54:02.339Z,1554382442.339 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 239.93 2019-04-04T12:54:38.698Z,1554382478.698 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2019-04-04T12:54:38.699Z,1554382478.699 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.0.93 2019-04-04T12:55:04.170Z,1554382504.170 [RDI_Pathfinder](ERROR): Failed to parse: :BD, +0.00, +0.00, +0.00, 0.00,1302.92 2019-04-04T12:56:25.339Z,1554382585.339 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T12:56:25.339Z,1554382585.339 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19040406045800,35.0, -0.1, 0 +0.00, 0.00,1382.91 2019-04-04T13:00:14.089Z,1554382814.089 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T13:00:14.493Z,1554382814.493 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.239494 2019-04-04T13:00:49.281Z,1554382849.281 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T13:01:24.429Z,1554382884.429 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T13:01:59.577Z,1554382919.577 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T13:02:34.725Z,1554382954.725 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T13:03:09.893Z,1554382989.893 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T13:03:23.833Z,1554383003.833 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002996 2019-04-04T13:04:20.389Z,1554383060.389 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T13:04:24.025Z,1554383064.025 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.195477 2019-04-04T13:04:31.225Z,1554383071.225 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003010 2019-04-04T13:05:30.609Z,1554383130.609 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T13:05:31.417Z,1554383131.417 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.195564 2019-04-04T13:06:05.757Z,1554383165.757 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T13:06:40.905Z,1554383200.905 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T13:07:00.393Z,1554383220.393 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003021 2019-04-04T13:11:26.699Z,1554383486.699 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2019-04-04T13:11:26.699Z,1554383486.699 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2019-04-04T13:13:31.135Z,1554383611.135 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2019-04-04T13:13:31.136Z,1554383611.136 [RDI_Pathfinder](ERROR): Failed to parse: :BS,-32768,-32768, 2019-04-04T13:17:27.860Z,1554383847.860 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T13:17:27.861Z,1554383847.861 [RDI_Pathfinder](ERROR): Failed to parse: :TS6260100,35.0, -0.1, 0.0,1448.9, 0 2019-04-04T13:18:53.128Z,1554383933.128 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2019-04-04T13:18:53.129Z,1554383933.129 [RDI_Pathfinder](ERROR): Failed to parse: :BS,-3768,-32768,V 2019-04-04T13:19:08.858Z,1554383948.858 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2019-04-04T13:19:08.858Z,1554383948.858 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2019-04-04T13:20:14.310Z,1554384014.310 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19040406284800,35.0, -0.1, 0.0,1448.9, 0 2019-04-04T13:20:20.780Z,1554384020.780 [keepstation:NeedComms] Running Loop=1 2019-04-04T13:20:20.780Z,1554384020.780 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2019-04-04T13:20:20.780Z,1554384020.780 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2019-04-04T13:20:20.780Z,1554384020.780 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-04-04T13:20:20.781Z,1554384020.781 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2019-04-04T13:20:20.782Z,1554384020.782 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2019-04-04T13:20:20.782Z,1554384020.782 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2019-04-04T13:20:20.782Z,1554384020.782 [keepstation:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-04-04T13:20:20.783Z,1554384020.783 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-04-04T13:20:20.783Z,1554384020.783 [keepstation:NeedComms:A] Running Loop=1 2019-04-04T13:20:20.784Z,1554384020.784 [keepstation:NeedComms:A](INFO): last time_fix was: 1554381313.000000 second since 1970/01/01T00:00:00Z 2019-04-04T13:20:20.784Z,1554384020.784 [keepstation:NeedComms:A] Stopped 2019-04-04T13:20:21.195Z,1554384021.195 [keepstation:NeedComms:C] Running Loop=1 2019-04-04T13:20:22.785Z,1554384022.785 [NAL9602](DEBUG): Fix Requested 2019-04-04T13:20:23.181Z,1554384023.181 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,132017.00,A,3646.35536,N,12150.78774,W,0.700,0.56,040419,,,A*7B 2019-04-04T13:20:23.183Z,1554384023.183 [NAL9602](INFO): GPS fix at 20190404T132017: (36.772589, -121.846462) 2019-04-04T13:20:23.239Z,1554384023.239 [keepstation:KeepStation:C.KeepStation](INFO): Rest->Waypoint 2019-04-04T13:20:23.242Z,1554384023.242 [keepstation:NeedComms:C] Stopped 2019-04-04T13:20:23.242Z,1554384023.242 [keepstation:NeedComms:D] Running Loop=1 2019-04-04T13:20:25.219Z,1554384025.219 [NAL9602](ERROR): parseGSV uart error: serial buffer full 2019-04-04T13:20:29.176Z,1554384029.176 [DataOverHttps](INFO): Sending 80 bytes from file Logs/20190404T062936/Courier0030.lzma 2019-04-04T13:20:30.386Z,1554384030.386 [DataOverHttps](INFO): Moved sent file to Logs/20190404T062936/Courier0030.lzma.bak 2019-04-04T13:20:30.387Z,1554384030.387 [DataOverHttps](INFO): SBD MOMSN=10432811 2019-04-04T13:20:42.908Z,1554384042.908 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20190404T062936/Express0031.lzma 2019-04-04T13:20:43.714Z,1554384043.714 [DataOverHttps](INFO): Moved sent file to Logs/20190404T062936/Express0031.lzma.bak 2019-04-04T13:20:43.715Z,1554384043.715 [DataOverHttps](INFO): SBD MOMSN=10432814 2019-04-04T13:20:44.612Z,1554384044.612 [keepstation:NeedComms:D] Stopped 2019-04-04T13:20:44.613Z,1554384044.613 [keepstation:NeedComms:E] Running Loop=1 2019-04-04T13:21:26.202Z,1554384086.202 [NAL9602](INFO): SBD MO Status=0, MOMSN=2738, MT Status=0, MTMSN=0 2019-04-04T13:21:26.202Z,1554384086.202 [NAL9602](INFO): No messages in MT queue 2019-04-04T13:21:27.021Z,1554384087.021 [NAL9602](DEBUG): Fix Requested 2019-04-04T13:21:27.417Z,1554384087.417 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,132121.00,A,3646.36098,N,12150.78375,W,0.505,62.40,040419,,,A*4C 2019-04-04T13:21:27.419Z,1554384087.419 [NAL9602](INFO): GPS fix at 20190404T132121: (36.772683, -121.846396) 2019-04-04T13:21:27.511Z,1554384087.511 [keepstation:NeedComms:E] Stopped 2019-04-04T13:21:27.512Z,1554384087.512 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2019-04-04T13:21:27.512Z,1554384087.512 [keepstation:NeedComms] Stopped 2019-04-04T13:21:27.517Z,1554384087.517 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2019-04-04T13:21:27.517Z,1554384087.517 [keepstation:NeedComms:B.GoToSurface] Stopped 2019-04-04T13:21:27.517Z,1554384087.517 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-04-04T13:21:29.034Z,1554384089.034 [NAL9602](ERROR): parseGSV uart error: serial buffer full 2019-04-04T13:21:59.736Z,1554384119.736 [NAL9602](INFO): Not Powering down - fast GPS 2019-04-04T13:22:13.498Z,1554384133.498 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2019-04-04T13:22:13.499Z,1554384133.499 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00, I,-32768,-32768,-32768,-32768,V 2019-04-04T13:22:31.549Z,1554384151.549 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T13:22:32.761Z,1554384152.761 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.195587 2019-04-04T13:22:52.849Z,1554384172.849 [Radio_Surface](INFO): Powering down 2019-04-04T13:23:06.697Z,1554384186.697 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T13:23:07.101Z,1554384187.101 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2019-04-04T13:26:52.653Z,1554384412.653 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2019-04-04T13:26:52.654Z,1554384412.654 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2019-04-04T13:27:39.131Z,1554384459.131 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2019-04-04T13:27:39.131Z,1554384459.131 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2019-04-04T13:29:48.799Z,1554384588.799 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position 6.00 mm (2 active estimators). 2019-04-04T13:32:06.157Z,1554384726.157 [keepstation:KeepStation:C.KeepStation](INFO): Waypoint->Rest 2019-04-04T13:35:13.608Z,1554384913.608 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T13:35:13.609Z,1554384913.609 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19040406434700,31, 0.0,1448.9, 0 2019-04-04T13:35:53.619Z,1554384953.619 [RDI_Pathfinder](ERROR): Failed to parse: :BE, +71, +9, -71,A 2019-04-04T13:36:54.608Z,1554385014.608 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T13:36:54.608Z,1554385014.608 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19040406452800,35.0, 0.0,1448.9, 0 2019-04-04T13:42:17.409Z,1554385337.409 [RDI_Pathfinder](ERROR): DVL BIT error. See manual. Result code: 1 2019-04-04T13:42:17.410Z,1554385337.410 [RDI_Pathfinder](ERROR): Failed to parse: :SA, +0.00, +0.00, 0.00 2019-04-04T13:52:06.152Z,1554385926.152 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-04-04T13:52:10.878Z,1554385930.878 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T13:52:20.593Z,1554385940.593 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T13:52:30.677Z,1554385950.677 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T13:52:40.388Z,1554385960.388 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T13:52:50.488Z,1554385970.488 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T13:53:00.580Z,1554385980.580 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T13:53:10.301Z,1554385990.301 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T13:53:20.377Z,1554386000.377 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T13:53:30.062Z,1554386010.062 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T13:53:39.762Z,1554386019.762 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T13:53:49.862Z,1554386029.862 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T13:53:59.962Z,1554386039.962 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T13:54:09.659Z,1554386049.659 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T13:54:19.360Z,1554386059.360 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T13:54:29.461Z,1554386069.461 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T13:55:23.603Z,1554386123.603 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 323.50, 307.03, 310.02, 319.01 2019-04-04T13:57:06.206Z,1554386226.206 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T13:57:06.211Z,1554386226.211 [BPC1](INFO): Calculating totals. Valid battery stick count: 52. Valid reserve battery stick count: 4. 2019-04-04T13:57:06.214Z,1554386226.214 [BPC1](INFO): Received data from all battery sticks. 2019-04-04T14:03:08.608Z,1554386588.608 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T14:03:08.608Z,1554386588.608 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19040407114200,35.0, 0.0,1448.9, 0 2019-04-04T14:10:51.590Z,1554387051.590 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 313.02, 298.05, 298.05, 0.00 2019-04-04T14:16:27.732Z,1554387387.732 [keepstation:NeedComms] Running Loop=1 2019-04-04T14:16:27.732Z,1554387387.732 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2019-04-04T14:16:27.732Z,1554387387.732 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2019-04-04T14:16:27.732Z,1554387387.732 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-04-04T14:16:27.733Z,1554387387.733 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2019-04-04T14:16:27.733Z,1554387387.733 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2019-04-04T14:16:27.734Z,1554387387.734 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2019-04-04T14:16:27.734Z,1554387387.734 [keepstation:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-04-04T14:16:27.734Z,1554387387.734 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-04-04T14:16:27.735Z,1554387387.735 [keepstation:NeedComms:A] Running Loop=1 2019-04-04T14:16:27.736Z,1554387387.736 [keepstation:NeedComms:A](INFO): last time_fix was: 1554384081.000000 second since 1970/01/01T00:00:00Z 2019-04-04T14:16:27.736Z,1554387387.736 [keepstation:NeedComms:A] Stopped 2019-04-04T14:16:56.179Z,1554387416.179 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2019-04-04T14:16:56.179Z,1554387416.179 [RDI_Pathfinder](ERROR): Failed to parse: :BD, +14.26, , -2.06, 0.00, 14.00 2019-04-04T14:17:39.839Z,1554387459.839 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2019-04-04T14:17:39.839Z,1554387459.839 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2019-04-04T14:17:43.113Z,1554387463.113 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2019-04-04T14:17:43.113Z,1554387463.113 [RDI_Pathfinder](ERROR): Failed to parse: :BD, +14 -46.81, -2.06, 0.00, 61.00 2019-04-04T14:17:51.178Z,1554387471.178 [keepstation:NeedComms:C] Running Loop=1 2019-04-04T14:17:51.913Z,1554387471.913 [Radio_Surface](INFO): Powering up 2019-04-04T14:17:52.769Z,1554387472.769 [NAL9602](DEBUG): Fix Requested 2019-04-04T14:17:55.308Z,1554387475.308 [NAL9602](ERROR): parseGSV uart error: serial buffer full 2019-04-04T14:17:57.281Z,1554387477.281 [DataOverHttps](INFO): Radio surface powered ON. 2019-04-04T14:18:08.229Z,1554387488.229 [NAL9602](INFO): SBD MO Status=0, MOMSN=2739, MT Status=0, MTMSN=0 2019-04-04T14:18:08.230Z,1554387488.230 [NAL9602](INFO): No messages in MT queue 2019-04-04T14:18:09.041Z,1554387489.041 [NAL9602](DEBUG): Fix Requested 2019-04-04T14:18:09.469Z,1554387489.469 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,141803.00,A,3646.06999,N,12150.96834,W,0.486,189.93,040419,,,A*74 2019-04-04T14:18:09.471Z,1554387489.471 [NAL9602](INFO): GPS fix at 20190404T141803: (36.767833, -121.849472) 2019-04-04T14:18:09.506Z,1554387489.506 [UniversalFixResidualReporter](INFO): Fix residual: 12.9 %DT, over the last 787.7 m. Residual distance 101.3 m at bearing -142.1 degrees. Fix at (36.7678, -121.8495) with 604.9 m made good. 2019-04-04T14:18:09.510Z,1554387489.510 [keepstation:NeedComms:C] Stopped 2019-04-04T14:18:09.510Z,1554387489.510 [keepstation:NeedComms:D] Running Loop=1 2019-04-04T14:18:11.215Z,1554387491.215 [NAL9602](ERROR): parseGSV uart error: serial buffer full 2019-04-04T14:18:22.660Z,1554387502.660 [NAL9602](INFO): SBD MO Status=1, MOMSN=2740, MT Status=0, MTMSN=0 2019-04-04T14:18:22.713Z,1554387502.713 [NAL9602](INFO): Sent 88 bytes from file Logs/20190404T062936/Courier0033.lzma 2019-04-04T14:18:22.713Z,1554387502.713 [NAL9602](INFO): Packets left to send: 0 2019-04-04T14:18:27.581Z,1554387507.581 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T14:18:36.173Z,1554387516.173 [NAL9602](INFO): SBD MO Status=1, MOMSN=2741, MT Status=0, MTMSN=0 2019-04-04T14:18:36.221Z,1554387516.221 [NAL9602](INFO): Sent 144 bytes from file Logs/20190404T062936/Express0034.lzma 2019-04-04T14:18:36.221Z,1554387516.221 [NAL9602](INFO): Packets left to send: 0 2019-04-04T14:18:44.630Z,1554387524.630 [NAL9602](INFO): SBD MO Status=0, MOMSN=2742, MT Status=0, MTMSN=0 2019-04-04T14:18:44.726Z,1554387524.726 [keepstation:NeedComms:D] Stopped 2019-04-04T14:18:44.727Z,1554387524.727 [keepstation:NeedComms:E] Running Loop=1 2019-04-04T14:18:46.657Z,1554387526.657 [NAL9602](DEBUG): Fix Requested 2019-04-04T14:18:47.056Z,1554387527.056 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,141841.00,A,3646.06927,N,12150.96723,W,0.641,220.03,040419,,,A*7E 2019-04-04T14:18:47.059Z,1554387527.059 [NAL9602](INFO): GPS fix at 20190404T141841: (36.767821, -121.849454) 2019-04-04T14:18:47.109Z,1554387527.109 [keepstation:NeedComms:E] Stopped 2019-04-04T14:18:47.110Z,1554387527.110 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2019-04-04T14:18:47.110Z,1554387527.110 [keepstation:NeedComms] Stopped 2019-04-04T14:18:47.110Z,1554387527.110 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2019-04-04T14:18:47.110Z,1554387527.110 [keepstation:NeedComms:B.GoToSurface] Stopped 2019-04-04T14:18:47.110Z,1554387527.110 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-04-04T14:18:49.074Z,1554387529.074 [NAL9602](ERROR): parseGSV uart error: serial buffer full 2019-04-04T14:19:02.729Z,1554387542.729 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T14:19:17.372Z,1554387557.372 [RDI_Pathfinder](ERROR): Failed to parse: :B +14.26, -46.81, -2.06, 0.00,154.00 2019-04-04T14:19:19.772Z,1554387559.772 [NAL9602](INFO): Not Powering down - fast GPS 2019-04-04T14:19:37.877Z,1554387577.877 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T14:19:50.127Z,1554387590.127 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2019-04-04T14:19:50.127Z,1554387590.127 [RDI_Pathfinder](ERROR): Failed to parse: :BS,-32768,-3268,V 2019-04-04T14:19:56.175Z,1554387596.175 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2019-04-04T14:19:56.175Z,1554387596.175 [RDI_Pathfinder](ERROR): Failed to parse: :BS,-32768,-3768,V 2019-04-04T14:20:13.025Z,1554387613.025 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T14:20:27.287Z,1554387627.287 [RDI_Pathfinder](ERROR): only read 0 of 4 data items 2019-04-04T14:20:27.287Z,1554387627.287 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.768,V 2019-04-04T14:20:48.173Z,1554387648.173 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T14:21:19.917Z,1554387679.917 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003147 2019-04-04T14:23:13.312Z,1554387793.312 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2019-04-04T14:23:13.312Z,1554387793.312 [RDI_Pathfinder](ERROR): Failed to parse: :BS,-32768,-327699 2019-04-04T14:24:27.647Z,1554387867.647 [RDI_Pathfinder](ERROR): Failed to parse: :BI,-32768,-32768,-32768,-32768,V 2019-04-04T14:39:02.312Z,1554388742.312 [RDI_Pathfinder](ERROR): Failed to parse: :B +0.00, +0.00, +0.00, 0.00,1338.92 2019-04-04T14:39:17.283Z,1554388757.283 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2019-04-04T14:39:17.283Z,1554388757.283 [RDI_Pathfinder](ERROR): Failed to parse: :BS,-32768,-32768,- 2019-04-04T14:39:57.642Z,1554388797.642 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2019-04-04T14:39:57.642Z,1554388797.642 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2019-04-04T14:41:26.538Z,1554388886.538 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T14:41:26.538Z,1554388886.538 [RDI_Pathfinder](ERROR): Failed to parse: :TS,1904000.1, 0.0,1448.9, 0 2019-04-04T14:44:25.128Z,1554389065.128 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2019-04-04T14:44:25.128Z,1554389065.128 [RDI_Pathfinder](ERROR): Failed to parse: :BS,-32768,-3278,V 2019-04-04T14:46:42.473Z,1554389202.473 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T14:46:42.473Z,1554389202.473 [RDI_Pathfinder](ERROR): Failed to parse: :TS,190404075516000.1, 0.0,1448.9, 0 2019-04-04T14:48:04.091Z,1554389284.091 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T14:48:04.091Z,1554389284.091 [RDI_Pathfinder](ERROR): Failed to parse: :TS, 0.00, 0.00, 0.00, 0.00 2019-04-04T14:50:04.453Z,1554389404.453 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T14:50:04.454Z,1554389404.454 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19040407583800,35.0, -0.,1448.9, 0 2019-04-04T14:51:05.128Z,1554389465.128 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2019-04-04T14:51:05.129Z,1554389465.129 [RDI_Pathfinder](ERROR): Failed to parse: :BS,-3768,-32768,V 2019-04-04T14:56:50.475Z,1554389810.475 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2019-04-04T14:56:50.475Z,1554389810.475 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2019-04-04T14:58:10.487Z,1554389890.487 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2019-04-04T14:58:10.488Z,1554389890.488 [RDI_Pathfinder](ERROR): Failed to parse: :BS,-32768,32768,V 2019-04-04T14:58:50.140Z,1554389930.140 [CBIT](IMPORTANT): Beginning ground fault scan 2019-04-04T14:59:01.003Z,1554389941.003 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.009859 CHAN A1 (24V): -0.027154 CHAN A2 (12V): -0.007170 CHAN A3 (5V): -0.001996 CHAN B0 (3.3V): 0.000125 CHAN B1 (3.15aV): 0.000387 CHAN B2 (3.15bV): -0.000076 CHAN B3 (GND): 0.002295 OPEN: 0.006013 Full Scale Calc: 4.765 mA, -1.589 mA 2019-04-04T14:59:27.267Z,1554389967.267 [RDI_Pathfinder](ERROR): Failed to parse: :BE,-32768,-32768,-32768,V 2019-04-04T14:59:39.786Z,1554389979.786 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T14:59:49.486Z,1554389989.486 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T14:59:59.587Z,1554389999.587 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T15:00:09.682Z,1554390009.682 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T15:00:19.358Z,1554390019.358 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T15:00:29.050Z,1554390029.050 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T15:00:39.154Z,1554390039.154 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T15:00:48.846Z,1554390048.846 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T15:00:58.553Z,1554390058.553 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T15:01:08.242Z,1554390068.242 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T15:01:17.939Z,1554390077.939 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T15:01:28.038Z,1554390088.038 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T15:01:37.734Z,1554390097.734 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T15:01:47.830Z,1554390107.830 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T15:01:57.934Z,1554390117.934 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T15:03:47.456Z,1554390227.456 [keepstation:NeedComms] Running Loop=1 2019-04-04T15:03:47.456Z,1554390227.456 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2019-04-04T15:03:47.456Z,1554390227.456 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2019-04-04T15:03:47.456Z,1554390227.456 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-04-04T15:03:47.461Z,1554390227.461 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2019-04-04T15:03:47.461Z,1554390227.461 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2019-04-04T15:03:47.462Z,1554390227.462 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2019-04-04T15:03:47.462Z,1554390227.462 [keepstation:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-04-04T15:03:47.462Z,1554390227.462 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-04-04T15:03:47.463Z,1554390227.463 [keepstation:NeedComms:A] Running Loop=1 2019-04-04T15:03:47.464Z,1554390227.464 [keepstation:NeedComms:A](INFO): last time_fix was: 1554387521.000000 second since 1970/01/01T00:00:00Z 2019-04-04T15:03:47.464Z,1554390227.464 [keepstation:NeedComms:A] Stopped 2019-04-04T15:03:47.855Z,1554390227.855 [keepstation:NeedComms:C] Running Loop=1 2019-04-04T15:03:49.453Z,1554390229.453 [NAL9602](DEBUG): Fix Requested 2019-04-04T15:03:49.829Z,1554390229.829 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,150343.00,A,3646.19492,N,12150.86907,W,1.575,36.81,040419,,,A*49 2019-04-04T15:03:49.831Z,1554390229.831 [NAL9602](INFO): GPS fix at 20190404T150343: (36.769915, -121.847818) 2019-04-04T15:03:49.861Z,1554390229.861 [keepstation:KeepStation:C.KeepStation](INFO): Rest->Waypoint 2019-04-04T15:03:49.863Z,1554390229.863 [keepstation:NeedComms:C] Stopped 2019-04-04T15:03:49.864Z,1554390229.864 [keepstation:NeedComms:D] Running Loop=1 2019-04-04T15:03:51.145Z,1554390231.145 [NAL9602](ERROR): parseGSV uart error: serial buffer full 2019-04-04T15:03:56.141Z,1554390236.141 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20190404T062936/Courier0036.lzma 2019-04-04T15:03:56.939Z,1554390236.939 [DataOverHttps](INFO): Moved sent file to Logs/20190404T062936/Courier0036.lzma.bak 2019-04-04T15:03:56.939Z,1554390236.939 [DataOverHttps](INFO): SBD MOMSN=10435446 2019-04-04T15:04:02.467Z,1554390242.467 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2019-04-04T15:04:02.468Z,1554390242.468 [RDI_Pathfinder](ERROR): Failed to parse: :BS,-32768,-32768,- 2019-04-04T15:04:07.705Z,1554390247.705 [NAL9602](INFO): SBD MO Status=0, MOMSN=2743, MT Status=0, MTMSN=0 2019-04-04T15:04:07.705Z,1554390247.705 [NAL9602](INFO): No messages in MT queue 2019-04-04T15:04:09.593Z,1554390249.593 [DataOverHttps](INFO): Sending 262 bytes from file Logs/20190404T062936/Express0037.lzma 2019-04-04T15:04:10.399Z,1554390250.399 [DataOverHttps](INFO): Moved sent file to Logs/20190404T062936/Express0037.lzma.bak 2019-04-04T15:04:10.399Z,1554390250.399 [DataOverHttps](INFO): SBD MOMSN=10435449 2019-04-04T15:04:10.977Z,1554390250.977 [keepstation:NeedComms:D] Stopped 2019-04-04T15:04:10.978Z,1554390250.978 [keepstation:NeedComms:E] Running Loop=1 2019-04-04T15:04:12.561Z,1554390252.561 [NAL9602](DEBUG): Fix Requested 2019-04-04T15:04:12.957Z,1554390252.957 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,150406.00,A,3646.19530,N,12150.86845,W,0.739,88.37,040419,,,A*42 2019-04-04T15:04:12.960Z,1554390252.960 [NAL9602](INFO): GPS fix at 20190404T150406: (36.769922, -121.847808) 2019-04-04T15:04:12.995Z,1554390252.995 [keepstation:NeedComms:E] Stopped 2019-04-04T15:04:12.996Z,1554390252.996 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2019-04-04T15:04:12.996Z,1554390252.996 [keepstation:NeedComms] Stopped 2019-04-04T15:04:12.996Z,1554390252.996 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2019-04-04T15:04:12.996Z,1554390252.996 [keepstation:NeedComms:B.GoToSurface] Stopped 2019-04-04T15:04:12.996Z,1554390252.996 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-04-04T15:04:14.565Z,1554390254.565 [ThrusterServo](ERROR): Thruster uart error: serial timeout 2019-04-04T15:04:14.565Z,1554390254.565 [ThrusterServo] Communications Fault, FailCount= 1 2019-04-04T15:04:14.565Z,1554390254.565 [ThrusterServo](ERROR): Communications Fault 2019-04-04T15:04:14.569Z,1554390254.569 [CBIT](ERROR): Communications Fault in component: ThrusterServo 2019-04-04T15:04:14.770Z,1554390254.770 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2019-04-04T15:04:14.770Z,1554390254.770 [ThrusterServo](INFO): Powering down 2019-04-04T15:04:15.014Z,1554390255.014 [NAL9602](ERROR): parseGSV uart error: serial buffer full 2019-04-04T15:04:15.602Z,1554390255.602 [ThrusterServo](DEBUG): Initializing EZServoServo. 2019-04-04T15:04:15.722Z,1554390255.722 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2019-04-04T15:04:15.726Z,1554390255.726 [CBIT](INFO): Clearing failed state for component ThrusterServo 2019-04-04T15:04:15.726Z,1554390255.726 [ThrusterServo] No Fault, FailCount= 1 2019-04-04T15:04:33.691Z,1554390273.691 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T15:04:33.697Z,1554390273.697 [BPC1](INFO): Calculating totals. Valid battery stick count: 52. Valid reserve battery stick count: 4. 2019-04-04T15:04:33.699Z,1554390273.699 [BPC1](INFO): Received data from all battery sticks. 2019-04-04T15:04:45.405Z,1554390285.405 [NAL9602](INFO): Not Powering down - fast GPS 2019-04-04T15:04:51.169Z,1554390291.169 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout 2019-04-04T15:04:51.169Z,1554390291.169 [RDI_Pathfinder] Communications Fault, FailCount= 1 2019-04-04T15:04:51.169Z,1554390291.169 [RDI_Pathfinder](ERROR): Communications Fault 2019-04-04T15:04:51.170Z,1554390291.170 [RDI_Pathfinder](ERROR): Failed to parse: 2019-04-04T15:04:51.373Z,1554390291.373 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2019-04-04T15:04:51.549Z,1554390291.549 [RDI_Pathfinder](INFO): Powering down 2019-04-04T15:04:52.493Z,1554390292.493 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2019-04-04T15:04:52.493Z,1554390292.493 [RDI_Pathfinder] No Fault, FailCount= 1 2019-04-04T15:05:05.627Z,1554390305.627 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 2019-04-04T15:05:52.801Z,1554390352.801 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.403595 2019-04-04T15:05:55.310Z,1554390355.310 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T15:05:55.310Z,1554390355.310 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19040408142849,35.0, -0.1, 0.0,1448.9,, 0.00, 0.00, 0.00, 0.00, 0.00 2019-04-04T15:06:14.289Z,1554390374.289 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T15:06:14.290Z,1554390374.290 [RDI_Pathfinder](ERROR): Failed to parse: :TS,190404081447490.1, 0.0,1448.9, 0 2019-04-04T15:06:24.429Z,1554390384.429 [Radio_Surface](INFO): Powering down 2019-04-04T15:06:26.737Z,1554390386.737 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T15:06:27.141Z,1554390387.141 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2019-04-04T15:06:57.939Z,1554390417.939 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2019-04-04T15:06:57.939Z,1554390417.939 [RDI_Pathfinder](ERROR): Failed to parse: :BD, .00 2019-04-04T15:11:38.719Z,1554390698.719 [keepstation:KeepStation:C.KeepStation](INFO): Waypoint->Rest 2019-04-04T15:11:39.117Z,1554390699.117 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 1.00 m/s, pitch 20.00 deg, mass-position 6.00 mm. 2019-04-04T15:13:03.955Z,1554390783.955 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2019-04-04T15:13:03.955Z,1554390783.955 [RDI_Pathfinder](ERROR): Failed to parse: :BS,-3276,-32768,V 2019-04-04T15:19:47.579Z,1554391187.579 [RDI_Pathfinder](ERROR): Failed to parse: 68,-32768,-32768,V 2019-04-04T15:21:26.953Z,1554391286.953 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2019-04-04T15:21:26.953Z,1554391286.953 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2019-04-04T15:22:09.405Z,1554391329.405 [BuoyancyServo](FAULT): Reading outside of valid range:0.000000 2019-04-04T15:22:09.405Z,1554391329.405 [BuoyancyServo](FAULT): Buoyancy engine reporting null position 2019-04-04T15:22:09.405Z,1554391329.405 [BuoyancyServo] Hardware Fault, FailCount= 1 2019-04-04T15:22:09.406Z,1554391329.406 [BuoyancyServo](ERROR): Hardware Fault 2019-04-04T15:22:09.410Z,1554391329.410 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo 2019-04-04T15:22:11.393Z,1554391331.393 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2019-04-04T15:22:11.393Z,1554391331.393 [BuoyancyServo](INFO): Powering down 2019-04-04T15:22:12.228Z,1554391332.228 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2019-04-04T15:22:12.228Z,1554391332.228 [BuoyancyServo] No Fault, FailCount= 1 2019-04-04T15:22:12.599Z,1554391332.599 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2019-04-04T15:22:12.600Z,1554391332.600 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2019-04-04T15:24:03.288Z,1554391443.288 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T15:24:03.289Z,1554391443.289 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19040408323649,35.0, -0.1, 48.9, 0 2019-04-04T15:29:14.793Z,1554391754.793 [RDI_Pathfinder](ERROR): Failed to parse: :SA, +0.00, +0.00, 0.00 2019-04-04T15:30:31.956Z,1554391831.956 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2019-04-04T15:30:31.957Z,1554391831.957 [RDI_Pathfinder](ERROR): Failed to parse: :BD, -1.1415.13, +0.03, 0.00,772.00 2019-04-04T15:34:16.976Z,1554392056.976 [RDI_Pathfinder](ERROR): Failed to parse: :0, 0.00, 0.00, 0.00, 0.00 2019-04-04T15:34:52.958Z,1554392092.958 [RDI_Pathfinder](ERROR): Failed to parse: :0, 0.00, 0.00, 0.00, 0.00 2019-04-04T15:35:33.316Z,1554392133.316 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T15:35:33.316Z,1554392133.316 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19040408440652,35.0, -0.,1448.9, 0 2019-04-04T15:38:55.322Z,1554392335.322 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2019-04-04T15:38:55.322Z,1554392335.322 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2019-04-04T15:40:01.982Z,1554392401.982 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2019-04-04T15:40:01.982Z,1554392401.982 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2019-04-04T15:42:01.963Z,1554392521.963 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2019-04-04T15:42:01.963Z,1554392521.963 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2019-04-04T15:46:35.101Z,1554392795.101 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T15:46:35.101Z,1554392795.101 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19040408550852,35.0, -0.1, 0.0,144 2019-04-04T15:47:57.913Z,1554392877.913 [RDI_Pathfinder](ERROR): Failed to parse: :SA, +0.00, +0.00, 0.00 2019-04-04T15:48:40.753Z,1554392920.753 [RDI_Pathfinder](ERROR): Failed to parse: :6, -132, +17, +86, -132, +17,A 2019-04-04T15:51:39.724Z,1554393099.724 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2019-04-04T15:51:39.724Z,1554393099.724 [RDI_Pathfinder](ERROR): Failed to parse: :BD, +18.86, , -1.94, 31.68, 0.00 2019-04-04T15:52:58.108Z,1554393178.108 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2019-04-04T15:52:58.108Z,1554393178.108 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 329.49, 310.02, 310.51 2019-04-04T15:53:40.105Z,1554393220.105 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T15:53:40.106Z,1554393220.106 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19040409021352,35.0, -0.1, 0.0,1448.9A, 0.00, 0.00, 0.00, 0.00, 0.00 2019-04-04T15:59:13.024Z,1554393553.024 [keepstation:NeedComms] Running Loop=1 2019-04-04T15:59:13.025Z,1554393553.025 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2019-04-04T15:59:13.025Z,1554393553.025 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2019-04-04T15:59:13.025Z,1554393553.025 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-04-04T15:59:13.025Z,1554393553.025 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2019-04-04T15:59:13.026Z,1554393553.026 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2019-04-04T15:59:13.026Z,1554393553.026 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2019-04-04T15:59:13.027Z,1554393553.027 [keepstation:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-04-04T15:59:13.027Z,1554393553.027 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-04-04T15:59:13.027Z,1554393553.027 [keepstation:NeedComms:A] Running Loop=1 2019-04-04T15:59:13.029Z,1554393553.029 [keepstation:NeedComms:A](INFO): last time_fix was: 1554390246.000000 second since 1970/01/01T00:00:00Z 2019-04-04T15:59:13.029Z,1554393553.029 [keepstation:NeedComms:A] Stopped 2019-04-04T15:59:22.820Z,1554393562.820 [RDI_Pathfinder](ERROR): Failed to parse::SA, +0.00, +0.00, 0.00 2019-04-04T15:59:47.501Z,1554393587.501 [keepstation:KeepStation:C.KeepStation](INFO): Rest->Waypoint 2019-04-04T15:59:58.139Z,1554393598.139 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2019-04-04T15:59:58.139Z,1554393598.139 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2019-04-04T16:00:32.970Z,1554393632.970 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2019-04-04T16:00:32.970Z,1554393632.970 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00, 2019-04-04T16:00:39.844Z,1554393639.844 [keepstation:NeedComms:C] Running Loop=1 2019-04-04T16:00:40.405Z,1554393640.405 [Radio_Surface](INFO): Powering up 2019-04-04T16:00:41.441Z,1554393641.441 [NAL9602](DEBUG): Fix Requested 2019-04-04T16:00:45.810Z,1554393645.810 [DataOverHttps](INFO): Radio surface powered ON. 2019-04-04T16:00:53.783Z,1554393653.783 [NAL9602](INFO): SBD MO Status=0, MOMSN=2744, MT Status=0, MTMSN=0 2019-04-04T16:00:53.784Z,1554393653.784 [NAL9602](INFO): No messages in MT queue 2019-04-04T16:00:54.613Z,1554393654.613 [NAL9602](DEBUG): Fix Requested 2019-04-04T16:00:55.001Z,1554393655.001 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160048.00,A,3645.88902,N,12151.09817,W,1.341,115.55,040419,,,A*73 2019-04-04T16:00:55.003Z,1554393655.003 [NAL9602](INFO): GPS fix at 20190404T160048: (36.764817, -121.851636) 2019-04-04T16:00:55.033Z,1554393655.033 [UniversalFixResidualReporter](INFO): Fix residual: 51.1 %DT, over the last 641.0 m. Residual distance 327.8 m at bearing -12.5 degrees. Fix at (36.7648, -121.8516) with 662.2 m made good. 2019-04-04T16:00:55.037Z,1554393655.037 [keepstation:NeedComms:C] Stopped 2019-04-04T16:00:55.037Z,1554393655.037 [keepstation:NeedComms:D] Running Loop=1 2019-04-04T16:00:57.022Z,1554393657.022 [NAL9602](ERROR): parseGSV uart error: serial buffer full 2019-04-04T16:01:09.775Z,1554393669.775 [NAL9602](INFO): SBD MO Status=1, MOMSN=2745, MT Status=0, MTMSN=0 2019-04-04T16:01:09.825Z,1554393669.825 [NAL9602](INFO): Sent 87 bytes from file Logs/20190404T062936/Courier0039.lzma 2019-04-04T16:01:09.825Z,1554393669.825 [NAL9602](INFO): Packets left to send: 0 2019-04-04T16:01:16.109Z,1554393676.109 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T16:01:24.222Z,1554393684.222 [NAL9602](INFO): SBD MO Status=2, MOMSN=2746, MT Status=2, MTMSN=0 2019-04-04T16:01:24.222Z,1554393684.222 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-04-04T16:01:39.802Z,1554393699.802 [NAL9602](INFO): SBD MO Status=1, MOMSN=2746, MT Status=0, MTMSN=0 2019-04-04T16:01:39.853Z,1554393699.853 [NAL9602](INFO): Sent 213 bytes from file Logs/20190404T062936/Express0040.lzma 2019-04-04T16:01:39.853Z,1554393699.853 [NAL9602](INFO): Packets left to send: 0 2019-04-04T16:01:49.940Z,1554393709.940 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T16:01:49.940Z,1554393709.940 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19040409102352,35.0, -0.1, 0.0,14 2019-04-04T16:01:51.269Z,1554393711.269 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T16:01:52.725Z,1554393712.725 [NAL9602](INFO): SBD MO Status=0, MOMSN=2747, MT Status=0, MTMSN=0 2019-04-04T16:01:52.833Z,1554393712.833 [keepstation:NeedComms:D] Stopped 2019-04-04T16:01:52.834Z,1554393712.834 [keepstation:NeedComms:E] Running Loop=1 2019-04-04T16:01:54.757Z,1554393714.757 [NAL9602](DEBUG): Fix Requested 2019-04-04T16:01:55.144Z,1554393715.144 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160149.00,A,3645.88944,N,12151.09312,W,0.661,68.87,040419,,,A*4D 2019-04-04T16:01:55.147Z,1554393715.147 [NAL9602](INFO): GPS fix at 20190404T160149: (36.764824, -121.851552) 2019-04-04T16:01:55.183Z,1554393715.183 [keepstation:NeedComms:E] Stopped 2019-04-04T16:01:55.183Z,1554393715.183 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2019-04-04T16:01:55.184Z,1554393715.184 [keepstation:NeedComms] Stopped 2019-04-04T16:01:55.184Z,1554393715.184 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2019-04-04T16:01:55.184Z,1554393715.184 [keepstation:NeedComms:B.GoToSurface] Stopped 2019-04-04T16:01:55.184Z,1554393715.184 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-04-04T16:01:57.169Z,1554393717.169 [NAL9602](ERROR): parseGSV uart error: serial buffer full 2019-04-04T16:02:24.761Z,1554393744.761 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 20.00 deg, mass-position 6.00 mm (2 active estimators). 2019-04-04T16:02:26.421Z,1554393746.421 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T16:02:27.956Z,1554393747.956 [NAL9602](INFO): Not Powering down - fast GPS 2019-04-04T16:02:50.178Z,1554393770.178 [Micromodem](ERROR): Response from modem failed NMEA checksum: F 2019-04-04T16:03:01.565Z,1554393781.565 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T16:03:02.724Z,1554393782.724 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T16:03:02.724Z,1554393782.724 [RDI_Pathfinder](ERROR): Failed to parse: :TS 2019-04-04T16:03:25.237Z,1554393805.237 [Radio_Surface](INFO): Powering down 2019-04-04T16:03:36.713Z,1554393816.713 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T16:03:37.117Z,1554393817.117 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2019-04-04T16:04:23.930Z,1554393863.930 [RDI_Pathfinder](ERROR): Failed to parse: :BE,-32768,-32768,-32768,V 2019-04-04T16:06:10.673Z,1554393970.673 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout 2019-04-04T16:06:10.673Z,1554393970.673 [RDI_Pathfinder] Communications Fault, FailCount= 1 2019-04-04T16:06:10.673Z,1554393970.673 [RDI_Pathfinder](ERROR): Communications Fault 2019-04-04T16:06:10.673Z,1554393970.673 [RDI_Pathfinder](ERROR): Failed to parse: 2019-04-04T16:06:10.729Z,1554393970.729 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2019-04-04T16:06:11.053Z,1554393971.053 [RDI_Pathfinder](INFO): Powering down 2019-04-04T16:06:11.845Z,1554393971.845 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2019-04-04T16:06:11.845Z,1554393971.845 [RDI_Pathfinder] No Fault, FailCount= 1 2019-04-04T16:06:25.142Z,1554393985.142 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 2019-04-04T16:07:07.153Z,1554394027.153 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T16:07:16.834Z,1554394036.834 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T16:07:26.530Z,1554394046.530 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T16:07:36.236Z,1554394056.236 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T16:07:39.541Z,1554394059.541 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout 2019-04-04T16:07:39.541Z,1554394059.541 [RDI_Pathfinder] Communications Fault, FailCount= 1 2019-04-04T16:07:39.541Z,1554394059.541 [RDI_Pathfinder](ERROR): Communications Fault 2019-04-04T16:07:39.541Z,1554394059.541 [RDI_Pathfinder](ERROR): Failed to parse: 2019-04-04T16:07:39.617Z,1554394059.617 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2019-04-04T16:07:39.937Z,1554394059.937 [RDI_Pathfinder](INFO): Powering down 2019-04-04T16:07:40.725Z,1554394060.725 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2019-04-04T16:07:40.725Z,1554394060.725 [RDI_Pathfinder] No Fault, FailCount= 1 2019-04-04T16:07:45.925Z,1554394065.925 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T16:07:54.018Z,1554394074.018 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 2019-04-04T16:07:56.037Z,1554394076.037 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T16:08:06.136Z,1554394086.136 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T16:08:15.822Z,1554394095.822 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T16:08:25.516Z,1554394105.516 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T16:08:35.220Z,1554394115.220 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T16:08:44.914Z,1554394124.914 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T16:08:54.606Z,1554394134.606 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T16:09:04.317Z,1554394144.317 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T16:09:14.026Z,1554394154.026 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T16:09:22.097Z,1554394162.097 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2019-04-04T16:09:22.098Z,1554394162.098 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00, 2019-04-04T16:09:24.113Z,1554394164.113 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T16:09:26.143Z,1554394166.143 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2019-04-04T16:09:26.143Z,1554394166.143 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00, BI,-32768,-32768,-32768,-32768,V 2019-04-04T16:10:30.006Z,1554394230.006 [keepstation:KeepStation:C.KeepStation](INFO): Waypoint->Rest 2019-04-04T16:11:59.631Z,1554394319.631 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-04-04T16:11:59.650Z,1554394319.650 [BPC1](INFO): Calculating totals. Valid battery stick count: 52. Valid reserve battery stick count: 4. 2019-04-04T16:11:59.652Z,1554394319.652 [BPC1](INFO): Received data from all battery sticks. 2019-04-04T16:13:03.106Z,1554394383.106 [RDI_Pathfinder](ERROR): only read 1 of 4 data items 2019-04-04T16:13:03.106Z,1554394383.106 [RDI_Pathfinder](ERROR): Failed to parse: :BS, +48, -, -44, +48, -7,A 2019-04-04T16:13:24.097Z,1554394404.097 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2019-04-04T16:13:24.097Z,1554394404.097 [RDI_Pathfinder](ERROR): Failed to parse: :BS, -55, +42, -8 -55, +42, -8,A 2019-04-04T16:14:23.458Z,1554394463.458 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T16:14:23.458Z,1554394463.458 [RDI_Pathfinder](ERROR): Failed to parse: :TS9225692,35.0, -0.1, 0.0,1448.9, 0 2019-04-04T16:14:44.467Z,1554394484.467 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2019-04-04T16:14:44.467Z,1554394484.467 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 287.50, 268.93, 286.24 2019-04-04T16:15:06.284Z,1554394506.284 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2019-04-04T16:15:06.284Z,1554394506.284 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 300.88, 20.00 2019-04-04T16:24:08.463Z,1554395048.463 [RDI_Pathfinder](ERROR): Failed to parse: :BE,-32768,-32768,-32768,V 2019-04-04T16:24:10.490Z,1554395050.490 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T16:24:10.490Z,1554395050.490 [RDI_Pathfinder](ERROR): Failed to parse: :TS9324392,35.0, -0.1, 0.0,1448.9, 00.00, 348.95, 0.00, 326.49, 339.97 2019-04-04T16:24:50.481Z,1554395090.481 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00, 0.00 2019-04-04T16:27:05.414Z,1554395225.414 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T16:27:05.414Z,1554395225.414 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19040409353892,35.0, -0.1, .9, 0 2019-04-04T16:27:39.344Z,1554395259.344 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T16:27:39.344Z,1554395259.344 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19040409361292,35.0, -0.1, .9, 0 2019-04-04T16:28:17.336Z,1554395297.336 [RDI_Pathfinder](ERROR): Failed to parse: :SA, +0.00, +0.00, 0.00 2019-04-04T16:29:36.508Z,1554395376.508 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2019-04-04T16:29:36.508Z,1554395376.508 [RDI_Pathfinder](ERROR): Failed to parse: :BS,-32768,-32768, 2019-04-04T16:32:21.348Z,1554395541.348 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T16:32:21.348Z,1554395541.348 [RDI_Pathfinder](ERROR): Failed to parse: :TS9405492,35.0, -0.1, 0.0,1448.9, 0 2019-04-04T16:33:01.341Z,1554395581.341 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T16:33:01.342Z,1554395581.342 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19040409413492,35.0, -0.1, 0.0,14 2019-04-04T16:34:10.417Z,1554395650.417 [RDI_Pathfinder](ERROR): Failed to parse: :BE,-32768,-32768,-32768,V 2019-04-04T16:37:27.991Z,1554395847.991 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2019-04-04T16:37:27.991Z,1554395847.991 [RDI_Pathfinder](ERROR): Failed to parse: :BS,-32768,-32768V 2019-04-04T16:39:05.338Z,1554395945.338 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-04-04T16:39:05.338Z,1554395945.338 [RDI_Pathfinder](ERROR): Failed to parse: :TS9473892,35.0, -0.1, 0.0,1448.9, 0 2019-04-04T16:52:14.359Z,1554396734.359 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2019-04-04T16:52:14.359Z,1554396734.359 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2019-04-04T16:53:31.140Z,1554396811.140 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2019-04-04T16:53:31.140Z,1554396811.140 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2019-04-04T16:56:55.152Z,1554397015.152 [keepstation:NeedComms] Running Loop=1 2019-04-04T16:56:55.152Z,1554397015.152 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2019-04-04T16:56:55.152Z,1554397015.152 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2019-04-04T16:56:55.152Z,1554397015.152 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-04-04T16:56:55.152Z,1554397015.152 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2019-04-04T16:56:55.169Z,1554397015.169 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2019-04-04T16:56:55.169Z,1554397015.169 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2019-04-04T16:56:55.170Z,1554397015.170 [keepstation:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-04-04T16:56:55.170Z,1554397015.170 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-04-04T16:56:55.170Z,1554397015.170 [keepstation:NeedComms:A] Running Loop=1 2019-04-04T16:56:55.172Z,1554397015.172 [keepstation:NeedComms:A](INFO): last time_fix was: 1554393709.000000 second since 1970/01/01T00:00:00Z 2019-04-04T16:56:55.172Z,1554397015.172 [keepstation:NeedComms:A] Stopped 2019-04-04T16:58:13.586Z,1554397093.586 [keepstation:NeedComms:C] Running Loop=1 2019-04-04T16:58:14.201Z,1554397094.201 [Radio_Surface](INFO): Powering up 2019-04-04T16:58:15.161Z,1554397095.161 [NAL9602](DEBUG): Fix Requested 2019-04-04T16:58:19.717Z,1554397099.717 [DataOverHttps](INFO): Radio surface powered ON. 2019-04-04T16:58:29.696Z,1554397109.696 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=2748, MT Status=1, MTMSN=111 2019-04-04T16:58:29.696Z,1554397109.696 [NAL9602](INFO): Data available in MT queue 2019-04-04T16:58:30.193Z,1554397110.193 [NAL9602](INFO): Received command:stop 2019-04-04T16:58:30.198Z,1554397110.198 [CommandLine](IMPORTANT): got command stop 2019-04-04T16:58:30.198Z,1554397110.198 [CommandLine](IMPORTANT): Scheduling is paused 2019-04-04T16:58:30.199Z,1554397110.199 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop 2019-04-04T16:58:30.232Z,1554397110.232 [MissionManager](INFO): MissionManager is completed. 2019-04-04T16:58:30.232Z,1554397110.232 [MissionManager](INFO): Uninitializing Mission keepstation 2019-04-04T16:58:30.232Z,1554397110.232 [keepstation] Stopped 2019-04-04T16:58:30.232Z,1554397110.232 [keepstation](DEBUG): Aggregate::uninitialize keepstation 2019-04-04T16:58:30.232Z,1554397110.232 [keepstation:NeedComms] Stopped 2019-04-04T16:58:30.232Z,1554397110.232 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2019-04-04T16:58:30.233Z,1554397110.233 [keepstation:NeedComms:B.GoToSurface] Stopped 2019-04-04T16:58:30.233Z,1554397110.233 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-04-04T16:58:30.233Z,1554397110.233 [keepstation:NeedComms:C] Stopped 2019-04-04T16:58:30.233Z,1554397110.233 [keepstation:StandardEnvelopes] Stopped 2019-04-04T16:58:30.233Z,1554397110.233 [keepstation:StandardEnvelopes](DEBUG): Aggregate::uninitialize keepstation:StandardEnvelopes 2019-04-04T16:58:30.233Z,1554397110.233 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2019-04-04T16:58:30.233Z,1554397110.233 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2019-04-04T16:58:30.233Z,1554397110.233 [keepstation:StandardEnvelopes:B.DepthEnvelope] Stopped 2019-04-04T16:58:30.233Z,1554397110.233 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2019-04-04T16:58:30.233Z,1554397110.233 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2019-04-04T16:58:30.234Z,1554397110.234 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2019-04-04T16:58:30.234Z,1554397110.234 [keepstation:F.Pitch] Stopped 2019-04-04T16:58:30.234Z,1554397110.234 [keepstation:KeepStation] Stopped 2019-04-04T16:58:30.234Z,1554397110.234 [keepstation:KeepStation](DEBUG): Aggregate::uninitialize keepstation:KeepStation 2019-04-04T16:58:30.234Z,1554397110.234 [keepstation:KeepStation:A] Stopped 2019-04-04T16:58:30.234Z,1554397110.234 [keepstation:KeepStation:B.Pitch] Stopped 2019-04-04T16:58:30.234Z,1554397110.234 [keepstation:KeepStation:C.KeepStation] Stopped 2019-04-04T16:58:30.234Z,1554397110.234 [keepstation:KeepStation:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2019-04-04T16:58:30.234Z,1554397110.234 [keepstation:KeepStation:D.Wait] Stopped 2019-04-04T16:58:30.234Z,1554397110.234 [keepstation:KeepStation:D.Wait](DEBUG): Uninitialize Wait Component. 2019-04-04T16:58:30.532Z,1554397110.532 [MissionManager](IMPORTANT): Started mission Default 2019-04-04T16:58:30.532Z,1554397110.532 [Default] Running Loop=1 2019-04-04T16:58:30.532Z,1554397110.532 [Default](DEBUG): Aggregate::initialize Default 2019-04-04T16:58:30.532Z,1554397110.532 [Default:B.GoToSurface] Running Loop=1 2019-04-04T16:58:30.532Z,1554397110.532 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-04-04T16:58:30.532Z,1554397110.532 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-04-04T16:58:30.533Z,1554397110.533 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-04-04T16:58:30.533Z,1554397110.533 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-04-04T16:58:30.534Z,1554397110.534 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-04-04T16:58:30.534Z,1554397110.534 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-04-04T16:58:30.534Z,1554397110.534 [Default:A.Wait] Running Loop=1 2019-04-04T16:58:30.534Z,1554397110.534 [Default:A.Wait](DEBUG): Initialize Wait Component. 2019-04-04T16:58:43.861Z,1554397123.861 [Default:A.Wait](INFO): Done Waiting. 2019-04-04T16:58:43.861Z,1554397123.861 [Default:A.Wait] Stopped 2019-04-04T16:58:43.861Z,1554397123.861 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2019-04-04T16:58:44.268Z,1554397124.268 [Default:CheckIn] Running Loop=1 2019-04-04T16:58:44.269Z,1554397124.269 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-04-04T16:58:44.269Z,1554397124.269 [Default:CheckIn:Read_GPS] Running Loop=1 2019-04-04T16:58:45.873Z,1554397125.873 [NAL9602](DEBUG): Fix Requested 2019-04-04T16:58:46.265Z,1554397126.265 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165840.00,A,3645.94600,N,12151.04910,W,0.622,0.00,040419,,,A*79 2019-04-04T16:58:46.267Z,1554397126.267 [NAL9602](INFO): GPS fix at 20190404T165840: (36.765767, -121.850818) 2019-04-04T16:58:46.322Z,1554397126.322 [UniversalFixResidualReporter](INFO): Fix residual: 50.3 %DT, over the last 694.2 m. Residual distance 349.5 m at bearing 43.1 degrees. Fix at (36.7658, -121.8508) with 123.5 m made good. 2019-04-04T16:58:46.323Z,1554397126.323 [Default:CheckIn:Read_GPS] Stopped 2019-04-04T16:58:46.323Z,1554397126.323 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-04-04T16:58:48.286Z,1554397128.286 [NAL9602](ERROR): parseGSV uart error: serial buffer full 2019-04-04T16:58:50.033Z,1554397130.033 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T16:59:09.384Z,1554397149.384 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=2749, MT Status=1, MTMSN=112 2019-04-04T16:59:09.437Z,1554397149.437 [NAL9602](INFO): Sent 87 bytes from file Logs/20190404T062936/Courier0042.lzma 2019-04-04T16:59:09.437Z,1554397149.437 [NAL9602](INFO): Packets left to send: 0 2019-04-04T16:59:10.009Z,1554397150.009 [NAL9602](INFO): Received command:configSet Express none TrackAcousticContact.contact_latitude persist;configSet Express none TrackAcousticContact.contact_longitude persist 2019-04-04T16:59:13.441Z,1554397153.441 [CommandLine](IMPORTANT): got command configSet Express none TrackAcousticContact.contact_latitude persist 2019-04-04T16:59:13.444Z,1554397153.444 [CommandLine](IMPORTANT): got command configSet Express none TrackAcousticContact.contact_longitude persist 2019-04-04T16:59:25.217Z,1554397165.217 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-04-04T16:59:29.864Z,1554397169.864 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=2750, MT Status=1, MTMSN=113 2019-04-04T16:59:29.913Z,1554397169.913 [NAL9602](INFO): Sent 248 bytes from file Logs/20190404T062936/Express0043.lzma 2019-04-04T16:59:29.913Z,1554397169.913 [NAL9602](INFO): Packets left to send: 0 2019-04-04T16:59:30.441Z,1554397170.441 [NAL9602](INFO): Received command:configSet Express linearApprox RDI_Pathfinder.height_above_sea_floor 2 meter persist 2019-04-04T16:59:32.145Z,1554397172.145 [CommandLine](IMPORTANT): got command configSet Express linearApproximation RDI_Pathfinder.height_above_sea_floor 2.000000 meter persist 2019-04-04T16:59:38.328Z,1554397178.328 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=2751, MT Status=1, MTMSN=114 2019-04-04T16:59:38.817Z,1554397178.817 [NAL9602](INFO): Received command:restart app 2019-04-04T16:59:38.854Z,1554397178.854 [CommandLine](IMPORTANT): got command restart application 2019-04-04T16:59:39.857Z,1554397179.857 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2019-04-04T16:59:39.857Z,1554397179.857 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-04-04T16:59:39.893Z,1554397179.893 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2019-04-04T16:59:39.893Z,1554397179.893 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-04-04T16:59:39.894Z,1554397179.894 [CommandLine](INFO): Join timeout helper Thread ID is 6704 2019-04-04T16:59:39.894Z,1554397179.894 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2019-04-04T16:59:39.894Z,1554397179.894 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-04-04T16:59:39.895Z,1554397179.895 [NavChartDb](INFO): Join timeout helper Thread ID is 6705 2019-04-04T16:59:40.269Z,1554397180.269 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2019-04-04T16:59:40.269Z,1554397180.269 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-04-04T16:59:40.281Z,1554397180.281 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler 2019-04-04T16:59:40.281Z,1554397180.281 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2019-04-04T16:59:40.281Z,1554397180.281 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 6706 2019-04-04T16:59:40.553Z,1554397180.553 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread. 2019-04-04T16:59:40.553Z,1554397180.553 [WetLabsSeaOWL_UV_A](INFO): Powering down 2019-04-04T16:59:40.555Z,1554397180.555 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2019-04-04T16:59:40.565Z,1554397180.565 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler 2019-04-04T16:59:40.565Z,1554397180.565 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2019-04-04T16:59:40.566Z,1554397180.566 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 6707 2019-04-04T16:59:40.582Z,1554397180.582 [CTD_NeilBrown](INFO): Powering down 2019-04-04T16:59:40.593Z,1554397180.593 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread. 2019-04-04T16:59:40.594Z,1554397180.594 [CTD_NeilBrown](INFO): Powering down 2019-04-04T16:59:40.609Z,1554397180.609 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2019-04-04T16:59:40.625Z,1554397180.625 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2019-04-04T16:59:40.625Z,1554397180.625 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-04-04T16:59:40.625Z,1554397180.625 [Radio_Surface](INFO): Join timeout helper Thread ID is 6708 2019-04-04T16:59:40.657Z,1554397180.657 [Radio_Surface](INFO): Powering down 2019-04-04T16:59:40.658Z,1554397180.658 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2019-04-04T16:59:40.658Z,1554397180.658 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-04-04T16:59:40.666Z,1554397180.666 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2019-04-04T16:59:40.666Z,1554397180.666 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-04-04T16:59:40.666Z,1554397180.666 [DataOverHttps](INFO): Join timeout helper Thread ID is 6709 2019-04-04T16:59:40.973Z,1554397180.973 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2019-04-04T16:59:40.974Z,1554397180.974 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-04-04T16:59:40.995Z,1554397180.995 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2019-04-04T16:59:40.996Z,1554397180.996 [logger ThreadHandler](INFO): Thread cancelled. 2019-04-04T16:59:40.997Z,1554397180.997 [logger](INFO): Join timeout helper Thread ID is 6710 2019-04-04T16:59:41.011Z,1554397181.011 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2019-04-04T16:59:41.012Z,1554397181.012 [logger ThreadHandler](INFO): Thread cancelled. 2019-04-04T16:59:41.027Z,1554397181.027 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2019-04-04T16:59:41.027Z,1554397181.027 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-04-04T16:59:41.027Z,1554397181.027 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2019-04-04T16:59:41.027Z,1554397181.027 [controlThread ThreadHandler](INFO): Thread cancelled. 2019-04-04T16:59:41.027Z,1554397181.027 [controlThread](INFO): Join timeout helper Thread ID is 6711 2019-04-04T16:59:41.133Z,1554397181.133 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2019-04-04T16:59:41.133Z,1554397181.133 [controlThread](DEBUG): Uninitializing ControlThread 2019-04-04T16:59:41.134Z,1554397181.134 [AHRS_M2](INFO): Powering down 2019-04-04T16:59:41.206Z,1554397181.206 [DUSBL_Hydroid](INFO): Powering down 2019-04-04T16:59:41.277Z,1554397181.277 [Micromodem](INFO): Powering down 2019-04-04T16:59:41.373Z,1554397181.373 [NAL9602](INFO): Powering down 2019-04-04T16:59:41.445Z,1554397181.445 [RDI_Pathfinder](INFO): Powering down 2019-04-04T16:59:41.446Z,1554397181.446 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2019-04-04T16:59:41.447Z,1554397181.447 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2019-04-04T16:59:41.448Z,1554397181.448 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2019-04-04T16:59:41.448Z,1554397181.448 [MissionManager](INFO): Uninitializing Mission Default 2019-04-04T16:59:41.448Z,1554397181.448 [Default] Stopped 2019-04-04T16:59:41.448Z,1554397181.448 [Default](DEBUG): Aggregate::uninitialize Default 2019-04-04T16:59:41.448Z,1554397181.448 [Default:B.GoToSurface] Stopped 2019-04-04T16:59:41.448Z,1554397181.448 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-04-04T16:59:41.448Z,1554397181.448 [Default:CheckIn] Stopped 2019-04-04T16:59:41.449Z,1554397181.449 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-04-04T16:59:41.449Z,1554397181.449 [Default:CheckIn:Read_Iridium] Stopped 2019-04-04T16:59:41.451Z,1554397181.451 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2019-04-04T16:59:41.451Z,1554397181.451 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2019-04-04T16:59:41.452Z,1554397181.452 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2019-04-04T16:59:41.452Z,1554397181.452 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2019-04-04T16:59:41.452Z,1554397181.452 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2019-04-04T16:59:41.452Z,1554397181.452 [BuoyancyServo](INFO): Powering down 2019-04-04T16:59:41.585Z,1554397181.585 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2019-04-04T16:59:41.585Z,1554397181.585 [ElevatorServo](INFO): Powering down 2019-04-04T16:59:41.586Z,1554397181.586 [MassServo](DEBUG): Uninitialize Mass Servo. 2019-04-04T16:59:41.586Z,1554397181.586 [MassServo](INFO): Powering down 2019-04-04T16:59:41.587Z,1554397181.587 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2019-04-04T16:59:41.587Z,1554397181.587 [RudderServo](INFO): Powering down 2019-04-04T16:59:41.588Z,1554397181.588 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2019-04-04T16:59:41.588Z,1554397181.588 [ThrusterServo](INFO): Powering down 2019-04-04T16:59:41.589Z,1554397181.589 [SBIT](DEBUG): Uninitialize SBIT Component. 2019-04-04T16:59:41.589Z,1554397181.589 [IBIT](DEBUG): Uninitialize IBIT Component. 2019-04-04T16:59:41.589Z,1554397181.589 [CBIT](DEBUG): Uninitialize CBIT Component. 2019-04-04T16:59:41.590Z,1554397181.590 [CBIT](DEBUG): Powering off loads. 2019-04-04T16:59:41.601Z,1554397181.601 [CBIT](DEBUG): Disabling WDT. 2019-04-04T16:59:41.613Z,1554397181.613 [CBIT](DEBUG): Opening all GF detection circuits. 2019-04-04T16:59:41.614Z,1554397181.614 [controlThread ThreadHandler](INFO): Thread cancelled. 2019-04-04T16:59:41.669Z,1554397181.669 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-04-04T16:59:41.680Z,1554397181.680 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-04-04T16:59:41.721Z,1554397181.721 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2019-04-04T16:59:41.723Z,1554397181.723 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2019-04-04T16:59:41.781Z,1554397181.781 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-04-04T16:59:41.862Z,1554397181.862 [logger ThreadHandler](INFO): Thread cancelled.