2019-11-05T00:44:24.657Z,1572914664.657 [CommandLine](IMPORTANT): got command restart logs
2019-11-05T00:45:08.944Z,1572914708.944 [NAL9602](INFO): SBD MO Status=2, MOMSN=9844, MT Status=2, MTMSN=0
2019-11-05T00:45:08.945Z,1572914708.945 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-11-05T00:45:25.104Z,1572914725.104 [NAL9602](INFO): SBD MO Status=2, MOMSN=9844, MT Status=2, MTMSN=0
2019-11-05T00:45:25.104Z,1572914725.104 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-11-05T00:45:50.970Z,1572914750.970 [NAL9602](INFO): SBD MO Status=2, MOMSN=9844, MT Status=2, MTMSN=0
2019-11-05T00:45:50.970Z,1572914750.970 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-11-05T00:45:51.363Z,1572914751.363 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-11-05T00:46:22.065Z,1572914782.065 [NAL9602](INFO): Not Powering down - fast GPS
2019-11-05T00:46:43.150Z,1572914803.150 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-11-05T00:46:43.150Z,1572914803.150 [Default:CheckIn:C.Wait] Stopped
2019-11-05T00:46:43.150Z,1572914803.150 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-11-05T00:46:43.150Z,1572914803.150 [Default:CheckIn:D] Running Loop=1
2019-11-05T00:46:43.546Z,1572914803.546 [Default:CheckIn:D] Stopped
2019-11-05T00:46:43.546Z,1572914803.546 [Default:CheckIn:E] Running Loop=1
2019-11-05T00:46:43.944Z,1572914803.944 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 35.119234 min 
2019-11-05T00:46:43.944Z,1572914803.944 [Default:CheckIn:E] Stopped
2019-11-05T00:46:43.944Z,1572914803.944 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-11-05T00:46:43.944Z,1572914803.944 [Default:CheckIn] Stopped
2019-11-05T00:46:43.944Z,1572914803.944 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-11-05T00:46:43.969Z,1572914803.969 [Default:CheckIn](INFO): Running loop #5
2019-11-05T00:46:43.969Z,1572914803.969 [Default:CheckIn] Running Loop=5
2019-11-05T00:46:43.969Z,1572914803.969 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-11-05T00:46:43.969Z,1572914803.969 [Default:CheckIn:Read_GPS] Running Loop=1
2019-11-05T00:46:45.906Z,1572914805.906 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,004646.00,A,3648.48786,N,12149.45075,W,4.413,4.44,051119,,,A*77
2019-11-05T00:46:45.908Z,1572914805.908 [NAL9602](INFO): GPS fix at 20191105T004646: (36.808131, -121.824179)
2019-11-05T00:46:45.970Z,1572914805.970 [Default:CheckIn:Read_GPS] Stopped
2019-11-05T00:46:45.970Z,1572914805.970 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-11-05T00:46:46.902Z,1572914806.902 [DataOverHttps](INFO): Sending 214 bytes from file Logs/20191105T004424/Courier0000.lzma
2019-11-05T00:46:48.518Z,1572914808.518 [DataOverHttps](INFO): Moved sent file to Logs/20191105T004424/Courier0000.lzma.bak
2019-11-05T00:46:48.518Z,1572914808.518 [DataOverHttps](INFO): SBD MOMSN=11966925
2019-11-05T00:47:02.140Z,1572914822.140 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-11-05T00:47:02.140Z,1572914822.140 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,19110417584964,35.0, -0.1,   0.0, 0
2019-11-05T00:47:06.516Z,1572914826.516 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20191105T003835/Express0004.lzma
2019-11-05T00:47:08.524Z,1572914828.524 [DataOverHttps](INFO): Moved sent file to Logs/20191105T003835/Express0004.lzma.bak
2019-11-05T00:47:08.524Z,1572914828.524 [DataOverHttps](INFO): SBD MOMSN=11966929
2019-11-05T00:47:33.233Z,1572914853.233 [DataOverHttps](INFO): Sending 425 bytes from file Logs/20191105T004424/Express0001.lzma
2019-11-05T00:47:35.239Z,1572914855.239 [DataOverHttps](INFO): Moved sent file to Logs/20191105T004424/Express0001.lzma.bak
2019-11-05T00:47:35.239Z,1572914855.239 [DataOverHttps](INFO): SBD MOMSN=11966932
2019-11-05T00:47:54.280Z,1572914874.280 [DataOverHttps](INFO): Sending 52 bytes from file Logs/20191105T004424/Express0002.lzma
2019-11-05T00:47:56.286Z,1572914876.286 [DataOverHttps](INFO): Moved sent file to Logs/20191105T004424/Express0002.lzma.bak
2019-11-05T00:47:56.286Z,1572914876.286 [DataOverHttps](INFO): SBD MOMSN=11966937
2019-11-05T00:47:57.500Z,1572914877.500 [Default:CheckIn:Read_Iridium] Stopped
2019-11-05T00:47:57.500Z,1572914877.500 [Default:CheckIn:C.Wait] Running Loop=1
2019-11-05T00:47:57.500Z,1572914877.500 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-11-05T00:49:04.505Z,1572914944.505 [NAL9602](INFO): SBD MO Status=2, MOMSN=9844, MT Status=2, MTMSN=0
2019-11-05T00:49:04.505Z,1572914944.505 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-11-05T00:49:54.600Z,1572914994.600 [NAL9602](INFO): SBD MO Status=2, MOMSN=9844, MT Status=2, MTMSN=0
2019-11-05T00:49:54.600Z,1572914994.600 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-11-05T00:50:33.384Z,1572915033.384 [NAL9602](INFO): SBD MO Status=2, MOMSN=9844, MT Status=2, MTMSN=0
2019-11-05T00:50:33.385Z,1572915033.385 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-11-05T00:51:03.678Z,1572915063.678 [NAL9602](INFO): SBD MO Status=2, MOMSN=9844, MT Status=2, MTMSN=0
2019-11-05T00:51:03.678Z,1572915063.678 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-11-05T00:51:28.737Z,1572915088.737 [NAL9602](INFO): SBD MO Status=2, MOMSN=9844, MT Status=2, MTMSN=0
2019-11-05T00:51:28.737Z,1572915088.737 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-11-05T00:51:56.612Z,1572915116.612 [NAL9602](INFO): SBD MO Status=2, MOMSN=9844, MT Status=2, MTMSN=0
2019-11-05T00:51:56.613Z,1572915116.613 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-11-05T00:51:57.019Z,1572915117.019 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-11-05T00:52:27.727Z,1572915147.727 [NAL9602](INFO): Not Powering down - fast GPS
2019-11-05T00:52:58.087Z,1572915178.087 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-11-05T00:52:58.087Z,1572915178.087 [Default:CheckIn:C.Wait] Stopped
2019-11-05T00:52:58.087Z,1572915178.087 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-11-05T00:52:58.087Z,1572915178.087 [Default:CheckIn:D] Running Loop=1
2019-11-05T00:52:58.452Z,1572915178.452 [Default:CheckIn:D] Stopped
2019-11-05T00:52:58.453Z,1572915178.453 [Default:CheckIn:E] Running Loop=1
2019-11-05T00:52:58.887Z,1572915178.887 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 41.367676 min 
2019-11-05T00:52:58.887Z,1572915178.887 [Default:CheckIn:E] Stopped
2019-11-05T00:52:58.887Z,1572915178.887 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-11-05T00:52:58.887Z,1572915178.887 [Default:CheckIn] Stopped
2019-11-05T00:52:58.888Z,1572915178.888 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-11-05T00:52:58.888Z,1572915178.888 [Default:CheckIn](INFO): Running loop #6
2019-11-05T00:52:58.888Z,1572915178.888 [Default:CheckIn] Running Loop=6
2019-11-05T00:52:58.888Z,1572915178.888 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-11-05T00:52:58.888Z,1572915178.888 [Default:CheckIn:Read_GPS] Running Loop=1
2019-11-05T00:53:00.849Z,1572915180.849 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,005301.00,A,3648.51153,N,12149.40852,W,0.467,11.51,051119,,,A*49
2019-11-05T00:53:00.851Z,1572915180.851 [NAL9602](INFO): GPS fix at 20191105T005301: (36.808526, -121.823475)
2019-11-05T00:53:00.904Z,1572915180.904 [Default:CheckIn:Read_GPS] Stopped
2019-11-05T00:53:00.904Z,1572915180.904 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-11-05T00:53:08.964Z,1572915188.964 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20191105T004424/Courier0005.lzma
2019-11-05T00:53:10.971Z,1572915190.971 [DataOverHttps](INFO): Moved sent file to Logs/20191105T004424/Courier0005.lzma.bak
2019-11-05T00:53:10.971Z,1572915190.971 [DataOverHttps](INFO): SBD MOMSN=11966939
2019-11-05T00:54:11.234Z,1572915251.234 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.263008
2019-11-05T00:54:42.258Z,1572915282.258 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003904
2019-11-05T00:54:50.124Z,1572915290.124 [DataOverHttps](INFO): Sending 195 bytes from file Logs/20191105T004424/Express0007.lzma
2019-11-05T00:54:52.134Z,1572915292.134 [DataOverHttps](INFO): Moved sent file to Logs/20191105T004424/Express0007.lzma.bak
2019-11-05T00:54:52.135Z,1572915292.135 [DataOverHttps](INFO): SBD MOMSN=11966942
2019-11-05T00:54:52.811Z,1572915292.811 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2019-11-05T00:54:52.811Z,1572915292.811 [RDI_Pathfinder](ERROR): Failed to parse:
:BD +0.00,        +0.00,        +0.00,   0.00,1132.95
2019-11-05T00:54:53.599Z,1572915293.599 [Default:CheckIn:Read_Iridium] Stopped
2019-11-05T00:54:53.600Z,1572915293.600 [Default:CheckIn:C.Wait] Running Loop=1
2019-11-05T00:54:53.600Z,1572915293.600 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-11-05T00:56:01.436Z,1572915361.436 [NAL9602](INFO): SBD MO Status=2, MOMSN=9844, MT Status=2, MTMSN=0
2019-11-05T00:56:01.437Z,1572915361.437 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-11-05T00:58:03.447Z,1572915483.447 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-11-05T00:58:24.798Z,1572915504.798 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.951813
2019-11-05T00:58:34.149Z,1572915514.149 [NAL9602](INFO): Not Powering down - fast GPS
2019-11-05T00:58:54.778Z,1572915534.778 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.015695
2019-11-05T00:58:59.228Z,1572915539.228 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2019-11-05T00:58:59.229Z,1572915539.229 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2019-11-05T00:59:54.188Z,1572915594.188 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-11-05T00:59:54.209Z,1572915594.209 [Default:CheckIn:C.Wait] Stopped
2019-11-05T00:59:54.209Z,1572915594.209 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-11-05T00:59:54.209Z,1572915594.209 [Default:CheckIn:D] Running Loop=1
2019-11-05T00:59:54.617Z,1572915594.617 [Default:CheckIn:D] Stopped
2019-11-05T00:59:54.617Z,1572915594.617 [Default:CheckIn:E] Running Loop=1
2019-11-05T00:59:55.015Z,1572915595.015 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 48.303756 min 
2019-11-05T00:59:55.015Z,1572915595.015 [Default:CheckIn:E] Stopped
2019-11-05T00:59:55.015Z,1572915595.015 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-11-05T00:59:55.015Z,1572915595.015 [Default:CheckIn] Stopped
2019-11-05T00:59:55.015Z,1572915595.015 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-11-05T00:59:55.016Z,1572915595.016 [Default:CheckIn](INFO): Running loop #7
2019-11-05T00:59:55.016Z,1572915595.016 [Default:CheckIn] Running Loop=7
2019-11-05T00:59:55.016Z,1572915595.016 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-11-05T00:59:55.016Z,1572915595.016 [Default:CheckIn:Read_GPS] Running Loop=1
2019-11-05T00:59:56.988Z,1572915596.988 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,005957.00,A,3648.24246,N,12148.19270,W,17.533,108.21,051119,,,D*4F
2019-11-05T00:59:56.991Z,1572915596.991 [NAL9602](INFO): GPS fix at 20191105T005957: (36.804041, -121.803212)
2019-11-05T00:59:57.046Z,1572915597.046 [Default:CheckIn:Read_GPS] Stopped
2019-11-05T00:59:57.046Z,1572915597.046 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-11-05T01:00:05.004Z,1572915605.004 [DataOverHttps](INFO): Sending 64 bytes from file Logs/20191105T004424/Courier0009.lzma
2019-11-05T01:00:07.010Z,1572915607.010 [DataOverHttps](INFO): Moved sent file to Logs/20191105T004424/Courier0009.lzma.bak
2019-11-05T01:00:07.011Z,1572915607.011 [DataOverHttps](INFO): SBD MOMSN=11966946
2019-11-05T01:00:24.529Z,1572915624.529 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20191105T004424/Express0010.lzma
2019-11-05T01:00:26.530Z,1572915626.530 [DataOverHttps](INFO): Moved sent file to Logs/20191105T004424/Express0010.lzma.bak
2019-11-05T01:00:26.531Z,1572915626.531 [DataOverHttps](INFO): SBD MOMSN=11966949
2019-11-05T01:00:27.698Z,1572915627.698 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2019-11-05T01:00:27.781Z,1572915627.781 [NAL9602](FAULT): received: 
+CSQ:0

