2022-06-08T17:48:06.677Z,1654710486.677 [CommandExec](IMPORTANT): got command restart logs
2022-06-08T17:51:51.444Z,1654710711.444 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-08T17:51:51.444Z,1654710711.444 [Default:CheckIn:C.Wait] Stopped
2022-06-08T17:51:51.444Z,1654710711.444 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-08T17:51:51.445Z,1654710711.445 [Default:CheckIn:D] Running Loop=1
2022-06-08T17:51:51.842Z,1654710711.842 [Default:CheckIn:D] Stopped
2022-06-08T17:51:51.842Z,1654710711.842 [Default:CheckIn:E] Running Loop=1
2022-06-08T17:51:52.255Z,1654710712.255 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 74.415942 min 
2022-06-08T17:51:52.255Z,1654710712.255 [Default:CheckIn:E] Stopped
2022-06-08T17:51:52.255Z,1654710712.255 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-08T17:51:52.255Z,1654710712.255 [Default:CheckIn] Stopped
2022-06-08T17:51:52.255Z,1654710712.255 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-08T17:51:52.255Z,1654710712.255 [Default:CheckIn](INFO): Running loop #14
2022-06-08T17:51:52.256Z,1654710712.256 [Default:CheckIn] Running Loop=14
2022-06-08T17:51:52.256Z,1654710712.256 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-08T17:51:52.256Z,1654710712.256 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-08T17:51:54.263Z,1654710714.263 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175153.00,A,3648.14312,N,12147.22863,W,0.058,229.28,080622,,,D*7D
2022-06-08T17:51:54.265Z,1654710714.265 [NAL9602](INFO): GPS fix at 20220608T175153: (36.802385, -121.787144)
2022-06-08T17:51:54.294Z,1654710714.294 [Default:CheckIn:Read_GPS] Stopped
2022-06-08T17:51:54.294Z,1654710714.294 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-08T17:52:01.651Z,1654710721.651 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220608T163436/Courier0043.lzma
2022-06-08T17:52:02.654Z,1654710722.654 [DataOverHttps](INFO): Moved sent file to Logs/20220608T163436/Courier0043.lzma.bak
2022-06-08T17:52:02.654Z,1654710722.654 [DataOverHttps](INFO): SBD MOMSN=16872386
2022-06-08T17:52:12.847Z,1654710732.847 [NAL9602](INFO): SBD MO Status=0, MOMSN=33435, MT Status=0, MTMSN=0
2022-06-08T17:52:12.847Z,1654710732.847 [NAL9602](INFO): No messages in MT queue
2022-06-08T17:52:19.385Z,1654710739.385 [DataOverHttps](INFO): Sending 269 bytes from file Logs/20220608T174806/Courier0000.lzma
2022-06-08T17:52:20.386Z,1654710740.386 [DataOverHttps](INFO): Moved sent file to Logs/20220608T174806/Courier0000.lzma.bak
2022-06-08T17:52:20.386Z,1654710740.386 [DataOverHttps](INFO): SBD MOMSN=16872388
2022-06-08T17:52:39.147Z,1654710759.147 [DataOverHttps](INFO): Sending 138 bytes from file Logs/20220608T163436/Express0044.lzma
2022-06-08T17:52:40.150Z,1654710760.150 [DataOverHttps](INFO): Moved sent file to Logs/20220608T163436/Express0044.lzma.bak
2022-06-08T17:52:40.150Z,1654710760.150 [DataOverHttps](INFO): SBD MOMSN=16872392
2022-06-08T17:52:43.593Z,1654710763.593 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-08T17:52:59.580Z,1654710779.580 [DataOverHttps](INFO): Sending 380 bytes from file Logs/20220608T174806/Express0001.lzma
2022-06-08T17:53:00.582Z,1654710780.582 [DataOverHttps](INFO): Moved sent file to Logs/20220608T174806/Express0001.lzma.bak
2022-06-08T17:53:00.582Z,1654710780.582 [DataOverHttps](INFO): SBD MOMSN=16872400
2022-06-08T17:53:16.515Z,1654710796.515 [DataOverHttps](INFO): Sending 52 bytes from file Logs/20220608T174806/Express0004.lzma
2022-06-08T17:53:17.518Z,1654710797.518 [DataOverHttps](INFO): Moved sent file to Logs/20220608T174806/Express0004.lzma.bak
2022-06-08T17:53:17.518Z,1654710797.518 [DataOverHttps](INFO): SBD MOMSN=16872407
2022-06-08T17:53:18.789Z,1654710798.789 [Default:CheckIn:Read_Iridium] Stopped
2022-06-08T17:53:18.789Z,1654710798.789 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-08T17:53:18.789Z,1654710798.789 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-08T17:58:19.436Z,1654711099.436 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-08T17:58:19.436Z,1654711099.436 [Default:CheckIn:C.Wait] Stopped
2022-06-08T17:58:19.436Z,1654711099.436 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-08T17:58:19.437Z,1654711099.437 [Default:CheckIn:D] Running Loop=1
2022-06-08T17:58:19.811Z,1654711099.811 [Default:CheckIn:D] Stopped
2022-06-08T17:58:19.811Z,1654711099.811 [Default:CheckIn:E] Running Loop=1
2022-06-08T17:58:20.225Z,1654711100.225 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 80.882096 min 
2022-06-08T17:58:20.225Z,1654711100.225 [Default:CheckIn:E] Stopped
2022-06-08T17:58:20.225Z,1654711100.225 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-08T17:58:20.225Z,1654711100.225 [Default:CheckIn] Stopped
2022-06-08T17:58:20.225Z,1654711100.225 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-08T17:58:20.225Z,1654711100.225 [Default:CheckIn](INFO): Running loop #15
2022-06-08T17:58:20.225Z,1654711100.225 [Default:CheckIn] Running Loop=15
2022-06-08T17:58:20.225Z,1654711100.225 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-08T17:58:20.226Z,1654711100.226 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-08T17:58:22.227Z,1654711102.227 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175821.00,A,3648.17312,N,12147.28083,W,0.194,288.03,080622,,,D*7D
2022-06-08T17:58:22.270Z,1654711102.270 [NAL9602](INFO): GPS fix at 20220608T175821: (36.802885, -121.788014)
2022-06-08T17:58:22.297Z,1654711102.297 [Default:CheckIn:Read_GPS] Stopped
2022-06-08T17:58:22.297Z,1654711102.297 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-08T17:58:31.451Z,1654711111.451 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220608T174806/Courier0006.lzma
2022-06-08T17:58:32.454Z,1654711112.454 [DataOverHttps](INFO): Moved sent file to Logs/20220608T174806/Courier0006.lzma.bak
2022-06-08T17:58:32.454Z,1654711112.454 [DataOverHttps](INFO): SBD MOMSN=16872418
2022-06-08T17:58:48.640Z,1654711128.640 [DataOverHttps](INFO): Sending 146 bytes from file Logs/20220608T174806/Express0007.lzma
2022-06-08T17:58:49.642Z,1654711129.642 [DataOverHttps](INFO): Moved sent file to Logs/20220608T174806/Express0007.lzma.bak
2022-06-08T17:58:49.642Z,1654711129.642 [DataOverHttps](INFO): SBD MOMSN=16872421
2022-06-08T17:58:50.937Z,1654711130.937 [Default:CheckIn:Read_Iridium] Stopped
2022-06-08T17:58:50.937Z,1654711130.937 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-08T17:58:50.937Z,1654711130.937 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-08T18:03:24.493Z,1654711404.493 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-06-08T18:03:51.581Z,1654711431.581 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-08T18:03:51.581Z,1654711431.581 [Default:CheckIn:C.Wait] Stopped
2022-06-08T18:03:51.581Z,1654711431.581 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-08T18:03:51.581Z,1654711431.581 [Default:CheckIn:D] Running Loop=1
2022-06-08T18:03:51.975Z,1654711431.975 [Default:CheckIn:D] Stopped
2022-06-08T18:03:51.975Z,1654711431.975 [Default:CheckIn:E] Running Loop=1
2022-06-08T18:03:52.374Z,1654711432.374 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 86.418164 min 
2022-06-08T18:03:52.374Z,1654711432.374 [Default:CheckIn:E] Stopped
2022-06-08T18:03:52.374Z,1654711432.374 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-08T18:03:52.375Z,1654711432.375 [Default:CheckIn] Stopped
2022-06-08T18:03:52.375Z,1654711432.375 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-08T18:03:52.375Z,1654711432.375 [Default:CheckIn](INFO): Running loop #16
2022-06-08T18:03:52.375Z,1654711432.375 [Default:CheckIn] Running Loop=16
2022-06-08T18:03:52.375Z,1654711432.375 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-08T18:03:52.375Z,1654711432.375 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-08T18:03:54.393Z,1654711434.393 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180353.00,A,3648.16483,N,12147.27342,W,0.350,38.21,080622,,,D*45
2022-06-08T18:03:54.395Z,1654711434.395 [NAL9602](INFO): GPS fix at 20220608T180353: (36.802747, -121.787890)
2022-06-08T18:03:54.412Z,1654711434.412 [Default:CheckIn:Read_GPS] Stopped
2022-06-08T18:03:54.413Z,1654711434.413 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-08T18:04:02.583Z,1654711442.583 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220608T174806/Courier0009.lzma
2022-06-08T18:04:03.586Z,1654711443.586 [DataOverHttps](INFO): Moved sent file to Logs/20220608T174806/Courier0009.lzma.bak
2022-06-08T18:04:03.586Z,1654711443.586 [DataOverHttps](INFO): SBD MOMSN=16872430
2022-06-08T18:04:21.428Z,1654711461.428 [DataOverHttps](INFO): Sending 229 bytes from file Logs/20220608T174806/Express0010.lzma
2022-06-08T18:04:22.430Z,1654711462.430 [DataOverHttps](INFO): Moved sent file to Logs/20220608T174806/Express0010.lzma.bak
2022-06-08T18:04:22.430Z,1654711462.430 [DataOverHttps](INFO): SBD MOMSN=16872439
2022-06-08T18:04:23.911Z,1654711463.911 [Default:CheckIn:Read_Iridium] Stopped
2022-06-08T18:04:23.912Z,1654711463.912 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-08T18:04:23.912Z,1654711463.912 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-08T18:04:26.349Z,1654711466.349 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-08T18:09:24.511Z,1654711764.511 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-08T18:09:24.511Z,1654711764.511 [Default:CheckIn:C.Wait] Stopped
2022-06-08T18:09:24.511Z,1654711764.511 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-08T18:09:24.511Z,1654711764.511 [Default:CheckIn:D] Running Loop=1
2022-06-08T18:09:24.909Z,1654711764.909 [Default:CheckIn:D] Stopped
2022-06-08T18:09:24.909Z,1654711764.909 [Default:CheckIn:E] Running Loop=1
2022-06-08T18:09:25.304Z,1654711765.304 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 91.967057 min 
2022-06-08T18:09:25.304Z,1654711765.304 [Default:CheckIn:E] Stopped
2022-06-08T18:09:25.304Z,1654711765.304 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-08T18:09:25.304Z,1654711765.304 [Default:CheckIn] Stopped
2022-06-08T18:09:25.304Z,1654711765.304 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-08T18:09:25.305Z,1654711765.305 [Default:CheckIn](INFO): Running loop #17
2022-06-08T18:09:25.305Z,1654711765.305 [Default:CheckIn] Running Loop=17
2022-06-08T18:09:25.305Z,1654711765.305 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-08T18:09:25.305Z,1654711765.305 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-08T18:09:27.312Z,1654711767.312 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180926.00,A,3648.16797,N,12147.28293,W,0.389,38.21,080622,,,D*4D
2022-06-08T18:09:27.314Z,1654711767.314 [NAL9602](INFO): GPS fix at 20220608T180926: (36.802799, -121.788049)
2022-06-08T18:09:27.326Z,1654711767.326 [Default:CheckIn:Read_GPS] Stopped
2022-06-08T18:09:27.326Z,1654711767.326 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-08T18:09:34.756Z,1654711774.756 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220608T174806/Courier0012.lzma
2022-06-08T18:09:35.758Z,1654711775.758 [DataOverHttps](INFO): Moved sent file to Logs/20220608T174806/Courier0012.lzma.bak
2022-06-08T18:09:35.758Z,1654711775.758 [DataOverHttps](INFO): SBD MOMSN=16872445
2022-06-08T18:09:52.003Z,1654711792.003 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20220608T174806/Express0013.lzma
2022-06-08T18:09:53.002Z,1654711793.002 [DataOverHttps](INFO): Moved sent file to Logs/20220608T174806/Express0013.lzma.bak
2022-06-08T18:09:53.002Z,1654711793.002 [DataOverHttps](INFO): SBD MOMSN=16872458
2022-06-08T18:09:54.415Z,1654711794.415 [Default:CheckIn:Read_Iridium] Stopped
2022-06-08T18:09:54.415Z,1654711794.415 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-08T18:09:54.415Z,1654711794.415 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-08T18:10:43.762Z,1654711843.762 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-08T18:10:59.119Z,1654711859.119 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-08T18:11:14.871Z,1654711874.871 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-08T18:11:30.223Z,1654711890.223 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-08T18:11:45.171Z,1654711905.171 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-08T18:12:00.123Z,1654711920.123 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-08T18:12:15.469Z,1654711935.469 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-08T18:12:30.821Z,1654711950.821 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-08T18:12:46.186Z,1654711966.186 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-08T18:13:00.795Z,1654711980.795 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-06-08T18:14:29.304Z,1654712069.304 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-06-08T18:14:55.151Z,1654712095.151 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-08T18:14:55.152Z,1654712095.152 [Default:CheckIn:C.Wait] Stopped
2022-06-08T18:14:55.152Z,1654712095.152 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-08T18:14:55.152Z,1654712095.152 [Default:CheckIn:D] Running Loop=1
2022-06-08T18:14:55.547Z,1654712095.547 [Default:CheckIn:D] Stopped
2022-06-08T18:14:55.547Z,1654712095.547 [Default:CheckIn:E] Running Loop=1
2022-06-08T18:14:55.951Z,1654712095.951 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 97.477694 min 
2022-06-08T18:14:55.951Z,1654712095.951 [Default:CheckIn:E] Stopped
2022-06-08T18:14:55.951Z,1654712095.951 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-08T18:14:55.952Z,1654712095.952 [Default:CheckIn] Stopped
2022-06-08T18:14:55.952Z,1654712095.952 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-08T18:14:55.952Z,1654712095.952 [Default:CheckIn](INFO): Running loop #18
2022-06-08T18:14:55.952Z,1654712095.952 [Default:CheckIn] Running Loop=18
2022-06-08T18:14:55.952Z,1654712095.952 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-08T18:14:55.952Z,1654712095.952 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-08T18:14:57.979Z,1654712097.979 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181457.00,A,3648.16529,N,12147.28949,W,0.564,323.40,080622,,,D*77
2022-06-08T18:14:57.981Z,1654712097.981 [NAL9602](INFO): GPS fix at 20220608T181457: (36.802755, -121.788158)
2022-06-08T18:14:57.992Z,1654712097.992 [Default:CheckIn:Read_GPS] Stopped
2022-06-08T18:14:57.992Z,1654712097.992 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-08T18:15:05.678Z,1654712105.678 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220608T174806/Courier0015.lzma
2022-06-08T18:15:06.678Z,1654712106.678 [DataOverHttps](INFO): Moved sent file to Logs/20220608T174806/Courier0015.lzma.bak
2022-06-08T18:15:06.678Z,1654712106.678 [DataOverHttps](INFO): SBD MOMSN=16872477
2022-06-08T18:15:23.032Z,1654712123.032 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20220608T174806/Express0016.lzma
2022-06-08T18:15:24.034Z,1654712124.034 [DataOverHttps](INFO): Moved sent file to Logs/20220608T174806/Express0016.lzma.bak
2022-06-08T18:15:24.034Z,1654712124.034 [DataOverHttps](INFO): SBD MOMSN=16872484
2022-06-08T18:15:25.455Z,1654712125.455 [Default:CheckIn:Read_Iridium] Stopped
2022-06-08T18:15:25.455Z,1654712125.455 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-08T18:15:25.455Z,1654712125.455 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-08T18:15:29.977Z,1654712129.977 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-08T18:20:18.334Z,1654712418.334 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-08T18:20:26.039Z,1654712426.039 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-08T18:20:26.039Z,1654712426.039 [Default:CheckIn:C.Wait] Stopped
2022-06-08T18:20:26.039Z,1654712426.039 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-08T18:20:26.039Z,1654712426.039 [Default:CheckIn:D] Running Loop=1
2022-06-08T18:20:26.440Z,1654712426.440 [Default:CheckIn:D] Stopped
2022-06-08T18:20:26.440Z,1654712426.440 [Default:CheckIn:E] Running Loop=1
2022-06-08T18:20:26.835Z,1654712426.835 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 102.992578 min 
2022-06-08T18:20:26.835Z,1654712426.835 [Default:CheckIn:E] Stopped
2022-06-08T18:20:26.835Z,1654712426.835 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-08T18:20:26.835Z,1654712426.835 [Default:CheckIn] Stopped
2022-06-08T18:20:26.835Z,1654712426.835 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-08T18:20:26.836Z,1654712426.836 [Default:CheckIn](INFO): Running loop #19
2022-06-08T18:20:26.836Z,1654712426.836 [Default:CheckIn] Running Loop=19
2022-06-08T18:20:26.836Z,1654712426.836 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-08T18:20:26.836Z,1654712426.836 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-08T18:20:28.847Z,1654712428.847 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182028.00,A,3648.16757,N,12147.28583,W,0.214,98.20,080622,,,D*4C
2022-06-08T18:20:28.849Z,1654712428.849 [NAL9602](INFO): GPS fix at 20220608T182028: (36.802793, -121.788097)
2022-06-08T18:20:28.860Z,1654712428.860 [Default:CheckIn:Read_GPS] Stopped
2022-06-08T18:20:28.860Z,1654712428.860 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-08T18:20:34.493Z,1654712434.493 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-08T18:20:36.635Z,1654712436.635 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220608T174806/Courier0018.lzma
2022-06-08T18:20:37.638Z,1654712437.638 [DataOverHttps](INFO): Moved sent file to Logs/20220608T174806/Courier0018.lzma.bak
2022-06-08T18:20:37.638Z,1654712437.638 [DataOverHttps](INFO): SBD MOMSN=16872556
2022-06-08T18:20:50.656Z,1654712450.656 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-08T18:20:56.783Z,1654712456.783 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20220608T174806/Express0019.lzma
2022-06-08T18:20:57.786Z,1654712457.786 [DataOverHttps](INFO): Moved sent file to Logs/20220608T174806/Express0019.lzma.bak
2022-06-08T18:20:57.786Z,1654712457.786 [DataOverHttps](INFO): SBD MOMSN=16872563
2022-06-08T18:20:59.217Z,1654712459.217 [Default:CheckIn:Read_Iridium] Stopped
2022-06-08T18:20:59.217Z,1654712459.217 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-08T18:20:59.217Z,1654712459.217 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-08T18:20:59.594Z,1654712459.594 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-06-08T18:20:59.676Z,1654712459.676 [NAL9602](ERROR): received: 
+CSQ:0

