2025-10-10T17:33:32.652Z,1760117612.652 [DataOverHttps](INFO): Received command: restart logs 2025-10-10T17:33:32.662Z,1760117612.662 [CommandExec](IMPORTANT): got command restart logs 2025-10-10T17:35:56.489Z,1760117756.489 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-10-10T17:36:19.112Z,1760117779.112 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-10T17:36:19.112Z,1760117779.112 [Default:CheckIn:C.Wait] Stopped 2025-10-10T17:36:19.112Z,1760117779.112 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-10T17:36:19.113Z,1760117779.113 [Default:CheckIn:D] Running Loop=1 2025-10-10T17:36:19.504Z,1760117779.504 [Default:CheckIn:D] Stopped 2025-10-10T17:36:19.504Z,1760117779.504 [Default:CheckIn:E] Running Loop=1 2025-10-10T17:36:19.909Z,1760117779.909 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 36.371989 min 2025-10-10T17:36:19.910Z,1760117779.910 [Default:CheckIn:E] Stopped 2025-10-10T17:36:19.910Z,1760117779.910 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-10T17:36:19.910Z,1760117779.910 [Default:CheckIn] Stopped 2025-10-10T17:36:19.910Z,1760117779.910 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-10T17:36:19.910Z,1760117779.910 [Default:CheckIn](INFO): Running loop #6 2025-10-10T17:36:19.910Z,1760117779.910 [Default:CheckIn] Running Loop=6 2025-10-10T17:36:19.910Z,1760117779.910 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-10T17:36:19.910Z,1760117779.910 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-10T17:36:21.928Z,1760117781.928 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173621.00,A,3646.12336,N,12200.16023,W,27.583,71.14,101025,,,D*73 2025-10-10T17:36:21.931Z,1760117781.931 [NAL9602](INFO): GPS fix at 20251010T173621: (36.768723, -122.002670) 2025-10-10T17:36:21.966Z,1760117781.966 [Default:CheckIn:Read_GPS] Stopped 2025-10-10T17:36:21.966Z,1760117781.966 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-10T17:36:23.036Z,1760117783.036 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20251008T193449/Courier0142.lzma 2025-10-10T17:36:23.838Z,1760117783.838 [DataOverHttps](INFO): Moved sent file to Logs/20251008T193449/Courier0142.lzma.bak 2025-10-10T17:36:23.838Z,1760117783.838 [DataOverHttps](INFO): SBD MOMSN=26168844 2025-10-10T17:36:33.635Z,1760117793.635 [NAL9602](INFO): SBD MO Status=0, MOMSN=28628, MT Status=0, MTMSN=0 2025-10-10T17:36:33.635Z,1760117793.635 [NAL9602](INFO): No messages in MT queue 2025-10-10T17:36:49.785Z,1760117809.785 [DataOverHttps](INFO): Sending 266 bytes from file Logs/20251010T173332/Courier0000.lzma 2025-10-10T17:36:50.787Z,1760117810.787 [DataOverHttps](INFO): Moved sent file to Logs/20251010T173332/Courier0000.lzma.bak 2025-10-10T17:36:50.787Z,1760117810.787 [DataOverHttps](INFO): SBD MOMSN=26168846 2025-10-10T17:37:04.379Z,1760117824.379 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-10T17:37:09.288Z,1760117829.288 [DataOverHttps](INFO): Sending 170 bytes from file Logs/20251008T193449/Express0143.lzma 2025-10-10T17:37:10.290Z,1760117830.290 [DataOverHttps](INFO): Moved sent file to Logs/20251008T193449/Express0143.lzma.bak 2025-10-10T17:37:10.290Z,1760117830.290 [DataOverHttps](INFO): SBD MOMSN=26168851 2025-10-10T17:37:26.325Z,1760117846.325 [DataOverHttps](INFO): Sending 500 bytes from file Logs/20251010T173332/Express0001.lzma 2025-10-10T17:37:27.326Z,1760117847.326 [DataOverHttps](INFO): Moved sent file to Logs/20251010T173332/Express0001.lzma.bak 2025-10-10T17:37:27.326Z,1760117847.326 [DataOverHttps](INFO): SBD MOMSN=26168857 2025-10-10T17:37:45.200Z,1760117865.200 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20251010T173332/Express0004.lzma 2025-10-10T17:37:46.203Z,1760117866.203 [DataOverHttps](INFO): Moved sent file to Logs/20251010T173332/Express0004.lzma.bak 2025-10-10T17:37:46.203Z,1760117866.203 [DataOverHttps](INFO): SBD MOMSN=26168861 2025-10-10T17:37:47.626Z,1760117867.626 [Default:CheckIn:Read_Iridium] Stopped 2025-10-10T17:37:47.627Z,1760117867.627 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-10T17:37:47.627Z,1760117867.627 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-10T17:42:48.089Z,1760118168.089 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-10T17:42:48.089Z,1760118168.089 [Default:CheckIn:C.Wait] Stopped 2025-10-10T17:42:48.089Z,1760118168.089 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-10T17:42:48.089Z,1760118168.089 [Default:CheckIn:D] Running Loop=1 2025-10-10T17:42:48.495Z,1760118168.495 [Default:CheckIn:D] Stopped 2025-10-10T17:42:48.495Z,1760118168.495 [Default:CheckIn:E] Running Loop=1 2025-10-10T17:42:48.908Z,1760118168.908 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.855172 min 2025-10-10T17:42:48.916Z,1760118168.916 [Default:CheckIn:E] Stopped 2025-10-10T17:42:48.916Z,1760118168.916 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-10T17:42:48.916Z,1760118168.916 [Default:CheckIn] Stopped 2025-10-10T17:42:48.916Z,1760118168.916 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-10T17:42:48.917Z,1760118168.917 [Default:CheckIn](INFO): Running loop #7 2025-10-10T17:42:48.917Z,1760118168.917 [Default:CheckIn] Running Loop=7 2025-10-10T17:42:48.917Z,1760118168.917 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-10T17:42:48.917Z,1760118168.917 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-10T17:42:50.899Z,1760118170.899 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174250.00,A,3646.61479,N,12156.52854,W,26.806,74.65,101025,,,D*74 2025-10-10T17:42:50.914Z,1760118170.914 [NAL9602](INFO): GPS fix at 20251010T174250: (36.776913, -121.942142) 2025-10-10T17:42:50.925Z,1760118170.925 [Default:CheckIn:Read_GPS] Stopped 2025-10-10T17:42:50.925Z,1760118170.925 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-10T17:43:00.448Z,1760118180.448 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20251010T173332/Courier0006.lzma 2025-10-10T17:43:01.450Z,1760118181.450 [DataOverHttps](INFO): Moved sent file to Logs/20251010T173332/Courier0006.lzma.bak 2025-10-10T17:43:01.450Z,1760118181.450 [DataOverHttps](INFO): SBD MOMSN=26168870 2025-10-10T17:43:06.883Z,1760118186.883 [NAL9602](INFO): SBD MO Status=0, MOMSN=28629, MT Status=0, MTMSN=0 2025-10-10T17:43:06.883Z,1760118186.883 [NAL9602](INFO): No messages in MT queue 2025-10-10T17:43:17.384Z,1760118197.384 [DataOverHttps](INFO): Sending 175 bytes from file Logs/20251010T173332/Express0007.lzma 2025-10-10T17:43:18.386Z,1760118198.386 [DataOverHttps](INFO): Moved sent file to Logs/20251010T173332/Express0007.lzma.bak 2025-10-10T17:43:18.386Z,1760118198.386 [DataOverHttps](INFO): SBD MOMSN=26168873 2025-10-10T17:43:19.817Z,1760118199.817 [Default:CheckIn:Read_Iridium] Stopped 2025-10-10T17:43:19.817Z,1760118199.817 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-10T17:43:19.817Z,1760118199.817 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-10T17:43:37.582Z,1760118217.582 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-10T17:44:51.348Z,1760118291.348 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-10-10T17:45:22.484Z,1760118322.484 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-10-10T17:45:56.563Z,1760118356.563 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-10-10T17:48:20.425Z,1760118500.425 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-10T17:48:20.425Z,1760118500.425 [Default:CheckIn:C.Wait] Stopped 2025-10-10T17:48:20.425Z,1760118500.425 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-10T17:48:20.426Z,1760118500.426 [Default:CheckIn:D] Running Loop=1 2025-10-10T17:48:20.828Z,1760118500.828 [Default:CheckIn:D] Stopped 2025-10-10T17:48:20.828Z,1760118500.828 [Default:CheckIn:E] Running Loop=1 2025-10-10T17:48:21.240Z,1760118501.240 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 48.394055 min 2025-10-10T17:48:21.241Z,1760118501.241 [Default:CheckIn:E] Stopped 2025-10-10T17:48:21.241Z,1760118501.241 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-10T17:48:21.241Z,1760118501.241 [Default:CheckIn] Stopped 2025-10-10T17:48:21.241Z,1760118501.241 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-10T17:48:21.241Z,1760118501.241 [Default:CheckIn](INFO): Running loop #8 2025-10-10T17:48:21.241Z,1760118501.241 [Default:CheckIn] Running Loop=8 2025-10-10T17:48:21.241Z,1760118501.241 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-10T17:48:21.241Z,1760118501.241 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-10T17:48:23.223Z,1760118503.223 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174822.00,A,3647.38175,N,12153.70139,W,30.285,94.27,101025,,,D*76 2025-10-10T17:48:23.226Z,1760118503.226 [NAL9602](INFO): GPS fix at 20251010T174822: (36.789696, -121.895023) 2025-10-10T17:48:23.250Z,1760118503.250 [Default:CheckIn:Read_GPS] Stopped 2025-10-10T17:48:23.250Z,1760118503.250 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-10T17:48:25.480Z,1760118505.480 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20251010T173332/Courier0009.lzma 2025-10-10T17:48:26.482Z,1760118506.482 [DataOverHttps](INFO): Moved sent file to Logs/20251010T173332/Courier0009.lzma.bak 2025-10-10T17:48:26.482Z,1760118506.482 [DataOverHttps](INFO): SBD MOMSN=26168931 2025-10-10T17:48:38.167Z,1760118518.167 [NAL9602](INFO): SBD MO Status=0, MOMSN=28630, MT Status=0, MTMSN=0 2025-10-10T17:48:38.167Z,1760118518.167 [NAL9602](INFO): No messages in MT queue 2025-10-10T17:48:42.556Z,1760118522.556 [DataOverHttps](INFO): Sending 175 bytes from file Logs/20251010T173332/Express0010.lzma 2025-10-10T17:48:43.558Z,1760118523.558 [DataOverHttps](INFO): Moved sent file to Logs/20251010T173332/Express0010.lzma.bak 2025-10-10T17:48:43.558Z,1760118523.558 [DataOverHttps](INFO): SBD MOMSN=26168942 2025-10-10T17:48:44.641Z,1760118524.641 [Default:CheckIn:Read_Iridium] Stopped 2025-10-10T17:48:44.641Z,1760118524.641 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-10T17:48:44.641Z,1760118524.641 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-10T17:49:08.915Z,1760118548.915 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-10T17:50:47.256Z,1760118647.256 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2025-10-10T17:53:45.261Z,1760118825.261 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-10T17:53:45.261Z,1760118825.261 [Default:CheckIn:C.Wait] Stopped 2025-10-10T17:53:45.261Z,1760118825.261 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-10T17:53:45.261Z,1760118825.261 [Default:CheckIn:D] Running Loop=1 2025-10-10T17:53:45.665Z,1760118825.665 [Default:CheckIn:D] Stopped 2025-10-10T17:53:45.665Z,1760118825.665 [Default:CheckIn:E] Running Loop=1 2025-10-10T17:53:46.078Z,1760118826.078 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 53.808004 min 2025-10-10T17:53:46.078Z,1760118826.078 [Default:CheckIn:E] Stopped 2025-10-10T17:53:46.078Z,1760118826.078 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-10T17:53:46.078Z,1760118826.078 [Default:CheckIn] Stopped 2025-10-10T17:53:46.078Z,1760118826.078 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-10T17:53:46.078Z,1760118826.078 [Default:CheckIn](INFO): Running loop #9 2025-10-10T17:53:46.078Z,1760118826.078 [Default:CheckIn] Running Loop=9 2025-10-10T17:53:46.079Z,1760118826.079 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-10T17:53:46.079Z,1760118826.079 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-10T17:53:48.082Z,1760118828.082 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175347.00,A,3647.85344,N,12151.07685,W,1.749,38.83,101025,,,D*44 2025-10-10T17:53:48.084Z,1760118828.084 [NAL9602](INFO): GPS fix at 20251010T175347: (36.797557, -121.851281) 2025-10-10T17:53:48.115Z,1760118828.115 [Default:CheckIn:Read_GPS] Stopped 2025-10-10T17:53:48.115Z,1760118828.115 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-10T17:53:55.368Z,1760118835.368 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20251010T173332/Courier0012.lzma 2025-10-10T17:53:56.370Z,1760118836.370 [DataOverHttps](INFO): Moved sent file to Logs/20251010T173332/Courier0012.lzma.bak 2025-10-10T17:53:56.370Z,1760118836.370 [DataOverHttps](INFO): SBD MOMSN=26168980 2025-10-10T17:54:03.431Z,1760118843.431 [NAL9602](INFO): SBD MO Status=0, MOMSN=28631, MT Status=0, MTMSN=0 2025-10-10T17:54:03.432Z,1760118843.432 [NAL9602](INFO): No messages in MT queue 2025-10-10T17:54:12.312Z,1760118852.312 [DataOverHttps](INFO): Sending 175 bytes from file Logs/20251010T173332/Express0013.lzma 2025-10-10T17:54:13.314Z,1760118853.314 [DataOverHttps](INFO): Moved sent file to Logs/20251010T173332/Express0013.lzma.bak 2025-10-10T17:54:13.314Z,1760118853.314 [DataOverHttps](INFO): SBD MOMSN=26168983 2025-10-10T17:54:14.760Z,1760118854.760 [Default:CheckIn:Read_Iridium] Stopped 2025-10-10T17:54:14.760Z,1760118854.760 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-10T17:54:14.760Z,1760118854.760 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-10T17:54:34.142Z,1760118874.142 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-10T17:55:56.581Z,1760118956.581 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-10-10T17:59:15.453Z,1760119155.453 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-10T17:59:15.454Z,1760119155.454 [Default:CheckIn:C.Wait] Stopped 2025-10-10T17:59:15.454Z,1760119155.454 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-10T17:59:15.454Z,1760119155.454 [Default:CheckIn:D] Running Loop=1 2025-10-10T17:59:15.848Z,1760119155.848 [Default:CheckIn:D] Stopped 2025-10-10T17:59:15.848Z,1760119155.848 [Default:CheckIn:E] Running Loop=1 2025-10-10T17:59:16.260Z,1760119156.260 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 59.311047 min 2025-10-10T17:59:16.260Z,1760119156.260 [Default:CheckIn:E] Stopped 2025-10-10T17:59:16.260Z,1760119156.260 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-10T17:59:16.260Z,1760119156.260 [Default:CheckIn] Stopped 2025-10-10T17:59:16.260Z,1760119156.260 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-10T17:59:16.261Z,1760119156.261 [Default:CheckIn](INFO): Running loop #10 2025-10-10T17:59:16.261Z,1760119156.261 [Default:CheckIn] Running Loop=10 2025-10-10T17:59:16.261Z,1760119156.261 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-10T17:59:16.261Z,1760119156.261 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-10T17:59:18.267Z,1760119158.267 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175917.00,A,3648.10090,N,12150.51528,W,23.404,82.97,101025,,,D*7A 2025-10-10T17:59:18.269Z,1760119158.269 [NAL9602](INFO): GPS fix at 20251010T175917: (36.801682, -121.841921) 2025-10-10T17:59:18.281Z,1760119158.281 [Default:CheckIn:Read_GPS] Stopped 2025-10-10T17:59:18.281Z,1760119158.281 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-10T17:59:27.244Z,1760119167.244 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20251010T173332/Courier0015.lzma 2025-10-10T17:59:28.246Z,1760119168.246 [DataOverHttps](INFO): Moved sent file to Logs/20251010T173332/Courier0015.lzma.bak 2025-10-10T17:59:28.246Z,1760119168.246 [DataOverHttps](INFO): SBD MOMSN=26169034 2025-10-10T17:59:36.847Z,1760119176.847 [NAL9602](INFO): SBD MO Status=0, MOMSN=28632, MT Status=0, MTMSN=0 2025-10-10T17:59:36.847Z,1760119176.847 [NAL9602](INFO): No messages in MT queue 2025-10-10T17:59:55.140Z,1760119195.140 [DataOverHttps](INFO): Sending 174 bytes from file Logs/20251010T173332/Express0016.lzma 2025-10-10T17:59:56.144Z,1760119196.144 [DataOverHttps](INFO): Moved sent file to Logs/20251010T173332/Express0016.lzma.bak 2025-10-10T17:59:56.144Z,1760119196.144 [DataOverHttps](INFO): SBD MOMSN=26169037 2025-10-10T17:59:57.483Z,1760119197.483 [Default:CheckIn:Read_Iridium] Stopped 2025-10-10T17:59:57.484Z,1760119197.484 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-10T17:59:57.484Z,1760119197.484 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-10T18:00:07.557Z,1760119207.557 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-10T18:00:25.743Z,1760119225.743 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-10-10T18:00:40.680Z,1760119240.680 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-10-10T18:00:54.424Z,1760119254.424 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-10-10T18:01:08.160Z,1760119268.160 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-10-10T18:01:21.897Z,1760119281.897 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-10-10T18:01:35.633Z,1760119295.633 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-10-10T18:01:49.368Z,1760119309.368 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-10-10T18:02:03.511Z,1760119323.511 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-10-10T18:02:17.244Z,1760119337.244 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-10-10T18:02:30.982Z,1760119350.982 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-10-10T18:04:58.101Z,1760119498.101 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-10T18:04:58.101Z,1760119498.101 [Default:CheckIn:C.Wait] Stopped 2025-10-10T18:04:58.101Z,1760119498.101 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-10T18:04:58.102Z,1760119498.102 [Default:CheckIn:D] Running Loop=1 2025-10-10T18:04:58.506Z,1760119498.506 [Default:CheckIn:D] Stopped 2025-10-10T18:04:58.506Z,1760119498.506 [Default:CheckIn:E] Running Loop=1 2025-10-10T18:04:58.917Z,1760119498.917 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 65.022021 min 2025-10-10T18:04:58.917Z,1760119498.917 [Default:CheckIn:E] Stopped 2025-10-10T18:04:58.918Z,1760119498.918 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-10T18:04:58.918Z,1760119498.918 [Default:CheckIn] Stopped 2025-10-10T18:04:58.918Z,1760119498.918 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-10T18:04:58.918Z,1760119498.918 [Default:CheckIn](INFO): Running loop #11 2025-10-10T18:04:58.918Z,1760119498.918 [Default:CheckIn] Running Loop=11 2025-10-10T18:04:58.918Z,1760119498.918 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-10T18:04:58.918Z,1760119498.918 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-10T18:05:00.927Z,1760119500.927 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180500.00,A,3648.33212,N,12147.91773,W,15.881,93.96,101025,,,D*70 2025-10-10T18:05:00.930Z,1760119500.930 [NAL9602](INFO): GPS fix at 20251010T180500: (36.805535, -121.798629) 2025-10-10T18:05:00.956Z,1760119500.956 [Default:CheckIn:Read_GPS] Stopped 2025-10-10T18:05:00.957Z,1760119500.957 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-10T18:05:03.577Z,1760119503.577 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2025-10-10T18:05:06.972Z,1760119506.972 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-10-10T18:05:11.112Z,1760119511.112 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20251010T173332/Courier0018.lzma 2025-10-10T18:05:12.114Z,1760119512.114 [DataOverHttps](INFO): Moved sent file to Logs/20251010T173332/Courier0018.lzma.bak 2025-10-10T18:05:12.114Z,1760119512.114 [DataOverHttps](INFO): SBD MOMSN=26169086 2025-10-10T18:05:19.905Z,1760119519.905 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-10-10T18:05:28.029Z,1760119528.029 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20251010T173332/Express0019.lzma 2025-10-10T18:05:29.031Z,1760119529.031 [DataOverHttps](INFO): Moved sent file to Logs/20251010T173332/Express0019.lzma.bak 2025-10-10T18:05:29.031Z,1760119529.031 [DataOverHttps](INFO): SBD MOMSN=26169089 2025-10-10T18:05:30.421Z,1760119530.421 [Default:CheckIn:Read_Iridium] Stopped 2025-10-10T18:05:30.421Z,1760119530.421 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-10T18:05:30.421Z,1760119530.421 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-10T18:05:32.834Z,1760119532.834 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-10-10T18:05:45.352Z,1760119545.352 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-10-10T18:05:56.272Z,1760119556.272 [NAL9602](INFO): SBD MO Status=2, MOMSN=28633, MT Status=2, MTMSN=0 2025-10-10T18:05:56.273Z,1760119556.273 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-10-10T18:05:56.688Z,1760119556.688 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-10-10T18:05:58.292Z,1760119558.292 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-10-10T18:06:05.976Z,1760119565.976 [NAL9602](INFO): SBD MO Status=0, MOMSN=28633, MT Status=0, MTMSN=0 2025-10-10T18:06:05.976Z,1760119565.976 [NAL9602](INFO): No messages in MT queue 2025-10-10T18:06:11.208Z,1760119571.208 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-10-10T18:06:24.140Z,1760119584.140 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-10-10T18:06:36.746Z,1760119596.746 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-10T18:06:37.069Z,1760119597.069 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-10-10T18:06:49.996Z,1760119609.996 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-10-10T18:07:02.924Z,1760119622.924 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-10-10T18:07:15.852Z,1760119635.852 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-10-10T18:07:28.802Z,1760119648.802 [BPC1](ERROR): Battery stick #16 (s/n: 00F8) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-10-10T18:07:28.803Z,1760119648.803 [BPC1](ERROR): Battery stick #17 (s/n: 0084) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AC0. 2025-10-10T18:07:28.804Z,1760119648.804 [BPC1](ERROR): Battery stick #24 (s/n: 01D3) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-10-10T18:07:28.809Z,1760119648.809 [BPC1](ERROR): Battery stick #29 (s/n: 00A7) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-10-10T18:07:28.809Z,1760119648.809 [BPC1](ERROR): Battery stick #38 (s/n: 01BC) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-10-10T18:07:28.810Z,1760119648.810 [BPC1](ERROR): Battery stick #51 (s/n: 01D6) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-10-10T18:07:28.811Z,1760119648.811 [BPC1](ERROR): Battery stick #52 (s/n: 0160) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-10-10T18:07:28.816Z,1760119648.816 [BPC1](ERROR): Battery stick #57 (s/n: 019F) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-10-10T18:07:28.817Z,1760119648.817 [BPC1](ERROR): Battery stick #59 (s/n: 0184) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-10-10T18:07:28.817Z,1760119648.817 [BPC1](INFO): Calculating totals. Valid battery stick count: 39. Valid reserve battery stick count: 4. 2025-10-10T18:07:28.820Z,1760119648.820 [BPC1](INFO): Received data from all battery sticks. 2025-10-10T18:10:31.009Z,1760119831.009 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-10T18:10:31.009Z,1760119831.009 [Default:CheckIn:C.Wait] Stopped 2025-10-10T18:10:31.009Z,1760119831.009 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-10T18:10:31.009Z,1760119831.009 [Default:CheckIn:D] Running Loop=1 2025-10-10T18:10:31.405Z,1760119831.405 [Default:CheckIn:D] Stopped 2025-10-10T18:10:31.405Z,1760119831.405 [Default:CheckIn:E] Running Loop=1 2025-10-10T18:10:31.804Z,1760119831.804 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 70.570337 min 2025-10-10T18:10:31.805Z,1760119831.805 [Default:CheckIn:E] Stopped 2025-10-10T18:10:31.805Z,1760119831.805 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-10T18:10:31.805Z,1760119831.805 [Default:CheckIn] Stopped 2025-10-10T18:10:31.805Z,1760119831.805 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-10T18:10:31.805Z,1760119831.805 [Default:CheckIn](INFO): Running loop #12 2025-10-10T18:10:31.805Z,1760119831.805 [Default:CheckIn] Running Loop=12 2025-10-10T18:10:31.805Z,1760119831.805 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-10T18:10:31.805Z,1760119831.805 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-10T18:10:33.815Z,1760119833.815 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181033.00,A,3648.37003,N,12147.12773,W,3.752,186.35,101025,,,D*73 2025-10-10T18:10:33.817Z,1760119833.817 [NAL9602](INFO): GPS fix at 20251010T181033: (36.806167, -121.785462) 2025-10-10T18:10:33.846Z,1760119833.846 [Default:CheckIn:Read_GPS] Stopped 2025-10-10T18:10:33.846Z,1760119833.846 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-10T18:10:41.608Z,1760119841.608 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20251010T173332/Courier0021.lzma 2025-10-10T18:10:42.610Z,1760119842.610 [DataOverHttps](INFO): Moved sent file to Logs/20251010T173332/Courier0021.lzma.bak 2025-10-10T18:10:42.610Z,1760119842.610 [DataOverHttps](INFO): SBD MOMSN=26169136 2025-10-10T18:10:59.719Z,1760119859.719 [NAL9602](INFO): SBD MO Status=0, MOMSN=28634, MT Status=0, MTMSN=0 2025-10-10T18:10:59.719Z,1760119859.719 [NAL9602](INFO): No messages in MT queue 2025-10-10T18:11:01.744Z,1760119861.744 [DataOverHttps](INFO): Sending 173 bytes from file Logs/20251010T173332/Express0022.lzma 2025-10-10T18:11:02.746Z,1760119862.746 [DataOverHttps](INFO): Moved sent file to Logs/20251010T173332/Express0022.lzma.bak 2025-10-10T18:11:02.746Z,1760119862.746 [DataOverHttps](INFO): SBD MOMSN=26169139 2025-10-10T18:11:04.173Z,1760119864.173 [Default:CheckIn:Read_Iridium] Stopped 2025-10-10T18:11:04.173Z,1760119864.173 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-10T18:11:04.173Z,1760119864.173 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-10T18:11:30.442Z,1760119890.442 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-10T18:15:56.687Z,1760120156.687 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-10-10T18:16:04.779Z,1760120164.779 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-10T18:16:04.779Z,1760120164.779 [Default:CheckIn:C.Wait] Stopped 2025-10-10T18:16:04.779Z,1760120164.779 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-10T18:16:04.780Z,1760120164.780 [Default:CheckIn:D] Running Loop=1 2025-10-10T18:16:05.175Z,1760120165.175 [Default:CheckIn:D] Stopped 2025-10-10T18:16:05.175Z,1760120165.175 [Default:CheckIn:E] Running Loop=1 2025-10-10T18:16:05.582Z,1760120165.582 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 76.133179 min 2025-10-10T18:16:05.582Z,1760120165.582 [Default:CheckIn:E] Stopped 2025-10-10T18:16:05.582Z,1760120165.582 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-10T18:16:05.582Z,1760120165.582 [Default:CheckIn] Stopped 2025-10-10T18:16:05.582Z,1760120165.582 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-10T18:16:05.582Z,1760120165.582 [Default:CheckIn](INFO): Running loop #13 2025-10-10T18:16:05.583Z,1760120165.583 [Default:CheckIn] Running Loop=13 2025-10-10T18:16:05.583Z,1760120165.583 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-10T18:16:05.583Z,1760120165.583 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-10T18:16:07.595Z,1760120167.595 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181606.00,A,3648.15089,N,12147.21941,W,0.039,199.30,101025,,,D*7C 2025-10-10T18:16:07.597Z,1760120167.597 [NAL9602](INFO): GPS fix at 20251010T181606: (36.802515, -121.786990) 2025-10-10T18:16:07.624Z,1760120167.624 [Default:CheckIn:Read_GPS] Stopped 2025-10-10T18:16:07.625Z,1760120167.625 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-10T18:16:15.132Z,1760120175.132 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20251010T173332/Courier0024.lzma 2025-10-10T18:16:16.134Z,1760120176.134 [DataOverHttps](INFO): Moved sent file to Logs/20251010T173332/Courier0024.lzma.bak 2025-10-10T18:16:16.134Z,1760120176.134 [DataOverHttps](INFO): SBD MOMSN=26169179 2025-10-10T18:16:21.735Z,1760120181.735 [NAL9602](INFO): SBD MO Status=0, MOMSN=28635, MT Status=0, MTMSN=0 2025-10-10T18:16:21.735Z,1760120181.735 [NAL9602](INFO): No messages in MT queue 2025-10-10T18:16:31.968Z,1760120191.968 [DataOverHttps](INFO): Sending 177 bytes from file Logs/20251010T173332/Express0025.lzma 2025-10-10T18:16:32.970Z,1760120192.970 [DataOverHttps](INFO): Moved sent file to Logs/20251010T173332/Express0025.lzma.bak 2025-10-10T18:16:32.970Z,1760120192.970 [DataOverHttps](INFO): SBD MOMSN=26169182 2025-10-10T18:16:34.275Z,1760120194.275 [Default:CheckIn:Read_Iridium] Stopped 2025-10-10T18:16:34.275Z,1760120194.275 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-10T18:16:34.275Z,1760120194.275 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-10T18:16:52.433Z,1760120212.433 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-10T18:21:34.861Z,1760120494.861 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-10T18:21:34.861Z,1760120494.861 [Default:CheckIn:C.Wait] Stopped 2025-10-10T18:21:34.861Z,1760120494.861 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-10T18:21:34.861Z,1760120494.861 [Default:CheckIn:D] Running Loop=1 2025-10-10T18:21:35.252Z,1760120495.252 [Default:CheckIn:D] Stopped 2025-10-10T18:21:35.252Z,1760120495.252 [Default:CheckIn:E] Running Loop=1 2025-10-10T18:21:35.655Z,1760120495.655 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 81.634448 min 2025-10-10T18:21:35.655Z,1760120495.655 [Default:CheckIn:E] Stopped 2025-10-10T18:21:35.655Z,1760120495.655 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-10T18:21:35.655Z,1760120495.655 [Default:CheckIn] Stopped 2025-10-10T18:21:35.655Z,1760120495.655 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-10T18:21:35.656Z,1760120495.656 [Default:CheckIn](INFO): Running loop #14 2025-10-10T18:21:35.656Z,1760120495.656 [Default:CheckIn] Running Loop=14 2025-10-10T18:21:35.656Z,1760120495.656 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-10T18:21:35.657Z,1760120495.657 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-10T18:21:37.670Z,1760120497.670 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182136.00,A,3648.15601,N,12147.22773,W,0.058,289.58,101025,,,D*7A 2025-10-10T18:21:37.672Z,1760120497.672 [NAL9602](INFO): GPS fix at 20251010T182136: (36.802600, -121.787129) 2025-10-10T18:21:37.683Z,1760120497.683 [Default:CheckIn:Read_GPS] Stopped 2025-10-10T18:21:37.684Z,1760120497.684 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-10T18:21:45.272Z,1760120505.272 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20251010T173332/Courier0027.lzma 2025-10-10T18:21:46.275Z,1760120506.275 [DataOverHttps](INFO): Moved sent file to Logs/20251010T173332/Courier0027.lzma.bak 2025-10-10T18:21:46.276Z,1760120506.276 [DataOverHttps](INFO): SBD MOMSN=26169222 2025-10-10T18:22:02.192Z,1760120522.192 [DataOverHttps](INFO): Sending 154 bytes from file Logs/20251010T173332/Express0028.lzma 2025-10-10T18:22:03.195Z,1760120523.195 [DataOverHttps](INFO): Moved sent file to Logs/20251010T173332/Express0028.lzma.bak 2025-10-10T18:22:03.195Z,1760120523.195 [DataOverHttps](INFO): SBD MOMSN=26169225 2025-10-10T18:22:04.341Z,1760120524.341 [Default:CheckIn:Read_Iridium] Stopped 2025-10-10T18:22:04.341Z,1760120524.341 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-10T18:22:04.341Z,1760120524.341 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-10T18:22:06.755Z,1760120526.755 [NAL9602](INFO): SBD MO Status=0, MOMSN=28636, MT Status=0, MTMSN=0 2025-10-10T18:22:06.755Z,1760120526.755 [NAL9602](INFO): No messages in MT queue 2025-10-10T18:22:37.453Z,1760120557.453 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-10T18:25:57.037Z,1760120757.037 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-10-10T18:27:04.912Z,1760120824.912 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-10T18:27:04.928Z,1760120824.928 [Default:CheckIn:C.Wait] Stopped 2025-10-10T18:27:04.928Z,1760120824.928 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-10T18:27:04.928Z,1760120824.928 [Default:CheckIn:D] Running Loop=1 2025-10-10T18:27:05.315Z,1760120825.315 [Default:CheckIn:D] Stopped 2025-10-10T18:27:05.315Z,1760120825.315 [Default:CheckIn:E] Running Loop=1 2025-10-10T18:27:05.717Z,1760120825.717 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 87.135506 min 2025-10-10T18:27:05.718Z,1760120825.718 [Default:CheckIn:E] Stopped 2025-10-10T18:27:05.718Z,1760120825.718 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-10T18:27:05.718Z,1760120825.718 [Default:CheckIn] Stopped 2025-10-10T18:27:05.718Z,1760120825.718 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-10T18:27:05.718Z,1760120825.718 [Default:CheckIn](INFO): Running loop #15 2025-10-10T18:27:05.718Z,1760120825.718 [Default:CheckIn] Running Loop=15 2025-10-10T18:27:05.718Z,1760120825.718 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-10T18:27:05.718Z,1760120825.718 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-10T18:27:07.736Z,1760120827.736 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182707.00,A,3648.15494,N,12147.22620,W,0.019,289.58,101025,,,D*72 2025-10-10T18:27:07.738Z,1760120827.738 [NAL9602](INFO): GPS fix at 20251010T182707: (36.802582, -121.787103) 2025-10-10T18:27:07.749Z,1760120827.749 [Default:CheckIn:Read_GPS] Stopped 2025-10-10T18:27:07.749Z,1760120827.749 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-10T18:27:15.136Z,1760120835.136 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20251010T173332/Courier0030.lzma 2025-10-10T18:27:16.138Z,1760120836.138 [DataOverHttps](INFO): Moved sent file to Logs/20251010T173332/Courier0030.lzma.bak 2025-10-10T18:27:16.138Z,1760120836.138 [DataOverHttps](INFO): SBD MOMSN=26169263 2025-10-10T18:27:31.996Z,1760120851.996 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20251010T173332/Express0031.lzma 2025-10-10T18:27:32.999Z,1760120852.999 [DataOverHttps](INFO): Moved sent file to Logs/20251010T173332/Express0031.lzma.bak 2025-10-10T18:27:32.999Z,1760120852.999 [DataOverHttps](INFO): SBD MOMSN=26169266 2025-10-10T18:27:34.400Z,1760120854.400 [Default:CheckIn:Read_Iridium] Stopped 2025-10-10T18:27:34.400Z,1760120854.400 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-10T18:27:34.400Z,1760120854.400 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-10T18:28:26.508Z,1760120906.508 [NAL9602](INFO): SBD MO Status=2, MOMSN=28637, MT Status=2, MTMSN=0 2025-10-10T18:28:26.508Z,1760120906.508 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-10-10T18:29:46.503Z,1760120986.503 [NAL9602](INFO): SBD MO Status=2, MOMSN=28637, MT Status=2, MTMSN=0 2025-10-10T18:29:46.503Z,1760120986.503 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-10-10T18:30:14.376Z,1760121014.376 [NAL9602](INFO): SBD MO Status=0, MOMSN=28637, MT Status=0, MTMSN=0 2025-10-10T18:30:14.381Z,1760121014.381 [NAL9602](INFO): No messages in MT queue 2025-10-10T18:30:45.081Z,1760121045.081 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-10T18:32:34.979Z,1760121154.979 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-10T18:32:34.979Z,1760121154.979 [Default:CheckIn:C.Wait] Stopped 2025-10-10T18:32:34.979Z,1760121154.979 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-10T18:32:34.980Z,1760121154.980 [Default:CheckIn:D] Running Loop=1 2025-10-10T18:32:35.388Z,1760121155.388 [Default:CheckIn:D] Stopped 2025-10-10T18:32:35.389Z,1760121155.389 [Default:CheckIn:E] Running Loop=1 2025-10-10T18:32:35.808Z,1760121155.808 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 92.636727 min 2025-10-10T18:32:35.808Z,1760121155.808 [Default:CheckIn:E] Stopped 2025-10-10T18:32:35.808Z,1760121155.808 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-10T18:32:35.808Z,1760121155.808 [Default:CheckIn] Stopped 2025-10-10T18:32:35.808Z,1760121155.808 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-10T18:32:35.808Z,1760121155.808 [Default:CheckIn](INFO): Running loop #16 2025-10-10T18:32:35.809Z,1760121155.809 [Default:CheckIn] Running Loop=16 2025-10-10T18:32:35.809Z,1760121155.809 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-10T18:32:35.809Z,1760121155.809 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-10T18:32:37.796Z,1760121157.796 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183237.00,A,3648.15575,N,12147.22640,W,0.039,289.58,101025,,,D*7F 2025-10-10T18:32:37.798Z,1760121157.798 [NAL9602](INFO): GPS fix at 20251010T183237: (36.802596, -121.787107) 2025-10-10T18:32:37.809Z,1760121157.809 [Default:CheckIn:Read_GPS] Stopped 2025-10-10T18:32:37.809Z,1760121157.809 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-10T18:32:45.124Z,1760121165.124 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20251010T173332/Courier0033.lzma 2025-10-10T18:32:46.126Z,1760121166.126 [DataOverHttps](INFO): Moved sent file to Logs/20251010T173332/Courier0033.lzma.bak 2025-10-10T18:32:46.126Z,1760121166.126 [DataOverHttps](INFO): SBD MOMSN=26169306 2025-10-10T18:33:04.844Z,1760121184.844 [DataOverHttps](INFO): Sending 162 bytes from file Logs/20251010T173332/Express0034.lzma 2025-10-10T18:33:05.846Z,1760121185.846 [DataOverHttps](INFO): Moved sent file to Logs/20251010T173332/Express0034.lzma.bak 2025-10-10T18:33:05.846Z,1760121185.846 [DataOverHttps](INFO): SBD MOMSN=26169309 2025-10-10T18:33:06.940Z,1760121186.940 [Default:CheckIn:Read_Iridium] Stopped 2025-10-10T18:33:06.941Z,1760121186.941 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-10T18:33:06.941Z,1760121186.941 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-10T18:33:43.291Z,1760121223.291 [NAL9602](INFO): SBD MO Status=2, MOMSN=28638, MT Status=2, MTMSN=0 2025-10-10T18:33:43.291Z,1760121223.291 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-10-10T18:34:49.184Z,1760121289.184 [NAL9602](INFO): SBD MO Status=2, MOMSN=28638, MT Status=2, MTMSN=0 2025-10-10T18:34:49.184Z,1760121289.184 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-10-10T18:35:04.943Z,1760121304.943 [NAL9602](INFO): SBD MO Status=0, MOMSN=28638, MT Status=0, MTMSN=0 2025-10-10T18:35:04.943Z,1760121304.943 [NAL9602](INFO): No messages in MT queue 2025-10-10T18:35:35.641Z,1760121335.641 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-10T18:35:57.058Z,1760121357.058 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-10-10T18:38:07.564Z,1760121487.564 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-10T18:38:07.564Z,1760121487.564 [Default:CheckIn:C.Wait] Stopped 2025-10-10T18:38:07.564Z,1760121487.564 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-10T18:38:07.566Z,1760121487.566 [Default:CheckIn:D] Running Loop=1 2025-10-10T18:38:07.970Z,1760121487.970 [Default:CheckIn:D] Stopped 2025-10-10T18:38:07.970Z,1760121487.970 [Default:CheckIn:E] Running Loop=1 2025-10-10T18:38:08.380Z,1760121488.380 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 98.179761 min 2025-10-10T18:38:08.380Z,1760121488.380 [Default:CheckIn:E] Stopped 2025-10-10T18:38:08.381Z,1760121488.381 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-10T18:38:08.381Z,1760121488.381 [Default:CheckIn] Stopped 2025-10-10T18:38:08.381Z,1760121488.381 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-10T18:38:08.381Z,1760121488.381 [Default:CheckIn](INFO): Running loop #17 2025-10-10T18:38:08.381Z,1760121488.381 [Default:CheckIn] Running Loop=17 2025-10-10T18:38:08.381Z,1760121488.381 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-10T18:38:08.381Z,1760121488.381 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-10T18:38:10.386Z,1760121490.386 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183809.00,A,3648.17348,N,12147.27750,W,1.808,286.81,101025,,,D*77 2025-10-10T18:38:10.388Z,1760121490.388 [NAL9602](INFO): GPS fix at 20251010T183809: (36.802891, -121.787958) 2025-10-10T18:38:10.417Z,1760121490.417 [Default:CheckIn:Read_GPS] Stopped 2025-10-10T18:38:10.417Z,1760121490.417 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-10T18:38:18.112Z,1760121498.112 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20251010T173332/Courier0036.lzma 2025-10-10T18:38:19.114Z,1760121499.114 [DataOverHttps](INFO): Moved sent file to Logs/20251010T173332/Courier0036.lzma.bak 2025-10-10T18:38:19.114Z,1760121499.114 [DataOverHttps](INFO): SBD MOMSN=26169348 2025-10-10T18:38:35.000Z,1760121515.000 [DataOverHttps](INFO): Sending 175 bytes from file Logs/20251010T173332/Express0037.lzma 2025-10-10T18:38:36.002Z,1760121516.002 [DataOverHttps](INFO): Moved sent file to Logs/20251010T173332/Express0037.lzma.bak 2025-10-10T18:38:36.002Z,1760121516.002 [DataOverHttps](INFO): SBD MOMSN=26169351 2025-10-10T18:38:37.465Z,1760121517.465 [Default:CheckIn:Read_Iridium] Stopped 2025-10-10T18:38:37.465Z,1760121517.465 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-10T18:38:37.466Z,1760121517.466 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-10T18:43:12.223Z,1760121792.223 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-10-10T18:43:38.097Z,1760121818.097 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-10T18:43:38.097Z,1760121818.097 [Default:CheckIn:C.Wait] Stopped 2025-10-10T18:43:38.097Z,1760121818.097 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-10T18:43:38.098Z,1760121818.098 [Default:CheckIn:D] Running Loop=1 2025-10-10T18:43:38.495Z,1760121818.495 [Default:CheckIn:D] Stopped 2025-10-10T18:43:38.495Z,1760121818.495 [Default:CheckIn:E] Running Loop=1 2025-10-10T18:43:38.897Z,1760121818.897 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 103.688509 min 2025-10-10T18:43:38.897Z,1760121818.897 [Default:CheckIn:E] Stopped 2025-10-10T18:43:38.897Z,1760121818.897 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-10T18:43:38.897Z,1760121818.897 [Default:CheckIn] Stopped 2025-10-10T18:43:38.897Z,1760121818.897 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-10T18:43:38.898Z,1760121818.898 [Default:CheckIn](INFO): Running loop #18 2025-10-10T18:43:38.898Z,1760121818.898 [Default:CheckIn] Running Loop=18 2025-10-10T18:43:38.898Z,1760121818.898 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-10T18:43:38.898Z,1760121818.898 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-10T18:43:40.909Z,1760121820.909 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184340.00,A,3648.17573,N,12147.27969,W,0.078,234.89,101025,,,A*76 2025-10-10T18:43:40.911Z,1760121820.911 [NAL9602](INFO): GPS fix at 20251010T184340: (36.802929, -121.787995) 2025-10-10T18:43:40.922Z,1760121820.922 [Default:CheckIn:Read_GPS] Stopped 2025-10-10T18:43:40.922Z,1760121820.922 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-10T18:43:50.304Z,1760121830.304 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20251010T173332/Courier0039.lzma 2025-10-10T18:43:51.306Z,1760121831.306 [DataOverHttps](INFO): Moved sent file to Logs/20251010T173332/Courier0039.lzma.bak 2025-10-10T18:43:51.306Z,1760121831.306 [DataOverHttps](INFO): SBD MOMSN=26169390 2025-10-10T18:44:12.332Z,1760121852.332 [DataOverHttps](INFO): Sending 257 bytes from file Logs/20251010T173332/Express0040.lzma 2025-10-10T18:44:13.335Z,1760121853.335 [DataOverHttps](INFO): Moved sent file to Logs/20251010T173332/Express0040.lzma.bak 2025-10-10T18:44:13.335Z,1760121853.335 [DataOverHttps](INFO): SBD MOMSN=26169393 2025-10-10T18:44:13.633Z,1760121853.633 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-10T18:44:14.455Z,1760121854.455 [Default:CheckIn:Read_Iridium] Stopped 2025-10-10T18:44:14.455Z,1760121854.455 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-10T18:44:14.456Z,1760121854.456 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-10T18:45:57.105Z,1760121957.105 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-10-10T18:49:15.187Z,1760122155.187 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-10T18:49:15.187Z,1760122155.187 [Default:CheckIn:C.Wait] Stopped 2025-10-10T18:49:15.187Z,1760122155.187 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-10T18:49:15.188Z,1760122155.188 [Default:CheckIn:D] Running Loop=1 2025-10-10T18:49:15.595Z,1760122155.595 [Default:CheckIn:D] Stopped 2025-10-10T18:49:15.595Z,1760122155.595 [Default:CheckIn:E] Running Loop=1 2025-10-10T18:49:15.988Z,1760122155.988 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 109.306836 min 2025-10-10T18:49:15.988Z,1760122155.988 [Default:CheckIn:E] Stopped 2025-10-10T18:49:15.989Z,1760122155.989 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-10T18:49:15.989Z,1760122155.989 [Default:CheckIn] Stopped 2025-10-10T18:49:15.989Z,1760122155.989 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-10T18:49:15.989Z,1760122155.989 [Default:CheckIn](INFO): Running loop #19 2025-10-10T18:49:15.989Z,1760122155.989 [Default:CheckIn] Running Loop=19 2025-10-10T18:49:15.989Z,1760122155.989 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-10T18:49:15.989Z,1760122155.989 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-10T18:49:17.997Z,1760122157.997 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184917.00,A,3648.17804,N,12147.27966,W,0.000,234.89,101025,,,A*73 2025-10-10T18:49:17.999Z,1760122157.999 [NAL9602](INFO): GPS fix at 20251010T184917: (36.802967, -121.787994) 2025-10-10T18:49:18.010Z,1760122158.010 [Default:CheckIn:Read_GPS] Stopped 2025-10-10T18:49:18.010Z,1760122158.010 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-10T18:49:27.732Z,1760122167.732 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20251010T173332/Courier0042.lzma 2025-10-10T18:49:31.746Z,1760122171.746 [DataOverHttps](INFO): Moved sent file to Logs/20251010T173332/Courier0042.lzma.bak 2025-10-10T18:49:31.746Z,1760122171.746 [DataOverHttps](INFO): SBD MOMSN=26169435 2025-10-10T18:49:47.860Z,1760122187.860 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20251010T173332/Express0043.lzma 2025-10-10T18:49:48.701Z,1760122188.701 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-10-10T18:49:48.776Z,1760122188.776 [NAL9602](ERROR): received: +CSQ:0 OK638, 0, 0, 0, 0 OK 2025-10-10T18:49:50.870Z,1760122190.870 [DataOverHttps](INFO): Moved sent file to Logs/20251010T173332/Express0043.lzma.bak 2025-10-10T18:49:50.870Z,1760122190.870 [DataOverHttps](INFO): SBD MOMSN=26169438 2025-10-10T18:49:51.965Z,1760122191.965 [Default:CheckIn:Read_Iridium] Stopped 2025-10-10T18:49:51.965Z,1760122191.965 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-10T18:49:51.965Z,1760122191.965 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-10T18:50:48.491Z,1760122248.491 [NAL9602](INFO): SBD MO Status=2, MOMSN=28639, MT Status=2, MTMSN=0 2025-10-10T18:50:48.491Z,1760122248.491 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-10-10T18:52:57.777Z,1760122377.777 [NAL9602](INFO): SBD MO Status=2, MOMSN=28639, MT Status=2, MTMSN=0 2025-10-10T18:52:57.777Z,1760122377.777 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-10-10T18:53:17.579Z,1760122397.579 [NAL9602](INFO): SBD MO Status=0, MOMSN=28639, MT Status=0, MTMSN=0 2025-10-10T18:53:17.579Z,1760122397.579 [NAL9602](INFO): No messages in MT queue 2025-10-10T18:53:48.269Z,1760122428.269 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-10T18:54:52.522Z,1760122492.522 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-10T18:54:52.523Z,1760122492.523 [Default:CheckIn:C.Wait] Stopped 2025-10-10T18:54:52.523Z,1760122492.523 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-10T18:54:52.523Z,1760122492.523 [Default:CheckIn:D] Running Loop=1 2025-10-10T18:54:52.959Z,1760122492.959 [Default:CheckIn:D] Stopped 2025-10-10T18:54:52.959Z,1760122492.959 [Default:CheckIn:E] Running Loop=1 2025-10-10T18:54:53.355Z,1760122493.355 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 114.929574 min 2025-10-10T18:54:53.355Z,1760122493.355 [Default:CheckIn:E] Stopped 2025-10-10T18:54:53.355Z,1760122493.355 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-10T18:54:53.355Z,1760122493.355 [Default:CheckIn] Stopped 2025-10-10T18:54:53.355Z,1760122493.355 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-10T18:54:53.355Z,1760122493.355 [Default:CheckIn](INFO): Running loop #20 2025-10-10T18:54:53.356Z,1760122493.356 [Default:CheckIn] Running Loop=20 2025-10-10T18:54:53.356Z,1760122493.356 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-10T18:54:53.356Z,1760122493.356 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-10T18:54:55.335Z,1760122495.335 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185454.00,A,3648.17472,N,12147.27873,W,0.078,320.29,101025,,,A*71 2025-10-10T18:54:55.338Z,1760122495.338 [NAL9602](INFO): GPS fix at 20251010T185454: (36.802912, -121.787979) 2025-10-10T18:54:55.350Z,1760122495.350 [Default:CheckIn:Read_GPS] Stopped 2025-10-10T18:54:55.350Z,1760122495.350 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-10T18:55:02.728Z,1760122502.728 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20251010T173332/Courier0045.lzma 2025-10-10T18:55:03.730Z,1760122503.730 [DataOverHttps](INFO): Moved sent file to Logs/20251010T173332/Courier0045.lzma.bak 2025-10-10T18:55:03.730Z,1760122503.730 [DataOverHttps](INFO): SBD MOMSN=26169478 2025-10-10T18:55:15.535Z,1760122515.535 [NAL9602](INFO): SBD MO Status=0, MOMSN=28640, MT Status=0, MTMSN=0 2025-10-10T18:55:15.535Z,1760122515.535 [NAL9602](INFO): No messages in MT queue 2025-10-10T18:55:20.496Z,1760122520.496 [DataOverHttps](INFO): Sending 157 bytes from file Logs/20251010T173332/Express0046.lzma 2025-10-10T18:55:27.522Z,1760122527.522 [DataOverHttps](INFO): Moved sent file to Logs/20251010T173332/Express0046.lzma.bak 2025-10-10T18:55:27.522Z,1760122527.522 [DataOverHttps](INFO): SBD MOMSN=26169481 2025-10-10T18:55:28.881Z,1760122528.881 [Default:CheckIn:Read_Iridium] Stopped 2025-10-10T18:55:28.881Z,1760122528.881 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-10T18:55:28.881Z,1760122528.881 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-10T18:55:46.241Z,1760122546.241 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-10T19:00:29.490Z,1760122829.490 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-10T19:00:29.490Z,1760122829.490 [Default:CheckIn:C.Wait] Stopped 2025-10-10T19:00:29.490Z,1760122829.490 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-10T19:00:29.490Z,1760122829.490 [Default:CheckIn:D] Running Loop=1 2025-10-10T19:00:29.900Z,1760122829.900 [Default:CheckIn:D] Stopped 2025-10-10T19:00:29.900Z,1760122829.900 [Default:CheckIn:E] Running Loop=1 2025-10-10T19:00:30.296Z,1760122830.296 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 120.545247 min 2025-10-10T19:00:30.296Z,1760122830.296 [Default:CheckIn:E] Stopped 2025-10-10T19:00:30.296Z,1760122830.296 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-10T19:00:30.296Z,1760122830.296 [Default:CheckIn] Stopped 2025-10-10T19:00:30.296Z,1760122830.296 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-10T19:00:30.296Z,1760122830.296 [Default:CheckIn](INFO): Running loop #21 2025-10-10T19:00:30.297Z,1760122830.297 [Default:CheckIn] Running Loop=21 2025-10-10T19:00:30.297Z,1760122830.297 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-10T19:00:30.297Z,1760122830.297 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-10T19:00:32.300Z,1760122832.300 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190031.00,A,3648.18767,N,12147.28026,W,0.369,320.29,101025,,,A*7E 2025-10-10T19:00:32.302Z,1760122832.302 [NAL9602](INFO): GPS fix at 20251010T190031: (36.803128, -121.788004) 2025-10-10T19:00:32.314Z,1760122832.314 [Default:CheckIn:Read_GPS] Stopped 2025-10-10T19:00:32.314Z,1760122832.314 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-10T19:00:39.648Z,1760122839.648 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20251010T173332/Courier0048.lzma 2025-10-10T19:00:40.650Z,1760122840.650 [DataOverHttps](INFO): Moved sent file to Logs/20251010T173332/Courier0048.lzma.bak 2025-10-10T19:00:40.650Z,1760122840.650 [DataOverHttps](INFO): SBD MOMSN=26169523 2025-10-10T19:00:59.736Z,1760122859.736 [DataOverHttps](INFO): Sending 166 bytes from file Logs/20251010T173332/Express0049.lzma 2025-10-10T19:01:00.738Z,1760122860.738 [DataOverHttps](INFO): Moved sent file to Logs/20251010T173332/Express0049.lzma.bak 2025-10-10T19:01:00.738Z,1760122860.738 [DataOverHttps](INFO): SBD MOMSN=26169526 2025-10-10T19:01:01.851Z,1760122861.851 [Default:CheckIn:Read_Iridium] Stopped 2025-10-10T19:01:01.851Z,1760122861.851 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-10T19:01:01.851Z,1760122861.851 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-10T19:01:59.201Z,1760122919.201 [NAL9602](INFO): SBD MO Status=2, MOMSN=28641, MT Status=2, MTMSN=0 2025-10-10T19:01:59.201Z,1760122919.201 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-10-10T19:02:07.283Z,1760122927.283 [NAL9602](INFO): SBD MO Status=0, MOMSN=28641, MT Status=0, MTMSN=0 2025-10-10T19:02:07.283Z,1760122927.283 [NAL9602](INFO): No messages in MT queue 2025-10-10T19:02:37.985Z,1760122957.985 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-10T19:06:02.438Z,1760123162.438 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-10T19:06:02.438Z,1760123162.438 [Default:CheckIn:C.Wait] Stopped 2025-10-10T19:06:02.438Z,1760123162.438 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-10T19:06:02.438Z,1760123162.438 [Default:CheckIn:D] Running Loop=1 2025-10-10T19:06:02.835Z,1760123162.835 [Default:CheckIn:D] Stopped 2025-10-10T19:06:02.835Z,1760123162.835 [Default:CheckIn:E] Running Loop=1 2025-10-10T19:06:03.237Z,1760123163.237 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 126.094173 min 2025-10-10T19:06:03.237Z,1760123163.237 [Default:CheckIn:E] Stopped 2025-10-10T19:06:03.237Z,1760123163.237 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-10T19:06:03.237Z,1760123163.237 [Default:CheckIn] Stopped 2025-10-10T19:06:03.237Z,1760123163.237 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-10T19:06:03.238Z,1760123163.238 [Default:CheckIn](INFO): Running loop #22 2025-10-10T19:06:03.238Z,1760123163.238 [Default:CheckIn] Running Loop=22 2025-10-10T19:06:03.238Z,1760123163.238 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-10T19:06:03.238Z,1760123163.238 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-10T19:06:05.255Z,1760123165.255 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190604.00,A,3648.18017,N,12147.27968,W,0.097,320.29,101025,,,A*70 2025-10-10T19:06:05.258Z,1760123165.258 [NAL9602](INFO): GPS fix at 20251010T190604: (36.803003, -121.787995) 2025-10-10T19:06:05.269Z,1760123165.269 [Default:CheckIn:Read_GPS] Stopped 2025-10-10T19:06:05.269Z,1760123165.269 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-10T19:06:13.044Z,1760123173.044 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20251010T173332/Courier0051.lzma 2025-10-10T19:06:14.047Z,1760123174.047 [DataOverHttps](INFO): Moved sent file to Logs/20251010T173332/Courier0051.lzma.bak 2025-10-10T19:06:14.047Z,1760123174.047 [DataOverHttps](INFO): SBD MOMSN=26169558 2025-10-10T19:06:24.236Z,1760123184.236 [NAL9602](INFO): SBD MO Status=0, MOMSN=28642, MT Status=0, MTMSN=0 2025-10-10T19:06:24.236Z,1760123184.236 [NAL9602](INFO): No messages in MT queue 2025-10-10T19:06:29.876Z,1760123189.876 [DataOverHttps](INFO): Sending 157 bytes from file Logs/20251010T173332/Express0052.lzma 2025-10-10T19:06:31.882Z,1760123191.882 [DataOverHttps](INFO): Moved sent file to Logs/20251010T173332/Express0052.lzma.bak 2025-10-10T19:06:31.882Z,1760123191.882 [DataOverHttps](INFO): SBD MOMSN=26169561 2025-10-10T19:06:33.145Z,1760123193.145 [Default:CheckIn:Read_Iridium] Stopped 2025-10-10T19:06:33.145Z,1760123193.145 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-10T19:06:33.145Z,1760123193.145 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-10T19:06:54.950Z,1760123214.950 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-10T19:10:57.376Z,1760123457.376 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7). 2025-10-10T19:11:03.099Z,1760123463.099 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-10-10T19:11:15.152Z,1760123475.152 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-10-10T19:11:27.676Z,1760123487.676 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-10-10T19:11:33.756Z,1760123493.756 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-10T19:11:33.756Z,1760123493.756 [Default:CheckIn:C.Wait] Stopped 2025-10-10T19:11:33.756Z,1760123493.756 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-10T19:11:33.757Z,1760123493.757 [Default:CheckIn:D] Running Loop=1 2025-10-10T19:11:34.170Z,1760123494.170 [Default:CheckIn:D] Stopped 2025-10-10T19:11:34.170Z,1760123494.170 [Default:CheckIn:E] Running Loop=1 2025-10-10T19:11:34.563Z,1760123494.563 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 131.616423 min 2025-10-10T19:11:34.563Z,1760123494.563 [Default:CheckIn:E] Stopped 2025-10-10T19:11:34.563Z,1760123494.563 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-10T19:11:34.564Z,1760123494.564 [Default:CheckIn] Stopped 2025-10-10T19:11:34.564Z,1760123494.564 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-10T19:11:34.564Z,1760123494.564 [Default:CheckIn](INFO): Running loop #23 2025-10-10T19:11:34.564Z,1760123494.564 [Default:CheckIn] Running Loop=23 2025-10-10T19:11:34.564Z,1760123494.564 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-10T19:11:34.564Z,1760123494.564 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-10T19:11:36.575Z,1760123496.575 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191135.00,A,3648.18213,N,12147.27770,W,0.136,320.29,101025,,,A*7F 2025-10-10T19:11:36.577Z,1760123496.577 [NAL9602](INFO): GPS fix at 20251010T191135: (36.803036, -121.787962) 2025-10-10T19:11:36.588Z,1760123496.588 [Default:CheckIn:Read_GPS] Stopped 2025-10-10T19:11:36.588Z,1760123496.588 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-10T19:11:40.616Z,1760123500.616 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-10-10T19:11:43.748Z,1760123503.748 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20251010T173332/Courier0054.lzma 2025-10-10T19:11:44.750Z,1760123504.750 [DataOverHttps](INFO): Moved sent file to Logs/20251010T173332/Courier0054.lzma.bak 2025-10-10T19:11:44.750Z,1760123504.750 [DataOverHttps](INFO): SBD MOMSN=26169606 2025-10-10T19:11:51.127Z,1760123511.127 [NAL9602](INFO): SBD MO Status=0, MOMSN=28643, MT Status=0, MTMSN=0 2025-10-10T19:11:51.127Z,1760123511.127 [NAL9602](INFO): No messages in MT queue 2025-10-10T19:11:53.544Z,1760123513.544 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-10-10T19:12:04.616Z,1760123524.616 [DataOverHttps](INFO): Sending 154 bytes from file Logs/20251010T173332/Express0055.lzma 2025-10-10T19:12:05.619Z,1760123525.619 [DataOverHttps](INFO): Moved sent file to Logs/20251010T173332/Express0055.lzma.bak 2025-10-10T19:12:05.619Z,1760123525.619 [DataOverHttps](INFO): SBD MOMSN=26169610 2025-10-10T19:12:06.472Z,1760123526.472 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-10-10T19:12:06.907Z,1760123526.907 [Default:CheckIn:Read_Iridium] Stopped 2025-10-10T19:12:06.908Z,1760123526.908 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-10T19:12:06.908Z,1760123526.908 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-10T19:12:09.357Z,1760123529.357 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-10-10T19:12:19.402Z,1760123539.402 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-10-10T19:12:21.829Z,1760123541.829 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-10T19:12:32.330Z,1760123552.330 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-10-10T19:12:45.286Z,1760123565.286 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-10-10T19:12:58.212Z,1760123578.212 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-10-10T19:13:11.141Z,1760123591.141 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-10-10T19:15:40.216Z,1760123740.216 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-10-10T19:15:40.222Z,1760123740.222 [BPC1](ERROR): Battery stick #16 (s/n: 00F8) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-10-10T19:15:40.223Z,1760123740.223 [BPC1](ERROR): Battery stick #17 (s/n: 0084) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AC0. 2025-10-10T19:15:40.225Z,1760123740.225 [BPC1](ERROR): Battery stick #24 (s/n: 01D3) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-10-10T19:15:40.226Z,1760123740.226 [BPC1](ERROR): Battery stick #29 (s/n: 00A7) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-10-10T19:15:40.227Z,1760123740.227 [BPC1](ERROR): Battery stick #38 (s/n: 01BC) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-10-10T19:15:40.228Z,1760123740.228 [BPC1](ERROR): Battery stick #51 (s/n: 01D6) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-10-10T19:15:40.229Z,1760123740.229 [BPC1](ERROR): Battery stick #52 (s/n: 0160) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-10-10T19:15:40.229Z,1760123740.229 [BPC1](ERROR): Battery stick #57 (s/n: 019F) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-10-10T19:15:40.230Z,1760123740.230 [BPC1](ERROR): Battery stick #59 (s/n: 0184) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-10-10T19:15:40.231Z,1760123740.231 [BPC1](INFO): Calculating totals. Valid battery stick count: 39. Valid reserve battery stick count: 4. 2025-10-10T19:15:40.253Z,1760123740.253 [BPC1](INFO): Received data from all battery sticks. 2025-10-10T19:17:07.526Z,1760123827.526 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-10T19:17:07.526Z,1760123827.526 [Default:CheckIn:C.Wait] Stopped 2025-10-10T19:17:07.526Z,1760123827.526 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-10T19:17:07.527Z,1760123827.527 [Default:CheckIn:D] Running Loop=1 2025-10-10T19:17:07.937Z,1760123827.937 [Default:CheckIn:D] Stopped 2025-10-10T19:17:07.937Z,1760123827.937 [Default:CheckIn:E] Running Loop=1 2025-10-10T19:17:08.353Z,1760123828.353 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 137.179199 min 2025-10-10T19:17:08.353Z,1760123828.353 [Default:CheckIn:E] Stopped 2025-10-10T19:17:08.353Z,1760123828.353 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-10T19:17:08.353Z,1760123828.353 [Default:CheckIn] Stopped 2025-10-10T19:17:08.353Z,1760123828.353 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-10T19:17:08.354Z,1760123828.354 [Default:CheckIn](INFO): Running loop #24 2025-10-10T19:17:08.354Z,1760123828.354 [Default:CheckIn] Running Loop=24 2025-10-10T19:17:08.354Z,1760123828.354 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-10T19:17:08.354Z,1760123828.354 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-10T19:17:10.346Z,1760123830.346 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191709.00,A,3648.18189,N,12147.27191,W,0.058,0.00,101025,,,A*7C 2025-10-10T19:17:10.349Z,1760123830.349 [NAL9602](INFO): GPS fix at 20251010T191709: (36.803032, -121.787865) 2025-10-10T19:17:10.359Z,1760123830.359 [Default:CheckIn:Read_GPS] Stopped 2025-10-10T19:17:10.359Z,1760123830.359 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-10T19:17:18.428Z,1760123838.428 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20251010T173332/Courier0057.lzma 2025-10-10T19:17:19.430Z,1760123839.430 [DataOverHttps](INFO): Moved sent file to Logs/20251010T173332/Courier0057.lzma.bak 2025-10-10T19:17:19.430Z,1760123839.430 [DataOverHttps](INFO): SBD MOMSN=26169655 2025-10-10T19:17:33.386Z,1760123853.386 [NAL9602](INFO): SBD MO Status=2, MOMSN=28644, MT Status=2, MTMSN=0 2025-10-10T19:17:33.386Z,1760123853.386 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-10-10T19:17:35.365Z,1760123855.365 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20251010T173332/Express0058.lzma 2025-10-10T19:17:36.366Z,1760123856.366 [DataOverHttps](INFO): Moved sent file to Logs/20251010T173332/Express0058.lzma.bak 2025-10-10T19:17:36.366Z,1760123856.366 [DataOverHttps](INFO): SBD MOMSN=26169658 2025-10-10T19:17:37.849Z,1760123857.849 [Default:CheckIn:Read_Iridium] Stopped 2025-10-10T19:17:37.849Z,1760123857.849 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-10T19:17:37.849Z,1760123857.849 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-10T19:20:10.195Z,1760124010.195 [NAL9602](INFO): SBD MO Status=0, MOMSN=28644, MT Status=0, MTMSN=0 2025-10-10T19:20:10.195Z,1760124010.195 [NAL9602](INFO): No messages in MT queue 2025-10-10T19:20:40.901Z,1760124040.901 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-10T19:22:38.486Z,1760124158.486 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-10T19:22:38.486Z,1760124158.486 [Default:CheckIn:C.Wait] Stopped 2025-10-10T19:22:38.486Z,1760124158.486 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-10T19:22:38.487Z,1760124158.487 [Default:CheckIn:D] Running Loop=1 2025-10-10T19:22:38.897Z,1760124158.897 [Default:CheckIn:D] Stopped 2025-10-10T19:22:38.897Z,1760124158.897 [Default:CheckIn:E] Running Loop=1 2025-10-10T19:22:39.316Z,1760124159.316 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 142.695215 min 2025-10-10T19:22:39.316Z,1760124159.316 [Default:CheckIn:E] Stopped 2025-10-10T19:22:39.316Z,1760124159.316 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-10T19:22:39.317Z,1760124159.317 [Default:CheckIn] Stopped 2025-10-10T19:22:39.317Z,1760124159.317 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-10T19:22:39.317Z,1760124159.317 [Default:CheckIn](INFO): Running loop #25 2025-10-10T19:22:39.317Z,1760124159.317 [Default:CheckIn] Running Loop=25 2025-10-10T19:22:39.317Z,1760124159.317 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-10T19:22:39.317Z,1760124159.317 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-10T19:22:41.322Z,1760124161.322 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192240.00,A,3648.17860,N,12147.27714,W,0.058,0.00,101025,,,A*7D 2025-10-10T19:22:41.324Z,1760124161.324 [NAL9602](INFO): GPS fix at 20251010T192240: (36.802977, -121.787952) 2025-10-10T19:22:41.352Z,1760124161.352 [Default:CheckIn:Read_GPS] Stopped 2025-10-10T19:22:41.352Z,1760124161.352 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-10T19:22:49.368Z,1760124169.368 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20251010T173332/Courier0060.lzma 2025-10-10T19:22:50.370Z,1760124170.370 [DataOverHttps](INFO): Moved sent file to Logs/20251010T173332/Courier0060.lzma.bak 2025-10-10T19:22:50.370Z,1760124170.370 [DataOverHttps](INFO): SBD MOMSN=26169709 2025-10-10T19:22:55.079Z,1760124175.079 [NAL9602](INFO): SBD MO Status=0, MOMSN=28645, MT Status=0, MTMSN=0 2025-10-10T19:22:55.079Z,1760124175.079 [NAL9602](INFO): No messages in MT queue 2025-10-10T19:23:08.812Z,1760124188.812 [DataOverHttps](INFO): Sending 154 bytes from file Logs/20251010T173332/Express0061.lzma 2025-10-10T19:23:09.814Z,1760124189.814 [DataOverHttps](INFO): Moved sent file to Logs/20251010T173332/Express0061.lzma.bak 2025-10-10T19:23:09.814Z,1760124189.814 [DataOverHttps](INFO): SBD MOMSN=26169712 2025-10-10T19:23:11.267Z,1760124191.267 [Default:CheckIn:Read_Iridium] Stopped 2025-10-10T19:23:11.267Z,1760124191.267 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-10T19:23:11.267Z,1760124191.267 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-10T19:23:25.786Z,1760124205.786 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-10T19:28:11.902Z,1760124491.902 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-10T19:28:11.902Z,1760124491.902 [Default:CheckIn:C.Wait] Stopped 2025-10-10T19:28:11.902Z,1760124491.902 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-10T19:28:11.902Z,1760124491.902 [Default:CheckIn:D] Running Loop=1 2025-10-10T19:28:12.317Z,1760124492.317 [Default:CheckIn:D] Stopped 2025-10-10T19:28:12.317Z,1760124492.317 [Default:CheckIn:E] Running Loop=1 2025-10-10T19:28:12.724Z,1760124492.724 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 148.252197 min 2025-10-10T19:28:12.724Z,1760124492.724 [Default:CheckIn:E] Stopped 2025-10-10T19:28:12.724Z,1760124492.724 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-10T19:28:12.725Z,1760124492.725 [Default:CheckIn] Stopped 2025-10-10T19:28:12.725Z,1760124492.725 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-10T19:28:12.725Z,1760124492.725 [Default:CheckIn](INFO): Running loop #26 2025-10-10T19:28:12.725Z,1760124492.725 [Default:CheckIn] Running Loop=26 2025-10-10T19:28:12.725Z,1760124492.725 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-10T19:28:12.725Z,1760124492.725 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-10T19:28:14.727Z,1760124494.727 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192814.00,A,3648.19260,N,12147.27032,W,0.039,0.00,101025,,,A*76 2025-10-10T19:28:14.729Z,1760124494.729 [NAL9602](INFO): GPS fix at 20251010T192814: (36.803210, -121.787839) 2025-10-10T19:28:14.760Z,1760124494.760 [Default:CheckIn:Read_GPS] Stopped 2025-10-10T19:28:14.760Z,1760124494.760 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-10T19:28:24.544Z,1760124504.544 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20251010T173332/Courier0063.lzma 2025-10-10T19:28:25.546Z,1760124505.546 [DataOverHttps](INFO): Moved sent file to Logs/20251010T173332/Courier0063.lzma.bak 2025-10-10T19:28:25.546Z,1760124505.546 [DataOverHttps](INFO): SBD MOMSN=26169755 2025-10-10T19:28:41.421Z,1760124521.421 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20251010T173332/Express0064.lzma 2025-10-10T19:28:42.422Z,1760124522.422 [DataOverHttps](INFO): Moved sent file to Logs/20251010T173332/Express0064.lzma.bak 2025-10-10T19:28:42.422Z,1760124522.422 [DataOverHttps](INFO): SBD MOMSN=26169758 2025-10-10T19:28:43.827Z,1760124523.827 [Default:CheckIn:Read_Iridium] Stopped 2025-10-10T19:28:43.828Z,1760124523.828 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-10T19:28:43.828Z,1760124523.828 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-10T19:29:46.487Z,1760124586.487 [NAL9602](INFO): SBD MO Status=2, MOMSN=28646, MT Status=2, MTMSN=0 2025-10-10T19:29:46.487Z,1760124586.487 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-10-10T19:30:02.647Z,1760124602.647 [NAL9602](INFO): SBD MO Status=0, MOMSN=28646, MT Status=0, MTMSN=0 2025-10-10T19:30:02.647Z,1760124602.647 [NAL9602](INFO): No messages in MT queue 2025-10-10T19:30:33.360Z,1760124633.360 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-10T19:31:15.436Z,1760124675.436 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-10-10T19:33:44.482Z,1760124824.482 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-10T19:33:44.482Z,1760124824.482 [Default:CheckIn:C.Wait] Stopped 2025-10-10T19:33:44.482Z,1760124824.482 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-10T19:33:44.483Z,1760124824.483 [Default:CheckIn:D] Running Loop=1 2025-10-10T19:33:44.893Z,1760124824.893 [Default:CheckIn:D] Stopped 2025-10-10T19:33:44.893Z,1760124824.893 [Default:CheckIn:E] Running Loop=1 2025-10-10T19:33:45.306Z,1760124825.306 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 153.795133 min 2025-10-10T19:33:45.307Z,1760124825.307 [Default:CheckIn:E] Stopped 2025-10-10T19:33:45.307Z,1760124825.307 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-10T19:33:45.307Z,1760124825.307 [Default:CheckIn] Stopped 2025-10-10T19:33:45.307Z,1760124825.307 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-10T19:33:45.307Z,1760124825.307 [Default:CheckIn](INFO): Running loop #27 2025-10-10T19:33:45.307Z,1760124825.307 [Default:CheckIn] Running Loop=27 2025-10-10T19:33:45.307Z,1760124825.307 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-10T19:33:45.307Z,1760124825.307 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-10T19:33:47.307Z,1760124827.307 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193346.00,A,3648.18853,N,12147.27340,W,1.011,319.59,101025,,,A*7A 2025-10-10T19:33:47.309Z,1760124827.309 [NAL9602](INFO): GPS fix at 20251010T193346: (36.803142, -121.787890) 2025-10-10T19:33:47.321Z,1760124827.321 [Default:CheckIn:Read_GPS] Stopped 2025-10-10T19:33:47.321Z,1760124827.321 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-10T19:33:56.652Z,1760124836.652 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20251010T173332/Courier0066.lzma 2025-10-10T19:33:57.654Z,1760124837.654 [DataOverHttps](INFO): Moved sent file to Logs/20251010T173332/Courier0066.lzma.bak 2025-10-10T19:33:57.654Z,1760124837.654 [DataOverHttps](INFO): SBD MOMSN=26169799 2025-10-10T19:33:58.619Z,1760124838.619 [NAL9602](INFO): SBD MO Status=0, MOMSN=28647, MT Status=0, MTMSN=0 2025-10-10T19:33:58.619Z,1760124838.619 [NAL9602](INFO): No messages in MT queue 2025-10-10T19:34:13.524Z,1760124853.524 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20251010T173332/Express0067.lzma 2025-10-10T19:34:14.526Z,1760124854.526 [DataOverHttps](INFO): Moved sent file to Logs/20251010T173332/Express0067.lzma.bak 2025-10-10T19:34:14.526Z,1760124854.526 [DataOverHttps](INFO): SBD MOMSN=26169802 2025-10-10T19:34:15.993Z,1760124855.993 [Default:CheckIn:Read_Iridium] Stopped 2025-10-10T19:34:15.993Z,1760124855.993 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-10T19:34:15.993Z,1760124855.993 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-10T19:34:29.316Z,1760124869.316 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-10T19:35:01.315Z,1760124901.315 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-10-10T19:35:05.757Z,1760124905.757 [GFScanner](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.000049 CHAN A1 (24V): 0.000831 CHAN A2 (12V): 0.000316 CHAN A3 (5V): -0.001636 CHAN B0 (3.3V): 0.000967 CHAN B1 (3.15aV): -0.000204 CHAN B2 (3.15bV): 0.000628 CHAN B3 (GND): -0.000851 OPEN: 0.006759 Full Scale: +/- 1 mA 2025-10-10T19:37:13.855Z,1760125033.855 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-10-10T19:37:13.855Z,1760125033.855 [DropWeight] Hardware Fault, FailCount= 1 2025-10-10T19:37:13.855Z,1760125033.855 [DropWeight](ERROR): Hardware Fault 2025-10-10T19:37:13.873Z,1760125033.873 [CommandExec](FAULT): Scheduling is paused 2025-10-10T19:37:13.873Z,1760125033.873 [CBIT](INFO): Critical error at 20251010T193713 2025-10-10T19:37:13.876Z,1760125033.876 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-10-10T19:37:13.877Z,1760125033.877 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-10-10T19:37:14.286Z,1760125034.286 [CBIT](INFO): Critical error at 20251010T193713 2025-10-10T19:39:16.744Z,1760125156.744 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-10T19:39:16.744Z,1760125156.744 [Default:CheckIn:C.Wait] Stopped 2025-10-10T19:39:16.744Z,1760125156.744 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-10T19:39:16.745Z,1760125156.745 [Default:CheckIn:D] Running Loop=1 2025-10-10T19:39:17.144Z,1760125157.144 [Default:CheckIn:D] Stopped 2025-10-10T19:39:17.144Z,1760125157.144 [Default:CheckIn:E] Running Loop=1 2025-10-10T19:39:17.545Z,1760125157.545 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 159.332650 min 2025-10-10T19:39:17.545Z,1760125157.545 [Default:CheckIn:E] Stopped 2025-10-10T19:39:17.546Z,1760125157.546 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-10T19:39:17.546Z,1760125157.546 [Default:CheckIn] Stopped 2025-10-10T19:39:17.546Z,1760125157.546 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-10T19:39:17.546Z,1760125157.546 [Default:CheckIn](INFO): Running loop #28 2025-10-10T19:39:17.546Z,1760125157.546 [Default:CheckIn] Running Loop=28 2025-10-10T19:39:17.546Z,1760125157.546 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-10T19:39:17.546Z,1760125157.546 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-10T19:39:19.559Z,1760125159.559 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193918.00,A,3648.17993,N,12147.27785,W,0.136,263.81,101025,,,A*78 2025-10-10T19:39:19.561Z,1760125159.561 [NAL9602](INFO): GPS fix at 20251010T193918: (36.802999, -121.787964) 2025-10-10T19:39:19.572Z,1760125159.572 [Default:CheckIn:Read_GPS] Stopped 2025-10-10T19:39:19.573Z,1760125159.573 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-10T19:39:28.357Z,1760125168.357 [DataOverHttps](INFO): Sending 178 bytes from file Logs/20251010T173332/Courier0069.lzma 2025-10-10T19:39:29.358Z,1760125169.358 [DataOverHttps](INFO): Moved sent file to Logs/20251010T173332/Courier0069.lzma.bak 2025-10-10T19:39:29.358Z,1760125169.358 [DataOverHttps](INFO): SBD MOMSN=26169849 2025-10-10T19:39:46.221Z,1760125186.221 [DataOverHttps](INFO): Sending 381 bytes from file Logs/20251010T173332/Express0070.lzma 2025-10-10T19:39:47.222Z,1760125187.222 [DataOverHttps](INFO): Moved sent file to Logs/20251010T173332/Express0070.lzma.bak 2025-10-10T19:39:47.222Z,1760125187.222 [DataOverHttps](INFO): SBD MOMSN=26169855 2025-10-10T19:39:48.656Z,1760125188.656 [Default:CheckIn:Read_Iridium] Stopped 2025-10-10T19:39:48.656Z,1760125188.656 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-10T19:39:48.656Z,1760125188.656 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-10T19:39:53.100Z,1760125193.100 [NAL9602](INFO): SBD MO Status=2, MOMSN=28648, MT Status=2, MTMSN=0 2025-10-10T19:39:53.100Z,1760125193.100 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-10-10T19:40:24.199Z,1760125224.199 [NAL9602](INFO): SBD MO Status=2, MOMSN=28648, MT Status=2, MTMSN=0 2025-10-10T19:40:24.199Z,1760125224.199 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-10-10T19:42:30.712Z,1760125350.712 [NAL9602](INFO): SBD MO Status=0, MOMSN=28648, MT Status=0, MTMSN=0 2025-10-10T19:42:30.712Z,1760125350.712 [NAL9602](INFO): No messages in MT queue 2025-10-10T19:43:01.413Z,1760125381.413 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-10T19:44:49.301Z,1760125489.301 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-10T19:44:49.302Z,1760125489.302 [Default:CheckIn:C.Wait] Stopped 2025-10-10T19:44:49.302Z,1760125489.302 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-10T19:44:49.302Z,1760125489.302 [Default:CheckIn:D] Running Loop=1 2025-10-10T19:44:49.706Z,1760125489.706 [Default:CheckIn:D] Stopped 2025-10-10T19:44:49.706Z,1760125489.706 [Default:CheckIn:E] Running Loop=1 2025-10-10T19:44:50.113Z,1760125490.113 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 164.875342 min 2025-10-10T19:44:50.113Z,1760125490.113 [Default:CheckIn:E] Stopped 2025-10-10T19:44:50.113Z,1760125490.113 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-10T19:44:50.113Z,1760125490.113 [Default:CheckIn] Stopped 2025-10-10T19:44:50.113Z,1760125490.113 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-10T19:44:50.114Z,1760125490.114 [Default:CheckIn](INFO): Running loop #29 2025-10-10T19:44:50.114Z,1760125490.114 [Default:CheckIn] Running Loop=29 2025-10-10T19:44:50.114Z,1760125490.114 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-10T19:44:50.114Z,1760125490.114 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-10T19:44:52.111Z,1760125492.111 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194451.00,A,3648.16252,N,12147.28325,W,0.292,263.81,101025,,,A*74 2025-10-10T19:44:52.113Z,1760125492.113 [NAL9602](INFO): GPS fix at 20251010T194451: (36.802709, -121.788054) 2025-10-10T19:44:52.157Z,1760125492.157 [Default:CheckIn:Read_GPS] Stopped 2025-10-10T19:44:52.157Z,1760125492.157 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-10T19:45:01.400Z,1760125501.400 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20251010T173332/Courier0072.lzma 2025-10-10T19:45:02.403Z,1760125502.403 [DataOverHttps](INFO): Moved sent file to Logs/20251010T173332/Courier0072.lzma.bak 2025-10-10T19:45:02.403Z,1760125502.403 [DataOverHttps](INFO): SBD MOMSN=26169898 2025-10-10T19:45:20.396Z,1760125520.396 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20251010T173332/Express0073.lzma 2025-10-10T19:45:22.402Z,1760125522.402 [DataOverHttps](INFO): Moved sent file to Logs/20251010T173332/Express0073.lzma.bak 2025-10-10T19:45:22.402Z,1760125522.402 [DataOverHttps](INFO): SBD MOMSN=26169901 2025-10-10T19:45:23.670Z,1760125523.670 [Default:CheckIn:Read_Iridium] Stopped 2025-10-10T19:45:23.670Z,1760125523.670 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-10T19:45:23.670Z,1760125523.670 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-10T19:49:54.391Z,1760125794.391 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-10-10T19:50:24.314Z,1760125824.314 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-10T19:50:24.314Z,1760125824.314 [Default:CheckIn:C.Wait] Stopped 2025-10-10T19:50:24.314Z,1760125824.314 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-10T19:50:24.314Z,1760125824.314 [Default:CheckIn:D] Running Loop=1 2025-10-10T19:50:24.698Z,1760125824.698 [Default:CheckIn:D] Stopped 2025-10-10T19:50:24.698Z,1760125824.698 [Default:CheckIn:E] Running Loop=1 2025-10-10T19:50:25.088Z,1760125825.088 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-10T19:50:25.106Z,1760125825.106 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 170.458545 min 2025-10-10T19:50:25.106Z,1760125825.106 [Default:CheckIn:E] Stopped 2025-10-10T19:50:25.106Z,1760125825.106 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-10T19:50:25.106Z,1760125825.106 [Default:CheckIn] Stopped 2025-10-10T19:50:25.106Z,1760125825.106 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-10T19:50:25.107Z,1760125825.107 [Default:CheckIn](INFO): Running loop #30 2025-10-10T19:50:25.107Z,1760125825.107 [Default:CheckIn] Running Loop=30 2025-10-10T19:50:25.107Z,1760125825.107 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-10T19:50:25.107Z,1760125825.107 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-10T19:50:27.121Z,1760125827.121 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195026.00,A,3648.16871,N,12147.28968,W,1.788,35.47,101025,,,A*4D 2025-10-10T19:50:27.124Z,1760125827.124 [NAL9602](INFO): GPS fix at 20251010T195026: (36.802812, -121.788161) 2025-10-10T19:50:27.135Z,1760125827.135 [Default:CheckIn:Read_GPS] Stopped 2025-10-10T19:50:27.135Z,1760125827.135 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-10T19:50:34.464Z,1760125834.464 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20251010T173332/Courier0075.lzma 2025-10-10T19:50:35.466Z,1760125835.466 [DataOverHttps](INFO): Moved sent file to Logs/20251010T173332/Courier0075.lzma.bak 2025-10-10T19:50:35.466Z,1760125835.466 [DataOverHttps](INFO): SBD MOMSN=26169933 2025-10-10T19:50:55.048Z,1760125855.048 [DataOverHttps](INFO): Sending 230 bytes from file Logs/20251010T173332/Express0076.lzma 2025-10-10T19:50:56.050Z,1760125856.050 [DataOverHttps](INFO): Moved sent file to Logs/20251010T173332/Express0076.lzma.bak 2025-10-10T19:50:56.051Z,1760125856.051 [DataOverHttps](INFO): SBD MOMSN=26169936 2025-10-10T19:50:57.480Z,1760125857.480 [Default:CheckIn:Read_Iridium] Stopped 2025-10-10T19:50:57.480Z,1760125857.480 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-10T19:50:57.480Z,1760125857.480 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-10T19:50:57.861Z,1760125857.861 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-10-10T19:50:57.944Z,1760125857.944 [NAL9602](ERROR): received: +CSQ:0 OK648, 0, 0, 0, 0 OK 2025-10-10T19:52:14.259Z,1760125934.259 [CBIT](INFO): Clearing failed state for component DropWeight 2025-10-10T19:52:14.259Z,1760125934.259 [DropWeight] No Fault, FailCount= 1 2025-10-10T19:55:29.383Z,1760126129.383 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-10-10T19:55:58.077Z,1760126158.077 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-10T19:55:58.077Z,1760126158.077 [Default:CheckIn:C.Wait] Stopped 2025-10-10T19:55:58.077Z,1760126158.077 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-10T19:55:58.078Z,1760126158.078 [Default:CheckIn:D] Running Loop=1 2025-10-10T19:55:58.483Z,1760126158.483 [Default:CheckIn:D] Stopped 2025-10-10T19:55:58.483Z,1760126158.483 [Default:CheckIn:E] Running Loop=1 2025-10-10T19:55:58.896Z,1760126158.896 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 176.021631 min 2025-10-10T19:55:58.896Z,1760126158.896 [Default:CheckIn:E] Stopped 2025-10-10T19:55:58.897Z,1760126158.897 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-10T19:55:58.897Z,1760126158.897 [Default:CheckIn] Stopped 2025-10-10T19:55:58.897Z,1760126158.897 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-10T19:55:58.897Z,1760126158.897 [Default:CheckIn](INFO): Running loop #31 2025-10-10T19:55:58.897Z,1760126158.897 [Default:CheckIn] Running Loop=31 2025-10-10T19:55:58.897Z,1760126158.897 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-10T19:55:58.897Z,1760126158.897 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-10T19:56:00.895Z,1760126160.895 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195600.00,A,3648.17093,N,12147.28969,W,5.054,256.58,101025,,,A*70 2025-10-10T19:56:00.898Z,1760126160.898 [NAL9602](INFO): GPS fix at 20251010T195600: (36.802849, -121.788162) 2025-10-10T19:56:00.929Z,1760126160.929 [Default:CheckIn:Read_GPS] Stopped 2025-10-10T19:56:00.929Z,1760126160.929 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-10T19:56:10.588Z,1760126170.588 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20251010T173332/Courier0078.lzma 2025-10-10T19:56:11.590Z,1760126171.590 [DataOverHttps](INFO): Moved sent file to Logs/20251010T173332/Courier0078.lzma.bak 2025-10-10T19:56:11.590Z,1760126171.590 [DataOverHttps](INFO): SBD MOMSN=26169979 2025-10-10T19:56:31.672Z,1760126191.672 [DataOverHttps](INFO): Sending 229 bytes from file Logs/20251010T173332/Express0079.lzma 2025-10-10T19:56:32.674Z,1760126192.674 [DataOverHttps](INFO): Moved sent file to Logs/20251010T173332/Express0079.lzma.bak 2025-10-10T19:56:32.674Z,1760126192.674 [DataOverHttps](INFO): SBD MOMSN=26169982 2025-10-10T19:56:33.645Z,1760126193.645 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-10T19:56:34.061Z,1760126194.061 [Default:CheckIn:Read_Iridium] Stopped 2025-10-10T19:56:34.061Z,1760126194.061 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-10T19:56:34.061Z,1760126194.061 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-10T20:01:34.651Z,1760126494.651 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-10T20:01:34.651Z,1760126494.651 [Default:CheckIn:C.Wait] Stopped 2025-10-10T20:01:34.652Z,1760126494.652 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-10T20:01:34.652Z,1760126494.652 [Default:CheckIn:D] Running Loop=1 2025-10-10T20:01:35.059Z,1760126495.059 [Default:CheckIn:D] Stopped 2025-10-10T20:01:35.059Z,1760126495.059 [Default:CheckIn:E] Running Loop=1 2025-10-10T20:01:35.450Z,1760126495.450 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 181.631234 min 2025-10-10T20:01:35.450Z,1760126495.450 [Default:CheckIn:E] Stopped 2025-10-10T20:01:35.450Z,1760126495.450 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-10T20:01:35.450Z,1760126495.450 [Default:CheckIn] Stopped 2025-10-10T20:01:35.450Z,1760126495.450 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-10T20:01:35.451Z,1760126495.451 [Default:CheckIn](INFO): Running loop #32 2025-10-10T20:01:35.451Z,1760126495.451 [Default:CheckIn] Running Loop=32 2025-10-10T20:01:35.451Z,1760126495.451 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-10T20:01:35.451Z,1760126495.451 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-10T20:01:37.460Z,1760126497.460 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200136.00,A,3648.18570,N,12147.28346,W,2.002,178.24,101025,,,A*7D 2025-10-10T20:01:37.463Z,1760126497.463 [NAL9602](INFO): GPS fix at 20251010T200136: (36.803095, -121.788058) 2025-10-10T20:01:37.474Z,1760126497.474 [Default:CheckIn:Read_GPS] Stopped 2025-10-10T20:01:37.475Z,1760126497.475 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-10T20:01:44.824Z,1760126504.824 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20251010T173332/Courier0081.lzma 2025-10-10T20:01:45.826Z,1760126505.826 [DataOverHttps](INFO): Moved sent file to Logs/20251010T173332/Courier0081.lzma.bak 2025-10-10T20:01:45.826Z,1760126505.826 [DataOverHttps](INFO): SBD MOMSN=26170014 2025-10-10T20:02:02.100Z,1760126522.100 [DataOverHttps](INFO): Sending 170 bytes from file Logs/20251010T173332/Express0082.lzma 2025-10-10T20:02:03.107Z,1760126523.107 [DataOverHttps](INFO): Moved sent file to Logs/20251010T173332/Express0082.lzma.bak 2025-10-10T20:02:03.107Z,1760126523.107 [DataOverHttps](INFO): SBD MOMSN=26170017 2025-10-10T20:02:04.565Z,1760126524.565 [Default:CheckIn:Read_Iridium] Stopped 2025-10-10T20:02:04.565Z,1760126524.565 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-10T20:02:04.565Z,1760126524.565 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-10T20:02:05.697Z,1760126525.697 [CommandExec](IMPORTANT): got command quit 2025-10-10T20:02:06.704Z,1760126526.704 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2025-10-10T20:02:06.705Z,1760126526.705 [CommandExec](INFO): Uninitializing the command executive. 2025-10-10T20:02:06.705Z,1760126526.705 [CommandExec](INFO): Uninitializing the command scheduler. 2025-10-10T20:02:06.705Z,1760126526.705 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:02:06.836Z,1760126526.836 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2025-10-10T20:02:06.836Z,1760126526.836 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2025-10-10T20:02:06.836Z,1760126526.836 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:02:06.837Z,1760126526.837 [NavChartDb](INFO): Join timeout helper Thread ID is 4926 2025-10-10T20:02:07.104Z,1760126527.104 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2025-10-10T20:02:07.105Z,1760126527.105 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:02:07.120Z,1760126527.120 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2025-10-10T20:02:07.120Z,1760126527.120 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:02:07.121Z,1760126527.121 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 4927 2025-10-10T20:02:07.173Z,1760126527.173 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2025-10-10T20:02:07.173Z,1760126527.173 [WetLabsBB2FL](INFO): Powering down 2025-10-10T20:02:07.174Z,1760126527.174 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:02:07.174Z,1760126527.174 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2025-10-10T20:02:07.174Z,1760126527.174 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:02:07.175Z,1760126527.175 [CTD_Seabird](INFO): Join timeout helper Thread ID is 4928 2025-10-10T20:02:07.920Z,1760126527.920 [CTD_Seabird](INFO): Powering down 2025-10-10T20:02:07.937Z,1760126527.937 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2025-10-10T20:02:07.937Z,1760126527.937 [CTD_Seabird](INFO): Powering down 2025-10-10T20:02:07.949Z,1760126527.949 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:02:07.964Z,1760126527.964 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2025-10-10T20:02:07.964Z,1760126527.964 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:02:07.965Z,1760126527.965 [Radio_Surface](INFO): Join timeout helper Thread ID is 4929 2025-10-10T20:02:08.028Z,1760126528.028 [Radio_Surface](INFO): Powering down 2025-10-10T20:02:08.029Z,1760126528.029 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2025-10-10T20:02:08.029Z,1760126528.029 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:02:08.036Z,1760126528.036 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2025-10-10T20:02:08.036Z,1760126528.036 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:02:08.037Z,1760126528.037 [Onboard](INFO): Join timeout helper Thread ID is 4930 2025-10-10T20:02:08.194Z,1760126528.194 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-10-10T20:02:08.268Z,1760126528.268 [NAL9602](ERROR): received: +CSQ:0 OK648, 0, 0, 0, 0 OK 2025-10-10T20:02:09.148Z,1760126529.148 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2025-10-10T20:02:09.149Z,1760126529.149 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:02:09.165Z,1760126529.165 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2025-10-10T20:02:09.165Z,1760126529.165 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:02:09.166Z,1760126529.166 [DataOverHttps](INFO): Join timeout helper Thread ID is 4931 2025-10-10T20:02:09.855Z,1760126529.855 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2025-10-10T20:02:09.875Z,1760126529.875 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:02:09.877Z,1760126529.877 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2025-10-10T20:02:09.877Z,1760126529.877 [DAT ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:02:09.878Z,1760126529.878 [DAT](INFO): Join timeout helper Thread ID is 4932 2025-10-10T20:02:09.981Z,1760126529.981 [DAT](INFO): Powering down 2025-10-10T20:02:10.049Z,1760126530.049 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2025-10-10T20:02:10.049Z,1760126530.049 [DAT](INFO): Powering down 2025-10-10T20:02:10.050Z,1760126530.050 [DAT ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:02:10.069Z,1760126530.069 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2025-10-10T20:02:10.069Z,1760126530.069 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:02:10.069Z,1760126530.069 [BackseatComponent](INFO): Join timeout helper Thread ID is 4933 2025-10-10T20:02:10.252Z,1760126530.252 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2025-10-10T20:02:10.253Z,1760126530.253 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:02:10.269Z,1760126530.269 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2025-10-10T20:02:10.269Z,1760126530.269 [logger ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:02:10.270Z,1760126530.270 [logger](INFO): Join timeout helper Thread ID is 4934 2025-10-10T20:02:10.273Z,1760126530.273 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2025-10-10T20:02:10.274Z,1760126530.274 [logger ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:02:10.289Z,1760126530.289 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2025-10-10T20:02:10.289Z,1760126530.289 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:02:10.289Z,1760126530.289 [CommandLine](INFO): Join timeout helper Thread ID is 4935 2025-10-10T20:02:10.380Z,1760126530.380 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2025-10-10T20:02:10.381Z,1760126530.381 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:02:10.389Z,1760126530.389 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2025-10-10T20:02:10.389Z,1760126530.389 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:02:10.389Z,1760126530.389 [CommandExec](INFO): Join timeout helper Thread ID is 4936 2025-10-10T20:02:10.390Z,1760126530.390 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2025-10-10T20:02:10.391Z,1760126530.391 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:02:10.391Z,1760126530.391 [controlThread](INFO): Join timeout helper Thread ID is 4937 2025-10-10T20:02:10.592Z,1760126530.592 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2025-10-10T20:02:10.593Z,1760126530.593 [controlThread](DEBUG): Uninitializing ControlThread 2025-10-10T20:02:10.593Z,1760126530.593 [AHRS_M2](INFO): Powering down 2025-10-10T20:02:10.672Z,1760126530.672 [NAL9602](INFO): Powering down 2025-10-10T20:02:10.674Z,1760126530.674 [Sonardyne_Nano](INFO): Powering down 2025-10-10T20:02:10.876Z,1760126530.876 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2025-10-10T20:02:10.877Z,1760126530.877 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2025-10-10T20:02:10.878Z,1760126530.878 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2025-10-10T20:02:10.878Z,1760126530.878 [MissionManager](INFO): Uninitializing Mission Default 2025-10-10T20:02:10.878Z,1760126530.878 [Default] Stopped 2025-10-10T20:02:10.878Z,1760126530.878 [Default](DEBUG): Aggregate::uninitialize Default 2025-10-10T20:02:10.878Z,1760126530.878 [Default:B.GoToSurface] Stopped 2025-10-10T20:02:10.879Z,1760126530.879 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-10-10T20:02:10.879Z,1760126530.879 [Default:CheckIn] Stopped 2025-10-10T20:02:10.879Z,1760126530.879 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-10T20:02:10.879Z,1760126530.879 [Default:CheckIn:C.Wait] Stopped 2025-10-10T20:02:10.879Z,1760126530.879 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-10T20:02:10.882Z,1760126530.882 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2025-10-10T20:02:10.882Z,1760126530.882 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2025-10-10T20:02:10.883Z,1760126530.883 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2025-10-10T20:02:10.883Z,1760126530.883 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2025-10-10T20:02:10.883Z,1760126530.883 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2025-10-10T20:02:10.883Z,1760126530.883 [BuoyancyServo](INFO): Powering down 2025-10-10T20:02:10.896Z,1760126530.896 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2025-10-10T20:02:10.896Z,1760126530.896 [ElevatorServo](INFO): Powering down 2025-10-10T20:02:10.897Z,1760126530.897 [MassServo](DEBUG): Uninitialize Mass Servo. 2025-10-10T20:02:10.897Z,1760126530.897 [MassServo](INFO): Powering down 2025-10-10T20:02:10.898Z,1760126530.898 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2025-10-10T20:02:10.898Z,1760126530.898 [RudderServo](INFO): Powering down 2025-10-10T20:02:10.899Z,1760126530.899 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2025-10-10T20:02:10.899Z,1760126530.899 [ThrusterHE](INFO): Powering down 2025-10-10T20:02:10.900Z,1760126530.900 [SBIT](DEBUG): Uninitialize SBIT Component. 2025-10-10T20:02:10.900Z,1760126530.900 [IBIT](DEBUG): Uninitialize IBIT Component. 2025-10-10T20:02:10.901Z,1760126530.901 [CBIT](DEBUG): Uninitialize CBIT Component. 2025-10-10T20:02:10.901Z,1760126530.901 [CBIT](DEBUG): Powering off loads. 2025-10-10T20:02:10.912Z,1760126530.912 [CBIT](DEBUG): Disabling WDT. 2025-10-10T20:02:10.924Z,1760126530.924 [GFScanner](DEBUG): Uninitialize GFScanner component. 2025-10-10T20:02:10.924Z,1760126530.924 [GFScanner](DEBUG): Opening all GF detection circuits. 2025-10-10T20:02:10.925Z,1760126530.925 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:02:10.966Z,1760126530.966 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:02:10.969Z,1760126530.969 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:02:10.975Z,1760126530.975 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:02:10.982Z,1760126530.982 [DAT ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:02:11.024Z,1760126531.024 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:02:11.059Z,1760126531.059 [GFScanner](DEBUG): Uninitialize GFScanner component. 2025-10-10T20:02:11.060Z,1760126531.060 [GFScanner](DEBUG): Opening all GF detection circuits. 2025-10-10T20:02:11.120Z,1760126531.120 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:02:11.126Z,1760126531.126 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:02:11.134Z,1760126531.134 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-10-10T20:02:11.240Z,1760126531.240 [logger ThreadHandler](INFO): Thread cancelled.