OK44, 2, 0, 0, 0

OK
2019-11-05T01:00:27.781Z,1572915627.781 [NAL9602] Data Fault, FailCount= 4
2019-11-05T01:00:27.781Z,1572915627.781 [NAL9602](ERROR): Data Fault
2019-11-05T01:00:27.853Z,1572915627.853 [Default:CheckIn:Read_Iridium] Stopped
2019-11-05T01:00:27.854Z,1572915627.854 [Default:CheckIn:C.Wait] Running Loop=1
2019-11-05T01:00:27.854Z,1572915627.854 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-11-05T01:00:27.870Z,1572915627.870 [CBIT](ERROR): Data Fault in component: NAL9602
2019-11-05T01:00:28.095Z,1572915628.095 [NAL9602](INFO): Powering down
2019-11-05T01:00:28.984Z,1572915628.984 [CBIT](INFO): Clearing failed state for component NAL9602
2019-11-05T01:00:28.984Z,1572915628.984 [NAL9602] No Fault, FailCount= 4
2019-11-05T01:00:58.395Z,1572915658.395 [NAL9602](INFO): Powering up NAL9602
2019-11-05T01:01:09.303Z,1572915669.303 [NAL9602](INFO): NAL9602 initialized
2019-11-05T01:01:40.451Z,1572915700.451 [NAL9602](INFO): Not Powering down - fast GPS
2019-11-05T01:05:28.346Z,1572915928.346 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-11-05T01:05:28.346Z,1572915928.346 [Default:CheckIn:C.Wait] Stopped
2019-11-05T01:05:28.346Z,1572915928.346 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-11-05T01:05:28.347Z,1572915928.347 [Default:CheckIn:D] Running Loop=1
2019-11-05T01:05:28.739Z,1572915928.739 [Default:CheckIn:D] Stopped
2019-11-05T01:05:28.739Z,1572915928.739 [Default:CheckIn:E] Running Loop=1
2019-11-05T01:05:29.186Z,1572915929.186 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 53.872453 min 
2019-11-05T01:05:29.186Z,1572915929.186 [Default:CheckIn:E] Stopped
2019-11-05T01:05:29.186Z,1572915929.186 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-11-05T01:05:29.186Z,1572915929.186 [Default:CheckIn] Stopped
2019-11-05T01:05:29.186Z,1572915929.186 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-11-05T01:05:29.186Z,1572915929.186 [Default:CheckIn](INFO): Running loop #8
2019-11-05T01:05:29.187Z,1572915929.187 [Default:CheckIn] Running Loop=8
2019-11-05T01:05:29.187Z,1572915929.187 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-11-05T01:05:29.187Z,1572915929.187 [Default:CheckIn:Read_GPS] Running Loop=1
2019-11-05T01:05:31.151Z,1572915931.151 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,010530.00,A,3648.26650,N,12147.14495,W,5.734,185.95,051119,,,D*74
2019-11-05T01:05:31.153Z,1572915931.153 [NAL9602](INFO): GPS fix at 20191105T010530: (36.804442, -121.785749)
2019-11-05T01:05:31.188Z,1572915931.188 [Default:CheckIn:Read_GPS] Stopped
2019-11-05T01:05:31.188Z,1572915931.188 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-11-05T01:05:33.171Z,1572915933.171 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-11-05T01:05:38.488Z,1572915938.488 [DataOverHttps](INFO): Sending 64 bytes from file Logs/20191105T004424/Courier0012.lzma
2019-11-05T01:05:40.518Z,1572915940.518 [DataOverHttps](INFO): Moved sent file to Logs/20191105T004424/Courier0012.lzma.bak
2019-11-05T01:05:40.519Z,1572915940.519 [DataOverHttps](INFO): SBD MOMSN=11966953
2019-11-05T01:05:58.253Z,1572915958.253 [DataOverHttps](INFO): Sending 173 bytes from file Logs/20191105T004424/Express0013.lzma
2019-11-05T01:06:00.261Z,1572915960.261 [DataOverHttps](INFO): Moved sent file to Logs/20191105T004424/Express0013.lzma.bak
2019-11-05T01:06:00.261Z,1572915960.261 [DataOverHttps](INFO): SBD MOMSN=11966956
2019-11-05T01:06:01.492Z,1572915961.492 [Default:CheckIn:Read_Iridium] Stopped
2019-11-05T01:06:01.492Z,1572915961.492 [Default:CheckIn:C.Wait] Running Loop=1
2019-11-05T01:06:01.493Z,1572915961.493 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-11-05T01:06:03.871Z,1572915963.871 [NAL9602](INFO): Not Powering down - fast GPS
2019-11-05T01:10:11.194Z,1572916211.194 [RDI_Pathfinder](ERROR): Failed to parse:
:B  +0.00,        +0.00,        +0.00,   0.00,2049.83
2019-11-05T01:11:02.065Z,1572916262.065 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-11-05T01:11:02.065Z,1572916262.065 [Default:CheckIn:C.Wait] Stopped
2019-11-05T01:11:02.065Z,1572916262.065 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-11-05T01:11:02.065Z,1572916262.065 [Default:CheckIn:D] Running Loop=1
2019-11-05T01:11:02.486Z,1572916262.486 [Default:CheckIn:D] Stopped
2019-11-05T01:11:02.486Z,1572916262.486 [Default:CheckIn:E] Running Loop=1
2019-11-05T01:11:02.909Z,1572916262.909 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 59.434900 min 
2019-11-05T01:11:02.909Z,1572916262.909 [Default:CheckIn:E] Stopped
2019-11-05T01:11:02.910Z,1572916262.910 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-11-05T01:11:02.910Z,1572916262.910 [Default:CheckIn] Stopped
2019-11-05T01:11:02.910Z,1572916262.910 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-11-05T01:11:02.910Z,1572916262.910 [Default:CheckIn](INFO): Running loop #9
2019-11-05T01:11:02.910Z,1572916262.910 [Default:CheckIn] Running Loop=9
2019-11-05T01:11:02.910Z,1572916262.910 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-11-05T01:11:02.910Z,1572916262.910 [Default:CheckIn:Read_GPS] Running Loop=1
2019-11-05T01:11:04.851Z,1572916264.851 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,011105.00,A,3648.11886,N,12147.22418,W,1.749,283.92,051119,,,A*7F
2019-11-05T01:11:04.854Z,1572916264.854 [NAL9602](INFO): GPS fix at 20191105T011105: (36.801981, -121.787070)
2019-11-05T01:11:04.914Z,1572916264.914 [Default:CheckIn:Read_GPS] Stopped
2019-11-05T01:11:04.915Z,1572916264.915 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-11-05T01:11:17.040Z,1572916277.040 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20191105T004424/Courier0015.lzma
2019-11-05T01:11:19.046Z,1572916279.046 [DataOverHttps](INFO): Moved sent file to Logs/20191105T004424/Courier0015.lzma.bak
2019-11-05T01:11:19.047Z,1572916279.047 [DataOverHttps](INFO): SBD MOMSN=11966960
2019-11-05T01:11:37.204Z,1572916297.204 [DataOverHttps](INFO): Sending 196 bytes from file Logs/20191105T004424/Express0016.lzma
2019-11-05T01:11:38.163Z,1572916298.163 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-11-05T01:11:38.164Z,1572916298.164 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,19110418232564,35.0, -0.1,   0.0,14
2019-11-05T01:11:39.222Z,1572916299.222 [DataOverHttps](INFO): Moved sent file to Logs/20191105T004424/Express0016.lzma.bak
2019-11-05T01:11:39.222Z,1572916299.222 [DataOverHttps](INFO): SBD MOMSN=11966963
2019-11-05T01:11:40.592Z,1572916300.592 [Default:CheckIn:Read_Iridium] Stopped
2019-11-05T01:11:40.593Z,1572916300.593 [Default:CheckIn:C.Wait] Running Loop=1
2019-11-05T01:11:40.593Z,1572916300.593 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-11-05T01:11:51.498Z,1572916311.498 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2019-11-05T01:11:51.499Z,1572916311.499 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2019-11-05T01:12:13.696Z,1572916333.696 [NAL9602](INFO): SBD MO Status=0, MOMSN=9844, MT Status=0, MTMSN=0
2019-11-05T01:12:13.696Z,1572916333.696 [NAL9602](INFO): No messages in MT queue
2019-11-05T01:12:37.150Z,1572916357.150 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2019-11-05T01:12:37.151Z,1572916357.151 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2019-11-05T01:12:44.399Z,1572916364.399 [NAL9602](INFO): Not Powering down - fast GPS
2019-11-05T01:13:38.159Z,1572916418.159 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-11-05T01:13:38.160Z,1572916418.160 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,19110418252564,35.0, -0.1,   0.0,1448.9A,  0.00,   0.00,   0.00,   0.00,   0.00
2019-11-05T01:15:51.501Z,1572916551.501 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2019-11-05T01:15:51.501Z,1572916551.501 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2019-11-05T01:16:41.214Z,1572916601.214 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-11-05T01:16:41.214Z,1572916601.214 [Default:CheckIn:C.Wait] Stopped
2019-11-05T01:16:41.214Z,1572916601.214 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-11-05T01:16:41.214Z,1572916601.214 [Default:CheckIn:D] Running Loop=1
2019-11-05T01:16:41.618Z,1572916601.618 [Default:CheckIn:D] Stopped
2019-11-05T01:16:41.618Z,1572916601.618 [Default:CheckIn:E] Running Loop=1
2019-11-05T01:16:42.005Z,1572916602.005 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 65.087105 min 
2019-11-05T01:16:42.006Z,1572916602.006 [Default:CheckIn:E] Stopped
2019-11-05T01:16:42.006Z,1572916602.006 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-11-05T01:16:42.006Z,1572916602.006 [Default:CheckIn] Stopped
2019-11-05T01:16:42.006Z,1572916602.006 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-11-05T01:16:42.006Z,1572916602.006 [Default:CheckIn](INFO): Running loop #10
2019-11-05T01:16:42.006Z,1572916602.006 [Default:CheckIn] Running Loop=10
2019-11-05T01:16:42.006Z,1572916602.006 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-11-05T01:16:42.006Z,1572916602.006 [Default:CheckIn:Read_GPS] Running Loop=1
2019-11-05T01:16:44.008Z,1572916604.008 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,011644.00,A,3648.11935,N,12147.23386,W,0.019,281.38,051119,,,D*71
2019-11-05T01:16:44.010Z,1572916604.010 [NAL9602](INFO): GPS fix at 20191105T011644: (36.801989, -121.787231)
2019-11-05T01:16:44.085Z,1572916604.085 [Default:CheckIn:Read_GPS] Stopped
2019-11-05T01:16:44.085Z,1572916604.085 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-11-05T01:16:51.656Z,1572916611.656 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20191105T004424/Courier0018.lzma
2019-11-05T01:16:53.662Z,1572916613.662 [DataOverHttps](INFO): Moved sent file to Logs/20191105T004424/Courier0018.lzma.bak
2019-11-05T01:16:53.663Z,1572916613.663 [DataOverHttps](INFO): SBD MOMSN=11966969
2019-11-05T01:17:14.969Z,1572916634.969 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20191105T004424/Express0019.lzma
2019-11-05T01:17:16.229Z,1572916636.229 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2019-11-05T01:17:16.232Z,1572916636.232 [BPC1](INFO): Received data from all battery sticks.
2019-11-05T01:17:16.974Z,1572916636.974 [DataOverHttps](INFO): Moved sent file to Logs/20191105T004424/Express0019.lzma.bak
2019-11-05T01:17:16.974Z,1572916636.974 [DataOverHttps](INFO): SBD MOMSN=11966972
2019-11-05T01:17:18.233Z,1572916638.233 [Default:CheckIn:Read_Iridium] Stopped
2019-11-05T01:17:18.233Z,1572916638.233 [Default:CheckIn:C.Wait] Running Loop=1
2019-11-05T01:17:18.234Z,1572916638.234 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-11-05T01:17:58.196Z,1572916678.196 [NAL9602](INFO): SBD MO Status=2, MOMSN=9845, MT Status=2, MTMSN=0
2019-11-05T01:17:58.196Z,1572916678.196 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-11-05T01:18:53.164Z,1572916733.164 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2019-11-05T01:18:53.165Z,1572916733.165 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2019-11-05T01:18:56.780Z,1572916736.780 [NAL9602](INFO): SBD MO Status=2, MOMSN=9845, MT Status=2, MTMSN=0
2019-11-05T01:18:56.780Z,1572916736.780 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-11-05T01:20:09.124Z,1572916809.124 [NAL9602](INFO): SBD MO Status=2, MOMSN=9845, MT Status=2, MTMSN=0
2019-11-05T01:20:09.124Z,1572916809.124 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-11-05T01:21:46.486Z,1572916906.486 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-11-05T01:22:17.191Z,1572916937.191 [NAL9602](INFO): Not Powering down - fast GPS
2019-11-05T01:22:18.873Z,1572916938.873 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-11-05T01:22:18.873Z,1572916938.873 [Default:CheckIn:C.Wait] Stopped
2019-11-05T01:22:18.873Z,1572916938.873 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-11-05T01:22:18.873Z,1572916938.873 [Default:CheckIn:D] Running Loop=1
2019-11-05T01:22:19.239Z,1572916939.239 [Default:CheckIn:D] Stopped
2019-11-05T01:22:19.240Z,1572916939.240 [Default:CheckIn:E] Running Loop=1
2019-11-05T01:22:19.642Z,1572916939.642 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 70.714128 min 
2019-11-05T01:22:19.642Z,1572916939.642 [Default:CheckIn:E] Stopped
2019-11-05T01:22:19.642Z,1572916939.642 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-11-05T01:22:19.642Z,1572916939.642 [Default:CheckIn] Stopped
2019-11-05T01:22:19.642Z,1572916939.642 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-11-05T01:22:19.643Z,1572916939.643 [Default:CheckIn](INFO): Running loop #11
2019-11-05T01:22:19.643Z,1572916939.643 [Default:CheckIn] Running Loop=11
2019-11-05T01:22:19.643Z,1572916939.643 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-11-05T01:22:19.643Z,1572916939.643 [Default:CheckIn:Read_GPS] Running Loop=1
2019-11-05T01:22:21.637Z,1572916941.637 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,012221.00,A,3648.17422,N,12147.27934,W,0.078,278.83,051119,,,D*7E
2019-11-05T01:22:21.639Z,1572916941.639 [NAL9602](INFO): GPS fix at 20191105T012221: (36.802904, -121.787989)
2019-11-05T01:22:21.690Z,1572916941.690 [Default:CheckIn:Read_GPS] Stopped
2019-11-05T01:22:21.690Z,1572916941.690 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-11-05T01:22:29.745Z,1572916949.745 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20191105T004424/Courier0021.lzma
2019-11-05T01:22:31.750Z,1572916951.750 [DataOverHttps](INFO): Moved sent file to Logs/20191105T004424/Courier0021.lzma.bak
2019-11-05T01:22:31.750Z,1572916951.750 [DataOverHttps](INFO): SBD MOMSN=11966975
2019-11-05T01:22:49.313Z,1572916969.313 [DataOverHttps](INFO): Sending 195 bytes from file Logs/20191105T004424/Express0022.lzma
2019-11-05T01:22:51.318Z,1572916971.318 [DataOverHttps](INFO): Moved sent file to Logs/20191105T004424/Express0022.lzma.bak
2019-11-05T01:22:51.319Z,1572916971.319 [DataOverHttps](INFO): SBD MOMSN=11966978
2019-11-05T01:22:52.364Z,1572916972.364 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2019-11-05T01:22:52.445Z,1572916972.445 [NAL9602](FAULT): received: 
+CSQ:0

