2024-03-13T17:09:06.273Z,1710349746.273 [CommandExec](IMPORTANT): got command restart logs 2024-03-13T17:11:46.911Z,1710349906.911 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-13T17:11:46.911Z,1710349906.911 [Default:CheckIn:C.Wait] Stopped 2024-03-13T17:11:46.911Z,1710349906.911 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-13T17:11:46.911Z,1710349906.911 [Default:CheckIn:D] Running Loop=1 2024-03-13T17:11:47.303Z,1710349907.303 [Default:CheckIn:D] Stopped 2024-03-13T17:11:47.303Z,1710349907.303 [Default:CheckIn:E] Running Loop=1 2024-03-13T17:11:47.711Z,1710349907.711 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 20.184088 min 2024-03-13T17:11:47.712Z,1710349907.712 [Default:CheckIn:E] Stopped 2024-03-13T17:11:47.712Z,1710349907.712 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-13T17:11:47.712Z,1710349907.712 [Default:CheckIn] Stopped 2024-03-13T17:11:47.712Z,1710349907.712 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-13T17:11:47.712Z,1710349907.712 [Default:CheckIn](INFO): Running loop #4 2024-03-13T17:11:47.712Z,1710349907.712 [Default:CheckIn] Running Loop=4 2024-03-13T17:11:47.713Z,1710349907.713 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-13T17:11:47.713Z,1710349907.713 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-13T17:11:49.723Z,1710349909.723 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171149.00,A,3648.42511,N,12149.48577,W,0.330,35.64,130324,,,D*4D 2024-03-13T17:11:49.725Z,1710349909.725 [NAL9602](INFO): GPS fix at 20240313T171149: (36.807085, -121.824763) 2024-03-13T17:11:49.761Z,1710349909.761 [Default:CheckIn:Read_GPS] Stopped 2024-03-13T17:11:49.761Z,1710349909.761 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-13T17:12:12.679Z,1710349932.679 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240312T202806/Courier0153.lzma 2024-03-13T17:12:13.682Z,1710349933.682 [DataOverHttps](INFO): Moved sent file to Logs/20240312T202806/Courier0153.lzma.bak 2024-03-13T17:12:13.682Z,1710349933.682 [DataOverHttps](INFO): SBD MOMSN=19317432 2024-03-13T17:12:16.787Z,1710349936.787 [NAL9602](INFO): SBD MO Status=0, MOMSN=47756, MT Status=0, MTMSN=0 2024-03-13T17:12:16.787Z,1710349936.787 [NAL9602](INFO): No messages in MT queue 2024-03-13T17:12:47.493Z,1710349967.493 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-13T17:13:16.281Z,1710349996.281 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-03-13T17:13:47.418Z,1710350027.418 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-03-13T17:14:18.541Z,1710350058.541 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-03-13T17:14:58.308Z,1710350098.308 [DataOverHttps](INFO): Sending 267 bytes from file Logs/20240313T170906/Courier0000.lzma 2024-03-13T17:14:59.310Z,1710350099.310 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Courier0000.lzma.bak 2024-03-13T17:14:59.310Z,1710350099.310 [DataOverHttps](INFO): SBD MOMSN=19317441 2024-03-13T17:15:15.344Z,1710350115.344 [DataOverHttps](INFO): Sending 105 bytes from file Logs/20240312T202806/Express0154.lzma 2024-03-13T17:15:16.346Z,1710350116.346 [DataOverHttps](INFO): Moved sent file to Logs/20240312T202806/Express0154.lzma.bak 2024-03-13T17:15:16.347Z,1710350116.347 [DataOverHttps](INFO): SBD MOMSN=19317445 2024-03-13T17:15:42.121Z,1710350142.121 [DataOverHttps](INFO): Sending 398 bytes from file Logs/20240313T170906/Express0001.lzma 2024-03-13T17:15:43.122Z,1710350143.122 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Express0001.lzma.bak 2024-03-13T17:15:43.122Z,1710350143.122 [DataOverHttps](INFO): SBD MOMSN=19317448 2024-03-13T17:15:58.868Z,1710350158.868 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20240313T170906/Express0004.lzma 2024-03-13T17:15:59.870Z,1710350159.870 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Express0004.lzma.bak 2024-03-13T17:15:59.870Z,1710350159.870 [DataOverHttps](INFO): SBD MOMSN=19317452 2024-03-13T17:16:01.092Z,1710350161.092 [Default:CheckIn:Read_Iridium] Stopped 2024-03-13T17:16:01.093Z,1710350161.093 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-13T17:16:01.093Z,1710350161.093 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-13T17:20:35.988Z,1710350435.988 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-03-13T17:21:01.677Z,1710350461.677 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-13T17:21:01.677Z,1710350461.677 [Default:CheckIn:C.Wait] Stopped 2024-03-13T17:21:01.677Z,1710350461.677 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-13T17:21:01.677Z,1710350461.677 [Default:CheckIn:D] Running Loop=1 2024-03-13T17:21:02.091Z,1710350462.091 [Default:CheckIn:D] Stopped 2024-03-13T17:21:02.091Z,1710350462.091 [Default:CheckIn:E] Running Loop=1 2024-03-13T17:21:02.487Z,1710350462.487 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 29.430556 min 2024-03-13T17:21:02.487Z,1710350462.487 [Default:CheckIn:E] Stopped 2024-03-13T17:21:02.488Z,1710350462.488 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-13T17:21:02.488Z,1710350462.488 [Default:CheckIn] Stopped 2024-03-13T17:21:02.488Z,1710350462.488 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-13T17:21:02.488Z,1710350462.488 [Default:CheckIn](INFO): Running loop #5 2024-03-13T17:21:02.488Z,1710350462.488 [Default:CheckIn] Running Loop=5 2024-03-13T17:21:02.488Z,1710350462.488 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-13T17:21:02.488Z,1710350462.488 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-13T17:21:04.499Z,1710350464.499 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172103.00,A,3648.45102,N,12149.47236,W,0.875,51.78,130324,,,D*49 2024-03-13T17:21:04.501Z,1710350464.501 [NAL9602](INFO): GPS fix at 20240313T172103: (36.807517, -121.824539) 2024-03-13T17:21:04.513Z,1710350464.513 [Default:CheckIn:Read_GPS] Stopped 2024-03-13T17:21:04.513Z,1710350464.513 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-13T17:21:07.113Z,1710350467.113 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-03-13T17:21:26.315Z,1710350486.315 [NAL9602](INFO): SBD MO Status=0, MOMSN=47757, MT Status=0, MTMSN=0 2024-03-13T17:21:26.315Z,1710350486.315 [NAL9602](INFO): No messages in MT queue 2024-03-13T17:21:38.238Z,1710350498.238 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-03-13T17:21:57.017Z,1710350517.017 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-13T17:22:09.368Z,1710350529.368 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-03-13T17:22:11.377Z,1710350531.377 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.047379 2024-03-13T17:22:24.933Z,1710350544.933 [NAL9602](INFO): SBD MO Status=1, MOMSN=47758, MT Status=0, MTMSN=0 2024-03-13T17:22:24.988Z,1710350544.988 [NAL9602](INFO): Sent 72 bytes from file Logs/20240313T170906/Courier0006.lzma 2024-03-13T17:22:24.989Z,1710350544.989 [NAL9602](INFO): Packets left to send: 0 2024-03-13T17:22:36.699Z,1710350556.699 [NAL9602](INFO): SBD MO Status=1, MOMSN=47759, MT Status=0, MTMSN=0 2024-03-13T17:22:36.754Z,1710350556.754 [NAL9602](INFO): Sent 143 bytes from file Logs/20240313T170906/Express0007.lzma 2024-03-13T17:22:36.754Z,1710350556.754 [NAL9602](INFO): Packets left to send: 0 2024-03-13T17:22:42.513Z,1710350562.513 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-03-13T17:22:55.687Z,1710350575.687 [NAL9602](INFO): SBD MO Status=2, MOMSN=47760, MT Status=2, MTMSN=0 2024-03-13T17:22:55.687Z,1710350575.687 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-03-13T17:23:06.192Z,1710350586.192 [NAL9602](INFO): SBD MO Status=0, MOMSN=47760, MT Status=0, MTMSN=0 2024-03-13T17:23:06.270Z,1710350586.270 [Default:CheckIn:Read_Iridium] Stopped 2024-03-13T17:23:06.270Z,1710350586.270 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-13T17:23:06.270Z,1710350586.270 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-13T17:23:13.636Z,1710350593.636 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-03-13T17:23:36.892Z,1710350616.892 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-13T17:23:44.761Z,1710350624.761 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-03-13T17:24:15.917Z,1710350655.917 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-03-13T17:24:47.041Z,1710350687.041 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-03-13T17:25:18.164Z,1710350718.164 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-03-13T17:25:49.289Z,1710350749.289 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-03-13T17:26:20.420Z,1710350780.420 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-03-13T17:27:08.429Z,1710350828.429 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002953 2024-03-13T17:28:06.776Z,1710350886.776 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-13T17:28:06.777Z,1710350886.777 [Default:CheckIn:C.Wait] Stopped 2024-03-13T17:28:06.777Z,1710350886.777 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-13T17:28:06.777Z,1710350886.777 [Default:CheckIn:D] Running Loop=1 2024-03-13T17:28:07.179Z,1710350887.179 [Default:CheckIn:D] Stopped 2024-03-13T17:28:07.179Z,1710350887.179 [Default:CheckIn:E] Running Loop=1 2024-03-13T17:28:07.587Z,1710350887.588 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 36.515352 min 2024-03-13T17:28:07.588Z,1710350887.588 [Default:CheckIn:E] Stopped 2024-03-13T17:28:07.588Z,1710350887.588 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-13T17:28:07.588Z,1710350887.588 [Default:CheckIn] Stopped 2024-03-13T17:28:07.589Z,1710350887.589 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-13T17:28:07.589Z,1710350887.589 [Default:CheckIn](INFO): Running loop #6 2024-03-13T17:28:07.589Z,1710350887.589 [Default:CheckIn] Running Loop=6 2024-03-13T17:28:07.589Z,1710350887.589 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-13T17:28:07.589Z,1710350887.589 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-13T17:28:09.596Z,1710350889.596 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172808.00,A,3648.55154,N,12149.49605,W,3.130,282.42,130324,,,D*7D 2024-03-13T17:28:09.599Z,1710350889.599 [NAL9602](INFO): GPS fix at 20240313T172808: (36.809192, -121.824934) 2024-03-13T17:28:09.637Z,1710350889.637 [Default:CheckIn:Read_GPS] Stopped 2024-03-13T17:28:09.638Z,1710350889.638 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-13T17:28:19.668Z,1710350899.668 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240313T170906/Courier0009.lzma 2024-03-13T17:28:20.670Z,1710350900.670 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Courier0009.lzma.bak 2024-03-13T17:28:20.670Z,1710350900.670 [DataOverHttps](INFO): SBD MOMSN=19317492 2024-03-13T17:28:22.527Z,1710350902.527 [NAL9602](INFO): SBD MO Status=0, MOMSN=47761, MT Status=0, MTMSN=0 2024-03-13T17:28:22.527Z,1710350902.527 [NAL9602](INFO): No messages in MT queue 2024-03-13T17:28:36.608Z,1710350916.608 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20240313T170906/Express0010.lzma 2024-03-13T17:28:37.610Z,1710350917.610 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Express0010.lzma.bak 2024-03-13T17:28:37.610Z,1710350917.610 [DataOverHttps](INFO): SBD MOMSN=19317507 2024-03-13T17:28:38.709Z,1710350918.709 [Default:CheckIn:Read_Iridium] Stopped 2024-03-13T17:28:38.709Z,1710350918.709 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-13T17:28:38.709Z,1710350918.709 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-13T17:28:53.225Z,1710350933.225 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-13T17:33:39.300Z,1710351219.300 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-13T17:33:39.301Z,1710351219.301 [Default:CheckIn:C.Wait] Stopped 2024-03-13T17:33:39.301Z,1710351219.301 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-13T17:33:39.301Z,1710351219.301 [Default:CheckIn:D] Running Loop=1 2024-03-13T17:33:39.717Z,1710351219.717 [Default:CheckIn:D] Stopped 2024-03-13T17:33:39.717Z,1710351219.717 [Default:CheckIn:E] Running Loop=1 2024-03-13T17:33:40.125Z,1710351220.125 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.057646 min 2024-03-13T17:33:40.125Z,1710351220.125 [Default:CheckIn:E] Stopped 2024-03-13T17:33:40.125Z,1710351220.125 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-13T17:33:40.125Z,1710351220.125 [Default:CheckIn] Stopped 2024-03-13T17:33:40.126Z,1710351220.126 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-13T17:33:40.126Z,1710351220.126 [Default:CheckIn](INFO): Running loop #7 2024-03-13T17:33:40.126Z,1710351220.126 [Default:CheckIn] Running Loop=7 2024-03-13T17:33:40.126Z,1710351220.126 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-13T17:33:40.126Z,1710351220.126 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-13T17:33:42.123Z,1710351222.123 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173341.00,A,3648.55999,N,12149.44515,W,1.769,160.05,130324,,,D*78 2024-03-13T17:33:42.126Z,1710351222.126 [NAL9602](INFO): GPS fix at 20240313T173341: (36.809333, -121.824086) 2024-03-13T17:33:42.136Z,1710351222.136 [Default:CheckIn:Read_GPS] Stopped 2024-03-13T17:33:42.137Z,1710351222.137 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-13T17:33:50.143Z,1710351230.143 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240313T170906/Courier0012.lzma 2024-03-13T17:33:51.146Z,1710351231.146 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Courier0012.lzma.bak 2024-03-13T17:33:51.146Z,1710351231.146 [DataOverHttps](INFO): SBD MOMSN=19317519 2024-03-13T17:34:05.955Z,1710351245.955 [NAL9602](INFO): SBD MO Status=0, MOMSN=47762, MT Status=0, MTMSN=0 2024-03-13T17:34:05.955Z,1710351245.955 [NAL9602](INFO): No messages in MT queue 2024-03-13T17:34:25.936Z,1710351265.936 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20240313T170906/Express0013.lzma 2024-03-13T17:34:26.940Z,1710351266.940 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Express0013.lzma.bak 2024-03-13T17:34:26.941Z,1710351266.941 [DataOverHttps](INFO): SBD MOMSN=19317525 2024-03-13T17:34:28.195Z,1710351268.195 [Default:CheckIn:Read_Iridium] Stopped 2024-03-13T17:34:28.196Z,1710351268.196 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-13T17:34:28.196Z,1710351268.196 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-13T17:34:36.657Z,1710351276.657 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-13T17:39:28.853Z,1710351568.853 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-13T17:39:28.853Z,1710351568.853 [Default:CheckIn:C.Wait] Stopped 2024-03-13T17:39:28.853Z,1710351568.853 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-13T17:39:28.854Z,1710351568.854 [Default:CheckIn:D] Running Loop=1 2024-03-13T17:39:29.248Z,1710351569.248 [Default:CheckIn:D] Stopped 2024-03-13T17:39:29.248Z,1710351569.248 [Default:CheckIn:E] Running Loop=1 2024-03-13T17:39:29.643Z,1710351569.643 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 47.883158 min 2024-03-13T17:39:29.643Z,1710351569.643 [Default:CheckIn:E] Stopped 2024-03-13T17:39:29.643Z,1710351569.643 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-13T17:39:29.643Z,1710351569.643 [Default:CheckIn] Stopped 2024-03-13T17:39:29.643Z,1710351569.643 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-13T17:39:29.644Z,1710351569.644 [Default:CheckIn](INFO): Running loop #8 2024-03-13T17:39:29.644Z,1710351569.644 [Default:CheckIn] Running Loop=8 2024-03-13T17:39:29.644Z,1710351569.644 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-13T17:39:29.644Z,1710351569.644 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-13T17:39:31.667Z,1710351571.667 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173930.00,A,3648.60719,N,12149.46046,W,0.661,21.46,130324,,,D*4E 2024-03-13T17:39:31.669Z,1710351571.669 [NAL9602](INFO): GPS fix at 20240313T173930: (36.810120, -121.824341) 2024-03-13T17:39:31.680Z,1710351571.680 [Default:CheckIn:Read_GPS] Stopped 2024-03-13T17:39:31.680Z,1710351571.680 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-13T17:39:50.652Z,1710351590.652 [NAL9602](INFO): SBD MO Status=0, MOMSN=47763, MT Status=0, MTMSN=0 2024-03-13T17:39:50.653Z,1710351590.653 [NAL9602](INFO): No messages in MT queue 2024-03-13T17:39:52.892Z,1710351592.892 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240313T170906/Courier0015.lzma 2024-03-13T17:39:53.894Z,1710351593.894 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Courier0015.lzma.bak 2024-03-13T17:39:53.894Z,1710351593.894 [DataOverHttps](INFO): SBD MOMSN=19317550 2024-03-13T17:40:09.984Z,1710351609.984 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20240313T170906/Express0016.lzma 2024-03-13T17:40:10.986Z,1710351610.986 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Express0016.lzma.bak 2024-03-13T17:40:10.986Z,1710351610.986 [DataOverHttps](INFO): SBD MOMSN=19317554 2024-03-13T17:40:12.095Z,1710351612.095 [Default:CheckIn:Read_Iridium] Stopped 2024-03-13T17:40:12.095Z,1710351612.095 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-13T17:40:12.096Z,1710351612.096 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-13T17:40:21.377Z,1710351621.377 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-13T17:44:46.996Z,1710351886.996 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-03-13T17:45:12.695Z,1710351912.695 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-13T17:45:12.695Z,1710351912.695 [Default:CheckIn:C.Wait] Stopped 2024-03-13T17:45:12.695Z,1710351912.695 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-13T17:45:12.696Z,1710351912.696 [Default:CheckIn:D] Running Loop=1 2024-03-13T17:45:13.094Z,1710351913.094 [Default:CheckIn:D] Stopped 2024-03-13T17:45:13.094Z,1710351913.094 [Default:CheckIn:E] Running Loop=1 2024-03-13T17:45:13.507Z,1710351913.507 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 53.613932 min 2024-03-13T17:45:13.507Z,1710351913.507 [Default:CheckIn:E] Stopped 2024-03-13T17:45:13.508Z,1710351913.508 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-13T17:45:13.508Z,1710351913.508 [Default:CheckIn] Stopped 2024-03-13T17:45:13.508Z,1710351913.508 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-13T17:45:13.508Z,1710351913.508 [Default:CheckIn](INFO): Running loop #9 2024-03-13T17:45:13.508Z,1710351913.508 [Default:CheckIn] Running Loop=9 2024-03-13T17:45:13.508Z,1710351913.508 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-13T17:45:13.508Z,1710351913.508 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-13T17:45:15.521Z,1710351915.521 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174514.00,A,3648.61298,N,12149.31618,W,3.285,90.84,130324,,,D*4A 2024-03-13T17:45:15.523Z,1710351915.523 [NAL9602](INFO): GPS fix at 20240313T174514: (36.810216, -121.821936) 2024-03-13T17:45:15.534Z,1710351915.534 [Default:CheckIn:Read_GPS] Stopped 2024-03-13T17:45:15.534Z,1710351915.534 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-13T17:45:18.121Z,1710351918.121 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-03-13T17:45:36.723Z,1710351936.723 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240313T170906/Courier0018.lzma 2024-03-13T17:45:36.922Z,1710351936.922 [NAL9602](INFO): SBD MO Status=0, MOMSN=47764, MT Status=0, MTMSN=0 2024-03-13T17:45:36.922Z,1710351936.922 [NAL9602](INFO): No messages in MT queue 2024-03-13T17:45:37.726Z,1710351937.726 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Courier0018.lzma.bak 2024-03-13T17:45:37.726Z,1710351937.726 [DataOverHttps](INFO): SBD MOMSN=19317563 2024-03-13T17:45:53.432Z,1710351953.432 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20240313T170906/Express0019.lzma 2024-03-13T17:45:54.434Z,1710351954.434 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Express0019.lzma.bak 2024-03-13T17:45:54.434Z,1710351954.434 [DataOverHttps](INFO): SBD MOMSN=19317566 2024-03-13T17:45:55.934Z,1710351955.934 [Default:CheckIn:Read_Iridium] Stopped 2024-03-13T17:45:55.934Z,1710351955.934 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-13T17:45:55.934Z,1710351955.934 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-13T17:46:07.625Z,1710351967.625 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-13T17:49:02.959Z,1710352142.959 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-13T17:49:15.483Z,1710352155.483 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-13T17:49:28.413Z,1710352168.413 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-13T17:49:41.743Z,1710352181.743 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-13T17:49:53.863Z,1710352193.863 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-13T17:50:07.195Z,1710352207.195 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-13T17:50:20.528Z,1710352220.528 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-13T17:50:56.509Z,1710352256.509 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-13T17:50:56.509Z,1710352256.509 [Default:CheckIn:C.Wait] Stopped 2024-03-13T17:50:56.509Z,1710352256.509 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-13T17:50:56.510Z,1710352256.510 [Default:CheckIn:D] Running Loop=1 2024-03-13T17:50:56.899Z,1710352256.899 [Default:CheckIn:D] Stopped 2024-03-13T17:50:56.899Z,1710352256.899 [Default:CheckIn:E] Running Loop=1 2024-03-13T17:50:57.308Z,1710352257.308 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 59.344010 min 2024-03-13T17:50:57.308Z,1710352257.308 [Default:CheckIn:E] Stopped 2024-03-13T17:50:57.308Z,1710352257.308 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-13T17:50:57.308Z,1710352257.308 [Default:CheckIn] Stopped 2024-03-13T17:50:57.308Z,1710352257.308 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-13T17:50:57.308Z,1710352257.308 [Default:CheckIn](INFO): Running loop #10 2024-03-13T17:50:57.308Z,1710352257.308 [Default:CheckIn] Running Loop=10 2024-03-13T17:50:57.309Z,1710352257.309 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-13T17:50:57.309Z,1710352257.309 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-13T17:50:59.316Z,1710352259.316 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175058.00,A,3648.54799,N,12148.78060,W,3.479,84.47,130324,,,D*4E 2024-03-13T17:50:59.319Z,1710352259.319 [NAL9602](INFO): GPS fix at 20240313T175058: (36.809133, -121.813010) 2024-03-13T17:50:59.329Z,1710352259.329 [Default:CheckIn:Read_GPS] Stopped 2024-03-13T17:50:59.329Z,1710352259.329 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-13T17:51:17.919Z,1710352277.919 [NAL9602](INFO): SBD MO Status=0, MOMSN=47765, MT Status=0, MTMSN=0 2024-03-13T17:51:17.920Z,1710352277.920 [NAL9602](INFO): No messages in MT queue 2024-03-13T17:51:48.609Z,1710352308.609 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-13T17:52:02.440Z,1710352322.440 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-03-13T17:52:42.624Z,1710352362.624 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240313T170906/Courier0021.lzma 2024-03-13T17:52:43.626Z,1710352363.626 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Courier0021.lzma.bak 2024-03-13T17:52:43.626Z,1710352363.626 [DataOverHttps](INFO): SBD MOMSN=19317625 2024-03-13T17:52:55.307Z,1710352375.307 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-13T17:52:55.314Z,1710352375.314 [BPC1](INFO): Calculating totals. Valid battery stick count: 39. Valid reserve battery stick count: 5. 2024-03-13T17:52:55.316Z,1710352375.316 [BPC1](INFO): Received data from all battery sticks. 2024-03-13T17:53:02.856Z,1710352382.856 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20240313T170906/Express0022.lzma 2024-03-13T17:53:03.858Z,1710352383.858 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Express0022.lzma.bak 2024-03-13T17:53:03.858Z,1710352383.858 [DataOverHttps](INFO): SBD MOMSN=19317628 2024-03-13T17:53:05.426Z,1710352385.426 [Default:CheckIn:Read_Iridium] Stopped 2024-03-13T17:53:05.426Z,1710352385.426 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-13T17:53:05.426Z,1710352385.426 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-13T17:58:06.010Z,1710352686.010 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-13T17:58:06.011Z,1710352686.011 [Default:CheckIn:C.Wait] Stopped 2024-03-13T17:58:06.011Z,1710352686.011 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-13T17:58:06.011Z,1710352686.011 [Default:CheckIn:D] Running Loop=1 2024-03-13T17:58:06.405Z,1710352686.405 [Default:CheckIn:D] Stopped 2024-03-13T17:58:06.405Z,1710352686.405 [Default:CheckIn:E] Running Loop=1 2024-03-13T17:58:06.807Z,1710352686.807 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 66.502454 min 2024-03-13T17:58:06.807Z,1710352686.807 [Default:CheckIn:E] Stopped 2024-03-13T17:58:06.808Z,1710352686.808 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-13T17:58:06.808Z,1710352686.808 [Default:CheckIn] Stopped 2024-03-13T17:58:06.808Z,1710352686.808 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-13T17:58:06.808Z,1710352686.808 [Default:CheckIn](INFO): Running loop #11 2024-03-13T17:58:06.808Z,1710352686.808 [Default:CheckIn] Running Loop=11 2024-03-13T17:58:06.808Z,1710352686.808 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-13T17:58:06.808Z,1710352686.808 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-13T17:58:08.815Z,1710352688.815 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175808.00,A,3648.36871,N,12148.13192,W,4.199,109.34,130324,,,D*73 2024-03-13T17:58:08.818Z,1710352688.818 [NAL9602](INFO): GPS fix at 20240313T175808: (36.806145, -121.802199) 2024-03-13T17:58:08.829Z,1710352688.829 [Default:CheckIn:Read_GPS] Stopped 2024-03-13T17:58:08.829Z,1710352688.829 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-13T17:58:16.736Z,1710352696.736 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20240313T170906/Courier0024.lzma 2024-03-13T17:58:17.738Z,1710352697.738 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Courier0024.lzma.bak 2024-03-13T17:58:17.738Z,1710352697.738 [DataOverHttps](INFO): SBD MOMSN=19317696 2024-03-13T17:58:31.843Z,1710352711.843 [NAL9602](INFO): SBD MO Status=0, MOMSN=47766, MT Status=0, MTMSN=0 2024-03-13T17:58:31.843Z,1710352711.843 [NAL9602](INFO): No messages in MT queue 2024-03-13T17:58:33.580Z,1710352713.580 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20240313T170906/Express0025.lzma 2024-03-13T17:58:34.582Z,1710352714.582 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Express0025.lzma.bak 2024-03-13T17:58:34.582Z,1710352714.582 [DataOverHttps](INFO): SBD MOMSN=19317700 2024-03-13T17:58:35.901Z,1710352715.901 [Default:CheckIn:Read_Iridium] Stopped 2024-03-13T17:58:35.901Z,1710352715.901 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-13T17:58:35.901Z,1710352715.901 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-13T17:59:02.553Z,1710352742.553 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-13T18:03:36.498Z,1710353016.498 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-13T18:03:36.498Z,1710353016.498 [Default:CheckIn:C.Wait] Stopped 2024-03-13T18:03:36.498Z,1710353016.498 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-13T18:03:36.498Z,1710353016.498 [Default:CheckIn:D] Running Loop=1 2024-03-13T18:03:36.900Z,1710353016.900 [Default:CheckIn:D] Stopped 2024-03-13T18:03:36.900Z,1710353016.900 [Default:CheckIn:E] Running Loop=1 2024-03-13T18:03:37.306Z,1710353017.306 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 72.010693 min 2024-03-13T18:03:37.306Z,1710353017.306 [Default:CheckIn:E] Stopped 2024-03-13T18:03:37.306Z,1710353017.306 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-13T18:03:37.306Z,1710353017.306 [Default:CheckIn] Stopped 2024-03-13T18:03:37.306Z,1710353017.306 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-13T18:03:37.306Z,1710353017.306 [Default:CheckIn](INFO): Running loop #12 2024-03-13T18:03:37.306Z,1710353017.306 [Default:CheckIn] Running Loop=12 2024-03-13T18:03:37.306Z,1710353017.306 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-13T18:03:37.307Z,1710353017.307 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-13T18:03:39.319Z,1710353019.319 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180338.00,A,3648.30989,N,12147.59856,W,4.898,81.24,130324,,,D*49 2024-03-13T18:03:39.321Z,1710353019.321 [NAL9602](INFO): GPS fix at 20240313T180338: (36.805165, -121.793309) 2024-03-13T18:03:39.350Z,1710353019.350 [Default:CheckIn:Read_GPS] Stopped 2024-03-13T18:03:39.350Z,1710353019.350 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-13T18:03:46.304Z,1710353026.304 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240313T170906/Courier0027.lzma 2024-03-13T18:03:47.306Z,1710353027.306 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Courier0027.lzma.bak 2024-03-13T18:03:47.306Z,1710353027.306 [DataOverHttps](INFO): SBD MOMSN=19317717 2024-03-13T18:03:51.047Z,1710353031.047 [NAL9602](INFO): SBD MO Status=0, MOMSN=47767, MT Status=0, MTMSN=0 2024-03-13T18:03:51.047Z,1710353031.047 [NAL9602](INFO): No messages in MT queue 2024-03-13T18:04:02.000Z,1710353043.000 [DataOverHttps](INFO): Sending 142 bytes from file Logs/20240313T170906/Express0028.lzma 2024-03-13T18:04:04.018Z,1710353044.018 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Express0028.lzma.bak 2024-03-13T18:04:04.019Z,1710353044.019 [DataOverHttps](INFO): SBD MOMSN=19317720 2024-03-13T18:04:05.205Z,1710353045.205 [Default:CheckIn:Read_Iridium] Stopped 2024-03-13T18:04:05.205Z,1710353045.205 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-13T18:04:05.205Z,1710353045.205 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-13T18:04:21.748Z,1710353061.748 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-13T18:09:05.785Z,1710353345.785 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-13T18:09:05.785Z,1710353345.785 [Default:CheckIn:C.Wait] Stopped 2024-03-13T18:09:05.785Z,1710353345.785 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-13T18:09:05.786Z,1710353345.786 [Default:CheckIn:D] Running Loop=1 2024-03-13T18:09:06.197Z,1710353346.197 [Default:CheckIn:D] Stopped 2024-03-13T18:09:06.197Z,1710353346.197 [Default:CheckIn:E] Running Loop=1 2024-03-13T18:09:06.578Z,1710353346.578 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 77.498983 min 2024-03-13T18:09:06.578Z,1710353346.578 [Default:CheckIn:E] Stopped 2024-03-13T18:09:06.578Z,1710353346.578 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-13T18:09:06.579Z,1710353346.579 [Default:CheckIn] Stopped 2024-03-13T18:09:06.579Z,1710353346.579 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-13T18:09:06.579Z,1710353346.579 [Default:CheckIn](INFO): Running loop #13 2024-03-13T18:09:06.579Z,1710353346.579 [Default:CheckIn] Running Loop=13 2024-03-13T18:09:06.579Z,1710353346.579 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-13T18:09:06.579Z,1710353346.579 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-13T18:09:08.595Z,1710353348.595 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180907.00,A,3648.50590,N,12147.20556,W,4.607,62.07,130324,,,D*4A 2024-03-13T18:09:08.597Z,1710353348.597 [NAL9602](INFO): GPS fix at 20240313T180907: (36.808432, -121.786759) 2024-03-13T18:09:08.608Z,1710353348.608 [Default:CheckIn:Read_GPS] Stopped 2024-03-13T18:09:08.608Z,1710353348.608 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-13T18:09:16.508Z,1710353356.508 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240313T170906/Courier0030.lzma 2024-03-13T18:09:17.510Z,1710353357.510 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Courier0030.lzma.bak 2024-03-13T18:09:17.510Z,1710353357.510 [DataOverHttps](INFO): SBD MOMSN=19317759 2024-03-13T18:09:33.316Z,1710353373.316 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20240313T170906/Express0031.lzma 2024-03-13T18:09:34.318Z,1710353374.318 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Express0031.lzma.bak 2024-03-13T18:09:34.318Z,1710353374.318 [DataOverHttps](INFO): SBD MOMSN=19317762 2024-03-13T18:09:35.668Z,1710353375.668 [Default:CheckIn:Read_Iridium] Stopped 2024-03-13T18:09:35.668Z,1710353375.668 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-13T18:09:35.668Z,1710353375.668 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-13T18:09:44.955Z,1710353384.955 [NAL9602](INFO): SBD MO Status=0, MOMSN=47768, MT Status=0, MTMSN=0 2024-03-13T18:09:44.955Z,1710353384.955 [NAL9602](INFO): No messages in MT queue 2024-03-13T18:10:15.653Z,1710353415.653 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-13T18:14:36.242Z,1710353676.242 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-13T18:14:36.243Z,1710353676.243 [Default:CheckIn:C.Wait] Stopped 2024-03-13T18:14:36.243Z,1710353676.243 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-13T18:14:36.243Z,1710353676.243 [Default:CheckIn:D] Running Loop=1 2024-03-13T18:14:36.647Z,1710353676.647 [Default:CheckIn:D] Stopped 2024-03-13T18:14:36.647Z,1710353676.647 [Default:CheckIn:E] Running Loop=1 2024-03-13T18:14:37.060Z,1710353677.060 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 83.006478 min 2024-03-13T18:14:37.060Z,1710353677.060 [Default:CheckIn:E] Stopped 2024-03-13T18:14:37.060Z,1710353677.060 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-13T18:14:37.060Z,1710353677.060 [Default:CheckIn] Stopped 2024-03-13T18:14:37.060Z,1710353677.060 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-13T18:14:37.060Z,1710353677.060 [Default:CheckIn](INFO): Running loop #14 2024-03-13T18:14:37.061Z,1710353677.061 [Default:CheckIn] Running Loop=14 2024-03-13T18:14:37.061Z,1710353677.061 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-13T18:14:37.061Z,1710353677.061 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-13T18:14:39.073Z,1710353679.073 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181438.00,A,3648.20800,N,12147.15507,W,1.847,225.65,130324,,,D*71 2024-03-13T18:14:39.075Z,1710353679.075 [NAL9602](INFO): GPS fix at 20240313T181438: (36.803467, -121.785918) 2024-03-13T18:14:39.086Z,1710353679.086 [Default:CheckIn:Read_GPS] Stopped 2024-03-13T18:14:39.086Z,1710353679.086 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-13T18:14:45.988Z,1710353685.988 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240313T170906/Courier0033.lzma 2024-03-13T18:14:46.990Z,1710353686.990 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Courier0033.lzma.bak 2024-03-13T18:14:46.990Z,1710353686.990 [DataOverHttps](INFO): SBD MOMSN=19317842 2024-03-13T18:14:56.527Z,1710353696.527 [NAL9602](INFO): SBD MO Status=0, MOMSN=47769, MT Status=0, MTMSN=0 2024-03-13T18:14:56.527Z,1710353696.527 [NAL9602](INFO): No messages in MT queue 2024-03-13T18:15:11.344Z,1710353711.344 [DataOverHttps](INFO): Sending 142 bytes from file Logs/20240313T170906/Express0034.lzma 2024-03-13T18:15:12.346Z,1710353712.346 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Express0034.lzma.bak 2024-03-13T18:15:12.346Z,1710353712.346 [DataOverHttps](INFO): SBD MOMSN=19317867 2024-03-13T18:15:13.545Z,1710353713.545 [Default:CheckIn:Read_Iridium] Stopped 2024-03-13T18:15:13.546Z,1710353713.546 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-13T18:15:13.546Z,1710353713.546 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-13T18:15:27.260Z,1710353727.260 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-13T18:20:14.223Z,1710354014.223 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-13T18:20:14.223Z,1710354014.223 [Default:CheckIn:C.Wait] Stopped 2024-03-13T18:20:14.223Z,1710354014.223 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-13T18:20:14.223Z,1710354014.223 [Default:CheckIn:D] Running Loop=1 2024-03-13T18:20:14.631Z,1710354014.631 [Default:CheckIn:D] Stopped 2024-03-13T18:20:14.631Z,1710354014.631 [Default:CheckIn:E] Running Loop=1 2024-03-13T18:20:15.023Z,1710354015.023 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 88.639543 min 2024-03-13T18:20:15.023Z,1710354015.023 [Default:CheckIn:E] Stopped 2024-03-13T18:20:15.023Z,1710354015.023 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-13T18:20:15.023Z,1710354015.023 [Default:CheckIn] Stopped 2024-03-13T18:20:15.023Z,1710354015.023 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-13T18:20:15.023Z,1710354015.023 [Default:CheckIn](INFO): Running loop #15 2024-03-13T18:20:15.023Z,1710354015.023 [Default:CheckIn] Running Loop=15 2024-03-13T18:20:15.024Z,1710354015.024 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-13T18:20:15.024Z,1710354015.024 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-13T18:20:17.041Z,1710354017.041 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182016.00,A,3648.13004,N,12147.21041,W,0.039,276.45,130324,,,D*72 2024-03-13T18:20:17.043Z,1710354017.043 [NAL9602](INFO): GPS fix at 20240313T182016: (36.802167, -121.786840) 2024-03-13T18:20:17.054Z,1710354017.054 [Default:CheckIn:Read_GPS] Stopped 2024-03-13T18:20:17.054Z,1710354017.054 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-13T18:20:24.223Z,1710354024.223 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240313T170906/Courier0036.lzma 2024-03-13T18:20:25.226Z,1710354025.226 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Courier0036.lzma.bak 2024-03-13T18:20:25.226Z,1710354025.226 [DataOverHttps](INFO): SBD MOMSN=19318014 2024-03-13T18:20:40.976Z,1710354040.976 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20240313T170906/Express0037.lzma 2024-03-13T18:20:41.978Z,1710354041.978 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Express0037.lzma.bak 2024-03-13T18:20:41.978Z,1710354041.978 [DataOverHttps](INFO): SBD MOMSN=19318136 2024-03-13T18:20:42.083Z,1710354042.083 [NAL9602](INFO): SBD MO Status=0, MOMSN=47770, MT Status=0, MTMSN=0 2024-03-13T18:20:42.083Z,1710354042.083 [NAL9602](INFO): No messages in MT queue 2024-03-13T18:20:43.321Z,1710354043.321 [Default:CheckIn:Read_Iridium] Stopped 2024-03-13T18:20:43.321Z,1710354043.321 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-13T18:20:43.321Z,1710354043.321 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-13T18:21:12.796Z,1710354072.796 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-13T18:25:43.995Z,1710354343.995 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-13T18:25:43.995Z,1710354343.995 [Default:CheckIn:C.Wait] Stopped 2024-03-13T18:25:43.996Z,1710354343.996 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-13T18:25:43.996Z,1710354343.996 [Default:CheckIn:D] Running Loop=1 2024-03-13T18:25:44.396Z,1710354344.396 [Default:CheckIn:D] Stopped 2024-03-13T18:25:44.396Z,1710354344.396 [Default:CheckIn:E] Running Loop=1 2024-03-13T18:25:44.810Z,1710354344.810 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 94.135628 min 2024-03-13T18:25:44.810Z,1710354344.810 [Default:CheckIn:E] Stopped 2024-03-13T18:25:44.810Z,1710354344.810 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-13T18:25:44.810Z,1710354344.810 [Default:CheckIn] Stopped 2024-03-13T18:25:44.811Z,1710354344.811 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-13T18:25:44.811Z,1710354344.811 [Default:CheckIn](INFO): Running loop #16 2024-03-13T18:25:44.811Z,1710354344.811 [Default:CheckIn] Running Loop=16 2024-03-13T18:25:44.811Z,1710354344.811 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-13T18:25:44.811Z,1710354344.811 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-13T18:25:46.811Z,1710354346.811 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182546.00,A,3648.13147,N,12147.20958,W,0.039,276.45,130324,,,A*71 2024-03-13T18:25:46.813Z,1710354346.813 [NAL9602](INFO): GPS fix at 20240313T182546: (36.802191, -121.786826) 2024-03-13T18:25:46.836Z,1710354346.836 [Default:CheckIn:Read_GPS] Stopped 2024-03-13T18:25:46.836Z,1710354346.836 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-13T18:25:54.056Z,1710354354.056 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240313T170906/Courier0039.lzma 2024-03-13T18:25:55.058Z,1710354355.058 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Courier0039.lzma.bak 2024-03-13T18:25:55.058Z,1710354355.058 [DataOverHttps](INFO): SBD MOMSN=19318192 2024-03-13T18:26:10.992Z,1710354370.992 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240313T170906/Express0040.lzma 2024-03-13T18:26:11.994Z,1710354371.994 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Express0040.lzma.bak 2024-03-13T18:26:11.994Z,1710354371.994 [DataOverHttps](INFO): SBD MOMSN=19318195 2024-03-13T18:26:13.095Z,1710354373.095 [Default:CheckIn:Read_Iridium] Stopped 2024-03-13T18:26:13.095Z,1710354373.095 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-13T18:26:13.096Z,1710354373.096 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-13T18:26:51.479Z,1710354411.479 [NAL9602](INFO): SBD MO Status=2, MOMSN=47771, MT Status=2, MTMSN=0 2024-03-13T18:26:51.479Z,1710354411.479 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-03-13T18:27:11.679Z,1710354431.679 [NAL9602](INFO): SBD MO Status=0, MOMSN=47771, MT Status=0, MTMSN=0 2024-03-13T18:27:11.679Z,1710354431.679 [NAL9602](INFO): No messages in MT queue 2024-03-13T18:27:42.380Z,1710354462.380 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-13T18:31:13.795Z,1710354673.795 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-13T18:31:13.795Z,1710354673.795 [Default:CheckIn:C.Wait] Stopped 2024-03-13T18:31:13.795Z,1710354673.795 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-13T18:31:13.796Z,1710354673.796 [Default:CheckIn:D] Running Loop=1 2024-03-13T18:31:14.198Z,1710354674.198 [Default:CheckIn:D] Stopped 2024-03-13T18:31:14.198Z,1710354674.198 [Default:CheckIn:E] Running Loop=1 2024-03-13T18:31:14.599Z,1710354674.599 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 99.632332 min 2024-03-13T18:31:14.600Z,1710354674.600 [Default:CheckIn:E] Stopped 2024-03-13T18:31:14.600Z,1710354674.600 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-13T18:31:14.600Z,1710354674.600 [Default:CheckIn] Stopped 2024-03-13T18:31:14.600Z,1710354674.600 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-13T18:31:14.600Z,1710354674.600 [Default:CheckIn](INFO): Running loop #17 2024-03-13T18:31:14.600Z,1710354674.600 [Default:CheckIn] Running Loop=17 2024-03-13T18:31:14.600Z,1710354674.600 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-13T18:31:14.601Z,1710354674.601 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-13T18:31:16.623Z,1710354676.623 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183115.00,A,3648.13175,N,12147.21002,W,0.058,276.45,130324,,,A*73 2024-03-13T18:31:16.626Z,1710354676.626 [NAL9602](INFO): GPS fix at 20240313T183115: (36.802196, -121.786834) 2024-03-13T18:31:16.656Z,1710354676.656 [Default:CheckIn:Read_GPS] Stopped 2024-03-13T18:31:16.657Z,1710354676.657 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-13T18:31:23.469Z,1710354683.469 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240313T170906/Courier0042.lzma 2024-03-13T18:31:24.466Z,1710354684.466 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Courier0042.lzma.bak 2024-03-13T18:31:24.466Z,1710354684.466 [DataOverHttps](INFO): SBD MOMSN=19318208 2024-03-13T18:31:39.231Z,1710354699.231 [NAL9602](INFO): SBD MO Status=2, MOMSN=47772, MT Status=2, MTMSN=0 2024-03-13T18:31:39.231Z,1710354699.231 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-03-13T18:31:40.568Z,1710354700.568 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20240313T170906/Express0043.lzma 2024-03-13T18:31:41.570Z,1710354701.570 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Express0043.lzma.bak 2024-03-13T18:31:41.570Z,1710354701.570 [DataOverHttps](INFO): SBD MOMSN=19318220 2024-03-13T18:31:42.882Z,1710354702.882 [Default:CheckIn:Read_Iridium] Stopped 2024-03-13T18:31:42.882Z,1710354702.882 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-13T18:31:42.882Z,1710354702.882 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-13T18:32:06.319Z,1710354726.319 [NAL9602](INFO): SBD MO Status=2, MOMSN=47772, MT Status=2, MTMSN=0 2024-03-13T18:32:06.319Z,1710354726.319 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-03-13T18:32:16.027Z,1710354736.027 [NAL9602](INFO): SBD MO Status=0, MOMSN=47772, MT Status=0, MTMSN=0 2024-03-13T18:32:16.027Z,1710354736.027 [NAL9602](INFO): No messages in MT queue 2024-03-13T18:32:46.729Z,1710354766.729 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-13T18:36:43.573Z,1710355003.573 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-13T18:36:43.573Z,1710355003.573 [Default:CheckIn:C.Wait] Stopped 2024-03-13T18:36:43.573Z,1710355003.573 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-13T18:36:43.573Z,1710355003.573 [Default:CheckIn:D] Running Loop=1 2024-03-13T18:36:43.975Z,1710355003.975 [Default:CheckIn:D] Stopped 2024-03-13T18:36:43.976Z,1710355003.976 [Default:CheckIn:E] Running Loop=1 2024-03-13T18:36:44.391Z,1710355004.391 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 105.128621 min 2024-03-13T18:36:44.391Z,1710355004.391 [Default:CheckIn:E] Stopped 2024-03-13T18:36:44.391Z,1710355004.391 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-13T18:36:44.391Z,1710355004.391 [Default:CheckIn] Stopped 2024-03-13T18:36:44.391Z,1710355004.391 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-13T18:36:44.392Z,1710355004.392 [Default:CheckIn](INFO): Running loop #18 2024-03-13T18:36:44.392Z,1710355004.392 [Default:CheckIn] Running Loop=18 2024-03-13T18:36:44.392Z,1710355004.392 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-13T18:36:44.392Z,1710355004.392 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-13T18:36:46.395Z,1710355006.395 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183645.00,A,3648.13990,N,12147.21051,W,0.058,303.95,130324,,,A*7A 2024-03-13T18:36:46.397Z,1710355006.397 [NAL9602](INFO): GPS fix at 20240313T183645: (36.802332, -121.786842) 2024-03-13T18:36:46.424Z,1710355006.424 [Default:CheckIn:Read_GPS] Stopped 2024-03-13T18:36:46.424Z,1710355006.424 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-13T18:36:56.508Z,1710355016.508 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240313T170906/Courier0045.lzma 2024-03-13T18:36:57.510Z,1710355017.510 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Courier0045.lzma.bak 2024-03-13T18:36:57.510Z,1710355017.510 [DataOverHttps](INFO): SBD MOMSN=19318317 2024-03-13T18:37:13.356Z,1710355033.356 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20240313T170906/Express0046.lzma 2024-03-13T18:37:14.358Z,1710355034.358 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Express0046.lzma.bak 2024-03-13T18:37:14.358Z,1710355034.358 [DataOverHttps](INFO): SBD MOMSN=19318320 2024-03-13T18:37:15.543Z,1710355035.543 [Default:CheckIn:Read_Iridium] Stopped 2024-03-13T18:37:15.544Z,1710355035.544 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-13T18:37:15.560Z,1710355035.560 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-13T18:37:32.107Z,1710355052.107 [NAL9602](INFO): SBD MO Status=2, MOMSN=47773, MT Status=2, MTMSN=0 2024-03-13T18:37:32.107Z,1710355052.107 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-03-13T18:37:59.591Z,1710355079.591 [NAL9602](INFO): SBD MO Status=0, MOMSN=47773, MT Status=0, MTMSN=0 2024-03-13T18:37:59.591Z,1710355079.591 [NAL9602](INFO): No messages in MT queue 2024-03-13T18:38:30.272Z,1710355110.272 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-13T18:42:16.218Z,1710355336.218 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-13T18:42:16.219Z,1710355336.219 [Default:CheckIn:C.Wait] Stopped 2024-03-13T18:42:16.219Z,1710355336.219 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-13T18:42:16.219Z,1710355336.219 [Default:CheckIn:D] Running Loop=1 2024-03-13T18:42:16.637Z,1710355336.637 [Default:CheckIn:D] Stopped 2024-03-13T18:42:16.637Z,1710355336.637 [Default:CheckIn:E] Running Loop=1 2024-03-13T18:42:17.031Z,1710355337.031 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 110.672982 min 2024-03-13T18:42:17.032Z,1710355337.032 [Default:CheckIn:E] Stopped 2024-03-13T18:42:17.032Z,1710355337.032 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-13T18:42:17.032Z,1710355337.032 [Default:CheckIn] Stopped 2024-03-13T18:42:17.032Z,1710355337.032 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-13T18:42:17.032Z,1710355337.032 [Default:CheckIn](INFO): Running loop #19 2024-03-13T18:42:17.032Z,1710355337.032 [Default:CheckIn] Running Loop=19 2024-03-13T18:42:17.032Z,1710355337.032 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-13T18:42:17.032Z,1710355337.032 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-13T18:42:19.049Z,1710355339.049 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184218.00,A,3648.13826,N,12147.22929,W,0.019,35.25,130324,,,D*45 2024-03-13T18:42:19.051Z,1710355339.051 [NAL9602](INFO): GPS fix at 20240313T184218: (36.802304, -121.787155) 2024-03-13T18:42:19.062Z,1710355339.062 [Default:CheckIn:Read_GPS] Stopped 2024-03-13T18:42:19.062Z,1710355339.062 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-13T18:42:25.935Z,1710355345.935 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240313T170906/Courier0048.lzma 2024-03-13T18:42:26.938Z,1710355346.938 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Courier0048.lzma.bak 2024-03-13T18:42:26.938Z,1710355346.938 [DataOverHttps](INFO): SBD MOMSN=19318330 2024-03-13T18:42:42.832Z,1710355362.832 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20240313T170906/Express0049.lzma 2024-03-13T18:42:43.830Z,1710355363.830 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Express0049.lzma.bak 2024-03-13T18:42:43.830Z,1710355363.830 [DataOverHttps](INFO): SBD MOMSN=19318333 2024-03-13T18:42:45.337Z,1710355365.337 [Default:CheckIn:Read_Iridium] Stopped 2024-03-13T18:42:45.337Z,1710355365.337 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-13T18:42:45.337Z,1710355365.337 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-13T18:43:54.039Z,1710355434.039 [NAL9602](INFO): SBD MO Status=0, MOMSN=47774, MT Status=0, MTMSN=0 2024-03-13T18:43:54.039Z,1710355434.039 [NAL9602](INFO): No messages in MT queue 2024-03-13T18:44:24.734Z,1710355464.734 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-13T18:47:46.064Z,1710355666.064 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-13T18:47:46.064Z,1710355666.064 [Default:CheckIn:C.Wait] Stopped 2024-03-13T18:47:46.080Z,1710355666.080 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-13T18:47:46.080Z,1710355666.080 [Default:CheckIn:D] Running Loop=1 2024-03-13T18:47:46.460Z,1710355666.460 [Default:CheckIn:D] Stopped 2024-03-13T18:47:46.461Z,1710355666.461 [Default:CheckIn:E] Running Loop=1 2024-03-13T18:47:46.865Z,1710355666.865 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 116.170036 min 2024-03-13T18:47:46.865Z,1710355666.865 [Default:CheckIn:E] Stopped 2024-03-13T18:47:46.865Z,1710355666.865 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-13T18:47:46.865Z,1710355666.865 [Default:CheckIn] Stopped 2024-03-13T18:47:46.866Z,1710355666.866 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-13T18:47:46.866Z,1710355666.866 [Default:CheckIn](INFO): Running loop #20 2024-03-13T18:47:46.866Z,1710355666.866 [Default:CheckIn] Running Loop=20 2024-03-13T18:47:46.866Z,1710355666.866 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-13T18:47:46.866Z,1710355666.866 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-13T18:47:48.875Z,1710355668.875 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184748.00,A,3648.13967,N,12147.22937,W,0.019,35.25,130324,,,D*4E 2024-03-13T18:47:48.878Z,1710355668.878 [NAL9602](INFO): GPS fix at 20240313T184748: (36.802328, -121.787156) 2024-03-13T18:47:48.911Z,1710355668.911 [Default:CheckIn:Read_GPS] Stopped 2024-03-13T18:47:48.911Z,1710355668.911 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-13T18:47:56.580Z,1710355676.580 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240313T170906/Courier0051.lzma 2024-03-13T18:47:57.582Z,1710355677.582 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Courier0051.lzma.bak 2024-03-13T18:47:57.582Z,1710355677.582 [DataOverHttps](INFO): SBD MOMSN=19318343 2024-03-13T18:48:13.500Z,1710355693.500 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240313T170906/Express0052.lzma 2024-03-13T18:48:14.502Z,1710355694.502 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Express0052.lzma.bak 2024-03-13T18:48:14.502Z,1710355694.502 [DataOverHttps](INFO): SBD MOMSN=19318346 2024-03-13T18:48:15.966Z,1710355695.966 [Default:CheckIn:Read_Iridium] Stopped 2024-03-13T18:48:15.966Z,1710355695.966 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-13T18:48:15.966Z,1710355695.966 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-13T18:48:26.851Z,1710355706.851 [NAL9602](INFO): SBD MO Status=0, MOMSN=47775, MT Status=0, MTMSN=0 2024-03-13T18:48:26.851Z,1710355706.851 [NAL9602](INFO): No messages in MT queue 2024-03-13T18:48:57.602Z,1710355737.602 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-13T18:53:16.676Z,1710355996.676 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-13T18:53:16.676Z,1710355996.676 [Default:CheckIn:C.Wait] Stopped 2024-03-13T18:53:16.676Z,1710355996.676 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-13T18:53:16.676Z,1710355996.676 [Default:CheckIn:D] Running Loop=1 2024-03-13T18:53:17.071Z,1710355997.071 [Default:CheckIn:D] Stopped 2024-03-13T18:53:17.071Z,1710355997.071 [Default:CheckIn:E] Running Loop=1 2024-03-13T18:53:17.480Z,1710355997.480 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 121.680216 min 2024-03-13T18:53:17.480Z,1710355997.480 [Default:CheckIn:E] Stopped 2024-03-13T18:53:17.480Z,1710355997.480 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-13T18:53:17.480Z,1710355997.480 [Default:CheckIn] Stopped 2024-03-13T18:53:17.480Z,1710355997.480 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-13T18:53:17.480Z,1710355997.480 [Default:CheckIn](INFO): Running loop #21 2024-03-13T18:53:17.481Z,1710355997.481 [Default:CheckIn] Running Loop=21 2024-03-13T18:53:17.481Z,1710355997.481 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-13T18:53:17.481Z,1710355997.481 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-13T18:53:19.483Z,1710355999.483 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185318.00,A,3648.13903,N,12147.23025,W,0.039,35.25,130324,,,D*45 2024-03-13T18:53:19.486Z,1710355999.486 [NAL9602](INFO): GPS fix at 20240313T185318: (36.802317, -121.787171) 2024-03-13T18:53:19.496Z,1710355999.496 [Default:CheckIn:Read_GPS] Stopped 2024-03-13T18:53:19.496Z,1710355999.496 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-13T18:53:26.844Z,1710356006.844 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240313T170906/Courier0054.lzma 2024-03-13T18:53:27.846Z,1710356007.846 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Courier0054.lzma.bak 2024-03-13T18:53:27.846Z,1710356007.846 [DataOverHttps](INFO): SBD MOMSN=19318356 2024-03-13T18:53:32.815Z,1710356012.815 [NAL9602](INFO): SBD MO Status=0, MOMSN=47776, MT Status=0, MTMSN=0 2024-03-13T18:53:32.815Z,1710356012.815 [NAL9602](INFO): No messages in MT queue 2024-03-13T18:53:43.772Z,1710356023.772 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240313T170906/Express0055.lzma 2024-03-13T18:53:44.774Z,1710356024.774 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Express0055.lzma.bak 2024-03-13T18:53:44.774Z,1710356024.774 [DataOverHttps](INFO): SBD MOMSN=19318362 2024-03-13T18:53:46.161Z,1710356026.161 [Default:CheckIn:Read_Iridium] Stopped 2024-03-13T18:53:46.161Z,1710356026.161 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-13T18:53:46.161Z,1710356026.161 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-13T18:54:02.303Z,1710356042.303 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-13T18:54:03.524Z,1710356043.524 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-13T18:54:14.423Z,1710356054.423 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-13T18:54:27.351Z,1710356067.351 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-13T18:54:39.875Z,1710356079.875 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-13T18:54:52.856Z,1710356092.856 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-13T18:55:05.787Z,1710356105.787 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-13T18:55:18.311Z,1710356118.311 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-13T18:57:51.139Z,1710356271.139 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-03-13T18:58:00.430Z,1710356280.430 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-13T18:58:00.437Z,1710356280.437 [BPC1](INFO): Calculating totals. Valid battery stick count: 37. Valid reserve battery stick count: 5. 2024-03-13T18:58:00.439Z,1710356280.439 [BPC1](INFO): Received data from all battery sticks. 2024-03-13T18:58:46.945Z,1710356326.945 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-13T18:58:46.945Z,1710356326.945 [Default:CheckIn:C.Wait] Stopped 2024-03-13T18:58:46.945Z,1710356326.945 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-13T18:58:46.945Z,1710356326.945 [Default:CheckIn:D] Running Loop=1 2024-03-13T18:58:47.429Z,1710356327.429 [Default:CheckIn:D] Stopped 2024-03-13T18:58:47.429Z,1710356327.429 [Default:CheckIn:E] Running Loop=1 2024-03-13T18:58:47.793Z,1710356327.793 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 127.186190 min 2024-03-13T18:58:47.793Z,1710356327.793 [Default:CheckIn:E] Stopped 2024-03-13T18:58:47.793Z,1710356327.793 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-13T18:58:47.793Z,1710356327.793 [Default:CheckIn] Stopped 2024-03-13T18:58:47.793Z,1710356327.793 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-13T18:58:47.794Z,1710356327.794 [Default:CheckIn](INFO): Running loop #22 2024-03-13T18:58:47.794Z,1710356327.794 [Default:CheckIn] Running Loop=22 2024-03-13T18:58:47.794Z,1710356327.794 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-13T18:58:47.794Z,1710356327.794 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-13T18:58:49.763Z,1710356329.763 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185849.00,A,3648.14414,N,12147.25284,W,0.019,308.69,130324,,,D*7E 2024-03-13T18:58:49.765Z,1710356329.765 [NAL9602](INFO): GPS fix at 20240313T185849: (36.802402, -121.787547) 2024-03-13T18:58:49.784Z,1710356329.784 [Default:CheckIn:Read_GPS] Stopped 2024-03-13T18:58:49.784Z,1710356329.784 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-13T18:58:59.891Z,1710356339.891 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20240313T170906/Courier0057.lzma 2024-03-13T18:59:00.894Z,1710356340.894 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Courier0057.lzma.bak 2024-03-13T18:59:00.894Z,1710356340.894 [DataOverHttps](INFO): SBD MOMSN=19318372 2024-03-13T18:59:03.559Z,1710356343.559 [NAL9602](INFO): SBD MO Status=0, MOMSN=47777, MT Status=0, MTMSN=0 2024-03-13T18:59:03.559Z,1710356343.559 [NAL9602](INFO): No messages in MT queue 2024-03-13T18:59:16.656Z,1710356356.656 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20240313T170906/Express0058.lzma 2024-03-13T18:59:17.658Z,1710356357.658 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Express0058.lzma.bak 2024-03-13T18:59:17.658Z,1710356357.658 [DataOverHttps](INFO): SBD MOMSN=19318378 2024-03-13T18:59:18.921Z,1710356358.921 [Default:CheckIn:Read_Iridium] Stopped 2024-03-13T18:59:18.921Z,1710356358.921 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-13T18:59:18.921Z,1710356358.921 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-13T18:59:34.252Z,1710356374.252 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-13T19:04:19.611Z,1710356659.611 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-13T19:04:19.611Z,1710356659.611 [Default:CheckIn:C.Wait] Stopped 2024-03-13T19:04:19.612Z,1710356659.612 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-13T19:04:19.612Z,1710356659.612 [Default:CheckIn:D] Running Loop=1 2024-03-13T19:04:20.030Z,1710356660.030 [Default:CheckIn:D] Stopped 2024-03-13T19:04:20.030Z,1710356660.030 [Default:CheckIn:E] Running Loop=1 2024-03-13T19:04:20.410Z,1710356660.410 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 132.729525 min 2024-03-13T19:04:20.410Z,1710356660.410 [Default:CheckIn:E] Stopped 2024-03-13T19:04:20.411Z,1710356660.411 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-13T19:04:20.411Z,1710356660.411 [Default:CheckIn] Stopped 2024-03-13T19:04:20.411Z,1710356660.411 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-13T19:04:20.411Z,1710356660.411 [Default:CheckIn](INFO): Running loop #23 2024-03-13T19:04:20.411Z,1710356660.411 [Default:CheckIn] Running Loop=23 2024-03-13T19:04:20.411Z,1710356660.411 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-13T19:04:20.411Z,1710356660.411 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-13T19:04:22.423Z,1710356662.423 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190421.00,A,3648.17480,N,12147.28080,W,0.194,333.81,130324,,,D*77 2024-03-13T19:04:22.425Z,1710356662.425 [NAL9602](INFO): GPS fix at 20240313T190421: (36.802913, -121.788013) 2024-03-13T19:04:22.466Z,1710356662.466 [Default:CheckIn:Read_GPS] Stopped 2024-03-13T19:04:22.466Z,1710356662.466 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-13T19:04:30.911Z,1710356670.911 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240313T170906/Courier0060.lzma 2024-03-13T19:04:31.914Z,1710356671.914 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Courier0060.lzma.bak 2024-03-13T19:04:31.914Z,1710356671.914 [DataOverHttps](INFO): SBD MOMSN=19318389 2024-03-13T19:04:38.207Z,1710356678.207 [NAL9602](INFO): SBD MO Status=0, MOMSN=47778, MT Status=0, MTMSN=0 2024-03-13T19:04:38.207Z,1710356678.207 [NAL9602](INFO): No messages in MT queue 2024-03-13T19:04:48.180Z,1710356688.180 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20240313T170906/Express0061.lzma 2024-03-13T19:04:49.182Z,1710356689.182 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Express0061.lzma.bak 2024-03-13T19:04:49.182Z,1710356689.182 [DataOverHttps](INFO): SBD MOMSN=19318392 2024-03-13T19:04:50.341Z,1710356690.341 [Default:CheckIn:Read_Iridium] Stopped 2024-03-13T19:04:50.341Z,1710356690.341 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-13T19:04:50.341Z,1710356690.341 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-13T19:05:08.988Z,1710356708.988 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-13T19:09:51.023Z,1710356991.023 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-13T19:09:51.023Z,1710356991.023 [Default:CheckIn:C.Wait] Stopped 2024-03-13T19:09:51.023Z,1710356991.023 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-13T19:09:51.023Z,1710356991.023 [Default:CheckIn:D] Running Loop=1 2024-03-13T19:09:51.423Z,1710356991.423 [Default:CheckIn:D] Stopped 2024-03-13T19:09:51.424Z,1710356991.424 [Default:CheckIn:E] Running Loop=1 2024-03-13T19:09:51.839Z,1710356991.839 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 138.252751 min 2024-03-13T19:09:51.840Z,1710356991.840 [Default:CheckIn:E] Stopped 2024-03-13T19:09:51.840Z,1710356991.840 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-13T19:09:51.840Z,1710356991.840 [Default:CheckIn] Stopped 2024-03-13T19:09:51.840Z,1710356991.840 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-13T19:09:51.840Z,1710356991.840 [Default:CheckIn](INFO): Running loop #24 2024-03-13T19:09:51.840Z,1710356991.840 [Default:CheckIn] Running Loop=24 2024-03-13T19:09:51.841Z,1710356991.841 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-13T19:09:51.841Z,1710356991.841 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-13T19:09:53.839Z,1710356993.839 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190953.00,A,3648.17848,N,12147.27994,W,0.078,241.47,130324,,,A*7C 2024-03-13T19:09:53.842Z,1710356993.842 [NAL9602](INFO): GPS fix at 20240313T190953: (36.802975, -121.787999) 2024-03-13T19:09:53.853Z,1710356993.853 [Default:CheckIn:Read_GPS] Stopped 2024-03-13T19:09:53.853Z,1710356993.853 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-13T19:10:01.640Z,1710357001.640 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240313T170906/Courier0063.lzma 2024-03-13T19:10:02.642Z,1710357002.642 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Courier0063.lzma.bak 2024-03-13T19:10:02.642Z,1710357002.642 [DataOverHttps](INFO): SBD MOMSN=19318403 2024-03-13T19:10:18.476Z,1710357018.476 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240313T170906/Express0064.lzma 2024-03-13T19:10:19.478Z,1710357019.478 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Express0064.lzma.bak 2024-03-13T19:10:19.478Z,1710357019.478 [DataOverHttps](INFO): SBD MOMSN=19318406 2024-03-13T19:10:20.924Z,1710357020.924 [Default:CheckIn:Read_Iridium] Stopped 2024-03-13T19:10:20.925Z,1710357020.925 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-13T19:10:20.925Z,1710357020.925 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-13T19:11:24.359Z,1710357084.359 [NAL9602](INFO): SBD MO Status=0, MOMSN=47779, MT Status=0, MTMSN=0 2024-03-13T19:11:24.359Z,1710357084.359 [NAL9602](INFO): No messages in MT queue 2024-03-13T19:11:55.061Z,1710357115.061 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-13T19:15:21.523Z,1710357321.523 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-13T19:15:21.524Z,1710357321.524 [Default:CheckIn:C.Wait] Stopped 2024-03-13T19:15:21.524Z,1710357321.524 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-13T19:15:21.524Z,1710357321.524 [Default:CheckIn:D] Running Loop=1 2024-03-13T19:15:21.937Z,1710357321.937 [Default:CheckIn:D] Stopped 2024-03-13T19:15:21.937Z,1710357321.937 [Default:CheckIn:E] Running Loop=1 2024-03-13T19:15:22.326Z,1710357322.326 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 143.761312 min 2024-03-13T19:15:22.326Z,1710357322.326 [Default:CheckIn:E] Stopped 2024-03-13T19:15:22.327Z,1710357322.327 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-13T19:15:22.327Z,1710357322.327 [Default:CheckIn] Stopped 2024-03-13T19:15:22.327Z,1710357322.327 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-13T19:15:22.327Z,1710357322.327 [Default:CheckIn](INFO): Running loop #25 2024-03-13T19:15:22.327Z,1710357322.327 [Default:CheckIn] Running Loop=25 2024-03-13T19:15:22.327Z,1710357322.327 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-13T19:15:22.327Z,1710357322.327 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-13T19:15:24.343Z,1710357324.343 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191523.00,A,3648.17928,N,12147.27988,W,0.214,241.47,130324,,,A*74 2024-03-13T19:15:24.345Z,1710357324.345 [NAL9602](INFO): GPS fix at 20240313T191523: (36.802988, -121.787998) 2024-03-13T19:15:24.356Z,1710357324.356 [Default:CheckIn:Read_GPS] Stopped 2024-03-13T19:15:24.356Z,1710357324.356 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-13T19:15:32.028Z,1710357332.028 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240313T170906/Courier0066.lzma 2024-03-13T19:15:33.030Z,1710357333.030 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Courier0066.lzma.bak 2024-03-13T19:15:33.030Z,1710357333.030 [DataOverHttps](INFO): SBD MOMSN=19318417 2024-03-13T19:15:40.503Z,1710357340.503 [NAL9602](INFO): SBD MO Status=0, MOMSN=47780, MT Status=0, MTMSN=0 2024-03-13T19:15:40.503Z,1710357340.503 [NAL9602](INFO): No messages in MT queue 2024-03-13T19:15:48.968Z,1710357348.968 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240313T170906/Express0067.lzma 2024-03-13T19:15:49.970Z,1710357349.970 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Express0067.lzma.bak 2024-03-13T19:15:49.970Z,1710357349.970 [DataOverHttps](INFO): SBD MOMSN=19318420 2024-03-13T19:15:51.435Z,1710357351.435 [Default:CheckIn:Read_Iridium] Stopped 2024-03-13T19:15:51.435Z,1710357351.435 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-13T19:15:51.435Z,1710357351.435 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-13T19:16:11.205Z,1710357371.205 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-13T19:20:52.060Z,1710357652.060 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-13T19:20:52.060Z,1710357652.060 [Default:CheckIn:C.Wait] Stopped 2024-03-13T19:20:52.060Z,1710357652.060 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-13T19:20:52.061Z,1710357652.061 [Default:CheckIn:D] Running Loop=1 2024-03-13T19:20:52.477Z,1710357652.477 [Default:CheckIn:D] Stopped 2024-03-13T19:20:52.477Z,1710357652.477 [Default:CheckIn:E] Running Loop=1 2024-03-13T19:20:52.898Z,1710357652.898 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 149.270312 min 2024-03-13T19:20:52.899Z,1710357652.899 [Default:CheckIn:E] Stopped 2024-03-13T19:20:52.899Z,1710357652.899 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-13T19:20:52.899Z,1710357652.899 [Default:CheckIn] Stopped 2024-03-13T19:20:52.899Z,1710357652.899 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-13T19:20:52.899Z,1710357652.899 [Default:CheckIn](INFO): Running loop #26 2024-03-13T19:20:52.899Z,1710357652.899 [Default:CheckIn] Running Loop=26 2024-03-13T19:20:52.899Z,1710357652.899 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-13T19:20:52.899Z,1710357652.899 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-13T19:20:54.893Z,1710357654.893 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192054.00,A,3648.17968,N,12147.27567,W,0.253,241.47,130324,,,A*78 2024-03-13T19:20:54.896Z,1710357654.896 [NAL9602](INFO): GPS fix at 20240313T192054: (36.802995, -121.787928) 2024-03-13T19:20:54.906Z,1710357654.906 [Default:CheckIn:Read_GPS] Stopped 2024-03-13T19:20:54.906Z,1710357654.906 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-13T19:21:02.292Z,1710357662.292 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240313T170906/Courier0069.lzma 2024-03-13T19:21:03.294Z,1710357663.294 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Courier0069.lzma.bak 2024-03-13T19:21:03.294Z,1710357663.294 [DataOverHttps](INFO): SBD MOMSN=19318432 2024-03-13T19:21:19.164Z,1710357679.164 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240313T170906/Express0070.lzma 2024-03-13T19:21:20.166Z,1710357680.166 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Express0070.lzma.bak 2024-03-13T19:21:20.166Z,1710357680.166 [DataOverHttps](INFO): SBD MOMSN=19318435 2024-03-13T19:21:21.624Z,1710357681.624 [Default:CheckIn:Read_Iridium] Stopped 2024-03-13T19:21:21.624Z,1710357681.624 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-13T19:21:21.624Z,1710357681.624 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-13T19:21:28.478Z,1710357688.478 [NAL9602](INFO): SBD MO Status=0, MOMSN=47781, MT Status=0, MTMSN=0 2024-03-13T19:21:28.478Z,1710357688.478 [NAL9602](INFO): No messages in MT queue 2024-03-13T19:21:59.181Z,1710357719.181 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-13T19:26:22.227Z,1710357982.227 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-13T19:26:22.227Z,1710357982.227 [Default:CheckIn:C.Wait] Stopped 2024-03-13T19:26:22.227Z,1710357982.227 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-13T19:26:22.228Z,1710357982.228 [Default:CheckIn:D] Running Loop=1 2024-03-13T19:26:22.631Z,1710357982.631 [Default:CheckIn:D] Stopped 2024-03-13T19:26:22.632Z,1710357982.632 [Default:CheckIn:E] Running Loop=1 2024-03-13T19:26:23.039Z,1710357983.039 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 154.772884 min 2024-03-13T19:26:23.039Z,1710357983.039 [Default:CheckIn:E] Stopped 2024-03-13T19:26:23.039Z,1710357983.039 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-13T19:26:23.039Z,1710357983.039 [Default:CheckIn] Stopped 2024-03-13T19:26:23.040Z,1710357983.040 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-13T19:26:23.040Z,1710357983.040 [Default:CheckIn](INFO): Running loop #27 2024-03-13T19:26:23.040Z,1710357983.040 [Default:CheckIn] Running Loop=27 2024-03-13T19:26:23.040Z,1710357983.040 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-13T19:26:23.040Z,1710357983.040 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-13T19:26:25.043Z,1710357985.043 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192624.00,A,3648.17801,N,12147.27773,W,0.214,0.00,130324,,,A*77 2024-03-13T19:26:25.045Z,1710357985.045 [NAL9602](INFO): GPS fix at 20240313T192624: (36.802967, -121.787962) 2024-03-13T19:26:25.083Z,1710357985.083 [Default:CheckIn:Read_GPS] Stopped 2024-03-13T19:26:25.083Z,1710357985.083 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-13T19:26:32.716Z,1710357992.716 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240313T170906/Courier0072.lzma 2024-03-13T19:26:33.718Z,1710357993.718 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Courier0072.lzma.bak 2024-03-13T19:26:33.718Z,1710357993.718 [DataOverHttps](INFO): SBD MOMSN=19318446 2024-03-13T19:26:51.400Z,1710358011.400 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240313T170906/Express0073.lzma 2024-03-13T19:26:52.402Z,1710358012.402 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Express0073.lzma.bak 2024-03-13T19:26:52.403Z,1710358012.403 [DataOverHttps](INFO): SBD MOMSN=19318449 2024-03-13T19:26:53.777Z,1710358013.777 [Default:CheckIn:Read_Iridium] Stopped 2024-03-13T19:26:53.777Z,1710358013.777 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-13T19:26:53.777Z,1710358013.777 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-13T19:30:02.839Z,1710358202.839 [NAL9602](INFO): SBD MO Status=2, MOMSN=47782, MT Status=2, MTMSN=0 2024-03-13T19:30:02.839Z,1710358202.839 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-03-13T19:30:37.175Z,1710358237.175 [NAL9602](INFO): SBD MO Status=0, MOMSN=47782, MT Status=0, MTMSN=0 2024-03-13T19:30:37.175Z,1710358237.175 [NAL9602](INFO): No messages in MT queue 2024-03-13T19:31:07.876Z,1710358267.876 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-13T19:31:54.391Z,1710358314.391 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-13T19:31:54.391Z,1710358314.391 [Default:CheckIn:C.Wait] Stopped 2024-03-13T19:31:54.391Z,1710358314.391 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-13T19:31:54.391Z,1710358314.391 [Default:CheckIn:D] Running Loop=1 2024-03-13T19:31:54.759Z,1710358314.759 [Default:CheckIn:D] Stopped 2024-03-13T19:31:54.759Z,1710358314.759 [Default:CheckIn:E] Running Loop=1 2024-03-13T19:31:55.162Z,1710358315.162 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 160.308350 min 2024-03-13T19:31:55.163Z,1710358315.163 [Default:CheckIn:E] Stopped 2024-03-13T19:31:55.163Z,1710358315.163 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-13T19:31:55.163Z,1710358315.163 [Default:CheckIn] Stopped 2024-03-13T19:31:55.163Z,1710358315.163 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-13T19:31:55.163Z,1710358315.163 [Default:CheckIn](INFO): Running loop #28 2024-03-13T19:31:55.163Z,1710358315.163 [Default:CheckIn] Running Loop=28 2024-03-13T19:31:55.163Z,1710358315.163 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-13T19:31:55.163Z,1710358315.163 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-13T19:31:57.167Z,1710358317.167 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193156.00,A,3648.17081,N,12147.27696,W,0.253,0.00,130324,,,A*7D 2024-03-13T19:31:57.169Z,1710358317.169 [NAL9602](INFO): GPS fix at 20240313T193156: (36.802847, -121.787949) 2024-03-13T19:31:57.214Z,1710358317.214 [Default:CheckIn:Read_GPS] Stopped 2024-03-13T19:31:57.214Z,1710358317.214 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-13T19:32:04.415Z,1710358324.415 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240313T170906/Courier0075.lzma 2024-03-13T19:32:05.418Z,1710358325.418 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Courier0075.lzma.bak 2024-03-13T19:32:05.418Z,1710358325.418 [DataOverHttps](INFO): SBD MOMSN=19318459 2024-03-13T19:32:16.963Z,1710358336.963 [NAL9602](INFO): SBD MO Status=0, MOMSN=47783, MT Status=0, MTMSN=0 2024-03-13T19:32:16.963Z,1710358336.963 [NAL9602](INFO): No messages in MT queue 2024-03-13T19:32:21.348Z,1710358341.348 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240313T170906/Express0076.lzma 2024-03-13T19:32:22.350Z,1710358342.350 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Express0076.lzma.bak 2024-03-13T19:32:22.350Z,1710358342.350 [DataOverHttps](INFO): SBD MOMSN=19318462 2024-03-13T19:32:23.853Z,1710358343.853 [Default:CheckIn:Read_Iridium] Stopped 2024-03-13T19:32:23.853Z,1710358343.853 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-13T19:32:23.853Z,1710358343.853 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-13T19:32:47.665Z,1710358367.665 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-13T19:34:29.069Z,1710358469.069 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2024-03-13T19:34:29.069Z,1710358469.069 [DropWeight] Hardware Fault, FailCount= 1 2024-03-13T19:34:29.069Z,1710358469.069 [DropWeight](ERROR): Hardware Fault 2024-03-13T19:34:29.108Z,1710358469.108 [CommandExec](FAULT): Scheduling is paused 2024-03-13T19:34:29.136Z,1710358469.136 [CBIT](INFO): Critical error at 20240313T193429 2024-03-13T19:34:29.139Z,1710358469.139 [CBIT](ERROR): Hardware Fault in component: DropWeight 2024-03-13T19:34:29.139Z,1710358469.139 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2024-03-13T19:34:29.547Z,1710358469.547 [CBIT](INFO): Critical error at 20240313T193429 2024-03-13T19:37:24.440Z,1710358644.440 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-13T19:37:24.440Z,1710358644.440 [Default:CheckIn:C.Wait] Stopped 2024-03-13T19:37:24.441Z,1710358644.441 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-13T19:37:24.441Z,1710358644.441 [Default:CheckIn:D] Running Loop=1 2024-03-13T19:37:24.818Z,1710358644.818 [Default:CheckIn:D] Stopped 2024-03-13T19:37:24.818Z,1710358644.818 [Default:CheckIn:E] Running Loop=1 2024-03-13T19:37:25.227Z,1710358645.227 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 165.809326 min 2024-03-13T19:37:25.227Z,1710358645.227 [Default:CheckIn:E] Stopped 2024-03-13T19:37:25.227Z,1710358645.227 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-13T19:37:25.227Z,1710358645.227 [Default:CheckIn] Stopped 2024-03-13T19:37:25.228Z,1710358645.228 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-13T19:37:25.228Z,1710358645.228 [Default:CheckIn](INFO): Running loop #29 2024-03-13T19:37:25.228Z,1710358645.228 [Default:CheckIn] Running Loop=29 2024-03-13T19:37:25.228Z,1710358645.228 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-13T19:37:25.228Z,1710358645.228 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-13T19:37:27.235Z,1710358647.235 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193726.00,A,3648.16457,N,12147.28293,W,0.078,151.68,130324,,,D*79 2024-03-13T19:37:27.237Z,1710358647.237 [NAL9602](INFO): GPS fix at 20240313T193726: (36.802743, -121.788049) 2024-03-13T19:37:27.253Z,1710358647.253 [Default:CheckIn:Read_GPS] Stopped 2024-03-13T19:37:27.253Z,1710358647.253 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-13T19:37:34.938Z,1710358654.938 [DataOverHttps](INFO): Sending 180 bytes from file Logs/20240313T170906/Courier0078.lzma 2024-03-13T19:37:35.818Z,1710358655.818 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Courier0078.lzma.bak 2024-03-13T19:37:35.818Z,1710358655.818 [DataOverHttps](INFO): SBD MOMSN=19318472 2024-03-13T19:37:51.940Z,1710358671.940 [DataOverHttps](INFO): Sending 157 bytes from file Logs/20240313T170906/Express0079.lzma 2024-03-13T19:37:52.942Z,1710358672.942 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Express0079.lzma.bak 2024-03-13T19:37:52.942Z,1710358672.942 [DataOverHttps](INFO): SBD MOMSN=19318477 2024-03-13T19:37:54.317Z,1710358674.317 [Default:CheckIn:Read_Iridium] Stopped 2024-03-13T19:37:54.317Z,1710358674.317 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-13T19:37:54.317Z,1710358674.317 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-13T19:38:26.623Z,1710358706.623 [NAL9602](INFO): SBD MO Status=2, MOMSN=47784, MT Status=2, MTMSN=0 2024-03-13T19:38:26.623Z,1710358706.623 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-03-13T19:38:52.479Z,1710358732.479 [NAL9602](INFO): SBD MO Status=2, MOMSN=47784, MT Status=2, MTMSN=0 2024-03-13T19:38:52.479Z,1710358732.479 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-03-13T19:41:54.283Z,1710358914.283 [NAL9602](INFO): SBD MO Status=2, MOMSN=47784, MT Status=2, MTMSN=0 2024-03-13T19:41:54.283Z,1710358914.283 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-03-13T19:42:29.433Z,1710358949.433 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-03-13T19:42:54.970Z,1710358974.970 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-13T19:42:54.970Z,1710358974.970 [Default:CheckIn:C.Wait] Stopped 2024-03-13T19:42:54.970Z,1710358974.970 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-13T19:42:54.970Z,1710358974.970 [Default:CheckIn:D] Running Loop=1 2024-03-13T19:42:55.350Z,1710358975.350 [Default:CheckIn:D] Stopped 2024-03-13T19:42:55.350Z,1710358975.350 [Default:CheckIn:E] Running Loop=1 2024-03-13T19:42:55.725Z,1710358975.725 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 171.318197 min 2024-03-13T19:42:55.725Z,1710358975.725 [Default:CheckIn:E] Stopped 2024-03-13T19:42:55.725Z,1710358975.725 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-13T19:42:55.725Z,1710358975.725 [Default:CheckIn] Stopped 2024-03-13T19:42:55.725Z,1710358975.725 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-13T19:42:55.726Z,1710358975.726 [Default:CheckIn](INFO): Running loop #30 2024-03-13T19:42:55.726Z,1710358975.726 [Default:CheckIn] Running Loop=30 2024-03-13T19:42:55.726Z,1710358975.726 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-13T19:42:55.726Z,1710358975.726 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-13T19:42:57.715Z,1710358977.715 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194257.00,A,3648.16513,N,12147.28409,W,0.661,151.68,130324,,,D*77 2024-03-13T19:42:57.718Z,1710358977.718 [NAL9602](INFO): GPS fix at 20240313T194257: (36.802752, -121.788068) 2024-03-13T19:42:57.751Z,1710358977.751 [Default:CheckIn:Read_GPS] Stopped 2024-03-13T19:42:57.751Z,1710358977.751 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-13T19:43:05.476Z,1710358985.476 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240313T170906/Courier0081.lzma 2024-03-13T19:43:06.478Z,1710358986.478 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Courier0081.lzma.bak 2024-03-13T19:43:06.478Z,1710358986.478 [DataOverHttps](INFO): SBD MOMSN=19318487 2024-03-13T19:43:22.304Z,1710359002.304 [DataOverHttps](INFO): Sending 231 bytes from file Logs/20240313T170906/Express0082.lzma 2024-03-13T19:43:23.306Z,1710359003.306 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Express0082.lzma.bak 2024-03-13T19:43:23.306Z,1710359003.306 [DataOverHttps](INFO): SBD MOMSN=19318490 2024-03-13T19:43:24.935Z,1710359004.935 [Default:CheckIn:Read_Iridium] Stopped 2024-03-13T19:43:24.935Z,1710359004.935 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-13T19:43:24.935Z,1710359004.935 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-13T19:43:30.437Z,1710359010.437 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-13T19:48:25.362Z,1710359305.362 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-13T19:48:25.362Z,1710359305.362 [Default:CheckIn:C.Wait] Stopped 2024-03-13T19:48:25.362Z,1710359305.362 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-13T19:48:25.363Z,1710359305.363 [Default:CheckIn:D] Running Loop=1 2024-03-13T19:48:25.775Z,1710359305.775 [Default:CheckIn:D] Stopped 2024-03-13T19:48:25.775Z,1710359305.775 [Default:CheckIn:E] Running Loop=1 2024-03-13T19:48:26.185Z,1710359306.185 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 176.825277 min 2024-03-13T19:48:26.185Z,1710359306.185 [Default:CheckIn:E] Stopped 2024-03-13T19:48:26.185Z,1710359306.185 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-13T19:48:26.185Z,1710359306.185 [Default:CheckIn] Stopped 2024-03-13T19:48:26.185Z,1710359306.185 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-13T19:48:26.186Z,1710359306.186 [Default:CheckIn](INFO): Running loop #31 2024-03-13T19:48:26.186Z,1710359306.186 [Default:CheckIn] Running Loop=31 2024-03-13T19:48:26.186Z,1710359306.186 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-13T19:48:26.186Z,1710359306.186 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-13T19:48:28.183Z,1710359308.183 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194827.00,A,3648.16654,N,12147.28248,W,0.097,127.12,130324,,,D*7A 2024-03-13T19:48:28.185Z,1710359308.185 [NAL9602](INFO): GPS fix at 20240313T194827: (36.802776, -121.788041) 2024-03-13T19:48:28.201Z,1710359308.201 [Default:CheckIn:Read_GPS] Stopped 2024-03-13T19:48:28.201Z,1710359308.201 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-13T19:48:35.887Z,1710359315.887 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240313T170906/Courier0084.lzma 2024-03-13T19:48:36.890Z,1710359316.890 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Courier0084.lzma.bak 2024-03-13T19:48:36.890Z,1710359316.890 [DataOverHttps](INFO): SBD MOMSN=19318500 2024-03-13T19:48:55.452Z,1710359335.452 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20240313T170906/Express0085.lzma 2024-03-13T19:48:56.454Z,1710359336.454 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Express0085.lzma.bak 2024-03-13T19:48:56.454Z,1710359336.454 [DataOverHttps](INFO): SBD MOMSN=19318503 2024-03-13T19:48:57.755Z,1710359337.755 [Default:CheckIn:Read_Iridium] Stopped 2024-03-13T19:48:57.755Z,1710359337.755 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-13T19:48:57.755Z,1710359337.755 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-13T19:48:58.937Z,1710359338.937 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2024-03-13T19:48:59.013Z,1710359339.013 [NAL9602](ERROR): received: +CSQ:0 OK784, 2, 0, 0, 0 OK 2024-03-13T19:49:29.300Z,1710359369.300 [CBIT](INFO): Clearing failed state for component DropWeight 2024-03-13T19:49:29.300Z,1710359369.300 [DropWeight] No Fault, FailCount= 1 2024-03-13T19:53:30.429Z,1710359610.429 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-03-13T19:53:58.330Z,1710359638.330 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-13T19:53:58.330Z,1710359638.330 [Default:CheckIn:C.Wait] Stopped 2024-03-13T19:53:58.330Z,1710359638.330 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-13T19:53:58.330Z,1710359638.330 [Default:CheckIn:D] Running Loop=1 2024-03-13T19:53:58.715Z,1710359638.715 [Default:CheckIn:D] Stopped 2024-03-13T19:53:58.715Z,1710359638.715 [Default:CheckIn:E] Running Loop=1 2024-03-13T19:53:59.129Z,1710359639.129 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 182.374268 min 2024-03-13T19:53:59.129Z,1710359639.129 [Default:CheckIn:E] Stopped 2024-03-13T19:53:59.129Z,1710359639.129 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-13T19:53:59.129Z,1710359639.129 [Default:CheckIn] Stopped 2024-03-13T19:53:59.130Z,1710359639.130 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-13T19:53:59.130Z,1710359639.130 [Default:CheckIn](INFO): Running loop #32 2024-03-13T19:53:59.130Z,1710359639.130 [Default:CheckIn] Running Loop=32 2024-03-13T19:53:59.130Z,1710359639.130 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-13T19:53:59.130Z,1710359639.130 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-13T19:54:01.135Z,1710359641.135 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195400.00,A,3648.16783,N,12147.28387,W,0.097,295.50,130324,,,D*77 2024-03-13T19:54:01.137Z,1710359641.137 [NAL9602](INFO): GPS fix at 20240313T195400: (36.802797, -121.788065) 2024-03-13T19:54:01.148Z,1710359641.148 [Default:CheckIn:Read_GPS] Stopped 2024-03-13T19:54:01.148Z,1710359641.148 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-13T19:54:09.103Z,1710359649.103 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240313T170906/Courier0087.lzma 2024-03-13T19:54:10.106Z,1710359650.106 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Courier0087.lzma.bak 2024-03-13T19:54:10.107Z,1710359650.107 [DataOverHttps](INFO): SBD MOMSN=19318513 2024-03-13T19:54:25.804Z,1710359665.804 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20240313T170906/Express0088.lzma 2024-03-13T19:54:26.806Z,1710359666.806 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Express0088.lzma.bak 2024-03-13T19:54:26.806Z,1710359666.806 [DataOverHttps](INFO): SBD MOMSN=19318516 2024-03-13T19:54:28.219Z,1710359668.219 [Default:CheckIn:Read_Iridium] Stopped 2024-03-13T19:54:28.219Z,1710359668.219 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-13T19:54:28.219Z,1710359668.219 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-13T19:54:33.455Z,1710359673.455 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-13T19:59:07.763Z,1710359947.763 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-13T19:59:20.693Z,1710359960.693 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-13T19:59:28.793Z,1710359968.793 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-13T19:59:28.793Z,1710359968.793 [Default:CheckIn:C.Wait] Stopped 2024-03-13T19:59:28.793Z,1710359968.793 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-13T19:59:28.793Z,1710359968.793 [Default:CheckIn:D] Running Loop=1 2024-03-13T19:59:29.192Z,1710359969.192 [Default:CheckIn:D] Stopped 2024-03-13T19:59:29.193Z,1710359969.193 [Default:CheckIn:E] Running Loop=1 2024-03-13T19:59:29.591Z,1710359969.591 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 187.882243 min 2024-03-13T19:59:29.591Z,1710359969.591 [Default:CheckIn:E] Stopped 2024-03-13T19:59:29.592Z,1710359969.592 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-13T19:59:29.592Z,1710359969.592 [Default:CheckIn] Stopped 2024-03-13T19:59:29.592Z,1710359969.592 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-13T19:59:29.592Z,1710359969.592 [Default:CheckIn](INFO): Running loop #33 2024-03-13T19:59:29.592Z,1710359969.592 [Default:CheckIn] Running Loop=33 2024-03-13T19:59:29.592Z,1710359969.592 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-13T19:59:29.592Z,1710359969.592 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-13T19:59:31.605Z,1710359971.605 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195930.00,A,3648.16717,N,12147.28068,W,0.603,259.18,130324,,,D*71 2024-03-13T19:59:31.607Z,1710359971.607 [NAL9602](INFO): GPS fix at 20240313T195930: (36.802786, -121.788011) 2024-03-13T19:59:31.618Z,1710359971.618 [Default:CheckIn:Read_GPS] Stopped 2024-03-13T19:59:31.618Z,1710359971.618 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-13T19:59:33.619Z,1710359973.619 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-13T19:59:38.995Z,1710359978.995 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20240313T170906/Courier0090.lzma 2024-03-13T19:59:39.998Z,1710359979.998 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Courier0090.lzma.bak 2024-03-13T19:59:39.998Z,1710359979.998 [DataOverHttps](INFO): SBD MOMSN=19318526 2024-03-13T19:59:46.547Z,1710359986.547 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-13T19:59:55.864Z,1710359995.864 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240313T170906/Express0091.lzma 2024-03-13T19:59:56.868Z,1710359996.868 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Express0091.lzma.bak 2024-03-13T19:59:56.868Z,1710359996.868 [DataOverHttps](INFO): SBD MOMSN=19318529 2024-03-13T19:59:58.286Z,1710359998.286 [Default:CheckIn:Read_Iridium] Stopped 2024-03-13T19:59:58.286Z,1710359998.286 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-13T19:59:58.286Z,1710359998.286 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-13T19:59:59.477Z,1710359999.477 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-13T20:00:02.436Z,1710360002.436 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2024-03-13T20:00:02.516Z,1710360002.516 [NAL9602](ERROR): received: +CSQ:0 OK784, 2, 0, 0, 0 OK 2024-03-13T20:00:13.211Z,1710360013.211 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-13T20:00:26.139Z,1710360026.139 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-13T20:02:58.042Z,1710360178.042 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-03-13T20:02:59.659Z,1710360179.659 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-13T20:02:59.666Z,1710360179.666 [BPC1](INFO): Calculating totals. Valid battery stick count: 37. Valid reserve battery stick count: 5. 2024-03-13T20:02:59.668Z,1710360179.668 [BPC1](INFO): Received data from all battery sticks. 2024-03-13T20:04:33.389Z,1710360273.389 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-03-13T20:04:58.860Z,1710360298.860 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-13T20:04:58.860Z,1710360298.860 [Default:CheckIn:C.Wait] Stopped 2024-03-13T20:04:58.860Z,1710360298.860 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-13T20:04:58.860Z,1710360298.860 [Default:CheckIn:D] Running Loop=1 2024-03-13T20:04:59.254Z,1710360299.254 [Default:CheckIn:D] Stopped 2024-03-13T20:04:59.254Z,1710360299.254 [Default:CheckIn:E] Running Loop=1 2024-03-13T20:04:59.663Z,1710360299.663 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 193.383268 min 2024-03-13T20:04:59.664Z,1710360299.664 [Default:CheckIn:E] Stopped 2024-03-13T20:04:59.664Z,1710360299.664 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-13T20:04:59.664Z,1710360299.664 [Default:CheckIn] Stopped 2024-03-13T20:04:59.664Z,1710360299.664 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-13T20:04:59.664Z,1710360299.664 [Default:CheckIn](INFO): Running loop #34 2024-03-13T20:04:59.664Z,1710360299.664 [Default:CheckIn] Running Loop=34 2024-03-13T20:04:59.665Z,1710360299.665 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-13T20:04:59.665Z,1710360299.665 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-13T20:05:01.675Z,1710360301.675 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200500.00,A,3648.17671,N,12147.27831,W,0.641,182.50,130324,,,D*75 2024-03-13T20:05:01.678Z,1710360301.678 [NAL9602](INFO): GPS fix at 20240313T200500: (36.802945, -121.787972) 2024-03-13T20:05:01.709Z,1710360301.709 [Default:CheckIn:Read_GPS] Stopped 2024-03-13T20:05:01.709Z,1710360301.709 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-13T20:05:10.415Z,1710360310.415 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20240313T170906/Courier0093.lzma 2024-03-13T20:05:11.418Z,1710360311.418 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Courier0093.lzma.bak 2024-03-13T20:05:11.418Z,1710360311.418 [DataOverHttps](INFO): SBD MOMSN=19318538 2024-03-13T20:05:27.224Z,1710360327.224 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20240313T170906/Express0094.lzma 2024-03-13T20:05:28.226Z,1710360328.226 [DataOverHttps](INFO): Moved sent file to Logs/20240313T170906/Express0094.lzma.bak 2024-03-13T20:05:28.226Z,1710360328.226 [DataOverHttps](INFO): SBD MOMSN=19318541 2024-03-13T20:05:29.577Z,1710360329.577 [Default:CheckIn:Read_Iridium] Stopped 2024-03-13T20:05:29.577Z,1710360329.577 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-13T20:05:29.577Z,1710360329.577 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-13T20:05:34.393Z,1710360334.393 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-13T20:06:38.081Z,1710360398.081 [CommandExec](IMPORTANT): got command quit 2024-03-13T20:06:39.084Z,1710360399.084 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2024-03-13T20:06:39.085Z,1710360399.085 [CommandExec](INFO): Uninitializing the command executive. 2024-03-13T20:06:39.085Z,1710360399.085 [CommandExec](INFO): Uninitializing the command scheduler. 2024-03-13T20:06:39.085Z,1710360399.085 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-03-13T20:06:39.156Z,1710360399.156 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2024-03-13T20:06:39.156Z,1710360399.156 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2024-03-13T20:06:39.156Z,1710360399.156 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-03-13T20:06:39.157Z,1710360399.157 [NavChartDb](INFO): Join timeout helper Thread ID is 9727 2024-03-13T20:06:39.204Z,1710360399.204 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2024-03-13T20:06:39.205Z,1710360399.205 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-03-13T20:06:39.218Z,1710360399.218 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2024-03-13T20:06:39.218Z,1710360399.218 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-03-13T20:06:39.219Z,1710360399.219 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 9728 2024-03-13T20:06:39.452Z,1710360399.452 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2024-03-13T20:06:39.453Z,1710360399.453 [WetLabsBB2FL](INFO): Powering down 2024-03-13T20:06:39.454Z,1710360399.454 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-03-13T20:06:39.460Z,1710360399.460 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2024-03-13T20:06:39.460Z,1710360399.460 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-03-13T20:06:39.461Z,1710360399.461 [CTD_Seabird](INFO): Join timeout helper Thread ID is 9729 2024-03-13T20:06:39.808Z,1710360399.808 [CTD_Seabird](INFO): Powering down 2024-03-13T20:06:39.820Z,1710360399.820 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2024-03-13T20:06:39.820Z,1710360399.820 [CTD_Seabird](INFO): Powering down 2024-03-13T20:06:39.836Z,1710360399.836 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-03-13T20:06:39.837Z,1710360399.837 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2024-03-13T20:06:39.837Z,1710360399.837 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-03-13T20:06:39.837Z,1710360399.837 [Radio_Surface](INFO): Join timeout helper Thread ID is 9730 2024-03-13T20:06:40.176Z,1710360400.176 [Radio_Surface](INFO): Powering down 2024-03-13T20:06:40.177Z,1710360400.177 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2024-03-13T20:06:40.177Z,1710360400.177 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-03-13T20:06:40.196Z,1710360400.196 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2024-03-13T20:06:40.196Z,1710360400.196 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-03-13T20:06:40.196Z,1710360400.196 [Onboard](INFO): Join timeout helper Thread ID is 9731 2024-03-13T20:06:40.896Z,1710360400.896 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2024-03-13T20:06:41.125Z,1710360401.125 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2024-03-13T20:06:41.125Z,1710360401.125 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-03-13T20:06:41.136Z,1710360401.136 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2024-03-13T20:06:41.136Z,1710360401.136 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-03-13T20:06:41.137Z,1710360401.137 [DataOverHttps](INFO): Join timeout helper Thread ID is 9732 2024-03-13T20:06:41.900Z,1710360401.900 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2024-03-13T20:06:41.901Z,1710360401.901 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-03-13T20:06:41.905Z,1710360401.905 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2024-03-13T20:06:41.905Z,1710360401.905 [logger ThreadHandler](INFO): Thread cancelled. 2024-03-13T20:06:41.905Z,1710360401.905 [logger](INFO): Join timeout helper Thread ID is 9733 2024-03-13T20:06:41.936Z,1710360401.936 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2024-03-13T20:06:41.937Z,1710360401.937 [logger ThreadHandler](INFO): Thread cancelled. 2024-03-13T20:06:41.953Z,1710360401.953 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2024-03-13T20:06:41.953Z,1710360401.953 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-03-13T20:06:41.953Z,1710360401.953 [CommandLine](INFO): Join timeout helper Thread ID is 9734 2024-03-13T20:06:42.012Z,1710360402.012 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2024-03-13T20:06:42.013Z,1710360402.013 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-03-13T20:06:42.033Z,1710360402.033 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2024-03-13T20:06:42.033Z,1710360402.033 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-03-13T20:06:42.033Z,1710360402.033 [CommandExec](INFO): Join timeout helper Thread ID is 9735 2024-03-13T20:06:42.034Z,1710360402.034 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2024-03-13T20:06:42.034Z,1710360402.034 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-03-13T20:06:42.035Z,1710360402.035 [controlThread](INFO): Join timeout helper Thread ID is 9736 2024-03-13T20:06:42.248Z,1710360402.248 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2024-03-13T20:06:42.248Z,1710360402.248 [controlThread](DEBUG): Uninitializing ControlThread 2024-03-13T20:06:42.249Z,1710360402.249 [AHRS_M2](INFO): Powering down 2024-03-13T20:06:42.321Z,1710360402.321 [NAL9602](INFO): Powering down 2024-03-13T20:06:42.322Z,1710360402.322 [Sonardyne_Nano](INFO): Powering down 2024-03-13T20:06:42.442Z,1710360402.442 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2024-03-13T20:06:42.443Z,1710360402.443 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2024-03-13T20:06:42.443Z,1710360402.443 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2024-03-13T20:06:42.444Z,1710360402.444 [MissionManager](INFO): Uninitializing Mission Default 2024-03-13T20:06:42.444Z,1710360402.444 [Default] Stopped 2024-03-13T20:06:42.444Z,1710360402.444 [Default](DEBUG): Aggregate::uninitialize Default 2024-03-13T20:06:42.444Z,1710360402.444 [Default:B.GoToSurface] Stopped 2024-03-13T20:06:42.444Z,1710360402.444 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-03-13T20:06:42.444Z,1710360402.444 [Default:CheckIn] Stopped 2024-03-13T20:06:42.444Z,1710360402.444 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-13T20:06:42.444Z,1710360402.444 [Default:CheckIn:C.Wait] Stopped 2024-03-13T20:06:42.444Z,1710360402.444 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-13T20:06:42.447Z,1710360402.447 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2024-03-13T20:06:42.447Z,1710360402.447 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2024-03-13T20:06:42.448Z,1710360402.448 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2024-03-13T20:06:42.448Z,1710360402.448 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2024-03-13T20:06:42.448Z,1710360402.448 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2024-03-13T20:06:42.448Z,1710360402.448 [BuoyancyServo](INFO): Powering down 2024-03-13T20:06:42.460Z,1710360402.460 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2024-03-13T20:06:42.460Z,1710360402.460 [ElevatorServo](INFO): Powering down 2024-03-13T20:06:42.461Z,1710360402.461 [MassServo](DEBUG): Uninitialize Mass Servo. 2024-03-13T20:06:42.461Z,1710360402.461 [MassServo](INFO): Powering down 2024-03-13T20:06:42.462Z,1710360402.462 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2024-03-13T20:06:42.462Z,1710360402.462 [RudderServo](INFO): Powering down 2024-03-13T20:06:42.463Z,1710360402.463 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2024-03-13T20:06:42.463Z,1710360402.463 [ThrusterHE](INFO): Powering down 2024-03-13T20:06:42.464Z,1710360402.464 [SBIT](DEBUG): Uninitialize SBIT Component. 2024-03-13T20:06:42.464Z,1710360402.464 [IBIT](DEBUG): Uninitialize IBIT Component. 2024-03-13T20:06:42.465Z,1710360402.465 [CBIT](DEBUG): Uninitialize CBIT Component. 2024-03-13T20:06:42.465Z,1710360402.465 [CBIT](DEBUG): Powering off loads. 2024-03-13T20:06:42.476Z,1710360402.476 [CBIT](DEBUG): Disabling WDT. 2024-03-13T20:06:42.488Z,1710360402.488 [CBIT](DEBUG): Opening all GF detection circuits. 2024-03-13T20:06:42.489Z,1710360402.489 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-03-13T20:06:42.538Z,1710360402.538 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-03-13T20:06:42.541Z,1710360402.541 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-03-13T20:06:42.547Z,1710360402.547 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-03-13T20:06:42.620Z,1710360402.620 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-03-13T20:06:42.624Z,1710360402.624 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-03-13T20:06:42.666Z,1710360402.666 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-03-13T20:06:42.776Z,1710360402.776 [logger ThreadHandler](INFO): Thread cancelled.