2020-09-25T19:26:22.409Z,1601061982.409 [CommandLine](IMPORTANT): got command restart logs
2020-09-25T19:27:07.701Z,1601062027.701 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-09-25T19:28:34.585Z,1601062114.585 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-09-25T19:28:34.585Z,1601062114.585 [Default:CheckIn:C.Wait] Stopped
2020-09-25T19:28:34.585Z,1601062114.585 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-09-25T19:28:34.585Z,1601062114.585 [Default:CheckIn:D] Running Loop=1
2020-09-25T19:28:34.994Z,1601062114.994 [Default:CheckIn:D] Stopped
2020-09-25T19:28:34.994Z,1601062114.994 [Default:CheckIn:E] Running Loop=1
2020-09-25T19:28:35.373Z,1601062115.373 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 9.390599 min 
2020-09-25T19:28:35.374Z,1601062115.374 [Default:CheckIn:E] Stopped
2020-09-25T19:28:35.374Z,1601062115.374 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-09-25T19:28:35.374Z,1601062115.374 [Default:CheckIn] Stopped
2020-09-25T19:28:35.374Z,1601062115.374 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-09-25T19:28:35.374Z,1601062115.374 [Default:CheckIn](INFO): Running loop #2
2020-09-25T19:28:35.374Z,1601062115.374 [Default:CheckIn] Running Loop=2
2020-09-25T19:28:35.374Z,1601062115.374 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-09-25T19:28:35.375Z,1601062115.375 [Default:CheckIn:Read_GPS] Running Loop=1
2020-09-25T19:28:37.380Z,1601062117.380 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192837.00,A,3647.76980,N,12150.89984,W,1.594,89.44,250920,,,A*4B
2020-09-25T19:28:37.382Z,1601062117.382 [NAL9602](INFO): GPS fix at 20200925T192837: (36.796163, -121.848331)
2020-09-25T19:28:37.427Z,1601062117.427 [Default:CheckIn:Read_GPS] Stopped
2020-09-25T19:28:37.427Z,1601062117.427 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-09-25T19:28:46.348Z,1601062126.348 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200925T170409/Courier0031.lzma
2020-09-25T19:28:47.827Z,1601062127.827 [DataOverHttps](INFO): Moved sent file to Logs/20200925T170409/Courier0031.lzma.bak
2020-09-25T19:28:47.827Z,1601062127.827 [DataOverHttps](INFO): SBD MOMSN=12671672
2020-09-25T19:28:57.979Z,1601062137.979 [NAL9602](INFO): SBD MO Status=0, MOMSN=9984, MT Status=0, MTMSN=0
2020-09-25T19:28:57.979Z,1601062137.979 [NAL9602](INFO): No messages in MT queue
2020-09-25T19:29:03.505Z,1601062143.505 [DataOverHttps](INFO): Sending 269 bytes from file Logs/20200925T192622/Courier0000.lzma
2020-09-25T19:29:04.506Z,1601062144.506 [DataOverHttps](INFO): Moved sent file to Logs/20200925T192622/Courier0000.lzma.bak
2020-09-25T19:29:04.506Z,1601062144.506 [DataOverHttps](INFO): SBD MOMSN=12671674
2020-09-25T19:29:22.240Z,1601062162.240 [DataOverHttps](INFO): Sending 107 bytes from file Logs/20200925T170409/Express0032.lzma
2020-09-25T19:29:23.242Z,1601062163.242 [DataOverHttps](INFO): Moved sent file to Logs/20200925T170409/Express0032.lzma.bak
2020-09-25T19:29:23.243Z,1601062163.243 [DataOverHttps](INFO): SBD MOMSN=12671678
2020-09-25T19:29:28.678Z,1601062168.678 [NAL9602](INFO): Not Powering down - fast GPS
2020-09-25T19:29:40.849Z,1601062180.849 [DataOverHttps](INFO): Sending 378 bytes from file Logs/20200925T192622/Express0001.lzma
2020-09-25T19:29:41.850Z,1601062181.850 [DataOverHttps](INFO): Moved sent file to Logs/20200925T192622/Express0001.lzma.bak
2020-09-25T19:29:41.850Z,1601062181.850 [DataOverHttps](INFO): SBD MOMSN=12671681
2020-09-25T19:29:59.348Z,1601062199.348 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20200925T192622/Express0004.lzma
2020-09-25T19:30:00.350Z,1601062200.350 [DataOverHttps](INFO): Moved sent file to Logs/20200925T192622/Express0004.lzma.bak
2020-09-25T19:30:00.351Z,1601062200.351 [DataOverHttps](INFO): SBD MOMSN=12671685
2020-09-25T19:30:04.240Z,1601062204.240 [Default:CheckIn:Read_Iridium] Stopped
2020-09-25T19:30:04.241Z,1601062204.241 [Default:CheckIn:C.Wait] Running Loop=1
2020-09-25T19:30:04.241Z,1601062204.241 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-09-25T19:35:04.915Z,1601062504.915 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-09-25T19:35:04.915Z,1601062504.915 [Default:CheckIn:C.Wait] Stopped
2020-09-25T19:35:04.915Z,1601062504.915 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-09-25T19:35:04.915Z,1601062504.915 [Default:CheckIn:D] Running Loop=1
2020-09-25T19:35:05.288Z,1601062505.288 [Default:CheckIn:D] Stopped
2020-09-25T19:35:05.288Z,1601062505.288 [Default:CheckIn:E] Running Loop=1
2020-09-25T19:35:05.698Z,1601062505.698 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 15.895492 min 
2020-09-25T19:35:05.698Z,1601062505.698 [Default:CheckIn:E] Stopped
2020-09-25T19:35:05.699Z,1601062505.699 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-09-25T19:35:05.699Z,1601062505.699 [Default:CheckIn] Stopped
2020-09-25T19:35:05.699Z,1601062505.699 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-09-25T19:35:05.699Z,1601062505.699 [Default:CheckIn](INFO): Running loop #3
2020-09-25T19:35:05.699Z,1601062505.699 [Default:CheckIn] Running Loop=3
2020-09-25T19:35:05.699Z,1601062505.699 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-09-25T19:35:05.699Z,1601062505.699 [Default:CheckIn:Read_GPS] Running Loop=1
2020-09-25T19:35:07.692Z,1601062507.692 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193507.00,A,3647.75795,N,12150.85043,W,0.583,83.55,250920,,,A*4E
2020-09-25T19:35:07.694Z,1601062507.694 [NAL9602](INFO): GPS fix at 20200925T193507: (36.795966, -121.847507)
2020-09-25T19:35:07.732Z,1601062507.732 [Default:CheckIn:Read_GPS] Stopped
2020-09-25T19:35:07.732Z,1601062507.732 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-09-25T19:35:16.196Z,1601062516.196 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20200925T192622/Courier0006.lzma
2020-09-25T19:35:17.198Z,1601062517.198 [DataOverHttps](INFO): Moved sent file to Logs/20200925T192622/Courier0006.lzma.bak
2020-09-25T19:35:17.199Z,1601062517.199 [DataOverHttps](INFO): SBD MOMSN=12671697
2020-09-25T19:35:34.848Z,1601062534.848 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20200925T192622/Express0007.lzma
2020-09-25T19:35:35.850Z,1601062535.850 [DataOverHttps](INFO): Moved sent file to Logs/20200925T192622/Express0007.lzma.bak
2020-09-25T19:35:35.851Z,1601062535.851 [DataOverHttps](INFO): SBD MOMSN=12671700
2020-09-25T19:35:38.418Z,1601062538.418 [Default:CheckIn:Read_Iridium] Stopped
2020-09-25T19:35:38.418Z,1601062538.418 [Default:CheckIn:C.Wait] Running Loop=1
2020-09-25T19:35:38.418Z,1601062538.418 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-09-25T19:35:44.451Z,1601062544.451 [NAL9602](INFO): SBD MO Status=0, MOMSN=9985, MT Status=0, MTMSN=0
2020-09-25T19:35:44.451Z,1601062544.451 [NAL9602](INFO): No messages in MT queue
2020-09-25T19:36:15.158Z,1601062575.158 [NAL9602](INFO): Not Powering down - fast GPS
2020-09-25T19:40:27.205Z,1601062827.205 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T19:40:35.237Z,1601062835.237 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.255596
2020-09-25T19:40:39.021Z,1601062839.021 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-09-25T19:40:39.021Z,1601062839.021 [Default:CheckIn:C.Wait] Stopped
2020-09-25T19:40:39.021Z,1601062839.021 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-09-25T19:40:39.022Z,1601062839.022 [Default:CheckIn:D] Running Loop=1
2020-09-25T19:40:39.399Z,1601062839.399 [Default:CheckIn:D] Stopped
2020-09-25T19:40:39.399Z,1601062839.399 [Default:CheckIn:E] Running Loop=1
2020-09-25T19:40:39.830Z,1601062839.830 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.464018 min 
2020-09-25T19:40:39.831Z,1601062839.831 [Default:CheckIn:E] Stopped
2020-09-25T19:40:39.831Z,1601062839.831 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-09-25T19:40:39.831Z,1601062839.831 [Default:CheckIn] Stopped
2020-09-25T19:40:39.831Z,1601062839.831 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-09-25T19:40:39.831Z,1601062839.831 [Default:CheckIn](INFO): Running loop #4
2020-09-25T19:40:39.831Z,1601062839.831 [Default:CheckIn] Running Loop=4
2020-09-25T19:40:39.831Z,1601062839.831 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-09-25T19:40:39.831Z,1601062839.831 [Default:CheckIn:Read_GPS] Running Loop=1
2020-09-25T19:40:41.816Z,1601062841.816 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194041.00,A,3647.77363,N,12150.74719,W,1.186,33.16,250920,,,A*4B
2020-09-25T19:40:41.827Z,1601062841.827 [NAL9602](INFO): GPS fix at 20200925T194041: (36.796227, -121.845787)
2020-09-25T19:40:41.848Z,1601062841.848 [Default:CheckIn:Read_GPS] Stopped
2020-09-25T19:40:41.848Z,1601062841.848 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-09-25T19:41:25.089Z,1601062885.089 [NAL9602](INFO): SBD MO Status=2, MOMSN=9986, MT Status=2, MTMSN=0
2020-09-25T19:41:25.090Z,1601062885.090 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-09-25T19:41:37.489Z,1601062897.489 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T19:41:46.884Z,1601062906.884 [NAL9602](INFO): SBD MO Status=2, MOMSN=9986, MT Status=2, MTMSN=0
2020-09-25T19:41:46.884Z,1601062906.884 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-09-25T19:42:12.630Z,1601062932.630 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T19:42:19.235Z,1601062939.235 [NAL9602](INFO): SBD MO Status=1, MOMSN=9986, MT Status=0, MTMSN=0
2020-09-25T19:42:19.285Z,1601062939.285 [NAL9602](INFO): Sent 72 bytes from file Logs/20200925T192622/Courier0009.lzma
2020-09-25T19:42:19.285Z,1601062939.285 [NAL9602](INFO): Packets left to send: 0
2020-09-25T19:42:35.055Z,1601062955.055 [NAL9602](INFO): SBD MO Status=2, MOMSN=9987, MT Status=2, MTMSN=0
2020-09-25T19:42:35.055Z,1601062955.055 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-09-25T19:42:47.769Z,1601062967.769 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T19:43:04.339Z,1601062984.339 [NAL9602](INFO): SBD MO Status=1, MOMSN=9987, MT Status=0, MTMSN=0
2020-09-25T19:43:04.393Z,1601062984.393 [NAL9602](INFO): Sent 253 bytes from file Logs/20200925T192622/Express0010.lzma
2020-09-25T19:43:04.393Z,1601062984.393 [NAL9602](INFO): Packets left to send: 0
2020-09-25T19:43:21.351Z,1601063001.351 [NAL9602](INFO): SBD MO Status=2, MOMSN=9988, MT Status=2, MTMSN=0
2020-09-25T19:43:21.352Z,1601063001.352 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-09-25T19:43:22.937Z,1601063002.937 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T19:43:37.443Z,1601063017.443 [NAL9602](INFO): SBD MO Status=0, MOMSN=9988, MT Status=0, MTMSN=0
2020-09-25T19:43:37.533Z,1601063017.533 [Default:CheckIn:Read_Iridium] Stopped
2020-09-25T19:43:37.533Z,1601063017.533 [Default:CheckIn:C.Wait] Running Loop=1
2020-09-25T19:43:37.533Z,1601063017.533 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-09-25T19:43:58.085Z,1601063038.085 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T19:44:08.146Z,1601063048.146 [NAL9602](INFO): Not Powering down - fast GPS
2020-09-25T19:44:33.253Z,1601063073.253 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T19:45:08.393Z,1601063108.393 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T19:45:28.638Z,1601063128.638 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-09-25T19:45:43.533Z,1601063143.533 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T19:46:17.222Z,1601063177.222 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003654
2020-09-25T19:48:38.054Z,1601063318.054 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-09-25T19:48:38.054Z,1601063318.054 [Default:CheckIn:C.Wait] Stopped
2020-09-25T19:48:38.055Z,1601063318.055 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-09-25T19:48:38.055Z,1601063318.055 [Default:CheckIn:D] Running Loop=1
2020-09-25T19:48:38.462Z,1601063318.462 [Default:CheckIn:D] Stopped
2020-09-25T19:48:38.462Z,1601063318.462 [Default:CheckIn:E] Running Loop=1
2020-09-25T19:48:38.887Z,1601063318.887 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 29.448391 min 
2020-09-25T19:48:38.887Z,1601063318.887 [Default:CheckIn:E] Stopped
2020-09-25T19:48:38.887Z,1601063318.887 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-09-25T19:48:38.887Z,1601063318.887 [Default:CheckIn] Stopped
2020-09-25T19:48:38.887Z,1601063318.887 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-09-25T19:48:38.887Z,1601063318.887 [Default:CheckIn](INFO): Running loop #5
2020-09-25T19:48:38.887Z,1601063318.887 [Default:CheckIn] Running Loop=5
2020-09-25T19:48:38.887Z,1601063318.887 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-09-25T19:48:38.887Z,1601063318.887 [Default:CheckIn:Read_GPS] Running Loop=1
2020-09-25T19:48:40.872Z,1601063320.872 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194841.00,A,3648.61691,N,12151.69128,W,2.022,49.38,250920,,,D*42
2020-09-25T19:48:40.874Z,1601063320.874 [NAL9602](INFO): GPS fix at 20200925T194841: (36.810282, -121.861521)
2020-09-25T19:48:40.915Z,1601063320.915 [Default:CheckIn:Read_GPS] Stopped
2020-09-25T19:48:40.915Z,1601063320.915 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-09-25T19:48:49.368Z,1601063329.368 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20200925T192622/Courier0012.lzma
2020-09-25T19:48:50.370Z,1601063330.370 [DataOverHttps](INFO): Moved sent file to Logs/20200925T192622/Courier0012.lzma.bak
2020-09-25T19:48:50.371Z,1601063330.371 [DataOverHttps](INFO): SBD MOMSN=12671742
2020-09-25T19:49:07.992Z,1601063347.992 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20200925T192622/Express0013.lzma
2020-09-25T19:49:08.994Z,1601063348.994 [DataOverHttps](INFO): Moved sent file to Logs/20200925T192622/Express0013.lzma.bak
2020-09-25T19:49:08.995Z,1601063348.995 [DataOverHttps](INFO): SBD MOMSN=12671745
2020-09-25T19:49:11.626Z,1601063351.626 [Default:CheckIn:Read_Iridium] Stopped
2020-09-25T19:49:11.627Z,1601063351.627 [Default:CheckIn:C.Wait] Running Loop=1
2020-09-25T19:49:11.627Z,1601063351.627 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-09-25T19:51:03.903Z,1601063463.903 [NAL9602](INFO): SBD MO Status=2, MOMSN=9989, MT Status=2, MTMSN=0
2020-09-25T19:51:03.903Z,1601063463.903 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-09-25T19:52:16.457Z,1601063536.457 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.254951
2020-09-25T19:52:17.019Z,1601063537.019 [NAL9602](INFO): SBD MO Status=2, MOMSN=9989, MT Status=2, MTMSN=0
2020-09-25T19:52:17.019Z,1601063537.019 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-09-25T19:52:28.166Z,1601063548.166 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003613
2020-09-25T19:52:34.383Z,1601063554.383 [NAL9602](INFO): SBD MO Status=2, MOMSN=9989, MT Status=2, MTMSN=0
2020-09-25T19:52:34.383Z,1601063554.383 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-09-25T19:53:25.389Z,1601063605.389 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T19:53:28.401Z,1601063608.401 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.239372
2020-09-25T19:53:39.025Z,1601063619.025 [NAL9602](INFO): SBD MO Status=2, MOMSN=9989, MT Status=2, MTMSN=0
2020-09-25T19:53:39.026Z,1601063619.026 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-09-25T19:53:43.463Z,1601063623.463 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-09-25T19:54:00.529Z,1601063640.529 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T19:54:12.159Z,1601063652.159 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-09-25T19:54:12.159Z,1601063652.159 [Default:CheckIn:C.Wait] Stopped
2020-09-25T19:54:12.159Z,1601063652.159 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-09-25T19:54:12.159Z,1601063652.159 [Default:CheckIn:D] Running Loop=1
2020-09-25T19:54:12.559Z,1601063652.559 [Default:CheckIn:D] Stopped
2020-09-25T19:54:12.560Z,1601063652.560 [Default:CheckIn:E] Running Loop=1
2020-09-25T19:54:12.988Z,1601063652.988 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 35.016687 min 
2020-09-25T19:54:12.989Z,1601063652.989 [Default:CheckIn:E] Stopped
2020-09-25T19:54:12.989Z,1601063652.989 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-09-25T19:54:12.989Z,1601063652.989 [Default:CheckIn] Stopped
2020-09-25T19:54:12.989Z,1601063652.989 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-09-25T19:54:12.989Z,1601063652.989 [Default:CheckIn](INFO): Running loop #6
2020-09-25T19:54:12.989Z,1601063652.989 [Default:CheckIn] Running Loop=6
2020-09-25T19:54:12.989Z,1601063652.989 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-09-25T19:54:12.989Z,1601063652.989 [Default:CheckIn:Read_GPS] Running Loop=1
2020-09-25T19:54:14.980Z,1601063654.980 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195415.00,A,3648.63087,N,12151.56289,W,1.788,97.98,250920,,,D*44
2020-09-25T19:54:14.982Z,1601063654.982 [NAL9602](INFO): GPS fix at 20200925T195415: (36.810514, -121.859381)
2020-09-25T19:54:14.992Z,1601063654.992 [Default:CheckIn:Read_GPS] Stopped
2020-09-25T19:54:14.992Z,1601063654.992 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-09-25T19:54:35.669Z,1601063675.669 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T19:54:45.682Z,1601063685.682 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response. 
2020-09-25T19:54:45.757Z,1601063685.757 [NAL9602](ERROR): received: 
+SBDI: 2, 9989, 2, 0, 0, 0