OK45, 2, 0, 0, 0

OK
2019-11-05T01:22:52.445Z,1572916972.445 [NAL9602] Data Fault, FailCount= 1
2019-11-05T01:22:52.445Z,1572916972.445 [NAL9602](ERROR): Data Fault
2019-11-05T01:22:52.515Z,1572916972.515 [Default:CheckIn:Read_Iridium] Stopped
2019-11-05T01:22:52.515Z,1572916972.515 [Default:CheckIn:C.Wait] Running Loop=1
2019-11-05T01:22:52.515Z,1572916972.515 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-11-05T01:22:52.532Z,1572916972.532 [CBIT](ERROR): Data Fault in component: NAL9602
2019-11-05T01:22:52.744Z,1572916972.744 [NAL9602](INFO): Powering down
2019-11-05T01:22:53.177Z,1572916973.177 [RDI_Pathfinder](ERROR): Failed to parse:
:8,-32768,-32768,V
2019-11-05T01:22:53.601Z,1572916973.601 [CBIT](INFO): Clearing failed state for component NAL9602
2019-11-05T01:22:53.601Z,1572916973.601 [NAL9602] No Fault, FailCount= 1
2019-11-05T01:23:23.049Z,1572917003.049 [NAL9602](INFO): Powering up NAL9602
2019-11-05T01:23:33.948Z,1572917013.948 [NAL9602](INFO): NAL9602 initialized
2019-11-05T01:24:05.056Z,1572917045.056 [NAL9602](INFO): Not Powering down - fast GPS
2019-11-05T01:26:10.322Z,1572917170.322 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2019-11-05T01:26:10.322Z,1572917170.322 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2019-11-05T01:27:52.978Z,1572917272.978 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-11-05T01:27:52.978Z,1572917272.978 [Default:CheckIn:C.Wait] Stopped
2019-11-05T01:27:52.978Z,1572917272.978 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-11-05T01:27:52.979Z,1572917272.979 [Default:CheckIn:D] Running Loop=1
2019-11-05T01:27:53.385Z,1572917273.385 [Default:CheckIn:D] Stopped
2019-11-05T01:27:53.385Z,1572917273.385 [Default:CheckIn:E] Running Loop=1
2019-11-05T01:27:53.812Z,1572917273.812 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 76.283219 min 
2019-11-05T01:27:53.812Z,1572917273.812 [Default:CheckIn:E] Stopped
2019-11-05T01:27:53.812Z,1572917273.812 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-11-05T01:27:53.813Z,1572917273.813 [Default:CheckIn] Stopped
2019-11-05T01:27:53.813Z,1572917273.813 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-11-05T01:27:53.813Z,1572917273.813 [Default:CheckIn](INFO): Running loop #12
2019-11-05T01:27:53.813Z,1572917273.813 [Default:CheckIn] Running Loop=12
2019-11-05T01:27:53.813Z,1572917273.813 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-11-05T01:27:53.813Z,1572917273.813 [Default:CheckIn:Read_GPS] Running Loop=1
2019-11-05T01:27:58.199Z,1572917278.199 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-11-05T01:29:56.458Z,1572917396.458 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.203503
2019-11-05T01:30:28.585Z,1572917428.585 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-11-05T01:31:03.725Z,1572917463.725 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-11-05T01:31:38.865Z,1572917498.865 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-11-05T01:32:12.859Z,1572917532.859 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.006480
2019-11-05T01:32:53.969Z,1572917573.969 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-11-05T01:27:53.8Z
2019-11-05T01:32:53.970Z,1572917573.970 [Default:CheckIn:Read_GPS] Stopped
2019-11-05T01:32:53.970Z,1572917573.970 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-11-05T01:33:01.936Z,1572917581.936 [DataOverHttps](INFO): Sending 236 bytes from file Logs/20191105T004424/Express0025.lzma
2019-11-05T01:33:03.942Z,1572917583.942 [DataOverHttps](INFO): Moved sent file to Logs/20191105T004424/Express0025.lzma.bak
2019-11-05T01:33:03.943Z,1572917583.943 [DataOverHttps](INFO): SBD MOMSN=11966982
2019-11-05T01:33:05.281Z,1572917585.281 [Default:CheckIn:Read_Iridium] Stopped
2019-11-05T01:33:05.281Z,1572917585.281 [Default:CheckIn:C.Wait] Running Loop=1
2019-11-05T01:33:05.281Z,1572917585.281 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-11-05T01:33:25.451Z,1572917605.451 [NAL9602](INFO): Not Powering down - fast GPS
2019-11-05T01:34:07.426Z,1572917647.426 [CommandLine](IMPORTANT): got command quit
2019-11-05T01:34:08.433Z,1572917648.433 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2019-11-05T01:34:08.433Z,1572917648.433 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-11-05T01:34:08.433Z,1572917648.433 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-11-05T01:34:08.569Z,1572917648.569 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-11-05T01:34:08.569Z,1572917648.569 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-11-05T01:34:08.570Z,1572917648.570 [CommandLine](INFO): Join timeout helper Thread ID is 5088
2019-11-05T01:34:08.571Z,1572917648.571 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-11-05T01:34:08.571Z,1572917648.571 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-11-05T01:34:08.572Z,1572917648.572 [NavChartDb](INFO): Join timeout helper Thread ID is 5089
2019-11-05T01:34:08.905Z,1572917648.905 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-11-05T01:34:08.905Z,1572917648.905 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-11-05T01:34:08.916Z,1572917648.916 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2019-11-05T01:34:08.916Z,1572917648.916 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-11-05T01:34:08.916Z,1572917648.916 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 5090
2019-11-05T01:34:09.313Z,1572917649.313 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2019-11-05T01:34:09.313Z,1572917649.313 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-11-05T01:34:09.314Z,1572917649.314 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-11-05T01:34:09.321Z,1572917649.321 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-11-05T01:34:09.321Z,1572917649.321 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-11-05T01:34:09.321Z,1572917649.321 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 5091
2019-11-05T01:34:09.437Z,1572917649.437 [CTD_NeilBrown](INFO): Powering down
2019-11-05T01:34:09.449Z,1572917649.449 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-11-05T01:34:09.449Z,1572917649.449 [CTD_NeilBrown](INFO): Powering down
2019-11-05T01:34:09.461Z,1572917649.461 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-11-05T01:34:09.461Z,1572917649.461 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-11-05T01:34:09.461Z,1572917649.461 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-11-05T01:34:09.462Z,1572917649.462 [Radio_Surface](INFO): Join timeout helper Thread ID is 5092
2019-11-05T01:34:09.617Z,1572917649.617 [Radio_Surface](INFO): Powering down
2019-11-05T01:34:09.618Z,1572917649.618 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-11-05T01:34:09.618Z,1572917649.618 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-11-05T01:34:09.622Z,1572917649.622 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-11-05T01:34:09.622Z,1572917649.622 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-11-05T01:34:09.623Z,1572917649.623 [DataOverHttps](INFO): Join timeout helper Thread ID is 5093
2019-11-05T01:34:09.801Z,1572917649.801 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-11-05T01:34:09.802Z,1572917649.802 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-11-05T01:34:09.821Z,1572917649.821 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-11-05T01:34:09.822Z,1572917649.822 [logger ThreadHandler](INFO): Thread cancelled.
2019-11-05T01:34:09.822Z,1572917649.822 [logger](INFO): Join timeout helper Thread ID is 5094
2019-11-05T01:34:09.853Z,1572917649.853 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-11-05T01:34:09.853Z,1572917649.853 [logger ThreadHandler](INFO): Thread cancelled.
2019-11-05T01:34:09.861Z,1572917649.861 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-11-05T01:34:09.862Z,1572917649.862 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-11-05T01:34:09.862Z,1572917649.862 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-11-05T01:34:09.862Z,1572917649.862 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-11-05T01:34:09.862Z,1572917649.862 [controlThread](INFO): Join timeout helper Thread ID is 5095
2019-11-05T01:34:09.869Z,1572917649.869 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-11-05T01:34:09.869Z,1572917649.869 [controlThread](DEBUG): Uninitializing ControlThread
2019-11-05T01:34:09.870Z,1572917649.870 [AHRS_M2](INFO): Powering down
2019-11-05T01:34:09.941Z,1572917649.941 [DUSBL_Hydroid](INFO): Powering down
2019-11-05T01:34:10.013Z,1572917650.013 [Micromodem](INFO): Powering down
2019-11-05T01:34:10.109Z,1572917650.109 [NAL9602](INFO): Powering down
2019-11-05T01:34:10.181Z,1572917650.181 [RDI_Pathfinder](INFO): Powering down
2019-11-05T01:34:10.183Z,1572917650.183 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-11-05T01:34:10.183Z,1572917650.183 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-11-05T01:34:10.184Z,1572917650.184 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-11-05T01:34:10.184Z,1572917650.184 [MissionManager](INFO): Uninitializing Mission Default
2019-11-05T01:34:10.184Z,1572917650.184 [Default] Stopped
2019-11-05T01:34:10.185Z,1572917650.185 [Default](DEBUG): Aggregate::uninitialize Default
2019-11-05T01:34:10.185Z,1572917650.185 [Default:B.GoToSurface] Stopped
2019-11-05T01:34:10.185Z,1572917650.185 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-11-05T01:34:10.185Z,1572917650.185 [Default:CheckIn] Stopped
2019-11-05T01:34:10.185Z,1572917650.185 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-11-05T01:34:10.185Z,1572917650.185 [Default:CheckIn:C.Wait] Stopped
2019-11-05T01:34:10.185Z,1572917650.185 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-11-05T01:34:10.188Z,1572917650.188 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-11-05T01:34:10.188Z,1572917650.188 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-11-05T01:34:10.188Z,1572917650.188 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-11-05T01:34:10.188Z,1572917650.188 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-11-05T01:34:10.189Z,1572917650.189 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-11-05T01:34:10.189Z,1572917650.189 [BuoyancyServo](INFO): Powering down
2019-11-05T01:34:10.201Z,1572917650.201 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-11-05T01:34:10.201Z,1572917650.201 [ElevatorServo](INFO): Powering down
2019-11-05T01:34:10.202Z,1572917650.202 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-11-05T01:34:10.202Z,1572917650.202 [MassServo](INFO): Powering down
2019-11-05T01:34:10.203Z,1572917650.203 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-11-05T01:34:10.203Z,1572917650.203 [RudderServo](INFO): Powering down
2019-11-05T01:34:10.204Z,1572917650.204 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-11-05T01:34:10.204Z,1572917650.204 [ThrusterServo](INFO): Powering down
2019-11-05T01:34:10.204Z,1572917650.204 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-11-05T01:34:10.205Z,1572917650.205 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-11-05T01:34:10.205Z,1572917650.205 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-11-05T01:34:10.205Z,1572917650.205 [CBIT](DEBUG): Powering off loads.
2019-11-05T01:34:10.217Z,1572917650.217 [CBIT](DEBUG): Disabling WDT.
2019-11-05T01:34:10.229Z,1572917650.229 [CBIT](DEBUG): Opening all GF detection circuits.
2019-11-05T01:34:10.230Z,1572917650.230 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-11-05T01:34:10.283Z,1572917650.283 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-11-05T01:34:10.293Z,1572917650.293 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-11-05T01:34:10.338Z,1572917650.338 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-11-05T01:34:10.341Z,1572917650.341 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-11-05T01:34:10.403Z,1572917650.403 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-11-05T01:34:10.473Z,1572917650.473 [logger ThreadHandler](INFO): Thread cancelled.