OK435, 0, 0, 0, 0

OK
2022-06-08T18:21:05.650Z,1654712465.650 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-08T18:21:20.598Z,1654712480.598 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-08T18:21:35.546Z,1654712495.546 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-08T18:21:50.494Z,1654712510.494 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-08T18:22:05.845Z,1654712525.845 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-08T18:22:20.794Z,1654712540.794 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-08T18:22:35.749Z,1654712555.749 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2022-06-08T18:25:31.521Z,1654712731.521 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-06-08T18:25:59.799Z,1654712759.799 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-08T18:25:59.799Z,1654712759.799 [Default:CheckIn:C.Wait] Stopped
2022-06-08T18:25:59.799Z,1654712759.799 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-08T18:25:59.799Z,1654712759.799 [Default:CheckIn:D] Running Loop=1
2022-06-08T18:26:00.219Z,1654712760.219 [Default:CheckIn:D] Stopped
2022-06-08T18:26:00.219Z,1654712760.219 [Default:CheckIn:E] Running Loop=1
2022-06-08T18:26:00.633Z,1654712760.633 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 108.555566 min 
2022-06-08T18:26:00.633Z,1654712760.633 [Default:CheckIn:E] Stopped
2022-06-08T18:26:00.634Z,1654712760.634 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-08T18:26:00.634Z,1654712760.634 [Default:CheckIn] Stopped
2022-06-08T18:26:00.634Z,1654712760.634 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-08T18:26:00.634Z,1654712760.634 [Default:CheckIn](INFO): Running loop #20
2022-06-08T18:26:00.634Z,1654712760.634 [Default:CheckIn] Running Loop=20
2022-06-08T18:26:00.634Z,1654712760.634 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-08T18:26:00.634Z,1654712760.634 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-08T18:26:02.615Z,1654712762.615 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182602.00,A,3648.16491,N,12147.27878,W,0.700,45.11,080622,,,D*4F
2022-06-08T18:26:02.617Z,1654712762.617 [NAL9602](INFO): GPS fix at 20220608T182602: (36.802748, -121.787980)
2022-06-08T18:26:02.628Z,1654712762.628 [Default:CheckIn:Read_GPS] Stopped
2022-06-08T18:26:02.628Z,1654712762.628 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-08T18:26:10.303Z,1654712770.303 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220608T174806/Courier0021.lzma
2022-06-08T18:26:11.306Z,1654712771.306 [DataOverHttps](INFO): Moved sent file to Logs/20220608T174806/Courier0021.lzma.bak
2022-06-08T18:26:11.306Z,1654712771.306 [DataOverHttps](INFO): SBD MOMSN=16872569
2022-06-08T18:26:30.920Z,1654712790.920 [DataOverHttps](INFO): Sending 204 bytes from file Logs/20220608T174806/Express0022.lzma
2022-06-08T18:26:31.922Z,1654712791.922 [DataOverHttps](INFO): Moved sent file to Logs/20220608T174806/Express0022.lzma.bak
2022-06-08T18:26:31.922Z,1654712791.922 [DataOverHttps](INFO): SBD MOMSN=16872578
2022-06-08T18:26:33.400Z,1654712793.400 [Default:CheckIn:Read_Iridium] Stopped
2022-06-08T18:26:33.400Z,1654712793.400 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-08T18:26:33.400Z,1654712793.400 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-08T18:26:35.409Z,1654712795.409 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-08T18:29:45.286Z,1654712985.286 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-08T18:29:52.155Z,1654712992.155 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-08T18:30:07.507Z,1654713007.507 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-08T18:30:22.463Z,1654713022.463 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-08T18:30:37.818Z,1654713037.818 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-08T18:30:52.763Z,1654713052.763 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-08T18:31:08.116Z,1654713068.116 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-08T18:31:23.062Z,1654713083.062 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-08T18:31:34.016Z,1654713094.016 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-08T18:31:34.017Z,1654713094.017 [Default:CheckIn:C.Wait] Stopped
2022-06-08T18:31:34.017Z,1654713094.017 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-08T18:31:34.017Z,1654713094.017 [Default:CheckIn:D] Running Loop=1
2022-06-08T18:31:34.396Z,1654713094.396 [Default:CheckIn:D] Stopped
2022-06-08T18:31:34.396Z,1654713094.396 [Default:CheckIn:E] Running Loop=1
2022-06-08T18:31:34.806Z,1654713094.806 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 114.125171 min 
2022-06-08T18:31:34.806Z,1654713094.806 [Default:CheckIn:E] Stopped
2022-06-08T18:31:34.806Z,1654713094.806 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-08T18:31:34.806Z,1654713094.806 [Default:CheckIn] Stopped
2022-06-08T18:31:34.806Z,1654713094.806 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-08T18:31:34.806Z,1654713094.806 [Default:CheckIn](INFO): Running loop #21
2022-06-08T18:31:34.806Z,1654713094.806 [Default:CheckIn] Running Loop=21
2022-06-08T18:31:34.806Z,1654713094.806 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-08T18:31:34.807Z,1654713094.807 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-08T18:31:36.807Z,1654713096.807 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183136.00,A,3648.16954,N,12147.29308,W,0.136,143.81,080622,,,D*75
2022-06-08T18:31:36.809Z,1654713096.809 [NAL9602](INFO): GPS fix at 20220608T183136: (36.802826, -121.788218)
2022-06-08T18:31:36.838Z,1654713096.838 [Default:CheckIn:Read_GPS] Stopped
2022-06-08T18:31:36.838Z,1654713096.838 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-08T18:31:38.010Z,1654713098.010 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-08T18:31:44.515Z,1654713104.515 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220608T174806/Courier0024.lzma
2022-06-08T18:31:45.518Z,1654713105.518 [DataOverHttps](INFO): Moved sent file to Logs/20220608T174806/Courier0024.lzma.bak
2022-06-08T18:31:45.518Z,1654713105.518 [DataOverHttps](INFO): SBD MOMSN=16872585
2022-06-08T18:31:52.959Z,1654713112.959 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-08T18:32:01.612Z,1654713121.612 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20220608T174806/Express0025.lzma
2022-06-08T18:32:02.614Z,1654713122.614 [DataOverHttps](INFO): Moved sent file to Logs/20220608T174806/Express0025.lzma.bak
2022-06-08T18:32:02.614Z,1654713122.614 [DataOverHttps](INFO): SBD MOMSN=16872593
2022-06-08T18:32:03.889Z,1654713123.889 [Default:CheckIn:Read_Iridium] Stopped
2022-06-08T18:32:03.889Z,1654713123.889 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-08T18:32:03.889Z,1654713123.889 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-08T18:32:07.503Z,1654713127.503 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2022-06-08T18:32:07.517Z,1654713127.517 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-06-08T18:32:07.600Z,1654713127.600 [NAL9602](ERROR): received: 
+CSQ:0

