2023-05-02T16:35:56.008Z,1683045356.008 [CommandExec](IMPORTANT): got command restart logs
2023-05-02T16:37:08.857Z,1683045428.857 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-02T16:37:08.858Z,1683045428.858 [Default:CheckIn:C.Wait] Stopped
2023-05-02T16:37:08.858Z,1683045428.858 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-02T16:37:08.858Z,1683045428.858 [Default:CheckIn:D] Running Loop=1
2023-05-02T16:37:09.294Z,1683045429.294 [Default:CheckIn:D] Stopped
2023-05-02T16:37:09.294Z,1683045429.294 [Default:CheckIn:E] Running Loop=1
2023-05-02T16:37:09.663Z,1683045429.663 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 24.951223 min 
2023-05-02T16:37:09.663Z,1683045429.663 [Default:CheckIn:E] Stopped
2023-05-02T16:37:09.663Z,1683045429.663 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-02T16:37:09.663Z,1683045429.663 [Default:CheckIn] Stopped
2023-05-02T16:37:09.663Z,1683045429.663 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-02T16:37:09.664Z,1683045429.664 [Default:CheckIn](INFO): Running loop #5
2023-05-02T16:37:09.664Z,1683045429.664 [Default:CheckIn] Running Loop=5
2023-05-02T16:37:09.664Z,1683045429.664 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-02T16:37:09.664Z,1683045429.664 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-02T16:37:11.673Z,1683045431.673 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163710.00,A,3648.56597,N,12149.64326,W,1.749,73.43,020523,,,D*4E
2023-05-02T16:37:11.675Z,1683045431.675 [NAL9602](INFO): GPS fix at 20230502T163710: (36.809433, -121.827388)
2023-05-02T16:37:11.705Z,1683045431.705 [Default:CheckIn:Read_GPS] Stopped
2023-05-02T16:37:11.706Z,1683045431.706 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-02T16:37:34.298Z,1683045454.298 [NAL9602](INFO): SBD MO Status=2, MOMSN=43343, MT Status=2, MTMSN=0
2023-05-02T16:37:34.298Z,1683045454.298 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-05-02T16:37:49.579Z,1683045469.579 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230502T151025/Courier0033.lzma
2023-05-02T16:37:52.588Z,1683045472.588 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2023-05-02T16:37:57.326Z,1683045477.326 [NAL9602](INFO): SBD MO Status=2, MOMSN=43343, MT Status=2, MTMSN=0
2023-05-02T16:37:57.326Z,1683045477.326 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-05-02T16:37:59.898Z,1683045479.898 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230502T151025/Courier0033.lzma
2023-05-02T16:38:00.901Z,1683045480.901 [DataOverHttps](INFO): Moved sent file to Logs/20230502T151025/Courier0033.lzma.bak
2023-05-02T16:38:00.901Z,1683045480.901 [DataOverHttps](INFO): SBD MOMSN=18276906
2023-05-02T16:38:24.801Z,1683045504.801 [NAL9602](INFO): SBD MO Status=0, MOMSN=43343, MT Status=0, MTMSN=0
2023-05-02T16:38:24.801Z,1683045504.801 [NAL9602](INFO): No messages in MT queue
2023-05-02T16:38:32.448Z,1683045512.448 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2023-05-02T16:38:55.504Z,1683045535.504 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-02T16:39:03.572Z,1683045543.572 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-05-02T16:39:34.698Z,1683045574.698 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-05-02T16:40:05.859Z,1683045605.859 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-05-02T16:40:36.984Z,1683045636.984 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-05-02T16:41:01.108Z,1683045661.108 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.207245
2023-05-02T16:41:20.979Z,1683045680.979 [NAL9602](INFO): SBD MO Status=2, MOMSN=43344, MT Status=2, MTMSN=0
2023-05-02T16:41:20.979Z,1683045680.979 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-05-02T16:41:32.232Z,1683045692.232 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-05-02T16:41:50.938Z,1683045710.938 [NAL9602](INFO): SBD MO Status=1, MOMSN=43344, MT Status=0, MTMSN=0
2023-05-02T16:41:50.992Z,1683045710.992 [NAL9602](INFO): Sent 269 bytes from file Logs/20230502T163556/Courier0000.lzma
2023-05-02T16:41:50.992Z,1683045710.992 [NAL9602](INFO): Packets left to send: 0
2023-05-02T16:42:03.355Z,1683045723.355 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-05-02T16:42:07.146Z,1683045727.146 [NAL9602](INFO): SBD MO Status=1, MOMSN=43345, MT Status=0, MTMSN=0
2023-05-02T16:42:07.199Z,1683045727.199 [NAL9602](INFO): Sent 106 bytes from file Logs/20230502T151025/Express0034.lzma
2023-05-02T16:42:07.200Z,1683045727.200 [NAL9602](INFO): Packets left to send: 0
2023-05-02T16:42:18.526Z,1683045738.526 [NAL9602](INFO): SBD MO Status=1, MOMSN=43346, MT Status=0, MTMSN=0
2023-05-02T16:42:18.579Z,1683045738.579 [NAL9602](INFO): Sent 332 bytes from file Logs/20230502T163556/Express0001.lzma
2023-05-02T16:42:18.580Z,1683045738.580 [NAL9602](INFO): Packets left to send: 1
2023-05-02T16:42:29.472Z,1683045749.472 [NAL9602](INFO): SBD MO Status=1, MOMSN=43347, MT Status=0, MTMSN=0
2023-05-02T16:42:29.523Z,1683045749.523 [NAL9602](INFO): Sent 59 bytes from file Logs/20230502T163556/Express0001.lzma
2023-05-02T16:42:29.523Z,1683045749.523 [NAL9602](INFO): Packets left to send: 0
2023-05-02T16:42:34.480Z,1683045754.480 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-05-02T16:42:36.804Z,1683045756.804 [NAL9602](INFO): SBD MO Status=1, MOMSN=43348, MT Status=0, MTMSN=0
2023-05-02T16:42:36.856Z,1683045756.856 [NAL9602](INFO): Sent 63 bytes from file Logs/20230502T163556/Express0004.lzma
2023-05-02T16:42:36.856Z,1683045756.856 [NAL9602](INFO): Packets left to send: 0
2023-05-02T16:42:54.169Z,1683045774.169 [NAL9602](INFO): SBD MO Status=2, MOMSN=43349, MT Status=2, MTMSN=0
2023-05-02T16:42:54.169Z,1683045774.169 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-05-02T16:42:59.113Z,1683045779.113 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2023-05-02T16:43:05.619Z,1683045785.619 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-05-02T16:43:22.845Z,1683045802.845 [NAL9602](INFO): SBD MO Status=2, MOMSN=43349, MT Status=2, MTMSN=0
2023-05-02T16:43:22.845Z,1683045802.845 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-05-02T16:43:35.370Z,1683045815.370 [NAL9602](INFO): SBD MO Status=0, MOMSN=43349, MT Status=0, MTMSN=0
2023-05-02T16:43:35.663Z,1683045815.663 [Default:CheckIn:Read_Iridium] Stopped
2023-05-02T16:43:35.664Z,1683045815.664 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-02T16:43:35.664Z,1683045815.664 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-02T16:43:36.767Z,1683045816.767 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-05-02T16:44:06.071Z,1683045846.071 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-02T16:44:07.892Z,1683045847.892 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-05-02T16:44:39.019Z,1683045879.019 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-05-02T16:45:10.143Z,1683045910.143 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-05-02T16:45:41.283Z,1683045941.283 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-05-02T16:46:12.407Z,1683045972.407 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-05-02T16:46:43.555Z,1683046003.555 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-05-02T16:47:14.682Z,1683046034.682 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-05-02T16:47:45.803Z,1683046065.803 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-05-02T16:48:17.003Z,1683046097.003 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-05-02T16:48:35.966Z,1683046115.966 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-02T16:48:35.966Z,1683046115.966 [Default:CheckIn:C.Wait] Stopped
2023-05-02T16:48:35.966Z,1683046115.966 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-02T16:48:35.966Z,1683046115.966 [Default:CheckIn:D] Running Loop=1
2023-05-02T16:48:36.359Z,1683046116.359 [Default:CheckIn:D] Stopped
2023-05-02T16:48:36.359Z,1683046116.359 [Default:CheckIn:E] Running Loop=1
2023-05-02T16:48:36.769Z,1683046116.769 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 36.402319 min 
2023-05-02T16:48:36.769Z,1683046116.769 [Default:CheckIn:E] Stopped
2023-05-02T16:48:36.769Z,1683046116.769 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-02T16:48:36.769Z,1683046116.769 [Default:CheckIn] Stopped
2023-05-02T16:48:36.769Z,1683046116.769 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-02T16:48:36.769Z,1683046116.769 [Default:CheckIn](INFO): Running loop #6
2023-05-02T16:48:36.769Z,1683046116.769 [Default:CheckIn] Running Loop=6
2023-05-02T16:48:36.769Z,1683046116.769 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-02T16:48:36.769Z,1683046116.769 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-02T16:48:38.778Z,1683046118.778 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164837.00,A,3648.30488,N,12147.13601,W,4.413,185.64,020523,,,D*76
2023-05-02T16:48:38.784Z,1683046118.784 [NAL9602](INFO): GPS fix at 20230502T164837: (36.805081, -121.785600)
2023-05-02T16:48:38.794Z,1683046118.794 [Default:CheckIn:Read_GPS] Stopped
2023-05-02T16:48:38.794Z,1683046118.794 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-02T16:48:48.128Z,1683046128.128 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-05-02T16:48:52.574Z,1683046132.574 [NAL9602](INFO): SBD MO Status=1, MOMSN=43350, MT Status=0, MTMSN=0
2023-05-02T16:48:52.623Z,1683046132.623 [NAL9602](INFO): Sent 72 bytes from file Logs/20230502T163556/Courier0006.lzma
2023-05-02T16:48:52.624Z,1683046132.624 [NAL9602](INFO): Packets left to send: 0
2023-05-02T16:48:59.485Z,1683046139.485 [NAL9602](INFO): SBD MO Status=1, MOMSN=43351, MT Status=0, MTMSN=0
2023-05-02T16:48:59.543Z,1683046139.543 [NAL9602](INFO): Sent 144 bytes from file Logs/20230502T163556/Express0007.lzma
2023-05-02T16:48:59.544Z,1683046139.544 [NAL9602](INFO): Packets left to send: 0
2023-05-02T16:49:09.181Z,1683046149.181 [NAL9602](INFO): SBD MO Status=0, MOMSN=43352, MT Status=0, MTMSN=0
2023-05-02T16:49:09.304Z,1683046149.304 [Default:CheckIn:Read_Iridium] Stopped
2023-05-02T16:49:09.305Z,1683046149.305 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-02T16:49:09.305Z,1683046149.305 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-02T16:49:19.253Z,1683046159.253 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-05-02T16:49:39.896Z,1683046179.896 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-02T16:49:50.383Z,1683046190.383 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-05-02T16:49:58.692Z,1683046198.692 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003301
2023-05-02T16:54:09.840Z,1683046449.840 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-02T16:54:09.840Z,1683046449.840 [Default:CheckIn:C.Wait] Stopped
2023-05-02T16:54:09.840Z,1683046449.840 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-02T16:54:09.840Z,1683046449.840 [Default:CheckIn:D] Running Loop=1
2023-05-02T16:54:10.250Z,1683046450.250 [Default:CheckIn:D] Stopped
2023-05-02T16:54:10.250Z,1683046450.250 [Default:CheckIn:E] Running Loop=1
2023-05-02T16:54:10.637Z,1683046450.637 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 41.967159 min 
2023-05-02T16:54:10.637Z,1683046450.637 [Default:CheckIn:E] Stopped
2023-05-02T16:54:10.637Z,1683046450.637 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-02T16:54:10.637Z,1683046450.637 [Default:CheckIn] Stopped
2023-05-02T16:54:10.637Z,1683046450.637 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-02T16:54:10.637Z,1683046450.637 [Default:CheckIn](INFO): Running loop #7
2023-05-02T16:54:10.637Z,1683046450.637 [Default:CheckIn] Running Loop=7
2023-05-02T16:54:10.637Z,1683046450.637 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-02T16:54:10.637Z,1683046450.637 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-02T16:54:12.654Z,1683046452.654 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165411.00,A,3648.14947,N,12147.22310,W,0.019,0.00,020523,,,D*74
2023-05-02T16:54:12.657Z,1683046452.657 [NAL9602](INFO): GPS fix at 20230502T165411: (36.802491, -121.787052)
2023-05-02T16:54:12.666Z,1683046452.666 [Default:CheckIn:Read_GPS] Stopped
2023-05-02T16:54:12.667Z,1683046452.667 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-02T16:54:20.802Z,1683046460.802 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230502T163556/Courier0009.lzma
2023-05-02T16:54:21.805Z,1683046461.805 [DataOverHttps](INFO): Moved sent file to Logs/20230502T163556/Courier0009.lzma.bak
2023-05-02T16:54:21.805Z,1683046461.805 [DataOverHttps](INFO): SBD MOMSN=18277076
2023-05-02T16:54:40.035Z,1683046480.035 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20230502T163556/Express0010.lzma
2023-05-02T16:54:41.037Z,1683046481.037 [DataOverHttps](INFO): Moved sent file to Logs/20230502T163556/Express0010.lzma.bak
2023-05-02T16:54:41.037Z,1683046481.037 [DataOverHttps](INFO): SBD MOMSN=18277079
2023-05-02T16:54:41.362Z,1683046481.362 [NAL9602](INFO): SBD MO Status=2, MOMSN=43353, MT Status=2, MTMSN=0
2023-05-02T16:54:41.362Z,1683046481.362 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-05-02T16:54:42.181Z,1683046482.181 [Default:CheckIn:Read_Iridium] Stopped
2023-05-02T16:54:42.181Z,1683046482.181 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-02T16:54:42.181Z,1683046482.181 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-02T16:55:00.402Z,1683046500.402 [NAL9602](INFO): SBD MO Status=0, MOMSN=43353, MT Status=0, MTMSN=0
2023-05-02T16:55:00.402Z,1683046500.402 [NAL9602](INFO): No messages in MT queue
2023-05-02T16:55:31.100Z,1683046531.100 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-02T16:59:31.975Z,1683046771.975 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-05-02T16:59:42.916Z,1683046782.916 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-02T16:59:42.916Z,1683046782.916 [Default:CheckIn:C.Wait] Stopped
2023-05-02T16:59:42.916Z,1683046782.916 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-02T16:59:42.916Z,1683046782.916 [Default:CheckIn:D] Running Loop=1
2023-05-02T16:59:43.302Z,1683046783.302 [Default:CheckIn:D] Stopped
2023-05-02T16:59:43.302Z,1683046783.302 [Default:CheckIn:E] Running Loop=1
2023-05-02T16:59:43.706Z,1683046783.706 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 47.518034 min 
2023-05-02T16:59:43.706Z,1683046783.706 [Default:CheckIn:E] Stopped
2023-05-02T16:59:43.706Z,1683046783.706 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-02T16:59:43.706Z,1683046783.706 [Default:CheckIn] Stopped
2023-05-02T16:59:43.706Z,1683046783.706 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-02T16:59:43.706Z,1683046783.706 [Default:CheckIn](INFO): Running loop #8
2023-05-02T16:59:43.706Z,1683046783.706 [Default:CheckIn] Running Loop=8
2023-05-02T16:59:43.707Z,1683046783.707 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-02T16:59:43.707Z,1683046783.707 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-02T16:59:45.722Z,1683046785.722 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165944.00,A,3648.15992,N,12147.23749,W,2.371,18.30,020523,,,D*4C
2023-05-02T16:59:45.724Z,1683046785.724 [NAL9602](INFO): GPS fix at 20230502T165944: (36.802665, -121.787291)
2023-05-02T16:59:45.735Z,1683046785.735 [Default:CheckIn:Read_GPS] Stopped
2023-05-02T16:59:45.735Z,1683046785.735 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-02T16:59:46.922Z,1683046786.922 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-05-02T16:59:53.266Z,1683046793.266 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230502T163556/Courier0012.lzma
2023-05-02T16:59:54.269Z,1683046794.269 [DataOverHttps](INFO): Moved sent file to Logs/20230502T163556/Courier0012.lzma.bak
2023-05-02T16:59:54.269Z,1683046794.269 [DataOverHttps](INFO): SBD MOMSN=18277113
2023-05-02T17:00:00.258Z,1683046800.258 [NAL9602](INFO): SBD MO Status=2, MOMSN=43354, MT Status=2, MTMSN=0
2023-05-02T17:00:00.258Z,1683046800.258 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-05-02T17:00:01.869Z,1683046801.869 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-05-02T17:00:13.155Z,1683046813.155 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20230502T163556/Express0013.lzma
2023-05-02T17:00:14.157Z,1683046814.157 [DataOverHttps](INFO): Moved sent file to Logs/20230502T163556/Express0013.lzma.bak
2023-05-02T17:00:14.157Z,1683046814.157 [DataOverHttps](INFO): SBD MOMSN=18277116
2023-05-02T17:00:15.634Z,1683046815.634 [Default:CheckIn:Read_Iridium] Stopped
2023-05-02T17:00:15.635Z,1683046815.635 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-02T17:00:15.635Z,1683046815.635 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-02T17:00:16.817Z,1683046816.817 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-05-02T17:00:31.806Z,1683046831.806 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-05-02T17:00:47.157Z,1683046847.157 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-05-02T17:01:02.166Z,1683046862.166 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-05-02T17:01:17.114Z,1683046877.114 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-05-02T17:01:32.468Z,1683046892.468 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-05-02T17:01:46.605Z,1683046906.605 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2023-05-02T17:04:13.338Z,1683047053.338 [NAL9602](INFO): SBD MO Status=2, MOMSN=43354, MT Status=2, MTMSN=0
2023-05-02T17:04:13.338Z,1683047053.338 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-05-02T17:04:48.160Z,1683047088.160 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-05-02T17:05:16.098Z,1683047116.098 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-02T17:05:16.098Z,1683047116.098 [Default:CheckIn:C.Wait] Stopped
2023-05-02T17:05:16.098Z,1683047116.098 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-02T17:05:16.099Z,1683047116.099 [Default:CheckIn:D] Running Loop=1
2023-05-02T17:05:16.494Z,1683047116.494 [Default:CheckIn:D] Stopped
2023-05-02T17:05:16.494Z,1683047116.494 [Default:CheckIn:E] Running Loop=1
2023-05-02T17:05:16.894Z,1683047116.894 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 53.071220 min 
2023-05-02T17:05:16.894Z,1683047116.894 [Default:CheckIn:E] Stopped
2023-05-02T17:05:16.894Z,1683047116.894 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-02T17:05:16.894Z,1683047116.894 [Default:CheckIn] Stopped
2023-05-02T17:05:16.894Z,1683047116.894 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-02T17:05:16.894Z,1683047116.894 [Default:CheckIn](INFO): Running loop #9
2023-05-02T17:05:16.894Z,1683047116.894 [Default:CheckIn] Running Loop=9
2023-05-02T17:05:16.894Z,1683047116.894 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-02T17:05:16.895Z,1683047116.895 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-02T17:05:18.906Z,1683047118.906 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170517.00,A,3648.16687,N,12147.28149,W,0.369,193.98,020523,,,A*79
2023-05-02T17:05:18.920Z,1683047118.920 [NAL9602](INFO): GPS fix at 20230502T170517: (36.802781, -121.788025)
2023-05-02T17:05:18.930Z,1683047118.930 [Default:CheckIn:Read_GPS] Stopped
2023-05-02T17:05:18.930Z,1683047118.930 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-02T17:05:25.838Z,1683047125.838 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230502T163556/Courier0015.lzma
2023-05-02T17:05:26.841Z,1683047126.841 [DataOverHttps](INFO): Moved sent file to Logs/20230502T163556/Courier0015.lzma.bak
2023-05-02T17:05:26.842Z,1683047126.842 [DataOverHttps](INFO): SBD MOMSN=18277193
2023-05-02T17:05:42.803Z,1683047142.803 [DataOverHttps](INFO): Sending 242 bytes from file Logs/20230502T163556/Express0016.lzma
2023-05-02T17:05:43.805Z,1683047143.805 [DataOverHttps](INFO): Moved sent file to Logs/20230502T163556/Express0016.lzma.bak
2023-05-02T17:05:43.805Z,1683047143.805 [DataOverHttps](INFO): SBD MOMSN=18277196
2023-05-02T17:05:45.190Z,1683047145.190 [Default:CheckIn:Read_Iridium] Stopped
2023-05-02T17:05:45.190Z,1683047145.190 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-02T17:05:45.190Z,1683047145.190 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-02T17:05:51.224Z,1683047151.224 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-02T17:08:59.247Z,1683047339.247 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-05-02T17:09:06.114Z,1683047346.114 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-05-02T17:09:21.869Z,1683047361.869 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-05-02T17:09:37.224Z,1683047377.224 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-05-02T17:09:52.169Z,1683047392.169 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-05-02T17:10:07.117Z,1683047407.117 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-05-02T17:10:22.096Z,1683047422.096 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-05-02T17:10:37.486Z,1683047437.486 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-05-02T17:10:45.979Z,1683047445.979 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-02T17:10:45.979Z,1683047445.979 [Default:CheckIn:C.Wait] Stopped
2023-05-02T17:10:45.979Z,1683047445.979 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-02T17:10:45.980Z,1683047445.980 [Default:CheckIn:D] Running Loop=1
2023-05-02T17:10:46.376Z,1683047446.376 [Default:CheckIn:D] Stopped
2023-05-02T17:10:46.377Z,1683047446.377 [Default:CheckIn:E] Running Loop=1
2023-05-02T17:10:46.786Z,1683047446.786 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 58.569267 min 
2023-05-02T17:10:46.786Z,1683047446.786 [Default:CheckIn:E] Stopped
2023-05-02T17:10:46.786Z,1683047446.786 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-02T17:10:46.786Z,1683047446.786 [Default:CheckIn] Stopped
2023-05-02T17:10:46.787Z,1683047446.787 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-02T17:10:46.787Z,1683047446.787 [Default:CheckIn](INFO): Running loop #10
2023-05-02T17:10:46.787Z,1683047446.787 [Default:CheckIn] Running Loop=10
2023-05-02T17:10:46.787Z,1683047446.787 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-02T17:10:46.787Z,1683047446.787 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-02T17:10:48.820Z,1683047448.820 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171047.00,A,3648.16945,N,12147.28179,W,0.194,160.62,020523,,,A*73
2023-05-02T17:10:48.822Z,1683047448.822 [NAL9602](INFO): GPS fix at 20230502T171047: (36.802824, -121.788030)
2023-05-02T17:10:48.928Z,1683047448.928 [Default:CheckIn:Read_GPS] Stopped
2023-05-02T17:10:48.928Z,1683047448.928 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-02T17:10:52.529Z,1683047452.529 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-05-02T17:10:59.115Z,1683047459.115 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230502T163556/Courier0018.lzma
2023-05-02T17:11:00.117Z,1683047460.117 [DataOverHttps](INFO): Moved sent file to Logs/20230502T163556/Courier0018.lzma.bak
2023-05-02T17:11:00.117Z,1683047460.117 [DataOverHttps](INFO): SBD MOMSN=18277200
2023-05-02T17:11:07.529Z,1683047467.529 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-05-02T17:11:15.907Z,1683047475.907 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20230502T163556/Express0019.lzma
2023-05-02T17:11:16.909Z,1683047476.909 [DataOverHttps](INFO): Moved sent file to Logs/20230502T163556/Express0019.lzma.bak
2023-05-02T17:11:16.909Z,1683047476.909 [DataOverHttps](INFO): SBD MOMSN=18277203
2023-05-02T17:11:18.051Z,1683047478.051 [Default:CheckIn:Read_Iridium] Stopped
2023-05-02T17:11:18.051Z,1683047478.051 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-02T17:11:18.051Z,1683047478.051 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-02T17:11:19.252Z,1683047479.252 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-05-02T17:11:19.327Z,1683047479.327 [NAL9602](ERROR): received: 
+CSQ:0

