2025-02-19T17:44:40.199Z,1739987080.199 [CommandExec](IMPORTANT): got command restart logs 2025-02-19T17:44:41.780Z,1739987081.780 [DataOverHttps](INFO): Moved sent file to Logs/20250219T023729/Courier0100.lzma.bak 2025-02-19T17:44:41.780Z,1739987081.780 [DataOverHttps](IMPORTANT): SBD MOMSN=23967411, MTMSN=20250219T174440 2025-02-19T17:45:00.946Z,1739987100.946 [DataOverHttps](INFO): Received command: stop 2025-02-19T17:45:09.506Z,1739987109.506 [CommandExec](IMPORTANT): got command stop 2025-02-19T17:45:09.507Z,1739987109.507 [CommandExec](IMPORTANT): Scheduling is paused 2025-02-19T17:45:09.507Z,1739987109.507 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop 2025-02-19T17:45:09.771Z,1739987109.771 [MissionManager](INFO): MissionManager is completed. 2025-02-19T17:45:09.772Z,1739987109.772 [MissionManager](INFO): Uninitializing Mission keepstation 2025-02-19T17:45:09.772Z,1739987109.772 [keepstation] Stopped 2025-02-19T17:45:09.772Z,1739987109.772 [keepstation](DEBUG): Aggregate::uninitialize keepstation 2025-02-19T17:45:09.772Z,1739987109.772 [keepstation:NeedComms] Stopped 2025-02-19T17:45:09.772Z,1739987109.772 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-02-19T17:45:09.772Z,1739987109.772 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-02-19T17:45:09.772Z,1739987109.772 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-02-19T17:45:09.772Z,1739987109.772 [keepstation:NeedComms:D] Stopped 2025-02-19T17:45:09.772Z,1739987109.772 [keepstation:StandardEnvelopes] Stopped 2025-02-19T17:45:09.772Z,1739987109.772 [keepstation:StandardEnvelopes](DEBUG): Aggregate::uninitialize keepstation:StandardEnvelopes 2025-02-19T17:45:09.772Z,1739987109.772 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2025-02-19T17:45:09.773Z,1739987109.773 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2025-02-19T17:45:09.773Z,1739987109.773 [keepstation:StandardEnvelopes:B.DepthEnvelope] Stopped 2025-02-19T17:45:09.773Z,1739987109.773 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2025-02-19T17:45:09.773Z,1739987109.773 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2025-02-19T17:45:09.773Z,1739987109.773 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2025-02-19T17:45:09.773Z,1739987109.773 [keepstation:BackseatDriver] Stopped 2025-02-19T17:45:09.773Z,1739987109.773 [keepstation:BackseatDriver](DEBUG): Aggregate::uninitialize keepstation:BackseatDriver 2025-02-19T17:45:09.790Z,1739987109.790 [keepstation:BackseatDriver:A.BackseatDriver] Stopped 2025-02-19T17:45:09.790Z,1739987109.790 [keepstation:PowerOnly] Stopped 2025-02-19T17:45:09.790Z,1739987109.790 [keepstation:PowerOnly](DEBUG): Aggregate::uninitialize keepstation:PowerOnly 2025-02-19T17:45:09.790Z,1739987109.790 [keepstation:PowerOnly:E.Wait] Stopped 2025-02-19T17:45:09.790Z,1739987109.790 [keepstation:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component. 2025-02-19T17:45:09.790Z,1739987109.790 [keepstation:H.Pitch] Stopped 2025-02-19T17:45:09.790Z,1739987109.790 [keepstation:KeepStation] Stopped 2025-02-19T17:45:09.791Z,1739987109.791 [keepstation:KeepStation](DEBUG): Aggregate::uninitialize keepstation:KeepStation 2025-02-19T17:45:09.791Z,1739987109.791 [keepstation:KeepStation:A] Stopped 2025-02-19T17:45:09.791Z,1739987109.791 [keepstation:KeepStation:B.Pitch] Stopped 2025-02-19T17:45:09.791Z,1739987109.791 [keepstation:KeepStation:C.KeepStation] Stopped 2025-02-19T17:45:09.791Z,1739987109.791 [keepstation:KeepStation:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-02-19T17:45:09.791Z,1739987109.791 [keepstation:KeepStation:D.Wait] Stopped 2025-02-19T17:45:09.791Z,1739987109.791 [keepstation:KeepStation:D.Wait](DEBUG): Uninitialize Wait Component. 2025-02-19T17:45:10.141Z,1739987110.141 [MissionManager](IMPORTANT): Started mission Default 2025-02-19T17:45:10.141Z,1739987110.141 [Default] Running Loop=1 2025-02-19T17:45:10.157Z,1739987110.157 [Default](DEBUG): Aggregate::initialize Default 2025-02-19T17:45:10.157Z,1739987110.157 [Default:B.GoToSurface] Running Loop=1 2025-02-19T17:45:10.158Z,1739987110.158 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-02-19T17:45:10.158Z,1739987110.158 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-02-19T17:45:10.158Z,1739987110.158 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-02-19T17:45:10.158Z,1739987110.158 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-02-19T17:45:10.159Z,1739987110.159 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-02-19T17:45:10.159Z,1739987110.159 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-02-19T17:45:10.159Z,1739987110.159 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-02-19T17:45:10.160Z,1739987110.160 [Default:A.Wait] Running Loop=1 2025-02-19T17:45:10.160Z,1739987110.160 [Default:A.Wait](DEBUG): Initialize Wait Component. 2025-02-19T17:45:18.161Z,1739987118.161 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250219T023729/Courier0103.lzma 2025-02-19T17:45:19.164Z,1739987119.164 [DataOverHttps](INFO): Moved sent file to Logs/20250219T023729/Courier0103.lzma.bak 2025-02-19T17:45:19.164Z,1739987119.164 [DataOverHttps](INFO): SBD MOMSN=23967416 2025-02-19T17:45:23.512Z,1739987123.512 [Default:A.Wait](INFO): Done Waiting. 2025-02-19T17:45:23.512Z,1739987123.512 [Default:A.Wait] Stopped 2025-02-19T17:45:23.512Z,1739987123.512 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2025-02-19T17:45:23.906Z,1739987123.906 [Default:CheckIn] Running Loop=1 2025-02-19T17:45:23.906Z,1739987123.906 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-02-19T17:45:23.907Z,1739987123.907 [Default:CheckIn:Read_GPS] Running Loop=1 2025-02-19T17:45:25.901Z,1739987125.901 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174525.00,A,3647.84729,N,12150.83484,W,0.467,47.05,190225,,,D*45 2025-02-19T17:45:25.904Z,1739987125.904 [NAL9602](INFO): GPS fix at 20250219T174525: (36.797455, -121.847247) 2025-02-19T17:45:25.920Z,1739987125.920 [Default:CheckIn:Read_GPS] Stopped 2025-02-19T17:45:25.921Z,1739987125.921 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-02-19T17:45:35.262Z,1739987135.262 [DataOverHttps](INFO): Sending 269 bytes from file Logs/20250219T174440/Courier0006.lzma 2025-02-19T17:45:36.263Z,1739987136.263 [DataOverHttps](INFO): Moved sent file to Logs/20250219T174440/Courier0006.lzma.bak 2025-02-19T17:45:36.263Z,1739987136.263 [DataOverHttps](INFO): SBD MOMSN=23967418 2025-02-19T17:45:43.296Z,1739987143.296 [NAL9602](INFO): SBD MO Status=0, MOMSN=12757, MT Status=0, MTMSN=0 2025-02-19T17:45:43.296Z,1739987143.296 [NAL9602](INFO): No messages in MT queue 2025-02-19T17:45:52.286Z,1739987152.286 [DataOverHttps](INFO): Sending 496 bytes from file Logs/20250219T023729/Express0101.lzma 2025-02-19T17:45:53.288Z,1739987153.288 [DataOverHttps](INFO): Moved sent file to Logs/20250219T023729/Express0101.lzma.bak 2025-02-19T17:45:53.288Z,1739987153.288 [DataOverHttps](INFO): SBD MOMSN=23967422 2025-02-19T17:46:09.450Z,1739987169.450 [DataOverHttps](INFO): Sending 589 bytes from file Logs/20250219T023729/Express0104.lzma 2025-02-19T17:46:10.452Z,1739987170.452 [DataOverHttps](INFO): Moved sent file to Logs/20250219T023729/Express0104.lzma.bak 2025-02-19T17:46:10.452Z,1739987170.452 [DataOverHttps](IMPORTANT): SBD MOMSN=23967425, MTMSN=20250219T174610 2025-02-19T17:46:13.994Z,1739987173.994 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-19T17:46:17.941Z,1739987177.941 [DataOverHttps](INFO): Received command: schedule clear;schedule resume 2025-02-19T17:46:17.973Z,1739987177.973 [CommandExec](IMPORTANT): got command schedule clear 2025-02-19T17:46:17.974Z,1739987177.974 [CommandExec](IMPORTANT): Cleared 0 scheduled commands. 2025-02-19T17:46:17.974Z,1739987177.974 [CommandExec](IMPORTANT): got command schedule resume 2025-02-19T17:46:17.974Z,1739987177.974 [CommandExec](IMPORTANT): Scheduling is resumed 2025-02-19T17:46:26.491Z,1739987186.491 [DataOverHttps](INFO): Sending 789 bytes from file Logs/20250219T174440/Express0007.lzma 2025-02-19T17:46:27.491Z,1739987187.491 [DataOverHttps](INFO): Moved sent file to Logs/20250219T174440/Express0007.lzma.bak 2025-02-19T17:46:27.491Z,1739987187.491 [DataOverHttps](INFO): SBD MOMSN=23967430 2025-02-19T17:46:45.421Z,1739987205.421 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250219T174440/Courier0009.lzma 2025-02-19T17:46:46.993Z,1739987206.993 [DataOverHttps](INFO): Moved sent file to Logs/20250219T174440/Courier0009.lzma.bak 2025-02-19T17:46:46.993Z,1739987206.993 [DataOverHttps](INFO): SBD MOMSN=23967437 2025-02-19T17:47:16.258Z,1739987236.258 [DataOverHttps](INFO): Sending 487 bytes from file Logs/20250219T174440/Express0010.lzma 2025-02-19T17:47:17.263Z,1739987237.263 [DataOverHttps](INFO): Moved sent file to Logs/20250219T174440/Express0010.lzma.bak 2025-02-19T17:47:17.263Z,1739987237.263 [DataOverHttps](INFO): SBD MOMSN=23967439 2025-02-19T17:47:19.207Z,1739987239.207 [Default:CheckIn:Read_Iridium] Stopped 2025-02-19T17:47:19.207Z,1739987239.207 [Default:CheckIn:C.Wait] Running Loop=1 2025-02-19T17:47:19.207Z,1739987239.207 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-02-19T17:52:19.937Z,1739987539.937 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-02-19T17:52:19.941Z,1739987539.941 [Default:CheckIn:C.Wait] Stopped 2025-02-19T17:52:19.942Z,1739987539.942 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-02-19T17:52:19.942Z,1739987539.942 [Default:CheckIn:D] Running Loop=1 2025-02-19T17:52:20.351Z,1739987540.351 [Default:CheckIn:D] Stopped 2025-02-19T17:52:20.351Z,1739987540.351 [Default:CheckIn:E] Running Loop=1 2025-02-19T17:52:20.761Z,1739987540.761 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 7.170169 min 2025-02-19T17:52:20.761Z,1739987540.761 [Default:CheckIn:E] Stopped 2025-02-19T17:52:20.761Z,1739987540.761 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-02-19T17:52:20.761Z,1739987540.761 [Default:CheckIn] Stopped 2025-02-19T17:52:20.761Z,1739987540.761 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-02-19T17:52:20.765Z,1739987540.765 [Default:CheckIn](INFO): Running loop #2 2025-02-19T17:52:20.766Z,1739987540.766 [Default:CheckIn] Running Loop=2 2025-02-19T17:52:20.766Z,1739987540.766 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-02-19T17:52:20.766Z,1739987540.766 [Default:CheckIn:Read_GPS] Running Loop=1 2025-02-19T17:52:22.740Z,1739987542.740 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175222.00,A,3647.86381,N,12150.82478,W,0.311,320.50,190225,,,D*76 2025-02-19T17:52:22.744Z,1739987542.744 [NAL9602](INFO): GPS fix at 20250219T175222: (36.797730, -121.847080) 2025-02-19T17:52:22.818Z,1739987542.818 [Default:CheckIn:Read_GPS] Stopped 2025-02-19T17:52:22.818Z,1739987542.818 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-02-19T17:52:34.233Z,1739987554.233 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250219T174440/Courier0012.lzma 2025-02-19T17:52:35.235Z,1739987555.235 [DataOverHttps](INFO): Moved sent file to Logs/20250219T174440/Courier0012.lzma.bak 2025-02-19T17:52:35.235Z,1739987555.235 [DataOverHttps](INFO): SBD MOMSN=23967446 2025-02-19T17:52:38.448Z,1739987558.448 [NAL9602](INFO): SBD MO Status=0, MOMSN=12758, MT Status=0, MTMSN=0 2025-02-19T17:52:38.448Z,1739987558.448 [NAL9602](INFO): No messages in MT queue 2025-02-19T17:53:01.214Z,1739987581.214 [DataOverHttps](INFO): Sending 1894 bytes from file Logs/20250219T174440/Express0013.lzma 2025-02-19T17:53:02.211Z,1739987582.211 [DataOverHttps](INFO): Moved sent file to Logs/20250219T174440/Express0013.lzma.bak 2025-02-19T17:53:02.212Z,1739987582.212 [DataOverHttps](INFO): SBD MOMSN=23967450 2025-02-19T17:53:04.392Z,1739987584.392 [Default:CheckIn:Read_Iridium] Stopped 2025-02-19T17:53:04.392Z,1739987584.392 [Default:CheckIn:C.Wait] Running Loop=1 2025-02-19T17:53:04.392Z,1739987584.392 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-02-19T17:53:09.194Z,1739987589.194 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-19T17:57:57.118Z,1739987877.118 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-19T17:58:05.274Z,1739987885.274 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-02-19T17:58:05.274Z,1739987885.274 [Default:CheckIn:C.Wait] Stopped 2025-02-19T17:58:05.274Z,1739987885.274 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-02-19T17:58:05.274Z,1739987885.274 [Default:CheckIn:D] Running Loop=1 2025-02-19T17:58:05.634Z,1739987885.634 [Default:CheckIn:D] Stopped 2025-02-19T17:58:05.634Z,1739987885.634 [Default:CheckIn:E] Running Loop=1 2025-02-19T17:58:06.042Z,1739987886.042 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 12.924875 min 2025-02-19T17:58:06.042Z,1739987886.042 [Default:CheckIn:E] Stopped 2025-02-19T17:58:06.042Z,1739987886.042 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-02-19T17:58:06.042Z,1739987886.042 [Default:CheckIn] Stopped 2025-02-19T17:58:06.042Z,1739987886.042 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-02-19T17:58:06.042Z,1739987886.042 [Default:CheckIn](INFO): Running loop #3 2025-02-19T17:58:06.042Z,1739987886.042 [Default:CheckIn] Running Loop=3 2025-02-19T17:58:06.043Z,1739987886.043 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-02-19T17:58:06.043Z,1739987886.043 [Default:CheckIn:Read_GPS] Running Loop=1 2025-02-19T17:58:08.049Z,1739987888.049 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175807.00,A,3647.87886,N,12150.82210,W,0.330,26.87,190225,,,D*42 2025-02-19T17:58:08.051Z,1739987888.051 [NAL9602](INFO): GPS fix at 20250219T175807: (36.797981, -121.847035) 2025-02-19T17:58:08.083Z,1739987888.083 [Default:CheckIn:Read_GPS] Stopped 2025-02-19T17:58:08.083Z,1739987888.083 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-02-19T17:58:09.248Z,1739987889.248 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-19T17:58:16.200Z,1739987896.200 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250219T174440/Courier0015.lzma 2025-02-19T17:58:17.107Z,1739987897.107 [DataOverHttps](INFO): Moved sent file to Logs/20250219T174440/Courier0015.lzma.bak 2025-02-19T17:58:17.107Z,1739987897.107 [DataOverHttps](INFO): SBD MOMSN=23967454 2025-02-19T17:58:21.773Z,1739987901.773 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-19T17:58:33.653Z,1739987913.653 [DataOverHttps](INFO): Sending 1765 bytes from file Logs/20250219T174440/Express0016.lzma 2025-02-19T17:58:34.651Z,1739987914.651 [DataOverHttps](INFO): Moved sent file to Logs/20250219T174440/Express0016.lzma.bak 2025-02-19T17:58:34.651Z,1739987914.651 [DataOverHttps](INFO): SBD MOMSN=23967457 2025-02-19T17:58:34.715Z,1739987914.715 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-19T17:58:35.940Z,1739987915.940 [Default:CheckIn:Read_Iridium] Stopped 2025-02-19T17:58:35.940Z,1739987915.940 [Default:CheckIn:C.Wait] Running Loop=1 2025-02-19T17:58:35.940Z,1739987915.940 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-02-19T17:58:46.031Z,1739987926.031 [NAL9602](INFO): SBD MO Status=2, MOMSN=12759, MT Status=2, MTMSN=0 2025-02-19T17:58:46.031Z,1739987926.031 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-02-19T17:58:46.829Z,1739987926.829 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-19T17:58:56.928Z,1739987936.928 [NAL9602](INFO): SBD MO Status=0, MOMSN=12759, MT Status=0, MTMSN=0 2025-02-19T17:58:56.928Z,1739987936.928 [NAL9602](INFO): No messages in MT queue 2025-02-19T17:58:59.350Z,1739987939.350 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-19T17:59:12.276Z,1739987952.276 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-19T17:59:27.646Z,1739987967.646 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-19T18:03:19.755Z,1739988199.755 [DataOverHttps](IMPORTANT): SBD MTMSN=20250219T180319 2025-02-19T18:03:27.229Z,1739988207.229 [DataOverHttps](INFO): Received command: restart logs