OK
2020-09-25T19:54:45.757Z,1601063685.757 [NAL9602] Data Fault, FailCount= 1
2020-09-25T19:54:45.757Z,1601063685.757 [NAL9602](ERROR): Data Fault
2020-09-25T19:54:45.786Z,1601063685.786 [CBIT](ERROR): Data Fault in component: NAL9602
2020-09-25T19:54:46.082Z,1601063686.082 [NAL9602](INFO): Powering down
2020-09-25T19:54:46.492Z,1601063686.492 [NAL9602](FAULT): LCB fault:  Software Overcurrent.
2020-09-25T19:54:46.492Z,1601063686.492 [NAL9602] Hardware Fault, FailCount= 1
2020-09-25T19:54:46.492Z,1601063686.492 [NAL9602](ERROR): Hardware Fault
2020-09-25T19:54:46.938Z,1601063686.938 [CBIT](INFO): Clearing failed state for component NAL9602
2020-09-25T19:54:46.938Z,1601063686.938 [NAL9602] No Fault, FailCount= 1
2020-09-25T19:55:10.809Z,1601063710.809 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T19:55:16.386Z,1601063716.386 [NAL9602](INFO): Powering up NAL9602
2020-09-25T19:55:27.294Z,1601063727.294 [NAL9602](INFO): NAL9602 initialized
2020-09-25T19:55:45.965Z,1601063745.965 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T19:55:47.493Z,1601063747.493 [NAL9602](INFO): SBD MO Status=1, MOMSN=9989, MT Status=0, MTMSN=0
2020-09-25T19:55:47.549Z,1601063747.549 [NAL9602](INFO): Sent 72 bytes from file Logs/20200925T192622/Courier0015.lzma
2020-09-25T19:55:47.549Z,1601063747.549 [NAL9602](INFO): Packets left to send: 0
2020-09-25T19:55:59.259Z,1601063759.259 [NAL9602](INFO): SBD MO Status=1, MOMSN=9990, MT Status=0, MTMSN=0
2020-09-25T19:55:59.309Z,1601063759.309 [NAL9602](INFO): Sent 230 bytes from file Logs/20200925T192622/Express0016.lzma
2020-09-25T19:55:59.309Z,1601063759.309 [NAL9602](INFO): Packets left to send: 0
2020-09-25T19:56:09.303Z,1601063769.303 [NAL9602](INFO): SBD MO Status=0, MOMSN=9991, MT Status=0, MTMSN=0
2020-09-25T19:56:09.381Z,1601063769.381 [Default:CheckIn:Read_Iridium] Stopped
2020-09-25T19:56:09.382Z,1601063769.382 [Default:CheckIn:C.Wait] Running Loop=1
2020-09-25T19:56:09.382Z,1601063769.382 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-09-25T19:56:21.109Z,1601063781.109 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T19:56:40.010Z,1601063800.010 [NAL9602](INFO): Not Powering down - fast GPS
2020-09-25T19:56:56.253Z,1601063816.253 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T19:57:31.393Z,1601063851.393 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T19:58:06.533Z,1601063886.533 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T19:58:41.673Z,1601063921.673 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T19:59:16.821Z,1601063956.821 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T19:59:29.602Z,1601063969.602 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003643
2020-09-25T20:01:09.906Z,1601064069.906 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-09-25T20:01:09.906Z,1601064069.906 [Default:CheckIn:C.Wait] Stopped
2020-09-25T20:01:09.906Z,1601064069.906 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-09-25T20:01:09.906Z,1601064069.906 [Default:CheckIn:D] Running Loop=1
2020-09-25T20:01:10.302Z,1601064070.302 [Default:CheckIn:D] Stopped
2020-09-25T20:01:10.302Z,1601064070.302 [Default:CheckIn:E] Running Loop=1
2020-09-25T20:01:10.722Z,1601064070.722 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 41.979061 min 
2020-09-25T20:01:10.722Z,1601064070.722 [Default:CheckIn:E] Stopped
2020-09-25T20:01:10.723Z,1601064070.723 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-09-25T20:01:10.723Z,1601064070.723 [Default:CheckIn] Stopped
2020-09-25T20:01:10.723Z,1601064070.723 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-09-25T20:01:10.723Z,1601064070.723 [Default:CheckIn](INFO): Running loop #7
2020-09-25T20:01:10.723Z,1601064070.723 [Default:CheckIn] Running Loop=7
2020-09-25T20:01:10.723Z,1601064070.723 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-09-25T20:01:10.723Z,1601064070.723 [Default:CheckIn:Read_GPS] Running Loop=1
2020-09-25T20:01:12.716Z,1601064072.716 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200112.00,A,3648.79806,N,12151.65628,W,7.853,315.62,250920,,,A*7A
2020-09-25T20:01:12.718Z,1601064072.718 [NAL9602](INFO): GPS fix at 20200925T200112: (36.813301, -121.860938)
2020-09-25T20:01:12.749Z,1601064072.749 [Default:CheckIn:Read_GPS] Stopped
2020-09-25T20:01:12.749Z,1601064072.749 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-09-25T20:01:33.040Z,1601064093.040 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20200925T192622/Courier0018.lzma
2020-09-25T20:01:34.042Z,1601064094.042 [DataOverHttps](INFO): Moved sent file to Logs/20200925T192622/Courier0018.lzma.bak
2020-09-25T20:01:34.042Z,1601064094.042 [DataOverHttps](INFO): SBD MOMSN=12671770
2020-09-25T20:01:38.619Z,1601064098.619 [NAL9602](INFO): SBD MO Status=2, MOMSN=9992, MT Status=2, MTMSN=0
2020-09-25T20:01:38.620Z,1601064098.620 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-09-25T20:01:45.890Z,1601064105.890 [NAL9602](INFO): SBD MO Status=0, MOMSN=9992, MT Status=0, MTMSN=0
2020-09-25T20:01:45.890Z,1601064105.890 [NAL9602](INFO): No messages in MT queue
2020-09-25T20:02:16.590Z,1601064136.590 [NAL9602](INFO): Not Powering down - fast GPS
2020-09-25T20:02:26.837Z,1601064146.837 [DataOverHttps](INFO): Sending 179 bytes from file Logs/20200925T192622/Express0019.lzma
2020-09-25T20:02:27.838Z,1601064147.838 [DataOverHttps](INFO): Moved sent file to Logs/20200925T192622/Express0019.lzma.bak
2020-09-25T20:02:27.839Z,1601064147.839 [DataOverHttps](INFO): SBD MOMSN=12671775
2020-09-25T20:02:31.548Z,1601064151.548 [Default:CheckIn:Read_Iridium] Stopped
2020-09-25T20:02:31.565Z,1601064151.565 [Default:CheckIn:C.Wait] Running Loop=1
2020-09-25T20:02:31.565Z,1601064151.565 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-09-25T20:07:32.193Z,1601064452.193 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-09-25T20:07:32.193Z,1601064452.193 [Default:CheckIn:C.Wait] Stopped
2020-09-25T20:07:32.193Z,1601064452.193 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-09-25T20:07:32.194Z,1601064452.194 [Default:CheckIn:D] Running Loop=1
2020-09-25T20:07:32.552Z,1601064452.552 [Default:CheckIn:D] Stopped
2020-09-25T20:07:32.552Z,1601064452.552 [Default:CheckIn:E] Running Loop=1
2020-09-25T20:07:32.966Z,1601064452.966 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 48.349894 min 
2020-09-25T20:07:32.966Z,1601064452.966 [Default:CheckIn:E] Stopped
2020-09-25T20:07:32.966Z,1601064452.966 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-09-25T20:07:32.966Z,1601064452.966 [Default:CheckIn] Stopped
2020-09-25T20:07:32.966Z,1601064452.966 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-09-25T20:07:32.966Z,1601064452.966 [Default:CheckIn](INFO): Running loop #8
2020-09-25T20:07:32.967Z,1601064452.967 [Default:CheckIn] Running Loop=8
2020-09-25T20:07:32.967Z,1601064452.967 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-09-25T20:07:32.967Z,1601064452.967 [Default:CheckIn:Read_GPS] Running Loop=1
2020-09-25T20:07:34.956Z,1601064454.956 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200735.00,A,3649.14879,N,12152.16401,W,1.594,111.48,250920,,,A*7B
2020-09-25T20:07:34.958Z,1601064454.958 [NAL9602](INFO): GPS fix at 20200925T200735: (36.819147, -121.869400)
2020-09-25T20:07:34.983Z,1601064454.983 [Default:CheckIn:Read_GPS] Stopped
2020-09-25T20:07:34.983Z,1601064454.983 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-09-25T20:07:42.976Z,1601064462.976 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20200925T192622/Courier0021.lzma
2020-09-25T20:07:43.978Z,1601064463.978 [DataOverHttps](INFO): Moved sent file to Logs/20200925T192622/Courier0021.lzma.bak
2020-09-25T20:07:43.979Z,1601064463.979 [DataOverHttps](INFO): SBD MOMSN=12671786
2020-09-25T20:07:49.118Z,1601064469.118 [NAL9602](INFO): SBD MO Status=0, MOMSN=9993, MT Status=0, MTMSN=0
2020-09-25T20:07:49.118Z,1601064469.118 [NAL9602](INFO): No messages in MT queue
2020-09-25T20:08:01.496Z,1601064481.496 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20200925T192622/Express0022.lzma
2020-09-25T20:08:02.498Z,1601064482.498 [DataOverHttps](INFO): Moved sent file to Logs/20200925T192622/Express0022.lzma.bak
2020-09-25T20:08:02.499Z,1601064482.499 [DataOverHttps](INFO): SBD MOMSN=12671791
2020-09-25T20:08:04.902Z,1601064484.902 [Default:CheckIn:Read_Iridium] Stopped
2020-09-25T20:08:04.902Z,1601064484.902 [Default:CheckIn:C.Wait] Running Loop=1
2020-09-25T20:08:04.902Z,1601064484.902 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-09-25T20:08:19.794Z,1601064499.794 [NAL9602](INFO): Not Powering down - fast GPS
2020-09-25T20:13:05.283Z,1601064785.283 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-09-25T20:13:05.283Z,1601064785.283 [Default:CheckIn:C.Wait] Stopped
2020-09-25T20:13:05.283Z,1601064785.283 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-09-25T20:13:05.283Z,1601064785.283 [Default:CheckIn:D] Running Loop=1
2020-09-25T20:13:05.535Z,1601064785.535 [Default:CheckIn:D] Stopped
2020-09-25T20:13:05.535Z,1601064785.535 [Default:CheckIn:E] Running Loop=1
2020-09-25T20:13:05.989Z,1601064785.989 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 53.899618 min 
2020-09-25T20:13:05.989Z,1601064785.989 [Default:CheckIn:E] Stopped
2020-09-25T20:13:05.989Z,1601064785.989 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-09-25T20:13:05.989Z,1601064785.989 [Default:CheckIn] Stopped
2020-09-25T20:13:05.989Z,1601064785.989 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-09-25T20:13:05.990Z,1601064785.990 [Default:CheckIn](INFO): Running loop #9
2020-09-25T20:13:05.990Z,1601064785.990 [Default:CheckIn] Running Loop=9
2020-09-25T20:13:05.990Z,1601064785.990 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-09-25T20:13:05.990Z,1601064785.990 [Default:CheckIn:Read_GPS] Running Loop=1
2020-09-25T20:13:07.922Z,1601064787.922 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201308.00,A,3649.12705,N,12152.05732,W,6.998,306.69,250920,,,A*73
2020-09-25T20:13:07.924Z,1601064787.924 [NAL9602](INFO): GPS fix at 20200925T201308: (36.818784, -121.867622)
2020-09-25T20:13:07.963Z,1601064787.963 [Default:CheckIn:Read_GPS] Stopped
2020-09-25T20:13:07.963Z,1601064787.963 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-09-25T20:13:17.646Z,1601064797.646 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20200925T192622/Courier0024.lzma
2020-09-25T20:13:18.059Z,1601064798.059 [NAL9602](INFO): SBD MO Status=0, MOMSN=9994, MT Status=0, MTMSN=0
2020-09-25T20:13:18.059Z,1601064798.059 [NAL9602](INFO): No messages in MT queue
2020-09-25T20:13:18.646Z,1601064798.646 [DataOverHttps](INFO): Moved sent file to Logs/20200925T192622/Courier0024.lzma.bak
2020-09-25T20:13:18.647Z,1601064798.647 [DataOverHttps](INFO): SBD MOMSN=12671801
2020-09-25T20:13:37.464Z,1601064817.464 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20200925T192622/Express0025.lzma
2020-09-25T20:13:38.466Z,1601064818.466 [DataOverHttps](INFO): Moved sent file to Logs/20200925T192622/Express0025.lzma.bak
2020-09-25T20:13:38.467Z,1601064818.467 [DataOverHttps](INFO): SBD MOMSN=12671806
2020-09-25T20:13:41.210Z,1601064821.210 [Default:CheckIn:Read_Iridium] Stopped
2020-09-25T20:13:41.210Z,1601064821.210 [Default:CheckIn:C.Wait] Running Loop=1
2020-09-25T20:13:41.210Z,1601064821.210 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-09-25T20:13:48.790Z,1601064828.790 [NAL9602](INFO): Not Powering down - fast GPS
2020-09-25T20:14:58.693Z,1601064898.693 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.299309
2020-09-25T20:15:30.821Z,1601064930.821 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T20:16:05.961Z,1601064965.961 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T20:16:41.106Z,1601065001.106 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T20:16:59.858Z,1601065019.858 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003630
2020-09-25T20:18:41.788Z,1601065121.788 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-09-25T20:18:41.788Z,1601065121.788 [Default:CheckIn:C.Wait] Stopped
2020-09-25T20:18:41.797Z,1601065121.797 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-09-25T20:18:41.797Z,1601065121.797 [Default:CheckIn:D] Running Loop=1
2020-09-25T20:18:42.189Z,1601065122.189 [Default:CheckIn:D] Stopped
2020-09-25T20:18:42.189Z,1601065122.189 [Default:CheckIn:E] Running Loop=1
2020-09-25T20:18:42.628Z,1601065122.628 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 59.510514 min 
2020-09-25T20:18:42.628Z,1601065122.628 [Default:CheckIn:E] Stopped
2020-09-25T20:18:42.628Z,1601065122.628 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-09-25T20:18:42.628Z,1601065122.628 [Default:CheckIn] Stopped
2020-09-25T20:18:42.628Z,1601065122.628 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-09-25T20:18:42.632Z,1601065122.632 [Default:CheckIn](INFO): Running loop #10
2020-09-25T20:18:42.633Z,1601065122.633 [Default:CheckIn] Running Loop=10
2020-09-25T20:18:42.633Z,1601065122.633 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-09-25T20:18:42.633Z,1601065122.633 [Default:CheckIn:Read_GPS] Running Loop=1
2020-09-25T20:18:44.584Z,1601065124.584 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201844.00,A,3649.78434,N,12152.88447,W,13.646,310.16,250920,,,A*4E
2020-09-25T20:18:44.590Z,1601065124.590 [NAL9602](INFO): GPS fix at 20200925T201844: (36.829739, -121.881408)
2020-09-25T20:18:44.609Z,1601065124.609 [Default:CheckIn:Read_GPS] Stopped
2020-09-25T20:18:44.609Z,1601065124.609 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-09-25T20:18:45.935Z,1601065125.935 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20200925T192622/Courier0027.lzma
2020-09-25T20:18:47.097Z,1601065127.097 [DataOverHttps](INFO): Moved sent file to Logs/20200925T192622/Courier0027.lzma.bak
2020-09-25T20:18:47.098Z,1601065127.098 [DataOverHttps](INFO): SBD MOMSN=12671820
2020-09-25T20:19:40.345Z,1601065180.345 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T20:20:09.462Z,1601065209.462 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.240124
2020-09-25T20:20:40.586Z,1601065240.586 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T20:20:44.694Z,1601065244.694 [NAL9602](INFO): SBD MO Status=2, MOMSN=9995, MT Status=2, MTMSN=0
2020-09-25T20:20:44.694Z,1601065244.694 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-09-25T20:21:15.725Z,1601065275.725 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T20:21:21.047Z,1601065281.047 [NAL9602](INFO): SBD MO Status=0, MOMSN=9995, MT Status=0, MTMSN=0
2020-09-25T20:21:21.047Z,1601065281.047 [NAL9602](INFO): No messages in MT queue
2020-09-25T20:21:48.811Z,1601065308.811 [NAL9602](INFO): SBD MO Status=1, MOMSN=9996, MT Status=0, MTMSN=0
2020-09-25T20:21:48.865Z,1601065308.865 [NAL9602](INFO): Sent 145 bytes from file Logs/20200925T192622/Express0028.lzma
2020-09-25T20:21:48.865Z,1601065308.865 [NAL9602](INFO): Packets left to send: 0
2020-09-25T20:21:50.866Z,1601065310.866 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T20:21:56.612Z,1601065316.612 [NAL9602](INFO): SBD MO Status=0, MOMSN=9997, MT Status=0, MTMSN=0
2020-09-25T20:21:56.718Z,1601065316.718 [Default:CheckIn:Read_Iridium] Stopped
2020-09-25T20:21:56.719Z,1601065316.719 [Default:CheckIn:C.Wait] Running Loop=1
2020-09-25T20:21:56.719Z,1601065316.719 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-09-25T20:22:26.006Z,1601065346.006 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T20:22:27.218Z,1601065347.218 [NAL9602](INFO): Not Powering down - fast GPS
2020-09-25T20:23:01.185Z,1601065381.185 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T20:23:36.325Z,1601065416.325 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T20:24:11.473Z,1601065451.473 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T20:24:46.613Z,1601065486.613 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T20:25:21.773Z,1601065521.773 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T20:25:56.917Z,1601065556.917 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T20:26:32.057Z,1601065592.057 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T20:26:57.120Z,1601065617.120 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-09-25T20:26:57.120Z,1601065617.120 [Default:CheckIn:C.Wait] Stopped
2020-09-25T20:26:57.120Z,1601065617.120 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-09-25T20:26:57.120Z,1601065617.120 [Default:CheckIn:D] Running Loop=1
2020-09-25T20:26:57.503Z,1601065617.503 [Default:CheckIn:D] Stopped
2020-09-25T20:26:57.503Z,1601065617.503 [Default:CheckIn:E] Running Loop=1
2020-09-25T20:26:57.920Z,1601065617.920 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 67.765751 min 
2020-09-25T20:26:57.920Z,1601065617.920 [Default:CheckIn:E] Stopped
2020-09-25T20:26:57.920Z,1601065617.920 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-09-25T20:26:57.920Z,1601065617.920 [Default:CheckIn] Stopped
2020-09-25T20:26:57.920Z,1601065617.920 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-09-25T20:26:57.921Z,1601065617.921 [Default:CheckIn](INFO): Running loop #11
2020-09-25T20:26:57.921Z,1601065617.921 [Default:CheckIn] Running Loop=11
2020-09-25T20:26:57.921Z,1601065617.921 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-09-25T20:26:57.921Z,1601065617.921 [Default:CheckIn:Read_GPS] Running Loop=1
2020-09-25T20:26:59.936Z,1601065619.936 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202700.00,A,3651.12199,N,12154.45413,W,11.663,319.10,250920,,,D*4C
2020-09-25T20:26:59.938Z,1601065619.938 [NAL9602](INFO): GPS fix at 20200925T202700: (36.852033, -121.907569)
2020-09-25T20:26:59.959Z,1601065619.959 [Default:CheckIn:Read_GPS] Stopped
2020-09-25T20:26:59.959Z,1601065619.959 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-09-25T20:27:07.197Z,1601065627.197 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T20:27:42.337Z,1601065662.337 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T20:28:01.992Z,1601065681.992 [NAL9602](INFO): SBD MO Status=1, MOMSN=9998, MT Status=0, MTMSN=0
2020-09-25T20:28:02.041Z,1601065682.041 [NAL9602](INFO): Sent 71 bytes from file Logs/20200925T192622/Courier0030.lzma
2020-09-25T20:28:02.041Z,1601065682.041 [NAL9602](INFO): Packets left to send: 0
2020-09-25T20:28:17.477Z,1601065697.477 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T20:28:18.309Z,1601065698.309 [NAL9602](INFO): SBD MO Status=2, MOMSN=9999, MT Status=2, MTMSN=0
2020-09-25T20:28:18.309Z,1601065698.309 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-09-25T20:28:48.367Z,1601065728.367 [NAL9602](INFO): SBD MO Status=2, MOMSN=9999, MT Status=2, MTMSN=0
2020-09-25T20:28:48.367Z,1601065728.367 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-09-25T20:28:52.617Z,1601065732.617 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T20:29:05.382Z,1601065745.382 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003668
2020-09-25T20:29:14.181Z,1601065754.181 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20200925T192622/Express0031.lzma
2020-09-25T20:29:15.182Z,1601065755.182 [DataOverHttps](INFO): Moved sent file to Logs/20200925T192622/Express0031.lzma.bak
2020-09-25T20:29:15.183Z,1601065755.183 [DataOverHttps](INFO): SBD MOMSN=12671842
2020-09-25T20:29:17.800Z,1601065757.800 [Default:CheckIn:Read_Iridium] Stopped
2020-09-25T20:29:17.800Z,1601065757.800 [Default:CheckIn:C.Wait] Running Loop=1
2020-09-25T20:29:17.800Z,1601065757.800 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-09-25T20:29:27.505Z,1601065767.505 [NAL9602](INFO): SBD MO Status=2, MOMSN=9999, MT Status=2, MTMSN=0
2020-09-25T20:29:27.505Z,1601065767.505 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-09-25T20:29:58.154Z,1601065798.154 [NAL9602](INFO): Not Powering down - fast GPS
2020-09-25T20:34:18.339Z,1601066058.339 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-09-25T20:34:18.339Z,1601066058.339 [Default:CheckIn:C.Wait] Stopped
2020-09-25T20:34:18.339Z,1601066058.339 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-09-25T20:34:18.340Z,1601066058.340 [Default:CheckIn:D] Running Loop=1
2020-09-25T20:34:18.754Z,1601066058.754 [Default:CheckIn:D] Stopped
2020-09-25T20:34:18.754Z,1601066058.754 [Default:CheckIn:E] Running Loop=1
2020-09-25T20:34:19.156Z,1601066059.156 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 75.119930 min 
2020-09-25T20:34:19.157Z,1601066059.157 [Default:CheckIn:E] Stopped
2020-09-25T20:34:19.157Z,1601066059.157 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-09-25T20:34:19.157Z,1601066059.157 [Default:CheckIn] Stopped
2020-09-25T20:34:19.157Z,1601066059.157 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-09-25T20:34:19.157Z,1601066059.157 [Default:CheckIn](INFO): Running loop #12
2020-09-25T20:34:19.157Z,1601066059.157 [Default:CheckIn] Running Loop=12
2020-09-25T20:34:19.157Z,1601066059.157 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-09-25T20:34:19.157Z,1601066059.157 [Default:CheckIn:Read_GPS] Running Loop=1
2020-09-25T20:34:21.156Z,1601066061.156 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203421.00,A,3652.20119,N,12155.79011,W,14.637,314.81,250920,,,D*4E
2020-09-25T20:34:21.158Z,1601066061.158 [NAL9602](INFO): GPS fix at 20200925T203421: (36.870020, -121.929835)
2020-09-25T20:34:21.188Z,1601066061.188 [Default:CheckIn:Read_GPS] Stopped
2020-09-25T20:34:21.188Z,1601066061.188 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-09-25T20:34:41.469Z,1601066081.469 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T20:34:43.480Z,1601066083.480 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.281113
2020-09-25T20:35:14.629Z,1601066114.629 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T20:35:49.769Z,1601066149.769 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T20:36:00.537Z,1601066160.537 [NAL9602](INFO): SBD MO Status=2, MOMSN=9999, MT Status=2, MTMSN=0
2020-09-25T20:36:00.537Z,1601066160.537 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-09-25T20:37:00.069Z,1601066220.069 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T20:37:18.577Z,1601066238.577 [NAL9602](INFO): SBD MO Status=2, MOMSN=9999, MT Status=2, MTMSN=0
2020-09-25T20:37:18.577Z,1601066238.577 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-09-25T20:37:22.955Z,1601066242.955 [BPC1](INFO): Calculating totals. Valid battery stick count: 50. Valid reserve battery stick count: 5.
2020-09-25T20:37:22.958Z,1601066242.958 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2020-09-25T20:37:35.209Z,1601066255.209 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T20:38:10.357Z,1601066290.357 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T20:38:28.003Z,1601066308.003 [NAL9602](INFO): SBD MO Status=1, MOMSN=9999, MT Status=0, MTMSN=0
2020-09-25T20:38:28.003Z,1601066308.003 [NAL9602](INFO): No messages in MT queue
2020-09-25T20:38:45.505Z,1601066325.505 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T20:38:53.427Z,1601066333.427 [NAL9602](INFO): SBD MO Status=2, MOMSN=10000, MT Status=2, MTMSN=0
2020-09-25T20:38:53.427Z,1601066333.427 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-09-25T20:39:12.775Z,1601066352.775 [NAL9602](INFO): SBD MO Status=1, MOMSN=10000, MT Status=0, MTMSN=0
2020-09-25T20:39:12.837Z,1601066352.837 [NAL9602](INFO): Sent 71 bytes from file Logs/20200925T192622/Courier0033.lzma
2020-09-25T20:39:12.837Z,1601066352.837 [NAL9602](INFO): Packets left to send: 0
2020-09-25T20:39:20.657Z,1601066360.657 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T20:39:31.583Z,1601066371.583 [NAL9602](INFO): SBD MO Status=1, MOMSN=10001, MT Status=0, MTMSN=0
2020-09-25T20:39:31.633Z,1601066371.633 [NAL9602](INFO): Sent 146 bytes from file Logs/20200925T192622/Express0034.lzma
2020-09-25T20:39:31.633Z,1601066371.633 [NAL9602](INFO): Packets left to send: 0
2020-09-25T20:39:47.663Z,1601066387.663 [NAL9602](INFO): SBD MO Status=0, MOMSN=10002, MT Status=0, MTMSN=0
2020-09-25T20:39:47.744Z,1601066387.744 [Default:CheckIn:Read_Iridium] Stopped
2020-09-25T20:39:47.746Z,1601066387.746 [Default:CheckIn:C.Wait] Running Loop=1
2020-09-25T20:39:47.746Z,1601066387.746 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-09-25T20:39:55.801Z,1601066395.801 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T20:40:18.371Z,1601066418.371 [NAL9602](INFO): Not Powering down - fast GPS
2020-09-25T20:40:30.941Z,1601066430.941 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T20:41:41.221Z,1601066501.221 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T20:42:16.361Z,1601066536.361 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T20:42:51.517Z,1601066571.517 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T20:43:26.657Z,1601066606.657 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T20:44:01.817Z,1601066641.817 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T20:44:36.965Z,1601066676.965 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T20:44:48.256Z,1601066688.256 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-09-25T20:44:48.256Z,1601066688.256 [Default:CheckIn:C.Wait] Stopped
2020-09-25T20:44:48.256Z,1601066688.256 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-09-25T20:44:48.256Z,1601066688.256 [Default:CheckIn:D] Running Loop=1
2020-09-25T20:44:48.675Z,1601066688.675 [Default:CheckIn:D] Stopped
2020-09-25T20:44:48.675Z,1601066688.675 [Default:CheckIn:E] Running Loop=1
2020-09-25T20:44:49.059Z,1601066689.059 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 85.618612 min 
2020-09-25T20:44:49.059Z,1601066689.059 [Default:CheckIn:E] Stopped
2020-09-25T20:44:49.060Z,1601066689.060 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-09-25T20:44:49.060Z,1601066689.060 [Default:CheckIn] Stopped
2020-09-25T20:44:49.060Z,1601066689.060 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-09-25T20:44:49.060Z,1601066689.060 [Default:CheckIn](INFO): Running loop #13
2020-09-25T20:44:49.060Z,1601066689.060 [Default:CheckIn] Running Loop=13
2020-09-25T20:44:49.060Z,1601066689.060 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-09-25T20:44:49.060Z,1601066689.060 [Default:CheckIn:Read_GPS] Running Loop=1
2020-09-25T20:44:51.071Z,1601066691.071 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204451.00,A,3652.92945,N,12156.67736,W,8.436,304.87,250920,,,D*71
2020-09-25T20:44:51.073Z,1601066691.073 [NAL9602](INFO): GPS fix at 20200925T204451: (36.882157, -121.944623)
2020-09-25T20:44:51.093Z,1601066691.093 [Default:CheckIn:Read_GPS] Stopped
2020-09-25T20:44:51.093Z,1601066691.093 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-09-25T20:45:12.105Z,1601066712.105 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T20:45:33.186Z,1601066733.186 [NAL9602](INFO): SBD MO Status=2, MOMSN=10003, MT Status=2, MTMSN=0
2020-09-25T20:45:33.187Z,1601066733.187 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-09-25T20:45:47.261Z,1601066747.261 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T20:46:18.703Z,1601066778.703 [NAL9602](INFO): SBD MO Status=2, MOMSN=10003, MT Status=2, MTMSN=0
2020-09-25T20:46:18.703Z,1601066778.703 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-09-25T20:46:22.401Z,1601066782.401 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T20:46:57.541Z,1601066817.541 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T20:47:13.764Z,1601066833.764 [NAL9602](INFO): SBD MO Status=1, MOMSN=10003, MT Status=0, MTMSN=0
2020-09-25T20:47:13.821Z,1601066833.821 [NAL9602](INFO): Sent 202 bytes from file Logs/20200925T192622/Courier0036.lzma
2020-09-25T20:47:13.821Z,1601066833.821 [NAL9602](INFO): Packets left to send: 0
2020-09-25T20:47:31.987Z,1601066851.987 [NAL9602](INFO): SBD MO Status=2, MOMSN=10004, MT Status=2, MTMSN=0
2020-09-25T20:47:31.987Z,1601066851.987 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-09-25T20:47:32.682Z,1601066852.682 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T20:48:07.837Z,1601066887.837 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T20:48:10.859Z,1601066890.859 [NAL9602](INFO): SBD MO Status=2, MOMSN=10004, MT Status=2, MTMSN=0
2020-09-25T20:48:10.859Z,1601066890.859 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-09-25T20:48:32.619Z,1601066912.619 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003659
2020-09-25T20:48:40.506Z,1601066920.506 [NAL9602](INFO): SBD MO Status=2, MOMSN=10004, MT Status=2, MTMSN=0
2020-09-25T20:48:40.506Z,1601066920.506 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-09-25T20:48:41.718Z,1601066921.718 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-09-25T20:49:05.082Z,1601066945.082 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T20:49:11.206Z,1601066951.206 [NAL9602](INFO): Not Powering down - fast GPS
2020-09-25T20:49:33.194Z,1601066973.194 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.579136
2020-09-25T20:50:04.317Z,1601067004.317 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T20:50:10.195Z,1601067010.195 [NAL9602](INFO): SBD MO Status=2, MOMSN=10004, MT Status=2, MTMSN=0
2020-09-25T20:50:10.195Z,1601067010.195 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-09-25T20:50:20.134Z,1601067020.134 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004300
2020-09-25T20:50:41.015Z,1601067041.015 [NAL9602](INFO): SBD MO Status=2, MOMSN=10004, MT Status=2, MTMSN=0
2020-09-25T20:50:41.015Z,1601067041.015 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-09-25T20:50:52.733Z,1601067052.733 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T20:51:03.368Z,1601067063.368 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20200925T192622/Express0037.lzma
2020-09-25T20:51:04.371Z,1601067064.371 [DataOverHttps](INFO): Moved sent file to Logs/20200925T192622/Express0037.lzma.bak
2020-09-25T20:51:04.371Z,1601067064.371 [DataOverHttps](INFO): SBD MOMSN=12671882
2020-09-25T20:51:06.918Z,1601067066.918 [Default:CheckIn:Read_Iridium] Stopped
2020-09-25T20:51:06.919Z,1601067066.919 [Default:CheckIn:C.Wait] Running Loop=1
2020-09-25T20:51:06.919Z,1601067066.919 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-09-25T20:51:11.714Z,1601067071.714 [NAL9602](INFO): Not Powering down - fast GPS
2020-09-25T20:56:07.480Z,1601067367.480 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-09-25T20:56:07.480Z,1601067367.480 [Default:CheckIn:C.Wait] Stopped
2020-09-25T20:56:07.480Z,1601067367.480 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-09-25T20:56:07.480Z,1601067367.480 [Default:CheckIn:D] Running Loop=1
2020-09-25T20:56:07.890Z,1601067367.890 [Default:CheckIn:D] Stopped
2020-09-25T20:56:07.890Z,1601067367.890 [Default:CheckIn:E] Running Loop=1
2020-09-25T20:56:08.280Z,1601067368.280 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 96.938859 min 
2020-09-25T20:56:08.280Z,1601067368.280 [Default:CheckIn:E] Stopped
2020-09-25T20:56:08.281Z,1601067368.281 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-09-25T20:56:08.281Z,1601067368.281 [Default:CheckIn] Stopped
2020-09-25T20:56:08.281Z,1601067368.281 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-09-25T20:56:08.281Z,1601067368.281 [Default:CheckIn](INFO): Running loop #14
2020-09-25T20:56:08.281Z,1601067368.281 [Default:CheckIn] Running Loop=14
2020-09-25T20:56:08.281Z,1601067368.281 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-09-25T20:56:08.281Z,1601067368.281 [Default:CheckIn:Read_GPS] Running Loop=1
2020-09-25T20:56:10.288Z,1601067370.288 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205610.00,A,3653.91073,N,12157.87379,W,7.562,313.70,250920,,,D*78
2020-09-25T20:56:10.290Z,1601067370.290 [NAL9602](INFO): GPS fix at 20200925T205610: (36.898512, -121.964563)
2020-09-25T20:56:10.304Z,1601067370.304 [Default:CheckIn:Read_GPS] Stopped
2020-09-25T20:56:10.304Z,1601067370.304 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-09-25T20:56:20.368Z,1601067380.368 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20200925T192622/Courier0039.lzma
2020-09-25T20:56:21.370Z,1601067381.370 [DataOverHttps](INFO): Moved sent file to Logs/20200925T192622/Courier0039.lzma.bak
2020-09-25T20:56:21.371Z,1601067381.371 [DataOverHttps](INFO): SBD MOMSN=12671892
2020-09-25T20:56:43.029Z,1601067403.029 [DataOverHttps](INFO): Sending 158 bytes from file Logs/20200925T192622/Express0040.lzma
2020-09-25T20:56:43.919Z,1601067403.919 [DataOverHttps](INFO): Moved sent file to Logs/20200925T192622/Express0040.lzma.bak
2020-09-25T20:56:43.919Z,1601067403.919 [DataOverHttps](INFO): SBD MOMSN=12671895
2020-09-25T20:56:44.671Z,1601067404.671 [NAL9602](INFO): SBD MO Status=2, MOMSN=10004, MT Status=2, MTMSN=0
2020-09-25T20:56:44.672Z,1601067404.672 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-09-25T20:56:46.292Z,1601067406.292 [Default:CheckIn:Read_Iridium] Stopped
2020-09-25T20:56:46.292Z,1601067406.292 [Default:CheckIn:C.Wait] Running Loop=1
2020-09-25T20:56:46.293Z,1601067406.293 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-09-25T20:57:01.635Z,1601067421.635 [NAL9602](INFO): SBD MO Status=2, MOMSN=10004, MT Status=2, MTMSN=0
2020-09-25T20:57:01.636Z,1601067421.636 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-09-25T20:57:20.223Z,1601067440.223 [NAL9602](INFO): SBD MO Status=2, MOMSN=10004, MT Status=2, MTMSN=0
2020-09-25T20:57:20.223Z,1601067440.223 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-09-25T20:57:37.995Z,1601067457.995 [NAL9602](INFO): SBD MO Status=2, MOMSN=10004, MT Status=2, MTMSN=0
2020-09-25T20:57:37.996Z,1601067457.996 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-09-25T20:58:32.939Z,1601067512.939 [NAL9602](INFO): SBD MO Status=1, MOMSN=10004, MT Status=0, MTMSN=0
2020-09-25T20:58:32.939Z,1601067512.939 [NAL9602](INFO): No messages in MT queue
2020-09-25T20:59:03.642Z,1601067543.642 [NAL9602](INFO): Not Powering down - fast GPS
2020-09-25T21:01:46.912Z,1601067706.912 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-09-25T21:01:46.912Z,1601067706.912 [Default:CheckIn:C.Wait] Stopped
2020-09-25T21:01:46.912Z,1601067706.912 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-09-25T21:01:46.917Z,1601067706.917 [Default:CheckIn:D] Running Loop=1
2020-09-25T21:01:47.303Z,1601067707.303 [Default:CheckIn:D] Stopped
2020-09-25T21:01:47.303Z,1601067707.303 [Default:CheckIn:E] Running Loop=1
2020-09-25T21:01:47.710Z,1601067707.710 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 102.595744 min 
2020-09-25T21:01:47.710Z,1601067707.710 [Default:CheckIn:E] Stopped
2020-09-25T21:01:47.710Z,1601067707.710 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-09-25T21:01:47.711Z,1601067707.711 [Default:CheckIn] Stopped
2020-09-25T21:01:47.711Z,1601067707.711 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-09-25T21:01:47.711Z,1601067707.711 [Default:CheckIn](INFO): Running loop #15
2020-09-25T21:01:47.711Z,1601067707.711 [Default:CheckIn] Running Loop=15
2020-09-25T21:01:47.711Z,1601067707.711 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-09-25T21:01:47.711Z,1601067707.711 [Default:CheckIn:Read_GPS] Running Loop=1
2020-09-25T21:01:49.695Z,1601067709.695 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210149.00,A,3654.12079,N,12157.90112,W,2.566,105.71,250920,,,D*7D
2020-09-25T21:01:49.698Z,1601067709.698 [NAL9602](INFO): GPS fix at 20200925T210149: (36.902013, -121.965019)
2020-09-25T21:01:49.718Z,1601067709.718 [Default:CheckIn:Read_GPS] Stopped
2020-09-25T21:01:49.718Z,1601067709.718 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-09-25T21:01:57.848Z,1601067717.848 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20200925T192622/Courier0042.lzma
2020-09-25T21:01:57.886Z,1601067717.886 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-09-25T21:02:00.858Z,1601067720.858 [DataOverHttps](INFO): Moved sent file to Logs/20200925T192622/Courier0042.lzma.bak
2020-09-25T21:02:00.859Z,1601067720.859 [DataOverHttps](INFO): SBD MOMSN=12671908
2020-09-25T21:02:18.780Z,1601067738.780 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20200925T192622/Express0043.lzma
2020-09-25T21:02:19.783Z,1601067739.783 [DataOverHttps](INFO): Moved sent file to Logs/20200925T192622/Express0043.lzma.bak
2020-09-25T21:02:19.783Z,1601067739.783 [DataOverHttps](INFO): SBD MOMSN=12671911
2020-09-25T21:02:22.575Z,1601067742.575 [Default:CheckIn:Read_Iridium] Stopped
2020-09-25T21:02:22.575Z,1601067742.575 [Default:CheckIn:C.Wait] Running Loop=1
2020-09-25T21:02:22.575Z,1601067742.575 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-09-25T21:02:59.258Z,1601067779.258 [NAL9602](INFO): SBD MO Status=1, MOMSN=10005, MT Status=0, MTMSN=0
2020-09-25T21:02:59.258Z,1601067779.258 [NAL9602](INFO): No messages in MT queue
2020-09-25T21:03:29.962Z,1601067809.962 [NAL9602](INFO): Not Powering down - fast GPS
2020-09-25T21:07:23.074Z,1601068043.074 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-09-25T21:07:23.074Z,1601068043.074 [Default:CheckIn:C.Wait] Stopped
2020-09-25T21:07:23.074Z,1601068043.074 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-09-25T21:07:23.075Z,1601068043.075 [Default:CheckIn:D] Running Loop=1
2020-09-25T21:07:23.484Z,1601068043.484 [Default:CheckIn:D] Stopped
2020-09-25T21:07:23.484Z,1601068043.484 [Default:CheckIn:E] Running Loop=1
2020-09-25T21:07:23.903Z,1601068043.903 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 108.198763 min 
2020-09-25T21:07:23.903Z,1601068043.903 [Default:CheckIn:E] Stopped
2020-09-25T21:07:23.904Z,1601068043.904 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-09-25T21:07:23.904Z,1601068043.904 [Default:CheckIn] Stopped
2020-09-25T21:07:23.904Z,1601068043.904 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-09-25T21:07:23.904Z,1601068043.904 [Default:CheckIn](INFO): Running loop #16
2020-09-25T21:07:23.904Z,1601068043.904 [Default:CheckIn] Running Loop=16
2020-09-25T21:07:23.904Z,1601068043.904 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-09-25T21:07:23.904Z,1601068043.904 [Default:CheckIn:Read_GPS] Running Loop=1
2020-09-25T21:07:25.899Z,1601068045.899 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210726.00,A,3654.06443,N,12157.67314,W,1.633,106.14,250920,,,A*73
2020-09-25T21:07:25.907Z,1601068045.907 [NAL9602](INFO): GPS fix at 20200925T210726: (36.901074, -121.961219)
2020-09-25T21:07:25.935Z,1601068045.935 [Default:CheckIn:Read_GPS] Stopped
2020-09-25T21:07:25.935Z,1601068045.935 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-09-25T21:07:33.524Z,1601068053.524 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20200925T192622/Courier0045.lzma
2020-09-25T21:07:34.526Z,1601068054.526 [DataOverHttps](INFO): Moved sent file to Logs/20200925T192622/Courier0045.lzma.bak
2020-09-25T21:07:34.527Z,1601068054.527 [DataOverHttps](INFO): SBD MOMSN=12671944
2020-09-25T21:07:54.168Z,1601068074.168 [DataOverHttps](INFO): Sending 136 bytes from file Logs/20200925T192622/Express0046.lzma
2020-09-25T21:07:55.171Z,1601068075.171 [DataOverHttps](INFO): Moved sent file to Logs/20200925T192622/Express0046.lzma.bak
2020-09-25T21:07:55.171Z,1601068075.171 [DataOverHttps](INFO): SBD MOMSN=12671947
2020-09-25T21:07:57.887Z,1601068077.887 [Default:CheckIn:Read_Iridium] Stopped
2020-09-25T21:07:57.888Z,1601068077.888 [Default:CheckIn:C.Wait] Running Loop=1
2020-09-25T21:07:57.888Z,1601068077.888 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-09-25T21:08:27.301Z,1601068107.301 [NAL9602](INFO): SBD MO Status=1, MOMSN=10006, MT Status=0, MTMSN=0
2020-09-25T21:08:27.301Z,1601068107.301 [NAL9602](INFO): No messages in MT queue
2020-09-25T21:08:58.014Z,1601068138.014 [NAL9602](INFO): Not Powering down - fast GPS
2020-09-25T21:12:58.400Z,1601068378.400 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-09-25T21:12:58.400Z,1601068378.400 [Default:CheckIn:C.Wait] Stopped
2020-09-25T21:12:58.400Z,1601068378.400 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-09-25T21:12:58.425Z,1601068378.425 [Default:CheckIn:D] Running Loop=1
2020-09-25T21:12:58.814Z,1601068378.814 [Default:CheckIn:D] Stopped
2020-09-25T21:12:58.814Z,1601068378.814 [Default:CheckIn:E] Running Loop=1
2020-09-25T21:12:59.211Z,1601068379.211 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 113.787598 min 
2020-09-25T21:12:59.211Z,1601068379.211 [Default:CheckIn:E] Stopped
2020-09-25T21:12:59.211Z,1601068379.211 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-09-25T21:12:59.211Z,1601068379.211 [Default:CheckIn] Stopped
2020-09-25T21:12:59.211Z,1601068379.211 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-09-25T21:12:59.212Z,1601068379.212 [Default:CheckIn](INFO): Running loop #17
2020-09-25T21:12:59.212Z,1601068379.212 [Default:CheckIn] Running Loop=17
2020-09-25T21:12:59.212Z,1601068379.212 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-09-25T21:12:59.212Z,1601068379.212 [Default:CheckIn:Read_GPS] Running Loop=1
2020-09-25T21:13:01.216Z,1601068381.216 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211301.00,A,3654.01166,N,12157.45116,W,2.352,101.49,250920,,,A*78
2020-09-25T21:13:01.218Z,1601068381.218 [NAL9602](INFO): GPS fix at 20200925T211301: (36.900194, -121.957519)
2020-09-25T21:13:01.240Z,1601068381.240 [Default:CheckIn:Read_GPS] Stopped
2020-09-25T21:13:01.240Z,1601068381.240 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-09-25T21:13:09.316Z,1601068389.316 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200925T192622/Courier0048.lzma
2020-09-25T21:13:10.319Z,1601068390.319 [DataOverHttps](INFO): Moved sent file to Logs/20200925T192622/Courier0048.lzma.bak
2020-09-25T21:13:10.319Z,1601068390.319 [DataOverHttps](INFO): SBD MOMSN=12671992
2020-09-25T21:13:29.232Z,1601068409.232 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20200925T192622/Express0049.lzma
2020-09-25T21:13:30.235Z,1601068410.235 [DataOverHttps](INFO): Moved sent file to Logs/20200925T192622/Express0049.lzma.bak
2020-09-25T21:13:30.235Z,1601068410.235 [DataOverHttps](INFO): SBD MOMSN=12671997
2020-09-25T21:13:33.164Z,1601068413.164 [Default:CheckIn:Read_Iridium] Stopped
2020-09-25T21:13:33.169Z,1601068413.169 [Default:CheckIn:C.Wait] Running Loop=1
2020-09-25T21:13:33.169Z,1601068413.169 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-09-25T21:14:02.628Z,1601068442.628 [NAL9602](INFO): SBD MO Status=2, MOMSN=10007, MT Status=2, MTMSN=0
2020-09-25T21:14:02.628Z,1601068442.628 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-09-25T21:14:25.268Z,1601068465.268 [NAL9602](INFO): SBD MO Status=1, MOMSN=10007, MT Status=0, MTMSN=0
2020-09-25T21:14:25.268Z,1601068465.268 [NAL9602](INFO): No messages in MT queue
2020-09-25T21:14:55.958Z,1601068495.958 [NAL9602](INFO): Not Powering down - fast GPS
2020-09-25T21:18:21.997Z,1601068701.997 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2020-09-25T21:18:33.789Z,1601068713.789 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-09-25T21:18:33.789Z,1601068713.789 [Default:CheckIn:C.Wait] Stopped
2020-09-25T21:18:33.790Z,1601068713.790 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-09-25T21:18:33.790Z,1601068713.790 [Default:CheckIn:D] Running Loop=1
2020-09-25T21:18:34.046Z,1601068714.046 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.855438
2020-09-25T21:18:34.164Z,1601068714.164 [Default:CheckIn:D] Stopped
2020-09-25T21:18:34.164Z,1601068714.164 [Default:CheckIn:E] Running Loop=1
2020-09-25T21:18:34.565Z,1601068714.565 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 119.376758 min 
2020-09-25T21:18:34.565Z,1601068714.565 [Default:CheckIn:E] Stopped
2020-09-25T21:18:34.565Z,1601068714.565 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-09-25T21:18:34.565Z,1601068714.565 [Default:CheckIn] Stopped
2020-09-25T21:18:34.566Z,1601068714.566 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-09-25T21:18:34.566Z,1601068714.566 [Default:CheckIn](INFO): Running loop #18
2020-09-25T21:18:34.566Z,1601068714.566 [Default:CheckIn] Running Loop=18
2020-09-25T21:18:34.566Z,1601068714.566 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-09-25T21:18:34.566Z,1601068714.566 [Default:CheckIn:Read_GPS] Running Loop=1
2020-09-25T21:18:36.585Z,1601068716.585 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211836.00,A,3653.41162,N,12156.37401,W,20.391,123.94,250920,,,D*4D
2020-09-25T21:18:36.587Z,1601068716.587 [NAL9602](INFO): GPS fix at 20200925T211836: (36.890194, -121.939567)
2020-09-25T21:18:36.598Z,1601068716.598 [Default:CheckIn:Read_GPS] Stopped
2020-09-25T21:18:36.598Z,1601068716.598 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-09-25T21:18:51.850Z,1601068731.850 [NAL9602](INFO): SBD MO Status=1, MOMSN=10008, MT Status=0, MTMSN=0
2020-09-25T21:18:51.901Z,1601068731.901 [NAL9602](INFO): Sent 71 bytes from file Logs/20200925T192622/Courier0051.lzma
2020-09-25T21:18:51.901Z,1601068731.901 [NAL9602](INFO): Packets left to send: 0
2020-09-25T21:19:04.827Z,1601068744.827 [NAL9602](INFO): SBD MO Status=1, MOMSN=10009, MT Status=0, MTMSN=0
2020-09-25T21:19:04.877Z,1601068744.877 [NAL9602](INFO): Sent 146 bytes from file Logs/20200925T192622/Express0052.lzma
2020-09-25T21:19:04.877Z,1601068744.877 [NAL9602](INFO): Packets left to send: 0
2020-09-25T21:19:14.909Z,1601068754.909 [NAL9602](INFO): SBD MO Status=0, MOMSN=10010, MT Status=0, MTMSN=0
2020-09-25T21:19:14.993Z,1601068754.993 [Default:CheckIn:Read_Iridium] Stopped
2020-09-25T21:19:14.994Z,1601068754.994 [Default:CheckIn:C.Wait] Running Loop=1
2020-09-25T21:19:14.994Z,1601068754.994 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-09-25T21:19:32.277Z,1601068772.277 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T21:19:45.617Z,1601068785.617 [NAL9602](INFO): Not Powering down - fast GPS
2020-09-25T21:20:07.425Z,1601068807.425 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T21:20:42.565Z,1601068842.565 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T21:21:17.705Z,1601068877.705 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T21:21:52.853Z,1601068912.853 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T21:22:27.993Z,1601068947.993 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T21:23:03.157Z,1601068983.157 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T21:23:38.297Z,1601069018.297 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T21:24:13.453Z,1601069053.453 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T21:24:15.503Z,1601069055.503 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-09-25T21:24:15.503Z,1601069055.503 [Default:CheckIn:C.Wait] Stopped
2020-09-25T21:24:15.503Z,1601069055.503 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-09-25T21:24:15.503Z,1601069055.503 [Default:CheckIn:D] Running Loop=1
2020-09-25T21:24:15.914Z,1601069055.914 [Default:CheckIn:D] Stopped
2020-09-25T21:24:15.914Z,1601069055.914 [Default:CheckIn:E] Running Loop=1
2020-09-25T21:24:16.309Z,1601069056.309 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 125.072591 min 
2020-09-25T21:24:16.309Z,1601069056.309 [Default:CheckIn:E] Stopped
2020-09-25T21:24:16.309Z,1601069056.309 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-09-25T21:24:16.309Z,1601069056.309 [Default:CheckIn] Stopped
2020-09-25T21:24:16.309Z,1601069056.309 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-09-25T21:24:16.310Z,1601069056.310 [Default:CheckIn](INFO): Running loop #19
2020-09-25T21:24:16.310Z,1601069056.310 [Default:CheckIn] Running Loop=19
2020-09-25T21:24:16.310Z,1601069056.310 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-09-25T21:24:16.310Z,1601069056.310 [Default:CheckIn:Read_GPS] Running Loop=1
2020-09-25T21:24:18.320Z,1601069058.320 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212418.00,A,3652.24588,N,12154.32804,W,20.566,125.71,250920,,,A*44
2020-09-25T21:24:18.322Z,1601069058.322 [NAL9602](INFO): GPS fix at 20200925T212418: (36.870765, -121.905467)
2020-09-25T21:24:18.359Z,1601069058.359 [Default:CheckIn:Read_GPS] Stopped
2020-09-25T21:24:18.360Z,1601069058.360 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-09-25T21:24:36.579Z,1601069076.579 [NAL9602](INFO): SBD MO Status=1, MOMSN=10011, MT Status=0, MTMSN=0
2020-09-25T21:24:36.629Z,1601069076.629 [NAL9602](INFO): Sent 72 bytes from file Logs/20200925T192622/Courier0054.lzma
2020-09-25T21:24:36.629Z,1601069076.629 [NAL9602](INFO): Packets left to send: 0
2020-09-25T21:24:46.368Z,1601069086.368 [NAL9602](INFO): SBD MO Status=1, MOMSN=10012, MT Status=0, MTMSN=0
2020-09-25T21:24:46.421Z,1601069086.421 [NAL9602](INFO): Sent 147 bytes from file Logs/20200925T192622/Express0055.lzma
2020-09-25T21:24:46.421Z,1601069086.421 [NAL9602](INFO): Packets left to send: 0
2020-09-25T21:24:48.593Z,1601069088.593 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T21:24:56.799Z,1601069096.799 [NAL9602](INFO): SBD MO Status=0, MOMSN=10013, MT Status=0, MTMSN=0
2020-09-25T21:24:57.028Z,1601069097.028 [Default:CheckIn:Read_Iridium] Stopped
2020-09-25T21:24:58.053Z,1601069098.053 [Default:CheckIn:C.Wait] Running Loop=1
2020-09-25T21:24:58.053Z,1601069098.053 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-09-25T21:25:23.741Z,1601069123.741 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T21:25:28.378Z,1601069128.378 [NAL9602](INFO): Not Powering down - fast GPS
2020-09-25T21:25:58.901Z,1601069158.901 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T21:26:34.042Z,1601069194.042 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T21:27:09.182Z,1601069229.182 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T21:27:44.322Z,1601069264.322 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T21:28:19.461Z,1601069299.461 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T21:28:54.601Z,1601069334.601 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T21:29:29.741Z,1601069369.741 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T21:29:58.267Z,1601069398.267 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-09-25T21:29:58.267Z,1601069398.267 [Default:CheckIn:C.Wait] Stopped
2020-09-25T21:29:58.267Z,1601069398.267 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-09-25T21:29:58.268Z,1601069398.268 [Default:CheckIn:D] Running Loop=1
2020-09-25T21:29:58.671Z,1601069398.671 [Default:CheckIn:D] Stopped
2020-09-25T21:29:58.671Z,1601069398.671 [Default:CheckIn:E] Running Loop=1
2020-09-25T21:29:59.099Z,1601069399.099 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 130.785213 min 
2020-09-25T21:29:59.099Z,1601069399.099 [Default:CheckIn:E] Stopped
2020-09-25T21:29:59.099Z,1601069399.099 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-09-25T21:29:59.099Z,1601069399.099 [Default:CheckIn] Stopped
2020-09-25T21:29:59.099Z,1601069399.099 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-09-25T21:29:59.099Z,1601069399.099 [Default:CheckIn](INFO): Running loop #20
2020-09-25T21:29:59.099Z,1601069399.099 [Default:CheckIn] Running Loop=20
2020-09-25T21:29:59.099Z,1601069399.099 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-09-25T21:29:59.100Z,1601069399.100 [Default:CheckIn:Read_GPS] Running Loop=1
2020-09-25T21:30:01.084Z,1601069401.084 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213001.00,A,3652.16654,N,12152.32599,W,21.168,47.83,250920,,,A*76
2020-09-25T21:30:01.086Z,1601069401.086 [NAL9602](INFO): GPS fix at 20200925T213001: (36.869442, -121.872100)
2020-09-25T21:30:01.116Z,1601069401.116 [Default:CheckIn:Read_GPS] Stopped
2020-09-25T21:30:01.116Z,1601069401.116 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-09-25T21:30:04.881Z,1601069404.881 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T21:30:27.257Z,1601069427.257 [NAL9602](INFO): SBD MO Status=1, MOMSN=10014, MT Status=0, MTMSN=0
2020-09-25T21:30:27.313Z,1601069427.313 [NAL9602](INFO): Sent 72 bytes from file Logs/20200925T192622/Courier0057.lzma
2020-09-25T21:30:27.313Z,1601069427.313 [NAL9602](INFO): Packets left to send: 0
2020-09-25T21:30:37.075Z,1601069437.075 [NAL9602](INFO): SBD MO Status=1, MOMSN=10015, MT Status=0, MTMSN=0
2020-09-25T21:30:37.121Z,1601069437.121 [NAL9602](INFO): Sent 162 bytes from file Logs/20200925T192622/Express0058.lzma
2020-09-25T21:30:37.121Z,1601069437.121 [NAL9602](INFO): Packets left to send: 0
2020-09-25T21:30:40.021Z,1601069440.021 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T21:30:44.780Z,1601069444.780 [NAL9602](INFO): SBD MO Status=0, MOMSN=10016, MT Status=0, MTMSN=0
2020-09-25T21:30:44.860Z,1601069444.860 [Default:CheckIn:Read_Iridium] Stopped
2020-09-25T21:30:44.860Z,1601069444.860 [Default:CheckIn:C.Wait] Running Loop=1
2020-09-25T21:30:44.860Z,1601069444.860 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-09-25T21:31:03.134Z,1601069463.134 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003650
2020-09-25T21:31:15.491Z,1601069475.491 [NAL9602](INFO): Not Powering down - fast GPS
2020-09-25T21:35:45.371Z,1601069745.371 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-09-25T21:35:45.371Z,1601069745.371 [Default:CheckIn:C.Wait] Stopped
2020-09-25T21:35:45.371Z,1601069745.371 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-09-25T21:35:45.371Z,1601069745.371 [Default:CheckIn:D] Running Loop=1
2020-09-25T21:35:45.792Z,1601069745.792 [Default:CheckIn:D] Stopped
2020-09-25T21:35:45.792Z,1601069745.792 [Default:CheckIn:E] Running Loop=1
2020-09-25T21:35:46.180Z,1601069746.180 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 136.570573 min 
2020-09-25T21:35:46.180Z,1601069746.180 [Default:CheckIn:E] Stopped
2020-09-25T21:35:46.180Z,1601069746.180 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-09-25T21:35:46.180Z,1601069746.180 [Default:CheckIn] Stopped
2020-09-25T21:35:46.181Z,1601069746.181 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-09-25T21:35:46.181Z,1601069746.181 [Default:CheckIn](INFO): Running loop #21
2020-09-25T21:35:46.181Z,1601069746.181 [Default:CheckIn] Running Loop=21
2020-09-25T21:35:46.181Z,1601069746.181 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-09-25T21:35:46.181Z,1601069746.181 [Default:CheckIn:Read_GPS] Running Loop=1
2020-09-25T21:35:48.185Z,1601069748.185 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213548.00,A,3652.76662,N,12150.56157,W,16.425,147.94,250920,,,A*44
2020-09-25T21:35:48.187Z,1601069748.187 [NAL9602](INFO): GPS fix at 20200925T213548: (36.879444, -121.842693)
2020-09-25T21:35:48.198Z,1601069748.198 [Default:CheckIn:Read_GPS] Stopped
2020-09-25T21:35:48.198Z,1601069748.198 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-09-25T21:35:55.652Z,1601069755.652 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20200925T192622/Courier0060.lzma
2020-09-25T21:35:56.655Z,1601069756.655 [DataOverHttps](INFO): Moved sent file to Logs/20200925T192622/Courier0060.lzma.bak
2020-09-25T21:35:56.655Z,1601069756.655 [DataOverHttps](INFO): SBD MOMSN=12672258
2020-09-25T21:36:03.155Z,1601069763.155 [NAL9602](INFO): SBD MO Status=0, MOMSN=10017, MT Status=0, MTMSN=0
2020-09-25T21:36:03.155Z,1601069763.155 [NAL9602](INFO): No messages in MT queue
2020-09-25T21:36:15.516Z,1601069775.516 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20200925T192622/Express0061.lzma
2020-09-25T21:36:16.519Z,1601069776.519 [DataOverHttps](INFO): Moved sent file to Logs/20200925T192622/Express0061.lzma.bak
2020-09-25T21:36:16.519Z,1601069776.519 [DataOverHttps](INFO): SBD MOMSN=12672266
2020-09-25T21:36:18.952Z,1601069778.952 [Default:CheckIn:Read_Iridium] Stopped
2020-09-25T21:36:18.976Z,1601069778.976 [Default:CheckIn:C.Wait] Running Loop=1
2020-09-25T21:36:18.977Z,1601069778.977 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-09-25T21:36:33.862Z,1601069793.862 [NAL9602](INFO): Not Powering down - fast GPS
2020-09-25T21:41:19.575Z,1601070079.575 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-09-25T21:41:19.575Z,1601070079.575 [Default:CheckIn:C.Wait] Stopped
2020-09-25T21:41:19.575Z,1601070079.575 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-09-25T21:41:19.575Z,1601070079.575 [Default:CheckIn:D] Running Loop=1
2020-09-25T21:41:19.940Z,1601070079.940 [Default:CheckIn:D] Stopped
2020-09-25T21:41:19.940Z,1601070079.940 [Default:CheckIn:E] Running Loop=1
2020-09-25T21:41:20.352Z,1601070080.352 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 142.139697 min 
2020-09-25T21:41:20.352Z,1601070080.352 [Default:CheckIn:E] Stopped
2020-09-25T21:41:20.352Z,1601070080.352 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-09-25T21:41:20.353Z,1601070080.353 [Default:CheckIn] Stopped
2020-09-25T21:41:20.353Z,1601070080.353 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-09-25T21:41:20.353Z,1601070080.353 [Default:CheckIn](INFO): Running loop #22
2020-09-25T21:41:20.353Z,1601070080.353 [Default:CheckIn] Running Loop=22
2020-09-25T21:41:20.353Z,1601070080.353 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-09-25T21:41:20.353Z,1601070080.353 [Default:CheckIn:Read_GPS] Running Loop=1
2020-09-25T21:41:22.356Z,1601070082.356 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214122.00,A,3651.02393,N,12149.30489,W,22.218,148.65,250920,,,A*40
2020-09-25T21:41:22.358Z,1601070082.358 [NAL9602](INFO): GPS fix at 20200925T214122: (36.850399, -121.821748)
2020-09-25T21:41:22.392Z,1601070082.392 [Default:CheckIn:Read_GPS] Stopped
2020-09-25T21:41:22.392Z,1601070082.392 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-09-25T21:41:53.378Z,1601070113.378 [NAL9602](INFO): SBD MO Status=0, MOMSN=10018, MT Status=0, MTMSN=0
2020-09-25T21:41:53.378Z,1601070113.378 [NAL9602](INFO): No messages in MT queue
2020-09-25T21:41:56.804Z,1601070116.804 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20200925T192622/Courier0063.lzma
2020-09-25T21:41:57.807Z,1601070117.807 [DataOverHttps](INFO): Moved sent file to Logs/20200925T192622/Courier0063.lzma.bak
2020-09-25T21:41:57.807Z,1601070117.807 [DataOverHttps](INFO): SBD MOMSN=12672330
2020-09-25T21:42:16.360Z,1601070136.360 [DataOverHttps](INFO): Sending 147 bytes from file Logs/20200925T192622/Express0064.lzma
2020-09-25T21:42:17.363Z,1601070137.363 [DataOverHttps](INFO): Moved sent file to Logs/20200925T192622/Express0064.lzma.bak
2020-09-25T21:42:17.363Z,1601070137.363 [DataOverHttps](INFO): SBD MOMSN=12672335
2020-09-25T21:42:19.978Z,1601070139.978 [Default:CheckIn:Read_Iridium] Stopped
2020-09-25T21:42:19.978Z,1601070139.978 [Default:CheckIn:C.Wait] Running Loop=1
2020-09-25T21:42:19.978Z,1601070139.978 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-09-25T21:42:23.858Z,1601070143.858 [NAL9602](INFO): Not Powering down - fast GPS
2020-09-25T21:47:08.397Z,1601070428.397 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T21:47:10.405Z,1601070430.405 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.259201
2020-09-25T21:47:20.336Z,1601070440.336 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-09-25T21:47:20.336Z,1601070440.336 [Default:CheckIn:C.Wait] Stopped
2020-09-25T21:47:20.336Z,1601070440.336 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-09-25T21:47:20.336Z,1601070440.336 [Default:CheckIn:D] Running Loop=1
2020-09-25T21:47:20.766Z,1601070440.766 [Default:CheckIn:D] Stopped
2020-09-25T21:47:20.766Z,1601070440.766 [Default:CheckIn:E] Running Loop=1
2020-09-25T21:47:21.155Z,1601070441.155 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 148.153451 min 
2020-09-25T21:47:21.155Z,1601070441.155 [Default:CheckIn:E] Stopped
2020-09-25T21:47:21.155Z,1601070441.155 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-09-25T21:47:21.156Z,1601070441.156 [Default:CheckIn] Stopped
2020-09-25T21:47:21.156Z,1601070441.156 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-09-25T21:47:21.156Z,1601070441.156 [Default:CheckIn](INFO): Running loop #23
2020-09-25T21:47:21.156Z,1601070441.156 [Default:CheckIn] Running Loop=23
2020-09-25T21:47:21.156Z,1601070441.156 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-09-25T21:47:21.156Z,1601070441.156 [Default:CheckIn:Read_GPS] Running Loop=1
2020-09-25T21:47:23.156Z,1601070443.156 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214723.00,A,3648.88606,N,12148.07730,W,24.590,156.79,250920,,,D*46
2020-09-25T21:47:23.158Z,1601070443.158 [NAL9602](INFO): GPS fix at 20200925T214723: (36.814768, -121.801288)
2020-09-25T21:47:23.200Z,1601070443.200 [Default:CheckIn:Read_GPS] Stopped
2020-09-25T21:47:23.200Z,1601070443.200 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-09-25T21:47:43.541Z,1601070463.541 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T21:47:50.647Z,1601070470.647 [NAL9602](INFO): SBD MO Status=2, MOMSN=10019, MT Status=2, MTMSN=0
2020-09-25T21:47:50.647Z,1601070470.647 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-09-25T21:48:16.771Z,1601070496.771 [NAL9602](INFO): SBD MO Status=2, MOMSN=10019, MT Status=2, MTMSN=0
2020-09-25T21:48:16.772Z,1601070496.772 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-09-25T21:48:18.681Z,1601070498.681 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-25T21:49:27.218Z,1601070567.218 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003618
2020-09-25T21:49:34.864Z,1601070574.864 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20200925T192622/Courier0066.lzma
2020-09-25T21:49:35.881Z,1601070575.881 [DataOverHttps](INFO): Moved sent file to Logs/20200925T192622/Courier0066.lzma.bak
2020-09-25T21:49:35.881Z,1601070575.881 [DataOverHttps](INFO): SBD MOMSN=12672417
2020-09-25T21:49:41.407Z,1601070581.407 [NAL9602](INFO): SBD MO Status=2, MOMSN=10019, MT Status=2, MTMSN=0
2020-09-25T21:49:41.407Z,1601070581.407 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-09-25T21:49:53.604Z,1601070593.604 [DataOverHttps](INFO): Sending 147 bytes from file Logs/20200925T192622/Express0067.lzma
2020-09-25T21:49:54.607Z,1601070594.607 [DataOverHttps](INFO): Moved sent file to Logs/20200925T192622/Express0067.lzma.bak
2020-09-25T21:49:54.607Z,1601070594.607 [DataOverHttps](INFO): SBD MOMSN=12672424
2020-09-25T21:49:57.218Z,1601070597.218 [Default:CheckIn:Read_Iridium] Stopped
2020-09-25T21:49:57.218Z,1601070597.218 [Default:CheckIn:C.Wait] Running Loop=1
2020-09-25T21:49:57.218Z,1601070597.218 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-09-25T21:50:12.110Z,1601070612.110 [NAL9602](INFO): Not Powering down - fast GPS
2020-09-25T21:54:57.796Z,1601070897.796 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-09-25T21:54:57.801Z,1601070897.801 [Default:CheckIn:C.Wait] Stopped
2020-09-25T21:54:57.801Z,1601070897.801 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-09-25T21:54:57.801Z,1601070897.801 [Default:CheckIn:D] Running Loop=1
2020-09-25T21:54:58.189Z,1601070898.189 [Default:CheckIn:D] Stopped
2020-09-25T21:54:58.189Z,1601070898.189 [Default:CheckIn:E] Running Loop=1
2020-09-25T21:54:58.618Z,1601070898.618 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 155.777181 min 
2020-09-25T21:54:58.618Z,1601070898.618 [Default:CheckIn:E] Stopped
2020-09-25T21:54:58.619Z,1601070898.619 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-09-25T21:54:58.619Z,1601070898.619 [Default:CheckIn] Stopped
2020-09-25T21:54:58.619Z,1601070898.619 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-09-25T21:54:58.619Z,1601070898.619 [Default:CheckIn](INFO): Running loop #24
2020-09-25T21:54:58.619Z,1601070898.619 [Default:CheckIn] Running Loop=24
2020-09-25T21:54:58.619Z,1601070898.619 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-09-25T21:54:58.619Z,1601070898.619 [Default:CheckIn:Read_GPS] Running Loop=1
2020-09-25T21:55:00.600Z,1601070900.600 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215500.00,A,3648.16687,N,12147.17267,W,3.907,206.87,250920,,,D*73
2020-09-25T21:55:00.602Z,1601070900.602 [NAL9602](INFO): GPS fix at 20200925T215500: (36.802781, -121.786211)
2020-09-25T21:55:00.612Z,1601070900.612 [Default:CheckIn:Read_GPS] Stopped
2020-09-25T21:55:00.612Z,1601070900.612 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-09-25T21:55:09.044Z,1601070909.044 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20200925T192622/Courier0069.lzma
2020-09-25T21:55:10.047Z,1601070910.047 [DataOverHttps](INFO): Moved sent file to Logs/20200925T192622/Courier0069.lzma.bak
2020-09-25T21:55:10.047Z,1601070910.047 [DataOverHttps](INFO): SBD MOMSN=12672482
2020-09-25T21:55:29.260Z,1601070929.260 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20200925T192622/Express0070.lzma
2020-09-25T21:55:30.263Z,1601070930.263 [DataOverHttps](INFO): Moved sent file to Logs/20200925T192622/Express0070.lzma.bak
2020-09-25T21:55:30.263Z,1601070930.263 [DataOverHttps](INFO): SBD MOMSN=12672488
2020-09-25T21:55:32.982Z,1601070932.982 [Default:CheckIn:Read_Iridium] Stopped
2020-09-25T21:55:32.982Z,1601070932.982 [Default:CheckIn:C.Wait] Running Loop=1
2020-09-25T21:55:32.982Z,1601070932.982 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-09-25T21:55:57.995Z,1601070957.995 [NAL9602](INFO): SBD MO Status=1, MOMSN=10019, MT Status=0, MTMSN=0
2020-09-25T21:55:57.996Z,1601070957.996 [NAL9602](INFO): No messages in MT queue
2020-09-25T21:56:28.774Z,1601070988.774 [NAL9602](INFO): Not Powering down - fast GPS
2020-09-25T22:00:33.515Z,1601071233.515 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-09-25T22:00:33.515Z,1601071233.515 [Default:CheckIn:C.Wait] Stopped
2020-09-25T22:00:33.516Z,1601071233.516 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-09-25T22:00:33.516Z,1601071233.516 [Default:CheckIn:D] Running Loop=1
2020-09-25T22:00:33.926Z,1601071233.926 [Default:CheckIn:D] Stopped
2020-09-25T22:00:33.926Z,1601071233.926 [Default:CheckIn:E] Running Loop=1
2020-09-25T22:00:34.289Z,1601071234.289 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 161.372803 min 
2020-09-25T22:00:34.289Z,1601071234.289 [Default:CheckIn:E] Stopped
2020-09-25T22:00:34.289Z,1601071234.289 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-09-25T22:00:34.289Z,1601071234.289 [Default:CheckIn] Stopped
2020-09-25T22:00:34.289Z,1601071234.289 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-09-25T22:00:34.290Z,1601071234.290 [Default:CheckIn](INFO): Running loop #25
2020-09-25T22:00:34.290Z,1601071234.290 [Default:CheckIn] Running Loop=25
2020-09-25T22:00:34.290Z,1601071234.290 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-09-25T22:00:34.290Z,1601071234.290 [Default:CheckIn:Read_GPS] Running Loop=1
2020-09-25T22:00:36.288Z,1601071236.288 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220036.00,A,3648.13740,N,12147.20602,W,0.039,322.63,250920,,,D*73
2020-09-25T22:00:36.290Z,1601071236.290 [NAL9602](INFO): GPS fix at 20200925T220036: (36.802290, -121.786767)
2020-09-25T22:00:36.370Z,1601071236.370 [Default:CheckIn:Read_GPS] Stopped
2020-09-25T22:00:36.370Z,1601071236.370 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-09-25T22:00:38.555Z,1601071238.555 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20200925T192622/Courier0072.lzma
2020-09-25T22:00:39.563Z,1601071239.563 [DataOverHttps](INFO): Moved sent file to Logs/20200925T192622/Courier0072.lzma.bak
2020-09-25T22:00:39.563Z,1601071239.563 [DataOverHttps](INFO): SBD MOMSN=12672505
2020-09-25T22:00:57.402Z,1601071257.402 [NAL9602](INFO): SBD MO Status=1, MOMSN=10020, MT Status=0, MTMSN=0
2020-09-25T22:00:57.402Z,1601071257.402 [NAL9602](INFO): No messages in MT queue
2020-09-25T22:00:57.873Z,1601071257.873 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20200925T192622/Express0073.lzma
2020-09-25T22:00:58.875Z,1601071258.875 [DataOverHttps](INFO): Moved sent file to Logs/20200925T192622/Express0073.lzma.bak
2020-09-25T22:00:58.875Z,1601071258.875 [DataOverHttps](INFO): SBD MOMSN=12672508
2020-09-25T22:01:01.463Z,1601071261.463 [Default:CheckIn:Read_Iridium] Stopped
2020-09-25T22:01:01.463Z,1601071261.463 [Default:CheckIn:C.Wait] Running Loop=1
2020-09-25T22:01:01.464Z,1601071261.464 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-09-25T22:01:28.167Z,1601071288.167 [NAL9602](INFO): Not Powering down - fast GPS
2020-09-25T22:06:01.874Z,1601071561.874 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-09-25T22:06:01.874Z,1601071561.874 [Default:CheckIn:C.Wait] Stopped
2020-09-25T22:06:01.874Z,1601071561.874 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-09-25T22:06:01.875Z,1601071561.875 [Default:CheckIn:D] Running Loop=1
2020-09-25T22:06:02.268Z,1601071562.268 [Default:CheckIn:D] Stopped
2020-09-25T22:06:02.268Z,1601071562.268 [Default:CheckIn:E] Running Loop=1
2020-09-25T22:06:02.653Z,1601071562.653 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 166.845166 min 
2020-09-25T22:06:02.653Z,1601071562.653 [Default:CheckIn:E] Stopped
2020-09-25T22:06:02.653Z,1601071562.653 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-09-25T22:06:02.653Z,1601071562.653 [Default:CheckIn] Stopped
2020-09-25T22:06:02.653Z,1601071562.653 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-09-25T22:06:02.653Z,1601071562.653 [Default:CheckIn](INFO): Running loop #26
2020-09-25T22:06:02.653Z,1601071562.653 [Default:CheckIn] Running Loop=26
2020-09-25T22:06:02.653Z,1601071562.653 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-09-25T22:06:02.654Z,1601071562.654 [Default:CheckIn:Read_GPS] Running Loop=1
2020-09-25T22:06:04.664Z,1601071564.664 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220604.00,A,3648.17110,N,12147.26711,W,2.935,287.98,250920,,,D*7B
2020-09-25T22:06:04.666Z,1601071564.666 [NAL9602](INFO): GPS fix at 20200925T220604: (36.802852, -121.787785)
2020-09-25T22:06:04.687Z,1601071564.687 [Default:CheckIn:Read_GPS] Stopped
2020-09-25T22:06:04.687Z,1601071564.687 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-09-25T22:06:12.484Z,1601071572.484 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20200925T192622/Courier0075.lzma
2020-09-25T22:06:13.493Z,1601071573.493 [DataOverHttps](INFO): Moved sent file to Logs/20200925T192622/Courier0075.lzma.bak
2020-09-25T22:06:13.494Z,1601071573.494 [DataOverHttps](INFO): SBD MOMSN=12672545
2020-09-25T22:06:35.393Z,1601071595.393 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20200925T192622/Express0076.lzma
2020-09-25T22:06:36.387Z,1601071596.387 [DataOverHttps](INFO): Moved sent file to Logs/20200925T192622/Express0076.lzma.bak
2020-09-25T22:06:36.387Z,1601071596.387 [DataOverHttps](INFO): SBD MOMSN=12672551
2020-09-25T22:06:39.102Z,1601071599.102 [Default:CheckIn:Read_Iridium] Stopped
2020-09-25T22:06:39.102Z,1601071599.102 [Default:CheckIn:C.Wait] Running Loop=1
2020-09-25T22:06:39.102Z,1601071599.102 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-09-25T22:08:22.131Z,1601071702.131 [NAL9602](INFO): SBD MO Status=1, MOMSN=10021, MT Status=0, MTMSN=0
2020-09-25T22:08:22.131Z,1601071702.131 [NAL9602](INFO): No messages in MT queue
2020-09-25T22:08:24.209Z,1601071704.209 [BPC1](INFO): Calculating totals. Valid battery stick count: 50. Valid reserve battery stick count: 5.
2020-09-25T22:08:24.212Z,1601071704.212 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2020-09-25T22:08:52.839Z,1601071732.839 [NAL9602](INFO): Not Powering down - fast GPS
2020-09-25T22:11:39.735Z,1601071899.735 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-09-25T22:11:39.735Z,1601071899.735 [Default:CheckIn:C.Wait] Stopped
2020-09-25T22:11:39.735Z,1601071899.735 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-09-25T22:11:39.736Z,1601071899.736 [Default:CheckIn:D] Running Loop=1
2020-09-25T22:11:40.123Z,1601071900.123 [Default:CheckIn:D] Stopped
2020-09-25T22:11:40.123Z,1601071900.123 [Default:CheckIn:E] Running Loop=1
2020-09-25T22:11:40.542Z,1601071900.542 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 172.476074 min 
2020-09-25T22:11:40.542Z,1601071900.542 [Default:CheckIn:E] Stopped
2020-09-25T22:11:40.542Z,1601071900.542 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-09-25T22:11:40.542Z,1601071900.542 [Default:CheckIn] Stopped
2020-09-25T22:11:40.542Z,1601071900.542 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-09-25T22:11:40.542Z,1601071900.542 [Default:CheckIn](INFO): Running loop #27
2020-09-25T22:11:40.542Z,1601071900.542 [Default:CheckIn] Running Loop=27
2020-09-25T22:11:40.542Z,1601071900.542 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-09-25T22:11:40.542Z,1601071900.542 [Default:CheckIn:Read_GPS] Running Loop=1
2020-09-25T22:11:42.565Z,1601071902.565 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221142.00,A,3648.17396,N,12147.27949,W,0.428,305.08,250920,,,D*70
2020-09-25T22:11:42.571Z,1601071902.571 [NAL9602](INFO): GPS fix at 20200925T221142: (36.802899, -121.787992)
2020-09-25T22:11:42.609Z,1601071902.609 [Default:CheckIn:Read_GPS] Stopped
2020-09-25T22:11:42.609Z,1601071902.609 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-09-25T22:11:50.096Z,1601071910.096 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20200925T192622/Courier0078.lzma
2020-09-25T22:11:51.099Z,1601071911.099 [DataOverHttps](INFO): Moved sent file to Logs/20200925T192622/Courier0078.lzma.bak
2020-09-25T22:11:51.099Z,1601071911.099 [DataOverHttps](INFO): SBD MOMSN=12672614
2020-09-25T22:12:08.744Z,1601071928.744 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20200925T192622/Express0079.lzma
2020-09-25T22:12:09.747Z,1601071929.747 [DataOverHttps](INFO): Moved sent file to Logs/20200925T192622/Express0079.lzma.bak
2020-09-25T22:12:09.747Z,1601071929.747 [DataOverHttps](INFO): SBD MOMSN=12672620
2020-09-25T22:12:13.292Z,1601071933.292 [Default:CheckIn:Read_Iridium] Stopped
2020-09-25T22:12:13.292Z,1601071933.292 [Default:CheckIn:C.Wait] Running Loop=1
2020-09-25T22:12:13.292Z,1601071933.292 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-09-25T22:16:49.627Z,1601072209.627 [NAL9602](INFO): SBD MO Status=2, MOMSN=10022, MT Status=2, MTMSN=0
2020-09-25T22:16:49.628Z,1601072209.628 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-09-25T22:16:50.034Z,1601072210.034 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-09-25T22:17:13.880Z,1601072233.880 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-09-25T22:17:13.880Z,1601072233.880 [Default:CheckIn:C.Wait] Stopped
2020-09-25T22:17:13.880Z,1601072233.880 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-09-25T22:17:13.881Z,1601072233.881 [Default:CheckIn:D] Running Loop=1
2020-09-25T22:17:14.290Z,1601072234.290 [Default:CheckIn:D] Stopped
2020-09-25T22:17:14.290Z,1601072234.290 [Default:CheckIn:E] Running Loop=1
2020-09-25T22:17:14.701Z,1601072234.701 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 178.045524 min 
2020-09-25T22:17:14.701Z,1601072234.701 [Default:CheckIn:E] Stopped
2020-09-25T22:17:14.701Z,1601072234.701 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-09-25T22:17:14.701Z,1601072234.701 [Default:CheckIn] Stopped
2020-09-25T22:17:14.701Z,1601072234.701 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-09-25T22:17:14.701Z,1601072234.701 [Default:CheckIn](INFO): Running loop #28
2020-09-25T22:17:14.701Z,1601072234.701 [Default:CheckIn] Running Loop=28
2020-09-25T22:17:14.702Z,1601072234.702 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-09-25T22:17:14.702Z,1601072234.702 [Default:CheckIn:Read_GPS] Running Loop=1
2020-09-25T22:17:16.696Z,1601072236.696 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221716.00,A,3648.16434,N,12147.28719,W,0.233,305.08,250920,,,D*71
2020-09-25T22:17:16.706Z,1601072236.706 [NAL9602](INFO): GPS fix at 20200925T221716: (36.802739, -121.788120)
2020-09-25T22:17:16.743Z,1601072236.743 [Default:CheckIn:Read_GPS] Stopped
2020-09-25T22:17:16.743Z,1601072236.743 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-09-25T22:17:24.860Z,1601072244.860 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20200925T192622/Courier0081.lzma
2020-09-25T22:17:25.863Z,1601072245.863 [DataOverHttps](INFO): Moved sent file to Logs/20200925T192622/Courier0081.lzma.bak
2020-09-25T22:17:25.863Z,1601072245.863 [DataOverHttps](INFO): SBD MOMSN=12672678
2020-09-25T22:17:44.709Z,1601072264.709 [DataOverHttps](INFO): Sending 219 bytes from file Logs/20200925T192622/Express0082.lzma
2020-09-25T22:17:45.711Z,1601072265.711 [DataOverHttps](INFO): Moved sent file to Logs/20200925T192622/Express0082.lzma.bak
2020-09-25T22:17:45.711Z,1601072265.711 [DataOverHttps](INFO): SBD MOMSN=12672687
2020-09-25T22:17:48.228Z,1601072268.228 [Default:CheckIn:Read_Iridium] Stopped
2020-09-25T22:17:48.228Z,1601072268.228 [Default:CheckIn:C.Wait] Running Loop=1
2020-09-25T22:17:48.228Z,1601072268.228 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-09-25T22:17:49.430Z,1601072269.430 [NAL9602](INFO): Not Powering down - fast GPS
2020-09-25T22:22:48.857Z,1601072568.857 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-09-25T22:22:48.857Z,1601072568.857 [Default:CheckIn:C.Wait] Stopped
2020-09-25T22:22:48.857Z,1601072568.857 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-09-25T22:22:48.858Z,1601072568.858 [Default:CheckIn:D] Running Loop=1
2020-09-25T22:22:49.226Z,1601072569.226 [Default:CheckIn:D] Stopped
2020-09-25T22:22:49.226Z,1601072569.226 [Default:CheckIn:E] Running Loop=1
2020-09-25T22:22:49.644Z,1601072569.644 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 183.627799 min 
2020-09-25T22:22:49.644Z,1601072569.644 [Default:CheckIn:E] Stopped
2020-09-25T22:22:49.649Z,1601072569.649 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-09-25T22:22:49.649Z,1601072569.649 [Default:CheckIn] Stopped
2020-09-25T22:22:49.649Z,1601072569.649 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-09-25T22:22:49.649Z,1601072569.649 [Default:CheckIn](INFO): Running loop #29
2020-09-25T22:22:49.649Z,1601072569.649 [Default:CheckIn] Running Loop=29
2020-09-25T22:22:49.649Z,1601072569.649 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-09-25T22:22:49.649Z,1601072569.649 [Default:CheckIn:Read_GPS] Running Loop=1
2020-09-25T22:22:51.632Z,1601072571.632 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222251.00,A,3648.16487,N,12147.28245,W,0.039,305.08,250920,,,D*78
2020-09-25T22:22:51.634Z,1601072571.634 [NAL9602](INFO): GPS fix at 20200925T222251: (36.802748, -121.788041)
2020-09-25T22:22:51.655Z,1601072571.655 [Default:CheckIn:Read_GPS] Stopped
2020-09-25T22:22:51.655Z,1601072571.655 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-09-25T22:22:59.840Z,1601072579.840 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20200925T192622/Courier0084.lzma
2020-09-25T22:23:00.843Z,1601072580.843 [DataOverHttps](INFO): Moved sent file to Logs/20200925T192622/Courier0084.lzma.bak
2020-09-25T22:23:00.843Z,1601072580.843 [DataOverHttps](INFO): SBD MOMSN=12672744
2020-09-25T22:23:18.368Z,1601072598.368 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20200925T192622/Express0085.lzma
2020-09-25T22:23:19.371Z,1601072599.371 [DataOverHttps](INFO): Moved sent file to Logs/20200925T192622/Express0085.lzma.bak
2020-09-25T22:23:19.371Z,1601072599.371 [DataOverHttps](INFO): SBD MOMSN=12672751
2020-09-25T22:23:21.971Z,1601072601.971 [Default:CheckIn:Read_Iridium] Stopped
2020-09-25T22:23:21.972Z,1601072601.972 [Default:CheckIn:C.Wait] Running Loop=1
2020-09-25T22:23:21.972Z,1601072601.972 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-09-25T22:25:33.665Z,1601072733.665 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-09-25T22:27:53.446Z,1601072873.446 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-09-25T22:28:22.594Z,1601072902.594 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-09-25T22:28:22.594Z,1601072902.594 [Default:CheckIn:C.Wait] Stopped
2020-09-25T22:28:22.594Z,1601072902.594 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-09-25T22:28:22.595Z,1601072902.595 [Default:CheckIn:D] Running Loop=1
2020-09-25T22:28:22.952Z,1601072902.952 [Default:CheckIn:D] Stopped
2020-09-25T22:28:22.952Z,1601072902.952 [Default:CheckIn:E] Running Loop=1
2020-09-25T22:28:23.360Z,1601072903.360 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 189.189893 min 
2020-09-25T22:28:23.360Z,1601072903.360 [Default:CheckIn:E] Stopped
2020-09-25T22:28:23.361Z,1601072903.361 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-09-25T22:28:23.361Z,1601072903.361 [Default:CheckIn] Stopped
2020-09-25T22:28:23.361Z,1601072903.361 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-09-25T22:28:23.361Z,1601072903.361 [Default:CheckIn](INFO): Running loop #30
2020-09-25T22:28:23.361Z,1601072903.361 [Default:CheckIn] Running Loop=30
2020-09-25T22:28:23.361Z,1601072903.361 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-09-25T22:28:23.361Z,1601072903.361 [Default:CheckIn:Read_GPS] Running Loop=1
2020-09-25T22:28:25.360Z,1601072905.360 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222825.00,A,3648.16369,N,12147.28128,W,0.136,0.00,250920,,,D*7E
2020-09-25T22:28:25.362Z,1601072905.362 [NAL9602](INFO): GPS fix at 20200925T222825: (36.802728, -121.788021)
2020-09-25T22:28:25.405Z,1601072905.405 [Default:CheckIn:Read_GPS] Stopped
2020-09-25T22:28:25.405Z,1601072905.405 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-09-25T22:28:36.932Z,1601072916.932 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20200925T192622/Courier0087.lzma
2020-09-25T22:28:37.935Z,1601072917.935 [DataOverHttps](INFO): Moved sent file to Logs/20200925T192622/Courier0087.lzma.bak
2020-09-25T22:28:37.935Z,1601072917.935 [DataOverHttps](INFO): SBD MOMSN=12672798
2020-09-25T22:28:55.300Z,1601072935.300 [DataOverHttps](INFO): Sending 205 bytes from file Logs/20200925T192622/Express0088.lzma
2020-09-25T22:28:56.303Z,1601072936.303 [DataOverHttps](INFO): Moved sent file to Logs/20200925T192622/Express0088.lzma.bak
2020-09-25T22:28:56.303Z,1601072936.303 [DataOverHttps](INFO): SBD MOMSN=12672801
2020-09-25T22:28:57.590Z,1601072937.590 [NAL9602](INFO): Not Powering down - fast GPS
2020-09-25T22:28:58.861Z,1601072938.861 [Default:CheckIn:Read_Iridium] Stopped
2020-09-25T22:28:58.861Z,1601072938.861 [Default:CheckIn:C.Wait] Running Loop=1
2020-09-25T22:28:58.862Z,1601072938.862 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-09-25T22:33:59.403Z,1601073239.403 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-09-25T22:33:59.403Z,1601073239.403 [Default:CheckIn:C.Wait] Stopped
2020-09-25T22:33:59.403Z,1601073239.403 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-09-25T22:33:59.403Z,1601073239.403 [Default:CheckIn:D] Running Loop=1
2020-09-25T22:33:59.824Z,1601073239.824 [Default:CheckIn:D] Stopped
2020-09-25T22:33:59.824Z,1601073239.824 [Default:CheckIn:E] Running Loop=1
2020-09-25T22:34:00.212Z,1601073240.212 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 194.804427 min 
2020-09-25T22:34:00.212Z,1601073240.212 [Default:CheckIn:E] Stopped
2020-09-25T22:34:00.229Z,1601073240.229 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-09-25T22:34:00.229Z,1601073240.229 [Default:CheckIn] Stopped
2020-09-25T22:34:00.229Z,1601073240.229 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-09-25T22:34:00.229Z,1601073240.229 [Default:CheckIn](INFO): Running loop #31
2020-09-25T22:34:00.229Z,1601073240.229 [Default:CheckIn] Running Loop=31
2020-09-25T22:34:00.229Z,1601073240.229 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-09-25T22:34:00.229Z,1601073240.229 [Default:CheckIn:Read_GPS] Running Loop=1
2020-09-25T22:34:02.217Z,1601073242.217 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,223402.00,A,3648.16760,N,12147.27929,W,0.428,0.00,250920,,,A*72
2020-09-25T22:34:02.219Z,1601073242.219 [NAL9602](INFO): GPS fix at 20200925T223402: (36.802793, -121.787988)
2020-09-25T22:34:02.240Z,1601073242.240 [Default:CheckIn:Read_GPS] Stopped
2020-09-25T22:34:02.240Z,1601073242.240 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-09-25T22:34:09.528Z,1601073249.528 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20200925T192622/Courier0090.lzma
2020-09-25T22:34:10.531Z,1601073250.531 [DataOverHttps](INFO): Moved sent file to Logs/20200925T192622/Courier0090.lzma.bak
2020-09-25T22:34:10.531Z,1601073250.531 [DataOverHttps](INFO): SBD MOMSN=12672837
2020-09-25T22:34:28.936Z,1601073268.936 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20200925T192622/Express0091.lzma
2020-09-25T22:34:29.939Z,1601073269.939 [DataOverHttps](INFO): Moved sent file to Logs/20200925T192622/Express0091.lzma.bak
2020-09-25T22:34:29.939Z,1601073269.939 [DataOverHttps](INFO): SBD MOMSN=12672840
2020-09-25T22:34:32.934Z,1601073272.934 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2020-09-25T22:34:33.009Z,1601073273.009 [NAL9602](FAULT): received: 
+CSQ:0