OK354, 2, 0, 0, 0

OK
2023-05-02T17:15:50.965Z,1683047750.965 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-05-02T17:16:18.873Z,1683047778.873 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-02T17:16:18.873Z,1683047778.873 [Default:CheckIn:C.Wait] Stopped
2023-05-02T17:16:18.873Z,1683047778.873 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-02T17:16:18.873Z,1683047778.873 [Default:CheckIn:D] Running Loop=1
2023-05-02T17:16:19.367Z,1683047779.367 [Default:CheckIn:D] Stopped
2023-05-02T17:16:19.367Z,1683047779.367 [Default:CheckIn:E] Running Loop=1
2023-05-02T17:16:19.678Z,1683047779.678 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 64.119104 min 
2023-05-02T17:16:19.678Z,1683047779.678 [Default:CheckIn:E] Stopped
2023-05-02T17:16:19.678Z,1683047779.678 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-02T17:16:19.678Z,1683047779.678 [Default:CheckIn] Stopped
2023-05-02T17:16:19.678Z,1683047779.678 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-02T17:16:19.678Z,1683047779.678 [Default:CheckIn](INFO): Running loop #11
2023-05-02T17:16:19.678Z,1683047779.678 [Default:CheckIn] Running Loop=11
2023-05-02T17:16:19.679Z,1683047779.679 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-02T17:16:19.679Z,1683047779.679 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-02T17:16:21.671Z,1683047781.671 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171620.00,A,3648.16581,N,12147.28322,W,0.330,73.17,020523,,,A*41
2023-05-02T17:16:21.673Z,1683047781.673 [NAL9602](INFO): GPS fix at 20230502T171620: (36.802763, -121.788054)
2023-05-02T17:16:21.684Z,1683047781.684 [Default:CheckIn:Read_GPS] Stopped
2023-05-02T17:16:21.684Z,1683047781.684 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-02T17:16:31.643Z,1683047791.643 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230502T163556/Courier0021.lzma
2023-05-02T17:16:32.645Z,1683047792.645 [DataOverHttps](INFO): Moved sent file to Logs/20230502T163556/Courier0021.lzma.bak
2023-05-02T17:16:32.645Z,1683047792.645 [DataOverHttps](INFO): SBD MOMSN=18277282
2023-05-02T17:16:48.523Z,1683047808.523 [DataOverHttps](INFO): Sending 204 bytes from file Logs/20230502T163556/Express0022.lzma
2023-05-02T17:16:49.527Z,1683047809.527 [DataOverHttps](INFO): Moved sent file to Logs/20230502T163556/Express0022.lzma.bak
2023-05-02T17:16:49.527Z,1683047809.527 [DataOverHttps](INFO): SBD MOMSN=18277285
2023-05-02T17:16:50.824Z,1683047810.824 [Default:CheckIn:Read_Iridium] Stopped
2023-05-02T17:16:50.824Z,1683047810.824 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-02T17:16:50.825Z,1683047810.825 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-02T17:16:54.493Z,1683047814.493 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-02T17:18:30.658Z,1683047910.658 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-05-02T17:18:45.610Z,1683047925.610 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-05-02T17:18:46.821Z,1683047926.821 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-05-02T17:18:46.848Z,1683047926.848 [BPC1](INFO): Calculating totals. Valid battery stick count: 43. Valid reserve battery stick count: 5.
2023-05-02T17:18:46.850Z,1683047926.850 [BPC1](INFO): Received data from all battery sticks.
2023-05-02T17:21:51.554Z,1683048111.554 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-02T17:21:51.554Z,1683048111.554 [Default:CheckIn:C.Wait] Stopped
2023-05-02T17:21:51.554Z,1683048111.554 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-02T17:21:51.555Z,1683048111.555 [Default:CheckIn:D] Running Loop=1
2023-05-02T17:21:51.970Z,1683048111.970 [Default:CheckIn:D] Stopped
2023-05-02T17:21:51.970Z,1683048111.970 [Default:CheckIn:E] Running Loop=1
2023-05-02T17:21:52.366Z,1683048112.366 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 69.662500 min 
2023-05-02T17:21:52.366Z,1683048112.366 [Default:CheckIn:E] Stopped
2023-05-02T17:21:52.366Z,1683048112.366 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-02T17:21:52.366Z,1683048112.366 [Default:CheckIn] Stopped
2023-05-02T17:21:52.366Z,1683048112.366 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-02T17:21:52.366Z,1683048112.366 [Default:CheckIn](INFO): Running loop #12
2023-05-02T17:21:52.366Z,1683048112.366 [Default:CheckIn] Running Loop=12
2023-05-02T17:21:52.367Z,1683048112.367 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-02T17:21:52.367Z,1683048112.367 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-02T17:21:54.374Z,1683048114.374 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172152.00,A,3648.16778,N,12147.28211,W,0.156,73.17,020523,,,A*47
2023-05-02T17:21:54.376Z,1683048114.376 [NAL9602](INFO): GPS fix at 20230502T172152: (36.802796, -121.788035)
2023-05-02T17:21:54.386Z,1683048114.386 [Default:CheckIn:Read_GPS] Stopped
2023-05-02T17:21:54.387Z,1683048114.387 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-02T17:22:02.163Z,1683048122.163 [DataOverHttps](INFO): Sending 201 bytes from file Logs/20230502T163556/Courier0024.lzma
2023-05-02T17:22:03.165Z,1683048123.165 [DataOverHttps](INFO): Moved sent file to Logs/20230502T163556/Courier0024.lzma.bak
2023-05-02T17:22:03.165Z,1683048123.165 [DataOverHttps](INFO): SBD MOMSN=18277290
2023-05-02T17:22:19.851Z,1683048139.851 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230502T163556/Express0025.lzma
2023-05-02T17:22:20.854Z,1683048140.854 [DataOverHttps](INFO): Moved sent file to Logs/20230502T163556/Express0025.lzma.bak
2023-05-02T17:22:20.854Z,1683048140.854 [DataOverHttps](INFO): SBD MOMSN=18277293
2023-05-02T17:22:22.288Z,1683048142.288 [Default:CheckIn:Read_Iridium] Stopped
2023-05-02T17:22:22.288Z,1683048142.288 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-02T17:22:22.288Z,1683048142.288 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-02T17:22:25.083Z,1683048145.083 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-05-02T17:22:25.159Z,1683048145.159 [NAL9602](ERROR): received: 
+CSQ:0

