2023-04-27T22:23:07.061Z,1682634187.061 [CommandExec](IMPORTANT): got command restart logs
2023-04-27T22:23:37.571Z,1682634217.571 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-27T22:24:41.018Z,1682634281.018 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-04-27T22:24:41.019Z,1682634281.019 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+12.6,0000.,000
2023-04-27T22:27:03.624Z,1682634423.624 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-27T22:27:03.624Z,1682634423.624 [Default:CheckIn:C.Wait] Stopped
2023-04-27T22:27:03.624Z,1682634423.624 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-27T22:27:03.624Z,1682634423.624 [Default:CheckIn:D] Running Loop=1
2023-04-27T22:27:04.020Z,1682634424.020 [Default:CheckIn:D] Stopped
2023-04-27T22:27:04.020Z,1682634424.020 [Default:CheckIn:E] Running Loop=1
2023-04-27T22:27:04.441Z,1682634424.441 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.414814 min 
2023-04-27T22:27:04.441Z,1682634424.441 [Default:CheckIn:E] Stopped
2023-04-27T22:27:04.441Z,1682634424.441 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-27T22:27:04.441Z,1682634424.441 [Default:CheckIn] Stopped
2023-04-27T22:27:04.441Z,1682634424.441 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-27T22:27:04.441Z,1682634424.441 [Default:CheckIn](INFO): Running loop #3
2023-04-27T22:27:04.441Z,1682634424.441 [Default:CheckIn] Running Loop=3
2023-04-27T22:27:04.442Z,1682634424.442 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-27T22:27:04.442Z,1682634424.442 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-27T22:27:06.441Z,1682634426.441 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222703.00,A,3648.14985,N,12147.22427,W,0.039,64.80,270423,,,D*47
2023-04-27T22:27:06.443Z,1682634426.443 [NAL9602](INFO): GPS fix at 20230427T222703: (36.802498, -121.787071)
2023-04-27T22:27:06.471Z,1682634426.471 [Default:CheckIn:Read_GPS] Stopped
2023-04-27T22:27:06.471Z,1682634426.471 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-27T22:27:14.429Z,1682634434.429 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230427T092153/Courier0106.lzma
2023-04-27T22:27:15.432Z,1682634435.432 [DataOverHttps](INFO): Moved sent file to Logs/20230427T092153/Courier0106.lzma.bak
2023-04-27T22:27:15.432Z,1682634435.432 [DataOverHttps](INFO): SBD MOMSN=18271578
2023-04-27T22:27:31.382Z,1682634451.382 [DataOverHttps](INFO): Sending 269 bytes from file Logs/20230427T222307/Courier0000.lzma
2023-04-27T22:27:32.383Z,1682634452.383 [DataOverHttps](INFO): Moved sent file to Logs/20230427T222307/Courier0000.lzma.bak
2023-04-27T22:27:32.384Z,1682634452.384 [DataOverHttps](INFO): SBD MOMSN=18271580
2023-04-27T22:27:48.517Z,1682634468.517 [DataOverHttps](INFO): Sending 116 bytes from file Logs/20230427T092153/Express0107.lzma
2023-04-27T22:27:49.520Z,1682634469.520 [DataOverHttps](INFO): Moved sent file to Logs/20230427T092153/Express0107.lzma.bak
2023-04-27T22:27:49.520Z,1682634469.520 [DataOverHttps](INFO): SBD MOMSN=18271584
2023-04-27T22:28:08.390Z,1682634488.390 [DataOverHttps](INFO): Sending 378 bytes from file Logs/20230427T222307/Express0001.lzma
2023-04-27T22:28:09.392Z,1682634489.392 [DataOverHttps](INFO): Moved sent file to Logs/20230427T222307/Express0001.lzma.bak
2023-04-27T22:28:09.392Z,1682634489.392 [DataOverHttps](INFO): SBD MOMSN=18271587
2023-04-27T22:28:11.567Z,1682634491.567 [Default:CheckIn:Read_Iridium] Stopped
2023-04-27T22:28:11.568Z,1682634491.568 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-27T22:28:11.568Z,1682634491.568 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-27T22:29:28.331Z,1682634568.331 [DVL_micro](ERROR): only read 3 of 4 data items
2023-04-27T22:29:28.331Z,1682634568.331 [DVL_micro](ERROR): Failed to parse:
:BI,+00012,-00118,+0001,I
2023-04-27T22:30:35.796Z,1682634635.796 [NAL9602](INFO): SBD MO Status=1, MOMSN=16887, MT Status=0, MTMSN=0
2023-04-27T22:30:35.796Z,1682634635.796 [NAL9602](INFO): No messages in MT queue
2023-04-27T22:31:06.503Z,1682634666.503 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-27T22:33:12.153Z,1682634792.153 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-27T22:33:12.154Z,1682634792.154 [Default:CheckIn:C.Wait] Stopped
2023-04-27T22:33:12.154Z,1682634792.154 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-27T22:33:12.154Z,1682634792.154 [Default:CheckIn:D] Running Loop=1
2023-04-27T22:33:12.582Z,1682634792.582 [Default:CheckIn:D] Stopped
2023-04-27T22:33:12.582Z,1682634792.582 [Default:CheckIn:E] Running Loop=1
2023-04-27T22:33:12.963Z,1682634792.963 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 17.557509 min 
2023-04-27T22:33:12.963Z,1682634792.963 [Default:CheckIn:E] Stopped
2023-04-27T22:33:12.963Z,1682634792.963 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-27T22:33:12.963Z,1682634792.963 [Default:CheckIn] Stopped
2023-04-27T22:33:12.963Z,1682634792.963 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-27T22:33:12.963Z,1682634792.963 [Default:CheckIn](INFO): Running loop #4
2023-04-27T22:33:12.963Z,1682634792.963 [Default:CheckIn] Running Loop=4
2023-04-27T22:33:12.964Z,1682634792.964 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-27T22:33:12.964Z,1682634792.964 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-27T22:33:14.973Z,1682634794.973 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,223312.00,A,3648.17819,N,12147.28166,W,0.389,354.97,270423,,,D*71
2023-04-27T22:33:14.976Z,1682634794.976 [NAL9602](INFO): GPS fix at 20230427T223312: (36.802970, -121.788028)
2023-04-27T22:33:14.991Z,1682634794.991 [Default:CheckIn:Read_GPS] Stopped
2023-04-27T22:33:14.991Z,1682634794.991 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-27T22:33:22.333Z,1682634802.333 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230427T222307/Courier0003.lzma
2023-04-27T22:33:23.335Z,1682634803.335 [DataOverHttps](INFO): Moved sent file to Logs/20230427T222307/Courier0003.lzma.bak
2023-04-27T22:33:23.336Z,1682634803.336 [DataOverHttps](INFO): SBD MOMSN=18271674
2023-04-27T22:33:40.105Z,1682634820.105 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230427T222307/Express0004.lzma
2023-04-27T22:33:41.107Z,1682634821.107 [DataOverHttps](INFO): Moved sent file to Logs/20230427T222307/Express0004.lzma.bak
2023-04-27T22:33:41.108Z,1682634821.108 [DataOverHttps](INFO): SBD MOMSN=18271677
2023-04-27T22:33:42.884Z,1682634822.884 [Default:CheckIn:Read_Iridium] Stopped
2023-04-27T22:33:42.885Z,1682634822.885 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-27T22:33:42.885Z,1682634822.885 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-27T22:34:17.642Z,1682634857.642 [DVL_micro](ERROR): only read 0 of 4 data items
2023-04-27T22:34:17.643Z,1682634857.643 [DVL_micro](ERROR): Failed to parse:
:RD,++9999.99,+9999.99,+9999.99
2023-04-27T22:38:17.252Z,1682635097.252 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-04-27T22:38:43.522Z,1682635123.522 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-27T22:38:43.522Z,1682635123.522 [Default:CheckIn:C.Wait] Stopped
2023-04-27T22:38:43.522Z,1682635123.522 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-27T22:38:43.522Z,1682635123.522 [Default:CheckIn:D] Running Loop=1
2023-04-27T22:38:43.938Z,1682635123.938 [Default:CheckIn:D] Stopped
2023-04-27T22:38:43.938Z,1682635123.938 [Default:CheckIn:E] Running Loop=1
2023-04-27T22:38:44.346Z,1682635124.346 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 23.080109 min 
2023-04-27T22:38:44.346Z,1682635124.346 [Default:CheckIn:E] Stopped
2023-04-27T22:38:44.346Z,1682635124.346 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-27T22:38:44.346Z,1682635124.346 [Default:CheckIn] Stopped
2023-04-27T22:38:44.346Z,1682635124.346 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-27T22:38:44.346Z,1682635124.346 [Default:CheckIn](INFO): Running loop #5
2023-04-27T22:38:44.346Z,1682635124.346 [Default:CheckIn] Running Loop=5
2023-04-27T22:38:44.346Z,1682635124.346 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-27T22:38:44.347Z,1682635124.347 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-27T22:38:46.355Z,1682635126.355 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,223843.00,A,3648.17121,N,12147.28570,W,0.641,285.96,270423,,,D*72
2023-04-27T22:38:46.358Z,1682635126.358 [NAL9602](INFO): GPS fix at 20230427T223843: (36.802853, -121.788095)
2023-04-27T22:38:46.389Z,1682635126.389 [Default:CheckIn:Read_GPS] Stopped
2023-04-27T22:38:46.389Z,1682635126.389 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-27T22:38:54.109Z,1682635134.109 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230427T222307/Courier0006.lzma
2023-04-27T22:38:55.112Z,1682635135.112 [DataOverHttps](INFO): Moved sent file to Logs/20230427T222307/Courier0006.lzma.bak
2023-04-27T22:38:55.112Z,1682635135.112 [DataOverHttps](INFO): SBD MOMSN=18271747
2023-04-27T22:39:10.885Z,1682635150.885 [DataOverHttps](INFO): Sending 228 bytes from file Logs/20230427T222307/Express0007.lzma
2023-04-27T22:39:11.888Z,1682635151.888 [DataOverHttps](INFO): Moved sent file to Logs/20230427T222307/Express0007.lzma.bak
2023-04-27T22:39:11.888Z,1682635151.888 [DataOverHttps](INFO): SBD MOMSN=18271788
2023-04-27T22:39:13.817Z,1682635153.817 [Default:CheckIn:Read_Iridium] Stopped
2023-04-27T22:39:13.817Z,1682635153.817 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-27T22:39:13.817Z,1682635153.817 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-27T22:39:18.255Z,1682635158.255 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-27T22:41:32.437Z,1682635292.437 [DVL_micro](ERROR): Failed to parse:
9.99,+9999.99,+9999.99,+9999.99
2023-04-27T22:42:20.893Z,1682635340.893 [DVL_micro](ERROR): only read 2 of 4 data items
2023-04-27T22:42:20.893Z,1682635340.893 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999..99,+9999.99
2023-04-27T22:42:48.765Z,1682635368.765 [DVL_micro](ERROR): Failed to parse:
0000000000,35.0,+13.9,0000.0,1489.0,000
2023-04-27T22:44:14.427Z,1682635454.427 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-27T22:44:14.427Z,1682635454.427 [Default:CheckIn:C.Wait] Stopped
2023-04-27T22:44:14.427Z,1682635454.427 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-27T22:44:14.427Z,1682635454.427 [Default:CheckIn:D] Running Loop=1
2023-04-27T22:44:14.837Z,1682635454.837 [Default:CheckIn:D] Stopped
2023-04-27T22:44:14.837Z,1682635454.837 [Default:CheckIn:E] Running Loop=1
2023-04-27T22:44:15.229Z,1682635455.229 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 28.595087 min 
2023-04-27T22:44:15.229Z,1682635455.229 [Default:CheckIn:E] Stopped
2023-04-27T22:44:15.229Z,1682635455.229 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-27T22:44:15.229Z,1682635455.229 [Default:CheckIn] Stopped
2023-04-27T22:44:15.229Z,1682635455.229 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-27T22:44:15.230Z,1682635455.230 [Default:CheckIn](INFO): Running loop #6
2023-04-27T22:44:15.230Z,1682635455.230 [Default:CheckIn] Running Loop=6
2023-04-27T22:44:15.230Z,1682635455.230 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-27T22:44:15.230Z,1682635455.230 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-27T22:44:17.241Z,1682635457.241 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224414.00,A,3648.16804,N,12147.28629,W,0.369,21.59,270423,,,D*4B
2023-04-27T22:44:17.243Z,1682635457.243 [NAL9602](INFO): GPS fix at 20230427T224414: (36.802801, -121.788105)
2023-04-27T22:44:17.255Z,1682635457.255 [Default:CheckIn:Read_GPS] Stopped
2023-04-27T22:44:17.255Z,1682635457.255 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-27T22:44:28.353Z,1682635468.353 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230427T222307/Courier0009.lzma
2023-04-27T22:44:29.352Z,1682635469.352 [DataOverHttps](INFO): Moved sent file to Logs/20230427T222307/Courier0009.lzma.bak
2023-04-27T22:44:29.352Z,1682635469.352 [DataOverHttps](INFO): SBD MOMSN=18271902
2023-04-27T22:44:31.039Z,1682635471.039 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-04-27T22:44:31.039Z,1682635471.039 [DVL_micro](ERROR): Failed to parse:
:TS,000000035.0,+14.0,0000.0,1489.0,000
2023-04-27T22:44:46.413Z,1682635486.413 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230427T222307/Express0010.lzma
2023-04-27T22:44:47.415Z,1682635487.415 [DataOverHttps](INFO): Moved sent file to Logs/20230427T222307/Express0010.lzma.bak
2023-04-27T22:44:47.416Z,1682635487.416 [DataOverHttps](INFO): SBD MOMSN=18271905
2023-04-27T22:44:48.003Z,1682635488.003 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-04-27T22:44:48.078Z,1682635488.078 [NAL9602](ERROR): received: 
+CSQ:0

