2026-03-12T18:51:22.997Z,1773341482.997 [CommandExec](IMPORTANT): got command restart logs 2026-03-12T18:51:23.159Z,1773341483.159 [Default] Stopped 2026-03-12T18:51:23.159Z,1773341483.159 [Default](DEBUG): Aggregate::uninitialize Default 2026-03-12T18:51:23.159Z,1773341483.159 [Default:B.GoToSurface] Stopped 2026-03-12T18:51:23.159Z,1773341483.159 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2026-03-12T18:51:23.159Z,1773341483.159 [Default:CheckIn] Stopped 2026-03-12T18:51:23.159Z,1773341483.159 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2026-03-12T18:51:23.159Z,1773341483.159 [Default:CheckIn:C.Wait] Stopped 2026-03-12T18:51:23.159Z,1773341483.159 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2026-03-12T18:51:23.159Z,1773341483.159 [MissionManager](IMPORTANT): Started mission keepstation 2026-03-12T18:51:23.160Z,1773341483.160 [keepstation] Running Loop=1 2026-03-12T18:51:23.160Z,1773341483.160 [keepstation](DEBUG): Aggregate::initialize keepstation 2026-03-12T18:51:23.160Z,1773341483.160 [keepstation:StandardEnvelopes] Running Loop=1 2026-03-12T18:51:23.160Z,1773341483.160 [keepstation:StandardEnvelopes](DEBUG): Aggregate::initialize keepstation:StandardEnvelopes 2026-03-12T18:51:23.160Z,1773341483.160 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2026-03-12T18:51:23.160Z,1773341483.160 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2026-03-12T18:51:23.160Z,1773341483.160 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2026-03-12T18:51:23.160Z,1773341483.160 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2026-03-12T18:51:23.161Z,1773341483.161 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2026-03-12T18:51:23.161Z,1773341483.161 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2026-03-12T18:51:23.161Z,1773341483.161 [keepstation:BackseatDriver] Running Loop=1 2026-03-12T18:51:23.161Z,1773341483.161 [keepstation:BackseatDriver](DEBUG): Aggregate::initialize keepstation:BackseatDriver 2026-03-12T18:51:23.161Z,1773341483.161 [keepstation:BackseatDriver:A.BackseatDriver] Running Loop=1 2026-03-12T18:51:23.161Z,1773341483.161 [keepstation:PowerOnly] Running Loop=1 2026-03-12T18:51:23.161Z,1773341483.161 [keepstation:PowerOnly](DEBUG): Aggregate::initialize keepstation:PowerOnly 2026-03-12T18:51:23.161Z,1773341483.161 [keepstation:PowerOnly:A] Running Loop=1 2026-03-12T18:51:23.161Z,1773341483.161 [keepstation:PowerOnly:B] Running Loop=1 2026-03-12T18:51:23.161Z,1773341483.161 [keepstation:PowerOnly:C] Running Loop=1 2026-03-12T18:51:23.161Z,1773341483.161 [keepstation:PowerOnly:D] Running Loop=1 2026-03-12T18:51:23.161Z,1773341483.161 [keepstation:PowerOnly:E.Wait] Running Loop=1 2026-03-12T18:51:23.162Z,1773341483.162 [keepstation:PowerOnly:E.Wait](DEBUG): Initialize Wait Component. 2026-03-12T18:51:23.162Z,1773341483.162 [keepstation:H.Pitch] Running Loop=1 2026-03-12T18:51:23.162Z,1773341483.162 [keepstation:H.Pitch](DEBUG): Initialize. 2026-03-12T18:51:23.162Z,1773341483.162 [keepstation:B] Running Loop=1 2026-03-12T18:51:23.163Z,1773341483.163 [keepstation:H.Pitch] Running Loop=1 2026-03-12T18:51:23.163Z,1773341483.163 [keepstation:PowerOnly] Running Loop=1 2026-03-12T18:51:23.163Z,1773341483.163 [keepstation:PowerOnly:D] Stopped 2026-03-12T18:51:23.163Z,1773341483.163 [keepstation:PowerOnly:C] Stopped 2026-03-12T18:51:23.164Z,1773341483.164 [keepstation:PowerOnly:B] Stopped 2026-03-12T18:51:23.164Z,1773341483.164 [keepstation:PowerOnly:A] Stopped 2026-03-12T18:51:23.164Z,1773341483.164 [keepstation:BackseatDriver] Running Loop=1 2026-03-12T18:51:23.164Z,1773341483.164 [keepstation:StandardEnvelopes] Running Loop=1 2026-03-12T18:51:23.164Z,1773341483.164 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2026-03-12T18:51:23.164Z,1773341483.164 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2026-03-12T18:51:23.165Z,1773341483.165 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2026-03-12T18:51:23.165Z,1773341483.165 [keepstation:B] Stopped 2026-03-12T18:51:23.165Z,1773341483.165 [keepstation:D] Running Loop=1 2026-03-12T18:51:23.565Z,1773341483.565 [keepstation:D] Stopped 2026-03-12T18:51:23.565Z,1773341483.565 [keepstation:E] Running Loop=1 2026-03-12T18:51:23.973Z,1773341483.973 [keepstation:E] Stopped 2026-03-12T18:51:23.973Z,1773341483.973 [keepstation:StartingMission] Running Loop=1 2026-03-12T18:51:23.973Z,1773341483.973 [keepstation:StartingMission](DEBUG): Aggregate::initialize keepstation:StartingMission 2026-03-12T18:51:24.390Z,1773341484.390 [keepstation:SurfaceComms] Running Loop=1 2026-03-12T18:51:24.390Z,1773341484.390 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2026-03-12T18:51:24.391Z,1773341484.391 [keepstation:SurfaceComms:A] Running Loop=1 2026-03-12T18:51:24.391Z,1773341484.391 [keepstation:SurfaceComms:A] Stopped 2026-03-12T18:51:24.391Z,1773341484.391 [keepstation:SurfaceComms:B] Running Loop=1 2026-03-12T18:51:24.391Z,1773341484.391 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2026-03-12T18:51:24.788Z,1773341484.788 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active. 2026-03-12T18:51:24.788Z,1773341484.788 [keepstation:NeedComms] Running Loop=1 2026-03-12T18:51:24.788Z,1773341484.788 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2026-03-12T18:51:24.788Z,1773341484.788 [keepstation:NeedComms:C.GoToSurface] Running Loop=1 2026-03-12T18:51:24.788Z,1773341484.788 [keepstation:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2026-03-12T18:51:24.789Z,1773341484.789 [keepstation:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s. 2026-03-12T18:51:24.789Z,1773341484.789 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2026-03-12T18:51:24.789Z,1773341484.789 [keepstation:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2026-03-12T18:51:24.790Z,1773341484.790 [keepstation:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds. 2026-03-12T18:51:24.790Z,1773341484.790 [keepstation:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2026-03-12T18:51:24.790Z,1773341484.790 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2026-03-12T18:51:24.791Z,1773341484.791 [keepstation:NeedComms:A] Running Loop=1 2026-03-12T18:51:24.797Z,1773341484.797 [keepstation:NeedComms:A](INFO): last time_fix was: 1773341193.000000 second since 1970/01/01T00:00:00Z 2026-03-12T18:51:24.797Z,1773341484.797 [keepstation:NeedComms:A] Stopped 2026-03-12T18:51:24.797Z,1773341484.797 [keepstation:NeedComms:setActive] Running Loop=1 2026-03-12T18:51:24.797Z,1773341484.797 [keepstation:NeedComms:setActive](DEBUG): Aggregate::initialize keepstation:NeedComms:setActive 2026-03-12T18:51:24.797Z,1773341484.797 [keepstation:NeedComms:setActive:A] Running Loop=1 2026-03-12T18:51:25.197Z,1773341485.197 [keepstation:NeedComms:setActive:A] Stopped 2026-03-12T18:51:25.197Z,1773341485.197 [keepstation:NeedComms:setActive:B] Running Loop=1 2026-03-12T18:51:25.588Z,1773341485.588 [keepstation:NeedComms:setActive:B] Stopped 2026-03-12T18:51:25.589Z,1773341485.589 [keepstation:NeedComms:setActive](INFO): Completed keepstation:NeedComms:setActive 2026-03-12T18:51:25.589Z,1773341485.589 [keepstation:NeedComms:setActive] Stopped 2026-03-12T18:51:25.589Z,1773341485.589 [keepstation:NeedComms:setActive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setActive 2026-03-12T18:51:25.998Z,1773341485.998 [keepstation:NeedComms:D] Running Loop=1 2026-03-12T18:51:26.409Z,1773341486.409 [keepstation:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense time_fix 2026-03-12T18:51:27.990Z,1773341487.990 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185123.00,A,3648.36025,N,12151.21691,W,0.467,344.76,120326,,,D*76 2026-03-12T18:51:27.993Z,1773341487.993 [NAL9602](INFO): GPS fix at 20260312T185123: (36.806004, -121.853615) 2026-03-12T18:51:28.024Z,1773341488.024 [keepstation:NeedComms:D] Stopped 2026-03-12T18:51:28.025Z,1773341488.025 [keepstation:NeedComms:E] Running Loop=1 2026-03-12T18:51:28.426Z,1773341488.426 [keepstation:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense platform_communications 2026-03-12T18:51:38.539Z,1773341498.539 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20260312T165858/Courier0051.lzma 2026-03-12T18:51:39.314Z,1773341499.314 [NAL9602](INFO): SBD MO Status=0, MOMSN=13208, MT Status=0, MTMSN=0 2026-03-12T18:51:39.315Z,1773341499.315 [NAL9602](INFO): No messages in MT queue 2026-03-12T18:51:39.541Z,1773341499.541 [DataOverHttps](INFO): Moved sent file to Logs/20260312T165858/Courier0051.lzma.bak 2026-03-12T18:51:39.542Z,1773341499.542 [DataOverHttps](INFO): SBD MOMSN=26724367 2026-03-12T18:52:10.025Z,1773341530.025 [NAL9602](INFO): Not Powering down - fast GPS 2026-03-12T18:52:11.672Z,1773341531.672 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-12T18:52:38.208Z,1773341558.208 [DataOverHttps](INFO): Sending 288 bytes from file Logs/20260312T165858/Express0052.lzma 2026-03-12T18:52:39.210Z,1773341559.210 [DataOverHttps](INFO): Moved sent file to Logs/20260312T165858/Express0052.lzma.bak 2026-03-12T18:52:39.210Z,1773341559.210 [DataOverHttps](INFO): SBD MOMSN=26724385 2026-03-12T18:52:40.745Z,1773341560.745 [keepstation:NeedComms:E] Stopped 2026-03-12T18:52:40.746Z,1773341560.746 [keepstation:NeedComms:F] Running Loop=1 2026-03-12T18:52:41.136Z,1773341561.136 [keepstation:NeedComms:F](DEBUG): Initialize ReadDataComponent to sense time_fix 2026-03-12T18:52:42.742Z,1773341562.742 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185237.00,A,3648.36694,N,12151.20837,W,0.836,1.68,120326,,,D*7A 2026-03-12T18:52:42.745Z,1773341562.745 [NAL9602](INFO): GPS fix at 20260312T185237: (36.806116, -121.853473) 2026-03-12T18:52:42.798Z,1773341562.798 [keepstation:NeedComms:F] Stopped 2026-03-12T18:52:42.798Z,1773341562.798 [keepstation:NeedComms:setRestoreMode] Running Loop=1 2026-03-12T18:52:42.798Z,1773341562.798 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::initialize keepstation:NeedComms:setRestoreMode 2026-03-12T18:52:42.798Z,1773341562.798 [keepstation:NeedComms:setRestoreMode:A] Running Loop=1 2026-03-12T18:52:43.203Z,1773341563.203 [keepstation:NeedComms:setRestoreMode:A] Stopped 2026-03-12T18:52:43.204Z,1773341563.204 [keepstation:NeedComms:setRestoreMode](INFO): Completed keepstation:NeedComms:setRestoreMode 2026-03-12T18:52:43.204Z,1773341563.204 [keepstation:NeedComms:setRestoreMode] Stopped 2026-03-12T18:52:43.204Z,1773341563.204 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setRestoreMode 2026-03-12T18:52:43.204Z,1773341563.204 [keepstation:NeedComms:setInactive] Running Loop=1 2026-03-12T18:52:43.204Z,1773341563.204 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::initialize keepstation:NeedComms:setInactive 2026-03-12T18:52:43.204Z,1773341563.204 [keepstation:NeedComms:setInactive:A] Running Loop=1 2026-03-12T18:52:43.573Z,1773341563.573 [keepstation:NeedComms:setInactive:A] Stopped 2026-03-12T18:52:43.574Z,1773341563.574 [keepstation:NeedComms:setInactive](INFO): Completed keepstation:NeedComms:setInactive 2026-03-12T18:52:43.574Z,1773341563.574 [keepstation:NeedComms:setInactive] Stopped 2026-03-12T18:52:43.574Z,1773341563.574 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setInactive 2026-03-12T18:52:43.575Z,1773341563.575 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2026-03-12T18:52:43.575Z,1773341563.575 [keepstation:NeedComms] Stopped 2026-03-12T18:52:43.575Z,1773341563.575 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2026-03-12T18:52:43.575Z,1773341563.575 [keepstation:NeedComms:C.GoToSurface] Stopped 2026-03-12T18:52:43.575Z,1773341563.575 [keepstation:NeedComms:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2026-03-12T18:52:43.986Z,1773341563.986 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2026-03-12T18:52:43.987Z,1773341563.987 [keepstation:SurfaceComms:B] Stopped 2026-03-12T18:52:43.987Z,1773341563.987 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2026-03-12T18:52:43.987Z,1773341563.987 [keepstation:SurfaceComms:HandleNan] Running Loop=1 2026-03-12T18:52:43.987Z,1773341563.987 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan 2026-03-12T18:52:43.987Z,1773341563.987 [keepstation:SurfaceComms:HandleNan:SetOnStation] Running Loop=1 2026-03-12T18:52:43.987Z,1773341563.987 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetOnStation 2026-03-12T18:52:43.987Z,1773341563.987 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Running Loop=1 2026-03-12T18:52:44.399Z,1773341564.399 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Stopped 2026-03-12T18:52:44.400Z,1773341564.400 [keepstation:SurfaceComms:HandleNan:SetOnStation](INFO): Completed keepstation:SurfaceComms:HandleNan:SetOnStation 2026-03-12T18:52:44.400Z,1773341564.400 [keepstation:SurfaceComms:HandleNan:SetOnStation] Stopped 2026-03-12T18:52:44.400Z,1773341564.400 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetOnStation 2026-03-12T18:52:44.400Z,1773341564.400 [keepstation:SurfaceComms:HandleNan:SetLat] Running Loop=1 2026-03-12T18:52:44.400Z,1773341564.400 [keepstation:SurfaceComms:HandleNan:SetLat](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetLat 2026-03-12T18:52:44.400Z,1773341564.400 [keepstation:SurfaceComms:HandleNan:SetLat:A] Running Loop=1 2026-03-12T18:52:44.780Z,1773341564.780 [keepstation:SurfaceComms:HandleNan:SetLat:A] Stopped 2026-03-12T18:52:44.781Z,1773341564.781 [keepstation:SurfaceComms:HandleNan:SetLat](INFO): Completed keepstation:SurfaceComms:HandleNan:SetLat 2026-03-12T18:52:44.781Z,1773341564.781 [keepstation:SurfaceComms:HandleNan:SetLat] Stopped 2026-03-12T18:52:44.781Z,1773341564.781 [keepstation:SurfaceComms:HandleNan:SetLat](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetLat 2026-03-12T18:52:44.781Z,1773341564.781 [keepstation:SurfaceComms:HandleNan:SetLon] Running Loop=1 2026-03-12T18:52:44.781Z,1773341564.781 [keepstation:SurfaceComms:HandleNan:SetLon](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetLon 2026-03-12T18:52:44.781Z,1773341564.781 [keepstation:SurfaceComms:HandleNan:SetLon:A] Running Loop=1 2026-03-12T18:52:45.196Z,1773341565.196 [keepstation:SurfaceComms:HandleNan:SetLon:A] Stopped 2026-03-12T18:52:45.196Z,1773341565.196 [keepstation:SurfaceComms:HandleNan:SetLon](INFO): Completed keepstation:SurfaceComms:HandleNan:SetLon 2026-03-12T18:52:45.196Z,1773341565.196 [keepstation:SurfaceComms:HandleNan:SetLon] Stopped 2026-03-12T18:52:45.196Z,1773341565.196 [keepstation:SurfaceComms:HandleNan:SetLon](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetLon 2026-03-12T18:52:45.196Z,1773341565.196 [keepstation:SurfaceComms:HandleNan](INFO): Completed keepstation:SurfaceComms:HandleNan 2026-03-12T18:52:45.196Z,1773341565.196 [keepstation:SurfaceComms:HandleNan] Stopped 2026-03-12T18:52:45.196Z,1773341565.196 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan 2026-03-12T18:52:45.197Z,1773341565.197 [keepstation:SurfaceComms:setTransit] Running Loop=1 2026-03-12T18:52:45.197Z,1773341565.197 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2026-03-12T18:52:45.197Z,1773341565.197 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2026-03-12T18:52:45.602Z,1773341565.602 [keepstation:SurfaceComms:setTransit] Stopped 2026-03-12T18:52:45.602Z,1773341565.602 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2026-03-12T18:52:45.602Z,1773341565.602 [keepstation:SurfaceComms:setTransit:A] Stopped 2026-03-12T18:52:45.602Z,1773341565.602 [keepstation:SurfaceComms:setStation] Running Loop=1 2026-03-12T18:52:45.602Z,1773341565.602 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2026-03-12T18:52:45.602Z,1773341565.602 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2026-03-12T18:52:45.984Z,1773341565.984 [keepstation:SurfaceComms:setStation:A] Stopped 2026-03-12T18:52:45.984Z,1773341565.984 [keepstation:SurfaceComms:setStation](INFO): Completed keepstation:SurfaceComms:setStation 2026-03-12T18:52:45.984Z,1773341565.984 [keepstation:SurfaceComms:setStation] Stopped 2026-03-12T18:52:45.985Z,1773341565.985 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2026-03-12T18:52:45.985Z,1773341565.985 [keepstation:SurfaceComms:setSink] Running Loop=1 2026-03-12T18:52:45.985Z,1773341565.985 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2026-03-12T18:52:45.985Z,1773341565.985 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2026-03-12T18:52:46.391Z,1773341566.391 [keepstation:SurfaceComms:setSink] Stopped 2026-03-12T18:52:46.391Z,1773341566.391 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2026-03-12T18:52:46.391Z,1773341566.391 [keepstation:SurfaceComms:setSink:A] Stopped 2026-03-12T18:52:46.392Z,1773341566.392 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2026-03-12T18:52:46.392Z,1773341566.392 [keepstation:SurfaceComms] Stopped 2026-03-12T18:52:46.392Z,1773341566.392 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2026-03-12T18:52:46.798Z,1773341566.798 [keepstation:StartingMission](INFO): Completed keepstation:StartingMission 2026-03-12T18:52:46.798Z,1773341566.798 [keepstation:StartingMission] Stopped 2026-03-12T18:52:46.798Z,1773341566.798 [keepstation:StartingMission](DEBUG): Aggregate::uninitialize keepstation:StartingMission 2026-03-12T18:52:46.798Z,1773341566.798 [keepstation:TransitToStation] Running Loop=1 2026-03-12T18:52:46.798Z,1773341566.798 [keepstation:TransitToStation](DEBUG): Aggregate::initialize keepstation:TransitToStation 2026-03-12T18:52:46.798Z,1773341566.798 [keepstation:TransitToStation:Dive] Running Loop=1 2026-03-12T18:52:46.798Z,1773341566.798 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive 2026-03-12T18:52:46.798Z,1773341566.798 [keepstation:TransitToStation:Dive:A] Running Loop=1 2026-03-12T18:52:46.798Z,1773341566.798 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive:A 2026-03-12T18:52:46.799Z,1773341566.799 [keepstation:TransitToStation:Wpt1.Waypoint] Running Loop=1 2026-03-12T18:52:46.799Z,1773341566.799 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent. 2026-03-12T18:52:46.800Z,1773341566.800 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.806116,-121.853473 2026-03-12T18:52:47.214Z,1773341567.214 [keepstation:TransitToStation:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.806116,-121.853473 2026-03-12T18:52:47.214Z,1773341567.214 [keepstation:TransitToStation:Dive] Running Loop=1 2026-03-12T18:52:47.214Z,1773341567.214 [keepstation:DiveCmd] Running Loop=1 2026-03-12T18:52:47.214Z,1773341567.214 [keepstation:DiveCmd](DEBUG): Aggregate::initialize keepstation:DiveCmd 2026-03-12T18:52:47.215Z,1773341567.215 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2026-03-12T18:52:47.215Z,1773341567.215 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2026-03-12T18:52:47.215Z,1773341567.215 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2026-03-12T18:52:47.215Z,1773341567.215 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2026-03-12T18:52:47.215Z,1773341567.215 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2026-03-12T18:52:47.215Z,1773341567.215 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2026-03-12T18:52:47.215Z,1773341567.215 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2026-03-12T18:52:47.215Z,1773341567.215 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2026-03-12T18:52:47.216Z,1773341567.216 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2026-03-12T18:52:47.216Z,1773341567.216 [keepstation:DiveCmd:TransitToStation] Running Loop=1 2026-03-12T18:52:47.216Z,1773341567.216 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation 2026-03-12T18:52:47.216Z,1773341567.216 [keepstation:DiveCmd:TransitToStation:dive] Running Loop=1 2026-03-12T18:52:47.216Z,1773341567.216 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation:dive 2026-03-12T18:52:47.216Z,1773341567.216 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1 2026-03-12T18:52:47.216Z,1773341567.216 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Initialize Buoyancy Component. 2026-03-12T18:52:47.216Z,1773341567.216 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1 2026-03-12T18:52:47.216Z,1773341567.216 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Initialize. 2026-03-12T18:52:47.217Z,1773341567.217 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1 2026-03-12T18:52:47.217Z,1773341567.217 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Initialize. 2026-03-12T18:52:47.217Z,1773341567.217 [keepstation:DiveCmd:StationKeep] Running Loop=1 2026-03-12T18:52:47.217Z,1773341567.217 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2026-03-12T18:52:47.217Z,1773341567.217 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2026-03-12T18:52:47.217Z,1773341567.217 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2026-03-12T18:52:47.217Z,1773341567.217 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2026-03-12T18:52:47.217Z,1773341567.217 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2026-03-12T18:52:47.217Z,1773341567.217 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2026-03-12T18:52:47.218Z,1773341567.218 [keepstation:DiveCmd:StationKeep] Running Loop=1 2026-03-12T18:52:47.218Z,1773341567.218 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2026-03-12T18:52:47.219Z,1773341567.219 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2026-03-12T18:52:47.219Z,1773341567.219 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2026-03-12T18:52:47.219Z,1773341567.219 [keepstation:DiveCmd:TransitToStation] Stopped 2026-03-12T18:52:47.220Z,1773341567.220 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation 2026-03-12T18:52:47.220Z,1773341567.220 [keepstation:DiveCmd:TransitToStation:dive] Stopped 2026-03-12T18:52:47.220Z,1773341567.220 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation:dive 2026-03-12T18:52:47.220Z,1773341567.220 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Stopped 2026-03-12T18:52:47.220Z,1773341567.220 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2026-03-12T18:52:47.220Z,1773341567.220 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Stopped 2026-03-12T18:52:47.220Z,1773341567.220 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Stopped 2026-03-12T18:52:47.220Z,1773341567.220 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Uninitialize. 2026-03-12T18:52:47.220Z,1773341567.220 [keepstation:DiveCmd:SurfaceSink] Stopped 2026-03-12T18:52:47.220Z,1773341567.220 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink 2026-03-12T18:52:47.220Z,1773341567.220 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2026-03-12T18:52:47.220Z,1773341567.220 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2026-03-12T18:52:47.221Z,1773341567.221 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2026-03-12T18:52:47.221Z,1773341567.221 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2026-03-12T18:52:47.221Z,1773341567.221 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2026-03-12T18:52:47.221Z,1773341567.221 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2026-03-12T18:52:47.221Z,1773341567.221 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2026-03-12T18:52:56.884Z,1773341576.884 [NAL9602](INFO): SBD MO Status=0, MOMSN=13209, MT Status=0, MTMSN=0 2026-03-12T18:52:56.884Z,1773341576.884 [NAL9602](INFO): No messages in MT queue 2026-03-12T18:53:27.585Z,1773341607.585 [NAL9602](INFO): Not Powering down - fast GPS 2026-03-12T18:54:19.100Z,1773341659.100 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:19.100Z,1773341659.100 [DATMMP](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER 2026-03-12T18:54:19.352Z,1773341659.352 [DATMMP](INFO): *** SENDING_FILL_BUFFER *** 2026-03-12T18:54:19.352Z,1773341659.352 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA 2026-03-12T18:54:19.604Z,1773341659.604 [DATMMP](INFO): *** SENDING_NAV_DATA *** 2026-03-12T18:54:19.604Z,1773341659.604 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT 2026-03-12T18:54:19.856Z,1773341659.856 [DATMMP](INFO): *** SENDING_TRANSMIT *** 2026-03-12T18:54:19.856Z,1773341659.856 [DATMMP](INFO): Tx to 1 len 32 xid 152 2026-03-12T18:54:19.857Z,1773341659.857 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2026-03-12T18:54:19.857Z,1773341659.857 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2026-03-12T18:54:20.108Z,1773341660.108 [DATMMP](INFO): GSXN notify for xid: 152 2026-03-12T18:54:20.109Z,1773341660.109 [DATMMP](INFO): cmd_result rx: x1 x7 x0 x1 2026-03-12T18:54:20.109Z,1773341660.109 [DATMMP](DEBUG): Command complete send 2026-03-12T18:54:20.109Z,1773341660.109 [DATMMP](ERROR): 1:COMPLETE 2026-03-12T18:54:20.110Z,1773341660.110 [DATMMP](INFO): Received message type: cmd_result 2026-03-12T18:54:20.110Z,1773341660.110 [DATMMP](INFO): Handled 2026-03-12T18:54:20.110Z,1773341660.110 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:20.360Z,1773341660.360 [DATMMP](INFO): GSXN notify for xid: 0 2026-03-12T18:54:20.361Z,1773341660.361 [DATMMP](INFO): timestamp rx: x0 x12 x36 x12 x1C47 2026-03-12T18:54:20.361Z,1773341660.361 [DATMMP](INFO): Received message type: timestamp 2026-03-12T18:54:20.361Z,1773341660.361 [DATMMP](INFO): Handled 2026-03-12T18:54:20.361Z,1773341660.361 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:20.612Z,1773341660.612 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:20.864Z,1773341660.864 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:21.116Z,1773341661.116 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:21.368Z,1773341661.368 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:21.620Z,1773341661.620 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:21.872Z,1773341661.872 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:22.125Z,1773341662.125 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:22.376Z,1773341662.376 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:22.628Z,1773341662.628 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:22.880Z,1773341662.880 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:23.132Z,1773341663.132 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:23.385Z,1773341663.385 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:23.636Z,1773341663.636 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:23.888Z,1773341663.888 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:24.142Z,1773341664.142 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:24.392Z,1773341664.392 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:24.644Z,1773341664.644 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:24.896Z,1773341664.896 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:25.148Z,1773341665.148 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:25.400Z,1773341665.400 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:25.652Z,1773341665.652 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:25.905Z,1773341665.905 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:26.156Z,1773341666.156 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:26.409Z,1773341666.409 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:26.660Z,1773341666.660 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:26.912Z,1773341666.912 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:27.164Z,1773341667.164 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:27.416Z,1773341667.416 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:27.668Z,1773341667.668 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:27.920Z,1773341667.920 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:28.172Z,1773341668.172 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:28.424Z,1773341668.424 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:28.676Z,1773341668.676 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:28.928Z,1773341668.928 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:29.180Z,1773341669.180 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:29.432Z,1773341669.432 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:29.684Z,1773341669.684 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:29.936Z,1773341669.936 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:29.936Z,1773341669.936 [DATMMP](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER 2026-03-12T18:54:30.188Z,1773341670.188 [DATMMP](INFO): *** SENDING_FILL_BUFFER *** 2026-03-12T18:54:30.188Z,1773341670.188 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA 2026-03-12T18:54:30.440Z,1773341670.440 [DATMMP](INFO): *** SENDING_NAV_DATA *** 2026-03-12T18:54:30.440Z,1773341670.440 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT 2026-03-12T18:54:30.692Z,1773341670.692 [DATMMP](INFO): *** SENDING_TRANSMIT *** 2026-03-12T18:54:30.692Z,1773341670.692 [DATMMP](INFO): Tx to 1 len 32 xid 153 2026-03-12T18:54:30.693Z,1773341670.693 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2026-03-12T18:54:30.693Z,1773341670.693 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2026-03-12T18:54:30.944Z,1773341670.944 [DATMMP](INFO): GSXN notify for xid: 153 2026-03-12T18:54:30.945Z,1773341670.945 [DATMMP](INFO): cmd_result rx: x1 x7 x0 x1 2026-03-12T18:54:30.945Z,1773341670.945 [DATMMP](DEBUG): Command complete send 2026-03-12T18:54:30.945Z,1773341670.945 [DATMMP](ERROR): 1:COMPLETE 2026-03-12T18:54:30.946Z,1773341670.946 [DATMMP](INFO): Received message type: cmd_result 2026-03-12T18:54:30.946Z,1773341670.946 [DATMMP](INFO): Handled 2026-03-12T18:54:30.946Z,1773341670.946 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:31.197Z,1773341671.197 [DATMMP](INFO): GSXN notify for xid: 0 2026-03-12T18:54:31.197Z,1773341671.197 [DATMMP](INFO): timestamp rx: x0 x12 x36 x1D x166C 2026-03-12T18:54:31.198Z,1773341671.198 [DATMMP](INFO): Received message type: timestamp 2026-03-12T18:54:31.198Z,1773341671.198 [DATMMP](INFO): Handled 2026-03-12T18:54:31.198Z,1773341671.198 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:31.448Z,1773341671.448 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:31.700Z,1773341671.700 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:31.953Z,1773341671.953 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:32.204Z,1773341672.204 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:32.456Z,1773341672.456 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:32.708Z,1773341672.708 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:32.960Z,1773341672.960 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:33.212Z,1773341673.212 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:33.464Z,1773341673.464 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:33.716Z,1773341673.716 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:33.968Z,1773341673.968 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:34.220Z,1773341674.220 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:34.472Z,1773341674.472 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:34.724Z,1773341674.724 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:34.976Z,1773341674.976 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:35.228Z,1773341675.228 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:35.480Z,1773341675.480 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:35.732Z,1773341675.732 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:35.984Z,1773341675.984 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:36.236Z,1773341676.236 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:36.488Z,1773341676.488 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:36.740Z,1773341676.740 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:36.992Z,1773341676.992 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:37.244Z,1773341677.244 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:37.496Z,1773341677.496 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:37.748Z,1773341677.748 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:37.000Z,1773341678.000 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:38.252Z,1773341678.252 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:38.504Z,1773341678.504 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:38.756Z,1773341678.756 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:39.008Z,1773341679.008 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:39.260Z,1773341679.260 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:39.512Z,1773341679.512 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:39.764Z,1773341679.764 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:40.016Z,1773341680.016 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:40.268Z,1773341680.268 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:40.520Z,1773341680.520 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:40.777Z,1773341680.777 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:40.777Z,1773341680.777 [DATMMP](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER 2026-03-12T18:54:41.028Z,1773341681.028 [DATMMP](INFO): *** SENDING_FILL_BUFFER *** 2026-03-12T18:54:41.028Z,1773341681.028 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA 2026-03-12T18:54:41.280Z,1773341681.280 [DATMMP](INFO): *** SENDING_NAV_DATA *** 2026-03-12T18:54:41.280Z,1773341681.280 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT 2026-03-12T18:54:41.532Z,1773341681.532 [DATMMP](INFO): *** SENDING_TRANSMIT *** 2026-03-12T18:54:41.532Z,1773341681.532 [DATMMP](INFO): Tx to 1 len 32 xid 154 2026-03-12T18:54:41.533Z,1773341681.533 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2026-03-12T18:54:41.533Z,1773341681.533 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2026-03-12T18:54:41.784Z,1773341681.784 [DATMMP](INFO): GSXN notify for xid: 154 2026-03-12T18:54:41.785Z,1773341681.785 [DATMMP](INFO): cmd_result rx: x1 x7 x0 x1 2026-03-12T18:54:41.785Z,1773341681.785 [DATMMP](DEBUG): Command complete send 2026-03-12T18:54:41.785Z,1773341681.785 [DATMMP](ERROR): 1:COMPLETE 2026-03-12T18:54:41.785Z,1773341681.785 [DATMMP](INFO): Received message type: cmd_result 2026-03-12T18:54:41.786Z,1773341681.786 [DATMMP](INFO): Handled 2026-03-12T18:54:41.786Z,1773341681.786 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:42.036Z,1773341682.036 [DATMMP](INFO): GSXN notify for xid: 0 2026-03-12T18:54:42.037Z,1773341682.037 [DATMMP](INFO): timestamp rx: x0 x12 x36 x28 x1091 2026-03-12T18:54:42.037Z,1773341682.037 [DATMMP](INFO): Received message type: timestamp 2026-03-12T18:54:42.037Z,1773341682.037 [DATMMP](INFO): Handled 2026-03-12T18:54:42.037Z,1773341682.037 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:42.288Z,1773341682.288 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:42.540Z,1773341682.540 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:42.792Z,1773341682.792 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:43.044Z,1773341683.044 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:43.296Z,1773341683.296 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:43.548Z,1773341683.548 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:43.800Z,1773341683.800 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:44.052Z,1773341684.052 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:44.304Z,1773341684.304 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:44.556Z,1773341684.556 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:44.808Z,1773341684.808 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:45.060Z,1773341685.060 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:45.312Z,1773341685.312 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:45.564Z,1773341685.564 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:45.816Z,1773341685.816 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:46.068Z,1773341686.068 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:46.322Z,1773341686.322 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:46.572Z,1773341686.572 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:46.824Z,1773341686.824 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:47.076Z,1773341687.076 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:47.328Z,1773341687.328 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:47.580Z,1773341687.580 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:47.832Z,1773341687.832 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:48.084Z,1773341688.084 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:48.336Z,1773341688.336 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:48.588Z,1773341688.588 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:48.840Z,1773341688.840 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:49.092Z,1773341689.092 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:49.344Z,1773341689.344 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:49.596Z,1773341689.596 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:49.848Z,1773341689.848 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:50.100Z,1773341690.100 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:50.352Z,1773341690.352 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:50.604Z,1773341690.604 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:50.856Z,1773341690.856 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:51.108Z,1773341691.108 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:51.360Z,1773341691.360 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:51.612Z,1773341691.612 [DATMMP](INFO): *** SENDING_ACK_WAITING *** 2026-03-12T18:54:51.612Z,1773341691.612 [DATMMP](DEBUG): In sendingAckWaiting, hit max timeouts so set commsState_ = SENDING_VERIFIED 2026-03-12T18:54:51.612Z,1773341691.612 [DATMMP](ERROR): Ack receipt timeout failure. 2026-03-12T18:54:51.864Z,1773341691.864 [DATMMP](INFO): *** SENDING_VERIFIED *** 2026-03-12T18:54:51.864Z,1773341691.864 [DATMMP](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER 2026-03-12T18:54:57.584Z,1773341697.584 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-12T18:54:59.592Z,1773341699.592 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.238773 2026-03-12T18:55:32.724Z,1773341732.724 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-12T18:55:40.240Z,1773341740.240 [Radio_Surface](INFO): Powering down 2026-03-12T18:56:07.876Z,1773341767.876 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-12T18:56:08.880Z,1773341768.880 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2026-03-12T18:59:09.066Z,1773341949.066 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2026-03-12T19:09:09.347Z,1773342549.347 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2026-03-12T19:12:43.013Z,1773342763.013 [keepstation:SurfaceComms] Running Loop=1 2026-03-12T19:12:43.013Z,1773342763.013 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2026-03-12T19:12:43.014Z,1773342763.014 [keepstation:SurfaceComms:A] Running Loop=1 2026-03-12T19:12:43.015Z,1773342763.015 [keepstation:SurfaceComms:A] Stopped 2026-03-12T19:12:43.015Z,1773342763.015 [keepstation:SurfaceComms:B] Running Loop=1 2026-03-12T19:12:43.020Z,1773342763.020 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2026-03-12T19:12:43.029Z,1773342763.029 [keepstation:NeedComms] Running Loop=1 2026-03-12T19:12:43.029Z,1773342763.029 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2026-03-12T19:12:43.029Z,1773342763.029 [keepstation:NeedComms:C.GoToSurface] Running Loop=1 2026-03-12T19:12:43.029Z,1773342763.029 [keepstation:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2026-03-12T19:12:43.030Z,1773342763.030 [keepstation:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s. 2026-03-12T19:12:43.036Z,1773342763.036 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2026-03-12T19:12:43.037Z,1773342763.037 [keepstation:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2026-03-12T19:12:43.038Z,1773342763.038 [keepstation:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds. 2026-03-12T19:12:43.039Z,1773342763.039 [keepstation:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2026-03-12T19:12:43.044Z,1773342763.044 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2026-03-12T19:12:43.044Z,1773342763.044 [keepstation:NeedComms:A] Running Loop=1 2026-03-12T19:12:43.053Z,1773342763.053 [keepstation:NeedComms:A](INFO): last time_fix was: 1773341557.000000 second since 1970/01/01T00:00:00Z 2026-03-12T19:12:43.054Z,1773342763.054 [keepstation:NeedComms:A] Stopped 2026-03-12T19:12:43.054Z,1773342763.054 [keepstation:NeedComms:setActive] Running Loop=1 2026-03-12T19:12:43.054Z,1773342763.054 [keepstation:NeedComms:setActive](DEBUG): Aggregate::initialize keepstation:NeedComms:setActive 2026-03-12T19:12:43.055Z,1773342763.055 [keepstation:NeedComms:setActive:A] Running Loop=1 2026-03-12T19:12:43.306Z,1773342763.306 [keepstation:TransitToStation:Dive] Stopped 2026-03-12T19:12:43.306Z,1773342763.306 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive 2026-03-12T19:12:43.306Z,1773342763.306 [keepstation:TransitToStation:Dive:A] Stopped 2026-03-12T19:12:43.306Z,1773342763.306 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive:A 2026-03-12T19:12:43.307Z,1773342763.307 [keepstation:DiveCmd:StationKeep] Stopped 2026-03-12T19:12:43.307Z,1773342763.307 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2026-03-12T19:12:43.307Z,1773342763.307 [keepstation:DiveCmd:StationKeep:A] Stopped 2026-03-12T19:12:43.307Z,1773342763.307 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2026-03-12T19:12:43.307Z,1773342763.307 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2026-03-12T19:12:43.307Z,1773342763.307 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2026-03-12T19:12:43.310Z,1773342763.310 [keepstation:NeedComms:setActive:A] Stopped 2026-03-12T19:12:43.310Z,1773342763.310 [keepstation:NeedComms:setActive:B] Running Loop=1 2026-03-12T19:12:43.725Z,1773342763.725 [keepstation:NeedComms:setActive:B] Stopped 2026-03-12T19:12:43.725Z,1773342763.725 [keepstation:NeedComms:setActive](INFO): Completed keepstation:NeedComms:setActive 2026-03-12T19:12:43.725Z,1773342763.725 [keepstation:NeedComms:setActive] Stopped 2026-03-12T19:12:43.725Z,1773342763.725 [keepstation:NeedComms:setActive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setActive 2026-03-12T19:12:47.856Z,1773342767.856 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Reached waypoint: 36.806116,-121.853473 2026-03-12T19:12:47.856Z,1773342767.856 [keepstation:TransitToStation:Wpt1.Waypoint] Stopped 2026-03-12T19:12:47.856Z,1773342767.856 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent. 2026-03-12T19:12:47.856Z,1773342767.856 [keepstation:TransitToStation:C] Running Loop=1 2026-03-12T19:12:48.495Z,1773342768.495 [keepstation:TransitToStation:C] Stopped 2026-03-12T19:12:48.500Z,1773342768.500 [keepstation:TransitToStation:D] Running Loop=1 2026-03-12T19:12:48.501Z,1773342768.501 [keepstation:DiveCmd:StationKeep] Running Loop=1 2026-03-12T19:12:48.501Z,1773342768.501 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2026-03-12T19:12:48.501Z,1773342768.501 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2026-03-12T19:12:48.502Z,1773342768.502 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2026-03-12T19:12:48.502Z,1773342768.502 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2026-03-12T19:12:48.502Z,1773342768.502 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2026-03-12T19:12:48.502Z,1773342768.502 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2026-03-12T19:12:48.998Z,1773342768.998 [keepstation:TransitToStation:D] Stopped 2026-03-12T19:12:48.999Z,1773342768.999 [keepstation:TransitToStation:Dive] Running Loop=1 2026-03-12T19:12:48.999Z,1773342768.999 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive 2026-03-12T19:12:48.999Z,1773342768.999 [keepstation:TransitToStation:Dive:A] Running Loop=1 2026-03-12T19:12:48.999Z,1773342768.999 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive:A 2026-03-12T19:12:48.999Z,1773342768.999 [keepstation:TransitToStation](INFO): Completed keepstation:TransitToStation 2026-03-12T19:12:48.999Z,1773342768.999 [keepstation:TransitToStation] Stopped 2026-03-12T19:12:48.999Z,1773342768.999 [keepstation:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:TransitToStation 2026-03-12T19:12:48.000Z,1773342769.000 [keepstation:TransitToStation:Dive] Stopped 2026-03-12T19:12:48.000Z,1773342769.000 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive 2026-03-12T19:12:48.000Z,1773342769.000 [keepstation:TransitToStation:Dive:A] Stopped 2026-03-12T19:12:48.000Z,1773342769.000 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive:A 2026-03-12T19:12:49.000Z,1773342769.000 [keepstation:OnStation] Running Loop=1 2026-03-12T19:12:49.000Z,1773342769.000 [keepstation:OnStation](DEBUG): Aggregate::initialize keepstation:OnStation 2026-03-12T19:14:47.296Z,1773342887.296 [keepstation:NeedComms:D] Running Loop=1 2026-03-12T19:14:47.752Z,1773342887.752 [Waterlinked](INFO): Powering down 2026-03-12T19:14:47.900Z,1773342887.900 [Radio_Surface](INFO): Powering up 2026-03-12T19:14:53.596Z,1773342893.596 [DataOverHttps](INFO): Radio surface powered ON. 2026-03-12T19:15:23.720Z,1773342923.720 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-12T19:15:55.767Z,1773342955.767 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2026-03-12T19:15:56.184Z,1773342956.184 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2026-03-12T19:15:58.860Z,1773342958.860 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-12T19:16:05.466Z,1773342965.466 [NAL9602](INFO): SBD MO Status=0, MOMSN=13210, MT Status=0, MTMSN=0 2026-03-12T19:16:05.466Z,1773342965.466 [NAL9602](INFO): No messages in MT queue 2026-03-12T19:16:06.678Z,1773342966.678 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191601.00,A,3648.50494,N,12151.11878,W,0.389,28.69,120326,,,D*40 2026-03-12T19:16:06.691Z,1773342966.691 [NAL9602](INFO): GPS fix at 20260312T191601: (36.808416, -121.851980) 2026-03-12T19:16:06.703Z,1773342966.703 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint 2026-03-12T19:16:06.706Z,1773342966.706 [keepstation:NeedComms:D] Stopped 2026-03-12T19:16:06.706Z,1773342966.706 [keepstation:NeedComms:E] Running Loop=1 2026-03-12T19:16:10.714Z,1773342970.714 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2026-03-12T19:16:13.444Z,1773342973.444 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2026-03-12T19:16:27.165Z,1773342987.165 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2026-03-12T19:16:27.590Z,1773342987.590 [NAL9602](INFO): SBD MO Status=1, MOMSN=13211, MT Status=0, MTMSN=0 2026-03-12T19:16:27.640Z,1773342987.640 [NAL9602](INFO): Sent 271 bytes from file Logs/20260312T185122/Courier0006.lzma 2026-03-12T19:16:27.640Z,1773342987.640 [NAL9602](INFO): Packets left to send: 0 2026-03-12T19:16:28.803Z,1773342988.803 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2026-03-12T19:16:34.004Z,1773342994.004 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-12T19:16:42.649Z,1773343002.649 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2026-03-12T19:16:45.880Z,1773343005.880 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2026-03-12T19:16:47.502Z,1773343007.502 [NAL9602](INFO): SBD MO Status=1, MOMSN=13212, MT Status=0, MTMSN=0 2026-03-12T19:16:47.552Z,1773343007.552 [NAL9602](INFO): Sent 332 bytes from file Logs/20260312T185122/Express0007.lzma 2026-03-12T19:16:47.552Z,1773343007.552 [NAL9602](INFO): Packets left to send: 2 2026-03-12T19:16:57.663Z,1773343017.663 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2026-03-12T19:17:01.301Z,1773343021.301 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2026-03-12T19:17:02.920Z,1773343022.920 [NAL9602](INFO): SBD MO Status=1, MOMSN=13213, MT Status=0, MTMSN=0 2026-03-12T19:17:02.973Z,1773343022.973 [NAL9602](INFO): Sent 332 bytes from file Logs/20260312T185122/Express0007.lzma 2026-03-12T19:17:02.974Z,1773343022.974 [NAL9602](INFO): Packets left to send: 1 2026-03-12T19:17:09.144Z,1773343029.144 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-12T19:17:12.658Z,1773343032.658 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2026-03-12T19:17:14.686Z,1773343034.686 [NAL9602](INFO): SBD MO Status=1, MOMSN=13214, MT Status=0, MTMSN=0 2026-03-12T19:17:14.736Z,1773343034.736 [NAL9602](INFO): Sent 72 bytes from file Logs/20260312T185122/Express0007.lzma 2026-03-12T19:17:14.736Z,1773343034.736 [NAL9602](INFO): Packets left to send: 0 2026-03-12T19:17:16.696Z,1773343036.696 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2026-03-12T19:17:22.354Z,1773343042.354 [NAL9602](INFO): SBD MO Status=0, MOMSN=13215, MT Status=0, MTMSN=0 2026-03-12T19:17:22.448Z,1773343042.448 [keepstation:NeedComms:E] Stopped 2026-03-12T19:17:22.448Z,1773343042.448 [keepstation:NeedComms:F] Running Loop=1 2026-03-12T19:17:24.781Z,1773343044.781 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191719.00,A,3648.51064,N,12151.10831,W,0.525,80.19,120326,,,D*4B 2026-03-12T19:17:24.784Z,1773343044.784 [NAL9602](INFO): GPS fix at 20260312T191719: (36.808511, -121.851805) 2026-03-12T19:17:24.799Z,1773343044.799 [keepstation:NeedComms:F] Stopped 2026-03-12T19:17:24.799Z,1773343044.799 [keepstation:NeedComms:setRestoreMode] Running Loop=1 2026-03-12T19:17:24.799Z,1773343044.799 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::initialize keepstation:NeedComms:setRestoreMode 2026-03-12T19:17:24.799Z,1773343044.799 [keepstation:NeedComms:setRestoreMode:A] Running Loop=1 2026-03-12T19:17:25.210Z,1773343045.210 [keepstation:NeedComms:setRestoreMode:A] Stopped 2026-03-12T19:17:25.210Z,1773343045.210 [keepstation:NeedComms:setRestoreMode](INFO): Completed keepstation:NeedComms:setRestoreMode 2026-03-12T19:17:25.210Z,1773343045.210 [keepstation:NeedComms:setRestoreMode] Stopped 2026-03-12T19:17:25.210Z,1773343045.210 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setRestoreMode 2026-03-12T19:17:25.211Z,1773343045.211 [keepstation:NeedComms:setInactive] Running Loop=1 2026-03-12T19:17:25.211Z,1773343045.211 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::initialize keepstation:NeedComms:setInactive 2026-03-12T19:17:25.211Z,1773343045.211 [keepstation:NeedComms:setInactive:A] Running Loop=1 2026-03-12T19:17:25.599Z,1773343045.599 [keepstation:NeedComms:setInactive:A] Stopped 2026-03-12T19:17:25.599Z,1773343045.599 [keepstation:NeedComms:setInactive](INFO): Completed keepstation:NeedComms:setInactive 2026-03-12T19:17:25.599Z,1773343045.599 [keepstation:NeedComms:setInactive] Stopped 2026-03-12T19:17:25.599Z,1773343045.599 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setInactive 2026-03-12T19:17:25.600Z,1773343045.600 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2026-03-12T19:17:25.600Z,1773343045.600 [keepstation:NeedComms] Stopped 2026-03-12T19:17:25.600Z,1773343045.600 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2026-03-12T19:17:25.600Z,1773343045.600 [keepstation:NeedComms:C.GoToSurface] Stopped 2026-03-12T19:17:25.600Z,1773343045.600 [keepstation:NeedComms:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2026-03-12T19:17:26.011Z,1773343046.011 [keepstation:OnStation](INFO): Completed keepstation:OnStation 2026-03-12T19:17:26.011Z,1773343046.011 [keepstation:OnStation] Stopped 2026-03-12T19:17:26.011Z,1773343046.011 [keepstation:OnStation](DEBUG): Aggregate::uninitialize keepstation:OnStation 2026-03-12T19:17:26.011Z,1773343046.011 [keepstation:KeepStation] Running Loop=1 2026-03-12T19:17:26.012Z,1773343046.012 [keepstation:KeepStation](DEBUG): Aggregate::initialize keepstation:KeepStation 2026-03-12T19:17:26.012Z,1773343046.012 [keepstation:KeepStation:Dive] Running Loop=1 2026-03-12T19:17:26.012Z,1773343046.012 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive 2026-03-12T19:17:26.012Z,1773343046.012 [keepstation:KeepStation:Dive:A] Running Loop=1 2026-03-12T19:17:26.012Z,1773343046.012 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A 2026-03-12T19:17:26.012Z,1773343046.012 [keepstation:KeepStation:B.Wait] Running Loop=1 2026-03-12T19:17:26.012Z,1773343046.012 [keepstation:KeepStation:B.Wait](DEBUG): Initialize Wait Component. 2026-03-12T19:17:26.014Z,1773343046.014 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2026-03-12T19:17:26.014Z,1773343046.014 [keepstation:SurfaceComms:B] Stopped 2026-03-12T19:17:26.015Z,1773343046.015 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2026-03-12T19:17:26.015Z,1773343046.015 [keepstation:SurfaceComms:HandleNan] Running Loop=1 2026-03-12T19:17:26.015Z,1773343046.015 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan 2026-03-12T19:17:26.015Z,1773343046.015 [keepstation:SurfaceComms:HandleNan:SetOnStation] Running Loop=1 2026-03-12T19:17:26.015Z,1773343046.015 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetOnStation 2026-03-12T19:17:26.015Z,1773343046.015 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Running Loop=1 2026-03-12T19:17:26.413Z,1773343046.413 [keepstation:KeepStation:Dive] Running Loop=1 2026-03-12T19:17:26.415Z,1773343046.415 [keepstation:SurfaceComms:HandleNan] Stopped 2026-03-12T19:17:26.415Z,1773343046.415 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan 2026-03-12T19:17:26.415Z,1773343046.415 [keepstation:SurfaceComms:HandleNan:SetOnStation] Stopped 2026-03-12T19:17:26.415Z,1773343046.415 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetOnStation 2026-03-12T19:17:26.415Z,1773343046.415 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Stopped 2026-03-12T19:17:26.416Z,1773343046.416 [keepstation:SurfaceComms:setTransit] Running Loop=1 2026-03-12T19:17:26.416Z,1773343046.416 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2026-03-12T19:17:26.416Z,1773343046.416 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2026-03-12T19:17:26.823Z,1773343046.823 [keepstation:SurfaceComms:setTransit] Stopped 2026-03-12T19:17:26.823Z,1773343046.823 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2026-03-12T19:17:26.823Z,1773343046.823 [keepstation:SurfaceComms:setTransit:A] Stopped 2026-03-12T19:17:26.823Z,1773343046.823 [keepstation:SurfaceComms:setStation] Running Loop=1 2026-03-12T19:17:26.823Z,1773343046.823 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2026-03-12T19:17:26.823Z,1773343046.823 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2026-03-12T19:17:27.219Z,1773343047.219 [keepstation:SurfaceComms:setStation:A] Stopped 2026-03-12T19:17:27.219Z,1773343047.219 [keepstation:SurfaceComms:setStation](INFO): Completed keepstation:SurfaceComms:setStation 2026-03-12T19:17:27.219Z,1773343047.219 [keepstation:SurfaceComms:setStation] Stopped 2026-03-12T19:17:27.219Z,1773343047.219 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2026-03-12T19:17:27.240Z,1773343047.240 [keepstation:SurfaceComms:setSink] Running Loop=1 2026-03-12T19:17:27.240Z,1773343047.240 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2026-03-12T19:17:27.240Z,1773343047.240 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2026-03-12T19:17:27.665Z,1773343047.665 [keepstation:SurfaceComms:setSink] Stopped 2026-03-12T19:17:27.665Z,1773343047.665 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2026-03-12T19:17:27.665Z,1773343047.665 [keepstation:SurfaceComms:setSink:A] Stopped 2026-03-12T19:17:27.665Z,1773343047.665 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2026-03-12T19:17:27.665Z,1773343047.665 [keepstation:SurfaceComms] Stopped 2026-03-12T19:17:27.665Z,1773343047.665 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2026-03-12T19:17:28.008Z,1773343048.008 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2026-03-12T19:17:31.642Z,1773343051.642 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2026-03-12T19:17:42.955Z,1773343062.955 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2026-03-12T19:17:44.285Z,1773343064.285 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-12T19:17:46.598Z,1773343066.598 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2026-03-12T19:17:56.701Z,1773343076.701 [NAL9602](INFO): Not Powering down - fast GPS 2026-03-12T19:17:57.912Z,1773343077.912 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2026-03-12T19:18:01.960Z,1773343081.960 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2026-03-12T19:18:13.318Z,1773343093.318 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2026-03-12T19:18:17.406Z,1773343097.406 [BPC1](INFO): Calculating totals. Valid battery stick count: 46. Valid reserve battery stick count: 5. 2026-03-12T19:18:17.409Z,1773343097.409 [BPC1](INFO): Received data from all battery sticks. 2026-03-12T19:18:19.424Z,1773343099.424 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-12T19:18:54.564Z,1773343134.564 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-12T19:19:09.369Z,1773343149.369 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2026-03-12T19:19:15.884Z,1773343155.884 [Radio_Surface](INFO): Powering down 2026-03-12T19:19:29.704Z,1773343169.704 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-12T19:19:30.708Z,1773343170.708 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2026-03-12T19:27:32.807Z,1773343652.807 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest 2026-03-12T19:29:09.449Z,1773343749.449 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2026-03-12T19:37:24.786Z,1773344244.786 [keepstation:SurfaceComms] Running Loop=1 2026-03-12T19:37:24.786Z,1773344244.786 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2026-03-12T19:37:24.786Z,1773344244.786 [keepstation:SurfaceComms:A] Running Loop=1 2026-03-12T19:37:24.787Z,1773344244.787 [keepstation:SurfaceComms:A] Stopped 2026-03-12T19:37:24.787Z,1773344244.787 [keepstation:SurfaceComms:B] Running Loop=1 2026-03-12T19:37:24.787Z,1773344244.787 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2026-03-12T19:37:25.176Z,1773344245.176 [keepstation:KeepStation:Dive] Stopped 2026-03-12T19:37:25.176Z,1773344245.176 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive 2026-03-12T19:37:25.177Z,1773344245.177 [keepstation:KeepStation:Dive:A] Stopped 2026-03-12T19:37:25.177Z,1773344245.177 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A 2026-03-12T19:37:25.177Z,1773344245.177 [keepstation:DiveCmd:StationKeep] Stopped 2026-03-12T19:37:25.177Z,1773344245.177 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2026-03-12T19:37:25.177Z,1773344245.177 [keepstation:DiveCmd:StationKeep:A] Stopped 2026-03-12T19:37:25.177Z,1773344245.177 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2026-03-12T19:37:25.177Z,1773344245.177 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2026-03-12T19:37:25.177Z,1773344245.177 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2026-03-12T19:37:25.179Z,1773344245.179 [keepstation:NeedComms] Running Loop=1 2026-03-12T19:37:25.179Z,1773344245.179 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2026-03-12T19:37:25.179Z,1773344245.179 [keepstation:NeedComms:C.GoToSurface] Running Loop=1 2026-03-12T19:37:25.179Z,1773344245.179 [keepstation:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2026-03-12T19:37:25.180Z,1773344245.180 [keepstation:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s. 2026-03-12T19:37:25.180Z,1773344245.180 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2026-03-12T19:37:25.180Z,1773344245.180 [keepstation:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2026-03-12T19:37:25.181Z,1773344245.181 [keepstation:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds. 2026-03-12T19:37:25.181Z,1773344245.181 [keepstation:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2026-03-12T19:37:25.181Z,1773344245.181 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2026-03-12T19:37:25.182Z,1773344245.182 [keepstation:NeedComms:A] Running Loop=1 2026-03-12T19:37:25.183Z,1773344245.183 [keepstation:NeedComms:A](INFO): last time_fix was: 1773343039.000000 second since 1970/01/01T00:00:00Z 2026-03-12T19:37:25.183Z,1773344245.183 [keepstation:NeedComms:A] Stopped 2026-03-12T19:37:25.183Z,1773344245.183 [keepstation:NeedComms:setActive] Running Loop=1 2026-03-12T19:37:25.183Z,1773344245.183 [keepstation:NeedComms:setActive](DEBUG): Aggregate::initialize keepstation:NeedComms:setActive 2026-03-12T19:37:25.184Z,1773344245.184 [keepstation:NeedComms:setActive:A] Running Loop=1 2026-03-12T19:37:25.555Z,1773344245.555 [keepstation:NeedComms:setActive:A] Stopped 2026-03-12T19:37:25.555Z,1773344245.555 [keepstation:NeedComms:setActive:B] Running Loop=1 2026-03-12T19:37:25.977Z,1773344245.977 [keepstation:NeedComms:setActive:B] Stopped 2026-03-12T19:37:25.977Z,1773344245.977 [keepstation:NeedComms:setActive](INFO): Completed keepstation:NeedComms:setActive 2026-03-12T19:37:25.978Z,1773344245.978 [keepstation:NeedComms:setActive] Stopped 2026-03-12T19:37:25.978Z,1773344245.978 [keepstation:NeedComms:setActive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setActive 2026-03-12T19:39:09.664Z,1773344349.664 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2026-03-12T19:39:18.161Z,1773344358.161 [keepstation:NeedComms:D] Running Loop=1 2026-03-12T19:39:18.560Z,1773344358.560 [Radio_Surface](INFO): Powering up 2026-03-12T19:39:18.728Z,1773344358.728 [Waterlinked](INFO): Powering down 2026-03-12T19:39:24.700Z,1773344364.700 [DataOverHttps](INFO): Radio surface powered ON. 2026-03-12T19:39:54.820Z,1773344394.820 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-12T19:40:29.980Z,1773344429.980 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-12T19:41:05.124Z,1773344465.124 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-12T19:41:40.278Z,1773344500.278 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-12T19:42:15.420Z,1773344535.420 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-12T19:42:50.560Z,1773344570.560 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-12T19:43:23.089Z,1773344603.089 [DataOverHttps](IMPORTANT): SBD MTMSN=20260312T194317 2026-03-12T19:43:23.093Z,1773344603.093 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003418 2026-03-12T19:43:30.435Z,1773344610.435 [DataOverHttps](INFO): Received command: stop;configSet DATMMP.loadAtStartup 0 bool persist;configSet DAT.loadAtStartup 1 bool persist;restart app 2026-03-12T19:43:30.909Z,1773344610.909 [CommandExec](IMPORTANT): got command stop 2026-03-12T19:43:30.910Z,1773344610.910 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop 2026-03-12T19:43:30.910Z,1773344610.910 [CommandExec](IMPORTANT): got command configSet DATMMP.loadAtStartup 0 bool persist 2026-03-12T19:43:30.910Z,1773344610.910 [CommandExec](IMPORTANT): configSet DATMMP.loadAtStartup requires a restart to take effect. 2026-03-12T19:43:30.914Z,1773344610.914 [CommandExec](IMPORTANT): got command configSet DAT.loadAtStartup 1 bool persist 2026-03-12T19:43:30.914Z,1773344610.914 [CommandExec](IMPORTANT): configSet DAT.loadAtStartup requires a restart to take effect. 2026-03-12T19:43:30.916Z,1773344610.916 [CommandExec](IMPORTANT): got command restart application 2026-03-12T19:43:31.112Z,1773344611.112 [MissionManager](INFO): MissionManager is completed. 2026-03-12T19:43:31.112Z,1773344611.112 [MissionManager](INFO): Uninitializing Mission keepstation 2026-03-12T19:43:31.112Z,1773344611.112 [keepstation] Stopped 2026-03-12T19:43:31.112Z,1773344611.112 [keepstation](DEBUG): Aggregate::uninitialize keepstation 2026-03-12T19:43:31.112Z,1773344611.112 [keepstation:NeedComms] Stopped 2026-03-12T19:43:31.112Z,1773344611.112 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2026-03-12T19:43:31.112Z,1773344611.112 [keepstation:NeedComms:C.GoToSurface] Stopped 2026-03-12T19:43:31.112Z,1773344611.112 [keepstation:NeedComms:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2026-03-12T19:43:31.112Z,1773344611.112 [keepstation:NeedComms:D] Stopped 2026-03-12T19:43:31.112Z,1773344611.112 [keepstation:StandardEnvelopes] Stopped 2026-03-12T19:43:31.112Z,1773344611.112 [keepstation:StandardEnvelopes](DEBUG): Aggregate::uninitialize keepstation:StandardEnvelopes 2026-03-12T19:43:31.113Z,1773344611.113 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2026-03-12T19:43:31.113Z,1773344611.113 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2026-03-12T19:43:31.113Z,1773344611.113 [keepstation:StandardEnvelopes:B.DepthEnvelope] Stopped 2026-03-12T19:43:31.113Z,1773344611.113 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2026-03-12T19:43:31.113Z,1773344611.113 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2026-03-12T19:43:31.113Z,1773344611.113 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2026-03-12T19:43:31.113Z,1773344611.113 [keepstation:BackseatDriver] Stopped 2026-03-12T19:43:31.113Z,1773344611.113 [keepstation:BackseatDriver](DEBUG): Aggregate::uninitialize keepstation:BackseatDriver 2026-03-12T19:43:31.113Z,1773344611.113 [keepstation:BackseatDriver:A.BackseatDriver] Stopped 2026-03-12T19:43:31.113Z,1773344611.113 [keepstation:PowerOnly] Stopped 2026-03-12T19:43:31.113Z,1773344611.113 [keepstation:PowerOnly](DEBUG): Aggregate::uninitialize keepstation:PowerOnly 2026-03-12T19:43:31.113Z,1773344611.113 [keepstation:PowerOnly:E.Wait] Stopped 2026-03-12T19:43:31.113Z,1773344611.113 [keepstation:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component. 2026-03-12T19:43:31.114Z,1773344611.114 [keepstation:H.Pitch] Stopped 2026-03-12T19:43:31.114Z,1773344611.114 [keepstation:SurfaceComms] Stopped 2026-03-12T19:43:31.114Z,1773344611.114 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2026-03-12T19:43:31.114Z,1773344611.114 [keepstation:SurfaceComms:B] Stopped 2026-03-12T19:43:31.114Z,1773344611.114 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2026-03-12T19:43:31.114Z,1773344611.114 [keepstation:DiveCmd] Stopped 2026-03-12T19:43:31.114Z,1773344611.114 [keepstation:DiveCmd](DEBUG): Aggregate::uninitialize keepstation:DiveCmd 2026-03-12T19:43:31.114Z,1773344611.114 [keepstation:KeepStation] Stopped 2026-03-12T19:43:31.114Z,1773344611.114 [keepstation:KeepStation](DEBUG): Aggregate::uninitialize keepstation:KeepStation 2026-03-12T19:43:31.114Z,1773344611.114 [keepstation:KeepStation:B.Wait] Stopped 2026-03-12T19:43:31.114Z,1773344611.114 [keepstation:KeepStation:B.Wait](DEBUG): Uninitialize Wait Component. 2026-03-12T19:43:31.508Z,1773344611.508 [MissionManager](IMPORTANT): Started mission Default 2026-03-12T19:43:31.508Z,1773344611.508 [Default] Running Loop=1 2026-03-12T19:43:31.508Z,1773344611.508 [Default](DEBUG): Aggregate::initialize Default 2026-03-12T19:43:31.508Z,1773344611.508 [Default:B.GoToSurface] Running Loop=1 2026-03-12T19:43:31.508Z,1773344611.508 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2026-03-12T19:43:31.509Z,1773344611.509 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2026-03-12T19:43:31.509Z,1773344611.509 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2026-03-12T19:43:31.509Z,1773344611.509 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2026-03-12T19:43:31.510Z,1773344611.510 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2026-03-12T19:43:31.510Z,1773344611.510 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2026-03-12T19:43:31.510Z,1773344611.510 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2026-03-12T19:43:31.511Z,1773344611.511 [Default:A.Wait] Running Loop=1 2026-03-12T19:43:31.511Z,1773344611.511 [Default:A.Wait](DEBUG): Initialize Wait Component. 2026-03-12T19:43:31.920Z,1773344611.920 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2026-03-12T19:43:31.920Z,1773344611.920 [CommandExec](INFO): Uninitializing the command executive. 2026-03-12T19:43:31.920Z,1773344611.920 [CommandExec](INFO): Uninitializing the command scheduler. 2026-03-12T19:43:31.920Z,1773344611.920 [CommandExec ThreadHandler](INFO): Thread cancelled. 2026-03-12T19:43:31.999Z,1773344611.999 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2026-03-12T19:43:31.000Z,1773344612.000 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2026-03-12T19:43:31.000Z,1773344612.000 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2026-03-12T19:43:32.000Z,1773344612.000 [NavChartDb](INFO): Join timeout helper Thread ID is 1628 2026-03-12T19:43:32.400Z,1773344612.400 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2026-03-12T19:43:32.400Z,1773344612.400 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2026-03-12T19:43:32.404Z,1773344612.404 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2026-03-12T19:43:32.404Z,1773344612.404 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2026-03-12T19:43:32.404Z,1773344612.404 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1629 2026-03-12T19:43:32.696Z,1773344612.696 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2026-03-12T19:43:32.696Z,1773344612.696 [WetLabsBB2FL](INFO): Powering down 2026-03-12T19:43:32.697Z,1773344612.697 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2026-03-12T19:43:32.704Z,1773344612.704 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2026-03-12T19:43:32.704Z,1773344612.704 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2026-03-12T19:43:32.704Z,1773344612.704 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1630 2026-03-12T19:43:33.236Z,1773344613.236 [CTD_Seabird](INFO): Powering down 2026-03-12T19:43:33.248Z,1773344613.248 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2026-03-12T19:43:33.248Z,1773344613.248 [CTD_Seabird](INFO): Powering down 2026-03-12T19:43:33.259Z,1773344613.259 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2026-03-12T19:43:33.267Z,1773344613.267 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2026-03-12T19:43:33.268Z,1773344613.268 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2026-03-12T19:43:33.268Z,1773344613.268 [Radio_Surface](INFO): Join timeout helper Thread ID is 1631 2026-03-12T19:43:33.484Z,1773344613.484 [Radio_Surface](INFO): Powering down 2026-03-12T19:43:33.484Z,1773344613.484 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2026-03-12T19:43:33.485Z,1773344613.485 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2026-03-12T19:43:33.491Z,1773344613.491 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2026-03-12T19:43:33.492Z,1773344613.492 [Onboard ThreadHandler](INFO): Thread cancelled. 2026-03-12T19:43:33.492Z,1773344613.492 [Onboard](INFO): Join timeout helper Thread ID is 1632 2026-03-12T19:43:34.448Z,1773344614.448 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2026-03-12T19:43:38.316Z,1773344618.316 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2026-03-12T19:43:38.316Z,1773344618.316 [Onboard ThreadHandler](INFO): Thread cancelled. 2026-03-12T19:43:38.324Z,1773344618.324 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2026-03-12T19:43:38.324Z,1773344618.324 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2026-03-12T19:43:38.325Z,1773344618.325 [DataOverHttps](INFO): Join timeout helper Thread ID is 1633 2026-03-12T19:43:38.464Z,1773344618.464 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2026-03-12T19:43:38.464Z,1773344618.464 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2026-03-12T19:43:38.480Z,1773344618.480 [ComponentRegistry](INFO): Shutting down DATMMP ThreadHandler 2026-03-12T19:43:38.480Z,1773344618.480 [DATMMP ThreadHandler](INFO): Thread cancelled. 2026-03-12T19:43:38.481Z,1773344618.481 [DATMMP](INFO): Join timeout helper Thread ID is 1634 2026-03-12T19:43:38.588Z,1773344618.588 [DATMMP](INFO): Stop 2026-03-12T19:43:38.588Z,1773344618.588 [DATMMP](INFO): uninitialize 2026-03-12T19:43:38.588Z,1773344618.588 [DATMMP](INFO): Powering down 2026-03-12T19:43:38.660Z,1773344618.660 [DATMMP ThreadHandler](INFO): Uninitializing protected caller thread. 2026-03-12T19:43:38.660Z,1773344618.660 [DATMMP](INFO): uninitialize 2026-03-12T19:43:38.660Z,1773344618.660 [DATMMP](INFO): Powering down 2026-03-12T19:43:38.661Z,1773344618.661 [DATMMP ThreadHandler](INFO): Thread cancelled. 2026-03-12T19:43:38.664Z,1773344618.664 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2026-03-12T19:43:38.664Z,1773344618.664 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2026-03-12T19:43:38.665Z,1773344618.665 [BackseatComponent](INFO): Join timeout helper Thread ID is 1635 2026-03-12T19:43:38.848Z,1773344618.848 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2026-03-12T19:43:38.848Z,1773344618.848 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2026-03-12T19:43:38.868Z,1773344618.868 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2026-03-12T19:43:38.869Z,1773344618.869 [logger ThreadHandler](INFO): Thread cancelled. 2026-03-12T19:43:38.869Z,1773344618.869 [logger](INFO): Join timeout helper Thread ID is 1636 2026-03-12T19:43:38.886Z,1773344618.886 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2026-03-12T19:43:38.886Z,1773344618.886 [logger ThreadHandler](INFO): Thread cancelled. 2026-03-12T19:43:38.900Z,1773344618.900 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2026-03-12T19:43:38.900Z,1773344618.900 [CommandLine ThreadHandler](INFO): Thread cancelled. 2026-03-12T19:43:38.901Z,1773344618.901 [CommandLine](INFO): Join timeout helper Thread ID is 1637 2026-03-12T19:43:38.969Z,1773344618.969 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2026-03-12T19:43:38.970Z,1773344618.970 [CommandLine ThreadHandler](INFO): Thread cancelled. 2026-03-12T19:43:38.980Z,1773344618.980 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2026-03-12T19:43:38.980Z,1773344618.980 [CommandExec ThreadHandler](INFO): Thread cancelled. 2026-03-12T19:43:38.981Z,1773344618.981 [CommandExec](INFO): Join timeout helper Thread ID is 1638 2026-03-12T19:43:38.982Z,1773344618.982 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2026-03-12T19:43:38.982Z,1773344618.982 [controlThread ThreadHandler](INFO): Thread cancelled. 2026-03-12T19:43:38.982Z,1773344618.982 [controlThread](INFO): Join timeout helper Thread ID is 1639 2026-03-12T19:43:39.152Z,1773344619.152 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2026-03-12T19:43:39.152Z,1773344619.152 [controlThread](DEBUG): Uninitializing ControlThread 2026-03-12T19:43:39.152Z,1773344619.152 [AHRS_M2](INFO): Powering down 2026-03-12T19:43:39.232Z,1773344619.232 [NAL9602](INFO): Powering down 2026-03-12T19:43:39.233Z,1773344619.233 [Sonardyne_Nano](INFO): Powering down 2026-03-12T19:43:39.352Z,1773344619.352 [Waterlinked](INFO): Powering down 2026-03-12T19:43:39.436Z,1773344619.436 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2026-03-12T19:43:39.437Z,1773344619.437 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2026-03-12T19:43:39.437Z,1773344619.437 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2026-03-12T19:43:39.438Z,1773344619.438 [MissionManager](INFO): Uninitializing Mission Default 2026-03-12T19:43:39.438Z,1773344619.438 [Default] Stopped 2026-03-12T19:43:39.438Z,1773344619.438 [Default](DEBUG): Aggregate::uninitialize Default 2026-03-12T19:43:39.438Z,1773344619.438 [Default:A.Wait] Stopped 2026-03-12T19:43:39.438Z,1773344619.438 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2026-03-12T19:43:39.438Z,1773344619.438 [Default:B.GoToSurface] Stopped 2026-03-12T19:43:39.438Z,1773344619.438 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2026-03-12T19:43:39.441Z,1773344619.441 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2026-03-12T19:43:39.441Z,1773344619.441 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2026-03-12T19:43:39.442Z,1773344619.442 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2026-03-12T19:43:39.442Z,1773344619.442 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2026-03-12T19:43:39.442Z,1773344619.442 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2026-03-12T19:43:39.442Z,1773344619.442 [BuoyancyServo](INFO): Powering down 2026-03-12T19:43:39.456Z,1773344619.456 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2026-03-12T19:43:39.456Z,1773344619.456 [ElevatorServo](INFO): Powering down 2026-03-12T19:43:39.457Z,1773344619.457 [MassServo](DEBUG): Uninitialize Mass Servo. 2026-03-12T19:43:39.457Z,1773344619.457 [MassServo](INFO): Powering down 2026-03-12T19:43:39.457Z,1773344619.457 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2026-03-12T19:43:39.458Z,1773344619.458 [RudderServo](INFO): Powering down 2026-03-12T19:43:39.458Z,1773344619.458 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2026-03-12T19:43:39.458Z,1773344619.458 [ThrusterHE](INFO): Powering down 2026-03-12T19:43:39.459Z,1773344619.459 [SBIT](DEBUG): Uninitialize SBIT Component. 2026-03-12T19:43:39.460Z,1773344619.460 [IBIT](DEBUG): Uninitialize IBIT Component. 2026-03-12T19:43:39.460Z,1773344619.460 [CBIT](DEBUG): Uninitialize CBIT Component. 2026-03-12T19:43:39.460Z,1773344619.460 [CBIT](DEBUG): Powering off loads. 2026-03-12T19:43:39.471Z,1773344619.471 [CBIT](DEBUG): Disabling WDT. 2026-03-12T19:43:39.484Z,1773344619.484 [GFScanner](DEBUG): Uninitialize GFScanner component. 2026-03-12T19:43:39.484Z,1773344619.484 [GFScanner](DEBUG): Opening all GF detection circuits. 2026-03-12T19:43:39.485Z,1773344619.485 [controlThread ThreadHandler](INFO): Thread cancelled. 2026-03-12T19:43:39.544Z,1773344619.544 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2026-03-12T19:43:39.547Z,1773344619.547 [Onboard ThreadHandler](INFO): Thread cancelled. 2026-03-12T19:43:39.553Z,1773344619.553 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2026-03-12T19:43:39.559Z,1773344619.559 [DATMMP ThreadHandler](INFO): Thread cancelled. 2026-03-12T19:43:39.599Z,1773344619.599 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2026-03-12T19:43:39.642Z,1773344619.642 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2026-03-12T19:43:39.647Z,1773344619.647 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2026-03-12T19:43:39.666Z,1773344619.666 [GFScanner](DEBUG): Uninitialize GFScanner component. 2026-03-12T19:43:39.667Z,1773344619.667 [GFScanner](DEBUG): Opening all GF detection circuits. 2026-03-12T19:43:39.682Z,1773344619.682 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2026-03-12T19:43:39.732Z,1773344619.732 [logger ThreadHandler](INFO): Thread cancelled.