2025-12-15T20:31:30.780Z,1765830690.780 [DataOverHttps](INFO): Received command: restart logs 2025-12-15T20:31:30.813Z,1765830690.813 [CommandExec](IMPORTANT): got command restart logs 2025-12-15T20:34:23.229Z,1765830863.229 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-12-15T20:34:23.229Z,1765830863.229 [Default:CheckIn:C.Wait] Stopped 2025-12-15T20:34:23.229Z,1765830863.229 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-12-15T20:34:23.229Z,1765830863.229 [Default:CheckIn:D] Running Loop=1 2025-12-15T20:34:23.630Z,1765830863.630 [Default:CheckIn:D] Stopped 2025-12-15T20:34:23.630Z,1765830863.630 [Default:CheckIn:E] Running Loop=1 2025-12-15T20:34:24.043Z,1765830864.043 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 36.779350 min 2025-12-15T20:34:24.043Z,1765830864.043 [Default:CheckIn:E] Stopped 2025-12-15T20:34:24.043Z,1765830864.043 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-12-15T20:34:24.043Z,1765830864.043 [Default:CheckIn] Stopped 2025-12-15T20:34:24.043Z,1765830864.043 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-12-15T20:34:24.043Z,1765830864.043 [Default:CheckIn](INFO): Running loop #7 2025-12-15T20:34:24.044Z,1765830864.044 [Default:CheckIn] Running Loop=7 2025-12-15T20:34:24.044Z,1765830864.044 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-12-15T20:34:24.044Z,1765830864.044 [Default:CheckIn:Read_GPS] Running Loop=1 2025-12-15T20:34:26.047Z,1765830866.047 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203424.00,A,3650.11096,N,12157.30782,W,1.691,74.17,151225,,,D*41 2025-12-15T20:34:26.049Z,1765830866.049 [NAL9602](INFO): GPS fix at 20251215T203424: (36.835183, -121.955130) 2025-12-15T20:34:26.084Z,1765830866.084 [Default:CheckIn:Read_GPS] Stopped 2025-12-15T20:34:26.084Z,1765830866.084 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-12-15T20:34:30.282Z,1765830870.282 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20251215T143000/Courier0066.lzma 2025-12-15T20:34:30.902Z,1765830870.902 [DataOverHttps](INFO): Moved sent file to Logs/20251215T143000/Courier0066.lzma.bak 2025-12-15T20:34:30.902Z,1765830870.902 [DataOverHttps](INFO): SBD MOMSN=26499358 2025-12-15T20:34:49.492Z,1765830889.492 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20251215T203118/Courier0000.lzma 2025-12-15T20:34:50.979Z,1765830890.979 [DataOverHttps](INFO): Moved sent file to Logs/20251215T203118/Courier0000.lzma.bak 2025-12-15T20:34:50.980Z,1765830890.980 [DataOverHttps](INFO): SBD MOMSN=26499363 2025-12-15T20:35:08.724Z,1765830908.724 [DataOverHttps](INFO): Sending 272 bytes from file Logs/20251215T203130/Courier0000.lzma 2025-12-15T20:35:09.726Z,1765830909.726 [DataOverHttps](INFO): Moved sent file to Logs/20251215T203130/Courier0000.lzma.bak 2025-12-15T20:35:09.726Z,1765830909.726 [DataOverHttps](INFO): SBD MOMSN=26499370 2025-12-15T20:35:25.875Z,1765830925.875 [DataOverHttps](INFO): Sending 125 bytes from file Logs/20251215T143000/Express0067.lzma 2025-12-15T20:35:26.878Z,1765830926.878 [DataOverHttps](INFO): Moved sent file to Logs/20251215T143000/Express0067.lzma.bak 2025-12-15T20:35:26.878Z,1765830926.878 [DataOverHttps](INFO): SBD MOMSN=26499374 2025-12-15T20:35:43.736Z,1765830943.736 [DataOverHttps](INFO): Sending 412 bytes from file Logs/20251215T203118/Express0001.lzma 2025-12-15T20:35:44.738Z,1765830944.738 [DataOverHttps](INFO): Moved sent file to Logs/20251215T203118/Express0001.lzma.bak 2025-12-15T20:35:44.738Z,1765830944.738 [DataOverHttps](INFO): SBD MOMSN=26499378 2025-12-15T20:36:00.648Z,1765830960.648 [DataOverHttps](INFO): Sending 594 bytes from file Logs/20251215T203130/Express0001.lzma 2025-12-15T20:36:01.650Z,1765830961.650 [DataOverHttps](INFO): Moved sent file to Logs/20251215T203130/Express0001.lzma.bak 2025-12-15T20:36:01.650Z,1765830961.650 [DataOverHttps](INFO): SBD MOMSN=26499382 2025-12-15T20:36:05.150Z,1765830965.150 [NAL9602](INFO): SBD MO Status=0, MOMSN=24487, MT Status=0, MTMSN=0 2025-12-15T20:36:05.150Z,1765830965.150 [NAL9602](INFO): No messages in MT queue 2025-12-15T20:36:17.951Z,1765830977.951 [DataOverHttps](INFO): Sending 81 bytes from file Logs/20251215T203130/Express0004.lzma 2025-12-15T20:36:18.954Z,1765830978.954 [DataOverHttps](INFO): Moved sent file to Logs/20251215T203130/Express0004.lzma.bak 2025-12-15T20:36:18.954Z,1765830978.954 [DataOverHttps](INFO): SBD MOMSN=26499386 2025-12-15T20:36:20.501Z,1765830980.501 [Default:CheckIn:Read_Iridium] Stopped 2025-12-15T20:36:20.501Z,1765830980.501 [Default:CheckIn:C.Wait] Running Loop=1 2025-12-15T20:36:20.501Z,1765830980.501 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-12-15T20:36:35.849Z,1765830995.849 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-15T20:41:21.083Z,1765831281.083 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-12-15T20:41:21.084Z,1765831281.084 [Default:CheckIn:C.Wait] Stopped 2025-12-15T20:41:21.084Z,1765831281.084 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-12-15T20:41:21.084Z,1765831281.084 [Default:CheckIn:D] Running Loop=1 2025-12-15T20:41:21.493Z,1765831281.493 [Default:CheckIn:D] Stopped 2025-12-15T20:41:21.493Z,1765831281.493 [Default:CheckIn:E] Running Loop=1 2025-12-15T20:41:21.890Z,1765831281.890 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 43.743730 min 2025-12-15T20:41:21.890Z,1765831281.890 [Default:CheckIn:E] Stopped 2025-12-15T20:41:21.890Z,1765831281.890 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-12-15T20:41:21.890Z,1765831281.890 [Default:CheckIn] Stopped 2025-12-15T20:41:21.890Z,1765831281.890 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-12-15T20:41:21.891Z,1765831281.891 [Default:CheckIn](INFO): Running loop #8 2025-12-15T20:41:21.891Z,1765831281.891 [Default:CheckIn] Running Loop=8 2025-12-15T20:41:21.891Z,1765831281.891 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-12-15T20:41:21.891Z,1765831281.891 [Default:CheckIn:Read_GPS] Running Loop=1 2025-12-15T20:41:23.902Z,1765831283.902 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204121.00,A,3649.50800,N,12155.19296,W,28.011,110.27,151225,,,D*40 2025-12-15T20:41:23.905Z,1765831283.905 [NAL9602](INFO): GPS fix at 20251215T204121: (36.825133, -121.919883) 2025-12-15T20:41:23.915Z,1765831283.915 [Default:CheckIn:Read_GPS] Stopped 2025-12-15T20:41:23.915Z,1765831283.915 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-12-15T20:41:31.000Z,1765831292.000 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20251215T203130/Courier0006.lzma 2025-12-15T20:41:33.002Z,1765831293.002 [DataOverHttps](INFO): Moved sent file to Logs/20251215T203130/Courier0006.lzma.bak 2025-12-15T20:41:33.002Z,1765831293.002 [DataOverHttps](INFO): SBD MOMSN=26499403 2025-12-15T20:41:49.032Z,1765831309.032 [DataOverHttps](INFO): Sending 197 bytes from file Logs/20251215T203130/Express0007.lzma 2025-12-15T20:41:49.354Z,1765831309.354 [NAL9602](INFO): SBD MO Status=0, MOMSN=24488, MT Status=0, MTMSN=0 2025-12-15T20:41:49.354Z,1765831309.354 [NAL9602](INFO): No messages in MT queue 2025-12-15T20:41:50.034Z,1765831310.034 [DataOverHttps](INFO): Moved sent file to Logs/20251215T203130/Express0007.lzma.bak 2025-12-15T20:41:50.034Z,1765831310.034 [DataOverHttps](INFO): SBD MOMSN=26499406 2025-12-15T20:41:52.248Z,1765831312.248 [Default:CheckIn:Read_Iridium] Stopped 2025-12-15T20:41:52.248Z,1765831312.248 [Default:CheckIn:C.Wait] Running Loop=1 2025-12-15T20:41:52.248Z,1765831312.248 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-12-15T20:42:20.076Z,1765831340.076 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-15T20:46:03.584Z,1765831563.584 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-15T20:46:34.709Z,1765831594.709 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-15T20:46:52.778Z,1765831612.778 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-12-15T20:46:52.779Z,1765831612.779 [Default:CheckIn:C.Wait] Stopped 2025-12-15T20:46:52.779Z,1765831612.779 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-12-15T20:46:52.781Z,1765831612.781 [Default:CheckIn:D] Running Loop=1 2025-12-15T20:46:53.203Z,1765831613.203 [Default:CheckIn:D] Stopped 2025-12-15T20:46:53.203Z,1765831613.203 [Default:CheckIn:E] Running Loop=1 2025-12-15T20:46:53.607Z,1765831613.607 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 49.272233 min 2025-12-15T20:46:53.607Z,1765831613.607 [Default:CheckIn:E] Stopped 2025-12-15T20:46:53.607Z,1765831613.607 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-12-15T20:46:53.607Z,1765831613.607 [Default:CheckIn] Stopped 2025-12-15T20:46:53.607Z,1765831613.607 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-12-15T20:46:53.607Z,1765831613.607 [Default:CheckIn](INFO): Running loop #9 2025-12-15T20:46:53.607Z,1765831613.607 [Default:CheckIn] Running Loop=9 2025-12-15T20:46:53.607Z,1765831613.607 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-12-15T20:46:53.608Z,1765831613.608 [Default:CheckIn:Read_GPS] Running Loop=1 2025-12-15T20:46:55.594Z,1765831615.594 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204653.00,A,3648.70872,N,12152.08164,W,27.175,98.19,151225,,,D*7D 2025-12-15T20:46:55.596Z,1765831615.596 [NAL9602](INFO): GPS fix at 20251215T204653: (36.811812, -121.868027) 2025-12-15T20:46:55.607Z,1765831615.607 [Default:CheckIn:Read_GPS] Stopped 2025-12-15T20:46:55.607Z,1765831615.607 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-12-15T20:47:02.815Z,1765831622.815 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20251215T203130/Courier0009.lzma 2025-12-15T20:47:03.814Z,1765831623.814 [DataOverHttps](INFO): Moved sent file to Logs/20251215T203130/Courier0009.lzma.bak 2025-12-15T20:47:03.814Z,1765831623.814 [DataOverHttps](INFO): SBD MOMSN=26499461 2025-12-15T20:47:19.796Z,1765831639.796 [DataOverHttps](INFO): Sending 198 bytes from file Logs/20251215T203130/Express0010.lzma 2025-12-15T20:47:20.639Z,1765831640.639 [NAL9602](INFO): SBD MO Status=0, MOMSN=24489, MT Status=0, MTMSN=0 2025-12-15T20:47:20.639Z,1765831640.639 [NAL9602](INFO): No messages in MT queue 2025-12-15T20:47:20.798Z,1765831640.798 [DataOverHttps](INFO): Moved sent file to Logs/20251215T203130/Express0010.lzma.bak 2025-12-15T20:47:20.798Z,1765831640.798 [DataOverHttps](INFO): SBD MOMSN=26499464 2025-12-15T20:47:22.271Z,1765831642.271 [Default:CheckIn:Read_Iridium] Stopped 2025-12-15T20:47:22.271Z,1765831642.271 [Default:CheckIn:C.Wait] Running Loop=1 2025-12-15T20:47:22.271Z,1765831642.271 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-12-15T20:47:51.343Z,1765831671.343 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-15T20:48:57.294Z,1765831737.294 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-12-15T20:52:22.856Z,1765831942.856 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-12-15T20:52:22.856Z,1765831942.856 [Default:CheckIn:C.Wait] Stopped 2025-12-15T20:52:22.856Z,1765831942.856 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-12-15T20:52:22.856Z,1765831942.856 [Default:CheckIn:D] Running Loop=1 2025-12-15T20:52:23.246Z,1765831943.246 [Default:CheckIn:D] Stopped 2025-12-15T20:52:23.246Z,1765831943.246 [Default:CheckIn:E] Running Loop=1 2025-12-15T20:52:23.651Z,1765831943.651 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 54.772945 min 2025-12-15T20:52:23.651Z,1765831943.651 [Default:CheckIn:E] Stopped 2025-12-15T20:52:23.651Z,1765831943.651 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-12-15T20:52:23.651Z,1765831943.651 [Default:CheckIn] Stopped 2025-12-15T20:52:23.651Z,1765831943.651 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-12-15T20:52:23.651Z,1765831943.651 [Default:CheckIn](INFO): Running loop #10 2025-12-15T20:52:23.651Z,1765831943.651 [Default:CheckIn] Running Loop=10 2025-12-15T20:52:23.652Z,1765831943.652 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-12-15T20:52:23.652Z,1765831943.652 [Default:CheckIn:Read_GPS] Running Loop=1 2025-12-15T20:52:25.679Z,1765831945.679 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205223.00,A,3648.37791,N,12149.19471,W,28.575,99.90,151225,,,D*7E 2025-12-15T20:52:25.681Z,1765831945.681 [NAL9602](INFO): GPS fix at 20251215T205223: (36.806298, -121.819912) 2025-12-15T20:52:25.696Z,1765831945.696 [Default:CheckIn:Read_GPS] Stopped 2025-12-15T20:52:25.696Z,1765831945.696 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-12-15T20:52:32.880Z,1765831952.880 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20251215T203130/Courier0012.lzma 2025-12-15T20:52:33.882Z,1765831953.882 [DataOverHttps](INFO): Moved sent file to Logs/20251215T203130/Courier0012.lzma.bak 2025-12-15T20:52:33.882Z,1765831953.882 [DataOverHttps](INFO): SBD MOMSN=26499481 2025-12-15T20:52:50.710Z,1765831970.710 [NAL9602](INFO): SBD MO Status=0, MOMSN=24490, MT Status=0, MTMSN=0 2025-12-15T20:52:50.710Z,1765831970.710 [NAL9602](INFO): No messages in MT queue 2025-12-15T20:52:58.080Z,1765831978.080 [DataOverHttps](INFO): Sending 197 bytes from file Logs/20251215T203130/Express0013.lzma 2025-12-15T20:52:59.082Z,1765831979.082 [DataOverHttps](INFO): Moved sent file to Logs/20251215T203130/Express0013.lzma.bak 2025-12-15T20:52:59.082Z,1765831979.082 [DataOverHttps](INFO): SBD MOMSN=26499485 2025-12-15T20:53:00.424Z,1765831980.424 [Default:CheckIn:Read_Iridium] Stopped 2025-12-15T20:53:00.424Z,1765831980.424 [Default:CheckIn:C.Wait] Running Loop=1 2025-12-15T20:53:00.424Z,1765831980.424 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-12-15T20:53:21.429Z,1765832001.429 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-15T20:58:01.055Z,1765832281.055 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-12-15T20:58:01.056Z,1765832281.056 [Default:CheckIn:C.Wait] Stopped 2025-12-15T20:58:01.056Z,1765832281.056 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-12-15T20:58:01.057Z,1765832281.057 [Default:CheckIn:D] Running Loop=1 2025-12-15T20:58:01.439Z,1765832281.439 [Default:CheckIn:D] Stopped 2025-12-15T20:58:01.439Z,1765832281.439 [Default:CheckIn:E] Running Loop=1 2025-12-15T20:58:01.840Z,1765832281.840 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 60.409501 min 2025-12-15T20:58:01.840Z,1765832281.840 [Default:CheckIn:E] Stopped 2025-12-15T20:58:01.840Z,1765832281.840 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-12-15T20:58:01.840Z,1765832281.840 [Default:CheckIn] Stopped 2025-12-15T20:58:01.840Z,1765832281.840 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-12-15T20:58:01.841Z,1765832281.841 [Default:CheckIn](INFO): Running loop #11 2025-12-15T20:58:01.841Z,1765832281.841 [Default:CheckIn] Running Loop=11 2025-12-15T20:58:01.841Z,1765832281.841 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-12-15T20:58:01.841Z,1765832281.841 [Default:CheckIn:Read_GPS] Running Loop=1 2025-12-15T20:58:03.857Z,1765832283.857 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205801.00,A,3648.48599,N,12147.13068,W,4.937,151.98,151225,,,D*77 2025-12-15T20:58:03.859Z,1765832283.859 [NAL9602](INFO): GPS fix at 20251215T205801: (36.808100, -121.785511) 2025-12-15T20:58:03.870Z,1765832283.870 [Default:CheckIn:Read_GPS] Stopped 2025-12-15T20:58:03.870Z,1765832283.870 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-12-15T20:58:10.939Z,1765832290.939 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20251215T203130/Courier0015.lzma 2025-12-15T20:58:11.942Z,1765832291.942 [DataOverHttps](INFO): Moved sent file to Logs/20251215T203130/Courier0015.lzma.bak 2025-12-15T20:58:11.942Z,1765832291.942 [DataOverHttps](INFO): SBD MOMSN=26499541 2025-12-15T20:58:26.590Z,1765832306.590 [NAL9602](INFO): SBD MO Status=0, MOMSN=24491, MT Status=0, MTMSN=0 2025-12-15T20:58:26.590Z,1765832306.590 [NAL9602](INFO): No messages in MT queue 2025-12-15T20:58:27.796Z,1765832307.796 [DataOverHttps](INFO): Sending 193 bytes from file Logs/20251215T203130/Express0016.lzma 2025-12-15T20:58:28.799Z,1765832308.799 [DataOverHttps](INFO): Moved sent file to Logs/20251215T203130/Express0016.lzma.bak 2025-12-15T20:58:28.799Z,1765832308.799 [DataOverHttps](INFO): SBD MOMSN=26499544 2025-12-15T20:58:30.127Z,1765832310.127 [Default:CheckIn:Read_Iridium] Stopped 2025-12-15T20:58:30.127Z,1765832310.127 [Default:CheckIn:C.Wait] Running Loop=1 2025-12-15T20:58:30.127Z,1765832310.127 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-12-15T20:58:57.183Z,1765832337.183 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-15T21:03:30.703Z,1765832610.703 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-12-15T21:03:30.703Z,1765832610.703 [Default:CheckIn:C.Wait] Stopped 2025-12-15T21:03:30.703Z,1765832610.703 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-12-15T21:03:30.703Z,1765832610.703 [Default:CheckIn:D] Running Loop=1 2025-12-15T21:03:31.109Z,1765832611.109 [Default:CheckIn:D] Stopped 2025-12-15T21:03:31.109Z,1765832611.109 [Default:CheckIn:E] Running Loop=1 2025-12-15T21:03:31.567Z,1765832611.567 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 65.904000 min 2025-12-15T21:03:31.567Z,1765832611.567 [Default:CheckIn:E] Stopped 2025-12-15T21:03:31.568Z,1765832611.568 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-12-15T21:03:31.568Z,1765832611.568 [Default:CheckIn] Stopped 2025-12-15T21:03:31.568Z,1765832611.568 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-12-15T21:03:31.568Z,1765832611.568 [Default:CheckIn](INFO): Running loop #12 2025-12-15T21:03:31.568Z,1765832611.568 [Default:CheckIn] Running Loop=12 2025-12-15T21:03:31.568Z,1765832611.568 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-12-15T21:03:31.568Z,1765832611.568 [Default:CheckIn:Read_GPS] Running Loop=1 2025-12-15T21:03:33.587Z,1765832613.587 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210331.00,A,3648.17452,N,12147.20123,W,2.333,224.22,151225,,,D*71 2025-12-15T21:03:33.589Z,1765832613.589 [NAL9602](INFO): GPS fix at 20251215T210331: (36.802909, -121.786687) 2025-12-15T21:03:33.599Z,1765832613.599 [Default:CheckIn:Read_GPS] Stopped 2025-12-15T21:03:33.600Z,1765832613.600 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-12-15T21:03:40.667Z,1765832620.667 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20251215T203130/Courier0018.lzma 2025-12-15T21:03:41.670Z,1765832621.670 [DataOverHttps](INFO): Moved sent file to Logs/20251215T203130/Courier0018.lzma.bak 2025-12-15T21:03:41.670Z,1765832621.670 [DataOverHttps](INFO): SBD MOMSN=26499562 2025-12-15T21:03:49.677Z,1765832629.677 [NAL9602](INFO): SBD MO Status=0, MOMSN=24492, MT Status=0, MTMSN=0 2025-12-15T21:03:49.677Z,1765832629.677 [NAL9602](INFO): No messages in MT queue 2025-12-15T21:03:57.708Z,1765832637.708 [DataOverHttps](INFO): Sending 199 bytes from file Logs/20251215T203130/Express0019.lzma 2025-12-15T21:03:58.710Z,1765832638.710 [DataOverHttps](INFO): Moved sent file to Logs/20251215T203130/Express0019.lzma.bak 2025-12-15T21:03:58.710Z,1765832638.710 [DataOverHttps](INFO): SBD MOMSN=26499565 2025-12-15T21:04:00.207Z,1765832640.207 [Default:CheckIn:Read_Iridium] Stopped 2025-12-15T21:04:00.207Z,1765832640.207 [Default:CheckIn:C.Wait] Running Loop=1 2025-12-15T21:04:00.207Z,1765832640.207 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-12-15T21:04:20.437Z,1765832660.437 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-15T21:07:46.067Z,1765832866.067 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2025-12-15T21:07:46.070Z,1765832866.070 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8. 2025-12-15T21:09:00.910Z,1765832940.910 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-12-15T21:09:00.911Z,1765832940.911 [Default:CheckIn:C.Wait] Stopped 2025-12-15T21:09:00.911Z,1765832940.911 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-12-15T21:09:00.911Z,1765832940.911 [Default:CheckIn:D] Running Loop=1 2025-12-15T21:09:01.386Z,1765832941.386 [Default:CheckIn:D] Stopped 2025-12-15T21:09:01.386Z,1765832941.386 [Default:CheckIn:E] Running Loop=1 2025-12-15T21:09:01.631Z,1765832941.631 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 71.408618 min 2025-12-15T21:09:01.631Z,1765832941.631 [Default:CheckIn:E] Stopped 2025-12-15T21:09:01.632Z,1765832941.632 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-12-15T21:09:01.632Z,1765832941.632 [Default:CheckIn] Stopped 2025-12-15T21:09:01.632Z,1765832941.632 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-12-15T21:09:01.632Z,1765832941.632 [Default:CheckIn](INFO): Running loop #13 2025-12-15T21:09:01.632Z,1765832941.632 [Default:CheckIn] Running Loop=13 2025-12-15T21:09:01.632Z,1765832941.632 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-12-15T21:09:01.632Z,1765832941.632 [Default:CheckIn:Read_GPS] Running Loop=1 2025-12-15T21:09:03.644Z,1765832943.644 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210901.00,A,3648.15250,N,12147.22193,W,0.330,341.66,151225,,,D*74 2025-12-15T21:09:03.646Z,1765832943.646 [NAL9602](INFO): GPS fix at 20251215T210901: (36.802542, -121.787032) 2025-12-15T21:09:03.657Z,1765832943.657 [Default:CheckIn:Read_GPS] Stopped 2025-12-15T21:09:03.657Z,1765832943.657 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-12-15T21:09:10.688Z,1765832950.688 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20251215T203130/Courier0021.lzma 2025-12-15T21:09:11.690Z,1765832951.690 [DataOverHttps](INFO): Moved sent file to Logs/20251215T203130/Courier0021.lzma.bak 2025-12-15T21:09:11.690Z,1765832951.690 [DataOverHttps](INFO): SBD MOMSN=26499615 2025-12-15T21:09:27.484Z,1765832967.484 [DataOverHttps](INFO): Sending 331 bytes from file Logs/20251215T203130/Express0022.lzma 2025-12-15T21:09:28.486Z,1765832968.486 [DataOverHttps](INFO): Moved sent file to Logs/20251215T203130/Express0022.lzma.bak 2025-12-15T21:09:28.486Z,1765832968.486 [DataOverHttps](INFO): SBD MOMSN=26499618 2025-12-15T21:09:29.967Z,1765832969.967 [Default:CheckIn:Read_Iridium] Stopped 2025-12-15T21:09:29.967Z,1765832969.967 [Default:CheckIn:C.Wait] Running Loop=1 2025-12-15T21:09:29.967Z,1765832969.967 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-12-15T21:09:38.842Z,1765832978.842 [NAL9602](INFO): SBD MO Status=0, MOMSN=24493, MT Status=0, MTMSN=0 2025-12-15T21:09:38.842Z,1765832978.842 [NAL9602](INFO): No messages in MT queue 2025-12-15T21:10:09.543Z,1765833009.543 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-15T21:14:30.573Z,1765833270.573 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-12-15T21:14:30.573Z,1765833270.573 [Default:CheckIn:C.Wait] Stopped 2025-12-15T21:14:30.573Z,1765833270.573 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-12-15T21:14:30.573Z,1765833270.573 [Default:CheckIn:D] Running Loop=1 2025-12-15T21:14:30.957Z,1765833270.957 [Default:CheckIn:D] Stopped 2025-12-15T21:14:30.957Z,1765833270.957 [Default:CheckIn:E] Running Loop=1 2025-12-15T21:14:31.364Z,1765833271.364 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 76.901465 min 2025-12-15T21:14:31.364Z,1765833271.364 [Default:CheckIn:E] Stopped 2025-12-15T21:14:31.365Z,1765833271.365 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-12-15T21:14:31.365Z,1765833271.365 [Default:CheckIn] Stopped 2025-12-15T21:14:31.365Z,1765833271.365 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-12-15T21:14:31.365Z,1765833271.365 [Default:CheckIn](INFO): Running loop #14 2025-12-15T21:14:31.365Z,1765833271.365 [Default:CheckIn] Running Loop=14 2025-12-15T21:14:31.365Z,1765833271.365 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-12-15T21:14:31.365Z,1765833271.365 [Default:CheckIn:Read_GPS] Running Loop=1 2025-12-15T21:14:33.447Z,1765833273.447 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211431.00,A,3648.15034,N,12147.22763,W,0.097,252.30,151225,,,D*7C 2025-12-15T21:14:33.450Z,1765833273.450 [NAL9602](INFO): GPS fix at 20251215T211431: (36.802506, -121.787127) 2025-12-15T21:14:33.468Z,1765833273.468 [Default:CheckIn:Read_GPS] Stopped 2025-12-15T21:14:33.468Z,1765833273.468 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-12-15T21:14:40.879Z,1765833280.879 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20251215T203130/Courier0024.lzma 2025-12-15T21:14:41.882Z,1765833281.882 [DataOverHttps](INFO): Moved sent file to Logs/20251215T203130/Courier0024.lzma.bak 2025-12-15T21:14:41.882Z,1765833281.882 [DataOverHttps](INFO): SBD MOMSN=26499638 2025-12-15T21:14:44.888Z,1765833284.888 [NAL9602](INFO): SBD MO Status=0, MOMSN=24494, MT Status=0, MTMSN=0 2025-12-15T21:14:44.888Z,1765833284.888 [NAL9602](INFO): No messages in MT queue 2025-12-15T21:14:57.744Z,1765833297.744 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20251215T203130/Express0025.lzma 2025-12-15T21:14:58.746Z,1765833298.746 [DataOverHttps](INFO): Moved sent file to Logs/20251215T203130/Express0025.lzma.bak 2025-12-15T21:14:58.746Z,1765833298.746 [DataOverHttps](INFO): SBD MOMSN=26499647 2025-12-15T21:15:00.262Z,1765833300.262 [Default:CheckIn:Read_Iridium] Stopped 2025-12-15T21:15:00.262Z,1765833300.262 [Default:CheckIn:C.Wait] Running Loop=1 2025-12-15T21:15:00.262Z,1765833300.262 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-12-15T21:15:15.586Z,1765833315.586 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-15T21:20:00.932Z,1765833600.932 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-12-15T21:20:00.932Z,1765833600.932 [Default:CheckIn:C.Wait] Stopped 2025-12-15T21:20:00.933Z,1765833600.933 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-12-15T21:20:00.933Z,1765833600.933 [Default:CheckIn:D] Running Loop=1 2025-12-15T21:20:01.282Z,1765833601.282 [Default:CheckIn:D] Stopped 2025-12-15T21:20:01.282Z,1765833601.282 [Default:CheckIn:E] Running Loop=1 2025-12-15T21:20:01.690Z,1765833601.690 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 82.406885 min 2025-12-15T21:20:01.690Z,1765833601.690 [Default:CheckIn:E] Stopped 2025-12-15T21:20:01.690Z,1765833601.690 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-12-15T21:20:01.690Z,1765833601.690 [Default:CheckIn] Stopped 2025-12-15T21:20:01.690Z,1765833601.690 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-12-15T21:20:01.691Z,1765833601.691 [Default:CheckIn](INFO): Running loop #15 2025-12-15T21:20:01.691Z,1765833601.691 [Default:CheckIn] Running Loop=15 2025-12-15T21:20:01.691Z,1765833601.691 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-12-15T21:20:01.691Z,1765833601.691 [Default:CheckIn:Read_GPS] Running Loop=1 2025-12-15T21:20:03.683Z,1765833603.683 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212001.00,A,3648.15190,N,12147.22742,W,0.058,357.78,151225,,,D*7F 2025-12-15T21:20:03.685Z,1765833603.685 [NAL9602](INFO): GPS fix at 20251215T212001: (36.802532, -121.787124) 2025-12-15T21:20:03.695Z,1765833603.695 [Default:CheckIn:Read_GPS] Stopped 2025-12-15T21:20:03.695Z,1765833603.695 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-12-15T21:20:11.419Z,1765833611.419 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20251215T203130/Courier0027.lzma 2025-12-15T21:20:12.422Z,1765833612.422 [DataOverHttps](INFO): Moved sent file to Logs/20251215T203130/Courier0027.lzma.bak 2025-12-15T21:20:12.422Z,1765833612.422 [DataOverHttps](INFO): SBD MOMSN=26499692 2025-12-15T21:20:17.844Z,1765833617.844 [NAL9602](INFO): SBD MO Status=0, MOMSN=24495, MT Status=0, MTMSN=0 2025-12-15T21:20:17.844Z,1765833617.844 [NAL9602](INFO): No messages in MT queue 2025-12-15T21:20:28.344Z,1765833628.344 [DataOverHttps](INFO): Sending 175 bytes from file Logs/20251215T203130/Express0028.lzma 2025-12-15T21:20:29.346Z,1765833629.346 [DataOverHttps](INFO): Moved sent file to Logs/20251215T203130/Express0028.lzma.bak 2025-12-15T21:20:29.346Z,1765833629.346 [DataOverHttps](INFO): SBD MOMSN=26499695 2025-12-15T21:20:30.768Z,1765833630.768 [Default:CheckIn:Read_Iridium] Stopped 2025-12-15T21:20:30.768Z,1765833630.768 [Default:CheckIn:C.Wait] Running Loop=1 2025-12-15T21:20:30.768Z,1765833630.768 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-12-15T21:20:48.545Z,1765833648.545 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-15T21:25:31.363Z,1765833931.363 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-12-15T21:25:31.363Z,1765833931.363 [Default:CheckIn:C.Wait] Stopped 2025-12-15T21:25:31.363Z,1765833931.363 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-12-15T21:25:31.363Z,1765833931.363 [Default:CheckIn:D] Running Loop=1 2025-12-15T21:25:31.754Z,1765833931.754 [Default:CheckIn:D] Stopped 2025-12-15T21:25:31.754Z,1765833931.754 [Default:CheckIn:E] Running Loop=1 2025-12-15T21:25:32.158Z,1765833932.158 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 87.914754 min 2025-12-15T21:25:32.158Z,1765833932.158 [Default:CheckIn:E] Stopped 2025-12-15T21:25:32.158Z,1765833932.158 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-12-15T21:25:32.158Z,1765833932.158 [Default:CheckIn] Stopped 2025-12-15T21:25:32.158Z,1765833932.158 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-12-15T21:25:32.159Z,1765833932.159 [Default:CheckIn](INFO): Running loop #16 2025-12-15T21:25:32.159Z,1765833932.159 [Default:CheckIn] Running Loop=16 2025-12-15T21:25:32.159Z,1765833932.159 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-12-15T21:25:32.159Z,1765833932.159 [Default:CheckIn:Read_GPS] Running Loop=1 2025-12-15T21:25:33.810Z,1765833933.810 [CBIT](FAULT): Main Battery Failure 2025-12-15T21:25:34.225Z,1765833934.225 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212532.00,A,3648.15025,N,12147.22965,W,0.058,357.78,151225,,,D*7E 2025-12-15T21:25:34.227Z,1765833934.227 [NAL9602](INFO): GPS fix at 20251215T212532: (36.802504, -121.787161) 2025-12-15T21:25:34.238Z,1765833934.238 [Default:CheckIn:Read_GPS] Stopped 2025-12-15T21:25:34.238Z,1765833934.238 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-12-15T21:25:42.211Z,1765833942.211 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20251215T203130/Courier0030.lzma 2025-12-15T21:25:43.215Z,1765833943.215 [DataOverHttps](INFO): Moved sent file to Logs/20251215T203130/Courier0030.lzma.bak 2025-12-15T21:25:43.215Z,1765833943.215 [DataOverHttps](INFO): SBD MOMSN=26499766 2025-12-15T21:25:46.722Z,1765833946.722 [NAL9602](INFO): SBD MO Status=0, MOMSN=24496, MT Status=0, MTMSN=0 2025-12-15T21:25:46.723Z,1765833946.723 [NAL9602](INFO): No messages in MT queue 2025-12-15T21:25:59.220Z,1765833959.220 [DataOverHttps](INFO): Sending 224 bytes from file Logs/20251215T203130/Express0031.lzma 2025-12-15T21:26:00.222Z,1765833960.222 [DataOverHttps](INFO): Moved sent file to Logs/20251215T203130/Express0031.lzma.bak 2025-12-15T21:26:00.222Z,1765833960.222 [DataOverHttps](INFO): SBD MOMSN=26499769 2025-12-15T21:26:01.658Z,1765833961.658 [Default:CheckIn:Read_Iridium] Stopped 2025-12-15T21:26:01.658Z,1765833961.658 [Default:CheckIn:C.Wait] Running Loop=1 2025-12-15T21:26:01.658Z,1765833961.658 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-12-15T21:26:17.396Z,1765833977.396 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-15T21:31:02.245Z,1765834262.245 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-12-15T21:31:02.245Z,1765834262.245 [Default:CheckIn:C.Wait] Stopped 2025-12-15T21:31:02.245Z,1765834262.245 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-12-15T21:31:02.245Z,1765834262.245 [Default:CheckIn:D] Running Loop=1 2025-12-15T21:31:02.655Z,1765834262.655 [Default:CheckIn:D] Stopped 2025-12-15T21:31:02.655Z,1765834262.655 [Default:CheckIn:E] Running Loop=1 2025-12-15T21:31:03.046Z,1765834263.046 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 93.429769 min 2025-12-15T21:31:03.046Z,1765834263.046 [Default:CheckIn:E] Stopped 2025-12-15T21:31:03.046Z,1765834263.046 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-12-15T21:31:03.046Z,1765834263.046 [Default:CheckIn] Stopped 2025-12-15T21:31:03.046Z,1765834263.046 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-12-15T21:31:03.046Z,1765834263.046 [Default:CheckIn](INFO): Running loop #17 2025-12-15T21:31:03.046Z,1765834263.046 [Default:CheckIn] Running Loop=17 2025-12-15T21:31:03.046Z,1765834263.046 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-12-15T21:31:03.047Z,1765834263.047 [Default:CheckIn:Read_GPS] Running Loop=1 2025-12-15T21:31:05.063Z,1765834265.063 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213103.00,A,3648.17366,N,12147.27879,W,0.058,303.14,151225,,,D*7D 2025-12-15T21:31:05.065Z,1765834265.065 [NAL9602](INFO): GPS fix at 20251215T213103: (36.802894, -121.787980) 2025-12-15T21:31:05.075Z,1765834265.075 [Default:CheckIn:Read_GPS] Stopped 2025-12-15T21:31:05.075Z,1765834265.075 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-12-15T21:31:12.303Z,1765834272.303 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20251215T203130/Courier0033.lzma 2025-12-15T21:31:13.306Z,1765834273.306 [DataOverHttps](INFO): Moved sent file to Logs/20251215T203130/Courier0033.lzma.bak 2025-12-15T21:31:13.306Z,1765834273.306 [DataOverHttps](INFO): SBD MOMSN=26499793 2025-12-15T21:31:22.437Z,1765834282.437 [NAL9602](INFO): SBD MO Status=0, MOMSN=24497, MT Status=0, MTMSN=0 2025-12-15T21:31:22.437Z,1765834282.437 [NAL9602](INFO): No messages in MT queue 2025-12-15T21:31:29.232Z,1765834289.232 [DataOverHttps](INFO): Sending 196 bytes from file Logs/20251215T203130/Express0034.lzma 2025-12-15T21:31:30.234Z,1765834290.234 [DataOverHttps](INFO): Moved sent file to Logs/20251215T203130/Express0034.lzma.bak 2025-12-15T21:31:30.234Z,1765834290.234 [DataOverHttps](INFO): SBD MOMSN=26499796 2025-12-15T21:31:32.150Z,1765834292.150 [Default:CheckIn:Read_Iridium] Stopped 2025-12-15T21:31:32.150Z,1765834292.150 [Default:CheckIn:C.Wait] Running Loop=1 2025-12-15T21:31:32.151Z,1765834292.151 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-12-15T21:31:53.134Z,1765834313.134 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-15T21:36:32.710Z,1765834592.710 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-12-15T21:36:32.710Z,1765834592.710 [Default:CheckIn:C.Wait] Stopped 2025-12-15T21:36:32.710Z,1765834592.710 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-12-15T21:36:32.710Z,1765834592.710 [Default:CheckIn:D] Running Loop=1 2025-12-15T21:36:33.123Z,1765834593.123 [Default:CheckIn:D] Stopped 2025-12-15T21:36:33.123Z,1765834593.123 [Default:CheckIn:E] Running Loop=1 2025-12-15T21:36:33.529Z,1765834593.529 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 98.937557 min 2025-12-15T21:36:33.530Z,1765834593.530 [Default:CheckIn:E] Stopped 2025-12-15T21:36:33.530Z,1765834593.530 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-12-15T21:36:33.530Z,1765834593.530 [Default:CheckIn] Stopped 2025-12-15T21:36:33.530Z,1765834593.530 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-12-15T21:36:33.530Z,1765834593.530 [Default:CheckIn](INFO): Running loop #18 2025-12-15T21:36:33.530Z,1765834593.530 [Default:CheckIn] Running Loop=18 2025-12-15T21:36:33.530Z,1765834593.530 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-12-15T21:36:33.530Z,1765834593.530 [Default:CheckIn:Read_GPS] Running Loop=1 2025-12-15T21:36:35.538Z,1765834595.538 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213633.00,A,3648.17423,N,12147.27996,W,0.097,6.49,151225,,,D*72 2025-12-15T21:36:35.541Z,1765834595.541 [NAL9602](INFO): GPS fix at 20251215T213633: (36.802904, -121.787999) 2025-12-15T21:36:35.551Z,1765834595.551 [Default:CheckIn:Read_GPS] Stopped 2025-12-15T21:36:35.551Z,1765834595.551 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-12-15T21:36:42.639Z,1765834602.639 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20251215T203130/Courier0036.lzma 2025-12-15T21:36:43.642Z,1765834603.642 [DataOverHttps](INFO): Moved sent file to Logs/20251215T203130/Courier0036.lzma.bak 2025-12-15T21:36:43.642Z,1765834603.642 [DataOverHttps](INFO): SBD MOMSN=26499897 2025-12-15T21:36:59.441Z,1765834619.441 [DataOverHttps](INFO): Sending 175 bytes from file Logs/20251215T203130/Express0037.lzma 2025-12-15T21:37:00.426Z,1765834620.426 [DataOverHttps](INFO): Moved sent file to Logs/20251215T203130/Express0037.lzma.bak 2025-12-15T21:37:00.426Z,1765834620.426 [DataOverHttps](INFO): SBD MOMSN=26499900 2025-12-15T21:37:01.812Z,1765834621.812 [Default:CheckIn:Read_Iridium] Stopped 2025-12-15T21:37:01.812Z,1765834621.812 [Default:CheckIn:C.Wait] Running Loop=1 2025-12-15T21:37:01.812Z,1765834621.812 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-12-15T21:39:09.071Z,1765834749.071 [NAL9602](INFO): SBD MO Status=0, MOMSN=24498, MT Status=0, MTMSN=0 2025-12-15T21:39:09.071Z,1765834749.071 [NAL9602](INFO): No messages in MT queue 2025-12-15T21:39:32.086Z,1765834772.086 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-12-15T21:39:38.561Z,1765834778.561 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-12-15T21:39:39.762Z,1765834779.762 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-15T21:42:02.409Z,1765834922.409 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-12-15T21:42:02.409Z,1765834922.409 [Default:CheckIn:C.Wait] Stopped 2025-12-15T21:42:02.409Z,1765834922.409 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-12-15T21:42:02.410Z,1765834922.410 [Default:CheckIn:D] Running Loop=1 2025-12-15T21:42:02.814Z,1765834922.814 [Default:CheckIn:D] Stopped 2025-12-15T21:42:02.814Z,1765834922.814 [Default:CheckIn:E] Running Loop=1 2025-12-15T21:42:03.220Z,1765834923.220 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 104.432414 min 2025-12-15T21:42:03.220Z,1765834923.220 [Default:CheckIn:E] Stopped 2025-12-15T21:42:03.221Z,1765834923.221 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-12-15T21:42:03.221Z,1765834923.221 [Default:CheckIn] Stopped 2025-12-15T21:42:03.221Z,1765834923.221 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-12-15T21:42:03.221Z,1765834923.221 [Default:CheckIn](INFO): Running loop #19 2025-12-15T21:42:03.221Z,1765834923.221 [Default:CheckIn] Running Loop=19 2025-12-15T21:42:03.221Z,1765834923.221 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-12-15T21:42:03.221Z,1765834923.221 [Default:CheckIn:Read_GPS] Running Loop=1 2025-12-15T21:42:05.230Z,1765834925.230 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214203.00,A,3648.17298,N,12147.27954,W,0.019,25.20,151225,,,D*42 2025-12-15T21:42:05.232Z,1765834925.232 [NAL9602](INFO): GPS fix at 20251215T214203: (36.802883, -121.787992) 2025-12-15T21:42:05.260Z,1765834925.260 [Default:CheckIn:Read_GPS] Stopped 2025-12-15T21:42:05.260Z,1765834925.260 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-12-15T21:42:12.459Z,1765834932.459 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20251215T203130/Courier0039.lzma 2025-12-15T21:42:13.462Z,1765834933.462 [DataOverHttps](INFO): Moved sent file to Logs/20251215T203130/Courier0039.lzma.bak 2025-12-15T21:42:13.463Z,1765834933.463 [DataOverHttps](IMPORTANT): SBD MOMSN=26499923, MTMSN=20251215T214211 2025-12-15T21:42:16.549Z,1765834936.549 [NAL9602](INFO): SBD MO Status=0, MOMSN=24499, MT Status=0, MTMSN=0 2025-12-15T21:42:16.549Z,1765834936.549 [NAL9602](INFO): No messages in MT queue 2025-12-15T21:42:20.884Z,1765834940.884 [DataOverHttps](INFO): Received command: ! echo 0 > /dev/loadB1 2025-12-15T21:42:20.979Z,1765834940.979 [CommandExec](IMPORTANT): got command ! echo 0 > /dev/loadB1 2025-12-15T21:42:29.224Z,1765834949.224 [DataOverHttps](INFO): Sending 174 bytes from file Logs/20251215T203130/Express0040.lzma 2025-12-15T21:42:30.226Z,1765834950.226 [DataOverHttps](INFO): Moved sent file to Logs/20251215T203130/Express0040.lzma.bak 2025-12-15T21:42:30.226Z,1765834950.226 [DataOverHttps](INFO): SBD MOMSN=26499930 2025-12-15T21:42:47.253Z,1765834967.253 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-15T21:42:48.867Z,1765834968.867 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20251215T203130/Courier0042.lzma 2025-12-15T21:42:49.870Z,1765834969.870 [DataOverHttps](INFO): Moved sent file to Logs/20251215T203130/Courier0042.lzma.bak 2025-12-15T21:42:49.870Z,1765834969.870 [DataOverHttps](INFO): SBD MOMSN=26499935 2025-12-15T21:43:07.160Z,1765834987.160 [DataOverHttps](INFO): Sending 186 bytes from file Logs/20251215T203130/Express0043.lzma 2025-12-15T21:43:08.162Z,1765834988.162 [DataOverHttps](INFO): Moved sent file to Logs/20251215T203130/Express0043.lzma.bak 2025-12-15T21:43:08.162Z,1765834988.162 [DataOverHttps](INFO): SBD MOMSN=26499938 2025-12-15T21:43:09.487Z,1765834989.487 [Default:CheckIn:Read_Iridium] Stopped 2025-12-15T21:43:09.504Z,1765834989.504 [Default:CheckIn:C.Wait] Running Loop=1 2025-12-15T21:43:09.504Z,1765834989.504 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-12-15T21:48:10.081Z,1765835290.081 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-12-15T21:48:10.082Z,1765835290.082 [Default:CheckIn:C.Wait] Stopped 2025-12-15T21:48:10.082Z,1765835290.082 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-12-15T21:48:10.082Z,1765835290.082 [Default:CheckIn:D] Running Loop=1 2025-12-15T21:48:10.485Z,1765835290.485 [Default:CheckIn:D] Stopped 2025-12-15T21:48:10.485Z,1765835290.485 [Default:CheckIn:E] Running Loop=1 2025-12-15T21:48:10.898Z,1765835290.898 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 110.560262 min 2025-12-15T21:48:10.898Z,1765835290.898 [Default:CheckIn:E] Stopped 2025-12-15T21:48:10.898Z,1765835290.898 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-12-15T21:48:10.898Z,1765835290.898 [Default:CheckIn] Stopped 2025-12-15T21:48:10.898Z,1765835290.898 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-12-15T21:48:10.898Z,1765835290.898 [Default:CheckIn](INFO): Running loop #20 2025-12-15T21:48:10.898Z,1765835290.898 [Default:CheckIn] Running Loop=20 2025-12-15T21:48:10.898Z,1765835290.898 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-12-15T21:48:10.899Z,1765835290.899 [Default:CheckIn:Read_GPS] Running Loop=1 2025-12-15T21:48:12.902Z,1765835292.902 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214811.00,A,3648.17165,N,12147.28162,W,0.039,25.20,151225,,,D*4A 2025-12-15T21:48:12.904Z,1765835292.904 [NAL9602](INFO): GPS fix at 20251215T214811: (36.802861, -121.788027) 2025-12-15T21:48:12.914Z,1765835292.914 [Default:CheckIn:Read_GPS] Stopped 2025-12-15T21:48:12.914Z,1765835292.914 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-12-15T21:48:20.403Z,1765835300.403 [DataOverHttps](INFO): Sending 78 bytes from file Logs/20251215T203130/Courier0045.lzma 2025-12-15T21:48:21.406Z,1765835301.406 [DataOverHttps](INFO): Moved sent file to Logs/20251215T203130/Courier0045.lzma.bak 2025-12-15T21:48:21.406Z,1765835301.406 [DataOverHttps](INFO): SBD MOMSN=26500017 2025-12-15T21:48:29.891Z,1765835309.891 [NAL9602](INFO): SBD MO Status=0, MOMSN=24500, MT Status=0, MTMSN=0 2025-12-15T21:48:29.891Z,1765835309.891 [NAL9602](INFO): No messages in MT queue 2025-12-15T21:48:40.288Z,1765835320.288 [DataOverHttps](INFO): Sending 192 bytes from file Logs/20251215T203130/Express0046.lzma 2025-12-15T21:48:41.290Z,1765835321.290 [DataOverHttps](INFO): Moved sent file to Logs/20251215T203130/Express0046.lzma.bak 2025-12-15T21:48:41.290Z,1765835321.290 [DataOverHttps](INFO): SBD MOMSN=26500020 2025-12-15T21:48:42.903Z,1765835322.903 [Default:CheckIn:Read_Iridium] Stopped 2025-12-15T21:48:42.903Z,1765835322.903 [Default:CheckIn:C.Wait] Running Loop=1 2025-12-15T21:48:42.903Z,1765835322.903 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-12-15T21:48:48.079Z,1765835328.079 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2025-12-15T21:48:48.083Z,1765835328.083 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8. 2025-12-15T21:49:00.603Z,1765835340.603 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-15T21:50:07.267Z,1765835407.267 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-12-15T21:50:07.267Z,1765835407.267 [DropWeight] Hardware Fault, FailCount= 1 2025-12-15T21:50:07.267Z,1765835407.267 [DropWeight](ERROR): Hardware Fault 2025-12-15T21:50:07.320Z,1765835407.320 [CommandExec](FAULT): Scheduling is paused 2025-12-15T21:50:07.321Z,1765835407.321 [CBIT](INFO): Critical error at 20251215T215007 2025-12-15T21:50:07.323Z,1765835407.323 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-12-15T21:50:07.323Z,1765835407.323 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-12-15T21:50:07.684Z,1765835407.684 [CBIT](INFO): Critical error at 20251215T215007 2025-12-15T21:53:43.464Z,1765835623.464 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-12-15T21:53:43.464Z,1765835623.464 [Default:CheckIn:C.Wait] Stopped 2025-12-15T21:53:43.464Z,1765835623.464 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-12-15T21:53:43.465Z,1765835623.465 [Default:CheckIn:D] Running Loop=1 2025-12-15T21:53:43.849Z,1765835623.849 [Default:CheckIn:D] Stopped 2025-12-15T21:53:43.849Z,1765835623.849 [Default:CheckIn:E] Running Loop=1 2025-12-15T21:53:44.258Z,1765835624.258 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 116.116325 min 2025-12-15T21:53:44.258Z,1765835624.258 [Default:CheckIn:E] Stopped 2025-12-15T21:53:44.258Z,1765835624.258 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-12-15T21:53:44.258Z,1765835624.258 [Default:CheckIn] Stopped 2025-12-15T21:53:44.258Z,1765835624.258 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-12-15T21:53:44.258Z,1765835624.258 [Default:CheckIn](INFO): Running loop #21 2025-12-15T21:53:44.258Z,1765835624.258 [Default:CheckIn] Running Loop=21 2025-12-15T21:53:44.258Z,1765835624.258 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-12-15T21:53:44.259Z,1765835624.259 [Default:CheckIn:Read_GPS] Running Loop=1 2025-12-15T21:53:46.271Z,1765835626.271 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215344.00,A,3648.17287,N,12147.28160,W,0.117,203.06,151225,,,D*72 2025-12-15T21:53:46.273Z,1765835626.273 [NAL9602](INFO): GPS fix at 20251215T215344: (36.802881, -121.788027) 2025-12-15T21:53:46.283Z,1765835626.283 [Default:CheckIn:Read_GPS] Stopped 2025-12-15T21:53:46.284Z,1765835626.284 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-12-15T21:53:53.592Z,1765835633.592 [DataOverHttps](INFO): Sending 194 bytes from file Logs/20251215T203130/Courier0048.lzma 2025-12-15T21:53:54.594Z,1765835634.594 [DataOverHttps](INFO): Moved sent file to Logs/20251215T203130/Courier0048.lzma.bak 2025-12-15T21:53:54.594Z,1765835634.594 [DataOverHttps](INFO): SBD MOMSN=26500059 2025-12-15T21:54:10.464Z,1765835650.464 [DataOverHttps](INFO): Sending 314 bytes from file Logs/20251215T203130/Express0049.lzma 2025-12-15T21:54:11.466Z,1765835651.466 [DataOverHttps](INFO): Moved sent file to Logs/20251215T203130/Express0049.lzma.bak 2025-12-15T21:54:11.466Z,1765835651.466 [DataOverHttps](INFO): SBD MOMSN=26500064 2025-12-15T21:54:12.947Z,1765835652.947 [Default:CheckIn:Read_Iridium] Stopped 2025-12-15T21:54:12.948Z,1765835652.948 [Default:CheckIn:C.Wait] Running Loop=1 2025-12-15T21:54:12.948Z,1765835652.948 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-12-15T21:54:50.908Z,1765835690.908 [NAL9602](INFO): SBD MO Status=2, MOMSN=24501, MT Status=2, MTMSN=0 2025-12-15T21:54:50.909Z,1765835690.909 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-12-15T21:55:06.666Z,1765835706.666 [NAL9602](INFO): SBD MO Status=2, MOMSN=24501, MT Status=2, MTMSN=0 2025-12-15T21:55:06.666Z,1765835706.666 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-12-15T21:55:16.762Z,1765835716.762 [NAL9602](INFO): SBD MO Status=0, MOMSN=24501, MT Status=0, MTMSN=0 2025-12-15T21:55:16.762Z,1765835716.762 [NAL9602](INFO): No messages in MT queue 2025-12-15T21:55:47.465Z,1765835747.465 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-15T21:59:13.543Z,1765835953.543 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-12-15T21:59:13.543Z,1765835953.543 [Default:CheckIn:C.Wait] Stopped 2025-12-15T21:59:13.543Z,1765835953.543 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-12-15T21:59:13.543Z,1765835953.543 [Default:CheckIn:D] Running Loop=1 2025-12-15T21:59:13.957Z,1765835953.957 [Default:CheckIn:D] Stopped 2025-12-15T21:59:13.957Z,1765835953.957 [Default:CheckIn:E] Running Loop=1 2025-12-15T21:59:14.346Z,1765835954.346 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 121.618123 min 2025-12-15T21:59:14.346Z,1765835954.346 [Default:CheckIn:E] Stopped 2025-12-15T21:59:14.346Z,1765835954.346 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-12-15T21:59:14.346Z,1765835954.346 [Default:CheckIn] Stopped 2025-12-15T21:59:14.346Z,1765835954.346 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-12-15T21:59:14.346Z,1765835954.346 [Default:CheckIn](INFO): Running loop #22 2025-12-15T21:59:14.347Z,1765835954.347 [Default:CheckIn] Running Loop=22 2025-12-15T21:59:14.347Z,1765835954.347 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-12-15T21:59:14.347Z,1765835954.347 [Default:CheckIn:Read_GPS] Running Loop=1 2025-12-15T21:59:16.364Z,1765835956.364 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215914.00,A,3648.17318,N,12147.28059,W,1.244,189.03,151225,,,D*71 2025-12-15T21:59:16.366Z,1765835956.366 [NAL9602](INFO): GPS fix at 20251215T215914: (36.802886, -121.788010) 2025-12-15T21:59:16.377Z,1765835956.377 [Default:CheckIn:Read_GPS] Stopped 2025-12-15T21:59:16.377Z,1765835956.377 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-12-15T21:59:26.487Z,1765835966.487 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20251215T203130/Courier0051.lzma 2025-12-15T21:59:27.494Z,1765835967.494 [DataOverHttps](INFO): Moved sent file to Logs/20251215T203130/Courier0051.lzma.bak 2025-12-15T21:59:27.494Z,1765835967.494 [DataOverHttps](INFO): SBD MOMSN=26500125 2025-12-15T21:59:48.052Z,1765835988.052 [DataOverHttps](INFO): Sending 171 bytes from file Logs/20251215T203130/Express0052.lzma 2025-12-15T21:59:49.055Z,1765835989.055 [DataOverHttps](INFO): Moved sent file to Logs/20251215T203130/Express0052.lzma.bak 2025-12-15T21:59:49.055Z,1765835989.055 [DataOverHttps](INFO): SBD MOMSN=26500136 2025-12-15T21:59:50.403Z,1765835990.403 [Default:CheckIn:Read_Iridium] Stopped 2025-12-15T21:59:50.403Z,1765835990.403 [Default:CheckIn:C.Wait] Running Loop=1 2025-12-15T21:59:50.404Z,1765835990.404 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-12-15T22:04:18.632Z,1765836258.632 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-12-15T22:04:49.334Z,1765836289.334 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-15T22:04:50.964Z,1765836290.964 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-12-15T22:04:50.965Z,1765836290.965 [Default:CheckIn:C.Wait] Stopped 2025-12-15T22:04:50.965Z,1765836290.965 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-12-15T22:04:50.965Z,1765836290.965 [Default:CheckIn:D] Running Loop=1 2025-12-15T22:04:51.400Z,1765836291.400 [Default:CheckIn:D] Stopped 2025-12-15T22:04:51.400Z,1765836291.400 [Default:CheckIn:E] Running Loop=1 2025-12-15T22:04:51.792Z,1765836291.792 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 127.242171 min 2025-12-15T22:04:51.792Z,1765836291.792 [Default:CheckIn:E] Stopped 2025-12-15T22:04:51.792Z,1765836291.792 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-12-15T22:04:51.792Z,1765836291.792 [Default:CheckIn] Stopped 2025-12-15T22:04:51.792Z,1765836291.792 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-12-15T22:04:51.793Z,1765836291.793 [Default:CheckIn](INFO): Running loop #23 2025-12-15T22:04:51.793Z,1765836291.793 [Default:CheckIn] Running Loop=23 2025-12-15T22:04:51.793Z,1765836291.793 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-12-15T22:04:51.793Z,1765836291.793 [Default:CheckIn:Read_GPS] Running Loop=1 2025-12-15T22:04:53.798Z,1765836293.798 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220451.00,A,3648.17176,N,12147.28130,W,0.117,217.55,151225,,,A*79 2025-12-15T22:04:53.801Z,1765836293.801 [NAL9602](INFO): GPS fix at 20251215T220451: (36.802863, -121.788022) 2025-12-15T22:04:53.812Z,1765836293.812 [Default:CheckIn:Read_GPS] Stopped 2025-12-15T22:04:53.812Z,1765836293.812 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-12-15T22:05:01.448Z,1765836301.448 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20251215T203130/Courier0054.lzma 2025-12-15T22:05:02.450Z,1765836302.450 [DataOverHttps](INFO): Moved sent file to Logs/20251215T203130/Courier0054.lzma.bak 2025-12-15T22:05:02.450Z,1765836302.450 [DataOverHttps](INFO): SBD MOMSN=26500172 2025-12-15T22:05:07.600Z,1765836307.600 [CBIT](INFO): Clearing failed state for component DropWeight 2025-12-15T22:05:07.601Z,1765836307.601 [DropWeight] No Fault, FailCount= 1 2025-12-15T22:05:18.436Z,1765836318.436 [DataOverHttps](INFO): Sending 275 bytes from file Logs/20251215T203130/Express0055.lzma 2025-12-15T22:05:19.438Z,1765836319.438 [DataOverHttps](INFO): Moved sent file to Logs/20251215T203130/Express0055.lzma.bak 2025-12-15T22:05:19.438Z,1765836319.438 [DataOverHttps](INFO): SBD MOMSN=26500175 2025-12-15T22:05:22.119Z,1765836322.119 [Default:CheckIn:Read_Iridium] Stopped 2025-12-15T22:05:22.119Z,1765836322.119 [Default:CheckIn:C.Wait] Running Loop=1 2025-12-15T22:05:22.119Z,1765836322.119 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-12-15T22:05:24.560Z,1765836324.560 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-12-15T22:05:24.644Z,1765836324.644 [NAL9602](ERROR): received: +CSQ:0 OK501, 0, 0, 0, 0 OK 2025-12-15T22:09:56.426Z,1765836596.426 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-12-15T22:10:22.690Z,1765836622.690 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-12-15T22:10:22.690Z,1765836622.690 [Default:CheckIn:C.Wait] Stopped 2025-12-15T22:10:22.690Z,1765836622.690 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-12-15T22:10:22.690Z,1765836622.690 [Default:CheckIn:D] Running Loop=1 2025-12-15T22:10:23.093Z,1765836623.093 [Default:CheckIn:D] Stopped 2025-12-15T22:10:23.094Z,1765836623.094 [Default:CheckIn:E] Running Loop=1 2025-12-15T22:10:23.569Z,1765836623.569 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 132.770402 min 2025-12-15T22:10:23.569Z,1765836623.569 [Default:CheckIn:E] Stopped 2025-12-15T22:10:23.569Z,1765836623.569 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-12-15T22:10:23.569Z,1765836623.569 [Default:CheckIn] Stopped 2025-12-15T22:10:23.569Z,1765836623.569 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-12-15T22:10:23.569Z,1765836623.569 [Default:CheckIn](INFO): Running loop #24 2025-12-15T22:10:23.569Z,1765836623.569 [Default:CheckIn] Running Loop=24 2025-12-15T22:10:23.570Z,1765836623.570 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-12-15T22:10:23.570Z,1765836623.570 [Default:CheckIn:Read_GPS] Running Loop=1 2025-12-15T22:10:25.510Z,1765836625.510 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221023.00,A,3648.17050,N,12147.28490,W,0.972,8.98,151225,,,A*75 2025-12-15T22:10:25.522Z,1765836625.522 [NAL9602](INFO): GPS fix at 20251215T221023: (36.802842, -121.788082) 2025-12-15T22:10:25.533Z,1765836625.533 [Default:CheckIn:Read_GPS] Stopped 2025-12-15T22:10:25.533Z,1765836625.533 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-12-15T22:10:32.851Z,1765836632.851 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20251215T203130/Courier0057.lzma 2025-12-15T22:10:33.854Z,1765836633.854 [DataOverHttps](INFO): Moved sent file to Logs/20251215T203130/Courier0057.lzma.bak 2025-12-15T22:10:33.854Z,1765836633.854 [DataOverHttps](INFO): SBD MOMSN=26500179 2025-12-15T22:10:49.748Z,1765836649.748 [DataOverHttps](INFO): Sending 251 bytes from file Logs/20251215T203130/Express0058.lzma 2025-12-15T22:10:51.544Z,1765836651.544 [DataOverHttps](INFO): Moved sent file to Logs/20251215T203130/Express0058.lzma.bak 2025-12-15T22:10:51.544Z,1765836651.544 [DataOverHttps](INFO): SBD MOMSN=26500182 2025-12-15T22:10:52.192Z,1765836652.192 [Default:CheckIn:Read_Iridium] Stopped 2025-12-15T22:10:52.192Z,1765836652.192 [Default:CheckIn:C.Wait] Running Loop=1 2025-12-15T22:10:52.192Z,1765836652.192 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-12-15T22:10:57.838Z,1765836657.838 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-15T22:11:02.682Z,1765836662.682 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-12-15T22:13:33.945Z,1765836813.945 [CommandExec](IMPORTANT): got command quit 2025-12-15T22:13:34.948Z,1765836814.948 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2025-12-15T22:13:34.948Z,1765836814.948 [CommandExec](INFO): Uninitializing the command executive. 2025-12-15T22:13:34.948Z,1765836814.948 [CommandExec](INFO): Uninitializing the command scheduler. 2025-12-15T22:13:34.949Z,1765836814.949 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-12-15T22:13:34.978Z,1765836814.978 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2025-12-15T22:13:34.979Z,1765836814.979 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2025-12-15T22:13:34.979Z,1765836814.979 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-12-15T22:13:34.979Z,1765836814.979 [NavChartDb](INFO): Join timeout helper Thread ID is 784 2025-12-15T22:13:35.137Z,1765836815.137 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2025-12-15T22:13:35.137Z,1765836815.137 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-12-15T22:13:35.140Z,1765836815.140 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2025-12-15T22:13:35.140Z,1765836815.140 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-12-15T22:13:35.140Z,1765836815.140 [Radio_Surface](INFO): Join timeout helper Thread ID is 785 2025-12-15T22:13:35.524Z,1765836815.524 [Radio_Surface](INFO): Powering down 2025-12-15T22:13:35.525Z,1765836815.525 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2025-12-15T22:13:35.525Z,1765836815.525 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-12-15T22:13:35.545Z,1765836815.545 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2025-12-15T22:13:35.546Z,1765836815.546 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-12-15T22:13:35.546Z,1765836815.546 [Onboard](INFO): Join timeout helper Thread ID is 786 2025-12-15T22:13:36.472Z,1765836816.472 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-12-15T22:13:39.932Z,1765836819.932 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2025-12-15T22:13:39.932Z,1765836819.932 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-12-15T22:13:39.940Z,1765836819.940 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2025-12-15T22:13:39.940Z,1765836819.940 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-12-15T22:13:39.940Z,1765836819.940 [DataOverHttps](INFO): Join timeout helper Thread ID is 787 2025-12-15T22:13:40.488Z,1765836820.488 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2025-12-15T22:13:40.488Z,1765836820.488 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-12-15T22:13:40.508Z,1765836820.508 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2025-12-15T22:13:40.508Z,1765836820.508 [DAT ThreadHandler](INFO): Thread cancelled. 2025-12-15T22:13:40.508Z,1765836820.508 [DAT](INFO): Join timeout helper Thread ID is 788 2025-12-15T22:13:40.712Z,1765836820.712 [DAT](INFO): Powering down 2025-12-15T22:13:40.781Z,1765836820.781 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2025-12-15T22:13:40.781Z,1765836820.781 [DAT](INFO): Powering down 2025-12-15T22:13:40.782Z,1765836820.782 [DAT ThreadHandler](INFO): Thread cancelled. 2025-12-15T22:13:40.801Z,1765836820.801 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2025-12-15T22:13:40.801Z,1765836820.801 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-12-15T22:13:40.801Z,1765836820.801 [BackseatComponent](INFO): Join timeout helper Thread ID is 789 2025-12-15T22:13:40.956Z,1765836820.956 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2025-12-15T22:13:40.956Z,1765836820.956 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-12-15T22:13:40.961Z,1765836820.961 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2025-12-15T22:13:40.961Z,1765836820.961 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-12-15T22:13:40.961Z,1765836820.961 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 791 2025-12-15T22:13:41.184Z,1765836821.184 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2025-12-15T22:13:41.184Z,1765836821.184 [WetLabsBB2FL](INFO): Powering down 2025-12-15T22:13:41.185Z,1765836821.185 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-12-15T22:13:41.193Z,1765836821.193 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2025-12-15T22:13:41.193Z,1765836821.193 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-12-15T22:13:41.193Z,1765836821.193 [CTD_Seabird](INFO): Join timeout helper Thread ID is 792 2025-12-15T22:13:41.268Z,1765836821.268 [CTD_Seabird](INFO): Powering down 2025-12-15T22:13:41.280Z,1765836821.280 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2025-12-15T22:13:41.280Z,1765836821.280 [CTD_Seabird](INFO): Powering down 2025-12-15T22:13:41.292Z,1765836821.292 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-12-15T22:13:41.293Z,1765836821.293 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2025-12-15T22:13:41.293Z,1765836821.293 [logger ThreadHandler](INFO): Thread cancelled. 2025-12-15T22:13:41.294Z,1765836821.294 [logger](INFO): Join timeout helper Thread ID is 795 2025-12-15T22:13:41.308Z,1765836821.308 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2025-12-15T22:13:41.308Z,1765836821.308 [logger ThreadHandler](INFO): Thread cancelled. 2025-12-15T22:13:41.313Z,1765836821.313 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2025-12-15T22:13:41.313Z,1765836821.313 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-12-15T22:13:41.313Z,1765836821.313 [CommandLine](INFO): Join timeout helper Thread ID is 796 2025-12-15T22:13:41.384Z,1765836821.384 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2025-12-15T22:13:41.384Z,1765836821.384 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-12-15T22:13:41.394Z,1765836821.394 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2025-12-15T22:13:41.395Z,1765836821.395 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-12-15T22:13:41.395Z,1765836821.395 [CommandExec](INFO): Join timeout helper Thread ID is 797 2025-12-15T22:13:41.397Z,1765836821.397 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2025-12-15T22:13:41.397Z,1765836821.397 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-12-15T22:13:41.397Z,1765836821.397 [controlThread](INFO): Join timeout helper Thread ID is 798 2025-12-15T22:13:41.428Z,1765836821.428 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2025-12-15T22:13:41.428Z,1765836821.428 [controlThread](DEBUG): Uninitializing ControlThread 2025-12-15T22:13:41.429Z,1765836821.429 [AHRS_M2](INFO): Powering down 2025-12-15T22:13:41.508Z,1765836821.508 [NAL9602](INFO): Powering down 2025-12-15T22:13:41.510Z,1765836821.510 [Sonardyne_Nano](INFO): Powering down 2025-12-15T22:13:41.716Z,1765836821.716 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2025-12-15T22:13:41.717Z,1765836821.717 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2025-12-15T22:13:41.717Z,1765836821.717 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2025-12-15T22:13:41.718Z,1765836821.718 [MissionManager](INFO): Uninitializing Mission Default 2025-12-15T22:13:41.718Z,1765836821.718 [Default] Stopped 2025-12-15T22:13:41.718Z,1765836821.718 [Default](DEBUG): Aggregate::uninitialize Default 2025-12-15T22:13:41.718Z,1765836821.718 [Default:B.GoToSurface] Stopped 2025-12-15T22:13:41.718Z,1765836821.718 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-12-15T22:13:41.718Z,1765836821.718 [Default:CheckIn] Stopped 2025-12-15T22:13:41.718Z,1765836821.718 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-12-15T22:13:41.718Z,1765836821.718 [Default:CheckIn:C.Wait] Stopped 2025-12-15T22:13:41.718Z,1765836821.718 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-12-15T22:13:41.721Z,1765836821.721 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2025-12-15T22:13:41.722Z,1765836821.722 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2025-12-15T22:13:41.722Z,1765836821.722 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2025-12-15T22:13:41.722Z,1765836821.722 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2025-12-15T22:13:41.723Z,1765836821.723 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2025-12-15T22:13:41.723Z,1765836821.723 [BuoyancyServo](INFO): Powering down 2025-12-15T22:13:41.736Z,1765836821.736 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2025-12-15T22:13:41.736Z,1765836821.736 [ElevatorServo](INFO): Powering down 2025-12-15T22:13:41.737Z,1765836821.737 [MassServo](DEBUG): Uninitialize Mass Servo. 2025-12-15T22:13:41.737Z,1765836821.737 [MassServo](INFO): Powering down 2025-12-15T22:13:41.738Z,1765836821.738 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2025-12-15T22:13:41.738Z,1765836821.738 [RudderServo](INFO): Powering down 2025-12-15T22:13:41.739Z,1765836821.739 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2025-12-15T22:13:41.739Z,1765836821.739 [ThrusterHE](INFO): Powering down 2025-12-15T22:13:41.740Z,1765836821.740 [SBIT](DEBUG): Uninitialize SBIT Component. 2025-12-15T22:13:41.740Z,1765836821.740 [IBIT](DEBUG): Uninitialize IBIT Component. 2025-12-15T22:13:41.740Z,1765836821.740 [CBIT](DEBUG): Uninitialize CBIT Component. 2025-12-15T22:13:41.740Z,1765836821.740 [CBIT](DEBUG): Powering off loads. 2025-12-15T22:13:41.752Z,1765836821.752 [CBIT](DEBUG): Disabling WDT. 2025-12-15T22:13:41.764Z,1765836821.764 [GFScanner](DEBUG): Uninitialize GFScanner component. 2025-12-15T22:13:41.764Z,1765836821.764 [GFScanner](DEBUG): Opening all GF detection circuits. 2025-12-15T22:13:41.765Z,1765836821.765 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-12-15T22:13:41.804Z,1765836821.804 [GFScanner](DEBUG): Uninitialize GFScanner component. 2025-12-15T22:13:41.805Z,1765836821.805 [GFScanner](DEBUG): Opening all GF detection circuits. 2025-12-15T22:13:41.820Z,1765836821.820 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-12-15T22:13:41.827Z,1765836821.827 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-12-15T22:13:41.860Z,1765836821.860 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-12-15T22:13:41.863Z,1765836821.863 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-12-15T22:13:41.870Z,1765836821.870 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-12-15T22:13:41.876Z,1765836821.876 [DAT ThreadHandler](INFO): Thread cancelled. 2025-12-15T22:13:41.921Z,1765836821.921 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-12-15T22:13:41.978Z,1765836821.978 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-12-15T22:13:42.078Z,1765836822.078 [logger ThreadHandler](INFO): Thread cancelled.