OK435, 0, 0, 0, 0

OK
2022-06-08T18:36:39.405Z,1654713399.405 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-06-08T18:36:49.512Z,1654713409.512 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2022-06-08T18:36:49.514Z,1654713409.514 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2022-06-08T18:37:04.484Z,1654713424.484 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-08T18:37:04.484Z,1654713424.484 [Default:CheckIn:C.Wait] Stopped
2022-06-08T18:37:04.484Z,1654713424.484 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-08T18:37:04.484Z,1654713424.484 [Default:CheckIn:D] Running Loop=1
2022-06-08T18:37:04.894Z,1654713424.894 [Default:CheckIn:D] Stopped
2022-06-08T18:37:04.894Z,1654713424.894 [Default:CheckIn:E] Running Loop=1
2022-06-08T18:37:05.283Z,1654713425.283 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 119.633480 min 
2022-06-08T18:37:05.283Z,1654713425.283 [Default:CheckIn:E] Stopped
2022-06-08T18:37:05.283Z,1654713425.283 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-08T18:37:05.284Z,1654713425.284 [Default:CheckIn] Stopped
2022-06-08T18:37:05.284Z,1654713425.284 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-08T18:37:05.284Z,1654713425.284 [Default:CheckIn](INFO): Running loop #22
2022-06-08T18:37:05.284Z,1654713425.284 [Default:CheckIn] Running Loop=22
2022-06-08T18:37:05.284Z,1654713425.284 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-08T18:37:05.284Z,1654713425.284 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-08T18:37:07.297Z,1654713427.297 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183706.00,A,3648.16670,N,12147.28442,W,0.097,109.54,080622,,,D*7D
2022-06-08T18:37:07.299Z,1654713427.299 [NAL9602](INFO): GPS fix at 20220608T183706: (36.802778, -121.788074)
2022-06-08T18:37:07.310Z,1654713427.310 [Default:CheckIn:Read_GPS] Stopped
2022-06-08T18:37:07.310Z,1654713427.310 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-08T18:37:15.304Z,1654713435.304 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20220608T174806/Courier0027.lzma
2022-06-08T18:37:16.306Z,1654713436.306 [DataOverHttps](INFO): Moved sent file to Logs/20220608T174806/Courier0027.lzma.bak
2022-06-08T18:37:16.306Z,1654713436.306 [DataOverHttps](INFO): SBD MOMSN=16872602
2022-06-08T18:37:32.316Z,1654713452.316 [DataOverHttps](INFO): Sending 204 bytes from file Logs/20220608T174806/Express0028.lzma
2022-06-08T18:37:33.318Z,1654713453.318 [DataOverHttps](INFO): Moved sent file to Logs/20220608T174806/Express0028.lzma.bak
2022-06-08T18:37:33.318Z,1654713453.318 [DataOverHttps](INFO): SBD MOMSN=16872608
2022-06-08T18:37:34.777Z,1654713454.777 [Default:CheckIn:Read_Iridium] Stopped
2022-06-08T18:37:34.777Z,1654713454.777 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-08T18:37:34.777Z,1654713454.777 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-08T18:37:39.209Z,1654713459.209 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-08T18:42:35.359Z,1654713755.359 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-08T18:42:35.360Z,1654713755.360 [Default:CheckIn:C.Wait] Stopped
2022-06-08T18:42:35.360Z,1654713755.360 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-08T18:42:35.360Z,1654713755.360 [Default:CheckIn:D] Running Loop=1
2022-06-08T18:42:35.767Z,1654713755.767 [Default:CheckIn:D] Stopped
2022-06-08T18:42:35.767Z,1654713755.767 [Default:CheckIn:E] Running Loop=1
2022-06-08T18:42:36.172Z,1654713756.172 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 125.148022 min 
2022-06-08T18:42:36.173Z,1654713756.173 [Default:CheckIn:E] Stopped
2022-06-08T18:42:36.173Z,1654713756.173 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-08T18:42:36.173Z,1654713756.173 [Default:CheckIn] Stopped
2022-06-08T18:42:36.173Z,1654713756.173 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-08T18:42:36.173Z,1654713756.173 [Default:CheckIn](INFO): Running loop #23
2022-06-08T18:42:36.173Z,1654713756.173 [Default:CheckIn] Running Loop=23
2022-06-08T18:42:36.173Z,1654713756.173 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-08T18:42:36.173Z,1654713756.173 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-08T18:42:38.175Z,1654713758.175 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184237.00,A,3648.16831,N,12147.28092,W,0.525,109.54,080622,,,D*73
2022-06-08T18:42:38.178Z,1654713758.178 [NAL9602](INFO): GPS fix at 20220608T184237: (36.802805, -121.788015)
2022-06-08T18:42:38.191Z,1654713758.191 [Default:CheckIn:Read_GPS] Stopped
2022-06-08T18:42:38.191Z,1654713758.191 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-08T18:42:46.071Z,1654713766.071 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20220608T174806/Courier0030.lzma
2022-06-08T18:42:47.074Z,1654713767.074 [DataOverHttps](INFO): Moved sent file to Logs/20220608T174806/Courier0030.lzma.bak
2022-06-08T18:42:47.074Z,1654713767.074 [DataOverHttps](INFO): SBD MOMSN=16872614
2022-06-08T18:43:06.210Z,1654713786.210 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20220608T174806/Express0031.lzma
2022-06-08T18:43:07.150Z,1654713787.150 [DataOverHttps](INFO): Moved sent file to Logs/20220608T174806/Express0031.lzma.bak
2022-06-08T18:43:07.150Z,1654713787.150 [DataOverHttps](INFO): SBD MOMSN=16872622
2022-06-08T18:43:08.534Z,1654713788.534 [Default:CheckIn:Read_Iridium] Stopped
2022-06-08T18:43:08.534Z,1654713788.534 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-08T18:43:08.534Z,1654713788.534 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-08T18:43:08.919Z,1654713788.919 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-06-08T18:43:08.996Z,1654713788.996 [NAL9602](ERROR): received: 
+CSQ:0