OK887, 0, 0, 0, 0

OK
2023-04-27T22:44:49.239Z,1682635489.239 [Default:CheckIn:Read_Iridium] Stopped
2023-04-27T22:44:49.240Z,1682635489.240 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-27T22:44:49.240Z,1682635489.240 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-27T22:49:19.534Z,1682635759.534 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-04-27T22:49:49.828Z,1682635789.828 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-27T22:49:49.829Z,1682635789.829 [Default:CheckIn:C.Wait] Stopped
2023-04-27T22:49:49.829Z,1682635789.829 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-27T22:49:49.829Z,1682635789.829 [Default:CheckIn:D] Running Loop=1
2023-04-27T22:49:50.241Z,1682635790.241 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-27T22:49:50.253Z,1682635790.253 [Default:CheckIn:D] Stopped
2023-04-27T22:49:50.253Z,1682635790.253 [Default:CheckIn:E] Running Loop=1
2023-04-27T22:49:50.646Z,1682635790.646 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 34.185364 min 
2023-04-27T22:49:50.646Z,1682635790.646 [Default:CheckIn:E] Stopped
2023-04-27T22:49:50.646Z,1682635790.646 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-27T22:49:50.646Z,1682635790.646 [Default:CheckIn] Stopped
2023-04-27T22:49:50.646Z,1682635790.646 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-27T22:49:50.646Z,1682635790.646 [Default:CheckIn](INFO): Running loop #7
2023-04-27T22:49:50.646Z,1682635790.646 [Default:CheckIn] Running Loop=7
2023-04-27T22:49:50.647Z,1682635790.647 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-27T22:49:50.647Z,1682635790.647 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-27T22:49:52.657Z,1682635792.657 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224950.00,A,3648.16346,N,12147.27713,W,0.486,169.05,270423,,,D*7E
2023-04-27T22:49:52.660Z,1682635792.660 [NAL9602](INFO): GPS fix at 20230427T224950: (36.802724, -121.787952)
2023-04-27T22:49:52.703Z,1682635792.703 [Default:CheckIn:Read_GPS] Stopped
2023-04-27T22:49:52.703Z,1682635792.703 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-27T22:49:59.629Z,1682635799.629 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230427T222307/Courier0012.lzma
2023-04-27T22:50:00.631Z,1682635800.631 [DataOverHttps](INFO): Moved sent file to Logs/20230427T222307/Courier0012.lzma.bak
2023-04-27T22:50:00.632Z,1682635800.632 [DataOverHttps](INFO): SBD MOMSN=18271908
2023-04-27T22:50:16.533Z,1682635816.533 [DataOverHttps](INFO): Sending 204 bytes from file Logs/20230427T222307/Express0013.lzma
2023-04-27T22:50:17.535Z,1682635817.535 [DataOverHttps](INFO): Moved sent file to Logs/20230427T222307/Express0013.lzma.bak
2023-04-27T22:50:17.536Z,1682635817.536 [DataOverHttps](INFO): SBD MOMSN=18271911
2023-04-27T22:50:19.439Z,1682635819.439 [Default:CheckIn:Read_Iridium] Stopped
2023-04-27T22:50:19.439Z,1682635819.439 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-27T22:50:19.440Z,1682635819.440 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-27T22:50:23.084Z,1682635823.084 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-04-27T22:50:23.159Z,1682635823.159 [NAL9602](ERROR): received: 
+CSQ:0

