2022-09-28T18:27:22.077Z,1664389642.077 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-09-28T18:27:24.085Z,1664389644.085 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.259281
2022-09-28T18:27:57.217Z,1664389677.217 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-09-28T18:28:09.413Z,1664389689.413 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-09-28T18:28:09.414Z,1664389689.414 [Default:CheckIn:C.Wait] Stopped
2022-09-28T18:28:09.414Z,1664389689.414 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-09-28T18:28:09.414Z,1664389689.414 [Default:CheckIn:D] Running Loop=1
2022-09-28T18:28:09.826Z,1664389689.826 [Default:CheckIn:D] Stopped
2022-09-28T18:28:09.826Z,1664389689.826 [Default:CheckIn:E] Running Loop=1
2022-09-28T18:28:10.216Z,1664389690.216 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 23.539876 min 
2022-09-28T18:28:10.216Z,1664389690.216 [Default:CheckIn:E] Stopped
2022-09-28T18:28:10.216Z,1664389690.216 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-09-28T18:28:10.216Z,1664389690.216 [Default:CheckIn] Stopped
2022-09-28T18:28:10.216Z,1664389690.216 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-09-28T18:28:10.217Z,1664389690.217 [Default:CheckIn](INFO): Running loop #5
2022-09-28T18:28:10.217Z,1664389690.217 [Default:CheckIn] Running Loop=5
2022-09-28T18:28:10.217Z,1664389690.217 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-09-28T18:28:10.217Z,1664389690.217 [Default:CheckIn:Read_GPS] Running Loop=1
2022-09-28T18:28:12.228Z,1664389692.228 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182819.00,A,3648.44017,N,12147.12231,W,5.443,183.22,280922,,,D*70
2022-09-28T18:28:12.230Z,1664389692.230 [NAL9602](INFO): GPS fix at 20220928T182819: (36.807336, -121.785372)
2022-09-28T18:28:12.241Z,1664389692.241 [Default:CheckIn:Read_GPS] Stopped
2022-09-28T18:28:12.241Z,1664389692.241 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-09-28T18:28:19.049Z,1664389699.049 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003418
2022-09-28T18:28:26.693Z,1664389706.693 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220928T030000/Courier0114.lzma
2022-09-28T18:28:27.691Z,1664389707.691 [DataOverHttps](INFO): Moved sent file to Logs/20220928T030000/Courier0114.lzma.bak
2022-09-28T18:28:27.691Z,1664389707.691 [DataOverHttps](INFO): SBD MOMSN=17210089
2022-09-28T18:28:33.459Z,1664389713.459 [NAL9602](INFO): SBD MO Status=1, MOMSN=21426, MT Status=0, MTMSN=0
2022-09-28T18:28:33.509Z,1664389713.509 [NAL9602](INFO): Sent 39 bytes from file Logs/20220928T030000/Courier0114.lzma
2022-09-28T18:28:33.509Z,1664389713.509 [NAL9602](INFO): Packets left to send: 0
2022-09-28T18:28:43.837Z,1664389723.837 [DataOverHttps](INFO): Sending 266 bytes from file Logs/20220928T182547/Courier0000.lzma
2022-09-28T18:28:44.838Z,1664389724.838 [DataOverHttps](INFO): Moved sent file to Logs/20220928T182547/Courier0000.lzma.bak
2022-09-28T18:28:44.839Z,1664389724.839 [DataOverHttps](INFO): SBD MOMSN=17210091
2022-09-28T18:29:01.028Z,1664389741.028 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220928T182547/Courier0003.lzma
2022-09-28T18:29:02.027Z,1664389742.027 [DataOverHttps](INFO): Moved sent file to Logs/20220928T182547/Courier0003.lzma.bak
2022-09-28T18:29:02.027Z,1664389742.027 [DataOverHttps](INFO): SBD MOMSN=17210095
2022-09-28T18:29:04.152Z,1664389744.152 [NAL9602](INFO): Not Powering down - fast GPS
2022-09-28T18:29:18.120Z,1664389758.120 [DataOverHttps](INFO): Sending 137 bytes from file Logs/20220928T030000/Express0115.lzma
2022-09-28T18:29:19.123Z,1664389759.123 [DataOverHttps](INFO): Moved sent file to Logs/20220928T030000/Express0115.lzma.bak
2022-09-28T18:29:19.123Z,1664389759.123 [DataOverHttps](INFO): SBD MOMSN=17210099
2022-09-28T18:29:35.365Z,1664389775.365 [DataOverHttps](INFO): Sending 346 bytes from file Logs/20220928T182547/Express0001.lzma
2022-09-28T18:29:36.367Z,1664389776.367 [DataOverHttps](INFO): Moved sent file to Logs/20220928T182547/Express0001.lzma.bak
2022-09-28T18:29:36.367Z,1664389776.367 [DataOverHttps](INFO): SBD MOMSN=17210112
2022-09-28T18:29:52.396Z,1664389792.396 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20220928T182547/Express0004.lzma
2022-09-28T18:29:53.761Z,1664389793.761 [DataOverHttps](INFO): Moved sent file to Logs/20220928T182547/Express0004.lzma.bak
2022-09-28T18:29:53.762Z,1664389793.762 [DataOverHttps](INFO): SBD MOMSN=17210168
2022-09-28T18:29:54.670Z,1664389794.670 [Default:CheckIn:Read_Iridium] Stopped
2022-09-28T18:29:54.670Z,1664389794.670 [Default:CheckIn:C.Wait] Running Loop=1
2022-09-28T18:29:54.670Z,1664389794.670 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-09-28T18:34:55.247Z,1664390095.247 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-09-28T18:34:55.247Z,1664390095.247 [Default:CheckIn:C.Wait] Stopped
2022-09-28T18:34:55.247Z,1664390095.247 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-09-28T18:34:55.247Z,1664390095.247 [Default:CheckIn:D] Running Loop=1
2022-09-28T18:34:55.646Z,1664390095.646 [Default:CheckIn:D] Stopped
2022-09-28T18:34:55.646Z,1664390095.646 [Default:CheckIn:E] Running Loop=1
2022-09-28T18:34:56.040Z,1664390096.040 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 30.303550 min 
2022-09-28T18:34:56.040Z,1664390096.040 [Default:CheckIn:E] Stopped
2022-09-28T18:34:56.040Z,1664390096.040 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-09-28T18:34:56.040Z,1664390096.040 [Default:CheckIn] Stopped
2022-09-28T18:34:56.041Z,1664390096.041 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-09-28T18:34:56.041Z,1664390096.041 [Default:CheckIn](INFO): Running loop #6
2022-09-28T18:34:56.041Z,1664390096.041 [Default:CheckIn] Running Loop=6
2022-09-28T18:34:56.041Z,1664390096.041 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-09-28T18:34:56.041Z,1664390096.041 [Default:CheckIn:Read_GPS] Running Loop=1
2022-09-28T18:34:58.052Z,1664390098.052 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183505.00,A,3648.14993,N,12147.22222,W,0.019,241.95,280922,,,D*7F
2022-09-28T18:34:58.054Z,1664390098.054 [NAL9602](INFO): GPS fix at 20220928T183505: (36.802499, -121.787037)
2022-09-28T18:34:58.065Z,1664390098.065 [Default:CheckIn:Read_GPS] Stopped
2022-09-28T18:34:58.065Z,1664390098.065 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-09-28T18:35:05.828Z,1664390105.828 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220928T182547/Courier0006.lzma
2022-09-28T18:35:06.830Z,1664390106.830 [DataOverHttps](INFO): Moved sent file to Logs/20220928T182547/Courier0006.lzma.bak
2022-09-28T18:35:06.831Z,1664390106.831 [DataOverHttps](INFO): SBD MOMSN=17210172
2022-09-28T18:35:11.028Z,1664390111.028 [NAL9602](INFO): SBD MO Status=0, MOMSN=21427, MT Status=0, MTMSN=0
2022-09-28T18:35:11.028Z,1664390111.028 [NAL9602](INFO): No messages in MT queue
2022-09-28T18:35:23.100Z,1664390123.100 [DataOverHttps](INFO): Sending 127 bytes from file Logs/20220928T182547/Express0007.lzma
2022-09-28T18:35:24.102Z,1664390124.102 [DataOverHttps](INFO): Moved sent file to Logs/20220928T182547/Express0007.lzma.bak
2022-09-28T18:35:24.103Z,1664390124.103 [DataOverHttps](INFO): SBD MOMSN=17210175
2022-09-28T18:35:25.534Z,1664390125.534 [Default:CheckIn:Read_Iridium] Stopped
2022-09-28T18:35:25.534Z,1664390125.534 [Default:CheckIn:C.Wait] Running Loop=1
2022-09-28T18:35:25.534Z,1664390125.534 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-09-28T18:35:41.682Z,1664390141.682 [NAL9602](INFO): Not Powering down - fast GPS
2022-09-28T18:40:26.103Z,1664390426.103 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-09-28T18:40:26.103Z,1664390426.103 [Default:CheckIn:C.Wait] Stopped
2022-09-28T18:40:26.104Z,1664390426.104 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-09-28T18:40:26.104Z,1664390426.104 [Default:CheckIn:D] Running Loop=1
2022-09-28T18:40:26.512Z,1664390426.512 [Default:CheckIn:D] Stopped
2022-09-28T18:40:26.513Z,1664390426.513 [Default:CheckIn:E] Running Loop=1
2022-09-28T18:40:26.918Z,1664390426.918 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 35.817989 min 
2022-09-28T18:40:26.918Z,1664390426.918 [Default:CheckIn:E] Stopped
2022-09-28T18:40:26.918Z,1664390426.918 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-09-28T18:40:26.918Z,1664390426.918 [Default:CheckIn] Stopped
2022-09-28T18:40:26.918Z,1664390426.918 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-09-28T18:40:26.918Z,1664390426.918 [Default:CheckIn](INFO): Running loop #7
2022-09-28T18:40:26.919Z,1664390426.919 [Default:CheckIn] Running Loop=7
2022-09-28T18:40:26.919Z,1664390426.919 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-09-28T18:40:26.919Z,1664390426.919 [Default:CheckIn:Read_GPS] Running Loop=1
2022-09-28T18:40:28.928Z,1664390428.928 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184036.00,A,3648.14982,N,12147.22184,W,0.019,241.95,280922,,,A*77
2022-09-28T18:40:28.930Z,1664390428.930 [NAL9602](INFO): GPS fix at 20220928T184036: (36.802497, -121.787031)
2022-09-28T18:40:28.965Z,1664390428.965 [Default:CheckIn:Read_GPS] Stopped
2022-09-28T18:40:28.965Z,1664390428.965 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-09-28T18:40:36.336Z,1664390436.336 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220928T182547/Courier0009.lzma
2022-09-28T18:40:37.339Z,1664390437.339 [DataOverHttps](INFO): Moved sent file to Logs/20220928T182547/Courier0009.lzma.bak
2022-09-28T18:40:37.339Z,1664390437.339 [DataOverHttps](INFO): SBD MOMSN=17210240
2022-09-28T18:40:39.087Z,1664390439.087 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2022-09-28T18:40:53.613Z,1664390453.613 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20220928T182547/Express0010.lzma
2022-09-28T18:40:54.615Z,1664390454.615 [DataOverHttps](INFO): Moved sent file to Logs/20220928T182547/Express0010.lzma.bak
2022-09-28T18:40:54.615Z,1664390454.615 [DataOverHttps](INFO): SBD MOMSN=17210243
2022-09-28T18:40:56.038Z,1664390456.038 [Default:CheckIn:Read_Iridium] Stopped
2022-09-28T18:40:56.038Z,1664390456.038 [Default:CheckIn:C.Wait] Running Loop=1
2022-09-28T18:40:56.038Z,1664390456.038 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-09-28T18:41:48.515Z,1664390508.515 [NAL9602](INFO): SBD MO Status=0, MOMSN=21428, MT Status=0, MTMSN=0
2022-09-28T18:41:48.515Z,1664390508.515 [NAL9602](INFO): No messages in MT queue
2022-09-28T18:42:19.222Z,1664390539.222 [NAL9602](INFO): Not Powering down - fast GPS
2022-09-28T18:45:56.592Z,1664390756.592 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-09-28T18:45:56.593Z,1664390756.593 [Default:CheckIn:C.Wait] Stopped
2022-09-28T18:45:56.593Z,1664390756.593 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-09-28T18:45:56.593Z,1664390756.593 [Default:CheckIn:D] Running Loop=1
2022-09-28T18:45:57.043Z,1664390757.043 [Default:CheckIn:D] Stopped
2022-09-28T18:45:57.043Z,1664390757.043 [Default:CheckIn:E] Running Loop=1
2022-09-28T18:45:57.393Z,1664390757.393 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 41.326839 min 
2022-09-28T18:45:57.393Z,1664390757.393 [Default:CheckIn:E] Stopped
2022-09-28T18:45:57.393Z,1664390757.393 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-09-28T18:45:57.393Z,1664390757.393 [Default:CheckIn] Stopped
2022-09-28T18:45:57.394Z,1664390757.394 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-09-28T18:45:57.394Z,1664390757.394 [Default:CheckIn](INFO): Running loop #8
2022-09-28T18:45:57.394Z,1664390757.394 [Default:CheckIn] Running Loop=8
2022-09-28T18:45:57.394Z,1664390757.394 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-09-28T18:45:57.394Z,1664390757.394 [Default:CheckIn:Read_GPS] Running Loop=1
2022-09-28T18:45:59.408Z,1664390759.408 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184607.00,A,3648.14210,N,12147.22748,W,0.039,203.61,280922,,,D*7F
2022-09-28T18:45:59.411Z,1664390759.411 [NAL9602](INFO): GPS fix at 20220928T184607: (36.802368, -121.787125)
2022-09-28T18:45:59.421Z,1664390759.421 [Default:CheckIn:Read_GPS] Stopped
2022-09-28T18:45:59.421Z,1664390759.421 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-09-28T18:46:07.448Z,1664390767.448 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20220928T182547/Courier0012.lzma
2022-09-28T18:46:08.451Z,1664390768.451 [DataOverHttps](INFO): Moved sent file to Logs/20220928T182547/Courier0012.lzma.bak
2022-09-28T18:46:08.451Z,1664390768.451 [DataOverHttps](INFO): SBD MOMSN=17210247
2022-09-28T18:46:13.135Z,1664390773.135 [NAL9602](INFO): SBD MO Status=0, MOMSN=21429, MT Status=0, MTMSN=0
2022-09-28T18:46:13.136Z,1664390773.136 [NAL9602](INFO): No messages in MT queue
2022-09-28T18:46:24.744Z,1664390784.744 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20220928T182547/Express0013.lzma
2022-09-28T18:46:25.746Z,1664390785.746 [DataOverHttps](INFO): Moved sent file to Logs/20220928T182547/Express0013.lzma.bak
2022-09-28T18:46:25.747Z,1664390785.747 [DataOverHttps](INFO): SBD MOMSN=17210250
2022-09-28T18:46:27.303Z,1664390787.303 [Default:CheckIn:Read_Iridium] Stopped
2022-09-28T18:46:27.303Z,1664390787.303 [Default:CheckIn:C.Wait] Running Loop=1
2022-09-28T18:46:27.303Z,1664390787.303 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-09-28T18:46:35.372Z,1664390795.372 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2022-09-28T18:46:43.836Z,1664390803.836 [NAL9602](INFO): Not Powering down - fast GPS
2022-09-28T18:51:27.858Z,1664391087.858 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-09-28T18:51:27.859Z,1664391087.859 [Default:CheckIn:C.Wait] Stopped
2022-09-28T18:51:27.859Z,1664391087.859 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-09-28T18:51:27.859Z,1664391087.859 [Default:CheckIn:D] Running Loop=1
2022-09-28T18:51:28.268Z,1664391088.268 [Default:CheckIn:D] Stopped
2022-09-28T18:51:28.268Z,1664391088.268 [Default:CheckIn:E] Running Loop=1
2022-09-28T18:51:28.672Z,1664391088.672 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 46.847253 min 
2022-09-28T18:51:28.672Z,1664391088.672 [Default:CheckIn:E] Stopped
2022-09-28T18:51:28.672Z,1664391088.672 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-09-28T18:51:28.672Z,1664391088.672 [Default:CheckIn] Stopped
2022-09-28T18:51:28.673Z,1664391088.673 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-09-28T18:51:28.673Z,1664391088.673 [Default:CheckIn](INFO): Running loop #9
2022-09-28T18:51:28.673Z,1664391088.673 [Default:CheckIn] Running Loop=9
2022-09-28T18:51:28.673Z,1664391088.673 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-09-28T18:51:28.673Z,1664391088.673 [Default:CheckIn:Read_GPS] Running Loop=1
2022-09-28T18:51:30.680Z,1664391090.680 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185138.00,A,3648.16349,N,12147.27847,W,0.739,296.91,280922,,,A*7E
2022-09-28T18:51:30.683Z,1664391090.683 [NAL9602](INFO): GPS fix at 20220928T185138: (36.802725, -121.787975)
2022-09-28T18:51:30.694Z,1664391090.694 [Default:CheckIn:Read_GPS] Stopped
2022-09-28T18:51:30.694Z,1664391090.694 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-09-28T18:51:37.880Z,1664391097.880 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220928T182547/Courier0015.lzma
2022-09-28T18:51:38.883Z,1664391098.883 [DataOverHttps](INFO): Moved sent file to Logs/20220928T182547/Courier0015.lzma.bak
2022-09-28T18:51:38.883Z,1664391098.883 [DataOverHttps](INFO): SBD MOMSN=17210323
2022-09-28T18:51:55.284Z,1664391115.284 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20220928T182547/Express0016.lzma
2022-09-28T18:51:56.286Z,1664391116.286 [DataOverHttps](INFO): Moved sent file to Logs/20220928T182547/Express0016.lzma.bak
2022-09-28T18:51:56.287Z,1664391116.287 [DataOverHttps](INFO): SBD MOMSN=17210326
2022-09-28T18:51:57.870Z,1664391117.870 [Default:CheckIn:Read_Iridium] Stopped
2022-09-28T18:51:57.870Z,1664391117.870 [Default:CheckIn:C.Wait] Running Loop=1
2022-09-28T18:51:57.870Z,1664391117.870 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-09-28T18:52:17.644Z,1664391137.644 [NAL9602](INFO): SBD MO Status=2, MOMSN=21430, MT Status=2, MTMSN=0
2022-09-28T18:52:17.645Z,1664391137.645 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-09-28T18:52:41.480Z,1664391161.480 [NAL9602](INFO): SBD MO Status=2, MOMSN=21430, MT Status=2, MTMSN=0
2022-09-28T18:52:41.480Z,1664391161.480 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-09-28T18:53:02.087Z,1664391182.087 [NAL9602](INFO): SBD MO Status=2, MOMSN=21430, MT Status=2, MTMSN=0
2022-09-28T18:53:02.088Z,1664391182.088 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-09-28T18:53:22.294Z,1664391202.294 [NAL9602](INFO): SBD MO Status=2, MOMSN=21430, MT Status=2, MTMSN=0
2022-09-28T18:53:22.294Z,1664391202.294 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-09-28T18:54:01.067Z,1664391241.067 [NAL9602](INFO): SBD MO Status=2, MOMSN=21430, MT Status=2, MTMSN=0
2022-09-28T18:54:01.068Z,1664391241.068 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-09-28T18:55:38.432Z,1664391338.432 [NAL9602](INFO): SBD MO Status=2, MOMSN=21430, MT Status=2, MTMSN=0
2022-09-28T18:55:38.432Z,1664391338.432 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-09-28T18:56:39.032Z,1664391399.032 [NAL9602](INFO): SBD MO Status=2, MOMSN=21430, MT Status=2, MTMSN=0
2022-09-28T18:56:39.032Z,1664391399.032 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-09-28T18:56:39.438Z,1664391399.438 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-09-28T18:56:58.436Z,1664391418.436 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-09-28T18:56:58.436Z,1664391418.436 [Default:CheckIn:C.Wait] Stopped
2022-09-28T18:56:58.436Z,1664391418.436 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-09-28T18:56:58.437Z,1664391418.437 [Default:CheckIn:D] Running Loop=1
2022-09-28T18:56:58.837Z,1664391418.837 [Default:CheckIn:D] Stopped
2022-09-28T18:56:58.837Z,1664391418.837 [Default:CheckIn:E] Running Loop=1
2022-09-28T18:56:59.272Z,1664391419.272 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.356738 min 
2022-09-28T18:56:59.272Z,1664391419.272 [Default:CheckIn:E] Stopped
2022-09-28T18:56:59.272Z,1664391419.272 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-09-28T18:56:59.272Z,1664391419.272 [Default:CheckIn] Stopped
2022-09-28T18:56:59.274Z,1664391419.274 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-09-28T18:56:59.274Z,1664391419.274 [Default:CheckIn](INFO): Running loop #10
2022-09-28T18:56:59.274Z,1664391419.274 [Default:CheckIn] Running Loop=10
2022-09-28T18:56:59.274Z,1664391419.274 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-09-28T18:56:59.274Z,1664391419.274 [Default:CheckIn:Read_GPS] Running Loop=1
2022-09-28T18:57:01.256Z,1664391421.256 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185708.00,A,3648.16664,N,12147.28167,W,0.078,322.45,280922,,,A*70
2022-09-28T18:57:01.258Z,1664391421.258 [NAL9602](INFO): GPS fix at 20220928T185708: (36.802777, -121.788028)
2022-09-28T18:57:01.313Z,1664391421.313 [Default:CheckIn:Read_GPS] Stopped
2022-09-28T18:57:01.313Z,1664391421.313 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-09-28T18:57:08.548Z,1664391428.548 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220928T182547/Courier0018.lzma
2022-09-28T18:57:09.551Z,1664391429.551 [DataOverHttps](INFO): Moved sent file to Logs/20220928T182547/Courier0018.lzma.bak
2022-09-28T18:57:09.551Z,1664391429.551 [DataOverHttps](INFO): SBD MOMSN=17210329
2022-09-28T18:57:25.705Z,1664391445.705 [DataOverHttps](INFO): Sending 231 bytes from file Logs/20220928T182547/Express0019.lzma
2022-09-28T18:57:26.706Z,1664391446.706 [DataOverHttps](INFO): Moved sent file to Logs/20220928T182547/Express0019.lzma.bak
2022-09-28T18:57:26.707Z,1664391446.707 [DataOverHttps](INFO): SBD MOMSN=17210332
2022-09-28T18:57:28.341Z,1664391448.341 [Default:CheckIn:Read_Iridium] Stopped
2022-09-28T18:57:28.341Z,1664391448.341 [Default:CheckIn:C.Wait] Running Loop=1
2022-09-28T18:57:28.341Z,1664391448.341 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-09-28T18:57:33.972Z,1664391453.972 [NAL9602](INFO): Not Powering down - fast GPS
2022-09-28T18:58:55.210Z,1664391535.210 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2022-09-28T19:02:12.738Z,1664391732.738 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2022-09-28T19:02:12.738Z,1664391732.738 [DropWeight] Hardware Fault, FailCount= 1
2022-09-28T19:02:12.738Z,1664391732.738 [DropWeight](ERROR): Hardware Fault
2022-09-28T19:02:12.797Z,1664391732.797 [CommandExec](FAULT): Scheduling is paused
2022-09-28T19:02:12.797Z,1664391732.797 [CBIT](INFO): Critical error at 20220928T190212
2022-09-28T19:02:12.800Z,1664391732.800 [CBIT](ERROR): Hardware Fault in component: DropWeight
2022-09-28T19:02:12.800Z,1664391732.800 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2022-09-28T19:02:13.267Z,1664391733.267 [CBIT](INFO): Critical error at 20220928T190212
2022-09-28T19:02:28.912Z,1664391748.912 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-09-28T19:02:28.912Z,1664391748.912 [Default:CheckIn:C.Wait] Stopped
2022-09-28T19:02:28.912Z,1664391748.912 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-09-28T19:02:28.912Z,1664391748.912 [Default:CheckIn:D] Running Loop=1
2022-09-28T19:02:29.347Z,1664391749.347 [Default:CheckIn:D] Stopped
2022-09-28T19:02:29.347Z,1664391749.347 [Default:CheckIn:E] Running Loop=1
2022-09-28T19:02:29.724Z,1664391749.724 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 57.865230 min 
2022-09-28T19:02:29.724Z,1664391749.724 [Default:CheckIn:E] Stopped
2022-09-28T19:02:29.724Z,1664391749.724 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-09-28T19:02:29.724Z,1664391749.724 [Default:CheckIn] Stopped
2022-09-28T19:02:29.724Z,1664391749.724 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-09-28T19:02:29.724Z,1664391749.724 [Default:CheckIn](INFO): Running loop #11
2022-09-28T19:02:29.724Z,1664391749.724 [Default:CheckIn] Running Loop=11
2022-09-28T19:02:29.724Z,1664391749.724 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-09-28T19:02:29.725Z,1664391749.725 [Default:CheckIn:Read_GPS] Running Loop=1
2022-09-28T19:02:31.731Z,1664391751.731 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190239.00,A,3648.16833,N,12147.28057,W,0.078,67.72,280922,,,A*4B
2022-09-28T19:02:31.750Z,1664391751.750 [NAL9602](INFO): GPS fix at 20220928T190239: (36.802805, -121.788010)
2022-09-28T19:02:31.761Z,1664391751.761 [Default:CheckIn:Read_GPS] Stopped
2022-09-28T19:02:31.761Z,1664391751.761 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-09-28T19:02:39.445Z,1664391759.445 [DataOverHttps](INFO): Sending 181 bytes from file Logs/20220928T182547/Courier0021.lzma
2022-09-28T19:02:40.447Z,1664391760.447 [DataOverHttps](INFO): Moved sent file to Logs/20220928T182547/Courier0021.lzma.bak
2022-09-28T19:02:40.447Z,1664391760.447 [DataOverHttps](INFO): SBD MOMSN=17210396
2022-09-28T19:02:52.335Z,1664391772.335 [NAL9602](INFO): SBD MO Status=2, MOMSN=21430, MT Status=2, MTMSN=0
2022-09-28T19:02:52.335Z,1664391772.335 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-09-28T19:02:56.745Z,1664391776.745 [DataOverHttps](INFO): Sending 180 bytes from file Logs/20220928T182547/Express0022.lzma
2022-09-28T19:02:57.747Z,1664391777.747 [DataOverHttps](INFO): Moved sent file to Logs/20220928T182547/Express0022.lzma.bak
2022-09-28T19:02:57.747Z,1664391777.747 [DataOverHttps](INFO): SBD MOMSN=17210401
2022-09-28T19:02:59.237Z,1664391779.237 [Default:CheckIn:Read_Iridium] Stopped
2022-09-28T19:02:59.237Z,1664391779.237 [Default:CheckIn:C.Wait] Running Loop=1
2022-09-28T19:02:59.237Z,1664391779.237 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-09-28T19:03:18.593Z,1664391798.593 [NAL9602](INFO): SBD MO Status=2, MOMSN=21430, MT Status=2, MTMSN=0
2022-09-28T19:03:18.594Z,1664391798.594 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-09-28T19:03:54.151Z,1664391834.151 [NAL9602](INFO): SBD MO Status=2, MOMSN=21430, MT Status=2, MTMSN=0
2022-09-28T19:03:54.151Z,1664391834.151 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-09-28T19:05:51.711Z,1664391951.711 [NAL9602](INFO): SBD MO Status=2, MOMSN=21430, MT Status=2, MTMSN=0
2022-09-28T19:05:51.711Z,1664391951.711 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-09-28T19:07:33.926Z,1664392053.926 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-09-28T19:07:54.554Z,1664392074.554 [CBIT](IMPORTANT): Beginning ground fault scan
2022-09-28T19:07:59.792Z,1664392079.792 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-09-28T19:07:59.792Z,1664392079.792 [Default:CheckIn:C.Wait] Stopped
2022-09-28T19:07:59.792Z,1664392079.792 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-09-28T19:07:59.792Z,1664392079.792 [Default:CheckIn:D] Running Loop=1
2022-09-28T19:08:00.192Z,1664392080.192 [Default:CheckIn:D] Stopped
2022-09-28T19:08:00.192Z,1664392080.192 [Default:CheckIn:E] Running Loop=1
2022-09-28T19:08:00.608Z,1664392080.608 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 63.379313 min 
2022-09-28T19:08:00.608Z,1664392080.608 [Default:CheckIn:E] Stopped
2022-09-28T19:08:00.608Z,1664392080.608 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-09-28T19:08:00.609Z,1664392080.609 [Default:CheckIn] Stopped
2022-09-28T19:08:00.609Z,1664392080.609 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-09-28T19:08:00.609Z,1664392080.609 [Default:CheckIn](INFO): Running loop #12
2022-09-28T19:08:00.609Z,1664392080.609 [Default:CheckIn] Running Loop=12
2022-09-28T19:08:00.609Z,1664392080.609 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-09-28T19:08:00.609Z,1664392080.609 [Default:CheckIn:Read_GPS] Running Loop=1
2022-09-28T19:08:02.611Z,1664392082.611 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190810.00,A,3648.17268,N,12147.28094,W,0.194,131.07,280922,,,A*73
2022-09-28T19:08:02.613Z,1664392082.613 [NAL9602](INFO): GPS fix at 20220928T190810: (36.802878, -121.788016)
2022-09-28T19:08:02.624Z,1664392082.624 [Default:CheckIn:Read_GPS] Stopped
2022-09-28T19:08:02.624Z,1664392082.624 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-09-28T19:08:05.497Z,1664392085.497 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.003906
CHAN A1 (24V): 0.009453
CHAN A2 (12V): -0.003436
CHAN A3 (5V): -0.000517
CHAN B0 (3.3V): 0.001620
CHAN B1 (3.15aV): 0.001252
CHAN B2 (3.15bV): 0.001110
CHAN B3 (GND): 0.001998
OPEN: 0.006044
Full Scale: +/- 1 mA
2022-09-28T19:08:10.588Z,1664392090.588 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220928T182547/Courier0024.lzma
2022-09-28T19:08:11.591Z,1664392091.591 [DataOverHttps](INFO): Moved sent file to Logs/20220928T182547/Courier0024.lzma.bak
2022-09-28T19:08:11.591Z,1664392091.591 [DataOverHttps](INFO): SBD MOMSN=17210405
2022-09-28T19:08:28.021Z,1664392108.021 [DataOverHttps](INFO): Sending 252 bytes from file Logs/20220928T182547/Express0025.lzma
2022-09-28T19:08:29.023Z,1664392109.023 [DataOverHttps](INFO): Moved sent file to Logs/20220928T182547/Express0025.lzma.bak
2022-09-28T19:08:29.023Z,1664392109.023 [DataOverHttps](INFO): SBD MOMSN=17210408
2022-09-28T19:08:30.512Z,1664392110.512 [Default:CheckIn:Read_Iridium] Stopped
2022-09-28T19:08:30.512Z,1664392110.512 [Default:CheckIn:C.Wait] Running Loop=1
2022-09-28T19:08:30.512Z,1664392110.512 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-09-28T19:08:34.930Z,1664392114.930 [NAL9602](INFO): Not Powering down - fast GPS
2022-09-28T19:13:31.109Z,1664392411.109 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-09-28T19:13:31.109Z,1664392411.109 [Default:CheckIn:C.Wait] Stopped
2022-09-28T19:13:31.109Z,1664392411.109 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-09-28T19:13:31.110Z,1664392411.110 [Default:CheckIn:D] Running Loop=1
2022-09-28T19:13:31.503Z,1664392411.503 [Default:CheckIn:D] Stopped
2022-09-28T19:13:31.503Z,1664392411.503 [Default:CheckIn:E] Running Loop=1
2022-09-28T19:13:31.879Z,1664392411.879 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 68.901164 min 
2022-09-28T19:13:31.880Z,1664392411.880 [Default:CheckIn:E] Stopped
2022-09-28T19:13:31.880Z,1664392411.880 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-09-28T19:13:31.880Z,1664392411.880 [Default:CheckIn] Stopped
2022-09-28T19:13:31.880Z,1664392411.880 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-09-28T19:13:31.880Z,1664392411.880 [Default:CheckIn](INFO): Running loop #13
2022-09-28T19:13:31.880Z,1664392411.880 [Default:CheckIn] Running Loop=13
2022-09-28T19:13:31.880Z,1664392411.880 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-09-28T19:13:31.880Z,1664392411.880 [Default:CheckIn:Read_GPS] Running Loop=1
2022-09-28T19:13:33.895Z,1664392413.895 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191341.00,A,3648.16511,N,12147.27993,W,0.350,131.07,280922,,,A*7E
2022-09-28T19:13:33.898Z,1664392413.898 [NAL9602](INFO): GPS fix at 20220928T191341: (36.802752, -121.787999)
2022-09-28T19:13:33.909Z,1664392413.909 [Default:CheckIn:Read_GPS] Stopped
2022-09-28T19:13:33.909Z,1664392413.909 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-09-28T19:13:42.092Z,1664392422.092 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20220928T182547/Courier0027.lzma
2022-09-28T19:13:43.095Z,1664392423.095 [DataOverHttps](INFO): Moved sent file to Logs/20220928T182547/Courier0027.lzma.bak
2022-09-28T19:13:43.095Z,1664392423.095 [DataOverHttps](INFO): SBD MOMSN=17210477
2022-09-28T19:13:59.614Z,1664392439.614 [DataOverHttps](INFO): Sending 288 bytes from file Logs/20220928T182547/Express0028.lzma
2022-09-28T19:14:00.615Z,1664392440.615 [DataOverHttps](INFO): Moved sent file to Logs/20220928T182547/Express0028.lzma.bak
2022-09-28T19:14:00.615Z,1664392440.615 [DataOverHttps](INFO): SBD MOMSN=17210480
2022-09-28T19:14:02.191Z,1664392442.191 [Default:CheckIn:Read_Iridium] Stopped
2022-09-28T19:14:02.191Z,1664392442.191 [Default:CheckIn:C.Wait] Running Loop=1
2022-09-28T19:14:02.191Z,1664392442.191 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-09-28T19:14:04.594Z,1664392444.594 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-09-28T19:14:04.669Z,1664392444.669 [NAL9602](ERROR): received: 
+CSQ:0