OK435, 0, 0, 0, 0

OK
2022-06-08T18:46:10.722Z,1654713970.722 [NAL9602](INFO): SBD MO Status=2, MOMSN=33436, MT Status=2, MTMSN=0
2022-06-08T18:46:10.722Z,1654713970.722 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-06-08T18:47:40.427Z,1654714060.427 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-06-08T18:48:09.111Z,1654714089.111 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-08T18:48:09.124Z,1654714089.124 [Default:CheckIn:C.Wait] Stopped
2022-06-08T18:48:09.124Z,1654714089.124 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-08T18:48:09.124Z,1654714089.124 [Default:CheckIn:D] Running Loop=1
2022-06-08T18:48:09.515Z,1654714089.515 [Default:CheckIn:D] Stopped
2022-06-08T18:48:09.515Z,1654714089.515 [Default:CheckIn:E] Running Loop=1
2022-06-08T18:48:09.927Z,1654714089.927 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 130.710498 min 
2022-06-08T18:48:09.927Z,1654714089.927 [Default:CheckIn:E] Stopped
2022-06-08T18:48:09.927Z,1654714089.927 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-08T18:48:09.927Z,1654714089.927 [Default:CheckIn] Stopped
2022-06-08T18:48:09.928Z,1654714089.928 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-08T18:48:09.928Z,1654714089.928 [Default:CheckIn](INFO): Running loop #24
2022-06-08T18:48:09.928Z,1654714089.928 [Default:CheckIn] Running Loop=24
2022-06-08T18:48:09.928Z,1654714089.928 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-08T18:48:09.928Z,1654714089.928 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-08T18:48:11.934Z,1654714091.934 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184811.00,A,3648.16316,N,12147.27582,W,0.078,59.77,080622,,,D*40
2022-06-08T18:48:11.936Z,1654714091.936 [NAL9602](INFO): GPS fix at 20220608T184811: (36.802719, -121.787930)
2022-06-08T18:48:11.947Z,1654714091.947 [Default:CheckIn:Read_GPS] Stopped
2022-06-08T18:48:11.947Z,1654714091.947 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-08T18:48:20.584Z,1654714100.584 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220608T174806/Courier0033.lzma
2022-06-08T18:48:21.586Z,1654714101.586 [DataOverHttps](INFO): Moved sent file to Logs/20220608T174806/Courier0033.lzma.bak
2022-06-08T18:48:21.586Z,1654714101.586 [DataOverHttps](INFO): SBD MOMSN=16872629
2022-06-08T18:48:39.872Z,1654714119.872 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20220608T174806/Express0034.lzma
2022-06-08T18:48:40.874Z,1654714120.874 [DataOverHttps](INFO): Moved sent file to Logs/20220608T174806/Express0034.lzma.bak
2022-06-08T18:48:40.874Z,1654714120.874 [DataOverHttps](INFO): SBD MOMSN=16872635
2022-06-08T18:48:42.320Z,1654714122.320 [Default:CheckIn:Read_Iridium] Stopped
2022-06-08T18:48:42.320Z,1654714122.320 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-08T18:48:42.320Z,1654714122.320 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-08T18:48:44.325Z,1654714124.325 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-08T18:53:43.112Z,1654714423.112 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-08T18:53:43.112Z,1654714423.112 [Default:CheckIn:C.Wait] Stopped
2022-06-08T18:53:43.112Z,1654714423.112 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-08T18:53:43.112Z,1654714423.112 [Default:CheckIn:D] Running Loop=1
2022-06-08T18:53:43.501Z,1654714423.501 [Default:CheckIn:D] Stopped
2022-06-08T18:53:43.501Z,1654714423.501 [Default:CheckIn:E] Running Loop=1
2022-06-08T18:53:43.903Z,1654714423.903 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 136.276929 min 
2022-06-08T18:53:43.903Z,1654714423.903 [Default:CheckIn:E] Stopped
2022-06-08T18:53:43.903Z,1654714423.903 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-08T18:53:43.903Z,1654714423.903 [Default:CheckIn] Stopped
2022-06-08T18:53:43.904Z,1654714423.904 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-08T18:53:43.904Z,1654714423.904 [Default:CheckIn](INFO): Running loop #25
2022-06-08T18:53:43.904Z,1654714423.904 [Default:CheckIn] Running Loop=25
2022-06-08T18:53:43.904Z,1654714423.904 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-08T18:53:43.904Z,1654714423.904 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-08T18:53:45.915Z,1654714425.915 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185345.00,A,3648.16607,N,12147.27597,W,0.544,59.77,080622,,,D*40
2022-06-08T18:53:45.917Z,1654714425.917 [NAL9602](INFO): GPS fix at 20220608T185345: (36.802768, -121.787933)
2022-06-08T18:53:45.949Z,1654714425.949 [Default:CheckIn:Read_GPS] Stopped
2022-06-08T18:53:45.949Z,1654714425.949 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-08T18:53:53.043Z,1654714433.043 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220608T174806/Courier0036.lzma
2022-06-08T18:53:54.046Z,1654714434.046 [DataOverHttps](INFO): Moved sent file to Logs/20220608T174806/Courier0036.lzma.bak
2022-06-08T18:53:54.046Z,1654714434.046 [DataOverHttps](INFO): SBD MOMSN=16872641
2022-06-08T18:54:10.092Z,1654714450.092 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20220608T174806/Express0037.lzma
2022-06-08T18:54:11.094Z,1654714451.094 [DataOverHttps](INFO): Moved sent file to Logs/20220608T174806/Express0037.lzma.bak
2022-06-08T18:54:11.094Z,1654714451.094 [DataOverHttps](INFO): SBD MOMSN=16872647
2022-06-08T18:54:12.585Z,1654714452.585 [Default:CheckIn:Read_Iridium] Stopped
2022-06-08T18:54:12.586Z,1654714452.586 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-08T18:54:12.586Z,1654714452.586 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-08T18:54:16.609Z,1654714456.609 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-06-08T18:54:16.692Z,1654714456.692 [NAL9602](ERROR): received: 
+CSQ:0

