2021-11-12T23:46:32.249Z,1636760792.249 [CommandExec](IMPORTANT): got command restart logs
2021-11-12T23:46:41.312Z,1636760801.312 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20211111T175901/Express0118.lzma
2021-11-12T23:46:44.322Z,1636760804.322 [DataOverHttps](INFO): Moved sent file to Logs/20211111T175901/Express0118.lzma.bak
2021-11-12T23:46:44.322Z,1636760804.322 [DataOverHttps](INFO): SBD MOMSN=16245999
2021-11-12T23:47:00.871Z,1636760820.871 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20211111T175901/Courier0120.lzma
2021-11-12T23:47:01.874Z,1636760821.874 [DataOverHttps](INFO): Moved sent file to Logs/20211111T175901/Courier0120.lzma.bak
2021-11-12T23:47:01.874Z,1636760821.874 [DataOverHttps](INFO): SBD MOMSN=16246002
2021-11-12T23:47:02.821Z,1636760822.821 [NAL9602](INFO): Not Powering down - fast GPS
2021-11-12T23:47:18.156Z,1636760838.156 [DataOverHttps](INFO): Sending 117 bytes from file Logs/20211111T175901/Express0121.lzma
2021-11-12T23:47:19.154Z,1636760839.154 [DataOverHttps](INFO): Moved sent file to Logs/20211111T175901/Express0121.lzma.bak
2021-11-12T23:47:19.154Z,1636760839.154 [DataOverHttps](INFO): SBD MOMSN=16246004
2021-11-12T23:47:20.680Z,1636760840.680 [Default:CheckIn:Read_Iridium] Stopped
2021-11-12T23:47:20.680Z,1636760840.680 [Default:CheckIn:C.Wait] Running Loop=1
2021-11-12T23:47:20.680Z,1636760840.680 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-11-12T23:49:39.956Z,1636760979.956 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.275272
2021-11-12T23:50:06.521Z,1636761006.521 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003405
2021-11-12T23:52:21.185Z,1636761141.185 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-11-12T23:52:21.185Z,1636761141.185 [Default:CheckIn:C.Wait] Stopped
2021-11-12T23:52:21.185Z,1636761141.185 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-11-12T23:52:21.185Z,1636761141.185 [Default:CheckIn:D] Running Loop=1
2021-11-12T23:52:21.589Z,1636761141.589 [Default:CheckIn:D] Stopped
2021-11-12T23:52:21.589Z,1636761141.589 [Default:CheckIn:E] Running Loop=1
2021-11-12T23:52:21.999Z,1636761141.999 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 32.183901 min 
2021-11-12T23:52:21.999Z,1636761141.999 [Default:CheckIn:E] Stopped
2021-11-12T23:52:21.999Z,1636761141.999 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-11-12T23:52:21.999Z,1636761141.999 [Default:CheckIn] Stopped
2021-11-12T23:52:21.999Z,1636761141.999 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-11-12T23:52:21.000Z,1636761142.000 [Default:CheckIn](INFO): Running loop #6
2021-11-12T23:52:21.000Z,1636761142.000 [Default:CheckIn] Running Loop=6
2021-11-12T23:52:22.000Z,1636761142.000 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-11-12T23:52:22.000Z,1636761142.000 [Default:CheckIn:Read_GPS] Running Loop=1
2021-11-12T23:52:24.003Z,1636761144.003 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235226.00,A,3648.04979,N,12149.03703,W,28.594,86.27,121121,,,D*70
2021-11-12T23:52:24.005Z,1636761144.005 [NAL9602](INFO): GPS fix at 20211112T235226: (36.800830, -121.817284)
2021-11-12T23:52:24.040Z,1636761144.040 [Default:CheckIn:Read_GPS] Stopped
2021-11-12T23:52:24.040Z,1636761144.040 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-11-12T23:52:44.201Z,1636761164.201 [NAL9602](INFO): SBD MO Status=0, MOMSN=17807, MT Status=0, MTMSN=0
2021-11-12T23:52:44.201Z,1636761164.201 [NAL9602](INFO): No messages in MT queue
2021-11-12T23:52:45.828Z,1636761165.828 [DataOverHttps](INFO): Sending 267 bytes from file Logs/20211112T234632/Courier0000.lzma
2021-11-12T23:52:46.830Z,1636761166.830 [DataOverHttps](INFO): Moved sent file to Logs/20211112T234632/Courier0000.lzma.bak
2021-11-12T23:52:46.830Z,1636761166.830 [DataOverHttps](INFO): SBD MOMSN=16246009
2021-11-12T23:53:03.388Z,1636761183.388 [DataOverHttps](INFO): Sending 390 bytes from file Logs/20211112T234632/Express0001.lzma
2021-11-12T23:53:04.390Z,1636761184.390 [DataOverHttps](INFO): Moved sent file to Logs/20211112T234632/Express0001.lzma.bak
2021-11-12T23:53:04.390Z,1636761184.390 [DataOverHttps](INFO): SBD MOMSN=16246013
2021-11-12T23:53:06.043Z,1636761186.043 [Default:CheckIn:Read_Iridium] Stopped
2021-11-12T23:53:06.044Z,1636761186.044 [Default:CheckIn:C.Wait] Running Loop=1
2021-11-12T23:53:06.044Z,1636761186.044 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-11-12T23:53:14.902Z,1636761194.902 [NAL9602](INFO): Not Powering down - fast GPS
2021-11-12T23:58:06.669Z,1636761486.669 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-11-12T23:58:06.669Z,1636761486.669 [Default:CheckIn:C.Wait] Stopped
2021-11-12T23:58:06.669Z,1636761486.669 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-11-12T23:58:06.669Z,1636761486.669 [Default:CheckIn:D] Running Loop=1
2021-11-12T23:58:07.005Z,1636761487.005 [Default:CheckIn:D] Stopped
2021-11-12T23:58:07.005Z,1636761487.005 [Default:CheckIn:E] Running Loop=1
2021-11-12T23:58:07.411Z,1636761487.411 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 37.940837 min 
2021-11-12T23:58:07.411Z,1636761487.411 [Default:CheckIn:E] Stopped
2021-11-12T23:58:07.411Z,1636761487.411 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-11-12T23:58:07.411Z,1636761487.411 [Default:CheckIn] Stopped
2021-11-12T23:58:07.411Z,1636761487.411 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-11-12T23:58:07.411Z,1636761487.411 [Default:CheckIn](INFO): Running loop #7
2021-11-12T23:58:07.411Z,1636761487.411 [Default:CheckIn] Running Loop=7
2021-11-12T23:58:07.412Z,1636761487.412 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-11-12T23:58:07.412Z,1636761487.412 [Default:CheckIn:Read_GPS] Running Loop=1
2021-11-12T23:58:09.423Z,1636761489.423 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235811.00,A,3648.42866,N,12147.11889,W,5.132,177.82,121121,,,D*74
2021-11-12T23:58:09.425Z,1636761489.425 [NAL9602](INFO): GPS fix at 20211112T235811: (36.807144, -121.785315)
2021-11-12T23:58:09.450Z,1636761489.450 [Default:CheckIn:Read_GPS] Stopped
2021-11-12T23:58:09.450Z,1636761489.450 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-11-12T23:58:16.835Z,1636761496.835 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20211112T234632/Courier0003.lzma
2021-11-12T23:58:17.838Z,1636761497.838 [DataOverHttps](INFO): Moved sent file to Logs/20211112T234632/Courier0003.lzma.bak
2021-11-12T23:58:17.838Z,1636761497.838 [DataOverHttps](INFO): SBD MOMSN=16246024
2021-11-12T23:58:34.124Z,1636761514.124 [DataOverHttps](INFO): Sending 147 bytes from file Logs/20211112T234632/Express0004.lzma
2021-11-12T23:58:35.126Z,1636761515.126 [DataOverHttps](INFO): Moved sent file to Logs/20211112T234632/Express0004.lzma.bak
2021-11-12T23:58:35.126Z,1636761515.126 [DataOverHttps](INFO): SBD MOMSN=16246027
2021-11-12T23:58:36.951Z,1636761516.951 [Default:CheckIn:Read_Iridium] Stopped
2021-11-12T23:58:36.951Z,1636761516.951 [Default:CheckIn:C.Wait] Running Loop=1
2021-11-12T23:58:36.951Z,1636761516.951 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-11-12T23:59:12.443Z,1636761552.443 [NAL9602](INFO): SBD MO Status=0, MOMSN=17808, MT Status=0, MTMSN=0
2021-11-12T23:59:12.443Z,1636761552.443 [NAL9602](INFO): No messages in MT queue
2021-11-12T23:59:43.146Z,1636761583.146 [NAL9602](INFO): Not Powering down - fast GPS
2021-11-13T00:03:37.485Z,1636761817.485 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-11-13T00:03:37.485Z,1636761817.485 [Default:CheckIn:C.Wait] Stopped
2021-11-13T00:03:37.485Z,1636761817.485 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-11-13T00:03:37.485Z,1636761817.485 [Default:CheckIn:D] Running Loop=1
2021-11-13T00:03:37.889Z,1636761817.889 [Default:CheckIn:D] Stopped
2021-11-13T00:03:37.889Z,1636761817.889 [Default:CheckIn:E] Running Loop=1
2021-11-13T00:03:38.290Z,1636761818.290 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 43.455570 min 
2021-11-13T00:03:38.290Z,1636761818.290 [Default:CheckIn:E] Stopped
2021-11-13T00:03:38.290Z,1636761818.290 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-11-13T00:03:38.290Z,1636761818.290 [Default:CheckIn] Stopped
2021-11-13T00:03:38.290Z,1636761818.290 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-11-13T00:03:38.290Z,1636761818.290 [Default:CheckIn](INFO): Running loop #8
2021-11-13T00:03:38.291Z,1636761818.291 [Default:CheckIn] Running Loop=8
2021-11-13T00:03:38.291Z,1636761818.291 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-11-13T00:03:38.291Z,1636761818.291 [Default:CheckIn:Read_GPS] Running Loop=1
2021-11-13T00:03:40.303Z,1636761820.303 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000342.00,A,3648.16054,N,12147.20864,W,1.380,231.93,131121,,,D*7B
2021-11-13T00:03:40.306Z,1636761820.306 [NAL9602](INFO): GPS fix at 20211113T000342: (36.802676, -121.786811)
2021-11-13T00:03:40.335Z,1636761820.335 [Default:CheckIn:Read_GPS] Stopped
2021-11-13T00:03:40.335Z,1636761820.335 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-11-13T00:03:47.567Z,1636761827.567 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20211112T234632/Courier0006.lzma
2021-11-13T00:03:48.573Z,1636761828.573 [DataOverHttps](INFO): Moved sent file to Logs/20211112T234632/Courier0006.lzma.bak
2021-11-13T00:03:48.573Z,1636761828.573 [DataOverHttps](INFO): SBD MOMSN=16246033
2021-11-13T00:04:05.020Z,1636761845.020 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20211112T234632/Express0007.lzma
2021-11-13T00:04:06.022Z,1636761846.022 [DataOverHttps](INFO): Moved sent file to Logs/20211112T234632/Express0007.lzma.bak
2021-11-13T00:04:06.022Z,1636761846.022 [DataOverHttps](INFO): SBD MOMSN=16246042
2021-11-13T00:04:06.632Z,1636761846.632 [NAL9602](INFO): SBD MO Status=2, MOMSN=17809, MT Status=2, MTMSN=0
2021-11-13T00:04:06.632Z,1636761846.632 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-11-13T00:04:07.414Z,1636761847.414 [Default:CheckIn:Read_Iridium] Stopped
2021-11-13T00:04:07.414Z,1636761847.414 [Default:CheckIn:C.Wait] Running Loop=1
2021-11-13T00:04:07.414Z,1636761847.414 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-11-13T00:04:25.978Z,1636761865.978 [NAL9602](INFO): SBD MO Status=0, MOMSN=17809, MT Status=0, MTMSN=0
2021-11-13T00:04:25.979Z,1636761865.979 [NAL9602](INFO): No messages in MT queue
2021-11-13T00:04:56.681Z,1636761896.681 [NAL9602](INFO): Not Powering down - fast GPS
2021-11-13T00:08:04.953Z,1636762084.953 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2021-11-13T00:09:07.995Z,1636762147.995 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-11-13T00:09:07.995Z,1636762147.995 [Default:CheckIn:C.Wait] Stopped
2021-11-13T00:09:07.995Z,1636762147.995 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-11-13T00:09:07.996Z,1636762147.996 [Default:CheckIn:D] Running Loop=1
2021-11-13T00:09:08.385Z,1636762148.385 [Default:CheckIn:D] Stopped
2021-11-13T00:09:08.385Z,1636762148.385 [Default:CheckIn:E] Running Loop=1
2021-11-13T00:09:08.851Z,1636762148.851 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 48.963843 min 
2021-11-13T00:09:08.851Z,1636762148.851 [Default:CheckIn:E] Stopped
2021-11-13T00:09:08.851Z,1636762148.851 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-11-13T00:09:08.851Z,1636762148.851 [Default:CheckIn] Stopped
2021-11-13T00:09:08.851Z,1636762148.851 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-11-13T00:09:08.851Z,1636762148.851 [Default:CheckIn](INFO): Running loop #9
2021-11-13T00:09:08.851Z,1636762148.851 [Default:CheckIn] Running Loop=9
2021-11-13T00:09:08.852Z,1636762148.852 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-11-13T00:09:08.852Z,1636762148.852 [Default:CheckIn:Read_GPS] Running Loop=1
2021-11-13T00:09:10.807Z,1636762150.807 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000912.00,A,3648.14879,N,12147.22409,W,0.019,197.75,131121,,,D*71
2021-11-13T00:09:10.809Z,1636762150.809 [NAL9602](INFO): GPS fix at 20211113T000912: (36.802480, -121.787068)
2021-11-13T00:09:10.842Z,1636762150.842 [Default:CheckIn:Read_GPS] Stopped
2021-11-13T00:09:10.842Z,1636762150.842 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-11-13T00:09:18.979Z,1636762158.980 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20211112T234632/Courier0009.lzma
2021-11-13T00:09:19.982Z,1636762159.982 [DataOverHttps](INFO): Moved sent file to Logs/20211112T234632/Courier0009.lzma.bak
2021-11-13T00:09:19.982Z,1636762159.982 [DataOverHttps](INFO): SBD MOMSN=16246067
2021-11-13T00:09:26.155Z,1636762166.155 [NAL9602](INFO): SBD MO Status=0, MOMSN=17810, MT Status=0, MTMSN=0
2021-11-13T00:09:26.155Z,1636762166.155 [NAL9602](INFO): No messages in MT queue
2021-11-13T00:09:36.445Z,1636762176.445 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20211112T234632/Express0010.lzma
2021-11-13T00:09:37.346Z,1636762177.346 [DataOverHttps](INFO): Moved sent file to Logs/20211112T234632/Express0010.lzma.bak
2021-11-13T00:09:37.346Z,1636762177.346 [DataOverHttps](INFO): SBD MOMSN=16246075
2021-11-13T00:09:38.713Z,1636762178.713 [Default:CheckIn:Read_Iridium] Stopped
2021-11-13T00:09:38.713Z,1636762178.713 [Default:CheckIn:C.Wait] Running Loop=1
2021-11-13T00:09:38.713Z,1636762178.713 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-11-13T00:09:56.857Z,1636762196.857 [NAL9602](INFO): Not Powering down - fast GPS
2021-11-13T00:11:56.872Z,1636762316.872 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2021-11-13T00:14:39.279Z,1636762479.279 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-11-13T00:14:39.279Z,1636762479.279 [Default:CheckIn:C.Wait] Stopped
2021-11-13T00:14:39.279Z,1636762479.279 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-11-13T00:14:39.279Z,1636762479.279 [Default:CheckIn:D] Running Loop=1
2021-11-13T00:14:39.692Z,1636762479.692 [Default:CheckIn:D] Stopped
2021-11-13T00:14:39.693Z,1636762479.693 [Default:CheckIn:E] Running Loop=1
2021-11-13T00:14:40.081Z,1636762480.081 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 54.485600 min 
2021-11-13T00:14:40.081Z,1636762480.081 [Default:CheckIn:E] Stopped
2021-11-13T00:14:40.081Z,1636762480.081 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-11-13T00:14:40.081Z,1636762480.081 [Default:CheckIn] Stopped
2021-11-13T00:14:40.081Z,1636762480.081 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-11-13T00:14:40.081Z,1636762480.081 [Default:CheckIn](INFO): Running loop #10
2021-11-13T00:14:40.082Z,1636762480.082 [Default:CheckIn] Running Loop=10
2021-11-13T00:14:40.082Z,1636762480.082 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-11-13T00:14:40.082Z,1636762480.082 [Default:CheckIn:Read_GPS] Running Loop=1
2021-11-13T00:14:42.095Z,1636762482.095 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001444.00,A,3648.14933,N,12147.22305,W,0.000,197.75,131121,,,D*72
2021-11-13T00:14:42.098Z,1636762482.098 [NAL9602](INFO): GPS fix at 20211113T001444: (36.802489, -121.787051)
2021-11-13T00:14:42.110Z,1636762482.110 [Default:CheckIn:Read_GPS] Stopped
2021-11-13T00:14:42.110Z,1636762482.110 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-11-13T00:14:49.359Z,1636762489.359 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20211112T234632/Courier0012.lzma
2021-11-13T00:14:50.362Z,1636762490.362 [DataOverHttps](INFO): Moved sent file to Logs/20211112T234632/Courier0012.lzma.bak
2021-11-13T00:14:50.362Z,1636762490.362 [DataOverHttps](INFO): SBD MOMSN=16246143
2021-11-13T00:15:06.816Z,1636762506.816 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20211112T234632/Express0013.lzma
2021-11-13T00:15:07.818Z,1636762507.818 [DataOverHttps](INFO): Moved sent file to Logs/20211112T234632/Express0013.lzma.bak
2021-11-13T00:15:07.818Z,1636762507.818 [DataOverHttps](INFO): SBD MOMSN=16246146
2021-11-13T00:15:09.225Z,1636762509.225 [Default:CheckIn:Read_Iridium] Stopped
2021-11-13T00:15:09.226Z,1636762509.226 [Default:CheckIn:C.Wait] Running Loop=1
2021-11-13T00:15:09.226Z,1636762509.226 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-11-13T00:17:41.511Z,1636762661.511 [NAL9602](INFO): SBD MO Status=0, MOMSN=17811, MT Status=0, MTMSN=0
2021-11-13T00:17:41.511Z,1636762661.511 [NAL9602](INFO): No messages in MT queue
2021-11-13T00:18:12.205Z,1636762692.205 [NAL9602](INFO): Not Powering down - fast GPS
2021-11-13T00:20:09.809Z,1636762809.809 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-11-13T00:20:09.809Z,1636762809.809 [Default:CheckIn:C.Wait] Stopped
2021-11-13T00:20:09.809Z,1636762809.809 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-11-13T00:20:09.810Z,1636762809.810 [Default:CheckIn:D] Running Loop=1
2021-11-13T00:20:10.309Z,1636762810.309 [Default:CheckIn:D] Stopped
2021-11-13T00:20:10.309Z,1636762810.309 [Default:CheckIn:E] Running Loop=1
2021-11-13T00:20:10.698Z,1636762810.698 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 59.995911 min 
2021-11-13T00:20:10.698Z,1636762810.698 [Default:CheckIn:E] Stopped
2021-11-13T00:20:10.698Z,1636762810.698 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-11-13T00:20:10.698Z,1636762810.698 [Default:CheckIn] Stopped
2021-11-13T00:20:10.698Z,1636762810.698 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-11-13T00:20:10.698Z,1636762810.698 [Default:CheckIn](INFO): Running loop #11
2021-11-13T00:20:10.698Z,1636762810.698 [Default:CheckIn] Running Loop=11
2021-11-13T00:20:10.698Z,1636762810.698 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-11-13T00:20:10.699Z,1636762810.699 [Default:CheckIn:Read_GPS] Running Loop=1
2021-11-13T00:20:12.623Z,1636762812.623 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,002014.00,A,3648.14888,N,12147.22333,W,0.058,197.75,131121,,,D*79
2021-11-13T00:20:12.625Z,1636762812.625 [NAL9602](INFO): GPS fix at 20211113T002014: (36.802481, -121.787055)
2021-11-13T00:20:12.678Z,1636762812.678 [Default:CheckIn:Read_GPS] Stopped
2021-11-13T00:20:12.678Z,1636762812.678 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-11-13T00:20:19.747Z,1636762819.747 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20211112T234632/Courier0015.lzma
2021-11-13T00:20:20.750Z,1636762820.750 [DataOverHttps](INFO): Moved sent file to Logs/20211112T234632/Courier0015.lzma.bak
2021-11-13T00:20:20.750Z,1636762820.750 [DataOverHttps](INFO): SBD MOMSN=16246157
2021-11-13T00:20:37.388Z,1636762837.388 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20211112T234632/Express0016.lzma
2021-11-13T00:20:38.390Z,1636762838.390 [DataOverHttps](INFO): Moved sent file to Logs/20211112T234632/Express0016.lzma.bak
2021-11-13T00:20:38.390Z,1636762838.390 [DataOverHttps](INFO): SBD MOMSN=16246161
2021-11-13T00:20:39.823Z,1636762839.823 [Default:CheckIn:Read_Iridium] Stopped
2021-11-13T00:20:39.823Z,1636762839.823 [Default:CheckIn:C.Wait] Running Loop=1
2021-11-13T00:20:39.823Z,1636762839.823 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-11-13T00:21:31.531Z,1636762891.531 [NAL9602](INFO): SBD MO Status=2, MOMSN=17812, MT Status=2, MTMSN=0
2021-11-13T00:21:31.531Z,1636762891.531 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-11-13T00:21:44.849Z,1636762904.849 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 6.
2021-11-13T00:21:44.858Z,1636762904.858 [BPC1](INFO): Received data from all battery sticks.
2021-11-13T00:22:21.603Z,1636762941.603 [NAL9602](INFO): SBD MO Status=0, MOMSN=17812, MT Status=0, MTMSN=0
2021-11-13T00:22:21.603Z,1636762941.603 [NAL9602](INFO): No messages in MT queue
2021-11-13T00:22:52.305Z,1636762972.305 [NAL9602](INFO): Not Powering down - fast GPS
2021-11-13T00:25:40.380Z,1636763140.380 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-11-13T00:25:40.380Z,1636763140.380 [Default:CheckIn:C.Wait] Stopped
2021-11-13T00:25:40.381Z,1636763140.381 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-11-13T00:25:40.381Z,1636763140.381 [Default:CheckIn:D] Running Loop=1
2021-11-13T00:25:40.809Z,1636763140.809 [Default:CheckIn:D] Stopped
2021-11-13T00:25:40.809Z,1636763140.809 [Default:CheckIn:E] Running Loop=1
2021-11-13T00:25:41.203Z,1636763141.203 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 65.504240 min 
2021-11-13T00:25:41.203Z,1636763141.203 [Default:CheckIn:E] Stopped
2021-11-13T00:25:41.203Z,1636763141.203 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-11-13T00:25:41.203Z,1636763141.203 [Default:CheckIn] Stopped
2021-11-13T00:25:41.203Z,1636763141.203 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-11-13T00:25:41.203Z,1636763141.203 [Default:CheckIn](INFO): Running loop #12
2021-11-13T00:25:41.204Z,1636763141.204 [Default:CheckIn] Running Loop=12
2021-11-13T00:25:41.204Z,1636763141.204 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-11-13T00:25:41.204Z,1636763141.204 [Default:CheckIn:Read_GPS] Running Loop=1
2021-11-13T00:25:43.195Z,1636763143.195 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,002545.00,A,3648.17461,N,12147.27444,W,2.546,297.56,131121,,,D*78
2021-11-13T00:25:43.197Z,1636763143.197 [NAL9602](INFO): GPS fix at 20211113T002545: (36.802910, -121.787907)
2021-11-13T00:25:43.237Z,1636763143.237 [Default:CheckIn:Read_GPS] Stopped
2021-11-13T00:25:43.237Z,1636763143.237 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-11-13T00:25:50.664Z,1636763150.664 [DataOverHttps](INFO): Sending 203 bytes from file Logs/20211112T234632/Courier0018.lzma
2021-11-13T00:25:51.668Z,1636763151.668 [DataOverHttps](INFO): Moved sent file to Logs/20211112T234632/Courier0018.lzma.bak
2021-11-13T00:25:51.668Z,1636763151.668 [DataOverHttps](INFO): SBD MOMSN=16246194
2021-11-13T00:26:08.160Z,1636763168.160 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20211112T234632/Express0019.lzma
2021-11-13T00:26:09.158Z,1636763169.158 [DataOverHttps](INFO): Moved sent file to Logs/20211112T234632/Express0019.lzma.bak
2021-11-13T00:26:09.158Z,1636763169.158 [DataOverHttps](INFO): SBD MOMSN=16246197
2021-11-13T00:26:10.663Z,1636763170.663 [NAL9602](INFO): SBD MO Status=0, MOMSN=17813, MT Status=0, MTMSN=0
2021-11-13T00:26:10.663Z,1636763170.663 [NAL9602](INFO): No messages in MT queue
2021-11-13T00:26:10.713Z,1636763170.713 [Default:CheckIn:Read_Iridium] Stopped
2021-11-13T00:26:10.713Z,1636763170.713 [Default:CheckIn:C.Wait] Running Loop=1
2021-11-13T00:26:10.713Z,1636763170.713 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-11-13T00:26:41.369Z,1636763201.369 [NAL9602](INFO): Not Powering down - fast GPS
2021-11-13T00:31:11.257Z,1636763471.257 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-11-13T00:31:11.258Z,1636763471.258 [Default:CheckIn:C.Wait] Stopped
2021-11-13T00:31:11.258Z,1636763471.258 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-11-13T00:31:11.258Z,1636763471.258 [Default:CheckIn:D] Running Loop=1
2021-11-13T00:31:11.664Z,1636763471.664 [Default:CheckIn:D] Stopped
2021-11-13T00:31:11.664Z,1636763471.664 [Default:CheckIn:E] Running Loop=1
2021-11-13T00:31:12.077Z,1636763472.077 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 71.018481 min 
2021-11-13T00:31:12.077Z,1636763472.077 [Default:CheckIn:E] Stopped
2021-11-13T00:31:12.077Z,1636763472.077 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-11-13T00:31:12.077Z,1636763472.077 [Default:CheckIn] Stopped
2021-11-13T00:31:12.078Z,1636763472.078 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-11-13T00:31:12.078Z,1636763472.078 [Default:CheckIn](INFO): Running loop #13
2021-11-13T00:31:12.078Z,1636763472.078 [Default:CheckIn] Running Loop=13
2021-11-13T00:31:12.078Z,1636763472.078 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-11-13T00:31:12.078Z,1636763472.078 [Default:CheckIn:Read_GPS] Running Loop=1
2021-11-13T00:31:14.071Z,1636763474.071 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,003116.00,A,3648.16155,N,12147.28201,W,0.972,236.52,131121,,,D*76
2021-11-13T00:31:14.073Z,1636763474.073 [NAL9602](INFO): GPS fix at 20211113T003116: (36.802692, -121.788033)
2021-11-13T00:31:14.086Z,1636763474.086 [Default:CheckIn:Read_GPS] Stopped
2021-11-13T00:31:14.086Z,1636763474.086 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-11-13T00:31:22.151Z,1636763482.151 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20211112T234632/Courier0021.lzma
2021-11-13T00:31:23.154Z,1636763483.154 [DataOverHttps](INFO): Moved sent file to Logs/20211112T234632/Courier0021.lzma.bak
2021-11-13T00:31:23.154Z,1636763483.154 [DataOverHttps](INFO): SBD MOMSN=16246201
2021-11-13T00:31:39.620Z,1636763499.620 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20211112T234632/Express0022.lzma
2021-11-13T00:31:40.622Z,1636763500.622 [DataOverHttps](INFO): Moved sent file to Logs/20211112T234632/Express0022.lzma.bak
2021-11-13T00:31:40.622Z,1636763500.622 [DataOverHttps](INFO): SBD MOMSN=16246204
2021-11-13T00:31:41.970Z,1636763501.970 [Default:CheckIn:Read_Iridium] Stopped
2021-11-13T00:31:41.970Z,1636763501.970 [Default:CheckIn:C.Wait] Running Loop=1
2021-11-13T00:31:41.970Z,1636763501.970 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-11-13T00:35:47.579Z,1636763747.579 [NAL9602](INFO): SBD MO Status=0, MOMSN=17814, MT Status=0, MTMSN=0
2021-11-13T00:35:47.579Z,1636763747.579 [NAL9602](INFO): No messages in MT queue
2021-11-13T00:36:18.301Z,1636763778.301 [NAL9602](INFO): Not Powering down - fast GPS
2021-11-13T00:36:42.633Z,1636763802.633 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-11-13T00:36:42.633Z,1636763802.633 [Default:CheckIn:C.Wait] Stopped
2021-11-13T00:36:42.633Z,1636763802.633 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-11-13T00:36:42.634Z,1636763802.634 [Default:CheckIn:D] Running Loop=1
2021-11-13T00:36:43.086Z,1636763803.086 [Default:CheckIn:D] Stopped
2021-11-13T00:36:43.086Z,1636763803.086 [Default:CheckIn:E] Running Loop=1
2021-11-13T00:36:43.374Z,1636763803.374 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 76.542179 min 
2021-11-13T00:36:43.375Z,1636763803.375 [Default:CheckIn:E] Stopped
2021-11-13T00:36:43.375Z,1636763803.375 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-11-13T00:36:43.375Z,1636763803.375 [Default:CheckIn] Stopped
2021-11-13T00:36:43.375Z,1636763803.375 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-11-13T00:36:43.376Z,1636763803.376 [Default:CheckIn](INFO): Running loop #14
2021-11-13T00:36:43.377Z,1636763803.377 [Default:CheckIn] Running Loop=14
2021-11-13T00:36:43.377Z,1636763803.377 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-11-13T00:36:43.377Z,1636763803.377 [Default:CheckIn:Read_GPS] Running Loop=1
2021-11-13T00:36:45.371Z,1636763805.371 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,003647.00,A,3648.16360,N,12147.28218,W,0.486,175.17,131121,,,A*7F
2021-11-13T00:36:45.373Z,1636763805.373 [NAL9602](INFO): GPS fix at 20211113T003647: (36.802727, -121.788036)
2021-11-13T00:36:45.405Z,1636763805.405 [Default:CheckIn:Read_GPS] Stopped
2021-11-13T00:36:45.406Z,1636763805.406 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-11-13T00:36:53.211Z,1636763813.211 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20211112T234632/Courier0024.lzma
2021-11-13T00:36:54.214Z,1636763814.214 [DataOverHttps](INFO): Moved sent file to Logs/20211112T234632/Courier0024.lzma.bak
2021-11-13T00:36:54.214Z,1636763814.214 [DataOverHttps](INFO): SBD MOMSN=16246213
2021-11-13T00:37:10.744Z,1636763830.744 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20211112T234632/Express0025.lzma
2021-11-13T00:37:11.746Z,1636763831.746 [DataOverHttps](INFO): Moved sent file to Logs/20211112T234632/Express0025.lzma.bak
2021-11-13T00:37:11.747Z,1636763831.747 [DataOverHttps](INFO): SBD MOMSN=16246216
2021-11-13T00:37:12.439Z,1636763832.439 [NAL9602](INFO): SBD MO Status=2, MOMSN=17815, MT Status=2, MTMSN=0
2021-11-13T00:37:12.439Z,1636763832.439 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-11-13T00:37:13.274Z,1636763833.274 [Default:CheckIn:Read_Iridium] Stopped
2021-11-13T00:37:13.274Z,1636763833.274 [Default:CheckIn:C.Wait] Running Loop=1
2021-11-13T00:37:13.274Z,1636763833.274 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-11-13T00:37:36.675Z,1636763856.675 [NAL9602](INFO): SBD MO Status=2, MOMSN=17815, MT Status=2, MTMSN=0
2021-11-13T00:37:36.675Z,1636763856.675 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-11-13T00:38:16.675Z,1636763896.675 [NAL9602](INFO): SBD MO Status=0, MOMSN=17815, MT Status=0, MTMSN=0
2021-11-13T00:38:16.675Z,1636763896.675 [NAL9602](INFO): No messages in MT queue
2021-11-13T00:38:47.375Z,1636763927.375 [NAL9602](INFO): Not Powering down - fast GPS
2021-11-13T00:42:13.828Z,1636764133.828 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-11-13T00:42:13.829Z,1636764133.829 [Default:CheckIn:C.Wait] Stopped
2021-11-13T00:42:13.829Z,1636764133.829 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-11-13T00:42:13.829Z,1636764133.829 [Default:CheckIn:D] Running Loop=1
2021-11-13T00:42:14.236Z,1636764134.236 [Default:CheckIn:D] Stopped
2021-11-13T00:42:14.236Z,1636764134.236 [Default:CheckIn:E] Running Loop=1
2021-11-13T00:42:14.665Z,1636764134.665 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 82.061353 min 
2021-11-13T00:42:14.666Z,1636764134.666 [Default:CheckIn:E] Stopped
2021-11-13T00:42:14.666Z,1636764134.666 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-11-13T00:42:14.666Z,1636764134.666 [Default:CheckIn] Stopped
2021-11-13T00:42:14.666Z,1636764134.666 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-11-13T00:42:14.666Z,1636764134.666 [Default:CheckIn](INFO): Running loop #15
2021-11-13T00:42:14.666Z,1636764134.666 [Default:CheckIn] Running Loop=15
2021-11-13T00:42:14.666Z,1636764134.666 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-11-13T00:42:14.666Z,1636764134.666 [Default:CheckIn:Read_GPS] Running Loop=1
2021-11-13T00:42:16.669Z,1636764136.669 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,004218.00,A,3648.16337,N,12147.28342,W,0.486,192.41,131121,,,A*70
2021-11-13T00:42:16.671Z,1636764136.671 [NAL9602](INFO): GPS fix at 20211113T004218: (36.802723, -121.788057)
2021-11-13T00:42:16.704Z,1636764136.704 [Default:CheckIn:Read_GPS] Stopped
2021-11-13T00:42:16.704Z,1636764136.704 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-11-13T00:42:24.887Z,1636764144.887 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20211112T234632/Courier0027.lzma
2021-11-13T00:42:25.890Z,1636764145.890 [DataOverHttps](INFO): Moved sent file to Logs/20211112T234632/Courier0027.lzma.bak
2021-11-13T00:42:25.890Z,1636764145.890 [DataOverHttps](INFO): SBD MOMSN=16246250
2021-11-13T00:42:42.456Z,1636764162.456 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20211112T234632/Express0028.lzma
2021-11-13T00:42:43.458Z,1636764163.458 [DataOverHttps](INFO): Moved sent file to Logs/20211112T234632/Express0028.lzma.bak
2021-11-13T00:42:43.458Z,1636764163.458 [DataOverHttps](INFO): SBD MOMSN=16246253
2021-11-13T00:42:45.006Z,1636764165.006 [Default:CheckIn:Read_Iridium] Stopped
2021-11-13T00:42:45.006Z,1636764165.006 [Default:CheckIn:C.Wait] Running Loop=1
2021-11-13T00:42:45.006Z,1636764165.006 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-11-13T00:47:18.477Z,1636764438.477 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2021-11-13T00:47:45.532Z,1636764465.532 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-11-13T00:47:45.533Z,1636764465.533 [Default:CheckIn:C.Wait] Stopped
2021-11-13T00:47:45.533Z,1636764465.533 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-11-13T00:47:45.533Z,1636764465.533 [Default:CheckIn:D] Running Loop=1
2021-11-13T00:47:45.934Z,1636764465.934 [Default:CheckIn:D] Stopped
2021-11-13T00:47:45.934Z,1636764465.934 [Default:CheckIn:E] Running Loop=1
2021-11-13T00:47:46.349Z,1636764466.349 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 87.589657 min 
2021-11-13T00:47:46.349Z,1636764466.349 [Default:CheckIn:E] Stopped
2021-11-13T00:47:46.350Z,1636764466.350 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-11-13T00:47:46.350Z,1636764466.350 [Default:CheckIn] Stopped
2021-11-13T00:47:46.350Z,1636764466.350 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-11-13T00:47:46.350Z,1636764466.350 [Default:CheckIn](INFO): Running loop #16
2021-11-13T00:47:46.350Z,1636764466.350 [Default:CheckIn] Running Loop=16
2021-11-13T00:47:46.350Z,1636764466.350 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-11-13T00:47:46.350Z,1636764466.350 [Default:CheckIn:Read_GPS] Running Loop=1
2021-11-13T00:47:48.347Z,1636764468.347 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,004750.00,A,3648.27531,N,12147.22909,W,2.838,16.69,131121,,,A*48
2021-11-13T00:47:48.350Z,1636764468.350 [NAL9602](INFO): GPS fix at 20211113T004750: (36.804589, -121.787151)
2021-11-13T00:47:48.362Z,1636764468.362 [Default:CheckIn:Read_GPS] Stopped
2021-11-13T00:47:48.362Z,1636764468.362 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-11-13T00:47:55.995Z,1636764475.995 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20211112T234632/Courier0030.lzma
2021-11-13T00:47:56.998Z,1636764476.998 [DataOverHttps](INFO): Moved sent file to Logs/20211112T234632/Courier0030.lzma.bak
2021-11-13T00:47:56.998Z,1636764476.998 [DataOverHttps](INFO): SBD MOMSN=16246270
2021-11-13T00:48:12.557Z,1636764492.557 [DataOverHttps](INFO): Sending 230 bytes from file Logs/20211112T234632/Express0031.lzma
2021-11-13T00:48:13.550Z,1636764493.550 [DataOverHttps](INFO): Moved sent file to Logs/20211112T234632/Express0031.lzma.bak
2021-11-13T00:48:13.550Z,1636764493.550 [DataOverHttps](INFO): SBD MOMSN=16246273
2021-11-13T00:48:15.021Z,1636764495.021 [Default:CheckIn:Read_Iridium] Stopped
2021-11-13T00:48:15.021Z,1636764495.021 [Default:CheckIn:C.Wait] Running Loop=1
2021-11-13T00:48:15.021Z,1636764495.021 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-11-13T00:48:20.669Z,1636764500.669 [NAL9602](INFO): Not Powering down - fast GPS
2021-11-13T00:51:34.378Z,1636764694.378 [CommandExec](IMPORTANT): got command quit
2021-11-13T00:51:35.384Z,1636764695.384 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2021-11-13T00:51:35.384Z,1636764695.384 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2021-11-13T00:51:35.384Z,1636764695.384 [CommandExec](INFO): Uninitializing the command executive.
2021-11-13T00:51:35.384Z,1636764695.384 [CommandExec](INFO): Uninitializing the command scheduler.
2021-11-13T00:51:35.385Z,1636764695.385 [CommandExec ThreadHandler](INFO): Thread cancelled.
2021-11-13T00:51:35.540Z,1636764695.540 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2021-11-13T00:51:35.540Z,1636764695.540 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2021-11-13T00:51:35.540Z,1636764695.540 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-11-13T00:51:35.541Z,1636764695.541 [NavChartDb](INFO): Join timeout helper Thread ID is 3181
2021-11-13T00:51:35.656Z,1636764695.656 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2021-11-13T00:51:35.657Z,1636764695.657 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-11-13T00:51:35.676Z,1636764695.676 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler
2021-11-13T00:51:35.676Z,1636764695.676 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2021-11-13T00:51:35.677Z,1636764695.677 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 3182
2021-11-13T00:51:35.974Z,1636764695.974 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread.
2021-11-13T00:51:35.975Z,1636764695.975 [WetLabsUBAT](INFO): Powering down
2021-11-13T00:51:35.975Z,1636764695.975 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2021-11-13T00:51:35.984Z,1636764695.984 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2021-11-13T00:51:35.984Z,1636764695.984 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-11-13T00:51:35.985Z,1636764695.985 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 3183
2021-11-13T00:51:35.985Z,1636764695.985 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2021-11-13T00:51:35.985Z,1636764695.985 [WetLabsBB2FL](INFO): Powering down
2021-11-13T00:51:35.986Z,1636764695.986 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-11-13T00:51:35.986Z,1636764695.986 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2021-11-13T00:51:35.986Z,1636764695.986 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-11-13T00:51:35.987Z,1636764695.987 [CTD_Seabird](INFO): Join timeout helper Thread ID is 3184
2021-11-13T00:51:36.590Z,1636764696.590 [CTD_Seabird](INFO): Powering down
2021-11-13T00:51:36.604Z,1636764696.604 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2021-11-13T00:51:36.604Z,1636764696.604 [CTD_Seabird](INFO): Powering down
2021-11-13T00:51:36.616Z,1636764696.616 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-11-13T00:51:36.628Z,1636764696.628 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2021-11-13T00:51:36.628Z,1636764696.628 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-11-13T00:51:36.628Z,1636764696.628 [Radio_Surface](INFO): Join timeout helper Thread ID is 3185
2021-11-13T00:51:36.824Z,1636764696.824 [Radio_Surface](INFO): Powering down
2021-11-13T00:51:36.825Z,1636764696.825 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2021-11-13T00:51:36.825Z,1636764696.825 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-11-13T00:51:36.841Z,1636764696.841 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2021-11-13T00:51:36.841Z,1636764696.841 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-11-13T00:51:36.841Z,1636764696.841 [Onboard](INFO): Join timeout helper Thread ID is 3186
2021-11-13T00:51:36.904Z,1636764696.904 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2021-11-13T00:51:38.640Z,1636764698.640 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2021-11-13T00:51:38.640Z,1636764698.640 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-11-13T00:51:38.657Z,1636764698.657 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2021-11-13T00:51:38.657Z,1636764698.657 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-11-13T00:51:38.657Z,1636764698.657 [DataOverHttps](INFO): Join timeout helper Thread ID is 3187
2021-11-13T00:51:38.912Z,1636764698.912 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2021-11-13T00:51:38.912Z,1636764698.912 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-11-13T00:51:38.917Z,1636764698.917 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2021-11-13T00:51:38.917Z,1636764698.917 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2021-11-13T00:51:38.917Z,1636764698.917 [BackseatComponent](INFO): Join timeout helper Thread ID is 3188
2021-11-13T00:51:38.918Z,1636764698.918 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2021-11-13T00:51:38.918Z,1636764698.918 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2021-11-13T00:51:38.919Z,1636764698.919 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2021-11-13T00:51:38.919Z,1636764698.919 [logger ThreadHandler](INFO): Thread cancelled.
2021-11-13T00:51:38.920Z,1636764698.920 [logger](INFO): Join timeout helper Thread ID is 3189
2021-11-13T00:51:38.940Z,1636764698.940 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2021-11-13T00:51:38.940Z,1636764698.940 [logger ThreadHandler](INFO): Thread cancelled.
2021-11-13T00:51:38.953Z,1636764698.953 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2021-11-13T00:51:38.953Z,1636764698.953 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-11-13T00:51:38.953Z,1636764698.953 [CommandLine](INFO): Join timeout helper Thread ID is 3190
2021-11-13T00:51:38.988Z,1636764698.988 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2021-11-13T00:51:38.988Z,1636764698.988 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-11-13T00:51:39.005Z,1636764699.005 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2021-11-13T00:51:39.005Z,1636764699.005 [CommandExec ThreadHandler](INFO): Thread cancelled.
2021-11-13T00:51:39.005Z,1636764699.005 [CommandExec](INFO): Join timeout helper Thread ID is 3191
2021-11-13T00:51:39.017Z,1636764699.017 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2021-11-13T00:51:39.017Z,1636764699.017 [controlThread ThreadHandler](INFO): Thread cancelled.
2021-11-13T00:51:39.017Z,1636764699.017 [controlThread](INFO): Join timeout helper Thread ID is 3192
2021-11-13T00:51:39.072Z,1636764699.072 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2021-11-13T00:51:39.072Z,1636764699.072 [controlThread](DEBUG): Uninitializing ControlThread
2021-11-13T00:51:39.073Z,1636764699.073 [AHRS_M2](INFO): Powering down
2021-11-13T00:51:39.145Z,1636764699.145 [NAL9602](INFO): Powering down
2021-11-13T00:51:39.216Z,1636764699.216 [RDI_Pathfinder](INFO): Powering down
2021-11-13T00:51:39.217Z,1636764699.217 [DAT](INFO): Powering down
2021-11-13T00:51:39.289Z,1636764699.289 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2021-11-13T00:51:39.290Z,1636764699.290 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2021-11-13T00:51:39.290Z,1636764699.290 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2021-11-13T00:51:39.291Z,1636764699.291 [MissionManager](INFO): Uninitializing Mission Default
2021-11-13T00:51:39.291Z,1636764699.291 [Default] Stopped
2021-11-13T00:51:39.291Z,1636764699.291 [Default](DEBUG): Aggregate::uninitialize Default
2021-11-13T00:51:39.291Z,1636764699.291 [Default:B.GoToSurface] Stopped
2021-11-13T00:51:39.291Z,1636764699.291 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-11-13T00:51:39.291Z,1636764699.291 [Default:CheckIn] Stopped
2021-11-13T00:51:39.292Z,1636764699.292 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-11-13T00:51:39.292Z,1636764699.292 [Default:CheckIn:C.Wait] Stopped
2021-11-13T00:51:39.292Z,1636764699.292 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-11-13T00:51:39.295Z,1636764699.295 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2021-11-13T00:51:39.295Z,1636764699.295 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2021-11-13T00:51:39.295Z,1636764699.295 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2021-11-13T00:51:39.296Z,1636764699.296 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2021-11-13T00:51:39.296Z,1636764699.296 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2021-11-13T00:51:39.296Z,1636764699.296 [BuoyancyServo](INFO): Powering down
2021-11-13T00:51:39.308Z,1636764699.308 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2021-11-13T00:51:39.308Z,1636764699.308 [ElevatorServo](INFO): Powering down
2021-11-13T00:51:39.309Z,1636764699.309 [MassServo](DEBUG): Uninitialize Mass Servo.
2021-11-13T00:51:39.309Z,1636764699.309 [MassServo](INFO): Powering down
2021-11-13T00:51:39.310Z,1636764699.310 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2021-11-13T00:51:39.310Z,1636764699.310 [RudderServo](INFO): Powering down
2021-11-13T00:51:39.311Z,1636764699.311 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2021-11-13T00:51:39.311Z,1636764699.311 [ThrusterServo](INFO): Powering down
2021-11-13T00:51:39.312Z,1636764699.312 [SBIT](DEBUG): Uninitialize SBIT Component.
2021-11-13T00:51:39.313Z,1636764699.313 [IBIT](DEBUG): Uninitialize IBIT Component.
2021-11-13T00:51:39.313Z,1636764699.313 [CBIT](DEBUG): Uninitialize CBIT Component.
2021-11-13T00:51:39.313Z,1636764699.313 [CBIT](DEBUG): Powering off loads.
2021-11-13T00:51:39.324Z,1636764699.324 [CBIT](DEBUG): Disabling WDT.
2021-11-13T00:51:39.336Z,1636764699.336 [CBIT](DEBUG): Opening all GF detection circuits.
2021-11-13T00:51:39.337Z,1636764699.337 [controlThread ThreadHandler](INFO): Thread cancelled.
2021-11-13T00:51:39.455Z,1636764699.455 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-11-13T00:51:39.458Z,1636764699.458 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-11-13T00:51:39.477Z,1636764699.477 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-11-13T00:51:39.566Z,1636764699.566 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2021-11-13T00:51:39.687Z,1636764699.687 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2021-11-13T00:51:39.689Z,1636764699.689 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-11-13T00:51:39.693Z,1636764699.693 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-11-13T00:51:39.711Z,1636764699.711 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-11-13T00:51:39.986Z,1636764699.986 [logger ThreadHandler](INFO): Thread cancelled.