OK022, 2, 0, 0, 0

OK
2020-09-25T22:34:33.009Z,1601073273.009 [NAL9602] Data Fault, FailCount= 1
2020-09-25T22:34:33.009Z,1601073273.009 [NAL9602](ERROR): Data Fault
2020-09-25T22:34:33.072Z,1601073273.072 [CBIT](ERROR): Data Fault in component: NAL9602
2020-09-25T22:34:33.326Z,1601073273.326 [NAL9602](INFO): Powering down
2020-09-25T22:34:33.730Z,1601073273.730 [NAL9602](FAULT): LCB fault:  Software Overcurrent.
2020-09-25T22:34:33.730Z,1601073273.730 [NAL9602] Hardware Fault, FailCount= 1
2020-09-25T22:34:33.730Z,1601073273.730 [NAL9602](ERROR): Hardware Fault
2020-09-25T22:34:34.160Z,1601073274.160 [Default:CheckIn:Read_Iridium] Stopped
2020-09-25T22:34:34.165Z,1601073274.165 [Default:CheckIn:C.Wait] Running Loop=1
2020-09-25T22:34:34.165Z,1601073274.165 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-09-25T22:34:34.182Z,1601073274.182 [CBIT](INFO): Clearing failed state for component NAL9602
2020-09-25T22:34:34.182Z,1601073274.182 [NAL9602] No Fault, FailCount= 1
2020-09-25T22:34:36.991Z,1601073276.991 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2020-09-25T22:34:36.991Z,1601073276.991 [DropWeight] Hardware Fault, FailCount= 1
2020-09-25T22:34:36.991Z,1601073276.991 [DropWeight](ERROR): Hardware Fault
2020-09-25T22:34:37.084Z,1601073277.084 [CommandLine](FAULT): Scheduling is paused
2020-09-25T22:34:37.084Z,1601073277.084 [CBIT](INFO): Critical error at 20200925T223436
2020-09-25T22:34:37.086Z,1601073277.086 [CBIT](ERROR): Hardware Fault in component: DropWeight
2020-09-25T22:34:37.087Z,1601073277.087 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2020-09-25T22:34:37.415Z,1601073277.415 [CBIT](INFO): Critical error at 20200925T223437
2020-09-25T22:35:03.630Z,1601073303.630 [NAL9602](INFO): Powering up NAL9602
2020-09-25T22:35:14.541Z,1601073314.541 [NAL9602](INFO): NAL9602 initialized
2020-09-25T22:35:45.645Z,1601073345.645 [NAL9602](INFO): Not Powering down - fast GPS
2020-09-25T22:39:34.770Z,1601073574.770 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-09-25T22:39:34.770Z,1601073574.770 [Default:CheckIn:C.Wait] Stopped
2020-09-25T22:39:34.770Z,1601073574.770 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-09-25T22:39:34.770Z,1601073574.770 [Default:CheckIn:D] Running Loop=1
2020-09-25T22:39:35.139Z,1601073575.139 [Default:CheckIn:D] Stopped
2020-09-25T22:39:35.139Z,1601073575.139 [Default:CheckIn:E] Running Loop=1
2020-09-25T22:39:35.574Z,1601073575.574 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 200.393001 min 
2020-09-25T22:39:35.574Z,1601073575.574 [Default:CheckIn:E] Stopped
2020-09-25T22:39:35.574Z,1601073575.574 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-09-25T22:39:35.574Z,1601073575.574 [Default:CheckIn] Stopped
2020-09-25T22:39:35.574Z,1601073575.574 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-09-25T22:39:35.574Z,1601073575.574 [Default:CheckIn](INFO): Running loop #32
2020-09-25T22:39:35.574Z,1601073575.574 [Default:CheckIn] Running Loop=32
2020-09-25T22:39:35.574Z,1601073575.574 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-09-25T22:39:35.574Z,1601073575.574 [Default:CheckIn:Read_GPS] Running Loop=1
2020-09-25T22:39:39.972Z,1601073579.972 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-09-25T22:44:35.726Z,1601073875.726 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-09-25T22:39:35.6Z
2020-09-25T22:44:35.726Z,1601073875.726 [Default:CheckIn:Read_GPS] Stopped
2020-09-25T22:44:35.727Z,1601073875.727 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-09-25T22:44:50.161Z,1601073890.161 [DataOverHttps](INFO): Sending 148 bytes from file Logs/20200925T192622/Courier0093.lzma
2020-09-25T22:44:51.163Z,1601073891.163 [DataOverHttps](INFO): Moved sent file to Logs/20200925T192622/Courier0093.lzma.bak
2020-09-25T22:44:51.163Z,1601073891.163 [DataOverHttps](INFO): SBD MOMSN=12672866
2020-09-25T22:45:07.369Z,1601073907.369 [NAL9602](INFO): Not Powering down - fast GPS
2020-09-25T22:45:21.777Z,1601073921.777 [DataOverHttps](INFO): Sending 311 bytes from file Logs/20200925T192622/Express0094.lzma
2020-09-25T22:45:22.779Z,1601073922.779 [DataOverHttps](INFO): Moved sent file to Logs/20200925T192622/Express0094.lzma.bak
2020-09-25T22:45:22.779Z,1601073922.779 [DataOverHttps](INFO): SBD MOMSN=12672870
2020-09-25T22:45:27.188Z,1601073927.188 [Default:CheckIn:Read_Iridium] Stopped
2020-09-25T22:45:27.189Z,1601073927.189 [Default:CheckIn:C.Wait] Running Loop=1
2020-09-25T22:45:27.189Z,1601073927.189 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-09-25T22:49:37.295Z,1601074177.295 [CBIT](INFO): Clearing failed state for component DropWeight
2020-09-25T22:49:37.295Z,1601074177.295 [DropWeight] No Fault, FailCount= 1
2020-09-25T22:50:27.778Z,1601074227.778 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-09-25T22:50:27.778Z,1601074227.778 [Default:CheckIn:C.Wait] Stopped
2020-09-25T22:50:27.778Z,1601074227.778 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-09-25T22:50:27.779Z,1601074227.779 [Default:CheckIn:D] Running Loop=1
2020-09-25T22:50:28.165Z,1601074228.165 [Default:CheckIn:D] Stopped
2020-09-25T22:50:28.165Z,1601074228.165 [Default:CheckIn:E] Running Loop=1
2020-09-25T22:50:28.587Z,1601074228.587 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 211.276774 min 
2020-09-25T22:50:28.587Z,1601074228.587 [Default:CheckIn:E] Stopped
2020-09-25T22:50:28.587Z,1601074228.587 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-09-25T22:50:28.587Z,1601074228.587 [Default:CheckIn] Stopped
2020-09-25T22:50:28.587Z,1601074228.587 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-09-25T22:50:28.587Z,1601074228.587 [Default:CheckIn](INFO): Running loop #33
2020-09-25T22:50:28.587Z,1601074228.587 [Default:CheckIn] Running Loop=33
2020-09-25T22:50:28.588Z,1601074228.588 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-09-25T22:50:28.588Z,1601074228.588 [Default:CheckIn:Read_GPS] Running Loop=1
2020-09-25T22:50:30.174Z,1601074230.174 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-09-25T22:50:30.174Z,1601074230.174 [NAL9602] Data Fault, FailCount= 1
2020-09-25T22:50:30.174Z,1601074230.174 [NAL9602](ERROR): Data Fault
2020-09-25T22:50:30.193Z,1601074230.193 [CBIT](ERROR): Data Fault in component: NAL9602
2020-09-25T22:50:30.582Z,1601074230.582 [NAL9602](INFO): Powering down
2020-09-25T22:50:31.454Z,1601074231.454 [CBIT](INFO): Clearing failed state for component NAL9602
2020-09-25T22:50:31.454Z,1601074231.454 [NAL9602] No Fault, FailCount= 1
2020-09-25T22:51:00.882Z,1601074260.882 [NAL9602](INFO): Powering up NAL9602
2020-09-25T22:51:11.783Z,1601074271.783 [NAL9602](INFO): NAL9602 initialized
2020-09-25T22:55:28.817Z,1601074528.817 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-09-25T22:50:28.6Z
2020-09-25T22:55:28.817Z,1601074528.817 [Default:CheckIn:Read_GPS] Stopped
2020-09-25T22:55:28.817Z,1601074528.817 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-09-25T22:55:41.076Z,1601074541.076 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200925T192622/Courier0096.lzma
2020-09-25T22:55:42.079Z,1601074542.079 [DataOverHttps](INFO): Moved sent file to Logs/20200925T192622/Courier0096.lzma.bak
2020-09-25T22:55:42.079Z,1601074542.079 [DataOverHttps](INFO): SBD MOMSN=12672892
2020-09-25T22:56:02.893Z,1601074562.893 [DataOverHttps](INFO): Sending 171 bytes from file Logs/20200925T192622/Express0097.lzma
2020-09-25T22:56:03.895Z,1601074563.895 [DataOverHttps](INFO): Moved sent file to Logs/20200925T192622/Express0097.lzma.bak
2020-09-25T22:56:03.895Z,1601074563.895 [DataOverHttps](INFO): SBD MOMSN=12672894
2020-09-25T22:56:06.440Z,1601074566.440 [Default:CheckIn:Read_Iridium] Stopped
2020-09-25T22:56:06.440Z,1601074566.440 [Default:CheckIn:C.Wait] Running Loop=1
2020-09-25T22:56:06.440Z,1601074566.440 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-09-25T22:56:14.914Z,1601074574.914 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-09-25T22:56:45.621Z,1601074605.621 [NAL9602](INFO): Not Powering down - fast GPS
2020-09-25T23:01:07.019Z,1601074867.019 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-09-25T23:01:07.019Z,1601074867.019 [Default:CheckIn:C.Wait] Stopped
2020-09-25T23:01:07.019Z,1601074867.019 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-09-25T23:01:07.019Z,1601074867.019 [Default:CheckIn:D] Running Loop=1
2020-09-25T23:01:07.439Z,1601074867.439 [Default:CheckIn:D] Stopped
2020-09-25T23:01:07.439Z,1601074867.439 [Default:CheckIn:E] Running Loop=1
2020-09-25T23:01:07.858Z,1601074867.858 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 221.931331 min 
2020-09-25T23:01:07.859Z,1601074867.859 [Default:CheckIn:E] Stopped
2020-09-25T23:01:07.859Z,1601074867.859 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-09-25T23:01:07.859Z,1601074867.859 [Default:CheckIn] Stopped
2020-09-25T23:01:07.859Z,1601074867.859 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-09-25T23:01:07.859Z,1601074867.859 [Default:CheckIn](INFO): Running loop #34
2020-09-25T23:01:07.859Z,1601074867.859 [Default:CheckIn] Running Loop=34
2020-09-25T23:01:07.859Z,1601074867.859 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-09-25T23:01:07.859Z,1601074867.859 [Default:CheckIn:Read_GPS] Running Loop=1
2020-09-25T23:01:40.538Z,1601074900.538 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2020-09-25T23:01:40.621Z,1601074900.621 [NAL9602](FAULT): received: 
+CSQ:0