OK436, 2, 0, 0, 0

OK
2022-06-08T18:55:37.055Z,1654714537.055 [NAL9602](INFO): SBD MO Status=2, MOMSN=33436, MT Status=2, MTMSN=0
2022-06-08T18:55:37.055Z,1654714537.055 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-06-08T18:58:48.313Z,1654714728.313 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-06-08T18:59:13.019Z,1654714753.019 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-08T18:59:13.020Z,1654714753.020 [Default:CheckIn:C.Wait] Stopped
2022-06-08T18:59:13.020Z,1654714753.020 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-08T18:59:13.020Z,1654714753.020 [Default:CheckIn:D] Running Loop=1
2022-06-08T18:59:13.442Z,1654714753.442 [Default:CheckIn:D] Stopped
2022-06-08T18:59:13.442Z,1654714753.442 [Default:CheckIn:E] Running Loop=1
2022-06-08T18:59:13.832Z,1654714753.832 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 141.775960 min 
2022-06-08T18:59:13.832Z,1654714753.832 [Default:CheckIn:E] Stopped
2022-06-08T18:59:13.832Z,1654714753.832 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-08T18:59:13.832Z,1654714753.832 [Default:CheckIn] Stopped
2022-06-08T18:59:13.833Z,1654714753.833 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-08T18:59:13.833Z,1654714753.833 [Default:CheckIn](INFO): Running loop #26
2022-06-08T18:59:13.833Z,1654714753.833 [Default:CheckIn] Running Loop=26
2022-06-08T18:59:13.833Z,1654714753.833 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-08T18:59:13.833Z,1654714753.833 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-08T18:59:15.843Z,1654714755.843 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185915.00,A,3648.16117,N,12147.28440,W,0.641,59.77,080622,,,D*4B
2022-06-08T18:59:15.845Z,1654714755.845 [NAL9602](INFO): GPS fix at 20220608T185915: (36.802686, -121.788073)
2022-06-08T18:59:15.857Z,1654714755.857 [Default:CheckIn:Read_GPS] Stopped
2022-06-08T18:59:15.857Z,1654714755.857 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-08T18:59:23.879Z,1654714763.879 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20220608T174806/Courier0039.lzma
2022-06-08T18:59:24.882Z,1654714764.882 [DataOverHttps](INFO): Moved sent file to Logs/20220608T174806/Courier0039.lzma.bak
2022-06-08T18:59:24.882Z,1654714764.882 [DataOverHttps](INFO): SBD MOMSN=16872658
2022-06-08T18:59:40.900Z,1654714780.900 [DataOverHttps](INFO): Sending 205 bytes from file Logs/20220608T174806/Express0040.lzma
2022-06-08T18:59:41.902Z,1654714781.902 [DataOverHttps](INFO): Moved sent file to Logs/20220608T174806/Express0040.lzma.bak
2022-06-08T18:59:41.902Z,1654714781.902 [DataOverHttps](INFO): SBD MOMSN=16872664
2022-06-08T18:59:43.327Z,1654714783.327 [Default:CheckIn:Read_Iridium] Stopped
2022-06-08T18:59:43.327Z,1654714783.327 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-08T18:59:43.327Z,1654714783.327 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-08T18:59:48.573Z,1654714788.573 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-08T19:04:43.743Z,1654715083.743 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-08T19:04:43.743Z,1654715083.743 [Default:CheckIn:C.Wait] Stopped
2022-06-08T19:04:43.743Z,1654715083.743 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-08T19:04:43.743Z,1654715083.743 [Default:CheckIn:D] Running Loop=1
2022-06-08T19:04:44.151Z,1654715084.151 [Default:CheckIn:D] Stopped
2022-06-08T19:04:44.151Z,1654715084.151 [Default:CheckIn:E] Running Loop=1
2022-06-08T19:04:44.562Z,1654715084.562 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 147.287760 min 
2022-06-08T19:04:44.562Z,1654715084.562 [Default:CheckIn:E] Stopped
2022-06-08T19:04:44.562Z,1654715084.562 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-08T19:04:44.562Z,1654715084.562 [Default:CheckIn] Stopped
2022-06-08T19:04:44.562Z,1654715084.562 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-08T19:04:44.562Z,1654715084.562 [Default:CheckIn](INFO): Running loop #27
2022-06-08T19:04:44.562Z,1654715084.562 [Default:CheckIn] Running Loop=27
2022-06-08T19:04:44.562Z,1654715084.562 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-08T19:04:44.563Z,1654715084.563 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-08T19:04:46.563Z,1654715086.563 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190445.00,A,3648.16564,N,12147.28025,W,0.214,105.07,080622,,,A*7E
2022-06-08T19:04:46.565Z,1654715086.565 [NAL9602](INFO): GPS fix at 20220608T190445: (36.802761, -121.788004)
2022-06-08T19:04:46.577Z,1654715086.577 [Default:CheckIn:Read_GPS] Stopped
2022-06-08T19:04:46.577Z,1654715086.577 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-08T19:04:55.597Z,1654715095.597 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2022-06-08T19:04:55.597Z,1654715095.597 [DropWeight] Hardware Fault, FailCount= 1
2022-06-08T19:04:55.597Z,1654715095.597 [DropWeight](ERROR): Hardware Fault
2022-06-08T19:04:55.727Z,1654715095.727 [CBIT](INFO): Critical error at 20220608T190455
2022-06-08T19:04:55.764Z,1654715095.764 [CBIT](ERROR): Hardware Fault in component: DropWeight
2022-06-08T19:04:55.765Z,1654715095.765 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2022-06-08T19:04:56.102Z,1654715096.102 [CBIT](INFO): Critical error at 20220608T190455
2022-06-08T19:04:57.687Z,1654715097.687 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220608T174806/Courier0042.lzma
2022-06-08T19:04:58.690Z,1654715098.690 [DataOverHttps](INFO): Moved sent file to Logs/20220608T174806/Courier0042.lzma.bak
2022-06-08T19:04:58.690Z,1654715098.690 [DataOverHttps](INFO): SBD MOMSN=16872682
2022-06-08T19:05:07.702Z,1654715107.702 [NAL9602](INFO): SBD MO Status=2, MOMSN=33436, MT Status=2, MTMSN=0
2022-06-08T19:05:07.702Z,1654715107.702 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-06-08T19:05:14.847Z,1654715114.847 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20220608T174806/Express0043.lzma
2022-06-08T19:05:15.850Z,1654715115.850 [DataOverHttps](INFO): Moved sent file to Logs/20220608T174806/Express0043.lzma.bak
2022-06-08T19:05:15.850Z,1654715115.850 [DataOverHttps](INFO): SBD MOMSN=16872685
2022-06-08T19:05:17.009Z,1654715117.009 [Default:CheckIn:Read_Iridium] Stopped
2022-06-08T19:05:17.009Z,1654715117.009 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-08T19:05:17.009Z,1654715117.009 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-08T19:09:49.435Z,1654715389.435 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-06-08T19:10:17.739Z,1654715417.739 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-08T19:10:17.739Z,1654715417.739 [Default:CheckIn:C.Wait] Stopped
2022-06-08T19:10:17.739Z,1654715417.739 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-08T19:10:17.739Z,1654715417.739 [Default:CheckIn:D] Running Loop=1
2022-06-08T19:10:18.153Z,1654715418.153 [Default:CheckIn:D] Stopped
2022-06-08T19:10:18.153Z,1654715418.153 [Default:CheckIn:E] Running Loop=1
2022-06-08T19:10:18.565Z,1654715418.565 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 152.854460 min 
2022-06-08T19:10:18.565Z,1654715418.565 [Default:CheckIn:E] Stopped
2022-06-08T19:10:18.566Z,1654715418.566 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-08T19:10:18.566Z,1654715418.566 [Default:CheckIn] Stopped
2022-06-08T19:10:18.566Z,1654715418.566 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-08T19:10:18.566Z,1654715418.566 [Default:CheckIn](INFO): Running loop #28
2022-06-08T19:10:18.566Z,1654715418.566 [Default:CheckIn] Running Loop=28
2022-06-08T19:10:18.566Z,1654715418.566 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-08T19:10:18.566Z,1654715418.566 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-08T19:10:20.545Z,1654715420.545 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191019.00,A,3648.16816,N,12147.27673,W,0.447,74.67,080622,,,A*41
2022-06-08T19:10:20.547Z,1654715420.547 [NAL9602](INFO): GPS fix at 20220608T191019: (36.802803, -121.787946)
2022-06-08T19:10:20.592Z,1654715420.592 [Default:CheckIn:Read_GPS] Stopped
2022-06-08T19:10:20.592Z,1654715420.592 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-08T19:10:30.884Z,1654715430.884 [DataOverHttps](INFO): Sending 179 bytes from file Logs/20220608T174806/Courier0045.lzma
2022-06-08T19:10:31.886Z,1654715431.886 [DataOverHttps](INFO): Moved sent file to Logs/20220608T174806/Courier0045.lzma.bak
2022-06-08T19:10:31.886Z,1654715431.886 [DataOverHttps](INFO): SBD MOMSN=16872724
2022-06-08T19:10:48.269Z,1654715448.269 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20220608T174806/Express0046.lzma
2022-06-08T19:10:49.270Z,1654715449.270 [DataOverHttps](INFO): Moved sent file to Logs/20220608T174806/Express0046.lzma.bak
2022-06-08T19:10:49.270Z,1654715449.270 [DataOverHttps](INFO): SBD MOMSN=16872729
2022-06-08T19:10:50.624Z,1654715450.624 [Default:CheckIn:Read_Iridium] Stopped
2022-06-08T19:10:50.625Z,1654715450.625 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-08T19:10:50.625Z,1654715450.625 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-08T19:10:53.465Z,1654715453.465 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-08T19:15:51.286Z,1654715751.286 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-08T19:15:51.286Z,1654715751.286 [Default:CheckIn:C.Wait] Stopped
2022-06-08T19:15:51.286Z,1654715751.286 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-08T19:15:51.286Z,1654715751.286 [Default:CheckIn:D] Running Loop=1
2022-06-08T19:15:51.657Z,1654715751.657 [Default:CheckIn:D] Stopped
2022-06-08T19:15:51.658Z,1654715751.658 [Default:CheckIn:E] Running Loop=1
2022-06-08T19:15:52.047Z,1654715752.047 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 158.412874 min 
2022-06-08T19:15:52.047Z,1654715752.047 [Default:CheckIn:E] Stopped
2022-06-08T19:15:52.047Z,1654715752.047 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-08T19:15:52.047Z,1654715752.047 [Default:CheckIn] Stopped
2022-06-08T19:15:52.047Z,1654715752.047 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-08T19:15:52.047Z,1654715752.047 [Default:CheckIn](INFO): Running loop #29
2022-06-08T19:15:52.047Z,1654715752.047 [Default:CheckIn] Running Loop=29
2022-06-08T19:15:52.047Z,1654715752.047 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-08T19:15:52.048Z,1654715752.048 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-08T19:15:54.059Z,1654715754.059 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191553.00,A,3648.04354,N,12147.32343,W,7.192,194.83,080622,,,A*79
2022-06-08T19:15:54.061Z,1654715754.061 [NAL9602](INFO): GPS fix at 20220608T191553: (36.800726, -121.788724)
2022-06-08T19:15:54.073Z,1654715754.073 [Default:CheckIn:Read_GPS] Stopped
2022-06-08T19:15:54.073Z,1654715754.073 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-08T19:16:01.403Z,1654715761.403 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220608T174806/Courier0048.lzma
2022-06-08T19:16:02.406Z,1654715762.406 [DataOverHttps](INFO): Moved sent file to Logs/20220608T174806/Courier0048.lzma.bak
2022-06-08T19:16:02.406Z,1654715762.406 [DataOverHttps](INFO): SBD MOMSN=16872740
2022-06-08T19:16:19.436Z,1654715779.436 [DataOverHttps](INFO): Sending 142 bytes from file Logs/20220608T174806/Express0049.lzma
2022-06-08T19:16:20.438Z,1654715780.438 [DataOverHttps](INFO): Moved sent file to Logs/20220608T174806/Express0049.lzma.bak
2022-06-08T19:16:20.438Z,1654715780.438 [DataOverHttps](INFO): SBD MOMSN=16872743
2022-06-08T19:16:21.616Z,1654715781.616 [Default:CheckIn:Read_Iridium] Stopped
2022-06-08T19:16:21.616Z,1654715781.616 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-08T19:16:21.616Z,1654715781.616 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-08T19:16:24.801Z,1654715784.801 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-06-08T19:16:24.884Z,1654715784.884 [NAL9602](ERROR): received: 
+CSQ:0