OK354, 2, 0, 0, 0

OK
2023-05-02T17:26:57.442Z,1683048417.442 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-05-02T17:27:22.905Z,1683048442.905 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-02T17:27:22.906Z,1683048442.906 [Default:CheckIn:C.Wait] Stopped
2023-05-02T17:27:22.906Z,1683048442.906 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-02T17:27:22.906Z,1683048442.906 [Default:CheckIn:D] Running Loop=1
2023-05-02T17:27:23.319Z,1683048443.319 [Default:CheckIn:D] Stopped
2023-05-02T17:27:23.319Z,1683048443.319 [Default:CheckIn:E] Running Loop=1
2023-05-02T17:27:23.707Z,1683048443.707 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 75.184969 min 
2023-05-02T17:27:23.707Z,1683048443.707 [Default:CheckIn:E] Stopped
2023-05-02T17:27:23.707Z,1683048443.707 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-02T17:27:23.707Z,1683048443.707 [Default:CheckIn] Stopped
2023-05-02T17:27:23.707Z,1683048443.707 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-02T17:27:23.708Z,1683048443.708 [Default:CheckIn](INFO): Running loop #13
2023-05-02T17:27:23.708Z,1683048443.708 [Default:CheckIn] Running Loop=13
2023-05-02T17:27:23.708Z,1683048443.708 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-02T17:27:23.708Z,1683048443.708 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-02T17:27:24.531Z,1683048444.531 [CBIT](IMPORTANT): Beginning ground fault scan
2023-05-02T17:27:25.722Z,1683048445.722 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172724.00,A,3648.16126,N,12147.28455,W,0.156,73.17,020523,,,A*4B
2023-05-02T17:27:25.724Z,1683048445.724 [NAL9602](INFO): GPS fix at 20230502T172724: (36.802688, -121.788076)
2023-05-02T17:27:25.734Z,1683048445.734 [Default:CheckIn:Read_GPS] Stopped
2023-05-02T17:27:25.734Z,1683048445.734 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-02T17:27:27.393Z,1683048447.393 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2023-05-02T17:27:33.078Z,1683048453.078 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230502T163556/Courier0027.lzma
2023-05-02T17:27:34.081Z,1683048454.081 [DataOverHttps](INFO): Moved sent file to Logs/20230502T163556/Courier0027.lzma.bak
2023-05-02T17:27:34.081Z,1683048454.081 [DataOverHttps](INFO): SBD MOMSN=18277366
2023-05-02T17:27:35.448Z,1683048455.448 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.002117
CHAN A1 (24V) N/A reporting off
CHAN A2 (12V): -0.002450
CHAN A3 (5V): -0.001819
CHAN B0 (3.3V): -0.000339
CHAN B1 (3.15aV): -0.000181
CHAN B2 (3.15bV): -0.000005
CHAN B3 (GND): 0.000043
OPEN: -0.000388
Full Scale: +/- 1 mA
2023-05-02T17:27:49.963Z,1683048469.963 [DataOverHttps](INFO): Sending 249 bytes from file Logs/20230502T163556/Express0028.lzma
2023-05-02T17:27:50.965Z,1683048470.965 [DataOverHttps](INFO): Moved sent file to Logs/20230502T163556/Express0028.lzma.bak
2023-05-02T17:27:50.965Z,1683048470.965 [DataOverHttps](INFO): SBD MOMSN=18277370
2023-05-02T17:27:52.404Z,1683048472.404 [Default:CheckIn:Read_Iridium] Stopped
2023-05-02T17:27:52.404Z,1683048472.404 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-02T17:27:52.404Z,1683048472.404 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-02T17:27:58.444Z,1683048478.444 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-02T17:32:53.028Z,1683048773.028 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-02T17:32:53.028Z,1683048773.028 [Default:CheckIn:C.Wait] Stopped
2023-05-02T17:32:53.028Z,1683048773.028 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-02T17:32:53.028Z,1683048773.028 [Default:CheckIn:D] Running Loop=1
2023-05-02T17:32:53.417Z,1683048773.417 [Default:CheckIn:D] Stopped
2023-05-02T17:32:53.417Z,1683048773.417 [Default:CheckIn:E] Running Loop=1
2023-05-02T17:32:53.830Z,1683048773.830 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 80.686621 min 
2023-05-02T17:32:53.831Z,1683048773.831 [Default:CheckIn:E] Stopped
2023-05-02T17:32:53.831Z,1683048773.831 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-02T17:32:53.831Z,1683048773.831 [Default:CheckIn] Stopped
2023-05-02T17:32:53.831Z,1683048773.831 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-02T17:32:53.831Z,1683048773.831 [Default:CheckIn](INFO): Running loop #14
2023-05-02T17:32:53.831Z,1683048773.831 [Default:CheckIn] Running Loop=14
2023-05-02T17:32:53.831Z,1683048773.831 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-02T17:32:53.831Z,1683048773.831 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-02T17:32:55.840Z,1683048775.840 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173254.00,A,3648.16501,N,12147.28681,W,0.369,0.00,020523,,,A*7E
2023-05-02T17:32:55.842Z,1683048775.842 [NAL9602](INFO): GPS fix at 20230502T173254: (36.802750, -121.788113)
2023-05-02T17:32:55.899Z,1683048775.899 [Default:CheckIn:Read_GPS] Stopped
2023-05-02T17:32:55.900Z,1683048775.900 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-02T17:33:03.643Z,1683048783.643 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230502T163556/Courier0030.lzma
2023-05-02T17:33:04.645Z,1683048784.645 [DataOverHttps](INFO): Moved sent file to Logs/20230502T163556/Courier0030.lzma.bak
2023-05-02T17:33:04.645Z,1683048784.645 [DataOverHttps](INFO): SBD MOMSN=18277375
2023-05-02T17:33:20.887Z,1683048800.887 [DataOverHttps](INFO): Sending 297 bytes from file Logs/20230502T163556/Express0031.lzma
2023-05-02T17:33:21.889Z,1683048801.889 [DataOverHttps](INFO): Moved sent file to Logs/20230502T163556/Express0031.lzma.bak
2023-05-02T17:33:21.889Z,1683048801.889 [DataOverHttps](INFO): SBD MOMSN=18277378
2023-05-02T17:33:23.329Z,1683048803.329 [Default:CheckIn:Read_Iridium] Stopped
2023-05-02T17:33:23.329Z,1683048803.329 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-02T17:33:23.329Z,1683048803.329 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-02T17:33:26.537Z,1683048806.537 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-05-02T17:33:26.615Z,1683048806.615 [NAL9602](ERROR): received: 
+CSQ:0

