2020-12-10T21:02:29.157Z,1607634149.157 [DataOverHttps](INFO): Received command:restart logs
2020-12-10T21:02:29.162Z,1607634149.162 [CommandLine](IMPORTANT): got command restart logs
2020-12-10T21:05:59.656Z,1607634359.656 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-12-10T21:05:59.656Z,1607634359.656 [Default:CheckIn:C.Wait] Stopped
2020-12-10T21:05:59.656Z,1607634359.656 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-10T21:05:59.657Z,1607634359.657 [Default:CheckIn:D] Running Loop=1
2020-12-10T21:06:00.069Z,1607634360.069 [Default:CheckIn:D] Stopped
2020-12-10T21:06:00.069Z,1607634360.069 [Default:CheckIn:E] Running Loop=1
2020-12-10T21:06:00.478Z,1607634360.478 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 15.549071 min 
2020-12-10T21:06:00.479Z,1607634360.479 [Default:CheckIn:E] Stopped
2020-12-10T21:06:00.479Z,1607634360.479 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-12-10T21:06:00.479Z,1607634360.479 [Default:CheckIn] Stopped
2020-12-10T21:06:00.479Z,1607634360.479 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-12-10T21:06:00.479Z,1607634360.479 [Default:CheckIn](INFO): Running loop #3
2020-12-10T21:06:00.479Z,1607634360.479 [Default:CheckIn] Running Loop=3
2020-12-10T21:06:00.479Z,1607634360.479 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-12-10T21:06:00.479Z,1607634360.479 [Default:CheckIn:Read_GPS] Running Loop=1
2020-12-10T21:06:02.474Z,1607634362.474 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210601.00,A,3649.28292,N,12150.30920,W,1.438,276.64,101220,,,D*76
2020-12-10T21:06:02.476Z,1607634362.476 [NAL9602](INFO): GPS fix at 20201210T210601: (36.821382, -121.838487)
2020-12-10T21:06:02.545Z,1607634362.545 [Default:CheckIn:Read_GPS] Stopped
2020-12-10T21:06:02.545Z,1607634362.545 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-12-10T21:06:10.512Z,1607634370.512 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201210T194059/Courier0037.lzma
2020-12-10T21:06:11.515Z,1607634371.515 [DataOverHttps](INFO): Moved sent file to Logs/20201210T194059/Courier0037.lzma.bak
2020-12-10T21:06:11.515Z,1607634371.515 [DataOverHttps](INFO): SBD MOMSN=13441575
2020-12-10T21:06:31.328Z,1607634391.328 [DataOverHttps](INFO): Sending 269 bytes from file Logs/20201210T210229/Courier0000.lzma
2020-12-10T21:06:33.115Z,1607634393.115 [DataOverHttps](INFO): Moved sent file to Logs/20201210T210229/Courier0000.lzma.bak
2020-12-10T21:06:33.115Z,1607634393.115 [DataOverHttps](INFO): SBD MOMSN=13441622
2020-12-10T21:06:54.312Z,1607634414.312 [NAL9602](INFO): SBD MO Status=0, MOMSN=12051, MT Status=0, MTMSN=0
2020-12-10T21:06:54.312Z,1607634414.312 [NAL9602](INFO): No messages in MT queue
2020-12-10T21:06:55.553Z,1607634415.553 [DataOverHttps](INFO): Sending 105 bytes from file Logs/20201210T194059/Express0038.lzma
2020-12-10T21:06:56.555Z,1607634416.555 [DataOverHttps](INFO): Moved sent file to Logs/20201210T194059/Express0038.lzma.bak
2020-12-10T21:06:56.555Z,1607634416.555 [DataOverHttps](INFO): SBD MOMSN=13441649
2020-12-10T21:07:13.297Z,1607634433.297 [DataOverHttps](INFO): Sending 378 bytes from file Logs/20201210T210229/Express0001.lzma
2020-12-10T21:07:14.299Z,1607634434.299 [DataOverHttps](INFO): Moved sent file to Logs/20201210T210229/Express0001.lzma.bak
2020-12-10T21:07:14.299Z,1607634434.299 [DataOverHttps](INFO): SBD MOMSN=13441674
2020-12-10T21:07:25.042Z,1607634445.042 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-10T21:07:30.656Z,1607634450.656 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20201210T210229/Express0004.lzma
2020-12-10T21:07:31.659Z,1607634451.659 [DataOverHttps](INFO): Moved sent file to Logs/20201210T210229/Express0004.lzma.bak
2020-12-10T21:07:31.659Z,1607634451.659 [DataOverHttps](INFO): SBD MOMSN=13441701
2020-12-10T21:07:33.136Z,1607634453.136 [Default:CheckIn:Read_Iridium] Stopped
2020-12-10T21:07:33.137Z,1607634453.137 [Default:CheckIn:C.Wait] Running Loop=1
2020-12-10T21:07:33.137Z,1607634453.137 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-10T21:12:33.771Z,1607634753.771 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-12-10T21:12:33.771Z,1607634753.771 [Default:CheckIn:C.Wait] Stopped
2020-12-10T21:12:33.771Z,1607634753.771 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-10T21:12:33.771Z,1607634753.771 [Default:CheckIn:D] Running Loop=1
2020-12-10T21:12:34.194Z,1607634754.194 [Default:CheckIn:D] Stopped
2020-12-10T21:12:34.194Z,1607634754.194 [Default:CheckIn:E] Running Loop=1
2020-12-10T21:12:34.589Z,1607634754.589 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 22.117826 min 
2020-12-10T21:12:34.589Z,1607634754.589 [Default:CheckIn:E] Stopped
2020-12-10T21:12:34.590Z,1607634754.590 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-12-10T21:12:34.590Z,1607634754.590 [Default:CheckIn] Stopped
2020-12-10T21:12:34.590Z,1607634754.590 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-12-10T21:12:34.590Z,1607634754.590 [Default:CheckIn](INFO): Running loop #4
2020-12-10T21:12:34.590Z,1607634754.590 [Default:CheckIn] Running Loop=4
2020-12-10T21:12:34.590Z,1607634754.590 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-12-10T21:12:34.590Z,1607634754.590 [Default:CheckIn:Read_GPS] Running Loop=1
2020-12-10T21:12:36.590Z,1607634756.590 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211236.00,A,3649.27871,N,12150.30174,W,1.322,56.23,101220,,,D*49
2020-12-10T21:12:36.592Z,1607634756.592 [NAL9602](INFO): GPS fix at 20201210T211236: (36.821312, -121.838362)
2020-12-10T21:12:36.620Z,1607634756.620 [Default:CheckIn:Read_GPS] Stopped
2020-12-10T21:12:36.620Z,1607634756.620 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-12-10T21:12:45.000Z,1607634765.000 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20201210T210229/Courier0006.lzma
2020-12-10T21:12:46.003Z,1607634766.003 [DataOverHttps](INFO): Moved sent file to Logs/20201210T210229/Courier0006.lzma.bak
2020-12-10T21:12:46.003Z,1607634766.003 [DataOverHttps](INFO): SBD MOMSN=13442134
2020-12-10T21:12:56.951Z,1607634776.951 [NAL9602](INFO): SBD MO Status=0, MOMSN=12052, MT Status=0, MTMSN=0
2020-12-10T21:12:56.951Z,1607634776.951 [NAL9602](INFO): No messages in MT queue
2020-12-10T21:13:06.252Z,1607634786.252 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20201210T210229/Express0007.lzma
2020-12-10T21:13:07.255Z,1607634787.255 [DataOverHttps](INFO): Moved sent file to Logs/20201210T210229/Express0007.lzma.bak
2020-12-10T21:13:07.255Z,1607634787.255 [DataOverHttps](INFO): SBD MOMSN=13442160
2020-12-10T21:13:09.211Z,1607634789.211 [Default:CheckIn:Read_Iridium] Stopped
2020-12-10T21:13:09.211Z,1607634789.211 [Default:CheckIn:C.Wait] Running Loop=1
2020-12-10T21:13:09.211Z,1607634789.211 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-10T21:13:27.426Z,1607634807.426 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-10T21:17:33.251Z,1607635053.251 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-12-10T21:18:09.631Z,1607635089.631 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-12-10T21:18:09.631Z,1607635089.631 [Default:CheckIn:C.Wait] Stopped
2020-12-10T21:18:09.631Z,1607635089.631 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-10T21:18:09.631Z,1607635089.631 [Default:CheckIn:D] Running Loop=1
2020-12-10T21:18:10.058Z,1607635090.058 [Default:CheckIn:D] Stopped
2020-12-10T21:18:10.058Z,1607635090.058 [Default:CheckIn:E] Running Loop=1
2020-12-10T21:18:10.432Z,1607635090.432 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 27.715564 min 
2020-12-10T21:18:10.432Z,1607635090.432 [Default:CheckIn:E] Stopped
2020-12-10T21:18:10.433Z,1607635090.433 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-12-10T21:18:10.445Z,1607635090.445 [Default:CheckIn] Stopped
2020-12-10T21:18:10.445Z,1607635090.445 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-12-10T21:18:10.445Z,1607635090.445 [Default:CheckIn](INFO): Running loop #5
2020-12-10T21:18:10.445Z,1607635090.445 [Default:CheckIn] Running Loop=5
2020-12-10T21:18:10.445Z,1607635090.445 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-12-10T21:18:10.446Z,1607635090.446 [Default:CheckIn:Read_GPS] Running Loop=1
2020-12-10T21:18:12.437Z,1607635092.437 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211811.00,A,3649.27888,N,12150.29787,W,0.505,317.09,101220,,,A*7B
2020-12-10T21:18:12.451Z,1607635092.451 [NAL9602](INFO): GPS fix at 20201210T211811: (36.821315, -121.838298)
2020-12-10T21:18:12.470Z,1607635092.470 [Default:CheckIn:Read_GPS] Stopped
2020-12-10T21:18:12.471Z,1607635092.471 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-12-10T21:18:34.612Z,1607635114.612 [NAL9602](INFO): SBD MO Status=0, MOMSN=12053, MT Status=0, MTMSN=0
2020-12-10T21:18:34.612Z,1607635114.612 [NAL9602](INFO): No messages in MT queue
2020-12-10T21:18:52.100Z,1607635132.100 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20201210T210229/Courier0009.lzma
2020-12-10T21:18:53.103Z,1607635133.103 [DataOverHttps](INFO): Moved sent file to Logs/20201210T210229/Courier0009.lzma.bak
2020-12-10T21:18:53.103Z,1607635133.103 [DataOverHttps](INFO): SBD MOMSN=13442680
2020-12-10T21:19:05.331Z,1607635145.331 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-10T21:19:22.560Z,1607635162.560 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20201210T210229/Express0010.lzma
2020-12-10T21:19:23.563Z,1607635163.563 [DataOverHttps](INFO): Moved sent file to Logs/20201210T210229/Express0010.lzma.bak
2020-12-10T21:19:23.563Z,1607635163.563 [DataOverHttps](INFO): SBD MOMSN=13442731
2020-12-10T21:19:25.132Z,1607635165.132 [Default:CheckIn:Read_Iridium] Stopped
2020-12-10T21:19:25.132Z,1607635165.132 [Default:CheckIn:C.Wait] Running Loop=1
2020-12-10T21:19:25.132Z,1607635165.132 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-10T21:24:25.772Z,1607635465.772 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-12-10T21:24:25.773Z,1607635465.773 [Default:CheckIn:C.Wait] Stopped
2020-12-10T21:24:25.773Z,1607635465.773 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-10T21:24:25.777Z,1607635465.777 [Default:CheckIn:D] Running Loop=1
2020-12-10T21:24:26.202Z,1607635466.202 [Default:CheckIn:D] Stopped
2020-12-10T21:24:26.202Z,1607635466.202 [Default:CheckIn:E] Running Loop=1
2020-12-10T21:24:26.606Z,1607635466.606 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 33.984623 min 
2020-12-10T21:24:26.606Z,1607635466.606 [Default:CheckIn:E] Stopped
2020-12-10T21:24:26.606Z,1607635466.606 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-12-10T21:24:26.606Z,1607635466.606 [Default:CheckIn] Stopped
2020-12-10T21:24:26.606Z,1607635466.606 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-12-10T21:24:26.606Z,1607635466.606 [Default:CheckIn](INFO): Running loop #6
2020-12-10T21:24:26.606Z,1607635466.606 [Default:CheckIn] Running Loop=6
2020-12-10T21:24:26.606Z,1607635466.606 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-12-10T21:24:26.607Z,1607635466.607 [Default:CheckIn:Read_GPS] Running Loop=1
2020-12-10T21:24:28.592Z,1607635468.592 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212428.00,A,3649.27759,N,12150.28850,W,0.156,67.24,101220,,,D*45
2020-12-10T21:24:28.594Z,1607635468.594 [NAL9602](INFO): GPS fix at 20201210T212428: (36.821293, -121.838142)
2020-12-10T21:24:28.614Z,1607635468.614 [Default:CheckIn:Read_GPS] Stopped
2020-12-10T21:24:28.614Z,1607635468.614 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-12-10T21:24:39.464Z,1607635479.464 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20201210T210229/Courier0012.lzma
2020-12-10T21:24:40.467Z,1607635480.467 [DataOverHttps](INFO): Moved sent file to Logs/20201210T210229/Courier0012.lzma.bak
2020-12-10T21:24:40.467Z,1607635480.467 [DataOverHttps](INFO): SBD MOMSN=13443147
2020-12-10T21:24:46.844Z,1607635486.844 [NAL9602](INFO): SBD MO Status=0, MOMSN=12054, MT Status=0, MTMSN=0
2020-12-10T21:24:46.844Z,1607635486.844 [NAL9602](INFO): No messages in MT queue
2020-12-10T21:25:06.593Z,1607635506.593 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20201210T210229/Express0013.lzma
2020-12-10T21:25:07.601Z,1607635507.601 [DataOverHttps](INFO): Moved sent file to Logs/20201210T210229/Express0013.lzma.bak
2020-12-10T21:25:07.601Z,1607635507.601 [DataOverHttps](INFO): SBD MOMSN=13443184
2020-12-10T21:25:09.072Z,1607635509.072 [Default:CheckIn:Read_Iridium] Stopped
2020-12-10T21:25:09.073Z,1607635509.073 [Default:CheckIn:C.Wait] Running Loop=1
2020-12-10T21:25:09.073Z,1607635509.073 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-10T21:25:16.211Z,1607635516.211 [DataOverHttps](IMPORTANT): SBD MTMSN=20201210T212515
2020-12-10T21:25:17.554Z,1607635517.554 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-10T21:25:23.781Z,1607635523.781 [DataOverHttps](INFO): Received command:load Transport/transit.xml;set transit.Latitude 36.81361 degree;set transit.Longitude -121.81761 degree;run
2020-12-10T21:25:23.798Z,1607635523.798 [CommandLine](IMPORTANT): got command load ./Missions/Transport/transit.xml
2020-12-10T21:25:23.798Z,1607635523.798 [MissionManager](INFO): Loading Mission: ./Missions/Transport/transit.xml
2020-12-10T21:25:23.887Z,1607635523.887 [MissionManager](INFO): DefineArg transit.MissionTimeout = 60.000000 min
2020-12-10T21:25:23.890Z,1607635523.890 [MissionManager](INFO): DefineArg transit.NeedCommsTime = 30.000000 min
2020-12-10T21:25:23.893Z,1607635523.893 [MissionManager](INFO): DefineArg transit.Latitude = nan arcdeg
2020-12-10T21:25:23.895Z,1607635523.895 [MissionManager](INFO): DefineArg transit.Longitude = nan arcdeg
2020-12-10T21:25:23.898Z,1607635523.898 [MissionManager](INFO): DefineArg transit.Northings = 0.000000 m
2020-12-10T21:25:23.901Z,1607635523.901 [MissionManager](INFO): DefineArg transit.Eastings = 0.000000 m
2020-12-10T21:25:23.903Z,1607635523.903 [MissionManager](INFO): DefineArg transit.Depth = 10.000000 m
2020-12-10T21:25:23.906Z,1607635523.906 [MissionManager](INFO): DefineArg transit.Speed = 1.000000 m/s
2020-12-10T21:25:23.909Z,1607635523.909 [MissionManager](INFO): DefineArg transit.MaxDepth = 20.000000 m
2020-12-10T21:25:23.912Z,1607635523.912 [MissionManager](INFO): DefineArg transit.MinOffshore = 1.000000 km
2020-12-10T21:25:23.914Z,1607635523.914 [MissionManager](INFO): DefineArg transit.MinAltitude = 7.000000 m
2020-12-10T21:25:23.917Z,1607635523.917 [MissionManager](INFO): DefineArg transit.MassHold = 1 bool
2020-12-10T21:25:23.919Z,1607635523.919 [MissionManager](INFO): DefineArg transit.BuoyancyHold = 1 bool
2020-12-10T21:25:23.920Z,1607635523.920 [MissionManager](INFO): Inserting Stack: Missions/Insert/NeedComms.xml
2020-12-10T21:25:24.078Z,1607635524.078 [MissionManager](INFO): DefineArg transit:NeedComms.DiveInterval = 3.000000 h
2020-12-10T21:25:24.081Z,1607635524.081 [MissionManager](INFO): DefineArg transit:NeedComms.WaitForPitchUp = 10.000000 min
2020-12-10T21:25:24.088Z,1607635524.088 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacePitch = 20.000000 arcdeg
2020-12-10T21:25:24.102Z,1607635524.102 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceDepthRate = nan m/s
2020-12-10T21:25:24.105Z,1607635524.105 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceSpeed = 1.000000 m/s
2020-12-10T21:25:24.108Z,1607635524.108 [MissionManager](INFO): DefineArg transit:NeedComms.GPSTimeout = 7.000000 min
2020-12-10T21:25:24.119Z,1607635524.119 [MissionManager](INFO): DefineArg transit:NeedComms.CommsTimeout = 30.000000 min
2020-12-10T21:25:24.131Z,1607635524.131 [transit:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-12-10T21:25:24.189Z,1607635524.189 [MissionManager](INFO): Inserting Stack: Missions/Insert/StandardEnvelopes.xml
2020-12-10T21:25:24.221Z,1607635524.221 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinAltitude = 5.000000 m
2020-12-10T21:25:24.224Z,1607635524.224 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepth = 200.000000 m
2020-12-10T21:25:24.231Z,1607635524.231 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinOffshore = 2000.000000 m
2020-12-10T21:25:24.232Z,1607635524.232 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2020-12-10T21:25:24.248Z,1607635524.248 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2020-12-10T21:25:24.264Z,1607635524.264 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2020-12-10T21:25:24.297Z,1607635524.297 [transit:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy.
2020-12-10T21:25:24.305Z,1607635524.305 [transit:MassHold.Pitch](DEBUG): Construct.
2020-12-10T21:25:24.312Z,1607635524.312 [transit:Transit:A.Pitch](DEBUG): Construct.
2020-12-10T21:25:24.337Z,1607635524.337 [transit:Transit:B.SetSpeed](DEBUG): Construct.
2020-12-10T21:25:24.340Z,1607635524.340 [transit:Transit:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2020-12-10T21:25:24.372Z,1607635524.372 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="utf-8"?>
<Mission xmlns="Tethys"
        xmlns:Control="Tethys/Control"
        xmlns:Guidance="Tethys/Guidance" 
        xmlns:Units="Tethys/Units"
        xmlns:Universal="Tethys/Universal"
        xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
        xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                            Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                            Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd                            
                            Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                            Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd" 
        Id="transit">

    <Description>
        Vehicle transits to desired waypoint and can be commanded to use/hold
        mass or buoyancy.
    </Description>

    <DefineArg Name="MissionTimeout"><Description>
        Maximum duration of mission
    </Description><Units:minute/><Value>60</Value></DefineArg>

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

    <DefineArg Name="Latitude"><Description>
        Latitude of waypoint to seek. If set to NaN, uses latitude at mission
        initialization.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Longitude"><Description>
        Longitude of waypoint to seek. If set to NaN, uses longitude at mission
        initialization.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Northings"><Description>
        Northward distance of the waypoint to seek. If combined with Latitude
        and/or Longitude, this is an offset from the specified Latitude and/or
        Longitude.
    </Description><Units:meter/><Value>0</Value></DefineArg>

    <DefineArg Name="Eastings"><Description>
        Eastward distance of the waypoint to seek. If combined with Latitude
        and/or Longitude, this is an offset from the specified Latitude and/or
        Longitude.
    </Description><Units:meter/><Value>0</Value></DefineArg>

    <DefineArg Name="Depth"><Description>
        Depth of flat and level flight during the mission.
    </Description><Units:meter /><Value>10</Value></DefineArg>

    <DefineArg Name="Speed"><Description>
        Speed of vehicle (relative to water) during the mission.
    </Description><Units:meter_per_second/><Value>1</Value></DefineArg>

    <DefineArg Name="MaxDepth"><Description>
        Maximum depth for the entire mission.
    </Description><Units:meter/><Value>20</Value></DefineArg>

    <DefineArg Name="MinOffshore"><Description>
        Minimum distance offshore for the entire mission.
    </Description><Units:kilometer/><Value>1</Value></DefineArg>

    <DefineArg Name="MinAltitude"><Description>
        Minimum height above the sea floor for the entire mission.
    </Description><Units:meter/><Value>7</Value></DefineArg>

    <DefineArg Name="MassHold"><Description>
        Set to True in order to hold mass at default position, False to allow
        mass to run on its own.
    </Description><True/></DefineArg>

    <DefineArg Name="BuoyancyHold"><Description>
        Set to True in order to hold buoyancy at neutral position, False to
        allow buoyancy to run on its own.
    </Description><True/></DefineArg>

    <Timeout Duration="MissionTimeout"/>

    <Insert Filename="Insert/NeedComms.xml" Id="NeedComms"/>

    <Assign><Sequence/><Arg Name="NeedComms:DiveInterval"/><Arg Name="NeedCommsTime"/></Assign>

    <Assign><Sequence/><Arg Name="NeedComms:WaitForPitchUp"/><Units:second/><Value>1</Value></Assign>

    <Insert Filename="Insert/StandardEnvelopes.xml"/>

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

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

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

    <Guidance:Buoyancy Id="BuoyancyHold">
        <While>
            <Arg Name="BuoyancyHold"/>
        </While>
        <Setting><Guidance:Buoyancy.position/><Control:VerticalControl.buoyancyNeutral/></Setting>
    </Guidance:Buoyancy>

    <Guidance:Pitch Id="MassHold">
        <While>
            <Arg Name="MassHold"/>
        </While>
        <Setting><Guidance:Pitch.massPosition/><Control:VerticalControl.massDefault/></Setting>
    </Guidance:Pitch>

    <Aggregate Id="Transit">

        <Sequence/>

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

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

        <Guidance:Waypoint Id="Wpt1">
            <Sequence/>
            <Setting><Guidance:Waypoint.latitude/><Arg Name="Latitude"/></Setting>
            <Setting><Guidance:Waypoint.longitude/><Arg Name="Longitude"/></Setting>
            <Setting><Guidance:Waypoint.northingsDelta/><Arg Name="Northings"/></Setting>
            <Setting><Guidance:Waypoint.eastingsDelta/><Arg Name="Eastings"/></Setting>
        </Guidance:Waypoint>

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

    </Aggregate>

</Mission>


2020-12-10T21:25:24.373Z,1607635524.373 [CommandLine](IMPORTANT): Loaded ./Missions/Transport/transit.xml
2020-12-10T21:25:27.159Z,1607635527.159 [CommandLine](IMPORTANT): got command set transit.Latitude 36.813610 degree
2020-12-10T21:25:27.160Z,1607635527.160 [CommandLine](IMPORTANT): got command set transit.Longitude -121.817612 degree
2020-12-10T21:25:27.160Z,1607635527.160 [CommandLine](IMPORTANT): got command run
2020-12-10T21:25:27.162Z,1607635527.162 [CommandLine](IMPORTANT): Running 
2020-12-10T21:25:27.278Z,1607635527.278 [Default] Stopped
2020-12-10T21:25:27.278Z,1607635527.278 [Default](DEBUG): Aggregate::uninitialize Default
2020-12-10T21:25:27.278Z,1607635527.278 [Default:B.GoToSurface] Stopped
2020-12-10T21:25:27.278Z,1607635527.278 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-12-10T21:25:27.278Z,1607635527.278 [Default:CheckIn] Stopped
2020-12-10T21:25:27.278Z,1607635527.278 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-12-10T21:25:27.278Z,1607635527.278 [Default:CheckIn:C.Wait] Stopped
2020-12-10T21:25:27.278Z,1607635527.278 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-10T21:25:27.278Z,1607635527.278 [MissionManager](IMPORTANT): Started mission transit
2020-12-10T21:25:27.279Z,1607635527.279 [transit] Running Loop=1
2020-12-10T21:25:27.279Z,1607635527.279 [transit](DEBUG): Aggregate::initialize transit
2020-12-10T21:25:27.279Z,1607635527.279 [transit:StandardEnvelopes] Running Loop=1
2020-12-10T21:25:27.279Z,1607635527.279 [transit:StandardEnvelopes](DEBUG): Aggregate::initialize transit:StandardEnvelopes
2020-12-10T21:25:27.279Z,1607635527.279 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2020-12-10T21:25:27.279Z,1607635527.279 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2020-12-10T21:25:27.279Z,1607635527.279 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2020-12-10T21:25:27.279Z,1607635527.279 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2020-12-10T21:25:27.280Z,1607635527.280 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2020-12-10T21:25:27.280Z,1607635527.280 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2020-12-10T21:25:27.280Z,1607635527.280 [transit:BuoyancyHold.Buoyancy] Running Loop=1
2020-12-10T21:25:27.280Z,1607635527.280 [transit:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2020-12-10T21:25:27.280Z,1607635527.280 [transit:MassHold.Pitch] Running Loop=1
2020-12-10T21:25:27.280Z,1607635527.280 [transit:MassHold.Pitch](DEBUG): Initialize.
2020-12-10T21:25:27.280Z,1607635527.280 [transit:B] Running Loop=1
2020-12-10T21:25:27.285Z,1607635527.285 [transit:MassHold.Pitch] Running Loop=1
2020-12-10T21:25:27.285Z,1607635527.285 [transit:BuoyancyHold.Buoyancy] Running Loop=1
2020-12-10T21:25:27.286Z,1607635527.286 [transit:StandardEnvelopes] Running Loop=1
2020-12-10T21:25:27.286Z,1607635527.286 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2020-12-10T21:25:27.286Z,1607635527.286 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2020-12-10T21:25:27.286Z,1607635527.286 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2020-12-10T21:25:27.287Z,1607635527.287 [transit:B] Stopped
2020-12-10T21:25:27.287Z,1607635527.287 [transit:C] Running Loop=1
2020-12-10T21:25:27.667Z,1607635527.667 [transit:C] Stopped
2020-12-10T21:25:27.667Z,1607635527.667 [transit:E] Running Loop=1
2020-12-10T21:25:28.134Z,1607635528.134 [transit:E] Stopped
2020-12-10T21:25:28.134Z,1607635528.134 [transit:F] Running Loop=1
2020-12-10T21:25:28.475Z,1607635528.475 [transit:F] Stopped
2020-12-10T21:25:28.475Z,1607635528.475 [transit:G] Running Loop=1
2020-12-10T21:25:28.900Z,1607635528.900 [transit:G] Stopped
2020-12-10T21:25:28.900Z,1607635528.900 [transit:Transit] Running Loop=1
2020-12-10T21:25:28.900Z,1607635528.900 [transit:Transit](DEBUG): Aggregate::initialize transit:Transit
2020-12-10T21:25:28.900Z,1607635528.900 [transit:Transit:A.Pitch] Running Loop=1
2020-12-10T21:25:28.900Z,1607635528.900 [transit:Transit:A.Pitch](DEBUG): Initialize.
2020-12-10T21:25:28.900Z,1607635528.900 [transit:Transit:B.SetSpeed] Running Loop=1
2020-12-10T21:25:28.900Z,1607635528.900 [transit:Transit:B.SetSpeed](DEBUG): Initialize.
2020-12-10T21:25:28.900Z,1607635528.900 [transit:Transit:Wpt1.Waypoint] Running Loop=1
2020-12-10T21:25:28.901Z,1607635528.901 [transit:Transit:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2020-12-10T21:25:29.278Z,1607635529.278 [transit:Transit:B.SetSpeed] Running Loop=1
2020-12-10T21:25:29.278Z,1607635529.278 [transit:Transit:A.Pitch] Running Loop=1
2020-12-10T21:26:05.627Z,1607635565.627 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position 7.57 mm (4 active estimators).
2020-12-10T21:26:27.503Z,1607635587.503 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.338131
2020-12-10T21:27:32.606Z,1607635652.606 [Radio_Surface](INFO): Powering down
2020-12-10T21:27:32.778Z,1607635652.778 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-12-10T21:27:33.781Z,1607635653.781 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2020-12-10T21:49:06.102Z,1607636946.102 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-12-10T21:52:47.105Z,1607637167.105 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout
2020-12-10T21:52:47.105Z,1607637167.105 [RDI_Pathfinder] Communications Fault, FailCount= 1
2020-12-10T21:52:47.105Z,1607637167.105 [RDI_Pathfinder](ERROR): Communications Fault
2020-12-10T21:52:47.181Z,1607637167.181 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-12-10T21:52:47.557Z,1607637167.557 [RDI_Pathfinder](INFO): Powering down
2020-12-10T21:52:48.341Z,1607637168.341 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-12-10T21:52:48.341Z,1607637168.341 [RDI_Pathfinder] No Fault, FailCount= 1
2020-12-10T21:54:28.903Z,1607637268.903 [transit:NeedComms] Running Loop=1
2020-12-10T21:54:28.903Z,1607637268.903 [transit:NeedComms](DEBUG): Aggregate::initialize transit:NeedComms
2020-12-10T21:54:28.904Z,1607637268.904 [transit:NeedComms:B.GoToSurface] Running Loop=1
2020-12-10T21:54:28.904Z,1607637268.904 [transit:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-12-10T21:54:28.904Z,1607637268.904 [transit:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2020-12-10T21:54:28.904Z,1607637268.904 [transit:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2020-12-10T21:54:28.905Z,1607637268.905 [transit:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2020-12-10T21:54:28.905Z,1607637268.905 [transit:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-12-10T21:54:28.906Z,1607637268.906 [transit:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-12-10T21:54:28.907Z,1607637268.907 [transit:NeedComms:A] Running Loop=1
2020-12-10T21:54:28.912Z,1607637268.912 [transit:NeedComms:A](INFO): last time_fix was: 1607635468.000000 second since 1970/01/01T00:00:00Z 
2020-12-10T21:54:28.912Z,1607637268.912 [transit:NeedComms:A] Stopped
2020-12-10T21:54:59.241Z,1607637299.241 [transit:NeedComms:C] Running Loop=1
2020-12-10T21:54:59.618Z,1607637299.618 [transit:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-12-10T21:54:59.729Z,1607637299.729 [Radio_Surface](INFO): Powering up
2020-12-10T21:55:05.805Z,1607637305.805 [DataOverHttps](INFO): Radio surface powered ON.
2020-12-10T21:55:35.925Z,1607637335.925 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-12-10T21:56:07.088Z,1607637367.088 [NAL9602](INFO): SBD MO Status=2, MOMSN=12055, MT Status=2, MTMSN=0
2020-12-10T21:56:07.093Z,1607637367.093 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-12-10T21:56:11.069Z,1607637371.069 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-12-10T21:56:46.210Z,1607637406.210 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-12-10T21:56:50.293Z,1607637410.293 [NAL9602](INFO): SBD MO Status=2, MOMSN=12055, MT Status=2, MTMSN=0
2020-12-10T21:56:50.294Z,1607637410.294 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-12-10T21:57:11.710Z,1607637431.710 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=12055, MT Status=1, MTMSN=678
2020-12-10T21:57:11.710Z,1607637431.710 [NAL9602](INFO): Data available in MT queue
2020-12-10T21:57:12.342Z,1607637432.342 [NAL9602](INFO): Received command:configSet AHRS_M2.readAngularVelocities 1 bool persist;configSet AHRS_M2.readMagnetics 1 bool persist;configSet AHRS_M2.readAccelerations 1 bool persist;
2020-12-10T21:57:12.896Z,1607637432.896 [CommandLine](IMPORTANT): got command configSet AHRS_M2.readAngularVelocities 1.000000 bool persist
2020-12-10T21:57:12.906Z,1607637432.906 [CommandLine](IMPORTANT): got command configSet AHRS_M2.readMagnetics 1.000000 bool persist
2020-12-10T21:57:12.909Z,1607637432.909 [CommandLine](IMPORTANT): got command configSet AHRS_M2.readAccelerations 1.000000 bool persist
2020-12-10T21:57:13.776Z,1607637433.776 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215713.00,A,3648.91763,N,12149.52285,W,0.564,42.37,101220,,,A*4E
2020-12-10T21:57:13.778Z,1607637433.778 [NAL9602](INFO): GPS fix at 20201210T215713: (36.815294, -121.825381)
2020-12-10T21:57:13.813Z,1607637433.813 [UniversalFixResidualReporter](INFO): Fix residual: 9.9 %DT, over the last 1470.0 m. Residual distance 146.1 m at bearing 5.7 degrees. Fix at (36.8153, -121.8254) with 1317.3 m made good.
2020-12-10T21:57:13.816Z,1607637433.816 [transit:NeedComms:C] Stopped
2020-12-10T21:57:13.816Z,1607637433.816 [transit:NeedComms:D] Running Loop=1
2020-12-10T21:57:14.234Z,1607637434.234 [transit:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-12-10T21:57:21.401Z,1607637441.401 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-12-10T21:57:30.892Z,1607637450.892 [NAL9602](INFO): SBD MO Status=1, MOMSN=12056, MT Status=0, MTMSN=0
2020-12-10T21:57:30.945Z,1607637450.945 [NAL9602](INFO): Sent 205 bytes from file Logs/20201210T210229/Courier0015.lzma
2020-12-10T21:57:30.946Z,1607637450.946 [NAL9602](INFO): Packets left to send: 0
2020-12-10T21:57:45.131Z,1607637465.131 [NAL9602](INFO): SBD MO Status=2, MOMSN=12057, MT Status=2, MTMSN=0
2020-12-10T21:57:45.131Z,1607637465.131 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-12-10T21:57:56.541Z,1607637476.541 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-12-10T21:58:16.828Z,1607637496.828 [NAL9602](INFO): SBD MO Status=1, MOMSN=12057, MT Status=0, MTMSN=0
2020-12-10T21:58:16.882Z,1607637496.882 [NAL9602](INFO): Sent 332 bytes from file Logs/20201210T210229/Express0016.lzma
2020-12-10T21:58:16.882Z,1607637496.882 [NAL9602](INFO): Packets left to send: 1
2020-12-10T21:58:31.820Z,1607637511.820 [NAL9602](INFO): SBD MO Status=1, MOMSN=12058, MT Status=0, MTMSN=0
2020-12-10T21:58:31.873Z,1607637511.873 [NAL9602](INFO): Sent 177 bytes from file Logs/20201210T210229/Express0016.lzma
2020-12-10T21:58:31.874Z,1607637511.874 [NAL9602](INFO): Packets left to send: 0
2020-12-10T21:58:46.637Z,1607637526.637 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003027
2020-12-10T21:58:47.500Z,1607637527.500 [NAL9602](INFO): SBD MO Status=0, MOMSN=12059, MT Status=0, MTMSN=0
2020-12-10T21:59:03.508Z,1607637543.508 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201210T210229/Courier0018.lzma
2020-12-10T21:59:04.511Z,1607637544.511 [DataOverHttps](INFO): Moved sent file to Logs/20201210T210229/Courier0018.lzma.bak
2020-12-10T21:59:04.511Z,1607637544.511 [DataOverHttps](INFO): SBD MOMSN=13446156
2020-12-10T21:59:18.746Z,1607637558.746 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-10T21:59:21.645Z,1607637561.645 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20201210T210229/Express0019.lzma
2020-12-10T21:59:22.643Z,1607637562.643 [DataOverHttps](INFO): Moved sent file to Logs/20201210T210229/Express0019.lzma.bak
2020-12-10T21:59:22.643Z,1607637562.643 [DataOverHttps](INFO): SBD MOMSN=13446179
2020-12-10T21:59:24.028Z,1607637564.028 [transit:NeedComms:D] Stopped
2020-12-10T21:59:24.028Z,1607637564.028 [transit:NeedComms:E] Running Loop=1
2020-12-10T21:59:24.440Z,1607637564.440 [transit:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-12-10T21:59:26.016Z,1607637566.016 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215925.00,A,3648.92924,N,12149.51648,W,0.641,21.78,101220,,,A*47
2020-12-10T21:59:26.018Z,1607637566.018 [NAL9602](INFO): GPS fix at 20201210T215925: (36.815487, -121.825275)
2020-12-10T21:59:26.067Z,1607637566.067 [transit:NeedComms:E] Stopped
2020-12-10T21:59:26.068Z,1607637566.068 [transit:NeedComms](INFO): Completed transit:NeedComms
2020-12-10T21:59:26.068Z,1607637566.068 [transit:NeedComms] Stopped
2020-12-10T21:59:26.068Z,1607637566.068 [transit:NeedComms](DEBUG): Aggregate::uninitialize transit:NeedComms
2020-12-10T21:59:26.068Z,1607637566.068 [transit:NeedComms:B.GoToSurface] Stopped
2020-12-10T21:59:26.068Z,1607637566.068 [transit:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-12-10T21:59:51.080Z,1607637591.080 [NAL9602](INFO): SBD MO Status=2, MOMSN=12060, MT Status=2, MTMSN=0
2020-12-10T21:59:51.080Z,1607637591.080 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-12-10T22:00:29.245Z,1607637629.245 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-12-10T22:00:32.258Z,1607637632.258 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.239304
2020-12-10T22:01:00.318Z,1607637660.318 [Radio_Surface](INFO): Powering down
2020-12-10T22:01:04.421Z,1607637664.421 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-12-10T22:01:05.425Z,1607637665.425 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2020-12-10T22:03:52.334Z,1607637832.334 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2020-12-10T22:03:52.337Z,1607637832.337 [BPC1](INFO): Received data from all battery sticks.
2020-12-10T22:04:28.282Z,1607637868.282 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-12-10T22:04:58.976Z,1607637898.976 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-10T22:18:11.258Z,1607638691.258 [transit:Transit:Wpt1.Waypoint](IMPORTANT): Reached Waypoint: 36.813610,-121.817612
2020-12-10T22:18:11.258Z,1607638691.258 [transit:Transit:Wpt1.Waypoint] Stopped
2020-12-10T22:18:11.259Z,1607638691.259 [transit:Transit:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2020-12-10T22:18:11.259Z,1607638691.259 [transit:Transit:PhoneHome] Running Loop=1
2020-12-10T22:18:11.259Z,1607638691.259 [transit:Transit:PhoneHome](DEBUG): Aggregate::initialize transit:Transit:PhoneHome
2020-12-10T22:18:11.660Z,1607638691.660 [transit:NeedComms] Running Loop=1
2020-12-10T22:18:11.660Z,1607638691.660 [transit:NeedComms](DEBUG): Aggregate::initialize transit:NeedComms
2020-12-10T22:18:11.660Z,1607638691.660 [transit:NeedComms:B.GoToSurface] Running Loop=1
2020-12-10T22:18:11.660Z,1607638691.660 [transit:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-12-10T22:18:11.665Z,1607638691.665 [transit:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2020-12-10T22:18:11.665Z,1607638691.665 [transit:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2020-12-10T22:18:11.666Z,1607638691.666 [transit:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2020-12-10T22:18:11.666Z,1607638691.666 [transit:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-12-10T22:18:11.666Z,1607638691.666 [transit:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-12-10T22:18:11.667Z,1607638691.667 [transit:NeedComms:A] Running Loop=1
2020-12-10T22:18:11.668Z,1607638691.668 [transit:NeedComms:A](INFO): last time_fix was: 1607637565.000000 second since 1970/01/01T00:00:00Z 
2020-12-10T22:18:11.668Z,1607638691.668 [transit:NeedComms:A] Stopped
2020-12-10T22:18:50.024Z,1607638730.024 [transit:NeedComms:C] Running Loop=1
2020-12-10T22:18:50.585Z,1607638730.585 [Radio_Surface](INFO): Powering up
2020-12-10T22:18:56.841Z,1607638736.841 [DataOverHttps](INFO): Radio surface powered ON.
2020-12-10T22:19:22.729Z,1607638762.729 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2020-12-10T22:19:22.809Z,1607638762.809 [NAL9602](FAULT): received: 
+CSQ:0

OK060, 2, 0, 0, 0

OK
2020-12-10T22:19:22.809Z,1607638762.809 [NAL9602] Data Fault, FailCount= 1
2020-12-10T22:19:22.809Z,1607638762.809 [NAL9602](ERROR): Data Fault
2020-12-10T22:19:22.914Z,1607638762.914 [CBIT](ERROR): Data Fault in component: NAL9602
2020-12-10T22:19:23.165Z,1607638763.165 [NAL9602](INFO): Powering down
2020-12-10T22:19:23.994Z,1607638763.994 [CBIT](INFO): Clearing failed state for component NAL9602
2020-12-10T22:19:23.994Z,1607638763.994 [NAL9602] No Fault, FailCount= 1
2020-12-10T22:19:26.961Z,1607638766.961 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-12-10T22:19:53.432Z,1607638793.432 [NAL9602](INFO): Powering up NAL9602
2020-12-10T22:20:02.123Z,1607638802.123 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-12-10T22:20:04.342Z,1607638804.342 [NAL9602](INFO): NAL9602 initialized
2020-12-10T22:20:37.261Z,1607638837.261 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-12-10T22:21:12.401Z,1607638872.401 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-12-10T22:21:24.334Z,1607638884.334 [NAL9602](INFO): SBD MO Status=2, MOMSN=12060, MT Status=2, MTMSN=0
2020-12-10T22:21:24.334Z,1607638884.334 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-12-10T22:21:46.556Z,1607638906.556 [NAL9602](INFO): SBD MO Status=0, MOMSN=12060, MT Status=0, MTMSN=0
2020-12-10T22:21:46.556Z,1607638906.556 [NAL9602](INFO): No messages in MT queue
2020-12-10T22:21:47.549Z,1607638907.549 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-12-10T22:22:22.833Z,1607638942.833 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003050
2020-12-10T22:23:26.864Z,1607639006.864 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222325.00,A,3648.80659,N,12149.05886,W,1.030,319.41,101220,,,A*71
2020-12-10T22:23:26.867Z,1607639006.867 [NAL9602](INFO): GPS fix at 20201210T222325: (36.813443, -121.817648)
2020-12-10T22:23:26.879Z,1607639006.879 [UniversalFixResidualReporter](INFO): Fix residual: 6.2 %DT, over the last 933.1 m. Residual distance 58.0 m at bearing 9.7 degrees. Fix at (36.8134, -121.8176) with 716.0 m made good.
2020-12-10T22:23:26.882Z,1607639006.882 [transit:NeedComms:C] Stopped
2020-12-10T22:23:26.882Z,1607639006.882 [transit:NeedComms:D] Running Loop=1
2020-12-10T22:23:34.849Z,1607639014.849 [DataOverHttps](INFO): Sending 212 bytes from file Logs/20201210T210229/Courier0023.lzma
2020-12-10T22:23:35.851Z,1607639015.851 [DataOverHttps](INFO): Moved sent file to Logs/20201210T210229/Courier0023.lzma.bak
2020-12-10T22:23:35.851Z,1607639015.851 [DataOverHttps](INFO): SBD MOMSN=13448282
2020-12-10T22:23:52.837Z,1607639032.837 [DataOverHttps](INFO): Sending 352 bytes from file Logs/20201210T210229/Express0022.lzma
2020-12-10T22:23:53.839Z,1607639033.839 [DataOverHttps](INFO): Moved sent file to Logs/20201210T210229/Express0022.lzma.bak
2020-12-10T22:23:53.839Z,1607639033.839 [DataOverHttps](INFO): SBD MOMSN=13448306
2020-12-10T22:23:55.204Z,1607639035.204 [transit:NeedComms:D] Stopped
2020-12-10T22:23:55.204Z,1607639035.204 [transit:NeedComms:E] Running Loop=1
2020-12-10T22:23:57.179Z,1607639037.179 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222355.00,A,3648.81092,N,12149.05900,W,1.924,342.65,101220,,,A*7D
2020-12-10T22:23:57.185Z,1607639037.185 [NAL9602](INFO): GPS fix at 20201210T222355: (36.813515, -121.817650)
2020-12-10T22:23:57.245Z,1607639037.245 [transit:NeedComms:E] Stopped
2020-12-10T22:23:57.250Z,1607639037.250 [transit:NeedComms](INFO): Completed transit:NeedComms
2020-12-10T22:23:57.250Z,1607639037.250 [transit:NeedComms] Stopped
2020-12-10T22:23:57.250Z,1607639037.250 [transit:NeedComms](DEBUG): Aggregate::uninitialize transit:NeedComms
2020-12-10T22:23:57.250Z,1607639037.250 [transit:NeedComms:B.GoToSurface] Stopped
2020-12-10T22:23:57.250Z,1607639037.250 [transit:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-12-10T22:23:57.613Z,1607639037.613 [transit:Transit:PhoneHome](INFO): Completed transit:Transit:PhoneHome
2020-12-10T22:23:57.613Z,1607639037.613 [transit:Transit:PhoneHome] Stopped
2020-12-10T22:23:57.613Z,1607639037.613 [transit:Transit:PhoneHome](DEBUG): Aggregate::uninitialize transit:Transit:PhoneHome
2020-12-10T22:23:57.614Z,1607639037.614 [transit:Transit](INFO): Completed transit:Transit
2020-12-10T22:23:57.614Z,1607639037.614 [transit:Transit] Stopped
2020-12-10T22:23:57.614Z,1607639037.614 [transit:Transit](DEBUG): Aggregate::uninitialize transit:Transit
2020-12-10T22:23:57.614Z,1607639037.614 [transit:Transit:A.Pitch] Stopped
2020-12-10T22:23:57.615Z,1607639037.615 [transit:Transit:B.SetSpeed] Stopped
2020-12-10T22:23:57.615Z,1607639037.615 [transit:Transit:B.SetSpeed](DEBUG): Uninitialize.
2020-12-10T22:23:57.616Z,1607639037.616 [transit](INFO): Completed transit
2020-12-10T22:23:57.616Z,1607639037.616 [MissionManager](INFO): transit is completed.
2020-12-10T22:23:57.616Z,1607639037.616 [MissionManager](INFO): Uninitializing Mission transit
2020-12-10T22:23:57.616Z,1607639037.616 [transit] Stopped
2020-12-10T22:23:57.616Z,1607639037.616 [transit](DEBUG): Aggregate::uninitialize transit
2020-12-10T22:23:57.617Z,1607639037.617 [transit:StandardEnvelopes] Stopped
2020-12-10T22:23:57.617Z,1607639037.617 [transit:StandardEnvelopes](DEBUG): Aggregate::uninitialize transit:StandardEnvelopes
2020-12-10T22:23:57.621Z,1607639037.621 [transit:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2020-12-10T22:23:57.621Z,1607639037.621 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2020-12-10T22:23:57.621Z,1607639037.621 [transit:StandardEnvelopes:B.DepthEnvelope] Stopped
2020-12-10T22:23:57.621Z,1607639037.621 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2020-12-10T22:23:57.621Z,1607639037.621 [transit:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2020-12-10T22:23:57.621Z,1607639037.621 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2020-12-10T22:23:57.621Z,1607639037.621 [transit:BuoyancyHold.Buoyancy] Stopped
2020-12-10T22:23:57.621Z,1607639037.621 [transit:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2020-12-10T22:23:57.621Z,1607639037.621 [transit:MassHold.Pitch] Stopped
2020-12-10T22:23:57.990Z,1607639037.990 [MissionManager](IMPORTANT): Started mission Default
2020-12-10T22:23:57.990Z,1607639037.990 [Default] Running Loop=1
2020-12-10T22:23:57.990Z,1607639037.990 [Default](DEBUG): Aggregate::initialize Default
2020-12-10T22:23:57.990Z,1607639037.990 [Default:B.GoToSurface] Running Loop=1
2020-12-10T22:23:57.990Z,1607639037.990 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-12-10T22:23:57.991Z,1607639037.991 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-12-10T22:23:57.991Z,1607639037.991 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-12-10T22:23:57.991Z,1607639037.991 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-12-10T22:23:57.992Z,1607639037.992 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-12-10T22:23:57.992Z,1607639037.992 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-12-10T22:23:57.992Z,1607639037.992 [Default:A.Wait] Running Loop=1
2020-12-10T22:23:57.992Z,1607639037.992 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-12-10T22:24:11.319Z,1607639051.319 [Default:A.Wait](INFO): Done Waiting.
2020-12-10T22:24:11.319Z,1607639051.319 [Default:A.Wait] Stopped
2020-12-10T22:24:11.319Z,1607639051.319 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-12-10T22:24:11.721Z,1607639051.721 [Default:CheckIn] Running Loop=1
2020-12-10T22:24:11.722Z,1607639051.722 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-12-10T22:24:11.722Z,1607639051.722 [Default:CheckIn:Read_GPS] Running Loop=1
2020-12-10T22:24:13.719Z,1607639053.719 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222412.00,A,3648.81449,N,12149.05874,W,0.544,6.57,101220,,,A*70
2020-12-10T22:24:13.721Z,1607639053.721 [NAL9602](INFO): GPS fix at 20201210T222412: (36.813575, -121.817646)
2020-12-10T22:24:13.732Z,1607639053.732 [Default:CheckIn:Read_GPS] Stopped
2020-12-10T22:24:13.732Z,1607639053.732 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-12-10T22:24:25.453Z,1607639065.453 [DataOverHttps](INFO): Sending 70 bytes from file Logs/20201210T210229/Courier0027.lzma
2020-12-10T22:24:26.455Z,1607639066.455 [DataOverHttps](INFO): Moved sent file to Logs/20201210T210229/Courier0027.lzma.bak
2020-12-10T22:24:26.455Z,1607639066.455 [DataOverHttps](INFO): SBD MOMSN=13448359
2020-12-10T22:24:46.186Z,1607639086.186 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-10T22:24:46.356Z,1607639086.356 [DataOverHttps](INFO): Sending 103 bytes from file Logs/20201210T210229/Express0028.lzma
2020-12-10T22:24:47.359Z,1607639087.359 [DataOverHttps](INFO): Moved sent file to Logs/20201210T210229/Express0028.lzma.bak
2020-12-10T22:24:47.359Z,1607639087.359 [DataOverHttps](INFO): SBD MOMSN=13448383
2020-12-10T22:24:49.020Z,1607639089.020 [Default:CheckIn:Read_Iridium] Stopped
2020-12-10T22:24:49.021Z,1607639089.021 [Default:CheckIn:C.Wait] Running Loop=1
2020-12-10T22:24:49.021Z,1607639089.021 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-10T22:29:49.716Z,1607639389.716 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-12-10T22:29:49.716Z,1607639389.716 [Default:CheckIn:C.Wait] Stopped
2020-12-10T22:29:49.717Z,1607639389.717 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-10T22:29:49.717Z,1607639389.717 [Default:CheckIn:D] Running Loop=1
2020-12-10T22:29:50.129Z,1607639390.129 [Default:CheckIn:D] Stopped
2020-12-10T22:29:50.130Z,1607639390.130 [Default:CheckIn:E] Running Loop=1
2020-12-10T22:29:50.543Z,1607639390.543 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 5.868975 min 
2020-12-10T22:29:50.544Z,1607639390.544 [Default:CheckIn:E] Stopped
2020-12-10T22:29:50.544Z,1607639390.544 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-12-10T22:29:50.544Z,1607639390.544 [Default:CheckIn] Stopped
2020-12-10T22:29:50.544Z,1607639390.544 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-12-10T22:29:50.544Z,1607639390.544 [Default:CheckIn](INFO): Running loop #2
2020-12-10T22:29:50.544Z,1607639390.544 [Default:CheckIn] Running Loop=2
2020-12-10T22:29:50.544Z,1607639390.544 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-12-10T22:29:50.544Z,1607639390.544 [Default:CheckIn:Read_GPS] Running Loop=1
2020-12-10T22:29:52.534Z,1607639392.534 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222952.00,A,3648.85958,N,12149.06900,W,0.700,336.63,101220,,,D*71
2020-12-10T22:29:52.536Z,1607639392.536 [NAL9602](INFO): GPS fix at 20201210T222952: (36.814326, -121.817817)
2020-12-10T22:29:52.547Z,1607639392.547 [Default:CheckIn:Read_GPS] Stopped
2020-12-10T22:29:52.548Z,1607639392.548 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-12-10T22:30:00.562Z,1607639400.562 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20201210T210229/Courier0030.lzma
2020-12-10T22:30:01.563Z,1607639401.563 [DataOverHttps](INFO): Moved sent file to Logs/20201210T210229/Courier0030.lzma.bak
2020-12-10T22:30:01.563Z,1607639401.563 [DataOverHttps](INFO): SBD MOMSN=13448845
2020-12-10T22:30:21.593Z,1607639421.593 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20201210T210229/Express0031.lzma
2020-12-10T22:30:22.595Z,1607639422.595 [DataOverHttps](INFO): Moved sent file to Logs/20201210T210229/Express0031.lzma.bak
2020-12-10T22:30:22.595Z,1607639422.595 [DataOverHttps](INFO): SBD MOMSN=13448873
2020-12-10T22:30:24.542Z,1607639424.542 [Default:CheckIn:Read_Iridium] Stopped
2020-12-10T22:30:24.542Z,1607639424.542 [Default:CheckIn:C.Wait] Running Loop=1
2020-12-10T22:30:24.542Z,1607639424.542 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-10T22:31:03.344Z,1607639463.344 [NAL9602](INFO): SBD MO Status=0, MOMSN=12061, MT Status=0, MTMSN=0
2020-12-10T22:31:03.344Z,1607639463.344 [NAL9602](INFO): No messages in MT queue
2020-12-10T22:31:34.044Z,1607639494.044 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-10T22:35:25.190Z,1607639725.190 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-12-10T22:35:25.190Z,1607639725.190 [Default:CheckIn:C.Wait] Stopped
2020-12-10T22:35:25.190Z,1607639725.190 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-10T22:35:25.191Z,1607639725.191 [Default:CheckIn:D] Running Loop=1
2020-12-10T22:35:25.560Z,1607639725.560 [Default:CheckIn:D] Stopped
2020-12-10T22:35:25.560Z,1607639725.560 [Default:CheckIn:E] Running Loop=1
2020-12-10T22:35:25.963Z,1607639725.963 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.459504 min 
2020-12-10T22:35:25.963Z,1607639725.963 [Default:CheckIn:E] Stopped
2020-12-10T22:35:25.963Z,1607639725.963 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-12-10T22:35:25.964Z,1607639725.964 [Default:CheckIn] Stopped
2020-12-10T22:35:25.964Z,1607639725.964 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-12-10T22:35:25.964Z,1607639725.964 [Default:CheckIn](INFO): Running loop #3
2020-12-10T22:35:25.964Z,1607639725.964 [Default:CheckIn] Running Loop=3
2020-12-10T22:35:25.964Z,1607639725.964 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-12-10T22:35:25.964Z,1607639725.964 [Default:CheckIn:Read_GPS] Running Loop=1
2020-12-10T22:35:27.972Z,1607639727.972 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,223527.00,A,3648.90470,N,12149.08052,W,0.525,332.65,101220,,,A*7F
2020-12-10T22:35:27.974Z,1607639727.974 [NAL9602](INFO): GPS fix at 20201210T223527: (36.815078, -121.818009)
2020-12-10T22:35:28.009Z,1607639728.009 [Default:CheckIn:Read_GPS] Stopped
2020-12-10T22:35:28.009Z,1607639728.009 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-12-10T22:35:35.780Z,1607639735.780 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20201210T210229/Courier0033.lzma
2020-12-10T22:35:36.783Z,1607639736.783 [DataOverHttps](INFO): Moved sent file to Logs/20201210T210229/Courier0033.lzma.bak
2020-12-10T22:35:36.783Z,1607639736.783 [DataOverHttps](INFO): SBD MOMSN=13449306
2020-12-10T22:35:42.916Z,1607639742.916 [NAL9602](INFO): SBD MO Status=0, MOMSN=12062, MT Status=0, MTMSN=0
2020-12-10T22:35:42.916Z,1607639742.916 [NAL9602](INFO): No messages in MT queue
2020-12-10T22:35:53.008Z,1607639753.008 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20201210T210229/Express0034.lzma
2020-12-10T22:35:54.012Z,1607639754.012 [DataOverHttps](INFO): Moved sent file to Logs/20201210T210229/Express0034.lzma.bak
2020-12-10T22:35:54.012Z,1607639754.012 [DataOverHttps](INFO): SBD MOMSN=13449310
2020-12-10T22:35:55.864Z,1607639755.864 [Default:CheckIn:Read_Iridium] Stopped
2020-12-10T22:35:55.865Z,1607639755.865 [Default:CheckIn:C.Wait] Running Loop=1
2020-12-10T22:35:55.865Z,1607639755.865 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-10T22:36:13.618Z,1607639773.618 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-10T22:40:56.281Z,1607640056.281 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-12-10T22:40:56.282Z,1607640056.282 [Default:CheckIn:C.Wait] Stopped
2020-12-10T22:40:56.282Z,1607640056.282 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-10T22:40:56.282Z,1607640056.282 [Default:CheckIn:D] Running Loop=1
2020-12-10T22:40:56.668Z,1607640056.668 [Default:CheckIn:D] Stopped
2020-12-10T22:40:56.668Z,1607640056.668 [Default:CheckIn:E] Running Loop=1
2020-12-10T22:40:57.067Z,1607640057.067 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 16.977965 min 
2020-12-10T22:40:57.067Z,1607640057.067 [Default:CheckIn:E] Stopped
2020-12-10T22:40:57.068Z,1607640057.068 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-12-10T22:40:57.068Z,1607640057.068 [Default:CheckIn] Stopped
2020-12-10T22:40:57.068Z,1607640057.068 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-12-10T22:40:57.068Z,1607640057.068 [Default:CheckIn](INFO): Running loop #4
2020-12-10T22:40:57.068Z,1607640057.068 [Default:CheckIn] Running Loop=4
2020-12-10T22:40:57.068Z,1607640057.068 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-12-10T22:40:57.068Z,1607640057.068 [Default:CheckIn:Read_GPS] Running Loop=1
2020-12-10T22:40:59.081Z,1607640059.081 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224058.00,A,3648.94725,N,12149.09085,W,0.505,317.76,101220,,,A*7E
2020-12-10T22:40:59.083Z,1607640059.083 [NAL9602](INFO): GPS fix at 20201210T224058: (36.815787, -121.818181)
2020-12-10T22:40:59.098Z,1607640059.098 [Default:CheckIn:Read_GPS] Stopped
2020-12-10T22:40:59.098Z,1607640059.098 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-12-10T22:41:06.360Z,1607640066.360 [NAL9602](INFO): SBD MO Status=0, MOMSN=12063, MT Status=0, MTMSN=0
2020-12-10T22:41:06.360Z,1607640066.360 [NAL9602](INFO): No messages in MT queue
2020-12-10T22:41:07.036Z,1607640067.036 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20201210T210229/Courier0036.lzma
2020-12-10T22:41:08.039Z,1607640068.039 [DataOverHttps](INFO): Moved sent file to Logs/20201210T210229/Courier0036.lzma.bak
2020-12-10T22:41:08.039Z,1607640068.039 [DataOverHttps](INFO): SBD MOMSN=13449763
2020-12-10T22:41:24.302Z,1607640084.302 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20201210T210229/Express0037.lzma
2020-12-10T22:41:25.303Z,1607640085.303 [DataOverHttps](INFO): Moved sent file to Logs/20201210T210229/Express0037.lzma.bak
2020-12-10T22:41:25.303Z,1607640085.303 [DataOverHttps](INFO): SBD MOMSN=13449790
2020-12-10T22:41:26.972Z,1607640086.972 [Default:CheckIn:Read_Iridium] Stopped
2020-12-10T22:41:26.972Z,1607640086.972 [Default:CheckIn:C.Wait] Running Loop=1
2020-12-10T22:41:26.972Z,1607640086.972 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-10T22:41:37.052Z,1607640097.052 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-10T22:46:27.689Z,1607640387.689 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-12-10T22:46:27.689Z,1607640387.689 [Default:CheckIn:C.Wait] Stopped
2020-12-10T22:46:27.690Z,1607640387.690 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-10T22:46:27.701Z,1607640387.701 [Default:CheckIn:D] Running Loop=1
2020-12-10T22:46:28.077Z,1607640388.077 [Default:CheckIn:D] Stopped
2020-12-10T22:46:28.077Z,1607640388.077 [Default:CheckIn:E] Running Loop=1
2020-12-10T22:46:28.505Z,1607640388.505 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 22.501442 min 
2020-12-10T22:46:28.505Z,1607640388.505 [Default:CheckIn:E] Stopped
2020-12-10T22:46:28.505Z,1607640388.505 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-12-10T22:46:28.505Z,1607640388.505 [Default:CheckIn] Stopped
2020-12-10T22:46:28.506Z,1607640388.506 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-12-10T22:46:28.506Z,1607640388.506 [Default:CheckIn](INFO): Running loop #5
2020-12-10T22:46:28.506Z,1607640388.506 [Default:CheckIn] Running Loop=5
2020-12-10T22:46:28.506Z,1607640388.506 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-12-10T22:46:28.506Z,1607640388.506 [Default:CheckIn:Read_GPS] Running Loop=1
2020-12-10T22:46:30.499Z,1607640390.499 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224630.00,A,3648.99158,N,12149.10376,W,0.389,339.44,101220,,,D*7A
2020-12-10T22:46:30.501Z,1607640390.501 [NAL9602](INFO): GPS fix at 20201210T224630: (36.816526, -121.818396)
2020-12-10T22:46:30.571Z,1607640390.571 [Default:CheckIn:Read_GPS] Stopped
2020-12-10T22:46:30.571Z,1607640390.571 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-12-10T22:46:42.480Z,1607640402.480 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20201210T210229/Courier0039.lzma
2020-12-10T22:46:43.483Z,1607640403.483 [DataOverHttps](INFO): Moved sent file to Logs/20201210T210229/Courier0039.lzma.bak
2020-12-10T22:46:43.483Z,1607640403.483 [DataOverHttps](INFO): SBD MOMSN=13450203
2020-12-10T22:46:53.416Z,1607640413.416 [NAL9602](INFO): SBD MO Status=0, MOMSN=12064, MT Status=0, MTMSN=0
2020-12-10T22:46:53.416Z,1607640413.416 [NAL9602](INFO): No messages in MT queue
2020-12-10T22:46:59.844Z,1607640419.844 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20201210T210229/Express0040.lzma
2020-12-10T22:47:00.847Z,1607640420.847 [DataOverHttps](INFO): Moved sent file to Logs/20201210T210229/Express0040.lzma.bak
2020-12-10T22:47:00.847Z,1607640420.847 [DataOverHttps](INFO): SBD MOMSN=13450227
2020-12-10T22:47:02.327Z,1607640422.327 [Default:CheckIn:Read_Iridium] Stopped
2020-12-10T22:47:02.328Z,1607640422.328 [Default:CheckIn:C.Wait] Running Loop=1
2020-12-10T22:47:02.328Z,1607640422.328 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-10T22:47:24.148Z,1607640444.148 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-10T22:50:22.316Z,1607640622.316 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-12-10T22:51:54.690Z,1607640714.690 [DataOverHttps](IMPORTANT): SBD MTMSN=20201210T225154