2023-01-19T17:11:34.792Z,1674148294.792 [CommandExec](IMPORTANT): got command restart logs 2023-01-19T17:11:36.419Z,1674148296.419 [NAL9602](INFO): Not Powering down - fast GPS 2023-01-19T17:14:31.142Z,1674148471.142 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-01-19T17:14:55.604Z,1674148495.604 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-01-19T17:14:55.604Z,1674148495.604 [Default:CheckIn:C.Wait] Stopped 2023-01-19T17:14:55.604Z,1674148495.604 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-01-19T17:14:55.605Z,1674148495.605 [Default:CheckIn:D] Running Loop=1 2023-01-19T17:14:56.003Z,1674148496.003 [Default:CheckIn:D] Stopped 2023-01-19T17:14:56.004Z,1674148496.004 [Default:CheckIn:E] Running Loop=1 2023-01-19T17:14:56.419Z,1674148496.419 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 58.821318 min 2023-01-19T17:14:56.419Z,1674148496.419 [Default:CheckIn:E] Stopped 2023-01-19T17:14:56.419Z,1674148496.419 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-01-19T17:14:56.419Z,1674148496.419 [Default:CheckIn] Stopped 2023-01-19T17:14:56.419Z,1674148496.419 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-01-19T17:14:56.420Z,1674148496.420 [Default:CheckIn](INFO): Running loop #6 2023-01-19T17:14:56.420Z,1674148496.420 [Default:CheckIn] Running Loop=6 2023-01-19T17:14:56.420Z,1674148496.420 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-01-19T17:14:56.420Z,1674148496.420 [Default:CheckIn:Read_GPS] Running Loop=1 2023-01-19T17:14:58.444Z,1674148498.444 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171457.00,A,3647.88110,N,12149.75955,W,19.730,50.89,190123,,,D*7A 2023-01-19T17:14:58.446Z,1674148498.446 [NAL9602](INFO): GPS fix at 20230119T171457: (36.798018, -121.829326) 2023-01-19T17:14:58.457Z,1674148498.457 [Default:CheckIn:Read_GPS] Stopped 2023-01-19T17:14:58.457Z,1674148498.457 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-01-19T17:15:02.266Z,1674148502.266 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-01-19T17:15:13.637Z,1674148513.637 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230118T195433/Courier0066.lzma 2023-01-19T17:15:14.182Z,1674148514.182 [NAL9602](INFO): SBD MO Status=0, MOMSN=38876, MT Status=0, MTMSN=0 2023-01-19T17:15:14.183Z,1674148514.183 [NAL9602](INFO): No messages in MT queue 2023-01-19T17:15:14.640Z,1674148514.640 [DataOverHttps](INFO): Moved sent file to Logs/20230118T195433/Courier0066.lzma.bak 2023-01-19T17:15:14.640Z,1674148514.640 [DataOverHttps](INFO): SBD MOMSN=17402835 2023-01-19T17:15:44.919Z,1674148544.919 [NAL9602](INFO): Not Powering down - fast GPS 2023-01-19T17:16:34.326Z,1674148594.326 [DataOverHttps](INFO): Sending 270 bytes from file Logs/20230119T171134/Courier0000.lzma 2023-01-19T17:16:35.328Z,1674148595.328 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Courier0000.lzma.bak 2023-01-19T17:16:35.328Z,1674148595.328 [DataOverHttps](INFO): SBD MOMSN=17402837 2023-01-19T17:17:06.030Z,1674148626.030 [DataOverHttps](INFO): Sending 158 bytes from file Logs/20230118T195433/Express0067.lzma 2023-01-19T17:17:07.032Z,1674148627.032 [DataOverHttps](INFO): Moved sent file to Logs/20230118T195433/Express0067.lzma.bak 2023-01-19T17:17:07.032Z,1674148627.032 [DataOverHttps](INFO): SBD MOMSN=17402841 2023-01-19T17:17:56.202Z,1674148676.202 [DataOverHttps](INFO): Sending 450 bytes from file Logs/20230119T171134/Express0001.lzma 2023-01-19T17:17:57.204Z,1674148677.204 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Express0001.lzma.bak 2023-01-19T17:17:57.204Z,1674148677.204 [DataOverHttps](INFO): SBD MOMSN=17402845 2023-01-19T17:18:12.905Z,1674148692.905 [DataOverHttps](INFO): Sending 52 bytes from file Logs/20230119T171134/Express0004.lzma 2023-01-19T17:18:13.908Z,1674148693.908 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Express0004.lzma.bak 2023-01-19T17:18:13.908Z,1674148693.908 [DataOverHttps](INFO): SBD MOMSN=17402849 2023-01-19T17:18:15.230Z,1674148695.230 [Default:CheckIn:Read_Iridium] Stopped 2023-01-19T17:18:15.230Z,1674148695.230 [Default:CheckIn:C.Wait] Running Loop=1 2023-01-19T17:18:15.230Z,1674148695.230 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-01-19T17:23:15.997Z,1674148995.997 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-01-19T17:23:15.997Z,1674148995.997 [Default:CheckIn:C.Wait] Stopped 2023-01-19T17:23:15.997Z,1674148995.997 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-01-19T17:23:15.998Z,1674148995.998 [Default:CheckIn:D] Running Loop=1 2023-01-19T17:23:16.407Z,1674148996.407 [Default:CheckIn:D] Stopped 2023-01-19T17:23:16.407Z,1674148996.407 [Default:CheckIn:E] Running Loop=1 2023-01-19T17:23:16.801Z,1674148996.801 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 67.161377 min 2023-01-19T17:23:16.801Z,1674148996.801 [Default:CheckIn:E] Stopped 2023-01-19T17:23:16.801Z,1674148996.801 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-01-19T17:23:16.801Z,1674148996.801 [Default:CheckIn] Stopped 2023-01-19T17:23:16.801Z,1674148996.801 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-01-19T17:23:16.801Z,1674148996.801 [Default:CheckIn](INFO): Running loop #7 2023-01-19T17:23:16.801Z,1674148996.801 [Default:CheckIn] Running Loop=7 2023-01-19T17:23:16.801Z,1674148996.801 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-01-19T17:23:16.801Z,1674148996.801 [Default:CheckIn:Read_GPS] Running Loop=1 2023-01-19T17:23:18.835Z,1674148998.835 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172318.00,A,3647.64078,N,12149.74431,W,4.704,240.23,190123,,,D*7E 2023-01-19T17:23:18.853Z,1674148998.853 [NAL9602](INFO): GPS fix at 20230119T172318: (36.794013, -121.829072) 2023-01-19T17:23:18.863Z,1674148998.863 [Default:CheckIn:Read_GPS] Stopped 2023-01-19T17:23:18.863Z,1674148998.863 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-01-19T17:23:26.201Z,1674149006.201 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230119T171134/Courier0006.lzma 2023-01-19T17:23:27.204Z,1674149007.204 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Courier0006.lzma.bak 2023-01-19T17:23:27.204Z,1674149007.204 [DataOverHttps](INFO): SBD MOMSN=17402852 2023-01-19T17:23:41.840Z,1674149021.840 [NAL9602](INFO): SBD MO Status=0, MOMSN=38877, MT Status=0, MTMSN=0 2023-01-19T17:23:41.840Z,1674149021.840 [NAL9602](INFO): No messages in MT queue 2023-01-19T17:23:43.041Z,1674149023.041 [DataOverHttps](INFO): Sending 165 bytes from file Logs/20230119T171134/Express0007.lzma 2023-01-19T17:23:44.044Z,1674149024.044 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Express0007.lzma.bak 2023-01-19T17:23:44.044Z,1674149024.044 [DataOverHttps](INFO): SBD MOMSN=17402855 2023-01-19T17:23:45.495Z,1674149025.495 [Default:CheckIn:Read_Iridium] Stopped 2023-01-19T17:23:45.495Z,1674149025.495 [Default:CheckIn:C.Wait] Running Loop=1 2023-01-19T17:23:45.495Z,1674149025.495 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-01-19T17:24:12.549Z,1674149052.549 [NAL9602](INFO): Not Powering down - fast GPS 2023-01-19T17:27:17.602Z,1674149237.602 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-01-19T17:28:46.161Z,1674149326.161 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-01-19T17:28:46.161Z,1674149326.161 [Default:CheckIn:C.Wait] Stopped 2023-01-19T17:28:46.161Z,1674149326.161 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-01-19T17:28:46.161Z,1674149326.161 [Default:CheckIn:D] Running Loop=1 2023-01-19T17:28:46.564Z,1674149326.564 [Default:CheckIn:D] Stopped 2023-01-19T17:28:46.564Z,1674149326.564 [Default:CheckIn:E] Running Loop=1 2023-01-19T17:28:46.969Z,1674149326.969 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 72.663989 min 2023-01-19T17:28:46.970Z,1674149326.970 [Default:CheckIn:E] Stopped 2023-01-19T17:28:46.970Z,1674149326.970 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-01-19T17:28:46.970Z,1674149326.970 [Default:CheckIn] Stopped 2023-01-19T17:28:46.970Z,1674149326.970 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-01-19T17:28:46.970Z,1674149326.970 [Default:CheckIn](INFO): Running loop #8 2023-01-19T17:28:46.970Z,1674149326.970 [Default:CheckIn] Running Loop=8 2023-01-19T17:28:46.970Z,1674149326.970 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-01-19T17:28:46.971Z,1674149326.971 [Default:CheckIn:Read_GPS] Running Loop=1 2023-01-19T17:28:48.979Z,1674149328.979 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172848.00,A,3647.55318,N,12149.85965,W,1.458,193.62,190123,,,D*72 2023-01-19T17:28:48.982Z,1674149328.982 [NAL9602](INFO): GPS fix at 20230119T172848: (36.792553, -121.830994) 2023-01-19T17:28:49.008Z,1674149329.008 [Default:CheckIn:Read_GPS] Stopped 2023-01-19T17:28:49.009Z,1674149329.009 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-01-19T17:29:02.637Z,1674149342.637 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230119T171134/Courier0009.lzma 2023-01-19T17:29:03.640Z,1674149343.640 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Courier0009.lzma.bak 2023-01-19T17:29:03.640Z,1674149343.640 [DataOverHttps](INFO): SBD MOMSN=17402859 2023-01-19T17:29:24.193Z,1674149364.193 [NAL9602](INFO): SBD MO Status=2, MOMSN=38878, MT Status=2, MTMSN=0 2023-01-19T17:29:24.193Z,1674149364.193 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-01-19T17:29:36.634Z,1674149376.634 [DataOverHttps](INFO): Sending 164 bytes from file Logs/20230119T171134/Express0010.lzma 2023-01-19T17:29:37.636Z,1674149377.636 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Express0010.lzma.bak 2023-01-19T17:29:37.636Z,1674149377.636 [DataOverHttps](INFO): SBD MOMSN=17402863 2023-01-19T17:29:38.745Z,1674149378.745 [Default:CheckIn:Read_Iridium] Stopped 2023-01-19T17:29:38.745Z,1674149378.745 [Default:CheckIn:C.Wait] Running Loop=1 2023-01-19T17:29:38.745Z,1674149378.745 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-01-19T17:29:51.269Z,1674149391.269 [NAL9602](INFO): SBD MO Status=2, MOMSN=38878, MT Status=2, MTMSN=0 2023-01-19T17:29:51.269Z,1674149391.269 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-01-19T17:30:02.975Z,1674149402.975 [NAL9602](INFO): SBD MO Status=0, MOMSN=38878, MT Status=0, MTMSN=0 2023-01-19T17:30:02.975Z,1674149402.975 [NAL9602](INFO): No messages in MT queue 2023-01-19T17:30:33.682Z,1674149433.682 [NAL9602](INFO): Not Powering down - fast GPS 2023-01-19T17:32:38.966Z,1674149558.966 [CBIT](IMPORTANT): Beginning ground fault scan 2023-01-19T17:32:41.777Z,1674149561.777 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes. 2023-01-19T17:32:49.855Z,1674149569.855 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.002079 CHAN A1 (24V) N/A reporting off CHAN A2 (12V): -0.002201 CHAN A3 (5V): -0.001621 CHAN B0 (3.3V): -0.000325 CHAN B1 (3.15aV): -0.000081 CHAN B2 (3.15bV): -0.000225 CHAN B3 (GND): -0.000160 OPEN: -0.000297 Full Scale: +/- 1 mA 2023-01-19T17:34:14.082Z,1674149654.082 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2023-01-19T17:34:39.320Z,1674149679.320 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-01-19T17:34:39.320Z,1674149679.320 [Default:CheckIn:C.Wait] Stopped 2023-01-19T17:34:39.320Z,1674149679.320 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-01-19T17:34:39.320Z,1674149679.320 [Default:CheckIn:D] Running Loop=1 2023-01-19T17:34:39.724Z,1674149679.724 [Default:CheckIn:D] Stopped 2023-01-19T17:34:39.724Z,1674149679.724 [Default:CheckIn:E] Running Loop=1 2023-01-19T17:34:40.129Z,1674149680.129 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 78.549992 min 2023-01-19T17:34:40.129Z,1674149680.129 [Default:CheckIn:E] Stopped 2023-01-19T17:34:40.129Z,1674149680.129 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-01-19T17:34:40.129Z,1674149680.129 [Default:CheckIn] Stopped 2023-01-19T17:34:40.129Z,1674149680.129 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-01-19T17:34:40.129Z,1674149680.129 [Default:CheckIn](INFO): Running loop #9 2023-01-19T17:34:40.129Z,1674149680.129 [Default:CheckIn] Running Loop=9 2023-01-19T17:34:40.129Z,1674149680.129 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-01-19T17:34:40.129Z,1674149680.129 [Default:CheckIn:Read_GPS] Running Loop=1 2023-01-19T17:34:42.141Z,1674149682.141 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173441.00,A,3647.47745,N,12149.90051,W,0.952,227.57,190123,,,D*7F 2023-01-19T17:34:42.148Z,1674149682.148 [NAL9602](INFO): GPS fix at 20230119T173441: (36.791291, -121.831675) 2023-01-19T17:34:42.182Z,1674149682.182 [Default:CheckIn:Read_GPS] Stopped 2023-01-19T17:34:42.182Z,1674149682.182 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-01-19T17:34:45.206Z,1674149685.206 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-01-19T17:35:01.205Z,1674149701.205 [NAL9602](INFO): SBD MO Status=2, MOMSN=38879, MT Status=2, MTMSN=0 2023-01-19T17:35:01.205Z,1674149701.205 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-01-19T17:35:02.814Z,1674149702.814 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230119T171134/Courier0012.lzma 2023-01-19T17:35:03.816Z,1674149703.816 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Courier0012.lzma.bak 2023-01-19T17:35:03.816Z,1674149703.816 [DataOverHttps](INFO): SBD MOMSN=17402867 2023-01-19T17:35:19.787Z,1674149719.787 [DataOverHttps](INFO): Sending 377 bytes from file Logs/20230119T171134/Express0013.lzma 2023-01-19T17:35:20.788Z,1674149720.788 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Express0013.lzma.bak 2023-01-19T17:35:20.788Z,1674149720.788 [DataOverHttps](INFO): SBD MOMSN=17402870 2023-01-19T17:35:22.223Z,1674149722.223 [Default:CheckIn:Read_Iridium] Stopped 2023-01-19T17:35:22.223Z,1674149722.223 [Default:CheckIn:C.Wait] Running Loop=1 2023-01-19T17:35:22.223Z,1674149722.223 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-01-19T17:35:44.425Z,1674149744.425 [NAL9602](INFO): SBD MO Status=2, MOMSN=38879, MT Status=2, MTMSN=0 2023-01-19T17:35:44.425Z,1674149744.425 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-01-19T17:35:54.121Z,1674149754.121 [NAL9602](INFO): SBD MO Status=0, MOMSN=38879, MT Status=0, MTMSN=0 2023-01-19T17:35:54.121Z,1674149754.121 [NAL9602](INFO): No messages in MT queue 2023-01-19T17:36:24.825Z,1674149784.825 [NAL9602](INFO): Not Powering down - fast GPS 2023-01-19T17:38:24.530Z,1674149904.530 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-01-19T17:39:56.594Z,1674149996.594 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-01-19T17:40:22.815Z,1674150022.815 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-01-19T17:40:22.815Z,1674150022.815 [Default:CheckIn:C.Wait] Stopped 2023-01-19T17:40:22.815Z,1674150022.815 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-01-19T17:40:22.816Z,1674150022.816 [Default:CheckIn:D] Running Loop=1 2023-01-19T17:40:23.212Z,1674150023.212 [Default:CheckIn:D] Stopped 2023-01-19T17:40:23.212Z,1674150023.212 [Default:CheckIn:E] Running Loop=1 2023-01-19T17:40:23.610Z,1674150023.610 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 84.274797 min 2023-01-19T17:40:23.610Z,1674150023.610 [Default:CheckIn:E] Stopped 2023-01-19T17:40:23.611Z,1674150023.611 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-01-19T17:40:23.611Z,1674150023.611 [Default:CheckIn] Stopped 2023-01-19T17:40:23.611Z,1674150023.611 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-01-19T17:40:23.611Z,1674150023.611 [Default:CheckIn](INFO): Running loop #10 2023-01-19T17:40:23.611Z,1674150023.611 [Default:CheckIn] Running Loop=10 2023-01-19T17:40:23.611Z,1674150023.611 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-01-19T17:40:23.611Z,1674150023.611 [Default:CheckIn:Read_GPS] Running Loop=1 2023-01-19T17:40:25.625Z,1674150025.625 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174024.00,A,3647.45432,N,12149.69903,W,3.130,66.39,190123,,,D*46 2023-01-19T17:40:25.627Z,1674150025.627 [NAL9602](INFO): GPS fix at 20230119T174024: (36.790905, -121.828317) 2023-01-19T17:40:25.637Z,1674150025.637 [Default:CheckIn:Read_GPS] Stopped 2023-01-19T17:40:25.637Z,1674150025.637 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-01-19T17:40:27.718Z,1674150027.718 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-01-19T17:40:38.968Z,1674150038.968 [NAL9602](INFO): SBD MO Status=0, MOMSN=38880, MT Status=0, MTMSN=0 2023-01-19T17:40:38.969Z,1674150038.969 [NAL9602](INFO): No messages in MT queue 2023-01-19T17:40:40.081Z,1674150040.081 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230119T171134/Courier0015.lzma 2023-01-19T17:40:41.084Z,1674150041.084 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Courier0015.lzma.bak 2023-01-19T17:40:41.084Z,1674150041.084 [DataOverHttps](INFO): SBD MOMSN=17402877 2023-01-19T17:41:09.723Z,1674150069.723 [NAL9602](INFO): Not Powering down - fast GPS 2023-01-19T17:41:32.069Z,1674150092.069 [DataOverHttps](INFO): Sending 181 bytes from file Logs/20230119T171134/Express0016.lzma 2023-01-19T17:41:47.128Z,1674150107.128 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Express0016.lzma.bak 2023-01-19T17:41:47.128Z,1674150107.128 [DataOverHttps](INFO): SBD MOMSN=17402880 2023-01-19T17:41:48.517Z,1674150108.517 [Default:CheckIn:Read_Iridium] Stopped 2023-01-19T17:41:48.517Z,1674150108.517 [Default:CheckIn:C.Wait] Running Loop=1 2023-01-19T17:41:48.517Z,1674150108.517 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-01-19T17:44:51.346Z,1674150291.346 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-01-19T17:45:22.471Z,1674150322.471 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-01-19T17:46:24.762Z,1674150384.762 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-01-19T17:46:34.803Z,1674150394.803 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.767441 2023-01-19T17:46:49.092Z,1674150409.092 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-01-19T17:46:49.092Z,1674150409.092 [Default:CheckIn:C.Wait] Stopped 2023-01-19T17:46:49.092Z,1674150409.092 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-01-19T17:46:49.092Z,1674150409.092 [Default:CheckIn:D] Running Loop=1 2023-01-19T17:46:49.501Z,1674150409.501 [Default:CheckIn:D] Stopped 2023-01-19T17:46:49.501Z,1674150409.501 [Default:CheckIn:E] Running Loop=1 2023-01-19T17:46:49.902Z,1674150409.902 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 90.712939 min 2023-01-19T17:46:49.902Z,1674150409.902 [Default:CheckIn:E] Stopped 2023-01-19T17:46:49.902Z,1674150409.902 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-01-19T17:46:49.902Z,1674150409.902 [Default:CheckIn] Stopped 2023-01-19T17:46:49.902Z,1674150409.902 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-01-19T17:46:49.902Z,1674150409.902 [Default:CheckIn](INFO): Running loop #11 2023-01-19T17:46:49.902Z,1674150409.902 [Default:CheckIn] Running Loop=11 2023-01-19T17:46:49.902Z,1674150409.902 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-01-19T17:46:49.902Z,1674150409.902 [Default:CheckIn:Read_GPS] Running Loop=1 2023-01-19T17:46:51.917Z,1674150411.917 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174651.00,A,3647.61629,N,12149.17687,W,4.801,71.69,190123,,,D*49 2023-01-19T17:46:51.928Z,1674150411.928 [NAL9602](INFO): GPS fix at 20230119T174651: (36.793605, -121.819615) 2023-01-19T17:46:51.939Z,1674150411.939 [Default:CheckIn:Read_GPS] Stopped 2023-01-19T17:46:51.939Z,1674150411.939 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-01-19T17:47:14.984Z,1674150434.984 [NAL9602](INFO): SBD MO Status=2, MOMSN=38881, MT Status=2, MTMSN=0 2023-01-19T17:47:14.984Z,1674150434.984 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-01-19T17:47:26.046Z,1674150446.046 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-01-19T17:47:32.401Z,1674150452.401 [NAL9602](INFO): SBD MO Status=2, MOMSN=38881, MT Status=2, MTMSN=0 2023-01-19T17:47:32.401Z,1674150452.401 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-01-19T17:47:54.450Z,1674150474.450 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003103 2023-01-19T17:48:01.945Z,1674150481.945 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230119T171134/Courier0018.lzma 2023-01-19T17:48:02.948Z,1674150482.948 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Courier0018.lzma.bak 2023-01-19T17:48:02.948Z,1674150482.948 [DataOverHttps](INFO): SBD MOMSN=17402886 2023-01-19T17:48:18.894Z,1674150498.894 [DataOverHttps](INFO): Sending 164 bytes from file Logs/20230119T171134/Express0019.lzma 2023-01-19T17:48:19.896Z,1674150499.896 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Express0019.lzma.bak 2023-01-19T17:48:19.896Z,1674150499.896 [DataOverHttps](INFO): SBD MOMSN=17402889 2023-01-19T17:48:21.296Z,1674150501.296 [Default:CheckIn:Read_Iridium] Stopped 2023-01-19T17:48:21.297Z,1674150501.297 [Default:CheckIn:C.Wait] Running Loop=1 2023-01-19T17:48:21.297Z,1674150501.297 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-01-19T17:48:25.319Z,1674150505.319 [NAL9602](INFO): Not Powering down - fast GPS 2023-01-19T17:53:21.919Z,1674150801.919 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-01-19T17:53:21.919Z,1674150801.919 [Default:CheckIn:C.Wait] Stopped 2023-01-19T17:53:21.919Z,1674150801.919 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-01-19T17:53:21.919Z,1674150801.919 [Default:CheckIn:D] Running Loop=1 2023-01-19T17:53:22.325Z,1674150802.325 [Default:CheckIn:D] Stopped 2023-01-19T17:53:22.325Z,1674150802.325 [Default:CheckIn:E] Running Loop=1 2023-01-19T17:53:22.741Z,1674150802.741 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 97.260002 min 2023-01-19T17:53:22.741Z,1674150802.741 [Default:CheckIn:E] Stopped 2023-01-19T17:53:22.742Z,1674150802.742 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-01-19T17:53:22.742Z,1674150802.742 [Default:CheckIn] Stopped 2023-01-19T17:53:22.742Z,1674150802.742 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-01-19T17:53:22.742Z,1674150802.742 [Default:CheckIn](INFO): Running loop #12 2023-01-19T17:53:22.742Z,1674150802.742 [Default:CheckIn] Running Loop=12 2023-01-19T17:53:22.742Z,1674150802.742 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-01-19T17:53:22.742Z,1674150802.742 [Default:CheckIn:Read_GPS] Running Loop=1 2023-01-19T17:53:24.741Z,1674150804.741 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175323.00,A,3647.79779,N,12148.63728,W,5.210,71.92,190123,,,D*4C 2023-01-19T17:53:24.744Z,1674150804.744 [NAL9602](INFO): GPS fix at 20230119T175323: (36.796630, -121.810621) 2023-01-19T17:53:24.754Z,1674150804.754 [Default:CheckIn:Read_GPS] Stopped 2023-01-19T17:53:24.754Z,1674150804.754 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-01-19T17:53:40.757Z,1674150820.757 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230119T171134/Courier0021.lzma 2023-01-19T17:53:41.760Z,1674150821.760 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Courier0021.lzma.bak 2023-01-19T17:53:41.760Z,1674150821.760 [DataOverHttps](INFO): SBD MOMSN=17402896 2023-01-19T17:53:55.849Z,1674150835.849 [NAL9602](INFO): SBD MO Status=1, MOMSN=38881, MT Status=0, MTMSN=0 2023-01-19T17:53:55.849Z,1674150835.849 [NAL9602](INFO): No messages in MT queue 2023-01-19T17:53:57.709Z,1674150837.709 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20230119T171134/Express0022.lzma 2023-01-19T17:53:58.712Z,1674150838.712 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Express0022.lzma.bak 2023-01-19T17:53:58.712Z,1674150838.712 [DataOverHttps](INFO): SBD MOMSN=17402899 2023-01-19T17:53:59.910Z,1674150839.910 [Default:CheckIn:Read_Iridium] Stopped 2023-01-19T17:53:59.910Z,1674150839.910 [Default:CheckIn:C.Wait] Running Loop=1 2023-01-19T17:53:59.910Z,1674150839.910 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-01-19T17:54:26.555Z,1674150866.555 [NAL9602](INFO): Not Powering down - fast GPS 2023-01-19T17:59:00.512Z,1674151140.512 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-01-19T17:59:00.512Z,1674151140.512 [Default:CheckIn:C.Wait] Stopped 2023-01-19T17:59:00.512Z,1674151140.512 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-01-19T17:59:00.512Z,1674151140.512 [Default:CheckIn:D] Running Loop=1 2023-01-19T17:59:00.920Z,1674151140.920 [Default:CheckIn:D] Stopped 2023-01-19T17:59:00.920Z,1674151140.920 [Default:CheckIn:E] Running Loop=1 2023-01-19T17:59:01.319Z,1674151141.319 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 102.903263 min 2023-01-19T17:59:01.320Z,1674151141.320 [Default:CheckIn:E] Stopped 2023-01-19T17:59:01.320Z,1674151141.320 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-01-19T17:59:01.320Z,1674151141.320 [Default:CheckIn] Stopped 2023-01-19T17:59:01.320Z,1674151141.320 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-01-19T17:59:01.320Z,1674151141.320 [Default:CheckIn](INFO): Running loop #13 2023-01-19T17:59:01.320Z,1674151141.320 [Default:CheckIn] Running Loop=13 2023-01-19T17:59:01.320Z,1674151141.320 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-01-19T17:59:01.320Z,1674151141.320 [Default:CheckIn:Read_GPS] Running Loop=1 2023-01-19T17:59:03.337Z,1674151143.337 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175902.00,A,3647.95512,N,12148.15709,W,3.868,62.34,190123,,,D*47 2023-01-19T17:59:03.339Z,1674151143.339 [NAL9602](INFO): GPS fix at 20230119T175902: (36.799252, -121.802618) 2023-01-19T17:59:03.349Z,1674151143.349 [Default:CheckIn:Read_GPS] Stopped 2023-01-19T17:59:03.349Z,1674151143.349 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-01-19T17:59:10.561Z,1674151150.561 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230119T171134/Courier0024.lzma 2023-01-19T17:59:11.564Z,1674151151.564 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Courier0024.lzma.bak 2023-01-19T17:59:11.564Z,1674151151.564 [DataOverHttps](INFO): SBD MOMSN=17402904 2023-01-19T17:59:28.538Z,1674151168.538 [DataOverHttps](INFO): Sending 163 bytes from file Logs/20230119T171134/Express0025.lzma 2023-01-19T17:59:29.540Z,1674151169.540 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Express0025.lzma.bak 2023-01-19T17:59:29.540Z,1674151169.540 [DataOverHttps](INFO): SBD MOMSN=17402907 2023-01-19T17:59:30.405Z,1674151170.405 [NAL9602](INFO): SBD MO Status=2, MOMSN=38882, MT Status=2, MTMSN=0 2023-01-19T17:59:30.406Z,1674151170.406 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-01-19T17:59:30.839Z,1674151170.839 [Default:CheckIn:Read_Iridium] Stopped 2023-01-19T17:59:30.839Z,1674151170.839 [Default:CheckIn:C.Wait] Running Loop=1 2023-01-19T17:59:30.839Z,1674151170.839 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-01-19T17:59:47.373Z,1674151187.373 [NAL9602](INFO): SBD MO Status=1, MOMSN=38882, MT Status=0, MTMSN=0 2023-01-19T17:59:47.373Z,1674151187.373 [NAL9602](INFO): No messages in MT queue 2023-01-19T18:00:18.071Z,1674151218.071 [NAL9602](INFO): Not Powering down - fast GPS 2023-01-19T18:04:31.412Z,1674151471.412 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-01-19T18:04:31.412Z,1674151471.412 [Default:CheckIn:C.Wait] Stopped 2023-01-19T18:04:31.412Z,1674151471.412 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-01-19T18:04:31.413Z,1674151471.413 [Default:CheckIn:D] Running Loop=1 2023-01-19T18:04:31.807Z,1674151471.807 [Default:CheckIn:D] Stopped 2023-01-19T18:04:31.807Z,1674151471.807 [Default:CheckIn:E] Running Loop=1 2023-01-19T18:04:32.217Z,1674151472.217 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 108.418034 min 2023-01-19T18:04:32.217Z,1674151472.217 [Default:CheckIn:E] Stopped 2023-01-19T18:04:32.217Z,1674151472.217 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-01-19T18:04:32.217Z,1674151472.217 [Default:CheckIn] Stopped 2023-01-19T18:04:32.217Z,1674151472.217 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-01-19T18:04:32.217Z,1674151472.217 [Default:CheckIn](INFO): Running loop #14 2023-01-19T18:04:32.217Z,1674151472.217 [Default:CheckIn] Running Loop=14 2023-01-19T18:04:32.218Z,1674151472.218 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-01-19T18:04:32.218Z,1674151472.218 [Default:CheckIn:Read_GPS] Running Loop=1 2023-01-19T18:04:34.227Z,1674151474.227 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180433.00,A,3648.14128,N,12147.73033,W,3.168,51.32,190123,,,D*47 2023-01-19T18:04:34.229Z,1674151474.229 [NAL9602](INFO): GPS fix at 20230119T180433: (36.802355, -121.795506) 2023-01-19T18:04:34.296Z,1674151474.296 [Default:CheckIn:Read_GPS] Stopped 2023-01-19T18:04:34.296Z,1674151474.296 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-01-19T18:04:42.333Z,1674151482.333 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230119T171134/Courier0027.lzma 2023-01-19T18:04:43.336Z,1674151483.336 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Courier0027.lzma.bak 2023-01-19T18:04:43.336Z,1674151483.336 [DataOverHttps](INFO): SBD MOMSN=17402912 2023-01-19T18:04:44.331Z,1674151484.331 [NAL9602](INFO): SBD MO Status=1, MOMSN=38883, MT Status=0, MTMSN=0 2023-01-19T18:04:44.331Z,1674151484.331 [NAL9602](INFO): No messages in MT queue 2023-01-19T18:05:02.148Z,1674151502.148 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-01-19T18:05:02.405Z,1674151502.405 [DataOverHttps](INFO): Sending 166 bytes from file Logs/20230119T171134/Express0028.lzma 2023-01-19T18:05:03.408Z,1674151503.408 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Express0028.lzma.bak 2023-01-19T18:05:03.408Z,1674151503.408 [DataOverHttps](INFO): SBD MOMSN=17402915 2023-01-19T18:05:04.603Z,1674151504.603 [Default:CheckIn:Read_Iridium] Stopped 2023-01-19T18:05:04.603Z,1674151504.603 [Default:CheckIn:C.Wait] Running Loop=1 2023-01-19T18:05:04.603Z,1674151504.603 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-01-19T18:05:15.079Z,1674151515.079 [NAL9602](INFO): Not Powering down - fast GPS 2023-01-19T18:05:17.098Z,1674151517.098 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-01-19T18:05:32.045Z,1674151532.045 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-01-19T18:05:46.993Z,1674151546.993 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-01-19T18:06:02.344Z,1674151562.344 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-01-19T18:06:17.293Z,1674151577.293 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-01-19T18:06:32.243Z,1674151592.243 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-01-19T18:06:47.592Z,1674151607.592 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-01-19T18:07:02.541Z,1674151622.541 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-01-19T18:07:17.086Z,1674151637.086 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2023-01-19T18:07:26.392Z,1674151646.392 [BPC1](INFO): Calculating totals. Valid battery stick count: 47. Valid reserve battery stick count: 5. 2023-01-19T18:07:26.394Z,1674151646.394 [BPC1](INFO): Received data from all battery sticks. 2023-01-19T18:10:05.186Z,1674151805.186 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-01-19T18:10:05.186Z,1674151805.186 [Default:CheckIn:C.Wait] Stopped 2023-01-19T18:10:05.186Z,1674151805.186 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-01-19T18:10:05.187Z,1674151805.187 [Default:CheckIn:D] Running Loop=1 2023-01-19T18:10:05.579Z,1674151805.579 [Default:CheckIn:D] Stopped 2023-01-19T18:10:05.579Z,1674151805.579 [Default:CheckIn:E] Running Loop=1 2023-01-19T18:10:05.989Z,1674151805.989 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 113.980916 min 2023-01-19T18:10:05.989Z,1674151805.989 [Default:CheckIn:E] Stopped 2023-01-19T18:10:05.989Z,1674151805.989 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-01-19T18:10:05.989Z,1674151805.989 [Default:CheckIn] Stopped 2023-01-19T18:10:05.989Z,1674151805.989 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-01-19T18:10:05.990Z,1674151805.990 [Default:CheckIn](INFO): Running loop #15 2023-01-19T18:10:05.990Z,1674151805.990 [Default:CheckIn] Running Loop=15 2023-01-19T18:10:05.990Z,1674151805.990 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-01-19T18:10:05.990Z,1674151805.990 [Default:CheckIn:Read_GPS] Running Loop=1 2023-01-19T18:10:07.993Z,1674151807.993 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181007.00,A,3648.31853,N,12147.47832,W,2.838,56.14,190123,,,D*47 2023-01-19T18:10:07.995Z,1674151807.995 [NAL9602](INFO): GPS fix at 20230119T181007: (36.805309, -121.791305) 2023-01-19T18:10:08.024Z,1674151808.024 [Default:CheckIn:Read_GPS] Stopped 2023-01-19T18:10:08.024Z,1674151808.024 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-01-19T18:10:15.618Z,1674151815.618 [DataOverHttps](INFO): Sending 204 bytes from file Logs/20230119T171134/Courier0030.lzma 2023-01-19T18:10:16.620Z,1674151816.620 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Courier0030.lzma.bak 2023-01-19T18:10:16.620Z,1674151816.620 [DataOverHttps](INFO): SBD MOMSN=17402920 2023-01-19T18:10:32.530Z,1674151832.530 [DataOverHttps](INFO): Sending 164 bytes from file Logs/20230119T171134/Express0031.lzma 2023-01-19T18:10:33.532Z,1674151833.532 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Express0031.lzma.bak 2023-01-19T18:10:33.532Z,1674151833.532 [DataOverHttps](INFO): SBD MOMSN=17402923 2023-01-19T18:10:34.674Z,1674151834.674 [Default:CheckIn:Read_Iridium] Stopped 2023-01-19T18:10:34.674Z,1674151834.674 [Default:CheckIn:C.Wait] Running Loop=1 2023-01-19T18:10:34.674Z,1674151834.674 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-01-19T18:10:46.373Z,1674151846.373 [NAL9602](INFO): SBD MO Status=1, MOMSN=38884, MT Status=0, MTMSN=0 2023-01-19T18:10:46.373Z,1674151846.373 [NAL9602](INFO): No messages in MT queue 2023-01-19T18:11:17.075Z,1674151877.075 [NAL9602](INFO): Not Powering down - fast GPS 2023-01-19T18:15:35.253Z,1674152135.253 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-01-19T18:15:35.253Z,1674152135.253 [Default:CheckIn:C.Wait] Stopped 2023-01-19T18:15:35.253Z,1674152135.253 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-01-19T18:15:35.253Z,1674152135.253 [Default:CheckIn:D] Running Loop=1 2023-01-19T18:15:35.660Z,1674152135.660 [Default:CheckIn:D] Stopped 2023-01-19T18:15:35.660Z,1674152135.660 [Default:CheckIn:E] Running Loop=1 2023-01-19T18:15:36.061Z,1674152136.061 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 119.482251 min 2023-01-19T18:15:36.061Z,1674152136.061 [Default:CheckIn:E] Stopped 2023-01-19T18:15:36.061Z,1674152136.061 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-01-19T18:15:36.061Z,1674152136.061 [Default:CheckIn] Stopped 2023-01-19T18:15:36.061Z,1674152136.061 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-01-19T18:15:36.061Z,1674152136.061 [Default:CheckIn](INFO): Running loop #16 2023-01-19T18:15:36.061Z,1674152136.061 [Default:CheckIn] Running Loop=16 2023-01-19T18:15:36.061Z,1674152136.061 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-01-19T18:15:36.061Z,1674152136.061 [Default:CheckIn:Read_GPS] Running Loop=1 2023-01-19T18:15:38.073Z,1674152138.073 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181537.00,A,3648.46045,N,12147.27872,W,2.780,51.30,190123,,,D*41 2023-01-19T18:15:38.076Z,1674152138.076 [NAL9602](INFO): GPS fix at 20230119T181537: (36.807674, -121.787979) 2023-01-19T18:15:38.086Z,1674152138.086 [Default:CheckIn:Read_GPS] Stopped 2023-01-19T18:15:38.086Z,1674152138.086 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-01-19T18:15:44.973Z,1674152144.973 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230119T171134/Courier0033.lzma 2023-01-19T18:15:45.976Z,1674152145.976 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Courier0033.lzma.bak 2023-01-19T18:15:45.976Z,1674152145.976 [DataOverHttps](INFO): SBD MOMSN=17402928 2023-01-19T18:16:01.805Z,1674152161.805 [DataOverHttps](INFO): Sending 163 bytes from file Logs/20230119T171134/Express0034.lzma 2023-01-19T18:16:02.808Z,1674152162.808 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Express0034.lzma.bak 2023-01-19T18:16:02.808Z,1674152162.808 [DataOverHttps](INFO): SBD MOMSN=17402931 2023-01-19T18:16:03.939Z,1674152163.939 [Default:CheckIn:Read_Iridium] Stopped 2023-01-19T18:16:03.939Z,1674152163.939 [Default:CheckIn:C.Wait] Running Loop=1 2023-01-19T18:16:03.939Z,1674152163.939 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-01-19T18:16:38.673Z,1674152198.673 [NAL9602](INFO): SBD MO Status=2, MOMSN=38885, MT Status=2, MTMSN=0 2023-01-19T18:16:38.673Z,1674152198.673 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-01-19T18:17:04.529Z,1674152224.529 [NAL9602](INFO): SBD MO Status=2, MOMSN=38885, MT Status=2, MTMSN=0 2023-01-19T18:17:04.529Z,1674152224.529 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-01-19T18:19:57.039Z,1674152397.039 [NAL9602](INFO): SBD MO Status=2, MOMSN=38885, MT Status=2, MTMSN=0 2023-01-19T18:19:57.039Z,1674152397.039 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-01-19T18:20:40.269Z,1674152440.269 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-01-19T18:21:04.523Z,1674152464.523 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-01-19T18:21:04.523Z,1674152464.523 [Default:CheckIn:C.Wait] Stopped 2023-01-19T18:21:04.523Z,1674152464.523 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-01-19T18:21:04.523Z,1674152464.523 [Default:CheckIn:D] Running Loop=1 2023-01-19T18:21:04.924Z,1674152464.924 [Default:CheckIn:D] Stopped 2023-01-19T18:21:04.924Z,1674152464.924 [Default:CheckIn:E] Running Loop=1 2023-01-19T18:21:05.342Z,1674152465.342 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 124.969995 min 2023-01-19T18:21:05.342Z,1674152465.342 [Default:CheckIn:E] Stopped 2023-01-19T18:21:05.342Z,1674152465.342 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-01-19T18:21:05.342Z,1674152465.342 [Default:CheckIn] Stopped 2023-01-19T18:21:05.343Z,1674152465.343 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-01-19T18:21:05.343Z,1674152465.343 [Default:CheckIn](INFO): Running loop #17 2023-01-19T18:21:05.343Z,1674152465.343 [Default:CheckIn] Running Loop=17 2023-01-19T18:21:05.343Z,1674152465.343 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-01-19T18:21:05.343Z,1674152465.343 [Default:CheckIn:Read_GPS] Running Loop=1 2023-01-19T18:21:07.345Z,1674152467.345 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182106.00,A,3648.32236,N,12147.12352,W,4.063,189.03,190123,,,D*76 2023-01-19T18:21:07.347Z,1674152467.347 [NAL9602](INFO): GPS fix at 20230119T182106: (36.805373, -121.785392) 2023-01-19T18:21:07.380Z,1674152467.380 [Default:CheckIn:Read_GPS] Stopped 2023-01-19T18:21:07.380Z,1674152467.380 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-01-19T18:21:14.373Z,1674152474.373 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230119T171134/Courier0036.lzma 2023-01-19T18:21:15.376Z,1674152475.376 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Courier0036.lzma.bak 2023-01-19T18:21:15.376Z,1674152475.376 [DataOverHttps](INFO): SBD MOMSN=17402936 2023-01-19T18:21:31.098Z,1674152491.098 [DataOverHttps](INFO): Sending 282 bytes from file Logs/20230119T171134/Express0037.lzma 2023-01-19T18:21:32.100Z,1674152492.100 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Express0037.lzma.bak 2023-01-19T18:21:32.100Z,1674152492.100 [DataOverHttps](INFO): SBD MOMSN=17402939 2023-01-19T18:21:33.218Z,1674152493.218 [Default:CheckIn:Read_Iridium] Stopped 2023-01-19T18:21:33.219Z,1674152493.219 [Default:CheckIn:C.Wait] Running Loop=1 2023-01-19T18:21:33.219Z,1674152493.219 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-01-19T18:21:39.664Z,1674152499.664 [NAL9602](INFO): Not Powering down - fast GPS 2023-01-19T18:26:33.794Z,1674152793.794 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-01-19T18:26:33.794Z,1674152793.794 [Default:CheckIn:C.Wait] Stopped 2023-01-19T18:26:33.794Z,1674152793.794 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-01-19T18:26:33.795Z,1674152793.795 [Default:CheckIn:D] Running Loop=1 2023-01-19T18:26:34.193Z,1674152794.193 [Default:CheckIn:D] Stopped 2023-01-19T18:26:34.193Z,1674152794.193 [Default:CheckIn:E] Running Loop=1 2023-01-19T18:26:34.588Z,1674152794.588 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 130.457812 min 2023-01-19T18:26:34.588Z,1674152794.588 [Default:CheckIn:E] Stopped 2023-01-19T18:26:34.588Z,1674152794.588 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-01-19T18:26:34.588Z,1674152794.588 [Default:CheckIn] Stopped 2023-01-19T18:26:34.588Z,1674152794.588 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-01-19T18:26:34.588Z,1674152794.588 [Default:CheckIn](INFO): Running loop #18 2023-01-19T18:26:34.588Z,1674152794.588 [Default:CheckIn] Running Loop=18 2023-01-19T18:26:34.588Z,1674152794.588 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-01-19T18:26:34.588Z,1674152794.588 [Default:CheckIn:Read_GPS] Running Loop=1 2023-01-19T18:26:36.601Z,1674152796.601 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182635.00,A,3648.13193,N,12147.20969,W,0.097,304.99,190123,,,D*76 2023-01-19T18:26:36.603Z,1674152796.603 [NAL9602](INFO): GPS fix at 20230119T182635: (36.802199, -121.786828) 2023-01-19T18:26:36.619Z,1674152796.619 [Default:CheckIn:Read_GPS] Stopped 2023-01-19T18:26:36.619Z,1674152796.619 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-01-19T18:26:44.421Z,1674152804.421 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20230119T171134/Courier0039.lzma 2023-01-19T18:26:45.424Z,1674152805.424 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Courier0039.lzma.bak 2023-01-19T18:26:45.424Z,1674152805.424 [DataOverHttps](INFO): SBD MOMSN=17402943 2023-01-19T18:27:03.817Z,1674152823.817 [DataOverHttps](INFO): Sending 159 bytes from file Logs/20230119T171134/Express0040.lzma 2023-01-19T18:27:04.820Z,1674152824.820 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Express0040.lzma.bak 2023-01-19T18:27:04.820Z,1674152824.820 [DataOverHttps](INFO): SBD MOMSN=17402946 2023-01-19T18:27:06.169Z,1674152826.169 [Default:CheckIn:Read_Iridium] Stopped 2023-01-19T18:27:06.169Z,1674152826.169 [Default:CheckIn:C.Wait] Running Loop=1 2023-01-19T18:27:06.169Z,1674152826.169 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-01-19T18:27:07.369Z,1674152827.369 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-01-19T18:27:07.446Z,1674152827.446 [NAL9602](ERROR): received: +CSQ:0 OK885, 2, 0, 0, 0 OK 2023-01-19T18:27:19.085Z,1674152839.085 [NAL9602](INFO): SBD MO Status=1, MOMSN=38885, MT Status=0, MTMSN=0 2023-01-19T18:27:19.085Z,1674152839.085 [NAL9602](INFO): No messages in MT queue 2023-01-19T18:27:49.781Z,1674152869.781 [NAL9602](INFO): Not Powering down - fast GPS 2023-01-19T18:32:06.751Z,1674153126.751 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-01-19T18:32:06.752Z,1674153126.752 [Default:CheckIn:C.Wait] Stopped 2023-01-19T18:32:06.752Z,1674153126.752 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-01-19T18:32:06.752Z,1674153126.752 [Default:CheckIn:D] Running Loop=1 2023-01-19T18:32:07.161Z,1674153127.161 [Default:CheckIn:D] Stopped 2023-01-19T18:32:07.161Z,1674153127.161 [Default:CheckIn:E] Running Loop=1 2023-01-19T18:32:07.575Z,1674153127.575 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 136.007275 min 2023-01-19T18:32:07.575Z,1674153127.575 [Default:CheckIn:E] Stopped 2023-01-19T18:32:07.575Z,1674153127.575 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-01-19T18:32:07.576Z,1674153127.576 [Default:CheckIn] Stopped 2023-01-19T18:32:07.576Z,1674153127.576 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-01-19T18:32:07.576Z,1674153127.576 [Default:CheckIn](INFO): Running loop #19 2023-01-19T18:32:07.576Z,1674153127.576 [Default:CheckIn] Running Loop=19 2023-01-19T18:32:07.576Z,1674153127.576 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-01-19T18:32:07.576Z,1674153127.576 [Default:CheckIn:Read_GPS] Running Loop=1 2023-01-19T18:32:09.577Z,1674153129.577 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183208.00,A,3648.13286,N,12147.20976,W,0.019,340.16,190123,,,D*75 2023-01-19T18:32:09.580Z,1674153129.580 [NAL9602](INFO): GPS fix at 20230119T183208: (36.802214, -121.786829) 2023-01-19T18:32:09.611Z,1674153129.611 [Default:CheckIn:Read_GPS] Stopped 2023-01-19T18:32:09.611Z,1674153129.611 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-01-19T18:32:17.589Z,1674153137.589 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230119T171134/Courier0042.lzma 2023-01-19T18:32:18.592Z,1674153138.592 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Courier0042.lzma.bak 2023-01-19T18:32:18.592Z,1674153138.592 [DataOverHttps](INFO): SBD MOMSN=17402952 2023-01-19T18:32:24.121Z,1674153144.121 [NAL9602](INFO): SBD MO Status=1, MOMSN=38886, MT Status=0, MTMSN=0 2023-01-19T18:32:24.121Z,1674153144.121 [NAL9602](INFO): No messages in MT queue 2023-01-19T18:32:34.361Z,1674153154.361 [DataOverHttps](INFO): Sending 148 bytes from file Logs/20230119T171134/Express0043.lzma 2023-01-19T18:32:35.364Z,1674153155.364 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Express0043.lzma.bak 2023-01-19T18:32:35.364Z,1674153155.364 [DataOverHttps](INFO): SBD MOMSN=17402955 2023-01-19T18:32:36.662Z,1674153156.662 [Default:CheckIn:Read_Iridium] Stopped 2023-01-19T18:32:36.662Z,1674153156.662 [Default:CheckIn:C.Wait] Running Loop=1 2023-01-19T18:32:36.662Z,1674153156.662 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-01-19T18:32:54.823Z,1674153174.823 [NAL9602](INFO): Not Powering down - fast GPS 2023-01-19T18:37:37.240Z,1674153457.240 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-01-19T18:37:37.240Z,1674153457.240 [Default:CheckIn:C.Wait] Stopped 2023-01-19T18:37:37.240Z,1674153457.240 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-01-19T18:37:37.241Z,1674153457.241 [Default:CheckIn:D] Running Loop=1 2023-01-19T18:37:37.655Z,1674153457.655 [Default:CheckIn:D] Stopped 2023-01-19T18:37:37.655Z,1674153457.655 [Default:CheckIn:E] Running Loop=1 2023-01-19T18:37:38.059Z,1674153458.059 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 141.515495 min 2023-01-19T18:37:38.059Z,1674153458.059 [Default:CheckIn:E] Stopped 2023-01-19T18:37:38.059Z,1674153458.059 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-01-19T18:37:38.059Z,1674153458.059 [Default:CheckIn] Stopped 2023-01-19T18:37:38.060Z,1674153458.060 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-01-19T18:37:38.060Z,1674153458.060 [Default:CheckIn](INFO): Running loop #20 2023-01-19T18:37:38.060Z,1674153458.060 [Default:CheckIn] Running Loop=20 2023-01-19T18:37:38.060Z,1674153458.060 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-01-19T18:37:38.060Z,1674153458.060 [Default:CheckIn:Read_GPS] Running Loop=1 2023-01-19T18:37:40.061Z,1674153460.061 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183739.00,A,3648.13245,N,12147.20972,W,0.058,340.16,190123,,,D*7C 2023-01-19T18:37:40.063Z,1674153460.063 [NAL9602](INFO): GPS fix at 20230119T183739: (36.802208, -121.786829) 2023-01-19T18:37:40.099Z,1674153460.099 [Default:CheckIn:Read_GPS] Stopped 2023-01-19T18:37:40.100Z,1674153460.100 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-01-19T18:37:47.873Z,1674153467.873 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230119T171134/Courier0045.lzma 2023-01-19T18:37:48.876Z,1674153468.876 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Courier0045.lzma.bak 2023-01-19T18:37:48.876Z,1674153468.876 [DataOverHttps](INFO): SBD MOMSN=17402960 2023-01-19T18:37:58.639Z,1674153478.639 [NAL9602](INFO): SBD MO Status=1, MOMSN=38887, MT Status=0, MTMSN=0 2023-01-19T18:37:58.640Z,1674153478.640 [NAL9602](INFO): No messages in MT queue 2023-01-19T18:38:04.718Z,1674153484.718 [DataOverHttps](INFO): Sending 147 bytes from file Logs/20230119T171134/Express0046.lzma 2023-01-19T18:38:05.720Z,1674153485.720 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Express0046.lzma.bak 2023-01-19T18:38:05.720Z,1674153485.720 [DataOverHttps](INFO): SBD MOMSN=17402963 2023-01-19T18:38:07.146Z,1674153487.146 [Default:CheckIn:Read_Iridium] Stopped 2023-01-19T18:38:07.146Z,1674153487.146 [Default:CheckIn:C.Wait] Running Loop=1 2023-01-19T18:38:07.146Z,1674153487.146 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-01-19T18:38:29.352Z,1674153509.352 [NAL9602](INFO): Not Powering down - fast GPS 2023-01-19T18:43:07.741Z,1674153787.741 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-01-19T18:43:07.741Z,1674153787.741 [Default:CheckIn:C.Wait] Stopped 2023-01-19T18:43:07.741Z,1674153787.741 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-01-19T18:43:07.741Z,1674153787.741 [Default:CheckIn:D] Running Loop=1 2023-01-19T18:43:08.150Z,1674153788.150 [Default:CheckIn:D] Stopped 2023-01-19T18:43:08.150Z,1674153788.150 [Default:CheckIn:E] Running Loop=1 2023-01-19T18:43:08.567Z,1674153788.567 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 147.023763 min 2023-01-19T18:43:08.567Z,1674153788.567 [Default:CheckIn:E] Stopped 2023-01-19T18:43:08.567Z,1674153788.567 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-01-19T18:43:08.567Z,1674153788.567 [Default:CheckIn] Stopped 2023-01-19T18:43:08.567Z,1674153788.567 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-01-19T18:43:08.567Z,1674153788.567 [Default:CheckIn](INFO): Running loop #21 2023-01-19T18:43:08.567Z,1674153788.567 [Default:CheckIn] Running Loop=21 2023-01-19T18:43:08.567Z,1674153788.567 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-01-19T18:43:08.567Z,1674153788.567 [Default:CheckIn:Read_GPS] Running Loop=1 2023-01-19T18:43:10.557Z,1674153790.557 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184309.00,A,3648.13837,N,12147.21186,W,0.019,279.80,190123,,,D*70 2023-01-19T18:43:10.559Z,1674153790.559 [NAL9602](INFO): GPS fix at 20230119T184309: (36.802306, -121.786864) 2023-01-19T18:43:10.605Z,1674153790.605 [Default:CheckIn:Read_GPS] Stopped 2023-01-19T18:43:10.606Z,1674153790.606 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-01-19T18:43:18.385Z,1674153798.385 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230119T171134/Courier0048.lzma 2023-01-19T18:43:19.388Z,1674153799.388 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Courier0048.lzma.bak 2023-01-19T18:43:19.388Z,1674153799.388 [DataOverHttps](INFO): SBD MOMSN=17402969 2023-01-19T18:43:35.241Z,1674153815.241 [DataOverHttps](INFO): Sending 147 bytes from file Logs/20230119T171134/Express0049.lzma 2023-01-19T18:43:36.244Z,1674153816.244 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Express0049.lzma.bak 2023-01-19T18:43:36.244Z,1674153816.244 [DataOverHttps](INFO): SBD MOMSN=17402972 2023-01-19T18:43:37.642Z,1674153817.642 [Default:CheckIn:Read_Iridium] Stopped 2023-01-19T18:43:37.643Z,1674153817.643 [Default:CheckIn:C.Wait] Running Loop=1 2023-01-19T18:43:37.643Z,1674153817.643 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-01-19T18:43:48.941Z,1674153828.941 [NAL9602](INFO): SBD MO Status=1, MOMSN=38888, MT Status=0, MTMSN=0 2023-01-19T18:43:48.941Z,1674153828.941 [NAL9602](INFO): No messages in MT queue 2023-01-19T18:44:19.639Z,1674153859.639 [NAL9602](INFO): Not Powering down - fast GPS 2023-01-19T18:48:38.222Z,1674154118.222 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-01-19T18:48:38.223Z,1674154118.223 [Default:CheckIn:C.Wait] Stopped 2023-01-19T18:48:38.223Z,1674154118.223 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-01-19T18:48:38.223Z,1674154118.223 [Default:CheckIn:D] Running Loop=1 2023-01-19T18:48:38.633Z,1674154118.633 [Default:CheckIn:D] Stopped 2023-01-19T18:48:38.633Z,1674154118.633 [Default:CheckIn:E] Running Loop=1 2023-01-19T18:48:39.038Z,1674154119.038 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 152.531803 min 2023-01-19T18:48:39.038Z,1674154119.038 [Default:CheckIn:E] Stopped 2023-01-19T18:48:39.038Z,1674154119.038 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-01-19T18:48:39.039Z,1674154119.039 [Default:CheckIn] Stopped 2023-01-19T18:48:39.039Z,1674154119.039 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-01-19T18:48:39.039Z,1674154119.039 [Default:CheckIn](INFO): Running loop #22 2023-01-19T18:48:39.039Z,1674154119.039 [Default:CheckIn] Running Loop=22 2023-01-19T18:48:39.039Z,1674154119.039 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-01-19T18:48:39.039Z,1674154119.039 [Default:CheckIn:Read_GPS] Running Loop=1 2023-01-19T18:48:41.046Z,1674154121.046 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184840.00,A,3648.13715,N,12147.23144,W,0.117,27.02,190123,,,D*49 2023-01-19T18:48:41.048Z,1674154121.048 [NAL9602](INFO): GPS fix at 20230119T184840: (36.802286, -121.787191) 2023-01-19T18:48:41.081Z,1674154121.081 [Default:CheckIn:Read_GPS] Stopped 2023-01-19T18:48:41.081Z,1674154121.081 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-01-19T18:48:48.810Z,1674154128.810 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230119T171134/Courier0051.lzma 2023-01-19T18:48:49.812Z,1674154129.812 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Courier0051.lzma.bak 2023-01-19T18:48:49.812Z,1674154129.812 [DataOverHttps](INFO): SBD MOMSN=17402977 2023-01-19T18:49:04.509Z,1674154144.509 [NAL9602](INFO): SBD MO Status=2, MOMSN=38889, MT Status=2, MTMSN=0 2023-01-19T18:49:04.509Z,1674154144.509 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-01-19T18:49:08.205Z,1674154148.205 [DataOverHttps](INFO): Sending 160 bytes from file Logs/20230119T171134/Express0052.lzma 2023-01-19T18:49:09.208Z,1674154149.208 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Express0052.lzma.bak 2023-01-19T18:49:09.208Z,1674154149.208 [DataOverHttps](INFO): SBD MOMSN=17402980 2023-01-19T18:49:10.586Z,1674154150.586 [Default:CheckIn:Read_Iridium] Stopped 2023-01-19T18:49:10.586Z,1674154150.586 [Default:CheckIn:C.Wait] Running Loop=1 2023-01-19T18:49:10.586Z,1674154150.586 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-01-19T18:49:14.604Z,1674154154.604 [NAL9602](INFO): SBD MO Status=1, MOMSN=38889, MT Status=0, MTMSN=0 2023-01-19T18:49:14.605Z,1674154154.605 [NAL9602](INFO): No messages in MT queue 2023-01-19T18:49:45.307Z,1674154185.307 [NAL9602](INFO): Not Powering down - fast GPS 2023-01-19T18:54:11.199Z,1674154451.199 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-01-19T18:54:11.199Z,1674154451.199 [Default:CheckIn:C.Wait] Stopped 2023-01-19T18:54:11.199Z,1674154451.199 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-01-19T18:54:11.199Z,1674154451.199 [Default:CheckIn:D] Running Loop=1 2023-01-19T18:54:11.593Z,1674154451.593 [Default:CheckIn:D] Stopped 2023-01-19T18:54:11.593Z,1674154451.593 [Default:CheckIn:E] Running Loop=1 2023-01-19T18:54:11.996Z,1674154451.996 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 158.081136 min 2023-01-19T18:54:11.996Z,1674154451.996 [Default:CheckIn:E] Stopped 2023-01-19T18:54:11.996Z,1674154451.996 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-01-19T18:54:11.996Z,1674154451.996 [Default:CheckIn] Stopped 2023-01-19T18:54:11.996Z,1674154451.996 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-01-19T18:54:11.996Z,1674154451.996 [Default:CheckIn](INFO): Running loop #23 2023-01-19T18:54:11.996Z,1674154451.996 [Default:CheckIn] Running Loop=23 2023-01-19T18:54:11.996Z,1674154451.996 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-01-19T18:54:11.996Z,1674154451.996 [Default:CheckIn:Read_GPS] Running Loop=1 2023-01-19T18:54:14.013Z,1674154454.013 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185413.00,A,3648.13861,N,12147.23145,W,0.058,27.02,190123,,,D*45 2023-01-19T18:54:14.015Z,1674154454.015 [NAL9602](INFO): GPS fix at 20230119T185413: (36.802310, -121.787191) 2023-01-19T18:54:14.025Z,1674154454.025 [Default:CheckIn:Read_GPS] Stopped 2023-01-19T18:54:14.025Z,1674154454.025 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-01-19T18:54:21.910Z,1674154461.910 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230119T171134/Courier0054.lzma 2023-01-19T18:54:22.912Z,1674154462.912 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Courier0054.lzma.bak 2023-01-19T18:54:22.912Z,1674154462.912 [DataOverHttps](INFO): SBD MOMSN=17402985 2023-01-19T18:54:38.985Z,1674154478.985 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20230119T171134/Express0055.lzma 2023-01-19T18:54:39.988Z,1674154479.988 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Express0055.lzma.bak 2023-01-19T18:54:39.988Z,1674154479.988 [DataOverHttps](INFO): SBD MOMSN=17402988 2023-01-19T18:54:41.107Z,1674154481.107 [Default:CheckIn:Read_Iridium] Stopped 2023-01-19T18:54:41.107Z,1674154481.107 [Default:CheckIn:C.Wait] Running Loop=1 2023-01-19T18:54:41.107Z,1674154481.107 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-01-19T18:55:12.600Z,1674154512.600 [NAL9602](INFO): SBD MO Status=1, MOMSN=38890, MT Status=0, MTMSN=0 2023-01-19T18:55:12.601Z,1674154512.601 [NAL9602](INFO): No messages in MT queue 2023-01-19T18:55:43.299Z,1674154543.299 [NAL9602](INFO): Not Powering down - fast GPS 2023-01-19T18:59:41.737Z,1674154781.737 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-01-19T18:59:41.737Z,1674154781.737 [Default:CheckIn:C.Wait] Stopped 2023-01-19T18:59:41.737Z,1674154781.737 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-01-19T18:59:41.738Z,1674154781.738 [Default:CheckIn:D] Running Loop=1 2023-01-19T18:59:42.149Z,1674154782.149 [Default:CheckIn:D] Stopped 2023-01-19T18:59:42.149Z,1674154782.149 [Default:CheckIn:E] Running Loop=1 2023-01-19T18:59:42.543Z,1674154782.543 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 163.590413 min 2023-01-19T18:59:42.543Z,1674154782.543 [Default:CheckIn:E] Stopped 2023-01-19T18:59:42.543Z,1674154782.543 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-01-19T18:59:42.543Z,1674154782.543 [Default:CheckIn] Stopped 2023-01-19T18:59:42.543Z,1674154782.543 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-01-19T18:59:42.544Z,1674154782.544 [Default:CheckIn](INFO): Running loop #24 2023-01-19T18:59:42.544Z,1674154782.544 [Default:CheckIn] Running Loop=24 2023-01-19T18:59:42.544Z,1674154782.544 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-01-19T18:59:42.544Z,1674154782.544 [Default:CheckIn:Read_GPS] Running Loop=1 2023-01-19T18:59:44.557Z,1674154784.557 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185943.00,A,3648.18320,N,12147.27578,W,0.447,236.58,190123,,,D*71 2023-01-19T18:59:44.559Z,1674154784.559 [NAL9602](INFO): GPS fix at 20230119T185943: (36.803053, -121.787930) 2023-01-19T18:59:44.570Z,1674154784.570 [Default:CheckIn:Read_GPS] Stopped 2023-01-19T18:59:44.570Z,1674154784.570 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-01-19T18:59:51.493Z,1674154791.493 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230119T171134/Courier0057.lzma 2023-01-19T18:59:52.496Z,1674154792.496 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Courier0057.lzma.bak 2023-01-19T18:59:52.496Z,1674154792.496 [DataOverHttps](INFO): SBD MOMSN=17402993 2023-01-19T19:00:08.294Z,1674154808.294 [DataOverHttps](INFO): Sending 166 bytes from file Logs/20230119T171134/Express0058.lzma 2023-01-19T19:00:09.296Z,1674154809.296 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Express0058.lzma.bak 2023-01-19T19:00:09.296Z,1674154809.296 [DataOverHttps](INFO): SBD MOMSN=17402996 2023-01-19T19:00:10.425Z,1674154810.425 [Default:CheckIn:Read_Iridium] Stopped 2023-01-19T19:00:10.425Z,1674154810.425 [Default:CheckIn:C.Wait] Running Loop=1 2023-01-19T19:00:10.425Z,1674154810.425 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-01-19T19:04:46.391Z,1674155086.391 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-01-19T19:05:11.072Z,1674155111.072 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-01-19T19:05:11.073Z,1674155111.073 [Default:CheckIn:C.Wait] Stopped 2023-01-19T19:05:11.073Z,1674155111.073 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-01-19T19:05:11.073Z,1674155111.073 [Default:CheckIn:D] Running Loop=1 2023-01-19T19:05:11.485Z,1674155111.485 [Default:CheckIn:D] Stopped 2023-01-19T19:05:11.485Z,1674155111.485 [Default:CheckIn:E] Running Loop=1 2023-01-19T19:05:11.909Z,1674155111.909 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 169.079329 min 2023-01-19T19:05:11.909Z,1674155111.909 [Default:CheckIn:E] Stopped 2023-01-19T19:05:11.909Z,1674155111.909 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-01-19T19:05:11.909Z,1674155111.909 [Default:CheckIn] Stopped 2023-01-19T19:05:11.909Z,1674155111.909 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-01-19T19:05:11.910Z,1674155111.910 [Default:CheckIn](INFO): Running loop #25 2023-01-19T19:05:11.910Z,1674155111.910 [Default:CheckIn] Running Loop=25 2023-01-19T19:05:11.910Z,1674155111.910 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-01-19T19:05:11.910Z,1674155111.910 [Default:CheckIn:Read_GPS] Running Loop=1 2023-01-19T19:05:13.897Z,1674155113.897 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190513.00,A,3648.16659,N,12147.28499,W,1.011,203.69,190123,,,A*7F 2023-01-19T19:05:13.899Z,1674155113.899 [NAL9602](INFO): GPS fix at 20230119T190513: (36.802777, -121.788083) 2023-01-19T19:05:13.934Z,1674155113.934 [Default:CheckIn:Read_GPS] Stopped 2023-01-19T19:05:13.934Z,1674155113.934 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-01-19T19:05:21.589Z,1674155121.589 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230119T171134/Courier0060.lzma 2023-01-19T19:05:22.592Z,1674155122.592 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Courier0060.lzma.bak 2023-01-19T19:05:22.592Z,1674155122.592 [DataOverHttps](INFO): SBD MOMSN=17402999 2023-01-19T19:05:38.498Z,1674155138.498 [DataOverHttps](INFO): Sending 228 bytes from file Logs/20230119T171134/Express0061.lzma 2023-01-19T19:05:39.500Z,1674155139.500 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Express0061.lzma.bak 2023-01-19T19:05:39.500Z,1674155139.500 [DataOverHttps](INFO): SBD MOMSN=17403002 2023-01-19T19:05:40.972Z,1674155140.972 [Default:CheckIn:Read_Iridium] Stopped 2023-01-19T19:05:40.972Z,1674155140.972 [Default:CheckIn:C.Wait] Running Loop=1 2023-01-19T19:05:40.972Z,1674155140.972 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-01-19T19:05:46.615Z,1674155146.615 [NAL9602](INFO): Not Powering down - fast GPS 2023-01-19T19:10:41.598Z,1674155441.598 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-01-19T19:10:41.598Z,1674155441.598 [Default:CheckIn:C.Wait] Stopped 2023-01-19T19:10:41.599Z,1674155441.599 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-01-19T19:10:41.599Z,1674155441.599 [Default:CheckIn:D] Running Loop=1 2023-01-19T19:10:41.998Z,1674155441.998 [Default:CheckIn:D] Stopped 2023-01-19T19:10:41.998Z,1674155441.998 [Default:CheckIn:E] Running Loop=1 2023-01-19T19:10:42.405Z,1674155442.405 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 174.587891 min 2023-01-19T19:10:42.405Z,1674155442.405 [Default:CheckIn:E] Stopped 2023-01-19T19:10:42.405Z,1674155442.405 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-01-19T19:10:42.405Z,1674155442.405 [Default:CheckIn] Stopped 2023-01-19T19:10:42.405Z,1674155442.405 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-01-19T19:10:42.406Z,1674155442.406 [Default:CheckIn](INFO): Running loop #26 2023-01-19T19:10:42.406Z,1674155442.406 [Default:CheckIn] Running Loop=26 2023-01-19T19:10:42.406Z,1674155442.406 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-01-19T19:10:42.406Z,1674155442.406 [Default:CheckIn:Read_GPS] Running Loop=1 2023-01-19T19:10:44.426Z,1674155444.426 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191043.00,A,3648.16056,N,12147.28398,W,0.311,203.69,190123,,,A*73 2023-01-19T19:10:44.428Z,1674155444.428 [NAL9602](INFO): GPS fix at 20230119T191043: (36.802676, -121.788066) 2023-01-19T19:10:44.438Z,1674155444.438 [Default:CheckIn:Read_GPS] Stopped 2023-01-19T19:10:44.438Z,1674155444.438 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-01-19T19:10:54.858Z,1674155454.858 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230119T171134/Courier0063.lzma 2023-01-19T19:10:55.860Z,1674155455.860 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Courier0063.lzma.bak 2023-01-19T19:10:55.860Z,1674155455.860 [DataOverHttps](INFO): SBD MOMSN=17403006 2023-01-19T19:11:12.214Z,1674155472.214 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20230119T171134/Express0064.lzma 2023-01-19T19:11:13.216Z,1674155473.216 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Express0064.lzma.bak 2023-01-19T19:11:13.216Z,1674155473.216 [DataOverHttps](INFO): SBD MOMSN=17403009 2023-01-19T19:11:14.436Z,1674155474.436 [Default:CheckIn:Read_Iridium] Stopped 2023-01-19T19:11:14.437Z,1674155474.437 [Default:CheckIn:C.Wait] Running Loop=1 2023-01-19T19:11:14.437Z,1674155474.437 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-01-19T19:12:25.456Z,1674155545.456 [BPC1](ERROR): BPC1B got IPBS message with 2 sticks (min is 7). 2023-01-19T19:12:37.980Z,1674155557.980 [BPC1](ERROR): BPC1B got IPBS message with 2 sticks (min is 7). 2023-01-19T19:12:50.538Z,1674155570.538 [BPC1](ERROR): BPC1B got IPBS message with 2 sticks (min is 7). 2023-01-19T19:13:03.084Z,1674155583.084 [BPC1](ERROR): BPC1B got IPBS message with 2 sticks (min is 7). 2023-01-19T19:13:15.206Z,1674155595.206 [BPC1](ERROR): BPC1B got IPBS message with 2 sticks (min is 7). 2023-01-19T19:13:27.728Z,1674155607.728 [BPC1](ERROR): BPC1B got IPBS message with 2 sticks (min is 7). 2023-01-19T19:13:40.658Z,1674155620.658 [BPC1](ERROR): BPC1B got IPBS message with 2 sticks (min is 7). 2023-01-19T19:13:52.776Z,1674155632.776 [BPC1](ERROR): BPC1B got IPBS message with 2 sticks (min is 7). 2023-01-19T19:14:05.300Z,1674155645.300 [BPC1](ERROR): BPC1B got IPBS message with 2 sticks (min is 7). 2023-01-19T19:14:17.420Z,1674155657.420 [BPC1](ERROR): BPC1B got IPBS message with 2 sticks (min is 7). 2023-01-19T19:14:29.944Z,1674155669.944 [BPC1](ERROR): BPC1B got IPBS message with 2 sticks (min is 7). 2023-01-19T19:14:42.470Z,1674155682.470 [BPC1](ERROR): BPC1B got IPBS message with 2 sticks (min is 7). 2023-01-19T19:14:47.318Z,1674155687.318 [BPC1](ERROR): BPC1B got IPBS message with 3 sticks (min is 7). 2023-01-19T19:15:46.350Z,1674155746.350 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-01-19T19:16:15.049Z,1674155775.049 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-01-19T19:16:15.049Z,1674155775.049 [Default:CheckIn:C.Wait] Stopped 2023-01-19T19:16:15.049Z,1674155775.049 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-01-19T19:16:15.049Z,1674155775.049 [Default:CheckIn:D] Running Loop=1 2023-01-19T19:16:15.461Z,1674155775.461 [Default:CheckIn:D] Stopped 2023-01-19T19:16:15.461Z,1674155775.461 [Default:CheckIn:E] Running Loop=1 2023-01-19T19:16:15.857Z,1674155775.857 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 180.145605 min 2023-01-19T19:16:15.858Z,1674155775.858 [Default:CheckIn:E] Stopped 2023-01-19T19:16:15.858Z,1674155775.858 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-01-19T19:16:15.858Z,1674155775.858 [Default:CheckIn] Stopped 2023-01-19T19:16:15.858Z,1674155775.858 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-01-19T19:16:15.858Z,1674155775.858 [Default:CheckIn](INFO): Running loop #27 2023-01-19T19:16:15.858Z,1674155775.858 [Default:CheckIn] Running Loop=27 2023-01-19T19:16:15.858Z,1674155775.858 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-01-19T19:16:15.859Z,1674155775.859 [Default:CheckIn:Read_GPS] Running Loop=1 2023-01-19T19:16:17.920Z,1674155777.920 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191617.00,A,3648.16073,N,12147.28762,W,1.283,136.06,190123,,,A*75 2023-01-19T19:16:17.922Z,1674155777.922 [NAL9602](INFO): GPS fix at 20230119T191617: (36.802679, -121.788127) 2023-01-19T19:16:17.932Z,1674155777.932 [Default:CheckIn:Read_GPS] Stopped 2023-01-19T19:16:17.932Z,1674155777.932 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-01-19T19:16:25.478Z,1674155785.478 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230119T171134/Courier0066.lzma 2023-01-19T19:16:26.480Z,1674155786.480 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Courier0066.lzma.bak 2023-01-19T19:16:26.480Z,1674155786.480 [DataOverHttps](INFO): SBD MOMSN=17403014 2023-01-19T19:16:42.602Z,1674155802.602 [DataOverHttps](INFO): Sending 216 bytes from file Logs/20230119T171134/Express0067.lzma 2023-01-19T19:16:43.604Z,1674155803.604 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Express0067.lzma.bak 2023-01-19T19:16:43.604Z,1674155803.604 [DataOverHttps](INFO): SBD MOMSN=17403017 2023-01-19T19:16:44.960Z,1674155804.960 [Default:CheckIn:Read_Iridium] Stopped 2023-01-19T19:16:44.960Z,1674155804.960 [Default:CheckIn:C.Wait] Running Loop=1 2023-01-19T19:16:44.960Z,1674155804.960 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-01-19T19:16:50.631Z,1674155810.631 [NAL9602](INFO): Not Powering down - fast GPS 2023-01-19T19:21:45.690Z,1674156105.690 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-01-19T19:21:45.690Z,1674156105.690 [Default:CheckIn:C.Wait] Stopped 2023-01-19T19:21:45.690Z,1674156105.690 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-01-19T19:21:45.690Z,1674156105.690 [Default:CheckIn:D] Running Loop=1 2023-01-19T19:21:46.097Z,1674156106.097 [Default:CheckIn:D] Stopped 2023-01-19T19:21:46.098Z,1674156106.098 [Default:CheckIn:E] Running Loop=1 2023-01-19T19:21:46.509Z,1674156106.509 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 185.656217 min 2023-01-19T19:21:46.510Z,1674156106.510 [Default:CheckIn:E] Stopped 2023-01-19T19:21:46.510Z,1674156106.510 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-01-19T19:21:46.510Z,1674156106.510 [Default:CheckIn] Stopped 2023-01-19T19:21:46.510Z,1674156106.510 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-01-19T19:21:46.510Z,1674156106.510 [Default:CheckIn](INFO): Running loop #28 2023-01-19T19:21:46.510Z,1674156106.510 [Default:CheckIn] Running Loop=28 2023-01-19T19:21:46.510Z,1674156106.510 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-01-19T19:21:46.511Z,1674156106.511 [Default:CheckIn:Read_GPS] Running Loop=1 2023-01-19T19:21:48.563Z,1674156108.563 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192147.00,A,3648.18435,N,12147.28878,W,1.886,32.13,190123,,,A*46 2023-01-19T19:21:48.566Z,1674156108.566 [NAL9602](INFO): GPS fix at 20230119T192147: (36.803072, -121.788146) 2023-01-19T19:21:48.592Z,1674156108.592 [Default:CheckIn:Read_GPS] Stopped 2023-01-19T19:21:48.592Z,1674156108.592 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-01-19T19:21:52.940Z,1674156112.940 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-01-19T19:21:52.947Z,1674156112.947 [BPC1](INFO): Calculating totals. Valid battery stick count: 47. Valid reserve battery stick count: 6. 2023-01-19T19:21:52.949Z,1674156112.949 [BPC1](INFO): Received data from all battery sticks. 2023-01-19T19:21:55.701Z,1674156115.701 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230119T171134/Courier0069.lzma 2023-01-19T19:21:56.704Z,1674156116.704 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Courier0069.lzma.bak 2023-01-19T19:21:56.704Z,1674156116.704 [DataOverHttps](INFO): SBD MOMSN=17403023 2023-01-19T19:22:12.502Z,1674156132.502 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20230119T171134/Express0070.lzma 2023-01-19T19:22:13.504Z,1674156133.504 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Express0070.lzma.bak 2023-01-19T19:22:13.504Z,1674156133.504 [DataOverHttps](INFO): SBD MOMSN=17403026 2023-01-19T19:22:14.783Z,1674156134.783 [Default:CheckIn:Read_Iridium] Stopped 2023-01-19T19:22:14.783Z,1674156134.783 [Default:CheckIn:C.Wait] Running Loop=1 2023-01-19T19:22:14.783Z,1674156134.783 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-01-19T19:22:19.203Z,1674156139.203 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-01-19T19:22:19.278Z,1674156139.278 [NAL9602](ERROR): received: +CSQ:0 OK890, 0, 0, 0, 0 OK 2023-01-19T19:26:50.395Z,1674156410.395 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-01-19T19:27:15.517Z,1674156435.517 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-01-19T19:27:15.517Z,1674156435.517 [Default:CheckIn:C.Wait] Stopped 2023-01-19T19:27:15.517Z,1674156435.517 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-01-19T19:27:15.518Z,1674156435.518 [Default:CheckIn:D] Running Loop=1 2023-01-19T19:27:15.931Z,1674156435.931 [Default:CheckIn:D] Stopped 2023-01-19T19:27:15.931Z,1674156435.931 [Default:CheckIn:E] Running Loop=1 2023-01-19T19:27:16.319Z,1674156436.319 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 191.153434 min 2023-01-19T19:27:16.319Z,1674156436.319 [Default:CheckIn:E] Stopped 2023-01-19T19:27:16.319Z,1674156436.319 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-01-19T19:27:16.319Z,1674156436.319 [Default:CheckIn] Stopped 2023-01-19T19:27:16.319Z,1674156436.319 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-01-19T19:27:16.319Z,1674156436.319 [Default:CheckIn](INFO): Running loop #29 2023-01-19T19:27:16.319Z,1674156436.319 [Default:CheckIn] Running Loop=29 2023-01-19T19:27:16.320Z,1674156436.320 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-01-19T19:27:16.320Z,1674156436.320 [Default:CheckIn:Read_GPS] Running Loop=1 2023-01-19T19:27:18.337Z,1674156438.337 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192717.00,A,3648.16321,N,12147.28471,W,0.311,32.13,190123,,,A*48 2023-01-19T19:27:18.339Z,1674156438.339 [NAL9602](INFO): GPS fix at 20230119T192717: (36.802720, -121.788078) 2023-01-19T19:27:18.349Z,1674156438.349 [Default:CheckIn:Read_GPS] Stopped 2023-01-19T19:27:18.349Z,1674156438.349 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-01-19T19:27:25.525Z,1674156445.525 [DataOverHttps](INFO): Sending 86 bytes from file Logs/20230119T171134/Courier0072.lzma 2023-01-19T19:27:26.528Z,1674156446.528 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Courier0072.lzma.bak 2023-01-19T19:27:26.528Z,1674156446.528 [DataOverHttps](INFO): SBD MOMSN=17403030 2023-01-19T19:27:42.254Z,1674156462.254 [DataOverHttps](INFO): Sending 225 bytes from file Logs/20230119T171134/Express0073.lzma 2023-01-19T19:27:43.256Z,1674156463.256 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Express0073.lzma.bak 2023-01-19T19:27:43.256Z,1674156463.256 [DataOverHttps](INFO): SBD MOMSN=17403033 2023-01-19T19:27:44.607Z,1674156464.607 [Default:CheckIn:Read_Iridium] Stopped 2023-01-19T19:27:44.607Z,1674156464.607 [Default:CheckIn:C.Wait] Running Loop=1 2023-01-19T19:27:44.607Z,1674156464.607 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-01-19T19:27:50.650Z,1674156470.650 [NAL9602](INFO): Not Powering down - fast GPS 2023-01-19T19:32:45.340Z,1674156765.340 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-01-19T19:32:45.340Z,1674156765.340 [Default:CheckIn:C.Wait] Stopped 2023-01-19T19:32:45.340Z,1674156765.340 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-01-19T19:32:45.340Z,1674156765.340 [Default:CheckIn:D] Running Loop=1 2023-01-19T19:32:45.753Z,1674156765.753 [Default:CheckIn:D] Stopped 2023-01-19T19:32:45.753Z,1674156765.753 [Default:CheckIn:E] Running Loop=1 2023-01-19T19:32:46.170Z,1674156766.170 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 196.650488 min 2023-01-19T19:32:46.170Z,1674156766.170 [Default:CheckIn:E] Stopped 2023-01-19T19:32:46.170Z,1674156766.170 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-01-19T19:32:46.170Z,1674156766.170 [Default:CheckIn] Stopped 2023-01-19T19:32:46.170Z,1674156766.170 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-01-19T19:32:46.170Z,1674156766.170 [Default:CheckIn](INFO): Running loop #30 2023-01-19T19:32:46.171Z,1674156766.171 [Default:CheckIn] Running Loop=30 2023-01-19T19:32:46.171Z,1674156766.171 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-01-19T19:32:46.171Z,1674156766.171 [Default:CheckIn:Read_GPS] Running Loop=1 2023-01-19T19:32:48.161Z,1674156768.161 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193247.00,A,3648.16335,N,12147.28353,W,0.136,0.00,190123,,,A*7F 2023-01-19T19:32:48.163Z,1674156768.163 [NAL9602](INFO): GPS fix at 20230119T193247: (36.802723, -121.788059) 2023-01-19T19:32:48.180Z,1674156768.180 [Default:CheckIn:Read_GPS] Stopped 2023-01-19T19:32:48.180Z,1674156768.180 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-01-19T19:32:58.226Z,1674156778.226 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230119T171134/Courier0075.lzma 2023-01-19T19:32:59.228Z,1674156779.228 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Courier0075.lzma.bak 2023-01-19T19:32:59.228Z,1674156779.228 [DataOverHttps](INFO): SBD MOMSN=17403038 2023-01-19T19:33:14.949Z,1674156794.949 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20230119T171134/Express0076.lzma 2023-01-19T19:33:15.952Z,1674156795.952 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Express0076.lzma.bak 2023-01-19T19:33:15.952Z,1674156795.952 [DataOverHttps](INFO): SBD MOMSN=17403041 2023-01-19T19:33:17.362Z,1674156797.362 [Default:CheckIn:Read_Iridium] Stopped 2023-01-19T19:33:17.362Z,1674156797.362 [Default:CheckIn:C.Wait] Running Loop=1 2023-01-19T19:33:17.362Z,1674156797.362 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-01-19T19:33:18.954Z,1674156798.954 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-01-19T19:33:19.026Z,1674156799.026 [NAL9602](ERROR): received: +CSQ:0 OK890, 0, 0, 0, 0 OK 2023-01-19T19:37:13.376Z,1674157033.376 [NAL9602](INFO): SBD MO Status=2, MOMSN=38891, MT Status=2, MTMSN=0 2023-01-19T19:37:13.376Z,1674157033.376 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-01-19T19:37:50.543Z,1674157070.543 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-01-19T19:38:18.029Z,1674157098.029 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-01-19T19:38:18.029Z,1674157098.029 [Default:CheckIn:C.Wait] Stopped 2023-01-19T19:38:18.029Z,1674157098.029 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-01-19T19:38:18.029Z,1674157098.029 [Default:CheckIn:D] Running Loop=1 2023-01-19T19:38:18.433Z,1674157098.433 [Default:CheckIn:D] Stopped 2023-01-19T19:38:18.433Z,1674157098.433 [Default:CheckIn:E] Running Loop=1 2023-01-19T19:38:18.846Z,1674157098.846 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 202.195133 min 2023-01-19T19:38:18.846Z,1674157098.846 [Default:CheckIn:E] Stopped 2023-01-19T19:38:18.847Z,1674157098.847 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-01-19T19:38:18.847Z,1674157098.847 [Default:CheckIn] Stopped 2023-01-19T19:38:18.847Z,1674157098.847 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-01-19T19:38:18.847Z,1674157098.847 [Default:CheckIn](INFO): Running loop #31 2023-01-19T19:38:18.847Z,1674157098.847 [Default:CheckIn] Running Loop=31 2023-01-19T19:38:18.847Z,1674157098.847 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-01-19T19:38:18.847Z,1674157098.847 [Default:CheckIn:Read_GPS] Running Loop=1 2023-01-19T19:38:20.873Z,1674157100.873 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193820.00,A,3648.16469,N,12147.28100,W,0.408,0.00,190123,,,A*76 2023-01-19T19:38:20.875Z,1674157100.875 [NAL9602](INFO): GPS fix at 20230119T193820: (36.802745, -121.788017) 2023-01-19T19:38:20.885Z,1674157100.885 [Default:CheckIn:Read_GPS] Stopped 2023-01-19T19:38:20.885Z,1674157100.885 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-01-19T19:38:28.441Z,1674157108.441 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230119T171134/Courier0078.lzma 2023-01-19T19:38:29.444Z,1674157109.444 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Courier0078.lzma.bak 2023-01-19T19:38:29.444Z,1674157109.444 [DataOverHttps](INFO): SBD MOMSN=17403060 2023-01-19T19:38:45.206Z,1674157125.206 [DataOverHttps](INFO): Sending 225 bytes from file Logs/20230119T171134/Express0079.lzma 2023-01-19T19:38:46.211Z,1674157126.211 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Express0079.lzma.bak 2023-01-19T19:38:46.211Z,1674157126.211 [DataOverHttps](INFO): SBD MOMSN=17403063 2023-01-19T19:38:47.556Z,1674157127.556 [Default:CheckIn:Read_Iridium] Stopped 2023-01-19T19:38:47.556Z,1674157127.556 [Default:CheckIn:C.Wait] Running Loop=1 2023-01-19T19:38:47.557Z,1674157127.557 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-01-19T19:38:53.603Z,1674157133.603 [NAL9602](INFO): Not Powering down - fast GPS 2023-01-19T19:40:35.423Z,1674157235.423 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2023-01-19T19:40:35.423Z,1674157235.423 [DropWeight] Hardware Fault, FailCount= 1 2023-01-19T19:40:35.423Z,1674157235.423 [DropWeight](ERROR): Hardware Fault 2023-01-19T19:40:35.476Z,1674157235.476 [CommandExec](FAULT): Scheduling is paused 2023-01-19T19:40:35.477Z,1674157235.477 [CBIT](INFO): Critical error at 20230119T194035 2023-01-19T19:40:35.477Z,1674157235.477 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2023-01-19T19:40:35.479Z,1674157235.479 [CBIT](ERROR): Hardware Fault in component: DropWeight 2023-01-19T19:40:35.480Z,1674157235.480 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2023-01-19T19:40:35.844Z,1674157235.844 [CBIT](INFO): Critical error at 20230119T194035 2023-01-19T19:43:48.216Z,1674157428.216 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-01-19T19:43:48.216Z,1674157428.216 [Default:CheckIn:C.Wait] Stopped 2023-01-19T19:43:48.216Z,1674157428.216 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-01-19T19:43:48.216Z,1674157428.216 [Default:CheckIn:D] Running Loop=1 2023-01-19T19:43:48.629Z,1674157428.629 [Default:CheckIn:D] Stopped 2023-01-19T19:43:48.630Z,1674157428.630 [Default:CheckIn:E] Running Loop=1 2023-01-19T19:43:49.027Z,1674157429.027 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 207.698421 min 2023-01-19T19:43:49.027Z,1674157429.027 [Default:CheckIn:E] Stopped 2023-01-19T19:43:49.027Z,1674157429.027 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-01-19T19:43:49.027Z,1674157429.027 [Default:CheckIn] Stopped 2023-01-19T19:43:49.027Z,1674157429.027 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-01-19T19:43:49.027Z,1674157429.027 [Default:CheckIn](INFO): Running loop #32 2023-01-19T19:43:49.027Z,1674157429.027 [Default:CheckIn] Running Loop=32 2023-01-19T19:43:49.027Z,1674157429.027 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-01-19T19:43:49.027Z,1674157429.027 [Default:CheckIn:Read_GPS] Running Loop=1 2023-01-19T19:43:51.037Z,1674157431.037 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194350.00,A,3648.16234,N,12147.28931,W,0.583,321.37,190123,,,A*7F 2023-01-19T19:43:51.039Z,1674157431.039 [NAL9602](INFO): GPS fix at 20230119T194350: (36.802706, -121.788155) 2023-01-19T19:43:51.051Z,1674157431.051 [Default:CheckIn:Read_GPS] Stopped 2023-01-19T19:43:51.051Z,1674157431.051 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-01-19T19:43:58.689Z,1674157438.689 [DataOverHttps](INFO): Sending 178 bytes from file Logs/20230119T171134/Courier0081.lzma 2023-01-19T19:43:59.692Z,1674157439.692 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Courier0081.lzma.bak 2023-01-19T19:43:59.692Z,1674157439.692 [DataOverHttps](INFO): SBD MOMSN=17403067 2023-01-19T19:44:15.438Z,1674157455.438 [DataOverHttps](INFO): Sending 167 bytes from file Logs/20230119T171134/Express0082.lzma 2023-01-19T19:44:16.440Z,1674157456.440 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Express0082.lzma.bak 2023-01-19T19:44:16.441Z,1674157456.441 [DataOverHttps](INFO): SBD MOMSN=17403072 2023-01-19T19:44:17.715Z,1674157457.715 [Default:CheckIn:Read_Iridium] Stopped 2023-01-19T19:44:17.715Z,1674157457.715 [Default:CheckIn:C.Wait] Running Loop=1 2023-01-19T19:44:17.715Z,1674157457.715 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-01-19T19:44:21.735Z,1674157461.735 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-01-19T19:44:21.810Z,1674157461.810 [NAL9602](ERROR): received: +CSQ:0 OK891, 2, 0, 0, 0 OK 2023-01-19T19:46:46.852Z,1674157606.852 [NAL9602](INFO): SBD MO Status=2, MOMSN=38891, MT Status=2, MTMSN=0 2023-01-19T19:46:46.852Z,1674157606.852 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-01-19T19:48:53.355Z,1674157733.355 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-01-19T19:49:18.417Z,1674157758.417 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-01-19T19:49:18.417Z,1674157758.417 [Default:CheckIn:C.Wait] Stopped 2023-01-19T19:49:18.417Z,1674157758.417 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-01-19T19:49:18.417Z,1674157758.417 [Default:CheckIn:D] Running Loop=1 2023-01-19T19:49:18.826Z,1674157758.826 [Default:CheckIn:D] Stopped 2023-01-19T19:49:18.826Z,1674157758.826 [Default:CheckIn:E] Running Loop=1 2023-01-19T19:49:19.219Z,1674157759.219 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 213.201693 min 2023-01-19T19:49:19.219Z,1674157759.219 [Default:CheckIn:E] Stopped 2023-01-19T19:49:19.220Z,1674157759.220 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-01-19T19:49:19.220Z,1674157759.220 [Default:CheckIn] Stopped 2023-01-19T19:49:19.220Z,1674157759.220 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-01-19T19:49:19.220Z,1674157759.220 [Default:CheckIn](INFO): Running loop #33 2023-01-19T19:49:19.220Z,1674157759.220 [Default:CheckIn] Running Loop=33 2023-01-19T19:49:19.220Z,1674157759.220 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-01-19T19:49:19.220Z,1674157759.220 [Default:CheckIn:Read_GPS] Running Loop=1 2023-01-19T19:49:21.233Z,1674157761.233 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194920.00,A,3648.16340,N,12147.28454,W,0.272,321.37,190123,,,A*77 2023-01-19T19:49:21.235Z,1674157761.235 [NAL9602](INFO): GPS fix at 20230119T194920: (36.802723, -121.788076) 2023-01-19T19:49:21.271Z,1674157761.271 [Default:CheckIn:Read_GPS] Stopped 2023-01-19T19:49:21.271Z,1674157761.271 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-01-19T19:49:29.201Z,1674157769.201 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230119T171134/Courier0084.lzma 2023-01-19T19:49:30.204Z,1674157770.204 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Courier0084.lzma.bak 2023-01-19T19:49:30.204Z,1674157770.204 [DataOverHttps](INFO): SBD MOMSN=17403078 2023-01-19T19:49:45.958Z,1674157785.958 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20230119T171134/Express0085.lzma 2023-01-19T19:49:46.960Z,1674157786.960 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Express0085.lzma.bak 2023-01-19T19:49:46.960Z,1674157786.960 [DataOverHttps](INFO): SBD MOMSN=17403081 2023-01-19T19:49:48.308Z,1674157788.308 [Default:CheckIn:Read_Iridium] Stopped 2023-01-19T19:49:48.308Z,1674157788.308 [Default:CheckIn:C.Wait] Running Loop=1 2023-01-19T19:49:48.308Z,1674157788.308 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-01-19T19:49:53.551Z,1674157793.551 [NAL9602](INFO): Not Powering down - fast GPS 2023-01-19T19:54:49.000Z,1674158089.000 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-01-19T19:54:49.001Z,1674158089.001 [Default:CheckIn:C.Wait] Stopped 2023-01-19T19:54:49.001Z,1674158089.001 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-01-19T19:54:49.002Z,1674158089.002 [Default:CheckIn:D] Running Loop=1 2023-01-19T19:54:49.412Z,1674158089.412 [Default:CheckIn:D] Stopped 2023-01-19T19:54:49.412Z,1674158089.412 [Default:CheckIn:E] Running Loop=1 2023-01-19T19:54:49.792Z,1674158089.792 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 218.711458 min 2023-01-19T19:54:49.793Z,1674158089.793 [Default:CheckIn:E] Stopped 2023-01-19T19:54:49.793Z,1674158089.793 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-01-19T19:54:49.793Z,1674158089.793 [Default:CheckIn] Stopped 2023-01-19T19:54:49.793Z,1674158089.793 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-01-19T19:54:49.793Z,1674158089.793 [Default:CheckIn](INFO): Running loop #34 2023-01-19T19:54:49.793Z,1674158089.793 [Default:CheckIn] Running Loop=34 2023-01-19T19:54:49.793Z,1674158089.793 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-01-19T19:54:49.793Z,1674158089.793 [Default:CheckIn:Read_GPS] Running Loop=1 2023-01-19T19:54:51.797Z,1674158091.797 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195451.00,A,3648.16631,N,12147.28137,W,0.603,0.00,190123,,,A*78 2023-01-19T19:54:51.818Z,1674158091.818 [NAL9602](INFO): GPS fix at 20230119T195451: (36.802772, -121.788023) 2023-01-19T19:54:51.837Z,1674158091.837 [Default:CheckIn:Read_GPS] Stopped 2023-01-19T19:54:51.837Z,1674158091.837 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-01-19T19:54:52.766Z,1674158092.766 [Default:CheckIn:Read_Iridium] Stopped 2023-01-19T19:54:52.766Z,1674158092.766 [Default:CheckIn:C.Wait] Running Loop=1 2023-01-19T19:54:52.766Z,1674158092.766 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-01-19T19:55:35.560Z,1674158135.560 [CBIT](INFO): Clearing failed state for component DropWeight 2023-01-19T19:55:35.560Z,1674158135.560 [DropWeight] No Fault, FailCount= 1 2023-01-19T19:56:17.949Z,1674158177.949 [NAL9602](INFO): SBD MO Status=2, MOMSN=38891, MT Status=2, MTMSN=0 2023-01-19T19:56:17.949Z,1674158177.949 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-01-19T19:57:39.956Z,1674158259.956 [NAL9602](INFO): SBD MO Status=1, MOMSN=38891, MT Status=0, MTMSN=0 2023-01-19T19:57:39.956Z,1674158259.956 [NAL9602](INFO): No messages in MT queue 2023-01-19T19:58:10.663Z,1674158290.663 [NAL9602](INFO): Not Powering down - fast GPS 2023-01-19T19:59:53.284Z,1674158393.284 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-01-19T19:59:53.284Z,1674158393.284 [Default:CheckIn:C.Wait] Stopped 2023-01-19T19:59:53.284Z,1674158393.284 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-01-19T19:59:53.284Z,1674158393.284 [Default:CheckIn:D] Running Loop=1 2023-01-19T19:59:53.693Z,1674158393.693 [Default:CheckIn:D] Stopped 2023-01-19T19:59:53.693Z,1674158393.693 [Default:CheckIn:E] Running Loop=1 2023-01-19T19:59:54.098Z,1674158394.098 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 223.782796 min 2023-01-19T19:59:54.098Z,1674158394.098 [Default:CheckIn:E] Stopped 2023-01-19T19:59:54.099Z,1674158394.099 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-01-19T19:59:54.099Z,1674158394.099 [Default:CheckIn] Stopped 2023-01-19T19:59:54.099Z,1674158394.099 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-01-19T19:59:54.099Z,1674158394.099 [Default:CheckIn](INFO): Running loop #35 2023-01-19T19:59:54.099Z,1674158394.099 [Default:CheckIn] Running Loop=35 2023-01-19T19:59:54.099Z,1674158394.099 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-01-19T19:59:54.099Z,1674158394.099 [Default:CheckIn:Read_GPS] Running Loop=1 2023-01-19T19:59:56.109Z,1674158396.109 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195955.00,A,3648.16794,N,12147.27972,W,0.253,54.43,190123,,,A*4E 2023-01-19T19:59:56.121Z,1674158396.121 [NAL9602](INFO): GPS fix at 20230119T195955: (36.802799, -121.787995) 2023-01-19T19:59:56.132Z,1674158396.132 [Default:CheckIn:Read_GPS] Stopped 2023-01-19T19:59:56.132Z,1674158396.132 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-01-19T20:00:03.937Z,1674158403.937 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230119T171134/Courier0087.lzma 2023-01-19T20:00:04.940Z,1674158404.940 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Courier0087.lzma.bak 2023-01-19T20:00:04.940Z,1674158404.940 [DataOverHttps](INFO): SBD MOMSN=17403087 2023-01-19T20:00:20.789Z,1674158420.789 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230119T171134/Courier0090.lzma 2023-01-19T20:00:21.792Z,1674158421.792 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Courier0090.lzma.bak 2023-01-19T20:00:21.792Z,1674158421.792 [DataOverHttps](INFO): SBD MOMSN=17403090 2023-01-19T20:00:37.705Z,1674158437.705 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20230119T171134/Express0088.lzma 2023-01-19T20:00:38.708Z,1674158438.708 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Express0088.lzma.bak 2023-01-19T20:00:38.708Z,1674158438.708 [DataOverHttps](INFO): SBD MOMSN=17403093 2023-01-19T20:00:57.322Z,1674158457.322 [DataOverHttps](INFO): Sending 153 bytes from file Logs/20230119T171134/Express0091.lzma 2023-01-19T20:00:58.326Z,1674158458.326 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Express0091.lzma.bak 2023-01-19T20:00:58.326Z,1674158458.326 [DataOverHttps](INFO): SBD MOMSN=17403096 2023-01-19T20:00:59.626Z,1674158459.626 [Default:CheckIn:Read_Iridium] Stopped 2023-01-19T20:00:59.627Z,1674158459.627 [Default:CheckIn:C.Wait] Running Loop=1 2023-01-19T20:00:59.627Z,1674158459.627 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-01-19T20:04:58.357Z,1674158698.357 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-01-19T20:05:29.063Z,1674158729.063 [NAL9602](INFO): Not Powering down - fast GPS 2023-01-19T20:06:00.189Z,1674158760.189 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-01-19T20:06:00.189Z,1674158760.189 [Default:CheckIn:C.Wait] Stopped 2023-01-19T20:06:00.190Z,1674158760.190 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-01-19T20:06:00.219Z,1674158760.219 [Default:CheckIn:D] Running Loop=1 2023-01-19T20:06:00.583Z,1674158760.583 [Default:CheckIn:D] Stopped 2023-01-19T20:06:00.583Z,1674158760.583 [Default:CheckIn:E] Running Loop=1 2023-01-19T20:06:00.993Z,1674158760.993 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 229.897640 min 2023-01-19T20:06:00.993Z,1674158760.993 [Default:CheckIn:E] Stopped 2023-01-19T20:06:00.993Z,1674158760.993 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-01-19T20:06:00.993Z,1674158760.993 [Default:CheckIn] Stopped 2023-01-19T20:06:00.993Z,1674158760.993 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-01-19T20:06:00.994Z,1674158760.994 [Default:CheckIn](INFO): Running loop #36 2023-01-19T20:06:00.994Z,1674158760.994 [Default:CheckIn] Running Loop=36 2023-01-19T20:06:00.994Z,1674158760.994 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-01-19T20:06:00.994Z,1674158760.994 [Default:CheckIn:Read_GPS] Running Loop=1 2023-01-19T20:06:03.005Z,1674158763.005 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200602.00,A,3648.16767,N,12147.28209,W,1.283,152.92,190123,,,A*7F 2023-01-19T20:06:03.007Z,1674158763.007 [NAL9602](INFO): GPS fix at 20230119T200602: (36.802794, -121.788035) 2023-01-19T20:06:03.035Z,1674158763.035 [Default:CheckIn:Read_GPS] Stopped 2023-01-19T20:06:03.035Z,1674158763.035 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-01-19T20:06:10.805Z,1674158770.805 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230119T171134/Courier0093.lzma 2023-01-19T20:06:11.808Z,1674158771.808 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Courier0093.lzma.bak 2023-01-19T20:06:11.808Z,1674158771.808 [DataOverHttps](INFO): SBD MOMSN=17403099 2023-01-19T20:06:27.630Z,1674158787.630 [DataOverHttps](INFO): Sending 218 bytes from file Logs/20230119T171134/Express0094.lzma 2023-01-19T20:06:28.632Z,1674158788.632 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Express0094.lzma.bak 2023-01-19T20:06:28.632Z,1674158788.632 [DataOverHttps](INFO): SBD MOMSN=17403102 2023-01-19T20:06:30.085Z,1674158790.085 [Default:CheckIn:Read_Iridium] Stopped 2023-01-19T20:06:30.085Z,1674158790.085 [Default:CheckIn:C.Wait] Running Loop=1 2023-01-19T20:06:30.085Z,1674158790.085 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-01-19T20:06:33.707Z,1674158793.707 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-01-19T20:06:33.782Z,1674158793.782 [NAL9602](ERROR): received: +CSQ:0 OK891, 0, 0, 0, 0 OK 2023-01-19T20:11:05.194Z,1674159065.194 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-01-19T20:11:30.656Z,1674159090.656 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-01-19T20:11:30.656Z,1674159090.656 [Default:CheckIn:C.Wait] Stopped 2023-01-19T20:11:30.656Z,1674159090.656 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-01-19T20:11:30.657Z,1674159090.657 [Default:CheckIn:D] Running Loop=1 2023-01-19T20:11:31.061Z,1674159091.061 [Default:CheckIn:D] Stopped 2023-01-19T20:11:31.061Z,1674159091.061 [Default:CheckIn:E] Running Loop=1 2023-01-19T20:11:31.469Z,1674159091.469 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 235.405615 min 2023-01-19T20:11:31.469Z,1674159091.469 [Default:CheckIn:E] Stopped 2023-01-19T20:11:31.469Z,1674159091.469 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-01-19T20:11:31.469Z,1674159091.469 [Default:CheckIn] Stopped 2023-01-19T20:11:31.469Z,1674159091.469 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-01-19T20:11:31.470Z,1674159091.470 [Default:CheckIn](INFO): Running loop #37 2023-01-19T20:11:31.470Z,1674159091.470 [Default:CheckIn] Running Loop=37 2023-01-19T20:11:31.470Z,1674159091.470 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-01-19T20:11:31.470Z,1674159091.470 [Default:CheckIn:Read_GPS] Running Loop=1 2023-01-19T20:11:33.476Z,1674159093.476 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201132.00,A,3648.15628,N,12147.26999,W,1.458,136.91,190123,,,A*7E 2023-01-19T20:11:33.478Z,1674159093.478 [NAL9602](INFO): GPS fix at 20230119T201132: (36.802605, -121.787833) 2023-01-19T20:11:33.512Z,1674159093.512 [Default:CheckIn:Read_GPS] Stopped 2023-01-19T20:11:33.512Z,1674159093.512 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-01-19T20:11:40.445Z,1674159100.445 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230119T171134/Courier0096.lzma 2023-01-19T20:11:41.448Z,1674159101.448 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Courier0096.lzma.bak 2023-01-19T20:11:41.448Z,1674159101.448 [DataOverHttps](INFO): SBD MOMSN=17403106 2023-01-19T20:11:57.174Z,1674159117.174 [DataOverHttps](INFO): Sending 230 bytes from file Logs/20230119T171134/Express0097.lzma 2023-01-19T20:11:58.176Z,1674159118.176 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Express0097.lzma.bak 2023-01-19T20:11:58.176Z,1674159118.176 [DataOverHttps](INFO): SBD MOMSN=17403109 2023-01-19T20:11:59.756Z,1674159119.756 [Default:CheckIn:Read_Iridium] Stopped 2023-01-19T20:11:59.756Z,1674159119.756 [Default:CheckIn:C.Wait] Running Loop=1 2023-01-19T20:11:59.756Z,1674159119.756 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-01-19T20:12:05.387Z,1674159125.387 [NAL9602](INFO): Not Powering down - fast GPS 2023-01-19T20:17:00.327Z,1674159420.327 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-01-19T20:17:00.327Z,1674159420.327 [Default:CheckIn:C.Wait] Stopped 2023-01-19T20:17:00.327Z,1674159420.327 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-01-19T20:17:00.327Z,1674159420.327 [Default:CheckIn:D] Running Loop=1 2023-01-19T20:17:00.732Z,1674159420.732 [Default:CheckIn:D] Stopped 2023-01-19T20:17:00.732Z,1674159420.732 [Default:CheckIn:E] Running Loop=1 2023-01-19T20:17:01.148Z,1674159421.148 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 240.900130 min 2023-01-19T20:17:01.148Z,1674159421.148 [Default:CheckIn:E] Stopped 2023-01-19T20:17:01.148Z,1674159421.148 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-01-19T20:17:01.148Z,1674159421.148 [Default:CheckIn] Stopped 2023-01-19T20:17:01.149Z,1674159421.149 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-01-19T20:17:01.149Z,1674159421.149 [Default:CheckIn](INFO): Running loop #38 2023-01-19T20:17:01.149Z,1674159421.149 [Default:CheckIn] Running Loop=38 2023-01-19T20:17:01.149Z,1674159421.149 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-01-19T20:17:01.149Z,1674159421.149 [Default:CheckIn:Read_GPS] Running Loop=1 2023-01-19T20:17:03.145Z,1674159423.145 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201702.00,A,3648.16726,N,12147.27039,W,2.955,74.05,190123,,,A*4C 2023-01-19T20:17:03.147Z,1674159423.147 [NAL9602](INFO): GPS fix at 20230119T201702: (36.802788, -121.787840) 2023-01-19T20:17:03.180Z,1674159423.180 [Default:CheckIn:Read_GPS] Stopped 2023-01-19T20:17:03.180Z,1674159423.180 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-01-19T20:17:10.709Z,1674159430.709 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230119T171134/Courier0099.lzma 2023-01-19T20:17:11.712Z,1674159431.712 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Courier0099.lzma.bak 2023-01-19T20:17:11.712Z,1674159431.712 [DataOverHttps](INFO): SBD MOMSN=17403113 2023-01-19T20:17:27.657Z,1674159447.657 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20230119T171134/Express0100.lzma 2023-01-19T20:17:28.660Z,1674159448.660 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Express0100.lzma.bak 2023-01-19T20:17:28.660Z,1674159448.660 [DataOverHttps](INFO): SBD MOMSN=17403116 2023-01-19T20:17:29.815Z,1674159449.815 [Default:CheckIn:Read_Iridium] Stopped 2023-01-19T20:17:29.815Z,1674159449.815 [Default:CheckIn:C.Wait] Running Loop=1 2023-01-19T20:17:29.815Z,1674159449.815 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-01-19T20:17:33.840Z,1674159453.840 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-01-19T20:17:33.918Z,1674159453.918 [NAL9602](ERROR): received: +CSQ:0 OK891, 0, 0, 0, 0 OK 2023-01-19T20:22:05.333Z,1674159725.333 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-01-19T20:22:30.388Z,1674159750.388 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-01-19T20:22:30.388Z,1674159750.388 [Default:CheckIn:C.Wait] Stopped 2023-01-19T20:22:30.388Z,1674159750.388 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-01-19T20:22:30.389Z,1674159750.389 [Default:CheckIn:D] Running Loop=1 2023-01-19T20:22:30.787Z,1674159750.787 [Default:CheckIn:D] Stopped 2023-01-19T20:22:30.787Z,1674159750.787 [Default:CheckIn:E] Running Loop=1 2023-01-19T20:22:31.197Z,1674159751.197 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 246.401042 min 2023-01-19T20:22:31.197Z,1674159751.197 [Default:CheckIn:E] Stopped 2023-01-19T20:22:31.197Z,1674159751.197 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-01-19T20:22:31.197Z,1674159751.197 [Default:CheckIn] Stopped 2023-01-19T20:22:31.197Z,1674159751.197 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-01-19T20:22:31.198Z,1674159751.198 [Default:CheckIn](INFO): Running loop #39 2023-01-19T20:22:31.198Z,1674159751.198 [Default:CheckIn] Running Loop=39 2023-01-19T20:22:31.198Z,1674159751.198 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-01-19T20:22:31.198Z,1674159751.198 [Default:CheckIn:Read_GPS] Running Loop=1 2023-01-19T20:22:33.224Z,1674159753.224 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202232.00,A,3648.18152,N,12147.25514,W,2.527,218.26,190123,,,A*7A 2023-01-19T20:22:33.227Z,1674159753.227 [NAL9602](INFO): GPS fix at 20230119T202232: (36.803025, -121.787586) 2023-01-19T20:22:33.237Z,1674159753.237 [Default:CheckIn:Read_GPS] Stopped 2023-01-19T20:22:33.237Z,1674159753.237 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-01-19T20:22:40.157Z,1674159760.157 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230119T171134/Courier0102.lzma 2023-01-19T20:22:41.160Z,1674159761.160 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Courier0102.lzma.bak 2023-01-19T20:22:41.160Z,1674159761.160 [DataOverHttps](INFO): SBD MOMSN=17403119 2023-01-19T20:22:59.962Z,1674159779.962 [DataOverHttps](INFO): Sending 231 bytes from file Logs/20230119T171134/Express0103.lzma 2023-01-19T20:23:00.964Z,1674159780.964 [DataOverHttps](INFO): Moved sent file to Logs/20230119T171134/Express0103.lzma.bak 2023-01-19T20:23:00.964Z,1674159780.964 [DataOverHttps](INFO): SBD MOMSN=17403122 2023-01-19T20:23:02.366Z,1674159782.366 [Default:CheckIn:Read_Iridium] Stopped 2023-01-19T20:23:02.366Z,1674159782.366 [Default:CheckIn:C.Wait] Running Loop=1 2023-01-19T20:23:02.366Z,1674159782.366 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-01-19T20:23:05.571Z,1674159785.571 [NAL9602](INFO): Not Powering down - fast GPS 2023-01-19T20:24:09.007Z,1674159849.007 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file). 2023-01-19T20:24:39.823Z,1674159879.823 [CommandExec](IMPORTANT): got command quit 2023-01-19T20:24:40.826Z,1674159880.826 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2023-01-19T20:24:40.826Z,1674159880.826 [CommandExec](INFO): Uninitializing the command executive. 2023-01-19T20:24:40.826Z,1674159880.826 [CommandExec](INFO): Uninitializing the command scheduler. 2023-01-19T20:24:40.827Z,1674159880.827 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-01-19T20:24:40.922Z,1674159880.922 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2023-01-19T20:24:40.922Z,1674159880.922 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2023-01-19T20:24:40.922Z,1674159880.922 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-01-19T20:24:40.923Z,1674159880.923 [NavChartDb](INFO): Join timeout helper Thread ID is 8698 2023-01-19T20:24:41.063Z,1674159881.063 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2023-01-19T20:24:41.063Z,1674159881.063 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-01-19T20:24:41.066Z,1674159881.066 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2023-01-19T20:24:41.066Z,1674159881.066 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-01-19T20:24:41.066Z,1674159881.066 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 8699 2023-01-19T20:24:41.530Z,1674159881.530 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2023-01-19T20:24:41.531Z,1674159881.531 [WetLabsBB2FL](INFO): Powering down 2023-01-19T20:24:41.531Z,1674159881.531 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-01-19T20:24:41.534Z,1674159881.534 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2023-01-19T20:24:41.534Z,1674159881.534 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-01-19T20:24:41.534Z,1674159881.534 [CTD_Seabird](INFO): Join timeout helper Thread ID is 8700 2023-01-19T20:24:41.974Z,1674159881.974 [CTD_Seabird](INFO): Powering down 2023-01-19T20:24:41.986Z,1674159881.986 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2023-01-19T20:24:41.986Z,1674159881.986 [CTD_Seabird](INFO): Powering down 2023-01-19T20:24:42.003Z,1674159882.003 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-01-19T20:24:42.013Z,1674159882.013 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2023-01-19T20:24:42.013Z,1674159882.013 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-01-19T20:24:42.014Z,1674159882.014 [Radio_Surface](INFO): Join timeout helper Thread ID is 8701 2023-01-19T20:24:42.394Z,1674159882.394 [Radio_Surface](INFO): Powering down 2023-01-19T20:24:42.395Z,1674159882.395 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2023-01-19T20:24:42.395Z,1674159882.395 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-01-19T20:24:42.402Z,1674159882.402 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2023-01-19T20:24:42.402Z,1674159882.402 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-01-19T20:24:42.402Z,1674159882.402 [Onboard](INFO): Join timeout helper Thread ID is 8702 2023-01-19T20:24:42.818Z,1674159882.818 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2023-01-19T20:24:43.250Z,1674159883.250 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2023-01-19T20:24:43.250Z,1674159883.250 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-01-19T20:24:43.264Z,1674159883.264 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2023-01-19T20:24:43.264Z,1674159883.264 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-01-19T20:24:43.265Z,1674159883.265 [DataOverHttps](INFO): Join timeout helper Thread ID is 8703 2023-01-19T20:24:43.822Z,1674159883.822 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2023-01-19T20:24:43.822Z,1674159883.822 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-01-19T20:24:43.839Z,1674159883.839 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2023-01-19T20:24:43.839Z,1674159883.839 [logger ThreadHandler](INFO): Thread cancelled. 2023-01-19T20:24:43.839Z,1674159883.839 [logger](INFO): Join timeout helper Thread ID is 8704 2023-01-19T20:24:43.866Z,1674159883.866 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2023-01-19T20:24:43.866Z,1674159883.866 [logger ThreadHandler](INFO): Thread cancelled. 2023-01-19T20:24:43.884Z,1674159883.884 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2023-01-19T20:24:43.884Z,1674159883.884 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-01-19T20:24:43.885Z,1674159883.885 [CommandLine](INFO): Join timeout helper Thread ID is 8705 2023-01-19T20:24:43.978Z,1674159883.978 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2023-01-19T20:24:43.978Z,1674159883.978 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-01-19T20:24:43.983Z,1674159883.983 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2023-01-19T20:24:43.983Z,1674159883.983 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-01-19T20:24:43.983Z,1674159883.983 [CommandExec](INFO): Join timeout helper Thread ID is 8706 2023-01-19T20:24:43.984Z,1674159883.984 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2023-01-19T20:24:43.984Z,1674159883.984 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-01-19T20:24:43.985Z,1674159883.985 [controlThread](INFO): Join timeout helper Thread ID is 8707 2023-01-19T20:24:44.130Z,1674159884.130 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2023-01-19T20:24:44.130Z,1674159884.130 [controlThread](DEBUG): Uninitializing ControlThread 2023-01-19T20:24:44.131Z,1674159884.131 [AHRS_M2](INFO): Powering down 2023-01-19T20:24:44.203Z,1674159884.203 [NAL9602](INFO): Powering down 2023-01-19T20:24:44.206Z,1674159884.206 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2023-01-19T20:24:44.207Z,1674159884.207 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2023-01-19T20:24:44.207Z,1674159884.207 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2023-01-19T20:24:44.208Z,1674159884.208 [MissionManager](INFO): Uninitializing Mission Default 2023-01-19T20:24:44.208Z,1674159884.208 [Default] Stopped 2023-01-19T20:24:44.208Z,1674159884.208 [Default](DEBUG): Aggregate::uninitialize Default 2023-01-19T20:24:44.208Z,1674159884.208 [Default:B.GoToSurface] Stopped 2023-01-19T20:24:44.208Z,1674159884.208 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-01-19T20:24:44.208Z,1674159884.208 [Default:CheckIn] Stopped 2023-01-19T20:24:44.208Z,1674159884.208 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-01-19T20:24:44.208Z,1674159884.208 [Default:CheckIn:C.Wait] Stopped 2023-01-19T20:24:44.208Z,1674159884.208 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-01-19T20:24:44.211Z,1674159884.211 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2023-01-19T20:24:44.211Z,1674159884.211 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2023-01-19T20:24:44.212Z,1674159884.212 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2023-01-19T20:24:44.212Z,1674159884.212 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2023-01-19T20:24:44.212Z,1674159884.212 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2023-01-19T20:24:44.212Z,1674159884.212 [BuoyancyServo](INFO): Powering down 2023-01-19T20:24:44.226Z,1674159884.226 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2023-01-19T20:24:44.226Z,1674159884.226 [ElevatorServo](INFO): Powering down 2023-01-19T20:24:44.227Z,1674159884.227 [MassServo](DEBUG): Uninitialize Mass Servo. 2023-01-19T20:24:44.227Z,1674159884.227 [MassServo](INFO): Powering down 2023-01-19T20:24:44.228Z,1674159884.228 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2023-01-19T20:24:44.228Z,1674159884.228 [RudderServo](INFO): Powering down 2023-01-19T20:24:44.229Z,1674159884.229 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2023-01-19T20:24:44.229Z,1674159884.229 [ThrusterServo](INFO): Powering down 2023-01-19T20:24:44.230Z,1674159884.230 [SBIT](DEBUG): Uninitialize SBIT Component. 2023-01-19T20:24:44.230Z,1674159884.230 [IBIT](DEBUG): Uninitialize IBIT Component. 2023-01-19T20:24:44.231Z,1674159884.231 [CBIT](DEBUG): Uninitialize CBIT Component. 2023-01-19T20:24:44.231Z,1674159884.231 [CBIT](DEBUG): Powering off loads. 2023-01-19T20:24:44.242Z,1674159884.242 [CBIT](DEBUG): Disabling WDT. 2023-01-19T20:24:44.254Z,1674159884.254 [CBIT](DEBUG): Opening all GF detection circuits. 2023-01-19T20:24:44.255Z,1674159884.255 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-01-19T20:24:44.304Z,1674159884.304 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-01-19T20:24:44.307Z,1674159884.307 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-01-19T20:24:44.313Z,1674159884.313 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-01-19T20:24:44.383Z,1674159884.383 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-01-19T20:24:44.386Z,1674159884.386 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-01-19T20:24:44.426Z,1674159884.426 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-01-19T20:24:44.529Z,1674159884.529 [logger ThreadHandler](INFO): Thread cancelled.