2026-03-12T16:28:50.286Z,1773332930.286 [CommandExec](IMPORTANT): got command schedule resume
2026-03-12T16:28:50.287Z,1773332930.287 [CommandExec](IMPORTANT): got command restart logs
2026-03-12T16:29:49.460Z,1773332989.460 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T16:29:51.468Z,1773332991.468 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.263325
2026-03-12T16:30:24.601Z,1773333024.601 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T16:30:37.144Z,1773333037.144 [Radio_Surface](INFO): Powering down
2026-03-12T16:30:59.740Z,1773333059.740 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T16:31:00.744Z,1773333060.744 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2026-03-12T16:37:26.158Z,1773333446.158 [keepstation:SurfaceComms] Running Loop=1
2026-03-12T16:37:26.158Z,1773333446.158 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2026-03-12T16:37:26.158Z,1773333446.158 [keepstation:SurfaceComms:A] Running Loop=1
2026-03-12T16:37:26.158Z,1773333446.158 [keepstation:SurfaceComms:A] Stopped
2026-03-12T16:37:26.158Z,1773333446.158 [keepstation:SurfaceComms:B] Running Loop=1
2026-03-12T16:37:26.159Z,1773333446.159 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2026-03-12T16:37:26.160Z,1773333446.160 [keepstation:NeedComms] Running Loop=1
2026-03-12T16:37:26.161Z,1773333446.161 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2026-03-12T16:37:26.161Z,1773333446.161 [keepstation:NeedComms:C.GoToSurface] Running Loop=1
2026-03-12T16:37:26.161Z,1773333446.161 [keepstation:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2026-03-12T16:37:26.161Z,1773333446.161 [keepstation:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s.
2026-03-12T16:37:26.161Z,1773333446.161 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2026-03-12T16:37:26.162Z,1773333446.162 [keepstation:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2026-03-12T16:37:26.162Z,1773333446.162 [keepstation:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds.
2026-03-12T16:37:26.162Z,1773333446.162 [keepstation:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2026-03-12T16:37:26.163Z,1773333446.163 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2026-03-12T16:37:26.163Z,1773333446.163 [keepstation:NeedComms:A] Running Loop=1
2026-03-12T16:37:26.165Z,1773333446.165 [keepstation:NeedComms:A](INFO): last time_fix was:  1773332840.000000 second since 1970/01/01T00:00:00Z 
2026-03-12T16:37:26.166Z,1773333446.166 [keepstation:NeedComms:A] Stopped
2026-03-12T16:37:26.166Z,1773333446.166 [keepstation:NeedComms:setActive] Running Loop=1
2026-03-12T16:37:26.166Z,1773333446.166 [keepstation:NeedComms:setActive](DEBUG): Aggregate::initialize keepstation:NeedComms:setActive
2026-03-12T16:37:26.166Z,1773333446.166 [keepstation:NeedComms:setActive:A] Running Loop=1
2026-03-12T16:37:26.743Z,1773333446.743 [keepstation:KeepStation:Dive] Stopped
2026-03-12T16:37:26.744Z,1773333446.744 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2026-03-12T16:37:26.744Z,1773333446.744 [keepstation:KeepStation:Dive:A] Stopped
2026-03-12T16:37:26.744Z,1773333446.744 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2026-03-12T16:37:26.745Z,1773333446.745 [keepstation:DiveCmd:StationKeep] Stopped
2026-03-12T16:37:26.745Z,1773333446.745 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2026-03-12T16:37:26.746Z,1773333446.746 [keepstation:DiveCmd:StationKeep:A] Stopped
2026-03-12T16:37:26.746Z,1773333446.746 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2026-03-12T16:37:26.746Z,1773333446.746 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2026-03-12T16:37:26.746Z,1773333446.746 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2026-03-12T16:37:26.755Z,1773333446.755 [keepstation:NeedComms:setActive:A] Stopped
2026-03-12T16:37:26.755Z,1773333446.755 [keepstation:NeedComms:setActive:B] Running Loop=1
2026-03-12T16:37:26.989Z,1773333446.989 [keepstation:NeedComms:setActive:B] Stopped
2026-03-12T16:37:26.989Z,1773333446.989 [keepstation:NeedComms:setActive](INFO): Completed keepstation:NeedComms:setActive
2026-03-12T16:37:26.989Z,1773333446.989 [keepstation:NeedComms:setActive] Stopped
2026-03-12T16:37:26.989Z,1773333446.989 [keepstation:NeedComms:setActive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setActive
2026-03-12T16:39:02.812Z,1773333542.812 [keepstation:NeedComms:D] Running Loop=1
2026-03-12T16:39:03.288Z,1773333543.288 [Waterlinked](INFO): Powering down
2026-03-12T16:39:03.412Z,1773333543.412 [Radio_Surface](INFO): Powering up
2026-03-12T16:39:08.736Z,1773333548.736 [DataOverHttps](INFO): Radio surface powered ON.
2026-03-12T16:39:38.860Z,1773333578.860 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T16:40:14.012Z,1773333614.012 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T16:40:24.854Z,1773333624.854 [NAL9602](INFO): SBD MO Status=1, MOMSN=13183, MT Status=0, MTMSN=0
2026-03-12T16:40:24.854Z,1773333624.854 [NAL9602](INFO): No messages in MT queue
2026-03-12T16:40:26.066Z,1773333626.066 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164020.00,A,3647.88133,N,12150.80565,W,0.700,297.40,120326,,,D*7D
2026-03-12T16:40:26.068Z,1773333626.068 [NAL9602](INFO): GPS fix at 20260312T164020: (36.798022, -121.846761)
2026-03-12T16:40:26.082Z,1773333626.082 [keepstation:NeedComms:D] Stopped
2026-03-12T16:40:26.082Z,1773333626.082 [keepstation:NeedComms:E] Running Loop=1
2026-03-12T16:40:40.702Z,1773333640.702 [NAL9602](INFO): SBD MO Status=1, MOMSN=13184, MT Status=0, MTMSN=0
2026-03-12T16:40:40.752Z,1773333640.752 [NAL9602](INFO): Sent 74 bytes from file Logs/20260312T154850/Courier0019.lzma
2026-03-12T16:40:40.752Z,1773333640.752 [NAL9602](INFO): Packets left to send: 0
2026-03-12T16:40:49.180Z,1773333649.180 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T16:40:53.712Z,1773333653.712 [NAL9602](INFO): SBD MO Status=1, MOMSN=13185, MT Status=0, MTMSN=0
2026-03-12T16:40:53.768Z,1773333653.768 [NAL9602](INFO): Sent 270 bytes from file Logs/20260312T162850/Courier0000.lzma
2026-03-12T16:40:53.768Z,1773333653.768 [NAL9602](INFO): Packets left to send: 0
2026-03-12T16:41:10.454Z,1773333670.454 [NAL9602](INFO): SBD MO Status=1, MOMSN=13186, MT Status=0, MTMSN=0
2026-03-12T16:41:10.516Z,1773333670.516 [NAL9602](INFO): Sent 129 bytes from file Logs/20260312T154850/Express0020.lzma
2026-03-12T16:41:10.516Z,1773333670.516 [NAL9602](INFO): Packets left to send: 0
2026-03-12T16:41:24.320Z,1773333684.320 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T16:41:24.670Z,1773333684.670 [NAL9602](INFO): SBD MO Status=1, MOMSN=13187, MT Status=0, MTMSN=0
2026-03-12T16:41:24.728Z,1773333684.728 [NAL9602](INFO): Sent 332 bytes from file Logs/20260312T162850/Express0001.lzma
2026-03-12T16:41:24.728Z,1773333684.728 [NAL9602](INFO): Packets left to send: 1
2026-03-12T16:41:48.169Z,1773333708.169 [NAL9602](INFO): SBD MO Status=2, MOMSN=13188, MT Status=2, MTMSN=0
2026-03-12T16:41:48.170Z,1773333708.170 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-12T16:41:59.460Z,1773333719.460 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T16:42:08.450Z,1773333728.450 [NAL9602](INFO): SBD MO Status=1, MOMSN=13188, MT Status=0, MTMSN=0
2026-03-12T16:42:08.500Z,1773333728.500 [NAL9602](INFO): Sent 177 bytes from file Logs/20260312T162850/Express0001.lzma
2026-03-12T16:42:08.500Z,1773333728.500 [NAL9602](INFO): Packets left to send: 0
2026-03-12T16:42:19.367Z,1773333739.367 [NAL9602](INFO): SBD MO Status=0, MOMSN=13189, MT Status=0, MTMSN=0
2026-03-12T16:42:19.450Z,1773333739.450 [keepstation:NeedComms:E] Stopped
2026-03-12T16:42:19.451Z,1773333739.451 [keepstation:NeedComms:F] Running Loop=1
2026-03-12T16:42:21.783Z,1773333741.783 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164216.00,A,3647.89207,N,12150.81798,W,0.836,297.46,120326,,,D*72
2026-03-12T16:42:21.785Z,1773333741.785 [NAL9602](INFO): GPS fix at 20260312T164216: (36.798201, -121.846966)
2026-03-12T16:42:21.798Z,1773333741.798 [keepstation:NeedComms:F] Stopped
2026-03-12T16:42:21.798Z,1773333741.798 [keepstation:NeedComms:setRestoreMode] Running Loop=1
2026-03-12T16:42:21.798Z,1773333741.798 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::initialize keepstation:NeedComms:setRestoreMode
2026-03-12T16:42:21.798Z,1773333741.798 [keepstation:NeedComms:setRestoreMode:A] Running Loop=1
2026-03-12T16:42:22.222Z,1773333742.222 [keepstation:NeedComms:setRestoreMode:A] Stopped
2026-03-12T16:42:22.222Z,1773333742.222 [keepstation:NeedComms:setRestoreMode](INFO): Completed keepstation:NeedComms:setRestoreMode
2026-03-12T16:42:22.222Z,1773333742.222 [keepstation:NeedComms:setRestoreMode] Stopped
2026-03-12T16:42:22.222Z,1773333742.222 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setRestoreMode
2026-03-12T16:42:22.222Z,1773333742.222 [keepstation:NeedComms:setInactive] Running Loop=1
2026-03-12T16:42:22.222Z,1773333742.222 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::initialize keepstation:NeedComms:setInactive
2026-03-12T16:42:22.222Z,1773333742.222 [keepstation:NeedComms:setInactive:A] Running Loop=1
2026-03-12T16:42:22.612Z,1773333742.612 [keepstation:NeedComms:setInactive:A] Stopped
2026-03-12T16:42:22.612Z,1773333742.612 [keepstation:NeedComms:setInactive](INFO): Completed keepstation:NeedComms:setInactive
2026-03-12T16:42:22.613Z,1773333742.613 [keepstation:NeedComms:setInactive] Stopped
2026-03-12T16:42:22.613Z,1773333742.613 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setInactive
2026-03-12T16:42:22.613Z,1773333742.613 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2026-03-12T16:42:22.613Z,1773333742.613 [keepstation:NeedComms] Stopped
2026-03-12T16:42:22.614Z,1773333742.614 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2026-03-12T16:42:22.614Z,1773333742.614 [keepstation:NeedComms:C.GoToSurface] Stopped
2026-03-12T16:42:22.614Z,1773333742.614 [keepstation:NeedComms:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2026-03-12T16:42:23.035Z,1773333743.035 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2026-03-12T16:42:23.035Z,1773333743.035 [keepstation:SurfaceComms:B] Stopped
2026-03-12T16:42:23.035Z,1773333743.035 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2026-03-12T16:42:23.036Z,1773333743.036 [keepstation:SurfaceComms:HandleNan] Running Loop=1
2026-03-12T16:42:23.036Z,1773333743.036 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan
2026-03-12T16:42:23.036Z,1773333743.036 [keepstation:SurfaceComms:HandleNan:SetOnStation] Running Loop=1
2026-03-12T16:42:23.036Z,1773333743.036 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetOnStation
2026-03-12T16:42:23.036Z,1773333743.036 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Running Loop=1
2026-03-12T16:42:23.410Z,1773333743.410 [keepstation:SurfaceComms:HandleNan] Stopped
2026-03-12T16:42:23.410Z,1773333743.410 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan
2026-03-12T16:42:23.410Z,1773333743.410 [keepstation:SurfaceComms:HandleNan:SetOnStation] Stopped
2026-03-12T16:42:23.410Z,1773333743.410 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetOnStation
2026-03-12T16:42:23.410Z,1773333743.410 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Stopped
2026-03-12T16:42:23.410Z,1773333743.410 [keepstation:SurfaceComms:setTransit] Running Loop=1
2026-03-12T16:42:23.410Z,1773333743.410 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2026-03-12T16:42:23.410Z,1773333743.410 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2026-03-12T16:42:23.817Z,1773333743.817 [keepstation:SurfaceComms:setTransit] Stopped
2026-03-12T16:42:23.817Z,1773333743.817 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2026-03-12T16:42:23.818Z,1773333743.818 [keepstation:SurfaceComms:setTransit:A] Stopped
2026-03-12T16:42:23.818Z,1773333743.818 [keepstation:SurfaceComms:setStation] Running Loop=1
2026-03-12T16:42:23.818Z,1773333743.818 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2026-03-12T16:42:23.818Z,1773333743.818 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2026-03-12T16:42:24.256Z,1773333744.256 [keepstation:SurfaceComms:setStation:A] Stopped
2026-03-12T16:42:24.256Z,1773333744.256 [keepstation:SurfaceComms:setStation](INFO): Completed keepstation:SurfaceComms:setStation
2026-03-12T16:42:24.256Z,1773333744.256 [keepstation:SurfaceComms:setStation] Stopped
2026-03-12T16:42:24.256Z,1773333744.256 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2026-03-12T16:42:24.256Z,1773333744.256 [keepstation:SurfaceComms:setSink] Running Loop=1
2026-03-12T16:42:24.256Z,1773333744.256 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2026-03-12T16:42:24.257Z,1773333744.257 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2026-03-12T16:42:24.704Z,1773333744.704 [keepstation:KeepStation:Dive] Running Loop=1
2026-03-12T16:42:24.705Z,1773333744.705 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2026-03-12T16:42:24.705Z,1773333744.705 [keepstation:KeepStation:Dive:A] Running Loop=1
2026-03-12T16:42:24.705Z,1773333744.705 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2026-03-12T16:42:24.705Z,1773333744.705 [keepstation:DiveCmd:StationKeep] Running Loop=1
2026-03-12T16:42:24.705Z,1773333744.705 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2026-03-12T16:42:24.705Z,1773333744.705 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2026-03-12T16:42:24.705Z,1773333744.705 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2026-03-12T16:42:24.705Z,1773333744.705 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2026-03-12T16:42:24.706Z,1773333744.706 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2026-03-12T16:42:24.706Z,1773333744.706 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2026-03-12T16:42:24.707Z,1773333744.707 [keepstation:SurfaceComms:setSink] Stopped
2026-03-12T16:42:24.708Z,1773333744.708 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2026-03-12T16:42:24.708Z,1773333744.708 [keepstation:SurfaceComms:setSink:A] Stopped
2026-03-12T16:42:24.708Z,1773333744.708 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2026-03-12T16:42:24.708Z,1773333744.708 [keepstation:SurfaceComms] Stopped
2026-03-12T16:42:24.708Z,1773333744.708 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2026-03-12T16:42:32.024Z,1773333752.024 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002748
2026-03-12T16:42:53.701Z,1773333773.701 [NAL9602](INFO): Not Powering down - fast GPS
2026-03-12T16:44:21.240Z,1773333861.240 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T16:44:23.249Z,1773333863.249 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.252068
2026-03-12T16:44:56.380Z,1773333896.380 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T16:45:15.498Z,1773333915.498 [Radio_Surface](INFO): Powering down
2026-03-12T16:45:31.521Z,1773333931.521 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T16:45:32.525Z,1773333932.525 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2026-03-12T16:52:22.165Z,1773334342.165 [keepstation:SurfaceComms] Running Loop=1
2026-03-12T16:52:22.165Z,1773334342.165 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2026-03-12T16:52:22.165Z,1773334342.165 [keepstation:SurfaceComms:A] Running Loop=1
2026-03-12T16:52:22.166Z,1773334342.166 [keepstation:SurfaceComms:A] Stopped
2026-03-12T16:52:22.166Z,1773334342.166 [keepstation:SurfaceComms:B] Running Loop=1
2026-03-12T16:52:22.166Z,1773334342.166 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2026-03-12T16:52:22.168Z,1773334342.168 [keepstation:NeedComms] Running Loop=1
2026-03-12T16:52:22.168Z,1773334342.168 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2026-03-12T16:52:22.168Z,1773334342.168 [keepstation:NeedComms:C.GoToSurface] Running Loop=1
2026-03-12T16:52:22.168Z,1773334342.168 [keepstation:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2026-03-12T16:52:22.168Z,1773334342.168 [keepstation:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s.
2026-03-12T16:52:22.169Z,1773334342.169 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2026-03-12T16:52:22.169Z,1773334342.169 [keepstation:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2026-03-12T16:52:22.169Z,1773334342.169 [keepstation:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds.
2026-03-12T16:52:22.170Z,1773334342.170 [keepstation:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2026-03-12T16:52:22.170Z,1773334342.170 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2026-03-12T16:52:22.170Z,1773334342.170 [keepstation:NeedComms:A] Running Loop=1
2026-03-12T16:52:22.172Z,1773334342.172 [keepstation:NeedComms:A](INFO): last time_fix was:  1773333736.000000 second since 1970/01/01T00:00:00Z 
2026-03-12T16:52:22.172Z,1773334342.172 [keepstation:NeedComms:A] Stopped
2026-03-12T16:52:22.172Z,1773334342.172 [keepstation:NeedComms:setActive] Running Loop=1
2026-03-12T16:52:22.172Z,1773334342.172 [keepstation:NeedComms:setActive](DEBUG): Aggregate::initialize keepstation:NeedComms:setActive
2026-03-12T16:52:22.172Z,1773334342.172 [keepstation:NeedComms:setActive:A] Running Loop=1
2026-03-12T16:52:22.700Z,1773334342.700 [keepstation:KeepStation:Dive] Stopped
2026-03-12T16:52:22.700Z,1773334342.700 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2026-03-12T16:52:22.700Z,1773334342.700 [keepstation:KeepStation:Dive:A] Stopped
2026-03-12T16:52:22.700Z,1773334342.700 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2026-03-12T16:52:22.702Z,1773334342.702 [keepstation:DiveCmd:StationKeep] Stopped
2026-03-12T16:52:22.702Z,1773334342.702 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2026-03-12T16:52:22.702Z,1773334342.702 [keepstation:DiveCmd:StationKeep:A] Stopped
2026-03-12T16:52:22.702Z,1773334342.702 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2026-03-12T16:52:22.702Z,1773334342.702 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2026-03-12T16:52:22.703Z,1773334342.703 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2026-03-12T16:52:22.719Z,1773334342.719 [keepstation:NeedComms:setActive:A] Stopped
2026-03-12T16:52:22.719Z,1773334342.719 [keepstation:NeedComms:setActive:B] Running Loop=1
2026-03-12T16:52:22.974Z,1773334342.974 [keepstation:NeedComms:setActive:B] Stopped
2026-03-12T16:52:22.974Z,1773334342.974 [keepstation:NeedComms:setActive](INFO): Completed keepstation:NeedComms:setActive
2026-03-12T16:52:22.974Z,1773334342.974 [keepstation:NeedComms:setActive] Stopped
2026-03-12T16:52:22.974Z,1773334342.974 [keepstation:NeedComms:setActive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setActive
2026-03-12T16:53:55.474Z,1773334435.474 [keepstation:NeedComms:D] Running Loop=1
2026-03-12T16:53:55.884Z,1773334435.884 [Radio_Surface](INFO): Powering up
2026-03-12T16:53:55.896Z,1773334435.896 [Waterlinked](INFO): Powering down
2026-03-12T16:54:01.654Z,1773334441.654 [DataOverHttps](INFO): Radio surface powered ON.
2026-03-12T16:54:31.772Z,1773334471.772 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T16:54:46.976Z,1773334486.976 [Waterlinked](INFO): Powering down
2026-03-12T16:54:57.768Z,1773334497.768 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=13190, MT Status=1, MTMSN=1630
2026-03-12T16:54:57.768Z,1773334497.768 [NAL9602](INFO): Data available in MT queue
2026-03-12T16:54:58.248Z,1773334498.248 [NAL9602](INFO): Received command: stop
2026-03-12T16:54:58.284Z,1773334498.284 [CommandExec](IMPORTANT): got command stop
2026-03-12T16:54:58.285Z,1773334498.285 [CommandExec](IMPORTANT): Scheduling is paused
2026-03-12T16:54:58.285Z,1773334498.285 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop
2026-03-12T16:54:58.578Z,1773334498.578 [MissionManager](INFO): MissionManager is completed.
2026-03-12T16:54:58.578Z,1773334498.578 [MissionManager](INFO): Uninitializing Mission keepstation
2026-03-12T16:54:58.578Z,1773334498.578 [keepstation] Stopped
2026-03-12T16:54:58.578Z,1773334498.578 [keepstation](DEBUG): Aggregate::uninitialize keepstation
2026-03-12T16:54:58.578Z,1773334498.578 [keepstation:NeedComms] Stopped
2026-03-12T16:54:58.578Z,1773334498.578 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2026-03-12T16:54:58.578Z,1773334498.578 [keepstation:NeedComms:C.GoToSurface] Stopped
2026-03-12T16:54:58.578Z,1773334498.578 [keepstation:NeedComms:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2026-03-12T16:54:58.578Z,1773334498.578 [keepstation:NeedComms:D] Stopped
2026-03-12T16:54:58.578Z,1773334498.578 [keepstation:StandardEnvelopes] Stopped
2026-03-12T16:54:58.579Z,1773334498.579 [keepstation:StandardEnvelopes](DEBUG): Aggregate::uninitialize keepstation:StandardEnvelopes
2026-03-12T16:54:58.579Z,1773334498.579 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2026-03-12T16:54:58.579Z,1773334498.579 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2026-03-12T16:54:58.579Z,1773334498.579 [keepstation:StandardEnvelopes:B.DepthEnvelope] Stopped
2026-03-12T16:54:58.579Z,1773334498.579 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2026-03-12T16:54:58.579Z,1773334498.579 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2026-03-12T16:54:58.579Z,1773334498.579 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2026-03-12T16:54:58.579Z,1773334498.579 [keepstation:BackseatDriver] Stopped
2026-03-12T16:54:58.579Z,1773334498.579 [keepstation:BackseatDriver](DEBUG): Aggregate::uninitialize keepstation:BackseatDriver
2026-03-12T16:54:58.579Z,1773334498.579 [keepstation:BackseatDriver:A.BackseatDriver] Stopped
2026-03-12T16:54:58.580Z,1773334498.580 [keepstation:PowerOnly] Stopped
2026-03-12T16:54:58.580Z,1773334498.580 [keepstation:PowerOnly](DEBUG): Aggregate::uninitialize keepstation:PowerOnly
2026-03-12T16:54:58.580Z,1773334498.580 [keepstation:PowerOnly:E.Wait] Stopped
2026-03-12T16:54:58.580Z,1773334498.580 [keepstation:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component.
2026-03-12T16:54:58.580Z,1773334498.580 [keepstation:H.Pitch] Stopped
2026-03-12T16:54:58.580Z,1773334498.580 [keepstation:SurfaceComms] Stopped
2026-03-12T16:54:58.580Z,1773334498.580 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2026-03-12T16:54:58.580Z,1773334498.580 [keepstation:SurfaceComms:B] Stopped
2026-03-12T16:54:58.580Z,1773334498.580 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2026-03-12T16:54:58.580Z,1773334498.580 [keepstation:DiveCmd] Stopped
2026-03-12T16:54:58.580Z,1773334498.580 [keepstation:DiveCmd](DEBUG): Aggregate::uninitialize keepstation:DiveCmd
2026-03-12T16:54:58.581Z,1773334498.581 [keepstation:KeepStation] Stopped
2026-03-12T16:54:58.581Z,1773334498.581 [keepstation:KeepStation](DEBUG): Aggregate::uninitialize keepstation:KeepStation
2026-03-12T16:54:58.581Z,1773334498.581 [keepstation:KeepStation:B.Wait] Stopped
2026-03-12T16:54:58.581Z,1773334498.581 [keepstation:KeepStation:B.Wait](DEBUG): Uninitialize Wait Component.
2026-03-12T16:54:59.003Z,1773334499.003 [MissionManager](IMPORTANT): Started mission Default
2026-03-12T16:54:59.005Z,1773334499.005 [Default] Running Loop=1
2026-03-12T16:54:59.005Z,1773334499.005 [Default](DEBUG): Aggregate::initialize Default
2026-03-12T16:54:59.005Z,1773334499.005 [Default:B.GoToSurface] Running Loop=1
2026-03-12T16:54:59.006Z,1773334499.006 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2026-03-12T16:54:59.007Z,1773334499.007 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2026-03-12T16:54:59.010Z,1773334499.010 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2026-03-12T16:54:59.010Z,1773334499.010 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2026-03-12T16:54:59.010Z,1773334499.010 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2026-03-12T16:54:59.011Z,1773334499.011 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2026-03-12T16:54:59.011Z,1773334499.011 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2026-03-12T16:54:59.012Z,1773334499.012 [Default:A.Wait] Running Loop=1
2026-03-12T16:54:59.012Z,1773334499.012 [Default:A.Wait](DEBUG): Initialize Wait Component.
2026-03-12T16:54:59.380Z,1773334499.380 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165454.00,A,3647.98800,N,12150.83948,W,1.536,50.19,120326,,,D*40
2026-03-12T16:54:59.382Z,1773334499.382 [NAL9602](INFO): GPS fix at 20260312T165454: (36.799800, -121.847325)
2026-03-12T16:55:06.912Z,1773334506.912 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T16:55:12.315Z,1773334512.315 [Default:A.Wait](INFO): Done Waiting.
2026-03-12T16:55:12.315Z,1773334512.315 [Default:A.Wait] Stopped
2026-03-12T16:55:12.315Z,1773334512.315 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2026-03-12T16:55:12.719Z,1773334512.719 [Default:CheckIn] Running Loop=1
2026-03-12T16:55:12.719Z,1773334512.719 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-03-12T16:55:12.719Z,1773334512.719 [Default:CheckIn:Read_GPS] Running Loop=1
2026-03-12T16:55:14.728Z,1773334514.728 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165509.00,A,3647.98967,N,12150.83906,W,1.011,34.39,120326,,,D*43
2026-03-12T16:55:14.730Z,1773334514.730 [NAL9602](INFO): GPS fix at 20260312T165509: (36.799828, -121.847318)
2026-03-12T16:55:14.741Z,1773334514.741 [Default:CheckIn:Read_GPS] Stopped
2026-03-12T16:55:14.741Z,1773334514.741 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-03-12T16:55:42.052Z,1773334542.052 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T16:55:49.142Z,1773334549.142 [NAL9602](INFO): SBD MO Status=1, MOMSN=13191, MT Status=0, MTMSN=0
2026-03-12T16:55:49.194Z,1773334549.194 [NAL9602](INFO): Sent 74 bytes from file Logs/20260312T162850/Courier0003.lzma
2026-03-12T16:55:49.195Z,1773334549.195 [NAL9602](INFO): Packets left to send: 0
2026-03-12T16:56:03.375Z,1773334563.375 [NAL9602](INFO): SBD MO Status=1, MOMSN=13192, MT Status=0, MTMSN=0
2026-03-12T16:56:03.428Z,1773334563.428 [NAL9602](INFO): Sent 332 bytes from file Logs/20260312T162850/Express0004.lzma
2026-03-12T16:56:03.428Z,1773334563.428 [NAL9602](INFO): Packets left to send: 1
2026-03-12T16:56:16.374Z,1773334576.374 [NAL9602](INFO): SBD MO Status=2, MOMSN=13193, MT Status=2, MTMSN=0
2026-03-12T16:56:16.374Z,1773334576.374 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-12T16:56:17.208Z,1773334577.208 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T16:56:52.360Z,1773334612.360 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T16:57:19.078Z,1773334639.078 [NAL9602](INFO): SBD MO Status=1, MOMSN=13193, MT Status=0, MTMSN=0
2026-03-12T16:57:19.128Z,1773334639.128 [NAL9602](INFO): Sent 63 bytes from file Logs/20260312T162850/Express0004.lzma
2026-03-12T16:57:19.128Z,1773334639.128 [NAL9602](INFO): Packets left to send: 0
2026-03-12T16:57:25.060Z,1773334645.060 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002662
2026-03-12T16:57:29.785Z,1773334649.785 [NAL9602](INFO): SBD MO Status=0, MOMSN=13194, MT Status=0, MTMSN=0
2026-03-12T16:57:32.715Z,1773334652.715 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20260312T162850/Courier0006.lzma
2026-03-12T16:57:33.717Z,1773334653.717 [DataOverHttps](INFO): Moved sent file to Logs/20260312T162850/Courier0006.lzma.bak
2026-03-12T16:57:33.717Z,1773334653.717 [DataOverHttps](INFO): SBD MOMSN=26722596
2026-03-12T16:57:49.830Z,1773334669.830 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20260312T162850/Express0007.lzma
2026-03-12T16:57:50.393Z,1773334670.393 [DataOverHttps](INFO): Moved sent file to Logs/20260312T162850/Express0007.lzma.bak
2026-03-12T16:57:50.393Z,1773334670.393 [DataOverHttps](INFO): SBD MOMSN=26722606
2026-03-12T16:58:00.989Z,1773334680.989 [NAL9602](INFO): Not Powering down - fast GPS
2026-03-12T16:58:06.363Z,1773334686.363 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20260312T162850/Express0010.lzma
2026-03-12T16:58:07.365Z,1773334687.365 [DataOverHttps](INFO): Moved sent file to Logs/20260312T162850/Express0010.lzma.bak
2026-03-12T16:58:07.366Z,1773334687.366 [DataOverHttps](INFO): SBD MOMSN=26722611
2026-03-12T16:58:08.731Z,1773334688.731 [Default:CheckIn:Read_Iridium] Stopped
2026-03-12T16:58:08.731Z,1773334688.731 [Default:CheckIn:C.Wait] Running Loop=1
2026-03-12T16:58:08.731Z,1773334688.731 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-03-12T16:58:51.220Z,1773334731.220 [DataOverHttps](IMPORTANT): SBD MTMSN=20260312T165845
2026-03-12T16:58:58.519Z,1773334738.519 [DataOverHttps](INFO): Received command: schedule resume;restart logs