OK354, 2, 0, 0, 0

OK
2023-05-02T17:37:58.036Z,1683049078.036 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-05-02T17:38:23.897Z,1683049103.897 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-02T17:38:23.897Z,1683049103.897 [Default:CheckIn:C.Wait] Stopped
2023-05-02T17:38:23.897Z,1683049103.897 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-02T17:38:23.897Z,1683049103.897 [Default:CheckIn:D] Running Loop=1
2023-05-02T17:38:24.301Z,1683049104.301 [Default:CheckIn:D] Stopped
2023-05-02T17:38:24.301Z,1683049104.301 [Default:CheckIn:E] Running Loop=1
2023-05-02T17:38:24.706Z,1683049104.706 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 86.201343 min 
2023-05-02T17:38:24.706Z,1683049104.706 [Default:CheckIn:E] Stopped
2023-05-02T17:38:24.706Z,1683049104.706 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-02T17:38:24.706Z,1683049104.706 [Default:CheckIn] Stopped
2023-05-02T17:38:24.707Z,1683049104.707 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-02T17:38:24.707Z,1683049104.707 [Default:CheckIn](INFO): Running loop #15
2023-05-02T17:38:24.707Z,1683049104.707 [Default:CheckIn] Running Loop=15
2023-05-02T17:38:24.707Z,1683049104.707 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-02T17:38:24.707Z,1683049104.707 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-02T17:38:26.722Z,1683049106.722 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173825.00,A,3648.16868,N,12147.28266,W,0.330,41.66,020523,,,A*44
2023-05-02T17:38:26.724Z,1683049106.724 [NAL9602](INFO): GPS fix at 20230502T173825: (36.802811, -121.788044)
2023-05-02T17:38:26.753Z,1683049106.753 [Default:CheckIn:Read_GPS] Stopped
2023-05-02T17:38:26.753Z,1683049106.753 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-02T17:38:33.342Z,1683049113.342 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230502T163556/Courier0033.lzma
2023-05-02T17:38:34.341Z,1683049114.341 [DataOverHttps](INFO): Moved sent file to Logs/20230502T163556/Courier0033.lzma.bak
2023-05-02T17:38:34.341Z,1683049114.341 [DataOverHttps](INFO): SBD MOMSN=18277452
2023-05-02T17:38:50.975Z,1683049130.975 [DataOverHttps](INFO): Sending 204 bytes from file Logs/20230502T163556/Express0034.lzma
2023-05-02T17:38:51.977Z,1683049131.977 [DataOverHttps](INFO): Moved sent file to Logs/20230502T163556/Express0034.lzma.bak
2023-05-02T17:38:51.977Z,1683049131.977 [DataOverHttps](INFO): SBD MOMSN=18277455
2023-05-02T17:38:53.418Z,1683049133.418 [Default:CheckIn:Read_Iridium] Stopped
2023-05-02T17:38:53.418Z,1683049133.418 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-02T17:38:53.418Z,1683049133.418 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-02T17:38:59.476Z,1683049139.476 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-02T17:43:54.016Z,1683049434.016 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-02T17:43:54.017Z,1683049434.017 [Default:CheckIn:C.Wait] Stopped
2023-05-02T17:43:54.017Z,1683049434.017 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-02T17:43:54.017Z,1683049434.017 [Default:CheckIn:D] Running Loop=1
2023-05-02T17:43:54.426Z,1683049434.426 [Default:CheckIn:D] Stopped
2023-05-02T17:43:54.426Z,1683049434.426 [Default:CheckIn:E] Running Loop=1
2023-05-02T17:43:54.834Z,1683049434.834 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 91.703418 min 
2023-05-02T17:43:54.834Z,1683049434.834 [Default:CheckIn:E] Stopped
2023-05-02T17:43:54.834Z,1683049434.834 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-02T17:43:54.834Z,1683049434.834 [Default:CheckIn] Stopped
2023-05-02T17:43:54.834Z,1683049434.834 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-02T17:43:54.834Z,1683049434.834 [Default:CheckIn](INFO): Running loop #16
2023-05-02T17:43:54.834Z,1683049434.834 [Default:CheckIn] Running Loop=16
2023-05-02T17:43:54.835Z,1683049434.835 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-02T17:43:54.835Z,1683049434.835 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-02T17:43:56.842Z,1683049436.842 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174355.00,A,3648.16922,N,12147.28000,W,0.058,41.66,020523,,,A*4F
2023-05-02T17:43:56.844Z,1683049436.844 [NAL9602](INFO): GPS fix at 20230502T174355: (36.802820, -121.788000)
2023-05-02T17:43:56.854Z,1683049436.854 [Default:CheckIn:Read_GPS] Stopped
2023-05-02T17:43:56.854Z,1683049436.854 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-02T17:44:04.002Z,1683049444.002 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230502T163556/Courier0036.lzma
2023-05-02T17:44:05.005Z,1683049445.005 [DataOverHttps](INFO): Moved sent file to Logs/20230502T163556/Courier0036.lzma.bak
2023-05-02T17:44:05.005Z,1683049445.005 [DataOverHttps](INFO): SBD MOMSN=18277459
2023-05-02T17:44:22.895Z,1683049462.895 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20230502T163556/Express0037.lzma
2023-05-02T17:44:23.897Z,1683049463.897 [DataOverHttps](INFO): Moved sent file to Logs/20230502T163556/Express0037.lzma.bak
2023-05-02T17:44:23.897Z,1683049463.897 [DataOverHttps](INFO): SBD MOMSN=18277462
2023-05-02T17:44:25.180Z,1683049465.180 [Default:CheckIn:Read_Iridium] Stopped
2023-05-02T17:44:25.180Z,1683049465.180 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-02T17:44:25.180Z,1683049465.180 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-02T17:44:27.588Z,1683049467.588 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-05-02T17:44:27.663Z,1683049467.663 [NAL9602](ERROR): received: 
+CSQ:0