OK887, 0, 0, 0, 0

OK
2023-04-27T22:53:27.759Z,1682636007.759 [NAL9602](INFO): SBD MO Status=1, MOMSN=16888, MT Status=0, MTMSN=0
2023-04-27T22:53:27.759Z,1682636007.759 [NAL9602](INFO): No messages in MT queue
2023-04-27T22:53:58.507Z,1682636038.507 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-27T22:55:20.068Z,1682636120.068 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-27T22:55:20.068Z,1682636120.068 [Default:CheckIn:C.Wait] Stopped
2023-04-27T22:55:20.068Z,1682636120.068 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-27T22:55:20.068Z,1682636120.068 [Default:CheckIn:D] Running Loop=1
2023-04-27T22:55:20.466Z,1682636120.466 [Default:CheckIn:D] Stopped
2023-04-27T22:55:20.466Z,1682636120.466 [Default:CheckIn:E] Running Loop=1
2023-04-27T22:55:20.871Z,1682636120.871 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 39.688904 min 
2023-04-27T22:55:20.871Z,1682636120.871 [Default:CheckIn:E] Stopped
2023-04-27T22:55:20.871Z,1682636120.871 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-27T22:55:20.872Z,1682636120.872 [Default:CheckIn] Stopped
2023-04-27T22:55:20.872Z,1682636120.872 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-27T22:55:20.872Z,1682636120.872 [Default:CheckIn](INFO): Running loop #8
2023-04-27T22:55:20.872Z,1682636120.872 [Default:CheckIn] Running Loop=8
2023-04-27T22:55:20.872Z,1682636120.872 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-27T22:55:20.872Z,1682636120.872 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-27T22:55:22.884Z,1682636122.884 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,225520.00,A,3648.17587,N,12147.27891,W,0.019,344.38,270423,,,D*7A
2023-04-27T22:55:22.886Z,1682636122.886 [NAL9602](INFO): GPS fix at 20230427T225520: (36.802931, -121.787982)
2023-04-27T22:55:22.918Z,1682636122.918 [Default:CheckIn:Read_GPS] Stopped
2023-04-27T22:55:22.918Z,1682636122.918 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-27T22:55:30.369Z,1682636130.369 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230427T222307/Courier0015.lzma
2023-04-27T22:55:31.372Z,1682636131.372 [DataOverHttps](INFO): Moved sent file to Logs/20230427T222307/Courier0015.lzma.bak
2023-04-27T22:55:31.372Z,1682636131.372 [DataOverHttps](INFO): SBD MOMSN=18271915
2023-04-27T22:55:47.189Z,1682636147.189 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230427T222307/Express0016.lzma
2023-04-27T22:55:48.191Z,1682636148.191 [DataOverHttps](INFO): Moved sent file to Logs/20230427T222307/Express0016.lzma.bak
2023-04-27T22:55:48.192Z,1682636148.192 [DataOverHttps](INFO): SBD MOMSN=18271920
2023-04-27T22:55:49.969Z,1682636149.969 [Default:CheckIn:Read_Iridium] Stopped
2023-04-27T22:55:49.969Z,1682636149.969 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-27T22:55:49.969Z,1682636149.969 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-27T22:55:55.660Z,1682636155.660 [NAL9602](INFO): SBD MO Status=1, MOMSN=16889, MT Status=0, MTMSN=0
2023-04-27T22:55:55.660Z,1682636155.660 [NAL9602](INFO): No messages in MT queue
2023-04-27T22:56:16.293Z,1682636176.293 [DVL_micro](ERROR): DVL BIT error. See manual. Result code: 587
2023-04-27T22:56:16.298Z,1682636176.298 [DVL_micro](ERROR): Failed to parse:
:BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000,+9999.99,+9999.99,+9999.99,+9999.99
2023-04-27T22:56:26.383Z,1682636186.383 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-27T22:57:53.661Z,1682636273.661 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-04-27T22:57:53.662Z,1682636273.662 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0000.0,1489.0,000
2023-04-27T22:58:19.117Z,1682636299.117 [DVL_micro](ERROR): only read 3 of 4 data items
2023-04-27T22:58:19.117Z,1682636299.117 [DVL_micro](ERROR): Failed to parse:
:BI,-00352,00156,+00000,I
2023-04-27T23:00:00.107Z,1682636400.107 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-04-27T23:00:00.113Z,1682636400.113 [BPC1](INFO): Calculating totals. Valid battery stick count: 48. Valid reserve battery stick count: 5.
2023-04-27T23:00:00.116Z,1682636400.116 [BPC1](INFO): Received data from all battery sticks.
2023-04-27T23:00:23.984Z,1682636423.984 [DVL_micro](ERROR): only read 3 of 4 data items
2023-04-27T23:00:23.984Z,1682636423.984 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+99999.99,+9999.99
2023-04-27T23:00:50.673Z,1682636450.673 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-27T23:00:50.673Z,1682636450.673 [Default:CheckIn:C.Wait] Stopped
2023-04-27T23:00:50.673Z,1682636450.673 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-27T23:00:50.674Z,1682636450.674 [Default:CheckIn:D] Running Loop=1
2023-04-27T23:00:51.062Z,1682636451.062 [Default:CheckIn:D] Stopped
2023-04-27T23:00:51.062Z,1682636451.062 [Default:CheckIn:E] Running Loop=1
2023-04-27T23:00:51.463Z,1682636451.463 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 45.198840 min 
2023-04-27T23:00:51.464Z,1682636451.464 [Default:CheckIn:E] Stopped
2023-04-27T23:00:51.464Z,1682636451.464 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-27T23:00:51.464Z,1682636451.464 [Default:CheckIn] Stopped
2023-04-27T23:00:51.464Z,1682636451.464 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-27T23:00:51.464Z,1682636451.464 [Default:CheckIn](INFO): Running loop #9
2023-04-27T23:00:51.464Z,1682636451.464 [Default:CheckIn] Running Loop=9
2023-04-27T23:00:51.464Z,1682636451.464 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-27T23:00:51.464Z,1682636451.464 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-27T23:00:53.472Z,1682636453.472 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230050.00,A,3648.17684,N,12147.28236,W,0.019,57.48,270423,,,D*42
2023-04-27T23:00:53.483Z,1682636453.483 [NAL9602](INFO): GPS fix at 20230427T230050: (36.802947, -121.788039)
2023-04-27T23:00:53.499Z,1682636453.499 [Default:CheckIn:Read_GPS] Stopped
2023-04-27T23:00:53.499Z,1682636453.499 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-27T23:01:01.089Z,1682636461.089 [DataOverHttps](INFO): Sending 203 bytes from file Logs/20230427T222307/Courier0018.lzma
2023-04-27T23:01:02.091Z,1682636462.091 [DataOverHttps](INFO): Moved sent file to Logs/20230427T222307/Courier0018.lzma.bak
2023-04-27T23:01:02.092Z,1682636462.092 [DataOverHttps](INFO): SBD MOMSN=18271925
2023-04-27T23:01:17.873Z,1682636477.873 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230427T222307/Express0019.lzma
2023-04-27T23:01:18.875Z,1682636478.875 [DataOverHttps](INFO): Moved sent file to Logs/20230427T222307/Express0019.lzma.bak
2023-04-27T23:01:18.876Z,1682636478.876 [DataOverHttps](INFO): SBD MOMSN=18271928
2023-04-27T23:01:20.559Z,1682636480.559 [Default:CheckIn:Read_Iridium] Stopped
2023-04-27T23:01:20.559Z,1682636480.559 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-27T23:01:20.559Z,1682636480.559 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-27T23:01:21.752Z,1682636481.752 [NAL9602](INFO): SBD MO Status=2, MOMSN=16890, MT Status=2, MTMSN=0
2023-04-27T23:01:21.752Z,1682636481.752 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-04-27T23:01:57.709Z,1682636517.709 [NAL9602](INFO): SBD MO Status=1, MOMSN=16890, MT Status=0, MTMSN=0
2023-04-27T23:01:57.709Z,1682636517.709 [NAL9602](INFO): No messages in MT queue
2023-04-27T23:02:28.415Z,1682636548.415 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-27T23:06:16.384Z,1682636776.384 [DVL_micro](ERROR): Failed to parse:
76,-00038,+00002,+00000,I
2023-04-27T23:06:21.238Z,1682636781.238 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-27T23:06:21.238Z,1682636781.238 [Default:CheckIn:C.Wait] Stopped
2023-04-27T23:06:21.238Z,1682636781.238 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-27T23:06:21.238Z,1682636781.238 [Default:CheckIn:D] Running Loop=1
2023-04-27T23:06:21.647Z,1682636781.647 [Default:CheckIn:D] Stopped
2023-04-27T23:06:21.648Z,1682636781.648 [Default:CheckIn:E] Running Loop=1
2023-04-27T23:06:22.052Z,1682636782.052 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 50.708598 min 
2023-04-27T23:06:22.052Z,1682636782.052 [Default:CheckIn:E] Stopped
2023-04-27T23:06:22.052Z,1682636782.052 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-27T23:06:22.052Z,1682636782.052 [Default:CheckIn] Stopped
2023-04-27T23:06:22.052Z,1682636782.052 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-27T23:06:22.053Z,1682636782.053 [Default:CheckIn](INFO): Running loop #10
2023-04-27T23:06:22.053Z,1682636782.053 [Default:CheckIn] Running Loop=10
2023-04-27T23:06:22.053Z,1682636782.053 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-27T23:06:22.053Z,1682636782.053 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-27T23:06:24.061Z,1682636784.061 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230621.00,A,3648.17394,N,12147.27929,W,0.078,305.89,270423,,,D*72
2023-04-27T23:06:24.064Z,1682636784.064 [NAL9602](INFO): GPS fix at 20230427T230621: (36.802899, -121.787988)
2023-04-27T23:06:24.099Z,1682636784.099 [Default:CheckIn:Read_GPS] Stopped
2023-04-27T23:06:24.099Z,1682636784.099 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-27T23:06:31.813Z,1682636791.813 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230427T222307/Courier0021.lzma
2023-04-27T23:06:32.815Z,1682636792.815 [DataOverHttps](INFO): Moved sent file to Logs/20230427T222307/Courier0021.lzma.bak
2023-04-27T23:06:32.816Z,1682636792.816 [DataOverHttps](INFO): SBD MOMSN=18271975
2023-04-27T23:06:34.561Z,1682636794.561 [DVL_micro](ERROR): only read 2 of 4 data items
2023-04-27T23:06:34.561Z,1682636794.561 [DVL_micro](ERROR): Failed to parse:
:RD,+99999.99,+99999.99
2023-04-27T23:06:49.913Z,1682636809.913 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230427T222307/Express0022.lzma
2023-04-27T23:06:50.915Z,1682636810.915 [DataOverHttps](INFO): Moved sent file to Logs/20230427T222307/Express0022.lzma.bak
2023-04-27T23:06:50.916Z,1682636810.916 [DataOverHttps](INFO): SBD MOMSN=18271978
2023-04-27T23:06:52.755Z,1682636812.755 [Default:CheckIn:Read_Iridium] Stopped
2023-04-27T23:06:52.755Z,1682636812.755 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-27T23:06:52.755Z,1682636812.755 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-27T23:07:04.856Z,1682636824.856 [NAL9602](INFO): SBD MO Status=2, MOMSN=16891, MT Status=2, MTMSN=0
2023-04-27T23:07:04.856Z,1682636824.856 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-04-27T23:07:43.670Z,1682636863.670 [NAL9602](INFO): SBD MO Status=2, MOMSN=16891, MT Status=2, MTMSN=0
2023-04-27T23:07:43.670Z,1682636863.670 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-04-27T23:08:07.589Z,1682636887.589 [NAL9602](INFO): SBD MO Status=1, MOMSN=16891, MT Status=0, MTMSN=0
2023-04-27T23:08:07.589Z,1682636887.589 [NAL9602](INFO): No messages in MT queue
2023-04-27T23:08:38.335Z,1682636918.335 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-27T23:11:53.479Z,1682637113.479 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-27T23:11:53.479Z,1682637113.479 [Default:CheckIn:C.Wait] Stopped
2023-04-27T23:11:53.480Z,1682637113.480 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-27T23:11:53.480Z,1682637113.480 [Default:CheckIn:D] Running Loop=1
2023-04-27T23:11:53.867Z,1682637113.867 [Default:CheckIn:D] Stopped
2023-04-27T23:11:53.867Z,1682637113.867 [Default:CheckIn:E] Running Loop=1
2023-04-27T23:11:54.288Z,1682637114.288 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 56.245593 min 
2023-04-27T23:11:54.289Z,1682637114.289 [Default:CheckIn:E] Stopped
2023-04-27T23:11:54.289Z,1682637114.289 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-27T23:11:54.289Z,1682637114.289 [Default:CheckIn] Stopped
2023-04-27T23:11:54.289Z,1682637114.289 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-27T23:11:54.289Z,1682637114.289 [Default:CheckIn](INFO): Running loop #11
2023-04-27T23:11:54.289Z,1682637114.289 [Default:CheckIn] Running Loop=11
2023-04-27T23:11:54.289Z,1682637114.289 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-27T23:11:54.289Z,1682637114.289 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-27T23:11:56.265Z,1682637116.265 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,231155.00,A,3648.17735,N,12147.27777,W,0.117,305.89,270423,,,D*75
2023-04-27T23:11:56.268Z,1682637116.268 [NAL9602](INFO): GPS fix at 20230427T231155: (36.802956, -121.787963)
2023-04-27T23:11:56.288Z,1682637116.288 [Default:CheckIn:Read_GPS] Stopped
2023-04-27T23:11:56.288Z,1682637116.288 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-27T23:12:00.335Z,1682637120.335 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-04-27T23:12:00.335Z,1682637120.335 [DVL_micro](ERROR): Failed to parse:
:TS,000000005.0,+15.2,0000.0,1489.0,000
2023-04-27T23:12:06.397Z,1682637126.397 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230427T222307/Courier0024.lzma
2023-04-27T23:12:07.400Z,1682637127.400 [DataOverHttps](INFO): Moved sent file to Logs/20230427T222307/Courier0024.lzma.bak
2023-04-27T23:12:07.400Z,1682637127.400 [DataOverHttps](INFO): SBD MOMSN=18271991
2023-04-27T23:12:23.334Z,1682637143.334 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20230427T222307/Express0025.lzma
2023-04-27T23:12:24.336Z,1682637144.336 [DataOverHttps](INFO): Moved sent file to Logs/20230427T222307/Express0025.lzma.bak
2023-04-27T23:12:24.336Z,1682637144.336 [DataOverHttps](INFO): SBD MOMSN=18271994
2023-04-27T23:12:26.229Z,1682637146.229 [Default:CheckIn:Read_Iridium] Stopped
2023-04-27T23:12:26.229Z,1682637146.229 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-27T23:12:26.229Z,1682637146.229 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-27T23:13:47.821Z,1682637227.821 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-04-27T23:13:47.822Z,1682637227.822 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,.2,0000.0,1489.0,000
2023-04-27T23:15:47.849Z,1682637347.849 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-04-27T23:15:47.849Z,1682637347.849 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+15.2,009.0,000
2023-04-27T23:16:01.533Z,1682637361.533 [NAL9602](INFO): SBD MO Status=2, MOMSN=16892, MT Status=2, MTMSN=0
2023-04-27T23:16:01.533Z,1682637361.533 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-04-27T23:17:06.577Z,1682637426.577 [NAL9602](INFO): SBD MO Status=1, MOMSN=16892, MT Status=0, MTMSN=0
2023-04-27T23:17:06.577Z,1682637426.577 [NAL9602](INFO): No messages in MT queue
2023-04-27T23:17:26.797Z,1682637446.797 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-27T23:17:26.797Z,1682637446.797 [Default:CheckIn:C.Wait] Stopped
2023-04-27T23:17:26.797Z,1682637446.797 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-27T23:17:26.797Z,1682637446.797 [Default:CheckIn:D] Running Loop=1
2023-04-27T23:17:27.195Z,1682637447.195 [Default:CheckIn:D] Stopped
2023-04-27T23:17:27.195Z,1682637447.195 [Default:CheckIn:E] Running Loop=1
2023-04-27T23:17:27.604Z,1682637447.604 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 61.801058 min 
2023-04-27T23:17:27.604Z,1682637447.604 [Default:CheckIn:E] Stopped
2023-04-27T23:17:27.604Z,1682637447.604 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-27T23:17:27.604Z,1682637447.604 [Default:CheckIn] Stopped
2023-04-27T23:17:27.604Z,1682637447.604 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-27T23:17:27.604Z,1682637447.604 [Default:CheckIn](INFO): Running loop #12
2023-04-27T23:17:27.604Z,1682637447.604 [Default:CheckIn] Running Loop=12
2023-04-27T23:17:27.604Z,1682637447.604 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-27T23:17:27.604Z,1682637447.604 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-27T23:17:29.613Z,1682637449.613 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,231728.00,A,3648.16469,N,12147.28256,W,0.156,237.40,270423,,,D*7B
2023-04-27T23:17:29.616Z,1682637449.616 [NAL9602](INFO): GPS fix at 20230427T231728: (36.802745, -121.788043)
2023-04-27T23:17:29.627Z,1682637449.627 [Default:CheckIn:Read_GPS] Stopped
2023-04-27T23:17:29.627Z,1682637449.627 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-27T23:17:37.457Z,1682637457.457 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230427T222307/Courier0027.lzma
2023-04-27T23:17:38.460Z,1682637458.460 [DataOverHttps](INFO): Moved sent file to Logs/20230427T222307/Courier0027.lzma.bak
2023-04-27T23:17:38.460Z,1682637458.460 [DataOverHttps](INFO): SBD MOMSN=18272004
2023-04-27T23:18:01.571Z,1682637481.571 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-27T23:18:50.595Z,1682637530.595 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.958858
2023-04-27T23:19:21.726Z,1682637561.726 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-27T23:19:23.250Z,1682637563.250 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-04-27T23:19:23.250Z,1682637563.250 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+15.4,0000.0000
2023-04-27T23:19:56.866Z,1682637596.866 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-27T23:20:32.007Z,1682637632.007 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-27T23:21:07.166Z,1682637667.166 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-27T23:21:20.991Z,1682637680.991 [CommandExec](IMPORTANT): got command quit
2023-04-27T23:21:21.994Z,1682637681.994 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-27T23:21:21.994Z,1682637681.994 [CommandExec](INFO): Uninitializing the command executive.
2023-04-27T23:21:21.994Z,1682637681.994 [CommandExec](INFO): Uninitializing the command scheduler.
2023-04-27T23:21:21.995Z,1682637681.995 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-04-27T23:21:22.150Z,1682637682.150 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2023-04-27T23:21:22.150Z,1682637682.150 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-04-27T23:21:22.150Z,1682637682.150 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-04-27T23:21:22.151Z,1682637682.151 [NavChartDb](INFO): Join timeout helper Thread ID is 7770
2023-04-27T23:21:22.262Z,1682637682.262 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-27T23:21:22.263Z,1682637682.263 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-04-27T23:21:22.274Z,1682637682.274 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-04-27T23:21:22.274Z,1682637682.274 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-04-27T23:21:22.275Z,1682637682.275 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 7771
2023-04-27T23:21:22.491Z,1682637682.491 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-27T23:21:22.491Z,1682637682.491 [WetLabsBB2FL](INFO): Powering down
2023-04-27T23:21:22.492Z,1682637682.492 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-04-27T23:21:22.498Z,1682637682.498 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-04-27T23:21:22.498Z,1682637682.498 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-04-27T23:21:22.499Z,1682637682.499 [CTD_Seabird](INFO): Join timeout helper Thread ID is 7772
2023-04-27T23:21:23.186Z,1682637683.186 [CTD_Seabird](INFO): Powering down
2023-04-27T23:21:23.198Z,1682637683.198 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-27T23:21:23.198Z,1682637683.198 [CTD_Seabird](INFO): Powering down
2023-04-27T23:21:23.210Z,1682637683.210 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-04-27T23:21:23.218Z,1682637683.218 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2023-04-27T23:21:23.218Z,1682637683.218 [DAT ThreadHandler](INFO): Thread cancelled.
2023-04-27T23:21:23.219Z,1682637683.219 [DAT](INFO): Join timeout helper Thread ID is 7773
2023-04-27T23:21:23.462Z,1682637683.462 [DAT](INFO): Powering down
2023-04-27T23:21:23.543Z,1682637683.543 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-27T23:21:23.543Z,1682637683.543 [DAT](INFO): Powering down
2023-04-27T23:21:23.544Z,1682637683.544 [DAT ThreadHandler](INFO): Thread cancelled.
2023-04-27T23:21:23.546Z,1682637683.546 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-04-27T23:21:23.546Z,1682637683.546 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-04-27T23:21:23.547Z,1682637683.547 [Radio_Surface](INFO): Join timeout helper Thread ID is 7774
2023-04-27T23:21:23.914Z,1682637683.914 [Radio_Surface](INFO): Powering down
2023-04-27T23:21:23.915Z,1682637683.915 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-27T23:21:23.915Z,1682637683.915 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-04-27T23:21:23.927Z,1682637683.927 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-04-27T23:21:23.927Z,1682637683.927 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-04-27T23:21:23.928Z,1682637683.928 [Onboard](INFO): Join timeout helper Thread ID is 7775
2023-04-27T23:21:28.062Z,1682637688.062 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-27T23:21:28.062Z,1682637688.062 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-04-27T23:21:28.079Z,1682637688.079 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-04-27T23:21:28.080Z,1682637688.080 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-04-27T23:21:28.080Z,1682637688.080 [DataOverHttps](INFO): Join timeout helper Thread ID is 7776
2023-04-27T23:21:28.250Z,1682637688.250 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-27T23:21:28.251Z,1682637688.251 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-04-27T23:21:28.259Z,1682637688.259 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2023-04-27T23:21:28.259Z,1682637688.259 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-04-27T23:21:28.259Z,1682637688.259 [BackseatComponent](INFO): Join timeout helper Thread ID is 7777
2023-04-27T23:21:28.270Z,1682637688.270 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-27T23:21:28.270Z,1682637688.270 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-04-27T23:21:28.283Z,1682637688.283 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-04-27T23:21:28.283Z,1682637688.283 [logger ThreadHandler](INFO): Thread cancelled.
2023-04-27T23:21:28.283Z,1682637688.283 [logger](INFO): Join timeout helper Thread ID is 7778
2023-04-27T23:21:28.315Z,1682637688.315 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-27T23:21:28.316Z,1682637688.316 [logger ThreadHandler](INFO): Thread cancelled.
2023-04-27T23:21:28.327Z,1682637688.327 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-04-27T23:21:28.327Z,1682637688.327 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-04-27T23:21:28.327Z,1682637688.327 [CommandLine](INFO): Join timeout helper Thread ID is 7779
2023-04-27T23:21:28.406Z,1682637688.406 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-27T23:21:28.407Z,1682637688.407 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-04-27T23:21:28.427Z,1682637688.427 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-04-27T23:21:28.427Z,1682637688.427 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-04-27T23:21:28.427Z,1682637688.427 [CommandExec](INFO): Join timeout helper Thread ID is 7780
2023-04-27T23:21:28.428Z,1682637688.428 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-04-27T23:21:28.429Z,1682637688.429 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-04-27T23:21:28.429Z,1682637688.429 [controlThread](INFO): Join timeout helper Thread ID is 7781
2023-04-27T23:21:28.522Z,1682637688.522 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-27T23:21:28.523Z,1682637688.523 [controlThread](DEBUG): Uninitializing ControlThread
2023-04-27T23:21:28.523Z,1682637688.523 [AHRS_M2](INFO): Powering down
2023-04-27T23:21:28.666Z,1682637688.666 [DVL_micro](INFO): Powering down
2023-04-27T23:21:28.667Z,1682637688.667 [NAL9602](INFO): Powering down
2023-04-27T23:21:28.670Z,1682637688.670 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-04-27T23:21:28.671Z,1682637688.671 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-04-27T23:21:28.672Z,1682637688.672 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-04-27T23:21:28.672Z,1682637688.672 [MissionManager](INFO): Uninitializing Mission Default
2023-04-27T23:21:28.672Z,1682637688.672 [Default] Stopped
2023-04-27T23:21:28.673Z,1682637688.673 [Default](DEBUG): Aggregate::uninitialize Default
2023-04-27T23:21:28.673Z,1682637688.673 [Default:B.GoToSurface] Stopped
2023-04-27T23:21:28.673Z,1682637688.673 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-04-27T23:21:28.673Z,1682637688.673 [Default:CheckIn] Stopped
2023-04-27T23:21:28.673Z,1682637688.673 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-27T23:21:28.673Z,1682637688.673 [Default:CheckIn:Read_Iridium] Stopped
2023-04-27T23:21:28.676Z,1682637688.676 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2023-04-27T23:21:28.676Z,1682637688.676 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2023-04-27T23:21:28.676Z,1682637688.676 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2023-04-27T23:21:28.677Z,1682637688.677 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2023-04-27T23:21:28.677Z,1682637688.677 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2023-04-27T23:21:28.677Z,1682637688.677 [BuoyancyServo](INFO): Powering down
2023-04-27T23:21:28.690Z,1682637688.690 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-04-27T23:21:28.690Z,1682637688.690 [ElevatorServo](INFO): Powering down
2023-04-27T23:21:28.691Z,1682637688.691 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-04-27T23:21:28.691Z,1682637688.691 [MassServo](INFO): Powering down
2023-04-27T23:21:28.692Z,1682637688.692 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-04-27T23:21:28.692Z,1682637688.692 [RudderServo](INFO): Powering down
2023-04-27T23:21:28.693Z,1682637688.693 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2023-04-27T23:21:28.693Z,1682637688.693 [ThrusterHE](INFO): Powering down
2023-04-27T23:21:28.694Z,1682637688.694 [SBIT](DEBUG): Uninitialize SBIT Component.
2023-04-27T23:21:28.694Z,1682637688.694 [IBIT](DEBUG): Uninitialize IBIT Component.
2023-04-27T23:21:28.695Z,1682637688.695 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-04-27T23:21:28.695Z,1682637688.695 [CBIT](DEBUG): Powering off loads.
2023-04-27T23:21:28.706Z,1682637688.706 [CBIT](DEBUG): Disabling WDT.
2023-04-27T23:21:28.718Z,1682637688.718 [CBIT](DEBUG): Opening all GF detection circuits.
2023-04-27T23:21:28.719Z,1682637688.719 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-04-27T23:21:28.767Z,1682637688.767 [DAT ThreadHandler](INFO): Thread cancelled.
2023-04-27T23:21:28.772Z,1682637688.772 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-04-27T23:21:28.774Z,1682637688.774 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-04-27T23:21:28.782Z,1682637688.782 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-04-27T23:21:28.825Z,1682637688.825 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-04-27T23:21:28.892Z,1682637688.892 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-04-27T23:21:28.979Z,1682637688.979 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-04-27T23:21:29.004Z,1682637689.004 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-04-27T23:21:29.084Z,1682637689.084 [logger ThreadHandler](INFO): Thread cancelled.