OK436, 2, 0, 0, 0

OK
2022-06-08T19:19:55.723Z,1654715995.723 [CBIT](INFO): Clearing failed state for component DropWeight
2022-06-08T19:19:55.723Z,1654715995.723 [DropWeight] No Fault, FailCount= 1
2022-06-08T19:20:56.289Z,1654716056.289 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-06-08T19:20:59.825Z,1654716059.825 [CommandExec](IMPORTANT): got command quit
2022-06-08T19:21:00.828Z,1654716060.828 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2022-06-08T19:21:00.828Z,1654716060.828 [CommandExec](INFO): Uninitializing the command executive.
2022-06-08T19:21:00.828Z,1654716060.828 [CommandExec](INFO): Uninitializing the command scheduler.
2022-06-08T19:21:00.829Z,1654716060.829 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-06-08T19:21:00.900Z,1654716060.900 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2022-06-08T19:21:00.900Z,1654716060.900 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2022-06-08T19:21:00.900Z,1654716060.900 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-06-08T19:21:00.901Z,1654716060.901 [NavChartDb](INFO): Join timeout helper Thread ID is 1683
2022-06-08T19:21:01.192Z,1654716061.192 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2022-06-08T19:21:01.192Z,1654716061.192 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-06-08T19:21:01.204Z,1654716061.204 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2022-06-08T19:21:01.204Z,1654716061.204 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-06-08T19:21:01.204Z,1654716061.204 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1684
2022-06-08T19:21:01.440Z,1654716061.440 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2022-06-08T19:21:01.440Z,1654716061.440 [WetLabsBB2FL](INFO): Powering down
2022-06-08T19:21:01.441Z,1654716061.441 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-06-08T19:21:01.448Z,1654716061.448 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2022-06-08T19:21:01.448Z,1654716061.448 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-06-08T19:21:01.449Z,1654716061.449 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1685
2022-06-08T19:21:01.449Z,1654716061.449 [CTD_Seabird](INFO): Powering down
2022-06-08T19:21:01.467Z,1654716061.467 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2022-06-08T19:21:01.468Z,1654716061.468 [CTD_Seabird](INFO): Powering down
2022-06-08T19:21:01.480Z,1654716061.480 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-06-08T19:21:01.493Z,1654716061.493 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2022-06-08T19:21:01.494Z,1654716061.494 [DAT ThreadHandler](INFO): Thread cancelled.
2022-06-08T19:21:01.496Z,1654716061.496 [DAT](INFO): Join timeout helper Thread ID is 1686
2022-06-08T19:21:01.552Z,1654716061.552 [DAT](INFO): Powering down
2022-06-08T19:21:01.624Z,1654716061.624 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2022-06-08T19:21:01.625Z,1654716061.625 [DAT](INFO): Powering down
2022-06-08T19:21:01.626Z,1654716061.626 [DAT ThreadHandler](INFO): Thread cancelled.
2022-06-08T19:21:01.628Z,1654716061.628 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2022-06-08T19:21:01.628Z,1654716061.628 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-06-08T19:21:01.628Z,1654716061.628 [Radio_Surface](INFO): Join timeout helper Thread ID is 1687
2022-06-08T19:21:01.672Z,1654716061.672 [Radio_Surface](INFO): Powering down
2022-06-08T19:21:01.673Z,1654716061.673 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2022-06-08T19:21:01.673Z,1654716061.673 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-06-08T19:21:01.685Z,1654716061.685 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2022-06-08T19:21:01.685Z,1654716061.685 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-06-08T19:21:01.685Z,1654716061.685 [Onboard](INFO): Join timeout helper Thread ID is 1688
2022-06-08T19:21:06.488Z,1654716066.488 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2022-06-08T19:21:06.488Z,1654716066.488 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-06-08T19:21:06.501Z,1654716066.501 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2022-06-08T19:21:06.501Z,1654716066.501 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-06-08T19:21:06.501Z,1654716066.501 [DataOverHttps](INFO): Join timeout helper Thread ID is 1689
2022-06-08T19:21:07.879Z,1654716067.879 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2022-06-08T19:21:07.882Z,1654716067.882 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-06-08T19:21:07.901Z,1654716067.901 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2022-06-08T19:21:07.901Z,1654716067.901 [logger ThreadHandler](INFO): Thread cancelled.
2022-06-08T19:21:07.901Z,1654716067.901 [logger](INFO): Join timeout helper Thread ID is 1690
2022-06-08T19:21:07.926Z,1654716067.926 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2022-06-08T19:21:07.927Z,1654716067.927 [logger ThreadHandler](INFO): Thread cancelled.
2022-06-08T19:21:07.933Z,1654716067.933 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2022-06-08T19:21:07.933Z,1654716067.933 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-06-08T19:21:07.933Z,1654716067.933 [CommandLine](INFO): Join timeout helper Thread ID is 1691
2022-06-08T19:21:07.956Z,1654716067.956 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2022-06-08T19:21:07.956Z,1654716067.956 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-06-08T19:21:07.973Z,1654716067.973 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2022-06-08T19:21:07.973Z,1654716067.973 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-06-08T19:21:07.973Z,1654716067.973 [CommandExec](INFO): Join timeout helper Thread ID is 1692
2022-06-08T19:21:08.013Z,1654716068.013 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2022-06-08T19:21:08.013Z,1654716068.013 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-06-08T19:21:08.013Z,1654716068.013 [controlThread](INFO): Join timeout helper Thread ID is 1693
2022-06-08T19:21:08.044Z,1654716068.044 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2022-06-08T19:21:08.044Z,1654716068.044 [controlThread](DEBUG): Uninitializing ControlThread
2022-06-08T19:21:08.045Z,1654716068.045 [AHRS_M2](INFO): Powering down
2022-06-08T19:21:08.117Z,1654716068.117 [NAL9602](INFO): Powering down
2022-06-08T19:21:08.284Z,1654716068.284 [AMEcho](INFO): Powering down.
2022-06-08T19:21:08.286Z,1654716068.286 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2022-06-08T19:21:08.286Z,1654716068.286 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2022-06-08T19:21:08.287Z,1654716068.287 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2022-06-08T19:21:08.287Z,1654716068.287 [MissionManager](INFO): Uninitializing Mission Default
2022-06-08T19:21:08.287Z,1654716068.287 [Default] Stopped
2022-06-08T19:21:08.288Z,1654716068.288 [Default](DEBUG): Aggregate::uninitialize Default
2022-06-08T19:21:08.288Z,1654716068.288 [Default:B.GoToSurface] Stopped
2022-06-08T19:21:08.288Z,1654716068.288 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-06-08T19:21:08.288Z,1654716068.288 [Default:CheckIn] Stopped
2022-06-08T19:21:08.288Z,1654716068.288 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-08T19:21:08.288Z,1654716068.288 [Default:CheckIn:C.Wait] Stopped
2022-06-08T19:21:08.288Z,1654716068.288 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-08T19:21:08.291Z,1654716068.291 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2022-06-08T19:21:08.291Z,1654716068.291 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2022-06-08T19:21:08.291Z,1654716068.291 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2022-06-08T19:21:08.292Z,1654716068.292 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2022-06-08T19:21:08.292Z,1654716068.292 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2022-06-08T19:21:08.292Z,1654716068.292 [BuoyancyServo](INFO): Powering down
2022-06-08T19:21:08.304Z,1654716068.304 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2022-06-08T19:21:08.304Z,1654716068.304 [ElevatorServo](INFO): Powering down
2022-06-08T19:21:08.305Z,1654716068.305 [MassServo](DEBUG): Uninitialize Mass Servo.
2022-06-08T19:21:08.305Z,1654716068.305 [MassServo](INFO): Powering down
2022-06-08T19:21:08.306Z,1654716068.306 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-06-08T19:21:08.306Z,1654716068.306 [RudderServo](INFO): Powering down
2022-06-08T19:21:08.307Z,1654716068.307 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2022-06-08T19:21:08.307Z,1654716068.307 [ThrusterServo](INFO): Powering down
2022-06-08T19:21:08.308Z,1654716068.308 [SBIT](DEBUG): Uninitialize SBIT Component.
2022-06-08T19:21:08.308Z,1654716068.308 [IBIT](DEBUG): Uninitialize IBIT Component.
2022-06-08T19:21:08.309Z,1654716068.309 [CBIT](DEBUG): Uninitialize CBIT Component.
2022-06-08T19:21:08.309Z,1654716068.309 [CBIT](DEBUG): Powering off loads.
2022-06-08T19:21:08.320Z,1654716068.320 [CBIT](DEBUG): Disabling WDT.
2022-06-08T19:21:08.332Z,1654716068.332 [CBIT](DEBUG): Opening all GF detection circuits.
2022-06-08T19:21:08.333Z,1654716068.333 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-06-08T19:21:08.389Z,1654716068.389 [DAT ThreadHandler](INFO): Thread cancelled.
2022-06-08T19:21:08.405Z,1654716068.405 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-06-08T19:21:08.407Z,1654716068.407 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-06-08T19:21:08.413Z,1654716068.413 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-06-08T19:21:08.557Z,1654716068.557 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-06-08T19:21:08.560Z,1654716068.560 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-06-08T19:21:08.642Z,1654716068.642 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-06-08T19:21:08.790Z,1654716068.790 [logger ThreadHandler](INFO): Thread cancelled.