OK354, 2, 0, 0, 0

OK
2023-05-02T17:48:59.068Z,1683049739.068 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-05-02T17:49:25.739Z,1683049765.739 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-02T17:49:25.739Z,1683049765.739 [Default:CheckIn:C.Wait] Stopped
2023-05-02T17:49:25.739Z,1683049765.739 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-02T17:49:25.739Z,1683049765.739 [Default:CheckIn:D] Running Loop=1
2023-05-02T17:49:26.153Z,1683049766.153 [Default:CheckIn:D] Stopped
2023-05-02T17:49:26.153Z,1683049766.153 [Default:CheckIn:E] Running Loop=1
2023-05-02T17:49:26.550Z,1683049766.550 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 97.232210 min 
2023-05-02T17:49:26.550Z,1683049766.550 [Default:CheckIn:E] Stopped
2023-05-02T17:49:26.550Z,1683049766.550 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-02T17:49:26.550Z,1683049766.550 [Default:CheckIn] Stopped
2023-05-02T17:49:26.550Z,1683049766.550 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-02T17:49:26.550Z,1683049766.550 [Default:CheckIn](INFO): Running loop #17
2023-05-02T17:49:26.550Z,1683049766.550 [Default:CheckIn] Running Loop=17
2023-05-02T17:49:26.551Z,1683049766.551 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-02T17:49:26.551Z,1683049766.551 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-02T17:49:28.558Z,1683049768.558 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174927.00,A,3648.16891,N,12147.28551,W,0.175,41.66,020523,,,A*46
2023-05-02T17:49:28.560Z,1683049768.560 [NAL9602](INFO): GPS fix at 20230502T174927: (36.802815, -121.788092)
2023-05-02T17:49:28.580Z,1683049768.580 [Default:CheckIn:Read_GPS] Stopped
2023-05-02T17:49:28.580Z,1683049768.580 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-02T17:49:36.342Z,1683049776.342 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230502T163556/Courier0039.lzma
2023-05-02T17:49:37.345Z,1683049777.345 [DataOverHttps](INFO): Moved sent file to Logs/20230502T163556/Courier0039.lzma.bak
2023-05-02T17:49:37.345Z,1683049777.345 [DataOverHttps](INFO): SBD MOMSN=18277537
2023-05-02T17:49:53.535Z,1683049793.535 [DataOverHttps](INFO): Sending 205 bytes from file Logs/20230502T163556/Express0040.lzma
2023-05-02T17:49:54.537Z,1683049794.537 [DataOverHttps](INFO): Moved sent file to Logs/20230502T163556/Express0040.lzma.bak
2023-05-02T17:49:54.537Z,1683049794.537 [DataOverHttps](INFO): SBD MOMSN=18277540
2023-05-02T17:49:56.048Z,1683049796.048 [Default:CheckIn:Read_Iridium] Stopped
2023-05-02T17:49:56.048Z,1683049796.048 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-02T17:49:56.048Z,1683049796.048 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-02T17:50:01.279Z,1683049801.279 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-02T17:54:56.632Z,1683050096.632 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-02T17:54:56.632Z,1683050096.632 [Default:CheckIn:C.Wait] Stopped
2023-05-02T17:54:56.632Z,1683050096.632 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-02T17:54:56.633Z,1683050096.633 [Default:CheckIn:D] Running Loop=1
2023-05-02T17:54:57.025Z,1683050097.025 [Default:CheckIn:D] Stopped
2023-05-02T17:54:57.025Z,1683050097.025 [Default:CheckIn:E] Running Loop=1
2023-05-02T17:54:57.434Z,1683050097.434 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 102.746737 min 
2023-05-02T17:54:57.434Z,1683050097.434 [Default:CheckIn:E] Stopped
2023-05-02T17:54:57.434Z,1683050097.434 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-02T17:54:57.434Z,1683050097.434 [Default:CheckIn] Stopped
2023-05-02T17:54:57.434Z,1683050097.434 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-02T17:54:57.434Z,1683050097.434 [Default:CheckIn](INFO): Running loop #18
2023-05-02T17:54:57.434Z,1683050097.434 [Default:CheckIn] Running Loop=18
2023-05-02T17:54:57.435Z,1683050097.435 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-02T17:54:57.435Z,1683050097.435 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-02T17:54:59.523Z,1683050099.523 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175457.00,A,3648.17299,N,12147.28185,W,1.341,0.00,020523,,,A*72
2023-05-02T17:54:59.533Z,1683050099.533 [NAL9602](INFO): GPS fix at 20230502T175457: (36.802883, -121.788031)
2023-05-02T17:54:59.563Z,1683050099.563 [Default:CheckIn:Read_GPS] Stopped
2023-05-02T17:54:59.576Z,1683050099.576 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-02T17:55:07.026Z,1683050107.026 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230502T163556/Courier0042.lzma
2023-05-02T17:55:08.029Z,1683050108.029 [DataOverHttps](INFO): Moved sent file to Logs/20230502T163556/Courier0042.lzma.bak
2023-05-02T17:55:08.029Z,1683050108.029 [DataOverHttps](INFO): SBD MOMSN=18277547
2023-05-02T17:55:23.747Z,1683050123.747 [DataOverHttps](INFO): Sending 135 bytes from file Logs/20230502T163556/Express0043.lzma
2023-05-02T17:55:24.749Z,1683050124.749 [DataOverHttps](INFO): Moved sent file to Logs/20230502T163556/Express0043.lzma.bak
2023-05-02T17:55:24.749Z,1683050124.749 [DataOverHttps](INFO): SBD MOMSN=18277556
2023-05-02T17:55:26.120Z,1683050126.120 [Default:CheckIn:Read_Iridium] Stopped
2023-05-02T17:55:26.120Z,1683050126.120 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-02T17:55:26.120Z,1683050126.120 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-02T17:55:30.145Z,1683050130.145 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-05-02T17:55:30.223Z,1683050130.223 [NAL9602](ERROR): received: 
+CSQ:0