OK430, 2, 0, 0, 0

OK
2022-09-28T19:16:42.162Z,1664392602.162 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2022-09-28T19:16:42.164Z,1664392602.164 [BPC1](INFO): Received data from all battery sticks.
2022-09-28T19:17:12.915Z,1664392632.915 [CBIT](INFO): Clearing failed state for component DropWeight
2022-09-28T19:17:12.915Z,1664392632.915 [DropWeight] No Fault, FailCount= 1
2022-09-28T19:18:36.090Z,1664392716.090 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-09-28T19:19:02.760Z,1664392742.760 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-09-28T19:19:02.760Z,1664392742.760 [Default:CheckIn:C.Wait] Stopped
2022-09-28T19:19:02.760Z,1664392742.760 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-09-28T19:19:02.760Z,1664392742.760 [Default:CheckIn:D] Running Loop=1
2022-09-28T19:19:03.190Z,1664392743.190 [Default:CheckIn:D] Stopped
2022-09-28T19:19:03.190Z,1664392743.190 [Default:CheckIn:E] Running Loop=1
2022-09-28T19:19:03.576Z,1664392743.576 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 74.429281 min 
2022-09-28T19:19:03.597Z,1664392743.597 [Default:CheckIn:E] Stopped
2022-09-28T19:19:03.598Z,1664392743.598 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-09-28T19:19:03.598Z,1664392743.598 [Default:CheckIn] Stopped
2022-09-28T19:19:03.598Z,1664392743.598 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-09-28T19:19:03.598Z,1664392743.598 [Default:CheckIn](INFO): Running loop #14
2022-09-28T19:19:03.598Z,1664392743.598 [Default:CheckIn] Running Loop=14
2022-09-28T19:19:03.598Z,1664392743.598 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-09-28T19:19:03.598Z,1664392743.598 [Default:CheckIn:Read_GPS] Running Loop=1
2022-09-28T19:19:05.584Z,1664392745.584 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191913.00,A,3648.14140,N,12147.27394,W,7.678,158.24,280922,,,A*7A
2022-09-28T19:19:05.586Z,1664392745.586 [NAL9602](INFO): GPS fix at 20220928T191913: (36.802357, -121.787899)
2022-09-28T19:19:05.596Z,1664392745.596 [Default:CheckIn:Read_GPS] Stopped
2022-09-28T19:19:05.597Z,1664392745.597 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-09-28T19:19:13.660Z,1664392753.660 [DataOverHttps](INFO): Sending 203 bytes from file Logs/20220928T182547/Courier0030.lzma
2022-09-28T19:19:14.663Z,1664392754.663 [DataOverHttps](INFO): Moved sent file to Logs/20220928T182547/Courier0030.lzma.bak
2022-09-28T19:19:14.663Z,1664392754.663 [DataOverHttps](INFO): SBD MOMSN=17210485
2022-09-28T19:19:30.996Z,1664392770.996 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20220928T182547/Express0031.lzma
2022-09-28T19:19:31.995Z,1664392771.995 [DataOverHttps](INFO): Moved sent file to Logs/20220928T182547/Express0031.lzma.bak
2022-09-28T19:19:31.995Z,1664392771.995 [DataOverHttps](INFO): SBD MOMSN=17210489
2022-09-28T19:19:33.474Z,1664392773.474 [Default:CheckIn:Read_Iridium] Stopped
2022-09-28T19:19:33.474Z,1664392773.474 [Default:CheckIn:C.Wait] Running Loop=1
2022-09-28T19:19:33.474Z,1664392773.474 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-09-28T19:19:37.898Z,1664392777.898 [NAL9602](INFO): Not Powering down - fast GPS
2022-09-28T19:23:17.246Z,1664392997.246 [CommandExec](IMPORTANT): got command quit
2022-09-28T19:23:18.253Z,1664392998.253 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2022-09-28T19:23:18.253Z,1664392998.253 [CommandExec](INFO): Uninitializing the command executive.
2022-09-28T19:23:18.253Z,1664392998.253 [CommandExec](INFO): Uninitializing the command scheduler.
2022-09-28T19:23:18.253Z,1664392998.253 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-09-28T19:23:18.453Z,1664392998.453 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2022-09-28T19:23:18.453Z,1664392998.453 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2022-09-28T19:23:18.453Z,1664392998.453 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-09-28T19:23:18.453Z,1664392998.453 [NavChartDb](INFO): Join timeout helper Thread ID is 655
2022-09-28T19:23:18.521Z,1664392998.521 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2022-09-28T19:23:18.521Z,1664392998.521 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-09-28T19:23:18.533Z,1664392998.533 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler
2022-09-28T19:23:18.533Z,1664392998.533 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2022-09-28T19:23:18.534Z,1664392998.534 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 656
2022-09-28T19:23:18.701Z,1664392998.701 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread.
2022-09-28T19:23:18.701Z,1664392998.701 [WetLabsUBAT](INFO): Powering down
2022-09-28T19:23:18.702Z,1664392998.702 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2022-09-28T19:23:18.713Z,1664392998.713 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2022-09-28T19:23:18.713Z,1664392998.713 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-09-28T19:23:18.713Z,1664392998.713 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 657
2022-09-28T19:23:19.085Z,1664392999.085 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2022-09-28T19:23:19.085Z,1664392999.085 [WetLabsBB2FL](INFO): Powering down
2022-09-28T19:23:19.086Z,1664392999.086 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-09-28T19:23:19.089Z,1664392999.089 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2022-09-28T19:23:19.089Z,1664392999.089 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-09-28T19:23:19.089Z,1664392999.089 [CTD_Seabird](INFO): Join timeout helper Thread ID is 658
2022-09-28T19:23:19.969Z,1664392999.969 [CTD_Seabird](INFO): Powering down
2022-09-28T19:23:19.981Z,1664392999.981 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2022-09-28T19:23:19.981Z,1664392999.981 [CTD_Seabird](INFO): Powering down
2022-09-28T19:23:19.993Z,1664392999.993 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-09-28T19:23:20.001Z,1664393000.001 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2022-09-28T19:23:20.001Z,1664393000.001 [DAT ThreadHandler](INFO): Thread cancelled.
2022-09-28T19:23:20.001Z,1664393000.001 [DAT](INFO): Join timeout helper Thread ID is 659
2022-09-28T19:23:20.241Z,1664393000.241 [DAT](INFO): Powering down
2022-09-28T19:23:20.313Z,1664393000.313 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2022-09-28T19:23:20.314Z,1664393000.314 [DAT](INFO): Powering down
2022-09-28T19:23:20.314Z,1664393000.314 [DAT ThreadHandler](INFO): Thread cancelled.
2022-09-28T19:23:20.318Z,1664393000.318 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2022-09-28T19:23:20.318Z,1664393000.318 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-09-28T19:23:20.318Z,1664393000.318 [Radio_Surface](INFO): Join timeout helper Thread ID is 660
2022-09-28T19:23:20.689Z,1664393000.689 [Radio_Surface](INFO): Powering down
2022-09-28T19:23:20.690Z,1664393000.690 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2022-09-28T19:23:20.690Z,1664393000.690 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-09-28T19:23:20.710Z,1664393000.710 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2022-09-28T19:23:20.710Z,1664393000.710 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-09-28T19:23:20.710Z,1664393000.710 [Onboard](INFO): Join timeout helper Thread ID is 661
2022-09-28T19:23:20.857Z,1664393000.857 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2022-09-28T19:23:21.169Z,1664393001.169 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2022-09-28T19:23:21.169Z,1664393001.169 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-09-28T19:23:21.174Z,1664393001.174 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2022-09-28T19:23:21.174Z,1664393001.174 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-09-28T19:23:21.174Z,1664393001.174 [DataOverHttps](INFO): Join timeout helper Thread ID is 662
2022-09-28T19:23:21.861Z,1664393001.861 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2022-09-28T19:23:21.861Z,1664393001.861 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-09-28T19:23:21.874Z,1664393001.874 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2022-09-28T19:23:21.874Z,1664393001.874 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-09-28T19:23:21.874Z,1664393001.874 [BackseatComponent](INFO): Join timeout helper Thread ID is 663
2022-09-28T19:23:22.029Z,1664393002.029 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2022-09-28T19:23:22.029Z,1664393002.029 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-09-28T19:23:22.034Z,1664393002.034 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2022-09-28T19:23:22.034Z,1664393002.034 [logger ThreadHandler](INFO): Thread cancelled.
2022-09-28T19:23:22.034Z,1664393002.034 [logger](INFO): Join timeout helper Thread ID is 664
2022-09-28T19:23:22.037Z,1664393002.037 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2022-09-28T19:23:22.037Z,1664393002.037 [logger ThreadHandler](INFO): Thread cancelled.
2022-09-28T19:23:22.053Z,1664393002.053 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2022-09-28T19:23:22.054Z,1664393002.054 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-09-28T19:23:22.054Z,1664393002.054 [CommandLine](INFO): Join timeout helper Thread ID is 665
2022-09-28T19:23:22.133Z,1664393002.133 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2022-09-28T19:23:22.133Z,1664393002.133 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-09-28T19:23:22.135Z,1664393002.135 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2022-09-28T19:23:22.135Z,1664393002.135 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-09-28T19:23:22.135Z,1664393002.135 [CommandExec](INFO): Join timeout helper Thread ID is 666
2022-09-28T19:23:22.148Z,1664393002.148 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2022-09-28T19:23:22.148Z,1664393002.148 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-09-28T19:23:22.149Z,1664393002.149 [controlThread](INFO): Join timeout helper Thread ID is 667
2022-09-28T19:23:22.505Z,1664393002.505 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2022-09-28T19:23:22.506Z,1664393002.506 [controlThread](DEBUG): Uninitializing ControlThread
2022-09-28T19:23:22.506Z,1664393002.506 [AHRS_M2](INFO): Powering down
2022-09-28T19:23:22.578Z,1664393002.578 [NAL9602](INFO): Powering down
2022-09-28T19:23:22.649Z,1664393002.649 [RDI_Pathfinder](INFO): Powering down
2022-09-28T19:23:22.650Z,1664393002.650 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2022-09-28T19:23:22.651Z,1664393002.651 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2022-09-28T19:23:22.652Z,1664393002.652 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2022-09-28T19:23:22.652Z,1664393002.652 [MissionManager](INFO): Uninitializing Mission Default
2022-09-28T19:23:22.652Z,1664393002.652 [Default] Stopped
2022-09-28T19:23:22.652Z,1664393002.652 [Default](DEBUG): Aggregate::uninitialize Default
2022-09-28T19:23:22.652Z,1664393002.652 [Default:B.GoToSurface] Stopped
2022-09-28T19:23:22.653Z,1664393002.653 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-09-28T19:23:22.653Z,1664393002.653 [Default:CheckIn] Stopped
2022-09-28T19:23:22.654Z,1664393002.654 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-09-28T19:23:22.654Z,1664393002.654 [Default:CheckIn:C.Wait] Stopped
2022-09-28T19:23:22.654Z,1664393002.654 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-09-28T19:23:22.684Z,1664393002.684 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2022-09-28T19:23:22.684Z,1664393002.684 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2022-09-28T19:23:22.685Z,1664393002.685 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2022-09-28T19:23:22.685Z,1664393002.685 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2022-09-28T19:23:22.687Z,1664393002.687 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2022-09-28T19:23:22.687Z,1664393002.687 [BuoyancyServo](INFO): Powering down
2022-09-28T19:23:22.701Z,1664393002.701 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2022-09-28T19:23:22.701Z,1664393002.701 [ElevatorServo](INFO): Powering down
2022-09-28T19:23:22.702Z,1664393002.702 [MassServo](DEBUG): Uninitialize Mass Servo.
2022-09-28T19:23:22.702Z,1664393002.702 [MassServo](INFO): Powering down
2022-09-28T19:23:22.703Z,1664393002.703 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-09-28T19:23:22.703Z,1664393002.703 [RudderServo](INFO): Powering down
2022-09-28T19:23:22.704Z,1664393002.704 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2022-09-28T19:23:22.704Z,1664393002.704 [ThrusterServo](INFO): Powering down
2022-09-28T19:23:22.705Z,1664393002.705 [SBIT](DEBUG): Uninitialize SBIT Component.
2022-09-28T19:23:22.705Z,1664393002.705 [IBIT](DEBUG): Uninitialize IBIT Component.
2022-09-28T19:23:22.705Z,1664393002.705 [CBIT](DEBUG): Uninitialize CBIT Component.
2022-09-28T19:23:22.705Z,1664393002.705 [CBIT](DEBUG): Powering off loads.
2022-09-28T19:23:22.717Z,1664393002.717 [CBIT](DEBUG): Disabling WDT.
2022-09-28T19:23:22.729Z,1664393002.729 [CBIT](DEBUG): Opening all GF detection circuits.
2022-09-28T19:23:22.730Z,1664393002.730 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-09-28T19:23:22.804Z,1664393002.804 [DAT ThreadHandler](INFO): Thread cancelled.
2022-09-28T19:23:22.811Z,1664393002.811 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-09-28T19:23:22.813Z,1664393002.813 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-09-28T19:23:22.819Z,1664393002.819 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-09-28T19:23:22.862Z,1664393002.862 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-09-28T19:23:22.919Z,1664393002.919 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2022-09-28T19:23:22.921Z,1664393002.921 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-09-28T19:23:22.925Z,1664393002.925 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-09-28T19:23:22.933Z,1664393002.933 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-09-28T19:23:23.028Z,1664393003.028 [logger ThreadHandler](INFO): Thread cancelled.