OK022, 2, 0, 0, 0

OK
2020-09-25T23:01:40.621Z,1601074900.621 [NAL9602] Data Fault, FailCount= 2
2020-09-25T23:01:40.621Z,1601074900.621 [NAL9602](ERROR): Data Fault
2020-09-25T23:01:40.643Z,1601074900.643 [CBIT](ERROR): Data Fault in component: NAL9602
2020-09-25T23:01:40.942Z,1601074900.942 [NAL9602](INFO): Powering down
2020-09-25T23:01:41.838Z,1601074901.838 [CBIT](INFO): Clearing failed state for component NAL9602
2020-09-25T23:01:41.838Z,1601074901.838 [NAL9602] No Fault, FailCount= 2
2020-09-25T23:02:11.252Z,1601074931.252 [NAL9602](INFO): Powering up NAL9602
2020-09-25T23:02:22.154Z,1601074942.154 [NAL9602](INFO): NAL9602 initialized
2020-09-25T23:02:22.962Z,1601074942.962 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-09-25T23:02:22.962Z,1601074942.962 [NAL9602] Data Fault, FailCount= 3
2020-09-25T23:02:22.962Z,1601074942.962 [NAL9602](ERROR): Data Fault
2020-09-25T23:02:22.981Z,1601074942.981 [CBIT](ERROR): Data Fault in component: NAL9602
2020-09-25T23:02:23.368Z,1601074943.368 [NAL9602](INFO): Powering down
2020-09-25T23:02:24.213Z,1601074944.213 [CBIT](INFO): Clearing failed state for component NAL9602
2020-09-25T23:02:24.213Z,1601074944.213 [NAL9602] No Fault, FailCount= 3
2020-09-25T23:02:53.443Z,1601074973.443 [NAL9602](INFO): Powering up NAL9602
2020-09-25T23:03:04.363Z,1601074984.363 [NAL9602](INFO): NAL9602 initialized
2020-09-25T23:06:08.190Z,1601075168.190 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-09-25T23:01:07.9Z
2020-09-25T23:06:08.190Z,1601075168.190 [Default:CheckIn:Read_GPS] Stopped
2020-09-25T23:06:08.190Z,1601075168.190 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-09-25T23:06:11.051Z,1601075171.051 [CBIT](IMPORTANT): Beginning ground fault scan
2020-09-25T23:06:11.815Z,1601075171.815 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-09-25T23:06:15.625Z,1601075175.625 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200925T192622/Courier0099.lzma
2020-09-25T23:06:16.628Z,1601075176.628 [DataOverHttps](INFO): Moved sent file to Logs/20200925T192622/Courier0099.lzma.bak
2020-09-25T23:06:16.628Z,1601075176.628 [DataOverHttps](INFO): SBD MOMSN=12672911
2020-09-25T23:06:21.949Z,1601075181.949 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.013790
CHAN A1 (24V): 0.009381
CHAN A2 (12V): -0.005878
CHAN A3 (5V): -0.003020
CHAN B0 (3.3V): 0.000134
CHAN B1 (3.15aV): -0.000161
CHAN B2 (3.15bV): 0.000651
CHAN B3 (GND): 0.001374
OPEN: 0.005845
Full Scale Calc: 4.765 mA, -1.589 mA
2020-09-25T23:06:35.622Z,1601075195.622 [DataOverHttps](INFO): Sending 283 bytes from file Logs/20200925T192622/Express0100.lzma
2020-09-25T23:06:36.624Z,1601075196.624 [DataOverHttps](INFO): Moved sent file to Logs/20200925T192622/Express0100.lzma.bak
2020-09-25T23:06:36.625Z,1601075196.625 [DataOverHttps](INFO): SBD MOMSN=12672913
2020-09-25T23:06:39.309Z,1601075199.309 [Default:CheckIn:Read_Iridium] Stopped
2020-09-25T23:06:39.309Z,1601075199.309 [Default:CheckIn:C.Wait] Running Loop=1
2020-09-25T23:06:39.309Z,1601075199.309 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-09-25T23:06:42.523Z,1601075202.523 [NAL9602](INFO): Not Powering down - fast GPS
2020-09-25T23:11:39.880Z,1601075499.880 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-09-25T23:11:39.880Z,1601075499.880 [Default:CheckIn:C.Wait] Stopped
2020-09-25T23:11:39.880Z,1601075499.880 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-09-25T23:11:39.881Z,1601075499.881 [Default:CheckIn:D] Running Loop=1
2020-09-25T23:11:40.295Z,1601075500.295 [Default:CheckIn:D] Stopped
2020-09-25T23:11:40.295Z,1601075500.295 [Default:CheckIn:E] Running Loop=1
2020-09-25T23:11:40.707Z,1601075500.707 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 232.478939 min 
2020-09-25T23:11:40.707Z,1601075500.707 [Default:CheckIn:E] Stopped
2020-09-25T23:11:40.707Z,1601075500.707 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-09-25T23:11:40.708Z,1601075500.708 [Default:CheckIn] Stopped
2020-09-25T23:11:40.708Z,1601075500.708 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-09-25T23:11:40.708Z,1601075500.708 [Default:CheckIn](INFO): Running loop #35
2020-09-25T23:11:40.708Z,1601075500.708 [Default:CheckIn] Running Loop=35
2020-09-25T23:11:40.708Z,1601075500.708 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-09-25T23:11:40.708Z,1601075500.708 [Default:CheckIn:Read_GPS] Running Loop=1
2020-09-25T23:15:29.134Z,1601075729.134 [CommandLine](IMPORTANT): got command quit
2020-09-25T23:15:30.142Z,1601075730.142 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-25T23:15:30.143Z,1601075730.143 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-09-25T23:15:30.230Z,1601075730.230 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-09-25T23:15:30.230Z,1601075730.230 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-09-25T23:15:30.231Z,1601075730.231 [CommandLine](INFO): Join timeout helper Thread ID is 7472
2020-09-25T23:15:30.254Z,1601075730.254 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-09-25T23:15:30.254Z,1601075730.254 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-09-25T23:15:30.254Z,1601075730.254 [NavChartDb](INFO): Join timeout helper Thread ID is 7473
2020-09-25T23:15:30.334Z,1601075730.334 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-25T23:15:30.334Z,1601075730.334 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-09-25T23:15:30.350Z,1601075730.350 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2020-09-25T23:15:30.350Z,1601075730.350 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-09-25T23:15:30.350Z,1601075730.350 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 7474
2020-09-25T23:15:30.766Z,1601075730.766 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-25T23:15:30.766Z,1601075730.766 [WetLabsBB2FL](INFO): Powering down
2020-09-25T23:15:30.767Z,1601075730.767 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-09-25T23:15:30.774Z,1601075730.774 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2020-09-25T23:15:30.774Z,1601075730.774 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-09-25T23:15:30.774Z,1601075730.774 [CTD_Seabird](INFO): Join timeout helper Thread ID is 7475
2020-09-25T23:15:31.678Z,1601075731.678 [CTD_Seabird](INFO): Powering down
2020-09-25T23:15:31.690Z,1601075731.690 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-25T23:15:31.690Z,1601075731.690 [CTD_Seabird](INFO): Powering down
2020-09-25T23:15:31.710Z,1601075731.710 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-09-25T23:15:31.718Z,1601075731.718 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-09-25T23:15:31.718Z,1601075731.718 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-09-25T23:15:31.718Z,1601075731.718 [Radio_Surface](INFO): Join timeout helper Thread ID is 7476
2020-09-25T23:15:31.906Z,1601075731.906 [Radio_Surface](INFO): Powering down
2020-09-25T23:15:31.907Z,1601075731.907 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-25T23:15:31.907Z,1601075731.907 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-09-25T23:15:31.918Z,1601075731.918 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2020-09-25T23:15:31.918Z,1601075731.918 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-09-25T23:15:31.919Z,1601075731.919 [Onboard](INFO): Join timeout helper Thread ID is 7477
2020-09-25T23:15:32.642Z,1601075732.642 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2020-09-25T23:15:35.186Z,1601075735.186 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-25T23:15:35.186Z,1601075735.186 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-09-25T23:15:35.202Z,1601075735.202 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-09-25T23:15:35.203Z,1601075735.203 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-09-25T23:15:35.203Z,1601075735.203 [DataOverHttps](INFO): Join timeout helper Thread ID is 7478
2020-09-25T23:15:35.654Z,1601075735.654 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-25T23:15:35.654Z,1601075735.654 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-09-25T23:15:35.663Z,1601075735.663 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2020-09-25T23:15:35.663Z,1601075735.663 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2020-09-25T23:15:35.663Z,1601075735.663 [BackseatComponent](INFO): Join timeout helper Thread ID is 7479
2020-09-25T23:15:35.726Z,1601075735.726 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-25T23:15:35.726Z,1601075735.726 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2020-09-25T23:15:35.743Z,1601075735.743 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-09-25T23:15:35.743Z,1601075735.743 [logger ThreadHandler](INFO): Thread cancelled.
2020-09-25T23:15:35.743Z,1601075735.743 [logger](INFO): Join timeout helper Thread ID is 7480
2020-09-25T23:15:35.762Z,1601075735.762 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-25T23:15:35.762Z,1601075735.762 [logger ThreadHandler](INFO): Thread cancelled.
2020-09-25T23:15:35.782Z,1601075735.782 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-09-25T23:15:35.783Z,1601075735.783 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-09-25T23:15:35.783Z,1601075735.783 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-09-25T23:15:35.783Z,1601075735.783 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-09-25T23:15:35.783Z,1601075735.783 [controlThread](INFO): Join timeout helper Thread ID is 7481
2020-09-25T23:15:35.806Z,1601075735.806 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-25T23:15:35.806Z,1601075735.806 [controlThread](DEBUG): Uninitializing ControlThread
2020-09-25T23:15:35.807Z,1601075735.807 [AHRS_M2](INFO): Powering down
2020-09-25T23:15:35.879Z,1601075735.879 [NAL9602](INFO): Powering down
2020-09-25T23:15:35.950Z,1601075735.950 [RDI_Pathfinder](INFO): Powering down
2020-09-25T23:15:35.951Z,1601075735.951 [DAT](INFO): Powering down
2020-09-25T23:15:36.070Z,1601075736.070 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-09-25T23:15:36.071Z,1601075736.071 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-09-25T23:15:36.072Z,1601075736.072 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-09-25T23:15:36.072Z,1601075736.072 [MissionManager](INFO): Uninitializing Mission Default
2020-09-25T23:15:36.072Z,1601075736.072 [Default] Stopped
2020-09-25T23:15:36.072Z,1601075736.072 [Default](DEBUG): Aggregate::uninitialize Default
2020-09-25T23:15:36.072Z,1601075736.072 [Default:B.GoToSurface] Stopped
2020-09-25T23:15:36.073Z,1601075736.073 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-09-25T23:15:36.073Z,1601075736.073 [Default:CheckIn] Stopped
2020-09-25T23:15:36.073Z,1601075736.073 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-09-25T23:15:36.073Z,1601075736.073 [Default:CheckIn:Read_GPS] Stopped
2020-09-25T23:15:36.076Z,1601075736.076 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-09-25T23:15:36.076Z,1601075736.076 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-09-25T23:15:36.076Z,1601075736.076 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-09-25T23:15:36.077Z,1601075736.077 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-09-25T23:15:36.077Z,1601075736.077 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-09-25T23:15:36.077Z,1601075736.077 [BuoyancyServo](INFO): Powering down
2020-09-25T23:15:36.090Z,1601075736.090 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-09-25T23:15:36.090Z,1601075736.090 [ElevatorServo](INFO): Powering down
2020-09-25T23:15:36.091Z,1601075736.091 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-09-25T23:15:36.091Z,1601075736.091 [MassServo](INFO): Powering down
2020-09-25T23:15:36.092Z,1601075736.092 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-09-25T23:15:36.092Z,1601075736.092 [RudderServo](INFO): Powering down
2020-09-25T23:15:36.093Z,1601075736.093 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-09-25T23:15:36.093Z,1601075736.093 [ThrusterServo](INFO): Powering down
2020-09-25T23:15:36.093Z,1601075736.093 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-09-25T23:15:36.094Z,1601075736.094 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-09-25T23:15:36.094Z,1601075736.094 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-09-25T23:15:36.094Z,1601075736.094 [CBIT](DEBUG): Powering off loads.
2020-09-25T23:15:36.106Z,1601075736.106 [CBIT](DEBUG): Disabling WDT.
2020-09-25T23:15:36.118Z,1601075736.118 [CBIT](DEBUG): Opening all GF detection circuits.
2020-09-25T23:15:36.119Z,1601075736.119 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-09-25T23:15:36.179Z,1601075736.179 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-09-25T23:15:36.181Z,1601075736.181 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-09-25T23:15:36.187Z,1601075736.187 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-09-25T23:15:36.232Z,1601075736.232 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2020-09-25T23:15:36.288Z,1601075736.288 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-09-25T23:15:36.291Z,1601075736.291 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-09-25T23:15:36.299Z,1601075736.299 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-09-25T23:15:36.392Z,1601075736.392 [logger ThreadHandler](INFO): Thread cancelled.