OK354, 2, 0, 0, 0

OK
2023-05-02T17:56:27.112Z,1683050187.112 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2023-05-02T17:56:27.112Z,1683050187.112 [DropWeight] Hardware Fault, FailCount= 1
2023-05-02T17:56:27.112Z,1683050187.112 [DropWeight](ERROR): Hardware Fault
2023-05-02T17:56:27.175Z,1683050187.175 [CommandExec](FAULT): Scheduling is paused
2023-05-02T17:56:27.175Z,1683050187.175 [CBIT](INFO): Critical error at 20230502T175627
2023-05-02T17:56:27.178Z,1683050187.178 [CBIT](ERROR): Hardware Fault in component: DropWeight
2023-05-02T17:56:27.178Z,1683050187.178 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2023-05-02T17:56:27.531Z,1683050187.531 [CBIT](INFO): Critical error at 20230502T175627
2023-05-02T18:00:02.048Z,1683050402.048 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-05-02T18:00:26.697Z,1683050426.697 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-02T18:00:26.697Z,1683050426.697 [Default:CheckIn:C.Wait] Stopped
2023-05-02T18:00:26.697Z,1683050426.697 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-02T18:00:26.697Z,1683050426.697 [Default:CheckIn:D] Running Loop=1
2023-05-02T18:00:27.108Z,1683050427.108 [Default:CheckIn:D] Stopped
2023-05-02T18:00:27.108Z,1683050427.108 [Default:CheckIn:E] Running Loop=1
2023-05-02T18:00:27.507Z,1683050427.507 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 108.248120 min 
2023-05-02T18:00:27.507Z,1683050427.507 [Default:CheckIn:E] Stopped
2023-05-02T18:00:27.507Z,1683050427.507 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-02T18:00:27.508Z,1683050427.508 [Default:CheckIn] Stopped
2023-05-02T18:00:27.508Z,1683050427.508 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-02T18:00:27.508Z,1683050427.508 [Default:CheckIn](INFO): Running loop #19
2023-05-02T18:00:27.508Z,1683050427.508 [Default:CheckIn] Running Loop=19
2023-05-02T18:00:27.508Z,1683050427.508 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-02T18:00:27.508Z,1683050427.508 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-02T18:00:29.518Z,1683050429.518 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180027.00,A,3648.16963,N,12147.28014,W,1.225,314.22,020523,,,A*78
2023-05-02T18:00:29.520Z,1683050429.520 [NAL9602](INFO): GPS fix at 20230502T180027: (36.802827, -121.788002)
2023-05-02T18:00:29.530Z,1683050429.530 [Default:CheckIn:Read_GPS] Stopped
2023-05-02T18:00:29.530Z,1683050429.530 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-02T18:00:36.723Z,1683050436.723 [DataOverHttps](INFO): Sending 177 bytes from file Logs/20230502T163556/Courier0045.lzma
2023-05-02T18:00:37.725Z,1683050437.725 [DataOverHttps](INFO): Moved sent file to Logs/20230502T163556/Courier0045.lzma.bak
2023-05-02T18:00:37.725Z,1683050437.725 [DataOverHttps](INFO): SBD MOMSN=18277649
2023-05-02T18:00:56.075Z,1683050456.075 [DataOverHttps](INFO): Sending 228 bytes from file Logs/20230502T163556/Express0046.lzma
2023-05-02T18:00:57.085Z,1683050457.085 [DataOverHttps](INFO): Moved sent file to Logs/20230502T163556/Express0046.lzma.bak
2023-05-02T18:00:57.085Z,1683050457.085 [DataOverHttps](INFO): SBD MOMSN=18277657
2023-05-02T18:00:58.683Z,1683050458.683 [Default:CheckIn:Read_Iridium] Stopped
2023-05-02T18:00:58.683Z,1683050458.683 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-02T18:00:58.683Z,1683050458.683 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-02T18:01:02.296Z,1683050462.296 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-02T18:05:59.302Z,1683050759.302 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-02T18:05:59.302Z,1683050759.302 [Default:CheckIn:C.Wait] Stopped
2023-05-02T18:05:59.302Z,1683050759.302 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-02T18:05:59.303Z,1683050759.303 [Default:CheckIn:D] Running Loop=1
2023-05-02T18:05:59.711Z,1683050759.711 [Default:CheckIn:D] Stopped
2023-05-02T18:05:59.711Z,1683050759.711 [Default:CheckIn:E] Running Loop=1
2023-05-02T18:06:00.105Z,1683050760.105 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 113.791512 min 
2023-05-02T18:06:00.105Z,1683050760.105 [Default:CheckIn:E] Stopped
2023-05-02T18:06:00.105Z,1683050760.105 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-02T18:06:00.105Z,1683050760.105 [Default:CheckIn] Stopped
2023-05-02T18:06:00.105Z,1683050760.105 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-02T18:06:00.105Z,1683050760.105 [Default:CheckIn](INFO): Running loop #20
2023-05-02T18:06:00.105Z,1683050760.105 [Default:CheckIn] Running Loop=20
2023-05-02T18:06:00.105Z,1683050760.105 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-02T18:06:00.105Z,1683050760.105 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-02T18:06:02.122Z,1683050762.122 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180600.00,A,3648.16681,N,12147.28184,W,0.311,314.22,020523,,,A*77
2023-05-02T18:06:02.124Z,1683050762.124 [NAL9602](INFO): GPS fix at 20230502T180600: (36.802780, -121.788031)
2023-05-02T18:06:02.134Z,1683050762.134 [Default:CheckIn:Read_GPS] Stopped
2023-05-02T18:06:02.134Z,1683050762.134 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-02T18:06:09.543Z,1683050769.543 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230502T163556/Courier0048.lzma
2023-05-02T18:06:10.545Z,1683050770.545 [DataOverHttps](INFO): Moved sent file to Logs/20230502T163556/Courier0048.lzma.bak
2023-05-02T18:06:10.545Z,1683050770.545 [DataOverHttps](INFO): SBD MOMSN=18277745
2023-05-02T18:06:29.175Z,1683050789.175 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230502T163556/Express0049.lzma
2023-05-02T18:06:30.177Z,1683050790.177 [DataOverHttps](INFO): Moved sent file to Logs/20230502T163556/Express0049.lzma.bak
2023-05-02T18:06:30.177Z,1683050790.177 [DataOverHttps](INFO): SBD MOMSN=18277749
2023-05-02T18:06:31.686Z,1683050791.686 [Default:CheckIn:Read_Iridium] Stopped
2023-05-02T18:06:31.687Z,1683050791.687 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-02T18:06:31.687Z,1683050791.687 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-02T18:06:32.883Z,1683050792.883 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-05-02T18:06:32.959Z,1683050792.959 [NAL9602](ERROR): received: 
+CSQ:0

