2025-08-07T15:50:28.758Z,1754581828.758 [CommandExec](IMPORTANT): got command restart logs
2025-08-07T15:50:29.645Z,1754581829.645 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155031.00,A,3644.00418,N,12155.62959,W,0.544,152.89,070825,,,D*7B
2025-08-07T15:50:29.648Z,1754581829.648 [NAL9602](INFO): GPS fix at 20250807T155031: (36.733403, -121.927160)
2025-08-07T15:50:29.673Z,1754581829.673 [UniversalFixResidualReporter](INFO): Fix residual: 32.9 %DT, over the last 3334.6 m. Residual distance 1096.6 m at bearing 45.7 degrees. Fix at (36.7334, -121.9272) with 2197.1 m made good.
2025-08-07T15:50:29.689Z,1754581829.689 [profile_station:NeedComms:C] Stopped
2025-08-07T15:50:29.689Z,1754581829.689 [profile_station:NeedComms:D] Running Loop=1
2025-08-07T15:50:30.929Z,1754581830.929 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T15:50:42.777Z,1754581842.777 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T15:50:45.981Z,1754581845.981 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T15:50:46.955Z,1754581846.955 [CTD_Seabird](ERROR): Failed to parse device response: 0.07,14.3640,4.738.70
2025-08-07T15:50:48.433Z,1754581848.433 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=26696, MT Status=1, MTMSN=972
2025-08-07T15:50:48.490Z,1754581848.490 [NAL9602](INFO): Sent 86 bytes from file Logs/20250807T060054/Courier0036.lzma
2025-08-07T15:50:48.490Z,1754581848.490 [NAL9602](INFO): Packets left to send: 0
2025-08-07T15:50:49.033Z,1754581849.033 [NAL9602](INFO): Received command: configSet Waypoint.wptRecalcBearing 1 bool persist
2025-08-07T15:50:49.501Z,1754581849.501 [CommandExec](IMPORTANT): got command configSet Waypoint.wptRecalcBearing 1 bool persist
2025-08-07T15:51:01.049Z,1754581861.049 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T15:51:07.097Z,1754581867.097 [NAL9602](INFO): SBD MO Status=1, MOMSN=26697, MT Status=0, MTMSN=0
2025-08-07T15:51:07.149Z,1754581867.149 [NAL9602](INFO): Sent 332 bytes from file Logs/20250807T060054/Express0037.lzma
2025-08-07T15:51:07.150Z,1754581867.150 [NAL9602](INFO): Packets left to send: 9
2025-08-07T15:51:13.909Z,1754581873.909 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T15:51:15.676Z,1754581875.676 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T15:51:17.708Z,1754581877.708 [NAL9602](INFO): SBD MO Status=1, MOMSN=26698, MT Status=0, MTMSN=0
2025-08-07T15:51:17.765Z,1754581877.765 [NAL9602](INFO): Sent 332 bytes from file Logs/20250807T060054/Express0037.lzma
2025-08-07T15:51:17.766Z,1754581877.766 [NAL9602](INFO): Packets left to send: 8
2025-08-07T15:51:30.342Z,1754581890.342 [NAL9602](INFO): SBD MO Status=1, MOMSN=26699, MT Status=0, MTMSN=0
2025-08-07T15:51:30.406Z,1754581890.406 [NAL9602](INFO): Sent 332 bytes from file Logs/20250807T060054/Express0037.lzma
2025-08-07T15:51:30.406Z,1754581890.406 [NAL9602](INFO): Packets left to send: 7
2025-08-07T15:51:31.572Z,1754581891.572 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T15:51:43.006Z,1754581903.006 [NAL9602](INFO): SBD MO Status=1, MOMSN=26700, MT Status=0, MTMSN=0
2025-08-07T15:51:43.057Z,1754581903.057 [NAL9602](INFO): Sent 332 bytes from file Logs/20250807T060054/Express0037.lzma
2025-08-07T15:51:43.058Z,1754581903.058 [NAL9602](INFO): Packets left to send: 6
2025-08-07T15:51:45.049Z,1754581905.049 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T15:51:48.460Z,1754581908.460 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T15:52:03.806Z,1754581923.806 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T15:52:05.035Z,1754581925.035 [NAL9602](INFO): SBD MO Status=1, MOMSN=26701, MT Status=0, MTMSN=0
2025-08-07T15:52:05.093Z,1754581925.093 [NAL9602](INFO): Sent 332 bytes from file Logs/20250807T060054/Express0037.lzma
2025-08-07T15:52:05.093Z,1754581925.093 [NAL9602](INFO): Packets left to send: 5
2025-08-07T15:52:16.175Z,1754581936.175 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T15:52:19.625Z,1754581939.625 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T15:52:20.457Z,1754581940.457 [NAL9602](INFO): SBD MO Status=2, MOMSN=26702, MT Status=2, MTMSN=0
2025-08-07T15:52:20.457Z,1754581940.457 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-07T15:52:41.514Z,1754581961.514 [NAL9602](INFO): SBD MO Status=1, MOMSN=26702, MT Status=0, MTMSN=0
2025-08-07T15:52:41.573Z,1754581961.573 [NAL9602](INFO): Sent 332 bytes from file Logs/20250807T060054/Express0037.lzma
2025-08-07T15:52:41.573Z,1754581961.573 [NAL9602](INFO): Packets left to send: 4
2025-08-07T15:52:47.310Z,1754581967.310 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T15:52:54.217Z,1754581974.217 [NAL9602](INFO): SBD MO Status=1, MOMSN=26703, MT Status=0, MTMSN=0
2025-08-07T15:52:54.273Z,1754581974.273 [NAL9602](INFO): Sent 332 bytes from file Logs/20250807T060054/Express0037.lzma
2025-08-07T15:52:54.274Z,1754581974.274 [NAL9602](INFO): Packets left to send: 3
2025-08-07T15:53:00.503Z,1754581980.503 [CTD_Seabird](ERROR): Failed to parse device response: 0.08,14.3649,4.095.33
2025-08-07T15:53:06.867Z,1754581986.867 [NAL9602](INFO): SBD MO Status=1, MOMSN=26704, MT Status=0, MTMSN=0
2025-08-07T15:53:06.917Z,1754581986.917 [NAL9602](INFO): Sent 332 bytes from file Logs/20250807T060054/Express0037.lzma
2025-08-07T15:53:06.917Z,1754581986.917 [NAL9602](INFO): Packets left to send: 2
2025-08-07T15:53:08.870Z,1754581988.870 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003582
2025-08-07T15:53:16.824Z,1754581996.824 [DataOverHttps](INFO): Sending 3313 bytes from file Logs/20250807T060054/Express0037.lzma
2025-08-07T15:53:19.519Z,1754581999.519 [NAL9602](INFO): SBD MO Status=2, MOMSN=26705, MT Status=2, MTMSN=0
2025-08-07T15:53:19.519Z,1754581999.519 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-07T15:53:26.871Z,1754582006.871 [DataOverHttps](INFO): Moved sent file to Logs/20250807T060054/Express0037.lzma.bak
2025-08-07T15:53:26.871Z,1754582006.871 [DataOverHttps](INFO): SBD MOMSN=25802756
2025-08-07T15:53:28.042Z,1754582008.042 [profile_station:NeedComms:D] Stopped
2025-08-07T15:53:28.042Z,1754582008.042 [profile_station:NeedComms:E] Running Loop=1
2025-08-07T15:53:30.060Z,1754582010.060 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155332.00,A,3643.99580,N,12155.62392,W,1.205,118.53,070825,,,D*7B
2025-08-07T15:53:30.066Z,1754582010.066 [NAL9602](INFO): GPS fix at 20250807T155332: (36.733263, -121.927065)
2025-08-07T15:53:30.098Z,1754582010.098 [profile_station:NeedComms:E] Stopped
2025-08-07T15:53:30.099Z,1754582010.099 [profile_station:NeedComms](INFO): Completed profile_station:NeedComms
2025-08-07T15:53:30.099Z,1754582010.099 [profile_station:NeedComms] Stopped
2025-08-07T15:53:30.099Z,1754582010.099 [profile_station:NeedComms](DEBUG): Aggregate::uninitialize profile_station:NeedComms
2025-08-07T15:53:30.099Z,1754582010.099 [profile_station:NeedComms:B.GoToSurface] Stopped
2025-08-07T15:53:30.099Z,1754582010.099 [profile_station:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-08-07T15:53:30.493Z,1754582010.493 [profile_station:SurfaceComms:B](INFO): Completed profile_station:SurfaceComms:B
2025-08-07T15:53:30.493Z,1754582010.493 [profile_station:SurfaceComms:B] Stopped
2025-08-07T15:53:30.493Z,1754582010.493 [profile_station:SurfaceComms:B](DEBUG): Aggregate::uninitialize profile_station:SurfaceComms:B
2025-08-07T15:53:30.493Z,1754582010.493 [profile_station:SurfaceComms:setInitial] Running Loop=1
2025-08-07T15:53:30.494Z,1754582010.494 [profile_station:SurfaceComms:setInitial](DEBUG): Aggregate::initialize profile_station:SurfaceComms:setInitial
2025-08-07T15:53:30.494Z,1754582010.494 [profile_station:SurfaceComms:setInitial:A] Running Loop=1
2025-08-07T15:53:30.901Z,1754582010.901 [profile_station:SurfaceComms:setInitial:A] Stopped
2025-08-07T15:53:30.901Z,1754582010.901 [profile_station:SurfaceComms:setInitial](INFO): Completed profile_station:SurfaceComms:setInitial
2025-08-07T15:53:30.901Z,1754582010.901 [profile_station:SurfaceComms:setInitial] Stopped
2025-08-07T15:53:30.901Z,1754582010.901 [profile_station:SurfaceComms:setInitial](DEBUG): Aggregate::uninitialize profile_station:SurfaceComms:setInitial
2025-08-07T15:53:30.902Z,1754582010.902 [profile_station:SurfaceComms:setSink] Running Loop=1
2025-08-07T15:53:30.902Z,1754582010.902 [profile_station:SurfaceComms:setSink](DEBUG): Aggregate::initialize profile_station:SurfaceComms:setSink
2025-08-07T15:53:30.902Z,1754582010.902 [profile_station:SurfaceComms:setSink:A] Running Loop=1
2025-08-07T15:53:31.296Z,1754582011.296 [profile_station:CircleWrapper:Dive] Running Loop=1
2025-08-07T15:53:31.296Z,1754582011.296 [profile_station:CircleWrapper:Dive](DEBUG): Aggregate::initialize profile_station:CircleWrapper:Dive
2025-08-07T15:53:31.296Z,1754582011.296 [profile_station:CircleWrapper:Dive:A] Running Loop=1
2025-08-07T15:53:31.296Z,1754582011.296 [profile_station:CircleWrapper:Dive:A](DEBUG): Aggregate::initialize profile_station:CircleWrapper:Dive:A
2025-08-07T15:53:31.297Z,1754582011.297 [profile_station:DiveCmd:YoYoDive] Running Loop=1
2025-08-07T15:53:31.317Z,1754582011.317 [profile_station:DiveCmd:YoYoDive](DEBUG): Aggregate::initialize profile_station:DiveCmd:YoYoDive
2025-08-07T15:53:31.317Z,1754582011.317 [profile_station:DiveCmd:YoYoDive:dive] Running Loop=1
2025-08-07T15:53:31.317Z,1754582011.317 [profile_station:DiveCmd:YoYoDive:dive](DEBUG): Aggregate::initialize profile_station:DiveCmd:YoYoDive:dive
2025-08-07T15:53:31.317Z,1754582011.317 [profile_station:DiveCmd:YoYoDive:dive:A.Pitch] Running Loop=1
2025-08-07T15:53:31.318Z,1754582011.318 [profile_station:DiveCmd:YoYoDive:dive:A.Pitch](DEBUG): Initialize.
2025-08-07T15:53:31.318Z,1754582011.318 [profile_station:DiveCmd:YoYoDive:dive:B.Buoyancy] Running Loop=1
2025-08-07T15:53:31.318Z,1754582011.318 [profile_station:DiveCmd:YoYoDive:dive:B.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-08-07T15:53:31.318Z,1754582011.318 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed] Running Loop=1
2025-08-07T15:53:31.318Z,1754582011.318 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed](DEBUG): Aggregate::initialize profile_station:DiveCmd:YoYoDive:dive:SetSpeed
2025-08-07T15:53:31.318Z,1754582011.318 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive] Running Loop=1
2025-08-07T15:53:31.318Z,1754582011.318 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive](DEBUG): Aggregate::initialize profile_station:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive
2025-08-07T15:53:31.318Z,1754582011.318 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed] Running Loop=1
2025-08-07T15:53:31.318Z,1754582011.318 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed](DEBUG): Aggregate::initialize profile_station:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed
2025-08-07T15:53:31.318Z,1754582011.318 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed:A.SetSpeed] Running Loop=1
2025-08-07T15:53:31.319Z,1754582011.319 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed:A.SetSpeed](DEBUG): Initialize.
2025-08-07T15:53:31.319Z,1754582011.319 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed] Running Loop=1
2025-08-07T15:53:31.319Z,1754582011.319 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed](DEBUG): Aggregate::initialize profile_station:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed
2025-08-07T15:53:31.319Z,1754582011.319 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed:A.SetSpeed] Running Loop=1
2025-08-07T15:53:31.319Z,1754582011.319 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed:A.SetSpeed](DEBUG): Initialize.
2025-08-07T15:53:31.319Z,1754582011.319 [profile_station:DiveCmd:YoYoDive:dive:D.DepthEnvelope] Running Loop=1
2025-08-07T15:53:31.319Z,1754582011.319 [profile_station:DiveCmd:YoYoDive:dive:D.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2025-08-07T15:53:31.319Z,1754582011.319 [profile_station:DiveCmd:YoYoDive:dive:E.AltitudeEnvelope] Running Loop=1
2025-08-07T15:53:31.319Z,1754582011.319 [profile_station:DiveCmd:YoYoDive:dive:E.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2025-08-07T15:53:31.320Z,1754582011.320 [profile_station:DiveCmd:YoYoDive:dive:F.YoYo] Running Loop=1
2025-08-07T15:53:31.320Z,1754582011.320 [profile_station:DiveCmd:YoYoDive:dive:F.YoYo](DEBUG): Initialize YoYoComponent.
2025-08-07T15:53:31.330Z,1754582011.330 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive] Stopped
2025-08-07T15:53:31.330Z,1754582011.330 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive](DEBUG): Aggregate::uninitialize profile_station:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive
2025-08-07T15:53:31.330Z,1754582011.330 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed] Stopped
2025-08-07T15:53:31.330Z,1754582011.330 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed](DEBUG): Aggregate::uninitialize profile_station:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed
2025-08-07T15:53:31.330Z,1754582011.330 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed:A.SetSpeed] Stopped
2025-08-07T15:53:31.330Z,1754582011.330 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed:A.SetSpeed](DEBUG): Uninitialize.
2025-08-07T15:53:31.331Z,1754582011.331 [profile_station:SurfaceComms:setSink] Stopped
2025-08-07T15:53:31.331Z,1754582011.331 [profile_station:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize profile_station:SurfaceComms:setSink
2025-08-07T15:53:31.331Z,1754582011.331 [profile_station:SurfaceComms:setSink:A] Stopped
2025-08-07T15:53:31.331Z,1754582011.331 [profile_station:SurfaceComms](INFO): Completed profile_station:SurfaceComms
2025-08-07T15:53:31.331Z,1754582011.331 [profile_station:SurfaceComms] Stopped
2025-08-07T15:53:31.331Z,1754582011.331 [profile_station:SurfaceComms](DEBUG): Aggregate::uninitialize profile_station:SurfaceComms
2025-08-07T15:54:02.587Z,1754582042.587 [NAL9602](INFO): Not Powering down - fast GPS
2025-08-07T15:54:28.597Z,1754582068.597 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T15:54:49.886Z,1754582089.886 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T15:54:53.841Z,1754582093.841 [Radio_Surface](INFO): Powering down
2025-08-07T15:54:56.348Z,1754582096.348 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T15:54:59.721Z,1754582099.721 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T15:55:00.725Z,1754582100.725 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-08-07T15:55:08.467Z,1754582108.467 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T15:55:21.080Z,1754582121.080 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T15:55:33.255Z,1754582133.255 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T15:55:45.853Z,1754582145.853 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T15:55:58.786Z,1754582158.786 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T15:56:11.310Z,1754582171.310 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T15:56:23.428Z,1754582183.428 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T15:56:36.362Z,1754582196.362 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T15:56:48.170Z,1754582208.170 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.733688
2025-08-07T15:56:48.480Z,1754582208.480 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T15:57:01.091Z,1754582221.091 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T15:57:24.925Z,1754582244.925 [BPC1](ERROR): Battery stick #10 (s/n: 00C4) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-08-07T15:57:24.926Z,1754582244.926 [BPC1](ERROR): Battery stick #24 (s/n: 01D3) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-08-07T15:57:24.927Z,1754582244.927 [BPC1](ERROR): Battery stick #29 (s/n: 00A7) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-08-07T15:57:24.928Z,1754582244.928 [BPC1](ERROR): Battery stick #51 (s/n: 01D6) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-08-07T15:57:24.929Z,1754582244.929 [BPC1](ERROR): Battery stick #57 (s/n: 019F) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-08-07T15:57:24.930Z,1754582244.930 [BPC1](ERROR): Battery stick #59 (s/n: 0184) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AC0.
2025-08-07T15:57:24.930Z,1754582244.930 [BPC1](INFO): Calculating totals. Valid battery stick count: 42. Valid reserve battery stick count: 5.
2025-08-07T15:57:24.958Z,1754582244.958 [BPC1](FAULT): Failed to receive data from 13 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 9, 10, 11, 14, 16, 24, 29, 35, 51, 52, 53, 57, 59.
2025-08-07T15:59:46.409Z,1754582386.409 [Radio_Surface](INFO): Powering up
2025-08-07T15:59:51.937Z,1754582391.937 [DataOverHttps](INFO): Radio surface powered ON.
2025-08-07T16:00:22.059Z,1754582422.059 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T16:00:52.669Z,1754582452.669 [Radio_Surface](INFO): Powering down
2025-08-07T16:00:53.193Z,1754582453.193 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T16:00:54.197Z,1754582454.197 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-08-07T16:02:49.494Z,1754582569.494 [Radio_Surface](INFO): Powering up
2025-08-07T16:02:55.556Z,1754582575.556 [Radio_Surface](INFO): Powering down
2025-08-07T16:05:50.085Z,1754582750.085 [Radio_Surface](INFO): Powering up
2025-08-07T16:05:55.426Z,1754582755.426 [DataOverHttps](INFO): Radio surface powered ON.
2025-08-07T16:05:56.146Z,1754582756.146 [Radio_Surface](INFO): Powering down
2025-08-07T16:06:25.553Z,1754582785.553 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T16:06:26.557Z,1754582786.557 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-08-07T16:08:59.517Z,1754582939.517 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T16:09:12.444Z,1754582952.444 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T16:09:25.372Z,1754582965.372 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T16:09:38.304Z,1754582978.304 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T16:09:51.231Z,1754582991.231 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T16:10:04.574Z,1754583004.574 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T16:10:17.897Z,1754583017.897 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T16:10:30.889Z,1754583030.889 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T16:10:43.477Z,1754583043.477 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T16:10:56.005Z,1754583056.005 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T16:11:08.565Z,1754583068.565 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T16:11:44.189Z,1754583104.189 [Radio_Surface](INFO): Powering up
2025-08-07T16:11:50.249Z,1754583110.249 [Radio_Surface](INFO): Powering down
2025-08-07T16:12:21.470Z,1754583141.470 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-08-07T16:13:38.581Z,1754583218.581 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7).
2025-08-07T16:13:45.451Z,1754583225.451 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T16:13:59.601Z,1754583239.601 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T16:14:13.108Z,1754583253.108 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T16:14:26.453Z,1754583266.453 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T16:14:40.192Z,1754583280.192 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T16:14:54.439Z,1754583294.439 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T16:15:08.263Z,1754583308.263 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2025-08-07T16:15:08.268Z,1754583308.268 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T16:15:21.626Z,1754583321.626 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T16:15:35.420Z,1754583335.420 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T16:15:49.970Z,1754583349.970 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T16:17:49.441Z,1754583469.441 [Radio_Surface](INFO): Powering up
2025-08-07T16:17:55.501Z,1754583475.501 [Radio_Surface](INFO): Powering down
2025-08-07T16:18:20.218Z,1754583500.218 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T16:18:27.093Z,1754583507.093 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T16:18:42.894Z,1754583522.894 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T16:18:58.237Z,1754583538.237 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T16:19:13.201Z,1754583553.201 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T16:19:28.186Z,1754583568.186 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T16:19:42.803Z,1754583582.803 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T16:19:57.776Z,1754583597.776 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T16:20:12.746Z,1754583612.746 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T16:20:28.109Z,1754583628.109 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T16:22:00.417Z,1754583720.417 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-08-07T16:23:02.088Z,1754583782.088 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T16:23:15.445Z,1754583795.445 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T16:23:28.778Z,1754583808.778 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T16:23:42.510Z,1754583822.510 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T16:23:55.867Z,1754583835.867 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T16:24:09.293Z,1754583849.293 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T16:24:22.727Z,1754583862.727 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T16:24:36.098Z,1754583876.098 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T16:24:49.450Z,1754583889.450 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T16:25:03.182Z,1754583903.182 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T16:25:17.082Z,1754583917.082 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T16:27:38.848Z,1754584058.848 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-08-07T16:27:44.837Z,1754584064.837 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T16:27:59.817Z,1754584079.817 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T16:28:15.202Z,1754584095.202 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T16:28:30.156Z,1754584110.156 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T16:28:45.163Z,1754584125.163 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T16:29:00.115Z,1754584140.115 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T16:29:12.651Z,1754584152.651 [BPC1](ERROR): Battery stick #10 (s/n: 00C4) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-08-07T16:29:12.652Z,1754584152.652 [BPC1](ERROR): Battery stick #16 (s/n: 00F8) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-08-07T16:29:12.653Z,1754584152.653 [BPC1](ERROR): Battery stick #24 (s/n: 01D3) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-08-07T16:29:12.654Z,1754584152.654 [BPC1](ERROR): Battery stick #29 (s/n: 00A7) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-08-07T16:29:12.654Z,1754584152.654 [BPC1](ERROR): Battery stick #51 (s/n: 01D6) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-08-07T16:29:12.655Z,1754584152.655 [BPC1](ERROR): Battery stick #57 (s/n: 019F) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-08-07T16:29:12.656Z,1754584152.656 [BPC1](ERROR): Battery stick #59 (s/n: 0184) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AC0.
2025-08-07T16:29:12.656Z,1754584152.656 [BPC1](INFO): Calculating totals. Valid battery stick count: 42. Valid reserve battery stick count: 4.
2025-08-07T16:29:12.659Z,1754584152.659 [BPC1](INFO): Received data from all battery sticks.
2025-08-07T16:35:49.241Z,1754584549.241 [Radio_Surface](INFO): Powering up
2025-08-07T16:35:54.831Z,1754584554.831 [DataOverHttps](INFO): Radio surface powered ON.
2025-08-07T16:35:55.301Z,1754584555.301 [Radio_Surface](INFO): Powering down
2025-08-07T16:36:24.950Z,1754584584.950 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T16:36:25.954Z,1754584585.954 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-08-07T16:37:15.546Z,1754584635.546 [CTD_Seabird](ERROR): Failed to parse device response: 29.22,4
2025-08-07T16:38:30.280Z,1754584710.280 [profile_station:SurfaceComms] Running Loop=1
2025-08-07T16:38:30.280Z,1754584710.280 [profile_station:SurfaceComms](DEBUG): Aggregate::initialize profile_station:SurfaceComms
2025-08-07T16:38:30.281Z,1754584710.281 [profile_station:SurfaceComms:A] Running Loop=1
2025-08-07T16:38:30.281Z,1754584710.281 [profile_station:SurfaceComms:A] Stopped
2025-08-07T16:38:30.281Z,1754584710.281 [profile_station:SurfaceComms:B] Running Loop=1
2025-08-07T16:38:30.281Z,1754584710.281 [profile_station:SurfaceComms:B](DEBUG): Aggregate::initialize profile_station:SurfaceComms:B
2025-08-07T16:38:30.286Z,1754584710.286 [profile_station:NeedComms] Running Loop=1
2025-08-07T16:38:30.286Z,1754584710.286 [profile_station:NeedComms](DEBUG): Aggregate::initialize profile_station:NeedComms
2025-08-07T16:38:30.286Z,1754584710.286 [profile_station:NeedComms:B.GoToSurface] Running Loop=1
2025-08-07T16:38:30.286Z,1754584710.286 [profile_station:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-08-07T16:38:30.287Z,1754584710.287 [profile_station:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-08-07T16:38:30.287Z,1754584710.287 [profile_station:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-08-07T16:38:30.287Z,1754584710.287 [profile_station:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-08-07T16:38:30.288Z,1754584710.288 [profile_station:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-08-07T16:38:30.288Z,1754584710.288 [profile_station:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-08-07T16:38:30.288Z,1754584710.288 [profile_station:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-08-07T16:38:30.289Z,1754584710.289 [profile_station:NeedComms:A] Running Loop=1
2025-08-07T16:38:30.290Z,1754584710.290 [profile_station:NeedComms:A](INFO): last time_fix was:  1754582012.000000 second since 1970/01/01T00:00:00Z 
2025-08-07T16:38:30.291Z,1754584710.291 [profile_station:NeedComms:A] Stopped
2025-08-07T16:38:30.690Z,1754584710.690 [profile_station:CircleWrapper:Dive] Stopped
2025-08-07T16:38:30.690Z,1754584710.690 [profile_station:CircleWrapper:Dive](DEBUG): Aggregate::uninitialize profile_station:CircleWrapper:Dive
2025-08-07T16:38:30.690Z,1754584710.690 [profile_station:CircleWrapper:Dive:A] Stopped
2025-08-07T16:38:30.690Z,1754584710.690 [profile_station:CircleWrapper:Dive:A](DEBUG): Aggregate::uninitialize profile_station:CircleWrapper:Dive:A
2025-08-07T16:38:30.691Z,1754584710.691 [profile_station:DiveCmd:YoYoDive] Stopped
2025-08-07T16:38:30.691Z,1754584710.691 [profile_station:DiveCmd:YoYoDive](DEBUG): Aggregate::uninitialize profile_station:DiveCmd:YoYoDive
2025-08-07T16:38:30.691Z,1754584710.691 [profile_station:DiveCmd:YoYoDive:dive] Stopped
2025-08-07T16:38:30.691Z,1754584710.691 [profile_station:DiveCmd:YoYoDive:dive](DEBUG): Aggregate::uninitialize profile_station:DiveCmd:YoYoDive:dive
2025-08-07T16:38:30.691Z,1754584710.691 [profile_station:DiveCmd:YoYoDive:dive:A.Pitch] Stopped
2025-08-07T16:38:30.691Z,1754584710.691 [profile_station:DiveCmd:YoYoDive:dive:B.Buoyancy] Stopped
2025-08-07T16:38:30.691Z,1754584710.691 [profile_station:DiveCmd:YoYoDive:dive:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-08-07T16:38:30.691Z,1754584710.691 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed] Stopped
2025-08-07T16:38:30.691Z,1754584710.691 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed](DEBUG): Aggregate::uninitialize profile_station:DiveCmd:YoYoDive:dive:SetSpeed
2025-08-07T16:38:30.692Z,1754584710.692 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed] Stopped
2025-08-07T16:38:30.692Z,1754584710.692 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed](DEBUG): Aggregate::uninitialize profile_station:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed
2025-08-07T16:38:30.692Z,1754584710.692 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed:A.SetSpeed] Stopped
2025-08-07T16:38:30.692Z,1754584710.692 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed:A.SetSpeed](DEBUG): Uninitialize.
2025-08-07T16:38:30.692Z,1754584710.692 [profile_station:DiveCmd:YoYoDive:dive:D.DepthEnvelope] Stopped
2025-08-07T16:38:30.692Z,1754584710.692 [profile_station:DiveCmd:YoYoDive:dive:D.DepthEnvelope](DEBUG): Uninitialize.
2025-08-07T16:38:30.692Z,1754584710.692 [profile_station:DiveCmd:YoYoDive:dive:E.AltitudeEnvelope] Stopped
2025-08-07T16:38:30.692Z,1754584710.692 [profile_station:DiveCmd:YoYoDive:dive:E.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2025-08-07T16:38:30.692Z,1754584710.692 [profile_station:DiveCmd:YoYoDive:dive:F.YoYo] Stopped
2025-08-07T16:38:30.692Z,1754584710.692 [profile_station:DiveCmd:YoYoDive:dive:F.YoYo](DEBUG): Uninitialize YoYoComponent.
2025-08-07T16:38:42.233Z,1754584722.233 [ThrusterHE](FAULT): Could not get status:serial timeout
2025-08-07T16:38:42.321Z,1754584722.321 [ThrusterHE](FAULT): getSpeed error serial timeout
2025-08-07T16:38:42.321Z,1754584722.321 [ThrusterHE](FAULT): Thruster uart error: serial timeout
2025-08-07T16:38:42.321Z,1754584722.321 [ThrusterHE] Communications Fault, FailCount= 1
2025-08-07T16:38:42.321Z,1754584722.321 [ThrusterHE](ERROR): Communications Fault
2025-08-07T16:38:42.325Z,1754584722.325 [CBIT](ERROR): Communications Fault in component: ThrusterHE
2025-08-07T16:38:42.548Z,1754584722.548 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2025-08-07T16:38:42.548Z,1754584722.548 [ThrusterHE](INFO): Powering down
2025-08-07T16:38:43.443Z,1754584723.443 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-08-07T16:38:43.558Z,1754584723.558 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-08-07T16:38:43.562Z,1754584723.562 [CBIT](INFO): Clearing failed state for component ThrusterHE
2025-08-07T16:38:43.562Z,1754584723.562 [ThrusterHE] No Fault, FailCount= 1
2025-08-07T16:38:48.103Z,1754584728.103 [profile_station:NeedComms:C] Running Loop=1
2025-08-07T16:38:48.621Z,1754584728.621 [Radio_Surface](INFO): Powering up
2025-08-07T16:38:54.549Z,1754584734.549 [DataOverHttps](INFO): Radio surface powered ON.
2025-08-07T16:39:13.141Z,1754584753.141 [NAL9602](INFO): SBD MO Status=2, MOMSN=26705, MT Status=2, MTMSN=0
2025-08-07T16:39:13.141Z,1754584753.141 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-07T16:39:24.669Z,1754584764.669 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T16:39:55.821Z,1754584795.821 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T16:40:00.109Z,1754584800.109 [NAL9602](INFO): SBD MO Status=2, MOMSN=26705, MT Status=2, MTMSN=0
2025-08-07T16:40:00.109Z,1754584800.109 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-07T16:40:16.278Z,1754584816.278 [NAL9602](INFO): SBD MO Status=2, MOMSN=26705, MT Status=2, MTMSN=0
2025-08-07T16:40:16.279Z,1754584816.279 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-07T16:40:26.958Z,1754584826.958 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T16:40:31.629Z,1754584831.629 [NAL9602](INFO): SBD MO Status=1, MOMSN=26705, MT Status=0, MTMSN=0
2025-08-07T16:40:31.629Z,1754584831.629 [NAL9602](INFO): No messages in MT queue
2025-08-07T16:40:32.841Z,1754584832.841 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164035.00,A,3644.74084,N,12154.38980,W,0.933,155.31,070825,,,D*7D
2025-08-07T16:40:32.844Z,1754584832.844 [NAL9602](INFO): GPS fix at 20250807T164035: (36.745681, -121.906497)
2025-08-07T16:40:32.857Z,1754584832.857 [UniversalFixResidualReporter](INFO): Fix residual: 30.2 %DT, over the last 3303.8 m. Residual distance 998.1 m at bearing 42.9 degrees. Fix at (36.7457, -121.9065) with 2294.7 m made good.
2025-08-07T16:40:32.862Z,1754584832.862 [profile_station:NeedComms:C] Stopped
2025-08-07T16:40:32.862Z,1754584832.862 [profile_station:NeedComms:D] Running Loop=1
2025-08-07T16:40:50.785Z,1754584850.785 [NAL9602](INFO): SBD MO Status=1, MOMSN=26706, MT Status=0, MTMSN=0
2025-08-07T16:40:50.837Z,1754584850.837 [NAL9602](INFO): Sent 332 bytes from file Logs/20250807T155028/Courier0003.lzma
2025-08-07T16:40:50.837Z,1754584850.837 [NAL9602](INFO): Packets left to send: 1
2025-08-07T16:40:58.081Z,1754584858.081 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T16:41:08.261Z,1754584868.261 [NAL9602](INFO): SBD MO Status=2, MOMSN=26707, MT Status=2, MTMSN=0
2025-08-07T16:41:08.262Z,1754584868.262 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-07T16:41:29.205Z,1754584889.205 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T16:41:35.452Z,1754584895.452 [NAL9602](INFO): SBD MO Status=1, MOMSN=26707, MT Status=0, MTMSN=0
2025-08-07T16:41:35.502Z,1754584895.502 [NAL9602](INFO): Sent 36 bytes from file Logs/20250807T155028/Courier0003.lzma
2025-08-07T16:41:35.502Z,1754584895.502 [NAL9602](INFO): Packets left to send: 0
2025-08-07T16:41:44.862Z,1754584904.862 [NAL9602](INFO): SBD MO Status=1, MOMSN=26708, MT Status=0, MTMSN=0
2025-08-07T16:41:44.917Z,1754584904.917 [NAL9602](INFO): Sent 332 bytes from file Logs/20250807T155028/Express0004.lzma
2025-08-07T16:41:44.917Z,1754584904.917 [NAL9602](INFO): Packets left to send: 11
2025-08-07T16:41:56.298Z,1754584916.298 [NAL9602](INFO): SBD MO Status=1, MOMSN=26709, MT Status=0, MTMSN=0
2025-08-07T16:41:56.355Z,1754584916.355 [NAL9602](INFO): Sent 332 bytes from file Logs/20250807T155028/Express0004.lzma
2025-08-07T16:41:56.355Z,1754584916.355 [NAL9602](INFO): Packets left to send: 10
2025-08-07T16:42:00.333Z,1754584920.333 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T16:42:14.590Z,1754584934.590 [NAL9602](INFO): SBD MO Status=2, MOMSN=26710, MT Status=2, MTMSN=0
2025-08-07T16:42:14.590Z,1754584934.590 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-07T16:42:31.409Z,1754584951.409 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2025-08-07T16:42:50.271Z,1754584970.271 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004354
2025-08-07T16:42:58.065Z,1754584978.065 [NAL9602](INFO): SBD MO Status=1, MOMSN=26710, MT Status=0, MTMSN=0
2025-08-07T16:42:58.125Z,1754584978.125 [NAL9602](INFO): Sent 332 bytes from file Logs/20250807T155028/Express0004.lzma
2025-08-07T16:42:58.125Z,1754584978.125 [NAL9602](INFO): Packets left to send: 9
2025-08-07T16:42:58.869Z,1754584978.869 [DataOverHttps](INFO): Sending 3680 bytes from file Logs/20250807T155028/Express0004.lzma
2025-08-07T16:43:09.887Z,1754584989.887 [DataOverHttps](INFO): Moved sent file to Logs/20250807T155028/Express0004.lzma.bak
2025-08-07T16:43:09.887Z,1754584989.887 [DataOverHttps](INFO): SBD MOMSN=25803288
2025-08-07T16:43:11.018Z,1754584991.018 [profile_station:NeedComms:D] Stopped
2025-08-07T16:43:11.018Z,1754584991.018 [profile_station:NeedComms:E] Running Loop=1
2025-08-07T16:43:13.009Z,1754584993.009 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164315.00,A,3644.73435,N,12154.38128,W,0.564,137.52,070825,,,D*70
2025-08-07T16:43:13.011Z,1754584993.011 [NAL9602](INFO): GPS fix at 20250807T164315: (36.745573, -121.906355)
2025-08-07T16:43:13.066Z,1754584993.066 [profile_station:NeedComms:E] Stopped
2025-08-07T16:43:13.067Z,1754584993.067 [profile_station:NeedComms](INFO): Completed profile_station:NeedComms
2025-08-07T16:43:13.067Z,1754584993.067 [profile_station:NeedComms] Stopped
2025-08-07T16:43:13.067Z,1754584993.067 [profile_station:NeedComms](DEBUG): Aggregate::uninitialize profile_station:NeedComms
2025-08-07T16:43:13.067Z,1754584993.067 [profile_station:NeedComms:B.GoToSurface] Stopped
2025-08-07T16:43:13.067Z,1754584993.067 [profile_station:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-08-07T16:43:13.452Z,1754584993.452 [profile_station:SurfaceComms:B](INFO): Completed profile_station:SurfaceComms:B
2025-08-07T16:43:13.452Z,1754584993.452 [profile_station:SurfaceComms:B] Stopped
2025-08-07T16:43:13.452Z,1754584993.452 [profile_station:SurfaceComms:B](DEBUG): Aggregate::uninitialize profile_station:SurfaceComms:B
2025-08-07T16:43:13.452Z,1754584993.452 [profile_station:SurfaceComms:setInitial] Running Loop=1
2025-08-07T16:43:13.452Z,1754584993.452 [profile_station:SurfaceComms:setInitial](DEBUG): Aggregate::initialize profile_station:SurfaceComms:setInitial
2025-08-07T16:43:13.452Z,1754584993.452 [profile_station:SurfaceComms:setInitial:A] Running Loop=1
2025-08-07T16:43:13.849Z,1754584993.849 [profile_station:SurfaceComms:setInitial:A] Stopped
2025-08-07T16:43:13.850Z,1754584993.850 [profile_station:SurfaceComms:setInitial](INFO): Completed profile_station:SurfaceComms:setInitial
2025-08-07T16:43:13.850Z,1754584993.850 [profile_station:SurfaceComms:setInitial] Stopped
2025-08-07T16:43:13.850Z,1754584993.850 [profile_station:SurfaceComms:setInitial](DEBUG): Aggregate::uninitialize profile_station:SurfaceComms:setInitial
2025-08-07T16:43:13.850Z,1754584993.850 [profile_station:SurfaceComms:setSink] Running Loop=1
2025-08-07T16:43:13.850Z,1754584993.850 [profile_station:SurfaceComms:setSink](DEBUG): Aggregate::initialize profile_station:SurfaceComms:setSink
2025-08-07T16:43:13.850Z,1754584993.850 [profile_station:SurfaceComms:setSink:A] Running Loop=1
2025-08-07T16:43:14.275Z,1754584994.275 [profile_station:CircleWrapper:Dive] Running Loop=1
2025-08-07T16:43:14.275Z,1754584994.275 [profile_station:CircleWrapper:Dive](DEBUG): Aggregate::initialize profile_station:CircleWrapper:Dive
2025-08-07T16:43:14.275Z,1754584994.275 [profile_station:CircleWrapper:Dive:A] Running Loop=1
2025-08-07T16:43:14.275Z,1754584994.275 [profile_station:CircleWrapper:Dive:A](DEBUG): Aggregate::initialize profile_station:CircleWrapper:Dive:A
2025-08-07T16:43:14.276Z,1754584994.276 [profile_station:DiveCmd:YoYoDive] Running Loop=1
2025-08-07T16:43:14.276Z,1754584994.276 [profile_station:DiveCmd:YoYoDive](DEBUG): Aggregate::initialize profile_station:DiveCmd:YoYoDive
2025-08-07T16:43:14.276Z,1754584994.276 [profile_station:DiveCmd:YoYoDive:dive] Running Loop=1
2025-08-07T16:43:14.276Z,1754584994.276 [profile_station:DiveCmd:YoYoDive:dive](DEBUG): Aggregate::initialize profile_station:DiveCmd:YoYoDive:dive
2025-08-07T16:43:14.276Z,1754584994.276 [profile_station:DiveCmd:YoYoDive:dive:A.Pitch] Running Loop=1
2025-08-07T16:43:14.276Z,1754584994.276 [profile_station:DiveCmd:YoYoDive:dive:A.Pitch](DEBUG): Initialize.
2025-08-07T16:43:14.277Z,1754584994.277 [profile_station:DiveCmd:YoYoDive:dive:B.Buoyancy] Running Loop=1
2025-08-07T16:43:14.281Z,1754584994.281 [profile_station:DiveCmd:YoYoDive:dive:B.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-08-07T16:43:14.281Z,1754584994.281 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed] Running Loop=1
2025-08-07T16:43:14.281Z,1754584994.281 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed](DEBUG): Aggregate::initialize profile_station:DiveCmd:YoYoDive:dive:SetSpeed
2025-08-07T16:43:14.281Z,1754584994.281 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive] Running Loop=1
2025-08-07T16:43:14.281Z,1754584994.281 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive](DEBUG): Aggregate::initialize profile_station:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive
2025-08-07T16:43:14.281Z,1754584994.281 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed] Running Loop=1
2025-08-07T16:43:14.282Z,1754584994.282 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed](DEBUG): Aggregate::initialize profile_station:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed
2025-08-07T16:43:14.282Z,1754584994.282 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed:A.SetSpeed] Running Loop=1
2025-08-07T16:43:14.282Z,1754584994.282 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed:A.SetSpeed](DEBUG): Initialize.
2025-08-07T16:43:14.282Z,1754584994.282 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed] Running Loop=1
2025-08-07T16:43:14.282Z,1754584994.282 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed](DEBUG): Aggregate::initialize profile_station:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed
2025-08-07T16:43:14.282Z,1754584994.282 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed:A.SetSpeed] Running Loop=1
2025-08-07T16:43:14.282Z,1754584994.282 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed:A.SetSpeed](DEBUG): Initialize.
2025-08-07T16:43:14.282Z,1754584994.282 [profile_station:DiveCmd:YoYoDive:dive:D.DepthEnvelope] Running Loop=1
2025-08-07T16:43:14.282Z,1754584994.282 [profile_station:DiveCmd:YoYoDive:dive:D.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2025-08-07T16:43:14.283Z,1754584994.283 [profile_station:DiveCmd:YoYoDive:dive:E.AltitudeEnvelope] Running Loop=1
2025-08-07T16:43:14.283Z,1754584994.283 [profile_station:DiveCmd:YoYoDive:dive:E.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2025-08-07T16:43:14.283Z,1754584994.283 [profile_station:DiveCmd:YoYoDive:dive:F.YoYo] Running Loop=1
2025-08-07T16:43:14.283Z,1754584994.283 [profile_station:DiveCmd:YoYoDive:dive:F.YoYo](DEBUG): Initialize YoYoComponent.
2025-08-07T16:43:14.289Z,1754584994.289 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive] Stopped
2025-08-07T16:43:14.289Z,1754584994.289 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive](DEBUG): Aggregate::uninitialize profile_station:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive
2025-08-07T16:43:14.289Z,1754584994.289 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed] Stopped
2025-08-07T16:43:14.289Z,1754584994.289 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed](DEBUG): Aggregate::uninitialize profile_station:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed
2025-08-07T16:43:14.289Z,1754584994.289 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed:A.SetSpeed] Stopped
2025-08-07T16:43:14.289Z,1754584994.289 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed:A.SetSpeed](DEBUG): Uninitialize.
2025-08-07T16:43:14.290Z,1754584994.290 [profile_station:SurfaceComms:setSink] Stopped
2025-08-07T16:43:14.290Z,1754584994.290 [profile_station:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize profile_station:SurfaceComms:setSink
2025-08-07T16:43:14.290Z,1754584994.290 [profile_station:SurfaceComms:setSink:A] Stopped
2025-08-07T16:43:14.290Z,1754584994.290 [profile_station:SurfaceComms](INFO): Completed profile_station:SurfaceComms
2025-08-07T16:43:14.290Z,1754584994.290 [profile_station:SurfaceComms] Stopped
2025-08-07T16:43:14.291Z,1754584994.291 [profile_station:SurfaceComms](DEBUG): Aggregate::uninitialize profile_station:SurfaceComms
2025-08-07T16:43:45.720Z,1754585025.720 [NAL9602](INFO): Not Powering down - fast GPS
2025-08-07T16:44:12.069Z,1754585052.069 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T16:44:38.241Z,1754585078.241 [Radio_Surface](INFO): Powering down
2025-08-07T16:44:43.193Z,1754585083.193 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T16:44:44.210Z,1754585084.210 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-08-07T16:46:31.694Z,1754585191.694 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.807083
2025-08-07T16:52:57.462Z,1754585577.462 [CTD_Seabird](ERROR): Failed to parse device response: 
2025-08-07T17:20:38.327Z,1754587238.327 [CTD_Seabird](ERROR): Failed to parse device response: .50
2025-08-07T17:28:13.415Z,1754587693.415 [profile_station:SurfaceComms] Running Loop=1
2025-08-07T17:28:13.415Z,1754587693.415 [profile_station:SurfaceComms](DEBUG): Aggregate::initialize profile_station:SurfaceComms
2025-08-07T17:28:13.416Z,1754587693.416 [profile_station:SurfaceComms:A] Running Loop=1
2025-08-07T17:28:13.416Z,1754587693.416 [profile_station:SurfaceComms:A] Stopped
2025-08-07T17:28:13.416Z,1754587693.416 [profile_station:SurfaceComms:B] Running Loop=1
2025-08-07T17:28:13.416Z,1754587693.416 [profile_station:SurfaceComms:B](DEBUG): Aggregate::initialize profile_station:SurfaceComms:B
2025-08-07T17:28:13.818Z,1754587693.818 [profile_station:CircleWrapper:Dive] Stopped
2025-08-07T17:28:13.818Z,1754587693.818 [profile_station:CircleWrapper:Dive](DEBUG): Aggregate::uninitialize profile_station:CircleWrapper:Dive
2025-08-07T17:28:13.818Z,1754587693.818 [profile_station:CircleWrapper:Dive:A] Stopped
2025-08-07T17:28:13.819Z,1754587693.819 [profile_station:CircleWrapper:Dive:A](DEBUG): Aggregate::uninitialize profile_station:CircleWrapper:Dive:A
2025-08-07T17:28:13.819Z,1754587693.819 [profile_station:DiveCmd:YoYoDive] Stopped
2025-08-07T17:28:13.819Z,1754587693.819 [profile_station:DiveCmd:YoYoDive](DEBUG): Aggregate::uninitialize profile_station:DiveCmd:YoYoDive
2025-08-07T17:28:13.819Z,1754587693.819 [profile_station:DiveCmd:YoYoDive:dive] Stopped
2025-08-07T17:28:13.819Z,1754587693.819 [profile_station:DiveCmd:YoYoDive:dive](DEBUG): Aggregate::uninitialize profile_station:DiveCmd:YoYoDive:dive
2025-08-07T17:28:13.820Z,1754587693.820 [profile_station:DiveCmd:YoYoDive:dive:A.Pitch] Stopped
2025-08-07T17:28:13.820Z,1754587693.820 [profile_station:DiveCmd:YoYoDive:dive:B.Buoyancy] Stopped
2025-08-07T17:28:13.820Z,1754587693.820 [profile_station:DiveCmd:YoYoDive:dive:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-08-07T17:28:13.820Z,1754587693.820 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed] Stopped
2025-08-07T17:28:13.820Z,1754587693.820 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed](DEBUG): Aggregate::uninitialize profile_station:DiveCmd:YoYoDive:dive:SetSpeed
2025-08-07T17:28:13.820Z,1754587693.820 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed] Stopped
2025-08-07T17:28:13.820Z,1754587693.820 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed](DEBUG): Aggregate::uninitialize profile_station:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed
2025-08-07T17:28:13.820Z,1754587693.820 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed:A.SetSpeed] Stopped
2025-08-07T17:28:13.820Z,1754587693.820 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed:A.SetSpeed](DEBUG): Uninitialize.
2025-08-07T17:28:13.820Z,1754587693.820 [profile_station:DiveCmd:YoYoDive:dive:D.DepthEnvelope] Stopped
2025-08-07T17:28:13.820Z,1754587693.820 [profile_station:DiveCmd:YoYoDive:dive:D.DepthEnvelope](DEBUG): Uninitialize.
2025-08-07T17:28:13.820Z,1754587693.820 [profile_station:DiveCmd:YoYoDive:dive:E.AltitudeEnvelope] Stopped
2025-08-07T17:28:13.820Z,1754587693.820 [profile_station:DiveCmd:YoYoDive:dive:E.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2025-08-07T17:28:13.821Z,1754587693.821 [profile_station:DiveCmd:YoYoDive:dive:F.YoYo] Stopped
2025-08-07T17:28:13.821Z,1754587693.821 [profile_station:DiveCmd:YoYoDive:dive:F.YoYo](DEBUG): Uninitialize YoYoComponent.
2025-08-07T17:28:13.826Z,1754587693.826 [profile_station:NeedComms] Running Loop=1
2025-08-07T17:28:13.826Z,1754587693.826 [profile_station:NeedComms](DEBUG): Aggregate::initialize profile_station:NeedComms
2025-08-07T17:28:13.826Z,1754587693.826 [profile_station:NeedComms:B.GoToSurface] Running Loop=1
2025-08-07T17:28:13.826Z,1754587693.826 [profile_station:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-08-07T17:28:13.826Z,1754587693.826 [profile_station:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-08-07T17:28:13.827Z,1754587693.827 [profile_station:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-08-07T17:28:13.827Z,1754587693.827 [profile_station:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-08-07T17:28:13.827Z,1754587693.827 [profile_station:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-08-07T17:28:13.828Z,1754587693.828 [profile_station:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-08-07T17:28:13.828Z,1754587693.828 [profile_station:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-08-07T17:28:13.828Z,1754587693.828 [profile_station:NeedComms:A] Running Loop=1
2025-08-07T17:28:13.830Z,1754587693.830 [profile_station:NeedComms:A](INFO): last time_fix was:  1754584995.000000 second since 1970/01/01T00:00:00Z 
2025-08-07T17:28:13.830Z,1754587693.830 [profile_station:NeedComms:A] Stopped
2025-08-07T17:28:18.657Z,1754587698.657 [ThrusterHE](FAULT): Could not get status:serial timeout
2025-08-07T17:28:18.757Z,1754587698.757 [ThrusterHE](FAULT): getSpeed error serial timeout
2025-08-07T17:28:18.757Z,1754587698.757 [ThrusterHE](FAULT): Thruster uart error: serial timeout
2025-08-07T17:28:18.757Z,1754587698.757 [ThrusterHE] Communications Fault, FailCount= 1
2025-08-07T17:28:18.757Z,1754587698.757 [ThrusterHE](ERROR): Communications Fault
2025-08-07T17:28:18.761Z,1754587698.761 [CBIT](ERROR): Communications Fault in component: ThrusterHE
2025-08-07T17:28:18.931Z,1754587698.931 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2025-08-07T17:28:18.931Z,1754587698.931 [ThrusterHE](INFO): Powering down
2025-08-07T17:28:19.822Z,1754587699.822 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-08-07T17:28:19.949Z,1754587699.949 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-08-07T17:28:19.953Z,1754587699.953 [CBIT](INFO): Clearing failed state for component ThrusterHE
2025-08-07T17:28:19.953Z,1754587699.953 [ThrusterHE] No Fault, FailCount= 1
2025-08-07T17:28:22.127Z,1754587702.127 [ThrusterHE](ERROR): Internal error reported. Error codes are not defined in interface spec.
2025-08-07T17:28:22.127Z,1754587702.127 [ThrusterHE](FAULT): Thruster failed to initialize
2025-08-07T17:28:22.127Z,1754587702.127 [ThrusterHE] Communications Fault, FailCount= 2
2025-08-07T17:28:22.127Z,1754587702.127 [ThrusterHE](ERROR): Communications Fault
2025-08-07T17:28:22.131Z,1754587702.131 [CBIT](ERROR): Communications Fault in component: ThrusterHE
2025-08-07T17:28:22.650Z,1754587702.650 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2025-08-07T17:28:22.650Z,1754587702.650 [ThrusterHE](INFO): Powering down
2025-08-07T17:28:23.478Z,1754587703.478 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-08-07T17:28:23.600Z,1754587703.600 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-08-07T17:28:23.604Z,1754587703.604 [CBIT](INFO): Clearing failed state for component ThrusterHE
2025-08-07T17:28:23.604Z,1754587703.604 [ThrusterHE] No Fault, FailCount= 2
2025-08-07T17:29:10.575Z,1754587750.575 [profile_station:NeedComms:C] Running Loop=1
2025-08-07T17:29:11.241Z,1754587751.241 [Radio_Surface](INFO): Powering up
2025-08-07T17:29:17.369Z,1754587757.369 [DataOverHttps](INFO): Radio surface powered ON.
2025-08-07T17:29:27.998Z,1754587767.998 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=26711, MT Status=1, MTMSN=973
2025-08-07T17:29:27.998Z,1754587767.998 [NAL9602](INFO): Data available in MT queue
2025-08-07T17:29:28.489Z,1754587768.489 [NAL9602](INFO): Received command: stop
2025-08-07T17:29:28.590Z,1754587768.590 [CommandExec](IMPORTANT): got command stop
2025-08-07T17:29:28.590Z,1754587768.590 [CommandExec](IMPORTANT): Scheduling is paused
2025-08-07T17:29:28.590Z,1754587768.590 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop
2025-08-07T17:29:28.841Z,1754587768.841 [MissionManager](INFO): MissionManager is completed.
2025-08-07T17:29:28.841Z,1754587768.841 [MissionManager](INFO): Uninitializing Mission profile_station
2025-08-07T17:29:28.841Z,1754587768.841 [profile_station] Stopped
2025-08-07T17:29:28.841Z,1754587768.841 [profile_station](DEBUG): Aggregate::uninitialize profile_station
2025-08-07T17:29:28.841Z,1754587768.841 [profile_station:Science] Stopped
2025-08-07T17:29:28.841Z,1754587768.841 [profile_station:Science](DEBUG): Aggregate::uninitialize profile_station:Science
2025-08-07T17:29:28.841Z,1754587768.841 [profile_station:Science:B] Stopped
2025-08-07T17:29:28.842Z,1754587768.842 [profile_station:Science:C] Stopped
2025-08-07T17:29:28.842Z,1754587768.842 [profile_station:Science:E] Stopped
2025-08-07T17:29:28.842Z,1754587768.842 [profile_station:Science:OceanCurrent] Stopped
2025-08-07T17:29:28.842Z,1754587768.842 [profile_station:Science:OceanCurrent](DEBUG): Aggregate::uninitialize profile_station:Science:OceanCurrent
2025-08-07T17:29:28.842Z,1754587768.842 [profile_station:Science:OceanCurrent:A.] Stopped
2025-08-07T17:29:28.842Z,1754587768.842 [profile_station:Science:PeakDetectChl] Stopped
2025-08-07T17:29:28.842Z,1754587768.842 [profile_station:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectChl
2025-08-07T17:29:28.842Z,1754587768.842 [profile_station:Science:PeakDetectChl:B.PeakDetectVsDepth] Stopped
2025-08-07T17:29:28.842Z,1754587768.842 [profile_station:Science:PeakDetectChl:B.PeakDetectVsDepth](DEBUG): Uninitialize.
2025-08-07T17:29:28.842Z,1754587768.842 [profile_station:NeedComms] Stopped
2025-08-07T17:29:28.842Z,1754587768.842 [profile_station:NeedComms](DEBUG): Aggregate::uninitialize profile_station:NeedComms
2025-08-07T17:29:28.842Z,1754587768.842 [profile_station:NeedComms:B.GoToSurface] Stopped
2025-08-07T17:29:28.843Z,1754587768.843 [profile_station:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-08-07T17:29:28.843Z,1754587768.843 [profile_station:NeedComms:C] Stopped
2025-08-07T17:29:28.843Z,1754587768.843 [profile_station:StandardEnvelopes] Stopped
2025-08-07T17:29:28.843Z,1754587768.843 [profile_station:StandardEnvelopes](DEBUG): Aggregate::uninitialize profile_station:StandardEnvelopes
2025-08-07T17:29:28.843Z,1754587768.843 [profile_station:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2025-08-07T17:29:28.843Z,1754587768.843 [profile_station:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2025-08-07T17:29:28.843Z,1754587768.843 [profile_station:StandardEnvelopes:B.DepthEnvelope] Stopped
2025-08-07T17:29:28.843Z,1754587768.843 [profile_station:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2025-08-07T17:29:28.843Z,1754587768.843 [profile_station:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2025-08-07T17:29:28.843Z,1754587768.843 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2025-08-07T17:29:28.843Z,1754587768.843 [profile_station:BackseatDriver] Stopped
2025-08-07T17:29:28.843Z,1754587768.843 [profile_station:BackseatDriver](DEBUG): Aggregate::uninitialize profile_station:BackseatDriver
2025-08-07T17:29:28.843Z,1754587768.843 [profile_station:BackseatDriver:A.BackseatDriver] Stopped
2025-08-07T17:29:28.844Z,1754587768.844 [profile_station:PowerOnly] Stopped
2025-08-07T17:29:28.844Z,1754587768.844 [profile_station:PowerOnly](DEBUG): Aggregate::uninitialize profile_station:PowerOnly
2025-08-07T17:29:28.844Z,1754587768.844 [profile_station:PowerOnly:E.Wait] Stopped
2025-08-07T17:29:28.844Z,1754587768.844 [profile_station:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component.
2025-08-07T17:29:28.844Z,1754587768.844 [profile_station:SurfaceComms] Stopped
2025-08-07T17:29:28.844Z,1754587768.844 [profile_station:SurfaceComms](DEBUG): Aggregate::uninitialize profile_station:SurfaceComms
2025-08-07T17:29:28.844Z,1754587768.844 [profile_station:SurfaceComms:B] Stopped
2025-08-07T17:29:28.844Z,1754587768.844 [profile_station:SurfaceComms:B](DEBUG): Aggregate::uninitialize profile_station:SurfaceComms:B
2025-08-07T17:29:28.844Z,1754587768.844 [profile_station:DiveCmd] Stopped
2025-08-07T17:29:28.844Z,1754587768.844 [profile_station:DiveCmd](DEBUG): Aggregate::uninitialize profile_station:DiveCmd
2025-08-07T17:29:28.844Z,1754587768.844 [profile_station:CircleWrapper] Stopped
2025-08-07T17:29:28.845Z,1754587768.845 [profile_station:CircleWrapper](DEBUG): Aggregate::uninitialize profile_station:CircleWrapper
2025-08-07T17:29:28.845Z,1754587768.845 [profile_station:CircleWrapper:A] Stopped
2025-08-07T17:29:28.861Z,1754587768.861 [profile_station:CircleWrapper:C.Circle] Stopped
2025-08-07T17:29:28.861Z,1754587768.861 [profile_station:CircleWrapper:C.Circle](DEBUG): Uninitialize.
2025-08-07T17:29:29.074Z,1754587769.074 [BackseatComponent](INFO): Sent LCM shutdown request.
2025-08-07T17:29:29.075Z,1754587769.075 [BackseatComponent](INFO): Shutdown requested. Waiting (30.00 sec to power down.).
2025-08-07T17:29:29.250Z,1754587769.250 [MissionManager](IMPORTANT): Started mission Default
2025-08-07T17:29:29.250Z,1754587769.250 [Default] Running Loop=1
2025-08-07T17:29:29.250Z,1754587769.250 [Default](DEBUG): Aggregate::initialize Default
2025-08-07T17:29:29.250Z,1754587769.250 [Default:B.GoToSurface] Running Loop=1
2025-08-07T17:29:29.251Z,1754587769.251 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-08-07T17:29:29.251Z,1754587769.251 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-08-07T17:29:29.251Z,1754587769.251 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-08-07T17:29:29.251Z,1754587769.251 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-08-07T17:29:29.252Z,1754587769.252 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-08-07T17:29:29.252Z,1754587769.252 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-08-07T17:29:29.253Z,1754587769.253 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-08-07T17:29:29.253Z,1754587769.253 [Default:A.Wait] Running Loop=1
2025-08-07T17:29:29.253Z,1754587769.253 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-08-07T17:29:29.434Z,1754587769.434 [WetLabsBB2FL](INFO): Powering down
2025-08-07T17:29:29.625Z,1754587769.625 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172931.00,A,3645.59661,N,12153.22201,W,0.778,109.57,070825,,,A*77
2025-08-07T17:29:29.627Z,1754587769.627 [NAL9602](INFO): GPS fix at 20250807T172931: (36.759943, -121.887034)
2025-08-07T17:29:29.658Z,1754587769.658 [UniversalFixResidualReporter](INFO): Fix residual: 28.6 %DT, over the last 3326.6 m. Residual distance 952.1 m at bearing 46.6 degrees. Fix at (36.7599, -121.8870) with 2348.8 m made good.
2025-08-07T17:29:42.553Z,1754587782.553 [Default:A.Wait](INFO): Done Waiting.
2025-08-07T17:29:42.553Z,1754587782.553 [Default:A.Wait] Stopped
2025-08-07T17:29:42.554Z,1754587782.554 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-08-07T17:29:42.963Z,1754587782.963 [Default:CheckIn] Running Loop=1
2025-08-07T17:29:42.963Z,1754587782.963 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-08-07T17:29:42.963Z,1754587782.963 [Default:CheckIn:Read_GPS] Running Loop=1
2025-08-07T17:29:44.963Z,1754587784.963 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172947.00,A,3645.59506,N,12153.21828,W,0.544,110.51,070825,,,D*70
2025-08-07T17:29:44.966Z,1754587784.966 [NAL9602](INFO): GPS fix at 20250807T172947: (36.759918, -121.886971)
2025-08-07T17:29:44.995Z,1754587784.995 [Default:CheckIn:Read_GPS] Stopped
2025-08-07T17:29:44.995Z,1754587784.995 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-08-07T17:29:45.526Z,1754587785.526 [DAT](INFO): DAT read: Rx Time:17:29:46.9484

2025-08-07T17:29:45.527Z,1754587785.527 [DAT](INFO): Rx dataTimestamp_ set to:1754587785.526326
2025-08-07T17:29:45.527Z,1754587785.527 [DAT](INFO): DAT read: 

2025-08-07T17:29:45.528Z,1754587785.528 [DAT](INFO): DAT read: $Packet for address 0

2025-08-07T17:29:45.528Z,1754587785.528 [DAT](INFO): received a packet notification
2025-08-07T17:29:47.497Z,1754587787.497 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T17:29:59.093Z,1754587799.093 [BackseatComponent](INFO): Powering down and starting shutdown timer (30.00 sec).
2025-08-07T17:29:59.094Z,1754587799.094 [BackseatComponent](INFO): Unsubscribing from LCM channels.
2025-08-07T17:29:59.094Z,1754587799.094 [LcmUniversalReporter](INFO): Deactivating messaging.
2025-08-07T17:29:59.188Z,1754587799.188 [Depth_Keller](INFO): Deactivating messaging.
2025-08-07T17:30:02.428Z,1754587802.428 [NAL9602](INFO): SBD MO Status=1, MOMSN=26712, MT Status=0, MTMSN=0
2025-08-07T17:30:02.485Z,1754587802.485 [NAL9602](INFO): Sent 85 bytes from file Logs/20250807T155028/Courier0006.lzma
2025-08-07T17:30:02.485Z,1754587802.485 [NAL9602](INFO): Packets left to send: 0
2025-08-07T17:30:14.656Z,1754587814.656 [NAL9602](INFO): SBD MO Status=1, MOMSN=26713, MT Status=0, MTMSN=0
2025-08-07T17:30:14.709Z,1754587814.709 [NAL9602](INFO): Sent 332 bytes from file Logs/20250807T155028/Express0007.lzma
2025-08-07T17:30:14.709Z,1754587814.709 [NAL9602](INFO): Packets left to send: 8
2025-08-07T17:30:18.621Z,1754587818.621 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T17:30:19.991Z,1754587819.991 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:30:30.495Z,1754587830.495 [NAL9602](INFO): SBD MO Status=1, MOMSN=26714, MT Status=0, MTMSN=0
2025-08-07T17:30:30.545Z,1754587830.545 [NAL9602](INFO): Sent 332 bytes from file Logs/20250807T155028/Express0007.lzma
2025-08-07T17:30:30.546Z,1754587830.546 [NAL9602](INFO): Packets left to send: 7
2025-08-07T17:30:34.210Z,1754587834.210 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:30:47.936Z,1754587847.936 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:30:48.764Z,1754587848.764 [NAL9602](INFO): SBD MO Status=1, MOMSN=26715, MT Status=0, MTMSN=0
2025-08-07T17:30:48.814Z,1754587848.814 [NAL9602](INFO): Sent 332 bytes from file Logs/20250807T155028/Express0007.lzma
2025-08-07T17:30:48.815Z,1754587848.815 [NAL9602](INFO): Packets left to send: 6
2025-08-07T17:30:49.765Z,1754587849.765 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T17:31:12.666Z,1754587872.666 [NAL9602](INFO): SBD MO Status=2, MOMSN=26716, MT Status=2, MTMSN=0
2025-08-07T17:31:12.666Z,1754587872.666 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-07T17:31:20.889Z,1754587880.889 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T17:31:52.013Z,1754587912.013 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T17:31:54.356Z,1754587914.356 [NAL9602](INFO): SBD MO Status=2, MOMSN=26716, MT Status=2, MTMSN=0
2025-08-07T17:31:54.356Z,1754587914.356 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-07T17:32:21.504Z,1754587941.504 [NAL9602](INFO): SBD MO Status=2, MOMSN=26716, MT Status=2, MTMSN=0
2025-08-07T17:32:21.504Z,1754587941.504 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-07T17:32:23.146Z,1754587943.146 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T17:32:48.634Z,1754587968.634 [NAL9602](INFO): SBD MO Status=2, MOMSN=26716, MT Status=2, MTMSN=0
2025-08-07T17:32:48.634Z,1754587968.634 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-07T17:32:54.289Z,1754587974.289 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T17:33:09.708Z,1754587989.708 [NAL9602](INFO): SBD MO Status=1, MOMSN=26716, MT Status=0, MTMSN=0
2025-08-07T17:33:09.757Z,1754587989.757 [NAL9602](INFO): Sent 332 bytes from file Logs/20250807T155028/Express0007.lzma
2025-08-07T17:33:09.757Z,1754587989.757 [NAL9602](INFO): Packets left to send: 5
2025-08-07T17:33:13.178Z,1754587993.178 [DataOverHttps](IMPORTANT): SBD MTMSN=20250807T173315
2025-08-07T17:33:13.183Z,1754587993.183 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004139
2025-08-07T17:33:20.697Z,1754588000.697 [NAL9602](INFO): SBD MO Status=1, MOMSN=26717, MT Status=0, MTMSN=0
2025-08-07T17:33:20.749Z,1754588000.749 [NAL9602](INFO): Sent 332 bytes from file Logs/20250807T155028/Express0007.lzma
2025-08-07T17:33:20.749Z,1754588000.749 [NAL9602](INFO): Packets left to send: 4
2025-08-07T17:33:20.866Z,1754588000.866 [DataOverHttps](INFO): Sending 2676 bytes from file Logs/20250807T155028/Express0007.lzma
2025-08-07T17:33:20.889Z,1754588000.889 [DataOverHttps](INFO): Received command: stop
2025-08-07T17:33:20.934Z,1754588000.934 [CommandExec](IMPORTANT): got command stop
2025-08-07T17:33:24.734Z,1754588004.734 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:33:32.903Z,1754588012.903 [DataOverHttps](INFO): Moved sent file to Logs/20250807T155028/Express0007.lzma.bak
2025-08-07T17:33:32.903Z,1754588012.903 [DataOverHttps](INFO): SBD MOMSN=25803733
2025-08-07T17:33:39.681Z,1754588019.681 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:33:51.815Z,1754588031.815 [NAL9602](INFO): Not Powering down - fast GPS
2025-08-07T17:33:54.649Z,1754588034.649 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:33:59.937Z,1754588039.937 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250807T155028/Courier0009.lzma
2025-08-07T17:34:09.578Z,1754588049.578 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:34:10.979Z,1754588050.979 [DataOverHttps](INFO): Moved sent file to Logs/20250807T155028/Courier0009.lzma.bak
2025-08-07T17:34:10.979Z,1754588050.979 [DataOverHttps](INFO): SBD MOMSN=25803748
2025-08-07T17:34:24.526Z,1754588064.526 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:34:39.065Z,1754588079.065 [DataOverHttps](INFO): Sending 154 bytes from file Logs/20250807T155028/Express0010.lzma
2025-08-07T17:34:39.878Z,1754588079.878 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:34:50.107Z,1754588090.107 [DataOverHttps](INFO): Moved sent file to Logs/20250807T155028/Express0010.lzma.bak
2025-08-07T17:34:50.107Z,1754588090.107 [DataOverHttps](INFO): SBD MOMSN=25803752
2025-08-07T17:34:54.844Z,1754588094.844 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:35:10.205Z,1754588110.205 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:35:20.760Z,1754588120.760 [DataOverHttps](INFO): Sending 53 bytes from file Logs/20250807T155028/Express0011.lzma
2025-08-07T17:35:25.143Z,1754588125.143 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:35:31.803Z,1754588131.803 [DataOverHttps](INFO): Moved sent file to Logs/20250807T155028/Express0011.lzma.bak
2025-08-07T17:35:31.803Z,1754588131.803 [DataOverHttps](INFO): SBD MOMSN=25803755
2025-08-07T17:35:39.701Z,1754588139.701 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7).
2025-08-07T17:35:57.933Z,1754588157.933 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20250807T155028/Express0013.lzma
2025-08-07T17:36:08.975Z,1754588168.975 [DataOverHttps](INFO): Moved sent file to Logs/20250807T155028/Express0013.lzma.bak
2025-08-07T17:36:08.975Z,1754588168.975 [DataOverHttps](INFO): SBD MOMSN=25803758
2025-08-07T17:36:10.438Z,1754588170.438 [Default:CheckIn:Read_Iridium] Stopped
2025-08-07T17:36:10.439Z,1754588170.439 [Default:CheckIn:C.Wait] Running Loop=1
2025-08-07T17:36:10.439Z,1754588170.439 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-08-07T17:36:41.100Z,1754588201.100 [BPC1](ERROR): BPC1A failed to parse battery stick message.
2025-08-07T17:38:01.316Z,1754588281.316 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-08-07T17:38:07.616Z,1754588287.616 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:38:21.756Z,1754588301.756 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:38:35.090Z,1754588315.090 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:38:48.848Z,1754588328.848 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:39:03.013Z,1754588343.013 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:39:03.014Z,1754588343.014 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-08-07T17:39:17.153Z,1754588357.153 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:39:30.890Z,1754588370.890 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:39:44.625Z,1754588384.625 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:39:58.362Z,1754588398.362 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:40:11.693Z,1754588411.693 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:41:11.135Z,1754588471.135 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-08-07T17:41:11.135Z,1754588471.135 [Default:CheckIn:C.Wait] Stopped
2025-08-07T17:41:11.135Z,1754588471.135 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-08-07T17:41:11.136Z,1754588471.136 [Default:CheckIn:D] Running Loop=1
2025-08-07T17:41:11.534Z,1754588471.534 [Default:CheckIn:D] Stopped
2025-08-07T17:41:11.534Z,1754588471.534 [Default:CheckIn:E] Running Loop=1
2025-08-07T17:41:11.933Z,1754588471.933 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  11.704721 min 
2025-08-07T17:41:11.933Z,1754588471.933 [Default:CheckIn:E] Stopped
2025-08-07T17:41:11.933Z,1754588471.933 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-08-07T17:41:11.933Z,1754588471.933 [Default:CheckIn] Stopped
2025-08-07T17:41:11.933Z,1754588471.933 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-08-07T17:41:11.934Z,1754588471.934 [Default:CheckIn](INFO): Running loop #2
2025-08-07T17:41:11.934Z,1754588471.934 [Default:CheckIn] Running Loop=2
2025-08-07T17:41:11.934Z,1754588471.934 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-08-07T17:41:11.934Z,1754588471.934 [Default:CheckIn:Read_GPS] Running Loop=1
2025-08-07T17:41:13.948Z,1754588473.948 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174116.00,A,3645.54874,N,12153.14496,W,0.797,157.81,070825,,,D*72
2025-08-07T17:41:13.950Z,1754588473.950 [NAL9602](INFO): GPS fix at 20250807T174116: (36.759146, -121.885749)
2025-08-07T17:41:13.962Z,1754588473.962 [Default:CheckIn:Read_GPS] Stopped
2025-08-07T17:41:13.962Z,1754588473.962 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-08-07T17:41:16.209Z,1754588476.209 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2025-08-07T17:41:41.008Z,1754588501.008 [NAL9602](INFO): SBD MO Status=0, MOMSN=26718, MT Status=0, MTMSN=0
2025-08-07T17:41:41.008Z,1754588501.008 [NAL9602](INFO): No messages in MT queue
2025-08-07T17:41:45.698Z,1754588505.698 [DAT](INFO): DAT read: Rx Time:17:41:47.2155

2025-08-07T17:41:45.699Z,1754588505.699 [DAT](INFO): Rx dataTimestamp_ set to:1754588505.698251
2025-08-07T17:41:46.706Z,1754588506.706 [DAT](INFO): DAT read: $Error in header

2025-08-07T17:41:46.706Z,1754588506.706 [DAT](INFO): Received a bad header
2025-08-07T17:41:46.997Z,1754588506.997 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20250807T155028/Courier0015.lzma
2025-08-07T17:41:47.997Z,1754588507.997 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2025-08-07T17:41:55.429Z,1754588515.429 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20250807T155028/Courier0015.lzma
2025-08-07T17:42:06.479Z,1754588526.479 [DataOverHttps](INFO): Moved sent file to Logs/20250807T155028/Courier0015.lzma.bak
2025-08-07T17:42:06.479Z,1754588526.479 [DataOverHttps](INFO): SBD MOMSN=25803769
2025-08-07T17:42:11.714Z,1754588531.714 [NAL9602](INFO): Not Powering down - fast GPS
2025-08-07T17:42:28.276Z,1754588548.276 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2025-08-07T17:42:30.865Z,1754588550.865 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20250807T155028/Express0016.lzma
2025-08-07T17:42:41.907Z,1754588561.907 [DataOverHttps](INFO): Moved sent file to Logs/20250807T155028/Express0016.lzma.bak
2025-08-07T17:42:41.907Z,1754588561.907 [DataOverHttps](INFO): SBD MOMSN=25803774
2025-08-07T17:42:43.240Z,1754588563.240 [Default:CheckIn:Read_Iridium] Stopped
2025-08-07T17:42:43.240Z,1754588563.240 [Default:CheckIn:C.Wait] Running Loop=1
2025-08-07T17:42:43.240Z,1754588563.240 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-08-07T17:42:48.088Z,1754588568.088 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:43:03.077Z,1754588583.077 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:43:18.026Z,1754588598.026 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:43:33.782Z,1754588613.782 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:43:48.848Z,1754588628.848 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:44:04.083Z,1754588644.083 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:44:19.433Z,1754588659.433 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:44:34.785Z,1754588674.785 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:44:50.158Z,1754588690.158 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:47:26.983Z,1754588846.983 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:47:40.720Z,1754588860.720 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:47:43.963Z,1754588863.963 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-08-07T17:47:43.963Z,1754588863.963 [Default:CheckIn:C.Wait] Stopped
2025-08-07T17:47:43.963Z,1754588863.963 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-08-07T17:47:43.963Z,1754588863.963 [Default:CheckIn:D] Running Loop=1
2025-08-07T17:47:44.374Z,1754588864.374 [Default:CheckIn:D] Stopped
2025-08-07T17:47:44.374Z,1754588864.374 [Default:CheckIn:E] Running Loop=1
2025-08-07T17:47:44.796Z,1754588864.796 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  18.252053 min 
2025-08-07T17:47:44.796Z,1754588864.796 [Default:CheckIn:E] Stopped
2025-08-07T17:47:44.796Z,1754588864.796 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-08-07T17:47:44.796Z,1754588864.796 [Default:CheckIn] Stopped
2025-08-07T17:47:44.796Z,1754588864.796 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-08-07T17:47:44.796Z,1754588864.796 [Default:CheckIn](INFO): Running loop #3
2025-08-07T17:47:44.797Z,1754588864.797 [Default:CheckIn] Running Loop=3
2025-08-07T17:47:44.797Z,1754588864.797 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-08-07T17:47:44.797Z,1754588864.797 [Default:CheckIn:Read_GPS] Running Loop=1
2025-08-07T17:47:46.844Z,1754588866.844 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174749.00,A,3645.51895,N,12153.10354,W,0.175,119.59,070825,,,D*7C
2025-08-07T17:47:46.846Z,1754588866.846 [NAL9602](INFO): GPS fix at 20250807T174749: (36.758649, -121.885059)
2025-08-07T17:47:46.878Z,1754588866.878 [Default:CheckIn:Read_GPS] Stopped
2025-08-07T17:47:46.878Z,1754588866.878 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-08-07T17:47:54.000Z,1754588874.000 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250807T155028/Courier0018.lzma
2025-08-07T17:47:54.456Z,1754588874.456 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:48:00.582Z,1754588880.582 [NAL9602](INFO): SBD MO Status=0, MOMSN=26719, MT Status=0, MTMSN=0
2025-08-07T17:48:00.582Z,1754588880.582 [NAL9602](INFO): No messages in MT queue
2025-08-07T17:48:06.047Z,1754588886.047 [DataOverHttps](INFO): Moved sent file to Logs/20250807T155028/Courier0018.lzma.bak
2025-08-07T17:48:06.047Z,1754588886.047 [DataOverHttps](INFO): SBD MOMSN=25803837
2025-08-07T17:48:08.190Z,1754588888.190 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:48:22.329Z,1754588902.329 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:48:31.218Z,1754588911.218 [NAL9602](INFO): Not Powering down - fast GPS
2025-08-07T17:48:32.193Z,1754588912.193 [DataOverHttps](INFO): Sending 174 bytes from file Logs/20250807T155028/Express0019.lzma
2025-08-07T17:48:36.873Z,1754588916.873 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:48:43.235Z,1754588923.235 [DataOverHttps](INFO): Moved sent file to Logs/20250807T155028/Express0019.lzma.bak
2025-08-07T17:48:43.235Z,1754588923.235 [DataOverHttps](INFO): SBD MOMSN=25803844
2025-08-07T17:48:44.573Z,1754588924.573 [Default:CheckIn:Read_Iridium] Stopped
2025-08-07T17:48:44.573Z,1754588924.573 [Default:CheckIn:C.Wait] Running Loop=1
2025-08-07T17:48:44.573Z,1754588924.573 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-08-07T17:48:51.060Z,1754588931.060 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:49:04.809Z,1754588944.809 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:49:18.544Z,1754588958.544 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:49:32.686Z,1754588972.686 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:52:07.538Z,1754589127.538 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:52:22.418Z,1754589142.418 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:52:37.772Z,1754589157.772 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:52:52.717Z,1754589172.717 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:53:07.260Z,1754589187.260 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:53:22.210Z,1754589202.210 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:53:37.561Z,1754589217.561 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:53:45.257Z,1754589225.257 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-08-07T17:53:45.257Z,1754589225.257 [Default:CheckIn:C.Wait] Stopped
2025-08-07T17:53:45.257Z,1754589225.257 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-08-07T17:53:45.258Z,1754589225.258 [Default:CheckIn:D] Running Loop=1
2025-08-07T17:53:45.669Z,1754589225.669 [Default:CheckIn:D] Stopped
2025-08-07T17:53:45.669Z,1754589225.669 [Default:CheckIn:E] Running Loop=1
2025-08-07T17:53:46.061Z,1754589226.061 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  24.273649 min 
2025-08-07T17:53:46.061Z,1754589226.061 [Default:CheckIn:E] Stopped
2025-08-07T17:53:46.061Z,1754589226.061 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-08-07T17:53:46.061Z,1754589226.061 [Default:CheckIn] Stopped
2025-08-07T17:53:46.062Z,1754589226.062 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-08-07T17:53:46.062Z,1754589226.062 [Default:CheckIn](INFO): Running loop #4
2025-08-07T17:53:46.062Z,1754589226.062 [Default:CheckIn] Running Loop=4
2025-08-07T17:53:46.062Z,1754589226.062 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-08-07T17:53:46.062Z,1754589226.062 [Default:CheckIn:Read_GPS] Running Loop=1
2025-08-07T17:53:48.068Z,1754589228.068 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175350.00,A,3645.48856,N,12153.06908,W,1.147,144.03,070825,,,D*75
2025-08-07T17:53:48.071Z,1754589228.071 [NAL9602](INFO): GPS fix at 20250807T175350: (36.758143, -121.884485)
2025-08-07T17:53:48.083Z,1754589228.083 [Default:CheckIn:Read_GPS] Stopped
2025-08-07T17:53:48.083Z,1754589228.083 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-08-07T17:53:52.508Z,1754589232.508 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:53:56.434Z,1754589236.434 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250807T155028/Courier0021.lzma
2025-08-07T17:54:03.828Z,1754589243.828 [NAL9602](INFO): SBD MO Status=0, MOMSN=26720, MT Status=0, MTMSN=0
2025-08-07T17:54:03.828Z,1754589243.828 [NAL9602](INFO): No messages in MT queue
2025-08-07T17:54:07.457Z,1754589247.457 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:54:07.475Z,1754589247.475 [DataOverHttps](INFO): Moved sent file to Logs/20250807T155028/Courier0021.lzma.bak
2025-08-07T17:54:07.475Z,1754589247.475 [DataOverHttps](INFO): SBD MOMSN=25803901
2025-08-07T17:54:22.406Z,1754589262.406 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:54:34.530Z,1754589274.530 [NAL9602](INFO): Not Powering down - fast GPS
2025-08-07T17:54:36.617Z,1754589276.617 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20250807T155028/Express0022.lzma
2025-08-07T17:54:48.663Z,1754589288.663 [DataOverHttps](INFO): Moved sent file to Logs/20250807T155028/Express0022.lzma.bak
2025-08-07T17:54:48.663Z,1754589288.663 [DataOverHttps](INFO): SBD MOMSN=25803907
2025-08-07T17:54:49.909Z,1754589289.909 [Default:CheckIn:Read_Iridium] Stopped
2025-08-07T17:54:49.909Z,1754589289.909 [Default:CheckIn:C.Wait] Running Loop=1
2025-08-07T17:54:49.909Z,1754589289.909 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-08-07T17:56:49.913Z,1754589409.913 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:57:03.247Z,1754589423.247 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:57:16.980Z,1754589436.980 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:57:31.130Z,1754589451.130 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:57:45.291Z,1754589465.291 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:57:53.185Z,1754589473.185 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T17:57:58.998Z,1754589478.998 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:58:12.733Z,1754589492.733 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:58:24.333Z,1754589504.333 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T17:58:26.470Z,1754589506.470 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:58:40.610Z,1754589520.610 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:58:54.346Z,1754589534.346 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T17:59:26.597Z,1754589566.597 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T17:59:50.526Z,1754589590.526 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-08-07T17:59:50.526Z,1754589590.526 [Default:CheckIn:C.Wait] Stopped
2025-08-07T17:59:50.526Z,1754589590.526 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-08-07T17:59:50.526Z,1754589590.526 [Default:CheckIn:D] Running Loop=1
2025-08-07T17:59:50.923Z,1754589590.923 [Default:CheckIn:D] Stopped
2025-08-07T17:59:50.924Z,1754589590.924 [Default:CheckIn:E] Running Loop=1
2025-08-07T17:59:51.341Z,1754589591.341 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  30.361216 min 
2025-08-07T17:59:51.342Z,1754589591.342 [Default:CheckIn:E] Stopped
2025-08-07T17:59:51.342Z,1754589591.342 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-08-07T17:59:51.342Z,1754589591.342 [Default:CheckIn] Stopped
2025-08-07T17:59:51.342Z,1754589591.342 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-08-07T17:59:51.342Z,1754589591.342 [Default:CheckIn](INFO): Running loop #5
2025-08-07T17:59:51.342Z,1754589591.342 [Default:CheckIn] Running Loop=5
2025-08-07T17:59:51.342Z,1754589591.342 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-08-07T17:59:51.342Z,1754589591.342 [Default:CheckIn:Read_GPS] Running Loop=1
2025-08-07T17:59:53.348Z,1754589593.348 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175955.00,A,3645.45972,N,12153.04455,W,0.816,124.89,070825,,,D*7F
2025-08-07T17:59:53.351Z,1754589593.351 [NAL9602](INFO): GPS fix at 20250807T175955: (36.757662, -121.884076)
2025-08-07T17:59:53.380Z,1754589593.380 [Default:CheckIn:Read_GPS] Stopped
2025-08-07T17:59:53.380Z,1754589593.380 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-08-07T17:59:57.729Z,1754589597.729 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T18:00:13.794Z,1754589613.794 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.767395
2025-08-07T18:00:13.936Z,1754589613.936 [BPC1](ERROR): Battery stick #10 (s/n: 00C4) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-08-07T18:00:13.937Z,1754589613.937 [BPC1](ERROR): Battery stick #16 (s/n: 00F8) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-08-07T18:00:13.938Z,1754589613.938 [BPC1](ERROR): Battery stick #24 (s/n: 01D3) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-08-07T18:00:13.939Z,1754589613.939 [BPC1](ERROR): Battery stick #29 (s/n: 00A7) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-08-07T18:00:13.940Z,1754589613.940 [BPC1](ERROR): Battery stick #51 (s/n: 01D6) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-08-07T18:00:13.940Z,1754589613.940 [BPC1](ERROR): Battery stick #57 (s/n: 019F) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-08-07T18:00:13.941Z,1754589613.941 [BPC1](ERROR): Battery stick #59 (s/n: 0184) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AC0.
2025-08-07T18:00:13.942Z,1754589613.942 [BPC1](INFO): Calculating totals. Valid battery stick count: 41. Valid reserve battery stick count: 4.
2025-08-07T18:00:13.946Z,1754589613.946 [BPC1](FAULT): Failed to receive data from 11 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 9, 10, 11, 14, 24, 29, 35, 51, 53, 57, 59.
2025-08-07T18:00:41.004Z,1754589641.004 [NAL9602](INFO): SBD MO Status=2, MOMSN=26721, MT Status=2, MTMSN=0
2025-08-07T18:00:41.004Z,1754589641.004 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-07T18:00:44.919Z,1754589644.919 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T18:01:01.205Z,1754589661.205 [NAL9602](INFO): SBD MO Status=2, MOMSN=26721, MT Status=2, MTMSN=0
2025-08-07T18:01:01.205Z,1754589661.205 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-07T18:01:12.112Z,1754589672.112 [NAL9602](INFO): SBD MO Status=0, MOMSN=26721, MT Status=0, MTMSN=0
2025-08-07T18:01:12.113Z,1754589672.113 [NAL9602](INFO): No messages in MT queue
2025-08-07T18:01:16.041Z,1754589676.041 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T18:01:32.769Z,1754589692.769 [NAL9602](INFO): SBD MO Status=1, MOMSN=26722, MT Status=0, MTMSN=0
2025-08-07T18:01:32.825Z,1754589692.825 [NAL9602](INFO): Sent 73 bytes from file Logs/20250807T155028/Courier0024.lzma
2025-08-07T18:01:32.825Z,1754589692.825 [NAL9602](INFO): Packets left to send: 0
2025-08-07T18:01:42.936Z,1754589702.936 [NAL9602](INFO): SBD MO Status=1, MOMSN=26723, MT Status=0, MTMSN=0
2025-08-07T18:01:42.985Z,1754589702.985 [NAL9602](INFO): Sent 174 bytes from file Logs/20250807T155028/Express0025.lzma
2025-08-07T18:01:42.986Z,1754589702.986 [NAL9602](INFO): Packets left to send: 0
2025-08-07T18:01:47.717Z,1754589707.717 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2025-08-07T18:01:50.613Z,1754589710.613 [NAL9602](INFO): SBD MO Status=0, MOMSN=26724, MT Status=0, MTMSN=0
2025-08-07T18:01:50.694Z,1754589710.694 [Default:CheckIn:Read_Iridium] Stopped
2025-08-07T18:01:50.695Z,1754589710.695 [Default:CheckIn:C.Wait] Running Loop=1
2025-08-07T18:01:50.695Z,1754589710.695 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-08-07T18:02:06.151Z,1754589726.151 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003843
2025-08-07T18:02:21.314Z,1754589741.314 [NAL9602](INFO): Not Powering down - fast GPS
2025-08-07T18:04:51.669Z,1754589891.669 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2025-08-07T18:05:22.815Z,1754589922.815 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T18:05:53.939Z,1754589953.939 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T18:06:25.062Z,1754589985.062 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T18:06:51.200Z,1754590011.200 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-08-07T18:06:51.200Z,1754590011.200 [Default:CheckIn:C.Wait] Stopped
2025-08-07T18:06:51.200Z,1754590011.200 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-08-07T18:06:51.201Z,1754590011.201 [Default:CheckIn:D] Running Loop=1
2025-08-07T18:06:51.606Z,1754590011.606 [Default:CheckIn:D] Stopped
2025-08-07T18:06:51.606Z,1754590011.606 [Default:CheckIn:E] Running Loop=1
2025-08-07T18:06:52.008Z,1754590012.008 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  37.372595 min 
2025-08-07T18:06:52.008Z,1754590012.008 [Default:CheckIn:E] Stopped
2025-08-07T18:06:52.008Z,1754590012.008 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-08-07T18:06:52.008Z,1754590012.008 [Default:CheckIn] Stopped
2025-08-07T18:06:52.008Z,1754590012.008 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-08-07T18:06:52.008Z,1754590012.008 [Default:CheckIn](INFO): Running loop #6
2025-08-07T18:06:52.008Z,1754590012.008 [Default:CheckIn] Running Loop=6
2025-08-07T18:06:52.008Z,1754590012.008 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-08-07T18:06:52.009Z,1754590012.009 [Default:CheckIn:Read_GPS] Running Loop=1
2025-08-07T18:06:54.016Z,1754590014.016 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180656.00,A,3645.42518,N,12153.01200,W,0.700,152.92,070825,,,D*7E
2025-08-07T18:06:54.027Z,1754590014.027 [NAL9602](INFO): GPS fix at 20250807T180656: (36.757086, -121.883533)
2025-08-07T18:06:54.038Z,1754590014.038 [Default:CheckIn:Read_GPS] Stopped
2025-08-07T18:06:54.038Z,1754590014.038 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-08-07T18:06:56.185Z,1754590016.185 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T18:07:09.238Z,1754590029.238 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.295532
2025-08-07T18:07:40.361Z,1754590060.361 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T18:07:50.176Z,1754590070.176 [NAL9602](INFO): SBD MO Status=2, MOMSN=26725, MT Status=2, MTMSN=0
2025-08-07T18:07:50.176Z,1754590070.176 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-07T18:08:08.356Z,1754590088.356 [NAL9602](INFO): SBD MO Status=0, MOMSN=26725, MT Status=0, MTMSN=0
2025-08-07T18:08:08.356Z,1754590088.356 [NAL9602](INFO): No messages in MT queue
2025-08-07T18:08:11.485Z,1754590091.485 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T18:08:31.420Z,1754590111.420 [NAL9602](INFO): SBD MO Status=2, MOMSN=26726, MT Status=2, MTMSN=0
2025-08-07T18:08:31.421Z,1754590111.421 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-07T18:08:42.610Z,1754590122.610 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T18:09:06.627Z,1754590146.627 [NAL9602](INFO): SBD MO Status=1, MOMSN=26726, MT Status=0, MTMSN=0
2025-08-07T18:09:06.681Z,1754590146.681 [NAL9602](INFO): Sent 84 bytes from file Logs/20250807T155028/Courier0027.lzma
2025-08-07T18:09:06.681Z,1754590146.681 [NAL9602](INFO): Packets left to send: 0
2025-08-07T18:09:13.749Z,1754590153.749 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T18:09:24.464Z,1754590164.464 [NAL9602](INFO): SBD MO Status=2, MOMSN=26727, MT Status=2, MTMSN=0
2025-08-07T18:09:24.464Z,1754590164.464 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-07T18:09:44.881Z,1754590184.881 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T18:09:54.812Z,1754590194.812 [NAL9602](INFO): SBD MO Status=2, MOMSN=26727, MT Status=2, MTMSN=0
2025-08-07T18:09:54.812Z,1754590194.812 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-07T18:10:16.005Z,1754590216.005 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T18:10:45.364Z,1754590245.364 [NAL9602](INFO): SBD MO Status=2, MOMSN=26727, MT Status=2, MTMSN=0
2025-08-07T18:10:45.364Z,1754590245.364 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-07T18:10:47.129Z,1754590247.129 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T18:11:10.457Z,1754590270.457 [NAL9602](INFO): SBD MO Status=1, MOMSN=26727, MT Status=0, MTMSN=0
2025-08-07T18:11:10.517Z,1754590270.517 [NAL9602](INFO): Sent 297 bytes from file Logs/20250807T155028/Express0028.lzma
2025-08-07T18:11:10.517Z,1754590270.517 [NAL9602](INFO): Packets left to send: 0
2025-08-07T18:11:18.253Z,1754590278.253 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T18:11:22.186Z,1754590282.186 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T18:11:29.446Z,1754590289.446 [NAL9602](INFO): SBD MO Status=0, MOMSN=26728, MT Status=0, MTMSN=0
2025-08-07T18:11:29.530Z,1754590289.530 [Default:CheckIn:Read_Iridium] Stopped
2025-08-07T18:11:29.530Z,1754590289.530 [Default:CheckIn:C.Wait] Running Loop=1
2025-08-07T18:11:29.530Z,1754590289.530 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-08-07T18:11:37.521Z,1754590297.521 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T18:11:49.377Z,1754590309.377 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T18:11:52.468Z,1754590312.468 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T18:12:00.148Z,1754590320.148 [NAL9602](INFO): Not Powering down - fast GPS
2025-08-07T18:12:07.822Z,1754590327.822 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T18:12:20.509Z,1754590340.509 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T18:12:23.177Z,1754590343.177 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T18:12:38.123Z,1754590358.123 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T18:12:51.641Z,1754590371.641 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T18:12:53.474Z,1754590373.474 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T18:13:08.421Z,1754590388.421 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7).
2025-08-07T18:13:22.765Z,1754590402.765 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T18:13:53.901Z,1754590433.901 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T18:14:25.025Z,1754590465.025 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T18:14:56.157Z,1754590496.157 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T18:15:27.283Z,1754590527.283 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T18:15:35.479Z,1754590535.479 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T18:15:49.212Z,1754590549.212 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T18:15:58.405Z,1754590558.405 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T18:16:02.950Z,1754590562.950 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T18:16:16.687Z,1754590576.687 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T18:16:29.529Z,1754590589.529 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T18:16:30.044Z,1754590590.044 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-08-07T18:16:30.044Z,1754590590.044 [Default:CheckIn:C.Wait] Stopped
2025-08-07T18:16:30.045Z,1754590590.045 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-08-07T18:16:30.045Z,1754590590.045 [Default:CheckIn:D] Running Loop=1
2025-08-07T18:16:30.480Z,1754590590.480 [Default:CheckIn:D] Stopped
2025-08-07T18:16:30.480Z,1754590590.480 [Default:CheckIn:E] Running Loop=1
2025-08-07T18:16:30.826Z,1754590590.826 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T18:16:30.849Z,1754590590.849 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  47.020496 min 
2025-08-07T18:16:30.850Z,1754590590.850 [Default:CheckIn:E] Stopped
2025-08-07T18:16:30.850Z,1754590590.850 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-08-07T18:16:30.850Z,1754590590.850 [Default:CheckIn] Stopped
2025-08-07T18:16:30.850Z,1754590590.850 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-08-07T18:16:30.850Z,1754590590.850 [Default:CheckIn](INFO): Running loop #7
2025-08-07T18:16:30.850Z,1754590590.850 [Default:CheckIn] Running Loop=7
2025-08-07T18:16:30.850Z,1754590590.850 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-08-07T18:16:30.850Z,1754590590.850 [Default:CheckIn:Read_GPS] Running Loop=1
2025-08-07T18:16:32.849Z,1754590592.849 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181635.00,A,3645.38027,N,12152.97225,W,0.622,128.14,070825,,,D*75
2025-08-07T18:16:32.852Z,1754590592.852 [NAL9602](INFO): GPS fix at 20250807T181635: (36.756338, -121.882871)
2025-08-07T18:16:32.864Z,1754590592.864 [Default:CheckIn:Read_GPS] Stopped
2025-08-07T18:16:32.864Z,1754590592.864 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-08-07T18:16:45.826Z,1754590605.826 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T18:16:56.360Z,1754590616.360 [NAL9602](INFO): SBD MO Status=1, MOMSN=26729, MT Status=0, MTMSN=0
2025-08-07T18:16:56.409Z,1754590616.409 [NAL9602](INFO): Sent 73 bytes from file Logs/20250807T155028/Courier0030.lzma
2025-08-07T18:16:56.409Z,1754590616.409 [NAL9602](INFO): Packets left to send: 0
2025-08-07T18:17:00.009Z,1754590620.009 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T18:17:00.673Z,1754590620.673 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T18:17:10.922Z,1754590630.922 [NAL9602](INFO): SBD MO Status=1, MOMSN=26730, MT Status=0, MTMSN=0
2025-08-07T18:17:10.975Z,1754590630.975 [NAL9602](INFO): Sent 173 bytes from file Logs/20250807T155028/Express0031.lzma
2025-08-07T18:17:10.975Z,1754590630.975 [NAL9602](INFO): Packets left to send: 0
2025-08-07T18:17:13.745Z,1754590633.745 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T18:17:19.816Z,1754590639.816 [NAL9602](INFO): SBD MO Status=0, MOMSN=26731, MT Status=0, MTMSN=0
2025-08-07T18:17:19.904Z,1754590639.904 [Default:CheckIn:Read_Iridium] Stopped
2025-08-07T18:17:19.904Z,1754590639.904 [Default:CheckIn:C.Wait] Running Loop=1
2025-08-07T18:17:19.904Z,1754590639.904 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-08-07T18:17:27.885Z,1754590647.885 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T18:17:31.797Z,1754590651.797 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T18:17:41.217Z,1754590661.217 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T18:17:50.511Z,1754590670.511 [NAL9602](INFO): Not Powering down - fast GPS
2025-08-07T18:18:02.946Z,1754590682.946 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T18:18:34.069Z,1754590714.069 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T18:19:05.193Z,1754590745.193 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T18:19:36.317Z,1754590776.317 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T18:20:07.441Z,1754590807.441 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T18:20:16.354Z,1754590816.354 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T18:20:31.304Z,1754590831.304 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T18:20:38.565Z,1754590838.565 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-07T18:20:46.658Z,1754590846.658 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T18:20:57.718Z,1754590857.718 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003881
2025-08-07T18:21:02.005Z,1754590862.005 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T18:21:16.965Z,1754590876.965 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T18:21:31.900Z,1754590891.900 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T18:21:47.252Z,1754590907.252 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T18:22:02.606Z,1754590922.606 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T18:22:17.149Z,1754590937.149 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-07T18:22:20.415Z,1754590940.415 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-08-07T18:22:20.415Z,1754590940.415 [Default:CheckIn:C.Wait] Stopped
2025-08-07T18:22:20.415Z,1754590940.415 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-08-07T18:22:20.415Z,1754590940.415 [Default:CheckIn:D] Running Loop=1
2025-08-07T18:22:20.805Z,1754590940.805 [Default:CheckIn:D] Stopped
2025-08-07T18:22:20.805Z,1754590940.805 [Default:CheckIn:E] Running Loop=1
2025-08-07T18:22:21.202Z,1754590941.202 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  52.859241 min 
2025-08-07T18:22:21.203Z,1754590941.203 [Default:CheckIn:E] Stopped
2025-08-07T18:22:21.203Z,1754590941.203 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-08-07T18:22:21.203Z,1754590941.203 [Default:CheckIn] Stopped
2025-08-07T18:22:21.203Z,1754590941.203 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-08-07T18:22:21.203Z,1754590941.203 [Default:CheckIn](INFO): Running loop #8
2025-08-07T18:22:21.203Z,1754590941.203 [Default:CheckIn] Running Loop=8
2025-08-07T18:22:21.203Z,1754590941.203 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-08-07T18:22:21.203Z,1754590941.203 [Default:CheckIn:Read_GPS] Running Loop=1
2025-08-07T18:22:23.220Z,1754590943.220 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182225.00,A,3645.36133,N,12152.92083,W,3.285,95.45,070825,,,D*4B
2025-08-07T18:22:23.222Z,1754590943.222 [NAL9602](INFO): GPS fix at 20250807T182225: (36.756022, -121.882014)
2025-08-07T18:22:23.249Z,1754590943.249 [Default:CheckIn:Read_GPS] Stopped
2025-08-07T18:22:23.249Z,1754590943.249 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-08-07T18:22:31.694Z,1754590951.694 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7).
2025-08-07T18:22:41.796Z,1754590961.796 [NAL9602](INFO): SBD MO Status=0, MOMSN=26732, MT Status=0, MTMSN=0
2025-08-07T18:22:41.796Z,1754590961.796 [NAL9602](INFO): No messages in MT queue
2025-08-07T18:22:43.309Z,1754590963.309 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250807T155028/Courier0033.lzma
2025-08-07T18:22:55.363Z,1754590975.363 [DataOverHttps](INFO): Moved sent file to Logs/20250807T155028/Courier0033.lzma.bak
2025-08-07T18:22:55.363Z,1754590975.363 [DataOverHttps](INFO): SBD MOMSN=25804109
2025-08-07T18:23:12.506Z,1754590992.506 [NAL9602](INFO): Not Powering down - fast GPS
2025-08-07T18:23:21.906Z,1754591001.906 [DataOverHttps](IMPORTANT): SBD MTMSN=20250807T182323
2025-08-07T18:23:29.465Z,1754591009.465 [DataOverHttps](INFO): Sending 175 bytes from file Logs/20250807T155028/Express0034.lzma
2025-08-07T18:23:29.468Z,1754591009.468 [DataOverHttps](INFO): Received command: restart logs