2021-08-11T18:21:03.148Z,1628706063.148 [CommandLine](IMPORTANT): got command restart logs 2021-08-11T18:21:12.190Z,1628706072.190 [Micromodem](INFO): Nmea in: $CAREV,182112,AUV,2.1.4000*23 2021-08-11T18:21:12.211Z,1628706072.211 [Micromodem](INFO): Nmea in: $CAREV,182112,COPROC,0.20.0.39330*72 2021-08-11T18:21:22.287Z,1628706082.287 [Micromodem](INFO): Nmea in: $CAREV,182122,AUV,2.1.4000*20 2021-08-11T18:21:22.289Z,1628706082.289 [Micromodem](INFO): Nmea in: $CAREV,182122,COPROC,0.20.0.39330*71 2021-08-11T18:21:32.408Z,1628706092.408 [Micromodem](INFO): Nmea in: $CAREV,182132,AUV,2.1.4000*21 2021-08-11T18:21:32.409Z,1628706092.409 [Micromodem](INFO): Nmea in: $CAREV,182132,COPROC,0.20.0.39330*70 2021-08-11T18:21:42.487Z,1628706102.487 [Micromodem](INFO): Nmea in: $CAREV,182142,AUV,2.1.4000*26 2021-08-11T18:21:42.488Z,1628706102.488 [Micromodem](INFO): Nmea in: $CAREV,182142,COPROC,0.20.0.39330*77 2021-08-11T18:21:52.207Z,1628706112.207 [Micromodem](INFO): Nmea in: $CAREV,182152,AUV,2.1.4000*27 2021-08-11T18:21:52.209Z,1628706112.209 [Micromodem](INFO): Nmea in: $CAREV,182152,COPROC,0.20.0.39330*76 2021-08-11T18:22:02.286Z,1628706122.286 [Micromodem](INFO): Nmea in: $CAREV,182202,AUV,2.1.4000*21 2021-08-11T18:22:02.289Z,1628706122.289 [Micromodem](INFO): Nmea in: $CAREV,182202,COPROC,0.20.0.39330*70 2021-08-11T18:22:05.162Z,1628706125.162 [CommandLine](IMPORTANT): got command load ./Missions/Engineering/LBLTest.xml 2021-08-11T18:22:05.162Z,1628706125.162 [MissionManager](INFO): Loading Mission: ./Missions/Engineering/LBLTest.xml 2021-08-11T18:22:05.237Z,1628706125.237 [MissionManager](INFO): DefineArg LBLTest.trans1Location = [36.803398,-121.822304] arcdeg 2021-08-11T18:22:05.239Z,1628706125.239 [MissionManager](INFO): DefineArg LBLTest.trans1Depth = 25.000000 m 2021-08-11T18:22:05.242Z,1628706125.242 [MissionManager](INFO): DefineArg LBLTest.trans2Location = [36.793400,-121.832199] arcdeg 2021-08-11T18:22:05.244Z,1628706125.244 [MissionManager](INFO): DefineArg LBLTest.trans2Depth = 25.000000 m 2021-08-11T18:22:05.246Z,1628706125.246 [MissionManager](INFO): DefineArg LBLTest.trans3Location = [36.793400,-121.812202] arcdeg 2021-08-11T18:22:05.251Z,1628706125.251 [MissionManager](INFO): DefineArg LBLTest.trans3Depth = 25.000000 m 2021-08-11T18:22:05.259Z,1628706125.259 [LBLTest:WhenAgg:D.LBL](DEBUG): Construct LBL. 2021-08-11T18:22:05.305Z,1628706125.305 [MissionManager](INFO): Inserting Stack: Missions/Transport/transit.xml 2021-08-11T18:22:05.391Z,1628706125.391 [MissionManager](INFO): DefineArg LBLTest:transit.MissionTimeout = 60.000000 min 2021-08-11T18:22:05.394Z,1628706125.394 [MissionManager](INFO): DefineArg LBLTest:transit.NeedCommsTime = 30.000000 min 2021-08-11T18:22:05.396Z,1628706125.396 [MissionManager](INFO): DefineArg LBLTest:transit.Latitude = nan arcdeg 2021-08-11T18:22:05.398Z,1628706125.398 [MissionManager](INFO): DefineArg LBLTest:transit.Longitude = nan arcdeg 2021-08-11T18:22:05.400Z,1628706125.400 [MissionManager](INFO): DefineArg LBLTest:transit.Northings = 0.000000 m 2021-08-11T18:22:05.402Z,1628706125.402 [MissionManager](INFO): DefineArg LBLTest:transit.Eastings = 0.000000 m 2021-08-11T18:22:05.405Z,1628706125.405 [MissionManager](INFO): DefineArg LBLTest:transit.Depth = 10.000000 m 2021-08-11T18:22:05.407Z,1628706125.407 [MissionManager](INFO): DefineArg LBLTest:transit.Speed = 1.000000 m/s 2021-08-11T18:22:05.409Z,1628706125.409 [MissionManager](INFO): DefineArg LBLTest:transit.MaxDepth = 20.000000 m 2021-08-11T18:22:05.411Z,1628706125.411 [MissionManager](INFO): DefineArg LBLTest:transit.MinOffshore = 1.000000 km 2021-08-11T18:22:05.414Z,1628706125.414 [MissionManager](INFO): DefineArg LBLTest:transit.MinAltitude = 7.000000 m 2021-08-11T18:22:05.416Z,1628706125.416 [MissionManager](INFO): DefineArg LBLTest:transit.MassHold = 1 bool 2021-08-11T18:22:05.418Z,1628706125.418 [MissionManager](INFO): DefineArg LBLTest:transit.BuoyancyHold = 1 bool 2021-08-11T18:22:05.418Z,1628706125.418 [MissionManager](INFO): Inserting Stack: Missions/Insert/NeedComms.xml 2021-08-11T18:22:05.638Z,1628706125.638 [MissionManager](INFO): DefineArg LBLTest:transit:NeedComms.DiveInterval = 3.000000 h 2021-08-11T18:22:05.640Z,1628706125.640 [MissionManager](INFO): DefineArg LBLTest:transit:NeedComms.WaitForPitchUp = 10.000000 min 2021-08-11T18:22:05.642Z,1628706125.642 [MissionManager](INFO): DefineArg LBLTest:transit:NeedComms.SurfacePitch = 20.000000 arcdeg 2021-08-11T18:22:05.668Z,1628706125.668 [MissionManager](INFO): DefineArg LBLTest:transit:NeedComms.SurfaceDepthRate = nan m/s 2021-08-11T18:22:05.670Z,1628706125.670 [MissionManager](INFO): DefineArg LBLTest:transit:NeedComms.SurfaceSpeed = 1.000000 m/s 2021-08-11T18:22:05.686Z,1628706125.686 [MissionManager](INFO): DefineArg LBLTest:transit:NeedComms.GPSTimeout = 7.000000 min 2021-08-11T18:22:05.710Z,1628706125.710 [MissionManager](INFO): DefineArg LBLTest:transit:NeedComms.CommsTimeout = 30.000000 min 2021-08-11T18:22:05.732Z,1628706125.732 [LBLTest:transit:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface. 2021-08-11T18:22:05.787Z,1628706125.787 [MissionManager](INFO): Inserting Stack: Missions/Insert/StandardEnvelopes.xml 2021-08-11T18:22:05.854Z,1628706125.854 [MissionManager](INFO): DefineArg LBLTest:transit:StandardEnvelopes.MinAltitude = 5.000000 m 2021-08-11T18:22:05.857Z,1628706125.857 [MissionManager](INFO): DefineArg LBLTest:transit:StandardEnvelopes.MaxDepth = 200.000000 m 2021-08-11T18:22:05.859Z,1628706125.859 [MissionManager](INFO): DefineArg LBLTest:transit:StandardEnvelopes.MinOffshore = 2000.000000 m 2021-08-11T18:22:05.860Z,1628706125.860 [LBLTest:transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2021-08-11T18:22:05.893Z,1628706125.893 [LBLTest:transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2021-08-11T18:22:05.919Z,1628706125.919 [LBLTest:transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2021-08-11T18:22:05.952Z,1628706125.952 [LBLTest:transit:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy. 2021-08-11T18:22:05.955Z,1628706125.955 [LBLTest:transit:MassHold.Pitch](DEBUG): Construct. 2021-08-11T18:22:05.992Z,1628706125.992 [LBLTest:transit:Transit:A.Pitch](DEBUG): Construct. 2021-08-11T18:22:06.008Z,1628706126.008 [LBLTest:transit:Transit:B.SetSpeed](DEBUG): Construct. 2021-08-11T18:22:06.011Z,1628706126.011 [LBLTest:transit:Transit:Wpt1.Waypoint](DEBUG): Construct Waypoint. 2021-08-11T18:22:06.030Z,1628706126.030 [MissionManager](INFO): RedefineArg LBLTest:transit.MissionTimeout = value:90.000000 min 2021-08-11T18:22:06.036Z,1628706126.036 [MissionManager](INFO): RedefineArg LBLTest:transit.Latitude = value:36.804901 arcdeg 2021-08-11T18:22:06.041Z,1628706126.041 [MissionManager](INFO): RedefineArg LBLTest:transit.Longitude = value:-121.810799 arcdeg 2021-08-11T18:22:06.043Z,1628706126.043 [MissionManager](INFO): RedefineArg LBLTest:transit.MinOffshore = value:750.000000 m 2021-08-11T18:22:06.070Z,1628706126.070 [MissionManager](DEBUG): [36.8034,-121.8223] 25 [36.7934,-121.8322] 25 [36.7934,-121.8122] 25 WhenAgg timed out 90 36.8049 -121.8108 750 2021-08-11T18:22:06.070Z,1628706126.070 [CommandLine](IMPORTANT): Loaded ./Missions/Engineering/LBLTest.xml 2021-08-11T18:22:12.382Z,1628706132.382 [Micromodem](INFO): Nmea in: $CAREV,182212,AUV,2.1.4000*20 2021-08-11T18:22:12.383Z,1628706132.383 [Micromodem](INFO): Nmea in: $CAREV,182212,COPROC,0.20.0.39330*71 2021-08-11T18:22:22.485Z,1628706142.485 [Micromodem](INFO): Nmea in: $CAREV,182222,AUV,2.1.4000*23 2021-08-11T18:22:22.486Z,1628706142.486 [Micromodem](INFO): Nmea in: $CAREV,182222,COPROC,0.20.0.39330*72 2021-08-11T18:22:25.312Z,1628706145.312 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items 2021-08-11T18:22:32.186Z,1628706152.186 [Micromodem](INFO): Nmea in: $CAREV,182232,AUV,2.1.4000*22 2021-08-11T18:22:32.582Z,1628706152.582 [Micromodem](INFO): Nmea in: $CAREV,182232,COPROC,0.20.0.39330*73 2021-08-11T18:22:34.212Z,1628706154.212 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31 2021-08-11T18:22:34.216Z,1628706154.216 [Micromodem](INFO): Nmea in: $CACST,6,1,20210811182233.958485,06,860,23,0097,0150,149,01,01,01,01,-1,-01,-01,1,3,1,0,150,25.9,0.76,-100,2.24,-01,-1.45,54,14500,4000*65 2021-08-11T18:22:34.598Z,1628706154.598 [Micromodem](INFO): Nmea in: $CAREV,182234,AUV,2.1.4000*24 2021-08-11T18:22:34.599Z,1628706154.599 [Micromodem](INFO): Nmea in: $CAREV,182234,COPROC,0.20.0.39330*75 2021-08-11T18:22:36.627Z,1628706156.627 [Micromodem](INFO): Nmea in: $CAMSG,BAD_CRC, Data Header,2*00 2021-08-11T18:22:37.034Z,1628706157.034 [Micromodem](INFO): Nmea in: $CACST,6,1,20210811182235.955592,06,739,22,0208,0150,208,02,02,01,02,5,-01,-01,2,3,1,0,150,1.0,6.47,-100,-3.47,-01,-1.35,63,14500,4000*51 2021-08-11T18:22:37.035Z,1628706157.035 [Micromodem](INFO): Nmea in: $CAREV,182237,AUV,2.1.4000*27 2021-08-11T18:22:37.430Z,1628706157.430 [Micromodem](INFO): Nmea in: $CAREV,182237,COPROC,0.20.0.39330*76 2021-08-11T18:22:39.840Z,1628706159.840 [CommandLine](IMPORTANT): got command run 2021-08-11T18:22:39.841Z,1628706159.841 [CommandLine](IMPORTANT): Running 2021-08-11T18:22:39.940Z,1628706159.940 [DefaultWithUndock] Stopped 2021-08-11T18:22:39.940Z,1628706159.940 [DefaultWithUndock](DEBUG): Aggregate::uninitialize DefaultWithUndock 2021-08-11T18:22:39.940Z,1628706159.940 [DefaultWithUndock:SurfaceDefault] Stopped 2021-08-11T18:22:39.940Z,1628706159.940 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault 2021-08-11T18:22:39.940Z,1628706159.940 [DefaultWithUndock:SurfaceDefault:B.GoToSurface] Stopped 2021-08-11T18:22:39.940Z,1628706159.940 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2021-08-11T18:22:39.940Z,1628706159.940 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped 2021-08-11T18:22:39.941Z,1628706159.941 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn 2021-08-11T18:22:39.941Z,1628706159.941 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped 2021-08-11T18:22:39.941Z,1628706159.941 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-08-11T18:22:39.941Z,1628706159.941 [MissionManager](IMPORTANT): Started mission LBLTest 2021-08-11T18:22:39.941Z,1628706159.941 [LBLTest] Running Loop=1 2021-08-11T18:22:39.941Z,1628706159.941 [LBLTest](DEBUG): Aggregate::initialize LBLTest 2021-08-11T18:22:39.941Z,1628706159.941 [LBLTest:transit] Running Loop=1 2021-08-11T18:22:39.942Z,1628706159.942 [LBLTest:transit](DEBUG): Aggregate::initialize LBLTest:transit 2021-08-11T18:22:39.942Z,1628706159.942 [LBLTest:transit:StandardEnvelopes] Running Loop=1 2021-08-11T18:22:39.942Z,1628706159.942 [LBLTest:transit:StandardEnvelopes](DEBUG): Aggregate::initialize LBLTest:transit:StandardEnvelopes 2021-08-11T18:22:39.942Z,1628706159.942 [LBLTest:transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2021-08-11T18:22:39.942Z,1628706159.942 [LBLTest:transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2021-08-11T18:22:39.942Z,1628706159.942 [LBLTest:transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2021-08-11T18:22:39.942Z,1628706159.942 [LBLTest:transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2021-08-11T18:22:39.942Z,1628706159.942 [LBLTest:transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2021-08-11T18:22:39.942Z,1628706159.942 [LBLTest:transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2021-08-11T18:22:39.942Z,1628706159.942 [LBLTest:transit:BuoyancyHold.Buoyancy] Running Loop=1 2021-08-11T18:22:39.943Z,1628706159.943 [LBLTest:transit:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2021-08-11T18:22:39.943Z,1628706159.943 [LBLTest:transit:MassHold.Pitch] Running Loop=1 2021-08-11T18:22:39.943Z,1628706159.943 [LBLTest:transit:MassHold.Pitch](DEBUG): Initialize. 2021-08-11T18:22:39.943Z,1628706159.943 [LBLTest:transit:B] Running Loop=1 2021-08-11T18:22:39.949Z,1628706159.949 [LBLTest:transit:MassHold.Pitch] Running Loop=1 2021-08-11T18:22:39.949Z,1628706159.949 [LBLTest:transit:BuoyancyHold.Buoyancy] Running Loop=1 2021-08-11T18:22:39.949Z,1628706159.949 [LBLTest:transit:StandardEnvelopes] Running Loop=1 2021-08-11T18:22:39.949Z,1628706159.949 [LBLTest:transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2021-08-11T18:22:39.949Z,1628706159.949 [LBLTest:transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2021-08-11T18:22:39.950Z,1628706159.950 [LBLTest:transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2021-08-11T18:22:39.950Z,1628706159.950 [LBLTest:transit:B] Stopped 2021-08-11T18:22:39.950Z,1628706159.950 [LBLTest:transit:C] Running Loop=1 2021-08-11T18:22:39.950Z,1628706159.950 [LBLTest:WhenAgg] Running Loop=1 2021-08-11T18:22:39.951Z,1628706159.951 [LBLTest:WhenAgg](DEBUG): Aggregate::initialize LBLTest:WhenAgg 2021-08-11T18:22:39.951Z,1628706159.951 [LBLTest:WhenAgg:A] Running Loop=1 2021-08-11T18:22:39.951Z,1628706159.951 [LBLTest:WhenAgg:B] Running Loop=1 2021-08-11T18:22:39.951Z,1628706159.951 [LBLTest:WhenAgg:C] Running Loop=1 2021-08-11T18:22:39.951Z,1628706159.951 [LBLTest:WhenAgg:D.LBL] Running Loop=1 2021-08-11T18:22:39.951Z,1628706159.951 [LBLTest:WhenAgg:D.LBL](DEBUG): Initialize LBLComponent. 2021-08-11T18:22:39.951Z,1628706159.951 [LBLTest:WhenAgg:D.LBL] Running Loop=1 2021-08-11T18:22:39.957Z,1628706159.957 [LBLTest:WhenAgg:C] Running Loop=1 2021-08-11T18:22:39.957Z,1628706159.957 [LBLTest:WhenAgg:B] Running Loop=1 2021-08-11T18:22:39.957Z,1628706159.957 [LBLTest:WhenAgg:A] Running Loop=1 2021-08-11T18:22:40.262Z,1628706160.262 [Micromodem](INFO): Nmea out: $CCPNT,14500,10,10,4050,10000,12000,0,0,3*66 2021-08-11T18:22:40.291Z,1628706160.291 [LBLNavigation](INFO): Set position 1 to [0.642340,-2.126200,25.000000] 2021-08-11T18:22:40.293Z,1628706160.293 [LBLNavigation](INFO): Set position 2 to [0.642166,-2.126373,25.000000] 2021-08-11T18:22:40.295Z,1628706160.295 [LBLNavigation](INFO): Set position 3 to [0.642166,-2.126024,25.000000] 2021-08-11T18:22:40.344Z,1628706160.344 [LBLTest:transit:C] Stopped 2021-08-11T18:22:40.344Z,1628706160.344 [LBLTest:transit:E] Running Loop=1 2021-08-11T18:22:40.662Z,1628706160.662 [Micromodem](INFO): Nmea in: $SNPNT,14500,10,10,4050,10000,12000,0,0,3*7B 2021-08-11T18:22:40.663Z,1628706160.663 [Micromodem](INFO): Nmea in: $CATXP,40*76 2021-08-11T18:22:40.759Z,1628706160.759 [LBLTest:transit:E] Stopped 2021-08-11T18:22:40.760Z,1628706160.760 [LBLTest:transit:F] Running Loop=1 2021-08-11T18:22:41.060Z,1628706161.060 [Micromodem](INFO): Nmea in: $CATXF,40*60 2021-08-11T18:22:41.073Z,1628706161.073 [LBLTest:transit:F] Stopped 2021-08-11T18:22:41.073Z,1628706161.073 [LBLTest:transit:G] Running Loop=1 2021-08-11T18:22:41.498Z,1628706161.498 [LBLTest:transit:G] Stopped 2021-08-11T18:22:41.498Z,1628706161.498 [LBLTest:transit:Transit] Running Loop=1 2021-08-11T18:22:41.498Z,1628706161.498 [LBLTest:transit:Transit](DEBUG): Aggregate::initialize LBLTest:transit:Transit 2021-08-11T18:22:41.498Z,1628706161.498 [LBLTest:transit:Transit:A.Pitch] Running Loop=1 2021-08-11T18:22:41.498Z,1628706161.498 [LBLTest:transit:Transit:A.Pitch](DEBUG): Initialize. 2021-08-11T18:22:41.498Z,1628706161.498 [LBLTest:transit:Transit:B.SetSpeed] Running Loop=1 2021-08-11T18:22:41.498Z,1628706161.498 [LBLTest:transit:Transit:B.SetSpeed](DEBUG): Initialize. 2021-08-11T18:22:41.499Z,1628706161.499 [LBLTest:transit:Transit:Wpt1.Waypoint] Running Loop=1 2021-08-11T18:22:41.499Z,1628706161.499 [LBLTest:transit:Transit:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent. 2021-08-11T18:22:41.925Z,1628706161.925 [LBLTest:transit:Transit:B.SetSpeed] Running Loop=1 2021-08-11T18:22:41.925Z,1628706161.925 [LBLTest:transit:Transit:A.Pitch] Running Loop=1 2021-08-11T18:22:42.284Z,1628706162.284 [Micromodem](INFO): Nmea in: $SNTTA,0.403505,0.794184,,,182240.75*51 2021-08-11T18:22:42.286Z,1628706162.286 [Micromodem](INFO): Nmea out: $CCPNT,14500,10,10,4050,10000,12000,0,0,3*66 2021-08-11T18:22:42.292Z,1628706162.292 [Micromodem](INFO): Nmea in: $CAREV,182242,AUV,2.1.4000*25 2021-08-11T18:22:42.378Z,1628706162.378 [LBLNavigation](INFO): Got ping from #1, tof=0.403505, distance= 605.759923 2021-08-11T18:22:42.378Z,1628706162.378 [LBLNavigation](INFO): Ignoring LBL ping, distance from baseline=-1738502.689157 2021-08-11T18:22:42.379Z,1628706162.379 [LBLNavigation](INFO): Got ping from #2, tof=0.794184, distance= 1191.531393 2021-08-11T18:22:42.391Z,1628706162.391 [LBLNavigation](INFO): Setting location to [36.798207,-121.820240] 2021-08-11T18:22:42.493Z,1628706162.493 [CommandLine](IMPORTANT): got command stop 2021-08-11T18:22:42.494Z,1628706162.494 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop 2021-08-11T18:22:42.679Z,1628706162.679 [Micromodem](INFO): Nmea in: $CAREV,182242,COPROC,0.20.0.39330*74 2021-08-11T18:22:42.680Z,1628706162.680 [Micromodem](INFO): Nmea in: $SNPNT,14500,10,10,4050,10000,12000,0,0,3*7B 2021-08-11T18:22:42.698Z,1628706162.698 [LBLTest:transit:Transit:Wpt1.Waypoint](IMPORTANT): Reached Waypoint: 36.804901,-121.810799 2021-08-11T18:22:42.698Z,1628706162.698 [LBLTest:transit:Transit:Wpt1.Waypoint] Stopped 2021-08-11T18:22:42.698Z,1628706162.698 [LBLTest:transit:Transit:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent. 2021-08-11T18:22:42.698Z,1628706162.698 [LBLTest:transit:Transit:PhoneHome] Running Loop=1 2021-08-11T18:22:42.698Z,1628706162.698 [LBLTest:transit:Transit:PhoneHome](DEBUG): Aggregate::initialize LBLTest:transit:Transit:PhoneHome 2021-08-11T18:22:42.702Z,1628706162.702 [MissionManager](INFO): MissionManager is completed. 2021-08-11T18:22:42.702Z,1628706162.702 [MissionManager](INFO): Uninitializing Mission LBLTest 2021-08-11T18:22:42.702Z,1628706162.702 [LBLTest] Stopped 2021-08-11T18:22:42.702Z,1628706162.702 [LBLTest](DEBUG): Aggregate::uninitialize LBLTest 2021-08-11T18:22:42.702Z,1628706162.702 [LBLTest:WhenAgg] Stopped 2021-08-11T18:22:42.702Z,1628706162.702 [LBLTest:WhenAgg](DEBUG): Aggregate::uninitialize LBLTest:WhenAgg 2021-08-11T18:22:42.702Z,1628706162.702 [LBLTest:WhenAgg:A] Stopped 2021-08-11T18:22:42.702Z,1628706162.702 [LBLTest:WhenAgg:B] Stopped 2021-08-11T18:22:42.702Z,1628706162.702 [LBLTest:WhenAgg:C] Stopped 2021-08-11T18:22:42.702Z,1628706162.702 [LBLTest:WhenAgg:D.LBL] Stopped 2021-08-11T18:22:42.702Z,1628706162.702 [LBLTest:WhenAgg:D.LBL](DEBUG): Uninitialize LBLComponent. 2021-08-11T18:22:42.703Z,1628706162.703 [LBLTest:transit] Stopped 2021-08-11T18:22:42.703Z,1628706162.703 [LBLTest:transit](DEBUG): Aggregate::uninitialize LBLTest:transit 2021-08-11T18:22:42.703Z,1628706162.703 [LBLTest:transit:NeedComms] Stopped 2021-08-11T18:22:42.703Z,1628706162.703 [LBLTest:transit:NeedComms](DEBUG): Aggregate::uninitialize LBLTest:transit:NeedComms 2021-08-11T18:22:42.703Z,1628706162.703 [LBLTest:transit:StandardEnvelopes] Stopped 2021-08-11T18:22:42.703Z,1628706162.703 [LBLTest:transit:StandardEnvelopes](DEBUG): Aggregate::uninitialize LBLTest:transit:StandardEnvelopes 2021-08-11T18:22:42.703Z,1628706162.703 [LBLTest:transit:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2021-08-11T18:22:42.703Z,1628706162.703 [LBLTest:transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2021-08-11T18:22:42.703Z,1628706162.703 [LBLTest:transit:StandardEnvelopes:B.DepthEnvelope] Stopped 2021-08-11T18:22:42.703Z,1628706162.703 [LBLTest:transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2021-08-11T18:22:42.703Z,1628706162.703 [LBLTest:transit:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2021-08-11T18:22:42.703Z,1628706162.703 [LBLTest:transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2021-08-11T18:22:42.704Z,1628706162.704 [LBLTest:transit:BuoyancyHold.Buoyancy] Stopped 2021-08-11T18:22:42.750Z,1628706162.750 [LBLTest:transit:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2021-08-11T18:22:42.750Z,1628706162.750 [LBLTest:transit:MassHold.Pitch] Stopped 2021-08-11T18:22:42.750Z,1628706162.750 [LBLTest:transit:Transit] Stopped 2021-08-11T18:22:42.750Z,1628706162.750 [LBLTest:transit:Transit](DEBUG): Aggregate::uninitialize LBLTest:transit:Transit 2021-08-11T18:22:42.750Z,1628706162.750 [LBLTest:transit:Transit:A.Pitch] Stopped 2021-08-11T18:22:42.750Z,1628706162.750 [LBLTest:transit:Transit:B.SetSpeed] Stopped 2021-08-11T18:22:42.750Z,1628706162.750 [LBLTest:transit:Transit:B.SetSpeed](DEBUG): Uninitialize. 2021-08-11T18:22:42.750Z,1628706162.750 [LBLTest:transit:Transit:PhoneHome] Stopped 2021-08-11T18:22:42.750Z,1628706162.750 [LBLTest:transit:Transit:PhoneHome](DEBUG): Aggregate::uninitialize LBLTest:transit:Transit:PhoneHome 2021-08-11T18:22:43.117Z,1628706163.117 [Micromodem](INFO): Nmea in: $CATXP,40*76 2021-08-11T18:22:43.118Z,1628706163.118 [Micromodem](INFO): Nmea in: $CATXF,40*60 2021-08-11T18:22:43.175Z,1628706163.175 [MissionManager](IMPORTANT): Started mission DefaultWithUndock 2021-08-11T18:22:43.175Z,1628706163.175 [DefaultWithUndock] Running Loop=1 2021-08-11T18:22:43.175Z,1628706163.175 [DefaultWithUndock](DEBUG): Aggregate::initialize DefaultWithUndock 2021-08-11T18:22:43.175Z,1628706163.175 [DefaultWithUndock:A.Wait] Running Loop=1 2021-08-11T18:22:43.175Z,1628706163.175 [DefaultWithUndock:A.Wait](DEBUG): Initialize Wait Component. 2021-08-11T18:22:44.699Z,1628706164.699 [Micromodem](INFO): Nmea in: $SNTTA,1.069152,0.794451,,,182242.78*5C 2021-08-11T18:22:44.701Z,1628706164.701 [Micromodem](INFO): Nmea in: $CAREV,182244,AUV,2.1.4000*23 2021-08-11T18:22:44.712Z,1628706164.712 [LBLNavigation](INFO): Got ping from #1, tof=1.069152, distance= 1603.918244 2021-08-11T18:22:44.714Z,1628706164.714 [LBLNavigation](INFO): Setting location to [36.789097,-121.819951] 2021-08-11T18:22:44.715Z,1628706164.715 [LBLNavigation](INFO): Got ping from #2, tof=0.794451, distance= 1191.932516 2021-08-11T18:22:44.717Z,1628706164.717 [LBLNavigation](INFO): Setting location to [36.789099,-121.819939] 2021-08-11T18:22:45.118Z,1628706165.118 [Micromodem](INFO): Nmea in: $CAREV,182244,COPROC,0.20.0.39330*72 2021-08-11T18:22:54.802Z,1628706174.802 [Micromodem](INFO): Nmea in: $CAREV,182254,AUV,2.1.4000*22 2021-08-11T18:22:54.803Z,1628706174.803 [Micromodem](INFO): Nmea in: $CAREV,182254,COPROC,0.20.0.39330*73 2021-08-11T18:23:04.902Z,1628706184.902 [Micromodem](INFO): Nmea in: $CAREV,182304,AUV,2.1.4000*26 2021-08-11T18:23:04.903Z,1628706184.903 [Micromodem](INFO): Nmea in: $CAREV,182304,COPROC,0.20.0.39330*77 2021-08-11T18:23:13.402Z,1628706193.402 [DefaultWithUndock:A.Wait](INFO): Done Waiting. 2021-08-11T18:23:13.402Z,1628706193.402 [DefaultWithUndock:A.Wait] Stopped 2021-08-11T18:23:13.402Z,1628706193.402 [DefaultWithUndock:A.Wait](DEBUG): Uninitialize Wait Component. 2021-08-11T18:23:13.402Z,1628706193.402 [DefaultWithUndock:LeaveDock] Running Loop=1 2021-08-11T18:23:13.402Z,1628706193.402 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::initialize DefaultWithUndock:LeaveDock 2021-08-11T18:23:13.403Z,1628706193.403 [DefaultWithUndock:LeaveDock:A.] Running Loop=1 2021-08-11T18:23:13.403Z,1628706193.403 [DefaultWithUndock:LeaveDock:A.](INFO): Initializing TrackAcousticContact. 2021-08-11T18:23:13.403Z,1628706193.403 [DefaultWithUndock:LeaveDock:B.Undock] Running Loop=1 2021-08-11T18:23:13.403Z,1628706193.403 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize. 2021-08-11T18:23:13.403Z,1628706193.403 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values. 2021-08-11T18:23:14.195Z,1628706194.195 [PowerOnly](INFO): Powering up loadControl 2021-08-11T18:23:14.710Z,1628706194.710 [Micromodem](INFO): Nmea in: $CAREV,182314,AUV,2.1.4000*27 2021-08-11T18:23:14.712Z,1628706194.712 [Micromodem](INFO): Nmea in: $CAREV,182314,COPROC,0.20.0.39330*76 2021-08-11T18:23:16.057Z,1628706196.057 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2021-08-11T18:23:16.671Z,1628706196.671 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76 2021-08-11T18:23:16.759Z,1628706196.759 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detaching from dock. 2021-08-11T18:23:17.052Z,1628706197.052 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B 2021-08-11T18:23:17.118Z,1628706197.118 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detected possible detachment. Starting timer. 2021-08-11T18:23:17.861Z,1628706197.861 [Micromodem](INFO): Nmea in: $SNTTA,,,,,182317.20*52 2021-08-11T18:23:17.863Z,1628706197.863 [Micromodem](INFO): Nmea in: $CAREV,182318,AUV,2.1.4000*2B 2021-08-11T18:23:18.284Z,1628706198.284 [Micromodem](INFO): Nmea in: $CAREV,182318,COPROC,0.20.0.39330*7A 2021-08-11T18:23:18.674Z,1628706198.674 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2021-08-11T18:23:20.273Z,1628706200.273 [DUSBL_Hydroid](ERROR): No response from remote modem. 2021-08-11T18:23:21.573Z,1628706201.573 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2021-08-11T18:23:22.307Z,1628706202.307 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76 2021-08-11T18:23:22.704Z,1628706202.704 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B 2021-08-11T18:23:23.578Z,1628706203.578 [Micromodem](INFO): Nmea in: $SNTTA,,,,,182322.84*5A 2021-08-11T18:23:23.579Z,1628706203.579 [Micromodem](INFO): Nmea in: $CAREV,182323,AUV,2.1.4000*23 2021-08-11T18:23:23.923Z,1628706203.923 [Micromodem](INFO): Nmea in: $CAREV,182323,COPROC,0.20.0.39330*72 2021-08-11T18:23:24.373Z,1628706204.373 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2021-08-11T18:23:25.941Z,1628706205.941 [DUSBL_Hydroid](ERROR): No response from remote modem. 2021-08-11T18:23:27.158Z,1628706207.158 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2021-08-11T18:23:27.955Z,1628706207.955 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76 2021-08-11T18:23:28.393Z,1628706208.393 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B 2021-08-11T18:23:29.198Z,1628706209.198 [Micromodem](INFO): Nmea in: $SNTTA,,,,,182328.49*51 2021-08-11T18:23:29.200Z,1628706209.200 [Micromodem](INFO): Nmea in: $CAREV,182329,AUV,2.1.4000*29 2021-08-11T18:23:29.642Z,1628706209.642 [Micromodem](INFO): Nmea in: $CAREV,182329,COPROC,0.20.0.39330*78 2021-08-11T18:23:29.767Z,1628706209.767 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2021-08-11T18:23:31.617Z,1628706211.617 [DUSBL_Hydroid](ERROR): No response from remote modem. 2021-08-11T18:23:32.414Z,1628706212.414 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2021-08-11T18:23:33.207Z,1628706213.207 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76 2021-08-11T18:23:33.620Z,1628706213.620 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B 2021-08-11T18:23:34.450Z,1628706214.450 [Micromodem](INFO): Nmea in: $SNTTA,,,,,182333.74*55 2021-08-11T18:23:34.452Z,1628706214.452 [Micromodem](INFO): Nmea in: $CAREV,182334,AUV,2.1.4000*25 2021-08-11T18:23:34.827Z,1628706214.827 [Micromodem](INFO): Nmea in: $CAREV,182334,COPROC,0.20.0.39330*74 2021-08-11T18:23:35.305Z,1628706215.305 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2021-08-11T18:23:36.841Z,1628706216.841 [DUSBL_Hydroid](ERROR): No response from remote modem. 2021-08-11T18:23:37.262Z,1628706217.262 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached at range: 624.36 m. Transitioning DDM to standby. 2021-08-11T18:23:38.071Z,1628706218.071 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2021-08-11T18:23:38.867Z,1628706218.867 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76 2021-08-11T18:23:39.273Z,1628706219.273 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B 2021-08-11T18:23:39.747Z,1628706219.747 [DefaultWithUndock:LeaveDock:B.Undock](INFO): DDM at standby. 2021-08-11T18:23:40.086Z,1628706220.086 [Micromodem](INFO): Nmea in: $SNTTA,,,,,182339.40*58 2021-08-11T18:23:40.087Z,1628706220.087 [Micromodem](INFO): Nmea in: $CAREV,182340,AUV,2.1.4000*26 2021-08-11T18:23:40.099Z,1628706220.099 [DefaultWithUndock:LeaveDock:B.Undock](IMPORTANT): Undocking sequence complete. 2021-08-11T18:23:40.100Z,1628706220.100 [DefaultWithUndock:LeaveDock:B.Undock] Stopped 2021-08-11T18:23:40.100Z,1628706220.100 [DefaultWithUndock:LeaveDock](INFO): Completed DefaultWithUndock:LeaveDock 2021-08-11T18:23:40.100Z,1628706220.100 [DefaultWithUndock:LeaveDock] Stopped 2021-08-11T18:23:40.100Z,1628706220.100 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::uninitialize DefaultWithUndock:LeaveDock 2021-08-11T18:23:40.100Z,1628706220.100 [DefaultWithUndock:LeaveDock:A.] Stopped 2021-08-11T18:23:40.101Z,1628706220.101 [DefaultWithUndock:SurfaceDefault] Running Loop=1 2021-08-11T18:23:40.101Z,1628706220.101 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault 2021-08-11T18:23:40.101Z,1628706220.101 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen] Running Loop=1 2021-08-11T18:23:40.101Z,1628706220.101 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:HoldLatchOpen 2021-08-11T18:23:40.101Z,1628706220.101 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen:A] Running Loop=1 2021-08-11T18:23:40.101Z,1628706220.101 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen:B] Running Loop=1 2021-08-11T18:23:40.101Z,1628706220.101 [DefaultWithUndock:SurfaceDefault:B.GoToSurface] Running Loop=1 2021-08-11T18:23:40.101Z,1628706220.101 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2021-08-11T18:23:40.101Z,1628706220.101 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2021-08-11T18:23:40.102Z,1628706220.102 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2021-08-11T18:23:40.102Z,1628706220.102 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2021-08-11T18:23:40.102Z,1628706220.102 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2021-08-11T18:23:40.103Z,1628706220.103 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2021-08-11T18:23:40.451Z,1628706220.451 [Micromodem](INFO): Nmea in: $CAREV,182340,COPROC,0.20.0.39330*77 2021-08-11T18:23:40.469Z,1628706220.469 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=1 2021-08-11T18:23:40.469Z,1628706220.469 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2021-08-11T18:23:40.470Z,1628706220.470 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2021-08-11T18:23:40.470Z,1628706220.470 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen] Stopped 2021-08-11T18:23:40.470Z,1628706220.470 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:HoldLatchOpen 2021-08-11T18:23:40.470Z,1628706220.470 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen:A] Stopped 2021-08-11T18:23:40.470Z,1628706220.470 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen:B] Stopped 2021-08-11T18:23:42.472Z,1628706222.472 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182341.00,A,4133.71986,N,07041.86444,W,0.486,167.25,110821,,,A*7B 2021-08-11T18:23:42.475Z,1628706222.475 [NAL9602](INFO): GPS fix at 20210811T182341: (41.561998, -70.697741) 2021-08-11T18:23:42.487Z,1628706222.487 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2021-08-11T18:23:42.487Z,1628706222.487 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2021-08-11T18:23:47.817Z,1628706227.817 [DataOverHttps](INFO): Sending 52 bytes from file Logs/20210811T160034/Courier0048.lzma 2021-08-11T18:23:48.818Z,1628706228.818 [DataOverHttps](INFO): Moved sent file to Logs/20210811T160034/Courier0048.lzma.bak 2021-08-11T18:23:48.818Z,1628706228.818 [DataOverHttps](INFO): SBD MOMSN=421548 2021-08-11T18:23:50.146Z,1628706230.146 [Micromodem](INFO): Nmea in: $CAREV,182350,AUV,2.1.4000*27 2021-08-11T18:23:50.554Z,1628706230.554 [Micromodem](INFO): Nmea in: $CAREV,182350,COPROC,0.20.0.39330*76 2021-08-11T18:23:59.884Z,1628706239.884 [DataOverHttps](INFO): Sending 255 bytes from file Logs/20210811T182103/Courier0000.lzma 2021-08-11T18:24:00.250Z,1628706240.250 [Micromodem](INFO): Nmea in: $CAREV,182400,AUV,2.1.4000*25 2021-08-11T18:24:00.654Z,1628706240.654 [Micromodem](INFO): Nmea in: $CAREV,182400,COPROC,0.20.0.39330*74 2021-08-11T18:24:00.886Z,1628706240.886 [DataOverHttps](INFO): Moved sent file to Logs/20210811T182103/Courier0000.lzma.bak 2021-08-11T18:24:00.886Z,1628706240.886 [DataOverHttps](INFO): SBD MOMSN=421550 2021-08-11T18:24:02.268Z,1628706242.268 [NAL9602](INFO): SBD MO Status=0, MOMSN=23245, MT Status=0, MTMSN=0 2021-08-11T18:24:02.268Z,1628706242.268 [NAL9602](INFO): No messages in MT queue 2021-08-11T18:24:09.923Z,1628706249.923 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20210811T160034/Express0049.lzma 2021-08-11T18:24:10.349Z,1628706250.349 [Micromodem](INFO): Nmea in: $CAREV,182410,AUV,2.1.4000*24 2021-08-11T18:24:10.754Z,1628706250.754 [Micromodem](INFO): Nmea in: $CAREV,182410,COPROC,0.20.0.39330*75 2021-08-11T18:24:10.926Z,1628706250.926 [DataOverHttps](INFO): Moved sent file to Logs/20210811T160034/Express0049.lzma.bak 2021-08-11T18:24:10.926Z,1628706250.926 [DataOverHttps](INFO): SBD MOMSN=421554 2021-08-11T18:24:19.965Z,1628706259.965 [DataOverHttps](INFO): Sending 578 bytes from file Logs/20210811T182103/Express0001.lzma 2021-08-11T18:24:20.052Z,1628706260.052 [Micromodem](INFO): Nmea in: $CAREV,182420,AUV,2.1.4000*27 2021-08-11T18:24:20.450Z,1628706260.450 [Micromodem](INFO): Nmea in: $CAREV,182420,COPROC,0.20.0.39330*76 2021-08-11T18:24:20.966Z,1628706260.966 [DataOverHttps](INFO): Moved sent file to Logs/20210811T182103/Express0001.lzma.bak 2021-08-11T18:24:20.966Z,1628706260.966 [DataOverHttps](INFO): SBD MOMSN=421556 2021-08-11T18:24:22.489Z,1628706262.489 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2021-08-11T18:24:22.489Z,1628706262.489 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1 2021-08-11T18:24:22.489Z,1628706262.489 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-08-11T18:24:30.146Z,1628706270.146 [Micromodem](INFO): Nmea in: $CAREV,182430,AUV,2.1.4000*26 2021-08-11T18:24:30.147Z,1628706270.147 [Micromodem](INFO): Nmea in: $CAREV,182430,COPROC,0.20.0.39330*77 2021-08-11T18:24:32.977Z,1628706272.977 [NAL9602](INFO): Not Powering down - fast GPS 2021-08-11T18:24:39.862Z,1628706279.862 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2021-08-11T18:24:40.242Z,1628706280.242 [Micromodem](INFO): Nmea in: $CAREV,182440,AUV,2.1.4000*21 2021-08-11T18:24:40.243Z,1628706280.243 [Micromodem](INFO): Nmea in: $CAREV,182440,COPROC,0.20.0.39330*70 2021-08-11T18:24:45.497Z,1628706285.497 [PowerOnly](INFO): Powering down loadControl 2021-08-11T18:24:50.346Z,1628706290.346 [Micromodem](INFO): Nmea in: $CAREV,182450,AUV,2.1.4000*20 2021-08-11T18:24:50.348Z,1628706290.348 [Micromodem](INFO): Nmea in: $CAREV,182450,COPROC,0.20.0.39330*71 2021-08-11T18:25:00.456Z,1628706300.456 [Micromodem](INFO): Nmea in: $CAREV,182500,AUV,2.1.4000*24 2021-08-11T18:25:00.457Z,1628706300.457 [Micromodem](INFO): Nmea in: $CAREV,182500,COPROC,0.20.0.39330*75 2021-08-11T18:25:10.153Z,1628706310.153 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31 2021-08-11T18:25:10.550Z,1628706310.550 [Micromodem](INFO): Nmea in: $CACST,6,1,20210811182509.971539,06,212,23,0103,0150,149,01,01,00,01,-1,-01,-01,1,3,5,0,150,25.1,-0.13,-100,3.13,-01,1.03,55,14500,4000*6D 2021-08-11T18:25:10.551Z,1628706310.551 [Micromodem](INFO): Nmea in: $CAREV,182510,AUV,2.1.4000*25 2021-08-11T18:25:10.946Z,1628706310.946 [Micromodem](INFO): Nmea in: $CAREV,182510,COPROC,0.20.0.39330*74 2021-08-11T18:25:20.238Z,1628706320.238 [Micromodem](INFO): Nmea in: $CAREV,182520,AUV,2.1.4000*26 2021-08-11T18:25:20.240Z,1628706320.240 [Micromodem](INFO): Nmea in: $CAREV,182520,COPROC,0.20.0.39330*77 2021-08-11T18:25:30.342Z,1628706330.342 [Micromodem](INFO): Nmea in: $CAREV,182530,AUV,2.1.4000*27 2021-08-11T18:25:30.344Z,1628706330.344 [Micromodem](INFO): Nmea in: $CAREV,182530,COPROC,0.20.0.39330*76 2021-08-11T18:25:40.440Z,1628706340.440 [Micromodem](INFO): Nmea in: $CAREV,182540,AUV,2.1.4000*20 2021-08-11T18:25:40.441Z,1628706340.441 [Micromodem](INFO): Nmea in: $CAREV,182540,COPROC,0.20.0.39330*71 2021-08-11T18:25:50.548Z,1628706350.548 [Micromodem](INFO): Nmea in: $CAREV,182550,AUV,2.1.4000*21 2021-08-11T18:25:50.549Z,1628706350.549 [Micromodem](INFO): Nmea in: $CAREV,182550,COPROC,0.20.0.39330*70 2021-08-11T18:25:54.183Z,1628706354.183 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31 2021-08-11T18:25:54.585Z,1628706354.585 [Micromodem](INFO): Nmea in: $CACST,6,1,20210811182553.972983,06,1077,24,0175,0150,208,02,02,01,02,-1,-01,-01,1,3,1,0,150,10.0,0.33,-100,2.67,-01,-1.49,64,14500,4000*51 2021-08-11T18:25:54.587Z,1628706354.587 [Micromodem](INFO): Nmea in: $CAREV,182554,AUV,2.1.4000*25 2021-08-11T18:25:54.982Z,1628706354.982 [Micromodem](INFO): Nmea in: $CAREV,182554,COPROC,0.20.0.39330*74 2021-08-11T18:26:04.274Z,1628706364.274 [Micromodem](INFO): Nmea in: $CAREV,182604,AUV,2.1.4000*23 2021-08-11T18:26:04.276Z,1628706364.276 [Micromodem](INFO): Nmea in: $CAREV,182604,COPROC,0.20.0.39330*72 2021-08-11T18:26:14.377Z,1628706374.377 [Micromodem](INFO): Nmea in: $CAREV,182614,AUV,2.1.4000*22 2021-08-11T18:26:14.378Z,1628706374.378 [Micromodem](INFO): Nmea in: $CAREV,182614,COPROC,0.20.0.39330*73 2021-08-11T18:26:24.486Z,1628706384.486 [Micromodem](INFO): Nmea in: $CAMSG,BAD_CRC, Data Header,2*00 2021-08-11T18:26:24.893Z,1628706384.893 [Micromodem](INFO): Nmea in: $CACST,6,1,20210811182621.966364,06,1931,26,0129,0150,149,01,01,01,01,5,-01,-01,2,3,5,0,150,4.7,-5.52,-100,8.52,-01,0.88,59,14500,4000*44 2021-08-11T18:26:24.895Z,1628706384.895 [Micromodem](INFO): Nmea in: $CAREV,182624,AUV,2.1.4000*21 2021-08-11T18:26:25.282Z,1628706385.282 [Micromodem](INFO): Nmea in: $CAREV,182624,COPROC,0.20.0.39330*70 2021-08-11T18:26:34.590Z,1628706394.590 [Micromodem](INFO): Nmea in: $CAREV,182634,AUV,2.1.4000*20 2021-08-11T18:26:34.592Z,1628706394.592 [Micromodem](INFO): Nmea in: $CAREV,182634,COPROC,0.20.0.39330*71 2021-08-11T18:26:44.673Z,1628706404.673 [Micromodem](INFO): Nmea in: $CAREV,182644,AUV,2.1.4000*27 2021-08-11T18:26:44.674Z,1628706404.674 [Micromodem](INFO): Nmea in: $CAREV,182644,COPROC,0.20.0.39330*76 2021-08-11T18:26:54.774Z,1628706414.774 [Micromodem](INFO): Nmea in: $CAREV,182654,AUV,2.1.4000*26 2021-08-11T18:26:54.775Z,1628706414.775 [Micromodem](INFO): Nmea in: $CAREV,182654,COPROC,0.20.0.39330*77 2021-08-11T18:27:04.874Z,1628706424.874 [Micromodem](INFO): Nmea in: $CAREV,182704,AUV,2.1.4000*22 2021-08-11T18:27:04.905Z,1628706424.905 [Micromodem](INFO): Nmea in: $CAREV,182704,COPROC,0.20.0.39330*73 2021-08-11T18:27:14.570Z,1628706434.570 [Micromodem](INFO): Nmea in: $CAREV,182714,AUV,2.1.4000*23 2021-08-11T18:27:14.601Z,1628706434.601 [Micromodem](INFO): Nmea in: $CAREV,182714,COPROC,0.20.0.39330*72 2021-08-11T18:27:24.265Z,1628706444.265 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31 2021-08-11T18:27:24.270Z,1628706444.270 [Micromodem](INFO): Nmea in: $CACST,6,1,20210811182723.959767,06,1601,26,0098,0150,208,02,02,01,02,-1,-01,-01,1,3,1,0,150,9.4,0.04,-100,2.96,-01,-2.20,63,14500,4000*63 2021-08-11T18:27:24.674Z,1628706444.674 [Micromodem](INFO): Nmea in: $CAREV,182724,AUV,2.1.4000*20 2021-08-11T18:27:24.676Z,1628706444.676 [Micromodem](INFO): Nmea in: $CAREV,182724,COPROC,0.20.0.39330*71 2021-08-11T18:27:34.370Z,1628706454.370 [Micromodem](INFO): Nmea in: $CAREV,182734,AUV,2.1.4000*21 2021-08-11T18:27:34.371Z,1628706454.371 [Micromodem](INFO): Nmea in: $CAREV,182734,COPROC,0.20.0.39330*70 2021-08-11T18:27:44.470Z,1628706464.470 [Micromodem](INFO): Nmea in: $CAREV,182744,AUV,2.1.4000*26 2021-08-11T18:27:44.471Z,1628706464.471 [Micromodem](INFO): Nmea in: $CAREV,182744,COPROC,0.20.0.39330*77 2021-08-11T18:27:54.170Z,1628706474.170 [Micromodem](INFO): Nmea in: $CAREV,182754,AUV,2.1.4000*27 2021-08-11T18:27:54.172Z,1628706474.172 [Micromodem](INFO): Nmea in: $CAREV,182754,COPROC,0.20.0.39330*76 2021-08-11T18:28:00.229Z,1628706480.229 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31 2021-08-11T18:28:00.233Z,1628706480.233 [Micromodem](INFO): Nmea in: $CACST,6,1,20210811182759.962148,06,1619,26,0127,0150,149,01,01,01,01,-1,-01,-01,1,3,6,0,150,24.2,0.10,-100,2.90,-01,-0.28,55,14500,4000*56 2021-08-11T18:28:00.630Z,1628706480.630 [Micromodem](INFO): Nmea in: $CAREV,182800,AUV,2.1.4000*29 2021-08-11T18:28:00.632Z,1628706480.632 [Micromodem](INFO): Nmea in: $CAREV,182800,COPROC,0.20.0.39330*78 2021-08-11T18:28:10.336Z,1628706490.336 [Micromodem](INFO): Nmea in: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error 2021-08-11T18:28:10.336Z,1628706490.336 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error expected 41 2021-08-11T18:28:10.338Z,1628706490.338 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31 2021-08-11T18:28:10.730Z,1628706490.730 [Micromodem](INFO): Nmea in: $CACST,6,1,20210811182809.977884,06,280,24,0127,0150,141,00,00,00,00,-1,-01,-01,1,3,1,0,150,-100.0,0.48,-100,2.52,-01,-1.25,-2147483648,14500,4000*56 2021-08-11T18:28:10.732Z,1628706490.732 [Micromodem](INFO): Nmea in: $CAREV,182810,AUV,2.1.4000*28 2021-08-11T18:28:11.132Z,1628706491.132 [Micromodem](INFO): Nmea in: $CAREV,182810,COPROC,0.20.0.39330*79 2021-08-11T18:28:20.426Z,1628706500.426 [Micromodem](INFO): Nmea in: $CAREV,182820,AUV,2.1.4000*2B 2021-08-11T18:28:20.427Z,1628706500.427 [Micromodem](INFO): Nmea in: $CAREV,182820,COPROC,0.20.0.39330*7A 2021-08-11T18:28:30.530Z,1628706510.530 [Micromodem](INFO): Nmea in: $CAREV,182830,AUV,2.1.4000*2A 2021-08-11T18:28:30.531Z,1628706510.531 [Micromodem](INFO): Nmea in: $CAREV,182830,COPROC,0.20.0.39330*7B 2021-08-11T18:28:40.226Z,1628706520.226 [Micromodem](INFO): Nmea in: $CAREV,182840,AUV,2.1.4000*2D 2021-08-11T18:28:40.227Z,1628706520.227 [Micromodem](INFO): Nmea in: $CAREV,182840,COPROC,0.20.0.39330*7C 2021-08-11T18:28:50.329Z,1628706530.329 [Micromodem](INFO): Nmea in: $CAREV,182850,AUV,2.1.4000*2C 2021-08-11T18:28:50.330Z,1628706530.330 [Micromodem](INFO): Nmea in: $CAREV,182850,COPROC,0.20.0.39330*7D 2021-08-11T18:28:57.192Z,1628706537.192 [Micromodem](INFO): Nmea in: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error 2021-08-11T18:28:57.192Z,1628706537.192 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error expected 41 2021-08-11T18:28:57.197Z,1628706537.197 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31 2021-08-11T18:28:57.605Z,1628706537.605 [Micromodem](INFO): Nmea in: $CACST,6,1,20210811182855.875274,06,164,16,0111,0150,149,01,01,01,01,-1,-01,-01,1,3,2,0,150,67.4,-1.75,-100,4.75,-01,1.92,-6,14500,4000*75 2021-08-11T18:28:57.606Z,1628706537.606 [Micromodem](INFO): Nmea in: $CAREV,182857,AUV,2.1.4000*2B 2021-08-11T18:28:58.006Z,1628706538.006 [Micromodem](INFO): Nmea in: $CAREV,182857,COPROC,0.20.0.39330*7A 2021-08-11T18:29:07.290Z,1628706547.290 [Micromodem](INFO): Nmea in: $CAREV,182907,AUV,2.1.4000*2F 2021-08-11T18:29:07.292Z,1628706547.292 [Micromodem](INFO): Nmea in: $CAREV,182907,COPROC,0.20.0.39330*7E 2021-08-11T18:29:16.984Z,1628706556.984 [Micromodem](INFO): Nmea in: $CAREV,182917,AUV,2.1.4000*2E 2021-08-11T18:29:16.986Z,1628706556.986 [Micromodem](INFO): Nmea in: $CAREV,182917,COPROC,0.20.0.39330*7F 2021-08-11T18:29:23.083Z,1628706563.083 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting. 2021-08-11T18:29:23.083Z,1628706563.083 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped 2021-08-11T18:29:23.083Z,1628706563.083 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-08-11T18:29:23.084Z,1628706563.084 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1 2021-08-11T18:29:23.493Z,1628706563.493 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped 2021-08-11T18:29:23.493Z,1628706563.493 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1 2021-08-11T18:29:23.900Z,1628706563.900 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 6.671965 min 2021-08-11T18:29:23.900Z,1628706563.900 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped 2021-08-11T18:29:23.900Z,1628706563.900 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn 2021-08-11T18:29:23.901Z,1628706563.901 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped 2021-08-11T18:29:23.901Z,1628706563.901 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn 2021-08-11T18:29:23.901Z,1628706563.901 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #2 2021-08-11T18:29:23.901Z,1628706563.901 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=2 2021-08-11T18:29:23.901Z,1628706563.901 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2021-08-11T18:29:23.901Z,1628706563.901 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2021-08-11T18:29:25.873Z,1628706565.873 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182925.00,A,4133.70443,N,07041.84941,W,0.039,167.94,110821,,,A*76 2021-08-11T18:29:25.875Z,1628706565.875 [NAL9602](INFO): GPS fix at 20210811T182925: (41.561740, -70.697490) 2021-08-11T18:29:25.957Z,1628706565.957 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2021-08-11T18:29:25.957Z,1628706565.957 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2021-08-11T18:29:27.081Z,1628706567.081 [Micromodem](INFO): Nmea in: $CAREV,182927,AUV,2.1.4000*2D 2021-08-11T18:29:27.490Z,1628706567.490 [Micromodem](INFO): Nmea in: $CAREV,182927,COPROC,0.20.0.39330*7C 2021-08-11T18:29:30.283Z,1628706570.283 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210811T182103/Courier0003.lzma 2021-08-11T18:29:31.286Z,1628706571.286 [DataOverHttps](INFO): Moved sent file to Logs/20210811T182103/Courier0003.lzma.bak 2021-08-11T18:29:31.286Z,1628706571.286 [DataOverHttps](INFO): SBD MOMSN=421595 2021-08-11T18:29:34.359Z,1628706574.359 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31 2021-08-11T18:29:34.765Z,1628706574.765 [Micromodem](INFO): Nmea in: $CACST,6,1,20210811182933.955848,06,304,18,0211,0150,208,02,02,01,02,-1,-01,-01,1,3,2,0,150,7.3,-0.03,-100,3.03,-01,-2.69,64,14500,4000*7A 2021-08-11T18:29:35.165Z,1628706575.165 [Micromodem](INFO): Nmea in: $CAREV,182934,AUV,2.1.4000*2F 2021-08-11T18:29:35.577Z,1628706575.577 [Micromodem](INFO): Nmea in: $CAREV,182934,COPROC,0.20.0.39330*7E 2021-08-11T18:29:39.214Z,1628706579.214 [NAL9602](INFO): SBD MO Status=0, MOMSN=23246, MT Status=0, MTMSN=0 2021-08-11T18:29:39.214Z,1628706579.214 [NAL9602](INFO): No messages in MT queue 2021-08-11T18:29:40.324Z,1628706580.324 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20210811T182103/Express0004.lzma 2021-08-11T18:29:41.325Z,1628706581.325 [DataOverHttps](INFO): Moved sent file to Logs/20210811T182103/Express0004.lzma.bak 2021-08-11T18:29:41.326Z,1628706581.326 [DataOverHttps](INFO): SBD MOMSN=421598 2021-08-11T18:29:42.849Z,1628706582.849 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2021-08-11T18:29:42.849Z,1628706582.849 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1 2021-08-11T18:29:42.849Z,1628706582.849 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-08-11T18:29:44.457Z,1628706584.457 [Micromodem](INFO): Nmea in: $CAREV,182944,AUV,2.1.4000*28 2021-08-11T18:29:44.459Z,1628706584.459 [Micromodem](INFO): Nmea in: $CAREV,182944,COPROC,0.20.0.39330*79 2021-08-11T18:29:52.139Z,1628706592.139 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31 2021-08-11T18:29:52.542Z,1628706592.542 [Micromodem](INFO): Nmea in: $CACST,6,1,20210811182951.968468,06,368,25,0105,0150,149,01,01,00,01,-1,-01,-01,1,3,1,0,150,23.0,-3.12,-100,6.12,-01,-2.18,55,14500,4000*4C 2021-08-11T18:29:52.543Z,1628706592.543 [Micromodem](INFO): Nmea in: $CAREV,182952,AUV,2.1.4000*2F 2021-08-11T18:29:52.938Z,1628706592.938 [Micromodem](INFO): Nmea in: $CAREV,182952,COPROC,0.20.0.39330*7E 2021-08-11T18:30:02.246Z,1628706602.246 [Micromodem](INFO): Nmea in: $CAREV,183002,AUV,2.1.4000*22 2021-08-11T18:30:02.247Z,1628706602.247 [Micromodem](INFO): Nmea in: $CAREV,183002,COPROC,0.20.0.39330*73 2021-08-11T18:30:09.922Z,1628706609.922 [NAL9602](INFO): Not Powering down - fast GPS 2021-08-11T18:30:12.337Z,1628706612.337 [Micromodem](INFO): Nmea in: $CAREV,183012,AUV,2.1.4000*23 2021-08-11T18:30:12.338Z,1628706612.338 [Micromodem](INFO): Nmea in: $CAREV,183012,COPROC,0.20.0.39330*72 2021-08-11T18:30:22.434Z,1628706622.434 [Micromodem](INFO): Nmea in: $CAREV,183022,AUV,2.1.4000*20 2021-08-11T18:30:22.435Z,1628706622.435 [Micromodem](INFO): Nmea in: $CAREV,183022,COPROC,0.20.0.39330*71 2021-08-11T18:30:22.493Z,1628706622.493 [DataOverHttps](IMPORTANT): SBD MTMSN=20210811T183021