OK354, 2, 0, 0, 0

OK
2023-05-02T18:07:08.849Z,1683050828.849 [NAL9602](INFO): SBD MO Status=2, MOMSN=43354, MT Status=2, MTMSN=0
2023-05-02T18:07:08.850Z,1683050828.850 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-05-02T18:07:50.862Z,1683050870.862 [NAL9602](INFO): SBD MO Status=2, MOMSN=43354, MT Status=2, MTMSN=0
2023-05-02T18:07:50.862Z,1683050870.862 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-05-02T18:11:04.528Z,1683051064.528 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-05-02T18:11:27.466Z,1683051087.466 [CBIT](INFO): Clearing failed state for component DropWeight
2023-05-02T18:11:27.466Z,1683051087.466 [DropWeight] No Fault, FailCount= 1
2023-05-02T18:11:32.281Z,1683051092.281 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-02T18:11:32.281Z,1683051092.281 [Default:CheckIn:C.Wait] Stopped
2023-05-02T18:11:32.281Z,1683051092.281 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-02T18:11:32.282Z,1683051092.282 [Default:CheckIn:D] Running Loop=1
2023-05-02T18:11:32.694Z,1683051092.694 [Default:CheckIn:D] Stopped
2023-05-02T18:11:32.694Z,1683051092.694 [Default:CheckIn:E] Running Loop=1
2023-05-02T18:11:33.095Z,1683051093.095 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 119.341227 min 
2023-05-02T18:11:33.095Z,1683051093.095 [Default:CheckIn:E] Stopped
2023-05-02T18:11:33.095Z,1683051093.095 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-02T18:11:33.095Z,1683051093.095 [Default:CheckIn] Stopped
2023-05-02T18:11:33.095Z,1683051093.095 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-02T18:11:33.095Z,1683051093.095 [Default:CheckIn](INFO): Running loop #21
2023-05-02T18:11:33.096Z,1683051093.096 [Default:CheckIn] Running Loop=21
2023-05-02T18:11:33.096Z,1683051093.096 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-02T18:11:33.096Z,1683051093.096 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-02T18:11:35.118Z,1683051095.118 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181133.00,A,3648.17279,N,12147.28169,W,0.311,0.00,020523,,,A*76
2023-05-02T18:11:35.121Z,1683051095.121 [NAL9602](INFO): GPS fix at 20230502T181133: (36.802880, -121.788028)
2023-05-02T18:11:35.131Z,1683051095.131 [Default:CheckIn:Read_GPS] Stopped
2023-05-02T18:11:35.131Z,1683051095.131 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-02T18:11:42.534Z,1683051102.534 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230502T163556/Courier0051.lzma
2023-05-02T18:11:43.537Z,1683051103.537 [DataOverHttps](INFO): Moved sent file to Logs/20230502T163556/Courier0051.lzma.bak
2023-05-02T18:11:43.537Z,1683051103.537 [DataOverHttps](INFO): SBD MOMSN=18277765
2023-05-02T18:11:59.303Z,1683051119.303 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20230502T163556/Express0052.lzma
2023-05-02T18:12:00.307Z,1683051120.307 [DataOverHttps](INFO): Moved sent file to Logs/20230502T163556/Express0052.lzma.bak
2023-05-02T18:12:00.307Z,1683051120.307 [DataOverHttps](INFO): SBD MOMSN=18277771
2023-05-02T18:12:01.786Z,1683051121.786 [Default:CheckIn:Read_Iridium] Stopped
2023-05-02T18:12:01.786Z,1683051121.786 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-02T18:12:01.786Z,1683051121.786 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-02T18:12:07.424Z,1683051127.424 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-02T18:17:02.364Z,1683051422.364 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-02T18:17:02.364Z,1683051422.364 [Default:CheckIn:C.Wait] Stopped
2023-05-02T18:17:02.365Z,1683051422.365 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-02T18:17:02.365Z,1683051422.365 [Default:CheckIn:D] Running Loop=1
2023-05-02T18:17:02.758Z,1683051422.758 [Default:CheckIn:D] Stopped
2023-05-02T18:17:02.758Z,1683051422.758 [Default:CheckIn:E] Running Loop=1
2023-05-02T18:17:03.157Z,1683051423.157 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 124.842285 min 
2023-05-02T18:17:03.157Z,1683051423.157 [Default:CheckIn:E] Stopped
2023-05-02T18:17:03.157Z,1683051423.157 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-02T18:17:03.157Z,1683051423.157 [Default:CheckIn] Stopped
2023-05-02T18:17:03.157Z,1683051423.157 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-02T18:17:03.158Z,1683051423.158 [Default:CheckIn](INFO): Running loop #22
2023-05-02T18:17:03.158Z,1683051423.158 [Default:CheckIn] Running Loop=22
2023-05-02T18:17:03.158Z,1683051423.158 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-02T18:17:03.158Z,1683051423.158 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-02T18:17:05.170Z,1683051425.170 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181703.00,A,3648.15158,N,12147.28931,W,1.166,228.75,020523,,,A*7D
2023-05-02T18:17:05.172Z,1683051425.172 [NAL9602](INFO): GPS fix at 20230502T181703: (36.802526, -121.788155)
2023-05-02T18:17:05.200Z,1683051425.200 [Default:CheckIn:Read_GPS] Stopped
2023-05-02T18:17:05.200Z,1683051425.200 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-02T18:17:12.542Z,1683051432.542 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230502T163556/Courier0054.lzma
2023-05-02T18:17:13.545Z,1683051433.545 [DataOverHttps](INFO): Moved sent file to Logs/20230502T163556/Courier0054.lzma.bak
2023-05-02T18:17:13.545Z,1683051433.545 [DataOverHttps](INFO): SBD MOMSN=18277896
2023-05-02T18:17:29.387Z,1683051449.387 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20230502T163556/Express0055.lzma
2023-05-02T18:17:30.391Z,1683051450.391 [DataOverHttps](INFO): Moved sent file to Logs/20230502T163556/Express0055.lzma.bak
2023-05-02T18:17:30.392Z,1683051450.392 [DataOverHttps](INFO): SBD MOMSN=18277899
2023-05-02T18:17:31.855Z,1683051451.855 [Default:CheckIn:Read_Iridium] Stopped
2023-05-02T18:17:31.855Z,1683051451.855 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-02T18:17:31.855Z,1683051451.855 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-02T18:17:35.873Z,1683051455.873 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-05-02T18:17:35.947Z,1683051455.947 [NAL9602](ERROR): received: 
+CSQ:0

OK354, 2, 0, 0, 0

OK
2023-05-02T18:21:54.629Z,1683051714.629 [CommandExec](IMPORTANT): got command quit
2023-05-02T18:21:55.631Z,1683051715.631 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-02T18:21:55.632Z,1683051715.632 [CommandExec](INFO): Uninitializing the command executive.
2023-05-02T18:21:55.632Z,1683051715.632 [CommandExec](INFO): Uninitializing the command scheduler.
2023-05-02T18:21:55.632Z,1683051715.632 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-05-02T18:21:55.755Z,1683051715.755 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2023-05-02T18:21:55.755Z,1683051715.755 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-05-02T18:21:55.756Z,1683051715.756 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-05-02T18:21:55.758Z,1683051715.758 [NavChartDb](INFO): Join timeout helper Thread ID is 6541
2023-05-02T18:21:56.040Z,1683051716.040 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-02T18:21:56.041Z,1683051716.041 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-05-02T18:21:56.059Z,1683051716.059 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-05-02T18:21:56.059Z,1683051716.059 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-05-02T18:21:56.060Z,1683051716.060 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 6542
2023-05-02T18:21:56.420Z,1683051716.420 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-02T18:21:56.420Z,1683051716.420 [WetLabsBB2FL](INFO): Powering down
2023-05-02T18:21:56.421Z,1683051716.421 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-05-02T18:21:56.427Z,1683051716.427 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-05-02T18:21:56.427Z,1683051716.427 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-05-02T18:21:56.428Z,1683051716.428 [CTD_Seabird](INFO): Join timeout helper Thread ID is 6543
2023-05-02T18:21:56.487Z,1683051716.487 [CTD_Seabird](INFO): Powering down
2023-05-02T18:21:56.499Z,1683051716.499 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-02T18:21:56.500Z,1683051716.500 [CTD_Seabird](INFO): Powering down
2023-05-02T18:21:56.511Z,1683051716.511 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-05-02T18:21:56.521Z,1683051716.521 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-05-02T18:21:56.522Z,1683051716.522 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-05-02T18:21:56.522Z,1683051716.522 [Radio_Surface](INFO): Join timeout helper Thread ID is 6544
2023-05-02T18:21:56.648Z,1683051716.648 [Radio_Surface](INFO): Powering down
2023-05-02T18:21:56.649Z,1683051716.649 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-02T18:21:56.649Z,1683051716.649 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-05-02T18:21:56.660Z,1683051716.660 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-05-02T18:21:56.660Z,1683051716.660 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-05-02T18:21:56.661Z,1683051716.661 [Onboard](INFO): Join timeout helper Thread ID is 6545
2023-05-02T18:21:56.991Z,1683051716.991 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2023-05-02T18:21:59.532Z,1683051719.532 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-02T18:21:59.532Z,1683051719.532 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-05-02T18:21:59.536Z,1683051719.536 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-05-02T18:21:59.536Z,1683051719.536 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-05-02T18:21:59.540Z,1683051719.540 [DataOverHttps](INFO): Join timeout helper Thread ID is 6546
2023-05-02T18:22:00.003Z,1683051720.003 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-02T18:22:00.004Z,1683051720.004 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-05-02T18:22:00.016Z,1683051720.016 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-05-02T18:22:00.016Z,1683051720.016 [logger ThreadHandler](INFO): Thread cancelled.
2023-05-02T18:22:00.017Z,1683051720.017 [logger](INFO): Join timeout helper Thread ID is 6547
2023-05-02T18:22:00.053Z,1683051720.053 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-02T18:22:00.054Z,1683051720.054 [logger ThreadHandler](INFO): Thread cancelled.
2023-05-02T18:22:00.056Z,1683051720.056 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-05-02T18:22:00.056Z,1683051720.056 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-05-02T18:22:00.056Z,1683051720.056 [CommandLine](INFO): Join timeout helper Thread ID is 6548
2023-05-02T18:22:00.143Z,1683051720.143 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-02T18:22:00.144Z,1683051720.144 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-05-02T18:22:00.160Z,1683051720.160 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-05-02T18:22:00.160Z,1683051720.160 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-05-02T18:22:00.160Z,1683051720.160 [CommandExec](INFO): Join timeout helper Thread ID is 6549
2023-05-02T18:22:00.172Z,1683051720.172 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-05-02T18:22:00.172Z,1683051720.172 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-05-02T18:22:00.172Z,1683051720.172 [controlThread](INFO): Join timeout helper Thread ID is 6550
2023-05-02T18:22:00.178Z,1683051720.178 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-02T18:22:00.178Z,1683051720.178 [controlThread](DEBUG): Uninitializing ControlThread
2023-05-02T18:22:00.178Z,1683051720.178 [AHRS_M2](INFO): Powering down
2023-05-02T18:22:00.252Z,1683051720.252 [NAL9602](INFO): Powering down
2023-05-02T18:22:00.255Z,1683051720.255 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-05-02T18:22:00.256Z,1683051720.256 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-05-02T18:22:00.256Z,1683051720.256 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-05-02T18:22:00.257Z,1683051720.257 [MissionManager](INFO): Uninitializing Mission Default
2023-05-02T18:22:00.257Z,1683051720.257 [Default] Stopped
2023-05-02T18:22:00.257Z,1683051720.257 [Default](DEBUG): Aggregate::uninitialize Default
2023-05-02T18:22:00.257Z,1683051720.257 [Default:B.GoToSurface] Stopped
2023-05-02T18:22:00.257Z,1683051720.257 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-05-02T18:22:00.257Z,1683051720.257 [Default:CheckIn] Stopped
2023-05-02T18:22:00.257Z,1683051720.257 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-02T18:22:00.257Z,1683051720.257 [Default:CheckIn:C.Wait] Stopped
2023-05-02T18:22:00.257Z,1683051720.257 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-02T18:22:00.260Z,1683051720.260 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2023-05-02T18:22:00.260Z,1683051720.260 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2023-05-02T18:22:00.261Z,1683051720.261 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2023-05-02T18:22:00.261Z,1683051720.261 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2023-05-02T18:22:00.261Z,1683051720.261 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2023-05-02T18:22:00.261Z,1683051720.261 [BuoyancyServo](INFO): Powering down
2023-05-02T18:22:00.275Z,1683051720.275 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-05-02T18:22:00.275Z,1683051720.275 [ElevatorServo](INFO): Powering down
2023-05-02T18:22:00.276Z,1683051720.276 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-05-02T18:22:00.276Z,1683051720.276 [MassServo](INFO): Powering down
2023-05-02T18:22:00.277Z,1683051720.277 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-05-02T18:22:00.277Z,1683051720.277 [RudderServo](INFO): Powering down
2023-05-02T18:22:00.278Z,1683051720.278 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2023-05-02T18:22:00.278Z,1683051720.278 [ThrusterServo](INFO): Powering down
2023-05-02T18:22:00.279Z,1683051720.279 [SBIT](DEBUG): Uninitialize SBIT Component.
2023-05-02T18:22:00.280Z,1683051720.280 [IBIT](DEBUG): Uninitialize IBIT Component.
2023-05-02T18:22:00.280Z,1683051720.280 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-05-02T18:22:00.280Z,1683051720.280 [CBIT](DEBUG): Powering off loads.
2023-05-02T18:22:00.291Z,1683051720.291 [CBIT](DEBUG): Disabling WDT.
2023-05-02T18:22:00.303Z,1683051720.303 [CBIT](DEBUG): Opening all GF detection circuits.
2023-05-02T18:22:00.304Z,1683051720.304 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-05-02T18:22:00.362Z,1683051720.362 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-05-02T18:22:00.365Z,1683051720.365 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-05-02T18:22:00.372Z,1683051720.372 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-05-02T18:22:00.443Z,1683051720.443 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-05-02T18:22:00.446Z,1683051720.446 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-05-02T18:22:00.487Z,1683051720.487 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-05-02T18:22:00.565Z,1683051720.565 [logger ThreadHandler](INFO): Thread cancelled.